Jan 30 13:58:33.169495 kernel: Linux version 6.6.74-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 13.3.1_p20240614 p17) 13.3.1 20240614, GNU ld (Gentoo 2.42 p3) 2.42.0) #1 SMP PREEMPT_DYNAMIC Wed Jan 29 10:09:32 -00 2025
Jan 30 13:58:33.169536 kernel: Command line: BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200n8 flatcar.first_boot=detected flatcar.oem.id=ec2 modprobe.blacklist=xen_fbfront net.ifnames=0 nvme_core.io_timeout=4294967295 verity.usrhash=befc9792b021bef43c896e00e1d5172b6224dbafc9b6c92b267e5e544378e681
Jan 30 13:58:33.169549 kernel: BIOS-provided physical RAM map:
Jan 30 13:58:33.169559 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable
Jan 30 13:58:33.169569 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved
Jan 30 13:58:33.169578 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
Jan 30 13:58:33.169592 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000007d9e9fff] usable
Jan 30 13:58:33.169602 kernel: BIOS-e820: [mem 0x000000007d9ea000-0x000000007fffffff] reserved
Jan 30 13:58:33.169612 kernel: BIOS-e820: [mem 0x00000000e0000000-0x00000000e03fffff] reserved
Jan 30 13:58:33.169622 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
Jan 30 13:58:33.169632 kernel: NX (Execute Disable) protection: active
Jan 30 13:58:33.169642 kernel: APIC: Static calls initialized
Jan 30 13:58:33.169652 kernel: SMBIOS 2.7 present.
Jan 30 13:58:33.169662 kernel: DMI: Amazon EC2 t3.small/, BIOS 1.0 10/16/2017
Jan 30 13:58:33.169677 kernel: Hypervisor detected: KVM
Jan 30 13:58:33.169688 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00
Jan 30 13:58:33.169699 kernel: kvm-clock: using sched offset of 6819848828 cycles
Jan 30 13:58:33.169712 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
Jan 30 13:58:33.169723 kernel: tsc: Detected 2499.998 MHz processor
Jan 30 13:58:33.169734 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
Jan 30 13:58:33.169745 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable
Jan 30 13:58:33.169759 kernel: last_pfn = 0x7d9ea max_arch_pfn = 0x400000000
Jan 30 13:58:33.169771 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs
Jan 30 13:58:33.169782 kernel: x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT  
Jan 30 13:58:33.169792 kernel: Using GB pages for direct mapping
Jan 30 13:58:33.169804 kernel: ACPI: Early table checksum verification disabled
Jan 30 13:58:33.169815 kernel: ACPI: RSDP 0x00000000000F8F40 000014 (v00 AMAZON)
Jan 30 13:58:33.169826 kernel: ACPI: RSDT 0x000000007D9EE350 000044 (v01 AMAZON AMZNRSDT 00000001 AMZN 00000001)
Jan 30 13:58:33.169838 kernel: ACPI: FACP 0x000000007D9EFF80 000074 (v01 AMAZON AMZNFACP 00000001 AMZN 00000001)
Jan 30 13:58:33.169849 kernel: ACPI: DSDT 0x000000007D9EE3A0 0010E9 (v01 AMAZON AMZNDSDT 00000001 AMZN 00000001)
Jan 30 13:58:33.169863 kernel: ACPI: FACS 0x000000007D9EFF40 000040
Jan 30 13:58:33.169874 kernel: ACPI: SSDT 0x000000007D9EF6C0 00087A (v01 AMAZON AMZNSSDT 00000001 AMZN 00000001)
Jan 30 13:58:33.169886 kernel: ACPI: APIC 0x000000007D9EF5D0 000076 (v01 AMAZON AMZNAPIC 00000001 AMZN 00000001)
Jan 30 13:58:33.169897 kernel: ACPI: SRAT 0x000000007D9EF530 0000A0 (v01 AMAZON AMZNSRAT 00000001 AMZN 00000001)
Jan 30 13:58:33.169907 kernel: ACPI: SLIT 0x000000007D9EF4C0 00006C (v01 AMAZON AMZNSLIT 00000001 AMZN 00000001)
Jan 30 13:58:33.169919 kernel: ACPI: WAET 0x000000007D9EF490 000028 (v01 AMAZON AMZNWAET 00000001 AMZN 00000001)
Jan 30 13:58:33.169930 kernel: ACPI: HPET 0x00000000000C9000 000038 (v01 AMAZON AMZNHPET 00000001 AMZN 00000001)
Jan 30 13:58:33.169941 kernel: ACPI: SSDT 0x00000000000C9040 00007B (v01 AMAZON AMZNSSDT 00000001 AMZN 00000001)
Jan 30 13:58:33.169952 kernel: ACPI: Reserving FACP table memory at [mem 0x7d9eff80-0x7d9efff3]
Jan 30 13:58:33.169966 kernel: ACPI: Reserving DSDT table memory at [mem 0x7d9ee3a0-0x7d9ef488]
Jan 30 13:58:33.169982 kernel: ACPI: Reserving FACS table memory at [mem 0x7d9eff40-0x7d9eff7f]
Jan 30 13:58:33.169995 kernel: ACPI: Reserving SSDT table memory at [mem 0x7d9ef6c0-0x7d9eff39]
Jan 30 13:58:33.170008 kernel: ACPI: Reserving APIC table memory at [mem 0x7d9ef5d0-0x7d9ef645]
Jan 30 13:58:33.170022 kernel: ACPI: Reserving SRAT table memory at [mem 0x7d9ef530-0x7d9ef5cf]
Jan 30 13:58:33.170102 kernel: ACPI: Reserving SLIT table memory at [mem 0x7d9ef4c0-0x7d9ef52b]
Jan 30 13:58:33.170118 kernel: ACPI: Reserving WAET table memory at [mem 0x7d9ef490-0x7d9ef4b7]
Jan 30 13:58:33.170133 kernel: ACPI: Reserving HPET table memory at [mem 0xc9000-0xc9037]
Jan 30 13:58:33.170148 kernel: ACPI: Reserving SSDT table memory at [mem 0xc9040-0xc90ba]
Jan 30 13:58:33.170162 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0
Jan 30 13:58:33.170176 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0
Jan 30 13:58:33.170191 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x7fffffff]
Jan 30 13:58:33.170206 kernel: NUMA: Initialized distance table, cnt=1
Jan 30 13:58:33.170220 kernel: NODE_DATA(0) allocated [mem 0x7d9e3000-0x7d9e8fff]
Jan 30 13:58:33.170239 kernel: Zone ranges:
Jan 30 13:58:33.170254 kernel:   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
Jan 30 13:58:33.172000 kernel:   DMA32    [mem 0x0000000001000000-0x000000007d9e9fff]
Jan 30 13:58:33.172025 kernel:   Normal   empty
Jan 30 13:58:33.172042 kernel: Movable zone start for each node
Jan 30 13:58:33.172058 kernel: Early memory node ranges
Jan 30 13:58:33.172073 kernel:   node   0: [mem 0x0000000000001000-0x000000000009efff]
Jan 30 13:58:33.172085 kernel:   node   0: [mem 0x0000000000100000-0x000000007d9e9fff]
Jan 30 13:58:33.172099 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000007d9e9fff]
Jan 30 13:58:33.172113 kernel: On node 0, zone DMA: 1 pages in unavailable ranges
Jan 30 13:58:33.172131 kernel: On node 0, zone DMA: 97 pages in unavailable ranges
Jan 30 13:58:33.172145 kernel: On node 0, zone DMA32: 9750 pages in unavailable ranges
Jan 30 13:58:33.172157 kernel: ACPI: PM-Timer IO Port: 0xb008
Jan 30 13:58:33.172173 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
Jan 30 13:58:33.172192 kernel: IOAPIC[0]: apic_id 0, version 32, address 0xfec00000, GSI 0-23
Jan 30 13:58:33.172210 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
Jan 30 13:58:33.172229 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
Jan 30 13:58:33.172246 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
Jan 30 13:58:33.172539 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
Jan 30 13:58:33.172569 kernel: ACPI: Using ACPI (MADT) for SMP configuration information
Jan 30 13:58:33.172585 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000
Jan 30 13:58:33.172598 kernel: TSC deadline timer available
Jan 30 13:58:33.172610 kernel: smpboot: Allowing 2 CPUs, 0 hotplug CPUs
Jan 30 13:58:33.172622 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()
Jan 30 13:58:33.172634 kernel: [mem 0x80000000-0xdfffffff] available for PCI devices
Jan 30 13:58:33.172646 kernel: Booting paravirtualized kernel on KVM
Jan 30 13:58:33.172659 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
Jan 30 13:58:33.172671 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1
Jan 30 13:58:33.172686 kernel: percpu: Embedded 58 pages/cpu s197032 r8192 d32344 u1048576
Jan 30 13:58:33.172698 kernel: pcpu-alloc: s197032 r8192 d32344 u1048576 alloc=1*2097152
Jan 30 13:58:33.172710 kernel: pcpu-alloc: [0] 0 1 
Jan 30 13:58:33.172722 kernel: kvm-guest: PV spinlocks enabled
Jan 30 13:58:33.172734 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear)
Jan 30 13:58:33.172748 kernel: Kernel command line: rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200n8 flatcar.first_boot=detected flatcar.oem.id=ec2 modprobe.blacklist=xen_fbfront net.ifnames=0 nvme_core.io_timeout=4294967295 verity.usrhash=befc9792b021bef43c896e00e1d5172b6224dbafc9b6c92b267e5e544378e681
Jan 30 13:58:33.172761 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space.
Jan 30 13:58:33.172773 kernel: random: crng init done
Jan 30 13:58:33.172788 kernel: Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)
Jan 30 13:58:33.172800 kernel: Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
Jan 30 13:58:33.172812 kernel: Fallback order for Node 0: 0 
Jan 30 13:58:33.172832 kernel: Built 1 zonelists, mobility grouping on.  Total pages: 506242
Jan 30 13:58:33.172845 kernel: Policy zone: DMA32
Jan 30 13:58:33.172857 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off
Jan 30 13:58:33.172870 kernel: Memory: 1932348K/2057760K available (12288K kernel code, 2301K rwdata, 22728K rodata, 42844K init, 2348K bss, 125152K reserved, 0K cma-reserved)
Jan 30 13:58:33.172881 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
Jan 30 13:58:33.172893 kernel: Kernel/User page tables isolation: enabled
Jan 30 13:58:33.172909 kernel: ftrace: allocating 37921 entries in 149 pages
Jan 30 13:58:33.172920 kernel: ftrace: allocated 149 pages with 4 groups
Jan 30 13:58:33.172932 kernel: Dynamic Preempt: voluntary
Jan 30 13:58:33.172944 kernel: rcu: Preemptible hierarchical RCU implementation.
Jan 30 13:58:33.172958 kernel: rcu:         RCU event tracing is enabled.
Jan 30 13:58:33.172971 kernel: rcu:         RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2.
Jan 30 13:58:33.172983 kernel:         Trampoline variant of Tasks RCU enabled.
Jan 30 13:58:33.172996 kernel:         Rude variant of Tasks RCU enabled.
Jan 30 13:58:33.173008 kernel:         Tracing variant of Tasks RCU enabled.
Jan 30 13:58:33.173022 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.
Jan 30 13:58:33.173041 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
Jan 30 13:58:33.173053 kernel: NR_IRQS: 33024, nr_irqs: 440, preallocated irqs: 16
Jan 30 13:58:33.173065 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention.
Jan 30 13:58:33.173078 kernel: Console: colour VGA+ 80x25
Jan 30 13:58:33.173090 kernel: printk: console [ttyS0] enabled
Jan 30 13:58:33.173102 kernel: ACPI: Core revision 20230628
Jan 30 13:58:33.173115 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 30580167144 ns
Jan 30 13:58:33.173127 kernel: APIC: Switch to symmetric I/O mode setup
Jan 30 13:58:33.173143 kernel: x2apic enabled
Jan 30 13:58:33.173155 kernel: APIC: Switched APIC routing to: physical x2apic
Jan 30 13:58:33.173178 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x240937b9988, max_idle_ns: 440795218083 ns
Jan 30 13:58:33.173193 kernel: Calibrating delay loop (skipped) preset value.. 4999.99 BogoMIPS (lpj=2499998)
Jan 30 13:58:33.173205 kernel: Last level iTLB entries: 4KB 64, 2MB 8, 4MB 8
Jan 30 13:58:33.173218 kernel: Last level dTLB entries: 4KB 64, 2MB 0, 4MB 0, 1GB 4
Jan 30 13:58:33.173230 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization
Jan 30 13:58:33.173242 kernel: Spectre V2 : Mitigation: Retpolines
Jan 30 13:58:33.173278 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
Jan 30 13:58:33.173290 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT
Jan 30 13:58:33.173303 kernel: RETBleed: WARNING: Spectre v2 mitigation leaves CPU vulnerable to RETBleed attacks, data leaks possible!
Jan 30 13:58:33.173316 kernel: RETBleed: Vulnerable
Jan 30 13:58:33.173328 kernel: Speculative Store Bypass: Vulnerable
Jan 30 13:58:33.173344 kernel: MDS: Vulnerable: Clear CPU buffers attempted, no microcode
Jan 30 13:58:33.173356 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode
Jan 30 13:58:33.173368 kernel: GDS: Unknown: Dependent on hypervisor status
Jan 30 13:58:33.173380 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
Jan 30 13:58:33.173393 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
Jan 30 13:58:33.173405 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
Jan 30 13:58:33.173493 kernel: x86/fpu: Supporting XSAVE feature 0x008: 'MPX bounds registers'
Jan 30 13:58:33.173508 kernel: x86/fpu: Supporting XSAVE feature 0x010: 'MPX CSR'
Jan 30 13:58:33.173520 kernel: x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'
Jan 30 13:58:33.173533 kernel: x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'
Jan 30 13:58:33.173545 kernel: x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'
Jan 30 13:58:33.173557 kernel: x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'
Jan 30 13:58:33.173570 kernel: x86/fpu: xstate_offset[2]:  576, xstate_sizes[2]:  256
Jan 30 13:58:33.173589 kernel: x86/fpu: xstate_offset[3]:  832, xstate_sizes[3]:   64
Jan 30 13:58:33.173602 kernel: x86/fpu: xstate_offset[4]:  896, xstate_sizes[4]:   64
Jan 30 13:58:33.173614 kernel: x86/fpu: xstate_offset[5]:  960, xstate_sizes[5]:   64
Jan 30 13:58:33.173626 kernel: x86/fpu: xstate_offset[6]: 1024, xstate_sizes[6]:  512
Jan 30 13:58:33.173641 kernel: x86/fpu: xstate_offset[7]: 1536, xstate_sizes[7]: 1024
Jan 30 13:58:33.173653 kernel: x86/fpu: xstate_offset[9]: 2560, xstate_sizes[9]:    8
Jan 30 13:58:33.173666 kernel: x86/fpu: Enabled xstate features 0x2ff, context size is 2568 bytes, using 'compacted' format.
Jan 30 13:58:33.173678 kernel: Freeing SMP alternatives memory: 32K
Jan 30 13:58:33.173690 kernel: pid_max: default: 32768 minimum: 301
Jan 30 13:58:33.173702 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity
Jan 30 13:58:33.173715 kernel: landlock: Up and running.
Jan 30 13:58:33.173727 kernel: SELinux:  Initializing.
Jan 30 13:58:33.173739 kernel: Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear)
Jan 30 13:58:33.173751 kernel: Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear)
Jan 30 13:58:33.173764 kernel: smpboot: CPU0: Intel(R) Xeon(R) Platinum 8259CL CPU @ 2.50GHz (family: 0x6, model: 0x55, stepping: 0x7)
Jan 30 13:58:33.173779 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2.
Jan 30 13:58:33.173792 kernel: RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2.
Jan 30 13:58:33.173811 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2.
Jan 30 13:58:33.173824 kernel: Performance Events: unsupported p6 CPU model 85 no PMU driver, software events only.
Jan 30 13:58:33.173837 kernel: signal: max sigframe size: 3632
Jan 30 13:58:33.173850 kernel: rcu: Hierarchical SRCU implementation.
Jan 30 13:58:33.173863 kernel: rcu:         Max phase no-delay instances is 400.
Jan 30 13:58:33.173875 kernel: NMI watchdog: Perf NMI watchdog permanently disabled
Jan 30 13:58:33.173887 kernel: smp: Bringing up secondary CPUs ...
Jan 30 13:58:33.173902 kernel: smpboot: x86: Booting SMP configuration:
Jan 30 13:58:33.173915 kernel: .... node  #0, CPUs:      #1
Jan 30 13:58:33.173928 kernel: MDS CPU bug present and SMT on, data leak possible. See https://www.kernel.org/doc/html/latest/admin-guide/hw-vuln/mds.html for more details.
Jan 30 13:58:33.173941 kernel: MMIO Stale Data CPU bug present and SMT on, data leak possible. See https://www.kernel.org/doc/html/latest/admin-guide/hw-vuln/processor_mmio_stale_data.html for more details.
Jan 30 13:58:33.173953 kernel: smp: Brought up 1 node, 2 CPUs
Jan 30 13:58:33.173966 kernel: smpboot: Max logical packages: 1
Jan 30 13:58:33.173978 kernel: smpboot: Total of 2 processors activated (9999.99 BogoMIPS)
Jan 30 13:58:33.173990 kernel: devtmpfs: initialized
Jan 30 13:58:33.174002 kernel: x86/mm: Memory block size: 128MB
Jan 30 13:58:33.174249 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
Jan 30 13:58:33.174284 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear)
Jan 30 13:58:33.174300 kernel: pinctrl core: initialized pinctrl subsystem
Jan 30 13:58:33.174316 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family
Jan 30 13:58:33.174331 kernel: audit: initializing netlink subsys (disabled)
Jan 30 13:58:33.174347 kernel: audit: type=2000 audit(1738245511.641:1): state=initialized audit_enabled=0 res=1
Jan 30 13:58:33.174362 kernel: thermal_sys: Registered thermal governor 'step_wise'
Jan 30 13:58:33.174377 kernel: thermal_sys: Registered thermal governor 'user_space'
Jan 30 13:58:33.174398 kernel: cpuidle: using governor menu
Jan 30 13:58:33.174414 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
Jan 30 13:58:33.174429 kernel: dca service started, version 1.12.1
Jan 30 13:58:33.174445 kernel: PCI: Using configuration type 1 for base access
Jan 30 13:58:33.174461 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.
Jan 30 13:58:33.174476 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages
Jan 30 13:58:33.174492 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page
Jan 30 13:58:33.174507 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages
Jan 30 13:58:33.174523 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page
Jan 30 13:58:33.174541 kernel: ACPI: Added _OSI(Module Device)
Jan 30 13:58:33.174556 kernel: ACPI: Added _OSI(Processor Device)
Jan 30 13:58:33.174572 kernel: ACPI: Added _OSI(3.0 _SCP Extensions)
Jan 30 13:58:33.174587 kernel: ACPI: Added _OSI(Processor Aggregator Device)
Jan 30 13:58:33.174602 kernel: ACPI: 3 ACPI AML tables successfully acquired and loaded
Jan 30 13:58:33.174618 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC
Jan 30 13:58:33.174633 kernel: ACPI: Interpreter enabled
Jan 30 13:58:33.174648 kernel: ACPI: PM: (supports S0 S5)
Jan 30 13:58:33.174663 kernel: ACPI: Using IOAPIC for interrupt routing
Jan 30 13:58:33.174679 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
Jan 30 13:58:33.174697 kernel: PCI: Using E820 reservations for host bridge windows
Jan 30 13:58:33.174713 kernel: ACPI: Enabled 16 GPEs in block 00 to 0F
Jan 30 13:58:33.174728 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
Jan 30 13:58:33.174957 kernel: acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3]
Jan 30 13:58:33.175125 kernel: acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI]
Jan 30 13:58:33.175460 kernel: acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended configuration space under this bridge
Jan 30 13:58:33.175484 kernel: acpiphp: Slot [3] registered
Jan 30 13:58:33.175506 kernel: acpiphp: Slot [4] registered
Jan 30 13:58:33.175522 kernel: acpiphp: Slot [5] registered
Jan 30 13:58:33.175538 kernel: acpiphp: Slot [6] registered
Jan 30 13:58:33.175554 kernel: acpiphp: Slot [7] registered
Jan 30 13:58:33.175570 kernel: acpiphp: Slot [8] registered
Jan 30 13:58:33.175586 kernel: acpiphp: Slot [9] registered
Jan 30 13:58:33.175602 kernel: acpiphp: Slot [10] registered
Jan 30 13:58:33.175618 kernel: acpiphp: Slot [11] registered
Jan 30 13:58:33.175634 kernel: acpiphp: Slot [12] registered
Jan 30 13:58:33.175653 kernel: acpiphp: Slot [13] registered
Jan 30 13:58:33.175669 kernel: acpiphp: Slot [14] registered
Jan 30 13:58:33.175685 kernel: acpiphp: Slot [15] registered
Jan 30 13:58:33.175701 kernel: acpiphp: Slot [16] registered
Jan 30 13:58:33.175717 kernel: acpiphp: Slot [17] registered
Jan 30 13:58:33.175733 kernel: acpiphp: Slot [18] registered
Jan 30 13:58:33.175749 kernel: acpiphp: Slot [19] registered
Jan 30 13:58:33.175765 kernel: acpiphp: Slot [20] registered
Jan 30 13:58:33.175781 kernel: acpiphp: Slot [21] registered
Jan 30 13:58:33.175797 kernel: acpiphp: Slot [22] registered
Jan 30 13:58:33.175816 kernel: acpiphp: Slot [23] registered
Jan 30 13:58:33.175831 kernel: acpiphp: Slot [24] registered
Jan 30 13:58:33.175847 kernel: acpiphp: Slot [25] registered
Jan 30 13:58:33.175863 kernel: acpiphp: Slot [26] registered
Jan 30 13:58:33.175878 kernel: acpiphp: Slot [27] registered
Jan 30 13:58:33.175977 kernel: acpiphp: Slot [28] registered
Jan 30 13:58:33.175996 kernel: acpiphp: Slot [29] registered
Jan 30 13:58:33.176014 kernel: acpiphp: Slot [30] registered
Jan 30 13:58:33.176030 kernel: acpiphp: Slot [31] registered
Jan 30 13:58:33.176050 kernel: PCI host bridge to bus 0000:00
Jan 30 13:58:33.176207 kernel: pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
Jan 30 13:58:33.176368 kernel: pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
Jan 30 13:58:33.176489 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
Jan 30 13:58:33.176607 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xfebfffff window]
Jan 30 13:58:33.176984 kernel: pci_bus 0000:00: root bus resource [bus 00-ff]
Jan 30 13:58:33.181056 kernel: pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
Jan 30 13:58:33.181276 kernel: pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
Jan 30 13:58:33.181998 kernel: pci 0000:00:01.3: [8086:7113] type 00 class 0x000000
Jan 30 13:58:33.182132 kernel: pci 0000:00:01.3: quirk: [io  0xb000-0xb03f] claimed by PIIX4 ACPI
Jan 30 13:58:33.182256 kernel: pci 0000:00:01.3: quirk: [io  0xb100-0xb10f] claimed by PIIX4 SMB
Jan 30 13:58:33.186785 kernel: pci 0000:00:01.3: PIIX4 devres E PIO at fff0-ffff
Jan 30 13:58:33.189228 kernel: pci 0000:00:01.3: PIIX4 devres F MMIO at ffc00000-ffffffff
Jan 30 13:58:33.190341 kernel: pci 0000:00:01.3: PIIX4 devres G PIO at fff0-ffff
Jan 30 13:58:33.190504 kernel: pci 0000:00:01.3: PIIX4 devres H MMIO at ffc00000-ffffffff
Jan 30 13:58:33.190858 kernel: pci 0000:00:01.3: PIIX4 devres I PIO at fff0-ffff
Jan 30 13:58:33.192005 kernel: pci 0000:00:01.3: PIIX4 devres J PIO at fff0-ffff
Jan 30 13:58:33.192154 kernel: pci 0000:00:01.3: quirk_piix4_acpi+0x0/0x180 took 17578 usecs
Jan 30 13:58:33.193415 kernel: pci 0000:00:03.0: [1d0f:1111] type 00 class 0x030000
Jan 30 13:58:33.193575 kernel: pci 0000:00:03.0: reg 0x10: [mem 0xfe400000-0xfe7fffff pref]
Jan 30 13:58:33.193958 kernel: pci 0000:00:03.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref]
Jan 30 13:58:33.196409 kernel: pci 0000:00:03.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
Jan 30 13:58:33.198191 kernel: pci 0000:00:04.0: [1d0f:8061] type 00 class 0x010802
Jan 30 13:58:33.198432 kernel: pci 0000:00:04.0: reg 0x10: [mem 0xfebf0000-0xfebf3fff]
Jan 30 13:58:33.198609 kernel: pci 0000:00:05.0: [1d0f:ec20] type 00 class 0x020000
Jan 30 13:58:33.198753 kernel: pci 0000:00:05.0: reg 0x10: [mem 0xfebf4000-0xfebf7fff]
Jan 30 13:58:33.198774 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10
Jan 30 13:58:33.198797 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10
Jan 30 13:58:33.198814 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11
Jan 30 13:58:33.198830 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11
Jan 30 13:58:33.199907 kernel: ACPI: PCI: Interrupt link LNKS configured for IRQ 9
Jan 30 13:58:33.199926 kernel: iommu: Default domain type: Translated
Jan 30 13:58:33.199943 kernel: iommu: DMA domain TLB invalidation policy: lazy mode
Jan 30 13:58:33.199960 kernel: PCI: Using ACPI for IRQ routing
Jan 30 13:58:33.199978 kernel: PCI: pci_cache_line_size set to 64 bytes
Jan 30 13:58:33.199996 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff]
Jan 30 13:58:33.204417 kernel: e820: reserve RAM buffer [mem 0x7d9ea000-0x7fffffff]
Jan 30 13:58:33.206130 kernel: pci 0000:00:03.0: vgaarb: setting as boot VGA device
Jan 30 13:58:33.210491 kernel: pci 0000:00:03.0: vgaarb: bridge control possible
Jan 30 13:58:33.210659 kernel: pci 0000:00:03.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
Jan 30 13:58:33.210679 kernel: vgaarb: loaded
Jan 30 13:58:33.210694 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0, 0, 0, 0, 0, 0
Jan 30 13:58:33.210708 kernel: hpet0: 8 comparators, 32-bit 62.500000 MHz counter
Jan 30 13:58:33.210723 kernel: clocksource: Switched to clocksource kvm-clock
Jan 30 13:58:33.210739 kernel: VFS: Disk quotas dquot_6.6.0
Jan 30 13:58:33.210761 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
Jan 30 13:58:33.210775 kernel: pnp: PnP ACPI init
Jan 30 13:58:33.210789 kernel: pnp: PnP ACPI: found 5 devices
Jan 30 13:58:33.210804 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
Jan 30 13:58:33.210820 kernel: NET: Registered PF_INET protocol family
Jan 30 13:58:33.210836 kernel: IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear)
Jan 30 13:58:33.210850 kernel: tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear)
Jan 30 13:58:33.210865 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
Jan 30 13:58:33.210880 kernel: TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear)
Jan 30 13:58:33.210899 kernel: TCP bind hash table entries: 16384 (order: 7, 524288 bytes, linear)
Jan 30 13:58:33.210913 kernel: TCP: Hash tables configured (established 16384 bind 16384)
Jan 30 13:58:33.210928 kernel: UDP hash table entries: 1024 (order: 3, 32768 bytes, linear)
Jan 30 13:58:33.210942 kernel: UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes, linear)
Jan 30 13:58:33.210957 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family
Jan 30 13:58:33.210971 kernel: NET: Registered PF_XDP protocol family
Jan 30 13:58:33.211148 kernel: pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
Jan 30 13:58:33.211309 kernel: pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
Jan 30 13:58:33.211435 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
Jan 30 13:58:33.211548 kernel: pci_bus 0000:00: resource 7 [mem 0x80000000-0xfebfffff window]
Jan 30 13:58:33.211685 kernel: pci 0000:00:00.0: Limiting direct PCI/PCI transfers
Jan 30 13:58:33.211704 kernel: PCI: CLS 0 bytes, default 64
Jan 30 13:58:33.211720 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer
Jan 30 13:58:33.211735 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x240937b9988, max_idle_ns: 440795218083 ns
Jan 30 13:58:33.211750 kernel: clocksource: Switched to clocksource tsc
Jan 30 13:58:33.211763 kernel: Initialise system trusted keyrings
Jan 30 13:58:33.211782 kernel: workingset: timestamp_bits=39 max_order=19 bucket_order=0
Jan 30 13:58:33.211797 kernel: Key type asymmetric registered
Jan 30 13:58:33.211812 kernel: Asymmetric key parser 'x509' registered
Jan 30 13:58:33.211826 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
Jan 30 13:58:33.211846 kernel: io scheduler mq-deadline registered
Jan 30 13:58:33.211874 kernel: io scheduler kyber registered
Jan 30 13:58:33.211889 kernel: io scheduler bfq registered
Jan 30 13:58:33.211903 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00
Jan 30 13:58:33.211914 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
Jan 30 13:58:33.211934 kernel: 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
Jan 30 13:58:33.211950 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
Jan 30 13:58:33.211964 kernel: i8042: Warning: Keylock active
Jan 30 13:58:33.211981 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1
Jan 30 13:58:33.211997 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12
Jan 30 13:58:33.212164 kernel: rtc_cmos 00:00: RTC can wake from S4
Jan 30 13:58:33.212452 kernel: rtc_cmos 00:00: registered as rtc0
Jan 30 13:58:33.212588 kernel: rtc_cmos 00:00: setting system clock to 2025-01-30T13:58:32 UTC (1738245512)
Jan 30 13:58:33.212729 kernel: rtc_cmos 00:00: alarms up to one day, 114 bytes nvram
Jan 30 13:58:33.212750 kernel: intel_pstate: CPU model not supported
Jan 30 13:58:33.212769 kernel: NET: Registered PF_INET6 protocol family
Jan 30 13:58:33.212786 kernel: Segment Routing with IPv6
Jan 30 13:58:33.212802 kernel: In-situ OAM (IOAM) with IPv6
Jan 30 13:58:33.212818 kernel: NET: Registered PF_PACKET protocol family
Jan 30 13:58:33.212835 kernel: Key type dns_resolver registered
Jan 30 13:58:33.212851 kernel: IPI shorthand broadcast: enabled
Jan 30 13:58:33.212867 kernel: sched_clock: Marking stable (724002026, 472998981)->(1307166719, -110165712)
Jan 30 13:58:33.212887 kernel: registered taskstats version 1
Jan 30 13:58:33.212904 kernel: Loading compiled-in X.509 certificates
Jan 30 13:58:33.212919 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.74-flatcar: 1efdcbe72fc44d29e4e6411cf9a3e64046be4375'
Jan 30 13:58:33.212935 kernel: Key type .fscrypt registered
Jan 30 13:58:33.212951 kernel: Key type fscrypt-provisioning registered
Jan 30 13:58:33.212967 kernel: ima: No TPM chip found, activating TPM-bypass!
Jan 30 13:58:33.212984 kernel: ima: Allocated hash algorithm: sha1
Jan 30 13:58:33.213000 kernel: ima: No architecture policies found
Jan 30 13:58:33.213016 kernel: clk: Disabling unused clocks
Jan 30 13:58:33.213036 kernel: Freeing unused kernel image (initmem) memory: 42844K
Jan 30 13:58:33.213052 kernel: Write protecting the kernel read-only data: 36864k
Jan 30 13:58:33.213069 kernel: Freeing unused kernel image (rodata/data gap) memory: 1848K
Jan 30 13:58:33.213085 kernel: Run /init as init process
Jan 30 13:58:33.213101 kernel:   with arguments:
Jan 30 13:58:33.213117 kernel:     /init
Jan 30 13:58:33.213133 kernel:   with environment:
Jan 30 13:58:33.213148 kernel:     HOME=/
Jan 30 13:58:33.213164 kernel:     TERM=linux
Jan 30 13:58:33.213183 kernel:     BOOT_IMAGE=/flatcar/vmlinuz-a
Jan 30 13:58:33.213228 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 30 13:58:33.213249 systemd[1]: Detected virtualization amazon.
Jan 30 13:58:33.213289 systemd[1]: Detected architecture x86-64.
Jan 30 13:58:33.213303 systemd[1]: Running in initrd.
Jan 30 13:58:33.213318 systemd[1]: No hostname configured, using default hostname.
Jan 30 13:58:33.213332 systemd[1]: Hostname set to <localhost>.
Jan 30 13:58:33.213354 systemd[1]: Initializing machine ID from VM UUID.
Jan 30 13:58:33.213371 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0
Jan 30 13:58:33.213388 systemd[1]: Queued start job for default target initrd.target.
Jan 30 13:58:33.213406 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch.
Jan 30 13:58:33.213424 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch.
Jan 30 13:58:33.213442 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM...
Jan 30 13:58:33.213460 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM...
Jan 30 13:58:33.213477 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT...
Jan 30 13:58:33.213498 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A...
Jan 30 13:58:33.213519 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132...
Jan 30 13:58:33.213535 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr...
Jan 30 13:58:33.213550 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre).
Jan 30 13:58:33.213565 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes.
Jan 30 13:58:33.213583 systemd[1]: Reached target paths.target - Path Units.
Jan 30 13:58:33.213600 systemd[1]: Reached target slices.target - Slice Units.
Jan 30 13:58:33.213620 systemd[1]: Reached target swap.target - Swaps.
Jan 30 13:58:33.213637 systemd[1]: Reached target timers.target - Timer Units.
Jan 30 13:58:33.213651 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket.
Jan 30 13:58:33.213668 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket.
Jan 30 13:58:33.213685 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log).
Jan 30 13:58:33.213701 systemd[1]: Listening on systemd-journald.socket - Journal Socket.
Jan 30 13:58:33.213718 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket.
Jan 30 13:58:33.213734 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket.
Jan 30 13:58:33.213754 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket.
Jan 30 13:58:33.213770 systemd[1]: Reached target sockets.target - Socket Units.
Jan 30 13:58:33.213787 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup...
Jan 30 13:58:33.213804 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes...
Jan 30 13:58:33.213821 systemd[1]: Finished network-cleanup.service - Network Cleanup.
Jan 30 13:58:33.213841 systemd[1]: Starting systemd-fsck-usr.service...
Jan 30 13:58:33.213860 systemd[1]: Starting systemd-journald.service - Journal Service...
Jan 30 13:58:33.213877 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules...
Jan 30 13:58:33.213919 systemd-journald[178]: Collecting audit messages is disabled.
Jan 30 13:58:33.213957 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup...
Jan 30 13:58:33.213976 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup.
Jan 30 13:58:33.213993 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes.
Jan 30 13:58:33.214010 systemd[1]: Finished systemd-fsck-usr.service.
Jan 30 13:58:33.214028 systemd-journald[178]: Journal started
Jan 30 13:58:33.214065 systemd-journald[178]: Runtime Journal (/run/log/journal/ec297702189fa97fc32cde6d225a1f0e) is 4.8M, max 38.6M, 33.7M free.
Jan 30 13:58:33.206982 systemd-modules-load[179]: Inserted module 'overlay'
Jan 30 13:58:33.296841 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully...
Jan 30 13:58:33.305256 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 30 13:58:33.308103 systemd-modules-load[179]: Inserted module 'br_netfilter'
Jan 30 13:58:33.465360 kernel: Bridge firewalling registered
Jan 30 13:58:33.474383 systemd[1]: Started systemd-journald.service - Journal Service.
Jan 30 13:58:33.476887 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules.
Jan 30 13:58:33.478841 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup.
Jan 30 13:58:33.483588 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully.
Jan 30 13:58:33.503647 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters...
Jan 30 13:58:33.508547 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables...
Jan 30 13:58:33.521234 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev...
Jan 30 13:58:33.541053 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories...
Jan 30 13:58:33.541539 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev.
Jan 30 13:58:33.554232 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables.
Jan 30 13:58:33.557329 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters.
Jan 30 13:58:33.566729 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook...
Jan 30 13:58:33.575597 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories.
Jan 30 13:58:33.594483 systemd[1]: Starting systemd-resolved.service - Network Name Resolution...
Jan 30 13:58:33.611827 dracut-cmdline[211]: dracut-dracut-053
Jan 30 13:58:33.620394 dracut-cmdline[211]: Using kernel command line parameters: rd.driver.pre=btrfs rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200n8 flatcar.first_boot=detected flatcar.oem.id=ec2 modprobe.blacklist=xen_fbfront net.ifnames=0 nvme_core.io_timeout=4294967295 verity.usrhash=befc9792b021bef43c896e00e1d5172b6224dbafc9b6c92b267e5e544378e681
Jan 30 13:58:33.680487 systemd-resolved[215]: Positive Trust Anchors:
Jan 30 13:58:33.680506 systemd-resolved[215]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
Jan 30 13:58:33.680566 systemd-resolved[215]: 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 30 13:58:33.685763 systemd-resolved[215]: Defaulting to hostname 'linux'.
Jan 30 13:58:33.687923 systemd[1]: Started systemd-resolved.service - Network Name Resolution.
Jan 30 13:58:33.692164 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups.
Jan 30 13:58:33.782299 kernel: SCSI subsystem initialized
Jan 30 13:58:33.792311 kernel: Loading iSCSI transport class v2.0-870.
Jan 30 13:58:33.806427 kernel: iscsi: registered transport (tcp)
Jan 30 13:58:33.858307 kernel: iscsi: registered transport (qla4xxx)
Jan 30 13:58:33.858383 kernel: QLogic iSCSI HBA Driver
Jan 30 13:58:33.957169 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook.
Jan 30 13:58:33.965611 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook...
Jan 30 13:58:33.996732 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.
Jan 30 13:58:33.996815 kernel: device-mapper: uevent: version 1.0.3
Jan 30 13:58:33.996836 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com
Jan 30 13:58:34.038289 kernel: raid6: avx512x4 gen() 16149 MB/s
Jan 30 13:58:34.055287 kernel: raid6: avx512x2 gen() 16943 MB/s
Jan 30 13:58:34.097294 kernel: raid6: avx512x1 gen()  5222 MB/s
Jan 30 13:58:34.116573 kernel: raid6: avx2x4   gen()  2755 MB/s
Jan 30 13:58:34.133297 kernel: raid6: avx2x2   gen()  9090 MB/s
Jan 30 13:58:34.150564 kernel: raid6: avx2x1   gen() 11312 MB/s
Jan 30 13:58:34.150643 kernel: raid6: using algorithm avx512x2 gen() 16943 MB/s
Jan 30 13:58:34.168493 kernel: raid6: .... xor() 17406 MB/s, rmw enabled
Jan 30 13:58:34.168579 kernel: raid6: using avx512x2 recovery algorithm
Jan 30 13:58:34.197291 kernel: xor: automatically using best checksumming function   avx       
Jan 30 13:58:34.498565 kernel: Btrfs loaded, zoned=no, fsverity=no
Jan 30 13:58:34.509190 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook.
Jan 30 13:58:34.516412 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files...
Jan 30 13:58:34.574627 systemd-udevd[398]: Using default interface naming scheme 'v255'.
Jan 30 13:58:34.592978 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files.
Jan 30 13:58:34.610126 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook...
Jan 30 13:58:34.678408 dracut-pre-trigger[402]: rd.md=0: removing MD RAID activation
Jan 30 13:58:34.716617 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook.
Jan 30 13:58:34.725499 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices...
Jan 30 13:58:34.879793 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices.
Jan 30 13:58:34.917855 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook...
Jan 30 13:58:34.999003 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook.
Jan 30 13:58:35.006576 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems.
Jan 30 13:58:35.010905 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes.
Jan 30 13:58:35.021655 systemd[1]: Reached target remote-fs.target - Remote File Systems.
Jan 30 13:58:35.072152 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook...
Jan 30 13:58:35.162769 kernel: ena 0000:00:05.0: ENA device version: 0.10
Jan 30 13:58:35.206547 kernel: ena 0000:00:05.0: ENA controller version: 0.0.1 implementation version 1
Jan 30 13:58:35.206762 kernel: ena 0000:00:05.0: LLQ is not supported Fallback to host mode policy.
Jan 30 13:58:35.206930 kernel: ena 0000:00:05.0: Elastic Network Adapter (ENA) found at mem febf4000, mac addr 06:d1:75:f3:c0:a7
Jan 30 13:58:35.207124 kernel: cryptd: max_cpu_qlen set to 1000
Jan 30 13:58:35.163481 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook.
Jan 30 13:58:35.232097 (udev-worker)[440]: Network interface NamePolicy= disabled on kernel command line.
Jan 30 13:58:35.245290 kernel: AVX2 version of gcm_enc/dec engaged.
Jan 30 13:58:35.245367 kernel: AES CTR mode by8 optimization enabled
Jan 30 13:58:35.279503 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully.
Jan 30 13:58:35.280145 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters.
Jan 30 13:58:35.299450 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters...
Jan 30 13:58:35.302786 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
Jan 30 13:58:35.303032 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup.
Jan 30 13:58:35.311525 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup...
Jan 30 13:58:35.328777 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup...
Jan 30 13:58:35.354283 kernel: nvme nvme0: pci function 0000:00:04.0
Jan 30 13:58:35.365618 kernel: ACPI: \_SB_.LNKD: Enabled at IRQ 11
Jan 30 13:58:35.394515 kernel: nvme nvme0: 2/0/0 default/read/poll queues
Jan 30 13:58:35.406586 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk.
Jan 30 13:58:35.406659 kernel: GPT:9289727 != 16777215
Jan 30 13:58:35.406679 kernel: GPT:Alternate GPT header not at the end of the disk.
Jan 30 13:58:35.406696 kernel: GPT:9289727 != 16777215
Jan 30 13:58:35.411208 kernel: GPT: Use GNU Parted to correct GPT errors.
Jan 30 13:58:35.411231 kernel:  nvme0n1: p1 p2 p3 p4 p6 p7 p9
Jan 30 13:58:35.567321 kernel: BTRFS: device fsid 64bb5b5a-85cc-41cc-a02b-2cfaa3e93b0a devid 1 transid 38 /dev/nvme0n1p3 scanned by (udev-worker) (443)
Jan 30 13:58:35.588303 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/nvme0n1p6 scanned by (udev-worker) (446)
Jan 30 13:58:35.692985 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup.
Jan 30 13:58:35.706526 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters...
Jan 30 13:58:35.747905 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Amazon Elastic Block Store EFI-SYSTEM.
Jan 30 13:58:35.780964 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters.
Jan 30 13:58:35.817782 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Amazon Elastic Block Store ROOT.
Jan 30 13:58:35.824971 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Amazon Elastic Block Store USR-A.
Jan 30 13:58:35.825117 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Amazon Elastic Block Store USR-A.
Jan 30 13:58:35.838304 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM.
Jan 30 13:58:35.850560 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary...
Jan 30 13:58:35.894255 disk-uuid[624]: Primary Header is updated.
Jan 30 13:58:35.894255 disk-uuid[624]: Secondary Entries is updated.
Jan 30 13:58:35.894255 disk-uuid[624]: Secondary Header is updated.
Jan 30 13:58:35.900284 kernel:  nvme0n1: p1 p2 p3 p4 p6 p7 p9
Jan 30 13:58:35.928291 kernel:  nvme0n1: p1 p2 p3 p4 p6 p7 p9
Jan 30 13:58:36.916285 kernel:  nvme0n1: p1 p2 p3 p4 p6 p7 p9
Jan 30 13:58:36.921065 disk-uuid[625]: The operation has completed successfully.
Jan 30 13:58:37.160753 systemd[1]: disk-uuid.service: Deactivated successfully.
Jan 30 13:58:37.160908 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary.
Jan 30 13:58:37.208891 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr...
Jan 30 13:58:37.219128 sh[885]: Success
Jan 30 13:58:37.264320 kernel: device-mapper: verity: sha256 using implementation "sha256-avx2"
Jan 30 13:58:37.434549 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr.
Jan 30 13:58:37.446190 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr...
Jan 30 13:58:37.449785 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr.
Jan 30 13:58:37.522039 kernel: BTRFS info (device dm-0): first mount of filesystem 64bb5b5a-85cc-41cc-a02b-2cfaa3e93b0a
Jan 30 13:58:37.522132 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm
Jan 30 13:58:37.522152 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead
Jan 30 13:58:37.530358 kernel: BTRFS info (device dm-0): disabling log replay at mount time
Jan 30 13:58:37.530398 kernel: BTRFS info (device dm-0): using free space tree
Jan 30 13:58:37.614294 kernel: BTRFS info (device dm-0): enabling ssd optimizations
Jan 30 13:58:37.617876 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr.
Jan 30 13:58:37.620342 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met.
Jan 30 13:58:37.633969 systemd[1]: Starting ignition-setup.service - Ignition (setup)...
Jan 30 13:58:37.637502 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline...
Jan 30 13:58:37.723930 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem aa75aabd-8755-4402-b4b6-23093345fe03
Jan 30 13:58:37.724007 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-intel) checksum algorithm
Jan 30 13:58:37.724028 kernel: BTRFS info (device nvme0n1p6): using free space tree
Jan 30 13:58:37.757426 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations
Jan 30 13:58:37.789356 systemd[1]: mnt-oem.mount: Deactivated successfully.
Jan 30 13:58:37.792506 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem aa75aabd-8755-4402-b4b6-23093345fe03
Jan 30 13:58:37.797462 systemd[1]: Finished ignition-setup.service - Ignition (setup).
Jan 30 13:58:37.807966 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)...
Jan 30 13:58:37.908371 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline.
Jan 30 13:58:37.922542 systemd[1]: Starting systemd-networkd.service - Network Configuration...
Jan 30 13:58:37.985974 systemd-networkd[1078]: lo: Link UP
Jan 30 13:58:37.988723 systemd-networkd[1078]: lo: Gained carrier
Jan 30 13:58:37.997953 systemd-networkd[1078]: Enumeration completed
Jan 30 13:58:37.998733 systemd[1]: Started systemd-networkd.service - Network Configuration.
Jan 30 13:58:37.998995 systemd-networkd[1078]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name.
Jan 30 13:58:37.999008 systemd-networkd[1078]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network.
Jan 30 13:58:38.011058 systemd[1]: Reached target network.target - Network.
Jan 30 13:58:38.018605 systemd-networkd[1078]: eth0: Link UP
Jan 30 13:58:38.018613 systemd-networkd[1078]: eth0: Gained carrier
Jan 30 13:58:38.018625 systemd-networkd[1078]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name.
Jan 30 13:58:38.060898 systemd-networkd[1078]: eth0: DHCPv4 address 172.31.29.140/20, gateway 172.31.16.1 acquired from 172.31.16.1
Jan 30 13:58:38.162967 ignition[996]: Ignition 2.19.0
Jan 30 13:58:38.163914 ignition[996]: Stage: fetch-offline
Jan 30 13:58:38.165609 ignition[996]: no configs at "/usr/lib/ignition/base.d"
Jan 30 13:58:38.165622 ignition[996]: no config dir at "/usr/lib/ignition/base.platform.d/aws"
Jan 30 13:58:38.175613 ignition[996]: Ignition finished successfully
Jan 30 13:58:38.180549 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline).
Jan 30 13:58:38.189972 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)...
Jan 30 13:58:38.254465 ignition[1087]: Ignition 2.19.0
Jan 30 13:58:38.254479 ignition[1087]: Stage: fetch
Jan 30 13:58:38.256814 ignition[1087]: no configs at "/usr/lib/ignition/base.d"
Jan 30 13:58:38.256832 ignition[1087]: no config dir at "/usr/lib/ignition/base.platform.d/aws"
Jan 30 13:58:38.257567 ignition[1087]: PUT http://169.254.169.254/latest/api/token: attempt #1
Jan 30 13:58:38.290994 ignition[1087]: PUT result: OK
Jan 30 13:58:38.297733 ignition[1087]: parsed url from cmdline: ""
Jan 30 13:58:38.297744 ignition[1087]: no config URL provided
Jan 30 13:58:38.297754 ignition[1087]: reading system config file "/usr/lib/ignition/user.ign"
Jan 30 13:58:38.297769 ignition[1087]: no config at "/usr/lib/ignition/user.ign"
Jan 30 13:58:38.297793 ignition[1087]: PUT http://169.254.169.254/latest/api/token: attempt #1
Jan 30 13:58:38.299105 ignition[1087]: PUT result: OK
Jan 30 13:58:38.299150 ignition[1087]: GET http://169.254.169.254/2019-10-01/user-data: attempt #1
Jan 30 13:58:38.301156 ignition[1087]: GET result: OK
Jan 30 13:58:38.301233 ignition[1087]: parsing config with SHA512: 081ae15f3f1403b76c0886720094973615fad7b7f709d13b2568c5ef64718260c76f914610acf4f641dc516483b82fc1ae9cafe5ec4ddedc186af5d1f33c9509
Jan 30 13:58:38.326632 unknown[1087]: fetched base config from "system"
Jan 30 13:58:38.327216 ignition[1087]: fetch: fetch complete
Jan 30 13:58:38.326648 unknown[1087]: fetched base config from "system"
Jan 30 13:58:38.327222 ignition[1087]: fetch: fetch passed
Jan 30 13:58:38.326659 unknown[1087]: fetched user config from "aws"
Jan 30 13:58:38.328282 ignition[1087]: Ignition finished successfully
Jan 30 13:58:38.333643 systemd[1]: Finished ignition-fetch.service - Ignition (fetch).
Jan 30 13:58:38.356374 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)...
Jan 30 13:58:38.422601 ignition[1094]: Ignition 2.19.0
Jan 30 13:58:38.422621 ignition[1094]: Stage: kargs
Jan 30 13:58:38.424170 ignition[1094]: no configs at "/usr/lib/ignition/base.d"
Jan 30 13:58:38.424186 ignition[1094]: no config dir at "/usr/lib/ignition/base.platform.d/aws"
Jan 30 13:58:38.425505 ignition[1094]: PUT http://169.254.169.254/latest/api/token: attempt #1
Jan 30 13:58:38.426680 ignition[1094]: PUT result: OK
Jan 30 13:58:38.441927 ignition[1094]: kargs: kargs passed
Jan 30 13:58:38.442023 ignition[1094]: Ignition finished successfully
Jan 30 13:58:38.451204 systemd[1]: Finished ignition-kargs.service - Ignition (kargs).
Jan 30 13:58:38.468983 systemd[1]: Starting ignition-disks.service - Ignition (disks)...
Jan 30 13:58:38.534122 ignition[1100]: Ignition 2.19.0
Jan 30 13:58:38.534137 ignition[1100]: Stage: disks
Jan 30 13:58:38.538551 ignition[1100]: no configs at "/usr/lib/ignition/base.d"
Jan 30 13:58:38.538580 ignition[1100]: no config dir at "/usr/lib/ignition/base.platform.d/aws"
Jan 30 13:58:38.539385 ignition[1100]: PUT http://169.254.169.254/latest/api/token: attempt #1
Jan 30 13:58:38.547016 ignition[1100]: PUT result: OK
Jan 30 13:58:38.561985 ignition[1100]: disks: disks passed
Jan 30 13:58:38.562091 ignition[1100]: Ignition finished successfully
Jan 30 13:58:38.565803 systemd[1]: Finished ignition-disks.service - Ignition (disks).
Jan 30 13:58:38.569551 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device.
Jan 30 13:58:38.575518 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems.
Jan 30 13:58:38.582054 systemd[1]: Reached target local-fs.target - Local File Systems.
Jan 30 13:58:38.590429 systemd[1]: Reached target sysinit.target - System Initialization.
Jan 30 13:58:38.605741 systemd[1]: Reached target basic.target - Basic System.
Jan 30 13:58:38.613529 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT...
Jan 30 13:58:38.656646 systemd-fsck[1108]: ROOT: clean, 14/553520 files, 52654/553472 blocks
Jan 30 13:58:38.659699 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT.
Jan 30 13:58:38.674524 systemd[1]: Mounting sysroot.mount - /sysroot...
Jan 30 13:58:38.965675 kernel: EXT4-fs (nvme0n1p9): mounted filesystem 9f41abed-fd12-4e57-bcd4-5c0ef7f8a1bf r/w with ordered data mode. Quota mode: none.
Jan 30 13:58:38.966315 systemd[1]: Mounted sysroot.mount - /sysroot.
Jan 30 13:58:38.970385 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System.
Jan 30 13:58:38.979680 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem...
Jan 30 13:58:38.986393 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr...
Jan 30 13:58:38.989658 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met.
Jan 30 13:58:38.989823 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot).
Jan 30 13:58:38.989849 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup.
Jan 30 13:58:39.037502 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/nvme0n1p6 scanned by mount (1127)
Jan 30 13:58:39.037784 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr.
Jan 30 13:58:39.049504 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem aa75aabd-8755-4402-b4b6-23093345fe03
Jan 30 13:58:39.049578 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-intel) checksum algorithm
Jan 30 13:58:39.049598 kernel: BTRFS info (device nvme0n1p6): using free space tree
Jan 30 13:58:39.055370 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations
Jan 30 13:58:39.056473 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup...
Jan 30 13:58:39.061914 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem.
Jan 30 13:58:39.291222 initrd-setup-root[1151]: cut: /sysroot/etc/passwd: No such file or directory
Jan 30 13:58:39.312039 initrd-setup-root[1158]: cut: /sysroot/etc/group: No such file or directory
Jan 30 13:58:39.321609 initrd-setup-root[1165]: cut: /sysroot/etc/shadow: No such file or directory
Jan 30 13:58:39.330387 initrd-setup-root[1172]: cut: /sysroot/etc/gshadow: No such file or directory
Jan 30 13:58:39.581477 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup.
Jan 30 13:58:39.591387 systemd[1]: Starting ignition-mount.service - Ignition (mount)...
Jan 30 13:58:39.610491 systemd[1]: Starting sysroot-boot.service - /sysroot/boot...
Jan 30 13:58:39.626486 systemd[1]: sysroot-oem.mount: Deactivated successfully.
Jan 30 13:58:39.627704 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem aa75aabd-8755-4402-b4b6-23093345fe03
Jan 30 13:58:39.646422 systemd-networkd[1078]: eth0: Gained IPv6LL
Jan 30 13:58:39.664475 systemd[1]: Finished sysroot-boot.service - /sysroot/boot.
Jan 30 13:58:39.671813 ignition[1240]: INFO     : Ignition 2.19.0
Jan 30 13:58:39.671813 ignition[1240]: INFO     : Stage: mount
Jan 30 13:58:39.673950 ignition[1240]: INFO     : no configs at "/usr/lib/ignition/base.d"
Jan 30 13:58:39.673950 ignition[1240]: INFO     : no config dir at "/usr/lib/ignition/base.platform.d/aws"
Jan 30 13:58:39.680389 ignition[1240]: INFO     : PUT http://169.254.169.254/latest/api/token: attempt #1
Jan 30 13:58:39.680389 ignition[1240]: INFO     : PUT result: OK
Jan 30 13:58:39.693067 ignition[1240]: INFO     : mount: mount passed
Jan 30 13:58:39.698128 ignition[1240]: INFO     : Ignition finished successfully
Jan 30 13:58:39.702275 systemd[1]: Finished ignition-mount.service - Ignition (mount).
Jan 30 13:58:39.721939 systemd[1]: Starting ignition-files.service - Ignition (files)...
Jan 30 13:58:39.971543 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem...
Jan 30 13:58:40.005288 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/nvme0n1p6 scanned by mount (1251)
Jan 30 13:58:40.007281 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem aa75aabd-8755-4402-b4b6-23093345fe03
Jan 30 13:58:40.007334 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-intel) checksum algorithm
Jan 30 13:58:40.008434 kernel: BTRFS info (device nvme0n1p6): using free space tree
Jan 30 13:58:40.013311 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations
Jan 30 13:58:40.016489 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem.
Jan 30 13:58:40.044374 ignition[1268]: INFO     : Ignition 2.19.0
Jan 30 13:58:40.044374 ignition[1268]: INFO     : Stage: files
Jan 30 13:58:40.047021 ignition[1268]: INFO     : no configs at "/usr/lib/ignition/base.d"
Jan 30 13:58:40.047021 ignition[1268]: INFO     : no config dir at "/usr/lib/ignition/base.platform.d/aws"
Jan 30 13:58:40.047021 ignition[1268]: INFO     : PUT http://169.254.169.254/latest/api/token: attempt #1
Jan 30 13:58:40.054520 ignition[1268]: INFO     : PUT result: OK
Jan 30 13:58:40.059150 ignition[1268]: DEBUG    : files: compiled without relabeling support, skipping
Jan 30 13:58:40.062201 ignition[1268]: INFO     : files: ensureUsers: op(1): [started]  creating or modifying user "core"
Jan 30 13:58:40.062201 ignition[1268]: DEBUG    : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core"
Jan 30 13:58:40.070425 ignition[1268]: INFO     : files: ensureUsers: op(1): [finished] creating or modifying user "core"
Jan 30 13:58:40.075176 ignition[1268]: INFO     : files: ensureUsers: op(2): [started]  adding ssh keys to user "core"
Jan 30 13:58:40.076959 unknown[1268]: wrote ssh authorized keys file for user: core
Jan 30 13:58:40.078434 ignition[1268]: INFO     : files: ensureUsers: op(2): [finished] adding ssh keys to user "core"
Jan 30 13:58:40.080861 ignition[1268]: INFO     : files: createFilesystemsFiles: createFiles: op(3): [started]  writing file "/sysroot/home/core/install.sh"
Jan 30 13:58:40.082997 ignition[1268]: INFO     : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh"
Jan 30 13:58:40.085770 ignition[1268]: INFO     : files: createFilesystemsFiles: createFiles: op(4): [started]  writing file "/sysroot/etc/flatcar/update.conf"
Jan 30 13:58:40.085770 ignition[1268]: INFO     : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf"
Jan 30 13:58:40.085770 ignition[1268]: INFO     : files: createFilesystemsFiles: createFiles: op(5): [started]  writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.0-x86-64.raw"
Jan 30 13:58:40.085770 ignition[1268]: INFO     : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.0-x86-64.raw"
Jan 30 13:58:40.085770 ignition[1268]: INFO     : files: createFilesystemsFiles: createFiles: op(6): [started]  writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.0-x86-64.raw"
Jan 30 13:58:40.085770 ignition[1268]: INFO     : files: createFilesystemsFiles: createFiles: op(6): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.32.0-x86-64.raw: attempt #1
Jan 30 13:58:40.598891 ignition[1268]: INFO     : files: createFilesystemsFiles: createFiles: op(6): GET result: OK
Jan 30 13:58:40.877591 ignition[1268]: INFO     : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.0-x86-64.raw"
Jan 30 13:58:40.881013 ignition[1268]: INFO     : files: createResultFile: createFiles: op(7): [started]  writing file "/sysroot/etc/.ignition-result.json"
Jan 30 13:58:40.884355 ignition[1268]: INFO     : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json"
Jan 30 13:58:40.884355 ignition[1268]: INFO     : files: files passed
Jan 30 13:58:40.884355 ignition[1268]: INFO     : Ignition finished successfully
Jan 30 13:58:40.899073 systemd[1]: Finished ignition-files.service - Ignition (files).
Jan 30 13:58:40.916727 systemd[1]: Starting ignition-quench.service - Ignition (record completion)...
Jan 30 13:58:40.929647 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion...
Jan 30 13:58:40.940923 systemd[1]: ignition-quench.service: Deactivated successfully.
Jan 30 13:58:40.941066 systemd[1]: Finished ignition-quench.service - Ignition (record completion).
Jan 30 13:58:40.982833 initrd-setup-root-after-ignition[1297]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory
Jan 30 13:58:40.982833 initrd-setup-root-after-ignition[1297]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory
Jan 30 13:58:40.988902 initrd-setup-root-after-ignition[1301]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory
Jan 30 13:58:40.999090 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion.
Jan 30 13:58:41.000218 systemd[1]: Reached target ignition-complete.target - Ignition Complete.
Jan 30 13:58:41.010498 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root...
Jan 30 13:58:41.061544 systemd[1]: initrd-parse-etc.service: Deactivated successfully.
Jan 30 13:58:41.062323 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root.
Jan 30 13:58:41.068962 systemd[1]: Reached target initrd-fs.target - Initrd File Systems.
Jan 30 13:58:41.069085 systemd[1]: Reached target initrd.target - Initrd Default Target.
Jan 30 13:58:41.082631 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met.
Jan 30 13:58:41.091789 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook...
Jan 30 13:58:41.132640 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook.
Jan 30 13:58:41.149633 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons...
Jan 30 13:58:41.222344 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups.
Jan 30 13:58:41.225069 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes.
Jan 30 13:58:41.227852 systemd[1]: Stopped target timers.target - Timer Units.
Jan 30 13:58:41.230450 systemd[1]: dracut-pre-pivot.service: Deactivated successfully.
Jan 30 13:58:41.230837 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook.
Jan 30 13:58:41.233881 systemd[1]: Stopped target initrd.target - Initrd Default Target.
Jan 30 13:58:41.239638 systemd[1]: Stopped target basic.target - Basic System.
Jan 30 13:58:41.247864 systemd[1]: Stopped target ignition-complete.target - Ignition Complete.
Jan 30 13:58:41.250801 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup.
Jan 30 13:58:41.256488 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device.
Jan 30 13:58:41.259475 systemd[1]: Stopped target remote-fs.target - Remote File Systems.
Jan 30 13:58:41.265811 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems.
Jan 30 13:58:41.270132 systemd[1]: Stopped target sysinit.target - System Initialization.
Jan 30 13:58:41.273120 systemd[1]: Stopped target local-fs.target - Local File Systems.
Jan 30 13:58:41.284352 systemd[1]: Stopped target swap.target - Swaps.
Jan 30 13:58:41.292416 systemd[1]: dracut-pre-mount.service: Deactivated successfully.
Jan 30 13:58:41.293802 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook.
Jan 30 13:58:41.294687 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes.
Jan 30 13:58:41.295204 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre).
Jan 30 13:58:41.301167 systemd[1]: clevis-luks-askpass.path: Deactivated successfully.
Jan 30 13:58:41.308096 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch.
Jan 30 13:58:41.314591 systemd[1]: dracut-initqueue.service: Deactivated successfully.
Jan 30 13:58:41.314775 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook.
Jan 30 13:58:41.327948 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully.
Jan 30 13:58:41.328173 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion.
Jan 30 13:58:41.344447 systemd[1]: ignition-files.service: Deactivated successfully.
Jan 30 13:58:41.348628 systemd[1]: Stopped ignition-files.service - Ignition (files).
Jan 30 13:58:41.360591 systemd[1]: Stopping ignition-mount.service - Ignition (mount)...
Jan 30 13:58:41.362411 systemd[1]: kmod-static-nodes.service: Deactivated successfully.
Jan 30 13:58:41.362635 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes.
Jan 30 13:58:41.374577 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot...
Jan 30 13:58:41.377401 systemd[1]: systemd-udev-trigger.service: Deactivated successfully.
Jan 30 13:58:41.377670 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices.
Jan 30 13:58:41.392217 systemd[1]: dracut-pre-trigger.service: Deactivated successfully.
Jan 30 13:58:41.392480 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook.
Jan 30 13:58:41.448639 systemd[1]: initrd-cleanup.service: Deactivated successfully.
Jan 30 13:58:41.451737 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons.
Jan 30 13:58:41.498053 ignition[1321]: INFO     : Ignition 2.19.0
Jan 30 13:58:41.511463 ignition[1321]: INFO     : Stage: umount
Jan 30 13:58:41.512455 ignition[1321]: INFO     : no configs at "/usr/lib/ignition/base.d"
Jan 30 13:58:41.512455 ignition[1321]: INFO     : no config dir at "/usr/lib/ignition/base.platform.d/aws"
Jan 30 13:58:41.512455 ignition[1321]: INFO     : PUT http://169.254.169.254/latest/api/token: attempt #1
Jan 30 13:58:41.534949 ignition[1321]: INFO     : PUT result: OK
Jan 30 13:58:41.539568 ignition[1321]: INFO     : umount: umount passed
Jan 30 13:58:41.539568 ignition[1321]: INFO     : Ignition finished successfully
Jan 30 13:58:41.539677 systemd[1]: sysroot-boot.mount: Deactivated successfully.
Jan 30 13:58:41.544822 systemd[1]: ignition-mount.service: Deactivated successfully.
Jan 30 13:58:41.544958 systemd[1]: Stopped ignition-mount.service - Ignition (mount).
Jan 30 13:58:41.546690 systemd[1]: ignition-disks.service: Deactivated successfully.
Jan 30 13:58:41.546758 systemd[1]: Stopped ignition-disks.service - Ignition (disks).
Jan 30 13:58:41.547965 systemd[1]: ignition-kargs.service: Deactivated successfully.
Jan 30 13:58:41.548025 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs).
Jan 30 13:58:41.550106 systemd[1]: ignition-fetch.service: Deactivated successfully.
Jan 30 13:58:41.550174 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch).
Jan 30 13:58:41.551699 systemd[1]: Stopped target network.target - Network.
Jan 30 13:58:41.553078 systemd[1]: ignition-fetch-offline.service: Deactivated successfully.
Jan 30 13:58:41.554674 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline).
Jan 30 13:58:41.556964 systemd[1]: Stopped target paths.target - Path Units.
Jan 30 13:58:41.563167 systemd[1]: systemd-ask-password-console.path: Deactivated successfully.
Jan 30 13:58:41.564912 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch.
Jan 30 13:58:41.574442 systemd[1]: Stopped target slices.target - Slice Units.
Jan 30 13:58:41.576995 systemd[1]: Stopped target sockets.target - Socket Units.
Jan 30 13:58:41.580118 systemd[1]: iscsid.socket: Deactivated successfully.
Jan 30 13:58:41.580196 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket.
Jan 30 13:58:41.582398 systemd[1]: iscsiuio.socket: Deactivated successfully.
Jan 30 13:58:41.582458 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket.
Jan 30 13:58:41.588697 systemd[1]: ignition-setup.service: Deactivated successfully.
Jan 30 13:58:41.588913 systemd[1]: Stopped ignition-setup.service - Ignition (setup).
Jan 30 13:58:41.594972 systemd[1]: ignition-setup-pre.service: Deactivated successfully.
Jan 30 13:58:41.595074 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup.
Jan 30 13:58:41.602606 systemd[1]: Stopping systemd-networkd.service - Network Configuration...
Jan 30 13:58:41.608206 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution...
Jan 30 13:58:41.614332 systemd-networkd[1078]: eth0: DHCPv6 lease lost
Jan 30 13:58:41.618818 systemd[1]: sysroot-boot.service: Deactivated successfully.
Jan 30 13:58:41.621356 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot.
Jan 30 13:58:41.624499 systemd[1]: systemd-networkd.service: Deactivated successfully.
Jan 30 13:58:41.624606 systemd[1]: Stopped systemd-networkd.service - Network Configuration.
Jan 30 13:58:41.645779 systemd[1]: systemd-networkd.socket: Deactivated successfully.
Jan 30 13:58:41.645865 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket.
Jan 30 13:58:41.648720 systemd[1]: initrd-setup-root.service: Deactivated successfully.
Jan 30 13:58:41.648804 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup.
Jan 30 13:58:41.668510 systemd[1]: Stopping network-cleanup.service - Network Cleanup...
Jan 30 13:58:41.674931 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully.
Jan 30 13:58:41.675036 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline.
Jan 30 13:58:41.677680 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files...
Jan 30 13:58:41.682715 systemd[1]: systemd-resolved.service: Deactivated successfully.
Jan 30 13:58:41.682841 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution.
Jan 30 13:58:41.694468 systemd[1]: systemd-sysctl.service: Deactivated successfully.
Jan 30 13:58:41.694590 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables.
Jan 30 13:58:41.698037 systemd[1]: systemd-modules-load.service: Deactivated successfully.
Jan 30 13:58:41.698111 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules.
Jan 30 13:58:41.719334 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.
Jan 30 13:58:41.720741 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories.
Jan 30 13:58:41.733361 systemd[1]: systemd-udevd.service: Deactivated successfully.
Jan 30 13:58:41.733555 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files.
Jan 30 13:58:41.738282 systemd[1]: network-cleanup.service: Deactivated successfully.
Jan 30 13:58:41.739449 systemd[1]: Stopped network-cleanup.service - Network Cleanup.
Jan 30 13:58:41.742148 systemd[1]: systemd-udevd-control.socket: Deactivated successfully.
Jan 30 13:58:41.742239 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket.
Jan 30 13:58:41.743642 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully.
Jan 30 13:58:41.743691 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket.
Jan 30 13:58:41.746325 systemd[1]: dracut-pre-udev.service: Deactivated successfully.
Jan 30 13:58:41.746399 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook.
Jan 30 13:58:41.761059 systemd[1]: dracut-cmdline.service: Deactivated successfully.
Jan 30 13:58:41.761124 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook.
Jan 30 13:58:41.766608 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully.
Jan 30 13:58:41.766716 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters.
Jan 30 13:58:41.777468 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database...
Jan 30 13:58:41.780905 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.
Jan 30 13:58:41.780996 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev.
Jan 30 13:58:41.785501 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
Jan 30 13:58:41.785568 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup.
Jan 30 13:58:41.793539 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.
Jan 30 13:58:41.794687 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database.
Jan 30 13:58:41.797395 systemd[1]: Reached target initrd-switch-root.target - Switch Root.
Jan 30 13:58:41.807528 systemd[1]: Starting initrd-switch-root.service - Switch Root...
Jan 30 13:58:41.828136 systemd[1]: Switching root.
Jan 30 13:58:41.869860 systemd-journald[178]: Journal stopped
Jan 30 13:58:43.979141 systemd-journald[178]: Received SIGTERM from PID 1 (systemd).
Jan 30 13:58:43.979253 kernel: SELinux:  policy capability network_peer_controls=1
Jan 30 13:58:43.979442 kernel: SELinux:  policy capability open_perms=1
Jan 30 13:58:43.979466 kernel: SELinux:  policy capability extended_socket_class=1
Jan 30 13:58:43.979486 kernel: SELinux:  policy capability always_check_network=0
Jan 30 13:58:43.979507 kernel: SELinux:  policy capability cgroup_seclabel=1
Jan 30 13:58:43.982625 kernel: SELinux:  policy capability nnp_nosuid_transition=1
Jan 30 13:58:43.982667 kernel: SELinux:  policy capability genfs_seclabel_symlinks=0
Jan 30 13:58:43.982691 kernel: SELinux:  policy capability ioctl_skip_cloexec=0
Jan 30 13:58:43.982732 kernel: audit: type=1403 audit(1738245522.207:2): auid=4294967295 ses=4294967295 lsm=selinux res=1
Jan 30 13:58:43.982764 systemd[1]: Successfully loaded SELinux policy in 82.600ms.
Jan 30 13:58:43.982805 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 20.407ms.
Jan 30 13:58:43.982831 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 30 13:58:43.982856 systemd[1]: Detected virtualization amazon.
Jan 30 13:58:43.982979 systemd[1]: Detected architecture x86-64.
Jan 30 13:58:43.983021 systemd[1]: Detected first boot.
Jan 30 13:58:43.983045 systemd[1]: Initializing machine ID from VM UUID.
Jan 30 13:58:43.983078 zram_generator::config[1365]: No configuration found.
Jan 30 13:58:43.983110 systemd[1]: Populated /etc with preset unit settings.
Jan 30 13:58:43.983134 systemd[1]: initrd-switch-root.service: Deactivated successfully.
Jan 30 13:58:43.983161 systemd[1]: Stopped initrd-switch-root.service - Switch Root.
Jan 30 13:58:43.983187 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.
Jan 30 13:58:43.983213 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config.
Jan 30 13:58:43.983243 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run.
Jan 30 13:58:43.985090 systemd[1]: Created slice system-getty.slice - Slice /system/getty.
Jan 30 13:58:43.985134 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe.
Jan 30 13:58:43.985163 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty.
Jan 30 13:58:43.985189 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit.
Jan 30 13:58:43.985215 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck.
Jan 30 13:58:43.985238 systemd[1]: Created slice user.slice - User and Session Slice.
Jan 30 13:58:43.985337 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch.
Jan 30 13:58:43.985359 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch.
Jan 30 13:58:43.985384 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch.
Jan 30 13:58:43.985403 systemd[1]: Set up automount boot.automount - Boot partition Automount Point.
Jan 30 13:58:43.985421 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point.
Jan 30 13:58:43.985445 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM...
Jan 30 13:58:43.985464 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0...
Jan 30 13:58:43.985488 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre).
Jan 30 13:58:43.985511 systemd[1]: Stopped target initrd-switch-root.target - Switch Root.
Jan 30 13:58:43.985534 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems.
Jan 30 13:58:43.985559 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System.
Jan 30 13:58:43.985586 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes.
Jan 30 13:58:43.985610 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes.
Jan 30 13:58:43.985634 systemd[1]: Reached target remote-fs.target - Remote File Systems.
Jan 30 13:58:43.985659 systemd[1]: Reached target slices.target - Slice Units.
Jan 30 13:58:43.985685 systemd[1]: Reached target swap.target - Swaps.
Jan 30 13:58:43.985709 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes.
Jan 30 13:58:43.985734 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket.
Jan 30 13:58:43.985841 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket.
Jan 30 13:58:43.985870 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket.
Jan 30 13:58:43.985896 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket.
Jan 30 13:58:43.985923 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket.
Jan 30 13:58:43.985947 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System...
Jan 30 13:58:43.985972 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System...
Jan 30 13:58:43.985996 systemd[1]: Mounting media.mount - External Media Directory...
Jan 30 13:58:43.986020 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen).
Jan 30 13:58:43.986045 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System...
Jan 30 13:58:43.986075 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System...
Jan 30 13:58:43.986097 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp...
Jan 30 13:58:43.986122 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 30 13:58:43.987651 systemd[1]: Reached target machines.target - Containers.
Jan 30 13:58:43.991953 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files...
Jan 30 13:58:43.992004 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met.
Jan 30 13:58:43.992032 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes...
Jan 30 13:58:43.992057 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs...
Jan 30 13:58:43.992083 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod...
Jan 30 13:58:43.992118 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm...
Jan 30 13:58:43.992144 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore...
Jan 30 13:58:43.992170 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse...
Jan 30 13:58:43.992194 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop...
Jan 30 13:58:43.992231 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf).
Jan 30 13:58:43.992254 systemd[1]: systemd-fsck-root.service: Deactivated successfully.
Jan 30 13:58:43.992319 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device.
Jan 30 13:58:43.992338 systemd[1]: systemd-fsck-usr.service: Deactivated successfully.
Jan 30 13:58:43.992361 systemd[1]: Stopped systemd-fsck-usr.service.
Jan 30 13:58:43.992385 systemd[1]: Starting systemd-journald.service - Journal Service...
Jan 30 13:58:43.992410 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules...
Jan 30 13:58:43.992433 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line...
Jan 30 13:58:43.992456 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems...
Jan 30 13:58:43.992481 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices...
Jan 30 13:58:43.992578 kernel: fuse: init (API version 7.39)
Jan 30 13:58:43.992609 systemd[1]: verity-setup.service: Deactivated successfully.
Jan 30 13:58:43.992633 systemd[1]: Stopped verity-setup.service.
Jan 30 13:58:43.992664 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen).
Jan 30 13:58:43.992689 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System.
Jan 30 13:58:43.992713 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System.
Jan 30 13:58:43.992737 systemd[1]: Mounted media.mount - External Media Directory.
Jan 30 13:58:43.992760 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System.
Jan 30 13:58:43.992785 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System.
Jan 30 13:58:43.992813 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp.
Jan 30 13:58:43.992837 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes.
Jan 30 13:58:43.992863 systemd[1]: modprobe@configfs.service: Deactivated successfully.
Jan 30 13:58:43.992884 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs.
Jan 30 13:58:43.992909 kernel: loop: module loaded
Jan 30 13:58:43.992931 kernel: ACPI: bus type drm_connector registered
Jan 30 13:58:43.992955 systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
Jan 30 13:58:43.992979 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod.
Jan 30 13:58:43.993055 systemd-journald[1446]: Collecting audit messages is disabled.
Jan 30 13:58:43.993101 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files.
Jan 30 13:58:43.993127 systemd[1]: modprobe@drm.service: Deactivated successfully.
Jan 30 13:58:43.993151 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm.
Jan 30 13:58:43.993175 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
Jan 30 13:58:43.993201 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore.
Jan 30 13:58:43.993226 systemd[1]: modprobe@fuse.service: Deactivated successfully.
Jan 30 13:58:43.993255 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse.
Jan 30 13:58:43.993552 systemd-journald[1446]: Journal started
Jan 30 13:58:43.997457 systemd-journald[1446]: Runtime Journal (/run/log/journal/ec297702189fa97fc32cde6d225a1f0e) is 4.8M, max 38.6M, 33.7M free.
Jan 30 13:58:43.997586 systemd[1]: modprobe@loop.service: Deactivated successfully.
Jan 30 13:58:43.997636 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop.
Jan 30 13:58:43.325192 systemd[1]: Queued start job for default target multi-user.target.
Jan 30 13:58:43.347715 systemd[1]: Unnecessary job was removed for dev-nvme0n1p6.device - /dev/nvme0n1p6.
Jan 30 13:58:44.001304 systemd[1]: Started systemd-journald.service - Journal Service.
Jan 30 13:58:43.348378 systemd[1]: systemd-journald.service: Deactivated successfully.
Jan 30 13:58:44.004170 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules.
Jan 30 13:58:44.007128 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line.
Jan 30 13:58:44.009538 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems.
Jan 30 13:58:44.030039 systemd[1]: Reached target network-pre.target - Preparation for Network.
Jan 30 13:58:44.041384 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System...
Jan 30 13:58:44.049363 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System...
Jan 30 13:58:44.051294 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).
Jan 30 13:58:44.051344 systemd[1]: Reached target local-fs.target - Local File Systems.
Jan 30 13:58:44.056105 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink).
Jan 30 13:58:44.066454 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown...
Jan 30 13:58:44.073484 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache...
Jan 30 13:58:44.075186 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met.
Jan 30 13:58:44.077503 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database...
Jan 30 13:58:44.085448 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage...
Jan 30 13:58:44.088104 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
Jan 30 13:58:44.093589 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed...
Jan 30 13:58:44.095180 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met.
Jan 30 13:58:44.098516 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables...
Jan 30 13:58:44.111875 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/...
Jan 30 13:58:44.133868 systemd[1]: Starting systemd-sysusers.service - Create System Users...
Jan 30 13:58:44.141420 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices.
Jan 30 13:58:44.144817 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System.
Jan 30 13:58:44.146541 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System.
Jan 30 13:58:44.180207 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown.
Jan 30 13:58:44.218638 systemd-journald[1446]: Time spent on flushing to /var/log/journal/ec297702189fa97fc32cde6d225a1f0e is 158.770ms for 944 entries.
Jan 30 13:58:44.218638 systemd-journald[1446]: System Journal (/var/log/journal/ec297702189fa97fc32cde6d225a1f0e) is 8.0M, max 195.6M, 187.6M free.
Jan 30 13:58:44.401361 systemd-journald[1446]: Received client request to flush runtime journal.
Jan 30 13:58:44.401429 kernel: loop0: detected capacity change from 0 to 140768
Jan 30 13:58:44.401457 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher
Jan 30 13:58:44.401488 kernel: loop1: detected capacity change from 0 to 142488
Jan 30 13:58:44.253283 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization...
Jan 30 13:58:44.255916 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed.
Jan 30 13:58:44.259934 systemd[1]: Reached target first-boot-complete.target - First Boot Complete.
Jan 30 13:58:44.273544 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk...
Jan 30 13:58:44.277588 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables.
Jan 30 13:58:44.380654 udevadm[1499]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in.
Jan 30 13:58:44.399447 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
Jan 30 13:58:44.403546 systemd[1]: Finished systemd-sysusers.service - Create System Users.
Jan 30 13:58:44.406356 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage.
Jan 30 13:58:44.411882 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk.
Jan 30 13:58:44.427847 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev...
Jan 30 13:58:44.482765 systemd-tmpfiles[1510]: ACLs are not supported, ignoring.
Jan 30 13:58:44.483397 kernel: loop2: detected capacity change from 0 to 218376
Jan 30 13:58:44.482798 systemd-tmpfiles[1510]: ACLs are not supported, ignoring.
Jan 30 13:58:44.493322 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev.
Jan 30 13:58:44.654334 kernel: loop3: detected capacity change from 0 to 61336
Jan 30 13:58:44.757852 kernel: loop4: detected capacity change from 0 to 140768
Jan 30 13:58:44.839292 kernel: loop5: detected capacity change from 0 to 142488
Jan 30 13:58:44.887037 kernel: loop6: detected capacity change from 0 to 218376
Jan 30 13:58:44.985102 kernel: loop7: detected capacity change from 0 to 61336
Jan 30 13:58:45.026143 (sd-merge)[1516]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-ami'.
Jan 30 13:58:45.026884 (sd-merge)[1516]: Merged extensions into '/usr'.
Jan 30 13:58:45.038584 systemd[1]: Reloading requested from client PID 1490 ('systemd-sysext') (unit systemd-sysext.service)...
Jan 30 13:58:45.038929 systemd[1]: Reloading...
Jan 30 13:58:45.222790 zram_generator::config[1539]: No configuration found.
Jan 30 13:58:45.572333 ldconfig[1485]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start.
Jan 30 13:58:45.650484 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 30 13:58:45.755099 systemd[1]: Reloading finished in 715 ms.
Jan 30 13:58:45.804558 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache.
Jan 30 13:58:45.806756 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/.
Jan 30 13:58:45.827770 systemd[1]: Starting ensure-sysext.service...
Jan 30 13:58:45.844003 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories...
Jan 30 13:58:45.868888 systemd[1]: Reloading requested from client PID 1591 ('systemctl') (unit ensure-sysext.service)...
Jan 30 13:58:45.868907 systemd[1]: Reloading...
Jan 30 13:58:45.926798 systemd-tmpfiles[1592]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring.
Jan 30 13:58:45.927513 systemd-tmpfiles[1592]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring.
Jan 30 13:58:45.930339 systemd-tmpfiles[1592]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring.
Jan 30 13:58:45.932881 systemd-tmpfiles[1592]: ACLs are not supported, ignoring.
Jan 30 13:58:45.932977 systemd-tmpfiles[1592]: ACLs are not supported, ignoring.
Jan 30 13:58:45.952845 systemd-tmpfiles[1592]: Detected autofs mount point /boot during canonicalization of boot.
Jan 30 13:58:45.952861 systemd-tmpfiles[1592]: Skipping /boot
Jan 30 13:58:46.069911 systemd-tmpfiles[1592]: Detected autofs mount point /boot during canonicalization of boot.
Jan 30 13:58:46.071323 systemd-tmpfiles[1592]: Skipping /boot
Jan 30 13:58:46.118357 zram_generator::config[1619]: No configuration found.
Jan 30 13:58:46.417820 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 30 13:58:46.547678 systemd[1]: Reloading finished in 677 ms.
Jan 30 13:58:46.582187 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database.
Jan 30 13:58:46.596167 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories.
Jan 30 13:58:46.637694 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules...
Jan 30 13:58:46.644138 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs...
Jan 30 13:58:46.663134 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog...
Jan 30 13:58:46.688637 systemd[1]: Starting systemd-resolved.service - Network Name Resolution...
Jan 30 13:58:46.708746 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files...
Jan 30 13:58:46.715667 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP...
Jan 30 13:58:46.727736 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen).
Jan 30 13:58:46.728354 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met.
Jan 30 13:58:46.745672 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod...
Jan 30 13:58:46.757854 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore...
Jan 30 13:58:46.762572 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop...
Jan 30 13:58:46.766593 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met.
Jan 30 13:58:46.780858 systemd[1]: Starting systemd-userdbd.service - User Database Manager...
Jan 30 13:58:46.783125 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen).
Jan 30 13:58:46.803900 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen).
Jan 30 13:58:46.804288 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met.
Jan 30 13:58:46.804636 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met.
Jan 30 13:58:46.804840 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen).
Jan 30 13:58:46.814102 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen).
Jan 30 13:58:46.814531 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met.
Jan 30 13:58:46.824070 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm...
Jan 30 13:58:46.825898 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met.
Jan 30 13:58:46.826348 systemd[1]: Reached target time-set.target - System Time Set.
Jan 30 13:58:46.827963 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen).
Jan 30 13:58:46.838883 systemd-udevd[1676]: Using default interface naming scheme 'v255'.
Jan 30 13:58:46.842076 systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
Jan 30 13:58:46.843997 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod.
Jan 30 13:58:46.851041 systemd[1]: Finished ensure-sysext.service.
Jan 30 13:58:46.852717 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog.
Jan 30 13:58:46.855102 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP.
Jan 30 13:58:46.857901 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
Jan 30 13:58:46.858086 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore.
Jan 30 13:58:46.861870 systemd[1]: modprobe@loop.service: Deactivated successfully.
Jan 30 13:58:46.862064 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop.
Jan 30 13:58:46.870638 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
Jan 30 13:58:46.870858 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met.
Jan 30 13:58:46.881503 systemd[1]: Starting systemd-update-done.service - Update is Completed...
Jan 30 13:58:46.889194 systemd[1]: modprobe@drm.service: Deactivated successfully.
Jan 30 13:58:46.889590 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm.
Jan 30 13:58:46.908174 augenrules[1704]: No rules
Jan 30 13:58:46.917398 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules.
Jan 30 13:58:46.930474 systemd[1]: Finished systemd-update-done.service - Update is Completed.
Jan 30 13:58:46.934063 systemd[1]: Started systemd-userdbd.service - User Database Manager.
Jan 30 13:58:46.943361 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files.
Jan 30 13:58:46.955951 systemd[1]: Starting systemd-networkd.service - Network Configuration...
Jan 30 13:58:46.989027 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs.
Jan 30 13:58:46.996836 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 30 13:58:47.098637 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped.
Jan 30 13:58:47.132830 (udev-worker)[1729]: Network interface NamePolicy= disabled on kernel command line.
Jan 30 13:58:47.148977 systemd-networkd[1718]: lo: Link UP
Jan 30 13:58:47.148989 systemd-networkd[1718]: lo: Gained carrier
Jan 30 13:58:47.152692 systemd-networkd[1718]: Enumeration completed
Jan 30 13:58:47.152981 systemd[1]: Started systemd-networkd.service - Network Configuration.
Jan 30 13:58:47.160577 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured...
Jan 30 13:58:47.165475 systemd-networkd[1718]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name.
Jan 30 13:58:47.165480 systemd-networkd[1718]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network.
Jan 30 13:58:47.173510 systemd-networkd[1718]: eth0: Link UP
Jan 30 13:58:47.173751 systemd-networkd[1718]: eth0: Gained carrier
Jan 30 13:58:47.173779 systemd-networkd[1718]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name.
Jan 30 13:58:47.192426 systemd-networkd[1718]: eth0: DHCPv4 address 172.31.29.140/20, gateway 172.31.16.1 acquired from 172.31.16.1
Jan 30 13:58:47.203794 systemd-networkd[1718]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name.
Jan 30 13:58:47.205231 systemd-resolved[1675]: Positive Trust Anchors:
Jan 30 13:58:47.205247 systemd-resolved[1675]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
Jan 30 13:58:47.206008 systemd-resolved[1675]: 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 30 13:58:47.222027 systemd-resolved[1675]: Defaulting to hostname 'linux'.
Jan 30 13:58:47.225890 systemd[1]: Started systemd-resolved.service - Network Name Resolution.
Jan 30 13:58:47.227545 systemd[1]: Reached target network.target - Network.
Jan 30 13:58:47.228639 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups.
Jan 30 13:58:47.258330 kernel: piix4_smbus 0000:00:01.3: SMBus Host Controller at 0xb100, revision 255
Jan 30 13:58:47.263694 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3
Jan 30 13:58:47.276471 kernel: ACPI: button: Power Button [PWRF]
Jan 30 13:58:47.292297 kernel: input: ImPS/2 Generic Wheel Mouse as /devices/platform/i8042/serio1/input/input4
Jan 30 13:58:47.294282 kernel: input: Sleep Button as /devices/LNXSYSTM:00/LNXSLPBN:00/input/input5
Jan 30 13:58:47.304287 kernel: ACPI: button: Sleep Button [SLPF]
Jan 30 13:58:47.320281 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 38 scanned by (udev-worker) (1726)
Jan 30 13:58:47.355346 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup...
Jan 30 13:58:47.399283 kernel: mousedev: PS/2 mouse device common for all mice
Jan 30 13:58:47.555502 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM.
Jan 30 13:58:47.652694 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization.
Jan 30 13:58:47.654651 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup.
Jan 30 13:58:47.666563 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes...
Jan 30 13:58:47.694484 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM...
Jan 30 13:58:47.721879 lvm[1835]:   WARNING: Failed to connect to lvmetad. Falling back to device scanning.
Jan 30 13:58:47.728691 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM.
Jan 30 13:58:47.761783 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes.
Jan 30 13:58:47.764278 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes.
Jan 30 13:58:47.768307 systemd[1]: Reached target sysinit.target - System Initialization.
Jan 30 13:58:47.771822 systemd[1]: Started motdgen.path - Watch for update engine configuration changes.
Jan 30 13:58:47.775672 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 30 13:58:47.779997 systemd[1]: Started logrotate.timer - Daily rotation of log files.
Jan 30 13:58:47.784741 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information..
Jan 30 13:58:47.792142 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories.
Jan 30 13:58:47.796502 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate).
Jan 30 13:58:47.796558 systemd[1]: Reached target paths.target - Path Units.
Jan 30 13:58:47.799818 systemd[1]: Reached target timers.target - Timer Units.
Jan 30 13:58:47.804801 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket.
Jan 30 13:58:47.811008 systemd[1]: Starting docker.socket - Docker Socket for the API...
Jan 30 13:58:47.820983 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket.
Jan 30 13:58:47.833403 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes...
Jan 30 13:58:47.837587 systemd[1]: Listening on docker.socket - Docker Socket for the API.
Jan 30 13:58:47.842198 systemd[1]: Reached target sockets.target - Socket Units.
Jan 30 13:58:47.845948 systemd[1]: Reached target basic.target - Basic System.
Jan 30 13:58:47.849440 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met.
Jan 30 13:58:47.849645 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met.
Jan 30 13:58:47.861272 systemd[1]: Starting containerd.service - containerd container runtime...
Jan 30 13:58:47.880168 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent...
Jan 30 13:58:47.897689 lvm[1843]:   WARNING: Failed to connect to lvmetad. Falling back to device scanning.
Jan 30 13:58:47.897580 systemd[1]: Starting dbus.service - D-Bus System Message Bus...
Jan 30 13:58:47.917436 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit...
Jan 30 13:58:47.934529 systemd[1]: Starting extend-filesystems.service - Extend Filesystems...
Jan 30 13:58:47.936117 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 30 13:58:47.941539 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd...
Jan 30 13:58:47.950552 systemd[1]: Started ntpd.service - Network Time Service.
Jan 30 13:58:47.963474 systemd[1]: Starting setup-oem.service - Setup OEM...
Jan 30 13:58:47.975834 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline...
Jan 30 13:58:48.000887 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys...
Jan 30 13:58:48.050999 jq[1847]: false
Jan 30 13:58:48.061473 systemd[1]: Starting systemd-logind.service - User Login Management...
Jan 30 13:58:48.066592 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0).
Jan 30 13:58:48.068022 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details.
Jan 30 13:58:48.072529 systemd[1]: Starting update-engine.service - Update Engine...
Jan 30 13:58:48.090268 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition...
Jan 30 13:58:48.093008 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes.
Jan 30 13:58:48.106773 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'.
Jan 30 13:58:48.107011 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped.
Jan 30 13:58:48.137769 systemd[1]: motdgen.service: Deactivated successfully.
Jan 30 13:58:48.137999 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd.
Jan 30 13:58:48.154621 ntpd[1850]: ntpd 4.2.8p17@1.4004-o Wed Jan 29 09:31:52 UTC 2025 (1): Starting
Jan 30 13:58:48.154657 ntpd[1850]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp
Jan 30 13:58:48.155391 ntpd[1850]: 30 Jan 13:58:48 ntpd[1850]: ntpd 4.2.8p17@1.4004-o Wed Jan 29 09:31:52 UTC 2025 (1): Starting
Jan 30 13:58:48.155391 ntpd[1850]: 30 Jan 13:58:48 ntpd[1850]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp
Jan 30 13:58:48.155391 ntpd[1850]: 30 Jan 13:58:48 ntpd[1850]: ----------------------------------------------------
Jan 30 13:58:48.155391 ntpd[1850]: 30 Jan 13:58:48 ntpd[1850]: ntp-4 is maintained by Network Time Foundation,
Jan 30 13:58:48.155391 ntpd[1850]: 30 Jan 13:58:48 ntpd[1850]: Inc. (NTF), a non-profit 501(c)(3) public-benefit
Jan 30 13:58:48.155391 ntpd[1850]: 30 Jan 13:58:48 ntpd[1850]: corporation.  Support and training for ntp-4 are
Jan 30 13:58:48.155391 ntpd[1850]: 30 Jan 13:58:48 ntpd[1850]: available at https://www.nwtime.org/support
Jan 30 13:58:48.155391 ntpd[1850]: 30 Jan 13:58:48 ntpd[1850]: ----------------------------------------------------
Jan 30 13:58:48.154667 ntpd[1850]: ----------------------------------------------------
Jan 30 13:58:48.157808 jq[1859]: true
Jan 30 13:58:48.154675 ntpd[1850]: ntp-4 is maintained by Network Time Foundation,
Jan 30 13:58:48.154684 ntpd[1850]: Inc. (NTF), a non-profit 501(c)(3) public-benefit
Jan 30 13:58:48.154692 ntpd[1850]: corporation.  Support and training for ntp-4 are
Jan 30 13:58:48.154701 ntpd[1850]: available at https://www.nwtime.org/support
Jan 30 13:58:48.154713 ntpd[1850]: ----------------------------------------------------
Jan 30 13:58:48.170818 ntpd[1850]: proto: precision = 0.061 usec (-24)
Jan 30 13:58:48.172676 ntpd[1850]: 30 Jan 13:58:48 ntpd[1850]: proto: precision = 0.061 usec (-24)
Jan 30 13:58:48.172676 ntpd[1850]: 30 Jan 13:58:48 ntpd[1850]: basedate set to 2025-01-17
Jan 30 13:58:48.172676 ntpd[1850]: 30 Jan 13:58:48 ntpd[1850]: gps base set to 2025-01-19 (week 2350)
Jan 30 13:58:48.171198 ntpd[1850]: basedate set to 2025-01-17
Jan 30 13:58:48.171216 ntpd[1850]: gps base set to 2025-01-19 (week 2350)
Jan 30 13:58:48.175659 dbus-daemon[1846]: [system] SELinux support is enabled
Jan 30 13:58:48.175872 systemd[1]: Started dbus.service - D-Bus System Message Bus.
Jan 30 13:58:48.184429 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 30 13:58:48.184509 systemd[1]: Reached target system-config.target - Load system-provided cloud configs.
Jan 30 13:58:48.186990 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 30 13:58:48.187024 systemd[1]: Reached target user-config.target - Load user-provided cloud configs.
Jan 30 13:58:48.196508 ntpd[1850]: Listen and drop on 0 v6wildcard [::]:123
Jan 30 13:58:48.196729 ntpd[1850]: 30 Jan 13:58:48 ntpd[1850]: Listen and drop on 0 v6wildcard [::]:123
Jan 30 13:58:48.196842 ntpd[1850]: Listen and drop on 1 v4wildcard 0.0.0.0:123
Jan 30 13:58:48.196913 ntpd[1850]: 30 Jan 13:58:48 ntpd[1850]: Listen and drop on 1 v4wildcard 0.0.0.0:123
Jan 30 13:58:48.197156 ntpd[1850]: Listen normally on 2 lo 127.0.0.1:123
Jan 30 13:58:48.197287 ntpd[1850]: 30 Jan 13:58:48 ntpd[1850]: Listen normally on 2 lo 127.0.0.1:123
Jan 30 13:58:48.197399 ntpd[1850]: Listen normally on 3 eth0 172.31.29.140:123
Jan 30 13:58:48.197476 ntpd[1850]: 30 Jan 13:58:48 ntpd[1850]: Listen normally on 3 eth0 172.31.29.140:123
Jan 30 13:58:48.197566 ntpd[1850]: Listen normally on 4 lo [::1]:123
Jan 30 13:58:48.198074 ntpd[1850]: 30 Jan 13:58:48 ntpd[1850]: Listen normally on 4 lo [::1]:123
Jan 30 13:58:48.198179 ntpd[1850]: bind(21) AF_INET6 fe80::4d1:75ff:fef3:c0a7%2#123 flags 0x11 failed: Cannot assign requested address
Jan 30 13:58:48.201329 ntpd[1850]: unable to create socket on eth0 (5) for fe80::4d1:75ff:fef3:c0a7%2#123
Jan 30 13:58:48.203577 ntpd[1850]: 30 Jan 13:58:48 ntpd[1850]: bind(21) AF_INET6 fe80::4d1:75ff:fef3:c0a7%2#123 flags 0x11 failed: Cannot assign requested address
Jan 30 13:58:48.203577 ntpd[1850]: 30 Jan 13:58:48 ntpd[1850]: unable to create socket on eth0 (5) for fe80::4d1:75ff:fef3:c0a7%2#123
Jan 30 13:58:48.203577 ntpd[1850]: 30 Jan 13:58:48 ntpd[1850]: failed to init interface for address fe80::4d1:75ff:fef3:c0a7%2
Jan 30 13:58:48.203577 ntpd[1850]: 30 Jan 13:58:48 ntpd[1850]: Listening on routing socket on fd #21 for interface updates
Jan 30 13:58:48.203577 ntpd[1850]: 30 Jan 13:58:48 ntpd[1850]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
Jan 30 13:58:48.203577 ntpd[1850]: 30 Jan 13:58:48 ntpd[1850]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
Jan 30 13:58:48.201352 ntpd[1850]: failed to init interface for address fe80::4d1:75ff:fef3:c0a7%2
Jan 30 13:58:48.203845 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully.
Jan 30 13:58:48.201402 ntpd[1850]: Listening on routing socket on fd #21 for interface updates
Jan 30 13:58:48.204105 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline.
Jan 30 13:58:48.202755 ntpd[1850]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
Jan 30 13:58:48.202781 ntpd[1850]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
Jan 30 13:58:48.223597 dbus-daemon[1846]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.2' (uid=244 pid=1718 comm="/usr/lib/systemd/systemd-networkd" label="system_u:system_r:kernel_t:s0")
Jan 30 13:58:48.226868 extend-filesystems[1848]: Found loop4
Jan 30 13:58:48.270710 extend-filesystems[1848]: Found loop5
Jan 30 13:58:48.270710 extend-filesystems[1848]: Found loop6
Jan 30 13:58:48.270710 extend-filesystems[1848]: Found loop7
Jan 30 13:58:48.270710 extend-filesystems[1848]: Found nvme0n1
Jan 30 13:58:48.270710 extend-filesystems[1848]: Found nvme0n1p1
Jan 30 13:58:48.270710 extend-filesystems[1848]: Found nvme0n1p2
Jan 30 13:58:48.270710 extend-filesystems[1848]: Found nvme0n1p3
Jan 30 13:58:48.270710 extend-filesystems[1848]: Found usr
Jan 30 13:58:48.270710 extend-filesystems[1848]: Found nvme0n1p4
Jan 30 13:58:48.270710 extend-filesystems[1848]: Found nvme0n1p6
Jan 30 13:58:48.270710 extend-filesystems[1848]: Found nvme0n1p7
Jan 30 13:58:48.270710 extend-filesystems[1848]: Found nvme0n1p9
Jan 30 13:58:48.270710 extend-filesystems[1848]: Checking size of /dev/nvme0n1p9
Jan 30 13:58:48.312405 jq[1875]: true
Jan 30 13:58:48.312573 update_engine[1858]: I20250130 13:58:48.245641  1858 main.cc:92] Flatcar Update Engine starting
Jan 30 13:58:48.312573 update_engine[1858]: I20250130 13:58:48.277853  1858 update_check_scheduler.cc:74] Next update check in 4m10s
Jan 30 13:58:48.243104 systemd[1]: Starting systemd-hostnamed.service - Hostname Service...
Jan 30 13:58:48.312927 extend-filesystems[1848]: Resized partition /dev/nvme0n1p9
Jan 30 13:58:48.272252 systemd-logind[1857]: Watching system buttons on /dev/input/event1 (Power Button)
Jan 30 13:58:48.278543 systemd-logind[1857]: Watching system buttons on /dev/input/event3 (Sleep Button)
Jan 30 13:58:48.326553 extend-filesystems[1894]: resize2fs 1.47.1 (20-May-2024)
Jan 30 13:58:48.278590 systemd-logind[1857]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)
Jan 30 13:58:48.281596 systemd-logind[1857]: New seat seat0.
Jan 30 13:58:48.329328 kernel: EXT4-fs (nvme0n1p9): resizing filesystem from 553472 to 1489915 blocks
Jan 30 13:58:48.287383 systemd-networkd[1718]: eth0: Gained IPv6LL
Jan 30 13:58:48.289352 (ntainerd)[1882]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR
Jan 30 13:58:48.292204 systemd[1]: Started systemd-logind.service - User Login Management.
Jan 30 13:58:48.302539 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured.
Jan 30 13:58:48.304592 systemd[1]: Finished setup-oem.service - Setup OEM.
Jan 30 13:58:48.306046 systemd[1]: Started update-engine.service - Update Engine.
Jan 30 13:58:48.335470 systemd[1]: Reached target network-online.target - Network is Online.
Jan 30 13:58:48.344418 systemd[1]: Started amazon-ssm-agent.service - amazon-ssm-agent.
Jan 30 13:58:48.357489 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent...
Jan 30 13:58:48.369160 systemd[1]: Starting nvidia.service - NVIDIA Configure Service...
Jan 30 13:58:48.383688 systemd[1]: Started locksmithd.service - Cluster reboot manager.
Jan 30 13:58:48.417314 coreos-metadata[1845]: Jan 30 13:58:48.413 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1
Jan 30 13:58:48.423311 coreos-metadata[1845]: Jan 30 13:58:48.420 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-id: Attempt #1
Jan 30 13:58:48.427509 coreos-metadata[1845]: Jan 30 13:58:48.426 INFO Fetch successful
Jan 30 13:58:48.427509 coreos-metadata[1845]: Jan 30 13:58:48.427 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-type: Attempt #1
Jan 30 13:58:48.428282 coreos-metadata[1845]: Jan 30 13:58:48.427 INFO Fetch successful
Jan 30 13:58:48.428282 coreos-metadata[1845]: Jan 30 13:58:48.428 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/local-ipv4: Attempt #1
Jan 30 13:58:48.429727 coreos-metadata[1845]: Jan 30 13:58:48.429 INFO Fetch successful
Jan 30 13:58:48.429727 coreos-metadata[1845]: Jan 30 13:58:48.429 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-ipv4: Attempt #1
Jan 30 13:58:48.436026 coreos-metadata[1845]: Jan 30 13:58:48.429 INFO Fetch successful
Jan 30 13:58:48.436026 coreos-metadata[1845]: Jan 30 13:58:48.429 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/ipv6: Attempt #1
Jan 30 13:58:48.436026 coreos-metadata[1845]: Jan 30 13:58:48.430 INFO Fetch failed with 404: resource not found
Jan 30 13:58:48.436026 coreos-metadata[1845]: Jan 30 13:58:48.430 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone: Attempt #1
Jan 30 13:58:48.436026 coreos-metadata[1845]: Jan 30 13:58:48.431 INFO Fetch successful
Jan 30 13:58:48.436026 coreos-metadata[1845]: Jan 30 13:58:48.431 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone-id: Attempt #1
Jan 30 13:58:48.443094 coreos-metadata[1845]: Jan 30 13:58:48.438 INFO Fetch successful
Jan 30 13:58:48.443094 coreos-metadata[1845]: Jan 30 13:58:48.438 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/hostname: Attempt #1
Jan 30 13:58:48.453724 coreos-metadata[1845]: Jan 30 13:58:48.448 INFO Fetch successful
Jan 30 13:58:48.453724 coreos-metadata[1845]: Jan 30 13:58:48.448 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-hostname: Attempt #1
Jan 30 13:58:48.454204 coreos-metadata[1845]: Jan 30 13:58:48.454 INFO Fetch successful
Jan 30 13:58:48.454204 coreos-metadata[1845]: Jan 30 13:58:48.454 INFO Fetching http://169.254.169.254/2021-01-03/dynamic/instance-identity/document: Attempt #1
Jan 30 13:58:48.456800 coreos-metadata[1845]: Jan 30 13:58:48.455 INFO Fetch successful
Jan 30 13:58:48.501297 kernel: EXT4-fs (nvme0n1p9): resized filesystem to 1489915
Jan 30 13:58:48.566643 bash[1924]: Updated "/home/core/.ssh/authorized_keys"
Jan 30 13:58:48.561924 systemd[1]: extend-filesystems.service: Deactivated successfully.
Jan 30 13:58:48.568660 extend-filesystems[1894]: Filesystem at /dev/nvme0n1p9 is mounted on /; on-line resizing required
Jan 30 13:58:48.568660 extend-filesystems[1894]: old_desc_blocks = 1, new_desc_blocks = 1
Jan 30 13:58:48.568660 extend-filesystems[1894]: The filesystem on /dev/nvme0n1p9 is now 1489915 (4k) blocks long.
Jan 30 13:58:48.562161 systemd[1]: Finished extend-filesystems.service - Extend Filesystems.
Jan 30 13:58:48.602574 extend-filesystems[1848]: Resized filesystem in /dev/nvme0n1p9
Jan 30 13:58:48.566555 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition.
Jan 30 13:58:48.601332 systemd[1]: Starting sshkeys.service...
Jan 30 13:58:48.645128 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent.
Jan 30 13:58:48.652951 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met.
Jan 30 13:58:48.669324 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 38 scanned by (udev-worker) (1716)
Jan 30 13:58:48.730939 systemd[1]: Finished nvidia.service - NVIDIA Configure Service.
Jan 30 13:58:48.758164 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys.
Jan 30 13:58:48.783054 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)...
Jan 30 13:58:48.839400 amazon-ssm-agent[1899]: Initializing new seelog logger
Jan 30 13:58:48.844331 amazon-ssm-agent[1899]: New Seelog Logger Creation Complete
Jan 30 13:58:48.844331 amazon-ssm-agent[1899]: 2025/01/30 13:58:48 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json.
Jan 30 13:58:48.844331 amazon-ssm-agent[1899]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json.
Jan 30 13:58:48.846033 dbus-daemon[1846]: [system] Successfully activated service 'org.freedesktop.hostname1'
Jan 30 13:58:48.848951 amazon-ssm-agent[1899]: 2025/01/30 13:58:48 processing appconfig overrides
Jan 30 13:58:48.846361 systemd[1]: Started systemd-hostnamed.service - Hostname Service.
Jan 30 13:58:48.851839 amazon-ssm-agent[1899]: 2025/01/30 13:58:48 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json.
Jan 30 13:58:48.851839 amazon-ssm-agent[1899]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json.
Jan 30 13:58:48.851839 amazon-ssm-agent[1899]: 2025/01/30 13:58:48 processing appconfig overrides
Jan 30 13:58:48.851839 amazon-ssm-agent[1899]: 2025/01/30 13:58:48 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json.
Jan 30 13:58:48.851839 amazon-ssm-agent[1899]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json.
Jan 30 13:58:48.851839 amazon-ssm-agent[1899]: 2025/01/30 13:58:48 processing appconfig overrides
Jan 30 13:58:48.853671 dbus-daemon[1846]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.5' (uid=0 pid=1885 comm="/usr/lib/systemd/systemd-hostnamed" label="system_u:system_r:kernel_t:s0")
Jan 30 13:58:48.871765 amazon-ssm-agent[1899]: 2025-01-30 13:58:48 INFO Proxy environment variables:
Jan 30 13:58:48.871535 systemd[1]: Starting polkit.service - Authorization Manager...
Jan 30 13:58:48.881133 amazon-ssm-agent[1899]: 2025/01/30 13:58:48 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json.
Jan 30 13:58:48.881133 amazon-ssm-agent[1899]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json.
Jan 30 13:58:48.881133 amazon-ssm-agent[1899]: 2025/01/30 13:58:48 processing appconfig overrides
Jan 30 13:58:48.915288 coreos-metadata[1965]: Jan 30 13:58:48.911 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1
Jan 30 13:58:48.915288 coreos-metadata[1965]: Jan 30 13:58:48.913 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys: Attempt #1
Jan 30 13:58:48.915288 coreos-metadata[1965]: Jan 30 13:58:48.914 INFO Fetch successful
Jan 30 13:58:48.915288 coreos-metadata[1965]: Jan 30 13:58:48.914 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys/0/openssh-key: Attempt #1
Jan 30 13:58:48.915288 coreos-metadata[1965]: Jan 30 13:58:48.914 INFO Fetch successful
Jan 30 13:58:48.916538 unknown[1965]: wrote ssh authorized keys file for user: core
Jan 30 13:58:48.975571 amazon-ssm-agent[1899]: 2025-01-30 13:58:48 INFO https_proxy:
Jan 30 13:58:49.000616 polkitd[1985]: Started polkitd version 121
Jan 30 13:58:49.011797 update-ssh-keys[1992]: Updated "/home/core/.ssh/authorized_keys"
Jan 30 13:58:49.013019 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys).
Jan 30 13:58:49.021613 systemd[1]: Finished sshkeys.service.
Jan 30 13:58:49.039056 polkitd[1985]: Loading rules from directory /etc/polkit-1/rules.d
Jan 30 13:58:49.048648 polkitd[1985]: Loading rules from directory /usr/share/polkit-1/rules.d
Jan 30 13:58:49.055213 polkitd[1985]: Finished loading, compiling and executing 2 rules
Jan 30 13:58:49.061944 dbus-daemon[1846]: [system] Successfully activated service 'org.freedesktop.PolicyKit1'
Jan 30 13:58:49.064374 systemd[1]: Started polkit.service - Authorization Manager.
Jan 30 13:58:49.068580 polkitd[1985]: Acquired the name org.freedesktop.PolicyKit1 on the system bus
Jan 30 13:58:49.083857 amazon-ssm-agent[1899]: 2025-01-30 13:58:48 INFO http_proxy:
Jan 30 13:58:49.162622 systemd-resolved[1675]: System hostname changed to 'ip-172-31-29-140'.
Jan 30 13:58:49.164440 systemd-hostnamed[1885]: Hostname set to <ip-172-31-29-140> (transient)
Jan 30 13:58:49.183733 sshd_keygen[1888]: ssh-keygen: generating new host keys: RSA ECDSA ED25519
Jan 30 13:58:49.194618 amazon-ssm-agent[1899]: 2025-01-30 13:58:48 INFO no_proxy:
Jan 30 13:58:49.238424 locksmithd[1903]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot"
Jan 30 13:58:49.246810 containerd[1882]: time="2025-01-30T13:58:49.245884671Z" level=info msg="starting containerd" revision=174e0d1785eeda18dc2beba45e1d5a188771636b version=v1.7.21
Jan 30 13:58:49.293279 amazon-ssm-agent[1899]: 2025-01-30 13:58:48 INFO Checking if agent identity type OnPrem can be assumed
Jan 30 13:58:49.303717 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys.
Jan 30 13:58:49.312779 systemd[1]: Starting issuegen.service - Generate /run/issue...
Jan 30 13:58:49.326672 containerd[1882]: time="2025-01-30T13:58:49.326589132Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
Jan 30 13:58:49.329910 containerd[1882]: time="2025-01-30T13:58:49.329864355Z" 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.74-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1
Jan 30 13:58:49.330028 containerd[1882]: time="2025-01-30T13:58:49.330013648Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
Jan 30 13:58:49.330098 containerd[1882]: time="2025-01-30T13:58:49.330085302Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
Jan 30 13:58:49.331505 containerd[1882]: time="2025-01-30T13:58:49.330357001Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1
Jan 30 13:58:49.331505 containerd[1882]: time="2025-01-30T13:58:49.330483666Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1
Jan 30 13:58:49.331505 containerd[1882]: time="2025-01-30T13:58:49.330566047Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1
Jan 30 13:58:49.331505 containerd[1882]: time="2025-01-30T13:58:49.330586429Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
Jan 30 13:58:49.331505 containerd[1882]: time="2025-01-30T13:58:49.330797195Z" 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 30 13:58:49.331505 containerd[1882]: time="2025-01-30T13:58:49.330814870Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
Jan 30 13:58:49.331505 containerd[1882]: time="2025-01-30T13:58:49.330830799Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1
Jan 30 13:58:49.331505 containerd[1882]: time="2025-01-30T13:58:49.330843610Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
Jan 30 13:58:49.331505 containerd[1882]: time="2025-01-30T13:58:49.330935956Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
Jan 30 13:58:49.331505 containerd[1882]: time="2025-01-30T13:58:49.331203077Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
Jan 30 13:58:49.331505 containerd[1882]: time="2025-01-30T13:58:49.331400914Z" 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 30 13:58:49.332010 containerd[1882]: time="2025-01-30T13:58:49.331423304Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
Jan 30 13:58:49.332247 containerd[1882]: time="2025-01-30T13:58:49.332221450Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
Jan 30 13:58:49.332479 containerd[1882]: time="2025-01-30T13:58:49.332458681Z" level=info msg="metadata content store policy set" policy=shared
Jan 30 13:58:49.343001 systemd[1]: issuegen.service: Deactivated successfully.
Jan 30 13:58:49.343469 systemd[1]: Finished issuegen.service - Generate /run/issue.
Jan 30 13:58:49.347414 containerd[1882]: time="2025-01-30T13:58:49.345839005Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
Jan 30 13:58:49.347414 containerd[1882]: time="2025-01-30T13:58:49.345914117Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
Jan 30 13:58:49.347414 containerd[1882]: time="2025-01-30T13:58:49.345937048Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1
Jan 30 13:58:49.347414 containerd[1882]: time="2025-01-30T13:58:49.345959645Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1
Jan 30 13:58:49.347414 containerd[1882]: time="2025-01-30T13:58:49.345986524Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
Jan 30 13:58:49.347414 containerd[1882]: time="2025-01-30T13:58:49.346166080Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
Jan 30 13:58:49.347414 containerd[1882]: time="2025-01-30T13:58:49.346510774Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
Jan 30 13:58:49.347414 containerd[1882]: time="2025-01-30T13:58:49.346646863Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2
Jan 30 13:58:49.347414 containerd[1882]: time="2025-01-30T13:58:49.346669613Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1
Jan 30 13:58:49.347414 containerd[1882]: time="2025-01-30T13:58:49.346692807Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1
Jan 30 13:58:49.347414 containerd[1882]: time="2025-01-30T13:58:49.346714424Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
Jan 30 13:58:49.347414 containerd[1882]: time="2025-01-30T13:58:49.346735372Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
Jan 30 13:58:49.347414 containerd[1882]: time="2025-01-30T13:58:49.346754949Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
Jan 30 13:58:49.347414 containerd[1882]: time="2025-01-30T13:58:49.346778858Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
Jan 30 13:58:49.347951 containerd[1882]: time="2025-01-30T13:58:49.346801190Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
Jan 30 13:58:49.347951 containerd[1882]: time="2025-01-30T13:58:49.346819719Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
Jan 30 13:58:49.347951 containerd[1882]: time="2025-01-30T13:58:49.346838517Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
Jan 30 13:58:49.347951 containerd[1882]: time="2025-01-30T13:58:49.346860110Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
Jan 30 13:58:49.347951 containerd[1882]: time="2025-01-30T13:58:49.346903785Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
Jan 30 13:58:49.347951 containerd[1882]: time="2025-01-30T13:58:49.346937640Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
Jan 30 13:58:49.347951 containerd[1882]: time="2025-01-30T13:58:49.346958809Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
Jan 30 13:58:49.347951 containerd[1882]: time="2025-01-30T13:58:49.346996371Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
Jan 30 13:58:49.347951 containerd[1882]: time="2025-01-30T13:58:49.347013165Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
Jan 30 13:58:49.347951 containerd[1882]: time="2025-01-30T13:58:49.347032311Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
Jan 30 13:58:49.347951 containerd[1882]: time="2025-01-30T13:58:49.347050661Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
Jan 30 13:58:49.347951 containerd[1882]: time="2025-01-30T13:58:49.347072472Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
Jan 30 13:58:49.347951 containerd[1882]: time="2025-01-30T13:58:49.347093425Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1
Jan 30 13:58:49.347951 containerd[1882]: time="2025-01-30T13:58:49.347116651Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1
Jan 30 13:58:49.353640 containerd[1882]: time="2025-01-30T13:58:49.347136639Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
Jan 30 13:58:49.353640 containerd[1882]: time="2025-01-30T13:58:49.347156608Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1
Jan 30 13:58:49.353640 containerd[1882]: time="2025-01-30T13:58:49.347175320Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
Jan 30 13:58:49.353640 containerd[1882]: time="2025-01-30T13:58:49.347198442Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1
Jan 30 13:58:49.353640 containerd[1882]: time="2025-01-30T13:58:49.347242755Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1
Jan 30 13:58:49.353640 containerd[1882]: time="2025-01-30T13:58:49.347277037Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
Jan 30 13:58:49.353640 containerd[1882]: time="2025-01-30T13:58:49.347292378Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
Jan 30 13:58:49.353640 containerd[1882]: time="2025-01-30T13:58:49.347344624Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
Jan 30 13:58:49.353640 containerd[1882]: time="2025-01-30T13:58:49.347369920Z" 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 30 13:58:49.353640 containerd[1882]: time="2025-01-30T13:58:49.347383996Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
Jan 30 13:58:49.353640 containerd[1882]: time="2025-01-30T13:58:49.347399477Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1
Jan 30 13:58:49.353640 containerd[1882]: time="2025-01-30T13:58:49.347413224Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
Jan 30 13:58:49.353640 containerd[1882]: time="2025-01-30T13:58:49.347428707Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1
Jan 30 13:58:49.353640 containerd[1882]: time="2025-01-30T13:58:49.347442600Z" level=info msg="NRI interface is disabled by configuration."
Jan 30 13:58:49.354626 containerd[1882]: time="2025-01-30T13:58:49.347455252Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1
Jan 30 13:58:49.354435 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions...
Jan 30 13:58:49.354736 containerd[1882]: time="2025-01-30T13:58:49.347867668Z" 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 30 13:58:49.354736 containerd[1882]: time="2025-01-30T13:58:49.347953571Z" level=info msg="Connect containerd service"
Jan 30 13:58:49.354736 containerd[1882]: time="2025-01-30T13:58:49.348003809Z" level=info msg="using legacy CRI server"
Jan 30 13:58:49.354736 containerd[1882]: time="2025-01-30T13:58:49.348015311Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this"
Jan 30 13:58:49.354736 containerd[1882]: time="2025-01-30T13:58:49.348148575Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\""
Jan 30 13:58:49.354736 containerd[1882]: time="2025-01-30T13:58:49.351370833Z" 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 30 13:58:49.354736 containerd[1882]: time="2025-01-30T13:58:49.351935042Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc
Jan 30 13:58:49.354736 containerd[1882]: time="2025-01-30T13:58:49.352017942Z" level=info msg=serving... address=/run/containerd/containerd.sock
Jan 30 13:58:49.354736 containerd[1882]: time="2025-01-30T13:58:49.352082876Z" level=info msg="Start subscribing containerd event"
Jan 30 13:58:49.354736 containerd[1882]: time="2025-01-30T13:58:49.352156200Z" level=info msg="Start recovering state"
Jan 30 13:58:49.354736 containerd[1882]: time="2025-01-30T13:58:49.353360345Z" level=info msg="Start event monitor"
Jan 30 13:58:49.354736 containerd[1882]: time="2025-01-30T13:58:49.353393865Z" level=info msg="Start snapshots syncer"
Jan 30 13:58:49.354736 containerd[1882]: time="2025-01-30T13:58:49.353424558Z" level=info msg="Start cni network conf syncer for default"
Jan 30 13:58:49.354736 containerd[1882]: time="2025-01-30T13:58:49.353434479Z" level=info msg="Start streaming server"
Jan 30 13:58:49.354736 containerd[1882]: time="2025-01-30T13:58:49.353520100Z" level=info msg="containerd successfully booted in 0.108686s"
Jan 30 13:58:49.356096 systemd[1]: Started containerd.service - containerd container runtime.
Jan 30 13:58:49.388414 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions.
Jan 30 13:58:49.393531 amazon-ssm-agent[1899]: 2025-01-30 13:58:48 INFO Checking if agent identity type EC2 can be assumed
Jan 30 13:58:49.402901 systemd[1]: Started getty@tty1.service - Getty on tty1.
Jan 30 13:58:49.412820 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0.
Jan 30 13:58:49.414741 systemd[1]: Reached target getty.target - Login Prompts.
Jan 30 13:58:49.492629 amazon-ssm-agent[1899]: 2025-01-30 13:58:49 INFO Agent will take identity from EC2
Jan 30 13:58:49.591518 amazon-ssm-agent[1899]: 2025-01-30 13:58:49 INFO [amazon-ssm-agent] using named pipe channel for IPC
Jan 30 13:58:49.690477 amazon-ssm-agent[1899]: 2025-01-30 13:58:49 INFO [amazon-ssm-agent] using named pipe channel for IPC
Jan 30 13:58:49.775810 amazon-ssm-agent[1899]: 2025-01-30 13:58:49 INFO [amazon-ssm-agent] using named pipe channel for IPC
Jan 30 13:58:49.775810 amazon-ssm-agent[1899]: 2025-01-30 13:58:49 INFO [amazon-ssm-agent] amazon-ssm-agent - v3.2.0.0
Jan 30 13:58:49.775810 amazon-ssm-agent[1899]: 2025-01-30 13:58:49 INFO [amazon-ssm-agent] OS: linux, Arch: amd64
Jan 30 13:58:49.775810 amazon-ssm-agent[1899]: 2025-01-30 13:58:49 INFO [amazon-ssm-agent] Starting Core Agent
Jan 30 13:58:49.775810 amazon-ssm-agent[1899]: 2025-01-30 13:58:49 INFO [amazon-ssm-agent] registrar detected. Attempting registration
Jan 30 13:58:49.775810 amazon-ssm-agent[1899]: 2025-01-30 13:58:49 INFO [Registrar] Starting registrar module
Jan 30 13:58:49.775810 amazon-ssm-agent[1899]: 2025-01-30 13:58:49 INFO [EC2Identity] no registration info found for ec2 instance, attempting registration
Jan 30 13:58:49.775810 amazon-ssm-agent[1899]: 2025-01-30 13:58:49 INFO [EC2Identity] EC2 registration was successful.
Jan 30 13:58:49.775810 amazon-ssm-agent[1899]: 2025-01-30 13:58:49 INFO [CredentialRefresher] credentialRefresher has started
Jan 30 13:58:49.775810 amazon-ssm-agent[1899]: 2025-01-30 13:58:49 INFO [CredentialRefresher] Starting credentials refresher loop
Jan 30 13:58:49.775810 amazon-ssm-agent[1899]: 2025-01-30 13:58:49 INFO EC2RoleProvider Successfully connected with instance profile role credentials
Jan 30 13:58:49.790084 amazon-ssm-agent[1899]: 2025-01-30 13:58:49 INFO [CredentialRefresher] Next credential rotation will be in 31.4083270083 minutes
Jan 30 13:58:50.820027 amazon-ssm-agent[1899]: 2025-01-30 13:58:50 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker is not running, starting worker process
Jan 30 13:58:50.923507 amazon-ssm-agent[1899]: 2025-01-30 13:58:50 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker (pid:2085) started
Jan 30 13:58:51.026733 amazon-ssm-agent[1899]: 2025-01-30 13:58:50 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] Monitor long running worker health every 60 seconds
Jan 30 13:58:51.155324 ntpd[1850]: Listen normally on 6 eth0 [fe80::4d1:75ff:fef3:c0a7%2]:123
Jan 30 13:58:51.155981 ntpd[1850]: 30 Jan 13:58:51 ntpd[1850]: Listen normally on 6 eth0 [fe80::4d1:75ff:fef3:c0a7%2]:123
Jan 30 13:58:51.245678 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd.
Jan 30 13:58:51.266933 systemd[1]: Started sshd@0-172.31.29.140:22-139.178.68.195:36968.service - OpenSSH per-connection server daemon (139.178.68.195:36968).
Jan 30 13:58:51.462565 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent.
Jan 30 13:58:51.472250 systemd[1]: Reached target multi-user.target - Multi-User System.
Jan 30 13:58:51.477567 systemd[1]: Startup finished in 899ms (kernel) + 9.389s (initrd) + 9.347s (userspace) = 19.636s.
Jan 30 13:58:51.482220 (kubelet)[2103]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS
Jan 30 13:58:51.614582 sshd[2096]: Accepted publickey for core from 139.178.68.195 port 36968 ssh2: RSA SHA256:sOLO7fjvEAJcFfGBVs/wB6N/GT+9eFp4KClrVR9nxhs
Jan 30 13:58:51.620560 sshd[2096]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Jan 30 13:58:51.648725 systemd[1]: Created slice user-500.slice - User Slice of UID 500.
Jan 30 13:58:51.664815 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500...
Jan 30 13:58:51.669370 systemd-logind[1857]: New session 1 of user core.
Jan 30 13:58:51.701127 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500.
Jan 30 13:58:51.707862 systemd[1]: Starting user@500.service - User Manager for UID 500...
Jan 30 13:58:51.728405 (systemd)[2110]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0)
Jan 30 13:58:52.004344 systemd[2110]: Queued start job for default target default.target.
Jan 30 13:58:52.013380 systemd[2110]: Created slice app.slice - User Application Slice.
Jan 30 13:58:52.013427 systemd[2110]: Reached target paths.target - Paths.
Jan 30 13:58:52.013448 systemd[2110]: Reached target timers.target - Timers.
Jan 30 13:58:52.021465 systemd[2110]: Starting dbus.socket - D-Bus User Message Bus Socket...
Jan 30 13:58:52.041476 systemd[2110]: Listening on dbus.socket - D-Bus User Message Bus Socket.
Jan 30 13:58:52.041738 systemd[2110]: Reached target sockets.target - Sockets.
Jan 30 13:58:52.041799 systemd[2110]: Reached target basic.target - Basic System.
Jan 30 13:58:52.041993 systemd[1]: Started user@500.service - User Manager for UID 500.
Jan 30 13:58:52.043858 systemd[2110]: Reached target default.target - Main User Target.
Jan 30 13:58:52.043994 systemd[2110]: Startup finished in 307ms.
Jan 30 13:58:52.050274 systemd[1]: Started session-1.scope - Session 1 of User core.
Jan 30 13:58:52.260068 systemd[1]: Started sshd@1-172.31.29.140:22-139.178.68.195:36984.service - OpenSSH per-connection server daemon (139.178.68.195:36984).
Jan 30 13:58:52.484394 sshd[2125]: Accepted publickey for core from 139.178.68.195 port 36984 ssh2: RSA SHA256:sOLO7fjvEAJcFfGBVs/wB6N/GT+9eFp4KClrVR9nxhs
Jan 30 13:58:52.485827 sshd[2125]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Jan 30 13:58:52.499488 systemd-logind[1857]: New session 2 of user core.
Jan 30 13:58:52.507966 systemd[1]: Started session-2.scope - Session 2 of User core.
Jan 30 13:58:52.655166 sshd[2125]: pam_unix(sshd:session): session closed for user core
Jan 30 13:58:52.664442 systemd[1]: sshd@1-172.31.29.140:22-139.178.68.195:36984.service: Deactivated successfully.
Jan 30 13:58:52.680123 systemd[1]: session-2.scope: Deactivated successfully.
Jan 30 13:58:52.681938 systemd-logind[1857]: Session 2 logged out. Waiting for processes to exit.
Jan 30 13:58:52.716867 systemd[1]: Started sshd@2-172.31.29.140:22-139.178.68.195:36990.service - OpenSSH per-connection server daemon (139.178.68.195:36990).
Jan 30 13:58:52.722292 systemd-logind[1857]: Removed session 2.
Jan 30 13:58:52.906809 sshd[2133]: Accepted publickey for core from 139.178.68.195 port 36990 ssh2: RSA SHA256:sOLO7fjvEAJcFfGBVs/wB6N/GT+9eFp4KClrVR9nxhs
Jan 30 13:58:52.910479 sshd[2133]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Jan 30 13:58:52.932689 systemd-logind[1857]: New session 3 of user core.
Jan 30 13:58:52.938873 systemd[1]: Started session-3.scope - Session 3 of User core.
Jan 30 13:58:53.080742 sshd[2133]: pam_unix(sshd:session): session closed for user core
Jan 30 13:58:53.099660 systemd[1]: sshd@2-172.31.29.140:22-139.178.68.195:36990.service: Deactivated successfully.
Jan 30 13:58:53.107621 systemd[1]: session-3.scope: Deactivated successfully.
Jan 30 13:58:53.124971 systemd-logind[1857]: Session 3 logged out. Waiting for processes to exit.
Jan 30 13:58:53.134462 systemd[1]: Started sshd@3-172.31.29.140:22-139.178.68.195:37002.service - OpenSSH per-connection server daemon (139.178.68.195:37002).
Jan 30 13:58:53.140973 systemd-logind[1857]: Removed session 3.
Jan 30 13:58:53.221566 kubelet[2103]: E0130 13:58:53.221404    2103 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory"
Jan 30 13:58:53.224459 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE
Jan 30 13:58:53.224648 systemd[1]: kubelet.service: Failed with result 'exit-code'.
Jan 30 13:58:53.225109 systemd[1]: kubelet.service: Consumed 1.061s CPU time.
Jan 30 13:58:53.301521 sshd[2140]: Accepted publickey for core from 139.178.68.195 port 37002 ssh2: RSA SHA256:sOLO7fjvEAJcFfGBVs/wB6N/GT+9eFp4KClrVR9nxhs
Jan 30 13:58:53.303169 sshd[2140]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Jan 30 13:58:53.317488 systemd-logind[1857]: New session 4 of user core.
Jan 30 13:58:53.321506 systemd[1]: Started session-4.scope - Session 4 of User core.
Jan 30 13:58:53.463919 sshd[2140]: pam_unix(sshd:session): session closed for user core
Jan 30 13:58:53.467788 systemd[1]: sshd@3-172.31.29.140:22-139.178.68.195:37002.service: Deactivated successfully.
Jan 30 13:58:53.473713 systemd[1]: session-4.scope: Deactivated successfully.
Jan 30 13:58:53.479487 systemd-logind[1857]: Session 4 logged out. Waiting for processes to exit.
Jan 30 13:58:53.483065 systemd-logind[1857]: Removed session 4.
Jan 30 13:58:53.504837 systemd[1]: Started sshd@4-172.31.29.140:22-139.178.68.195:37008.service - OpenSSH per-connection server daemon (139.178.68.195:37008).
Jan 30 13:58:53.683662 sshd[2148]: Accepted publickey for core from 139.178.68.195 port 37008 ssh2: RSA SHA256:sOLO7fjvEAJcFfGBVs/wB6N/GT+9eFp4KClrVR9nxhs
Jan 30 13:58:53.685241 sshd[2148]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Jan 30 13:58:53.691438 systemd-logind[1857]: New session 5 of user core.
Jan 30 13:58:53.700785 systemd[1]: Started session-5.scope - Session 5 of User core.
Jan 30 13:58:53.845002 sudo[2151]:     core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh
Jan 30 13:58:53.845765 sudo[2151]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500)
Jan 30 13:58:55.488418 systemd-resolved[1675]: Clock change detected. Flushing caches.
Jan 30 13:58:55.717929 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent.
Jan 30 13:58:55.718172 systemd[1]: kubelet.service: Consumed 1.061s CPU time.
Jan 30 13:58:55.730434 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent...
Jan 30 13:58:55.834624 systemd[1]: Reloading requested from client PID 2185 ('systemctl') (unit session-5.scope)...
Jan 30 13:58:55.834834 systemd[1]: Reloading...
Jan 30 13:58:56.150577 zram_generator::config[2222]: No configuration found.
Jan 30 13:58:56.420917 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 30 13:58:56.580141 systemd[1]: Reloading finished in 744 ms.
Jan 30 13:58:56.691770 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM
Jan 30 13:58:56.691880 systemd[1]: kubelet.service: Failed with result 'signal'.
Jan 30 13:58:56.692317 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent.
Jan 30 13:58:56.699043 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent...
Jan 30 13:58:57.017788 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent.
Jan 30 13:58:57.036772 (kubelet)[2285]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS
Jan 30 13:58:57.122627 kubelet[2285]: 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 30 13:58:57.122627 kubelet[2285]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI.
Jan 30 13:58:57.122627 kubelet[2285]: 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 30 13:58:57.124842 kubelet[2285]: I0130 13:58:57.122737    2285 server.go:215] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime"
Jan 30 13:58:57.740584 kubelet[2285]: I0130 13:58:57.740537    2285 server.go:520] "Kubelet version" kubeletVersion="v1.32.0"
Jan 30 13:58:57.740584 kubelet[2285]: I0130 13:58:57.740572    2285 server.go:522] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK=""
Jan 30 13:58:57.741035 kubelet[2285]: I0130 13:58:57.741010    2285 server.go:954] "Client rotation is on, will bootstrap in background"
Jan 30 13:58:57.810352 kubelet[2285]: I0130 13:58:57.809616    2285 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt"
Jan 30 13:58:57.828898 kubelet[2285]: E0130 13:58:57.828842    2285 log.go:32] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService"
Jan 30 13:58:57.828898 kubelet[2285]: I0130 13:58:57.828897    2285 server.go:1421] "CRI implementation should be updated to support RuntimeConfig when KubeletCgroupDriverFromCRI feature gate has been enabled. Falling back to using cgroupDriver from kubelet config."
Jan 30 13:58:57.831535 kubelet[2285]: I0130 13:58:57.831506    2285 server.go:772] "--cgroups-per-qos enabled, but --cgroup-root was not specified.  defaulting to /"
Jan 30 13:58:57.831794 kubelet[2285]: I0130 13:58:57.831754    2285 container_manager_linux.go:268] "Container manager verified user specified cgroup-root exists" cgroupRoot=[]
Jan 30 13:58:57.831993 kubelet[2285]: I0130 13:58:57.831796    2285 container_manager_linux.go:273] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"172.31.29.140","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,"CgroupVersion":2}
Jan 30 13:58:57.832230 kubelet[2285]: I0130 13:58:57.831995    2285 topology_manager.go:138] "Creating topology manager with none policy"
Jan 30 13:58:57.832230 kubelet[2285]: I0130 13:58:57.832010    2285 container_manager_linux.go:304] "Creating device plugin manager"
Jan 30 13:58:57.832230 kubelet[2285]: I0130 13:58:57.832215    2285 state_mem.go:36] "Initialized new in-memory state store"
Jan 30 13:58:57.838362 kubelet[2285]: I0130 13:58:57.838324    2285 kubelet.go:446] "Attempting to sync node with API server"
Jan 30 13:58:57.838362 kubelet[2285]: I0130 13:58:57.838355    2285 kubelet.go:341] "Adding static pod path" path="/etc/kubernetes/manifests"
Jan 30 13:58:57.840389 kubelet[2285]: I0130 13:58:57.838382    2285 kubelet.go:352] "Adding apiserver pod source"
Jan 30 13:58:57.840389 kubelet[2285]: I0130 13:58:57.838395    2285 apiserver.go:42] "Waiting for node sync before watching apiserver pods"
Jan 30 13:58:57.840389 kubelet[2285]: E0130 13:58:57.838798    2285 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:58:57.840389 kubelet[2285]: E0130 13:58:57.839066    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:58:57.849613 kubelet[2285]: I0130 13:58:57.849543    2285 kuberuntime_manager.go:269] "Container runtime initialized" containerRuntime="containerd" version="v1.7.21" apiVersion="v1"
Jan 30 13:58:57.853266 kubelet[2285]: I0130 13:58:57.852582    2285 kubelet.go:890] "Not starting ClusterTrustBundle informer because we are in static kubelet mode"
Jan 30 13:58:57.858077 kubelet[2285]: W0130 13:58:57.856968    2285 reflector.go:569] 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 30 13:58:57.858077 kubelet[2285]: E0130 13:58:57.857073    2285 reflector.go:166] "Unhandled Error" err="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" logger="UnhandledError"
Jan 30 13:58:57.858288 kubelet[2285]: W0130 13:58:57.858270    2285 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating.
Jan 30 13:58:57.866020 kubelet[2285]: I0130 13:58:57.865984    2285 watchdog_linux.go:99] "Systemd watchdog is not enabled"
Jan 30 13:58:57.866168 kubelet[2285]: I0130 13:58:57.866044    2285 server.go:1287] "Started kubelet"
Jan 30 13:58:57.866403 kubelet[2285]: I0130 13:58:57.866254    2285 server.go:169] "Starting to listen" address="0.0.0.0" port=10250
Jan 30 13:58:57.870596 kubelet[2285]: I0130 13:58:57.869841    2285 server.go:490] "Adding debug handlers to kubelet server"
Jan 30 13:58:57.880220 kubelet[2285]: I0130 13:58:57.880045    2285 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10
Jan 30 13:58:57.881517 kubelet[2285]: I0130 13:58:57.881454    2285 server.go:243] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock"
Jan 30 13:58:57.891907 kubelet[2285]: I0130 13:58:57.891875    2285 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer"
Jan 30 13:58:57.894482 kubelet[2285]: I0130 13:58:57.894441    2285 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key"
Jan 30 13:58:57.906062 kubelet[2285]: E0130 13:58:57.904473    2285 kubelet.go:1561] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem"
Jan 30 13:58:57.906062 kubelet[2285]: E0130 13:58:57.904532    2285 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"172.31.29.140\" not found"
Jan 30 13:58:57.906062 kubelet[2285]: I0130 13:58:57.904806    2285 desired_state_of_world_populator.go:149] "Desired state populator starts to run"
Jan 30 13:58:57.906062 kubelet[2285]: I0130 13:58:57.905027    2285 volume_manager.go:297] "Starting Kubelet Volume Manager"
Jan 30 13:58:57.906062 kubelet[2285]: I0130 13:58:57.905121    2285 reconciler.go:26] "Reconciler: start to sync state"
Jan 30 13:58:57.906609 kubelet[2285]: I0130 13:58:57.906116    2285 factory.go:221] Registration of the systemd container factory successfully
Jan 30 13:58:57.906609 kubelet[2285]: I0130 13:58:57.906241    2285 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 30 13:58:57.907435 kubelet[2285]: I0130 13:58:57.907409    2285 factory.go:221] Registration of the containerd container factory successfully
Jan 30 13:58:57.929544 kubelet[2285]: E0130 13:58:57.928283    2285 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"172.31.29.140\" not found" node="172.31.29.140"
Jan 30 13:58:57.933438 kubelet[2285]: I0130 13:58:57.933240    2285 cpu_manager.go:221] "Starting CPU manager" policy="none"
Jan 30 13:58:57.933438 kubelet[2285]: I0130 13:58:57.933257    2285 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s"
Jan 30 13:58:57.933438 kubelet[2285]: I0130 13:58:57.933294    2285 state_mem.go:36] "Initialized new in-memory state store"
Jan 30 13:58:57.937410 kubelet[2285]: I0130 13:58:57.936974    2285 policy_none.go:49] "None policy: Start"
Jan 30 13:58:57.937410 kubelet[2285]: I0130 13:58:57.936999    2285 memory_manager.go:186] "Starting memorymanager" policy="None"
Jan 30 13:58:57.937410 kubelet[2285]: I0130 13:58:57.937015    2285 state_mem.go:35] "Initializing new in-memory state store"
Jan 30 13:58:57.960435 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice.
Jan 30 13:58:58.008545 kubelet[2285]: E0130 13:58:58.005217    2285 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"172.31.29.140\" not found"
Jan 30 13:58:58.010989 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice.
Jan 30 13:58:58.031507 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice.
Jan 30 13:58:58.041662 kubelet[2285]: I0130 13:58:58.041633    2285 manager.go:519] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found"
Jan 30 13:58:58.043066 kubelet[2285]: I0130 13:58:58.042345    2285 eviction_manager.go:189] "Eviction manager: starting control loop"
Jan 30 13:58:58.043066 kubelet[2285]: I0130 13:58:58.042397    2285 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s"
Jan 30 13:58:58.043066 kubelet[2285]: I0130 13:58:58.042768    2285 plugin_manager.go:118] "Starting Kubelet Plugin Manager"
Jan 30 13:58:58.049239 kubelet[2285]: E0130 13:58:58.047611    2285 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime"
Jan 30 13:58:58.049239 kubelet[2285]: E0130 13:58:58.047727    2285 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"172.31.29.140\" not found"
Jan 30 13:58:58.089451 kubelet[2285]: I0130 13:58:58.089404    2285 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4"
Jan 30 13:58:58.092673 kubelet[2285]: I0130 13:58:58.091992    2285 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6"
Jan 30 13:58:58.092673 kubelet[2285]: I0130 13:58:58.092057    2285 status_manager.go:227] "Starting to sync pod status with apiserver"
Jan 30 13:58:58.092673 kubelet[2285]: I0130 13:58:58.092264    2285 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started."
Jan 30 13:58:58.092673 kubelet[2285]: I0130 13:58:58.092277    2285 kubelet.go:2388] "Starting kubelet main sync loop"
Jan 30 13:58:58.092673 kubelet[2285]: E0130 13:58:58.092451    2285 kubelet.go:2412] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful"
Jan 30 13:58:58.143812 kubelet[2285]: I0130 13:58:58.143782    2285 kubelet_node_status.go:76] "Attempting to register node" node="172.31.29.140"
Jan 30 13:58:58.155359 kubelet[2285]: I0130 13:58:58.155277    2285 kubelet_node_status.go:79] "Successfully registered node" node="172.31.29.140"
Jan 30 13:58:58.155359 kubelet[2285]: E0130 13:58:58.155316    2285 kubelet_node_status.go:549] "Error updating node status, will retry" err="error getting node \"172.31.29.140\": node \"172.31.29.140\" not found"
Jan 30 13:58:58.279835 kubelet[2285]: I0130 13:58:58.279712    2285 kuberuntime_manager.go:1702] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24"
Jan 30 13:58:58.281236 containerd[1882]: time="2025-01-30T13:58:58.281038137Z" level=info msg="No cni config template is specified, wait for other system components to drop the config."
Jan 30 13:58:58.282856 kubelet[2285]: I0130 13:58:58.282821    2285 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24"
Jan 30 13:58:58.581605 sudo[2151]: pam_unix(sudo:session): session closed for user root
Jan 30 13:58:58.605569 sshd[2148]: pam_unix(sshd:session): session closed for user core
Jan 30 13:58:58.612934 systemd[1]: sshd@4-172.31.29.140:22-139.178.68.195:37008.service: Deactivated successfully.
Jan 30 13:58:58.616961 systemd[1]: session-5.scope: Deactivated successfully.
Jan 30 13:58:58.619008 systemd-logind[1857]: Session 5 logged out. Waiting for processes to exit.
Jan 30 13:58:58.621322 systemd-logind[1857]: Removed session 5.
Jan 30 13:58:58.743193 kubelet[2285]: I0130 13:58:58.743145    2285 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials"
Jan 30 13:58:58.743401 kubelet[2285]: W0130 13:58:58.743366    2285 reflector.go:492] k8s.io/client-go/informers/factory.go:160: watch of *v1.RuntimeClass ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received
Jan 30 13:58:58.743471 kubelet[2285]: W0130 13:58:58.743418    2285 reflector.go:492] k8s.io/client-go/informers/factory.go:160: watch of *v1.CSIDriver ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received
Jan 30 13:58:58.839397 kubelet[2285]: I0130 13:58:58.839215    2285 apiserver.go:52] "Watching apiserver"
Jan 30 13:58:58.839397 kubelet[2285]: E0130 13:58:58.839219    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:58:58.862570 systemd[1]: Created slice kubepods-besteffort-podfcbf01e9_2b6d_4496_8130_5a03dce7f79d.slice - libcontainer container kubepods-besteffort-podfcbf01e9_2b6d_4496_8130_5a03dce7f79d.slice.
Jan 30 13:58:58.888932 systemd[1]: Created slice kubepods-burstable-pod31868794_b7dc_4723_bb31_986c73dc23a0.slice - libcontainer container kubepods-burstable-pod31868794_b7dc_4723_bb31_986c73dc23a0.slice.
Jan 30 13:58:58.905941 kubelet[2285]: I0130 13:58:58.905900    2285 desired_state_of_world_populator.go:157] "Finished populating initial desired state of world"
Jan 30 13:58:58.915213 kubelet[2285]: I0130 13:58:58.915058    2285 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/31868794-b7dc-4723-bb31-986c73dc23a0-xtables-lock\") pod \"kube-flannel-ds-jlhs4\" (UID: \"31868794-b7dc-4723-bb31-986c73dc23a0\") " pod="kube-flannel/kube-flannel-ds-jlhs4"
Jan 30 13:58:58.915213 kubelet[2285]: I0130 13:58:58.915130    2285 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-wv9hp\" (UniqueName: \"kubernetes.io/projected/31868794-b7dc-4723-bb31-986c73dc23a0-kube-api-access-wv9hp\") pod \"kube-flannel-ds-jlhs4\" (UID: \"31868794-b7dc-4723-bb31-986c73dc23a0\") " pod="kube-flannel/kube-flannel-ds-jlhs4"
Jan 30 13:58:58.915213 kubelet[2285]: I0130 13:58:58.915203    2285 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/fcbf01e9-2b6d-4496-8130-5a03dce7f79d-kube-proxy\") pod \"kube-proxy-snn72\" (UID: \"fcbf01e9-2b6d-4496-8130-5a03dce7f79d\") " pod="kube-system/kube-proxy-snn72"
Jan 30 13:58:58.915213 kubelet[2285]: I0130 13:58:58.915227    2285 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/fcbf01e9-2b6d-4496-8130-5a03dce7f79d-xtables-lock\") pod \"kube-proxy-snn72\" (UID: \"fcbf01e9-2b6d-4496-8130-5a03dce7f79d\") " pod="kube-system/kube-proxy-snn72"
Jan 30 13:58:58.915645 kubelet[2285]: I0130 13:58:58.915262    2285 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/fcbf01e9-2b6d-4496-8130-5a03dce7f79d-lib-modules\") pod \"kube-proxy-snn72\" (UID: \"fcbf01e9-2b6d-4496-8130-5a03dce7f79d\") " pod="kube-system/kube-proxy-snn72"
Jan 30 13:58:58.915645 kubelet[2285]: I0130 13:58:58.915285    2285 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/31868794-b7dc-4723-bb31-986c73dc23a0-cni\") pod \"kube-flannel-ds-jlhs4\" (UID: \"31868794-b7dc-4723-bb31-986c73dc23a0\") " pod="kube-flannel/kube-flannel-ds-jlhs4"
Jan 30 13:58:58.915645 kubelet[2285]: I0130 13:58:58.915311    2285 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-9w77l\" (UniqueName: \"kubernetes.io/projected/fcbf01e9-2b6d-4496-8130-5a03dce7f79d-kube-api-access-9w77l\") pod \"kube-proxy-snn72\" (UID: \"fcbf01e9-2b6d-4496-8130-5a03dce7f79d\") " pod="kube-system/kube-proxy-snn72"
Jan 30 13:58:58.915645 kubelet[2285]: I0130 13:58:58.915330    2285 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/31868794-b7dc-4723-bb31-986c73dc23a0-run\") pod \"kube-flannel-ds-jlhs4\" (UID: \"31868794-b7dc-4723-bb31-986c73dc23a0\") " pod="kube-flannel/kube-flannel-ds-jlhs4"
Jan 30 13:58:58.915645 kubelet[2285]: I0130 13:58:58.915365    2285 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/31868794-b7dc-4723-bb31-986c73dc23a0-cni-plugin\") pod \"kube-flannel-ds-jlhs4\" (UID: \"31868794-b7dc-4723-bb31-986c73dc23a0\") " pod="kube-flannel/kube-flannel-ds-jlhs4"
Jan 30 13:58:58.915808 kubelet[2285]: I0130 13:58:58.915387    2285 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/31868794-b7dc-4723-bb31-986c73dc23a0-flannel-cfg\") pod \"kube-flannel-ds-jlhs4\" (UID: \"31868794-b7dc-4723-bb31-986c73dc23a0\") " pod="kube-flannel/kube-flannel-ds-jlhs4"
Jan 30 13:58:59.180436 containerd[1882]: time="2025-01-30T13:58:59.180267501Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-snn72,Uid:fcbf01e9-2b6d-4496-8130-5a03dce7f79d,Namespace:kube-system,Attempt:0,}"
Jan 30 13:58:59.208451 containerd[1882]: time="2025-01-30T13:58:59.204453067Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-jlhs4,Uid:31868794-b7dc-4723-bb31-986c73dc23a0,Namespace:kube-flannel,Attempt:0,}"
Jan 30 13:58:59.843824 kubelet[2285]: E0130 13:58:59.842924    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:58:59.845102 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount611952798.mount: Deactivated successfully.
Jan 30 13:58:59.865384 containerd[1882]: time="2025-01-30T13:58:59.865331538Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0"
Jan 30 13:58:59.865952 containerd[1882]: time="2025-01-30T13:58:59.865914540Z" 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 30 13:58:59.866709 containerd[1882]: time="2025-01-30T13:58:59.866673753Z" level=info msg="ImageCreate event name:\"sha256:4873874c08efc72e9729683a83ffbb7502ee729e9a5ac097723806ea7fa13517\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}"
Jan 30 13:58:59.867770 containerd[1882]: time="2025-01-30T13:58:59.867733404Z" 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 30 13:58:59.868664 containerd[1882]: time="2025-01-30T13:58:59.868636899Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=312056"
Jan 30 13:58:59.870525 containerd[1882]: time="2025-01-30T13:58:59.870454234Z" 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 30 13:58:59.872170 containerd[1882]: time="2025-01-30T13:58:59.871366561Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.8\" with image id \"sha256:4873874c08efc72e9729683a83ffbb7502ee729e9a5ac097723806ea7fa13517\", repo tag \"registry.k8s.io/pause:3.8\", repo digest \"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\", size \"311286\" in 690.962853ms"
Jan 30 13:58:59.873923 containerd[1882]: time="2025-01-30T13:58:59.873884773Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.8\" with image id \"sha256:4873874c08efc72e9729683a83ffbb7502ee729e9a5ac097723806ea7fa13517\", repo tag \"registry.k8s.io/pause:3.8\", repo digest \"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\", size \"311286\" in 667.863952ms"
Jan 30 13:59:00.171658 containerd[1882]: time="2025-01-30T13:59:00.170889101Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1
Jan 30 13:59:00.172412 containerd[1882]: time="2025-01-30T13:59:00.171842953Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1
Jan 30 13:59:00.172647 containerd[1882]: time="2025-01-30T13:59:00.172591888Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jan 30 13:59:00.177605 containerd[1882]: time="2025-01-30T13:59:00.176945213Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jan 30 13:59:00.185335 containerd[1882]: time="2025-01-30T13:59:00.182461426Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1
Jan 30 13:59:00.185335 containerd[1882]: time="2025-01-30T13:59:00.182706687Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1
Jan 30 13:59:00.185335 containerd[1882]: time="2025-01-30T13:59:00.182783361Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jan 30 13:59:00.185335 containerd[1882]: time="2025-01-30T13:59:00.185008904Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jan 30 13:59:00.389043 systemd[1]: Started cri-containerd-cb8bccf5ca2980d2b4d1de15627b495061259f6e077357a5b2be55e9b3c2d1f1.scope - libcontainer container cb8bccf5ca2980d2b4d1de15627b495061259f6e077357a5b2be55e9b3c2d1f1.
Jan 30 13:59:00.392126 systemd[1]: Started cri-containerd-f90e8266a4f80e76ecc05b0b48d9b203f7d8a3d6c1bd11023d8fe94d1aa8f274.scope - libcontainer container f90e8266a4f80e76ecc05b0b48d9b203f7d8a3d6c1bd11023d8fe94d1aa8f274.
Jan 30 13:59:00.479354 containerd[1882]: time="2025-01-30T13:59:00.478576210Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-snn72,Uid:fcbf01e9-2b6d-4496-8130-5a03dce7f79d,Namespace:kube-system,Attempt:0,} returns sandbox id \"cb8bccf5ca2980d2b4d1de15627b495061259f6e077357a5b2be55e9b3c2d1f1\""
Jan 30 13:59:00.483538 containerd[1882]: time="2025-01-30T13:59:00.483269696Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.1\""
Jan 30 13:59:00.500779 containerd[1882]: time="2025-01-30T13:59:00.500744205Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-jlhs4,Uid:31868794-b7dc-4723-bb31-986c73dc23a0,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"f90e8266a4f80e76ecc05b0b48d9b203f7d8a3d6c1bd11023d8fe94d1aa8f274\""
Jan 30 13:59:00.844046 kubelet[2285]: E0130 13:59:00.843925    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:01.053005 systemd[1]: run-containerd-runc-k8s.io-f90e8266a4f80e76ecc05b0b48d9b203f7d8a3d6c1bd11023d8fe94d1aa8f274-runc.UDnE2z.mount: Deactivated successfully.
Jan 30 13:59:01.844885 kubelet[2285]: E0130 13:59:01.844841    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:02.706278 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3483981404.mount: Deactivated successfully.
Jan 30 13:59:02.847306 kubelet[2285]: E0130 13:59:02.847266    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:03.848902 kubelet[2285]: E0130 13:59:03.848848    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:04.085847 containerd[1882]: time="2025-01-30T13:59:04.085785224Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.32.1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 30 13:59:04.088759 containerd[1882]: time="2025-01-30T13:59:04.088142551Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.32.1: active requests=0, bytes read=30909466"
Jan 30 13:59:04.097847 containerd[1882]: time="2025-01-30T13:59:04.097784227Z" level=info msg="ImageCreate event name:\"sha256:e29f9c7391fd92d96bc72026fc755b0f9589536e36ecd7102161f1ded087897a\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 30 13:59:04.118923 containerd[1882]: time="2025-01-30T13:59:04.118740836Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:0244651801747edf2368222f93a7d17cba6e668a890db72532d6b67a7e06dca5\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 30 13:59:04.120473 containerd[1882]: time="2025-01-30T13:59:04.120430340Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.32.1\" with image id \"sha256:e29f9c7391fd92d96bc72026fc755b0f9589536e36ecd7102161f1ded087897a\", repo tag \"registry.k8s.io/kube-proxy:v1.32.1\", repo digest \"registry.k8s.io/kube-proxy@sha256:0244651801747edf2368222f93a7d17cba6e668a890db72532d6b67a7e06dca5\", size \"30908485\" in 3.637117222s"
Jan 30 13:59:04.120602 containerd[1882]: time="2025-01-30T13:59:04.120478134Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.1\" returns image reference \"sha256:e29f9c7391fd92d96bc72026fc755b0f9589536e36ecd7102161f1ded087897a\""
Jan 30 13:59:04.122431 containerd[1882]: time="2025-01-30T13:59:04.122405180Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\""
Jan 30 13:59:04.123561 containerd[1882]: time="2025-01-30T13:59:04.123524086Z" level=info msg="CreateContainer within sandbox \"cb8bccf5ca2980d2b4d1de15627b495061259f6e077357a5b2be55e9b3c2d1f1\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}"
Jan 30 13:59:04.169609 containerd[1882]: time="2025-01-30T13:59:04.169560232Z" level=info msg="CreateContainer within sandbox \"cb8bccf5ca2980d2b4d1de15627b495061259f6e077357a5b2be55e9b3c2d1f1\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"7e1083a23794b23d505204f555a2222af8fe9d495ec1261f3dc61b0e1858ecc6\""
Jan 30 13:59:04.173223 containerd[1882]: time="2025-01-30T13:59:04.173185656Z" level=info msg="StartContainer for \"7e1083a23794b23d505204f555a2222af8fe9d495ec1261f3dc61b0e1858ecc6\""
Jan 30 13:59:04.273773 systemd[1]: Started cri-containerd-7e1083a23794b23d505204f555a2222af8fe9d495ec1261f3dc61b0e1858ecc6.scope - libcontainer container 7e1083a23794b23d505204f555a2222af8fe9d495ec1261f3dc61b0e1858ecc6.
Jan 30 13:59:04.407636 containerd[1882]: time="2025-01-30T13:59:04.407495522Z" level=info msg="StartContainer for \"7e1083a23794b23d505204f555a2222af8fe9d495ec1261f3dc61b0e1858ecc6\" returns successfully"
Jan 30 13:59:04.850615 kubelet[2285]: E0130 13:59:04.849446    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:05.246068 kubelet[2285]: I0130 13:59:05.245873    2285 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-snn72" podStartSLOduration=3.606485252 podStartE2EDuration="7.245845365s" podCreationTimestamp="2025-01-30 13:58:58 +0000 UTC" firstStartedPulling="2025-01-30 13:59:00.482414656 +0000 UTC m=+3.439085227" lastFinishedPulling="2025-01-30 13:59:04.121774789 +0000 UTC m=+7.078445340" observedRunningTime="2025-01-30 13:59:05.241436983 +0000 UTC m=+8.198107574" watchObservedRunningTime="2025-01-30 13:59:05.245845365 +0000 UTC m=+8.202515946"
Jan 30 13:59:05.850391 kubelet[2285]: E0130 13:59:05.850316    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:06.126639 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4228964740.mount: Deactivated successfully.
Jan 30 13:59:06.222969 containerd[1882]: time="2025-01-30T13:59:06.222912734Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 30 13:59:06.224161 containerd[1882]: time="2025-01-30T13:59:06.224098844Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3852937"
Jan 30 13:59:06.227701 containerd[1882]: time="2025-01-30T13:59:06.227307171Z" level=info msg="ImageCreate event name:\"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 30 13:59:06.232596 containerd[1882]: time="2025-01-30T13:59:06.232546877Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 30 13:59:06.233452 containerd[1882]: time="2025-01-30T13:59:06.233410423Z" level=info msg="Pulled image \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" with image id \"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\", repo tag \"docker.io/flannel/flannel-cni-plugin:v1.1.2\", repo digest \"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\", size \"3842055\" in 2.110806055s"
Jan 30 13:59:06.233582 containerd[1882]: time="2025-01-30T13:59:06.233457681Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\""
Jan 30 13:59:06.244087 containerd[1882]: time="2025-01-30T13:59:06.244045538Z" level=info msg="CreateContainer within sandbox \"f90e8266a4f80e76ecc05b0b48d9b203f7d8a3d6c1bd11023d8fe94d1aa8f274\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}"
Jan 30 13:59:06.274187 containerd[1882]: time="2025-01-30T13:59:06.274134396Z" level=info msg="CreateContainer within sandbox \"f90e8266a4f80e76ecc05b0b48d9b203f7d8a3d6c1bd11023d8fe94d1aa8f274\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"1f8690b91a3e783a812591260e46c1cc1c5f41edd738384d96d7d290017c3540\""
Jan 30 13:59:06.274951 containerd[1882]: time="2025-01-30T13:59:06.274907753Z" level=info msg="StartContainer for \"1f8690b91a3e783a812591260e46c1cc1c5f41edd738384d96d7d290017c3540\""
Jan 30 13:59:06.356074 systemd[1]: Started cri-containerd-1f8690b91a3e783a812591260e46c1cc1c5f41edd738384d96d7d290017c3540.scope - libcontainer container 1f8690b91a3e783a812591260e46c1cc1c5f41edd738384d96d7d290017c3540.
Jan 30 13:59:06.442104 containerd[1882]: time="2025-01-30T13:59:06.441982369Z" level=info msg="StartContainer for \"1f8690b91a3e783a812591260e46c1cc1c5f41edd738384d96d7d290017c3540\" returns successfully"
Jan 30 13:59:06.442719 systemd[1]: cri-containerd-1f8690b91a3e783a812591260e46c1cc1c5f41edd738384d96d7d290017c3540.scope: Deactivated successfully.
Jan 30 13:59:06.631456 containerd[1882]: time="2025-01-30T13:59:06.631246843Z" level=info msg="shim disconnected" id=1f8690b91a3e783a812591260e46c1cc1c5f41edd738384d96d7d290017c3540 namespace=k8s.io
Jan 30 13:59:06.631456 containerd[1882]: time="2025-01-30T13:59:06.631309724Z" level=warning msg="cleaning up after shim disconnected" id=1f8690b91a3e783a812591260e46c1cc1c5f41edd738384d96d7d290017c3540 namespace=k8s.io
Jan 30 13:59:06.631456 containerd[1882]: time="2025-01-30T13:59:06.631442238Z" level=info msg="cleaning up dead shim" namespace=k8s.io
Jan 30 13:59:06.851777 kubelet[2285]: E0130 13:59:06.851374    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:06.938312 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-1f8690b91a3e783a812591260e46c1cc1c5f41edd738384d96d7d290017c3540-rootfs.mount: Deactivated successfully.
Jan 30 13:59:07.235321 containerd[1882]: time="2025-01-30T13:59:07.235068947Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\""
Jan 30 13:59:07.851901 kubelet[2285]: E0130 13:59:07.851801    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:08.852251 kubelet[2285]: E0130 13:59:08.852209    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:09.395244 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3366995882.mount: Deactivated successfully.
Jan 30 13:59:09.853633 kubelet[2285]: E0130 13:59:09.853475    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:10.652964 containerd[1882]: time="2025-01-30T13:59:10.652911560Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 30 13:59:10.655528 containerd[1882]: time="2025-01-30T13:59:10.655383068Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26866357"
Jan 30 13:59:10.657306 containerd[1882]: time="2025-01-30T13:59:10.656974007Z" level=info msg="ImageCreate event name:\"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 30 13:59:10.666351 containerd[1882]: time="2025-01-30T13:59:10.666298378Z" level=info msg="Pulled image \"docker.io/flannel/flannel:v0.22.0\" with image id \"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\", repo tag \"docker.io/flannel/flannel:v0.22.0\", repo digest \"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\", size \"26855532\" in 3.431177573s"
Jan 30 13:59:10.666351 containerd[1882]: time="2025-01-30T13:59:10.666354304Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\""
Jan 30 13:59:10.667619 containerd[1882]: time="2025-01-30T13:59:10.667303190Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 30 13:59:10.671095 containerd[1882]: time="2025-01-30T13:59:10.671053715Z" level=info msg="CreateContainer within sandbox \"f90e8266a4f80e76ecc05b0b48d9b203f7d8a3d6c1bd11023d8fe94d1aa8f274\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}"
Jan 30 13:59:10.723298 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3547799303.mount: Deactivated successfully.
Jan 30 13:59:10.735733 containerd[1882]: time="2025-01-30T13:59:10.735679712Z" level=info msg="CreateContainer within sandbox \"f90e8266a4f80e76ecc05b0b48d9b203f7d8a3d6c1bd11023d8fe94d1aa8f274\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"2a4c27211804e17e4846d10c8a560ce423c6999dfb9861614b0a5969d701a14a\""
Jan 30 13:59:10.736421 containerd[1882]: time="2025-01-30T13:59:10.736382158Z" level=info msg="StartContainer for \"2a4c27211804e17e4846d10c8a560ce423c6999dfb9861614b0a5969d701a14a\""
Jan 30 13:59:10.786717 systemd[1]: Started cri-containerd-2a4c27211804e17e4846d10c8a560ce423c6999dfb9861614b0a5969d701a14a.scope - libcontainer container 2a4c27211804e17e4846d10c8a560ce423c6999dfb9861614b0a5969d701a14a.
Jan 30 13:59:10.822902 systemd[1]: cri-containerd-2a4c27211804e17e4846d10c8a560ce423c6999dfb9861614b0a5969d701a14a.scope: Deactivated successfully.
Jan 30 13:59:10.826952 containerd[1882]: time="2025-01-30T13:59:10.826915832Z" level=info msg="StartContainer for \"2a4c27211804e17e4846d10c8a560ce423c6999dfb9861614b0a5969d701a14a\" returns successfully"
Jan 30 13:59:10.851621 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-2a4c27211804e17e4846d10c8a560ce423c6999dfb9861614b0a5969d701a14a-rootfs.mount: Deactivated successfully.
Jan 30 13:59:10.855203 kubelet[2285]: E0130 13:59:10.855159    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:10.908698 kubelet[2285]: I0130 13:59:10.907405    2285 kubelet_node_status.go:502] "Fast updating node status as it just became ready"
Jan 30 13:59:10.964447 containerd[1882]: time="2025-01-30T13:59:10.964268161Z" level=info msg="shim disconnected" id=2a4c27211804e17e4846d10c8a560ce423c6999dfb9861614b0a5969d701a14a namespace=k8s.io
Jan 30 13:59:10.964447 containerd[1882]: time="2025-01-30T13:59:10.964429031Z" level=warning msg="cleaning up after shim disconnected" id=2a4c27211804e17e4846d10c8a560ce423c6999dfb9861614b0a5969d701a14a namespace=k8s.io
Jan 30 13:59:10.964447 containerd[1882]: time="2025-01-30T13:59:10.964450448Z" level=info msg="cleaning up dead shim" namespace=k8s.io
Jan 30 13:59:10.983863 containerd[1882]: time="2025-01-30T13:59:10.983812252Z" level=warning msg="cleanup warnings time=\"2025-01-30T13:59:10Z\" 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 30 13:59:11.241881 containerd[1882]: time="2025-01-30T13:59:11.241758104Z" level=info msg="CreateContainer within sandbox \"f90e8266a4f80e76ecc05b0b48d9b203f7d8a3d6c1bd11023d8fe94d1aa8f274\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}"
Jan 30 13:59:11.263478 containerd[1882]: time="2025-01-30T13:59:11.263407699Z" level=info msg="CreateContainer within sandbox \"f90e8266a4f80e76ecc05b0b48d9b203f7d8a3d6c1bd11023d8fe94d1aa8f274\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"b2b523e3351a444c740a7a5198e92e3acf7f62297a9dafc63bc173a9115137be\""
Jan 30 13:59:11.265333 containerd[1882]: time="2025-01-30T13:59:11.265083509Z" level=info msg="StartContainer for \"b2b523e3351a444c740a7a5198e92e3acf7f62297a9dafc63bc173a9115137be\""
Jan 30 13:59:11.326743 systemd[1]: Started cri-containerd-b2b523e3351a444c740a7a5198e92e3acf7f62297a9dafc63bc173a9115137be.scope - libcontainer container b2b523e3351a444c740a7a5198e92e3acf7f62297a9dafc63bc173a9115137be.
Jan 30 13:59:11.391109 containerd[1882]: time="2025-01-30T13:59:11.390973334Z" level=info msg="StartContainer for \"b2b523e3351a444c740a7a5198e92e3acf7f62297a9dafc63bc173a9115137be\" returns successfully"
Jan 30 13:59:11.855653 kubelet[2285]: E0130 13:59:11.855595    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:12.479327 (udev-worker)[2781]: Network interface NamePolicy= disabled on kernel command line.
Jan 30 13:59:12.510376 systemd-networkd[1718]: flannel.1: Link UP
Jan 30 13:59:12.510385 systemd-networkd[1718]: flannel.1: Gained carrier
Jan 30 13:59:12.856235 kubelet[2285]: E0130 13:59:12.856101    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:13.770789 systemd-networkd[1718]: flannel.1: Gained IPv6LL
Jan 30 13:59:13.856876 kubelet[2285]: E0130 13:59:13.856814    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:14.857711 kubelet[2285]: E0130 13:59:14.857649    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:15.858092 kubelet[2285]: E0130 13:59:15.858033    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:16.438442 kubelet[2285]: I0130 13:59:16.438382    2285 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-jlhs4" podStartSLOduration=8.271081373 podStartE2EDuration="18.438337858s" podCreationTimestamp="2025-01-30 13:58:58 +0000 UTC" firstStartedPulling="2025-01-30 13:59:00.502117585 +0000 UTC m=+3.458788141" lastFinishedPulling="2025-01-30 13:59:10.669374069 +0000 UTC m=+13.626044626" observedRunningTime="2025-01-30 13:59:12.256760035 +0000 UTC m=+15.213430605" watchObservedRunningTime="2025-01-30 13:59:16.438337858 +0000 UTC m=+19.395008407"
Jan 30 13:59:16.454204 systemd[1]: Created slice kubepods-besteffort-poddda11df6_573b_4d81_bb06_ff18cebdfcc2.slice - libcontainer container kubepods-besteffort-poddda11df6_573b_4d81_bb06_ff18cebdfcc2.slice.
Jan 30 13:59:16.487831 ntpd[1850]: Listen normally on 7 flannel.1 192.168.1.0:123
Jan 30 13:59:16.487919 ntpd[1850]: Listen normally on 8 flannel.1 [fe80::14a9:efff:fea1:8d4f%3]:123
Jan 30 13:59:16.488303 ntpd[1850]: 30 Jan 13:59:16 ntpd[1850]: Listen normally on 7 flannel.1 192.168.1.0:123
Jan 30 13:59:16.488303 ntpd[1850]: 30 Jan 13:59:16 ntpd[1850]: Listen normally on 8 flannel.1 [fe80::14a9:efff:fea1:8d4f%3]:123
Jan 30 13:59:16.494365 kubelet[2285]: I0130 13:59:16.494307    2285 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-czb5h\" (UniqueName: \"kubernetes.io/projected/dda11df6-573b-4d81-bb06-ff18cebdfcc2-kube-api-access-czb5h\") pod \"nginx-deployment-7fcdb87857-mgh5w\" (UID: \"dda11df6-573b-4d81-bb06-ff18cebdfcc2\") " pod="default/nginx-deployment-7fcdb87857-mgh5w"
Jan 30 13:59:16.771691 containerd[1882]: time="2025-01-30T13:59:16.771560598Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-mgh5w,Uid:dda11df6-573b-4d81-bb06-ff18cebdfcc2,Namespace:default,Attempt:0,}"
Jan 30 13:59:16.820670 systemd-networkd[1718]: cni0: Link UP
Jan 30 13:59:16.820678 systemd-networkd[1718]: cni0: Gained carrier
Jan 30 13:59:16.829676 (udev-worker)[2877]: Network interface NamePolicy= disabled on kernel command line.
Jan 30 13:59:16.831304 systemd-networkd[1718]: cni0: Lost carrier
Jan 30 13:59:16.842902 systemd-networkd[1718]: veth75541eaa: Link UP
Jan 30 13:59:16.847920 kernel: cni0: port 1(veth75541eaa) entered blocking state
Jan 30 13:59:16.848077 kernel: cni0: port 1(veth75541eaa) entered disabled state
Jan 30 13:59:16.850623 kernel: veth75541eaa: entered allmulticast mode
Jan 30 13:59:16.853894 kernel: veth75541eaa: entered promiscuous mode
Jan 30 13:59:16.854079 kernel: cni0: port 1(veth75541eaa) entered blocking state
Jan 30 13:59:16.854103 kernel: cni0: port 1(veth75541eaa) entered forwarding state
Jan 30 13:59:16.856579 kernel: cni0: port 1(veth75541eaa) entered disabled state
Jan 30 13:59:16.859095 kubelet[2285]: E0130 13:59:16.858970    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:16.860922 (udev-worker)[2882]: Network interface NamePolicy= disabled on kernel command line.
Jan 30 13:59:16.872041 kernel: cni0: port 1(veth75541eaa) entered blocking state
Jan 30 13:59:16.872348 kernel: cni0: port 1(veth75541eaa) entered forwarding state
Jan 30 13:59:16.872443 systemd-networkd[1718]: veth75541eaa: Gained carrier
Jan 30 13:59:16.873325 systemd-networkd[1718]: cni0: Gained carrier
Jan 30 13:59:16.897287 containerd[1882]: 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)(0xc00009a8e8), "name":"cbr0", "type":"bridge"}
Jan 30 13:59:16.897287 containerd[1882]: delegateAdd: netconf sent to delegate plugin:
Jan 30 13:59:16.962671 containerd[1882]: {"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-30T13:59:16.962568717Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1
Jan 30 13:59:16.963226 containerd[1882]: time="2025-01-30T13:59:16.963068597Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1
Jan 30 13:59:16.963780 containerd[1882]: time="2025-01-30T13:59:16.963708650Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jan 30 13:59:16.964089 containerd[1882]: time="2025-01-30T13:59:16.964000526Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jan 30 13:59:17.040691 systemd[1]: Started cri-containerd-0fc79895e3a9fbe3aa1edb323d60bc6f53a23eb278367579d3cc7afcc80c6890.scope - libcontainer container 0fc79895e3a9fbe3aa1edb323d60bc6f53a23eb278367579d3cc7afcc80c6890.
Jan 30 13:59:17.089002 containerd[1882]: time="2025-01-30T13:59:17.088783110Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-mgh5w,Uid:dda11df6-573b-4d81-bb06-ff18cebdfcc2,Namespace:default,Attempt:0,} returns sandbox id \"0fc79895e3a9fbe3aa1edb323d60bc6f53a23eb278367579d3cc7afcc80c6890\""
Jan 30 13:59:17.091062 containerd[1882]: time="2025-01-30T13:59:17.091024853Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\""
Jan 30 13:59:17.839527 kubelet[2285]: E0130 13:59:17.839453    2285 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:17.860105 kubelet[2285]: E0130 13:59:17.859994    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:18.250674 systemd-networkd[1718]: veth75541eaa: Gained IPv6LL
Jan 30 13:59:18.698681 systemd-networkd[1718]: cni0: Gained IPv6LL
Jan 30 13:59:18.860977 kubelet[2285]: E0130 13:59:18.860926    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:19.536033 systemd[1]: systemd-hostnamed.service: Deactivated successfully.
Jan 30 13:59:19.862133 kubelet[2285]: E0130 13:59:19.861850    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:20.395000 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2195477802.mount: Deactivated successfully.
Jan 30 13:59:20.862482 kubelet[2285]: E0130 13:59:20.862375    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:21.488026 ntpd[1850]: Listen normally on 9 cni0 192.168.1.1:123
Jan 30 13:59:21.488194 ntpd[1850]: Listen normally on 10 cni0 [fe80::c8a1:f6ff:fee6:13c1%4]:123
Jan 30 13:59:21.490326 ntpd[1850]: 30 Jan 13:59:21 ntpd[1850]: Listen normally on 9 cni0 192.168.1.1:123
Jan 30 13:59:21.490326 ntpd[1850]: 30 Jan 13:59:21 ntpd[1850]: Listen normally on 10 cni0 [fe80::c8a1:f6ff:fee6:13c1%4]:123
Jan 30 13:59:21.490326 ntpd[1850]: 30 Jan 13:59:21 ntpd[1850]: Listen normally on 11 veth75541eaa [fe80::6057:b1ff:fe8a:2e04%5]:123
Jan 30 13:59:21.488435 ntpd[1850]: Listen normally on 11 veth75541eaa [fe80::6057:b1ff:fe8a:2e04%5]:123
Jan 30 13:59:21.864233 kubelet[2285]: E0130 13:59:21.864060    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:22.342078 containerd[1882]: time="2025-01-30T13:59:22.341401194Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 30 13:59:22.343360 containerd[1882]: time="2025-01-30T13:59:22.343303428Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=71015561"
Jan 30 13:59:22.344566 containerd[1882]: time="2025-01-30T13:59:22.344532665Z" level=info msg="ImageCreate event name:\"sha256:0dcfd986e814f68db775fba6b61fbaec3761562dc2ae3043d38dbff123e1bb1e\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 30 13:59:22.348426 containerd[1882]: time="2025-01-30T13:59:22.348023395Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:2ffeb5a7ca68f2017f0bc48251750a6e40fcd3c341b94a22fc7812dcabbb84db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 30 13:59:22.350326 containerd[1882]: time="2025-01-30T13:59:22.350213736Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:0dcfd986e814f68db775fba6b61fbaec3761562dc2ae3043d38dbff123e1bb1e\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:2ffeb5a7ca68f2017f0bc48251750a6e40fcd3c341b94a22fc7812dcabbb84db\", size \"71015439\" in 5.25914767s"
Jan 30 13:59:22.350326 containerd[1882]: time="2025-01-30T13:59:22.350313244Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:0dcfd986e814f68db775fba6b61fbaec3761562dc2ae3043d38dbff123e1bb1e\""
Jan 30 13:59:22.357032 containerd[1882]: time="2025-01-30T13:59:22.356956108Z" level=info msg="CreateContainer within sandbox \"0fc79895e3a9fbe3aa1edb323d60bc6f53a23eb278367579d3cc7afcc80c6890\" for container &ContainerMetadata{Name:nginx,Attempt:0,}"
Jan 30 13:59:22.401184 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1711622833.mount: Deactivated successfully.
Jan 30 13:59:22.403893 containerd[1882]: time="2025-01-30T13:59:22.403847368Z" level=info msg="CreateContainer within sandbox \"0fc79895e3a9fbe3aa1edb323d60bc6f53a23eb278367579d3cc7afcc80c6890\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"20643f8243f5cdac393f21c45fb4f9823ef11634bd2bd5a06b1e702392cdb6c7\""
Jan 30 13:59:22.404858 containerd[1882]: time="2025-01-30T13:59:22.404816144Z" level=info msg="StartContainer for \"20643f8243f5cdac393f21c45fb4f9823ef11634bd2bd5a06b1e702392cdb6c7\""
Jan 30 13:59:22.445945 systemd[1]: run-containerd-runc-k8s.io-20643f8243f5cdac393f21c45fb4f9823ef11634bd2bd5a06b1e702392cdb6c7-runc.qkxS7M.mount: Deactivated successfully.
Jan 30 13:59:22.468785 systemd[1]: Started cri-containerd-20643f8243f5cdac393f21c45fb4f9823ef11634bd2bd5a06b1e702392cdb6c7.scope - libcontainer container 20643f8243f5cdac393f21c45fb4f9823ef11634bd2bd5a06b1e702392cdb6c7.
Jan 30 13:59:22.513891 containerd[1882]: time="2025-01-30T13:59:22.513773061Z" level=info msg="StartContainer for \"20643f8243f5cdac393f21c45fb4f9823ef11634bd2bd5a06b1e702392cdb6c7\" returns successfully"
Jan 30 13:59:22.864903 kubelet[2285]: E0130 13:59:22.864843    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:23.865955 kubelet[2285]: E0130 13:59:23.865896    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:24.866905 kubelet[2285]: E0130 13:59:24.866845    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:25.867101 kubelet[2285]: E0130 13:59:25.867049    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:26.868039 kubelet[2285]: E0130 13:59:26.867983    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:27.868901 kubelet[2285]: E0130 13:59:27.868843    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:28.869278 kubelet[2285]: E0130 13:59:28.869151    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:29.869715 kubelet[2285]: E0130 13:59:29.869375    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:30.077459 kubelet[2285]: I0130 13:59:30.077345    2285 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-mgh5w" podStartSLOduration=8.813750451 podStartE2EDuration="14.07732348s" podCreationTimestamp="2025-01-30 13:59:16 +0000 UTC" firstStartedPulling="2025-01-30 13:59:17.090189917 +0000 UTC m=+20.046860467" lastFinishedPulling="2025-01-30 13:59:22.353762936 +0000 UTC m=+25.310433496" observedRunningTime="2025-01-30 13:59:23.284126922 +0000 UTC m=+26.240797493" watchObservedRunningTime="2025-01-30 13:59:30.07732348 +0000 UTC m=+33.033994053"
Jan 30 13:59:30.086906 kubelet[2285]: I0130 13:59:30.086838    2285 status_manager.go:890] "Failed to get status for pod" podUID="fd54dcd2-ae1a-45d7-a5d1-6554a06704ee" pod="default/nfs-server-provisioner-0" err="pods \"nfs-server-provisioner-0\" is forbidden: User \"system:node:172.31.29.140\" cannot get resource \"pods\" in API group \"\" in the namespace \"default\": no relationship found between node '172.31.29.140' and this object"
Jan 30 13:59:30.109812 systemd[1]: Created slice kubepods-besteffort-podfd54dcd2_ae1a_45d7_a5d1_6554a06704ee.slice - libcontainer container kubepods-besteffort-podfd54dcd2_ae1a_45d7_a5d1_6554a06704ee.slice.
Jan 30 13:59:30.192112 kubelet[2285]: I0130 13:59:30.191960    2285 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/fd54dcd2-ae1a-45d7-a5d1-6554a06704ee-data\") pod \"nfs-server-provisioner-0\" (UID: \"fd54dcd2-ae1a-45d7-a5d1-6554a06704ee\") " pod="default/nfs-server-provisioner-0"
Jan 30 13:59:30.192112 kubelet[2285]: I0130 13:59:30.192008    2285 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-n9mwh\" (UniqueName: \"kubernetes.io/projected/fd54dcd2-ae1a-45d7-a5d1-6554a06704ee-kube-api-access-n9mwh\") pod \"nfs-server-provisioner-0\" (UID: \"fd54dcd2-ae1a-45d7-a5d1-6554a06704ee\") " pod="default/nfs-server-provisioner-0"
Jan 30 13:59:30.415048 containerd[1882]: time="2025-01-30T13:59:30.414968103Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:fd54dcd2-ae1a-45d7-a5d1-6554a06704ee,Namespace:default,Attempt:0,}"
Jan 30 13:59:30.447398 systemd-networkd[1718]: vethc7b68c65: Link UP
Jan 30 13:59:30.447722 (udev-worker)[3095]: Network interface NamePolicy= disabled on kernel command line.
Jan 30 13:59:30.452044 kernel: cni0: port 2(vethc7b68c65) entered blocking state
Jan 30 13:59:30.452140 kernel: cni0: port 2(vethc7b68c65) entered disabled state
Jan 30 13:59:30.452168 kernel: vethc7b68c65: entered allmulticast mode
Jan 30 13:59:30.452191 kernel: vethc7b68c65: entered promiscuous mode
Jan 30 13:59:30.464810 kernel: cni0: port 2(vethc7b68c65) entered blocking state
Jan 30 13:59:30.465065 kernel: cni0: port 2(vethc7b68c65) entered forwarding state
Jan 30 13:59:30.465381 systemd-networkd[1718]: vethc7b68c65: Gained carrier
Jan 30 13:59:30.467663 containerd[1882]: 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)(0xc000018938), "name":"cbr0", "type":"bridge"}
Jan 30 13:59:30.467663 containerd[1882]: delegateAdd: netconf sent to delegate plugin:
Jan 30 13:59:30.494394 containerd[1882]: {"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-30T13:59:30.494088996Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1
Jan 30 13:59:30.494394 containerd[1882]: time="2025-01-30T13:59:30.494160128Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1
Jan 30 13:59:30.494394 containerd[1882]: time="2025-01-30T13:59:30.494176334Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jan 30 13:59:30.494394 containerd[1882]: time="2025-01-30T13:59:30.494260068Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jan 30 13:59:30.526475 systemd[1]: Started cri-containerd-ebb3879073aaf678615e49968c464a485ca09129b0edb7b480cab19bb2f36f99.scope - libcontainer container ebb3879073aaf678615e49968c464a485ca09129b0edb7b480cab19bb2f36f99.
Jan 30 13:59:30.579866 containerd[1882]: time="2025-01-30T13:59:30.577581124Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:fd54dcd2-ae1a-45d7-a5d1-6554a06704ee,Namespace:default,Attempt:0,} returns sandbox id \"ebb3879073aaf678615e49968c464a485ca09129b0edb7b480cab19bb2f36f99\""
Jan 30 13:59:30.582111 containerd[1882]: time="2025-01-30T13:59:30.581872147Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\""
Jan 30 13:59:30.870744 kubelet[2285]: E0130 13:59:30.870685    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:31.318210 systemd[1]: run-containerd-runc-k8s.io-ebb3879073aaf678615e49968c464a485ca09129b0edb7b480cab19bb2f36f99-runc.GCM144.mount: Deactivated successfully.
Jan 30 13:59:31.872304 kubelet[2285]: E0130 13:59:31.872207    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:32.138777 systemd-networkd[1718]: vethc7b68c65: Gained IPv6LL
Jan 30 13:59:32.873118 kubelet[2285]: E0130 13:59:32.873076    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:33.875387 kubelet[2285]: E0130 13:59:33.875349    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:34.179163 update_engine[1858]: I20250130 13:59:34.177590  1858 update_attempter.cc:509] Updating boot flags...
Jan 30 13:59:34.299174 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 38 scanned by (udev-worker) (3215)
Jan 30 13:59:34.391771 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount829956774.mount: Deactivated successfully.
Jan 30 13:59:34.487985 ntpd[1850]: Listen normally on 12 vethc7b68c65 [fe80::6cb3:88ff:fecd:1dc1%6]:123
Jan 30 13:59:34.491035 ntpd[1850]: 30 Jan 13:59:34 ntpd[1850]: Listen normally on 12 vethc7b68c65 [fe80::6cb3:88ff:fecd:1dc1%6]:123
Jan 30 13:59:34.715576 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 38 scanned by (udev-worker) (3219)
Jan 30 13:59:34.875607 kubelet[2285]: E0130 13:59:34.875566    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:35.878876 kubelet[2285]: E0130 13:59:35.877148    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:36.889586 kubelet[2285]: E0130 13:59:36.880850    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:37.838875 kubelet[2285]: E0130 13:59:37.838832    2285 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:37.895200 kubelet[2285]: E0130 13:59:37.892755    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:38.495802 containerd[1882]: time="2025-01-30T13:59:38.495736716Z" 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 30 13:59:38.498024 containerd[1882]: time="2025-01-30T13:59:38.497684631Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=91039406"
Jan 30 13:59:38.500517 containerd[1882]: time="2025-01-30T13:59:38.500383660Z" level=info msg="ImageCreate event name:\"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 30 13:59:38.506883 containerd[1882]: time="2025-01-30T13:59:38.505772359Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 30 13:59:38.508536 containerd[1882]: time="2025-01-30T13:59:38.508475761Z" level=info msg="Pulled image \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" with image id \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\", repo tag \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\", repo digest \"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\", size \"91036984\" in 7.926506303s"
Jan 30 13:59:38.508669 containerd[1882]: time="2025-01-30T13:59:38.508542924Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\""
Jan 30 13:59:38.513136 containerd[1882]: time="2025-01-30T13:59:38.513096670Z" level=info msg="CreateContainer within sandbox \"ebb3879073aaf678615e49968c464a485ca09129b0edb7b480cab19bb2f36f99\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}"
Jan 30 13:59:38.538375 containerd[1882]: time="2025-01-30T13:59:38.538320798Z" level=info msg="CreateContainer within sandbox \"ebb3879073aaf678615e49968c464a485ca09129b0edb7b480cab19bb2f36f99\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"98d1eb6142c0ba4463f608ca30297debd165ceaa977b0137462788d3ffa82330\""
Jan 30 13:59:38.539062 containerd[1882]: time="2025-01-30T13:59:38.538969601Z" level=info msg="StartContainer for \"98d1eb6142c0ba4463f608ca30297debd165ceaa977b0137462788d3ffa82330\""
Jan 30 13:59:38.670790 systemd[1]: run-containerd-runc-k8s.io-98d1eb6142c0ba4463f608ca30297debd165ceaa977b0137462788d3ffa82330-runc.87Kx07.mount: Deactivated successfully.
Jan 30 13:59:38.680923 systemd[1]: Started cri-containerd-98d1eb6142c0ba4463f608ca30297debd165ceaa977b0137462788d3ffa82330.scope - libcontainer container 98d1eb6142c0ba4463f608ca30297debd165ceaa977b0137462788d3ffa82330.
Jan 30 13:59:38.730091 containerd[1882]: time="2025-01-30T13:59:38.730044700Z" level=info msg="StartContainer for \"98d1eb6142c0ba4463f608ca30297debd165ceaa977b0137462788d3ffa82330\" returns successfully"
Jan 30 13:59:38.894010 kubelet[2285]: E0130 13:59:38.893093    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:39.894237 kubelet[2285]: E0130 13:59:39.894187    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:40.895083 kubelet[2285]: E0130 13:59:40.895028    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:41.895593 kubelet[2285]: E0130 13:59:41.895532    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:42.896512 kubelet[2285]: E0130 13:59:42.896402    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:43.897634 kubelet[2285]: E0130 13:59:43.897587    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:44.898578 kubelet[2285]: E0130 13:59:44.898516    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:45.898924 kubelet[2285]: E0130 13:59:45.898884    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:46.899305 kubelet[2285]: E0130 13:59:46.899247    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:47.899775 kubelet[2285]: E0130 13:59:47.899716    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:48.648544 kubelet[2285]: I0130 13:59:48.648394    2285 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=10.718752566 podStartE2EDuration="18.648379132s" podCreationTimestamp="2025-01-30 13:59:30 +0000 UTC" firstStartedPulling="2025-01-30 13:59:30.581631527 +0000 UTC m=+33.538302086" lastFinishedPulling="2025-01-30 13:59:38.511258098 +0000 UTC m=+41.467928652" observedRunningTime="2025-01-30 13:59:39.541984146 +0000 UTC m=+42.498654718" watchObservedRunningTime="2025-01-30 13:59:48.648379132 +0000 UTC m=+51.605049703"
Jan 30 13:59:48.663371 systemd[1]: Created slice kubepods-besteffort-pod8dc3460d_4d6e_4f23_b0b8_704815cd1441.slice - libcontainer container kubepods-besteffort-pod8dc3460d_4d6e_4f23_b0b8_704815cd1441.slice.
Jan 30 13:59:48.849362 kubelet[2285]: I0130 13:59:48.849304    2285 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-626b352f-d109-4f80-8195-2bea4399cc2d\" (UniqueName: \"kubernetes.io/nfs/8dc3460d-4d6e-4f23-b0b8-704815cd1441-pvc-626b352f-d109-4f80-8195-2bea4399cc2d\") pod \"test-pod-1\" (UID: \"8dc3460d-4d6e-4f23-b0b8-704815cd1441\") " pod="default/test-pod-1"
Jan 30 13:59:48.849362 kubelet[2285]: I0130 13:59:48.849360    2285 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-p6vjx\" (UniqueName: \"kubernetes.io/projected/8dc3460d-4d6e-4f23-b0b8-704815cd1441-kube-api-access-p6vjx\") pod \"test-pod-1\" (UID: \"8dc3460d-4d6e-4f23-b0b8-704815cd1441\") " pod="default/test-pod-1"
Jan 30 13:59:48.900410 kubelet[2285]: E0130 13:59:48.900260    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:49.026522 kernel: FS-Cache: Loaded
Jan 30 13:59:49.210405 kernel: RPC: Registered named UNIX socket transport module.
Jan 30 13:59:49.210636 kernel: RPC: Registered udp transport module.
Jan 30 13:59:49.210690 kernel: RPC: Registered tcp transport module.
Jan 30 13:59:49.210721 kernel: RPC: Registered tcp-with-tls transport module.
Jan 30 13:59:49.210749 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module.
Jan 30 13:59:49.900698 kubelet[2285]: E0130 13:59:49.900612    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:49.996864 kernel: NFS: Registering the id_resolver key type
Jan 30 13:59:49.997040 kernel: Key type id_resolver registered
Jan 30 13:59:49.997072 kernel: Key type id_legacy registered
Jan 30 13:59:50.057044 nfsidmap[3563]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'us-west-2.compute.internal'
Jan 30 13:59:50.063416 nfsidmap[3564]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'us-west-2.compute.internal'
Jan 30 13:59:50.168543 containerd[1882]: time="2025-01-30T13:59:50.168395829Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:8dc3460d-4d6e-4f23-b0b8-704815cd1441,Namespace:default,Attempt:0,}"
Jan 30 13:59:50.272185 (udev-worker)[3561]: Network interface NamePolicy= disabled on kernel command line.
Jan 30 13:59:50.274114 systemd-networkd[1718]: veth9c36ad9a: Link UP
Jan 30 13:59:50.276811 kernel: cni0: port 3(veth9c36ad9a) entered blocking state
Jan 30 13:59:50.276889 kernel: cni0: port 3(veth9c36ad9a) entered disabled state
Jan 30 13:59:50.279691 kernel: veth9c36ad9a: entered allmulticast mode
Jan 30 13:59:50.279798 kernel: veth9c36ad9a: entered promiscuous mode
Jan 30 13:59:50.289529 kernel: cni0: port 3(veth9c36ad9a) entered blocking state
Jan 30 13:59:50.289800 kernel: cni0: port 3(veth9c36ad9a) entered forwarding state
Jan 30 13:59:50.290147 systemd-networkd[1718]: veth9c36ad9a: Gained carrier
Jan 30 13:59:50.296528 containerd[1882]: 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)(0xc000018938), "name":"cbr0", "type":"bridge"}
Jan 30 13:59:50.296528 containerd[1882]: delegateAdd: netconf sent to delegate plugin:
Jan 30 13:59:50.373262 containerd[1882]: {"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-30T13:59:50.373115248Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1
Jan 30 13:59:50.373262 containerd[1882]: time="2025-01-30T13:59:50.373210121Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1
Jan 30 13:59:50.373606 containerd[1882]: time="2025-01-30T13:59:50.373230744Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jan 30 13:59:50.373606 containerd[1882]: time="2025-01-30T13:59:50.373342231Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jan 30 13:59:50.407005 systemd[1]: Started cri-containerd-7b2b980bcf57f2e7f80886dfb27f1f9e8e80ca7eac684227491f901545ca7ac8.scope - libcontainer container 7b2b980bcf57f2e7f80886dfb27f1f9e8e80ca7eac684227491f901545ca7ac8.
Jan 30 13:59:50.459899 containerd[1882]: time="2025-01-30T13:59:50.459848235Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:8dc3460d-4d6e-4f23-b0b8-704815cd1441,Namespace:default,Attempt:0,} returns sandbox id \"7b2b980bcf57f2e7f80886dfb27f1f9e8e80ca7eac684227491f901545ca7ac8\""
Jan 30 13:59:50.462924 containerd[1882]: time="2025-01-30T13:59:50.462889395Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\""
Jan 30 13:59:50.820531 containerd[1882]: time="2025-01-30T13:59:50.820374257Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 30 13:59:50.823527 containerd[1882]: time="2025-01-30T13:59:50.822095119Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61"
Jan 30 13:59:50.825078 containerd[1882]: time="2025-01-30T13:59:50.824965402Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:0dcfd986e814f68db775fba6b61fbaec3761562dc2ae3043d38dbff123e1bb1e\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:2ffeb5a7ca68f2017f0bc48251750a6e40fcd3c341b94a22fc7812dcabbb84db\", size \"71015439\" in 361.91245ms"
Jan 30 13:59:50.825078 containerd[1882]: time="2025-01-30T13:59:50.825074564Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:0dcfd986e814f68db775fba6b61fbaec3761562dc2ae3043d38dbff123e1bb1e\""
Jan 30 13:59:50.828605 containerd[1882]: time="2025-01-30T13:59:50.828563624Z" level=info msg="CreateContainer within sandbox \"7b2b980bcf57f2e7f80886dfb27f1f9e8e80ca7eac684227491f901545ca7ac8\" for container &ContainerMetadata{Name:test,Attempt:0,}"
Jan 30 13:59:50.855779 containerd[1882]: time="2025-01-30T13:59:50.855714101Z" level=info msg="CreateContainer within sandbox \"7b2b980bcf57f2e7f80886dfb27f1f9e8e80ca7eac684227491f901545ca7ac8\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"e70a25670a6d9a6b3187e577e3c18a8c70a54f92c036ea1361862cfc3f00eb19\""
Jan 30 13:59:50.856541 containerd[1882]: time="2025-01-30T13:59:50.856437085Z" level=info msg="StartContainer for \"e70a25670a6d9a6b3187e577e3c18a8c70a54f92c036ea1361862cfc3f00eb19\""
Jan 30 13:59:50.901680 kubelet[2285]: E0130 13:59:50.901639    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:50.940734 systemd[1]: Started cri-containerd-e70a25670a6d9a6b3187e577e3c18a8c70a54f92c036ea1361862cfc3f00eb19.scope - libcontainer container e70a25670a6d9a6b3187e577e3c18a8c70a54f92c036ea1361862cfc3f00eb19.
Jan 30 13:59:51.024395 containerd[1882]: time="2025-01-30T13:59:51.023977527Z" level=info msg="StartContainer for \"e70a25670a6d9a6b3187e577e3c18a8c70a54f92c036ea1361862cfc3f00eb19\" returns successfully"
Jan 30 13:59:51.574738 kubelet[2285]: I0130 13:59:51.574675    2285 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=21.209373781 podStartE2EDuration="21.574658374s" podCreationTimestamp="2025-01-30 13:59:30 +0000 UTC" firstStartedPulling="2025-01-30 13:59:50.461690761 +0000 UTC m=+53.418361310" lastFinishedPulling="2025-01-30 13:59:50.826975343 +0000 UTC m=+53.783645903" observedRunningTime="2025-01-30 13:59:51.574314775 +0000 UTC m=+54.530985364" watchObservedRunningTime="2025-01-30 13:59:51.574658374 +0000 UTC m=+54.531328946"
Jan 30 13:59:51.902752 kubelet[2285]: E0130 13:59:51.902698    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:51.978707 systemd-networkd[1718]: veth9c36ad9a: Gained IPv6LL
Jan 30 13:59:52.903343 kubelet[2285]: E0130 13:59:52.903284    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:53.904097 kubelet[2285]: E0130 13:59:53.903989    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:54.487470 ntpd[1850]: Listen normally on 13 veth9c36ad9a [fe80::1480:95ff:fe29:c1f5%7]:123
Jan 30 13:59:54.487919 ntpd[1850]: 30 Jan 13:59:54 ntpd[1850]: Listen normally on 13 veth9c36ad9a [fe80::1480:95ff:fe29:c1f5%7]:123
Jan 30 13:59:54.905082 kubelet[2285]: E0130 13:59:54.905026    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:55.906086 kubelet[2285]: E0130 13:59:55.906029    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:56.906884 kubelet[2285]: E0130 13:59:56.906829    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:57.839025 kubelet[2285]: E0130 13:59:57.838982    2285 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:57.911928 kubelet[2285]: E0130 13:59:57.911565    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:58.911832 kubelet[2285]: E0130 13:59:58.911774    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 13:59:59.913118 kubelet[2285]: E0130 13:59:59.913036    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 14:00:00.913307 kubelet[2285]: E0130 14:00:00.913257    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 14:00:01.914150 kubelet[2285]: E0130 14:00:01.914090    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 14:00:02.915538 kubelet[2285]: E0130 14:00:02.915258    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 14:00:03.916554 kubelet[2285]: E0130 14:00:03.916472    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 14:00:04.917347 kubelet[2285]: E0130 14:00:04.917289    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 14:00:05.917648 kubelet[2285]: E0130 14:00:05.917587    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 14:00:06.918834 kubelet[2285]: E0130 14:00:06.918776    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 14:00:07.919543 kubelet[2285]: E0130 14:00:07.919469    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 14:00:08.919949 kubelet[2285]: E0130 14:00:08.919889    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 14:00:09.921002 kubelet[2285]: E0130 14:00:09.920948    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 14:00:10.921284 kubelet[2285]: E0130 14:00:10.921230    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 14:00:11.921626 kubelet[2285]: E0130 14:00:11.921569    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 14:00:12.922734 kubelet[2285]: E0130 14:00:12.922673    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 14:00:13.923850 kubelet[2285]: E0130 14:00:13.923788    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 14:00:14.924361 kubelet[2285]: E0130 14:00:14.924220    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 14:00:15.924542 kubelet[2285]: E0130 14:00:15.924470    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 14:00:16.925377 kubelet[2285]: E0130 14:00:16.925318    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 14:00:17.839157 kubelet[2285]: E0130 14:00:17.839108    2285 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 14:00:17.926631 kubelet[2285]: E0130 14:00:17.926572    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 14:00:18.927285 kubelet[2285]: E0130 14:00:18.927228    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 14:00:19.370949 kubelet[2285]: E0130 14:00:19.370823    2285 controller.go:195] "Failed to update lease" err="Put \"https://172.31.22.123:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.29.140?timeout=10s\": context deadline exceeded"
Jan 30 14:00:19.928119 kubelet[2285]: E0130 14:00:19.928060    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 14:00:20.929316 kubelet[2285]: E0130 14:00:20.929253    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 14:00:21.929788 kubelet[2285]: E0130 14:00:21.929729    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 14:00:22.930533 kubelet[2285]: E0130 14:00:22.930468    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 14:00:23.930724 kubelet[2285]: E0130 14:00:23.930664    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 14:00:24.932007 kubelet[2285]: E0130 14:00:24.931946    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 14:00:25.933039 kubelet[2285]: E0130 14:00:25.932975    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 14:00:26.933425 kubelet[2285]: E0130 14:00:26.933364    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 30 14:00:27.934530 kubelet[2285]: E0130 14:00:27.934457    2285 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"