Oct 9 00:58:39.897405 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Oct 9 00:58:39.897438 kernel: Linux version 6.6.54-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 13.3.1_p20240614 p17) 13.3.1 20240614, GNU ld (Gentoo 2.42 p3) 2.42.0) #1 SMP PREEMPT Tue Oct 8 23:34:40 -00 2024 Oct 9 00:58:39.897449 kernel: KASLR enabled Oct 9 00:58:39.897454 kernel: efi: EFI v2.7 by EDK II Oct 9 00:58:39.897460 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdba86018 ACPI 2.0=0xd9710018 RNG=0xd971e498 MEMRESERVE=0xd9b43d18 Oct 9 00:58:39.897465 kernel: random: crng init done Oct 9 00:58:39.897472 kernel: secureboot: Secure boot disabled Oct 9 00:58:39.897478 kernel: ACPI: Early table checksum verification disabled Oct 9 00:58:39.897484 kernel: ACPI: RSDP 0x00000000D9710018 000024 (v02 BOCHS ) Oct 9 00:58:39.897491 kernel: ACPI: XSDT 0x00000000D971FE98 000064 (v01 BOCHS BXPC 00000001 01000013) Oct 9 00:58:39.897497 kernel: ACPI: FACP 0x00000000D971FA98 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Oct 9 00:58:39.897502 kernel: ACPI: DSDT 0x00000000D9717518 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 9 00:58:39.897508 kernel: ACPI: APIC 0x00000000D971FC18 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Oct 9 00:58:39.897514 kernel: ACPI: PPTT 0x00000000D971D898 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 9 00:58:39.897521 kernel: ACPI: GTDT 0x00000000D971E818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 9 00:58:39.897529 kernel: ACPI: MCFG 0x00000000D971E918 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 9 00:58:39.897535 kernel: ACPI: SPCR 0x00000000D971FF98 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 9 00:58:39.897541 kernel: ACPI: DBG2 0x00000000D971E418 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Oct 9 00:58:39.897547 kernel: ACPI: IORT 0x00000000D971E718 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Oct 9 00:58:39.897554 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Oct 9 00:58:39.897560 kernel: NUMA: Failed to initialise from firmware Oct 9 00:58:39.897566 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Oct 9 00:58:39.897572 kernel: NUMA: NODE_DATA [mem 0xdc957800-0xdc95cfff] Oct 9 00:58:39.897578 kernel: Zone ranges: Oct 9 00:58:39.897584 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Oct 9 00:58:39.897591 kernel: DMA32 empty Oct 9 00:58:39.897597 kernel: Normal empty Oct 9 00:58:39.897603 kernel: Movable zone start for each node Oct 9 00:58:39.897609 kernel: Early memory node ranges Oct 9 00:58:39.897615 kernel: node 0: [mem 0x0000000040000000-0x00000000d976ffff] Oct 9 00:58:39.897621 kernel: node 0: [mem 0x00000000d9770000-0x00000000d9b3ffff] Oct 9 00:58:39.897627 kernel: node 0: [mem 0x00000000d9b40000-0x00000000dce1ffff] Oct 9 00:58:39.897633 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Oct 9 00:58:39.897639 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Oct 9 00:58:39.897645 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Oct 9 00:58:39.897651 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Oct 9 00:58:39.897658 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Oct 9 00:58:39.897665 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Oct 9 00:58:39.897671 kernel: psci: probing for conduit method from ACPI. Oct 9 00:58:39.897678 kernel: psci: PSCIv1.1 detected in firmware. Oct 9 00:58:39.897686 kernel: psci: Using standard PSCI v0.2 function IDs Oct 9 00:58:39.897693 kernel: psci: Trusted OS migration not required Oct 9 00:58:39.897700 kernel: psci: SMC Calling Convention v1.1 Oct 9 00:58:39.897707 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Oct 9 00:58:39.897714 kernel: percpu: Embedded 31 pages/cpu s86632 r8192 d32152 u126976 Oct 9 00:58:39.897720 kernel: pcpu-alloc: s86632 r8192 d32152 u126976 alloc=31*4096 Oct 9 00:58:39.897727 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 9 00:58:39.897734 kernel: Detected PIPT I-cache on CPU0 Oct 9 00:58:39.897740 kernel: CPU features: detected: GIC system register CPU interface Oct 9 00:58:39.897747 kernel: CPU features: detected: Hardware dirty bit management Oct 9 00:58:39.897754 kernel: CPU features: detected: Spectre-v4 Oct 9 00:58:39.897760 kernel: CPU features: detected: Spectre-BHB Oct 9 00:58:39.897767 kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 9 00:58:39.897787 kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 9 00:58:39.897796 kernel: CPU features: detected: ARM erratum 1418040 Oct 9 00:58:39.897804 kernel: CPU features: detected: SSBS not fully self-synchronizing Oct 9 00:58:39.897813 kernel: alternatives: applying boot alternatives Oct 9 00:58:39.897822 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=d2d67b5440410ae2d0aa86eba97891969be0a7a421fa55f13442706ef7ed2a5e Oct 9 00:58:39.897829 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Oct 9 00:58:39.897839 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 9 00:58:39.897846 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 9 00:58:39.897857 kernel: Fallback order for Node 0: 0 Oct 9 00:58:39.897864 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Oct 9 00:58:39.897870 kernel: Policy zone: DMA Oct 9 00:58:39.897878 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 9 00:58:39.897885 kernel: software IO TLB: area num 4. Oct 9 00:58:39.897891 kernel: software IO TLB: mapped [mem 0x00000000d2e00000-0x00000000d6e00000] (64MB) Oct 9 00:58:39.897898 kernel: Memory: 2386400K/2572288K available (10240K kernel code, 2184K rwdata, 8092K rodata, 39552K init, 897K bss, 185888K reserved, 0K cma-reserved) Oct 9 00:58:39.897905 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 9 00:58:39.897911 kernel: trace event string verifier disabled Oct 9 00:58:39.897918 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 9 00:58:39.897925 kernel: rcu: RCU event tracing is enabled. Oct 9 00:58:39.897931 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Oct 9 00:58:39.897938 kernel: Trampoline variant of Tasks RCU enabled. Oct 9 00:58:39.897945 kernel: Tracing variant of Tasks RCU enabled. Oct 9 00:58:39.897955 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 9 00:58:39.897964 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 9 00:58:39.897970 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 9 00:58:39.897977 kernel: GICv3: 256 SPIs implemented Oct 9 00:58:39.897983 kernel: GICv3: 0 Extended SPIs implemented Oct 9 00:58:39.897989 kernel: Root IRQ handler: gic_handle_irq Oct 9 00:58:39.897996 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Oct 9 00:58:39.898002 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Oct 9 00:58:39.898009 kernel: ITS [mem 0x08080000-0x0809ffff] Oct 9 00:58:39.898016 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400d0000 (indirect, esz 8, psz 64K, shr 1) Oct 9 00:58:39.898022 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400e0000 (flat, esz 8, psz 64K, shr 1) Oct 9 00:58:39.898029 kernel: GICv3: using LPI property table @0x00000000400f0000 Oct 9 00:58:39.898037 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040100000 Oct 9 00:58:39.898043 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Oct 9 00:58:39.898050 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 9 00:58:39.898056 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Oct 9 00:58:39.898063 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Oct 9 00:58:39.898070 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Oct 9 00:58:39.898076 kernel: arm-pv: using stolen time PV Oct 9 00:58:39.898083 kernel: Console: colour dummy device 80x25 Oct 9 00:58:39.898090 kernel: ACPI: Core revision 20230628 Oct 9 00:58:39.898097 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Oct 9 00:58:39.898103 kernel: pid_max: default: 32768 minimum: 301 Oct 9 00:58:39.898111 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Oct 9 00:58:39.898118 kernel: landlock: Up and running. Oct 9 00:58:39.898124 kernel: SELinux: Initializing. Oct 9 00:58:39.898131 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 9 00:58:39.898138 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 9 00:58:39.898144 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. Oct 9 00:58:39.898151 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. Oct 9 00:58:39.898158 kernel: rcu: Hierarchical SRCU implementation. Oct 9 00:58:39.898164 kernel: rcu: Max phase no-delay instances is 400. Oct 9 00:58:39.898172 kernel: Platform MSI: ITS@0x8080000 domain created Oct 9 00:58:39.898179 kernel: PCI/MSI: ITS@0x8080000 domain created Oct 9 00:58:39.898186 kernel: Remapping and enabling EFI services. Oct 9 00:58:39.898193 kernel: smp: Bringing up secondary CPUs ... Oct 9 00:58:39.898199 kernel: Detected PIPT I-cache on CPU1 Oct 9 00:58:39.898206 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Oct 9 00:58:39.898213 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040110000 Oct 9 00:58:39.898220 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 9 00:58:39.898226 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Oct 9 00:58:39.898234 kernel: Detected PIPT I-cache on CPU2 Oct 9 00:58:39.898241 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Oct 9 00:58:39.898252 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040120000 Oct 9 00:58:39.898260 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 9 00:58:39.898267 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Oct 9 00:58:39.898274 kernel: Detected PIPT I-cache on CPU3 Oct 9 00:58:39.898281 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Oct 9 00:58:39.898289 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040130000 Oct 9 00:58:39.898296 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 9 00:58:39.898304 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Oct 9 00:58:39.898311 kernel: smp: Brought up 1 node, 4 CPUs Oct 9 00:58:39.898318 kernel: SMP: Total of 4 processors activated. Oct 9 00:58:39.898325 kernel: CPU features: detected: 32-bit EL0 Support Oct 9 00:58:39.898332 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Oct 9 00:58:39.898339 kernel: CPU features: detected: Common not Private translations Oct 9 00:58:39.898346 kernel: CPU features: detected: CRC32 instructions Oct 9 00:58:39.898353 kernel: CPU features: detected: Enhanced Virtualization Traps Oct 9 00:58:39.898361 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Oct 9 00:58:39.898368 kernel: CPU features: detected: LSE atomic instructions Oct 9 00:58:39.898379 kernel: CPU features: detected: Privileged Access Never Oct 9 00:58:39.898387 kernel: CPU features: detected: RAS Extension Support Oct 9 00:58:39.898394 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Oct 9 00:58:39.898402 kernel: CPU: All CPU(s) started at EL1 Oct 9 00:58:39.898415 kernel: alternatives: applying system-wide alternatives Oct 9 00:58:39.898422 kernel: devtmpfs: initialized Oct 9 00:58:39.898430 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 9 00:58:39.898439 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 9 00:58:39.898446 kernel: pinctrl core: initialized pinctrl subsystem Oct 9 00:58:39.898453 kernel: SMBIOS 3.0.0 present. Oct 9 00:58:39.898460 kernel: DMI: QEMU KVM Virtual Machine, BIOS edk2-20230524-3.fc38 05/24/2023 Oct 9 00:58:39.898467 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Oct 9 00:58:39.898474 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 9 00:58:39.898482 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 9 00:58:39.898489 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 9 00:58:39.898496 kernel: audit: initializing netlink subsys (disabled) Oct 9 00:58:39.898504 kernel: audit: type=2000 audit(0.020:1): state=initialized audit_enabled=0 res=1 Oct 9 00:58:39.898511 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 9 00:58:39.898519 kernel: cpuidle: using governor menu Oct 9 00:58:39.898526 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 9 00:58:39.898533 kernel: ASID allocator initialised with 32768 entries Oct 9 00:58:39.898540 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 9 00:58:39.898547 kernel: Serial: AMBA PL011 UART driver Oct 9 00:58:39.898554 kernel: Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Oct 9 00:58:39.898561 kernel: Modules: 0 pages in range for non-PLT usage Oct 9 00:58:39.898568 kernel: Modules: 508992 pages in range for PLT usage Oct 9 00:58:39.898576 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Oct 9 00:58:39.898583 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Oct 9 00:58:39.898590 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Oct 9 00:58:39.898598 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Oct 9 00:58:39.898605 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Oct 9 00:58:39.898612 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Oct 9 00:58:39.898619 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Oct 9 00:58:39.898626 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Oct 9 00:58:39.898633 kernel: ACPI: Added _OSI(Module Device) Oct 9 00:58:39.898641 kernel: ACPI: Added _OSI(Processor Device) Oct 9 00:58:39.898648 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Oct 9 00:58:39.898655 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 9 00:58:39.898662 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 9 00:58:39.898669 kernel: ACPI: Interpreter enabled Oct 9 00:58:39.898676 kernel: ACPI: Using GIC for interrupt routing Oct 9 00:58:39.898682 kernel: ACPI: MCFG table detected, 1 entries Oct 9 00:58:39.898690 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Oct 9 00:58:39.898697 kernel: printk: console [ttyAMA0] enabled Oct 9 00:58:39.898705 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 9 00:58:39.898829 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 9 00:58:39.898899 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 9 00:58:39.898961 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 9 00:58:39.899022 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Oct 9 00:58:39.899083 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Oct 9 00:58:39.899092 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Oct 9 00:58:39.899101 kernel: PCI host bridge to bus 0000:00 Oct 9 00:58:39.899169 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Oct 9 00:58:39.899244 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 9 00:58:39.899317 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Oct 9 00:58:39.899373 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 9 00:58:39.899482 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Oct 9 00:58:39.899557 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Oct 9 00:58:39.899643 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Oct 9 00:58:39.899715 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Oct 9 00:58:39.899788 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Oct 9 00:58:39.899851 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Oct 9 00:58:39.899913 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Oct 9 00:58:39.899976 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Oct 9 00:58:39.900037 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Oct 9 00:58:39.900092 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 9 00:58:39.900148 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Oct 9 00:58:39.900157 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Oct 9 00:58:39.900165 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Oct 9 00:58:39.900172 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Oct 9 00:58:39.900179 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Oct 9 00:58:39.900186 kernel: iommu: Default domain type: Translated Oct 9 00:58:39.900195 kernel: iommu: DMA domain TLB invalidation policy: strict mode Oct 9 00:58:39.900202 kernel: efivars: Registered efivars operations Oct 9 00:58:39.900209 kernel: vgaarb: loaded Oct 9 00:58:39.900217 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 9 00:58:39.900224 kernel: VFS: Disk quotas dquot_6.6.0 Oct 9 00:58:39.900231 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 9 00:58:39.900238 kernel: pnp: PnP ACPI init Oct 9 00:58:39.900310 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Oct 9 00:58:39.900325 kernel: pnp: PnP ACPI: found 1 devices Oct 9 00:58:39.900334 kernel: NET: Registered PF_INET protocol family Oct 9 00:58:39.900341 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 9 00:58:39.900348 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 9 00:58:39.900355 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Oct 9 00:58:39.900363 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 9 00:58:39.900370 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Oct 9 00:58:39.900384 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 9 00:58:39.900391 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 9 00:58:39.900400 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 9 00:58:39.900417 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Oct 9 00:58:39.900426 kernel: PCI: CLS 0 bytes, default 64 Oct 9 00:58:39.900433 kernel: kvm [1]: HYP mode not available Oct 9 00:58:39.900440 kernel: Initialise system trusted keyrings Oct 9 00:58:39.900448 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 9 00:58:39.900455 kernel: Key type asymmetric registered Oct 9 00:58:39.900462 kernel: Asymmetric key parser 'x509' registered Oct 9 00:58:39.900469 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Oct 9 00:58:39.900476 kernel: io scheduler mq-deadline registered Oct 9 00:58:39.900485 kernel: io scheduler kyber registered Oct 9 00:58:39.900492 kernel: io scheduler bfq registered Oct 9 00:58:39.900499 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 9 00:58:39.900507 kernel: ACPI: button: Power Button [PWRB] Oct 9 00:58:39.900514 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Oct 9 00:58:39.900592 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Oct 9 00:58:39.900602 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 9 00:58:39.900609 kernel: thunder_xcv, ver 1.0 Oct 9 00:58:39.900616 kernel: thunder_bgx, ver 1.0 Oct 9 00:58:39.900625 kernel: nicpf, ver 1.0 Oct 9 00:58:39.900632 kernel: nicvf, ver 1.0 Oct 9 00:58:39.900704 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 9 00:58:39.900764 kernel: rtc-efi rtc-efi.0: setting system clock to 2024-10-09T00:58:39 UTC (1728435519) Oct 9 00:58:39.900773 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 9 00:58:39.900781 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Oct 9 00:58:39.900803 kernel: watchdog: Delayed init of the lockup detector failed: -19 Oct 9 00:58:39.900810 kernel: watchdog: Hard watchdog permanently disabled Oct 9 00:58:39.900818 kernel: NET: Registered PF_INET6 protocol family Oct 9 00:58:39.900825 kernel: Segment Routing with IPv6 Oct 9 00:58:39.900832 kernel: In-situ OAM (IOAM) with IPv6 Oct 9 00:58:39.900839 kernel: NET: Registered PF_PACKET protocol family Oct 9 00:58:39.900846 kernel: Key type dns_resolver registered Oct 9 00:58:39.900853 kernel: registered taskstats version 1 Oct 9 00:58:39.900860 kernel: Loading compiled-in X.509 certificates Oct 9 00:58:39.900868 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.54-flatcar: 80611b0a9480eaf6d787b908c6349fdb5d07fa81' Oct 9 00:58:39.900875 kernel: Key type .fscrypt registered Oct 9 00:58:39.900883 kernel: Key type fscrypt-provisioning registered Oct 9 00:58:39.900890 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 9 00:58:39.900897 kernel: ima: Allocated hash algorithm: sha1 Oct 9 00:58:39.900904 kernel: ima: No architecture policies found Oct 9 00:58:39.900912 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Oct 9 00:58:39.900919 kernel: clk: Disabling unused clocks Oct 9 00:58:39.900926 kernel: Freeing unused kernel memory: 39552K Oct 9 00:58:39.900933 kernel: Run /init as init process Oct 9 00:58:39.900940 kernel: with arguments: Oct 9 00:58:39.900948 kernel: /init Oct 9 00:58:39.900955 kernel: with environment: Oct 9 00:58:39.900962 kernel: HOME=/ Oct 9 00:58:39.900969 kernel: TERM=linux Oct 9 00:58:39.900976 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 9 00:58:39.900985 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Oct 9 00:58:39.900993 systemd[1]: Detected virtualization kvm. Oct 9 00:58:39.901003 systemd[1]: Detected architecture arm64. Oct 9 00:58:39.901010 systemd[1]: Running in initrd. Oct 9 00:58:39.901017 systemd[1]: No hostname configured, using default hostname. Oct 9 00:58:39.901025 systemd[1]: Hostname set to . Oct 9 00:58:39.901032 systemd[1]: Initializing machine ID from VM UUID. Oct 9 00:58:39.901040 systemd[1]: Queued start job for default target initrd.target. Oct 9 00:58:39.901047 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 9 00:58:39.901055 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 9 00:58:39.901064 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Oct 9 00:58:39.901072 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 9 00:58:39.901080 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Oct 9 00:58:39.901087 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Oct 9 00:58:39.901096 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Oct 9 00:58:39.901104 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Oct 9 00:58:39.901112 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 9 00:58:39.901121 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 9 00:58:39.901128 systemd[1]: Reached target paths.target - Path Units. Oct 9 00:58:39.901136 systemd[1]: Reached target slices.target - Slice Units. Oct 9 00:58:39.901143 systemd[1]: Reached target swap.target - Swaps. Oct 9 00:58:39.901155 systemd[1]: Reached target timers.target - Timer Units. Oct 9 00:58:39.901163 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Oct 9 00:58:39.901171 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 9 00:58:39.901179 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Oct 9 00:58:39.901186 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Oct 9 00:58:39.901196 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 9 00:58:39.901204 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 9 00:58:39.901211 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 9 00:58:39.901219 systemd[1]: Reached target sockets.target - Socket Units. Oct 9 00:58:39.901226 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Oct 9 00:58:39.901234 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 9 00:58:39.901241 systemd[1]: Finished network-cleanup.service - Network Cleanup. Oct 9 00:58:39.901249 systemd[1]: Starting systemd-fsck-usr.service... Oct 9 00:58:39.901257 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 9 00:58:39.901265 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 9 00:58:39.901272 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 9 00:58:39.901280 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Oct 9 00:58:39.901287 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 9 00:58:39.901295 systemd[1]: Finished systemd-fsck-usr.service. Oct 9 00:58:39.901321 systemd-journald[237]: Collecting audit messages is disabled. Oct 9 00:58:39.901340 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 9 00:58:39.901348 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 9 00:58:39.901357 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Oct 9 00:58:39.901364 kernel: Bridge firewalling registered Oct 9 00:58:39.901372 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 9 00:58:39.901388 systemd-journald[237]: Journal started Oct 9 00:58:39.901407 systemd-journald[237]: Runtime Journal (/run/log/journal/91759658f8c944af9b18a806407939b7) is 5.9M, max 47.3M, 41.4M free. Oct 9 00:58:39.901451 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 9 00:58:39.878566 systemd-modules-load[239]: Inserted module 'overlay' Oct 9 00:58:39.899743 systemd-modules-load[239]: Inserted module 'br_netfilter' Oct 9 00:58:39.906222 systemd[1]: Started systemd-journald.service - Journal Service. Oct 9 00:58:39.905620 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 9 00:58:39.908369 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 9 00:58:39.909705 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 9 00:58:39.913742 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 9 00:58:39.919115 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 9 00:58:39.920216 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 9 00:58:39.923121 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 9 00:58:39.942542 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 9 00:58:39.943404 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 9 00:58:39.945776 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Oct 9 00:58:39.958209 dracut-cmdline[277]: dracut-dracut-053 Oct 9 00:58:39.960306 dracut-cmdline[277]: 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=d2d67b5440410ae2d0aa86eba97891969be0a7a421fa55f13442706ef7ed2a5e Oct 9 00:58:39.973495 systemd-resolved[272]: Positive Trust Anchors: Oct 9 00:58:39.973563 systemd-resolved[272]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 9 00:58:39.973594 systemd-resolved[272]: 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 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Oct 9 00:58:39.978178 systemd-resolved[272]: Defaulting to hostname 'linux'. Oct 9 00:58:39.979039 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 9 00:58:39.982319 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 9 00:58:40.036436 kernel: SCSI subsystem initialized Oct 9 00:58:40.040423 kernel: Loading iSCSI transport class v2.0-870. Oct 9 00:58:40.050437 kernel: iscsi: registered transport (tcp) Oct 9 00:58:40.062429 kernel: iscsi: registered transport (qla4xxx) Oct 9 00:58:40.062445 kernel: QLogic iSCSI HBA Driver Oct 9 00:58:40.101483 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Oct 9 00:58:40.111631 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Oct 9 00:58:40.126800 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Oct 9 00:58:40.126848 kernel: device-mapper: uevent: version 1.0.3 Oct 9 00:58:40.126865 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Oct 9 00:58:40.173458 kernel: raid6: neonx8 gen() 15716 MB/s Oct 9 00:58:40.190438 kernel: raid6: neonx4 gen() 15622 MB/s Oct 9 00:58:40.207427 kernel: raid6: neonx2 gen() 13192 MB/s Oct 9 00:58:40.224435 kernel: raid6: neonx1 gen() 10476 MB/s Oct 9 00:58:40.241435 kernel: raid6: int64x8 gen() 6931 MB/s Oct 9 00:58:40.258435 kernel: raid6: int64x4 gen() 7318 MB/s Oct 9 00:58:40.275434 kernel: raid6: int64x2 gen() 6111 MB/s Oct 9 00:58:40.292435 kernel: raid6: int64x1 gen() 5046 MB/s Oct 9 00:58:40.292460 kernel: raid6: using algorithm neonx8 gen() 15716 MB/s Oct 9 00:58:40.309430 kernel: raid6: .... xor() 11895 MB/s, rmw enabled Oct 9 00:58:40.309444 kernel: raid6: using neon recovery algorithm Oct 9 00:58:40.314751 kernel: xor: measuring software checksum speed Oct 9 00:58:40.314778 kernel: 8regs : 19745 MB/sec Oct 9 00:58:40.314796 kernel: 32regs : 19669 MB/sec Oct 9 00:58:40.315689 kernel: arm64_neon : 26927 MB/sec Oct 9 00:58:40.315702 kernel: xor: using function: arm64_neon (26927 MB/sec) Oct 9 00:58:40.365434 kernel: Btrfs loaded, zoned=no, fsverity=no Oct 9 00:58:40.375351 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Oct 9 00:58:40.392553 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 9 00:58:40.404588 systemd-udevd[458]: Using default interface naming scheme 'v255'. Oct 9 00:58:40.407650 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 9 00:58:40.411322 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Oct 9 00:58:40.424332 dracut-pre-trigger[465]: rd.md=0: removing MD RAID activation Oct 9 00:58:40.448919 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Oct 9 00:58:40.456548 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 9 00:58:40.493574 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 9 00:58:40.501584 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Oct 9 00:58:40.511860 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Oct 9 00:58:40.513369 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Oct 9 00:58:40.514796 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 9 00:58:40.516573 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 9 00:58:40.527568 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Oct 9 00:58:40.537706 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Oct 9 00:58:40.537856 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Oct 9 00:58:40.537647 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Oct 9 00:58:40.542636 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Oct 9 00:58:40.542663 kernel: GPT:9289727 != 19775487 Oct 9 00:58:40.542673 kernel: GPT:Alternate GPT header not at the end of the disk. Oct 9 00:58:40.543027 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 9 00:58:40.545237 kernel: GPT:9289727 != 19775487 Oct 9 00:58:40.545254 kernel: GPT: Use GNU Parted to correct GPT errors. Oct 9 00:58:40.545264 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 9 00:58:40.543129 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 9 00:58:40.549398 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 9 00:58:40.551904 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 9 00:58:40.552042 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 9 00:58:40.553568 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Oct 9 00:58:40.563707 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by (udev-worker) (507) Oct 9 00:58:40.563750 kernel: BTRFS: device fsid c25b3a2f-539f-42a7-8842-97b35e474647 devid 1 transid 37 /dev/vda3 scanned by (udev-worker) (502) Oct 9 00:58:40.562648 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 9 00:58:40.571222 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Oct 9 00:58:40.578234 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Oct 9 00:58:40.579390 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 9 00:58:40.590307 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Oct 9 00:58:40.593863 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Oct 9 00:58:40.594764 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Oct 9 00:58:40.607616 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Oct 9 00:58:40.609562 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 9 00:58:40.614051 disk-uuid[550]: Primary Header is updated. Oct 9 00:58:40.614051 disk-uuid[550]: Secondary Entries is updated. Oct 9 00:58:40.614051 disk-uuid[550]: Secondary Header is updated. Oct 9 00:58:40.616532 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 9 00:58:40.633912 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 9 00:58:41.630433 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 9 00:58:41.630878 disk-uuid[552]: The operation has completed successfully. Oct 9 00:58:41.656962 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 9 00:58:41.657058 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Oct 9 00:58:41.673598 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Oct 9 00:58:41.676303 sh[574]: Success Oct 9 00:58:41.689444 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Oct 9 00:58:41.715978 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Oct 9 00:58:41.725765 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Oct 9 00:58:41.727265 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Oct 9 00:58:41.737627 kernel: BTRFS info (device dm-0): first mount of filesystem c25b3a2f-539f-42a7-8842-97b35e474647 Oct 9 00:58:41.737668 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Oct 9 00:58:41.737679 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Oct 9 00:58:41.738458 kernel: BTRFS info (device dm-0): disabling log replay at mount time Oct 9 00:58:41.739517 kernel: BTRFS info (device dm-0): using free space tree Oct 9 00:58:41.743029 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Oct 9 00:58:41.744171 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Oct 9 00:58:41.749627 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Oct 9 00:58:41.750964 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Oct 9 00:58:41.759124 kernel: BTRFS info (device vda6): first mount of filesystem 6fd98f99-a3f6-49b2-9c3b-44aa7ae4e99b Oct 9 00:58:41.759172 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Oct 9 00:58:41.759183 kernel: BTRFS info (device vda6): using free space tree Oct 9 00:58:41.762430 kernel: BTRFS info (device vda6): auto enabling async discard Oct 9 00:58:41.771490 systemd[1]: mnt-oem.mount: Deactivated successfully. Oct 9 00:58:41.772862 kernel: BTRFS info (device vda6): last unmount of filesystem 6fd98f99-a3f6-49b2-9c3b-44aa7ae4e99b Oct 9 00:58:41.777358 systemd[1]: Finished ignition-setup.service - Ignition (setup). Oct 9 00:58:41.782601 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Oct 9 00:58:41.851830 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 9 00:58:41.863571 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 9 00:58:41.892707 systemd-networkd[765]: lo: Link UP Oct 9 00:58:41.892716 systemd-networkd[765]: lo: Gained carrier Oct 9 00:58:41.894840 systemd-networkd[765]: Enumeration completed Oct 9 00:58:41.896159 systemd-networkd[765]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 9 00:58:41.896164 systemd-networkd[765]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 9 00:58:41.896939 systemd-networkd[765]: eth0: Link UP Oct 9 00:58:41.896942 systemd-networkd[765]: eth0: Gained carrier Oct 9 00:58:41.896948 systemd-networkd[765]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 9 00:58:41.897505 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 9 00:58:41.899526 systemd[1]: Reached target network.target - Network. Oct 9 00:58:41.916093 ignition[669]: Ignition 2.19.0 Oct 9 00:58:41.916104 ignition[669]: Stage: fetch-offline Oct 9 00:58:41.916145 ignition[669]: no configs at "/usr/lib/ignition/base.d" Oct 9 00:58:41.916153 ignition[669]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 9 00:58:41.916297 ignition[669]: parsed url from cmdline: "" Oct 9 00:58:41.918480 systemd-networkd[765]: eth0: DHCPv4 address 10.0.0.115/16, gateway 10.0.0.1 acquired from 10.0.0.1 Oct 9 00:58:41.916300 ignition[669]: no config URL provided Oct 9 00:58:41.916304 ignition[669]: reading system config file "/usr/lib/ignition/user.ign" Oct 9 00:58:41.916311 ignition[669]: no config at "/usr/lib/ignition/user.ign" Oct 9 00:58:41.916335 ignition[669]: op(1): [started] loading QEMU firmware config module Oct 9 00:58:41.916339 ignition[669]: op(1): executing: "modprobe" "qemu_fw_cfg" Oct 9 00:58:41.922876 ignition[669]: op(1): [finished] loading QEMU firmware config module Oct 9 00:58:41.929545 ignition[669]: parsing config with SHA512: f14eaf0839ffca1bfe53ceca52bfc755193ea98239a6bf5e35e0774d5a6170c9371948cc3b0a24acad6ed0cb70e833a6e809544de64a55d54cae10d49d2e172a Oct 9 00:58:41.932570 unknown[669]: fetched base config from "system" Oct 9 00:58:41.932580 unknown[669]: fetched user config from "qemu" Oct 9 00:58:41.932938 ignition[669]: fetch-offline: fetch-offline passed Oct 9 00:58:41.933009 ignition[669]: Ignition finished successfully Oct 9 00:58:41.935238 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Oct 9 00:58:41.937406 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Oct 9 00:58:41.946605 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Oct 9 00:58:41.956177 ignition[773]: Ignition 2.19.0 Oct 9 00:58:41.956187 ignition[773]: Stage: kargs Oct 9 00:58:41.956329 ignition[773]: no configs at "/usr/lib/ignition/base.d" Oct 9 00:58:41.956338 ignition[773]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 9 00:58:41.957005 ignition[773]: kargs: kargs passed Oct 9 00:58:41.957043 ignition[773]: Ignition finished successfully Oct 9 00:58:41.961473 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Oct 9 00:58:41.974633 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Oct 9 00:58:41.983907 ignition[782]: Ignition 2.19.0 Oct 9 00:58:41.983917 ignition[782]: Stage: disks Oct 9 00:58:41.984070 ignition[782]: no configs at "/usr/lib/ignition/base.d" Oct 9 00:58:41.984079 ignition[782]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 9 00:58:41.984724 ignition[782]: disks: disks passed Oct 9 00:58:41.984766 ignition[782]: Ignition finished successfully Oct 9 00:58:41.987445 systemd[1]: Finished ignition-disks.service - Ignition (disks). Oct 9 00:58:41.989719 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Oct 9 00:58:41.990840 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Oct 9 00:58:41.992633 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 9 00:58:41.993312 systemd[1]: Reached target sysinit.target - System Initialization. Oct 9 00:58:41.994110 systemd[1]: Reached target basic.target - Basic System. Oct 9 00:58:42.006549 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Oct 9 00:58:42.017302 systemd-fsck[793]: ROOT: clean, 14/553520 files, 52654/553472 blocks Oct 9 00:58:42.020760 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Oct 9 00:58:42.034507 systemd[1]: Mounting sysroot.mount - /sysroot... Oct 9 00:58:42.076252 systemd[1]: Mounted sysroot.mount - /sysroot. Oct 9 00:58:42.077589 kernel: EXT4-fs (vda9): mounted filesystem 3a4adf89-ce2b-46a9-8e1a-433a27a27d16 r/w with ordered data mode. Quota mode: none. Oct 9 00:58:42.077512 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Oct 9 00:58:42.087491 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 9 00:58:42.088979 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Oct 9 00:58:42.090981 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Oct 9 00:58:42.091030 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Oct 9 00:58:42.091052 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Oct 9 00:58:42.098070 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/vda6 scanned by mount (801) Oct 9 00:58:42.098088 kernel: BTRFS info (device vda6): first mount of filesystem 6fd98f99-a3f6-49b2-9c3b-44aa7ae4e99b Oct 9 00:58:42.098098 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Oct 9 00:58:42.094868 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Oct 9 00:58:42.100472 kernel: BTRFS info (device vda6): using free space tree Oct 9 00:58:42.096988 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Oct 9 00:58:42.102423 kernel: BTRFS info (device vda6): auto enabling async discard Oct 9 00:58:42.103426 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 9 00:58:42.137742 initrd-setup-root[825]: cut: /sysroot/etc/passwd: No such file or directory Oct 9 00:58:42.141445 initrd-setup-root[832]: cut: /sysroot/etc/group: No such file or directory Oct 9 00:58:42.144206 initrd-setup-root[839]: cut: /sysroot/etc/shadow: No such file or directory Oct 9 00:58:42.147937 initrd-setup-root[846]: cut: /sysroot/etc/gshadow: No such file or directory Oct 9 00:58:42.217272 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Oct 9 00:58:42.228567 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Oct 9 00:58:42.230019 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Oct 9 00:58:42.234448 kernel: BTRFS info (device vda6): last unmount of filesystem 6fd98f99-a3f6-49b2-9c3b-44aa7ae4e99b Oct 9 00:58:42.250717 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Oct 9 00:58:42.252860 ignition[914]: INFO : Ignition 2.19.0 Oct 9 00:58:42.252860 ignition[914]: INFO : Stage: mount Oct 9 00:58:42.252860 ignition[914]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 9 00:58:42.252860 ignition[914]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 9 00:58:42.252860 ignition[914]: INFO : mount: mount passed Oct 9 00:58:42.252860 ignition[914]: INFO : Ignition finished successfully Oct 9 00:58:42.253563 systemd[1]: Finished ignition-mount.service - Ignition (mount). Oct 9 00:58:42.267626 systemd[1]: Starting ignition-files.service - Ignition (files)... Oct 9 00:58:42.737003 systemd[1]: sysroot-oem.mount: Deactivated successfully. Oct 9 00:58:42.746647 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 9 00:58:42.753114 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by mount (929) Oct 9 00:58:42.753145 kernel: BTRFS info (device vda6): first mount of filesystem 6fd98f99-a3f6-49b2-9c3b-44aa7ae4e99b Oct 9 00:58:42.753156 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Oct 9 00:58:42.754529 kernel: BTRFS info (device vda6): using free space tree Oct 9 00:58:42.756431 kernel: BTRFS info (device vda6): auto enabling async discard Oct 9 00:58:42.757501 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 9 00:58:42.772399 ignition[946]: INFO : Ignition 2.19.0 Oct 9 00:58:42.772399 ignition[946]: INFO : Stage: files Oct 9 00:58:42.773662 ignition[946]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 9 00:58:42.773662 ignition[946]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 9 00:58:42.773662 ignition[946]: DEBUG : files: compiled without relabeling support, skipping Oct 9 00:58:42.776306 ignition[946]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Oct 9 00:58:42.776306 ignition[946]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Oct 9 00:58:42.776306 ignition[946]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Oct 9 00:58:42.776306 ignition[946]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Oct 9 00:58:42.776306 ignition[946]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Oct 9 00:58:42.775978 unknown[946]: wrote ssh authorized keys file for user: core Oct 9 00:58:42.782066 ignition[946]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Oct 9 00:58:42.782066 ignition[946]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Oct 9 00:58:42.782066 ignition[946]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Oct 9 00:58:42.782066 ignition[946]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Oct 9 00:58:42.782066 ignition[946]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.0-arm64.raw" Oct 9 00:58:42.782066 ignition[946]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.0-arm64.raw" Oct 9 00:58:42.782066 ignition[946]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.0-arm64.raw" Oct 9 00:58:42.782066 ignition[946]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.31.0-arm64.raw: attempt #1 Oct 9 00:58:43.044148 ignition[946]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Oct 9 00:58:43.264521 systemd-networkd[765]: eth0: Gained IPv6LL Oct 9 00:58:43.432714 ignition[946]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.0-arm64.raw" Oct 9 00:58:43.432714 ignition[946]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Oct 9 00:58:43.435475 ignition[946]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Oct 9 00:58:43.435475 ignition[946]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Oct 9 00:58:43.435475 ignition[946]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Oct 9 00:58:43.435475 ignition[946]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Oct 9 00:58:43.454442 ignition[946]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Oct 9 00:58:43.457850 ignition[946]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Oct 9 00:58:43.458914 ignition[946]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Oct 9 00:58:43.458914 ignition[946]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Oct 9 00:58:43.458914 ignition[946]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Oct 9 00:58:43.458914 ignition[946]: INFO : files: files passed Oct 9 00:58:43.458914 ignition[946]: INFO : Ignition finished successfully Oct 9 00:58:43.459505 systemd[1]: Finished ignition-files.service - Ignition (files). Oct 9 00:58:43.472608 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Oct 9 00:58:43.474001 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Oct 9 00:58:43.476211 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 9 00:58:43.476298 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Oct 9 00:58:43.481148 initrd-setup-root-after-ignition[975]: grep: /sysroot/oem/oem-release: No such file or directory Oct 9 00:58:43.483299 initrd-setup-root-after-ignition[977]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 9 00:58:43.483299 initrd-setup-root-after-ignition[977]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Oct 9 00:58:43.486556 initrd-setup-root-after-ignition[981]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 9 00:58:43.488290 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 9 00:58:43.489462 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Oct 9 00:58:43.500584 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Oct 9 00:58:43.520007 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 9 00:58:43.520104 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Oct 9 00:58:43.521765 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Oct 9 00:58:43.523086 systemd[1]: Reached target initrd.target - Initrd Default Target. Oct 9 00:58:43.524418 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Oct 9 00:58:43.525070 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Oct 9 00:58:43.540444 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 9 00:58:43.551600 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Oct 9 00:58:43.559009 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Oct 9 00:58:43.559950 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 9 00:58:43.561494 systemd[1]: Stopped target timers.target - Timer Units. Oct 9 00:58:43.562886 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 9 00:58:43.562998 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 9 00:58:43.564998 systemd[1]: Stopped target initrd.target - Initrd Default Target. Oct 9 00:58:43.566472 systemd[1]: Stopped target basic.target - Basic System. Oct 9 00:58:43.567691 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Oct 9 00:58:43.568940 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Oct 9 00:58:43.570326 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Oct 9 00:58:43.571854 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Oct 9 00:58:43.573185 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Oct 9 00:58:43.574624 systemd[1]: Stopped target sysinit.target - System Initialization. Oct 9 00:58:43.576073 systemd[1]: Stopped target local-fs.target - Local File Systems. Oct 9 00:58:43.577345 systemd[1]: Stopped target swap.target - Swaps. Oct 9 00:58:43.578480 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 9 00:58:43.578589 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Oct 9 00:58:43.580305 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Oct 9 00:58:43.581761 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 9 00:58:43.583175 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Oct 9 00:58:43.586478 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 9 00:58:43.587391 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 9 00:58:43.587517 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Oct 9 00:58:43.589613 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Oct 9 00:58:43.589725 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Oct 9 00:58:43.591173 systemd[1]: Stopped target paths.target - Path Units. Oct 9 00:58:43.592331 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 9 00:58:43.597457 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 9 00:58:43.598404 systemd[1]: Stopped target slices.target - Slice Units. Oct 9 00:58:43.600119 systemd[1]: Stopped target sockets.target - Socket Units. Oct 9 00:58:43.601277 systemd[1]: iscsid.socket: Deactivated successfully. Oct 9 00:58:43.601364 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Oct 9 00:58:43.602504 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 9 00:58:43.602576 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 9 00:58:43.603723 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Oct 9 00:58:43.603819 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 9 00:58:43.605117 systemd[1]: ignition-files.service: Deactivated successfully. Oct 9 00:58:43.605209 systemd[1]: Stopped ignition-files.service - Ignition (files). Oct 9 00:58:43.616638 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Oct 9 00:58:43.618621 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Oct 9 00:58:43.619240 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 9 00:58:43.619347 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Oct 9 00:58:43.621061 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 9 00:58:43.621212 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Oct 9 00:58:43.626326 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 9 00:58:43.626447 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Oct 9 00:58:43.631289 systemd[1]: sysroot-boot.mount: Deactivated successfully. Oct 9 00:58:43.633636 ignition[1001]: INFO : Ignition 2.19.0 Oct 9 00:58:43.633636 ignition[1001]: INFO : Stage: umount Oct 9 00:58:43.635545 ignition[1001]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 9 00:58:43.635545 ignition[1001]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 9 00:58:43.635545 ignition[1001]: INFO : umount: umount passed Oct 9 00:58:43.635545 ignition[1001]: INFO : Ignition finished successfully Oct 9 00:58:43.636187 systemd[1]: ignition-mount.service: Deactivated successfully. Oct 9 00:58:43.636340 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Oct 9 00:58:43.637988 systemd[1]: Stopped target network.target - Network. Oct 9 00:58:43.638935 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 9 00:58:43.638987 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Oct 9 00:58:43.640266 systemd[1]: ignition-kargs.service: Deactivated successfully. Oct 9 00:58:43.640306 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Oct 9 00:58:43.641553 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 9 00:58:43.641588 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Oct 9 00:58:43.642994 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Oct 9 00:58:43.643032 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Oct 9 00:58:43.644453 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Oct 9 00:58:43.645733 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Oct 9 00:58:43.653874 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 9 00:58:43.653987 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Oct 9 00:58:43.656192 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 9 00:58:43.656254 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 9 00:58:43.658466 systemd-networkd[765]: eth0: DHCPv6 lease lost Oct 9 00:58:43.659827 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 9 00:58:43.659919 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Oct 9 00:58:43.661015 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 9 00:58:43.661045 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Oct 9 00:58:43.668504 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Oct 9 00:58:43.669150 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Oct 9 00:58:43.669196 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 9 00:58:43.670653 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 9 00:58:43.670688 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Oct 9 00:58:43.672045 systemd[1]: systemd-modules-load.service: Deactivated successfully. Oct 9 00:58:43.672082 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Oct 9 00:58:43.674805 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 9 00:58:43.683176 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 9 00:58:43.683298 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Oct 9 00:58:43.688893 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 9 00:58:43.688994 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Oct 9 00:58:43.690392 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 9 00:58:43.690449 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Oct 9 00:58:43.693048 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 9 00:58:43.693184 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 9 00:58:43.694821 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 9 00:58:43.694856 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Oct 9 00:58:43.696142 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 9 00:58:43.696173 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Oct 9 00:58:43.697449 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 9 00:58:43.697486 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Oct 9 00:58:43.699806 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 9 00:58:43.699844 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Oct 9 00:58:43.701790 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 9 00:58:43.701829 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 9 00:58:43.710548 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Oct 9 00:58:43.711286 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 9 00:58:43.711331 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 9 00:58:43.713036 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Oct 9 00:58:43.713074 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 9 00:58:43.714505 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 9 00:58:43.714552 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Oct 9 00:58:43.716158 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 9 00:58:43.716193 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 9 00:58:43.717895 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 9 00:58:43.717964 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Oct 9 00:58:43.720626 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Oct 9 00:58:43.722821 systemd[1]: Starting initrd-switch-root.service - Switch Root... Oct 9 00:58:43.731587 systemd[1]: Switching root. Oct 9 00:58:43.762202 systemd-journald[237]: Journal stopped Oct 9 00:58:44.403161 systemd-journald[237]: Received SIGTERM from PID 1 (systemd). Oct 9 00:58:44.403214 kernel: SELinux: policy capability network_peer_controls=1 Oct 9 00:58:44.403226 kernel: SELinux: policy capability open_perms=1 Oct 9 00:58:44.403239 kernel: SELinux: policy capability extended_socket_class=1 Oct 9 00:58:44.403252 kernel: SELinux: policy capability always_check_network=0 Oct 9 00:58:44.403266 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 9 00:58:44.403275 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 9 00:58:44.403285 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 9 00:58:44.403294 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Oct 9 00:58:44.403304 systemd[1]: Successfully loaded SELinux policy in 31.587ms. Oct 9 00:58:44.403316 kernel: audit: type=1403 audit(1728435523.887:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 9 00:58:44.403327 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 8.908ms. Oct 9 00:58:44.403340 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Oct 9 00:58:44.403362 systemd[1]: Detected virtualization kvm. Oct 9 00:58:44.403374 systemd[1]: Detected architecture arm64. Oct 9 00:58:44.403386 systemd[1]: Detected first boot. Oct 9 00:58:44.403396 systemd[1]: Initializing machine ID from VM UUID. Oct 9 00:58:44.403421 zram_generator::config[1048]: No configuration found. Oct 9 00:58:44.403435 systemd[1]: Populated /etc with preset unit settings. Oct 9 00:58:44.403446 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 9 00:58:44.403456 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Oct 9 00:58:44.403466 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 9 00:58:44.403477 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Oct 9 00:58:44.403488 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Oct 9 00:58:44.403498 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Oct 9 00:58:44.403510 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Oct 9 00:58:44.403521 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Oct 9 00:58:44.403531 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Oct 9 00:58:44.403542 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Oct 9 00:58:44.403552 systemd[1]: Created slice user.slice - User and Session Slice. Oct 9 00:58:44.403562 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 9 00:58:44.403573 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 9 00:58:44.403584 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Oct 9 00:58:44.403594 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Oct 9 00:58:44.403607 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Oct 9 00:58:44.403619 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 9 00:58:44.403632 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Oct 9 00:58:44.403643 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 9 00:58:44.403653 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Oct 9 00:58:44.403664 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Oct 9 00:58:44.403675 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Oct 9 00:58:44.403688 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Oct 9 00:58:44.403698 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 9 00:58:44.403708 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 9 00:58:44.403719 systemd[1]: Reached target slices.target - Slice Units. Oct 9 00:58:44.403729 systemd[1]: Reached target swap.target - Swaps. Oct 9 00:58:44.403744 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Oct 9 00:58:44.403754 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Oct 9 00:58:44.403765 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 9 00:58:44.403775 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 9 00:58:44.403785 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 9 00:58:44.403797 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Oct 9 00:58:44.403807 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Oct 9 00:58:44.403817 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Oct 9 00:58:44.403827 systemd[1]: Mounting media.mount - External Media Directory... Oct 9 00:58:44.403838 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Oct 9 00:58:44.403849 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Oct 9 00:58:44.403865 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Oct 9 00:58:44.403875 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Oct 9 00:58:44.403888 systemd[1]: Reached target machines.target - Containers. Oct 9 00:58:44.403898 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Oct 9 00:58:44.403909 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 9 00:58:44.403920 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 9 00:58:44.403930 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Oct 9 00:58:44.403940 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 9 00:58:44.403950 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 9 00:58:44.403960 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 9 00:58:44.403971 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Oct 9 00:58:44.403983 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 9 00:58:44.403995 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Oct 9 00:58:44.404005 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 9 00:58:44.404018 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Oct 9 00:58:44.404028 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 9 00:58:44.404038 systemd[1]: Stopped systemd-fsck-usr.service. Oct 9 00:58:44.404053 kernel: loop: module loaded Oct 9 00:58:44.404063 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 9 00:58:44.404074 kernel: fuse: init (API version 7.39) Oct 9 00:58:44.404086 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 9 00:58:44.404098 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 9 00:58:44.404108 kernel: ACPI: bus type drm_connector registered Oct 9 00:58:44.404119 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Oct 9 00:58:44.404129 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 9 00:58:44.404140 systemd[1]: verity-setup.service: Deactivated successfully. Oct 9 00:58:44.404151 systemd[1]: Stopped verity-setup.service. Oct 9 00:58:44.404160 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Oct 9 00:58:44.404170 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Oct 9 00:58:44.404199 systemd-journald[1112]: Collecting audit messages is disabled. Oct 9 00:58:44.404222 systemd[1]: Mounted media.mount - External Media Directory. Oct 9 00:58:44.404233 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Oct 9 00:58:44.404245 systemd-journald[1112]: Journal started Oct 9 00:58:44.404267 systemd-journald[1112]: Runtime Journal (/run/log/journal/91759658f8c944af9b18a806407939b7) is 5.9M, max 47.3M, 41.4M free. Oct 9 00:58:44.231254 systemd[1]: Queued start job for default target multi-user.target. Oct 9 00:58:44.251325 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Oct 9 00:58:44.251673 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 9 00:58:44.406732 systemd[1]: Started systemd-journald.service - Journal Service. Oct 9 00:58:44.407302 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Oct 9 00:58:44.408261 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Oct 9 00:58:44.410474 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 9 00:58:44.411651 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Oct 9 00:58:44.412761 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 9 00:58:44.412898 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Oct 9 00:58:44.414314 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 9 00:58:44.414488 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 9 00:58:44.415925 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 9 00:58:44.416072 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 9 00:58:44.417176 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 9 00:58:44.417305 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 9 00:58:44.419738 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 9 00:58:44.419883 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Oct 9 00:58:44.420925 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 9 00:58:44.421055 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 9 00:58:44.422774 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 9 00:58:44.423836 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 9 00:58:44.424955 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Oct 9 00:58:44.436176 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 9 00:58:44.450530 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Oct 9 00:58:44.452443 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Oct 9 00:58:44.453481 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Oct 9 00:58:44.453520 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 9 00:58:44.455388 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Oct 9 00:58:44.457548 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Oct 9 00:58:44.459574 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Oct 9 00:58:44.460624 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 9 00:58:44.462140 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Oct 9 00:58:44.464311 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Oct 9 00:58:44.465663 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 9 00:58:44.469673 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Oct 9 00:58:44.471364 systemd-journald[1112]: Time spent on flushing to /var/log/journal/91759658f8c944af9b18a806407939b7 is 22.194ms for 838 entries. Oct 9 00:58:44.471364 systemd-journald[1112]: System Journal (/var/log/journal/91759658f8c944af9b18a806407939b7) is 8.0M, max 195.6M, 187.6M free. Oct 9 00:58:44.510655 systemd-journald[1112]: Received client request to flush runtime journal. Oct 9 00:58:44.510690 kernel: loop0: detected capacity change from 0 to 189592 Oct 9 00:58:44.473437 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 9 00:58:44.474311 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 9 00:58:44.477590 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Oct 9 00:58:44.482851 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 9 00:58:44.486211 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 9 00:58:44.487825 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Oct 9 00:58:44.489081 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Oct 9 00:58:44.490717 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Oct 9 00:58:44.496256 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Oct 9 00:58:44.497926 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Oct 9 00:58:44.505210 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Oct 9 00:58:44.512612 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Oct 9 00:58:44.514160 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Oct 9 00:58:44.516099 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 9 00:58:44.519960 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Oct 9 00:58:44.523965 systemd-tmpfiles[1161]: ACLs are not supported, ignoring. Oct 9 00:58:44.523986 systemd-tmpfiles[1161]: ACLs are not supported, ignoring. Oct 9 00:58:44.532103 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 9 00:58:44.540728 systemd[1]: Starting systemd-sysusers.service - Create System Users... Oct 9 00:58:44.542521 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 9 00:58:44.543353 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Oct 9 00:58:44.546204 udevadm[1173]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Oct 9 00:58:44.553444 kernel: loop1: detected capacity change from 0 to 113456 Oct 9 00:58:44.564243 systemd[1]: Finished systemd-sysusers.service - Create System Users. Oct 9 00:58:44.578503 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 9 00:58:44.580563 kernel: loop2: detected capacity change from 0 to 116808 Oct 9 00:58:44.589681 systemd-tmpfiles[1183]: ACLs are not supported, ignoring. Oct 9 00:58:44.589698 systemd-tmpfiles[1183]: ACLs are not supported, ignoring. Oct 9 00:58:44.594494 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 9 00:58:44.616459 kernel: loop3: detected capacity change from 0 to 189592 Oct 9 00:58:44.623430 kernel: loop4: detected capacity change from 0 to 113456 Oct 9 00:58:44.628437 kernel: loop5: detected capacity change from 0 to 116808 Oct 9 00:58:44.632820 (sd-merge)[1187]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'. Oct 9 00:58:44.633232 (sd-merge)[1187]: Merged extensions into '/usr'. Oct 9 00:58:44.636479 systemd[1]: Reloading requested from client PID 1160 ('systemd-sysext') (unit systemd-sysext.service)... Oct 9 00:58:44.636494 systemd[1]: Reloading... Oct 9 00:58:44.677679 zram_generator::config[1213]: No configuration found. Oct 9 00:58:44.751491 ldconfig[1155]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 9 00:58:44.774014 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Oct 9 00:58:44.808776 systemd[1]: Reloading finished in 171 ms. Oct 9 00:58:44.839284 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Oct 9 00:58:44.844432 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Oct 9 00:58:44.857635 systemd[1]: Starting ensure-sysext.service... Oct 9 00:58:44.859626 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 9 00:58:44.869309 systemd[1]: Reloading requested from client PID 1247 ('systemctl') (unit ensure-sysext.service)... Oct 9 00:58:44.869326 systemd[1]: Reloading... Oct 9 00:58:44.876644 systemd-tmpfiles[1248]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Oct 9 00:58:44.876942 systemd-tmpfiles[1248]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Oct 9 00:58:44.877784 systemd-tmpfiles[1248]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Oct 9 00:58:44.878044 systemd-tmpfiles[1248]: ACLs are not supported, ignoring. Oct 9 00:58:44.878095 systemd-tmpfiles[1248]: ACLs are not supported, ignoring. Oct 9 00:58:44.880608 systemd-tmpfiles[1248]: Detected autofs mount point /boot during canonicalization of boot. Oct 9 00:58:44.880618 systemd-tmpfiles[1248]: Skipping /boot Oct 9 00:58:44.887359 systemd-tmpfiles[1248]: Detected autofs mount point /boot during canonicalization of boot. Oct 9 00:58:44.887468 systemd-tmpfiles[1248]: Skipping /boot Oct 9 00:58:44.918436 zram_generator::config[1275]: No configuration found. Oct 9 00:58:44.996376 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Oct 9 00:58:45.030758 systemd[1]: Reloading finished in 161 ms. Oct 9 00:58:45.047112 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Oct 9 00:58:45.048315 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 9 00:58:45.065861 systemd[1]: Starting audit-rules.service - Load Audit Rules... Oct 9 00:58:45.069334 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Oct 9 00:58:45.071198 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Oct 9 00:58:45.076569 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 9 00:58:45.082844 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 9 00:58:45.084954 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Oct 9 00:58:45.093696 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Oct 9 00:58:45.097566 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 9 00:58:45.099720 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 9 00:58:45.102790 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 9 00:58:45.106281 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 9 00:58:45.107721 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 9 00:58:45.108811 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 9 00:58:45.109419 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 9 00:58:45.111954 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 9 00:58:45.112068 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 9 00:58:45.114099 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 9 00:58:45.114210 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 9 00:58:45.118215 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Oct 9 00:58:45.119020 systemd-udevd[1321]: Using default interface naming scheme 'v255'. Oct 9 00:58:45.120857 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Oct 9 00:58:45.125294 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 9 00:58:45.132082 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 9 00:58:45.133960 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 9 00:58:45.138128 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 9 00:58:45.138969 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 9 00:58:45.141770 systemd[1]: Starting systemd-update-done.service - Update is Completed... Oct 9 00:58:45.143571 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 9 00:58:45.145689 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 9 00:58:45.149102 systemd[1]: Started systemd-userdbd.service - User Database Manager. Oct 9 00:58:45.150221 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 9 00:58:45.152461 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Oct 9 00:58:45.153887 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 9 00:58:45.157810 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 9 00:58:45.159203 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 9 00:58:45.159331 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 9 00:58:45.160564 systemd[1]: Finished systemd-update-done.service - Update is Completed. Oct 9 00:58:45.174852 systemd[1]: Finished ensure-sysext.service. Oct 9 00:58:45.179972 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 9 00:58:45.191834 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 9 00:58:45.196601 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 9 00:58:45.201574 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 9 00:58:45.203628 kernel: BTRFS info: devid 1 device path /dev/mapper/usr changed to /dev/dm-0 scanned by (udev-worker) (1354) Oct 9 00:58:45.205879 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 9 00:58:45.207279 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 9 00:58:45.210718 systemd-resolved[1315]: Positive Trust Anchors: Oct 9 00:58:45.212000 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 9 00:58:45.212959 augenrules[1384]: No rules Oct 9 00:58:45.213425 systemd-resolved[1315]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 9 00:58:45.213463 systemd-resolved[1315]: 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 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Oct 9 00:58:45.214813 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Oct 9 00:58:45.216429 kernel: BTRFS info: devid 1 device path /dev/dm-0 changed to /dev/mapper/usr scanned by (udev-worker) (1354) Oct 9 00:58:45.218498 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Oct 9 00:58:45.219226 systemd[1]: audit-rules.service: Deactivated successfully. Oct 9 00:58:45.219427 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 37 scanned by (udev-worker) (1357) Oct 9 00:58:45.219420 systemd[1]: Finished audit-rules.service - Load Audit Rules. Oct 9 00:58:45.220594 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 9 00:58:45.220758 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 9 00:58:45.221911 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 9 00:58:45.222057 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 9 00:58:45.223065 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 9 00:58:45.223199 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 9 00:58:45.224039 systemd-resolved[1315]: Defaulting to hostname 'linux'. Oct 9 00:58:45.224507 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 9 00:58:45.224626 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 9 00:58:45.227843 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 9 00:58:45.230917 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Oct 9 00:58:45.233881 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 9 00:58:45.235454 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 9 00:58:45.235510 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 9 00:58:45.245628 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Oct 9 00:58:45.256642 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Oct 9 00:58:45.276717 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Oct 9 00:58:45.283655 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Oct 9 00:58:45.285776 systemd[1]: Reached target time-set.target - System Time Set. Oct 9 00:58:45.293668 systemd-networkd[1386]: lo: Link UP Oct 9 00:58:45.293679 systemd-networkd[1386]: lo: Gained carrier Oct 9 00:58:45.296615 systemd-networkd[1386]: Enumeration completed Oct 9 00:58:45.296693 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 9 00:58:45.297576 systemd[1]: Reached target network.target - Network. Oct 9 00:58:45.303693 systemd-networkd[1386]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 9 00:58:45.303703 systemd-networkd[1386]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 9 00:58:45.304723 systemd-networkd[1386]: eth0: Link UP Oct 9 00:58:45.304733 systemd-networkd[1386]: eth0: Gained carrier Oct 9 00:58:45.304747 systemd-networkd[1386]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 9 00:58:45.309587 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Oct 9 00:58:45.316043 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 9 00:58:45.321483 systemd-networkd[1386]: eth0: DHCPv4 address 10.0.0.115/16, gateway 10.0.0.1 acquired from 10.0.0.1 Oct 9 00:58:45.323520 systemd-timesyncd[1390]: Network configuration changed, trying to establish connection. Oct 9 00:58:45.324322 systemd-timesyncd[1390]: Contacted time server 10.0.0.1:123 (10.0.0.1). Oct 9 00:58:45.324379 systemd-timesyncd[1390]: Initial clock synchronization to Wed 2024-10-09 00:58:45.321399 UTC. Oct 9 00:58:45.327727 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Oct 9 00:58:45.336625 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Oct 9 00:58:45.349137 lvm[1414]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 9 00:58:45.361221 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 9 00:58:45.386876 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Oct 9 00:58:45.387959 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 9 00:58:45.389577 systemd[1]: Reached target sysinit.target - System Initialization. Oct 9 00:58:45.390428 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Oct 9 00:58:45.391543 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 9 00:58:45.392599 systemd[1]: Started logrotate.timer - Daily rotation of log files. Oct 9 00:58:45.393476 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Oct 9 00:58:45.394359 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Oct 9 00:58:45.395298 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Oct 9 00:58:45.395332 systemd[1]: Reached target paths.target - Path Units. Oct 9 00:58:45.396010 systemd[1]: Reached target timers.target - Timer Units. Oct 9 00:58:45.397537 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Oct 9 00:58:45.399471 systemd[1]: Starting docker.socket - Docker Socket for the API... Oct 9 00:58:45.414239 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Oct 9 00:58:45.416103 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Oct 9 00:58:45.417382 systemd[1]: Listening on docker.socket - Docker Socket for the API. Oct 9 00:58:45.418250 systemd[1]: Reached target sockets.target - Socket Units. Oct 9 00:58:45.418988 systemd[1]: Reached target basic.target - Basic System. Oct 9 00:58:45.419690 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Oct 9 00:58:45.419716 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Oct 9 00:58:45.420558 systemd[1]: Starting containerd.service - containerd container runtime... Oct 9 00:58:45.422168 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Oct 9 00:58:45.423445 lvm[1421]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 9 00:58:45.425854 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Oct 9 00:58:45.428167 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Oct 9 00:58:45.429285 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Oct 9 00:58:45.440108 dbus-daemon[1423]: [system] SELinux support is enabled Oct 9 00:58:45.443952 jq[1424]: false Oct 9 00:58:45.443604 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Oct 9 00:58:45.445473 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Oct 9 00:58:45.447780 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Oct 9 00:58:45.453384 systemd[1]: Starting systemd-logind.service - User Login Management... Oct 9 00:58:45.455051 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Oct 9 00:58:45.456504 extend-filesystems[1425]: Found loop3 Oct 9 00:58:45.456504 extend-filesystems[1425]: Found loop4 Oct 9 00:58:45.456504 extend-filesystems[1425]: Found loop5 Oct 9 00:58:45.456504 extend-filesystems[1425]: Found vda Oct 9 00:58:45.456504 extend-filesystems[1425]: Found vda1 Oct 9 00:58:45.456504 extend-filesystems[1425]: Found vda2 Oct 9 00:58:45.456504 extend-filesystems[1425]: Found vda3 Oct 9 00:58:45.456504 extend-filesystems[1425]: Found usr Oct 9 00:58:45.456504 extend-filesystems[1425]: Found vda4 Oct 9 00:58:45.456504 extend-filesystems[1425]: Found vda6 Oct 9 00:58:45.456504 extend-filesystems[1425]: Found vda7 Oct 9 00:58:45.456504 extend-filesystems[1425]: Found vda9 Oct 9 00:58:45.456504 extend-filesystems[1425]: Checking size of /dev/vda9 Oct 9 00:58:45.507224 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Oct 9 00:58:45.507258 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 37 scanned by (udev-worker) (1371) Oct 9 00:58:45.507271 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Oct 9 00:58:45.455434 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 9 00:58:45.507509 extend-filesystems[1425]: Resized partition /dev/vda9 Oct 9 00:58:45.457542 systemd[1]: Starting update-engine.service - Update Engine... Oct 9 00:58:45.509676 extend-filesystems[1445]: resize2fs 1.47.1 (20-May-2024) Oct 9 00:58:45.460491 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Oct 9 00:58:45.463977 systemd[1]: Started dbus.service - D-Bus System Message Bus. Oct 9 00:58:45.511144 jq[1441]: true Oct 9 00:58:45.511294 extend-filesystems[1445]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Oct 9 00:58:45.511294 extend-filesystems[1445]: old_desc_blocks = 1, new_desc_blocks = 1 Oct 9 00:58:45.511294 extend-filesystems[1445]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Oct 9 00:58:45.466882 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Oct 9 00:58:45.516750 extend-filesystems[1425]: Resized filesystem in /dev/vda9 Oct 9 00:58:45.471073 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Oct 9 00:58:45.517517 jq[1447]: true Oct 9 00:58:45.471263 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Oct 9 00:58:45.471531 systemd[1]: motdgen.service: Deactivated successfully. Oct 9 00:58:45.471663 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Oct 9 00:58:45.473840 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 9 00:58:45.473973 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Oct 9 00:58:45.486809 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Oct 9 00:58:45.486831 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Oct 9 00:58:45.495465 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Oct 9 00:58:45.495491 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Oct 9 00:58:45.498753 (ntainerd)[1448]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Oct 9 00:58:45.512838 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 9 00:58:45.513011 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Oct 9 00:58:45.536316 systemd-logind[1435]: Watching system buttons on /dev/input/event0 (Power Button) Oct 9 00:58:45.541842 update_engine[1437]: I20241009 00:58:45.539667 1437 main.cc:92] Flatcar Update Engine starting Oct 9 00:58:45.541943 systemd-logind[1435]: New seat seat0. Oct 9 00:58:45.542506 systemd[1]: Started update-engine.service - Update Engine. Oct 9 00:58:45.543888 systemd[1]: Started systemd-logind.service - User Login Management. Oct 9 00:58:45.549304 update_engine[1437]: I20241009 00:58:45.549252 1437 update_check_scheduler.cc:74] Next update check in 4m9s Oct 9 00:58:45.557453 systemd[1]: Started locksmithd.service - Cluster reboot manager. Oct 9 00:58:45.581717 bash[1475]: Updated "/home/core/.ssh/authorized_keys" Oct 9 00:58:45.582095 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Oct 9 00:58:45.584645 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Oct 9 00:58:45.593080 locksmithd[1473]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 9 00:58:45.685816 containerd[1448]: time="2024-10-09T00:58:45.685720040Z" level=info msg="starting containerd" revision=b2ce781edcbd6cb758f172ecab61c79d607cc41d version=v1.7.22 Oct 9 00:58:45.688588 sshd_keygen[1442]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Oct 9 00:58:45.707458 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Oct 9 00:58:45.708729 containerd[1448]: time="2024-10-09T00:58:45.708485560Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Oct 9 00:58:45.709648 containerd[1448]: time="2024-10-09T00:58:45.709608720Z" 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/6.6.54-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Oct 9 00:58:45.709648 containerd[1448]: time="2024-10-09T00:58:45.709644600Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Oct 9 00:58:45.709703 containerd[1448]: time="2024-10-09T00:58:45.709662360Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Oct 9 00:58:45.709831 containerd[1448]: time="2024-10-09T00:58:45.709806000Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Oct 9 00:58:45.709856 containerd[1448]: time="2024-10-09T00:58:45.709831760Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Oct 9 00:58:45.709899 containerd[1448]: time="2024-10-09T00:58:45.709885560Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Oct 9 00:58:45.709921 containerd[1448]: time="2024-10-09T00:58:45.709899840Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Oct 9 00:58:45.710064 containerd[1448]: time="2024-10-09T00:58:45.710038400Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Oct 9 00:58:45.710064 containerd[1448]: time="2024-10-09T00:58:45.710058920Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Oct 9 00:58:45.710103 containerd[1448]: time="2024-10-09T00:58:45.710073200Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Oct 9 00:58:45.710103 containerd[1448]: time="2024-10-09T00:58:45.710082000Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Oct 9 00:58:45.710162 containerd[1448]: time="2024-10-09T00:58:45.710148640Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Oct 9 00:58:45.710357 containerd[1448]: time="2024-10-09T00:58:45.710324880Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Oct 9 00:58:45.710467 containerd[1448]: time="2024-10-09T00:58:45.710450840Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Oct 9 00:58:45.710496 containerd[1448]: time="2024-10-09T00:58:45.710469240Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Oct 9 00:58:45.710561 containerd[1448]: time="2024-10-09T00:58:45.710547520Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Oct 9 00:58:45.710605 containerd[1448]: time="2024-10-09T00:58:45.710592480Z" level=info msg="metadata content store policy set" policy=shared Oct 9 00:58:45.713419 containerd[1448]: time="2024-10-09T00:58:45.713389720Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Oct 9 00:58:45.713450 containerd[1448]: time="2024-10-09T00:58:45.713441360Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Oct 9 00:58:45.713469 containerd[1448]: time="2024-10-09T00:58:45.713457240Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Oct 9 00:58:45.713499 containerd[1448]: time="2024-10-09T00:58:45.713471920Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Oct 9 00:58:45.713499 containerd[1448]: time="2024-10-09T00:58:45.713486760Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Oct 9 00:58:45.713636 containerd[1448]: time="2024-10-09T00:58:45.713610040Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Oct 9 00:58:45.714447 containerd[1448]: time="2024-10-09T00:58:45.713856800Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Oct 9 00:58:45.714447 containerd[1448]: time="2024-10-09T00:58:45.713990560Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Oct 9 00:58:45.714447 containerd[1448]: time="2024-10-09T00:58:45.714011080Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Oct 9 00:58:45.714447 containerd[1448]: time="2024-10-09T00:58:45.714025440Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Oct 9 00:58:45.714447 containerd[1448]: time="2024-10-09T00:58:45.714038560Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Oct 9 00:58:45.714447 containerd[1448]: time="2024-10-09T00:58:45.714049840Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Oct 9 00:58:45.714447 containerd[1448]: time="2024-10-09T00:58:45.714060920Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Oct 9 00:58:45.714447 containerd[1448]: time="2024-10-09T00:58:45.714073280Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Oct 9 00:58:45.714447 containerd[1448]: time="2024-10-09T00:58:45.714085920Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Oct 9 00:58:45.714447 containerd[1448]: time="2024-10-09T00:58:45.714098760Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Oct 9 00:58:45.714447 containerd[1448]: time="2024-10-09T00:58:45.714109720Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Oct 9 00:58:45.714447 containerd[1448]: time="2024-10-09T00:58:45.714119200Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Oct 9 00:58:45.714447 containerd[1448]: time="2024-10-09T00:58:45.714137120Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Oct 9 00:58:45.714447 containerd[1448]: time="2024-10-09T00:58:45.714149360Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Oct 9 00:58:45.714740 containerd[1448]: time="2024-10-09T00:58:45.714160280Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Oct 9 00:58:45.714740 containerd[1448]: time="2024-10-09T00:58:45.714171320Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Oct 9 00:58:45.714740 containerd[1448]: time="2024-10-09T00:58:45.714182560Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Oct 9 00:58:45.714740 containerd[1448]: time="2024-10-09T00:58:45.714194240Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Oct 9 00:58:45.714740 containerd[1448]: time="2024-10-09T00:58:45.714205640Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Oct 9 00:58:45.714740 containerd[1448]: time="2024-10-09T00:58:45.714217880Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Oct 9 00:58:45.714740 containerd[1448]: time="2024-10-09T00:58:45.714228960Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Oct 9 00:58:45.714740 containerd[1448]: time="2024-10-09T00:58:45.714242840Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Oct 9 00:58:45.714740 containerd[1448]: time="2024-10-09T00:58:45.714253440Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Oct 9 00:58:45.714740 containerd[1448]: time="2024-10-09T00:58:45.714264760Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Oct 9 00:58:45.714740 containerd[1448]: time="2024-10-09T00:58:45.714276720Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Oct 9 00:58:45.714740 containerd[1448]: time="2024-10-09T00:58:45.714289720Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Oct 9 00:58:45.714740 containerd[1448]: time="2024-10-09T00:58:45.714311000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Oct 9 00:58:45.714740 containerd[1448]: time="2024-10-09T00:58:45.714324560Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Oct 9 00:58:45.714740 containerd[1448]: time="2024-10-09T00:58:45.714344880Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Oct 9 00:58:45.714707 systemd[1]: Starting issuegen.service - Generate /run/issue... Oct 9 00:58:45.715020 containerd[1448]: time="2024-10-09T00:58:45.714566400Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Oct 9 00:58:45.715020 containerd[1448]: time="2024-10-09T00:58:45.714596800Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Oct 9 00:58:45.715020 containerd[1448]: time="2024-10-09T00:58:45.714607920Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Oct 9 00:58:45.715020 containerd[1448]: time="2024-10-09T00:58:45.714624720Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Oct 9 00:58:45.715020 containerd[1448]: time="2024-10-09T00:58:45.714638560Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Oct 9 00:58:45.716129 containerd[1448]: time="2024-10-09T00:58:45.715676040Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Oct 9 00:58:45.716129 containerd[1448]: time="2024-10-09T00:58:45.715717280Z" level=info msg="NRI interface is disabled by configuration." Oct 9 00:58:45.716129 containerd[1448]: time="2024-10-09T00:58:45.715736160Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Oct 9 00:58:45.716478 containerd[1448]: time="2024-10-09T00:58:45.716266360Z" 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 PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false 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.8 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 EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Oct 9 00:58:45.716478 containerd[1448]: time="2024-10-09T00:58:45.716354080Z" level=info msg="Connect containerd service" Oct 9 00:58:45.716478 containerd[1448]: time="2024-10-09T00:58:45.716388040Z" level=info msg="using legacy CRI server" Oct 9 00:58:45.716478 containerd[1448]: time="2024-10-09T00:58:45.716395120Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Oct 9 00:58:45.716647 containerd[1448]: time="2024-10-09T00:58:45.716483680Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Oct 9 00:58:45.718863 containerd[1448]: time="2024-10-09T00:58:45.718829680Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Oct 9 00:58:45.719479 containerd[1448]: time="2024-10-09T00:58:45.719105240Z" level=info msg="Start subscribing containerd event" Oct 9 00:58:45.719479 containerd[1448]: time="2024-10-09T00:58:45.719149960Z" level=info msg="Start recovering state" Oct 9 00:58:45.719479 containerd[1448]: time="2024-10-09T00:58:45.719211200Z" level=info msg="Start event monitor" Oct 9 00:58:45.719479 containerd[1448]: time="2024-10-09T00:58:45.719233160Z" level=info msg="Start snapshots syncer" Oct 9 00:58:45.719479 containerd[1448]: time="2024-10-09T00:58:45.719245640Z" level=info msg="Start cni network conf syncer for default" Oct 9 00:58:45.719479 containerd[1448]: time="2024-10-09T00:58:45.719253360Z" level=info msg="Start streaming server" Oct 9 00:58:45.719479 containerd[1448]: time="2024-10-09T00:58:45.719356560Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Oct 9 00:58:45.719479 containerd[1448]: time="2024-10-09T00:58:45.719393920Z" level=info msg=serving... address=/run/containerd/containerd.sock Oct 9 00:58:45.719542 systemd[1]: Started containerd.service - containerd container runtime. Oct 9 00:58:45.720745 containerd[1448]: time="2024-10-09T00:58:45.719456120Z" level=info msg="containerd successfully booted in 0.034617s" Oct 9 00:58:45.720731 systemd[1]: issuegen.service: Deactivated successfully. Oct 9 00:58:45.720879 systemd[1]: Finished issuegen.service - Generate /run/issue. Oct 9 00:58:45.723493 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Oct 9 00:58:45.733656 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Oct 9 00:58:45.736474 systemd[1]: Started getty@tty1.service - Getty on tty1. Oct 9 00:58:45.738483 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Oct 9 00:58:45.739460 systemd[1]: Reached target getty.target - Login Prompts. Oct 9 00:58:47.296603 systemd-networkd[1386]: eth0: Gained IPv6LL Oct 9 00:58:47.301487 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Oct 9 00:58:47.305798 systemd[1]: Reached target network-online.target - Network is Online. Oct 9 00:58:47.324856 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Oct 9 00:58:47.327526 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 9 00:58:47.329375 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Oct 9 00:58:47.344714 systemd[1]: coreos-metadata.service: Deactivated successfully. Oct 9 00:58:47.344911 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Oct 9 00:58:47.346171 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Oct 9 00:58:47.349789 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Oct 9 00:58:47.853186 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Oct 9 00:58:47.854460 systemd[1]: Reached target multi-user.target - Multi-User System. Oct 9 00:58:47.855444 systemd[1]: Startup finished in 525ms (kernel) + 4.192s (initrd) + 4.000s (userspace) = 8.718s. Oct 9 00:58:47.857698 (kubelet)[1529]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Oct 9 00:58:48.274925 kubelet[1529]: E1009 00:58:48.274786 1529 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Oct 9 00:58:48.276575 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Oct 9 00:58:48.276707 systemd[1]: kubelet.service: Failed with result 'exit-code'. Oct 9 00:58:52.252017 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Oct 9 00:58:52.253053 systemd[1]: Started sshd@0-10.0.0.115:22-10.0.0.1:50728.service - OpenSSH per-connection server daemon (10.0.0.1:50728). Oct 9 00:58:52.300464 sshd[1542]: Accepted publickey for core from 10.0.0.1 port 50728 ssh2: RSA SHA256:nRWADPtu01909VH1n4/VEkamAOeuD1sYuu1knWF4jhs Oct 9 00:58:52.302056 sshd[1542]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 9 00:58:52.310713 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Oct 9 00:58:52.320765 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Oct 9 00:58:52.322632 systemd-logind[1435]: New session 1 of user core. Oct 9 00:58:52.330454 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Oct 9 00:58:52.332387 systemd[1]: Starting user@500.service - User Manager for UID 500... Oct 9 00:58:52.338003 (systemd)[1546]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 9 00:58:52.408067 systemd[1546]: Queued start job for default target default.target. Oct 9 00:58:52.419298 systemd[1546]: Created slice app.slice - User Application Slice. Oct 9 00:58:52.419343 systemd[1546]: Reached target paths.target - Paths. Oct 9 00:58:52.419354 systemd[1546]: Reached target timers.target - Timers. Oct 9 00:58:52.420561 systemd[1546]: Starting dbus.socket - D-Bus User Message Bus Socket... Oct 9 00:58:52.429722 systemd[1546]: Listening on dbus.socket - D-Bus User Message Bus Socket. Oct 9 00:58:52.429796 systemd[1546]: Reached target sockets.target - Sockets. Oct 9 00:58:52.429809 systemd[1546]: Reached target basic.target - Basic System. Oct 9 00:58:52.429845 systemd[1546]: Reached target default.target - Main User Target. Oct 9 00:58:52.429871 systemd[1546]: Startup finished in 87ms. Oct 9 00:58:52.430101 systemd[1]: Started user@500.service - User Manager for UID 500. Oct 9 00:58:52.431320 systemd[1]: Started session-1.scope - Session 1 of User core. Oct 9 00:58:52.504772 systemd[1]: Started sshd@1-10.0.0.115:22-10.0.0.1:38172.service - OpenSSH per-connection server daemon (10.0.0.1:38172). Oct 9 00:58:52.533669 sshd[1557]: Accepted publickey for core from 10.0.0.1 port 38172 ssh2: RSA SHA256:nRWADPtu01909VH1n4/VEkamAOeuD1sYuu1knWF4jhs Oct 9 00:58:52.534794 sshd[1557]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 9 00:58:52.538100 systemd-logind[1435]: New session 2 of user core. Oct 9 00:58:52.546614 systemd[1]: Started session-2.scope - Session 2 of User core. Oct 9 00:58:52.598019 sshd[1557]: pam_unix(sshd:session): session closed for user core Oct 9 00:58:52.613579 systemd[1]: sshd@1-10.0.0.115:22-10.0.0.1:38172.service: Deactivated successfully. Oct 9 00:58:52.614839 systemd[1]: session-2.scope: Deactivated successfully. Oct 9 00:58:52.617434 systemd-logind[1435]: Session 2 logged out. Waiting for processes to exit. Oct 9 00:58:52.618444 systemd[1]: Started sshd@2-10.0.0.115:22-10.0.0.1:38180.service - OpenSSH per-connection server daemon (10.0.0.1:38180). Oct 9 00:58:52.619171 systemd-logind[1435]: Removed session 2. Oct 9 00:58:52.651063 sshd[1564]: Accepted publickey for core from 10.0.0.1 port 38180 ssh2: RSA SHA256:nRWADPtu01909VH1n4/VEkamAOeuD1sYuu1knWF4jhs Oct 9 00:58:52.652129 sshd[1564]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 9 00:58:52.655576 systemd-logind[1435]: New session 3 of user core. Oct 9 00:58:52.669596 systemd[1]: Started session-3.scope - Session 3 of User core. Oct 9 00:58:52.716662 sshd[1564]: pam_unix(sshd:session): session closed for user core Oct 9 00:58:52.728507 systemd[1]: sshd@2-10.0.0.115:22-10.0.0.1:38180.service: Deactivated successfully. Oct 9 00:58:52.730633 systemd[1]: session-3.scope: Deactivated successfully. Oct 9 00:58:52.731910 systemd-logind[1435]: Session 3 logged out. Waiting for processes to exit. Oct 9 00:58:52.732666 systemd[1]: Started sshd@3-10.0.0.115:22-10.0.0.1:38192.service - OpenSSH per-connection server daemon (10.0.0.1:38192). Oct 9 00:58:52.733383 systemd-logind[1435]: Removed session 3. Oct 9 00:58:52.765368 sshd[1572]: Accepted publickey for core from 10.0.0.1 port 38192 ssh2: RSA SHA256:nRWADPtu01909VH1n4/VEkamAOeuD1sYuu1knWF4jhs Oct 9 00:58:52.766395 sshd[1572]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 9 00:58:52.769999 systemd-logind[1435]: New session 4 of user core. Oct 9 00:58:52.777544 systemd[1]: Started session-4.scope - Session 4 of User core. Oct 9 00:58:52.827930 sshd[1572]: pam_unix(sshd:session): session closed for user core Oct 9 00:58:52.842554 systemd[1]: sshd@3-10.0.0.115:22-10.0.0.1:38192.service: Deactivated successfully. Oct 9 00:58:52.843907 systemd[1]: session-4.scope: Deactivated successfully. Oct 9 00:58:52.845106 systemd-logind[1435]: Session 4 logged out. Waiting for processes to exit. Oct 9 00:58:52.846201 systemd[1]: Started sshd@4-10.0.0.115:22-10.0.0.1:38206.service - OpenSSH per-connection server daemon (10.0.0.1:38206). Oct 9 00:58:52.847271 systemd-logind[1435]: Removed session 4. Oct 9 00:58:52.878515 sshd[1579]: Accepted publickey for core from 10.0.0.1 port 38206 ssh2: RSA SHA256:nRWADPtu01909VH1n4/VEkamAOeuD1sYuu1knWF4jhs Oct 9 00:58:52.879609 sshd[1579]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 9 00:58:52.883103 systemd-logind[1435]: New session 5 of user core. Oct 9 00:58:52.903544 systemd[1]: Started session-5.scope - Session 5 of User core. Oct 9 00:58:52.963773 sudo[1582]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Oct 9 00:58:52.964035 sudo[1582]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 9 00:58:52.982787 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Oct 9 00:58:52.996254 systemd[1]: coreos-metadata.service: Deactivated successfully. Oct 9 00:58:52.996468 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Oct 9 00:58:53.385719 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Oct 9 00:58:53.394694 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 9 00:58:53.412778 systemd[1]: Reloading requested from client PID 1622 ('systemctl') (unit session-5.scope)... Oct 9 00:58:53.412795 systemd[1]: Reloading... Oct 9 00:58:53.485638 zram_generator::config[1663]: No configuration found. Oct 9 00:58:53.655213 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Oct 9 00:58:53.707487 systemd[1]: Reloading finished in 294 ms. Oct 9 00:58:53.762651 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Oct 9 00:58:53.762732 systemd[1]: kubelet.service: Failed with result 'signal'. Oct 9 00:58:53.762921 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Oct 9 00:58:53.764850 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 9 00:58:53.853209 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Oct 9 00:58:53.856681 (kubelet)[1706]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Oct 9 00:58:53.889285 kubelet[1706]: Flag --container-runtime-endpoint has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Oct 9 00:58:53.889285 kubelet[1706]: 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. Oct 9 00:58:53.889285 kubelet[1706]: Flag --volume-plugin-dir has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Oct 9 00:58:53.889567 kubelet[1706]: I1009 00:58:53.889404 1706 server.go:206] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Oct 9 00:58:55.498360 kubelet[1706]: I1009 00:58:55.496676 1706 server.go:486] "Kubelet version" kubeletVersion="v1.31.0" Oct 9 00:58:55.498360 kubelet[1706]: I1009 00:58:55.496713 1706 server.go:488] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Oct 9 00:58:55.498360 kubelet[1706]: I1009 00:58:55.496958 1706 server.go:929] "Client rotation is on, will bootstrap in background" Oct 9 00:58:55.537231 kubelet[1706]: I1009 00:58:55.537187 1706 dynamic_cafile_content.go:160] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Oct 9 00:58:55.547032 kubelet[1706]: E1009 00:58:55.546987 1706 log.go:32] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" Oct 9 00:58:55.547032 kubelet[1706]: I1009 00:58:55.547027 1706 server.go:1403] "CRI implementation should be updated to support RuntimeConfig when KubeletCgroupDriverFromCRI feature gate has been enabled. Falling back to using cgroupDriver from kubelet config." Oct 9 00:58:55.551312 kubelet[1706]: I1009 00:58:55.551288 1706 server.go:744] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Oct 9 00:58:55.552389 kubelet[1706]: I1009 00:58:55.552331 1706 swap_util.go:113] "Swap is on" /proc/swaps contents="Filename\t\t\t\tType\t\tSize\t\tUsed\t\tPriority" Oct 9 00:58:55.552576 kubelet[1706]: I1009 00:58:55.552540 1706 container_manager_linux.go:264] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Oct 9 00:58:55.552866 kubelet[1706]: I1009 00:58:55.552569 1706 container_manager_linux.go:269] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.115","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":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Oct 9 00:58:55.552946 kubelet[1706]: I1009 00:58:55.552877 1706 topology_manager.go:138] "Creating topology manager with none policy" Oct 9 00:58:55.552946 kubelet[1706]: I1009 00:58:55.552890 1706 container_manager_linux.go:300] "Creating device plugin manager" Oct 9 00:58:55.553112 kubelet[1706]: I1009 00:58:55.553069 1706 state_mem.go:36] "Initialized new in-memory state store" Oct 9 00:58:55.557490 kubelet[1706]: I1009 00:58:55.557404 1706 kubelet.go:408] "Attempting to sync node with API server" Oct 9 00:58:55.557490 kubelet[1706]: I1009 00:58:55.557486 1706 kubelet.go:303] "Adding static pod path" path="/etc/kubernetes/manifests" Oct 9 00:58:55.557599 kubelet[1706]: I1009 00:58:55.557577 1706 kubelet.go:314] "Adding apiserver pod source" Oct 9 00:58:55.557599 kubelet[1706]: I1009 00:58:55.557589 1706 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Oct 9 00:58:55.558046 kubelet[1706]: E1009 00:58:55.557797 1706 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 00:58:55.558046 kubelet[1706]: E1009 00:58:55.557834 1706 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 00:58:55.560238 kubelet[1706]: I1009 00:58:55.560213 1706 kuberuntime_manager.go:262] "Container runtime initialized" containerRuntime="containerd" version="v1.7.22" apiVersion="v1" Oct 9 00:58:55.561830 kubelet[1706]: I1009 00:58:55.561815 1706 kubelet.go:837] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Oct 9 00:58:55.562079 kubelet[1706]: W1009 00:58:55.562050 1706 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Oct 9 00:58:55.562796 kubelet[1706]: I1009 00:58:55.562766 1706 server.go:1269] "Started kubelet" Oct 9 00:58:55.564045 kubelet[1706]: I1009 00:58:55.563566 1706 server.go:163] "Starting to listen" address="0.0.0.0" port=10250 Oct 9 00:58:55.566743 kubelet[1706]: I1009 00:58:55.566688 1706 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Oct 9 00:58:55.567442 kubelet[1706]: I1009 00:58:55.566995 1706 server.go:236] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Oct 9 00:58:55.568827 kubelet[1706]: I1009 00:58:55.568499 1706 server.go:460] "Adding debug handlers to kubelet server" Oct 9 00:58:55.571336 kubelet[1706]: I1009 00:58:55.570906 1706 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Oct 9 00:58:55.572822 kubelet[1706]: I1009 00:58:55.572800 1706 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Oct 9 00:58:55.573783 kubelet[1706]: E1009 00:58:55.573356 1706 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.115\" not found" Oct 9 00:58:55.573783 kubelet[1706]: I1009 00:58:55.573558 1706 volume_manager.go:289] "Starting Kubelet Volume Manager" Oct 9 00:58:55.573898 kubelet[1706]: I1009 00:58:55.573876 1706 reconciler.go:26] "Reconciler: start to sync state" Oct 9 00:58:55.574401 kubelet[1706]: I1009 00:58:55.574381 1706 desired_state_of_world_populator.go:146] "Desired state populator starts to run" Oct 9 00:58:55.575595 kubelet[1706]: I1009 00:58:55.575571 1706 factory.go:221] Registration of the systemd container factory successfully Oct 9 00:58:55.575714 kubelet[1706]: I1009 00:58:55.575674 1706 factory.go:219] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory Oct 9 00:58:55.577706 kubelet[1706]: E1009 00:58:55.577599 1706 kubelet.go:1478] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Oct 9 00:58:55.577776 kubelet[1706]: I1009 00:58:55.577724 1706 factory.go:221] Registration of the containerd container factory successfully Oct 9 00:58:55.586128 kubelet[1706]: E1009 00:58:55.586082 1706 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.115\" not found" node="10.0.0.115" Oct 9 00:58:55.586885 kubelet[1706]: I1009 00:58:55.586866 1706 cpu_manager.go:214] "Starting CPU manager" policy="none" Oct 9 00:58:55.586922 kubelet[1706]: I1009 00:58:55.586882 1706 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" Oct 9 00:58:55.586922 kubelet[1706]: I1009 00:58:55.586915 1706 state_mem.go:36] "Initialized new in-memory state store" Oct 9 00:58:55.655153 kubelet[1706]: I1009 00:58:55.655114 1706 policy_none.go:49] "None policy: Start" Oct 9 00:58:55.655910 kubelet[1706]: I1009 00:58:55.655887 1706 memory_manager.go:170] "Starting memorymanager" policy="None" Oct 9 00:58:55.655983 kubelet[1706]: I1009 00:58:55.655923 1706 state_mem.go:35] "Initializing new in-memory state store" Oct 9 00:58:55.662152 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Oct 9 00:58:55.672656 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Oct 9 00:58:55.673632 kubelet[1706]: E1009 00:58:55.673611 1706 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.115\" not found" Oct 9 00:58:55.676221 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Oct 9 00:58:55.683000 kubelet[1706]: I1009 00:58:55.682960 1706 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Oct 9 00:58:55.684051 kubelet[1706]: I1009 00:58:55.684033 1706 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Oct 9 00:58:55.684458 kubelet[1706]: I1009 00:58:55.684166 1706 status_manager.go:217] "Starting to sync pod status with apiserver" Oct 9 00:58:55.684458 kubelet[1706]: I1009 00:58:55.684197 1706 kubelet.go:2321] "Starting kubelet main sync loop" Oct 9 00:58:55.684458 kubelet[1706]: E1009 00:58:55.684241 1706 kubelet.go:2345] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" Oct 9 00:58:55.685746 kubelet[1706]: I1009 00:58:55.685713 1706 manager.go:510] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Oct 9 00:58:55.686058 kubelet[1706]: I1009 00:58:55.685906 1706 eviction_manager.go:189] "Eviction manager: starting control loop" Oct 9 00:58:55.686058 kubelet[1706]: I1009 00:58:55.685923 1706 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Oct 9 00:58:55.686806 kubelet[1706]: I1009 00:58:55.686779 1706 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Oct 9 00:58:55.689208 kubelet[1706]: E1009 00:58:55.689183 1706 eviction_manager.go:285] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.115\" not found" Oct 9 00:58:55.787378 kubelet[1706]: I1009 00:58:55.787280 1706 kubelet_node_status.go:72] "Attempting to register node" node="10.0.0.115" Oct 9 00:58:55.795017 kubelet[1706]: I1009 00:58:55.794945 1706 kubelet_node_status.go:75] "Successfully registered node" node="10.0.0.115" Oct 9 00:58:55.795017 kubelet[1706]: E1009 00:58:55.794978 1706 kubelet_node_status.go:535] "Error updating node status, will retry" err="error getting node \"10.0.0.115\": node \"10.0.0.115\" not found" Oct 9 00:58:55.799703 kubelet[1706]: I1009 00:58:55.799672 1706 kuberuntime_manager.go:1633] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Oct 9 00:58:55.800096 containerd[1448]: time="2024-10-09T00:58:55.800054330Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Oct 9 00:58:55.800813 kubelet[1706]: I1009 00:58:55.800793 1706 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Oct 9 00:58:55.880641 sudo[1582]: pam_unix(sudo:session): session closed for user root Oct 9 00:58:55.882744 sshd[1579]: pam_unix(sshd:session): session closed for user core Oct 9 00:58:55.885475 systemd[1]: sshd@4-10.0.0.115:22-10.0.0.1:38206.service: Deactivated successfully. Oct 9 00:58:55.887120 systemd[1]: session-5.scope: Deactivated successfully. Oct 9 00:58:55.888448 systemd-logind[1435]: Session 5 logged out. Waiting for processes to exit. Oct 9 00:58:55.889422 systemd-logind[1435]: Removed session 5. Oct 9 00:58:56.499836 kubelet[1706]: I1009 00:58:56.499776 1706 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Oct 9 00:58:56.500214 kubelet[1706]: W1009 00:58:56.499978 1706 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.RuntimeClass ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Oct 9 00:58:56.500214 kubelet[1706]: W1009 00:58:56.499991 1706 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.CSIDriver ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Oct 9 00:58:56.500214 kubelet[1706]: W1009 00:58:56.500014 1706 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.Service ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Oct 9 00:58:56.557961 kubelet[1706]: I1009 00:58:56.557893 1706 apiserver.go:52] "Watching apiserver" Oct 9 00:58:56.557961 kubelet[1706]: E1009 00:58:56.557931 1706 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 00:58:56.573686 systemd[1]: Created slice kubepods-besteffort-poda2ba8fcc_fb38_41b2_87a9_2c6af7adb92f.slice - libcontainer container kubepods-besteffort-poda2ba8fcc_fb38_41b2_87a9_2c6af7adb92f.slice. Oct 9 00:58:56.577427 kubelet[1706]: I1009 00:58:56.575590 1706 desired_state_of_world_populator.go:154] "Finished populating initial desired state of world" Oct 9 00:58:56.580799 kubelet[1706]: I1009 00:58:56.580766 1706 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-8fz9z\" (UniqueName: \"kubernetes.io/projected/f0d54aa3-8031-4d10-b160-eef04d5727a7-kube-api-access-8fz9z\") pod \"kube-flannel-ds-dj7md\" (UID: \"f0d54aa3-8031-4d10-b160-eef04d5727a7\") " pod="kube-flannel/kube-flannel-ds-dj7md" Oct 9 00:58:56.580859 kubelet[1706]: I1009 00:58:56.580805 1706 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/a2ba8fcc-fb38-41b2-87a9-2c6af7adb92f-xtables-lock\") pod \"kube-proxy-q5ztx\" (UID: \"a2ba8fcc-fb38-41b2-87a9-2c6af7adb92f\") " pod="kube-system/kube-proxy-q5ztx" Oct 9 00:58:56.580859 kubelet[1706]: I1009 00:58:56.580824 1706 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/a2ba8fcc-fb38-41b2-87a9-2c6af7adb92f-lib-modules\") pod \"kube-proxy-q5ztx\" (UID: \"a2ba8fcc-fb38-41b2-87a9-2c6af7adb92f\") " pod="kube-system/kube-proxy-q5ztx" Oct 9 00:58:56.580859 kubelet[1706]: I1009 00:58:56.580840 1706 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-x878l\" (UniqueName: \"kubernetes.io/projected/a2ba8fcc-fb38-41b2-87a9-2c6af7adb92f-kube-api-access-x878l\") pod \"kube-proxy-q5ztx\" (UID: \"a2ba8fcc-fb38-41b2-87a9-2c6af7adb92f\") " pod="kube-system/kube-proxy-q5ztx" Oct 9 00:58:56.580859 kubelet[1706]: I1009 00:58:56.580854 1706 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/f0d54aa3-8031-4d10-b160-eef04d5727a7-run\") pod \"kube-flannel-ds-dj7md\" (UID: \"f0d54aa3-8031-4d10-b160-eef04d5727a7\") " pod="kube-flannel/kube-flannel-ds-dj7md" Oct 9 00:58:56.580961 kubelet[1706]: I1009 00:58:56.580868 1706 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/f0d54aa3-8031-4d10-b160-eef04d5727a7-cni-plugin\") pod \"kube-flannel-ds-dj7md\" (UID: \"f0d54aa3-8031-4d10-b160-eef04d5727a7\") " pod="kube-flannel/kube-flannel-ds-dj7md" Oct 9 00:58:56.580961 kubelet[1706]: I1009 00:58:56.580882 1706 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/f0d54aa3-8031-4d10-b160-eef04d5727a7-cni\") pod \"kube-flannel-ds-dj7md\" (UID: \"f0d54aa3-8031-4d10-b160-eef04d5727a7\") " pod="kube-flannel/kube-flannel-ds-dj7md" Oct 9 00:58:56.580961 kubelet[1706]: I1009 00:58:56.580895 1706 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/f0d54aa3-8031-4d10-b160-eef04d5727a7-flannel-cfg\") pod \"kube-flannel-ds-dj7md\" (UID: \"f0d54aa3-8031-4d10-b160-eef04d5727a7\") " pod="kube-flannel/kube-flannel-ds-dj7md" Oct 9 00:58:56.580961 kubelet[1706]: I1009 00:58:56.580910 1706 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/f0d54aa3-8031-4d10-b160-eef04d5727a7-xtables-lock\") pod \"kube-flannel-ds-dj7md\" (UID: \"f0d54aa3-8031-4d10-b160-eef04d5727a7\") " pod="kube-flannel/kube-flannel-ds-dj7md" Oct 9 00:58:56.580961 kubelet[1706]: I1009 00:58:56.580925 1706 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/a2ba8fcc-fb38-41b2-87a9-2c6af7adb92f-kube-proxy\") pod \"kube-proxy-q5ztx\" (UID: \"a2ba8fcc-fb38-41b2-87a9-2c6af7adb92f\") " pod="kube-system/kube-proxy-q5ztx" Oct 9 00:58:56.585744 systemd[1]: Created slice kubepods-burstable-podf0d54aa3_8031_4d10_b160_eef04d5727a7.slice - libcontainer container kubepods-burstable-podf0d54aa3_8031_4d10_b160_eef04d5727a7.slice. Oct 9 00:58:56.884364 kubelet[1706]: E1009 00:58:56.884033 1706 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Oct 9 00:58:56.884695 containerd[1448]: time="2024-10-09T00:58:56.884662134Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-q5ztx,Uid:a2ba8fcc-fb38-41b2-87a9-2c6af7adb92f,Namespace:kube-system,Attempt:0,}" Oct 9 00:58:56.905153 kubelet[1706]: E1009 00:58:56.905126 1706 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Oct 9 00:58:56.905619 containerd[1448]: time="2024-10-09T00:58:56.905588300Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-dj7md,Uid:f0d54aa3-8031-4d10-b160-eef04d5727a7,Namespace:kube-flannel,Attempt:0,}" Oct 9 00:58:57.401490 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount296037831.mount: Deactivated successfully. Oct 9 00:58:57.408345 containerd[1448]: time="2024-10-09T00:58:57.408299656Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Oct 9 00:58:57.409182 containerd[1448]: time="2024-10-09T00:58:57.409150282Z" level=info msg="ImageUpdate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Oct 9 00:58:57.409662 containerd[1448]: time="2024-10-09T00:58:57.409628653Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=269175" Oct 9 00:58:57.410267 containerd[1448]: time="2024-10-09T00:58:57.410233695Z" level=info msg="ImageCreate event name:\"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Oct 9 00:58:57.412563 containerd[1448]: time="2024-10-09T00:58:57.412533591Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Oct 9 00:58:57.413802 containerd[1448]: time="2024-10-09T00:58:57.413770113Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Oct 9 00:58:57.415876 containerd[1448]: time="2024-10-09T00:58:57.415847063Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.8\" with image id \"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\", repo tag \"registry.k8s.io/pause:3.8\", repo digest \"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\", size \"268403\" in 531.109774ms" Oct 9 00:58:57.416524 containerd[1448]: time="2024-10-09T00:58:57.416227959Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.8\" with image id \"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\", repo tag \"registry.k8s.io/pause:3.8\", repo digest \"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\", size \"268403\" in 510.563624ms" Oct 9 00:58:57.526032 containerd[1448]: time="2024-10-09T00:58:57.525842292Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 9 00:58:57.526032 containerd[1448]: time="2024-10-09T00:58:57.525944806Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 9 00:58:57.526032 containerd[1448]: time="2024-10-09T00:58:57.525972964Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 9 00:58:57.526189 containerd[1448]: time="2024-10-09T00:58:57.526086557Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 9 00:58:57.531482 containerd[1448]: time="2024-10-09T00:58:57.529244159Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 9 00:58:57.531482 containerd[1448]: time="2024-10-09T00:58:57.531450701Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 9 00:58:57.531482 containerd[1448]: time="2024-10-09T00:58:57.531466140Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 9 00:58:57.531731 containerd[1448]: time="2024-10-09T00:58:57.531556374Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 9 00:58:57.558926 kubelet[1706]: E1009 00:58:57.558045 1706 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 00:58:57.620608 systemd[1]: Started cri-containerd-333c7d0b09e738bf682d54f3f7a3a5c49028aae9b681ee5474c26363e1b6fdea.scope - libcontainer container 333c7d0b09e738bf682d54f3f7a3a5c49028aae9b681ee5474c26363e1b6fdea. Oct 9 00:58:57.622200 systemd[1]: Started cri-containerd-90cf20f1765308eba91773b30d904d4bee937730c639aba5a02a11c2744e0321.scope - libcontainer container 90cf20f1765308eba91773b30d904d4bee937730c639aba5a02a11c2744e0321. Oct 9 00:58:57.646243 containerd[1448]: time="2024-10-09T00:58:57.646198192Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-q5ztx,Uid:a2ba8fcc-fb38-41b2-87a9-2c6af7adb92f,Namespace:kube-system,Attempt:0,} returns sandbox id \"333c7d0b09e738bf682d54f3f7a3a5c49028aae9b681ee5474c26363e1b6fdea\"" Oct 9 00:58:57.647246 kubelet[1706]: E1009 00:58:57.647221 1706 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Oct 9 00:58:57.649263 containerd[1448]: time="2024-10-09T00:58:57.649199404Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.0\"" Oct 9 00:58:57.654422 containerd[1448]: time="2024-10-09T00:58:57.654317004Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-dj7md,Uid:f0d54aa3-8031-4d10-b160-eef04d5727a7,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"90cf20f1765308eba91773b30d904d4bee937730c639aba5a02a11c2744e0321\"" Oct 9 00:58:57.654987 kubelet[1706]: E1009 00:58:57.654961 1706 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Oct 9 00:58:58.559231 kubelet[1706]: E1009 00:58:58.559135 1706 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 00:58:58.731166 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4269603605.mount: Deactivated successfully. Oct 9 00:58:58.947164 containerd[1448]: time="2024-10-09T00:58:58.947045032Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.31.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 9 00:58:58.947847 containerd[1448]: time="2024-10-09T00:58:58.947814746Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.31.0: active requests=0, bytes read=26753317" Oct 9 00:58:58.948841 containerd[1448]: time="2024-10-09T00:58:58.948795806Z" level=info msg="ImageCreate event name:\"sha256:71d55d66fd4eec8986225089a135fadd96bc6624d987096808772ce1e1924d89\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 9 00:58:58.950764 containerd[1448]: time="2024-10-09T00:58:58.950739328Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:c727efb1c6f15a68060bf7f207f5c7a765355b7e3340c513e582ec819c5cd2fe\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 9 00:58:58.952198 containerd[1448]: time="2024-10-09T00:58:58.952168361Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.31.0\" with image id \"sha256:71d55d66fd4eec8986225089a135fadd96bc6624d987096808772ce1e1924d89\", repo tag \"registry.k8s.io/kube-proxy:v1.31.0\", repo digest \"registry.k8s.io/kube-proxy@sha256:c727efb1c6f15a68060bf7f207f5c7a765355b7e3340c513e582ec819c5cd2fe\", size \"26752334\" in 1.30292012s" Oct 9 00:58:58.952246 containerd[1448]: time="2024-10-09T00:58:58.952199000Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.0\" returns image reference \"sha256:71d55d66fd4eec8986225089a135fadd96bc6624d987096808772ce1e1924d89\"" Oct 9 00:58:58.953356 containerd[1448]: time="2024-10-09T00:58:58.953120624Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Oct 9 00:58:58.954823 containerd[1448]: time="2024-10-09T00:58:58.954784963Z" level=info msg="CreateContainer within sandbox \"333c7d0b09e738bf682d54f3f7a3a5c49028aae9b681ee5474c26363e1b6fdea\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Oct 9 00:58:58.972886 containerd[1448]: time="2024-10-09T00:58:58.972845907Z" level=info msg="CreateContainer within sandbox \"333c7d0b09e738bf682d54f3f7a3a5c49028aae9b681ee5474c26363e1b6fdea\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"926a73b62785461474eebcd7df9f8c56e26695270363467debc19906a998ef1b\"" Oct 9 00:58:58.973519 containerd[1448]: time="2024-10-09T00:58:58.973488068Z" level=info msg="StartContainer for \"926a73b62785461474eebcd7df9f8c56e26695270363467debc19906a998ef1b\"" Oct 9 00:58:58.994579 systemd[1]: Started cri-containerd-926a73b62785461474eebcd7df9f8c56e26695270363467debc19906a998ef1b.scope - libcontainer container 926a73b62785461474eebcd7df9f8c56e26695270363467debc19906a998ef1b. Oct 9 00:58:59.016061 containerd[1448]: time="2024-10-09T00:58:59.016006877Z" level=info msg="StartContainer for \"926a73b62785461474eebcd7df9f8c56e26695270363467debc19906a998ef1b\" returns successfully" Oct 9 00:58:59.559381 kubelet[1706]: E1009 00:58:59.559326 1706 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 00:58:59.698098 kubelet[1706]: E1009 00:58:59.698072 1706 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Oct 9 00:58:59.707193 kubelet[1706]: I1009 00:58:59.707092 1706 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-q5ztx" podStartSLOduration=3.4027140559999998 podStartE2EDuration="4.707079047s" podCreationTimestamp="2024-10-09 00:58:55 +0000 UTC" firstStartedPulling="2024-10-09 00:58:57.648621081 +0000 UTC m=+3.788806905" lastFinishedPulling="2024-10-09 00:58:58.952986072 +0000 UTC m=+5.093171896" observedRunningTime="2024-10-09 00:58:59.706956615 +0000 UTC m=+5.847142399" watchObservedRunningTime="2024-10-09 00:58:59.707079047 +0000 UTC m=+5.847264871" Oct 9 00:59:00.039983 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2312038688.mount: Deactivated successfully. Oct 9 00:59:00.064987 containerd[1448]: time="2024-10-09T00:59:00.064796333Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 9 00:59:00.065801 containerd[1448]: time="2024-10-09T00:59:00.065557890Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3673532" Oct 9 00:59:00.066515 containerd[1448]: time="2024-10-09T00:59:00.066479117Z" level=info msg="ImageCreate event name:\"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 9 00:59:00.068977 containerd[1448]: time="2024-10-09T00:59:00.068942737Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 9 00:59:00.069838 containerd[1448]: time="2024-10-09T00:59:00.069812447Z" level=info msg="Pulled image \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" with image id \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\", repo tag \"docker.io/flannel/flannel-cni-plugin:v1.1.2\", repo digest \"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\", size \"3662650\" in 1.116660306s" Oct 9 00:59:00.069931 containerd[1448]: time="2024-10-09T00:59:00.069914841Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" Oct 9 00:59:00.072423 containerd[1448]: time="2024-10-09T00:59:00.072380541Z" level=info msg="CreateContainer within sandbox \"90cf20f1765308eba91773b30d904d4bee937730c639aba5a02a11c2744e0321\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Oct 9 00:59:00.083098 containerd[1448]: time="2024-10-09T00:59:00.083026575Z" level=info msg="CreateContainer within sandbox \"90cf20f1765308eba91773b30d904d4bee937730c639aba5a02a11c2744e0321\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"2ffc92b921e522d7bfbe4468c5bea9166ed983812f94d8fa11e6a3642377c0f2\"" Oct 9 00:59:00.083742 containerd[1448]: time="2024-10-09T00:59:00.083500308Z" level=info msg="StartContainer for \"2ffc92b921e522d7bfbe4468c5bea9166ed983812f94d8fa11e6a3642377c0f2\"" Oct 9 00:59:00.106561 systemd[1]: Started cri-containerd-2ffc92b921e522d7bfbe4468c5bea9166ed983812f94d8fa11e6a3642377c0f2.scope - libcontainer container 2ffc92b921e522d7bfbe4468c5bea9166ed983812f94d8fa11e6a3642377c0f2. Oct 9 00:59:00.131605 containerd[1448]: time="2024-10-09T00:59:00.131566730Z" level=info msg="StartContainer for \"2ffc92b921e522d7bfbe4468c5bea9166ed983812f94d8fa11e6a3642377c0f2\" returns successfully" Oct 9 00:59:00.133397 systemd[1]: cri-containerd-2ffc92b921e522d7bfbe4468c5bea9166ed983812f94d8fa11e6a3642377c0f2.scope: Deactivated successfully. Oct 9 00:59:00.274432 containerd[1448]: time="2024-10-09T00:59:00.274360717Z" level=info msg="shim disconnected" id=2ffc92b921e522d7bfbe4468c5bea9166ed983812f94d8fa11e6a3642377c0f2 namespace=k8s.io Oct 9 00:59:00.274432 containerd[1448]: time="2024-10-09T00:59:00.274437793Z" level=warning msg="cleaning up after shim disconnected" id=2ffc92b921e522d7bfbe4468c5bea9166ed983812f94d8fa11e6a3642377c0f2 namespace=k8s.io Oct 9 00:59:00.274652 containerd[1448]: time="2024-10-09T00:59:00.274448992Z" level=info msg="cleaning up dead shim" namespace=k8s.io Oct 9 00:59:00.559785 kubelet[1706]: E1009 00:59:00.559733 1706 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 00:59:00.701253 kubelet[1706]: E1009 00:59:00.701213 1706 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Oct 9 00:59:00.701376 kubelet[1706]: E1009 00:59:00.701278 1706 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Oct 9 00:59:00.702120 containerd[1448]: time="2024-10-09T00:59:00.702087356Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Oct 9 00:59:00.992306 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-2ffc92b921e522d7bfbe4468c5bea9166ed983812f94d8fa11e6a3642377c0f2-rootfs.mount: Deactivated successfully. Oct 9 00:59:01.560223 kubelet[1706]: E1009 00:59:01.560174 1706 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 00:59:01.901355 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2905232643.mount: Deactivated successfully. Oct 9 00:59:02.348870 containerd[1448]: time="2024-10-09T00:59:02.348530180Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 9 00:59:02.349560 containerd[1448]: time="2024-10-09T00:59:02.349035833Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26874261" Oct 9 00:59:02.350183 containerd[1448]: time="2024-10-09T00:59:02.350149294Z" level=info msg="ImageCreate event name:\"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 9 00:59:02.353961 containerd[1448]: time="2024-10-09T00:59:02.353927812Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 9 00:59:02.355041 containerd[1448]: time="2024-10-09T00:59:02.354953877Z" level=info msg="Pulled image \"docker.io/flannel/flannel:v0.22.0\" with image id \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\", repo tag \"docker.io/flannel/flannel:v0.22.0\", repo digest \"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\", size \"26863435\" in 1.652827163s" Oct 9 00:59:02.355041 containerd[1448]: time="2024-10-09T00:59:02.354987915Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" Oct 9 00:59:02.357098 containerd[1448]: time="2024-10-09T00:59:02.356982209Z" level=info msg="CreateContainer within sandbox \"90cf20f1765308eba91773b30d904d4bee937730c639aba5a02a11c2744e0321\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Oct 9 00:59:02.365844 containerd[1448]: time="2024-10-09T00:59:02.365802417Z" level=info msg="CreateContainer within sandbox \"90cf20f1765308eba91773b30d904d4bee937730c639aba5a02a11c2744e0321\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"e3b362739b63c388a1b1c160f4de1f4010012f286f4fa053a30173170acef545\"" Oct 9 00:59:02.366216 containerd[1448]: time="2024-10-09T00:59:02.366188557Z" level=info msg="StartContainer for \"e3b362739b63c388a1b1c160f4de1f4010012f286f4fa053a30173170acef545\"" Oct 9 00:59:02.389538 systemd[1]: Started cri-containerd-e3b362739b63c388a1b1c160f4de1f4010012f286f4fa053a30173170acef545.scope - libcontainer container e3b362739b63c388a1b1c160f4de1f4010012f286f4fa053a30173170acef545. Oct 9 00:59:02.408883 containerd[1448]: time="2024-10-09T00:59:02.408836917Z" level=info msg="StartContainer for \"e3b362739b63c388a1b1c160f4de1f4010012f286f4fa053a30173170acef545\" returns successfully" Oct 9 00:59:02.412308 systemd[1]: cri-containerd-e3b362739b63c388a1b1c160f4de1f4010012f286f4fa053a30173170acef545.scope: Deactivated successfully. Oct 9 00:59:02.425879 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-e3b362739b63c388a1b1c160f4de1f4010012f286f4fa053a30173170acef545-rootfs.mount: Deactivated successfully. Oct 9 00:59:02.427876 kubelet[1706]: I1009 00:59:02.427852 1706 kubelet_node_status.go:488] "Fast updating node status as it just became ready" Oct 9 00:59:02.535936 containerd[1448]: time="2024-10-09T00:59:02.535870967Z" level=info msg="shim disconnected" id=e3b362739b63c388a1b1c160f4de1f4010012f286f4fa053a30173170acef545 namespace=k8s.io Oct 9 00:59:02.535936 containerd[1448]: time="2024-10-09T00:59:02.535925084Z" level=warning msg="cleaning up after shim disconnected" id=e3b362739b63c388a1b1c160f4de1f4010012f286f4fa053a30173170acef545 namespace=k8s.io Oct 9 00:59:02.535936 containerd[1448]: time="2024-10-09T00:59:02.535933324Z" level=info msg="cleaning up dead shim" namespace=k8s.io Oct 9 00:59:02.560841 kubelet[1706]: E1009 00:59:02.560799 1706 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 00:59:02.705655 kubelet[1706]: E1009 00:59:02.705551 1706 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Oct 9 00:59:02.707327 containerd[1448]: time="2024-10-09T00:59:02.707238087Z" level=info msg="CreateContainer within sandbox \"90cf20f1765308eba91773b30d904d4bee937730c639aba5a02a11c2744e0321\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Oct 9 00:59:02.717788 containerd[1448]: time="2024-10-09T00:59:02.717747286Z" level=info msg="CreateContainer within sandbox \"90cf20f1765308eba91773b30d904d4bee937730c639aba5a02a11c2744e0321\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"b7f4b0ed474c7ffa6ddefc6b1b940813324aca0ba6e503a1de63cd423d296737\"" Oct 9 00:59:02.718693 containerd[1448]: time="2024-10-09T00:59:02.718662877Z" level=info msg="StartContainer for \"b7f4b0ed474c7ffa6ddefc6b1b940813324aca0ba6e503a1de63cd423d296737\"" Oct 9 00:59:02.743552 systemd[1]: Started cri-containerd-b7f4b0ed474c7ffa6ddefc6b1b940813324aca0ba6e503a1de63cd423d296737.scope - libcontainer container b7f4b0ed474c7ffa6ddefc6b1b940813324aca0ba6e503a1de63cd423d296737. Oct 9 00:59:02.763676 containerd[1448]: time="2024-10-09T00:59:02.763632153Z" level=info msg="StartContainer for \"b7f4b0ed474c7ffa6ddefc6b1b940813324aca0ba6e503a1de63cd423d296737\" returns successfully" Oct 9 00:59:03.561454 kubelet[1706]: E1009 00:59:03.561392 1706 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 00:59:03.708118 kubelet[1706]: E1009 00:59:03.708083 1706 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Oct 9 00:59:03.833842 systemd-networkd[1386]: flannel.1: Link UP Oct 9 00:59:03.834669 systemd-networkd[1386]: flannel.1: Gained carrier Oct 9 00:59:04.562296 kubelet[1706]: E1009 00:59:04.562218 1706 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 00:59:04.709386 kubelet[1706]: E1009 00:59:04.709354 1706 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Oct 9 00:59:05.562667 kubelet[1706]: E1009 00:59:05.562603 1706 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 00:59:05.792590 systemd-networkd[1386]: flannel.1: Gained IPv6LL Oct 9 00:59:06.563223 kubelet[1706]: E1009 00:59:06.563161 1706 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 00:59:06.746691 kubelet[1706]: I1009 00:59:06.746616 1706 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-dj7md" podStartSLOduration=7.046172287 podStartE2EDuration="11.746598303s" podCreationTimestamp="2024-10-09 00:58:55 +0000 UTC" firstStartedPulling="2024-10-09 00:58:57.655508369 +0000 UTC m=+3.795694193" lastFinishedPulling="2024-10-09 00:59:02.355934385 +0000 UTC m=+8.496120209" observedRunningTime="2024-10-09 00:59:03.719791506 +0000 UTC m=+9.859977330" watchObservedRunningTime="2024-10-09 00:59:06.746598303 +0000 UTC m=+12.886784127" Oct 9 00:59:06.751657 systemd[1]: Created slice kubepods-besteffort-podd79251af_627a_424f_b5e8_79c78fdcec14.slice - libcontainer container kubepods-besteffort-podd79251af_627a_424f_b5e8_79c78fdcec14.slice. Oct 9 00:59:06.838534 kubelet[1706]: I1009 00:59:06.838376 1706 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-srrdz\" (UniqueName: \"kubernetes.io/projected/d79251af-627a-424f-b5e8-79c78fdcec14-kube-api-access-srrdz\") pod \"nginx-deployment-8587fbcb89-j4flj\" (UID: \"d79251af-627a-424f-b5e8-79c78fdcec14\") " pod="default/nginx-deployment-8587fbcb89-j4flj" Oct 9 00:59:07.054832 containerd[1448]: time="2024-10-09T00:59:07.054761271Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-j4flj,Uid:d79251af-627a-424f-b5e8-79c78fdcec14,Namespace:default,Attempt:0,}" Oct 9 00:59:07.089520 systemd-networkd[1386]: cni0: Link UP Oct 9 00:59:07.089529 systemd-networkd[1386]: cni0: Gained carrier Oct 9 00:59:07.093971 systemd-networkd[1386]: cni0: Lost carrier Oct 9 00:59:07.095330 systemd-networkd[1386]: veth1d4a9c4c: Link UP Oct 9 00:59:07.096609 kernel: cni0: port 1(veth1d4a9c4c) entered blocking state Oct 9 00:59:07.096664 kernel: cni0: port 1(veth1d4a9c4c) entered disabled state Oct 9 00:59:07.096696 kernel: veth1d4a9c4c: entered allmulticast mode Oct 9 00:59:07.097490 kernel: veth1d4a9c4c: entered promiscuous mode Oct 9 00:59:07.097531 kernel: cni0: port 1(veth1d4a9c4c) entered blocking state Oct 9 00:59:07.099811 kernel: cni0: port 1(veth1d4a9c4c) entered forwarding state Oct 9 00:59:07.099876 kernel: cni0: port 1(veth1d4a9c4c) entered disabled state Oct 9 00:59:07.108867 kernel: cni0: port 1(veth1d4a9c4c) entered blocking state Oct 9 00:59:07.108933 kernel: cni0: port 1(veth1d4a9c4c) entered forwarding state Oct 9 00:59:07.108989 systemd-networkd[1386]: veth1d4a9c4c: Gained carrier Oct 9 00:59:07.109211 systemd-networkd[1386]: cni0: Gained carrier Oct 9 00:59:07.110701 containerd[1448]: 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)(0x4000012938), "name":"cbr0", "type":"bridge"} Oct 9 00:59:07.110701 containerd[1448]: delegateAdd: netconf sent to delegate plugin: Oct 9 00:59:07.128931 containerd[1448]: {"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-10-09T00:59:07.128725258Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 9 00:59:07.128931 containerd[1448]: time="2024-10-09T00:59:07.128784895Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 9 00:59:07.128931 containerd[1448]: time="2024-10-09T00:59:07.128807014Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 9 00:59:07.129567 containerd[1448]: time="2024-10-09T00:59:07.129367548Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 9 00:59:07.146631 systemd[1]: Started cri-containerd-f4f09b864586b8e5ee2ed94f87569edb80925d0717800583e6dab703e0c3fe32.scope - libcontainer container f4f09b864586b8e5ee2ed94f87569edb80925d0717800583e6dab703e0c3fe32. Oct 9 00:59:07.155155 systemd-resolved[1315]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Oct 9 00:59:07.169482 containerd[1448]: time="2024-10-09T00:59:07.169449800Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-j4flj,Uid:d79251af-627a-424f-b5e8-79c78fdcec14,Namespace:default,Attempt:0,} returns sandbox id \"f4f09b864586b8e5ee2ed94f87569edb80925d0717800583e6dab703e0c3fe32\"" Oct 9 00:59:07.172363 containerd[1448]: time="2024-10-09T00:59:07.170914494Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Oct 9 00:59:07.563702 kubelet[1706]: E1009 00:59:07.563557 1706 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 00:59:08.096595 systemd-networkd[1386]: cni0: Gained IPv6LL Oct 9 00:59:08.289200 systemd-networkd[1386]: veth1d4a9c4c: Gained IPv6LL Oct 9 00:59:08.564504 kubelet[1706]: E1009 00:59:08.564231 1706 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 00:59:08.950184 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3523471752.mount: Deactivated successfully. Oct 9 00:59:09.564519 kubelet[1706]: E1009 00:59:09.564473 1706 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 00:59:09.611925 containerd[1448]: time="2024-10-09T00:59:09.611772238Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 9 00:59:09.612687 containerd[1448]: time="2024-10-09T00:59:09.612497727Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=67683126" Oct 9 00:59:09.613578 containerd[1448]: time="2024-10-09T00:59:09.613525883Z" level=info msg="ImageCreate event name:\"sha256:f54f66f995855943802b9f875104327460a71ca1a3e31f6e9b5da90452a195ce\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 9 00:59:09.616075 containerd[1448]: time="2024-10-09T00:59:09.616043615Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:e833b9da37130a69c1c2ed7b8c61e1f861f75e4804436b3cf81d8463a4e6e5f1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 9 00:59:09.617989 containerd[1448]: time="2024-10-09T00:59:09.617911255Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:f54f66f995855943802b9f875104327460a71ca1a3e31f6e9b5da90452a195ce\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:e833b9da37130a69c1c2ed7b8c61e1f861f75e4804436b3cf81d8463a4e6e5f1\", size \"67683004\" in 2.446961604s" Oct 9 00:59:09.617989 containerd[1448]: time="2024-10-09T00:59:09.617944014Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:f54f66f995855943802b9f875104327460a71ca1a3e31f6e9b5da90452a195ce\"" Oct 9 00:59:09.619918 containerd[1448]: time="2024-10-09T00:59:09.619761736Z" level=info msg="CreateContainer within sandbox \"f4f09b864586b8e5ee2ed94f87569edb80925d0717800583e6dab703e0c3fe32\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Oct 9 00:59:09.628238 containerd[1448]: time="2024-10-09T00:59:09.628204415Z" level=info msg="CreateContainer within sandbox \"f4f09b864586b8e5ee2ed94f87569edb80925d0717800583e6dab703e0c3fe32\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"8c91cf93a23a87f28b20737a6d885f80512fdaad1c4ac768df881b0ecc89e16b\"" Oct 9 00:59:09.628618 containerd[1448]: time="2024-10-09T00:59:09.628588238Z" level=info msg="StartContainer for \"8c91cf93a23a87f28b20737a6d885f80512fdaad1c4ac768df881b0ecc89e16b\"" Oct 9 00:59:09.651538 systemd[1]: Started cri-containerd-8c91cf93a23a87f28b20737a6d885f80512fdaad1c4ac768df881b0ecc89e16b.scope - libcontainer container 8c91cf93a23a87f28b20737a6d885f80512fdaad1c4ac768df881b0ecc89e16b. Oct 9 00:59:09.669463 containerd[1448]: time="2024-10-09T00:59:09.669375933Z" level=info msg="StartContainer for \"8c91cf93a23a87f28b20737a6d885f80512fdaad1c4ac768df881b0ecc89e16b\" returns successfully" Oct 9 00:59:09.733472 kubelet[1706]: I1009 00:59:09.732809 1706 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-8587fbcb89-j4flj" podStartSLOduration=1.284869937 podStartE2EDuration="3.732793058s" podCreationTimestamp="2024-10-09 00:59:06 +0000 UTC" firstStartedPulling="2024-10-09 00:59:07.170604508 +0000 UTC m=+13.310790332" lastFinishedPulling="2024-10-09 00:59:09.618527629 +0000 UTC m=+15.758713453" observedRunningTime="2024-10-09 00:59:09.729639393 +0000 UTC m=+15.869825217" watchObservedRunningTime="2024-10-09 00:59:09.732793058 +0000 UTC m=+15.872978882" Oct 9 00:59:10.565501 kubelet[1706]: E1009 00:59:10.565447 1706 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 00:59:11.566514 kubelet[1706]: E1009 00:59:11.566467 1706 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 00:59:12.567355 kubelet[1706]: E1009 00:59:12.567292 1706 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 00:59:13.568228 kubelet[1706]: E1009 00:59:13.568186 1706 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 00:59:13.672707 systemd[1]: Created slice kubepods-besteffort-podb494728d_a5f6_4fe7_bc10_6c732ed2c2c3.slice - libcontainer container kubepods-besteffort-podb494728d_a5f6_4fe7_bc10_6c732ed2c2c3.slice. Oct 9 00:59:13.676516 kubelet[1706]: I1009 00:59:13.675512 1706 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/b494728d-a5f6-4fe7-bc10-6c732ed2c2c3-data\") pod \"nfs-server-provisioner-0\" (UID: \"b494728d-a5f6-4fe7-bc10-6c732ed2c2c3\") " pod="default/nfs-server-provisioner-0" Oct 9 00:59:13.676516 kubelet[1706]: I1009 00:59:13.675545 1706 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-mdfzk\" (UniqueName: \"kubernetes.io/projected/b494728d-a5f6-4fe7-bc10-6c732ed2c2c3-kube-api-access-mdfzk\") pod \"nfs-server-provisioner-0\" (UID: \"b494728d-a5f6-4fe7-bc10-6c732ed2c2c3\") " pod="default/nfs-server-provisioner-0" Oct 9 00:59:13.976350 containerd[1448]: time="2024-10-09T00:59:13.976238131Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:b494728d-a5f6-4fe7-bc10-6c732ed2c2c3,Namespace:default,Attempt:0,}" Oct 9 00:59:14.001040 systemd-networkd[1386]: veth5cf6b0a8: Link UP Oct 9 00:59:14.003382 kernel: cni0: port 2(veth5cf6b0a8) entered blocking state Oct 9 00:59:14.003452 kernel: cni0: port 2(veth5cf6b0a8) entered disabled state Oct 9 00:59:14.003481 kernel: veth5cf6b0a8: entered allmulticast mode Oct 9 00:59:14.003498 kernel: veth5cf6b0a8: entered promiscuous mode Oct 9 00:59:14.006819 kernel: cni0: port 2(veth5cf6b0a8) entered blocking state Oct 9 00:59:14.006878 kernel: cni0: port 2(veth5cf6b0a8) entered forwarding state Oct 9 00:59:14.007369 systemd-networkd[1386]: veth5cf6b0a8: Gained carrier Oct 9 00:59:14.009300 containerd[1448]: 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)(0x40001148e8), "name":"cbr0", "type":"bridge"} Oct 9 00:59:14.009300 containerd[1448]: delegateAdd: netconf sent to delegate plugin: Oct 9 00:59:14.023481 containerd[1448]: {"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-10-09T00:59:14.023106551Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 9 00:59:14.023481 containerd[1448]: time="2024-10-09T00:59:14.023431579Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 9 00:59:14.023481 containerd[1448]: time="2024-10-09T00:59:14.023445339Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 9 00:59:14.023684 containerd[1448]: time="2024-10-09T00:59:14.023529816Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 9 00:59:14.045576 systemd[1]: Started cri-containerd-82fa77d74c3d41c39ef7a06a128c474ffb41df4123d62691dd26512f7f1e0f75.scope - libcontainer container 82fa77d74c3d41c39ef7a06a128c474ffb41df4123d62691dd26512f7f1e0f75. Oct 9 00:59:14.054600 systemd-resolved[1315]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Oct 9 00:59:14.070030 containerd[1448]: time="2024-10-09T00:59:14.069999079Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:b494728d-a5f6-4fe7-bc10-6c732ed2c2c3,Namespace:default,Attempt:0,} returns sandbox id \"82fa77d74c3d41c39ef7a06a128c474ffb41df4123d62691dd26512f7f1e0f75\"" Oct 9 00:59:14.071496 containerd[1448]: time="2024-10-09T00:59:14.071450626Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Oct 9 00:59:14.568529 kubelet[1706]: E1009 00:59:14.568483 1706 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 00:59:15.136674 systemd-networkd[1386]: veth5cf6b0a8: Gained IPv6LL Oct 9 00:59:15.558712 kubelet[1706]: E1009 00:59:15.558308 1706 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 00:59:15.568851 kubelet[1706]: E1009 00:59:15.568818 1706 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 00:59:15.835837 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1732886563.mount: Deactivated successfully. Oct 9 00:59:16.569052 kubelet[1706]: E1009 00:59:16.569014 1706 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 00:59:17.352468 containerd[1448]: time="2024-10-09T00:59:17.352422854Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 9 00:59:17.352888 containerd[1448]: time="2024-10-09T00:59:17.352790041Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373625" Oct 9 00:59:17.355817 containerd[1448]: time="2024-10-09T00:59:17.354732417Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 9 00:59:17.358201 containerd[1448]: time="2024-10-09T00:59:17.358146664Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 9 00:59:17.359248 containerd[1448]: time="2024-10-09T00:59:17.359208348Z" level=info msg="Pulled image \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" with image id \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\", repo tag \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\", repo digest \"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\", size \"87371201\" in 3.287544131s" Oct 9 00:59:17.359295 containerd[1448]: time="2024-10-09T00:59:17.359245867Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Oct 9 00:59:17.361363 containerd[1448]: time="2024-10-09T00:59:17.361330278Z" level=info msg="CreateContainer within sandbox \"82fa77d74c3d41c39ef7a06a128c474ffb41df4123d62691dd26512f7f1e0f75\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Oct 9 00:59:17.376552 containerd[1448]: time="2024-10-09T00:59:17.376485535Z" level=info msg="CreateContainer within sandbox \"82fa77d74c3d41c39ef7a06a128c474ffb41df4123d62691dd26512f7f1e0f75\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"920425c95daef624432f6d456f713f24e45b5fced59886794997a91f8070e986\"" Oct 9 00:59:17.377167 containerd[1448]: time="2024-10-09T00:59:17.376904081Z" level=info msg="StartContainer for \"920425c95daef624432f6d456f713f24e45b5fced59886794997a91f8070e986\"" Oct 9 00:59:17.407567 systemd[1]: Started cri-containerd-920425c95daef624432f6d456f713f24e45b5fced59886794997a91f8070e986.scope - libcontainer container 920425c95daef624432f6d456f713f24e45b5fced59886794997a91f8070e986. Oct 9 00:59:17.428941 containerd[1448]: time="2024-10-09T00:59:17.427332927Z" level=info msg="StartContainer for \"920425c95daef624432f6d456f713f24e45b5fced59886794997a91f8070e986\" returns successfully" Oct 9 00:59:17.569352 kubelet[1706]: E1009 00:59:17.569303 1706 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 00:59:17.747733 kubelet[1706]: I1009 00:59:17.747586 1706 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.458582054 podStartE2EDuration="4.747571935s" podCreationTimestamp="2024-10-09 00:59:13 +0000 UTC" firstStartedPulling="2024-10-09 00:59:14.071174196 +0000 UTC m=+20.211360020" lastFinishedPulling="2024-10-09 00:59:17.360164077 +0000 UTC m=+23.500349901" observedRunningTime="2024-10-09 00:59:17.74711795 +0000 UTC m=+23.887303854" watchObservedRunningTime="2024-10-09 00:59:17.747571935 +0000 UTC m=+23.887757759" Oct 9 00:59:18.569717 kubelet[1706]: E1009 00:59:18.569673 1706 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 00:59:19.570851 kubelet[1706]: E1009 00:59:19.570798 1706 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 00:59:20.571565 kubelet[1706]: E1009 00:59:20.571527 1706 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 00:59:21.572544 kubelet[1706]: E1009 00:59:21.572503 1706 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 00:59:22.573197 kubelet[1706]: E1009 00:59:22.573157 1706 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 00:59:23.573759 kubelet[1706]: E1009 00:59:23.573706 1706 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 00:59:24.574099 kubelet[1706]: E1009 00:59:24.574040 1706 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 00:59:25.574739 kubelet[1706]: E1009 00:59:25.574701 1706 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 00:59:26.574882 kubelet[1706]: E1009 00:59:26.574831 1706 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 00:59:27.476117 systemd[1]: Created slice kubepods-besteffort-podc4066a03_c3a7_4532_acb4_b25416681b63.slice - libcontainer container kubepods-besteffort-podc4066a03_c3a7_4532_acb4_b25416681b63.slice. Oct 9 00:59:27.575335 kubelet[1706]: E1009 00:59:27.575282 1706 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 00:59:27.637501 kubelet[1706]: I1009 00:59:27.637463 1706 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-9671a3ce-5757-429d-a335-929c42de7d1c\" (UniqueName: \"kubernetes.io/nfs/c4066a03-c3a7-4532-acb4-b25416681b63-pvc-9671a3ce-5757-429d-a335-929c42de7d1c\") pod \"test-pod-1\" (UID: \"c4066a03-c3a7-4532-acb4-b25416681b63\") " pod="default/test-pod-1" Oct 9 00:59:27.637501 kubelet[1706]: I1009 00:59:27.637500 1706 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-r29l5\" (UniqueName: \"kubernetes.io/projected/c4066a03-c3a7-4532-acb4-b25416681b63-kube-api-access-r29l5\") pod \"test-pod-1\" (UID: \"c4066a03-c3a7-4532-acb4-b25416681b63\") " pod="default/test-pod-1" Oct 9 00:59:27.759431 kernel: FS-Cache: Loaded Oct 9 00:59:27.782811 kernel: RPC: Registered named UNIX socket transport module. Oct 9 00:59:27.782872 kernel: RPC: Registered udp transport module. Oct 9 00:59:27.782893 kernel: RPC: Registered tcp transport module. Oct 9 00:59:27.782909 kernel: RPC: Registered tcp-with-tls transport module. Oct 9 00:59:27.782923 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Oct 9 00:59:27.959455 kernel: NFS: Registering the id_resolver key type Oct 9 00:59:27.959580 kernel: Key type id_resolver registered Oct 9 00:59:27.959608 kernel: Key type id_legacy registered Oct 9 00:59:27.979936 nfsidmap[2742]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Oct 9 00:59:27.983099 nfsidmap[2745]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Oct 9 00:59:28.079026 containerd[1448]: time="2024-10-09T00:59:28.078987922Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:c4066a03-c3a7-4532-acb4-b25416681b63,Namespace:default,Attempt:0,}" Oct 9 00:59:28.093731 systemd-networkd[1386]: veth7c69d555: Link UP Oct 9 00:59:28.095511 kernel: cni0: port 3(veth7c69d555) entered blocking state Oct 9 00:59:28.095575 kernel: cni0: port 3(veth7c69d555) entered disabled state Oct 9 00:59:28.095596 kernel: veth7c69d555: entered allmulticast mode Oct 9 00:59:28.095611 kernel: veth7c69d555: entered promiscuous mode Oct 9 00:59:28.101225 systemd-networkd[1386]: veth7c69d555: Gained carrier Oct 9 00:59:28.102114 kernel: cni0: port 3(veth7c69d555) entered blocking state Oct 9 00:59:28.102181 kernel: cni0: port 3(veth7c69d555) entered forwarding state Oct 9 00:59:28.103343 containerd[1448]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40000a68e8), "name":"cbr0", "type":"bridge"} Oct 9 00:59:28.103343 containerd[1448]: delegateAdd: netconf sent to delegate plugin: Oct 9 00:59:28.118527 containerd[1448]: {"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-10-09T00:59:28.118435998Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 9 00:59:28.118527 containerd[1448]: time="2024-10-09T00:59:28.118505876Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 9 00:59:28.118527 containerd[1448]: time="2024-10-09T00:59:28.118517796Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 9 00:59:28.118732 containerd[1448]: time="2024-10-09T00:59:28.118593914Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 9 00:59:28.142596 systemd[1]: Started cri-containerd-a1e3bb24b3eba941ffe3c436f388a928857f522aafd0df39f9e8449b17af6bd8.scope - libcontainer container a1e3bb24b3eba941ffe3c436f388a928857f522aafd0df39f9e8449b17af6bd8. Oct 9 00:59:28.151732 systemd-resolved[1315]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Oct 9 00:59:28.166650 containerd[1448]: time="2024-10-09T00:59:28.166618070Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:c4066a03-c3a7-4532-acb4-b25416681b63,Namespace:default,Attempt:0,} returns sandbox id \"a1e3bb24b3eba941ffe3c436f388a928857f522aafd0df39f9e8449b17af6bd8\"" Oct 9 00:59:28.167851 containerd[1448]: time="2024-10-09T00:59:28.167829122Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Oct 9 00:59:28.381995 containerd[1448]: time="2024-10-09T00:59:28.381877870Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 9 00:59:28.382877 containerd[1448]: time="2024-10-09T00:59:28.382726650Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Oct 9 00:59:28.386238 containerd[1448]: time="2024-10-09T00:59:28.386155930Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:f54f66f995855943802b9f875104327460a71ca1a3e31f6e9b5da90452a195ce\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:e833b9da37130a69c1c2ed7b8c61e1f861f75e4804436b3cf81d8463a4e6e5f1\", size \"67683004\" in 218.197731ms" Oct 9 00:59:28.386238 containerd[1448]: time="2024-10-09T00:59:28.386188489Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:f54f66f995855943802b9f875104327460a71ca1a3e31f6e9b5da90452a195ce\"" Oct 9 00:59:28.388034 containerd[1448]: time="2024-10-09T00:59:28.388007407Z" level=info msg="CreateContainer within sandbox \"a1e3bb24b3eba941ffe3c436f388a928857f522aafd0df39f9e8449b17af6bd8\" for container &ContainerMetadata{Name:test,Attempt:0,}" Oct 9 00:59:28.397674 containerd[1448]: time="2024-10-09T00:59:28.397638541Z" level=info msg="CreateContainer within sandbox \"a1e3bb24b3eba941ffe3c436f388a928857f522aafd0df39f9e8449b17af6bd8\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"becf6f15c1793a8d096332d773364643ab71254a62cc2781371674d8c2986074\"" Oct 9 00:59:28.398199 containerd[1448]: time="2024-10-09T00:59:28.398176089Z" level=info msg="StartContainer for \"becf6f15c1793a8d096332d773364643ab71254a62cc2781371674d8c2986074\"" Oct 9 00:59:28.420554 systemd[1]: Started cri-containerd-becf6f15c1793a8d096332d773364643ab71254a62cc2781371674d8c2986074.scope - libcontainer container becf6f15c1793a8d096332d773364643ab71254a62cc2781371674d8c2986074. Oct 9 00:59:28.441857 containerd[1448]: time="2024-10-09T00:59:28.441815187Z" level=info msg="StartContainer for \"becf6f15c1793a8d096332d773364643ab71254a62cc2781371674d8c2986074\" returns successfully" Oct 9 00:59:28.575713 kubelet[1706]: E1009 00:59:28.575658 1706 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 00:59:28.763357 kubelet[1706]: I1009 00:59:28.763189 1706 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=15.544003475 podStartE2EDuration="15.763173823s" podCreationTimestamp="2024-10-09 00:59:13 +0000 UTC" firstStartedPulling="2024-10-09 00:59:28.167616367 +0000 UTC m=+34.307802151" lastFinishedPulling="2024-10-09 00:59:28.386786715 +0000 UTC m=+34.526972499" observedRunningTime="2024-10-09 00:59:28.762974668 +0000 UTC m=+34.903160492" watchObservedRunningTime="2024-10-09 00:59:28.763173823 +0000 UTC m=+34.903359647" Oct 9 00:59:29.280589 systemd-networkd[1386]: veth7c69d555: Gained IPv6LL Oct 9 00:59:29.576166 kubelet[1706]: E1009 00:59:29.576129 1706 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 00:59:30.576279 kubelet[1706]: E1009 00:59:30.576211 1706 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 00:59:31.140799 update_engine[1437]: I20241009 00:59:31.140729 1437 update_attempter.cc:509] Updating boot flags... Oct 9 00:59:31.228491 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 37 scanned by (udev-worker) (2909) Oct 9 00:59:31.250439 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 37 scanned by (udev-worker) (2908)