Jan 13 20:08:41.180542 kernel: Booting Linux on physical CPU 0x0000000000 [0x410fd083]
Jan 13 20:08:41.180588 kernel: Linux version 6.6.71-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241116 p3) 14.2.1 20241116, GNU ld (Gentoo 2.42 p6) 2.42.0) #1 SMP PREEMPT Mon Jan 13 18:56:28 -00 2025
Jan 13 20:08:41.180613 kernel: KASLR disabled due to lack of seed
Jan 13 20:08:41.180630 kernel: efi: EFI v2.7 by EDK II
Jan 13 20:08:41.180646 kernel: efi: SMBIOS=0x7bed0000 SMBIOS 3.0=0x7beb0000 ACPI=0x786e0000 ACPI 2.0=0x786e0014 MEMATTR=0x7a736a98 MEMRESERVE=0x78557598 
Jan 13 20:08:41.180662 kernel: secureboot: Secure boot disabled
Jan 13 20:08:41.180679 kernel: ACPI: Early table checksum verification disabled
Jan 13 20:08:41.180695 kernel: ACPI: RSDP 0x00000000786E0014 000024 (v02 AMAZON)
Jan 13 20:08:41.180711 kernel: ACPI: XSDT 0x00000000786D00E8 000064 (v01 AMAZON AMZNFACP 00000001      01000013)
Jan 13 20:08:41.180727 kernel: ACPI: FACP 0x00000000786B0000 000114 (v06 AMAZON AMZNFACP 00000001 AMZN 00000001)
Jan 13 20:08:41.180748 kernel: ACPI: DSDT 0x0000000078640000 00159D (v02 AMAZON AMZNDSDT 00000001 INTL 20160527)
Jan 13 20:08:41.180764 kernel: ACPI: APIC 0x00000000786C0000 000108 (v04 AMAZON AMZNAPIC 00000001 AMZN 00000001)
Jan 13 20:08:41.180780 kernel: ACPI: SPCR 0x00000000786A0000 000050 (v02 AMAZON AMZNSPCR 00000001 AMZN 00000001)
Jan 13 20:08:41.180796 kernel: ACPI: GTDT 0x0000000078690000 000060 (v02 AMAZON AMZNGTDT 00000001 AMZN 00000001)
Jan 13 20:08:41.180814 kernel: ACPI: MCFG 0x0000000078680000 00003C (v02 AMAZON AMZNMCFG 00000001 AMZN 00000001)
Jan 13 20:08:41.180835 kernel: ACPI: SLIT 0x0000000078670000 00002D (v01 AMAZON AMZNSLIT 00000001 AMZN 00000001)
Jan 13 20:08:41.180853 kernel: ACPI: IORT 0x0000000078660000 000078 (v01 AMAZON AMZNIORT 00000001 AMZN 00000001)
Jan 13 20:08:41.180870 kernel: ACPI: PPTT 0x0000000078650000 0000EC (v01 AMAZON AMZNPPTT 00000001 AMZN 00000001)
Jan 13 20:08:41.180886 kernel: ACPI: SPCR: console: uart,mmio,0x90a0000,115200
Jan 13 20:08:41.180902 kernel: earlycon: uart0 at MMIO 0x00000000090a0000 (options '115200')
Jan 13 20:08:41.180918 kernel: printk: bootconsole [uart0] enabled
Jan 13 20:08:41.180934 kernel: NUMA: Failed to initialise from firmware
Jan 13 20:08:41.180950 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000004b5ffffff]
Jan 13 20:08:41.180966 kernel: NUMA: NODE_DATA [mem 0x4b583f800-0x4b5844fff]
Jan 13 20:08:41.180983 kernel: Zone ranges:
Jan 13 20:08:41.180999 kernel:   DMA      [mem 0x0000000040000000-0x00000000ffffffff]
Jan 13 20:08:41.181019 kernel:   DMA32    empty
Jan 13 20:08:41.181036 kernel:   Normal   [mem 0x0000000100000000-0x00000004b5ffffff]
Jan 13 20:08:41.181052 kernel: Movable zone start for each node
Jan 13 20:08:41.181068 kernel: Early memory node ranges
Jan 13 20:08:41.181084 kernel:   node   0: [mem 0x0000000040000000-0x000000007862ffff]
Jan 13 20:08:41.181100 kernel:   node   0: [mem 0x0000000078630000-0x000000007863ffff]
Jan 13 20:08:41.181135 kernel:   node   0: [mem 0x0000000078640000-0x00000000786effff]
Jan 13 20:08:41.181156 kernel:   node   0: [mem 0x00000000786f0000-0x000000007872ffff]
Jan 13 20:08:41.181173 kernel:   node   0: [mem 0x0000000078730000-0x000000007bbfffff]
Jan 13 20:08:41.181190 kernel:   node   0: [mem 0x000000007bc00000-0x000000007bfdffff]
Jan 13 20:08:41.181235 kernel:   node   0: [mem 0x000000007bfe0000-0x000000007fffffff]
Jan 13 20:08:41.181257 kernel:   node   0: [mem 0x0000000400000000-0x00000004b5ffffff]
Jan 13 20:08:41.183290 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000004b5ffffff]
Jan 13 20:08:41.183311 kernel: On node 0, zone Normal: 8192 pages in unavailable ranges
Jan 13 20:08:41.183335 kernel: psci: probing for conduit method from ACPI.
Jan 13 20:08:41.183353 kernel: psci: PSCIv1.0 detected in firmware.
Jan 13 20:08:41.183370 kernel: psci: Using standard PSCI v0.2 function IDs
Jan 13 20:08:41.183393 kernel: psci: Trusted OS migration not required
Jan 13 20:08:41.183411 kernel: psci: SMC Calling Convention v1.1
Jan 13 20:08:41.183428 kernel: percpu: Embedded 31 pages/cpu s86696 r8192 d32088 u126976
Jan 13 20:08:41.183445 kernel: pcpu-alloc: s86696 r8192 d32088 u126976 alloc=31*4096
Jan 13 20:08:41.183464 kernel: pcpu-alloc: [0] 0 [0] 1 
Jan 13 20:08:41.183481 kernel: Detected PIPT I-cache on CPU0
Jan 13 20:08:41.183499 kernel: CPU features: detected: GIC system register CPU interface
Jan 13 20:08:41.183516 kernel: CPU features: detected: Spectre-v2
Jan 13 20:08:41.183533 kernel: CPU features: detected: Spectre-v3a
Jan 13 20:08:41.183550 kernel: CPU features: detected: Spectre-BHB
Jan 13 20:08:41.183567 kernel: CPU features: detected: ARM erratum 1742098
Jan 13 20:08:41.183584 kernel: CPU features: detected: ARM errata 1165522, 1319367, or 1530923
Jan 13 20:08:41.183606 kernel: alternatives: applying boot alternatives
Jan 13 20:08:41.183626 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=tty1 console=ttyS0,115200n8 earlycon flatcar.first_boot=detected acpi=force flatcar.oem.id=ec2 modprobe.blacklist=xen_fbfront net.ifnames=0 nvme_core.io_timeout=4294967295 verity.usrhash=9798117b3b15ef802e3d618077f87253cc08e0d5280b8fe28b307e7558b7ebcc
Jan 13 20:08:41.183645 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space.
Jan 13 20:08:41.183662 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)
Jan 13 20:08:41.183680 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)
Jan 13 20:08:41.183697 kernel: Fallback order for Node 0: 0 
Jan 13 20:08:41.183714 kernel: Built 1 zonelists, mobility grouping on.  Total pages: 991872
Jan 13 20:08:41.183731 kernel: Policy zone: Normal
Jan 13 20:08:41.183748 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off
Jan 13 20:08:41.183765 kernel: software IO TLB: area num 2.
Jan 13 20:08:41.183786 kernel: software IO TLB: mapped [mem 0x000000007c000000-0x0000000080000000] (64MB)
Jan 13 20:08:41.183804 kernel: Memory: 3819640K/4030464K available (10304K kernel code, 2184K rwdata, 8092K rodata, 39936K init, 897K bss, 210824K reserved, 0K cma-reserved)
Jan 13 20:08:41.183822 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
Jan 13 20:08:41.183839 kernel: rcu: Preemptible hierarchical RCU implementation.
Jan 13 20:08:41.183857 kernel: rcu:         RCU event tracing is enabled.
Jan 13 20:08:41.183875 kernel: rcu:         RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2.
Jan 13 20:08:41.183893 kernel:         Trampoline variant of Tasks RCU enabled.
Jan 13 20:08:41.183910 kernel:         Tracing variant of Tasks RCU enabled.
Jan 13 20:08:41.183927 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.
Jan 13 20:08:41.183945 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
Jan 13 20:08:41.183962 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
Jan 13 20:08:41.183983 kernel: GICv3: 96 SPIs implemented
Jan 13 20:08:41.184001 kernel: GICv3: 0 Extended SPIs implemented
Jan 13 20:08:41.184019 kernel: Root IRQ handler: gic_handle_irq
Jan 13 20:08:41.184036 kernel: GICv3: GICv3 features: 16 PPIs
Jan 13 20:08:41.184053 kernel: GICv3: CPU0: found redistributor 0 region 0:0x0000000010200000
Jan 13 20:08:41.184071 kernel: ITS [mem 0x10080000-0x1009ffff]
Jan 13 20:08:41.184088 kernel: ITS@0x0000000010080000: allocated 8192 Devices @4000b0000 (indirect, esz 8, psz 64K, shr 1)
Jan 13 20:08:41.184106 kernel: ITS@0x0000000010080000: allocated 8192 Interrupt Collections @4000c0000 (flat, esz 8, psz 64K, shr 1)
Jan 13 20:08:41.184123 kernel: GICv3: using LPI property table @0x00000004000d0000
Jan 13 20:08:41.184141 kernel: ITS: Using hypervisor restricted LPI range [128]
Jan 13 20:08:41.184158 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000004000e0000
Jan 13 20:08:41.184175 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention.
Jan 13 20:08:41.184197 kernel: arch_timer: cp15 timer(s) running at 83.33MHz (virt).
Jan 13 20:08:41.184304 kernel: clocksource: arch_sys_counter: mask: 0x1ffffffffffffff max_cycles: 0x13381ebeec, max_idle_ns: 440795203145 ns
Jan 13 20:08:41.184325 kernel: sched_clock: 57 bits at 83MHz, resolution 12ns, wraps every 4398046511100ns
Jan 13 20:08:41.184343 kernel: Console: colour dummy device 80x25
Jan 13 20:08:41.184362 kernel: printk: console [tty1] enabled
Jan 13 20:08:41.184380 kernel: ACPI: Core revision 20230628
Jan 13 20:08:41.184398 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 166.66 BogoMIPS (lpj=83333)
Jan 13 20:08:41.184416 kernel: pid_max: default: 32768 minimum: 301
Jan 13 20:08:41.184434 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity
Jan 13 20:08:41.184451 kernel: landlock: Up and running.
Jan 13 20:08:41.184476 kernel: SELinux:  Initializing.
Jan 13 20:08:41.184494 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
Jan 13 20:08:41.184512 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
Jan 13 20:08:41.184530 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2.
Jan 13 20:08:41.184547 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2.
Jan 13 20:08:41.184565 kernel: rcu: Hierarchical SRCU implementation.
Jan 13 20:08:41.184583 kernel: rcu:         Max phase no-delay instances is 400.
Jan 13 20:08:41.184600 kernel: Platform MSI: ITS@0x10080000 domain created
Jan 13 20:08:41.184622 kernel: PCI/MSI: ITS@0x10080000 domain created
Jan 13 20:08:41.184640 kernel: Remapping and enabling EFI services.
Jan 13 20:08:41.184658 kernel: smp: Bringing up secondary CPUs ...
Jan 13 20:08:41.184675 kernel: Detected PIPT I-cache on CPU1
Jan 13 20:08:41.184692 kernel: GICv3: CPU1: found redistributor 1 region 0:0x0000000010220000
Jan 13 20:08:41.184710 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000004000f0000
Jan 13 20:08:41.184728 kernel: CPU1: Booted secondary processor 0x0000000001 [0x410fd083]
Jan 13 20:08:41.184745 kernel: smp: Brought up 1 node, 2 CPUs
Jan 13 20:08:41.184762 kernel: SMP: Total of 2 processors activated.
Jan 13 20:08:41.184780 kernel: CPU features: detected: 32-bit EL0 Support
Jan 13 20:08:41.184801 kernel: CPU features: detected: 32-bit EL1 Support
Jan 13 20:08:41.184820 kernel: CPU features: detected: CRC32 instructions
Jan 13 20:08:41.184848 kernel: CPU: All CPU(s) started at EL1
Jan 13 20:08:41.184871 kernel: alternatives: applying system-wide alternatives
Jan 13 20:08:41.184889 kernel: devtmpfs: initialized
Jan 13 20:08:41.184907 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
Jan 13 20:08:41.184926 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear)
Jan 13 20:08:41.184943 kernel: pinctrl core: initialized pinctrl subsystem
Jan 13 20:08:41.184962 kernel: SMBIOS 3.0.0 present.
Jan 13 20:08:41.184984 kernel: DMI: Amazon EC2 a1.large/, BIOS 1.0 11/1/2018
Jan 13 20:08:41.185003 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family
Jan 13 20:08:41.185021 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations
Jan 13 20:08:41.185040 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
Jan 13 20:08:41.185058 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
Jan 13 20:08:41.185076 kernel: audit: initializing netlink subsys (disabled)
Jan 13 20:08:41.185094 kernel: audit: type=2000 audit(0.222:1): state=initialized audit_enabled=0 res=1
Jan 13 20:08:41.185137 kernel: thermal_sys: Registered thermal governor 'step_wise'
Jan 13 20:08:41.185159 kernel: cpuidle: using governor menu
Jan 13 20:08:41.185177 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
Jan 13 20:08:41.185195 kernel: ASID allocator initialised with 65536 entries
Jan 13 20:08:41.185236 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
Jan 13 20:08:41.185258 kernel: Serial: AMBA PL011 UART driver
Jan 13 20:08:41.185276 kernel: Modules: 17360 pages in range for non-PLT usage
Jan 13 20:08:41.185294 kernel: Modules: 508880 pages in range for PLT usage
Jan 13 20:08:41.185313 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages
Jan 13 20:08:41.185337 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page
Jan 13 20:08:41.185356 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages
Jan 13 20:08:41.185374 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page
Jan 13 20:08:41.185392 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages
Jan 13 20:08:41.185411 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page
Jan 13 20:08:41.185429 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages
Jan 13 20:08:41.185447 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page
Jan 13 20:08:41.185465 kernel: ACPI: Added _OSI(Module Device)
Jan 13 20:08:41.185483 kernel: ACPI: Added _OSI(Processor Device)
Jan 13 20:08:41.185505 kernel: ACPI: Added _OSI(3.0 _SCP Extensions)
Jan 13 20:08:41.185524 kernel: ACPI: Added _OSI(Processor Aggregator Device)
Jan 13 20:08:41.185542 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded
Jan 13 20:08:41.185561 kernel: ACPI: Interpreter enabled
Jan 13 20:08:41.185578 kernel: ACPI: Using GIC for interrupt routing
Jan 13 20:08:41.185596 kernel: ACPI: MCFG table detected, 1 entries
Jan 13 20:08:41.185615 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-0f])
Jan 13 20:08:41.185919 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]
Jan 13 20:08:41.186135 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR]
Jan 13 20:08:41.188480 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability]
Jan 13 20:08:41.188717 kernel: acpi PNP0A08:00: ECAM area [mem 0x20000000-0x20ffffff] reserved by PNP0C02:00
Jan 13 20:08:41.188921 kernel: acpi PNP0A08:00: ECAM at [mem 0x20000000-0x20ffffff] for [bus 00-0f]
Jan 13 20:08:41.188948 kernel: ACPI: Remapped I/O 0x000000001fff0000 to [io  0x0000-0xffff window]
Jan 13 20:08:41.188967 kernel: acpiphp: Slot [1] registered
Jan 13 20:08:41.188987 kernel: acpiphp: Slot [2] registered
Jan 13 20:08:41.189007 kernel: acpiphp: Slot [3] registered
Jan 13 20:08:41.189038 kernel: acpiphp: Slot [4] registered
Jan 13 20:08:41.189058 kernel: acpiphp: Slot [5] registered
Jan 13 20:08:41.189078 kernel: acpiphp: Slot [6] registered
Jan 13 20:08:41.189096 kernel: acpiphp: Slot [7] registered
Jan 13 20:08:41.189133 kernel: acpiphp: Slot [8] registered
Jan 13 20:08:41.189157 kernel: acpiphp: Slot [9] registered
Jan 13 20:08:41.189176 kernel: acpiphp: Slot [10] registered
Jan 13 20:08:41.189194 kernel: acpiphp: Slot [11] registered
Jan 13 20:08:41.189250 kernel: acpiphp: Slot [12] registered
Jan 13 20:08:41.189271 kernel: acpiphp: Slot [13] registered
Jan 13 20:08:41.189298 kernel: acpiphp: Slot [14] registered
Jan 13 20:08:41.189317 kernel: acpiphp: Slot [15] registered
Jan 13 20:08:41.189335 kernel: acpiphp: Slot [16] registered
Jan 13 20:08:41.189354 kernel: acpiphp: Slot [17] registered
Jan 13 20:08:41.189372 kernel: acpiphp: Slot [18] registered
Jan 13 20:08:41.189391 kernel: acpiphp: Slot [19] registered
Jan 13 20:08:41.189409 kernel: acpiphp: Slot [20] registered
Jan 13 20:08:41.189427 kernel: acpiphp: Slot [21] registered
Jan 13 20:08:41.189445 kernel: acpiphp: Slot [22] registered
Jan 13 20:08:41.189469 kernel: acpiphp: Slot [23] registered
Jan 13 20:08:41.189488 kernel: acpiphp: Slot [24] registered
Jan 13 20:08:41.189507 kernel: acpiphp: Slot [25] registered
Jan 13 20:08:41.189525 kernel: acpiphp: Slot [26] registered
Jan 13 20:08:41.189543 kernel: acpiphp: Slot [27] registered
Jan 13 20:08:41.189561 kernel: acpiphp: Slot [28] registered
Jan 13 20:08:41.189579 kernel: acpiphp: Slot [29] registered
Jan 13 20:08:41.189596 kernel: acpiphp: Slot [30] registered
Jan 13 20:08:41.189614 kernel: acpiphp: Slot [31] registered
Jan 13 20:08:41.189632 kernel: PCI host bridge to bus 0000:00
Jan 13 20:08:41.189868 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xffffffff window]
Jan 13 20:08:41.190055 kernel: pci_bus 0000:00: root bus resource [io  0x0000-0xffff window]
Jan 13 20:08:41.192332 kernel: pci_bus 0000:00: root bus resource [mem 0x400000000000-0x407fffffffff window]
Jan 13 20:08:41.192554 kernel: pci_bus 0000:00: root bus resource [bus 00-0f]
Jan 13 20:08:41.192786 kernel: pci 0000:00:00.0: [1d0f:0200] type 00 class 0x060000
Jan 13 20:08:41.193021 kernel: pci 0000:00:01.0: [1d0f:8250] type 00 class 0x070003
Jan 13 20:08:41.193315 kernel: pci 0000:00:01.0: reg 0x10: [mem 0x80118000-0x80118fff]
Jan 13 20:08:41.193538 kernel: pci 0000:00:04.0: [1d0f:8061] type 00 class 0x010802
Jan 13 20:08:41.193743 kernel: pci 0000:00:04.0: reg 0x10: [mem 0x80114000-0x80117fff]
Jan 13 20:08:41.193945 kernel: pci 0000:00:04.0: PME# supported from D0 D1 D2 D3hot D3cold
Jan 13 20:08:41.194165 kernel: pci 0000:00:05.0: [1d0f:ec20] type 00 class 0x020000
Jan 13 20:08:41.196490 kernel: pci 0000:00:05.0: reg 0x10: [mem 0x80110000-0x80113fff]
Jan 13 20:08:41.196717 kernel: pci 0000:00:05.0: reg 0x18: [mem 0x80000000-0x800fffff pref]
Jan 13 20:08:41.196929 kernel: pci 0000:00:05.0: reg 0x20: [mem 0x80100000-0x8010ffff]
Jan 13 20:08:41.197160 kernel: pci 0000:00:05.0: PME# supported from D0 D1 D2 D3hot D3cold
Jan 13 20:08:41.199441 kernel: pci 0000:00:05.0: BAR 2: assigned [mem 0x80000000-0x800fffff pref]
Jan 13 20:08:41.199654 kernel: pci 0000:00:05.0: BAR 4: assigned [mem 0x80100000-0x8010ffff]
Jan 13 20:08:41.199863 kernel: pci 0000:00:04.0: BAR 0: assigned [mem 0x80110000-0x80113fff]
Jan 13 20:08:41.200065 kernel: pci 0000:00:05.0: BAR 0: assigned [mem 0x80114000-0x80117fff]
Jan 13 20:08:41.200304 kernel: pci 0000:00:01.0: BAR 0: assigned [mem 0x80118000-0x80118fff]
Jan 13 20:08:41.200503 kernel: pci_bus 0000:00: resource 4 [mem 0x80000000-0xffffffff window]
Jan 13 20:08:41.200689 kernel: pci_bus 0000:00: resource 5 [io  0x0000-0xffff window]
Jan 13 20:08:41.200873 kernel: pci_bus 0000:00: resource 6 [mem 0x400000000000-0x407fffffffff window]
Jan 13 20:08:41.200898 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35
Jan 13 20:08:41.200918 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36
Jan 13 20:08:41.200937 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37
Jan 13 20:08:41.200955 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38
Jan 13 20:08:41.200974 kernel: iommu: Default domain type: Translated
Jan 13 20:08:41.200998 kernel: iommu: DMA domain TLB invalidation policy: strict mode
Jan 13 20:08:41.201017 kernel: efivars: Registered efivars operations
Jan 13 20:08:41.201034 kernel: vgaarb: loaded
Jan 13 20:08:41.201052 kernel: clocksource: Switched to clocksource arch_sys_counter
Jan 13 20:08:41.201070 kernel: VFS: Disk quotas dquot_6.6.0
Jan 13 20:08:41.201089 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
Jan 13 20:08:41.201107 kernel: pnp: PnP ACPI init
Jan 13 20:08:41.201364 kernel: system 00:00: [mem 0x20000000-0x2fffffff] could not be reserved
Jan 13 20:08:41.201399 kernel: pnp: PnP ACPI: found 1 devices
Jan 13 20:08:41.201418 kernel: NET: Registered PF_INET protocol family
Jan 13 20:08:41.201437 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)
Jan 13 20:08:41.201455 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)
Jan 13 20:08:41.201474 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
Jan 13 20:08:41.201492 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)
Jan 13 20:08:41.201511 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear)
Jan 13 20:08:41.201529 kernel: TCP: Hash tables configured (established 32768 bind 32768)
Jan 13 20:08:41.201547 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear)
Jan 13 20:08:41.201570 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear)
Jan 13 20:08:41.201589 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family
Jan 13 20:08:41.201607 kernel: PCI: CLS 0 bytes, default 64
Jan 13 20:08:41.201625 kernel: kvm [1]: HYP mode not available
Jan 13 20:08:41.201643 kernel: Initialise system trusted keyrings
Jan 13 20:08:41.201662 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0
Jan 13 20:08:41.201680 kernel: Key type asymmetric registered
Jan 13 20:08:41.201698 kernel: Asymmetric key parser 'x509' registered
Jan 13 20:08:41.201716 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)
Jan 13 20:08:41.201738 kernel: io scheduler mq-deadline registered
Jan 13 20:08:41.201757 kernel: io scheduler kyber registered
Jan 13 20:08:41.201775 kernel: io scheduler bfq registered
Jan 13 20:08:41.201990 kernel: pl061_gpio ARMH0061:00: PL061 GPIO chip registered
Jan 13 20:08:41.202018 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0
Jan 13 20:08:41.202036 kernel: ACPI: button: Power Button [PWRB]
Jan 13 20:08:41.202055 kernel: input: Sleep Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0E:00/input/input1
Jan 13 20:08:41.202073 kernel: ACPI: button: Sleep Button [SLPB]
Jan 13 20:08:41.202097 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
Jan 13 20:08:41.202116 kernel: ACPI: \_SB_.PCI0.GSI2: Enabled at IRQ 37
Jan 13 20:08:41.204458 kernel: serial 0000:00:01.0: enabling device (0010 -> 0012)
Jan 13 20:08:41.204504 kernel: printk: console [ttyS0] disabled
Jan 13 20:08:41.204523 kernel: 0000:00:01.0: ttyS0 at MMIO 0x80118000 (irq = 14, base_baud = 115200) is a 16550A
Jan 13 20:08:41.204542 kernel: printk: console [ttyS0] enabled
Jan 13 20:08:41.204562 kernel: printk: bootconsole [uart0] disabled
Jan 13 20:08:41.204580 kernel: thunder_xcv, ver 1.0
Jan 13 20:08:41.204599 kernel: thunder_bgx, ver 1.0
Jan 13 20:08:41.204617 kernel: nicpf, ver 1.0
Jan 13 20:08:41.204647 kernel: nicvf, ver 1.0
Jan 13 20:08:41.204880 kernel: rtc-efi rtc-efi.0: registered as rtc0
Jan 13 20:08:41.205072 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-01-13T20:08:40 UTC (1736798920)
Jan 13 20:08:41.205098 kernel: hid: raw HID events driver (C) Jiri Kosina
Jan 13 20:08:41.205138 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 3 counters available
Jan 13 20:08:41.205160 kernel: watchdog: Delayed init of the lockup detector failed: -19
Jan 13 20:08:41.205178 kernel: watchdog: Hard watchdog permanently disabled
Jan 13 20:08:41.205222 kernel: NET: Registered PF_INET6 protocol family
Jan 13 20:08:41.205246 kernel: Segment Routing with IPv6
Jan 13 20:08:41.205265 kernel: In-situ OAM (IOAM) with IPv6
Jan 13 20:08:41.205283 kernel: NET: Registered PF_PACKET protocol family
Jan 13 20:08:41.205301 kernel: Key type dns_resolver registered
Jan 13 20:08:41.205320 kernel: registered taskstats version 1
Jan 13 20:08:41.205338 kernel: Loading compiled-in X.509 certificates
Jan 13 20:08:41.205356 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.71-flatcar: 46cb4d1b22f3a5974766fe7d7b651e2f296d4fe0'
Jan 13 20:08:41.205374 kernel: Key type .fscrypt registered
Jan 13 20:08:41.205392 kernel: Key type fscrypt-provisioning registered
Jan 13 20:08:41.205417 kernel: ima: No TPM chip found, activating TPM-bypass!
Jan 13 20:08:41.205436 kernel: ima: Allocated hash algorithm: sha1
Jan 13 20:08:41.205454 kernel: ima: No architecture policies found
Jan 13 20:08:41.205472 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng)
Jan 13 20:08:41.205490 kernel: clk: Disabling unused clocks
Jan 13 20:08:41.205508 kernel: Freeing unused kernel memory: 39936K
Jan 13 20:08:41.205526 kernel: Run /init as init process
Jan 13 20:08:41.205544 kernel:   with arguments:
Jan 13 20:08:41.205562 kernel:     /init
Jan 13 20:08:41.205584 kernel:   with environment:
Jan 13 20:08:41.205602 kernel:     HOME=/
Jan 13 20:08:41.205620 kernel:     TERM=linux
Jan 13 20:08:41.205638 kernel:     BOOT_IMAGE=/flatcar/vmlinuz-a
Jan 13 20:08:41.205661 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)
Jan 13 20:08:41.205683 systemd[1]: Detected virtualization amazon.
Jan 13 20:08:41.205704 systemd[1]: Detected architecture arm64.
Jan 13 20:08:41.205728 systemd[1]: Running in initrd.
Jan 13 20:08:41.205748 systemd[1]: No hostname configured, using default hostname.
Jan 13 20:08:41.205767 systemd[1]: Hostname set to <localhost>.
Jan 13 20:08:41.205787 systemd[1]: Initializing machine ID from VM UUID.
Jan 13 20:08:41.205806 systemd[1]: Queued start job for default target initrd.target.
Jan 13 20:08:41.205825 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch.
Jan 13 20:08:41.205846 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch.
Jan 13 20:08:41.205867 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM...
Jan 13 20:08:41.205891 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM...
Jan 13 20:08:41.205912 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT...
Jan 13 20:08:41.205932 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A...
Jan 13 20:08:41.205954 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132...
Jan 13 20:08:41.205975 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr...
Jan 13 20:08:41.205995 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre).
Jan 13 20:08:41.206014 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes.
Jan 13 20:08:41.206038 systemd[1]: Reached target paths.target - Path Units.
Jan 13 20:08:41.206058 systemd[1]: Reached target slices.target - Slice Units.
Jan 13 20:08:41.206078 systemd[1]: Reached target swap.target - Swaps.
Jan 13 20:08:41.206098 systemd[1]: Reached target timers.target - Timer Units.
Jan 13 20:08:41.206118 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket.
Jan 13 20:08:41.206137 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket.
Jan 13 20:08:41.206157 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log).
Jan 13 20:08:41.206176 systemd[1]: Listening on systemd-journald.socket - Journal Socket.
Jan 13 20:08:41.206196 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket.
Jan 13 20:08:41.209956 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket.
Jan 13 20:08:41.209982 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket.
Jan 13 20:08:41.210003 systemd[1]: Reached target sockets.target - Socket Units.
Jan 13 20:08:41.210024 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup...
Jan 13 20:08:41.210045 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes...
Jan 13 20:08:41.210065 systemd[1]: Finished network-cleanup.service - Network Cleanup.
Jan 13 20:08:41.210085 systemd[1]: Starting systemd-fsck-usr.service...
Jan 13 20:08:41.210105 systemd[1]: Starting systemd-journald.service - Journal Service...
Jan 13 20:08:41.210133 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules...
Jan 13 20:08:41.210154 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup...
Jan 13 20:08:41.210174 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup.
Jan 13 20:08:41.210194 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes.
Jan 13 20:08:41.210280 systemd-journald[252]: Collecting audit messages is disabled.
Jan 13 20:08:41.210330 systemd[1]: Finished systemd-fsck-usr.service.
Jan 13 20:08:41.210353 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully...
Jan 13 20:08:41.210373 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
Jan 13 20:08:41.210393 systemd-journald[252]: Journal started
Jan 13 20:08:41.210442 systemd-journald[252]: Runtime Journal (/run/log/journal/ec2d6d8e90d95936b6e9c32f02642c9d) is 8.0M, max 75.3M, 67.3M free.
Jan 13 20:08:41.172630 systemd-modules-load[253]: Inserted module 'overlay'
Jan 13 20:08:41.222713 systemd[1]: Started systemd-journald.service - Journal Service.
Jan 13 20:08:41.224071 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup.
Jan 13 20:08:41.231471 kernel: Bridge firewalling registered
Jan 13 20:08:41.229550 systemd-modules-load[253]: Inserted module 'br_netfilter'
Jan 13 20:08:41.234835 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules.
Jan 13 20:08:41.242818 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully.
Jan 13 20:08:41.254529 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters...
Jan 13 20:08:41.259138 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables...
Jan 13 20:08:41.266524 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev...
Jan 13 20:08:41.273492 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories...
Jan 13 20:08:41.315885 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev.
Jan 13 20:08:41.328951 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables.
Jan 13 20:08:41.330129 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories.
Jan 13 20:08:41.353616 systemd[1]: Starting systemd-resolved.service - Network Name Resolution...
Jan 13 20:08:41.366798 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters.
Jan 13 20:08:41.382588 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook...
Jan 13 20:08:41.424202 systemd-resolved[285]: Positive Trust Anchors:
Jan 13 20:08:41.424251 systemd-resolved[285]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
Jan 13 20:08:41.424311 systemd-resolved[285]: 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
Jan 13 20:08:41.451061 dracut-cmdline[290]: dracut-dracut-053
Jan 13 20:08:41.451061 dracut-cmdline[290]: 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=tty1 console=ttyS0,115200n8 earlycon flatcar.first_boot=detected acpi=force flatcar.oem.id=ec2 modprobe.blacklist=xen_fbfront net.ifnames=0 nvme_core.io_timeout=4294967295 verity.usrhash=9798117b3b15ef802e3d618077f87253cc08e0d5280b8fe28b307e7558b7ebcc
Jan 13 20:08:41.575246 kernel: SCSI subsystem initialized
Jan 13 20:08:41.584230 kernel: Loading iSCSI transport class v2.0-870.
Jan 13 20:08:41.595248 kernel: iscsi: registered transport (tcp)
Jan 13 20:08:41.617315 kernel: iscsi: registered transport (qla4xxx)
Jan 13 20:08:41.617388 kernel: QLogic iSCSI HBA Driver
Jan 13 20:08:41.688247 kernel: random: crng init done
Jan 13 20:08:41.688573 systemd-resolved[285]: Defaulting to hostname 'linux'.
Jan 13 20:08:41.693749 systemd[1]: Started systemd-resolved.service - Network Name Resolution.
Jan 13 20:08:41.698511 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups.
Jan 13 20:08:41.718970 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook.
Jan 13 20:08:41.730619 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook...
Jan 13 20:08:41.764533 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.
Jan 13 20:08:41.764608 kernel: device-mapper: uevent: version 1.0.3
Jan 13 20:08:41.766406 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com
Jan 13 20:08:41.831282 kernel: raid6: neonx8   gen()  6561 MB/s
Jan 13 20:08:41.848239 kernel: raid6: neonx4   gen()  6525 MB/s
Jan 13 20:08:41.865239 kernel: raid6: neonx2   gen()  5438 MB/s
Jan 13 20:08:41.882240 kernel: raid6: neonx1   gen()  3946 MB/s
Jan 13 20:08:41.899245 kernel: raid6: int64x8  gen()  3597 MB/s
Jan 13 20:08:41.916241 kernel: raid6: int64x4  gen()  3704 MB/s
Jan 13 20:08:41.933238 kernel: raid6: int64x2  gen()  3600 MB/s
Jan 13 20:08:41.950979 kernel: raid6: int64x1  gen()  2765 MB/s
Jan 13 20:08:41.951018 kernel: raid6: using algorithm neonx8 gen() 6561 MB/s
Jan 13 20:08:41.968971 kernel: raid6: .... xor() 4763 MB/s, rmw enabled
Jan 13 20:08:41.969023 kernel: raid6: using neon recovery algorithm
Jan 13 20:08:41.976243 kernel: xor: measuring software checksum speed
Jan 13 20:08:41.976297 kernel:    8regs           : 11938 MB/sec
Jan 13 20:08:41.978239 kernel:    32regs          : 11356 MB/sec
Jan 13 20:08:41.980240 kernel:    arm64_neon      :  9006 MB/sec
Jan 13 20:08:41.980282 kernel: xor: using function: 8regs (11938 MB/sec)
Jan 13 20:08:42.062263 kernel: Btrfs loaded, zoned=no, fsverity=no
Jan 13 20:08:42.080977 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook.
Jan 13 20:08:42.092507 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files...
Jan 13 20:08:42.131260 systemd-udevd[472]: Using default interface naming scheme 'v255'.
Jan 13 20:08:42.138960 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files.
Jan 13 20:08:42.159572 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook...
Jan 13 20:08:42.185558 dracut-pre-trigger[483]: rd.md=0: removing MD RAID activation
Jan 13 20:08:42.240293 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook.
Jan 13 20:08:42.254482 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices...
Jan 13 20:08:42.365393 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices.
Jan 13 20:08:42.377780 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook...
Jan 13 20:08:42.425037 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook.
Jan 13 20:08:42.429854 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems.
Jan 13 20:08:42.440536 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes.
Jan 13 20:08:42.443636 systemd[1]: Reached target remote-fs.target - Remote File Systems.
Jan 13 20:08:42.463633 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook...
Jan 13 20:08:42.506645 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook.
Jan 13 20:08:42.580790 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36
Jan 13 20:08:42.580855 kernel: ena 0000:00:05.0: enabling device (0010 -> 0012)
Jan 13 20:08:42.601851 kernel: ena 0000:00:05.0: ENA device version: 0.10
Jan 13 20:08:42.603133 kernel: ena 0000:00:05.0: ENA controller version: 0.0.1 implementation version 1
Jan 13 20:08:42.603472 kernel: ena 0000:00:05.0: Elastic Network Adapter (ENA) found at mem 80114000, mac addr 06:93:d5:9c:fd:cf
Jan 13 20:08:42.580421 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully.
Jan 13 20:08:42.580647 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters.
Jan 13 20:08:42.586787 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters...
Jan 13 20:08:42.592394 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
Jan 13 20:08:42.592693 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup.
Jan 13 20:08:42.596478 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup...
Jan 13 20:08:42.606916 (udev-worker)[528]: Network interface NamePolicy= disabled on kernel command line.
Jan 13 20:08:42.633658 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup...
Jan 13 20:08:42.650587 kernel: ACPI: \_SB_.PCI0.GSI0: Enabled at IRQ 35
Jan 13 20:08:42.650662 kernel: nvme nvme0: pci function 0000:00:04.0
Jan 13 20:08:42.660236 kernel: nvme nvme0: 2/0/0 default/read/poll queues
Jan 13 20:08:42.675261 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk.
Jan 13 20:08:42.675338 kernel: GPT:9289727 != 16777215
Jan 13 20:08:42.675363 kernel: GPT:Alternate GPT header not at the end of the disk.
Jan 13 20:08:42.678917 kernel: GPT:9289727 != 16777215
Jan 13 20:08:42.678982 kernel: GPT: Use GNU Parted to correct GPT errors.
Jan 13 20:08:42.679007 kernel:  nvme0n1: p1 p2 p3 p4 p6 p7 p9
Jan 13 20:08:42.687321 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup.
Jan 13 20:08:42.701598 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters...
Jan 13 20:08:42.739290 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters.
Jan 13 20:08:42.784536 kernel: BTRFS: device fsid 2be7cc1c-29d4-4496-b29b-8561323213d2 devid 1 transid 38 /dev/nvme0n1p3 scanned by (udev-worker) (526)
Jan 13 20:08:42.822322 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/nvme0n1p6 scanned by (udev-worker) (543)
Jan 13 20:08:42.834773 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Amazon Elastic Block Store EFI-SYSTEM.
Jan 13 20:08:42.879361 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Amazon Elastic Block Store USR-A.
Jan 13 20:08:42.888922 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Amazon Elastic Block Store USR-A.
Jan 13 20:08:42.907629 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary...
Jan 13 20:08:42.928050 disk-uuid[661]: Primary Header is updated.
Jan 13 20:08:42.928050 disk-uuid[661]: Secondary Entries is updated.
Jan 13 20:08:42.928050 disk-uuid[661]: Secondary Header is updated.
Jan 13 20:08:42.938927 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Amazon Elastic Block Store ROOT.
Jan 13 20:08:42.949709 kernel:  nvme0n1: p1 p2 p3 p4 p6 p7 p9
Jan 13 20:08:43.198435 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM.
Jan 13 20:08:43.968296 kernel:  nvme0n1: p1 p2 p3 p4 p6 p7 p9
Jan 13 20:08:43.968453 disk-uuid[662]: The operation has completed successfully.
Jan 13 20:08:44.143110 systemd[1]: disk-uuid.service: Deactivated successfully.
Jan 13 20:08:44.143328 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary.
Jan 13 20:08:44.198515 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr...
Jan 13 20:08:44.208292 sh[923]: Success
Jan 13 20:08:44.233323 kernel: device-mapper: verity: sha256 using implementation "sha256-ce"
Jan 13 20:08:44.367159 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr.
Jan 13 20:08:44.375265 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr.
Jan 13 20:08:44.387495 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr...
Jan 13 20:08:44.422708 kernel: BTRFS info (device dm-0): first mount of filesystem 2be7cc1c-29d4-4496-b29b-8561323213d2
Jan 13 20:08:44.422784 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm
Jan 13 20:08:44.422825 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead
Jan 13 20:08:44.424425 kernel: BTRFS info (device dm-0): disabling log replay at mount time
Jan 13 20:08:44.425619 kernel: BTRFS info (device dm-0): using free space tree
Jan 13 20:08:44.452245 kernel: BTRFS info (device dm-0): enabling ssd optimizations
Jan 13 20:08:44.455394 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr.
Jan 13 20:08:44.460450 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met.
Jan 13 20:08:44.475473 systemd[1]: Starting ignition-setup.service - Ignition (setup)...
Jan 13 20:08:44.486416 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline...
Jan 13 20:08:44.507757 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem 9f8ecb6c-ace6-4d16-8781-f4e964dc0779
Jan 13 20:08:44.507820 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm
Jan 13 20:08:44.509004 kernel: BTRFS info (device nvme0n1p6): using free space tree
Jan 13 20:08:44.519681 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations
Jan 13 20:08:44.535927 systemd[1]: mnt-oem.mount: Deactivated successfully.
Jan 13 20:08:44.540845 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem 9f8ecb6c-ace6-4d16-8781-f4e964dc0779
Jan 13 20:08:44.550755 systemd[1]: Finished ignition-setup.service - Ignition (setup).
Jan 13 20:08:44.564594 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)...
Jan 13 20:08:44.674270 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline.
Jan 13 20:08:44.690612 systemd[1]: Starting systemd-networkd.service - Network Configuration...
Jan 13 20:08:44.754361 systemd-networkd[1118]: lo: Link UP
Jan 13 20:08:44.754823 systemd-networkd[1118]: lo: Gained carrier
Jan 13 20:08:44.757928 systemd-networkd[1118]: Enumeration completed
Jan 13 20:08:44.758815 systemd-networkd[1118]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name.
Jan 13 20:08:44.758822 systemd-networkd[1118]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network.
Jan 13 20:08:44.759201 systemd[1]: Started systemd-networkd.service - Network Configuration.
Jan 13 20:08:44.778157 systemd[1]: Reached target network.target - Network.
Jan 13 20:08:44.782821 systemd-networkd[1118]: eth0: Link UP
Jan 13 20:08:44.782834 systemd-networkd[1118]: eth0: Gained carrier
Jan 13 20:08:44.782853 systemd-networkd[1118]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name.
Jan 13 20:08:44.800899 ignition[1024]: Ignition 2.20.0
Jan 13 20:08:44.800920 ignition[1024]: Stage: fetch-offline
Jan 13 20:08:44.801372 ignition[1024]: no configs at "/usr/lib/ignition/base.d"
Jan 13 20:08:44.805617 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline).
Jan 13 20:08:44.801396 ignition[1024]: no config dir at "/usr/lib/ignition/base.platform.d/aws"
Jan 13 20:08:44.807519 systemd-networkd[1118]: eth0: DHCPv4 address 172.31.22.23/20, gateway 172.31.16.1 acquired from 172.31.16.1
Jan 13 20:08:44.801813 ignition[1024]: Ignition finished successfully
Jan 13 20:08:44.823651 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)...
Jan 13 20:08:44.847983 ignition[1126]: Ignition 2.20.0
Jan 13 20:08:44.848012 ignition[1126]: Stage: fetch
Jan 13 20:08:44.848730 ignition[1126]: no configs at "/usr/lib/ignition/base.d"
Jan 13 20:08:44.848755 ignition[1126]: no config dir at "/usr/lib/ignition/base.platform.d/aws"
Jan 13 20:08:44.848916 ignition[1126]: PUT http://169.254.169.254/latest/api/token: attempt #1
Jan 13 20:08:44.870671 ignition[1126]: PUT result: OK
Jan 13 20:08:44.873729 ignition[1126]: parsed url from cmdline: ""
Jan 13 20:08:44.873744 ignition[1126]: no config URL provided
Jan 13 20:08:44.873758 ignition[1126]: reading system config file "/usr/lib/ignition/user.ign"
Jan 13 20:08:44.873784 ignition[1126]: no config at "/usr/lib/ignition/user.ign"
Jan 13 20:08:44.873814 ignition[1126]: PUT http://169.254.169.254/latest/api/token: attempt #1
Jan 13 20:08:44.876044 ignition[1126]: PUT result: OK
Jan 13 20:08:44.876125 ignition[1126]: GET http://169.254.169.254/2019-10-01/user-data: attempt #1
Jan 13 20:08:44.878766 ignition[1126]: GET result: OK
Jan 13 20:08:44.887054 unknown[1126]: fetched base config from "system"
Jan 13 20:08:44.880052 ignition[1126]: parsing config with SHA512: 810b099e41ebffcb9fcc1581e747a2893d41076fb048c6ba11492309ce7336c015a41d29156ee1d0a3ee5d31c651ba22254a8d0b075c56241e31ca9fd343a3e5
Jan 13 20:08:44.887070 unknown[1126]: fetched base config from "system"
Jan 13 20:08:44.887524 ignition[1126]: fetch: fetch complete
Jan 13 20:08:44.887083 unknown[1126]: fetched user config from "aws"
Jan 13 20:08:44.887535 ignition[1126]: fetch: fetch passed
Jan 13 20:08:44.895456 systemd[1]: Finished ignition-fetch.service - Ignition (fetch).
Jan 13 20:08:44.887606 ignition[1126]: Ignition finished successfully
Jan 13 20:08:44.906601 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)...
Jan 13 20:08:44.945066 ignition[1133]: Ignition 2.20.0
Jan 13 20:08:44.945598 ignition[1133]: Stage: kargs
Jan 13 20:08:44.946185 ignition[1133]: no configs at "/usr/lib/ignition/base.d"
Jan 13 20:08:44.946231 ignition[1133]: no config dir at "/usr/lib/ignition/base.platform.d/aws"
Jan 13 20:08:44.946410 ignition[1133]: PUT http://169.254.169.254/latest/api/token: attempt #1
Jan 13 20:08:44.951374 ignition[1133]: PUT result: OK
Jan 13 20:08:44.960192 ignition[1133]: kargs: kargs passed
Jan 13 20:08:44.960302 ignition[1133]: Ignition finished successfully
Jan 13 20:08:44.972254 systemd[1]: Finished ignition-kargs.service - Ignition (kargs).
Jan 13 20:08:44.983510 systemd[1]: Starting ignition-disks.service - Ignition (disks)...
Jan 13 20:08:45.009122 ignition[1140]: Ignition 2.20.0
Jan 13 20:08:45.009144 ignition[1140]: Stage: disks
Jan 13 20:08:45.009969 ignition[1140]: no configs at "/usr/lib/ignition/base.d"
Jan 13 20:08:45.009995 ignition[1140]: no config dir at "/usr/lib/ignition/base.platform.d/aws"
Jan 13 20:08:45.010156 ignition[1140]: PUT http://169.254.169.254/latest/api/token: attempt #1
Jan 13 20:08:45.012859 ignition[1140]: PUT result: OK
Jan 13 20:08:45.023451 ignition[1140]: disks: disks passed
Jan 13 20:08:45.023540 ignition[1140]: Ignition finished successfully
Jan 13 20:08:45.025970 systemd[1]: Finished ignition-disks.service - Ignition (disks).
Jan 13 20:08:45.031965 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device.
Jan 13 20:08:45.034458 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems.
Jan 13 20:08:45.042395 systemd[1]: Reached target local-fs.target - Local File Systems.
Jan 13 20:08:45.044796 systemd[1]: Reached target sysinit.target - System Initialization.
Jan 13 20:08:45.047141 systemd[1]: Reached target basic.target - Basic System.
Jan 13 20:08:45.069577 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT...
Jan 13 20:08:45.120550 systemd-fsck[1149]: ROOT: clean, 14/553520 files, 52654/553472 blocks
Jan 13 20:08:45.126652 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT.
Jan 13 20:08:45.145532 systemd[1]: Mounting sysroot.mount - /sysroot...
Jan 13 20:08:45.233301 kernel: EXT4-fs (nvme0n1p9): mounted filesystem f9a95e53-2d63-4443-b523-cb2108fb48f6 r/w with ordered data mode. Quota mode: none.
Jan 13 20:08:45.234483 systemd[1]: Mounted sysroot.mount - /sysroot.
Jan 13 20:08:45.239693 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System.
Jan 13 20:08:45.262415 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem...
Jan 13 20:08:45.272783 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr...
Jan 13 20:08:45.277071 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met.
Jan 13 20:08:45.277173 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot).
Jan 13 20:08:45.311309 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/nvme0n1p6 scanned by mount (1168)
Jan 13 20:08:45.311354 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem 9f8ecb6c-ace6-4d16-8781-f4e964dc0779
Jan 13 20:08:45.311390 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm
Jan 13 20:08:45.311417 kernel: BTRFS info (device nvme0n1p6): using free space tree
Jan 13 20:08:45.277244 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup.
Jan 13 20:08:45.289967 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr.
Jan 13 20:08:45.317595 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup...
Jan 13 20:08:45.328269 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations
Jan 13 20:08:45.331823 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem.
Jan 13 20:08:45.423867 initrd-setup-root[1193]: cut: /sysroot/etc/passwd: No such file or directory
Jan 13 20:08:45.434038 initrd-setup-root[1200]: cut: /sysroot/etc/group: No such file or directory
Jan 13 20:08:45.443964 initrd-setup-root[1207]: cut: /sysroot/etc/shadow: No such file or directory
Jan 13 20:08:45.453981 initrd-setup-root[1214]: cut: /sysroot/etc/gshadow: No such file or directory
Jan 13 20:08:45.620614 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup.
Jan 13 20:08:45.634411 systemd[1]: Starting ignition-mount.service - Ignition (mount)...
Jan 13 20:08:45.644528 systemd[1]: Starting sysroot-boot.service - /sysroot/boot...
Jan 13 20:08:45.657725 systemd[1]: sysroot-oem.mount: Deactivated successfully.
Jan 13 20:08:45.661614 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem 9f8ecb6c-ace6-4d16-8781-f4e964dc0779
Jan 13 20:08:45.706504 ignition[1286]: INFO     : Ignition 2.20.0
Jan 13 20:08:45.709599 ignition[1286]: INFO     : Stage: mount
Jan 13 20:08:45.709599 ignition[1286]: INFO     : no configs at "/usr/lib/ignition/base.d"
Jan 13 20:08:45.709599 ignition[1286]: INFO     : no config dir at "/usr/lib/ignition/base.platform.d/aws"
Jan 13 20:08:45.709599 ignition[1286]: INFO     : PUT http://169.254.169.254/latest/api/token: attempt #1
Jan 13 20:08:45.727403 ignition[1286]: INFO     : PUT result: OK
Jan 13 20:08:45.727403 ignition[1286]: INFO     : mount: mount passed
Jan 13 20:08:45.727403 ignition[1286]: INFO     : Ignition finished successfully
Jan 13 20:08:45.716254 systemd[1]: Finished sysroot-boot.service - /sysroot/boot.
Jan 13 20:08:45.727288 systemd[1]: Finished ignition-mount.service - Ignition (mount).
Jan 13 20:08:45.746448 systemd[1]: Starting ignition-files.service - Ignition (files)...
Jan 13 20:08:45.768593 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem...
Jan 13 20:08:45.801899 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/nvme0n1p6 scanned by mount (1299)
Jan 13 20:08:45.801964 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem 9f8ecb6c-ace6-4d16-8781-f4e964dc0779
Jan 13 20:08:45.801991 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm
Jan 13 20:08:45.804603 kernel: BTRFS info (device nvme0n1p6): using free space tree
Jan 13 20:08:45.809241 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations
Jan 13 20:08:45.813070 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem.
Jan 13 20:08:45.845741 ignition[1316]: INFO     : Ignition 2.20.0
Jan 13 20:08:45.845741 ignition[1316]: INFO     : Stage: files
Jan 13 20:08:45.850843 ignition[1316]: INFO     : no configs at "/usr/lib/ignition/base.d"
Jan 13 20:08:45.850843 ignition[1316]: INFO     : no config dir at "/usr/lib/ignition/base.platform.d/aws"
Jan 13 20:08:45.850843 ignition[1316]: INFO     : PUT http://169.254.169.254/latest/api/token: attempt #1
Jan 13 20:08:45.863274 ignition[1316]: INFO     : PUT result: OK
Jan 13 20:08:45.867794 ignition[1316]: DEBUG    : files: compiled without relabeling support, skipping
Jan 13 20:08:45.873652 ignition[1316]: INFO     : files: ensureUsers: op(1): [started]  creating or modifying user "core"
Jan 13 20:08:45.873652 ignition[1316]: DEBUG    : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core"
Jan 13 20:08:45.890955 ignition[1316]: INFO     : files: ensureUsers: op(1): [finished] creating or modifying user "core"
Jan 13 20:08:45.895018 ignition[1316]: INFO     : files: ensureUsers: op(2): [started]  adding ssh keys to user "core"
Jan 13 20:08:45.899325 unknown[1316]: wrote ssh authorized keys file for user: core
Jan 13 20:08:45.903123 ignition[1316]: INFO     : files: ensureUsers: op(2): [finished] adding ssh keys to user "core"
Jan 13 20:08:45.908318 ignition[1316]: INFO     : files: createFilesystemsFiles: createFiles: op(3): [started]  writing file "/sysroot/home/core/install.sh"
Jan 13 20:08:45.908318 ignition[1316]: INFO     : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh"
Jan 13 20:08:45.908318 ignition[1316]: INFO     : files: createFilesystemsFiles: createFiles: op(4): [started]  writing file "/sysroot/etc/flatcar/update.conf"
Jan 13 20:08:45.908318 ignition[1316]: INFO     : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf"
Jan 13 20:08:45.908318 ignition[1316]: INFO     : files: createFilesystemsFiles: createFiles: op(5): [started]  writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.30.1-arm64.raw"
Jan 13 20:08:45.908318 ignition[1316]: INFO     : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.30.1-arm64.raw"
Jan 13 20:08:45.908318 ignition[1316]: INFO     : files: createFilesystemsFiles: createFiles: op(6): [started]  writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.30.1-arm64.raw"
Jan 13 20:08:45.908318 ignition[1316]: INFO     : files: createFilesystemsFiles: createFiles: op(6): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.30.1-arm64.raw: attempt #1
Jan 13 20:08:46.396495 ignition[1316]: INFO     : files: createFilesystemsFiles: createFiles: op(6): GET result: OK
Jan 13 20:08:46.481360 systemd-networkd[1118]: eth0: Gained IPv6LL
Jan 13 20:08:46.768312 ignition[1316]: INFO     : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.30.1-arm64.raw"
Jan 13 20:08:46.777495 ignition[1316]: INFO     : files: createResultFile: createFiles: op(7): [started]  writing file "/sysroot/etc/.ignition-result.json"
Jan 13 20:08:46.777495 ignition[1316]: INFO     : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json"
Jan 13 20:08:46.777495 ignition[1316]: INFO     : files: files passed
Jan 13 20:08:46.777495 ignition[1316]: INFO     : Ignition finished successfully
Jan 13 20:08:46.791455 systemd[1]: Finished ignition-files.service - Ignition (files).
Jan 13 20:08:46.814520 systemd[1]: Starting ignition-quench.service - Ignition (record completion)...
Jan 13 20:08:46.822603 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion...
Jan 13 20:08:46.827947 systemd[1]: ignition-quench.service: Deactivated successfully.
Jan 13 20:08:46.828166 systemd[1]: Finished ignition-quench.service - Ignition (record completion).
Jan 13 20:08:46.860256 initrd-setup-root-after-ignition[1344]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory
Jan 13 20:08:46.860256 initrd-setup-root-after-ignition[1344]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory
Jan 13 20:08:46.869997 initrd-setup-root-after-ignition[1348]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory
Jan 13 20:08:46.877202 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion.
Jan 13 20:08:46.883040 systemd[1]: Reached target ignition-complete.target - Ignition Complete.
Jan 13 20:08:46.905649 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root...
Jan 13 20:08:46.952859 systemd[1]: initrd-parse-etc.service: Deactivated successfully.
Jan 13 20:08:46.954281 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root.
Jan 13 20:08:46.965487 systemd[1]: Reached target initrd-fs.target - Initrd File Systems.
Jan 13 20:08:46.967634 systemd[1]: Reached target initrd.target - Initrd Default Target.
Jan 13 20:08:46.969738 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met.
Jan 13 20:08:46.984558 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook...
Jan 13 20:08:47.013261 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook.
Jan 13 20:08:47.033201 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons...
Jan 13 20:08:47.059959 systemd[1]: initrd-cleanup.service: Deactivated successfully.
Jan 13 20:08:47.060371 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons.
Jan 13 20:08:47.070658 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups.
Jan 13 20:08:47.073018 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes.
Jan 13 20:08:47.075435 systemd[1]: Stopped target timers.target - Timer Units.
Jan 13 20:08:47.077332 systemd[1]: dracut-pre-pivot.service: Deactivated successfully.
Jan 13 20:08:47.077430 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook.
Jan 13 20:08:47.080114 systemd[1]: Stopped target initrd.target - Initrd Default Target.
Jan 13 20:08:47.088425 systemd[1]: Stopped target basic.target - Basic System.
Jan 13 20:08:47.101656 systemd[1]: Stopped target ignition-complete.target - Ignition Complete.
Jan 13 20:08:47.104175 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup.
Jan 13 20:08:47.106891 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device.
Jan 13 20:08:47.109517 systemd[1]: Stopped target remote-fs.target - Remote File Systems.
Jan 13 20:08:47.111831 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems.
Jan 13 20:08:47.114668 systemd[1]: Stopped target sysinit.target - System Initialization.
Jan 13 20:08:47.117071 systemd[1]: Stopped target local-fs.target - Local File Systems.
Jan 13 20:08:47.119459 systemd[1]: Stopped target swap.target - Swaps.
Jan 13 20:08:47.121395 systemd[1]: dracut-pre-mount.service: Deactivated successfully.
Jan 13 20:08:47.121504 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook.
Jan 13 20:08:47.124168 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes.
Jan 13 20:08:47.126777 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre).
Jan 13 20:08:47.129487 systemd[1]: clevis-luks-askpass.path: Deactivated successfully.
Jan 13 20:08:47.159846 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch.
Jan 13 20:08:47.162389 systemd[1]: dracut-initqueue.service: Deactivated successfully.
Jan 13 20:08:47.162495 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook.
Jan 13 20:08:47.164898 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully.
Jan 13 20:08:47.164980 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion.
Jan 13 20:08:47.167660 systemd[1]: ignition-files.service: Deactivated successfully.
Jan 13 20:08:47.167758 systemd[1]: Stopped ignition-files.service - Ignition (files).
Jan 13 20:08:47.193487 systemd[1]: Stopping ignition-mount.service - Ignition (mount)...
Jan 13 20:08:47.197432 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot...
Jan 13 20:08:47.199442 systemd[1]: systemd-udev-trigger.service: Deactivated successfully.
Jan 13 20:08:47.199556 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices.
Jan 13 20:08:47.208136 systemd[1]: dracut-pre-trigger.service: Deactivated successfully.
Jan 13 20:08:47.208802 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook.
Jan 13 20:08:47.238905 ignition[1369]: INFO     : Ignition 2.20.0
Jan 13 20:08:47.242251 ignition[1369]: INFO     : Stage: umount
Jan 13 20:08:47.242251 ignition[1369]: INFO     : no configs at "/usr/lib/ignition/base.d"
Jan 13 20:08:47.242251 ignition[1369]: INFO     : no config dir at "/usr/lib/ignition/base.platform.d/aws"
Jan 13 20:08:47.242251 ignition[1369]: INFO     : PUT http://169.254.169.254/latest/api/token: attempt #1
Jan 13 20:08:47.247689 systemd[1]: sysroot-boot.mount: Deactivated successfully.
Jan 13 20:08:47.255290 ignition[1369]: INFO     : PUT result: OK
Jan 13 20:08:47.259299 ignition[1369]: INFO     : umount: umount passed
Jan 13 20:08:47.261973 ignition[1369]: INFO     : Ignition finished successfully
Jan 13 20:08:47.264811 systemd[1]: ignition-mount.service: Deactivated successfully.
Jan 13 20:08:47.266460 systemd[1]: Stopped ignition-mount.service - Ignition (mount).
Jan 13 20:08:47.271891 systemd[1]: ignition-disks.service: Deactivated successfully.
Jan 13 20:08:47.271986 systemd[1]: Stopped ignition-disks.service - Ignition (disks).
Jan 13 20:08:47.274490 systemd[1]: ignition-kargs.service: Deactivated successfully.
Jan 13 20:08:47.274597 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs).
Jan 13 20:08:47.277179 systemd[1]: ignition-fetch.service: Deactivated successfully.
Jan 13 20:08:47.277294 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch).
Jan 13 20:08:47.279733 systemd[1]: Stopped target network.target - Network.
Jan 13 20:08:47.282059 systemd[1]: ignition-fetch-offline.service: Deactivated successfully.
Jan 13 20:08:47.282169 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline).
Jan 13 20:08:47.285038 systemd[1]: Stopped target paths.target - Path Units.
Jan 13 20:08:47.287100 systemd[1]: systemd-ask-password-console.path: Deactivated successfully.
Jan 13 20:08:47.313349 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch.
Jan 13 20:08:47.315853 systemd[1]: Stopped target slices.target - Slice Units.
Jan 13 20:08:47.317679 systemd[1]: Stopped target sockets.target - Socket Units.
Jan 13 20:08:47.319670 systemd[1]: iscsid.socket: Deactivated successfully.
Jan 13 20:08:47.319752 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket.
Jan 13 20:08:47.321784 systemd[1]: iscsiuio.socket: Deactivated successfully.
Jan 13 20:08:47.321853 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket.
Jan 13 20:08:47.324525 systemd[1]: ignition-setup.service: Deactivated successfully.
Jan 13 20:08:47.324609 systemd[1]: Stopped ignition-setup.service - Ignition (setup).
Jan 13 20:08:47.346317 systemd[1]: ignition-setup-pre.service: Deactivated successfully.
Jan 13 20:08:47.346424 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup.
Jan 13 20:08:47.349069 systemd[1]: Stopping systemd-networkd.service - Network Configuration...
Jan 13 20:08:47.351450 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution...
Jan 13 20:08:47.373314 systemd-networkd[1118]: eth0: DHCPv6 lease lost
Jan 13 20:08:47.377974 systemd[1]: systemd-networkd.service: Deactivated successfully.
Jan 13 20:08:47.378182 systemd[1]: Stopped systemd-networkd.service - Network Configuration.
Jan 13 20:08:47.389795 systemd[1]: systemd-resolved.service: Deactivated successfully.
Jan 13 20:08:47.390718 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution.
Jan 13 20:08:47.400720 systemd[1]: systemd-networkd.socket: Deactivated successfully.
Jan 13 20:08:47.400883 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket.
Jan 13 20:08:47.419459 systemd[1]: Stopping network-cleanup.service - Network Cleanup...
Jan 13 20:08:47.422406 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully.
Jan 13 20:08:47.422529 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline.
Jan 13 20:08:47.425663 systemd[1]: systemd-sysctl.service: Deactivated successfully.
Jan 13 20:08:47.425770 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables.
Jan 13 20:08:47.439488 systemd[1]: systemd-modules-load.service: Deactivated successfully.
Jan 13 20:08:47.439596 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules.
Jan 13 20:08:47.442278 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.
Jan 13 20:08:47.442364 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories.
Jan 13 20:08:47.446434 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files...
Jan 13 20:08:47.475980 systemd[1]: network-cleanup.service: Deactivated successfully.
Jan 13 20:08:47.479747 systemd[1]: Stopped network-cleanup.service - Network Cleanup.
Jan 13 20:08:47.489942 systemd[1]: systemd-udevd.service: Deactivated successfully.
Jan 13 20:08:47.491309 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files.
Jan 13 20:08:47.498867 systemd[1]: sysroot-boot.service: Deactivated successfully.
Jan 13 20:08:47.499116 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot.
Jan 13 20:08:47.507874 systemd[1]: systemd-udevd-control.socket: Deactivated successfully.
Jan 13 20:08:47.508008 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket.
Jan 13 20:08:47.511287 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully.
Jan 13 20:08:47.511376 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket.
Jan 13 20:08:47.523243 systemd[1]: dracut-pre-udev.service: Deactivated successfully.
Jan 13 20:08:47.523365 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook.
Jan 13 20:08:47.526135 systemd[1]: dracut-cmdline.service: Deactivated successfully.
Jan 13 20:08:47.526247 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook.
Jan 13 20:08:47.528978 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully.
Jan 13 20:08:47.529069 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters.
Jan 13 20:08:47.532158 systemd[1]: initrd-setup-root.service: Deactivated successfully.
Jan 13 20:08:47.532262 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup.
Jan 13 20:08:47.555493 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database...
Jan 13 20:08:47.557968 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.
Jan 13 20:08:47.558071 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev.
Jan 13 20:08:47.561098 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.
Jan 13 20:08:47.561185 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully.
Jan 13 20:08:47.564166 systemd[1]: kmod-static-nodes.service: Deactivated successfully.
Jan 13 20:08:47.564264 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes.
Jan 13 20:08:47.585940 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
Jan 13 20:08:47.586033 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup.
Jan 13 20:08:47.605416 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.
Jan 13 20:08:47.605770 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database.
Jan 13 20:08:47.613301 systemd[1]: Reached target initrd-switch-root.target - Switch Root.
Jan 13 20:08:47.634556 systemd[1]: Starting initrd-switch-root.service - Switch Root...
Jan 13 20:08:47.652443 systemd[1]: Switching root.
Jan 13 20:08:47.685028 systemd-journald[252]: Journal stopped
Jan 13 20:08:49.447755 systemd-journald[252]: Received SIGTERM from PID 1 (systemd).
Jan 13 20:08:49.447894 kernel: SELinux:  policy capability network_peer_controls=1
Jan 13 20:08:49.447939 kernel: SELinux:  policy capability open_perms=1
Jan 13 20:08:49.447970 kernel: SELinux:  policy capability extended_socket_class=1
Jan 13 20:08:49.448001 kernel: SELinux:  policy capability always_check_network=0
Jan 13 20:08:49.448031 kernel: SELinux:  policy capability cgroup_seclabel=1
Jan 13 20:08:49.448062 kernel: SELinux:  policy capability nnp_nosuid_transition=1
Jan 13 20:08:49.448092 kernel: SELinux:  policy capability genfs_seclabel_symlinks=0
Jan 13 20:08:49.448127 kernel: SELinux:  policy capability ioctl_skip_cloexec=0
Jan 13 20:08:49.448157 kernel: audit: type=1403 audit(1736798927.888:2): auid=4294967295 ses=4294967295 lsm=selinux res=1
Jan 13 20:08:49.448197 systemd[1]: Successfully loaded SELinux policy in 50.266ms.
Jan 13 20:08:49.448300 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 22.724ms.
Jan 13 20:08:49.448339 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)
Jan 13 20:08:49.448372 systemd[1]: Detected virtualization amazon.
Jan 13 20:08:49.448401 systemd[1]: Detected architecture arm64.
Jan 13 20:08:49.448429 systemd[1]: Detected first boot.
Jan 13 20:08:49.448460 systemd[1]: Initializing machine ID from VM UUID.
Jan 13 20:08:49.448497 zram_generator::config[1410]: No configuration found.
Jan 13 20:08:49.448531 systemd[1]: Populated /etc with preset unit settings.
Jan 13 20:08:49.448563 systemd[1]: initrd-switch-root.service: Deactivated successfully.
Jan 13 20:08:49.448593 systemd[1]: Stopped initrd-switch-root.service - Switch Root.
Jan 13 20:08:49.448622 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.
Jan 13 20:08:49.448658 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config.
Jan 13 20:08:49.448699 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run.
Jan 13 20:08:49.448728 systemd[1]: Created slice system-getty.slice - Slice /system/getty.
Jan 13 20:08:49.448760 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe.
Jan 13 20:08:49.448792 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty.
Jan 13 20:08:49.448824 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit.
Jan 13 20:08:49.448856 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck.
Jan 13 20:08:49.448891 systemd[1]: Created slice user.slice - User and Session Slice.
Jan 13 20:08:49.448927 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch.
Jan 13 20:08:49.448956 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch.
Jan 13 20:08:49.448985 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch.
Jan 13 20:08:49.449016 systemd[1]: Set up automount boot.automount - Boot partition Automount Point.
Jan 13 20:08:49.449051 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point.
Jan 13 20:08:49.449096 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM...
Jan 13 20:08:49.449133 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0...
Jan 13 20:08:49.449163 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre).
Jan 13 20:08:49.449191 systemd[1]: Stopped target initrd-switch-root.target - Switch Root.
Jan 13 20:08:49.452642 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems.
Jan 13 20:08:49.452688 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System.
Jan 13 20:08:49.452718 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes.
Jan 13 20:08:49.452750 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes.
Jan 13 20:08:49.452781 systemd[1]: Reached target remote-fs.target - Remote File Systems.
Jan 13 20:08:49.452810 systemd[1]: Reached target slices.target - Slice Units.
Jan 13 20:08:49.452841 systemd[1]: Reached target swap.target - Swaps.
Jan 13 20:08:49.452870 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes.
Jan 13 20:08:49.452903 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket.
Jan 13 20:08:49.452936 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket.
Jan 13 20:08:49.452965 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket.
Jan 13 20:08:49.452996 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket.
Jan 13 20:08:49.453024 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket.
Jan 13 20:08:49.453052 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System...
Jan 13 20:08:49.453101 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System...
Jan 13 20:08:49.454357 systemd[1]: Mounting media.mount - External Media Directory...
Jan 13 20:08:49.454392 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System...
Jan 13 20:08:49.454431 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System...
Jan 13 20:08:49.454463 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp...
Jan 13 20:08:49.454495 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).
Jan 13 20:08:49.454524 systemd[1]: Reached target machines.target - Containers.
Jan 13 20:08:49.454553 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files...
Jan 13 20:08:49.454584 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met.
Jan 13 20:08:49.456265 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes...
Jan 13 20:08:49.456312 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs...
Jan 13 20:08:49.456342 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod...
Jan 13 20:08:49.456379 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm...
Jan 13 20:08:49.456412 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore...
Jan 13 20:08:49.456441 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse...
Jan 13 20:08:49.456489 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop...
Jan 13 20:08:49.456523 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf).
Jan 13 20:08:49.456553 systemd[1]: systemd-fsck-root.service: Deactivated successfully.
Jan 13 20:08:49.456599 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device.
Jan 13 20:08:49.456632 systemd[1]: systemd-fsck-usr.service: Deactivated successfully.
Jan 13 20:08:49.456668 systemd[1]: Stopped systemd-fsck-usr.service.
Jan 13 20:08:49.456700 kernel: fuse: init (API version 7.39)
Jan 13 20:08:49.456730 systemd[1]: Starting systemd-journald.service - Journal Service...
Jan 13 20:08:49.456759 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules...
Jan 13 20:08:49.456788 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line...
Jan 13 20:08:49.456827 kernel: loop: module loaded
Jan 13 20:08:49.456855 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems...
Jan 13 20:08:49.456883 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices...
Jan 13 20:08:49.456913 systemd[1]: verity-setup.service: Deactivated successfully.
Jan 13 20:08:49.456947 systemd[1]: Stopped verity-setup.service.
Jan 13 20:08:49.456978 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System.
Jan 13 20:08:49.457009 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System.
Jan 13 20:08:49.457041 systemd[1]: Mounted media.mount - External Media Directory.
Jan 13 20:08:49.457070 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System.
Jan 13 20:08:49.457119 kernel: ACPI: bus type drm_connector registered
Jan 13 20:08:49.457151 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System.
Jan 13 20:08:49.457180 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp.
Jan 13 20:08:49.458270 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes.
Jan 13 20:08:49.458395 systemd-journald[1495]: Collecting audit messages is disabled.
Jan 13 20:08:49.458445 systemd[1]: modprobe@configfs.service: Deactivated successfully.
Jan 13 20:08:49.458475 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs.
Jan 13 20:08:49.458503 systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
Jan 13 20:08:49.458539 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod.
Jan 13 20:08:49.458569 systemd-journald[1495]: Journal started
Jan 13 20:08:49.458626 systemd-journald[1495]: Runtime Journal (/run/log/journal/ec2d6d8e90d95936b6e9c32f02642c9d) is 8.0M, max 75.3M, 67.3M free.
Jan 13 20:08:48.879380 systemd[1]: Queued start job for default target multi-user.target.
Jan 13 20:08:48.904571 systemd[1]: Unnecessary job was removed for dev-nvme0n1p6.device - /dev/nvme0n1p6.
Jan 13 20:08:48.905405 systemd[1]: systemd-journald.service: Deactivated successfully.
Jan 13 20:08:49.464269 systemd[1]: Started systemd-journald.service - Journal Service.
Jan 13 20:08:49.469160 systemd[1]: modprobe@drm.service: Deactivated successfully.
Jan 13 20:08:49.469628 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm.
Jan 13 20:08:49.474784 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
Jan 13 20:08:49.475093 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore.
Jan 13 20:08:49.480973 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files.
Jan 13 20:08:49.486606 systemd[1]: modprobe@fuse.service: Deactivated successfully.
Jan 13 20:08:49.486892 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse.
Jan 13 20:08:49.492410 systemd[1]: modprobe@loop.service: Deactivated successfully.
Jan 13 20:08:49.492709 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop.
Jan 13 20:08:49.497980 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules.
Jan 13 20:08:49.503410 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line.
Jan 13 20:08:49.509187 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems.
Jan 13 20:08:49.535453 systemd[1]: Reached target network-pre.target - Preparation for Network.
Jan 13 20:08:49.546464 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System...
Jan 13 20:08:49.561412 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System...
Jan 13 20:08:49.568668 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).
Jan 13 20:08:49.568741 systemd[1]: Reached target local-fs.target - Local File Systems.
Jan 13 20:08:49.575948 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink).
Jan 13 20:08:49.586986 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown...
Jan 13 20:08:49.603509 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache...
Jan 13 20:08:49.608052 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met.
Jan 13 20:08:49.620624 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database...
Jan 13 20:08:49.630708 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage...
Jan 13 20:08:49.637793 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
Jan 13 20:08:49.648593 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed...
Jan 13 20:08:49.652864 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met.
Jan 13 20:08:49.657734 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables...
Jan 13 20:08:49.664815 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/...
Jan 13 20:08:49.675079 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully...
Jan 13 20:08:49.683927 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices.
Jan 13 20:08:49.694882 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System.
Jan 13 20:08:49.710474 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System.
Jan 13 20:08:49.716788 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown.
Jan 13 20:08:49.735566 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization...
Jan 13 20:08:49.741771 systemd-journald[1495]: Time spent on flushing to /var/log/journal/ec2d6d8e90d95936b6e9c32f02642c9d is 54.419ms for 893 entries.
Jan 13 20:08:49.741771 systemd-journald[1495]: System Journal (/var/log/journal/ec2d6d8e90d95936b6e9c32f02642c9d) is 8.0M, max 195.6M, 187.6M free.
Jan 13 20:08:49.817189 systemd-journald[1495]: Received client request to flush runtime journal.
Jan 13 20:08:49.817277 kernel: loop0: detected capacity change from 0 to 53784
Jan 13 20:08:49.785997 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed.
Jan 13 20:08:49.791366 systemd[1]: Reached target first-boot-complete.target - First Boot Complete.
Jan 13 20:08:49.813946 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk...
Jan 13 20:08:49.827360 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage.
Jan 13 20:08:49.855484 udevadm[1545]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in.
Jan 13 20:08:49.862010 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables.
Jan 13 20:08:49.881946 systemd-tmpfiles[1540]: ACLs are not supported, ignoring.
Jan 13 20:08:49.881979 systemd-tmpfiles[1540]: ACLs are not supported, ignoring.
Jan 13 20:08:49.889868 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
Jan 13 20:08:49.891582 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk.
Jan 13 20:08:49.910397 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully.
Jan 13 20:08:49.921728 systemd[1]: Starting systemd-sysusers.service - Create System Users...
Jan 13 20:08:49.948466 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher
Jan 13 20:08:49.973359 kernel: loop1: detected capacity change from 0 to 194096
Jan 13 20:08:50.050490 systemd[1]: Finished systemd-sysusers.service - Create System Users.
Jan 13 20:08:50.064617 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev...
Jan 13 20:08:50.109226 systemd-tmpfiles[1561]: ACLs are not supported, ignoring.
Jan 13 20:08:50.109266 systemd-tmpfiles[1561]: ACLs are not supported, ignoring.
Jan 13 20:08:50.125719 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev.
Jan 13 20:08:50.150688 kernel: loop2: detected capacity change from 0 to 113552
Jan 13 20:08:50.216398 kernel: loop3: detected capacity change from 0 to 116784
Jan 13 20:08:50.285265 kernel: loop4: detected capacity change from 0 to 53784
Jan 13 20:08:50.306254 kernel: loop5: detected capacity change from 0 to 194096
Jan 13 20:08:50.332243 kernel: loop6: detected capacity change from 0 to 113552
Jan 13 20:08:50.366262 kernel: loop7: detected capacity change from 0 to 116784
Jan 13 20:08:50.390183 (sd-merge)[1567]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-ami'.
Jan 13 20:08:50.393164 (sd-merge)[1567]: Merged extensions into '/usr'.
Jan 13 20:08:50.408300 systemd[1]: Reloading requested from client PID 1539 ('systemd-sysext') (unit systemd-sysext.service)...
Jan 13 20:08:50.408337 systemd[1]: Reloading...
Jan 13 20:08:50.620266 zram_generator::config[1595]: No configuration found.
Jan 13 20:08:50.640732 ldconfig[1534]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start.
Jan 13 20:08:50.908532 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.
Jan 13 20:08:51.017579 systemd[1]: Reloading finished in 605 ms.
Jan 13 20:08:51.060053 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache.
Jan 13 20:08:51.063412 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database.
Jan 13 20:08:51.067031 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/.
Jan 13 20:08:51.082572 systemd[1]: Starting ensure-sysext.service...
Jan 13 20:08:51.097406 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories...
Jan 13 20:08:51.104581 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files...
Jan 13 20:08:51.126447 systemd[1]: Reloading requested from client PID 1646 ('systemctl') (unit ensure-sysext.service)...
Jan 13 20:08:51.126475 systemd[1]: Reloading...
Jan 13 20:08:51.139993 systemd-tmpfiles[1647]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring.
Jan 13 20:08:51.140578 systemd-tmpfiles[1647]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring.
Jan 13 20:08:51.144445 systemd-tmpfiles[1647]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring.
Jan 13 20:08:51.145008 systemd-tmpfiles[1647]: ACLs are not supported, ignoring.
Jan 13 20:08:51.145173 systemd-tmpfiles[1647]: ACLs are not supported, ignoring.
Jan 13 20:08:51.160928 systemd-tmpfiles[1647]: Detected autofs mount point /boot during canonicalization of boot.
Jan 13 20:08:51.160954 systemd-tmpfiles[1647]: Skipping /boot
Jan 13 20:08:51.213293 systemd-tmpfiles[1647]: Detected autofs mount point /boot during canonicalization of boot.
Jan 13 20:08:51.213321 systemd-tmpfiles[1647]: Skipping /boot
Jan 13 20:08:51.229513 systemd-udevd[1648]: Using default interface naming scheme 'v255'.
Jan 13 20:08:51.302631 zram_generator::config[1674]: No configuration found.
Jan 13 20:08:51.491869 (udev-worker)[1696]: Network interface NamePolicy= disabled on kernel command line.
Jan 13 20:08:51.684259 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 38 scanned by (udev-worker) (1701)
Jan 13 20:08:51.701421 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.
Jan 13 20:08:51.857345 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped.
Jan 13 20:08:51.857577 systemd[1]: Reloading finished in 730 ms.
Jan 13 20:08:51.896971 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files.
Jan 13 20:08:51.904641 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories.
Jan 13 20:08:51.968654 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization.
Jan 13 20:08:52.016299 systemd[1]: Finished ensure-sysext.service.
Jan 13 20:08:52.023362 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM.
Jan 13 20:08:52.037571 systemd[1]: Starting audit-rules.service - Load Audit Rules...
Jan 13 20:08:52.044540 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs...
Jan 13 20:08:52.048720 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met.
Jan 13 20:08:52.060659 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes...
Jan 13 20:08:52.076633 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod...
Jan 13 20:08:52.090514 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm...
Jan 13 20:08:52.108485 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore...
Jan 13 20:08:52.117946 lvm[1845]:   WARNING: Failed to connect to lvmetad. Falling back to device scanning.
Jan 13 20:08:52.119513 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop...
Jan 13 20:08:52.119903 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met.
Jan 13 20:08:52.127602 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM...
Jan 13 20:08:52.134524 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog...
Jan 13 20:08:52.143547 systemd[1]: Starting systemd-networkd.service - Network Configuration...
Jan 13 20:08:52.157646 systemd[1]: Starting systemd-resolved.service - Network Name Resolution...
Jan 13 20:08:52.161483 systemd[1]: Reached target time-set.target - System Time Set.
Jan 13 20:08:52.171563 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP...
Jan 13 20:08:52.178524 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup...
Jan 13 20:08:52.182640 systemd[1]: modprobe@drm.service: Deactivated successfully.
Jan 13 20:08:52.182949 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm.
Jan 13 20:08:52.186046 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
Jan 13 20:08:52.186369 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore.
Jan 13 20:08:52.189562 systemd[1]: modprobe@loop.service: Deactivated successfully.
Jan 13 20:08:52.189853 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop.
Jan 13 20:08:52.200504 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
Jan 13 20:08:52.215863 systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
Jan 13 20:08:52.216265 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod.
Jan 13 20:08:52.219515 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met.
Jan 13 20:08:52.244112 systemd[1]: Starting systemd-userdbd.service - User Database Manager...
Jan 13 20:08:52.258397 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes.
Jan 13 20:08:52.261934 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes.
Jan 13 20:08:52.287921 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes...
Jan 13 20:08:52.299901 augenrules[1882]: No rules
Jan 13 20:08:52.302456 systemd[1]: audit-rules.service: Deactivated successfully.
Jan 13 20:08:52.303618 systemd[1]: Finished audit-rules.service - Load Audit Rules.
Jan 13 20:08:52.334199 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP.
Jan 13 20:08:52.358287 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog.
Jan 13 20:08:52.363760 lvm[1881]:   WARNING: Failed to connect to lvmetad. Falling back to device scanning.
Jan 13 20:08:52.378698 systemd[1]: Starting systemd-update-done.service - Update is Completed...
Jan 13 20:08:52.388990 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM.
Jan 13 20:08:52.397322 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs.
Jan 13 20:08:52.408057 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).
Jan 13 20:08:52.428934 systemd[1]: Finished systemd-update-done.service - Update is Completed.
Jan 13 20:08:52.437086 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes.
Jan 13 20:08:52.442742 systemd[1]: Started systemd-userdbd.service - User Database Manager.
Jan 13 20:08:52.482551 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup.
Jan 13 20:08:52.567301 systemd-networkd[1869]: lo: Link UP
Jan 13 20:08:52.567318 systemd-networkd[1869]: lo: Gained carrier
Jan 13 20:08:52.570722 systemd-networkd[1869]: Enumeration completed
Jan 13 20:08:52.571078 systemd[1]: Started systemd-networkd.service - Network Configuration.
Jan 13 20:08:52.572876 systemd-networkd[1869]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name.
Jan 13 20:08:52.573505 systemd-networkd[1869]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network.
Jan 13 20:08:52.576221 systemd-networkd[1869]: eth0: Link UP
Jan 13 20:08:52.577872 systemd-networkd[1869]: eth0: Gained carrier
Jan 13 20:08:52.578517 systemd-networkd[1869]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name.
Jan 13 20:08:52.583655 systemd-resolved[1870]: Positive Trust Anchors:
Jan 13 20:08:52.583695 systemd-resolved[1870]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
Jan 13 20:08:52.583756 systemd-resolved[1870]: 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
Jan 13 20:08:52.587472 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured...
Jan 13 20:08:52.592851 systemd-networkd[1869]: eth0: DHCPv4 address 172.31.22.23/20, gateway 172.31.16.1 acquired from 172.31.16.1
Jan 13 20:08:52.597562 systemd-resolved[1870]: Defaulting to hostname 'linux'.
Jan 13 20:08:52.601718 systemd[1]: Started systemd-resolved.service - Network Name Resolution.
Jan 13 20:08:52.606450 systemd[1]: Reached target network.target - Network.
Jan 13 20:08:52.610384 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups.
Jan 13 20:08:52.613489 systemd[1]: Reached target sysinit.target - System Initialization.
Jan 13 20:08:52.616095 systemd[1]: Started motdgen.path - Watch for update engine configuration changes.
Jan 13 20:08:52.619294 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data.
Jan 13 20:08:52.622517 systemd[1]: Started logrotate.timer - Daily rotation of log files.
Jan 13 20:08:52.625165 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information..
Jan 13 20:08:52.627958 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories.
Jan 13 20:08:52.630792 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate).
Jan 13 20:08:52.630849 systemd[1]: Reached target paths.target - Path Units.
Jan 13 20:08:52.632902 systemd[1]: Reached target timers.target - Timer Units.
Jan 13 20:08:52.636122 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket.
Jan 13 20:08:52.641362 systemd[1]: Starting docker.socket - Docker Socket for the API...
Jan 13 20:08:52.654520 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket.
Jan 13 20:08:52.658128 systemd[1]: Listening on docker.socket - Docker Socket for the API.
Jan 13 20:08:52.660993 systemd[1]: Reached target sockets.target - Socket Units.
Jan 13 20:08:52.663297 systemd[1]: Reached target basic.target - Basic System.
Jan 13 20:08:52.665503 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met.
Jan 13 20:08:52.665555 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met.
Jan 13 20:08:52.667882 systemd[1]: Starting containerd.service - containerd container runtime...
Jan 13 20:08:52.678555 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent...
Jan 13 20:08:52.684578 systemd[1]: Starting dbus.service - D-Bus System Message Bus...
Jan 13 20:08:52.692435 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit...
Jan 13 20:08:52.700318 systemd[1]: Starting extend-filesystems.service - Extend Filesystems...
Jan 13 20:08:52.703397 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).
Jan 13 20:08:52.710513 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd...
Jan 13 20:08:52.728548 systemd[1]: Started ntpd.service - Network Time Service.
Jan 13 20:08:52.747596 systemd[1]: Starting setup-oem.service - Setup OEM...
Jan 13 20:08:52.752395 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline...
Jan 13 20:08:52.757999 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys...
Jan 13 20:08:52.768531 systemd[1]: Starting systemd-logind.service - User Login Management...
Jan 13 20:08:52.772699 jq[1913]: false
Jan 13 20:08:52.771716 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0).
Jan 13 20:08:52.772602 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details.
Jan 13 20:08:52.776554 systemd[1]: Starting update-engine.service - Update Engine...
Jan 13 20:08:52.787546 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition...
Jan 13 20:08:52.797987 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'.
Jan 13 20:08:52.798411 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped.
Jan 13 20:08:52.879421 dbus-daemon[1912]: [system] SELinux support is enabled
Jan 13 20:08:52.886262 jq[1924]: true
Jan 13 20:08:52.887660 systemd[1]: Started dbus.service - D-Bus System Message Bus.
Jan 13 20:08:52.897126 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).
Jan 13 20:08:52.897199 systemd[1]: Reached target system-config.target - Load system-provided cloud configs.
Jan 13 20:08:52.900517 extend-filesystems[1914]: Found loop4
Jan 13 20:08:52.911564 extend-filesystems[1914]: Found loop5
Jan 13 20:08:52.911564 extend-filesystems[1914]: Found loop6
Jan 13 20:08:52.911564 extend-filesystems[1914]: Found loop7
Jan 13 20:08:52.911564 extend-filesystems[1914]: Found nvme0n1
Jan 13 20:08:52.911564 extend-filesystems[1914]: Found nvme0n1p1
Jan 13 20:08:52.911564 extend-filesystems[1914]: Found nvme0n1p2
Jan 13 20:08:52.911564 extend-filesystems[1914]: Found nvme0n1p3
Jan 13 20:08:52.911564 extend-filesystems[1914]: Found usr
Jan 13 20:08:52.911564 extend-filesystems[1914]: Found nvme0n1p4
Jan 13 20:08:52.911564 extend-filesystems[1914]: Found nvme0n1p6
Jan 13 20:08:52.911564 extend-filesystems[1914]: Found nvme0n1p7
Jan 13 20:08:52.911564 extend-filesystems[1914]: Found nvme0n1p9
Jan 13 20:08:52.911564 extend-filesystems[1914]: Checking size of /dev/nvme0n1p9
Jan 13 20:08:52.902573 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).
Jan 13 20:08:52.913363 dbus-daemon[1912]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.2' (uid=244 pid=1869 comm="/usr/lib/systemd/systemd-networkd" label="system_u:system_r:kernel_t:s0")
Jan 13 20:08:52.902611 systemd[1]: Reached target user-config.target - Load user-provided cloud configs.
Jan 13 20:08:52.967528 systemd[1]: Starting systemd-hostnamed.service - Hostname Service...
Jan 13 20:08:52.995148 extend-filesystems[1914]: Resized partition /dev/nvme0n1p9
Jan 13 20:08:52.999932 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully.
Jan 13 20:08:53.000345 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline.
Jan 13 20:08:53.008919 systemd[1]: motdgen.service: Deactivated successfully.
Jan 13 20:08:53.009392 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd.
Jan 13 20:08:53.039567 update_engine[1923]: I20250113 20:08:53.039402  1923 main.cc:92] Flatcar Update Engine starting
Jan 13 20:08:53.045259 extend-filesystems[1956]: resize2fs 1.47.1 (20-May-2024)
Jan 13 20:08:53.050479 (ntainerd)[1938]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR
Jan 13 20:08:53.053955 ntpd[1916]: ntpd 4.2.8p17@1.4004-o Mon Jan 13 18:25:48 UTC 2025 (1): Starting
Jan 13 20:08:53.059337 kernel: EXT4-fs (nvme0n1p9): resizing filesystem from 553472 to 1489915 blocks
Jan 13 20:08:53.059386 ntpd[1916]: 13 Jan 20:08:53 ntpd[1916]: ntpd 4.2.8p17@1.4004-o Mon Jan 13 18:25:48 UTC 2025 (1): Starting
Jan 13 20:08:53.059386 ntpd[1916]: 13 Jan 20:08:53 ntpd[1916]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp
Jan 13 20:08:53.059386 ntpd[1916]: 13 Jan 20:08:53 ntpd[1916]: ----------------------------------------------------
Jan 13 20:08:53.059386 ntpd[1916]: 13 Jan 20:08:53 ntpd[1916]: ntp-4 is maintained by Network Time Foundation,
Jan 13 20:08:53.059386 ntpd[1916]: 13 Jan 20:08:53 ntpd[1916]: Inc. (NTF), a non-profit 501(c)(3) public-benefit
Jan 13 20:08:53.059386 ntpd[1916]: 13 Jan 20:08:53 ntpd[1916]: corporation.  Support and training for ntp-4 are
Jan 13 20:08:53.059386 ntpd[1916]: 13 Jan 20:08:53 ntpd[1916]: available at https://www.nwtime.org/support
Jan 13 20:08:53.059386 ntpd[1916]: 13 Jan 20:08:53 ntpd[1916]: ----------------------------------------------------
Jan 13 20:08:53.054956 systemd[1]: Started update-engine.service - Update Engine.
Jan 13 20:08:53.054011 ntpd[1916]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp
Jan 13 20:08:53.054031 ntpd[1916]: ----------------------------------------------------
Jan 13 20:08:53.054050 ntpd[1916]: ntp-4 is maintained by Network Time Foundation,
Jan 13 20:08:53.054068 ntpd[1916]: Inc. (NTF), a non-profit 501(c)(3) public-benefit
Jan 13 20:08:53.054085 ntpd[1916]: corporation.  Support and training for ntp-4 are
Jan 13 20:08:53.054102 ntpd[1916]: available at https://www.nwtime.org/support
Jan 13 20:08:53.054120 ntpd[1916]: ----------------------------------------------------
Jan 13 20:08:53.067401 update_engine[1923]: I20250113 20:08:53.064603  1923 update_check_scheduler.cc:74] Next update check in 8m13s
Jan 13 20:08:53.067517 ntpd[1916]: 13 Jan 20:08:53 ntpd[1916]: proto: precision = 0.096 usec (-23)
Jan 13 20:08:53.065022 ntpd[1916]: proto: precision = 0.096 usec (-23)
Jan 13 20:08:53.068428 ntpd[1916]: basedate set to 2025-01-01
Jan 13 20:08:53.073575 systemd[1]: Started locksmithd.service - Cluster reboot manager.
Jan 13 20:08:53.087572 ntpd[1916]: 13 Jan 20:08:53 ntpd[1916]: basedate set to 2025-01-01
Jan 13 20:08:53.087572 ntpd[1916]: 13 Jan 20:08:53 ntpd[1916]: gps base set to 2025-01-05 (week 2348)
Jan 13 20:08:53.087572 ntpd[1916]: 13 Jan 20:08:53 ntpd[1916]: Listen and drop on 0 v6wildcard [::]:123
Jan 13 20:08:53.087572 ntpd[1916]: 13 Jan 20:08:53 ntpd[1916]: Listen and drop on 1 v4wildcard 0.0.0.0:123
Jan 13 20:08:53.087572 ntpd[1916]: 13 Jan 20:08:53 ntpd[1916]: Listen normally on 2 lo 127.0.0.1:123
Jan 13 20:08:53.087572 ntpd[1916]: 13 Jan 20:08:53 ntpd[1916]: Listen normally on 3 eth0 172.31.22.23:123
Jan 13 20:08:53.087572 ntpd[1916]: 13 Jan 20:08:53 ntpd[1916]: Listen normally on 4 lo [::1]:123
Jan 13 20:08:53.087572 ntpd[1916]: 13 Jan 20:08:53 ntpd[1916]: bind(21) AF_INET6 fe80::493:d5ff:fe9c:fdcf%2#123 flags 0x11 failed: Cannot assign requested address
Jan 13 20:08:53.087572 ntpd[1916]: 13 Jan 20:08:53 ntpd[1916]: unable to create socket on eth0 (5) for fe80::493:d5ff:fe9c:fdcf%2#123
Jan 13 20:08:53.087572 ntpd[1916]: 13 Jan 20:08:53 ntpd[1916]: failed to init interface for address fe80::493:d5ff:fe9c:fdcf%2
Jan 13 20:08:53.087572 ntpd[1916]: 13 Jan 20:08:53 ntpd[1916]: Listening on routing socket on fd #21 for interface updates
Jan 13 20:08:53.087572 ntpd[1916]: 13 Jan 20:08:53 ntpd[1916]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
Jan 13 20:08:53.087572 ntpd[1916]: 13 Jan 20:08:53 ntpd[1916]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
Jan 13 20:08:53.088118 jq[1948]: true
Jan 13 20:08:53.068490 ntpd[1916]: gps base set to 2025-01-05 (week 2348)
Jan 13 20:08:53.155644 coreos-metadata[1911]: Jan 13 20:08:53.134 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1
Jan 13 20:08:53.155644 coreos-metadata[1911]: Jan 13 20:08:53.136 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-id: Attempt #1
Jan 13 20:08:53.155644 coreos-metadata[1911]: Jan 13 20:08:53.136 INFO Fetch successful
Jan 13 20:08:53.155644 coreos-metadata[1911]: Jan 13 20:08:53.137 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-type: Attempt #1
Jan 13 20:08:53.155644 coreos-metadata[1911]: Jan 13 20:08:53.138 INFO Fetch successful
Jan 13 20:08:53.155644 coreos-metadata[1911]: Jan 13 20:08:53.138 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/local-ipv4: Attempt #1
Jan 13 20:08:53.155644 coreos-metadata[1911]: Jan 13 20:08:53.139 INFO Fetch successful
Jan 13 20:08:53.155644 coreos-metadata[1911]: Jan 13 20:08:53.139 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-ipv4: Attempt #1
Jan 13 20:08:53.155644 coreos-metadata[1911]: Jan 13 20:08:53.140 INFO Fetch successful
Jan 13 20:08:53.155644 coreos-metadata[1911]: Jan 13 20:08:53.140 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/ipv6: Attempt #1
Jan 13 20:08:53.155644 coreos-metadata[1911]: Jan 13 20:08:53.141 INFO Fetch failed with 404: resource not found
Jan 13 20:08:53.155644 coreos-metadata[1911]: Jan 13 20:08:53.141 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone: Attempt #1
Jan 13 20:08:53.155644 coreos-metadata[1911]: Jan 13 20:08:53.142 INFO Fetch successful
Jan 13 20:08:53.155644 coreos-metadata[1911]: Jan 13 20:08:53.142 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone-id: Attempt #1
Jan 13 20:08:53.155644 coreos-metadata[1911]: Jan 13 20:08:53.144 INFO Fetch successful
Jan 13 20:08:53.155644 coreos-metadata[1911]: Jan 13 20:08:53.144 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/hostname: Attempt #1
Jan 13 20:08:53.155644 coreos-metadata[1911]: Jan 13 20:08:53.145 INFO Fetch successful
Jan 13 20:08:53.155644 coreos-metadata[1911]: Jan 13 20:08:53.145 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-hostname: Attempt #1
Jan 13 20:08:53.155644 coreos-metadata[1911]: Jan 13 20:08:53.146 INFO Fetch successful
Jan 13 20:08:53.155644 coreos-metadata[1911]: Jan 13 20:08:53.146 INFO Fetching http://169.254.169.254/2021-01-03/dynamic/instance-identity/document: Attempt #1
Jan 13 20:08:53.155644 coreos-metadata[1911]: Jan 13 20:08:53.148 INFO Fetch successful
Jan 13 20:08:53.074314 ntpd[1916]: Listen and drop on 0 v6wildcard [::]:123
Jan 13 20:08:53.074393 ntpd[1916]: Listen and drop on 1 v4wildcard 0.0.0.0:123
Jan 13 20:08:53.074639 ntpd[1916]: Listen normally on 2 lo 127.0.0.1:123
Jan 13 20:08:53.074700 ntpd[1916]: Listen normally on 3 eth0 172.31.22.23:123
Jan 13 20:08:53.074764 ntpd[1916]: Listen normally on 4 lo [::1]:123
Jan 13 20:08:53.074839 ntpd[1916]: bind(21) AF_INET6 fe80::493:d5ff:fe9c:fdcf%2#123 flags 0x11 failed: Cannot assign requested address
Jan 13 20:08:53.171889 systemd-logind[1922]: Watching system buttons on /dev/input/event0 (Power Button)
Jan 13 20:08:53.074877 ntpd[1916]: unable to create socket on eth0 (5) for fe80::493:d5ff:fe9c:fdcf%2#123
Jan 13 20:08:53.171925 systemd-logind[1922]: Watching system buttons on /dev/input/event1 (Sleep Button)
Jan 13 20:08:53.074903 ntpd[1916]: failed to init interface for address fe80::493:d5ff:fe9c:fdcf%2
Jan 13 20:08:53.074952 ntpd[1916]: Listening on routing socket on fd #21 for interface updates
Jan 13 20:08:53.081298 ntpd[1916]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
Jan 13 20:08:53.081349 ntpd[1916]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
Jan 13 20:08:53.175923 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd.
Jan 13 20:08:53.179735 systemd-logind[1922]: New seat seat0.
Jan 13 20:08:53.184201 systemd[1]: Started systemd-logind.service - User Login Management.
Jan 13 20:08:53.232239 kernel: EXT4-fs (nvme0n1p9): resized filesystem to 1489915
Jan 13 20:08:53.272291 systemd[1]: Finished setup-oem.service - Setup OEM.
Jan 13 20:08:53.275878 extend-filesystems[1956]: Filesystem at /dev/nvme0n1p9 is mounted on /; on-line resizing required
Jan 13 20:08:53.275878 extend-filesystems[1956]: old_desc_blocks = 1, new_desc_blocks = 1
Jan 13 20:08:53.275878 extend-filesystems[1956]: The filesystem on /dev/nvme0n1p9 is now 1489915 (4k) blocks long.
Jan 13 20:08:53.307413 extend-filesystems[1914]: Resized filesystem in /dev/nvme0n1p9
Jan 13 20:08:53.282289 systemd[1]: extend-filesystems.service: Deactivated successfully.
Jan 13 20:08:53.282661 systemd[1]: Finished extend-filesystems.service - Extend Filesystems.
Jan 13 20:08:53.311646 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent.
Jan 13 20:08:53.318067 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met.
Jan 13 20:08:53.325305 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 38 scanned by (udev-worker) (1695)
Jan 13 20:08:53.378198 bash[1998]: Updated "/home/core/.ssh/authorized_keys"
Jan 13 20:08:53.382595 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition.
Jan 13 20:08:53.408968 systemd[1]: Starting sshkeys.service...
Jan 13 20:08:53.438426 dbus-daemon[1912]: [system] Successfully activated service 'org.freedesktop.hostname1'
Jan 13 20:08:53.439912 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys.
Jan 13 20:08:53.444646 dbus-daemon[1912]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.5' (uid=0 pid=1947 comm="/usr/lib/systemd/systemd-hostnamed" label="system_u:system_r:kernel_t:s0")
Jan 13 20:08:53.452396 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)...
Jan 13 20:08:53.457809 systemd[1]: Started systemd-hostnamed.service - Hostname Service.
Jan 13 20:08:53.467802 systemd[1]: Starting polkit.service - Authorization Manager...
Jan 13 20:08:53.500293 polkitd[2018]: Started polkitd version 121
Jan 13 20:08:53.527955 polkitd[2018]: Loading rules from directory /etc/polkit-1/rules.d
Jan 13 20:08:53.528077 polkitd[2018]: Loading rules from directory /usr/share/polkit-1/rules.d
Jan 13 20:08:53.534799 locksmithd[1957]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot"
Jan 13 20:08:53.535787 polkitd[2018]: Finished loading, compiling and executing 2 rules
Jan 13 20:08:53.537496 dbus-daemon[1912]: [system] Successfully activated service 'org.freedesktop.PolicyKit1'
Jan 13 20:08:53.538067 systemd[1]: Started polkit.service - Authorization Manager.
Jan 13 20:08:53.547358 polkitd[2018]: Acquired the name org.freedesktop.PolicyKit1 on the system bus
Jan 13 20:08:53.615270 systemd-resolved[1870]: System hostname changed to 'ip-172-31-22-23'.
Jan 13 20:08:53.615271 systemd-hostnamed[1947]: Hostname set to <ip-172-31-22-23> (transient)
Jan 13 20:08:53.713371 systemd-networkd[1869]: eth0: Gained IPv6LL
Jan 13 20:08:53.726399 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured.
Jan 13 20:08:53.735389 systemd[1]: Reached target network-online.target - Network is Online.
Jan 13 20:08:53.752830 systemd[1]: Started amazon-ssm-agent.service - amazon-ssm-agent.
Jan 13 20:08:53.767719 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent...
Jan 13 20:08:53.781839 systemd[1]: Starting nvidia.service - NVIDIA Configure Service...
Jan 13 20:08:53.799909 containerd[1938]: time="2025-01-13T20:08:53.799775554Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23
Jan 13 20:08:53.814342 coreos-metadata[2017]: Jan 13 20:08:53.814 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1
Jan 13 20:08:53.818381 coreos-metadata[2017]: Jan 13 20:08:53.815 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys: Attempt #1
Jan 13 20:08:53.822964 coreos-metadata[2017]: Jan 13 20:08:53.820 INFO Fetch successful
Jan 13 20:08:53.822964 coreos-metadata[2017]: Jan 13 20:08:53.820 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys/0/openssh-key: Attempt #1
Jan 13 20:08:53.829928 coreos-metadata[2017]: Jan 13 20:08:53.829 INFO Fetch successful
Jan 13 20:08:53.836369 unknown[2017]: wrote ssh authorized keys file for user: core
Jan 13 20:08:53.891327 systemd[1]: Finished nvidia.service - NVIDIA Configure Service.
Jan 13 20:08:53.922186 update-ssh-keys[2115]: Updated "/home/core/.ssh/authorized_keys"
Jan 13 20:08:53.923556 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys).
Jan 13 20:08:53.939453 systemd[1]: Finished sshkeys.service.
Jan 13 20:08:53.976493 amazon-ssm-agent[2091]: Initializing new seelog logger
Jan 13 20:08:53.976916 amazon-ssm-agent[2091]: New Seelog Logger Creation Complete
Jan 13 20:08:53.976916 amazon-ssm-agent[2091]: 2025/01/13 20:08:53 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json.
Jan 13 20:08:53.976916 amazon-ssm-agent[2091]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json.
Jan 13 20:08:53.977418 amazon-ssm-agent[2091]: 2025/01/13 20:08:53 processing appconfig overrides
Jan 13 20:08:53.978461 amazon-ssm-agent[2091]: 2025/01/13 20:08:53 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json.
Jan 13 20:08:53.978461 amazon-ssm-agent[2091]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json.
Jan 13 20:08:53.978461 amazon-ssm-agent[2091]: 2025/01/13 20:08:53 processing appconfig overrides
Jan 13 20:08:53.982232 amazon-ssm-agent[2091]: 2025/01/13 20:08:53 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json.
Jan 13 20:08:53.982232 amazon-ssm-agent[2091]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json.
Jan 13 20:08:53.982232 amazon-ssm-agent[2091]: 2025/01/13 20:08:53 processing appconfig overrides
Jan 13 20:08:53.982232 amazon-ssm-agent[2091]: 2025-01-13 20:08:53 INFO Proxy environment variables:
Jan 13 20:08:53.986650 amazon-ssm-agent[2091]: 2025/01/13 20:08:53 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json.
Jan 13 20:08:53.986650 amazon-ssm-agent[2091]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json.
Jan 13 20:08:53.986828 amazon-ssm-agent[2091]: 2025/01/13 20:08:53 processing appconfig overrides
Jan 13 20:08:54.025815 containerd[1938]: time="2025-01-13T20:08:54.022020451Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
Jan 13 20:08:54.028389 containerd[1938]: time="2025-01-13T20:08:54.028323247Z" 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.71-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1
Jan 13 20:08:54.029008 containerd[1938]: time="2025-01-13T20:08:54.028978075Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
Jan 13 20:08:54.029138 containerd[1938]: time="2025-01-13T20:08:54.029111431Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
Jan 13 20:08:54.029558 containerd[1938]: time="2025-01-13T20:08:54.029528251Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1
Jan 13 20:08:54.030011 containerd[1938]: time="2025-01-13T20:08:54.029981731Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1
Jan 13 20:08:54.030380 containerd[1938]: time="2025-01-13T20:08:54.030340351Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1
Jan 13 20:08:54.030756 containerd[1938]: time="2025-01-13T20:08:54.030724363Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
Jan 13 20:08:54.032098 containerd[1938]: time="2025-01-13T20:08:54.032045479Z" 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
Jan 13 20:08:54.032423 containerd[1938]: time="2025-01-13T20:08:54.032392519Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
Jan 13 20:08:54.035448 containerd[1938]: time="2025-01-13T20:08:54.034379467Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1
Jan 13 20:08:54.035448 containerd[1938]: time="2025-01-13T20:08:54.034425379Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
Jan 13 20:08:54.035448 containerd[1938]: time="2025-01-13T20:08:54.034639855Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
Jan 13 20:08:54.035448 containerd[1938]: time="2025-01-13T20:08:54.035045431Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
Jan 13 20:08:54.038936 containerd[1938]: time="2025-01-13T20:08:54.038497411Z" 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
Jan 13 20:08:54.038936 containerd[1938]: time="2025-01-13T20:08:54.038548987Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
Jan 13 20:08:54.038936 containerd[1938]: time="2025-01-13T20:08:54.038773027Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
Jan 13 20:08:54.038936 containerd[1938]: time="2025-01-13T20:08:54.038870623Z" level=info msg="metadata content store policy set" policy=shared
Jan 13 20:08:54.046438 containerd[1938]: time="2025-01-13T20:08:54.046369471Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
Jan 13 20:08:54.046554 containerd[1938]: time="2025-01-13T20:08:54.046485475Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
Jan 13 20:08:54.046648 containerd[1938]: time="2025-01-13T20:08:54.046584391Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1
Jan 13 20:08:54.046648 containerd[1938]: time="2025-01-13T20:08:54.046634479Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1
Jan 13 20:08:54.046732 containerd[1938]: time="2025-01-13T20:08:54.046670815Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
Jan 13 20:08:54.048241 containerd[1938]: time="2025-01-13T20:08:54.046922599Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
Jan 13 20:08:54.048678 containerd[1938]: time="2025-01-13T20:08:54.048622531Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
Jan 13 20:08:54.048914 containerd[1938]: time="2025-01-13T20:08:54.048869371Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2
Jan 13 20:08:54.048976 containerd[1938]: time="2025-01-13T20:08:54.048921091Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1
Jan 13 20:08:54.049022 containerd[1938]: time="2025-01-13T20:08:54.048970255Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1
Jan 13 20:08:54.049022 containerd[1938]: time="2025-01-13T20:08:54.049002451Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
Jan 13 20:08:54.049128 containerd[1938]: time="2025-01-13T20:08:54.049041451Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
Jan 13 20:08:54.049128 containerd[1938]: time="2025-01-13T20:08:54.049090375Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
Jan 13 20:08:54.049280 containerd[1938]: time="2025-01-13T20:08:54.049124023Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
Jan 13 20:08:54.049280 containerd[1938]: time="2025-01-13T20:08:54.049157359Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
Jan 13 20:08:54.049280 containerd[1938]: time="2025-01-13T20:08:54.049187095Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
Jan 13 20:08:54.050328 containerd[1938]: time="2025-01-13T20:08:54.050275363Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
Jan 13 20:08:54.050416 containerd[1938]: time="2025-01-13T20:08:54.050328487Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
Jan 13 20:08:54.050416 containerd[1938]: time="2025-01-13T20:08:54.050374675Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
Jan 13 20:08:54.050416 containerd[1938]: time="2025-01-13T20:08:54.050406559Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
Jan 13 20:08:54.050563 containerd[1938]: time="2025-01-13T20:08:54.050445799Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
Jan 13 20:08:54.050563 containerd[1938]: time="2025-01-13T20:08:54.050486491Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
Jan 13 20:08:54.050563 containerd[1938]: time="2025-01-13T20:08:54.050517199Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
Jan 13 20:08:54.050563 containerd[1938]: time="2025-01-13T20:08:54.050547943Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
Jan 13 20:08:54.050738 containerd[1938]: time="2025-01-13T20:08:54.050575543Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
Jan 13 20:08:54.050738 containerd[1938]: time="2025-01-13T20:08:54.050610175Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
Jan 13 20:08:54.050738 containerd[1938]: time="2025-01-13T20:08:54.050641171Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1
Jan 13 20:08:54.050738 containerd[1938]: time="2025-01-13T20:08:54.050675467Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1
Jan 13 20:08:54.050738 containerd[1938]: time="2025-01-13T20:08:54.050704267Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
Jan 13 20:08:54.050738 containerd[1938]: time="2025-01-13T20:08:54.050731663Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1
Jan 13 20:08:54.050982 containerd[1938]: time="2025-01-13T20:08:54.050760511Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
Jan 13 20:08:54.050982 containerd[1938]: time="2025-01-13T20:08:54.050791783Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1
Jan 13 20:08:54.050982 containerd[1938]: time="2025-01-13T20:08:54.050836423Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1
Jan 13 20:08:54.050982 containerd[1938]: time="2025-01-13T20:08:54.050869651Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
Jan 13 20:08:54.050982 containerd[1938]: time="2025-01-13T20:08:54.050896351Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
Jan 13 20:08:54.055246 containerd[1938]: time="2025-01-13T20:08:54.053272111Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
Jan 13 20:08:54.055246 containerd[1938]: time="2025-01-13T20:08:54.053476699Z" 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
Jan 13 20:08:54.055246 containerd[1938]: time="2025-01-13T20:08:54.053509591Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
Jan 13 20:08:54.055246 containerd[1938]: time="2025-01-13T20:08:54.053538499Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1
Jan 13 20:08:54.055246 containerd[1938]: time="2025-01-13T20:08:54.053566603Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
Jan 13 20:08:54.055246 containerd[1938]: time="2025-01-13T20:08:54.053597239Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1
Jan 13 20:08:54.055246 containerd[1938]: time="2025-01-13T20:08:54.053631031Z" level=info msg="NRI interface is disabled by configuration."
Jan 13 20:08:54.055246 containerd[1938]: time="2025-01-13T20:08:54.053662795Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1
Jan 13 20:08:54.055663 containerd[1938]: time="2025-01-13T20:08:54.054195043Z" 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}"
Jan 13 20:08:54.055663 containerd[1938]: time="2025-01-13T20:08:54.054329683Z" level=info msg="Connect containerd service"
Jan 13 20:08:54.055663 containerd[1938]: time="2025-01-13T20:08:54.054413551Z" level=info msg="using legacy CRI server"
Jan 13 20:08:54.055663 containerd[1938]: time="2025-01-13T20:08:54.054434371Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this"
Jan 13 20:08:54.055663 containerd[1938]: time="2025-01-13T20:08:54.054696403Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\""
Jan 13 20:08:54.060274 containerd[1938]: time="2025-01-13T20:08:54.057965983Z" 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"
Jan 13 20:08:54.060274 containerd[1938]: time="2025-01-13T20:08:54.059380675Z" level=info msg="Start subscribing containerd event"
Jan 13 20:08:54.060274 containerd[1938]: time="2025-01-13T20:08:54.059458339Z" level=info msg="Start recovering state"
Jan 13 20:08:54.060274 containerd[1938]: time="2025-01-13T20:08:54.059592451Z" level=info msg="Start event monitor"
Jan 13 20:08:54.060274 containerd[1938]: time="2025-01-13T20:08:54.059617243Z" level=info msg="Start snapshots syncer"
Jan 13 20:08:54.060274 containerd[1938]: time="2025-01-13T20:08:54.059640187Z" level=info msg="Start cni network conf syncer for default"
Jan 13 20:08:54.060274 containerd[1938]: time="2025-01-13T20:08:54.059658727Z" level=info msg="Start streaming server"
Jan 13 20:08:54.061658 containerd[1938]: time="2025-01-13T20:08:54.061594735Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc
Jan 13 20:08:54.061756 containerd[1938]: time="2025-01-13T20:08:54.061713715Z" level=info msg=serving... address=/run/containerd/containerd.sock
Jan 13 20:08:54.061931 systemd[1]: Started containerd.service - containerd container runtime.
Jan 13 20:08:54.068644 containerd[1938]: time="2025-01-13T20:08:54.067088443Z" level=info msg="containerd successfully booted in 0.278089s"
Jan 13 20:08:54.081662 amazon-ssm-agent[2091]: 2025-01-13 20:08:53 INFO https_proxy:
Jan 13 20:08:54.183286 amazon-ssm-agent[2091]: 2025-01-13 20:08:53 INFO http_proxy:
Jan 13 20:08:54.281773 amazon-ssm-agent[2091]: 2025-01-13 20:08:53 INFO no_proxy:
Jan 13 20:08:54.380646 amazon-ssm-agent[2091]: 2025-01-13 20:08:53 INFO Checking if agent identity type OnPrem can be assumed
Jan 13 20:08:54.478999 amazon-ssm-agent[2091]: 2025-01-13 20:08:53 INFO Checking if agent identity type EC2 can be assumed
Jan 13 20:08:54.582473 amazon-ssm-agent[2091]: 2025-01-13 20:08:54 INFO Agent will take identity from EC2
Jan 13 20:08:54.602101 amazon-ssm-agent[2091]: 2025-01-13 20:08:54 INFO [amazon-ssm-agent] using named pipe channel for IPC
Jan 13 20:08:54.602394 amazon-ssm-agent[2091]: 2025-01-13 20:08:54 INFO [amazon-ssm-agent] using named pipe channel for IPC
Jan 13 20:08:54.602495 amazon-ssm-agent[2091]: 2025-01-13 20:08:54 INFO [amazon-ssm-agent] using named pipe channel for IPC
Jan 13 20:08:54.602605 amazon-ssm-agent[2091]: 2025-01-13 20:08:54 INFO [amazon-ssm-agent] amazon-ssm-agent - v3.2.0.0
Jan 13 20:08:54.602741 amazon-ssm-agent[2091]: 2025-01-13 20:08:54 INFO [amazon-ssm-agent] OS: linux, Arch: arm64
Jan 13 20:08:54.602851 amazon-ssm-agent[2091]: 2025-01-13 20:08:54 INFO [amazon-ssm-agent] Starting Core Agent
Jan 13 20:08:54.602960 amazon-ssm-agent[2091]: 2025-01-13 20:08:54 INFO [amazon-ssm-agent] registrar detected. Attempting registration
Jan 13 20:08:54.603064 amazon-ssm-agent[2091]: 2025-01-13 20:08:54 INFO [Registrar] Starting registrar module
Jan 13 20:08:54.603163 amazon-ssm-agent[2091]: 2025-01-13 20:08:54 INFO [EC2Identity] no registration info found for ec2 instance, attempting registration
Jan 13 20:08:54.604316 amazon-ssm-agent[2091]: 2025-01-13 20:08:54 INFO [EC2Identity] EC2 registration was successful.
Jan 13 20:08:54.604316 amazon-ssm-agent[2091]: 2025-01-13 20:08:54 INFO [CredentialRefresher] Starting credentials refresher loop
Jan 13 20:08:54.604316 amazon-ssm-agent[2091]: 2025-01-13 20:08:54 INFO [CredentialRefresher] credentialRefresher has started
Jan 13 20:08:54.604316 amazon-ssm-agent[2091]: 2025-01-13 20:08:54 INFO EC2RoleProvider Successfully connected with instance profile role credentials
Jan 13 20:08:54.679365 amazon-ssm-agent[2091]: 2025-01-13 20:08:54 INFO [CredentialRefresher] Next credential rotation will be in 31.333273610133332 minutes
Jan 13 20:08:54.828817 sshd_keygen[1943]: ssh-keygen: generating new host keys: RSA ECDSA ED25519
Jan 13 20:08:54.872146 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys.
Jan 13 20:08:54.881781 systemd[1]: Starting issuegen.service - Generate /run/issue...
Jan 13 20:08:54.892892 systemd[1]: Started sshd@0-172.31.22.23:22-147.75.109.163:41036.service - OpenSSH per-connection server daemon (147.75.109.163:41036).
Jan 13 20:08:54.916745 systemd[1]: issuegen.service: Deactivated successfully.
Jan 13 20:08:54.918735 systemd[1]: Finished issuegen.service - Generate /run/issue.
Jan 13 20:08:54.944195 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions...
Jan 13 20:08:54.979438 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions.
Jan 13 20:08:54.995969 systemd[1]: Started getty@tty1.service - Getty on tty1.
Jan 13 20:08:55.000846 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0.
Jan 13 20:08:55.004434 systemd[1]: Reached target getty.target - Login Prompts.
Jan 13 20:08:55.132367 sshd[2142]: Accepted publickey for core from 147.75.109.163 port 41036 ssh2: RSA SHA256:IRHkteilZRLg/mCVEzdResksy7NfUBDRRywgALKaHg0
Jan 13 20:08:55.136160 sshd-session[2142]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Jan 13 20:08:55.157598 systemd-logind[1922]: New session 1 of user core.
Jan 13 20:08:55.158759 systemd[1]: Created slice user-500.slice - User Slice of UID 500.
Jan 13 20:08:55.169933 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500...
Jan 13 20:08:55.200862 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500.
Jan 13 20:08:55.218749 systemd[1]: Starting user@500.service - User Manager for UID 500...
Jan 13 20:08:55.231960 (systemd)[2153]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0)
Jan 13 20:08:55.456493 systemd[2153]: Queued start job for default target default.target.
Jan 13 20:08:55.465318 systemd[2153]: Created slice app.slice - User Application Slice.
Jan 13 20:08:55.465383 systemd[2153]: Reached target paths.target - Paths.
Jan 13 20:08:55.465415 systemd[2153]: Reached target timers.target - Timers.
Jan 13 20:08:55.467918 systemd[2153]: Starting dbus.socket - D-Bus User Message Bus Socket...
Jan 13 20:08:55.509089 systemd[2153]: Listening on dbus.socket - D-Bus User Message Bus Socket.
Jan 13 20:08:55.509404 systemd[2153]: Reached target sockets.target - Sockets.
Jan 13 20:08:55.509450 systemd[2153]: Reached target basic.target - Basic System.
Jan 13 20:08:55.509532 systemd[2153]: Reached target default.target - Main User Target.
Jan 13 20:08:55.509597 systemd[2153]: Startup finished in 265ms.
Jan 13 20:08:55.509675 systemd[1]: Started user@500.service - User Manager for UID 500.
Jan 13 20:08:55.525532 systemd[1]: Started session-1.scope - Session 1 of User core.
Jan 13 20:08:55.634763 amazon-ssm-agent[2091]: 2025-01-13 20:08:55 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker is not running, starting worker process
Jan 13 20:08:55.695728 systemd[1]: Started sshd@1-172.31.22.23:22-147.75.109.163:41040.service - OpenSSH per-connection server daemon (147.75.109.163:41040).
Jan 13 20:08:55.735820 amazon-ssm-agent[2091]: 2025-01-13 20:08:55 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker (pid:2164) started
Jan 13 20:08:55.836479 amazon-ssm-agent[2091]: 2025-01-13 20:08:55 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] Monitor long running worker health every 60 seconds
Jan 13 20:08:55.933261 sshd[2169]: Accepted publickey for core from 147.75.109.163 port 41040 ssh2: RSA SHA256:IRHkteilZRLg/mCVEzdResksy7NfUBDRRywgALKaHg0
Jan 13 20:08:55.934473 sshd-session[2169]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Jan 13 20:08:55.944469 systemd-logind[1922]: New session 2 of user core.
Jan 13 20:08:55.955501 systemd[1]: Started session-2.scope - Session 2 of User core.
Jan 13 20:08:56.061561 ntpd[1916]: Listen normally on 6 eth0 [fe80::493:d5ff:fe9c:fdcf%2]:123
Jan 13 20:08:56.062105 ntpd[1916]: 13 Jan 20:08:56 ntpd[1916]: Listen normally on 6 eth0 [fe80::493:d5ff:fe9c:fdcf%2]:123
Jan 13 20:08:56.084721 sshd[2177]: Connection closed by 147.75.109.163 port 41040
Jan 13 20:08:56.086520 sshd-session[2169]: pam_unix(sshd:session): session closed for user core
Jan 13 20:08:56.093868 systemd[1]: sshd@1-172.31.22.23:22-147.75.109.163:41040.service: Deactivated successfully.
Jan 13 20:08:56.097599 systemd[1]: session-2.scope: Deactivated successfully.
Jan 13 20:08:56.099543 systemd-logind[1922]: Session 2 logged out. Waiting for processes to exit.
Jan 13 20:08:56.101573 systemd-logind[1922]: Removed session 2.
Jan 13 20:08:56.124746 systemd[1]: Started sshd@2-172.31.22.23:22-147.75.109.163:41042.service - OpenSSH per-connection server daemon (147.75.109.163:41042).
Jan 13 20:08:56.168916 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent.
Jan 13 20:08:56.174987 systemd[1]: Reached target multi-user.target - Multi-User System.
Jan 13 20:08:56.177841 (kubelet)[2189]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS
Jan 13 20:08:56.190035 systemd[1]: Startup finished in 1.133s (kernel) + 7.105s (initrd) + 8.349s (userspace) = 16.588s.
Jan 13 20:08:56.198198 agetty[2150]: failed to open credentials directory
Jan 13 20:08:56.214111 agetty[2149]: failed to open credentials directory
Jan 13 20:08:56.313982 sshd[2182]: Accepted publickey for core from 147.75.109.163 port 41042 ssh2: RSA SHA256:IRHkteilZRLg/mCVEzdResksy7NfUBDRRywgALKaHg0
Jan 13 20:08:56.317007 sshd-session[2182]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Jan 13 20:08:56.327542 systemd-logind[1922]: New session 3 of user core.
Jan 13 20:08:56.334505 systemd[1]: Started session-3.scope - Session 3 of User core.
Jan 13 20:08:56.463057 sshd[2194]: Connection closed by 147.75.109.163 port 41042
Jan 13 20:08:56.464025 sshd-session[2182]: pam_unix(sshd:session): session closed for user core
Jan 13 20:08:56.471394 systemd[1]: sshd@2-172.31.22.23:22-147.75.109.163:41042.service: Deactivated successfully.
Jan 13 20:08:56.476306 systemd[1]: session-3.scope: Deactivated successfully.
Jan 13 20:08:56.478364 systemd-logind[1922]: Session 3 logged out. Waiting for processes to exit.
Jan 13 20:08:56.480339 systemd-logind[1922]: Removed session 3.
Jan 13 20:08:57.420598 kubelet[2189]: E0113 20:08:57.420517    2189 run.go:74] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory"
Jan 13 20:08:57.424988 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE
Jan 13 20:08:57.425376 systemd[1]: kubelet.service: Failed with result 'exit-code'.
Jan 13 20:08:57.425923 systemd[1]: kubelet.service: Consumed 1.317s CPU time.
Jan 13 20:08:59.627003 systemd-resolved[1870]: Clock change detected. Flushing caches.
Jan 13 20:09:06.062733 systemd[1]: Started sshd@3-172.31.22.23:22-147.75.109.163:48482.service - OpenSSH per-connection server daemon (147.75.109.163:48482).
Jan 13 20:09:06.253181 sshd[2206]: Accepted publickey for core from 147.75.109.163 port 48482 ssh2: RSA SHA256:IRHkteilZRLg/mCVEzdResksy7NfUBDRRywgALKaHg0
Jan 13 20:09:06.255655 sshd-session[2206]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Jan 13 20:09:06.264179 systemd-logind[1922]: New session 4 of user core.
Jan 13 20:09:06.270149 systemd[1]: Started session-4.scope - Session 4 of User core.
Jan 13 20:09:06.395279 sshd[2208]: Connection closed by 147.75.109.163 port 48482
Jan 13 20:09:06.394207 sshd-session[2206]: pam_unix(sshd:session): session closed for user core
Jan 13 20:09:06.399693 systemd[1]: sshd@3-172.31.22.23:22-147.75.109.163:48482.service: Deactivated successfully.
Jan 13 20:09:06.402472 systemd[1]: session-4.scope: Deactivated successfully.
Jan 13 20:09:06.406052 systemd-logind[1922]: Session 4 logged out. Waiting for processes to exit.
Jan 13 20:09:06.407998 systemd-logind[1922]: Removed session 4.
Jan 13 20:09:06.439251 systemd[1]: Started sshd@4-172.31.22.23:22-147.75.109.163:48498.service - OpenSSH per-connection server daemon (147.75.109.163:48498).
Jan 13 20:09:06.616209 sshd[2213]: Accepted publickey for core from 147.75.109.163 port 48498 ssh2: RSA SHA256:IRHkteilZRLg/mCVEzdResksy7NfUBDRRywgALKaHg0
Jan 13 20:09:06.618585 sshd-session[2213]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Jan 13 20:09:06.627021 systemd-logind[1922]: New session 5 of user core.
Jan 13 20:09:06.634060 systemd[1]: Started session-5.scope - Session 5 of User core.
Jan 13 20:09:06.751501 sshd[2215]: Connection closed by 147.75.109.163 port 48498
Jan 13 20:09:06.752875 sshd-session[2213]: pam_unix(sshd:session): session closed for user core
Jan 13 20:09:06.759010 systemd[1]: sshd@4-172.31.22.23:22-147.75.109.163:48498.service: Deactivated successfully.
Jan 13 20:09:06.762311 systemd[1]: session-5.scope: Deactivated successfully.
Jan 13 20:09:06.763536 systemd-logind[1922]: Session 5 logged out. Waiting for processes to exit.
Jan 13 20:09:06.765681 systemd-logind[1922]: Removed session 5.
Jan 13 20:09:06.798336 systemd[1]: Started sshd@5-172.31.22.23:22-147.75.109.163:48504.service - OpenSSH per-connection server daemon (147.75.109.163:48504).
Jan 13 20:09:06.977215 sshd[2220]: Accepted publickey for core from 147.75.109.163 port 48504 ssh2: RSA SHA256:IRHkteilZRLg/mCVEzdResksy7NfUBDRRywgALKaHg0
Jan 13 20:09:06.979626 sshd-session[2220]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Jan 13 20:09:06.986915 systemd-logind[1922]: New session 6 of user core.
Jan 13 20:09:06.998075 systemd[1]: Started session-6.scope - Session 6 of User core.
Jan 13 20:09:06.999614 systemd[1]: kubelet.service: Scheduled restart job, restart counter is at 1.
Jan 13 20:09:07.003260 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent...
Jan 13 20:09:07.127999 sshd[2223]: Connection closed by 147.75.109.163 port 48504
Jan 13 20:09:07.128875 sshd-session[2220]: pam_unix(sshd:session): session closed for user core
Jan 13 20:09:07.136948 systemd-logind[1922]: Session 6 logged out. Waiting for processes to exit.
Jan 13 20:09:07.139395 systemd[1]: sshd@5-172.31.22.23:22-147.75.109.163:48504.service: Deactivated successfully.
Jan 13 20:09:07.146005 systemd[1]: session-6.scope: Deactivated successfully.
Jan 13 20:09:07.147893 systemd-logind[1922]: Removed session 6.
Jan 13 20:09:07.167331 systemd[1]: Started sshd@6-172.31.22.23:22-147.75.109.163:48516.service - OpenSSH per-connection server daemon (147.75.109.163:48516).
Jan 13 20:09:07.367695 sshd[2230]: Accepted publickey for core from 147.75.109.163 port 48516 ssh2: RSA SHA256:IRHkteilZRLg/mCVEzdResksy7NfUBDRRywgALKaHg0
Jan 13 20:09:07.369153 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent.
Jan 13 20:09:07.371488 sshd-session[2230]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Jan 13 20:09:07.382396 (kubelet)[2237]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS
Jan 13 20:09:07.386434 systemd-logind[1922]: New session 7 of user core.
Jan 13 20:09:07.398132 systemd[1]: Started session-7.scope - Session 7 of User core.
Jan 13 20:09:07.467666 kubelet[2237]: E0113 20:09:07.467608    2237 run.go:74] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory"
Jan 13 20:09:07.475203 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE
Jan 13 20:09:07.475531 systemd[1]: kubelet.service: Failed with result 'exit-code'.
Jan 13 20:09:07.515124 sudo[2246]:     core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh
Jan 13 20:09:07.515758 sudo[2246]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500)
Jan 13 20:09:08.629684 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent.
Jan 13 20:09:08.642279 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent...
Jan 13 20:09:08.678896 systemd[1]: Reloading requested from client PID 2283 ('systemctl') (unit session-7.scope)...
Jan 13 20:09:08.678922 systemd[1]: Reloading...
Jan 13 20:09:08.897880 zram_generator::config[2323]: No configuration found.
Jan 13 20:09:09.148529 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.
Jan 13 20:09:09.316358 systemd[1]: Reloading finished in 636 ms.
Jan 13 20:09:09.413194 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM
Jan 13 20:09:09.413385 systemd[1]: kubelet.service: Failed with result 'signal'.
Jan 13 20:09:09.414914 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent.
Jan 13 20:09:09.422581 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent...
Jan 13 20:09:09.749068 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent.
Jan 13 20:09:09.749423 (kubelet)[2386]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS
Jan 13 20:09:09.825840 kubelet[2386]: 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.
Jan 13 20:09:09.825840 kubelet[2386]: 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.
Jan 13 20:09:09.825840 kubelet[2386]: 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.
Jan 13 20:09:09.827762 kubelet[2386]: I0113 20:09:09.827670    2386 server.go:205] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime"
Jan 13 20:09:11.595968 kubelet[2386]: I0113 20:09:11.595901    2386 server.go:484] "Kubelet version" kubeletVersion="v1.30.1"
Jan 13 20:09:11.595968 kubelet[2386]: I0113 20:09:11.595951    2386 server.go:486] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK=""
Jan 13 20:09:11.596644 kubelet[2386]: I0113 20:09:11.596278    2386 server.go:927] "Client rotation is on, will bootstrap in background"
Jan 13 20:09:11.620902 kubelet[2386]: I0113 20:09:11.620593    2386 dynamic_cafile_content.go:157] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt"
Jan 13 20:09:11.638383 kubelet[2386]: I0113 20:09:11.638323    2386 server.go:742] "--cgroups-per-qos enabled, but --cgroup-root was not specified.  defaulting to /"
Jan 13 20:09:11.641138 kubelet[2386]: I0113 20:09:11.641060    2386 container_manager_linux.go:265] "Container manager verified user specified cgroup-root exists" cgroupRoot=[]
Jan 13 20:09:11.641481 kubelet[2386]: I0113 20:09:11.641133    2386 container_manager_linux.go:270] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"172.31.22.23","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null}
Jan 13 20:09:11.641649 kubelet[2386]: I0113 20:09:11.641500    2386 topology_manager.go:138] "Creating topology manager with none policy"
Jan 13 20:09:11.641649 kubelet[2386]: I0113 20:09:11.641522    2386 container_manager_linux.go:301] "Creating device plugin manager"
Jan 13 20:09:11.641880 kubelet[2386]: I0113 20:09:11.641765    2386 state_mem.go:36] "Initialized new in-memory state store"
Jan 13 20:09:11.643390 kubelet[2386]: I0113 20:09:11.643343    2386 kubelet.go:400] "Attempting to sync node with API server"
Jan 13 20:09:11.643390 kubelet[2386]: I0113 20:09:11.643386    2386 kubelet.go:301] "Adding static pod path" path="/etc/kubernetes/manifests"
Jan 13 20:09:11.643558 kubelet[2386]: I0113 20:09:11.643503    2386 kubelet.go:312] "Adding apiserver pod source"
Jan 13 20:09:11.644713 kubelet[2386]: I0113 20:09:11.643613    2386 apiserver.go:42] "Waiting for node sync before watching apiserver pods"
Jan 13 20:09:11.644713 kubelet[2386]: E0113 20:09:11.644326    2386 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:11.644713 kubelet[2386]: E0113 20:09:11.644438    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:11.645575 kubelet[2386]: I0113 20:09:11.645533    2386 kuberuntime_manager.go:261] "Container runtime initialized" containerRuntime="containerd" version="v1.7.23" apiVersion="v1"
Jan 13 20:09:11.646021 kubelet[2386]: I0113 20:09:11.645984    2386 kubelet.go:815] "Not starting ClusterTrustBundle informer because we are in static kubelet mode"
Jan 13 20:09:11.646115 kubelet[2386]: W0113 20:09:11.646061    2386 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating.
Jan 13 20:09:11.647418 kubelet[2386]: I0113 20:09:11.647182    2386 server.go:1264] "Started kubelet"
Jan 13 20:09:11.650146 kubelet[2386]: I0113 20:09:11.649644    2386 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer"
Jan 13 20:09:11.658869 kubelet[2386]: I0113 20:09:11.657677    2386 server.go:163] "Starting to listen" address="0.0.0.0" port=10250
Jan 13 20:09:11.659726 kubelet[2386]: I0113 20:09:11.659695    2386 server.go:455] "Adding debug handlers to kubelet server"
Jan 13 20:09:11.660872 kubelet[2386]: I0113 20:09:11.660801    2386 volume_manager.go:291] "Starting Kubelet Volume Manager"
Jan 13 20:09:11.662054 kubelet[2386]: I0113 20:09:11.661772    2386 desired_state_of_world_populator.go:149] "Desired state populator starts to run"
Jan 13 20:09:11.664850 kubelet[2386]: I0113 20:09:11.662617    2386 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10
Jan 13 20:09:11.664850 kubelet[2386]: I0113 20:09:11.662973    2386 server.go:227] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock"
Jan 13 20:09:11.665588 kubelet[2386]: I0113 20:09:11.665542    2386 reconciler.go:26] "Reconciler: start to sync state"
Jan 13 20:09:11.671897 kubelet[2386]: E0113 20:09:11.671852    2386 kubelet.go:1467] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem"
Jan 13 20:09:11.672110 kubelet[2386]: I0113 20:09:11.672063    2386 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
Jan 13 20:09:11.676957 kubelet[2386]: W0113 20:09:11.676915    2386 reflector.go:547] k8s.io/client-go/informers/factory.go:160: failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User "system:anonymous" cannot list resource "csidrivers" in API group "storage.k8s.io" at the cluster scope
Jan 13 20:09:11.677170 kubelet[2386]: E0113 20:09:11.677148    2386 reflector.go:150] k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.CSIDriver: failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User "system:anonymous" cannot list resource "csidrivers" in API group "storage.k8s.io" at the cluster scope
Jan 13 20:09:11.677375 kubelet[2386]: E0113 20:09:11.677341    2386 controller.go:145] "Failed to ensure lease exists, will retry" err="leases.coordination.k8s.io \"172.31.22.23\" is forbidden: User \"system:anonymous\" cannot get resource \"leases\" in API group \"coordination.k8s.io\" in the namespace \"kube-node-lease\"" interval="200ms"
Jan 13 20:09:11.677734 kubelet[2386]: E0113 20:09:11.677571    2386 event.go:359] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{172.31.22.23.181a5971cf32fa27  default    0 0001-01-01 00:00:00 +0000 UTC <nil> <nil> map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:172.31.22.23,UID:172.31.22.23,APIVersion:,ResourceVersion:,FieldPath:,},Reason:Starting,Message:Starting kubelet.,Source:EventSource{Component:kubelet,Host:172.31.22.23,},FirstTimestamp:2025-01-13 20:09:11.647148583 +0000 UTC m=+1.889043550,LastTimestamp:2025-01-13 20:09:11.647148583 +0000 UTC m=+1.889043550,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:172.31.22.23,}"
Jan 13 20:09:11.678893 kubelet[2386]: W0113 20:09:11.678852    2386 reflector.go:547] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Node: nodes "172.31.22.23" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope
Jan 13 20:09:11.679341 kubelet[2386]: E0113 20:09:11.679318    2386 reflector.go:150] k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Node: failed to list *v1.Node: nodes "172.31.22.23" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope
Jan 13 20:09:11.679457 kubelet[2386]: W0113 20:09:11.679267    2386 reflector.go:547] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope
Jan 13 20:09:11.679594 kubelet[2386]: E0113 20:09:11.679551    2386 reflector.go:150] k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Service: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope
Jan 13 20:09:11.680041 kubelet[2386]: I0113 20:09:11.680003    2386 factory.go:221] Registration of the containerd container factory successfully
Jan 13 20:09:11.680041 kubelet[2386]: I0113 20:09:11.680035    2386 factory.go:221] Registration of the systemd container factory successfully
Jan 13 20:09:11.706905 kubelet[2386]: I0113 20:09:11.706865    2386 cpu_manager.go:214] "Starting CPU manager" policy="none"
Jan 13 20:09:11.706905 kubelet[2386]: I0113 20:09:11.706899    2386 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s"
Jan 13 20:09:11.707112 kubelet[2386]: I0113 20:09:11.706932    2386 state_mem.go:36] "Initialized new in-memory state store"
Jan 13 20:09:11.710481 kubelet[2386]: I0113 20:09:11.710441    2386 policy_none.go:49] "None policy: Start"
Jan 13 20:09:11.711903 kubelet[2386]: I0113 20:09:11.711740    2386 memory_manager.go:170] "Starting memorymanager" policy="None"
Jan 13 20:09:11.711903 kubelet[2386]: I0113 20:09:11.711785    2386 state_mem.go:35] "Initializing new in-memory state store"
Jan 13 20:09:11.726833 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice.
Jan 13 20:09:11.742051 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice.
Jan 13 20:09:11.750683 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice.
Jan 13 20:09:11.767185 kubelet[2386]: I0113 20:09:11.763554    2386 manager.go:479] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found"
Jan 13 20:09:11.767185 kubelet[2386]: I0113 20:09:11.763872    2386 container_log_manager.go:186] "Initializing container log rotate workers" workers=1 monitorPeriod="10s"
Jan 13 20:09:11.767185 kubelet[2386]: I0113 20:09:11.764086    2386 plugin_manager.go:118] "Starting Kubelet Plugin Manager"
Jan 13 20:09:11.770910 kubelet[2386]: I0113 20:09:11.770858    2386 kubelet_node_status.go:73] "Attempting to register node" node="172.31.22.23"
Jan 13 20:09:11.773685 kubelet[2386]: E0113 20:09:11.773632    2386 eviction_manager.go:282] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"172.31.22.23\" not found"
Jan 13 20:09:11.779402 kubelet[2386]: I0113 20:09:11.779351    2386 kubelet_node_status.go:76] "Successfully registered node" node="172.31.22.23"
Jan 13 20:09:11.780015 kubelet[2386]: I0113 20:09:11.779969    2386 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4"
Jan 13 20:09:11.783804 kubelet[2386]: I0113 20:09:11.783686    2386 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6"
Jan 13 20:09:11.783804 kubelet[2386]: I0113 20:09:11.783754    2386 status_manager.go:217] "Starting to sync pod status with apiserver"
Jan 13 20:09:11.783804 kubelet[2386]: I0113 20:09:11.783788    2386 kubelet.go:2337] "Starting kubelet main sync loop"
Jan 13 20:09:11.784065 kubelet[2386]: E0113 20:09:11.783918    2386 kubelet.go:2361] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful"
Jan 13 20:09:11.831226 kubelet[2386]: E0113 20:09:11.831137    2386 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.22.23\" not found"
Jan 13 20:09:11.834092 sudo[2246]: pam_unix(sudo:session): session closed for user root
Jan 13 20:09:11.856937 sshd[2242]: Connection closed by 147.75.109.163 port 48516
Jan 13 20:09:11.857055 sshd-session[2230]: pam_unix(sshd:session): session closed for user core
Jan 13 20:09:11.865267 systemd[1]: sshd@6-172.31.22.23:22-147.75.109.163:48516.service: Deactivated successfully.
Jan 13 20:09:11.866014 systemd-logind[1922]: Session 7 logged out. Waiting for processes to exit.
Jan 13 20:09:11.868721 systemd[1]: session-7.scope: Deactivated successfully.
Jan 13 20:09:11.872175 systemd-logind[1922]: Removed session 7.
Jan 13 20:09:11.931978 kubelet[2386]: E0113 20:09:11.931891    2386 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.22.23\" not found"
Jan 13 20:09:12.032613 kubelet[2386]: E0113 20:09:12.032555    2386 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.22.23\" not found"
Jan 13 20:09:12.133524 kubelet[2386]: E0113 20:09:12.133385    2386 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.22.23\" not found"
Jan 13 20:09:12.234248 kubelet[2386]: E0113 20:09:12.234197    2386 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.22.23\" not found"
Jan 13 20:09:12.334912 kubelet[2386]: E0113 20:09:12.334862    2386 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.22.23\" not found"
Jan 13 20:09:12.435545 kubelet[2386]: E0113 20:09:12.435424    2386 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.22.23\" not found"
Jan 13 20:09:12.536192 kubelet[2386]: E0113 20:09:12.536135    2386 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.22.23\" not found"
Jan 13 20:09:12.599801 kubelet[2386]: I0113 20:09:12.599753    2386 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials"
Jan 13 20:09:12.600329 kubelet[2386]: W0113 20:09:12.600016    2386 reflector.go:470] k8s.io/client-go/informers/factory.go:160: watch of *v1.RuntimeClass ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received
Jan 13 20:09:12.637329 kubelet[2386]: E0113 20:09:12.637259    2386 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.22.23\" not found"
Jan 13 20:09:12.645045 kubelet[2386]: E0113 20:09:12.644975    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:12.738292 kubelet[2386]: E0113 20:09:12.738139    2386 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.22.23\" not found"
Jan 13 20:09:12.838469 kubelet[2386]: E0113 20:09:12.838409    2386 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.22.23\" not found"
Jan 13 20:09:12.939021 kubelet[2386]: E0113 20:09:12.938975    2386 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.22.23\" not found"
Jan 13 20:09:13.039695 kubelet[2386]: E0113 20:09:13.039546    2386 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.22.23\" not found"
Jan 13 20:09:13.140326 kubelet[2386]: E0113 20:09:13.140272    2386 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.22.23\" not found"
Jan 13 20:09:13.241656 kubelet[2386]: I0113 20:09:13.241380    2386 kuberuntime_manager.go:1523] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24"
Jan 13 20:09:13.241947 containerd[1938]: time="2025-01-13T20:09:13.241869559Z" level=info msg="No cni config template is specified, wait for other system components to drop the config."
Jan 13 20:09:13.243085 kubelet[2386]: I0113 20:09:13.242257    2386 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24"
Jan 13 20:09:13.645427 kubelet[2386]: I0113 20:09:13.645150    2386 apiserver.go:52] "Watching apiserver"
Jan 13 20:09:13.646072 kubelet[2386]: E0113 20:09:13.645906    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:13.651094 kubelet[2386]: I0113 20:09:13.651036    2386 topology_manager.go:215] "Topology Admit Handler" podUID="b7ff9fe6-4bfd-4cea-86f3-df7e2f100195" podNamespace="kube-flannel" podName="kube-flannel-ds-ptgj4"
Jan 13 20:09:13.651249 kubelet[2386]: I0113 20:09:13.651202    2386 topology_manager.go:215] "Topology Admit Handler" podUID="4c414a93-f868-47b2-8934-e0f729f50bdf" podNamespace="kube-system" podName="kube-proxy-px27z"
Jan 13 20:09:13.662333 kubelet[2386]: I0113 20:09:13.662266    2386 desired_state_of_world_populator.go:157] "Finished populating initial desired state of world"
Jan 13 20:09:13.666179 systemd[1]: Created slice kubepods-burstable-podb7ff9fe6_4bfd_4cea_86f3_df7e2f100195.slice - libcontainer container kubepods-burstable-podb7ff9fe6_4bfd_4cea_86f3_df7e2f100195.slice.
Jan 13 20:09:13.680351 kubelet[2386]: I0113 20:09:13.680310    2386 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/4c414a93-f868-47b2-8934-e0f729f50bdf-kube-proxy\") pod \"kube-proxy-px27z\" (UID: \"4c414a93-f868-47b2-8934-e0f729f50bdf\") " pod="kube-system/kube-proxy-px27z"
Jan 13 20:09:13.680554 kubelet[2386]: I0113 20:09:13.680525    2386 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/b7ff9fe6-4bfd-4cea-86f3-df7e2f100195-run\") pod \"kube-flannel-ds-ptgj4\" (UID: \"b7ff9fe6-4bfd-4cea-86f3-df7e2f100195\") " pod="kube-flannel/kube-flannel-ds-ptgj4"
Jan 13 20:09:13.680683 kubelet[2386]: I0113 20:09:13.680660    2386 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/b7ff9fe6-4bfd-4cea-86f3-df7e2f100195-cni-plugin\") pod \"kube-flannel-ds-ptgj4\" (UID: \"b7ff9fe6-4bfd-4cea-86f3-df7e2f100195\") " pod="kube-flannel/kube-flannel-ds-ptgj4"
Jan 13 20:09:13.680845 kubelet[2386]: I0113 20:09:13.680797    2386 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/b7ff9fe6-4bfd-4cea-86f3-df7e2f100195-flannel-cfg\") pod \"kube-flannel-ds-ptgj4\" (UID: \"b7ff9fe6-4bfd-4cea-86f3-df7e2f100195\") " pod="kube-flannel/kube-flannel-ds-ptgj4"
Jan 13 20:09:13.681328 kubelet[2386]: I0113 20:09:13.680941    2386 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/4c414a93-f868-47b2-8934-e0f729f50bdf-xtables-lock\") pod \"kube-proxy-px27z\" (UID: \"4c414a93-f868-47b2-8934-e0f729f50bdf\") " pod="kube-system/kube-proxy-px27z"
Jan 13 20:09:13.681328 kubelet[2386]: I0113 20:09:13.680998    2386 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/4c414a93-f868-47b2-8934-e0f729f50bdf-lib-modules\") pod \"kube-proxy-px27z\" (UID: \"4c414a93-f868-47b2-8934-e0f729f50bdf\") " pod="kube-system/kube-proxy-px27z"
Jan 13 20:09:13.681328 kubelet[2386]: I0113 20:09:13.681049    2386 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-f44mq\" (UniqueName: \"kubernetes.io/projected/4c414a93-f868-47b2-8934-e0f729f50bdf-kube-api-access-f44mq\") pod \"kube-proxy-px27z\" (UID: \"4c414a93-f868-47b2-8934-e0f729f50bdf\") " pod="kube-system/kube-proxy-px27z"
Jan 13 20:09:13.681328 kubelet[2386]: I0113 20:09:13.681086    2386 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/b7ff9fe6-4bfd-4cea-86f3-df7e2f100195-cni\") pod \"kube-flannel-ds-ptgj4\" (UID: \"b7ff9fe6-4bfd-4cea-86f3-df7e2f100195\") " pod="kube-flannel/kube-flannel-ds-ptgj4"
Jan 13 20:09:13.681328 kubelet[2386]: I0113 20:09:13.681127    2386 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/b7ff9fe6-4bfd-4cea-86f3-df7e2f100195-xtables-lock\") pod \"kube-flannel-ds-ptgj4\" (UID: \"b7ff9fe6-4bfd-4cea-86f3-df7e2f100195\") " pod="kube-flannel/kube-flannel-ds-ptgj4"
Jan 13 20:09:13.681664 kubelet[2386]: I0113 20:09:13.681170    2386 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-7t478\" (UniqueName: \"kubernetes.io/projected/b7ff9fe6-4bfd-4cea-86f3-df7e2f100195-kube-api-access-7t478\") pod \"kube-flannel-ds-ptgj4\" (UID: \"b7ff9fe6-4bfd-4cea-86f3-df7e2f100195\") " pod="kube-flannel/kube-flannel-ds-ptgj4"
Jan 13 20:09:13.684196 systemd[1]: Created slice kubepods-besteffort-pod4c414a93_f868_47b2_8934_e0f729f50bdf.slice - libcontainer container kubepods-besteffort-pod4c414a93_f868_47b2_8934_e0f729f50bdf.slice.
Jan 13 20:09:13.985914 containerd[1938]: time="2025-01-13T20:09:13.984993863Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-ptgj4,Uid:b7ff9fe6-4bfd-4cea-86f3-df7e2f100195,Namespace:kube-flannel,Attempt:0,}"
Jan 13 20:09:13.995880 containerd[1938]: time="2025-01-13T20:09:13.995679203Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-px27z,Uid:4c414a93-f868-47b2-8934-e0f729f50bdf,Namespace:kube-system,Attempt:0,}"
Jan 13 20:09:14.579057 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount943454363.mount: Deactivated successfully.
Jan 13 20:09:14.589374 containerd[1938]: time="2025-01-13T20:09:14.588890014Z" 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\"}"
Jan 13 20:09:14.591127 containerd[1938]: time="2025-01-13T20:09:14.591055786Z" 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\"}"
Jan 13 20:09:14.592615 containerd[1938]: time="2025-01-13T20:09:14.592568134Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=269173"
Jan 13 20:09:14.593856 containerd[1938]: time="2025-01-13T20:09:14.593691862Z" level=info msg="ImageCreate event name:\"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}"
Jan 13 20:09:14.594208 containerd[1938]: time="2025-01-13T20:09:14.594155698Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0"
Jan 13 20:09:14.597539 containerd[1938]: time="2025-01-13T20:09:14.597460534Z" 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\"}"
Jan 13 20:09:14.602403 containerd[1938]: time="2025-01-13T20:09:14.602092234Z" 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 606.308523ms"
Jan 13 20:09:14.604917 containerd[1938]: time="2025-01-13T20:09:14.604850350Z" 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 618.919887ms"
Jan 13 20:09:14.646859 kubelet[2386]: E0113 20:09:14.646763    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:14.854865 containerd[1938]: time="2025-01-13T20:09:14.851168879Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1
Jan 13 20:09:14.854865 containerd[1938]: time="2025-01-13T20:09:14.851502995Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1
Jan 13 20:09:14.854865 containerd[1938]: time="2025-01-13T20:09:14.851544107Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jan 13 20:09:14.854865 containerd[1938]: time="2025-01-13T20:09:14.852076475Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jan 13 20:09:14.856761 containerd[1938]: time="2025-01-13T20:09:14.838193903Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1
Jan 13 20:09:14.856761 containerd[1938]: time="2025-01-13T20:09:14.839937239Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1
Jan 13 20:09:14.856761 containerd[1938]: time="2025-01-13T20:09:14.839966195Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jan 13 20:09:14.856761 containerd[1938]: time="2025-01-13T20:09:14.840224999Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jan 13 20:09:14.958350 systemd[1]: run-containerd-runc-k8s.io-57353cff8cf9a0427e983b320e5a30dd4b749789fa92c5d13f34feda36d322b6-runc.3cwVO2.mount: Deactivated successfully.
Jan 13 20:09:14.980124 systemd[1]: Started cri-containerd-57353cff8cf9a0427e983b320e5a30dd4b749789fa92c5d13f34feda36d322b6.scope - libcontainer container 57353cff8cf9a0427e983b320e5a30dd4b749789fa92c5d13f34feda36d322b6.
Jan 13 20:09:14.984601 systemd[1]: Started cri-containerd-7d60ba8c2d1802a30ef037607f9703dd2067fa993233ad8b5316a302c0d5705c.scope - libcontainer container 7d60ba8c2d1802a30ef037607f9703dd2067fa993233ad8b5316a302c0d5705c.
Jan 13 20:09:15.035163 containerd[1938]: time="2025-01-13T20:09:15.034916972Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-px27z,Uid:4c414a93-f868-47b2-8934-e0f729f50bdf,Namespace:kube-system,Attempt:0,} returns sandbox id \"57353cff8cf9a0427e983b320e5a30dd4b749789fa92c5d13f34feda36d322b6\""
Jan 13 20:09:15.039768 containerd[1938]: time="2025-01-13T20:09:15.039451112Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.30.8\""
Jan 13 20:09:15.061505 containerd[1938]: time="2025-01-13T20:09:15.061453244Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-ptgj4,Uid:b7ff9fe6-4bfd-4cea-86f3-df7e2f100195,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"7d60ba8c2d1802a30ef037607f9703dd2067fa993233ad8b5316a302c0d5705c\""
Jan 13 20:09:15.647405 kubelet[2386]: E0113 20:09:15.647306    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:16.356455 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3973854813.mount: Deactivated successfully.
Jan 13 20:09:16.648102 kubelet[2386]: E0113 20:09:16.647859    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:16.852692 containerd[1938]: time="2025-01-13T20:09:16.852405277Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.30.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 13 20:09:16.853782 containerd[1938]: time="2025-01-13T20:09:16.853714885Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.30.8: active requests=0, bytes read=25662011"
Jan 13 20:09:16.854788 containerd[1938]: time="2025-01-13T20:09:16.854691277Z" level=info msg="ImageCreate event name:\"sha256:4612aebc0675831aedbbde7cd56b85db91f1fdcf05ef923072961538ec497adb\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 13 20:09:16.859327 containerd[1938]: time="2025-01-13T20:09:16.859229425Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:f6d6be9417e22af78905000ac4fd134896bacd2188ea63c7cac8edd7a5d7e9b5\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 13 20:09:16.861582 containerd[1938]: time="2025-01-13T20:09:16.860641597Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.30.8\" with image id \"sha256:4612aebc0675831aedbbde7cd56b85db91f1fdcf05ef923072961538ec497adb\", repo tag \"registry.k8s.io/kube-proxy:v1.30.8\", repo digest \"registry.k8s.io/kube-proxy@sha256:f6d6be9417e22af78905000ac4fd134896bacd2188ea63c7cac8edd7a5d7e9b5\", size \"25661030\" in 1.821132669s"
Jan 13 20:09:16.861582 containerd[1938]: time="2025-01-13T20:09:16.860742445Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.30.8\" returns image reference \"sha256:4612aebc0675831aedbbde7cd56b85db91f1fdcf05ef923072961538ec497adb\""
Jan 13 20:09:16.862665 containerd[1938]: time="2025-01-13T20:09:16.862617457Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\""
Jan 13 20:09:16.865376 containerd[1938]: time="2025-01-13T20:09:16.865289869Z" level=info msg="CreateContainer within sandbox \"57353cff8cf9a0427e983b320e5a30dd4b749789fa92c5d13f34feda36d322b6\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}"
Jan 13 20:09:16.890186 containerd[1938]: time="2025-01-13T20:09:16.890127481Z" level=info msg="CreateContainer within sandbox \"57353cff8cf9a0427e983b320e5a30dd4b749789fa92c5d13f34feda36d322b6\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"b2f94b6eb308504c24695f5dfa5897c63c327423f377904d68a06a719813d416\""
Jan 13 20:09:16.891606 containerd[1938]: time="2025-01-13T20:09:16.891561925Z" level=info msg="StartContainer for \"b2f94b6eb308504c24695f5dfa5897c63c327423f377904d68a06a719813d416\""
Jan 13 20:09:16.948128 systemd[1]: Started cri-containerd-b2f94b6eb308504c24695f5dfa5897c63c327423f377904d68a06a719813d416.scope - libcontainer container b2f94b6eb308504c24695f5dfa5897c63c327423f377904d68a06a719813d416.
Jan 13 20:09:17.002710 containerd[1938]: time="2025-01-13T20:09:17.002453590Z" level=info msg="StartContainer for \"b2f94b6eb308504c24695f5dfa5897c63c327423f377904d68a06a719813d416\" returns successfully"
Jan 13 20:09:17.649004 kubelet[2386]: E0113 20:09:17.648949    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:18.645162 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount118893065.mount: Deactivated successfully.
Jan 13 20:09:18.650061 kubelet[2386]: E0113 20:09:18.649986    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:18.714505 containerd[1938]: time="2025-01-13T20:09:18.714426122Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 13 20:09:18.716098 containerd[1938]: time="2025-01-13T20:09:18.716014130Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3673532"
Jan 13 20:09:18.717331 containerd[1938]: time="2025-01-13T20:09:18.717245534Z" level=info msg="ImageCreate event name:\"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 13 20:09:18.722070 containerd[1938]: time="2025-01-13T20:09:18.721973162Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 13 20:09:18.723747 containerd[1938]: time="2025-01-13T20:09:18.723559550Z" 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.860428025s"
Jan 13 20:09:18.723747 containerd[1938]: time="2025-01-13T20:09:18.723613250Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\""
Jan 13 20:09:18.727921 containerd[1938]: time="2025-01-13T20:09:18.727583234Z" level=info msg="CreateContainer within sandbox \"7d60ba8c2d1802a30ef037607f9703dd2067fa993233ad8b5316a302c0d5705c\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}"
Jan 13 20:09:18.747585 containerd[1938]: time="2025-01-13T20:09:18.747511178Z" level=info msg="CreateContainer within sandbox \"7d60ba8c2d1802a30ef037607f9703dd2067fa993233ad8b5316a302c0d5705c\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"d646c194ac1add444d0a147ca45910af57f63b1a286ce7d936cb49abf98edb6c\""
Jan 13 20:09:18.748591 containerd[1938]: time="2025-01-13T20:09:18.748212866Z" level=info msg="StartContainer for \"d646c194ac1add444d0a147ca45910af57f63b1a286ce7d936cb49abf98edb6c\""
Jan 13 20:09:18.798144 systemd[1]: Started cri-containerd-d646c194ac1add444d0a147ca45910af57f63b1a286ce7d936cb49abf98edb6c.scope - libcontainer container d646c194ac1add444d0a147ca45910af57f63b1a286ce7d936cb49abf98edb6c.
Jan 13 20:09:18.842536 containerd[1938]: time="2025-01-13T20:09:18.842472519Z" level=info msg="StartContainer for \"d646c194ac1add444d0a147ca45910af57f63b1a286ce7d936cb49abf98edb6c\" returns successfully"
Jan 13 20:09:18.845059 systemd[1]: cri-containerd-d646c194ac1add444d0a147ca45910af57f63b1a286ce7d936cb49abf98edb6c.scope: Deactivated successfully.
Jan 13 20:09:19.195287 containerd[1938]: time="2025-01-13T20:09:19.195160717Z" level=info msg="shim disconnected" id=d646c194ac1add444d0a147ca45910af57f63b1a286ce7d936cb49abf98edb6c namespace=k8s.io
Jan 13 20:09:19.195287 containerd[1938]: time="2025-01-13T20:09:19.195254977Z" level=warning msg="cleaning up after shim disconnected" id=d646c194ac1add444d0a147ca45910af57f63b1a286ce7d936cb49abf98edb6c namespace=k8s.io
Jan 13 20:09:19.195287 containerd[1938]: time="2025-01-13T20:09:19.195276433Z" level=info msg="cleaning up dead shim" namespace=k8s.io
Jan 13 20:09:19.214870 containerd[1938]: time="2025-01-13T20:09:19.214664977Z" level=warning msg="cleanup warnings time=\"2025-01-13T20:09:19Z\" level=warning msg=\"failed to remove runc container\" error=\"runc did not terminate successfully: exit status 255: \" runtime=io.containerd.runc.v2\n" namespace=k8s.io
Jan 13 20:09:19.517604 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-d646c194ac1add444d0a147ca45910af57f63b1a286ce7d936cb49abf98edb6c-rootfs.mount: Deactivated successfully.
Jan 13 20:09:19.650505 kubelet[2386]: E0113 20:09:19.650436    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:19.850179 containerd[1938]: time="2025-01-13T20:09:19.850046464Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\""
Jan 13 20:09:19.867133 kubelet[2386]: I0113 20:09:19.866963    2386 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-px27z" podStartSLOduration=7.042578255 podStartE2EDuration="8.866942764s" podCreationTimestamp="2025-01-13 20:09:11 +0000 UTC" firstStartedPulling="2025-01-13 20:09:15.037986884 +0000 UTC m=+5.279881851" lastFinishedPulling="2025-01-13 20:09:16.862351393 +0000 UTC m=+7.104246360" observedRunningTime="2025-01-13 20:09:17.85023527 +0000 UTC m=+8.092130285" watchObservedRunningTime="2025-01-13 20:09:19.866942764 +0000 UTC m=+10.108837743"
Jan 13 20:09:20.650881 kubelet[2386]: E0113 20:09:20.650801    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:21.651766 kubelet[2386]: E0113 20:09:21.651689    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:21.733616 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1601739748.mount: Deactivated successfully.
Jan 13 20:09:22.652987 kubelet[2386]: E0113 20:09:22.652720    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:22.752314 containerd[1938]: time="2025-01-13T20:09:22.752238882Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 13 20:09:22.755133 containerd[1938]: time="2025-01-13T20:09:22.755056566Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26874261"
Jan 13 20:09:22.756956 containerd[1938]: time="2025-01-13T20:09:22.756854262Z" level=info msg="ImageCreate event name:\"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 13 20:09:22.762494 containerd[1938]: time="2025-01-13T20:09:22.762396522Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 13 20:09:22.764937 containerd[1938]: time="2025-01-13T20:09:22.764734758Z" 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 2.914082462s"
Jan 13 20:09:22.764937 containerd[1938]: time="2025-01-13T20:09:22.764788662Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\""
Jan 13 20:09:22.769429 containerd[1938]: time="2025-01-13T20:09:22.769251438Z" level=info msg="CreateContainer within sandbox \"7d60ba8c2d1802a30ef037607f9703dd2067fa993233ad8b5316a302c0d5705c\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}"
Jan 13 20:09:22.788233 containerd[1938]: time="2025-01-13T20:09:22.788168731Z" level=info msg="CreateContainer within sandbox \"7d60ba8c2d1802a30ef037607f9703dd2067fa993233ad8b5316a302c0d5705c\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"a09d7a05a3e01aa4b1a246a7ddd292249a4f4e2be6f7cb6c42855b5c9eff4275\""
Jan 13 20:09:22.789910 containerd[1938]: time="2025-01-13T20:09:22.788873995Z" level=info msg="StartContainer for \"a09d7a05a3e01aa4b1a246a7ddd292249a4f4e2be6f7cb6c42855b5c9eff4275\""
Jan 13 20:09:22.836488 systemd[1]: run-containerd-runc-k8s.io-a09d7a05a3e01aa4b1a246a7ddd292249a4f4e2be6f7cb6c42855b5c9eff4275-runc.OudifW.mount: Deactivated successfully.
Jan 13 20:09:22.852093 systemd[1]: Started cri-containerd-a09d7a05a3e01aa4b1a246a7ddd292249a4f4e2be6f7cb6c42855b5c9eff4275.scope - libcontainer container a09d7a05a3e01aa4b1a246a7ddd292249a4f4e2be6f7cb6c42855b5c9eff4275.
Jan 13 20:09:22.899273 systemd[1]: cri-containerd-a09d7a05a3e01aa4b1a246a7ddd292249a4f4e2be6f7cb6c42855b5c9eff4275.scope: Deactivated successfully.
Jan 13 20:09:22.901968 containerd[1938]: time="2025-01-13T20:09:22.901433587Z" level=info msg="StartContainer for \"a09d7a05a3e01aa4b1a246a7ddd292249a4f4e2be6f7cb6c42855b5c9eff4275\" returns successfully"
Jan 13 20:09:22.907964 kubelet[2386]: I0113 20:09:22.907780    2386 kubelet_node_status.go:497] "Fast updating node status as it just became ready"
Jan 13 20:09:23.133013 containerd[1938]: time="2025-01-13T20:09:23.132927088Z" level=info msg="shim disconnected" id=a09d7a05a3e01aa4b1a246a7ddd292249a4f4e2be6f7cb6c42855b5c9eff4275 namespace=k8s.io
Jan 13 20:09:23.133340 containerd[1938]: time="2025-01-13T20:09:23.133308376Z" level=warning msg="cleaning up after shim disconnected" id=a09d7a05a3e01aa4b1a246a7ddd292249a4f4e2be6f7cb6c42855b5c9eff4275 namespace=k8s.io
Jan 13 20:09:23.133491 containerd[1938]: time="2025-01-13T20:09:23.133462228Z" level=info msg="cleaning up dead shim" namespace=k8s.io
Jan 13 20:09:23.204366 systemd[1]: systemd-hostnamed.service: Deactivated successfully.
Jan 13 20:09:23.653303 kubelet[2386]: E0113 20:09:23.653151    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:23.780012 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-a09d7a05a3e01aa4b1a246a7ddd292249a4f4e2be6f7cb6c42855b5c9eff4275-rootfs.mount: Deactivated successfully.
Jan 13 20:09:23.869671 containerd[1938]: time="2025-01-13T20:09:23.869563268Z" level=info msg="CreateContainer within sandbox \"7d60ba8c2d1802a30ef037607f9703dd2067fa993233ad8b5316a302c0d5705c\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}"
Jan 13 20:09:23.893982 containerd[1938]: time="2025-01-13T20:09:23.893846864Z" level=info msg="CreateContainer within sandbox \"7d60ba8c2d1802a30ef037607f9703dd2067fa993233ad8b5316a302c0d5705c\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"7f45509e9751ab5066a7e0e295e91d81bad081f08d2d001b0333c1d7544ad1f4\""
Jan 13 20:09:23.894431 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount636846503.mount: Deactivated successfully.
Jan 13 20:09:23.895506 containerd[1938]: time="2025-01-13T20:09:23.895225772Z" level=info msg="StartContainer for \"7f45509e9751ab5066a7e0e295e91d81bad081f08d2d001b0333c1d7544ad1f4\""
Jan 13 20:09:23.948133 systemd[1]: Started cri-containerd-7f45509e9751ab5066a7e0e295e91d81bad081f08d2d001b0333c1d7544ad1f4.scope - libcontainer container 7f45509e9751ab5066a7e0e295e91d81bad081f08d2d001b0333c1d7544ad1f4.
Jan 13 20:09:23.980414 kubelet[2386]: I0113 20:09:23.980185    2386 topology_manager.go:215] "Topology Admit Handler" podUID="7c599318-0d65-4a66-b64d-3972c1acda69" podNamespace="default" podName="nginx-deployment-85f456d6dd-vrjqn"
Jan 13 20:09:23.998787 systemd[1]: Created slice kubepods-besteffort-pod7c599318_0d65_4a66_b64d_3972c1acda69.slice - libcontainer container kubepods-besteffort-pod7c599318_0d65_4a66_b64d_3972c1acda69.slice.
Jan 13 20:09:24.000434 containerd[1938]: time="2025-01-13T20:09:23.999697677Z" level=info msg="StartContainer for \"7f45509e9751ab5066a7e0e295e91d81bad081f08d2d001b0333c1d7544ad1f4\" returns successfully"
Jan 13 20:09:24.092888 kubelet[2386]: I0113 20:09:24.092799    2386 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-gdnb8\" (UniqueName: \"kubernetes.io/projected/7c599318-0d65-4a66-b64d-3972c1acda69-kube-api-access-gdnb8\") pod \"nginx-deployment-85f456d6dd-vrjqn\" (UID: \"7c599318-0d65-4a66-b64d-3972c1acda69\") " pod="default/nginx-deployment-85f456d6dd-vrjqn"
Jan 13 20:09:24.306721 containerd[1938]: time="2025-01-13T20:09:24.306653454Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-85f456d6dd-vrjqn,Uid:7c599318-0d65-4a66-b64d-3972c1acda69,Namespace:default,Attempt:0,}"
Jan 13 20:09:24.348644 containerd[1938]: time="2025-01-13T20:09:24.348568338Z" level=error msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-85f456d6dd-vrjqn,Uid:7c599318-0d65-4a66-b64d-3972c1acda69,Namespace:default,Attempt:0,} failed, error" error="failed to setup network for sandbox \"2d305ca068557d5374efb9d7e2f8cf8c28fd0b55f306f17046fb202c4347681f\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory"
Jan 13 20:09:24.348995 kubelet[2386]: E0113 20:09:24.348940    2386 remote_runtime.go:193] "RunPodSandbox from runtime service failed" err="rpc error: code = Unknown desc = failed to setup network for sandbox \"2d305ca068557d5374efb9d7e2f8cf8c28fd0b55f306f17046fb202c4347681f\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory"
Jan 13 20:09:24.349105 kubelet[2386]: E0113 20:09:24.349035    2386 kuberuntime_sandbox.go:72] "Failed to create sandbox for pod" err="rpc error: code = Unknown desc = failed to setup network for sandbox \"2d305ca068557d5374efb9d7e2f8cf8c28fd0b55f306f17046fb202c4347681f\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" pod="default/nginx-deployment-85f456d6dd-vrjqn"
Jan 13 20:09:24.349105 kubelet[2386]: E0113 20:09:24.349071    2386 kuberuntime_manager.go:1166] "CreatePodSandbox for pod failed" err="rpc error: code = Unknown desc = failed to setup network for sandbox \"2d305ca068557d5374efb9d7e2f8cf8c28fd0b55f306f17046fb202c4347681f\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" pod="default/nginx-deployment-85f456d6dd-vrjqn"
Jan 13 20:09:24.349360 kubelet[2386]: E0113 20:09:24.349143    2386 pod_workers.go:1298] "Error syncing pod, skipping" err="failed to \"CreatePodSandbox\" for \"nginx-deployment-85f456d6dd-vrjqn_default(7c599318-0d65-4a66-b64d-3972c1acda69)\" with CreatePodSandboxError: \"Failed to create sandbox for pod \\\"nginx-deployment-85f456d6dd-vrjqn_default(7c599318-0d65-4a66-b64d-3972c1acda69)\\\": rpc error: code = Unknown desc = failed to setup network for sandbox \\\"2d305ca068557d5374efb9d7e2f8cf8c28fd0b55f306f17046fb202c4347681f\\\": plugin type=\\\"flannel\\\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory\"" pod="default/nginx-deployment-85f456d6dd-vrjqn" podUID="7c599318-0d65-4a66-b64d-3972c1acda69"
Jan 13 20:09:24.653766 kubelet[2386]: E0113 20:09:24.653609    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:24.786641 systemd[1]: run-containerd-runc-k8s.io-7f45509e9751ab5066a7e0e295e91d81bad081f08d2d001b0333c1d7544ad1f4-runc.78l1TA.mount: Deactivated successfully.
Jan 13 20:09:24.889346 kubelet[2386]: I0113 20:09:24.889211    2386 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-ptgj4" podStartSLOduration=6.186478007 podStartE2EDuration="13.889189797s" podCreationTimestamp="2025-01-13 20:09:11 +0000 UTC" firstStartedPulling="2025-01-13 20:09:15.064003856 +0000 UTC m=+5.305898823" lastFinishedPulling="2025-01-13 20:09:22.766715646 +0000 UTC m=+13.008610613" observedRunningTime="2025-01-13 20:09:24.889060965 +0000 UTC m=+15.130955932" watchObservedRunningTime="2025-01-13 20:09:24.889189797 +0000 UTC m=+15.131084764"
Jan 13 20:09:25.071117 (udev-worker)[2906]: Network interface NamePolicy= disabled on kernel command line.
Jan 13 20:09:25.091932 systemd-networkd[1869]: flannel.1: Link UP
Jan 13 20:09:25.092919 systemd-networkd[1869]: flannel.1: Gained carrier
Jan 13 20:09:25.654038 kubelet[2386]: E0113 20:09:25.653976    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:26.622990 systemd-networkd[1869]: flannel.1: Gained IPv6LL
Jan 13 20:09:26.654607 kubelet[2386]: E0113 20:09:26.654527    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:27.655634 kubelet[2386]: E0113 20:09:27.655574    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:28.626857 ntpd[1916]: Listen normally on 7 flannel.1 192.168.1.0:123
Jan 13 20:09:28.626986 ntpd[1916]: Listen normally on 8 flannel.1 [fe80::601c:f7ff:fe0e:c22%3]:123
Jan 13 20:09:28.627448 ntpd[1916]: 13 Jan 20:09:28 ntpd[1916]: Listen normally on 7 flannel.1 192.168.1.0:123
Jan 13 20:09:28.627448 ntpd[1916]: 13 Jan 20:09:28 ntpd[1916]: Listen normally on 8 flannel.1 [fe80::601c:f7ff:fe0e:c22%3]:123
Jan 13 20:09:28.655928 kubelet[2386]: E0113 20:09:28.655871    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:29.656943 kubelet[2386]: E0113 20:09:29.656901    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:30.658602 kubelet[2386]: E0113 20:09:30.658534    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:31.643749 kubelet[2386]: E0113 20:09:31.643693    2386 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:31.659230 kubelet[2386]: E0113 20:09:31.659189    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:32.660283 kubelet[2386]: E0113 20:09:32.660210    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:33.661397 kubelet[2386]: E0113 20:09:33.661333    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:34.662075 kubelet[2386]: E0113 20:09:34.662015    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:35.663208 kubelet[2386]: E0113 20:09:35.663146    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:36.663550 kubelet[2386]: E0113 20:09:36.663489    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:37.663693 kubelet[2386]: E0113 20:09:37.663622    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:38.181919 update_engine[1923]: I20250113 20:09:38.181175  1923 update_attempter.cc:509] Updating boot flags...
Jan 13 20:09:38.279029 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 38 scanned by (udev-worker) (3042)
Jan 13 20:09:38.559021 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 38 scanned by (udev-worker) (3041)
Jan 13 20:09:38.664090 kubelet[2386]: E0113 20:09:38.664014    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:38.820973 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 38 scanned by (udev-worker) (3041)
Jan 13 20:09:39.664789 kubelet[2386]: E0113 20:09:39.664742    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:39.786111 containerd[1938]: time="2025-01-13T20:09:39.785527571Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-85f456d6dd-vrjqn,Uid:7c599318-0d65-4a66-b64d-3972c1acda69,Namespace:default,Attempt:0,}"
Jan 13 20:09:39.816501 systemd-networkd[1869]: cni0: Link UP
Jan 13 20:09:39.816521 systemd-networkd[1869]: cni0: Gained carrier
Jan 13 20:09:39.818717 (udev-worker)[3044]: Network interface NamePolicy= disabled on kernel command line.
Jan 13 20:09:39.819077 systemd-networkd[1869]: cni0: Lost carrier
Jan 13 20:09:39.829799 (udev-worker)[3046]: Network interface NamePolicy= disabled on kernel command line.
Jan 13 20:09:39.833395 systemd-networkd[1869]: veth6b50c9a8: Link UP
Jan 13 20:09:39.836382 kernel: cni0: port 1(veth6b50c9a8) entered blocking state
Jan 13 20:09:39.836493 kernel: cni0: port 1(veth6b50c9a8) entered disabled state
Jan 13 20:09:39.839879 kernel: veth6b50c9a8: entered allmulticast mode
Jan 13 20:09:39.841049 kernel: veth6b50c9a8: entered promiscuous mode
Jan 13 20:09:39.844216 kernel: cni0: port 1(veth6b50c9a8) entered blocking state
Jan 13 20:09:39.844304 kernel: cni0: port 1(veth6b50c9a8) entered forwarding state
Jan 13 20:09:39.850863 kernel: cni0: port 1(veth6b50c9a8) entered disabled state
Jan 13 20:09:39.865928 kernel: cni0: port 1(veth6b50c9a8) entered blocking state
Jan 13 20:09:39.866067 kernel: cni0: port 1(veth6b50c9a8) entered forwarding state
Jan 13 20:09:39.865064 systemd-networkd[1869]: veth6b50c9a8: Gained carrier
Jan 13 20:09:39.867135 systemd-networkd[1869]: cni0: Gained carrier
Jan 13 20:09:39.869639 containerd[1938]: 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)(0x40000928e8), "name":"cbr0", "type":"bridge"}
Jan 13 20:09:39.869639 containerd[1938]: delegateAdd: netconf sent to delegate plugin:
Jan 13 20:09:39.899369 containerd[1938]: {"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":8951,"name":"cbr0","type":"bridge"}time="2025-01-13T20:09:39.898969992Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1
Jan 13 20:09:39.899369 containerd[1938]: time="2025-01-13T20:09:39.899085480Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1
Jan 13 20:09:39.899369 containerd[1938]: time="2025-01-13T20:09:39.899122008Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jan 13 20:09:39.899369 containerd[1938]: time="2025-01-13T20:09:39.899284104Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jan 13 20:09:39.941146 systemd[1]: Started cri-containerd-4dc89ea3056f69fd7b2a2b76f4e1d95de4adbb98b0e14f484acb1cd463f02bb3.scope - libcontainer container 4dc89ea3056f69fd7b2a2b76f4e1d95de4adbb98b0e14f484acb1cd463f02bb3.
Jan 13 20:09:40.003101 containerd[1938]: time="2025-01-13T20:09:40.003041744Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-85f456d6dd-vrjqn,Uid:7c599318-0d65-4a66-b64d-3972c1acda69,Namespace:default,Attempt:0,} returns sandbox id \"4dc89ea3056f69fd7b2a2b76f4e1d95de4adbb98b0e14f484acb1cd463f02bb3\""
Jan 13 20:09:40.006215 containerd[1938]: time="2025-01-13T20:09:40.006112532Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\""
Jan 13 20:09:40.665737 kubelet[2386]: E0113 20:09:40.665671    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:41.151493 systemd-networkd[1869]: cni0: Gained IPv6LL
Jan 13 20:09:41.666151 kubelet[2386]: E0113 20:09:41.666095    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:41.727155 systemd-networkd[1869]: veth6b50c9a8: Gained IPv6LL
Jan 13 20:09:42.667244 kubelet[2386]: E0113 20:09:42.667188    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:43.159229 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount171081149.mount: Deactivated successfully.
Jan 13 20:09:43.668033 kubelet[2386]: E0113 20:09:43.667971    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:44.479349 containerd[1938]: time="2025-01-13T20:09:44.479080610Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 13 20:09:44.481105 containerd[1938]: time="2025-01-13T20:09:44.480961886Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=67697045"
Jan 13 20:09:44.482198 containerd[1938]: time="2025-01-13T20:09:44.482104094Z" level=info msg="ImageCreate event name:\"sha256:a86cd5b7fd4c45b8b60dbcc26c955515e3a36347f806d2b7092c4908f54e0a55\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 13 20:09:44.487221 containerd[1938]: time="2025-01-13T20:09:44.487139726Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:eca1d1ff18c7af45f86b7e0b572090f563a676ddca3da2ecff678390366335ad\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 13 20:09:44.489613 containerd[1938]: time="2025-01-13T20:09:44.489101486Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:a86cd5b7fd4c45b8b60dbcc26c955515e3a36347f806d2b7092c4908f54e0a55\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:eca1d1ff18c7af45f86b7e0b572090f563a676ddca3da2ecff678390366335ad\", size \"67696923\" in 4.482936934s"
Jan 13 20:09:44.489613 containerd[1938]: time="2025-01-13T20:09:44.489156002Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:a86cd5b7fd4c45b8b60dbcc26c955515e3a36347f806d2b7092c4908f54e0a55\""
Jan 13 20:09:44.494081 containerd[1938]: time="2025-01-13T20:09:44.493892486Z" level=info msg="CreateContainer within sandbox \"4dc89ea3056f69fd7b2a2b76f4e1d95de4adbb98b0e14f484acb1cd463f02bb3\" for container &ContainerMetadata{Name:nginx,Attempt:0,}"
Jan 13 20:09:44.513307 containerd[1938]: time="2025-01-13T20:09:44.513245030Z" level=info msg="CreateContainer within sandbox \"4dc89ea3056f69fd7b2a2b76f4e1d95de4adbb98b0e14f484acb1cd463f02bb3\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"607c9473fc51bf982a5e9615f58115973587ab76f7ba7ba793913a36f2b783a2\""
Jan 13 20:09:44.515407 containerd[1938]: time="2025-01-13T20:09:44.514030754Z" level=info msg="StartContainer for \"607c9473fc51bf982a5e9615f58115973587ab76f7ba7ba793913a36f2b783a2\""
Jan 13 20:09:44.561150 systemd[1]: Started cri-containerd-607c9473fc51bf982a5e9615f58115973587ab76f7ba7ba793913a36f2b783a2.scope - libcontainer container 607c9473fc51bf982a5e9615f58115973587ab76f7ba7ba793913a36f2b783a2.
Jan 13 20:09:44.605156 containerd[1938]: time="2025-01-13T20:09:44.604992735Z" level=info msg="StartContainer for \"607c9473fc51bf982a5e9615f58115973587ab76f7ba7ba793913a36f2b783a2\" returns successfully"
Jan 13 20:09:44.626854 ntpd[1916]: Listen normally on 9 cni0 192.168.1.1:123
Jan 13 20:09:44.627863 ntpd[1916]: 13 Jan 20:09:44 ntpd[1916]: Listen normally on 9 cni0 192.168.1.1:123
Jan 13 20:09:44.627863 ntpd[1916]: 13 Jan 20:09:44 ntpd[1916]: Listen normally on 10 cni0 [fe80::180c:3aff:fe0b:5ff3%4]:123
Jan 13 20:09:44.627863 ntpd[1916]: 13 Jan 20:09:44 ntpd[1916]: Listen normally on 11 veth6b50c9a8 [fe80::8873:28ff:feec:3ea5%5]:123
Jan 13 20:09:44.627121 ntpd[1916]: Listen normally on 10 cni0 [fe80::180c:3aff:fe0b:5ff3%4]:123
Jan 13 20:09:44.627220 ntpd[1916]: Listen normally on 11 veth6b50c9a8 [fe80::8873:28ff:feec:3ea5%5]:123
Jan 13 20:09:44.668703 kubelet[2386]: E0113 20:09:44.668614    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:45.669743 kubelet[2386]: E0113 20:09:45.669672    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:46.670545 kubelet[2386]: E0113 20:09:46.670488    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:47.670888 kubelet[2386]: E0113 20:09:47.670803    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:48.671063 kubelet[2386]: E0113 20:09:48.671007    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:49.456328 kubelet[2386]: I0113 20:09:49.456230    2386 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-85f456d6dd-vrjqn" podStartSLOduration=21.970657613 podStartE2EDuration="26.456207439s" podCreationTimestamp="2025-01-13 20:09:23 +0000 UTC" firstStartedPulling="2025-01-13 20:09:40.005379968 +0000 UTC m=+30.247274923" lastFinishedPulling="2025-01-13 20:09:44.490929782 +0000 UTC m=+34.732824749" observedRunningTime="2025-01-13 20:09:44.936110825 +0000 UTC m=+35.178005816" watchObservedRunningTime="2025-01-13 20:09:49.456207439 +0000 UTC m=+39.698102418"
Jan 13 20:09:49.456559 kubelet[2386]: I0113 20:09:49.456422    2386 topology_manager.go:215] "Topology Admit Handler" podUID="45854a10-4c3b-4e0b-ad79-3724690e2bac" podNamespace="default" podName="nfs-server-provisioner-0"
Jan 13 20:09:49.466311 systemd[1]: Created slice kubepods-besteffort-pod45854a10_4c3b_4e0b_ad79_3724690e2bac.slice - libcontainer container kubepods-besteffort-pod45854a10_4c3b_4e0b_ad79_3724690e2bac.slice.
Jan 13 20:09:49.547086 kubelet[2386]: I0113 20:09:49.547036    2386 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-svbxz\" (UniqueName: \"kubernetes.io/projected/45854a10-4c3b-4e0b-ad79-3724690e2bac-kube-api-access-svbxz\") pod \"nfs-server-provisioner-0\" (UID: \"45854a10-4c3b-4e0b-ad79-3724690e2bac\") " pod="default/nfs-server-provisioner-0"
Jan 13 20:09:49.547086 kubelet[2386]: I0113 20:09:49.547101    2386 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/45854a10-4c3b-4e0b-ad79-3724690e2bac-data\") pod \"nfs-server-provisioner-0\" (UID: \"45854a10-4c3b-4e0b-ad79-3724690e2bac\") " pod="default/nfs-server-provisioner-0"
Jan 13 20:09:49.671680 kubelet[2386]: E0113 20:09:49.671622    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:49.772196 containerd[1938]: time="2025-01-13T20:09:49.772035117Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:45854a10-4c3b-4e0b-ad79-3724690e2bac,Namespace:default,Attempt:0,}"
Jan 13 20:09:49.818168 (udev-worker)[3492]: Network interface NamePolicy= disabled on kernel command line.
Jan 13 20:09:49.818685 systemd-networkd[1869]: veth65aab283: Link UP
Jan 13 20:09:49.822622 kernel: cni0: port 2(veth65aab283) entered blocking state
Jan 13 20:09:49.822691 kernel: cni0: port 2(veth65aab283) entered disabled state
Jan 13 20:09:49.823868 kernel: veth65aab283: entered allmulticast mode
Jan 13 20:09:49.825146 kernel: veth65aab283: entered promiscuous mode
Jan 13 20:09:49.838966 kernel: cni0: port 2(veth65aab283) entered blocking state
Jan 13 20:09:49.839106 kernel: cni0: port 2(veth65aab283) entered forwarding state
Jan 13 20:09:49.839587 systemd-networkd[1869]: veth65aab283: Gained carrier
Jan 13 20:09:49.842980 containerd[1938]: 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"}
Jan 13 20:09:49.842980 containerd[1938]: delegateAdd: netconf sent to delegate plugin:
Jan 13 20:09:49.874273 containerd[1938]: {"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":8951,"name":"cbr0","type":"bridge"}time="2025-01-13T20:09:49.874073145Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1
Jan 13 20:09:49.875023 containerd[1938]: time="2025-01-13T20:09:49.874239021Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1
Jan 13 20:09:49.876070 containerd[1938]: time="2025-01-13T20:09:49.875974677Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jan 13 20:09:49.876316 containerd[1938]: time="2025-01-13T20:09:49.876238509Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jan 13 20:09:49.911136 systemd[1]: Started cri-containerd-67da5d2163ff527276281218f6803d7a231918aff8420771b08d1b67b25a8882.scope - libcontainer container 67da5d2163ff527276281218f6803d7a231918aff8420771b08d1b67b25a8882.
Jan 13 20:09:49.968702 containerd[1938]: time="2025-01-13T20:09:49.968645950Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:45854a10-4c3b-4e0b-ad79-3724690e2bac,Namespace:default,Attempt:0,} returns sandbox id \"67da5d2163ff527276281218f6803d7a231918aff8420771b08d1b67b25a8882\""
Jan 13 20:09:49.971869 containerd[1938]: time="2025-01-13T20:09:49.971783194Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\""
Jan 13 20:09:50.672748 kubelet[2386]: E0113 20:09:50.672665    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:50.943458 systemd-networkd[1869]: veth65aab283: Gained IPv6LL
Jan 13 20:09:51.644629 kubelet[2386]: E0113 20:09:51.644519    2386 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:51.673047 kubelet[2386]: E0113 20:09:51.672966    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:52.433140 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1080320130.mount: Deactivated successfully.
Jan 13 20:09:52.673468 kubelet[2386]: E0113 20:09:52.673323    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:53.627443 ntpd[1916]: Listen normally on 12 veth65aab283 [fe80::c19:18ff:feaa:9f60%6]:123
Jan 13 20:09:53.628074 ntpd[1916]: 13 Jan 20:09:53 ntpd[1916]: Listen normally on 12 veth65aab283 [fe80::c19:18ff:feaa:9f60%6]:123
Jan 13 20:09:53.674683 kubelet[2386]: E0113 20:09:53.674575    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:54.675522 kubelet[2386]: E0113 20:09:54.675466    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:55.576563 containerd[1938]: time="2025-01-13T20:09:55.576249085Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 13 20:09:55.578188 containerd[1938]: time="2025-01-13T20:09:55.578119981Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373623"
Jan 13 20:09:55.578847 containerd[1938]: time="2025-01-13T20:09:55.578685229Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 13 20:09:55.584152 containerd[1938]: time="2025-01-13T20:09:55.584062489Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 13 20:09:55.586377 containerd[1938]: time="2025-01-13T20:09:55.586181809Z" 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 5.614311327s"
Jan 13 20:09:55.586377 containerd[1938]: time="2025-01-13T20:09:55.586237285Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\""
Jan 13 20:09:55.590775 containerd[1938]: time="2025-01-13T20:09:55.590704981Z" level=info msg="CreateContainer within sandbox \"67da5d2163ff527276281218f6803d7a231918aff8420771b08d1b67b25a8882\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}"
Jan 13 20:09:55.612629 containerd[1938]: time="2025-01-13T20:09:55.612558890Z" level=info msg="CreateContainer within sandbox \"67da5d2163ff527276281218f6803d7a231918aff8420771b08d1b67b25a8882\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"3bbbf0b8fe7809384b92ac632f52f0e02519b9a2c5b0cad842e437be21701ae5\""
Jan 13 20:09:55.613733 containerd[1938]: time="2025-01-13T20:09:55.613694246Z" level=info msg="StartContainer for \"3bbbf0b8fe7809384b92ac632f52f0e02519b9a2c5b0cad842e437be21701ae5\""
Jan 13 20:09:55.667137 systemd[1]: Started cri-containerd-3bbbf0b8fe7809384b92ac632f52f0e02519b9a2c5b0cad842e437be21701ae5.scope - libcontainer container 3bbbf0b8fe7809384b92ac632f52f0e02519b9a2c5b0cad842e437be21701ae5.
Jan 13 20:09:55.677166 kubelet[2386]: E0113 20:09:55.677091    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:55.710386 containerd[1938]: time="2025-01-13T20:09:55.710298830Z" level=info msg="StartContainer for \"3bbbf0b8fe7809384b92ac632f52f0e02519b9a2c5b0cad842e437be21701ae5\" returns successfully"
Jan 13 20:09:56.677751 kubelet[2386]: E0113 20:09:56.677694    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:57.678033 kubelet[2386]: E0113 20:09:57.677964    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:58.678424 kubelet[2386]: E0113 20:09:58.678357    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:09:59.678685 kubelet[2386]: E0113 20:09:59.678618    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:10:00.679709 kubelet[2386]: E0113 20:10:00.679642    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:10:01.679846 kubelet[2386]: E0113 20:10:01.679756    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:10:02.680312 kubelet[2386]: E0113 20:10:02.680241    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:10:03.680410 kubelet[2386]: E0113 20:10:03.680348    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:10:04.681033 kubelet[2386]: E0113 20:10:04.680975    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:10:05.501206 kubelet[2386]: I0113 20:10:05.501119    2386 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=10.884035808 podStartE2EDuration="16.501096503s" podCreationTimestamp="2025-01-13 20:09:49 +0000 UTC" firstStartedPulling="2025-01-13 20:09:49.971059342 +0000 UTC m=+40.212954309" lastFinishedPulling="2025-01-13 20:09:55.588120049 +0000 UTC m=+45.830015004" observedRunningTime="2025-01-13 20:09:55.991801191 +0000 UTC m=+46.233696182" watchObservedRunningTime="2025-01-13 20:10:05.501096503 +0000 UTC m=+55.742991482"
Jan 13 20:10:05.501629 kubelet[2386]: I0113 20:10:05.501581    2386 topology_manager.go:215] "Topology Admit Handler" podUID="f8e6d1c0-2a1d-42bc-8a1a-393571cce1e7" podNamespace="default" podName="test-pod-1"
Jan 13 20:10:05.514363 systemd[1]: Created slice kubepods-besteffort-podf8e6d1c0_2a1d_42bc_8a1a_393571cce1e7.slice - libcontainer container kubepods-besteffort-podf8e6d1c0_2a1d_42bc_8a1a_393571cce1e7.slice.
Jan 13 20:10:05.628977 kubelet[2386]: I0113 20:10:05.628901    2386 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-5df6788d-4d23-4935-981d-c38b2354f59e\" (UniqueName: \"kubernetes.io/nfs/f8e6d1c0-2a1d-42bc-8a1a-393571cce1e7-pvc-5df6788d-4d23-4935-981d-c38b2354f59e\") pod \"test-pod-1\" (UID: \"f8e6d1c0-2a1d-42bc-8a1a-393571cce1e7\") " pod="default/test-pod-1"
Jan 13 20:10:05.628977 kubelet[2386]: I0113 20:10:05.628971    2386 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-r9g7z\" (UniqueName: \"kubernetes.io/projected/f8e6d1c0-2a1d-42bc-8a1a-393571cce1e7-kube-api-access-r9g7z\") pod \"test-pod-1\" (UID: \"f8e6d1c0-2a1d-42bc-8a1a-393571cce1e7\") " pod="default/test-pod-1"
Jan 13 20:10:05.681512 kubelet[2386]: E0113 20:10:05.681457    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:10:05.764860 kernel: FS-Cache: Loaded
Jan 13 20:10:05.807676 kernel: RPC: Registered named UNIX socket transport module.
Jan 13 20:10:05.807804 kernel: RPC: Registered udp transport module.
Jan 13 20:10:05.807870 kernel: RPC: Registered tcp transport module.
Jan 13 20:10:05.807913 kernel: RPC: Registered tcp-with-tls transport module.
Jan 13 20:10:05.808783 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module.
Jan 13 20:10:06.110225 kernel: NFS: Registering the id_resolver key type
Jan 13 20:10:06.110352 kernel: Key type id_resolver registered
Jan 13 20:10:06.110407 kernel: Key type id_legacy registered
Jan 13 20:10:06.148256 nfsidmap[3769]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'us-west-2.compute.internal'
Jan 13 20:10:06.153991 nfsidmap[3770]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'us-west-2.compute.internal'
Jan 13 20:10:06.420915 containerd[1938]: time="2025-01-13T20:10:06.420744527Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:f8e6d1c0-2a1d-42bc-8a1a-393571cce1e7,Namespace:default,Attempt:0,}"
Jan 13 20:10:06.455378 systemd-networkd[1869]: veth5916c8d3: Link UP
Jan 13 20:10:06.455555 (udev-worker)[3761]: Network interface NamePolicy= disabled on kernel command line.
Jan 13 20:10:06.459627 kernel: cni0: port 3(veth5916c8d3) entered blocking state
Jan 13 20:10:06.459729 kernel: cni0: port 3(veth5916c8d3) entered disabled state
Jan 13 20:10:06.460794 kernel: veth5916c8d3: entered allmulticast mode
Jan 13 20:10:06.462162 kernel: veth5916c8d3: entered promiscuous mode
Jan 13 20:10:06.470955 kernel: cni0: port 3(veth5916c8d3) entered blocking state
Jan 13 20:10:06.471080 kernel: cni0: port 3(veth5916c8d3) entered forwarding state
Jan 13 20:10:06.475438 systemd-networkd[1869]: veth5916c8d3: Gained carrier
Jan 13 20:10:06.478358 containerd[1938]: 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)(0x40000948e8), "name":"cbr0", "type":"bridge"}
Jan 13 20:10:06.478358 containerd[1938]: delegateAdd: netconf sent to delegate plugin:
Jan 13 20:10:06.506980 containerd[1938]: {"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":8951,"name":"cbr0","type":"bridge"}time="2025-01-13T20:10:06.506516568Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1
Jan 13 20:10:06.506980 containerd[1938]: time="2025-01-13T20:10:06.506603700Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1
Jan 13 20:10:06.506980 containerd[1938]: time="2025-01-13T20:10:06.506628672Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jan 13 20:10:06.506980 containerd[1938]: time="2025-01-13T20:10:06.506753220Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jan 13 20:10:06.536138 systemd[1]: Started cri-containerd-481c76413f245f2953b19eef9c7c1bfafc904418ade97dde058f8aefc1670805.scope - libcontainer container 481c76413f245f2953b19eef9c7c1bfafc904418ade97dde058f8aefc1670805.
Jan 13 20:10:06.593743 containerd[1938]: time="2025-01-13T20:10:06.593656428Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:f8e6d1c0-2a1d-42bc-8a1a-393571cce1e7,Namespace:default,Attempt:0,} returns sandbox id \"481c76413f245f2953b19eef9c7c1bfafc904418ade97dde058f8aefc1670805\""
Jan 13 20:10:06.596370 containerd[1938]: time="2025-01-13T20:10:06.596024952Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\""
Jan 13 20:10:06.682700 kubelet[2386]: E0113 20:10:06.682502    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:10:07.045131 containerd[1938]: time="2025-01-13T20:10:07.045053890Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 13 20:10:07.046130 containerd[1938]: time="2025-01-13T20:10:07.046056022Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61"
Jan 13 20:10:07.052337 containerd[1938]: time="2025-01-13T20:10:07.052185634Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:a86cd5b7fd4c45b8b60dbcc26c955515e3a36347f806d2b7092c4908f54e0a55\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:eca1d1ff18c7af45f86b7e0b572090f563a676ddca3da2ecff678390366335ad\", size \"67696923\" in 456.022022ms"
Jan 13 20:10:07.052337 containerd[1938]: time="2025-01-13T20:10:07.052239886Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:a86cd5b7fd4c45b8b60dbcc26c955515e3a36347f806d2b7092c4908f54e0a55\""
Jan 13 20:10:07.056100 containerd[1938]: time="2025-01-13T20:10:07.055923634Z" level=info msg="CreateContainer within sandbox \"481c76413f245f2953b19eef9c7c1bfafc904418ade97dde058f8aefc1670805\" for container &ContainerMetadata{Name:test,Attempt:0,}"
Jan 13 20:10:07.078722 containerd[1938]: time="2025-01-13T20:10:07.078637907Z" level=info msg="CreateContainer within sandbox \"481c76413f245f2953b19eef9c7c1bfafc904418ade97dde058f8aefc1670805\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"b7c5879d0f5292a2e00677f4744caa29336d92e2df25c1c91d5cb6c8e49da4b4\""
Jan 13 20:10:07.079945 containerd[1938]: time="2025-01-13T20:10:07.079893599Z" level=info msg="StartContainer for \"b7c5879d0f5292a2e00677f4744caa29336d92e2df25c1c91d5cb6c8e49da4b4\""
Jan 13 20:10:07.135113 systemd[1]: Started cri-containerd-b7c5879d0f5292a2e00677f4744caa29336d92e2df25c1c91d5cb6c8e49da4b4.scope - libcontainer container b7c5879d0f5292a2e00677f4744caa29336d92e2df25c1c91d5cb6c8e49da4b4.
Jan 13 20:10:07.181570 containerd[1938]: time="2025-01-13T20:10:07.181509179Z" level=info msg="StartContainer for \"b7c5879d0f5292a2e00677f4744caa29336d92e2df25c1c91d5cb6c8e49da4b4\" returns successfully"
Jan 13 20:10:07.683411 kubelet[2386]: E0113 20:10:07.683351    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:10:07.746025 systemd[1]: run-containerd-runc-k8s.io-b7c5879d0f5292a2e00677f4744caa29336d92e2df25c1c91d5cb6c8e49da4b4-runc.GSX92B.mount: Deactivated successfully.
Jan 13 20:10:08.021409 kubelet[2386]: I0113 20:10:08.021259    2386 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=17.563385781 podStartE2EDuration="18.021238523s" podCreationTimestamp="2025-01-13 20:09:50 +0000 UTC" firstStartedPulling="2025-01-13 20:10:06.595559628 +0000 UTC m=+56.837454595" lastFinishedPulling="2025-01-13 20:10:07.05341237 +0000 UTC m=+57.295307337" observedRunningTime="2025-01-13 20:10:08.021171179 +0000 UTC m=+58.263066170" watchObservedRunningTime="2025-01-13 20:10:08.021238523 +0000 UTC m=+58.263133490"
Jan 13 20:10:08.095053 systemd-networkd[1869]: veth5916c8d3: Gained IPv6LL
Jan 13 20:10:08.683590 kubelet[2386]: E0113 20:10:08.683509    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:10:09.684410 kubelet[2386]: E0113 20:10:09.684344    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:10:10.626998 ntpd[1916]: Listen normally on 13 veth5916c8d3 [fe80::cca2:85ff:fec2:291a%7]:123
Jan 13 20:10:10.627608 ntpd[1916]: 13 Jan 20:10:10 ntpd[1916]: Listen normally on 13 veth5916c8d3 [fe80::cca2:85ff:fec2:291a%7]:123
Jan 13 20:10:10.685054 kubelet[2386]: E0113 20:10:10.684982    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:10:11.643688 kubelet[2386]: E0113 20:10:11.643628    2386 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:10:11.685743 kubelet[2386]: E0113 20:10:11.685692    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:10:12.685845 kubelet[2386]: E0113 20:10:12.685773    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:10:13.686234 kubelet[2386]: E0113 20:10:13.686141    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:10:14.686648 kubelet[2386]: E0113 20:10:14.686574    2386 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"