Jan 13 20:34:20.872626 kernel: Linux version 6.6.71-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 13.3.1_p20240614 p1) 13.3.1 20240614, GNU ld (Gentoo 2.42 p6) 2.42.0) #1 SMP PREEMPT_DYNAMIC Mon Jan 13 19:01:45 -00 2025
Jan 13 20:34:20.872646 kernel: Command line: BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected verity.usrhash=1175b5bd4028ce8485b23b7d346f787308cbfa43cca7b1fefd4254406dce7d07
Jan 13 20:34:20.872657 kernel: BIOS-provided physical RAM map:
Jan 13 20:34:20.872663 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable
Jan 13 20:34:20.872669 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved
Jan 13 20:34:20.872675 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
Jan 13 20:34:20.872682 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000009cfdbfff] usable
Jan 13 20:34:20.872688 kernel: BIOS-e820: [mem 0x000000009cfdc000-0x000000009cffffff] reserved
Jan 13 20:34:20.872694 kernel: BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved
Jan 13 20:34:20.872702 kernel: BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved
Jan 13 20:34:20.872708 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
Jan 13 20:34:20.872713 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
Jan 13 20:34:20.872719 kernel: BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved
Jan 13 20:34:20.872725 kernel: NX (Execute Disable) protection: active
Jan 13 20:34:20.872733 kernel: APIC: Static calls initialized
Jan 13 20:34:20.872741 kernel: SMBIOS 2.8 present.
Jan 13 20:34:20.872748 kernel: DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.16.2-debian-1.16.2-1 04/01/2014
Jan 13 20:34:20.872755 kernel: Hypervisor detected: KVM
Jan 13 20:34:20.872761 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00
Jan 13 20:34:20.872768 kernel: kvm-clock: using sched offset of 2246135226 cycles
Jan 13 20:34:20.872774 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
Jan 13 20:34:20.872781 kernel: tsc: Detected 2794.748 MHz processor
Jan 13 20:34:20.872788 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
Jan 13 20:34:20.872795 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable
Jan 13 20:34:20.872802 kernel: last_pfn = 0x9cfdc max_arch_pfn = 0x400000000
Jan 13 20:34:20.872811 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs
Jan 13 20:34:20.872818 kernel: x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT  
Jan 13 20:34:20.872824 kernel: Using GB pages for direct mapping
Jan 13 20:34:20.872831 kernel: ACPI: Early table checksum verification disabled
Jan 13 20:34:20.872838 kernel: ACPI: RSDP 0x00000000000F59D0 000014 (v00 BOCHS )
Jan 13 20:34:20.872844 kernel: ACPI: RSDT 0x000000009CFE2408 000038 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
Jan 13 20:34:20.872851 kernel: ACPI: FACP 0x000000009CFE21E8 0000F4 (v03 BOCHS  BXPC     00000001 BXPC 00000001)
Jan 13 20:34:20.872858 kernel: ACPI: DSDT 0x000000009CFE0040 0021A8 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
Jan 13 20:34:20.872866 kernel: ACPI: FACS 0x000000009CFE0000 000040
Jan 13 20:34:20.872873 kernel: ACPI: APIC 0x000000009CFE22DC 000090 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
Jan 13 20:34:20.872880 kernel: ACPI: HPET 0x000000009CFE236C 000038 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
Jan 13 20:34:20.872887 kernel: ACPI: MCFG 0x000000009CFE23A4 00003C (v01 BOCHS  BXPC     00000001 BXPC 00000001)
Jan 13 20:34:20.872893 kernel: ACPI: WAET 0x000000009CFE23E0 000028 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
Jan 13 20:34:20.872900 kernel: ACPI: Reserving FACP table memory at [mem 0x9cfe21e8-0x9cfe22db]
Jan 13 20:34:20.872907 kernel: ACPI: Reserving DSDT table memory at [mem 0x9cfe0040-0x9cfe21e7]
Jan 13 20:34:20.872925 kernel: ACPI: Reserving FACS table memory at [mem 0x9cfe0000-0x9cfe003f]
Jan 13 20:34:20.872935 kernel: ACPI: Reserving APIC table memory at [mem 0x9cfe22dc-0x9cfe236b]
Jan 13 20:34:20.872943 kernel: ACPI: Reserving HPET table memory at [mem 0x9cfe236c-0x9cfe23a3]
Jan 13 20:34:20.872949 kernel: ACPI: Reserving MCFG table memory at [mem 0x9cfe23a4-0x9cfe23df]
Jan 13 20:34:20.872956 kernel: ACPI: Reserving WAET table memory at [mem 0x9cfe23e0-0x9cfe2407]
Jan 13 20:34:20.872973 kernel: No NUMA configuration found
Jan 13 20:34:20.872980 kernel: Faking a node at [mem 0x0000000000000000-0x000000009cfdbfff]
Jan 13 20:34:20.872987 kernel: NODE_DATA(0) allocated [mem 0x9cfd6000-0x9cfdbfff]
Jan 13 20:34:20.872996 kernel: Zone ranges:
Jan 13 20:34:20.873003 kernel:   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
Jan 13 20:34:20.873010 kernel:   DMA32    [mem 0x0000000001000000-0x000000009cfdbfff]
Jan 13 20:34:20.873017 kernel:   Normal   empty
Jan 13 20:34:20.873023 kernel: Movable zone start for each node
Jan 13 20:34:20.873030 kernel: Early memory node ranges
Jan 13 20:34:20.873037 kernel:   node   0: [mem 0x0000000000001000-0x000000000009efff]
Jan 13 20:34:20.873044 kernel:   node   0: [mem 0x0000000000100000-0x000000009cfdbfff]
Jan 13 20:34:20.873051 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000009cfdbfff]
Jan 13 20:34:20.873059 kernel: On node 0, zone DMA: 1 pages in unavailable ranges
Jan 13 20:34:20.873066 kernel: On node 0, zone DMA: 97 pages in unavailable ranges
Jan 13 20:34:20.873073 kernel: On node 0, zone DMA32: 12324 pages in unavailable ranges
Jan 13 20:34:20.873080 kernel: ACPI: PM-Timer IO Port: 0x608
Jan 13 20:34:20.873087 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
Jan 13 20:34:20.873093 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
Jan 13 20:34:20.873100 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
Jan 13 20:34:20.873107 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
Jan 13 20:34:20.873114 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
Jan 13 20:34:20.873123 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
Jan 13 20:34:20.873129 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
Jan 13 20:34:20.873136 kernel: ACPI: Using ACPI (MADT) for SMP configuration information
Jan 13 20:34:20.873143 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000
Jan 13 20:34:20.873150 kernel: TSC deadline timer available
Jan 13 20:34:20.873157 kernel: smpboot: Allowing 4 CPUs, 0 hotplug CPUs
Jan 13 20:34:20.873164 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()
Jan 13 20:34:20.873170 kernel: kvm-guest: KVM setup pv remote TLB flush
Jan 13 20:34:20.873177 kernel: kvm-guest: setup PV sched yield
Jan 13 20:34:20.873186 kernel: [mem 0xc0000000-0xfed1bfff] available for PCI devices
Jan 13 20:34:20.873193 kernel: Booting paravirtualized kernel on KVM
Jan 13 20:34:20.873200 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
Jan 13 20:34:20.873207 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1
Jan 13 20:34:20.873214 kernel: percpu: Embedded 58 pages/cpu s197032 r8192 d32344 u524288
Jan 13 20:34:20.873221 kernel: pcpu-alloc: s197032 r8192 d32344 u524288 alloc=1*2097152
Jan 13 20:34:20.873227 kernel: pcpu-alloc: [0] 0 1 2 3 
Jan 13 20:34:20.873234 kernel: kvm-guest: PV spinlocks enabled
Jan 13 20:34:20.873241 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear)
Jan 13 20:34:20.873251 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,115200 flatcar.first_boot=detected verity.usrhash=1175b5bd4028ce8485b23b7d346f787308cbfa43cca7b1fefd4254406dce7d07
Jan 13 20:34:20.873258 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space.
Jan 13 20:34:20.873265 kernel: random: crng init done
Jan 13 20:34:20.873272 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)
Jan 13 20:34:20.873279 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)
Jan 13 20:34:20.873286 kernel: Fallback order for Node 0: 0 
Jan 13 20:34:20.873293 kernel: Built 1 zonelists, mobility grouping on.  Total pages: 632732
Jan 13 20:34:20.873299 kernel: Policy zone: DMA32
Jan 13 20:34:20.873306 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off
Jan 13 20:34:20.873316 kernel: Memory: 2434592K/2571752K available (12288K kernel code, 2299K rwdata, 22736K rodata, 42976K init, 2216K bss, 136900K reserved, 0K cma-reserved)
Jan 13 20:34:20.873323 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
Jan 13 20:34:20.873329 kernel: ftrace: allocating 37920 entries in 149 pages
Jan 13 20:34:20.873336 kernel: ftrace: allocated 149 pages with 4 groups
Jan 13 20:34:20.873343 kernel: Dynamic Preempt: voluntary
Jan 13 20:34:20.873350 kernel: rcu: Preemptible hierarchical RCU implementation.
Jan 13 20:34:20.873357 kernel: rcu:         RCU event tracing is enabled.
Jan 13 20:34:20.873364 kernel: rcu:         RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4.
Jan 13 20:34:20.873371 kernel:         Trampoline variant of Tasks RCU enabled.
Jan 13 20:34:20.873381 kernel:         Rude variant of Tasks RCU enabled.
Jan 13 20:34:20.873388 kernel:         Tracing variant of Tasks RCU enabled.
Jan 13 20:34:20.873395 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.
Jan 13 20:34:20.873402 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4
Jan 13 20:34:20.873408 kernel: NR_IRQS: 33024, nr_irqs: 456, preallocated irqs: 16
Jan 13 20:34:20.873415 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention.
Jan 13 20:34:20.873422 kernel: Console: colour VGA+ 80x25
Jan 13 20:34:20.873429 kernel: printk: console [ttyS0] enabled
Jan 13 20:34:20.873436 kernel: ACPI: Core revision 20230628
Jan 13 20:34:20.873445 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
Jan 13 20:34:20.873452 kernel: APIC: Switch to symmetric I/O mode setup
Jan 13 20:34:20.873458 kernel: x2apic enabled
Jan 13 20:34:20.873465 kernel: APIC: Switched APIC routing to: physical x2apic
Jan 13 20:34:20.873472 kernel: kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask()
Jan 13 20:34:20.873479 kernel: kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself()
Jan 13 20:34:20.873486 kernel: kvm-guest: setup PV IPIs
Jan 13 20:34:20.873502 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
Jan 13 20:34:20.873509 kernel: tsc: Marking TSC unstable due to TSCs unsynchronized
Jan 13 20:34:20.873516 kernel: Calibrating delay loop (skipped) preset value.. 5589.49 BogoMIPS (lpj=2794748)
Jan 13 20:34:20.873523 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated
Jan 13 20:34:20.873530 kernel: Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127
Jan 13 20:34:20.873539 kernel: Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0
Jan 13 20:34:20.873547 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization
Jan 13 20:34:20.873554 kernel: Spectre V2 : Mitigation: Retpolines
Jan 13 20:34:20.873561 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
Jan 13 20:34:20.873570 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT
Jan 13 20:34:20.873577 kernel: Spectre V2 : Enabling Speculation Barrier for firmware calls
Jan 13 20:34:20.873584 kernel: RETBleed: Mitigation: untrained return thunk
Jan 13 20:34:20.873592 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier
Jan 13 20:34:20.873599 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl
Jan 13 20:34:20.873606 kernel: Speculative Return Stack Overflow: IBPB-extending microcode not applied!
Jan 13 20:34:20.873614 kernel: Speculative Return Stack Overflow: WARNING: See https://kernel.org/doc/html/latest/admin-guide/hw-vuln/srso.html for mitigation options.
Jan 13 20:34:20.873621 kernel: Speculative Return Stack Overflow: Vulnerable: Safe RET, no microcode
Jan 13 20:34:20.873628 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
Jan 13 20:34:20.873637 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
Jan 13 20:34:20.873645 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
Jan 13 20:34:20.873652 kernel: x86/fpu: xstate_offset[2]:  576, xstate_sizes[2]:  256
Jan 13 20:34:20.873659 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'compacted' format.
Jan 13 20:34:20.873666 kernel: Freeing SMP alternatives memory: 32K
Jan 13 20:34:20.873673 kernel: pid_max: default: 32768 minimum: 301
Jan 13 20:34:20.873680 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity
Jan 13 20:34:20.873687 kernel: landlock: Up and running.
Jan 13 20:34:20.873694 kernel: SELinux:  Initializing.
Jan 13 20:34:20.873704 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
Jan 13 20:34:20.873711 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
Jan 13 20:34:20.873718 kernel: smpboot: CPU0: AMD EPYC 7402P 24-Core Processor (family: 0x17, model: 0x31, stepping: 0x0)
Jan 13 20:34:20.873725 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4.
Jan 13 20:34:20.873733 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4.
Jan 13 20:34:20.873740 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4.
Jan 13 20:34:20.873747 kernel: Performance Events: Fam17h+ core perfctr, AMD PMU driver.
Jan 13 20:34:20.873754 kernel: ... version:                0
Jan 13 20:34:20.873763 kernel: ... bit width:              48
Jan 13 20:34:20.873770 kernel: ... generic registers:      6
Jan 13 20:34:20.873777 kernel: ... value mask:             0000ffffffffffff
Jan 13 20:34:20.873784 kernel: ... max period:             00007fffffffffff
Jan 13 20:34:20.873791 kernel: ... fixed-purpose events:   0
Jan 13 20:34:20.873798 kernel: ... event mask:             000000000000003f
Jan 13 20:34:20.873805 kernel: signal: max sigframe size: 1776
Jan 13 20:34:20.873813 kernel: rcu: Hierarchical SRCU implementation.
Jan 13 20:34:20.873820 kernel: rcu:         Max phase no-delay instances is 400.
Jan 13 20:34:20.873827 kernel: smp: Bringing up secondary CPUs ...
Jan 13 20:34:20.873836 kernel: smpboot: x86: Booting SMP configuration:
Jan 13 20:34:20.873843 kernel: .... node  #0, CPUs:      #1 #2 #3
Jan 13 20:34:20.873850 kernel: smp: Brought up 1 node, 4 CPUs
Jan 13 20:34:20.873857 kernel: smpboot: Max logical packages: 1
Jan 13 20:34:20.873865 kernel: smpboot: Total of 4 processors activated (22357.98 BogoMIPS)
Jan 13 20:34:20.873872 kernel: devtmpfs: initialized
Jan 13 20:34:20.873879 kernel: x86/mm: Memory block size: 128MB
Jan 13 20:34:20.873886 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
Jan 13 20:34:20.873893 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
Jan 13 20:34:20.873902 kernel: pinctrl core: initialized pinctrl subsystem
Jan 13 20:34:20.873910 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family
Jan 13 20:34:20.873925 kernel: audit: initializing netlink subsys (disabled)
Jan 13 20:34:20.873933 kernel: audit: type=2000 audit(1736800461.110:1): state=initialized audit_enabled=0 res=1
Jan 13 20:34:20.873940 kernel: thermal_sys: Registered thermal governor 'step_wise'
Jan 13 20:34:20.873947 kernel: thermal_sys: Registered thermal governor 'user_space'
Jan 13 20:34:20.873954 kernel: cpuidle: using governor menu
Jan 13 20:34:20.873973 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
Jan 13 20:34:20.873981 kernel: dca service started, version 1.12.1
Jan 13 20:34:20.873991 kernel: PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xb0000000-0xbfffffff] (base 0xb0000000)
Jan 13 20:34:20.873998 kernel: PCI: MMCONFIG at [mem 0xb0000000-0xbfffffff] reserved as E820 entry
Jan 13 20:34:20.874005 kernel: PCI: Using configuration type 1 for base access
Jan 13 20:34:20.874012 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.
Jan 13 20:34:20.874020 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages
Jan 13 20:34:20.874027 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page
Jan 13 20:34:20.874034 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages
Jan 13 20:34:20.874041 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page
Jan 13 20:34:20.874048 kernel: ACPI: Added _OSI(Module Device)
Jan 13 20:34:20.874057 kernel: ACPI: Added _OSI(Processor Device)
Jan 13 20:34:20.874064 kernel: ACPI: Added _OSI(3.0 _SCP Extensions)
Jan 13 20:34:20.874072 kernel: ACPI: Added _OSI(Processor Aggregator Device)
Jan 13 20:34:20.874079 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded
Jan 13 20:34:20.874086 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC
Jan 13 20:34:20.874093 kernel: ACPI: Interpreter enabled
Jan 13 20:34:20.874100 kernel: ACPI: PM: (supports S0 S3 S5)
Jan 13 20:34:20.874107 kernel: ACPI: Using IOAPIC for interrupt routing
Jan 13 20:34:20.874114 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
Jan 13 20:34:20.874123 kernel: PCI: Using E820 reservations for host bridge windows
Jan 13 20:34:20.874131 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F
Jan 13 20:34:20.874138 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
Jan 13 20:34:20.874308 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]
Jan 13 20:34:20.874435 kernel: acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]
Jan 13 20:34:20.874553 kernel: acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]
Jan 13 20:34:20.874563 kernel: PCI host bridge to bus 0000:00
Jan 13 20:34:20.874687 kernel: pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
Jan 13 20:34:20.874797 kernel: pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
Jan 13 20:34:20.874919 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
Jan 13 20:34:20.875044 kernel: pci_bus 0000:00: root bus resource [mem 0x9d000000-0xafffffff window]
Jan 13 20:34:20.875154 kernel: pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]
Jan 13 20:34:20.875262 kernel: pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]
Jan 13 20:34:20.875370 kernel: pci_bus 0000:00: root bus resource [bus 00-ff]
Jan 13 20:34:20.875508 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000
Jan 13 20:34:20.875654 kernel: pci 0000:00:01.0: [1234:1111] type 00 class 0x030000
Jan 13 20:34:20.875850 kernel: pci 0000:00:01.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref]
Jan 13 20:34:20.875997 kernel: pci 0000:00:01.0: reg 0x18: [mem 0xfebd0000-0xfebd0fff]
Jan 13 20:34:20.876121 kernel: pci 0000:00:01.0: reg 0x30: [mem 0xfebc0000-0xfebcffff pref]
Jan 13 20:34:20.876239 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
Jan 13 20:34:20.876373 kernel: pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00
Jan 13 20:34:20.876496 kernel: pci 0000:00:02.0: reg 0x10: [io  0xc0c0-0xc0df]
Jan 13 20:34:20.876615 kernel: pci 0000:00:02.0: reg 0x14: [mem 0xfebd1000-0xfebd1fff]
Jan 13 20:34:20.876733 kernel: pci 0000:00:02.0: reg 0x20: [mem 0xfe000000-0xfe003fff 64bit pref]
Jan 13 20:34:20.876859 kernel: pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000
Jan 13 20:34:20.877049 kernel: pci 0000:00:03.0: reg 0x10: [io  0xc000-0xc07f]
Jan 13 20:34:20.877195 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xfebd2000-0xfebd2fff]
Jan 13 20:34:20.877392 kernel: pci 0000:00:03.0: reg 0x20: [mem 0xfe004000-0xfe007fff 64bit pref]
Jan 13 20:34:20.877524 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000
Jan 13 20:34:20.877644 kernel: pci 0000:00:04.0: reg 0x10: [io  0xc0e0-0xc0ff]
Jan 13 20:34:20.877764 kernel: pci 0000:00:04.0: reg 0x14: [mem 0xfebd3000-0xfebd3fff]
Jan 13 20:34:20.877883 kernel: pci 0000:00:04.0: reg 0x20: [mem 0xfe008000-0xfe00bfff 64bit pref]
Jan 13 20:34:20.878032 kernel: pci 0000:00:04.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref]
Jan 13 20:34:20.878160 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100
Jan 13 20:34:20.878285 kernel: pci 0000:00:1f.0: quirk: [io  0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO
Jan 13 20:34:20.878409 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601
Jan 13 20:34:20.878536 kernel: pci 0000:00:1f.2: reg 0x20: [io  0xc100-0xc11f]
Jan 13 20:34:20.878791 kernel: pci 0000:00:1f.2: reg 0x24: [mem 0xfebd4000-0xfebd4fff]
Jan 13 20:34:20.878943 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500
Jan 13 20:34:20.879080 kernel: pci 0000:00:1f.3: reg 0x20: [io  0x0700-0x073f]
Jan 13 20:34:20.879091 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10
Jan 13 20:34:20.879103 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10
Jan 13 20:34:20.879111 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11
Jan 13 20:34:20.879118 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11
Jan 13 20:34:20.879125 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 10
Jan 13 20:34:20.879132 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 10
Jan 13 20:34:20.879139 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11
Jan 13 20:34:20.879146 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11
Jan 13 20:34:20.879153 kernel: ACPI: PCI: Interrupt link GSIA configured for IRQ 16
Jan 13 20:34:20.879163 kernel: ACPI: PCI: Interrupt link GSIB configured for IRQ 17
Jan 13 20:34:20.879170 kernel: ACPI: PCI: Interrupt link GSIC configured for IRQ 18
Jan 13 20:34:20.879177 kernel: ACPI: PCI: Interrupt link GSID configured for IRQ 19
Jan 13 20:34:20.879184 kernel: ACPI: PCI: Interrupt link GSIE configured for IRQ 20
Jan 13 20:34:20.879191 kernel: ACPI: PCI: Interrupt link GSIF configured for IRQ 21
Jan 13 20:34:20.879198 kernel: ACPI: PCI: Interrupt link GSIG configured for IRQ 22
Jan 13 20:34:20.879206 kernel: ACPI: PCI: Interrupt link GSIH configured for IRQ 23
Jan 13 20:34:20.879213 kernel: iommu: Default domain type: Translated
Jan 13 20:34:20.879220 kernel: iommu: DMA domain TLB invalidation policy: lazy mode
Jan 13 20:34:20.879227 kernel: PCI: Using ACPI for IRQ routing
Jan 13 20:34:20.879237 kernel: PCI: pci_cache_line_size set to 64 bytes
Jan 13 20:34:20.879244 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff]
Jan 13 20:34:20.879251 kernel: e820: reserve RAM buffer [mem 0x9cfdc000-0x9fffffff]
Jan 13 20:34:20.879370 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device
Jan 13 20:34:20.879489 kernel: pci 0000:00:01.0: vgaarb: bridge control possible
Jan 13 20:34:20.879607 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
Jan 13 20:34:20.879617 kernel: vgaarb: loaded
Jan 13 20:34:20.879624 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
Jan 13 20:34:20.879635 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter
Jan 13 20:34:20.879650 kernel: clocksource: Switched to clocksource kvm-clock
Jan 13 20:34:20.879659 kernel: VFS: Disk quotas dquot_6.6.0
Jan 13 20:34:20.879674 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
Jan 13 20:34:20.879688 kernel: pnp: PnP ACPI init
Jan 13 20:34:20.879840 kernel: system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved
Jan 13 20:34:20.879852 kernel: pnp: PnP ACPI: found 6 devices
Jan 13 20:34:20.879860 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
Jan 13 20:34:20.879870 kernel: NET: Registered PF_INET protocol family
Jan 13 20:34:20.879878 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)
Jan 13 20:34:20.879886 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)
Jan 13 20:34:20.879893 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
Jan 13 20:34:20.879901 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)
Jan 13 20:34:20.879909 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear)
Jan 13 20:34:20.879926 kernel: TCP: Hash tables configured (established 32768 bind 32768)
Jan 13 20:34:20.879934 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear)
Jan 13 20:34:20.879941 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear)
Jan 13 20:34:20.879951 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family
Jan 13 20:34:20.879958 kernel: NET: Registered PF_XDP protocol family
Jan 13 20:34:20.880134 kernel: pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
Jan 13 20:34:20.880242 kernel: pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
Jan 13 20:34:20.880350 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
Jan 13 20:34:20.880457 kernel: pci_bus 0000:00: resource 7 [mem 0x9d000000-0xafffffff window]
Jan 13 20:34:20.880564 kernel: pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]
Jan 13 20:34:20.880671 kernel: pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]
Jan 13 20:34:20.880684 kernel: PCI: CLS 0 bytes, default 64
Jan 13 20:34:20.880692 kernel: Initialise system trusted keyrings
Jan 13 20:34:20.880700 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0
Jan 13 20:34:20.880708 kernel: Key type asymmetric registered
Jan 13 20:34:20.880715 kernel: Asymmetric key parser 'x509' registered
Jan 13 20:34:20.880722 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
Jan 13 20:34:20.880730 kernel: io scheduler mq-deadline registered
Jan 13 20:34:20.880737 kernel: io scheduler kyber registered
Jan 13 20:34:20.880744 kernel: io scheduler bfq registered
Jan 13 20:34:20.880754 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00
Jan 13 20:34:20.880761 kernel: ACPI: \_SB_.GSIG: Enabled at IRQ 22
Jan 13 20:34:20.880769 kernel: ACPI: \_SB_.GSIH: Enabled at IRQ 23
Jan 13 20:34:20.880776 kernel: ACPI: \_SB_.GSIE: Enabled at IRQ 20
Jan 13 20:34:20.880783 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
Jan 13 20:34:20.880790 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
Jan 13 20:34:20.880798 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
Jan 13 20:34:20.880805 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1
Jan 13 20:34:20.880812 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12
Jan 13 20:34:20.880948 kernel: rtc_cmos 00:04: RTC can wake from S4
Jan 13 20:34:20.880970 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0
Jan 13 20:34:20.881083 kernel: rtc_cmos 00:04: registered as rtc0
Jan 13 20:34:20.881195 kernel: rtc_cmos 00:04: setting system clock to 2025-01-13T20:34:20 UTC (1736800460)
Jan 13 20:34:20.881305 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs
Jan 13 20:34:20.881315 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled
Jan 13 20:34:20.881322 kernel: NET: Registered PF_INET6 protocol family
Jan 13 20:34:20.881330 kernel: Segment Routing with IPv6
Jan 13 20:34:20.881340 kernel: In-situ OAM (IOAM) with IPv6
Jan 13 20:34:20.881348 kernel: NET: Registered PF_PACKET protocol family
Jan 13 20:34:20.881355 kernel: Key type dns_resolver registered
Jan 13 20:34:20.881362 kernel: IPI shorthand broadcast: enabled
Jan 13 20:34:20.881369 kernel: sched_clock: Marking stable (539002564, 105412308)->(699282224, -54867352)
Jan 13 20:34:20.881376 kernel: registered taskstats version 1
Jan 13 20:34:20.881383 kernel: Loading compiled-in X.509 certificates
Jan 13 20:34:20.881391 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.71-flatcar: 98739e9049f62881f4df7ffd1e39335f7f55b344'
Jan 13 20:34:20.881398 kernel: Key type .fscrypt registered
Jan 13 20:34:20.881407 kernel: Key type fscrypt-provisioning registered
Jan 13 20:34:20.881414 kernel: ima: No TPM chip found, activating TPM-bypass!
Jan 13 20:34:20.881421 kernel: ima: Allocated hash algorithm: sha1
Jan 13 20:34:20.881428 kernel: ima: No architecture policies found
Jan 13 20:34:20.881436 kernel: clk: Disabling unused clocks
Jan 13 20:34:20.881443 kernel: Freeing unused kernel image (initmem) memory: 42976K
Jan 13 20:34:20.881450 kernel: Write protecting the kernel read-only data: 36864k
Jan 13 20:34:20.881457 kernel: Freeing unused kernel image (rodata/data gap) memory: 1840K
Jan 13 20:34:20.881464 kernel: Run /init as init process
Jan 13 20:34:20.881474 kernel:   with arguments:
Jan 13 20:34:20.881481 kernel:     /init
Jan 13 20:34:20.881488 kernel:   with environment:
Jan 13 20:34:20.881495 kernel:     HOME=/
Jan 13 20:34:20.881502 kernel:     TERM=linux
Jan 13 20:34:20.881509 kernel:     BOOT_IMAGE=/flatcar/vmlinuz-a
Jan 13 20:34:20.881518 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified)
Jan 13 20:34:20.881528 systemd[1]: Detected virtualization kvm.
Jan 13 20:34:20.881538 systemd[1]: Detected architecture x86-64.
Jan 13 20:34:20.881546 systemd[1]: Running in initrd.
Jan 13 20:34:20.881553 systemd[1]: No hostname configured, using default hostname.
Jan 13 20:34:20.881561 systemd[1]: Hostname set to <localhost>.
Jan 13 20:34:20.881569 systemd[1]: Initializing machine ID from VM UUID.
Jan 13 20:34:20.881577 systemd[1]: Queued start job for default target initrd.target.
Jan 13 20:34:20.881584 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch.
Jan 13 20:34:20.881592 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch.
Jan 13 20:34:20.881603 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM...
Jan 13 20:34:20.881622 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM...
Jan 13 20:34:20.881632 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT...
Jan 13 20:34:20.881641 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A...
Jan 13 20:34:20.881650 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132...
Jan 13 20:34:20.881661 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr...
Jan 13 20:34:20.881668 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre).
Jan 13 20:34:20.881676 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes.
Jan 13 20:34:20.881684 systemd[1]: Reached target paths.target - Path Units.
Jan 13 20:34:20.881692 systemd[1]: Reached target slices.target - Slice Units.
Jan 13 20:34:20.881700 systemd[1]: Reached target swap.target - Swaps.
Jan 13 20:34:20.881708 systemd[1]: Reached target timers.target - Timer Units.
Jan 13 20:34:20.881715 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket.
Jan 13 20:34:20.881728 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket.
Jan 13 20:34:20.881736 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log).
Jan 13 20:34:20.881744 systemd[1]: Listening on systemd-journald.socket - Journal Socket.
Jan 13 20:34:20.881752 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket.
Jan 13 20:34:20.881760 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket.
Jan 13 20:34:20.881768 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket.
Jan 13 20:34:20.881776 systemd[1]: Reached target sockets.target - Socket Units.
Jan 13 20:34:20.881783 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup...
Jan 13 20:34:20.881794 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes...
Jan 13 20:34:20.881801 systemd[1]: Finished network-cleanup.service - Network Cleanup.
Jan 13 20:34:20.881809 systemd[1]: Starting systemd-fsck-usr.service...
Jan 13 20:34:20.881817 systemd[1]: Starting systemd-journald.service - Journal Service...
Jan 13 20:34:20.881825 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules...
Jan 13 20:34:20.881833 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup...
Jan 13 20:34:20.881841 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup.
Jan 13 20:34:20.881849 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes.
Jan 13 20:34:20.881857 systemd[1]: Finished systemd-fsck-usr.service.
Jan 13 20:34:20.881885 systemd-journald[194]: Collecting audit messages is disabled.
Jan 13 20:34:20.881909 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully...
Jan 13 20:34:20.881929 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully.
Jan 13 20:34:20.881937 systemd-journald[194]: Journal started
Jan 13 20:34:20.881957 systemd-journald[194]: Runtime Journal (/run/log/journal/84b50cffbc9548b19f7d105f8f081759) is 6.0M, max 48.4M, 42.3M free.
Jan 13 20:34:20.877395 systemd-modules-load[195]: Inserted module 'overlay'
Jan 13 20:34:20.913605 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
Jan 13 20:34:20.913620 kernel: Bridge firewalling registered
Jan 13 20:34:20.904242 systemd-modules-load[195]: Inserted module 'br_netfilter'
Jan 13 20:34:20.916291 systemd[1]: Started systemd-journald.service - Journal Service.
Jan 13 20:34:20.916673 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules.
Jan 13 20:34:20.918937 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup.
Jan 13 20:34:20.931197 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters...
Jan 13 20:34:20.932537 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables...
Jan 13 20:34:20.933922 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev...
Jan 13 20:34:20.937842 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories...
Jan 13 20:34:20.947083 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev.
Jan 13 20:34:20.948243 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables.
Jan 13 20:34:20.953625 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters.
Jan 13 20:34:20.963194 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook...
Jan 13 20:34:20.964099 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories.
Jan 13 20:34:20.968605 systemd[1]: Starting systemd-resolved.service - Network Name Resolution...
Jan 13 20:34:20.976018 dracut-cmdline[228]: dracut-dracut-053
Jan 13 20:34:20.980025 dracut-cmdline[228]: 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,115200 flatcar.first_boot=detected verity.usrhash=1175b5bd4028ce8485b23b7d346f787308cbfa43cca7b1fefd4254406dce7d07
Jan 13 20:34:21.014107 systemd-resolved[232]: Positive Trust Anchors:
Jan 13 20:34:21.014126 systemd-resolved[232]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
Jan 13 20:34:21.014163 systemd-resolved[232]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test
Jan 13 20:34:21.016793 systemd-resolved[232]: Defaulting to hostname 'linux'.
Jan 13 20:34:21.017985 systemd[1]: Started systemd-resolved.service - Network Name Resolution.
Jan 13 20:34:21.024124 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups.
Jan 13 20:34:21.075999 kernel: SCSI subsystem initialized
Jan 13 20:34:21.084987 kernel: Loading iSCSI transport class v2.0-870.
Jan 13 20:34:21.095995 kernel: iscsi: registered transport (tcp)
Jan 13 20:34:21.119993 kernel: iscsi: registered transport (qla4xxx)
Jan 13 20:34:21.120016 kernel: QLogic iSCSI HBA Driver
Jan 13 20:34:21.171213 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook.
Jan 13 20:34:21.184149 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook...
Jan 13 20:34:21.210879 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.
Jan 13 20:34:21.210952 kernel: device-mapper: uevent: version 1.0.3
Jan 13 20:34:21.210979 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com
Jan 13 20:34:21.251992 kernel: raid6: avx2x4   gen() 29871 MB/s
Jan 13 20:34:21.268989 kernel: raid6: avx2x2   gen() 30656 MB/s
Jan 13 20:34:21.286138 kernel: raid6: avx2x1   gen() 25690 MB/s
Jan 13 20:34:21.286159 kernel: raid6: using algorithm avx2x2 gen() 30656 MB/s
Jan 13 20:34:21.304104 kernel: raid6: .... xor() 19647 MB/s, rmw enabled
Jan 13 20:34:21.304125 kernel: raid6: using avx2x2 recovery algorithm
Jan 13 20:34:21.324993 kernel: xor: automatically using best checksumming function   avx       
Jan 13 20:34:21.477994 kernel: Btrfs loaded, zoned=no, fsverity=no
Jan 13 20:34:21.488223 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook.
Jan 13 20:34:21.502115 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files...
Jan 13 20:34:21.513914 systemd-udevd[414]: Using default interface naming scheme 'v255'.
Jan 13 20:34:21.518820 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files.
Jan 13 20:34:21.526120 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook...
Jan 13 20:34:21.538681 dracut-pre-trigger[420]: rd.md=0: removing MD RAID activation
Jan 13 20:34:21.568266 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook.
Jan 13 20:34:21.579083 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices...
Jan 13 20:34:21.648073 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices.
Jan 13 20:34:21.660136 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook...
Jan 13 20:34:21.675991 kernel: virtio_blk virtio1: 4/0/0 default/read/poll queues
Jan 13 20:34:21.709804 kernel: cryptd: max_cpu_qlen set to 1000
Jan 13 20:34:21.709832 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB)
Jan 13 20:34:21.710137 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk.
Jan 13 20:34:21.710154 kernel: GPT:9289727 != 19775487
Jan 13 20:34:21.710168 kernel: GPT:Alternate GPT header not at the end of the disk.
Jan 13 20:34:21.710181 kernel: GPT:9289727 != 19775487
Jan 13 20:34:21.710194 kernel: GPT: Use GNU Parted to correct GPT errors.
Jan 13 20:34:21.710208 kernel:  vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9
Jan 13 20:34:21.710222 kernel: AVX2 version of gcm_enc/dec engaged.
Jan 13 20:34:21.710240 kernel: AES CTR mode by8 optimization enabled
Jan 13 20:34:21.680352 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook.
Jan 13 20:34:21.682349 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems.
Jan 13 20:34:21.683994 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes.
Jan 13 20:34:21.718358 kernel: libata version 3.00 loaded.
Jan 13 20:34:21.686124 systemd[1]: Reached target remote-fs.target - Remote File Systems.
Jan 13 20:34:21.697146 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook...
Jan 13 20:34:21.724111 kernel: ahci 0000:00:1f.2: version 3.0
Jan 13 20:34:21.759528 kernel: ACPI: \_SB_.GSIA: Enabled at IRQ 16
Jan 13 20:34:21.759554 kernel: ahci 0000:00:1f.2: AHCI 0001.0000 32 slots 6 ports 1.5 Gbps 0x3f impl SATA mode
Jan 13 20:34:21.759741 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only 
Jan 13 20:34:21.759909 kernel: BTRFS: device fsid 5e7921ba-229a-48a0-bc77-9b30aaa34aeb devid 1 transid 36 /dev/vda3 scanned by (udev-worker) (468)
Jan 13 20:34:21.759923 kernel: scsi host0: ahci
Jan 13 20:34:21.760134 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by (udev-worker) (471)
Jan 13 20:34:21.760148 kernel: scsi host1: ahci
Jan 13 20:34:21.760317 kernel: scsi host2: ahci
Jan 13 20:34:21.760490 kernel: scsi host3: ahci
Jan 13 20:34:21.760679 kernel: scsi host4: ahci
Jan 13 20:34:21.760861 kernel: scsi host5: ahci
Jan 13 20:34:21.761107 kernel: ata1: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4100 irq 34
Jan 13 20:34:21.761124 kernel: ata2: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4180 irq 34
Jan 13 20:34:21.761138 kernel: ata3: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4200 irq 34
Jan 13 20:34:21.761157 kernel: ata4: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4280 irq 34
Jan 13 20:34:21.761171 kernel: ata5: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4300 irq 34
Jan 13 20:34:21.761185 kernel: ata6: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4380 irq 34
Jan 13 20:34:21.716669 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook.
Jan 13 20:34:21.730584 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully.
Jan 13 20:34:21.730725 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters.
Jan 13 20:34:21.733525 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters...
Jan 13 20:34:21.735163 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
Jan 13 20:34:21.735368 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup.
Jan 13 20:34:21.738058 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup...
Jan 13 20:34:21.747262 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup...
Jan 13 20:34:21.764107 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM.
Jan 13 20:34:21.784146 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT.
Jan 13 20:34:21.802569 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup.
Jan 13 20:34:21.808720 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A.
Jan 13 20:34:21.810530 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132.
Jan 13 20:34:21.817199 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM.
Jan 13 20:34:21.826086 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary...
Jan 13 20:34:21.828091 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters...
Jan 13 20:34:21.835727 disk-uuid[565]: Primary Header is updated.
Jan 13 20:34:21.835727 disk-uuid[565]: Secondary Entries is updated.
Jan 13 20:34:21.835727 disk-uuid[565]: Secondary Header is updated.
Jan 13 20:34:21.839561 kernel:  vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9
Jan 13 20:34:21.843003 kernel:  vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9
Jan 13 20:34:21.849912 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters.
Jan 13 20:34:22.068419 kernel: ata2: SATA link down (SStatus 0 SControl 300)
Jan 13 20:34:22.068493 kernel: ata1: SATA link down (SStatus 0 SControl 300)
Jan 13 20:34:22.068519 kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
Jan 13 20:34:22.069986 kernel: ata5: SATA link down (SStatus 0 SControl 300)
Jan 13 20:34:22.070997 kernel: ata6: SATA link down (SStatus 0 SControl 300)
Jan 13 20:34:22.071011 kernel: ata4: SATA link down (SStatus 0 SControl 300)
Jan 13 20:34:22.071991 kernel: ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100
Jan 13 20:34:22.073220 kernel: ata3.00: applying bridge limits
Jan 13 20:34:22.073231 kernel: ata3.00: configured for UDMA/100
Jan 13 20:34:22.073985 kernel: scsi 2:0:0:0: CD-ROM            QEMU     QEMU DVD-ROM     2.5+ PQ: 0 ANSI: 5
Jan 13 20:34:22.126003 kernel: sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
Jan 13 20:34:22.139570 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20
Jan 13 20:34:22.139588 kernel: sr 2:0:0:0: Attached scsi CD-ROM sr0
Jan 13 20:34:22.844004 kernel:  vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9
Jan 13 20:34:22.844379 disk-uuid[567]: The operation has completed successfully.
Jan 13 20:34:22.873222 systemd[1]: disk-uuid.service: Deactivated successfully.
Jan 13 20:34:22.873333 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary.
Jan 13 20:34:22.907096 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr...
Jan 13 20:34:22.910282 sh[590]: Success
Jan 13 20:34:22.922064 kernel: device-mapper: verity: sha256 using implementation "sha256-ni"
Jan 13 20:34:22.954593 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr.
Jan 13 20:34:22.968524 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr...
Jan 13 20:34:22.971096 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr.
Jan 13 20:34:22.983494 kernel: BTRFS info (device dm-0): first mount of filesystem 5e7921ba-229a-48a0-bc77-9b30aaa34aeb
Jan 13 20:34:22.983526 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm
Jan 13 20:34:22.983536 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead
Jan 13 20:34:22.984526 kernel: BTRFS info (device dm-0): disabling log replay at mount time
Jan 13 20:34:22.985985 kernel: BTRFS info (device dm-0): using free space tree
Jan 13 20:34:22.989643 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr.
Jan 13 20:34:22.990776 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met.
Jan 13 20:34:22.991592 systemd[1]: Starting ignition-setup.service - Ignition (setup)...
Jan 13 20:34:22.992668 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline...
Jan 13 20:34:23.007850 kernel: BTRFS info (device vda6): first mount of filesystem 1066b41d-395d-4ccb-b5ae-be36ea0fc11e
Jan 13 20:34:23.007895 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm
Jan 13 20:34:23.007906 kernel: BTRFS info (device vda6): using free space tree
Jan 13 20:34:23.011003 kernel: BTRFS info (device vda6): auto enabling async discard
Jan 13 20:34:23.019487 systemd[1]: mnt-oem.mount: Deactivated successfully.
Jan 13 20:34:23.021313 kernel: BTRFS info (device vda6): last unmount of filesystem 1066b41d-395d-4ccb-b5ae-be36ea0fc11e
Jan 13 20:34:23.030921 systemd[1]: Finished ignition-setup.service - Ignition (setup).
Jan 13 20:34:23.037107 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)...
Jan 13 20:34:23.087819 ignition[692]: Ignition 2.20.0
Jan 13 20:34:23.087830 ignition[692]: Stage: fetch-offline
Jan 13 20:34:23.087877 ignition[692]: no configs at "/usr/lib/ignition/base.d"
Jan 13 20:34:23.087888 ignition[692]: no config dir at "/usr/lib/ignition/base.platform.d/qemu"
Jan 13 20:34:23.088044 ignition[692]: parsed url from cmdline: ""
Jan 13 20:34:23.088049 ignition[692]: no config URL provided
Jan 13 20:34:23.088056 ignition[692]: reading system config file "/usr/lib/ignition/user.ign"
Jan 13 20:34:23.088068 ignition[692]: no config at "/usr/lib/ignition/user.ign"
Jan 13 20:34:23.088100 ignition[692]: op(1): [started]  loading QEMU firmware config module
Jan 13 20:34:23.088106 ignition[692]: op(1): executing: "modprobe" "qemu_fw_cfg"
Jan 13 20:34:23.095656 ignition[692]: op(1): [finished] loading QEMU firmware config module
Jan 13 20:34:23.097151 ignition[692]: parsing config with SHA512: 3cc3bdf8331e0f551987584e5e6260422dfefbc488d2cb594bc12d3a951f847736cff71f0b3064517a9419d1018da897751714482537b12115f3f3d705cc35ab
Jan 13 20:34:23.099501 unknown[692]: fetched base config from "system"
Jan 13 20:34:23.099512 unknown[692]: fetched user config from "qemu"
Jan 13 20:34:23.099721 ignition[692]: fetch-offline: fetch-offline passed
Jan 13 20:34:23.100009 ignition[692]: Ignition finished successfully
Jan 13 20:34:23.102289 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline).
Jan 13 20:34:23.111168 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline.
Jan 13 20:34:23.124149 systemd[1]: Starting systemd-networkd.service - Network Configuration...
Jan 13 20:34:23.144615 systemd-networkd[779]: lo: Link UP
Jan 13 20:34:23.144624 systemd-networkd[779]: lo: Gained carrier
Jan 13 20:34:23.146407 systemd-networkd[779]: Enumeration completed
Jan 13 20:34:23.146838 systemd-networkd[779]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name.
Jan 13 20:34:23.146842 systemd-networkd[779]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network.
Jan 13 20:34:23.147335 systemd[1]: Started systemd-networkd.service - Network Configuration.
Jan 13 20:34:23.147648 systemd-networkd[779]: eth0: Link UP
Jan 13 20:34:23.147652 systemd-networkd[779]: eth0: Gained carrier
Jan 13 20:34:23.147659 systemd-networkd[779]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name.
Jan 13 20:34:23.156151 systemd[1]: Reached target network.target - Network.
Jan 13 20:34:23.158289 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json).
Jan 13 20:34:23.167001 systemd-networkd[779]: eth0: DHCPv4 address 10.0.0.54/16, gateway 10.0.0.1 acquired from 10.0.0.1
Jan 13 20:34:23.170105 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)...
Jan 13 20:34:23.182658 ignition[781]: Ignition 2.20.0
Jan 13 20:34:23.182669 ignition[781]: Stage: kargs
Jan 13 20:34:23.182835 ignition[781]: no configs at "/usr/lib/ignition/base.d"
Jan 13 20:34:23.182864 ignition[781]: no config dir at "/usr/lib/ignition/base.platform.d/qemu"
Jan 13 20:34:23.183645 ignition[781]: kargs: kargs passed
Jan 13 20:34:23.183690 ignition[781]: Ignition finished successfully
Jan 13 20:34:23.190022 systemd[1]: Finished ignition-kargs.service - Ignition (kargs).
Jan 13 20:34:23.198176 systemd[1]: Starting ignition-disks.service - Ignition (disks)...
Jan 13 20:34:23.208339 ignition[791]: Ignition 2.20.0
Jan 13 20:34:23.208348 ignition[791]: Stage: disks
Jan 13 20:34:23.208494 ignition[791]: no configs at "/usr/lib/ignition/base.d"
Jan 13 20:34:23.208505 ignition[791]: no config dir at "/usr/lib/ignition/base.platform.d/qemu"
Jan 13 20:34:23.209084 ignition[791]: disks: disks passed
Jan 13 20:34:23.209128 ignition[791]: Ignition finished successfully
Jan 13 20:34:23.215113 systemd[1]: Finished ignition-disks.service - Ignition (disks).
Jan 13 20:34:23.215724 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device.
Jan 13 20:34:23.217514 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems.
Jan 13 20:34:23.217898 systemd[1]: Reached target local-fs.target - Local File Systems.
Jan 13 20:34:23.222330 systemd[1]: Reached target sysinit.target - System Initialization.
Jan 13 20:34:23.224209 systemd[1]: Reached target basic.target - Basic System.
Jan 13 20:34:23.236079 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT...
Jan 13 20:34:23.249655 systemd-fsck[801]: ROOT: clean, 14/553520 files, 52654/553472 blocks
Jan 13 20:34:23.257431 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT.
Jan 13 20:34:23.266034 systemd[1]: Mounting sysroot.mount - /sysroot...
Jan 13 20:34:23.349991 kernel: EXT4-fs (vda9): mounted filesystem 84bcd1b2-5573-4e91-8fd5-f97782397085 r/w with ordered data mode. Quota mode: none.
Jan 13 20:34:23.350780 systemd[1]: Mounted sysroot.mount - /sysroot.
Jan 13 20:34:23.351746 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System.
Jan 13 20:34:23.370027 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem...
Jan 13 20:34:23.371443 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr...
Jan 13 20:34:23.372464 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met.
Jan 13 20:34:23.372498 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot).
Jan 13 20:34:23.372517 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup.
Jan 13 20:34:23.380670 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr.
Jan 13 20:34:23.382201 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup...
Jan 13 20:34:23.387015 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 scanned by mount (810)
Jan 13 20:34:23.389270 kernel: BTRFS info (device vda6): first mount of filesystem 1066b41d-395d-4ccb-b5ae-be36ea0fc11e
Jan 13 20:34:23.389297 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm
Jan 13 20:34:23.389311 kernel: BTRFS info (device vda6): using free space tree
Jan 13 20:34:23.393028 kernel: BTRFS info (device vda6): auto enabling async discard
Jan 13 20:34:23.394122 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem.
Jan 13 20:34:23.419513 initrd-setup-root[834]: cut: /sysroot/etc/passwd: No such file or directory
Jan 13 20:34:23.423783 initrd-setup-root[841]: cut: /sysroot/etc/group: No such file or directory
Jan 13 20:34:23.428543 initrd-setup-root[848]: cut: /sysroot/etc/shadow: No such file or directory
Jan 13 20:34:23.432617 initrd-setup-root[855]: cut: /sysroot/etc/gshadow: No such file or directory
Jan 13 20:34:23.514489 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup.
Jan 13 20:34:23.526096 systemd[1]: Starting ignition-mount.service - Ignition (mount)...
Jan 13 20:34:23.527783 systemd[1]: Starting sysroot-boot.service - /sysroot/boot...
Jan 13 20:34:23.533988 kernel: BTRFS info (device vda6): last unmount of filesystem 1066b41d-395d-4ccb-b5ae-be36ea0fc11e
Jan 13 20:34:23.550433 systemd[1]: Finished sysroot-boot.service - /sysroot/boot.
Jan 13 20:34:23.553580 ignition[924]: INFO     : Ignition 2.20.0
Jan 13 20:34:23.554488 ignition[924]: INFO     : Stage: mount
Jan 13 20:34:23.554488 ignition[924]: INFO     : no configs at "/usr/lib/ignition/base.d"
Jan 13 20:34:23.554488 ignition[924]: INFO     : no config dir at "/usr/lib/ignition/base.platform.d/qemu"
Jan 13 20:34:23.558362 ignition[924]: INFO     : mount: mount passed
Jan 13 20:34:23.558362 ignition[924]: INFO     : Ignition finished successfully
Jan 13 20:34:23.556707 systemd[1]: Finished ignition-mount.service - Ignition (mount).
Jan 13 20:34:23.567063 systemd[1]: Starting ignition-files.service - Ignition (files)...
Jan 13 20:34:23.982461 systemd[1]: sysroot-oem.mount: Deactivated successfully.
Jan 13 20:34:23.996132 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem...
Jan 13 20:34:24.003409 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/vda6 scanned by mount (937)
Jan 13 20:34:24.003439 kernel: BTRFS info (device vda6): first mount of filesystem 1066b41d-395d-4ccb-b5ae-be36ea0fc11e
Jan 13 20:34:24.003454 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm
Jan 13 20:34:24.004984 kernel: BTRFS info (device vda6): using free space tree
Jan 13 20:34:24.006976 kernel: BTRFS info (device vda6): auto enabling async discard
Jan 13 20:34:24.008645 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem.
Jan 13 20:34:24.033354 ignition[954]: INFO     : Ignition 2.20.0
Jan 13 20:34:24.033354 ignition[954]: INFO     : Stage: files
Jan 13 20:34:24.035119 ignition[954]: INFO     : no configs at "/usr/lib/ignition/base.d"
Jan 13 20:34:24.035119 ignition[954]: INFO     : no config dir at "/usr/lib/ignition/base.platform.d/qemu"
Jan 13 20:34:24.037543 ignition[954]: DEBUG    : files: compiled without relabeling support, skipping
Jan 13 20:34:24.038785 ignition[954]: INFO     : files: ensureUsers: op(1): [started]  creating or modifying user "core"
Jan 13 20:34:24.038785 ignition[954]: DEBUG    : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core"
Jan 13 20:34:24.042021 ignition[954]: INFO     : files: ensureUsers: op(1): [finished] creating or modifying user "core"
Jan 13 20:34:24.043452 ignition[954]: INFO     : files: ensureUsers: op(2): [started]  adding ssh keys to user "core"
Jan 13 20:34:24.043452 ignition[954]: INFO     : files: ensureUsers: op(2): [finished] adding ssh keys to user "core"
Jan 13 20:34:24.042601 unknown[954]: wrote ssh authorized keys file for user: core
Jan 13 20:34:24.047401 ignition[954]: INFO     : files: createFilesystemsFiles: createFiles: op(3): [started]  writing file "/sysroot/home/core/install.sh"
Jan 13 20:34:24.049157 ignition[954]: INFO     : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh"
Jan 13 20:34:24.051132 ignition[954]: INFO     : files: createFilesystemsFiles: createFiles: op(4): [started]  writing file "/sysroot/etc/flatcar/update.conf"
Jan 13 20:34:24.052943 ignition[954]: INFO     : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf"
Jan 13 20:34:24.054696 ignition[954]: INFO     : files: createFilesystemsFiles: createFiles: op(5): [started]  writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.30.1-x86-64.raw"
Jan 13 20:34:24.057189 ignition[954]: INFO     : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.30.1-x86-64.raw"
Jan 13 20:34:24.059591 ignition[954]: INFO     : files: createFilesystemsFiles: createFiles: op(6): [started]  writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.30.1-x86-64.raw"
Jan 13 20:34:24.061649 ignition[954]: INFO     : files: createFilesystemsFiles: createFiles: op(6): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.30.1-x86-64.raw: attempt #1
Jan 13 20:34:24.453571 ignition[954]: INFO     : files: createFilesystemsFiles: createFiles: op(6): GET result: OK
Jan 13 20:34:24.517392 systemd-networkd[779]: eth0: Gained IPv6LL
Jan 13 20:34:24.861710 ignition[954]: INFO     : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.30.1-x86-64.raw"
Jan 13 20:34:24.861710 ignition[954]: INFO     : files: op(7): [started]  processing unit "coreos-metadata.service"
Jan 13 20:34:24.865771 ignition[954]: INFO     : files: op(7): op(8): [started]  writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service"
Jan 13 20:34:24.865771 ignition[954]: INFO     : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service"
Jan 13 20:34:24.865771 ignition[954]: INFO     : files: op(7): [finished] processing unit "coreos-metadata.service"
Jan 13 20:34:24.865771 ignition[954]: INFO     : files: op(9): [started]  setting preset to disabled for "coreos-metadata.service"
Jan 13 20:34:24.885479 ignition[954]: INFO     : files: op(9): op(a): [started]  removing enablement symlink(s) for "coreos-metadata.service"
Jan 13 20:34:24.891879 ignition[954]: INFO     : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service"
Jan 13 20:34:24.891879 ignition[954]: INFO     : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service"
Jan 13 20:34:24.891879 ignition[954]: INFO     : files: createResultFile: createFiles: op(b): [started]  writing file "/sysroot/etc/.ignition-result.json"
Jan 13 20:34:24.896876 ignition[954]: INFO     : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json"
Jan 13 20:34:24.896876 ignition[954]: INFO     : files: files passed
Jan 13 20:34:24.896876 ignition[954]: INFO     : Ignition finished successfully
Jan 13 20:34:24.902282 systemd[1]: Finished ignition-files.service - Ignition (files).
Jan 13 20:34:24.916115 systemd[1]: Starting ignition-quench.service - Ignition (record completion)...
Jan 13 20:34:24.917383 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion...
Jan 13 20:34:24.924376 systemd[1]: ignition-quench.service: Deactivated successfully.
Jan 13 20:34:24.924519 systemd[1]: Finished ignition-quench.service - Ignition (record completion).
Jan 13 20:34:24.928277 initrd-setup-root-after-ignition[983]: grep: /sysroot/oem/oem-release: No such file or directory
Jan 13 20:34:24.931106 initrd-setup-root-after-ignition[985]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory
Jan 13 20:34:24.931106 initrd-setup-root-after-ignition[985]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory
Jan 13 20:34:24.935951 initrd-setup-root-after-ignition[989]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory
Jan 13 20:34:24.933815 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion.
Jan 13 20:34:24.936617 systemd[1]: Reached target ignition-complete.target - Ignition Complete.
Jan 13 20:34:24.951094 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root...
Jan 13 20:34:24.977344 systemd[1]: initrd-parse-etc.service: Deactivated successfully.
Jan 13 20:34:24.977464 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root.
Jan 13 20:34:24.978385 systemd[1]: Reached target initrd-fs.target - Initrd File Systems.
Jan 13 20:34:24.981248 systemd[1]: Reached target initrd.target - Initrd Default Target.
Jan 13 20:34:24.981607 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met.
Jan 13 20:34:24.993093 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook...
Jan 13 20:34:25.010063 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook.
Jan 13 20:34:25.021287 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons...
Jan 13 20:34:25.032338 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups.
Jan 13 20:34:25.033764 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes.
Jan 13 20:34:25.036048 systemd[1]: Stopped target timers.target - Timer Units.
Jan 13 20:34:25.038051 systemd[1]: dracut-pre-pivot.service: Deactivated successfully.
Jan 13 20:34:25.038203 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook.
Jan 13 20:34:25.040327 systemd[1]: Stopped target initrd.target - Initrd Default Target.
Jan 13 20:34:25.042105 systemd[1]: Stopped target basic.target - Basic System.
Jan 13 20:34:25.044161 systemd[1]: Stopped target ignition-complete.target - Ignition Complete.
Jan 13 20:34:25.046240 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup.
Jan 13 20:34:25.048269 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device.
Jan 13 20:34:25.050446 systemd[1]: Stopped target remote-fs.target - Remote File Systems.
Jan 13 20:34:25.052617 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems.
Jan 13 20:34:25.054929 systemd[1]: Stopped target sysinit.target - System Initialization.
Jan 13 20:34:25.056996 systemd[1]: Stopped target local-fs.target - Local File Systems.
Jan 13 20:34:25.059216 systemd[1]: Stopped target swap.target - Swaps.
Jan 13 20:34:25.061031 systemd[1]: dracut-pre-mount.service: Deactivated successfully.
Jan 13 20:34:25.061180 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook.
Jan 13 20:34:25.063295 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes.
Jan 13 20:34:25.064990 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre).
Jan 13 20:34:25.067088 systemd[1]: clevis-luks-askpass.path: Deactivated successfully.
Jan 13 20:34:25.067217 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch.
Jan 13 20:34:25.069346 systemd[1]: dracut-initqueue.service: Deactivated successfully.
Jan 13 20:34:25.069491 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook.
Jan 13 20:34:25.071666 systemd[1]: ignition-fetch-offline.service: Deactivated successfully.
Jan 13 20:34:25.071816 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline).
Jan 13 20:34:25.073896 systemd[1]: Stopped target paths.target - Path Units.
Jan 13 20:34:25.075626 systemd[1]: systemd-ask-password-console.path: Deactivated successfully.
Jan 13 20:34:25.075814 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch.
Jan 13 20:34:25.078271 systemd[1]: Stopped target slices.target - Slice Units.
Jan 13 20:34:25.080073 systemd[1]: Stopped target sockets.target - Socket Units.
Jan 13 20:34:25.082028 systemd[1]: iscsid.socket: Deactivated successfully.
Jan 13 20:34:25.082126 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket.
Jan 13 20:34:25.084011 systemd[1]: iscsiuio.socket: Deactivated successfully.
Jan 13 20:34:25.084099 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket.
Jan 13 20:34:25.086132 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully.
Jan 13 20:34:25.086251 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion.
Jan 13 20:34:25.088160 systemd[1]: ignition-files.service: Deactivated successfully.
Jan 13 20:34:25.088265 systemd[1]: Stopped ignition-files.service - Ignition (files).
Jan 13 20:34:25.097135 systemd[1]: Stopping ignition-mount.service - Ignition (mount)...
Jan 13 20:34:25.099165 systemd[1]: kmod-static-nodes.service: Deactivated successfully.
Jan 13 20:34:25.099285 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes.
Jan 13 20:34:25.102998 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot...
Jan 13 20:34:25.104014 systemd[1]: systemd-udev-trigger.service: Deactivated successfully.
Jan 13 20:34:25.115560 ignition[1009]: INFO     : Ignition 2.20.0
Jan 13 20:34:25.115560 ignition[1009]: INFO     : Stage: umount
Jan 13 20:34:25.115560 ignition[1009]: INFO     : no configs at "/usr/lib/ignition/base.d"
Jan 13 20:34:25.115560 ignition[1009]: INFO     : no config dir at "/usr/lib/ignition/base.platform.d/qemu"
Jan 13 20:34:25.115560 ignition[1009]: INFO     : umount: umount passed
Jan 13 20:34:25.115560 ignition[1009]: INFO     : Ignition finished successfully
Jan 13 20:34:25.104208 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices.
Jan 13 20:34:25.106593 systemd[1]: dracut-pre-trigger.service: Deactivated successfully.
Jan 13 20:34:25.106796 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook.
Jan 13 20:34:25.113212 systemd[1]: initrd-cleanup.service: Deactivated successfully.
Jan 13 20:34:25.113358 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons.
Jan 13 20:34:25.117681 systemd[1]: ignition-mount.service: Deactivated successfully.
Jan 13 20:34:25.117838 systemd[1]: Stopped ignition-mount.service - Ignition (mount).
Jan 13 20:34:25.119855 systemd[1]: Stopped target network.target - Network.
Jan 13 20:34:25.121740 systemd[1]: ignition-disks.service: Deactivated successfully.
Jan 13 20:34:25.121795 systemd[1]: Stopped ignition-disks.service - Ignition (disks).
Jan 13 20:34:25.123630 systemd[1]: ignition-kargs.service: Deactivated successfully.
Jan 13 20:34:25.123676 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs).
Jan 13 20:34:25.125540 systemd[1]: ignition-setup.service: Deactivated successfully.
Jan 13 20:34:25.125587 systemd[1]: Stopped ignition-setup.service - Ignition (setup).
Jan 13 20:34:25.127631 systemd[1]: ignition-setup-pre.service: Deactivated successfully.
Jan 13 20:34:25.127680 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup.
Jan 13 20:34:25.131466 systemd[1]: Stopping systemd-networkd.service - Network Configuration...
Jan 13 20:34:25.133318 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution...
Jan 13 20:34:25.136007 systemd-networkd[779]: eth0: DHCPv6 lease lost
Jan 13 20:34:25.136612 systemd[1]: sysroot-boot.mount: Deactivated successfully.
Jan 13 20:34:25.138885 systemd[1]: systemd-networkd.service: Deactivated successfully.
Jan 13 20:34:25.139037 systemd[1]: Stopped systemd-networkd.service - Network Configuration.
Jan 13 20:34:25.141428 systemd[1]: systemd-resolved.service: Deactivated successfully.
Jan 13 20:34:25.141549 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution.
Jan 13 20:34:25.145017 systemd[1]: systemd-networkd.socket: Deactivated successfully.
Jan 13 20:34:25.145076 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket.
Jan 13 20:34:25.156101 systemd[1]: Stopping network-cleanup.service - Network Cleanup...
Jan 13 20:34:25.157115 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully.
Jan 13 20:34:25.157198 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline.
Jan 13 20:34:25.159826 systemd[1]: systemd-sysctl.service: Deactivated successfully.
Jan 13 20:34:25.159888 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables.
Jan 13 20:34:25.162121 systemd[1]: systemd-modules-load.service: Deactivated successfully.
Jan 13 20:34:25.162181 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules.
Jan 13 20:34:25.163451 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.
Jan 13 20:34:25.163511 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories.
Jan 13 20:34:25.166121 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files...
Jan 13 20:34:25.180539 systemd[1]: network-cleanup.service: Deactivated successfully.
Jan 13 20:34:25.180676 systemd[1]: Stopped network-cleanup.service - Network Cleanup.
Jan 13 20:34:25.184923 systemd[1]: systemd-udevd.service: Deactivated successfully.
Jan 13 20:34:25.185167 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files.
Jan 13 20:34:25.187574 systemd[1]: systemd-udevd-control.socket: Deactivated successfully.
Jan 13 20:34:25.187637 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket.
Jan 13 20:34:25.189540 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully.
Jan 13 20:34:25.189589 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket.
Jan 13 20:34:25.192051 systemd[1]: dracut-pre-udev.service: Deactivated successfully.
Jan 13 20:34:25.192112 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook.
Jan 13 20:34:25.194595 systemd[1]: dracut-cmdline.service: Deactivated successfully.
Jan 13 20:34:25.194655 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook.
Jan 13 20:34:25.196809 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully.
Jan 13 20:34:25.196870 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters.
Jan 13 20:34:25.216244 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database...
Jan 13 20:34:25.218522 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.
Jan 13 20:34:25.218603 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev.
Jan 13 20:34:25.220875 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
Jan 13 20:34:25.220925 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup.
Jan 13 20:34:25.225021 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.
Jan 13 20:34:25.225144 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database.
Jan 13 20:34:25.527378 systemd[1]: sysroot-boot.service: Deactivated successfully.
Jan 13 20:34:25.527521 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot.
Jan 13 20:34:25.529842 systemd[1]: Reached target initrd-switch-root.target - Switch Root.
Jan 13 20:34:25.531765 systemd[1]: initrd-setup-root.service: Deactivated successfully.
Jan 13 20:34:25.531826 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup.
Jan 13 20:34:25.553142 systemd[1]: Starting initrd-switch-root.service - Switch Root...
Jan 13 20:34:25.562381 systemd[1]: Switching root.
Jan 13 20:34:25.594335 systemd-journald[194]: Journal stopped
Jan 13 20:34:26.951633 systemd-journald[194]: Received SIGTERM from PID 1 (systemd).
Jan 13 20:34:26.951689 kernel: SELinux:  policy capability network_peer_controls=1
Jan 13 20:34:26.951735 kernel: SELinux:  policy capability open_perms=1
Jan 13 20:34:26.951758 kernel: SELinux:  policy capability extended_socket_class=1
Jan 13 20:34:26.951770 kernel: SELinux:  policy capability always_check_network=0
Jan 13 20:34:26.951786 kernel: SELinux:  policy capability cgroup_seclabel=1
Jan 13 20:34:26.951797 kernel: SELinux:  policy capability nnp_nosuid_transition=1
Jan 13 20:34:26.951809 kernel: SELinux:  policy capability genfs_seclabel_symlinks=0
Jan 13 20:34:26.951819 kernel: SELinux:  policy capability ioctl_skip_cloexec=0
Jan 13 20:34:26.951830 kernel: audit: type=1403 audit(1736800466.091:2): auid=4294967295 ses=4294967295 lsm=selinux res=1
Jan 13 20:34:26.951846 systemd[1]: Successfully loaded SELinux policy in 39.820ms.
Jan 13 20:34:26.951867 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 12.605ms.
Jan 13 20:34:26.951883 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified)
Jan 13 20:34:26.951895 systemd[1]: Detected virtualization kvm.
Jan 13 20:34:26.951908 systemd[1]: Detected architecture x86-64.
Jan 13 20:34:26.951920 systemd[1]: Detected first boot.
Jan 13 20:34:26.951931 systemd[1]: Initializing machine ID from VM UUID.
Jan 13 20:34:26.951943 zram_generator::config[1055]: No configuration found.
Jan 13 20:34:26.951956 systemd[1]: Populated /etc with preset unit settings.
Jan 13 20:34:26.951992 systemd[1]: initrd-switch-root.service: Deactivated successfully.
Jan 13 20:34:26.952007 systemd[1]: Stopped initrd-switch-root.service - Switch Root.
Jan 13 20:34:26.952022 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.
Jan 13 20:34:26.952034 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config.
Jan 13 20:34:26.952046 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run.
Jan 13 20:34:26.952058 systemd[1]: Created slice system-getty.slice - Slice /system/getty.
Jan 13 20:34:26.952072 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe.
Jan 13 20:34:26.952083 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty.
Jan 13 20:34:26.952095 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit.
Jan 13 20:34:26.952107 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck.
Jan 13 20:34:26.952118 systemd[1]: Created slice user.slice - User and Session Slice.
Jan 13 20:34:26.952130 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch.
Jan 13 20:34:26.952142 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch.
Jan 13 20:34:26.952154 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch.
Jan 13 20:34:26.952166 systemd[1]: Set up automount boot.automount - Boot partition Automount Point.
Jan 13 20:34:26.952180 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point.
Jan 13 20:34:26.952193 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM...
Jan 13 20:34:26.952205 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0...
Jan 13 20:34:26.952217 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre).
Jan 13 20:34:26.952228 systemd[1]: Stopped target initrd-switch-root.target - Switch Root.
Jan 13 20:34:26.952240 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems.
Jan 13 20:34:26.952251 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System.
Jan 13 20:34:26.952265 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes.
Jan 13 20:34:26.952277 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes.
Jan 13 20:34:26.952289 systemd[1]: Reached target remote-fs.target - Remote File Systems.
Jan 13 20:34:26.952301 systemd[1]: Reached target slices.target - Slice Units.
Jan 13 20:34:26.952312 systemd[1]: Reached target swap.target - Swaps.
Jan 13 20:34:26.952324 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes.
Jan 13 20:34:26.952336 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket.
Jan 13 20:34:26.952347 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket.
Jan 13 20:34:26.952359 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket.
Jan 13 20:34:26.952371 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket.
Jan 13 20:34:26.952385 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket.
Jan 13 20:34:26.952396 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System...
Jan 13 20:34:26.952408 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System...
Jan 13 20:34:26.952420 systemd[1]: Mounting media.mount - External Media Directory...
Jan 13 20:34:26.952432 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen).
Jan 13 20:34:26.952444 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System...
Jan 13 20:34:26.952456 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System...
Jan 13 20:34:26.952468 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp...
Jan 13 20:34:26.952480 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw).
Jan 13 20:34:26.952494 systemd[1]: Reached target machines.target - Containers.
Jan 13 20:34:26.952506 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files...
Jan 13 20:34:26.952518 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met.
Jan 13 20:34:26.952529 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes...
Jan 13 20:34:26.952541 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs...
Jan 13 20:34:26.952553 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod...
Jan 13 20:34:26.952565 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm...
Jan 13 20:34:26.952577 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore...
Jan 13 20:34:26.952590 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse...
Jan 13 20:34:26.952602 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop...
Jan 13 20:34:26.952614 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf).
Jan 13 20:34:26.952626 systemd[1]: systemd-fsck-root.service: Deactivated successfully.
Jan 13 20:34:26.952637 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device.
Jan 13 20:34:26.952649 systemd[1]: systemd-fsck-usr.service: Deactivated successfully.
Jan 13 20:34:26.952661 systemd[1]: Stopped systemd-fsck-usr.service.
Jan 13 20:34:26.952673 systemd[1]: Starting systemd-journald.service - Journal Service...
Jan 13 20:34:26.952687 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules...
Jan 13 20:34:26.952699 kernel: loop: module loaded
Jan 13 20:34:26.952714 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line...
Jan 13 20:34:26.952729 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems...
Jan 13 20:34:26.952743 kernel: fuse: init (API version 7.39)
Jan 13 20:34:26.952763 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices...
Jan 13 20:34:26.952795 systemd-journald[1118]: Collecting audit messages is disabled.
Jan 13 20:34:26.952821 systemd[1]: verity-setup.service: Deactivated successfully.
Jan 13 20:34:26.952835 systemd[1]: Stopped verity-setup.service.
Jan 13 20:34:26.952847 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen).
Jan 13 20:34:26.952859 systemd-journald[1118]: Journal started
Jan 13 20:34:26.952880 systemd-journald[1118]: Runtime Journal (/run/log/journal/84b50cffbc9548b19f7d105f8f081759) is 6.0M, max 48.4M, 42.3M free.
Jan 13 20:34:26.701870 systemd[1]: Queued start job for default target multi-user.target.
Jan 13 20:34:26.717442 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6.
Jan 13 20:34:26.717875 systemd[1]: systemd-journald.service: Deactivated successfully.
Jan 13 20:34:26.959984 systemd[1]: Started systemd-journald.service - Journal Service.
Jan 13 20:34:26.961371 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System.
Jan 13 20:34:26.962696 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System.
Jan 13 20:34:26.964107 systemd[1]: Mounted media.mount - External Media Directory.
Jan 13 20:34:26.965377 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System.
Jan 13 20:34:26.966725 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System.
Jan 13 20:34:26.968293 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp.
Jan 13 20:34:26.969984 kernel: ACPI: bus type drm_connector registered
Jan 13 20:34:26.970222 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes.
Jan 13 20:34:26.971986 systemd[1]: modprobe@configfs.service: Deactivated successfully.
Jan 13 20:34:26.972168 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs.
Jan 13 20:34:26.973787 systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
Jan 13 20:34:26.973951 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod.
Jan 13 20:34:26.975454 systemd[1]: modprobe@drm.service: Deactivated successfully.
Jan 13 20:34:26.975618 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm.
Jan 13 20:34:26.977014 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
Jan 13 20:34:26.977177 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore.
Jan 13 20:34:26.978888 systemd[1]: modprobe@fuse.service: Deactivated successfully.
Jan 13 20:34:26.979098 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse.
Jan 13 20:34:26.980515 systemd[1]: modprobe@loop.service: Deactivated successfully.
Jan 13 20:34:26.980676 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop.
Jan 13 20:34:26.982224 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line.
Jan 13 20:34:26.984504 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems.
Jan 13 20:34:26.996535 systemd[1]: Reached target network-pre.target - Preparation for Network.
Jan 13 20:34:27.006163 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System...
Jan 13 20:34:27.009799 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System...
Jan 13 20:34:27.011060 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).
Jan 13 20:34:27.011100 systemd[1]: Reached target local-fs.target - Local File Systems.
Jan 13 20:34:27.013530 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink).
Jan 13 20:34:27.016154 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown...
Jan 13 20:34:27.018636 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache...
Jan 13 20:34:27.019981 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met.
Jan 13 20:34:27.022773 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database...
Jan 13 20:34:27.025829 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage...
Jan 13 20:34:27.027179 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
Jan 13 20:34:27.030480 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed...
Jan 13 20:34:27.034014 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met.
Jan 13 20:34:27.036595 systemd-journald[1118]: Time spent on flushing to /var/log/journal/84b50cffbc9548b19f7d105f8f081759 is 16.503ms for 929 entries.
Jan 13 20:34:27.036595 systemd-journald[1118]: System Journal (/var/log/journal/84b50cffbc9548b19f7d105f8f081759) is 8.0M, max 195.6M, 187.6M free.
Jan 13 20:34:27.206625 systemd-journald[1118]: Received client request to flush runtime journal.
Jan 13 20:34:27.206679 kernel: loop0: detected capacity change from 0 to 140992
Jan 13 20:34:27.206697 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher
Jan 13 20:34:27.206714 kernel: loop1: detected capacity change from 0 to 138184
Jan 13 20:34:27.036020 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/...
Jan 13 20:34:27.043434 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules.
Jan 13 20:34:27.047338 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System.
Jan 13 20:34:27.049264 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System.
Jan 13 20:34:27.050746 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown.
Jan 13 20:34:27.062116 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables...
Jan 13 20:34:27.118424 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices.
Jan 13 20:34:27.135176 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization...
Jan 13 20:34:27.143455 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables.
Jan 13 20:34:27.145210 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed.
Jan 13 20:34:27.147236 systemd[1]: Reached target first-boot-complete.target - First Boot Complete.
Jan 13 20:34:27.159372 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk...
Jan 13 20:34:27.162382 udevadm[1177]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in.
Jan 13 20:34:27.196733 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files.
Jan 13 20:34:27.208809 systemd[1]: Starting systemd-sysusers.service - Create System Users...
Jan 13 20:34:27.217142 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage.
Jan 13 20:34:27.247998 kernel: loop2: detected capacity change from 0 to 210664
Jan 13 20:34:27.275390 systemd[1]: Finished systemd-sysusers.service - Create System Users.
Jan 13 20:34:27.284156 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev...
Jan 13 20:34:27.299998 kernel: loop3: detected capacity change from 0 to 140992
Jan 13 20:34:27.325004 kernel: loop4: detected capacity change from 0 to 138184
Jan 13 20:34:27.326182 systemd-tmpfiles[1191]: ACLs are not supported, ignoring.
Jan 13 20:34:27.326213 systemd-tmpfiles[1191]: ACLs are not supported, ignoring.
Jan 13 20:34:27.335488 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev.
Jan 13 20:34:27.345991 kernel: loop5: detected capacity change from 0 to 210664
Jan 13 20:34:27.354617 (sd-merge)[1193]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'.
Jan 13 20:34:27.355310 (sd-merge)[1193]: Merged extensions into '/usr'.
Jan 13 20:34:27.359249 systemd[1]: Reloading requested from client PID 1152 ('systemd-sysext') (unit systemd-sysext.service)...
Jan 13 20:34:27.359264 systemd[1]: Reloading...
Jan 13 20:34:27.429998 zram_generator::config[1224]: No configuration found.
Jan 13 20:34:27.496351 ldconfig[1148]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start.
Jan 13 20:34:27.560988 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly.
Jan 13 20:34:27.628240 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
Jan 13 20:34:27.629052 systemd[1]: Reloading finished in 269 ms.
Jan 13 20:34:27.670834 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache.
Jan 13 20:34:27.672550 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/.
Jan 13 20:34:27.674368 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk.
Jan 13 20:34:27.695304 systemd[1]: Starting ensure-sysext.service...
Jan 13 20:34:27.697500 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories...
Jan 13 20:34:27.704878 systemd[1]: Reloading requested from client PID 1259 ('systemctl') (unit ensure-sysext.service)...
Jan 13 20:34:27.704898 systemd[1]: Reloading...
Jan 13 20:34:27.746292 systemd-tmpfiles[1260]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring.
Jan 13 20:34:27.746743 systemd-tmpfiles[1260]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring.
Jan 13 20:34:27.747844 systemd-tmpfiles[1260]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring.
Jan 13 20:34:27.750609 systemd-tmpfiles[1260]: ACLs are not supported, ignoring.
Jan 13 20:34:27.750778 systemd-tmpfiles[1260]: ACLs are not supported, ignoring.
Jan 13 20:34:27.754314 systemd-tmpfiles[1260]: Detected autofs mount point /boot during canonicalization of boot.
Jan 13 20:34:27.754431 systemd-tmpfiles[1260]: Skipping /boot
Jan 13 20:34:27.758086 zram_generator::config[1290]: No configuration found.
Jan 13 20:34:27.767037 systemd-tmpfiles[1260]: Detected autofs mount point /boot during canonicalization of boot.
Jan 13 20:34:27.767052 systemd-tmpfiles[1260]: Skipping /boot
Jan 13 20:34:27.868745 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly.
Jan 13 20:34:27.917734 systemd[1]: Reloading finished in 212 ms.
Jan 13 20:34:27.935176 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database.
Jan 13 20:34:27.947420 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories.
Jan 13 20:34:27.955668 systemd[1]: Starting audit-rules.service - Load Audit Rules...
Jan 13 20:34:27.958069 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs...
Jan 13 20:34:27.960377 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog...
Jan 13 20:34:27.964084 systemd[1]: Starting systemd-resolved.service - Network Name Resolution...
Jan 13 20:34:27.966606 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files...
Jan 13 20:34:27.971154 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP...
Jan 13 20:34:27.974568 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen).
Jan 13 20:34:27.974783 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met.
Jan 13 20:34:27.987118 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod...
Jan 13 20:34:27.990297 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore...
Jan 13 20:34:27.995600 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop...
Jan 13 20:34:27.999142 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met.
Jan 13 20:34:28.003061 systemd[1]: Starting systemd-userdbd.service - User Database Manager...
Jan 13 20:34:28.004316 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen).
Jan 13 20:34:28.005896 systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
Jan 13 20:34:28.006576 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod.
Jan 13 20:34:28.009770 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog.
Jan 13 20:34:28.011840 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
Jan 13 20:34:28.012103 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore.
Jan 13 20:34:28.014348 systemd[1]: modprobe@loop.service: Deactivated successfully.
Jan 13 20:34:28.014649 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop.
Jan 13 20:34:28.017027 systemd-udevd[1330]: Using default interface naming scheme 'v255'.
Jan 13 20:34:28.025187 augenrules[1355]: No rules
Jan 13 20:34:28.025910 systemd[1]: audit-rules.service: Deactivated successfully.
Jan 13 20:34:28.026182 systemd[1]: Finished audit-rules.service - Load Audit Rules.
Jan 13 20:34:28.036797 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen).
Jan 13 20:34:28.037275 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met.
Jan 13 20:34:28.042215 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod...
Jan 13 20:34:28.048522 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore...
Jan 13 20:34:28.054225 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop...
Jan 13 20:34:28.055735 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met.
Jan 13 20:34:28.060240 systemd[1]: Starting systemd-update-done.service - Update is Completed...
Jan 13 20:34:28.063007 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen).
Jan 13 20:34:28.064003 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files.
Jan 13 20:34:28.068151 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs.
Jan 13 20:34:28.072003 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP.
Jan 13 20:34:28.074517 systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
Jan 13 20:34:28.075333 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod.
Jan 13 20:34:28.083285 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
Jan 13 20:34:28.084026 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore.
Jan 13 20:34:28.106998 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (1372)
Jan 13 20:34:28.105837 systemd[1]: modprobe@loop.service: Deactivated successfully.
Jan 13 20:34:28.108004 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop.
Jan 13 20:34:28.111419 systemd[1]: Started systemd-userdbd.service - User Database Manager.
Jan 13 20:34:28.126462 systemd[1]: Finished systemd-update-done.service - Update is Completed.
Jan 13 20:34:28.136435 systemd[1]: Finished ensure-sysext.service.
Jan 13 20:34:28.143017 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped.
Jan 13 20:34:28.160392 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM.
Jan 13 20:34:28.162283 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen).
Jan 13 20:34:28.163991 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2
Jan 13 20:34:28.170782 kernel: ACPI: button: Power Button [PWRF]
Jan 13 20:34:28.171167 systemd[1]: Starting audit-rules.service - Load Audit Rules...
Jan 13 20:34:28.172507 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met.
Jan 13 20:34:28.174271 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod...
Jan 13 20:34:28.177771 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm...
Jan 13 20:34:28.182276 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore...
Jan 13 20:34:28.185498 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met.
Jan 13 20:34:28.187365 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM...
Jan 13 20:34:28.191040 systemd[1]: Starting systemd-networkd.service - Network Configuration...
Jan 13 20:34:28.195734 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization...
Jan 13 20:34:28.197154 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt).
Jan 13 20:34:28.197186 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen).
Jan 13 20:34:28.197884 systemd[1]: modprobe@drm.service: Deactivated successfully.
Jan 13 20:34:28.198205 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm.
Jan 13 20:34:28.200941 systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
Jan 13 20:34:28.201361 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod.
Jan 13 20:34:28.204373 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met.
Jan 13 20:34:28.206370 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
Jan 13 20:34:28.206613 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore.
Jan 13 20:34:28.207693 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
Jan 13 20:34:28.218270 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM.
Jan 13 20:34:28.227627 augenrules[1407]: /sbin/augenrules: No change
Jan 13 20:34:28.238787 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3
Jan 13 20:34:28.236383 systemd-resolved[1329]: Positive Trust Anchors:
Jan 13 20:34:28.236402 systemd-resolved[1329]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
Jan 13 20:34:28.236446 systemd-resolved[1329]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test
Jan 13 20:34:28.241827 augenrules[1437]: No rules
Jan 13 20:34:28.243767 systemd[1]: audit-rules.service: Deactivated successfully.
Jan 13 20:34:28.244123 systemd[1]: Finished audit-rules.service - Load Audit Rules.
Jan 13 20:34:28.246740 systemd-resolved[1329]: Defaulting to hostname 'linux'.
Jan 13 20:34:28.251074 systemd[1]: Started systemd-resolved.service - Network Name Resolution.
Jan 13 20:34:28.252693 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups.
Jan 13 20:34:28.268886 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt
Jan 13 20:34:28.269234 kernel: i2c i2c-0: 1/1 memory slots populated (from DMI)
Jan 13 20:34:28.269465 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD
Jan 13 20:34:28.269981 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup...
Jan 13 20:34:28.333701 systemd-networkd[1418]: lo: Link UP
Jan 13 20:34:28.334097 systemd-networkd[1418]: lo: Gained carrier
Jan 13 20:34:28.336184 systemd-networkd[1418]: Enumeration completed
Jan 13 20:34:28.336371 systemd[1]: Started systemd-networkd.service - Network Configuration.
Jan 13 20:34:28.337096 systemd[1]: Reached target network.target - Network.
Jan 13 20:34:28.337406 systemd-networkd[1418]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name.
Jan 13 20:34:28.337415 systemd-networkd[1418]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network.
Jan 13 20:34:28.339607 systemd-networkd[1418]: eth0: Link UP
Jan 13 20:34:28.339619 systemd-networkd[1418]: eth0: Gained carrier
Jan 13 20:34:28.339635 systemd-networkd[1418]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name.
Jan 13 20:34:28.385059 kernel: mousedev: PS/2 mouse device common for all mice
Jan 13 20:34:28.397096 kernel: kvm_amd: TSC scaling supported
Jan 13 20:34:28.397148 kernel: kvm_amd: Nested Virtualization enabled
Jan 13 20:34:28.397180 kernel: kvm_amd: Nested Paging enabled
Jan 13 20:34:28.397196 kernel: kvm_amd: LBR virtualization supported
Jan 13 20:34:28.397208 kernel: kvm_amd: Virtual VMLOAD VMSAVE supported
Jan 13 20:34:28.397220 kernel: kvm_amd: Virtual GIF supported
Jan 13 20:34:28.397424 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured...
Jan 13 20:34:28.403100 systemd-networkd[1418]: eth0: DHCPv4 address 10.0.0.54/16, gateway 10.0.0.1 acquired from 10.0.0.1
Jan 13 20:34:28.406473 systemd-timesyncd[1419]: Network configuration changed, trying to establish connection.
Jan 13 20:34:29.601300 systemd-resolved[1329]: Clock change detected. Flushing caches.
Jan 13 20:34:29.601350 systemd-timesyncd[1419]: Contacted time server 10.0.0.1:123 (10.0.0.1).
Jan 13 20:34:29.601391 systemd-timesyncd[1419]: Initial clock synchronization to Mon 2025-01-13 20:34:29.601267 UTC.
Jan 13 20:34:29.613232 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization.
Jan 13 20:34:29.615210 kernel: EDAC MC: Ver: 3.0.0
Jan 13 20:34:29.615210 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup.
Jan 13 20:34:29.617625 systemd[1]: Reached target time-set.target - System Time Set.
Jan 13 20:34:29.654786 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization.
Jan 13 20:34:29.669291 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes...
Jan 13 20:34:29.678349 lvm[1456]:   WARNING: Failed to connect to lvmetad. Falling back to device scanning.
Jan 13 20:34:29.710050 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes.
Jan 13 20:34:29.711608 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes.
Jan 13 20:34:29.712744 systemd[1]: Reached target sysinit.target - System Initialization.
Jan 13 20:34:29.713929 systemd[1]: Started motdgen.path - Watch for update engine configuration changes.
Jan 13 20:34:29.715216 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data.
Jan 13 20:34:29.716660 systemd[1]: Started logrotate.timer - Daily rotation of log files.
Jan 13 20:34:29.717936 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information..
Jan 13 20:34:29.719343 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories.
Jan 13 20:34:29.720607 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate).
Jan 13 20:34:29.720630 systemd[1]: Reached target paths.target - Path Units.
Jan 13 20:34:29.721566 systemd[1]: Reached target timers.target - Timer Units.
Jan 13 20:34:29.723044 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket.
Jan 13 20:34:29.725609 systemd[1]: Starting docker.socket - Docker Socket for the API...
Jan 13 20:34:29.735079 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket.
Jan 13 20:34:29.737442 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes...
Jan 13 20:34:29.739197 systemd[1]: Listening on docker.socket - Docker Socket for the API.
Jan 13 20:34:29.740496 systemd[1]: Reached target sockets.target - Socket Units.
Jan 13 20:34:29.741583 systemd[1]: Reached target basic.target - Basic System.
Jan 13 20:34:29.742667 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met.
Jan 13 20:34:29.742702 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met.
Jan 13 20:34:29.743783 systemd[1]: Starting containerd.service - containerd container runtime...
Jan 13 20:34:29.746077 systemd[1]: Starting dbus.service - D-Bus System Message Bus...
Jan 13 20:34:29.749593 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit...
Jan 13 20:34:29.754385 systemd[1]: Starting extend-filesystems.service - Extend Filesystems...
Jan 13 20:34:29.755694 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment).
Jan 13 20:34:29.756078 lvm[1460]:   WARNING: Failed to connect to lvmetad. Falling back to device scanning.
Jan 13 20:34:29.760211 jq[1463]: false
Jan 13 20:34:29.759287 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd...
Jan 13 20:34:29.763752 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline...
Jan 13 20:34:29.768300 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys...
Jan 13 20:34:29.771218 extend-filesystems[1464]: Found loop3
Jan 13 20:34:29.773139 extend-filesystems[1464]: Found loop4
Jan 13 20:34:29.773139 extend-filesystems[1464]: Found loop5
Jan 13 20:34:29.773139 extend-filesystems[1464]: Found sr0
Jan 13 20:34:29.773139 extend-filesystems[1464]: Found vda
Jan 13 20:34:29.773139 extend-filesystems[1464]: Found vda1
Jan 13 20:34:29.773139 extend-filesystems[1464]: Found vda2
Jan 13 20:34:29.773139 extend-filesystems[1464]: Found vda3
Jan 13 20:34:29.773139 extend-filesystems[1464]: Found usr
Jan 13 20:34:29.773139 extend-filesystems[1464]: Found vda4
Jan 13 20:34:29.773139 extend-filesystems[1464]: Found vda6
Jan 13 20:34:29.773139 extend-filesystems[1464]: Found vda7
Jan 13 20:34:29.773139 extend-filesystems[1464]: Found vda9
Jan 13 20:34:29.773139 extend-filesystems[1464]: Checking size of /dev/vda9
Jan 13 20:34:29.789469 extend-filesystems[1464]: Resized partition /dev/vda9
Jan 13 20:34:29.782293 dbus-daemon[1462]: [system] SELinux support is enabled
Jan 13 20:34:29.784048 systemd[1]: Starting systemd-logind.service - User Login Management...
Jan 13 20:34:29.792540 extend-filesystems[1479]: resize2fs 1.47.1 (20-May-2024)
Jan 13 20:34:29.787739 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0).
Jan 13 20:34:29.788270 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details.
Jan 13 20:34:29.789230 systemd[1]: Starting update-engine.service - Update Engine...
Jan 13 20:34:29.793926 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition...
Jan 13 20:34:29.796131 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks
Jan 13 20:34:29.796953 systemd[1]: Started dbus.service - D-Bus System Message Bus.
Jan 13 20:34:29.801338 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes.
Jan 13 20:34:29.813135 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (1387)
Jan 13 20:34:29.811313 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'.
Jan 13 20:34:29.811834 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped.
Jan 13 20:34:29.812350 systemd[1]: motdgen.service: Deactivated successfully.
Jan 13 20:34:29.813209 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd.
Jan 13 20:34:29.814850 jq[1481]: true
Jan 13 20:34:29.817259 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully.
Jan 13 20:34:29.817529 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline.
Jan 13 20:34:29.823111 update_engine[1480]: I20250113 20:34:29.823032  1480 main.cc:92] Flatcar Update Engine starting
Jan 13 20:34:29.827841 update_engine[1480]: I20250113 20:34:29.824368  1480 update_check_scheduler.cc:74] Next update check in 10m51s
Jan 13 20:34:29.830883 kernel: EXT4-fs (vda9): resized filesystem to 1864699
Jan 13 20:34:29.839089 jq[1485]: true
Jan 13 20:34:29.843567 (ntainerd)[1487]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR
Jan 13 20:34:29.854433 systemd-logind[1476]: Watching system buttons on /dev/input/event1 (Power Button)
Jan 13 20:34:29.854453 systemd-logind[1476]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)
Jan 13 20:34:29.855331 extend-filesystems[1479]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required
Jan 13 20:34:29.855331 extend-filesystems[1479]: old_desc_blocks = 1, new_desc_blocks = 1
Jan 13 20:34:29.855331 extend-filesystems[1479]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long.
Jan 13 20:34:29.865882 extend-filesystems[1464]: Resized filesystem in /dev/vda9
Jan 13 20:34:29.855537 systemd-logind[1476]: New seat seat0.
Jan 13 20:34:29.856068 systemd[1]: Started update-engine.service - Update Engine.
Jan 13 20:34:29.860947 systemd[1]: Started systemd-logind.service - User Login Management.
Jan 13 20:34:29.861659 systemd[1]: extend-filesystems.service: Deactivated successfully.
Jan 13 20:34:29.862590 systemd[1]: Finished extend-filesystems.service - Extend Filesystems.
Jan 13 20:34:29.873723 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd.
Jan 13 20:34:29.875766 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml).
Jan 13 20:34:29.875915 systemd[1]: Reached target system-config.target - Load system-provided cloud configs.
Jan 13 20:34:29.877570 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url).
Jan 13 20:34:29.878420 systemd[1]: Reached target user-config.target - Load user-provided cloud configs.
Jan 13 20:34:29.887971 bash[1513]: Updated "/home/core/.ssh/authorized_keys"
Jan 13 20:34:29.892612 systemd[1]: Started locksmithd.service - Cluster reboot manager.
Jan 13 20:34:29.896686 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition.
Jan 13 20:34:29.900642 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met.
Jan 13 20:34:29.919322 locksmithd[1515]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot"
Jan 13 20:34:29.979868 sshd_keygen[1486]: ssh-keygen: generating new host keys: RSA ECDSA ED25519
Jan 13 20:34:30.001379 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys.
Jan 13 20:34:30.016468 systemd[1]: Starting issuegen.service - Generate /run/issue...
Jan 13 20:34:30.018939 systemd[1]: Started sshd@0-10.0.0.54:22-10.0.0.1:58436.service - OpenSSH per-connection server daemon (10.0.0.1:58436).
Jan 13 20:34:30.023467 systemd[1]: issuegen.service: Deactivated successfully.
Jan 13 20:34:30.023728 systemd[1]: Finished issuegen.service - Generate /run/issue.
Jan 13 20:34:30.028377 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions...
Jan 13 20:34:30.035959 containerd[1487]: time="2025-01-13T20:34:30.035866829Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23
Jan 13 20:34:30.043949 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions.
Jan 13 20:34:30.058578 systemd[1]: Started getty@tty1.service - Getty on tty1.
Jan 13 20:34:30.061169 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0.
Jan 13 20:34:30.062501 systemd[1]: Reached target getty.target - Login Prompts.
Jan 13 20:34:30.063548 containerd[1487]: time="2025-01-13T20:34:30.062527307Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
Jan 13 20:34:30.065257 containerd[1487]: time="2025-01-13T20:34:30.065215418Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.71-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1
Jan 13 20:34:30.065257 containerd[1487]: time="2025-01-13T20:34:30.065254070Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
Jan 13 20:34:30.065329 containerd[1487]: time="2025-01-13T20:34:30.065281672Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
Jan 13 20:34:30.065520 containerd[1487]: time="2025-01-13T20:34:30.065493800Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1
Jan 13 20:34:30.065575 containerd[1487]: time="2025-01-13T20:34:30.065520400Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1
Jan 13 20:34:30.065633 containerd[1487]: time="2025-01-13T20:34:30.065607072Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1
Jan 13 20:34:30.065667 containerd[1487]: time="2025-01-13T20:34:30.065630727Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
Jan 13 20:34:30.065894 containerd[1487]: time="2025-01-13T20:34:30.065864726Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1
Jan 13 20:34:30.065894 containerd[1487]: time="2025-01-13T20:34:30.065890504Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
Jan 13 20:34:30.065959 containerd[1487]: time="2025-01-13T20:34:30.065907997Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1
Jan 13 20:34:30.065959 containerd[1487]: time="2025-01-13T20:34:30.065920901Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
Jan 13 20:34:30.066064 containerd[1487]: time="2025-01-13T20:34:30.066039243Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
Jan 13 20:34:30.066366 containerd[1487]: time="2025-01-13T20:34:30.066337843Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
Jan 13 20:34:30.066727 containerd[1487]: time="2025-01-13T20:34:30.066688550Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1
Jan 13 20:34:30.066727 containerd[1487]: time="2025-01-13T20:34:30.066723486Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
Jan 13 20:34:30.067004 containerd[1487]: time="2025-01-13T20:34:30.066976230Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
Jan 13 20:34:30.067090 containerd[1487]: time="2025-01-13T20:34:30.067064866Z" level=info msg="metadata content store policy set" policy=shared
Jan 13 20:34:30.073317 containerd[1487]: time="2025-01-13T20:34:30.073284330Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
Jan 13 20:34:30.073369 containerd[1487]: time="2025-01-13T20:34:30.073332620Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
Jan 13 20:34:30.073369 containerd[1487]: time="2025-01-13T20:34:30.073352628Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1
Jan 13 20:34:30.073438 containerd[1487]: time="2025-01-13T20:34:30.073371022Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1
Jan 13 20:34:30.073438 containerd[1487]: time="2025-01-13T20:34:30.073388535Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
Jan 13 20:34:30.073564 containerd[1487]: time="2025-01-13T20:34:30.073536874Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
Jan 13 20:34:30.073821 containerd[1487]: time="2025-01-13T20:34:30.073793455Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
Jan 13 20:34:30.073954 containerd[1487]: time="2025-01-13T20:34:30.073934018Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2
Jan 13 20:34:30.073976 containerd[1487]: time="2025-01-13T20:34:30.073952623Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1
Jan 13 20:34:30.073976 containerd[1487]: time="2025-01-13T20:34:30.073966429Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1
Jan 13 20:34:30.074009 containerd[1487]: time="2025-01-13T20:34:30.073978572Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
Jan 13 20:34:30.074009 containerd[1487]: time="2025-01-13T20:34:30.073989993Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
Jan 13 20:34:30.074009 containerd[1487]: time="2025-01-13T20:34:30.074001345Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
Jan 13 20:34:30.074067 containerd[1487]: time="2025-01-13T20:34:30.074016743Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
Jan 13 20:34:30.074067 containerd[1487]: time="2025-01-13T20:34:30.074036380Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
Jan 13 20:34:30.074067 containerd[1487]: time="2025-01-13T20:34:30.074051388Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
Jan 13 20:34:30.074136 containerd[1487]: time="2025-01-13T20:34:30.074065956Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
Jan 13 20:34:30.074136 containerd[1487]: time="2025-01-13T20:34:30.074079391Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
Jan 13 20:34:30.074136 containerd[1487]: time="2025-01-13T20:34:30.074101933Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
Jan 13 20:34:30.074202 containerd[1487]: time="2025-01-13T20:34:30.074135376Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
Jan 13 20:34:30.074202 containerd[1487]: time="2025-01-13T20:34:30.074155654Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
Jan 13 20:34:30.074202 containerd[1487]: time="2025-01-13T20:34:30.074177655Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
Jan 13 20:34:30.074202 containerd[1487]: time="2025-01-13T20:34:30.074193285Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
Jan 13 20:34:30.074278 containerd[1487]: time="2025-01-13T20:34:30.074209435Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
Jan 13 20:34:30.074278 containerd[1487]: time="2025-01-13T20:34:30.074223802Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
Jan 13 20:34:30.074278 containerd[1487]: time="2025-01-13T20:34:30.074238960Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
Jan 13 20:34:30.074278 containerd[1487]: time="2025-01-13T20:34:30.074259449Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1
Jan 13 20:34:30.074278 containerd[1487]: time="2025-01-13T20:34:30.074276491Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1
Jan 13 20:34:30.074358 containerd[1487]: time="2025-01-13T20:34:30.074289505Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
Jan 13 20:34:30.074358 containerd[1487]: time="2025-01-13T20:34:30.074303501Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1
Jan 13 20:34:30.074358 containerd[1487]: time="2025-01-13T20:34:30.074316796Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
Jan 13 20:34:30.074358 containerd[1487]: time="2025-01-13T20:34:30.074331624Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1
Jan 13 20:34:30.074358 containerd[1487]: time="2025-01-13T20:34:30.074354126Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1
Jan 13 20:34:30.074448 containerd[1487]: time="2025-01-13T20:34:30.074369485Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
Jan 13 20:34:30.074448 containerd[1487]: time="2025-01-13T20:34:30.074382509Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
Jan 13 20:34:30.074448 containerd[1487]: time="2025-01-13T20:34:30.074436601Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
Jan 13 20:34:30.074502 containerd[1487]: time="2025-01-13T20:34:30.074456598Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1
Jan 13 20:34:30.074502 containerd[1487]: time="2025-01-13T20:34:30.074470114Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
Jan 13 20:34:30.074502 containerd[1487]: time="2025-01-13T20:34:30.074484791Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1
Jan 13 20:34:30.074502 containerd[1487]: time="2025-01-13T20:34:30.074497615Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
Jan 13 20:34:30.074569 containerd[1487]: time="2025-01-13T20:34:30.074512754Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1
Jan 13 20:34:30.074569 containerd[1487]: time="2025-01-13T20:34:30.074526269Z" level=info msg="NRI interface is disabled by configuration."
Jan 13 20:34:30.074569 containerd[1487]: time="2025-01-13T20:34:30.074540055Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1
Jan 13 20:34:30.074927 containerd[1487]: time="2025-01-13T20:34:30.074849004Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}"
Jan 13 20:34:30.074927 containerd[1487]: time="2025-01-13T20:34:30.074925438Z" level=info msg="Connect containerd service"
Jan 13 20:34:30.075077 containerd[1487]: time="2025-01-13T20:34:30.074959481Z" level=info msg="using legacy CRI server"
Jan 13 20:34:30.075077 containerd[1487]: time="2025-01-13T20:34:30.074969160Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this"
Jan 13 20:34:30.075134 containerd[1487]: time="2025-01-13T20:34:30.075083734Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\""
Jan 13 20:34:30.075798 containerd[1487]: time="2025-01-13T20:34:30.075764411Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config"
Jan 13 20:34:30.076210 containerd[1487]: time="2025-01-13T20:34:30.075943517Z" level=info msg="Start subscribing containerd event"
Jan 13 20:34:30.076210 containerd[1487]: time="2025-01-13T20:34:30.075983181Z" level=info msg="Start recovering state"
Jan 13 20:34:30.076210 containerd[1487]: time="2025-01-13T20:34:30.076050367Z" level=info msg="Start event monitor"
Jan 13 20:34:30.076210 containerd[1487]: time="2025-01-13T20:34:30.076066187Z" level=info msg="Start snapshots syncer"
Jan 13 20:34:30.076210 containerd[1487]: time="2025-01-13T20:34:30.076076035Z" level=info msg="Start cni network conf syncer for default"
Jan 13 20:34:30.076210 containerd[1487]: time="2025-01-13T20:34:30.076085122Z" level=info msg="Start streaming server"
Jan 13 20:34:30.076337 containerd[1487]: time="2025-01-13T20:34:30.076241856Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc
Jan 13 20:34:30.076337 containerd[1487]: time="2025-01-13T20:34:30.076320684Z" level=info msg=serving... address=/run/containerd/containerd.sock
Jan 13 20:34:30.076460 systemd[1]: Started containerd.service - containerd container runtime.
Jan 13 20:34:30.077191 containerd[1487]: time="2025-01-13T20:34:30.077166480Z" level=info msg="containerd successfully booted in 0.042268s"
Jan 13 20:34:30.077871 sshd[1537]: Accepted publickey for core from 10.0.0.1 port 58436 ssh2: RSA SHA256:uJ7Cm0ZiB1cKFsV9zv9H+G33T+grLCcYOUFbEs15LGg
Jan 13 20:34:30.079738 sshd-session[1537]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Jan 13 20:34:30.087409 systemd[1]: Created slice user-500.slice - User Slice of UID 500.
Jan 13 20:34:30.106374 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500...
Jan 13 20:34:30.109469 systemd-logind[1476]: New session 1 of user core.
Jan 13 20:34:30.120438 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500.
Jan 13 20:34:30.136457 systemd[1]: Starting user@500.service - User Manager for UID 500...
Jan 13 20:34:30.140403 (systemd)[1551]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0)
Jan 13 20:34:30.240420 systemd[1551]: Queued start job for default target default.target.
Jan 13 20:34:30.259384 systemd[1551]: Created slice app.slice - User Application Slice.
Jan 13 20:34:30.259408 systemd[1551]: Reached target paths.target - Paths.
Jan 13 20:34:30.259420 systemd[1551]: Reached target timers.target - Timers.
Jan 13 20:34:30.260955 systemd[1551]: Starting dbus.socket - D-Bus User Message Bus Socket...
Jan 13 20:34:30.272108 systemd[1551]: Listening on dbus.socket - D-Bus User Message Bus Socket.
Jan 13 20:34:30.272237 systemd[1551]: Reached target sockets.target - Sockets.
Jan 13 20:34:30.272255 systemd[1551]: Reached target basic.target - Basic System.
Jan 13 20:34:30.272289 systemd[1551]: Reached target default.target - Main User Target.
Jan 13 20:34:30.272320 systemd[1551]: Startup finished in 125ms.
Jan 13 20:34:30.272743 systemd[1]: Started user@500.service - User Manager for UID 500.
Jan 13 20:34:30.286429 systemd[1]: Started session-1.scope - Session 1 of User core.
Jan 13 20:34:30.355265 systemd[1]: Started sshd@1-10.0.0.54:22-10.0.0.1:58450.service - OpenSSH per-connection server daemon (10.0.0.1:58450).
Jan 13 20:34:30.398107 sshd[1562]: Accepted publickey for core from 10.0.0.1 port 58450 ssh2: RSA SHA256:uJ7Cm0ZiB1cKFsV9zv9H+G33T+grLCcYOUFbEs15LGg
Jan 13 20:34:30.400155 sshd-session[1562]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Jan 13 20:34:30.404893 systemd-logind[1476]: New session 2 of user core.
Jan 13 20:34:30.414301 systemd[1]: Started session-2.scope - Session 2 of User core.
Jan 13 20:34:30.470713 sshd[1564]: Connection closed by 10.0.0.1 port 58450
Jan 13 20:34:30.471110 sshd-session[1562]: pam_unix(sshd:session): session closed for user core
Jan 13 20:34:30.483891 systemd[1]: sshd@1-10.0.0.54:22-10.0.0.1:58450.service: Deactivated successfully.
Jan 13 20:34:30.486034 systemd[1]: session-2.scope: Deactivated successfully.
Jan 13 20:34:30.488053 systemd-logind[1476]: Session 2 logged out. Waiting for processes to exit.
Jan 13 20:34:30.506213 systemd[1]: Started sshd@2-10.0.0.54:22-10.0.0.1:58458.service - OpenSSH per-connection server daemon (10.0.0.1:58458).
Jan 13 20:34:30.512323 systemd-logind[1476]: Removed session 2.
Jan 13 20:34:30.573083 sshd[1569]: Accepted publickey for core from 10.0.0.1 port 58458 ssh2: RSA SHA256:uJ7Cm0ZiB1cKFsV9zv9H+G33T+grLCcYOUFbEs15LGg
Jan 13 20:34:30.575066 sshd-session[1569]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Jan 13 20:34:30.584076 systemd-logind[1476]: New session 3 of user core.
Jan 13 20:34:30.631422 systemd[1]: Started session-3.scope - Session 3 of User core.
Jan 13 20:34:30.704297 systemd-networkd[1418]: eth0: Gained IPv6LL
Jan 13 20:34:30.726752 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured.
Jan 13 20:34:30.730667 systemd[1]: Reached target network-online.target - Network is Online.
Jan 13 20:34:30.754755 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent...
Jan 13 20:34:30.764583 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent...
Jan 13 20:34:30.771175 systemd[1]: Starting nvidia.service - NVIDIA Configure Service...
Jan 13 20:34:30.809454 sshd[1571]: Connection closed by 10.0.0.1 port 58458
Jan 13 20:34:30.805939 sshd-session[1569]: pam_unix(sshd:session): session closed for user core
Jan 13 20:34:30.832572 systemd[1]: sshd@2-10.0.0.54:22-10.0.0.1:58458.service: Deactivated successfully.
Jan 13 20:34:30.835100 systemd[1]: coreos-metadata.service: Deactivated successfully.
Jan 13 20:34:30.835614 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent.
Jan 13 20:34:30.839863 systemd[1]: session-3.scope: Deactivated successfully.
Jan 13 20:34:30.850281 systemd-logind[1476]: Session 3 logged out. Waiting for processes to exit.
Jan 13 20:34:30.851149 systemd[1]: Finished nvidia.service - NVIDIA Configure Service.
Jan 13 20:34:30.861804 systemd-logind[1476]: Removed session 3.
Jan 13 20:34:30.862619 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met.
Jan 13 20:34:31.598372 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent.
Jan 13 20:34:31.617433 (kubelet)[1597]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS
Jan 13 20:34:31.617564 systemd[1]: Reached target multi-user.target - Multi-User System.
Jan 13 20:34:31.618966 systemd[1]: Startup finished in 672ms (kernel) + 5.399s (initrd) + 4.371s (userspace) = 10.442s.
Jan 13 20:34:32.418429 kubelet[1597]: E0113 20:34:32.418332    1597 run.go:74] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory"
Jan 13 20:34:32.423124 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE
Jan 13 20:34:32.423389 systemd[1]: kubelet.service: Failed with result 'exit-code'.
Jan 13 20:34:32.423783 systemd[1]: kubelet.service: Consumed 1.187s CPU time.
Jan 13 20:34:40.811894 systemd[1]: Started sshd@3-10.0.0.54:22-10.0.0.1:45794.service - OpenSSH per-connection server daemon (10.0.0.1:45794).
Jan 13 20:34:40.856849 sshd[1611]: Accepted publickey for core from 10.0.0.1 port 45794 ssh2: RSA SHA256:uJ7Cm0ZiB1cKFsV9zv9H+G33T+grLCcYOUFbEs15LGg
Jan 13 20:34:40.858301 sshd-session[1611]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Jan 13 20:34:40.862203 systemd-logind[1476]: New session 4 of user core.
Jan 13 20:34:40.872352 systemd[1]: Started session-4.scope - Session 4 of User core.
Jan 13 20:34:40.928400 sshd[1613]: Connection closed by 10.0.0.1 port 45794
Jan 13 20:34:40.928814 sshd-session[1611]: pam_unix(sshd:session): session closed for user core
Jan 13 20:34:40.942028 systemd[1]: sshd@3-10.0.0.54:22-10.0.0.1:45794.service: Deactivated successfully.
Jan 13 20:34:40.943811 systemd[1]: session-4.scope: Deactivated successfully.
Jan 13 20:34:40.945191 systemd-logind[1476]: Session 4 logged out. Waiting for processes to exit.
Jan 13 20:34:40.946545 systemd[1]: Started sshd@4-10.0.0.54:22-10.0.0.1:45804.service - OpenSSH per-connection server daemon (10.0.0.1:45804).
Jan 13 20:34:40.947380 systemd-logind[1476]: Removed session 4.
Jan 13 20:34:41.003907 sshd[1618]: Accepted publickey for core from 10.0.0.1 port 45804 ssh2: RSA SHA256:uJ7Cm0ZiB1cKFsV9zv9H+G33T+grLCcYOUFbEs15LGg
Jan 13 20:34:41.005508 sshd-session[1618]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Jan 13 20:34:41.010059 systemd-logind[1476]: New session 5 of user core.
Jan 13 20:34:41.019339 systemd[1]: Started session-5.scope - Session 5 of User core.
Jan 13 20:34:41.069582 sshd[1620]: Connection closed by 10.0.0.1 port 45804
Jan 13 20:34:41.069930 sshd-session[1618]: pam_unix(sshd:session): session closed for user core
Jan 13 20:34:41.082309 systemd[1]: sshd@4-10.0.0.54:22-10.0.0.1:45804.service: Deactivated successfully.
Jan 13 20:34:41.083990 systemd[1]: session-5.scope: Deactivated successfully.
Jan 13 20:34:41.085723 systemd-logind[1476]: Session 5 logged out. Waiting for processes to exit.
Jan 13 20:34:41.097401 systemd[1]: Started sshd@5-10.0.0.54:22-10.0.0.1:45814.service - OpenSSH per-connection server daemon (10.0.0.1:45814).
Jan 13 20:34:41.098324 systemd-logind[1476]: Removed session 5.
Jan 13 20:34:41.133201 sshd[1625]: Accepted publickey for core from 10.0.0.1 port 45814 ssh2: RSA SHA256:uJ7Cm0ZiB1cKFsV9zv9H+G33T+grLCcYOUFbEs15LGg
Jan 13 20:34:41.134548 sshd-session[1625]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Jan 13 20:34:41.138872 systemd-logind[1476]: New session 6 of user core.
Jan 13 20:34:41.150264 systemd[1]: Started session-6.scope - Session 6 of User core.
Jan 13 20:34:41.204957 sshd[1627]: Connection closed by 10.0.0.1 port 45814
Jan 13 20:34:41.205262 sshd-session[1625]: pam_unix(sshd:session): session closed for user core
Jan 13 20:34:41.222966 systemd[1]: sshd@5-10.0.0.54:22-10.0.0.1:45814.service: Deactivated successfully.
Jan 13 20:34:41.224620 systemd[1]: session-6.scope: Deactivated successfully.
Jan 13 20:34:41.226202 systemd-logind[1476]: Session 6 logged out. Waiting for processes to exit.
Jan 13 20:34:41.233412 systemd[1]: Started sshd@6-10.0.0.54:22-10.0.0.1:45830.service - OpenSSH per-connection server daemon (10.0.0.1:45830).
Jan 13 20:34:41.234482 systemd-logind[1476]: Removed session 6.
Jan 13 20:34:41.269671 sshd[1632]: Accepted publickey for core from 10.0.0.1 port 45830 ssh2: RSA SHA256:uJ7Cm0ZiB1cKFsV9zv9H+G33T+grLCcYOUFbEs15LGg
Jan 13 20:34:41.271134 sshd-session[1632]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Jan 13 20:34:41.275242 systemd-logind[1476]: New session 7 of user core.
Jan 13 20:34:41.290318 systemd[1]: Started session-7.scope - Session 7 of User core.
Jan 13 20:34:41.348863 sudo[1635]:     core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh
Jan 13 20:34:41.349237 sudo[1635]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500)
Jan 13 20:34:41.371387 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent...
Jan 13 20:34:41.391004 systemd[1]: coreos-metadata.service: Deactivated successfully.
Jan 13 20:34:41.391266 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent.
Jan 13 20:34:41.849890 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent.
Jan 13 20:34:41.850076 systemd[1]: kubelet.service: Consumed 1.187s CPU time.
Jan 13 20:34:41.860297 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent...
Jan 13 20:34:41.877886 systemd[1]: Reloading requested from client PID 1682 ('systemctl') (unit session-7.scope)...
Jan 13 20:34:41.877901 systemd[1]: Reloading...
Jan 13 20:34:41.957499 zram_generator::config[1726]: No configuration found.
Jan 13 20:34:42.194729 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly.
Jan 13 20:34:42.269314 systemd[1]: Reloading finished in 390 ms.
Jan 13 20:34:42.319997 systemd[1]: Stopping kubelet.service - kubelet: The Kubernetes Node Agent...
Jan 13 20:34:42.323005 systemd[1]: kubelet.service: Deactivated successfully.
Jan 13 20:34:42.323287 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent.
Jan 13 20:34:42.324989 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent...
Jan 13 20:34:42.490088 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent.
Jan 13 20:34:42.497303 (kubelet)[1770]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS
Jan 13 20:34:42.545158 kubelet[1770]: Flag --container-runtime-endpoint has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information.
Jan 13 20:34:42.545158 kubelet[1770]: Flag --pod-infra-container-image has been deprecated, will be removed in a future release. Image garbage collector will get sandbox image information from CRI.
Jan 13 20:34:42.545158 kubelet[1770]: Flag --volume-plugin-dir has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information.
Jan 13 20:34:42.546094 kubelet[1770]: I0113 20:34:42.546041    1770 server.go:205] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime"
Jan 13 20:34:43.045541 kubelet[1770]: I0113 20:34:43.045473    1770 server.go:484] "Kubelet version" kubeletVersion="v1.30.1"
Jan 13 20:34:43.045541 kubelet[1770]: I0113 20:34:43.045504    1770 server.go:486] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK=""
Jan 13 20:34:43.045764 kubelet[1770]: I0113 20:34:43.045739    1770 server.go:927] "Client rotation is on, will bootstrap in background"
Jan 13 20:34:43.060611 kubelet[1770]: I0113 20:34:43.060570    1770 dynamic_cafile_content.go:157] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt"
Jan 13 20:34:43.077951 kubelet[1770]: I0113 20:34:43.077907    1770 server.go:742] "--cgroups-per-qos enabled, but --cgroup-root was not specified.  defaulting to /"
Jan 13 20:34:43.078239 kubelet[1770]: I0113 20:34:43.078195    1770 container_manager_linux.go:265] "Container manager verified user specified cgroup-root exists" cgroupRoot=[]
Jan 13 20:34:43.078397 kubelet[1770]: I0113 20:34:43.078228    1770 container_manager_linux.go:270] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.54","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null}
Jan 13 20:34:43.078506 kubelet[1770]: I0113 20:34:43.078403    1770 topology_manager.go:138] "Creating topology manager with none policy"
Jan 13 20:34:43.078506 kubelet[1770]: I0113 20:34:43.078413    1770 container_manager_linux.go:301] "Creating device plugin manager"
Jan 13 20:34:43.078573 kubelet[1770]: I0113 20:34:43.078555    1770 state_mem.go:36] "Initialized new in-memory state store"
Jan 13 20:34:43.079219 kubelet[1770]: I0113 20:34:43.079193    1770 kubelet.go:400] "Attempting to sync node with API server"
Jan 13 20:34:43.079219 kubelet[1770]: I0113 20:34:43.079209    1770 kubelet.go:301] "Adding static pod path" path="/etc/kubernetes/manifests"
Jan 13 20:34:43.079330 kubelet[1770]: I0113 20:34:43.079230    1770 kubelet.go:312] "Adding apiserver pod source"
Jan 13 20:34:43.079330 kubelet[1770]: I0113 20:34:43.079248    1770 apiserver.go:42] "Waiting for node sync before watching apiserver pods"
Jan 13 20:34:43.079395 kubelet[1770]: E0113 20:34:43.079364    1770 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:34:43.079686 kubelet[1770]: E0113 20:34:43.079615    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:34:43.082723 kubelet[1770]: I0113 20:34:43.082675    1770 kuberuntime_manager.go:261] "Container runtime initialized" containerRuntime="containerd" version="v1.7.23" apiVersion="v1"
Jan 13 20:34:43.083460 kubelet[1770]: W0113 20:34:43.083439    1770 reflector.go:547] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope
Jan 13 20:34:43.083553 kubelet[1770]: E0113 20:34:43.083488    1770 reflector.go:150] k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Service: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope
Jan 13 20:34:43.083656 kubelet[1770]: W0113 20:34:43.083638    1770 reflector.go:547] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Node: nodes "10.0.0.54" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope
Jan 13 20:34:43.083689 kubelet[1770]: E0113 20:34:43.083657    1770 reflector.go:150] k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Node: failed to list *v1.Node: nodes "10.0.0.54" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope
Jan 13 20:34:43.084075 kubelet[1770]: I0113 20:34:43.084050    1770 kubelet.go:815] "Not starting ClusterTrustBundle informer because we are in static kubelet mode"
Jan 13 20:34:43.084133 kubelet[1770]: W0113 20:34:43.084108    1770 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating.
Jan 13 20:34:43.084905 kubelet[1770]: I0113 20:34:43.084807    1770 server.go:1264] "Started kubelet"
Jan 13 20:34:43.085865 kubelet[1770]: I0113 20:34:43.085058    1770 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10
Jan 13 20:34:43.085865 kubelet[1770]: I0113 20:34:43.085442    1770 server.go:163] "Starting to listen" address="0.0.0.0" port=10250
Jan 13 20:34:43.085865 kubelet[1770]: I0113 20:34:43.085524    1770 server.go:227] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock"
Jan 13 20:34:43.086883 kubelet[1770]: I0113 20:34:43.086284    1770 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer"
Jan 13 20:34:43.086883 kubelet[1770]: I0113 20:34:43.086325    1770 server.go:455] "Adding debug handlers to kubelet server"
Jan 13 20:34:43.088696 kubelet[1770]: I0113 20:34:43.087298    1770 volume_manager.go:291] "Starting Kubelet Volume Manager"
Jan 13 20:34:43.088696 kubelet[1770]: I0113 20:34:43.087391    1770 desired_state_of_world_populator.go:149] "Desired state populator starts to run"
Jan 13 20:34:43.088696 kubelet[1770]: I0113 20:34:43.087438    1770 reconciler.go:26] "Reconciler: start to sync state"
Jan 13 20:34:43.089845 kubelet[1770]: I0113 20:34:43.089815    1770 factory.go:221] Registration of the systemd container factory successfully
Jan 13 20:34:43.089970 kubelet[1770]: I0113 20:34:43.089928    1770 factory.go:219] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory
Jan 13 20:34:43.090784 kubelet[1770]: E0113 20:34:43.090753    1770 controller.go:145] "Failed to ensure lease exists, will retry" err="leases.coordination.k8s.io \"10.0.0.54\" is forbidden: User \"system:anonymous\" cannot get resource \"leases\" in API group \"coordination.k8s.io\" in the namespace \"kube-node-lease\"" interval="200ms"
Jan 13 20:34:43.090940 kubelet[1770]: W0113 20:34:43.090906    1770 reflector.go:547] k8s.io/client-go/informers/factory.go:160: failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User "system:anonymous" cannot list resource "csidrivers" in API group "storage.k8s.io" at the cluster scope
Jan 13 20:34:43.090940 kubelet[1770]: E0113 20:34:43.090937    1770 reflector.go:150] k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.CSIDriver: failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User "system:anonymous" cannot list resource "csidrivers" in API group "storage.k8s.io" at the cluster scope
Jan 13 20:34:43.091474 kubelet[1770]: I0113 20:34:43.091448    1770 factory.go:221] Registration of the containerd container factory successfully
Jan 13 20:34:43.091634 kubelet[1770]: E0113 20:34:43.091554    1770 kubelet.go:1467] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem"
Jan 13 20:34:43.101849 kubelet[1770]: E0113 20:34:43.101690    1770 event.go:359] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{10.0.0.54.181a5ad65ffeaa45  default    0 0001-01-01 00:00:00 +0000 UTC <nil> <nil> map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.54,UID:10.0.0.54,APIVersion:,ResourceVersion:,FieldPath:,},Reason:Starting,Message:Starting kubelet.,Source:EventSource{Component:kubelet,Host:10.0.0.54,},FirstTimestamp:2025-01-13 20:34:43.084773957 +0000 UTC m=+0.582669365,LastTimestamp:2025-01-13 20:34:43.084773957 +0000 UTC m=+0.582669365,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.54,}"
Jan 13 20:34:43.102750 kubelet[1770]: I0113 20:34:43.102720    1770 cpu_manager.go:214] "Starting CPU manager" policy="none"
Jan 13 20:34:43.102750 kubelet[1770]: I0113 20:34:43.102744    1770 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s"
Jan 13 20:34:43.102837 kubelet[1770]: I0113 20:34:43.102761    1770 state_mem.go:36] "Initialized new in-memory state store"
Jan 13 20:34:43.103351 kubelet[1770]: E0113 20:34:43.103277    1770 event.go:359] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{10.0.0.54.181a5ad66065e677  default    0 0001-01-01 00:00:00 +0000 UTC <nil> <nil> map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.54,UID:10.0.0.54,APIVersion:,ResourceVersion:,FieldPath:,},Reason:InvalidDiskCapacity,Message:invalid capacity 0 on image filesystem,Source:EventSource{Component:kubelet,Host:10.0.0.54,},FirstTimestamp:2025-01-13 20:34:43.091539575 +0000 UTC m=+0.589434972,LastTimestamp:2025-01-13 20:34:43.091539575 +0000 UTC m=+0.589434972,Count:1,Type:Warning,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.54,}"
Jan 13 20:34:43.107480 kubelet[1770]: E0113 20:34:43.107379    1770 event.go:359] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{10.0.0.54.181a5ad661064744  default    0 0001-01-01 00:00:00 +0000 UTC <nil> <nil> map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.54,UID:10.0.0.54,APIVersion:,ResourceVersion:,FieldPath:,},Reason:NodeHasSufficientMemory,Message:Node 10.0.0.54 status is now: NodeHasSufficientMemory,Source:EventSource{Component:kubelet,Host:10.0.0.54,},FirstTimestamp:2025-01-13 20:34:43.102050116 +0000 UTC m=+0.599945513,LastTimestamp:2025-01-13 20:34:43.102050116 +0000 UTC m=+0.599945513,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.54,}"
Jan 13 20:34:43.111191 kubelet[1770]: E0113 20:34:43.111034    1770 event.go:359] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{10.0.0.54.181a5ad661065981  default    0 0001-01-01 00:00:00 +0000 UTC <nil> <nil> map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.54,UID:10.0.0.54,APIVersion:,ResourceVersion:,FieldPath:,},Reason:NodeHasNoDiskPressure,Message:Node 10.0.0.54 status is now: NodeHasNoDiskPressure,Source:EventSource{Component:kubelet,Host:10.0.0.54,},FirstTimestamp:2025-01-13 20:34:43.102054785 +0000 UTC m=+0.599950182,LastTimestamp:2025-01-13 20:34:43.102054785 +0000 UTC m=+0.599950182,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.54,}"
Jan 13 20:34:43.115166 kubelet[1770]: E0113 20:34:43.115090    1770 event.go:359] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{10.0.0.54.181a5ad661066372  default    0 0001-01-01 00:00:00 +0000 UTC <nil> <nil> map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.54,UID:10.0.0.54,APIVersion:,ResourceVersion:,FieldPath:,},Reason:NodeHasSufficientPID,Message:Node 10.0.0.54 status is now: NodeHasSufficientPID,Source:EventSource{Component:kubelet,Host:10.0.0.54,},FirstTimestamp:2025-01-13 20:34:43.10205733 +0000 UTC m=+0.599952717,LastTimestamp:2025-01-13 20:34:43.10205733 +0000 UTC m=+0.599952717,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.54,}"
Jan 13 20:34:43.188926 kubelet[1770]: I0113 20:34:43.188893    1770 kubelet_node_status.go:73] "Attempting to register node" node="10.0.0.54"
Jan 13 20:34:43.192859 kubelet[1770]: E0113 20:34:43.192752    1770 event.go:359] "Server rejected event (will not retry!)" err="events \"10.0.0.54.181a5ad661064744\" is forbidden: User \"system:anonymous\" cannot patch resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{10.0.0.54.181a5ad661064744  default    0 0001-01-01 00:00:00 +0000 UTC <nil> <nil> map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.54,UID:10.0.0.54,APIVersion:,ResourceVersion:,FieldPath:,},Reason:NodeHasSufficientMemory,Message:Node 10.0.0.54 status is now: NodeHasSufficientMemory,Source:EventSource{Component:kubelet,Host:10.0.0.54,},FirstTimestamp:2025-01-13 20:34:43.102050116 +0000 UTC m=+0.599945513,LastTimestamp:2025-01-13 20:34:43.188858633 +0000 UTC m=+0.686754030,Count:2,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.54,}"
Jan 13 20:34:43.193035 kubelet[1770]: E0113 20:34:43.192892    1770 kubelet_node_status.go:96] "Unable to register node with API server" err="nodes is forbidden: User \"system:anonymous\" cannot create resource \"nodes\" in API group \"\" at the cluster scope" node="10.0.0.54"
Jan 13 20:34:43.196230 kubelet[1770]: E0113 20:34:43.196135    1770 event.go:359] "Server rejected event (will not retry!)" err="events \"10.0.0.54.181a5ad661065981\" is forbidden: User \"system:anonymous\" cannot patch resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{10.0.0.54.181a5ad661065981  default    0 0001-01-01 00:00:00 +0000 UTC <nil> <nil> map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.54,UID:10.0.0.54,APIVersion:,ResourceVersion:,FieldPath:,},Reason:NodeHasNoDiskPressure,Message:Node 10.0.0.54 status is now: NodeHasNoDiskPressure,Source:EventSource{Component:kubelet,Host:10.0.0.54,},FirstTimestamp:2025-01-13 20:34:43.102054785 +0000 UTC m=+0.599950182,LastTimestamp:2025-01-13 20:34:43.188863322 +0000 UTC m=+0.686758719,Count:2,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.54,}"
Jan 13 20:34:43.199491 kubelet[1770]: E0113 20:34:43.199391    1770 event.go:359] "Server rejected event (will not retry!)" err="events \"10.0.0.54.181a5ad661066372\" is forbidden: User \"system:anonymous\" cannot patch resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{10.0.0.54.181a5ad661066372  default    0 0001-01-01 00:00:00 +0000 UTC <nil> <nil> map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.54,UID:10.0.0.54,APIVersion:,ResourceVersion:,FieldPath:,},Reason:NodeHasSufficientPID,Message:Node 10.0.0.54 status is now: NodeHasSufficientPID,Source:EventSource{Component:kubelet,Host:10.0.0.54,},FirstTimestamp:2025-01-13 20:34:43.10205733 +0000 UTC m=+0.599952717,LastTimestamp:2025-01-13 20:34:43.188865737 +0000 UTC m=+0.686761124,Count:2,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.54,}"
Jan 13 20:34:43.294569 kubelet[1770]: E0113 20:34:43.294492    1770 controller.go:145] "Failed to ensure lease exists, will retry" err="leases.coordination.k8s.io \"10.0.0.54\" is forbidden: User \"system:anonymous\" cannot get resource \"leases\" in API group \"coordination.k8s.io\" in the namespace \"kube-node-lease\"" interval="400ms"
Jan 13 20:34:43.393935 kubelet[1770]: I0113 20:34:43.393824    1770 kubelet_node_status.go:73] "Attempting to register node" node="10.0.0.54"
Jan 13 20:34:43.787047 sudo[1635]: pam_unix(sudo:session): session closed for user root
Jan 13 20:34:43.788849 sshd[1634]: Connection closed by 10.0.0.1 port 45830
Jan 13 20:34:43.789187 sshd-session[1632]: pam_unix(sshd:session): session closed for user core
Jan 13 20:34:43.793252 systemd[1]: sshd@6-10.0.0.54:22-10.0.0.1:45830.service: Deactivated successfully.
Jan 13 20:34:43.795176 systemd[1]: session-7.scope: Deactivated successfully.
Jan 13 20:34:43.795745 systemd-logind[1476]: Session 7 logged out. Waiting for processes to exit.
Jan 13 20:34:43.796583 systemd-logind[1476]: Removed session 7.
Jan 13 20:34:44.082050 kubelet[1770]: I0113 20:34:44.081873    1770 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials"
Jan 13 20:34:44.082468 kubelet[1770]: E0113 20:34:44.082099    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:34:44.083455 kubelet[1770]: I0113 20:34:44.083303    1770 kubelet_node_status.go:76] "Successfully registered node" node="10.0.0.54"
Jan 13 20:34:44.088533 kubelet[1770]: I0113 20:34:44.088514    1770 kuberuntime_manager.go:1523] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24"
Jan 13 20:34:44.088958 containerd[1487]: time="2025-01-13T20:34:44.088922404Z" level=info msg="No cni config template is specified, wait for other system components to drop the config."
Jan 13 20:34:44.089295 kubelet[1770]: I0113 20:34:44.089162    1770 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24"
Jan 13 20:34:44.107106 kubelet[1770]: I0113 20:34:44.107074    1770 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4"
Jan 13 20:34:44.108350 kubelet[1770]: I0113 20:34:44.108322    1770 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6"
Jan 13 20:34:44.108387 kubelet[1770]: I0113 20:34:44.108354    1770 status_manager.go:217] "Starting to sync pod status with apiserver"
Jan 13 20:34:44.108387 kubelet[1770]: I0113 20:34:44.108375    1770 kubelet.go:2337] "Starting kubelet main sync loop"
Jan 13 20:34:44.108448 kubelet[1770]: E0113 20:34:44.108421    1770 kubelet.go:2361] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]"
Jan 13 20:34:44.127752 kubelet[1770]: I0113 20:34:44.127707    1770 policy_none.go:49] "None policy: Start"
Jan 13 20:34:44.128365 kubelet[1770]: I0113 20:34:44.128347    1770 memory_manager.go:170] "Starting memorymanager" policy="None"
Jan 13 20:34:44.128421 kubelet[1770]: I0113 20:34:44.128371    1770 state_mem.go:35] "Initializing new in-memory state store"
Jan 13 20:34:44.167608 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice.
Jan 13 20:34:44.178400 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice.
Jan 13 20:34:44.181605 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice.
Jan 13 20:34:44.191146 kubelet[1770]: I0113 20:34:44.191084    1770 manager.go:479] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found"
Jan 13 20:34:44.191388 kubelet[1770]: I0113 20:34:44.191348    1770 container_log_manager.go:186] "Initializing container log rotate workers" workers=1 monitorPeriod="10s"
Jan 13 20:34:44.191780 kubelet[1770]: I0113 20:34:44.191606    1770 plugin_manager.go:118] "Starting Kubelet Plugin Manager"
Jan 13 20:34:45.083009 kubelet[1770]: E0113 20:34:45.082476    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:34:45.083009 kubelet[1770]: I0113 20:34:45.082529    1770 apiserver.go:52] "Watching apiserver"
Jan 13 20:34:45.087639 kubelet[1770]: I0113 20:34:45.087506    1770 topology_manager.go:215] "Topology Admit Handler" podUID="3ef3a1ff-ccdf-40a4-ad75-52b0dab25366" podNamespace="kube-flannel" podName="kube-flannel-ds-wwtjk"
Jan 13 20:34:45.087639 kubelet[1770]: I0113 20:34:45.087642    1770 topology_manager.go:215] "Topology Admit Handler" podUID="3f60ce72-23fe-4821-a109-47059e64c179" podNamespace="kube-system" podName="kube-proxy-dvtb6"
Jan 13 20:34:45.099347 systemd[1]: Created slice kubepods-besteffort-pod3f60ce72_23fe_4821_a109_47059e64c179.slice - libcontainer container kubepods-besteffort-pod3f60ce72_23fe_4821_a109_47059e64c179.slice.
Jan 13 20:34:45.112879 systemd[1]: Created slice kubepods-burstable-pod3ef3a1ff_ccdf_40a4_ad75_52b0dab25366.slice - libcontainer container kubepods-burstable-pod3ef3a1ff_ccdf_40a4_ad75_52b0dab25366.slice.
Jan 13 20:34:45.188138 kubelet[1770]: I0113 20:34:45.188091    1770 desired_state_of_world_populator.go:157] "Finished populating initial desired state of world"
Jan 13 20:34:45.287848 kubelet[1770]: I0113 20:34:45.287749    1770 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/3ef3a1ff-ccdf-40a4-ad75-52b0dab25366-flannel-cfg\") pod \"kube-flannel-ds-wwtjk\" (UID: \"3ef3a1ff-ccdf-40a4-ad75-52b0dab25366\") " pod="kube-flannel/kube-flannel-ds-wwtjk"
Jan 13 20:34:45.287848 kubelet[1770]: I0113 20:34:45.287822    1770 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/3ef3a1ff-ccdf-40a4-ad75-52b0dab25366-xtables-lock\") pod \"kube-flannel-ds-wwtjk\" (UID: \"3ef3a1ff-ccdf-40a4-ad75-52b0dab25366\") " pod="kube-flannel/kube-flannel-ds-wwtjk"
Jan 13 20:34:45.287848 kubelet[1770]: I0113 20:34:45.287849    1770 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-9cts9\" (UniqueName: \"kubernetes.io/projected/3ef3a1ff-ccdf-40a4-ad75-52b0dab25366-kube-api-access-9cts9\") pod \"kube-flannel-ds-wwtjk\" (UID: \"3ef3a1ff-ccdf-40a4-ad75-52b0dab25366\") " pod="kube-flannel/kube-flannel-ds-wwtjk"
Jan 13 20:34:45.288049 kubelet[1770]: I0113 20:34:45.287871    1770 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/3f60ce72-23fe-4821-a109-47059e64c179-xtables-lock\") pod \"kube-proxy-dvtb6\" (UID: \"3f60ce72-23fe-4821-a109-47059e64c179\") " pod="kube-system/kube-proxy-dvtb6"
Jan 13 20:34:45.288049 kubelet[1770]: I0113 20:34:45.287892    1770 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-8jt5q\" (UniqueName: \"kubernetes.io/projected/3f60ce72-23fe-4821-a109-47059e64c179-kube-api-access-8jt5q\") pod \"kube-proxy-dvtb6\" (UID: \"3f60ce72-23fe-4821-a109-47059e64c179\") " pod="kube-system/kube-proxy-dvtb6"
Jan 13 20:34:45.288049 kubelet[1770]: I0113 20:34:45.287927    1770 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/3ef3a1ff-ccdf-40a4-ad75-52b0dab25366-cni-plugin\") pod \"kube-flannel-ds-wwtjk\" (UID: \"3ef3a1ff-ccdf-40a4-ad75-52b0dab25366\") " pod="kube-flannel/kube-flannel-ds-wwtjk"
Jan 13 20:34:45.288049 kubelet[1770]: I0113 20:34:45.287949    1770 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/3ef3a1ff-ccdf-40a4-ad75-52b0dab25366-cni\") pod \"kube-flannel-ds-wwtjk\" (UID: \"3ef3a1ff-ccdf-40a4-ad75-52b0dab25366\") " pod="kube-flannel/kube-flannel-ds-wwtjk"
Jan 13 20:34:45.288049 kubelet[1770]: I0113 20:34:45.287974    1770 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/3f60ce72-23fe-4821-a109-47059e64c179-kube-proxy\") pod \"kube-proxy-dvtb6\" (UID: \"3f60ce72-23fe-4821-a109-47059e64c179\") " pod="kube-system/kube-proxy-dvtb6"
Jan 13 20:34:45.288183 kubelet[1770]: I0113 20:34:45.288070    1770 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/3f60ce72-23fe-4821-a109-47059e64c179-lib-modules\") pod \"kube-proxy-dvtb6\" (UID: \"3f60ce72-23fe-4821-a109-47059e64c179\") " pod="kube-system/kube-proxy-dvtb6"
Jan 13 20:34:45.288183 kubelet[1770]: I0113 20:34:45.288160    1770 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/3ef3a1ff-ccdf-40a4-ad75-52b0dab25366-run\") pod \"kube-flannel-ds-wwtjk\" (UID: \"3ef3a1ff-ccdf-40a4-ad75-52b0dab25366\") " pod="kube-flannel/kube-flannel-ds-wwtjk"
Jan 13 20:34:45.410359 kubelet[1770]: E0113 20:34:45.409827    1770 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8"
Jan 13 20:34:45.410721 containerd[1487]: time="2025-01-13T20:34:45.410667338Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-dvtb6,Uid:3f60ce72-23fe-4821-a109-47059e64c179,Namespace:kube-system,Attempt:0,}"
Jan 13 20:34:45.425041 kubelet[1770]: E0113 20:34:45.424666    1770 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8"
Jan 13 20:34:45.425404 containerd[1487]: time="2025-01-13T20:34:45.425313855Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-wwtjk,Uid:3ef3a1ff-ccdf-40a4-ad75-52b0dab25366,Namespace:kube-flannel,Attempt:0,}"
Jan 13 20:34:46.083416 kubelet[1770]: E0113 20:34:46.083356    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:34:47.084446 kubelet[1770]: E0113 20:34:47.084358    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:34:47.107419 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3830996806.mount: Deactivated successfully.
Jan 13 20:34:47.240135 containerd[1487]: time="2025-01-13T20:34:47.240052919Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause:3.8\"  labels:{key:\"io.cri-containerd.image\"  value:\"managed\"}  labels:{key:\"io.cri-containerd.pinned\"  value:\"pinned\"}"
Jan 13 20:34:47.272675 containerd[1487]: time="2025-01-13T20:34:47.272615976Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=312056"
Jan 13 20:34:47.289234 containerd[1487]: time="2025-01-13T20:34:47.289169450Z" level=info msg="ImageUpdate event name:\"registry.k8s.io/pause:3.8\"  labels:{key:\"io.cri-containerd.image\"  value:\"managed\"}  labels:{key:\"io.cri-containerd.pinned\"  value:\"pinned\"}"
Jan 13 20:34:47.303845 containerd[1487]: time="2025-01-13T20:34:47.303762327Z" 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 13 20:34:47.318466 containerd[1487]: time="2025-01-13T20:34:47.318365653Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0"
Jan 13 20:34:47.337416 containerd[1487]: time="2025-01-13T20:34:47.337232505Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\"  labels:{key:\"io.cri-containerd.image\"  value:\"managed\"}  labels:{key:\"io.cri-containerd.pinned\"  value:\"pinned\"}"
Jan 13 20:34:47.338467 containerd[1487]: time="2025-01-13T20:34:47.338406787Z" 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 1.927574811s"
Jan 13 20:34:47.341025 containerd[1487]: time="2025-01-13T20:34:47.340981105Z" 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 1.915543037s"
Jan 13 20:34:47.872633 containerd[1487]: time="2025-01-13T20:34:47.872348348Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1
Jan 13 20:34:47.872633 containerd[1487]: time="2025-01-13T20:34:47.872418300Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1
Jan 13 20:34:47.872633 containerd[1487]: time="2025-01-13T20:34:47.872432055Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jan 13 20:34:47.872633 containerd[1487]: time="2025-01-13T20:34:47.872513668Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jan 13 20:34:47.873593 containerd[1487]: time="2025-01-13T20:34:47.871405250Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1
Jan 13 20:34:47.873593 containerd[1487]: time="2025-01-13T20:34:47.873571883Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1
Jan 13 20:34:47.873764 containerd[1487]: time="2025-01-13T20:34:47.873587222Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jan 13 20:34:47.873764 containerd[1487]: time="2025-01-13T20:34:47.873693511Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jan 13 20:34:48.006394 systemd[1]: Started cri-containerd-ce0231ea4c0b98ea4a383f67b25cd96dea7f0eb6b46907d271f36998ff6ef184.scope - libcontainer container ce0231ea4c0b98ea4a383f67b25cd96dea7f0eb6b46907d271f36998ff6ef184.
Jan 13 20:34:48.012653 systemd[1]: Started cri-containerd-0c1b9bb5e4839d9a3d0ee43aba052f29b7a8941bd6951e3c19ed551e54edd81d.scope - libcontainer container 0c1b9bb5e4839d9a3d0ee43aba052f29b7a8941bd6951e3c19ed551e54edd81d.
Jan 13 20:34:48.090789 kubelet[1770]: E0113 20:34:48.085068    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:34:48.257173 containerd[1487]: time="2025-01-13T20:34:48.256986622Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-wwtjk,Uid:3ef3a1ff-ccdf-40a4-ad75-52b0dab25366,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"0c1b9bb5e4839d9a3d0ee43aba052f29b7a8941bd6951e3c19ed551e54edd81d\""
Jan 13 20:34:48.258826 kubelet[1770]: E0113 20:34:48.258437    1770 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8"
Jan 13 20:34:48.260139 containerd[1487]: time="2025-01-13T20:34:48.260108758Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\""
Jan 13 20:34:48.443002 containerd[1487]: time="2025-01-13T20:34:48.442949529Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-dvtb6,Uid:3f60ce72-23fe-4821-a109-47059e64c179,Namespace:kube-system,Attempt:0,} returns sandbox id \"ce0231ea4c0b98ea4a383f67b25cd96dea7f0eb6b46907d271f36998ff6ef184\""
Jan 13 20:34:48.443783 kubelet[1770]: E0113 20:34:48.443759    1770 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8"
Jan 13 20:34:49.085859 kubelet[1770]: E0113 20:34:49.085819    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:34:50.086871 kubelet[1770]: E0113 20:34:50.086741    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:34:51.035245 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2809027976.mount: Deactivated successfully.
Jan 13 20:34:51.087865 kubelet[1770]: E0113 20:34:51.087821    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:34:51.620228 containerd[1487]: time="2025-01-13T20:34:51.619038720Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\"  labels:{key:\"io.cri-containerd.image\"  value:\"managed\"}"
Jan 13 20:34:51.633800 containerd[1487]: time="2025-01-13T20:34:51.633063371Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3852937"
Jan 13 20:34:51.640884 containerd[1487]: time="2025-01-13T20:34:51.639605510Z" level=info msg="ImageCreate event name:\"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\"  labels:{key:\"io.cri-containerd.image\"  value:\"managed\"}"
Jan 13 20:34:51.656837 containerd[1487]: time="2025-01-13T20:34:51.652456050Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\"  labels:{key:\"io.cri-containerd.image\"  value:\"managed\"}"
Jan 13 20:34:51.656837 containerd[1487]: time="2025-01-13T20:34:51.653530324Z" 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 3.393383646s"
Jan 13 20:34:51.656837 containerd[1487]: time="2025-01-13T20:34:51.653559769Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\""
Jan 13 20:34:51.657619 containerd[1487]: time="2025-01-13T20:34:51.657364344Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.30.8\""
Jan 13 20:34:51.658784 containerd[1487]: time="2025-01-13T20:34:51.658631200Z" level=info msg="CreateContainer within sandbox \"0c1b9bb5e4839d9a3d0ee43aba052f29b7a8941bd6951e3c19ed551e54edd81d\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}"
Jan 13 20:34:51.771867 containerd[1487]: time="2025-01-13T20:34:51.770864127Z" level=info msg="CreateContainer within sandbox \"0c1b9bb5e4839d9a3d0ee43aba052f29b7a8941bd6951e3c19ed551e54edd81d\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"8f0a4169660a94c899da3049bf8b082aa1b2ebbf9104a243220eaa1c3efc5ee8\""
Jan 13 20:34:51.775352 containerd[1487]: time="2025-01-13T20:34:51.772274382Z" level=info msg="StartContainer for \"8f0a4169660a94c899da3049bf8b082aa1b2ebbf9104a243220eaa1c3efc5ee8\""
Jan 13 20:34:51.838427 systemd[1]: Started cri-containerd-8f0a4169660a94c899da3049bf8b082aa1b2ebbf9104a243220eaa1c3efc5ee8.scope - libcontainer container 8f0a4169660a94c899da3049bf8b082aa1b2ebbf9104a243220eaa1c3efc5ee8.
Jan 13 20:34:52.088829 kubelet[1770]: E0113 20:34:52.088620    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:34:52.222747 systemd[1]: cri-containerd-8f0a4169660a94c899da3049bf8b082aa1b2ebbf9104a243220eaa1c3efc5ee8.scope: Deactivated successfully.
Jan 13 20:34:52.259085 containerd[1487]: time="2025-01-13T20:34:52.258230092Z" level=info msg="StartContainer for \"8f0a4169660a94c899da3049bf8b082aa1b2ebbf9104a243220eaa1c3efc5ee8\" returns successfully"
Jan 13 20:34:52.310967 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-8f0a4169660a94c899da3049bf8b082aa1b2ebbf9104a243220eaa1c3efc5ee8-rootfs.mount: Deactivated successfully.
Jan 13 20:34:52.450612 containerd[1487]: time="2025-01-13T20:34:52.450422211Z" level=info msg="shim disconnected" id=8f0a4169660a94c899da3049bf8b082aa1b2ebbf9104a243220eaa1c3efc5ee8 namespace=k8s.io
Jan 13 20:34:52.450612 containerd[1487]: time="2025-01-13T20:34:52.450492753Z" level=warning msg="cleaning up after shim disconnected" id=8f0a4169660a94c899da3049bf8b082aa1b2ebbf9104a243220eaa1c3efc5ee8 namespace=k8s.io
Jan 13 20:34:52.450612 containerd[1487]: time="2025-01-13T20:34:52.450505086Z" level=info msg="cleaning up dead shim" namespace=k8s.io
Jan 13 20:34:53.088986 kubelet[1770]: E0113 20:34:53.088934    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:34:53.149898 kubelet[1770]: E0113 20:34:53.149868    1770 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8"
Jan 13 20:34:54.089089 kubelet[1770]: E0113 20:34:54.089036    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:34:54.293245 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1817967738.mount: Deactivated successfully.
Jan 13 20:34:54.997148 containerd[1487]: time="2025-01-13T20:34:54.996497882Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.30.8\"  labels:{key:\"io.cri-containerd.image\"  value:\"managed\"}"
Jan 13 20:34:54.998265 containerd[1487]: time="2025-01-13T20:34:54.998211004Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.30.8: active requests=0, bytes read=29057470"
Jan 13 20:34:55.012607 containerd[1487]: time="2025-01-13T20:34:55.012543673Z" level=info msg="ImageCreate event name:\"sha256:ce61fda67eb41cf09d2b984e7979e289b5042e3983ddfc67be678425632cc0d2\"  labels:{key:\"io.cri-containerd.image\"  value:\"managed\"}"
Jan 13 20:34:55.022972 containerd[1487]: time="2025-01-13T20:34:55.022888183Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:f6d6be9417e22af78905000ac4fd134896bacd2188ea63c7cac8edd7a5d7e9b5\"  labels:{key:\"io.cri-containerd.image\"  value:\"managed\"}"
Jan 13 20:34:55.025030 containerd[1487]: time="2025-01-13T20:34:55.023777781Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.30.8\" with image id \"sha256:ce61fda67eb41cf09d2b984e7979e289b5042e3983ddfc67be678425632cc0d2\", repo tag \"registry.k8s.io/kube-proxy:v1.30.8\", repo digest \"registry.k8s.io/kube-proxy@sha256:f6d6be9417e22af78905000ac4fd134896bacd2188ea63c7cac8edd7a5d7e9b5\", size \"29056489\" in 3.366378251s"
Jan 13 20:34:55.025030 containerd[1487]: time="2025-01-13T20:34:55.023842002Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.30.8\" returns image reference \"sha256:ce61fda67eb41cf09d2b984e7979e289b5042e3983ddfc67be678425632cc0d2\""
Jan 13 20:34:55.026747 containerd[1487]: time="2025-01-13T20:34:55.026692618Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\""
Jan 13 20:34:55.027741 containerd[1487]: time="2025-01-13T20:34:55.027692954Z" level=info msg="CreateContainer within sandbox \"ce0231ea4c0b98ea4a383f67b25cd96dea7f0eb6b46907d271f36998ff6ef184\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}"
Jan 13 20:34:55.089766 kubelet[1770]: E0113 20:34:55.089622    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:34:55.220246 containerd[1487]: time="2025-01-13T20:34:55.220153947Z" level=info msg="CreateContainer within sandbox \"ce0231ea4c0b98ea4a383f67b25cd96dea7f0eb6b46907d271f36998ff6ef184\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"d67832f8570a6abf2a44a8aafdcec4f8dbca22de6dd791fbad5131008000919e\""
Jan 13 20:34:55.221055 containerd[1487]: time="2025-01-13T20:34:55.220887542Z" level=info msg="StartContainer for \"d67832f8570a6abf2a44a8aafdcec4f8dbca22de6dd791fbad5131008000919e\""
Jan 13 20:34:55.282844 systemd[1]: Started cri-containerd-d67832f8570a6abf2a44a8aafdcec4f8dbca22de6dd791fbad5131008000919e.scope - libcontainer container d67832f8570a6abf2a44a8aafdcec4f8dbca22de6dd791fbad5131008000919e.
Jan 13 20:34:55.402943 containerd[1487]: time="2025-01-13T20:34:55.402846300Z" level=info msg="StartContainer for \"d67832f8570a6abf2a44a8aafdcec4f8dbca22de6dd791fbad5131008000919e\" returns successfully"
Jan 13 20:34:56.089874 kubelet[1770]: E0113 20:34:56.089798    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:34:56.161706 kubelet[1770]: E0113 20:34:56.161669    1770 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8"
Jan 13 20:34:56.192915 kubelet[1770]: I0113 20:34:56.192799    1770 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-dvtb6" podStartSLOduration=6.611081363 podStartE2EDuration="13.192776842s" podCreationTimestamp="2025-01-13 20:34:43 +0000 UTC" firstStartedPulling="2025-01-13 20:34:48.444333254 +0000 UTC m=+5.942228651" lastFinishedPulling="2025-01-13 20:34:55.026028723 +0000 UTC m=+12.523924130" observedRunningTime="2025-01-13 20:34:56.192637921 +0000 UTC m=+13.690533318" watchObservedRunningTime="2025-01-13 20:34:56.192776842 +0000 UTC m=+13.690672239"
Jan 13 20:34:57.090446 kubelet[1770]: E0113 20:34:57.090380    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:34:57.170568 kubelet[1770]: E0113 20:34:57.170038    1770 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8"
Jan 13 20:34:57.879638 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount180320208.mount: Deactivated successfully.
Jan 13 20:34:58.091340 kubelet[1770]: E0113 20:34:58.091072    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:34:59.092245 kubelet[1770]: E0113 20:34:59.092181    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:35:00.092489 kubelet[1770]: E0113 20:35:00.092438    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:35:00.229671 containerd[1487]: time="2025-01-13T20:35:00.229605456Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\"  labels:{key:\"io.cri-containerd.image\"  value:\"managed\"}"
Jan 13 20:35:00.259598 containerd[1487]: time="2025-01-13T20:35:00.259525818Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26866358"
Jan 13 20:35:00.271011 containerd[1487]: time="2025-01-13T20:35:00.270970701Z" level=info msg="ImageCreate event name:\"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\"  labels:{key:\"io.cri-containerd.image\"  value:\"managed\"}"
Jan 13 20:35:00.316984 containerd[1487]: time="2025-01-13T20:35:00.316925192Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\"  labels:{key:\"io.cri-containerd.image\"  value:\"managed\"}"
Jan 13 20:35:00.320536 containerd[1487]: time="2025-01-13T20:35:00.319484892Z" 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 5.292748623s"
Jan 13 20:35:00.320536 containerd[1487]: time="2025-01-13T20:35:00.319523615Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\""
Jan 13 20:35:00.322414 containerd[1487]: time="2025-01-13T20:35:00.322376666Z" level=info msg="CreateContainer within sandbox \"0c1b9bb5e4839d9a3d0ee43aba052f29b7a8941bd6951e3c19ed551e54edd81d\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}"
Jan 13 20:35:00.762656 containerd[1487]: time="2025-01-13T20:35:00.760839700Z" level=info msg="CreateContainer within sandbox \"0c1b9bb5e4839d9a3d0ee43aba052f29b7a8941bd6951e3c19ed551e54edd81d\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"55c7590d37fa2930e6a4289cbf3bbdc20bf3e432ee135f2de2671bc8e72739e1\""
Jan 13 20:35:00.762656 containerd[1487]: time="2025-01-13T20:35:00.761708029Z" level=info msg="StartContainer for \"55c7590d37fa2930e6a4289cbf3bbdc20bf3e432ee135f2de2671bc8e72739e1\""
Jan 13 20:35:00.828927 systemd[1]: Started cri-containerd-55c7590d37fa2930e6a4289cbf3bbdc20bf3e432ee135f2de2671bc8e72739e1.scope - libcontainer container 55c7590d37fa2930e6a4289cbf3bbdc20bf3e432ee135f2de2671bc8e72739e1.
Jan 13 20:35:00.875937 systemd[1]: cri-containerd-55c7590d37fa2930e6a4289cbf3bbdc20bf3e432ee135f2de2671bc8e72739e1.scope: Deactivated successfully.
Jan 13 20:35:00.882385 containerd[1487]: time="2025-01-13T20:35:00.881639673Z" level=warning msg="error from *cgroupsv2.Manager.EventChan" error="failed to add inotify watch for \"/sys/fs/cgroup/kubepods.slice/kubepods-burstable.slice/kubepods-burstable-pod3ef3a1ff_ccdf_40a4_ad75_52b0dab25366.slice/cri-containerd-55c7590d37fa2930e6a4289cbf3bbdc20bf3e432ee135f2de2671bc8e72739e1.scope/memory.events\": no such file or directory"
Jan 13 20:35:00.919732 kubelet[1770]: I0113 20:35:00.919685    1770 kubelet_node_status.go:497] "Fast updating node status as it just became ready"
Jan 13 20:35:00.943619 containerd[1487]: time="2025-01-13T20:35:00.942492584Z" level=info msg="StartContainer for \"55c7590d37fa2930e6a4289cbf3bbdc20bf3e432ee135f2de2671bc8e72739e1\" returns successfully"
Jan 13 20:35:00.972693 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-55c7590d37fa2930e6a4289cbf3bbdc20bf3e432ee135f2de2671bc8e72739e1-rootfs.mount: Deactivated successfully.
Jan 13 20:35:01.093564 kubelet[1770]: E0113 20:35:01.093417    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:35:01.178107 kubelet[1770]: E0113 20:35:01.178073    1770 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8"
Jan 13 20:35:02.093947 kubelet[1770]: E0113 20:35:02.093776    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:35:02.181291 kubelet[1770]: E0113 20:35:02.180972    1770 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8"
Jan 13 20:35:03.000150 containerd[1487]: time="2025-01-13T20:35:02.999998201Z" level=info msg="shim disconnected" id=55c7590d37fa2930e6a4289cbf3bbdc20bf3e432ee135f2de2671bc8e72739e1 namespace=k8s.io
Jan 13 20:35:03.000150 containerd[1487]: time="2025-01-13T20:35:03.000080892Z" level=warning msg="cleaning up after shim disconnected" id=55c7590d37fa2930e6a4289cbf3bbdc20bf3e432ee135f2de2671bc8e72739e1 namespace=k8s.io
Jan 13 20:35:03.000150 containerd[1487]: time="2025-01-13T20:35:03.000092634Z" level=info msg="cleaning up dead shim" namespace=k8s.io
Jan 13 20:35:03.080407 kubelet[1770]: E0113 20:35:03.080325    1770 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:35:03.094146 kubelet[1770]: E0113 20:35:03.093988    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:35:03.261264 kubelet[1770]: E0113 20:35:03.260346    1770 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8"
Jan 13 20:35:03.264411 containerd[1487]: time="2025-01-13T20:35:03.264011185Z" level=info msg="CreateContainer within sandbox \"0c1b9bb5e4839d9a3d0ee43aba052f29b7a8941bd6951e3c19ed551e54edd81d\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}"
Jan 13 20:35:04.039187 containerd[1487]: time="2025-01-13T20:35:04.039136684Z" level=info msg="CreateContainer within sandbox \"0c1b9bb5e4839d9a3d0ee43aba052f29b7a8941bd6951e3c19ed551e54edd81d\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"28bef31f37fb5f41e7bcfffc08973b24e7558ce8a2a99b61d51e4846f32bf942\""
Jan 13 20:35:04.039758 containerd[1487]: time="2025-01-13T20:35:04.039726025Z" level=info msg="StartContainer for \"28bef31f37fb5f41e7bcfffc08973b24e7558ce8a2a99b61d51e4846f32bf942\""
Jan 13 20:35:04.073643 systemd[1]: Started cri-containerd-28bef31f37fb5f41e7bcfffc08973b24e7558ce8a2a99b61d51e4846f32bf942.scope - libcontainer container 28bef31f37fb5f41e7bcfffc08973b24e7558ce8a2a99b61d51e4846f32bf942.
Jan 13 20:35:04.094293 kubelet[1770]: E0113 20:35:04.094251    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:35:04.139179 containerd[1487]: time="2025-01-13T20:35:04.139045212Z" level=info msg="StartContainer for \"28bef31f37fb5f41e7bcfffc08973b24e7558ce8a2a99b61d51e4846f32bf942\" returns successfully"
Jan 13 20:35:04.268467 kubelet[1770]: E0113 20:35:04.268417    1770 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8"
Jan 13 20:35:05.095539 kubelet[1770]: E0113 20:35:05.095460    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:35:05.259740 kubelet[1770]: I0113 20:35:05.259534    1770 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-wwtjk" podStartSLOduration=10.19803496 podStartE2EDuration="22.259508418s" podCreationTimestamp="2025-01-13 20:34:43 +0000 UTC" firstStartedPulling="2025-01-13 20:34:48.259633426 +0000 UTC m=+5.757528823" lastFinishedPulling="2025-01-13 20:35:00.321106874 +0000 UTC m=+17.819002281" observedRunningTime="2025-01-13 20:35:04.401927865 +0000 UTC m=+21.899823262" watchObservedRunningTime="2025-01-13 20:35:05.259508418 +0000 UTC m=+22.757403815"
Jan 13 20:35:05.259990 kubelet[1770]: I0113 20:35:05.259794    1770 topology_manager.go:215] "Topology Admit Handler" podUID="678d4265-987c-454b-9e88-456b77eea309" podNamespace="default" podName="nginx-deployment-85f456d6dd-h74pt"
Jan 13 20:35:05.272981 systemd[1]: Created slice kubepods-besteffort-pod678d4265_987c_454b_9e88_456b77eea309.slice - libcontainer container kubepods-besteffort-pod678d4265_987c_454b_9e88_456b77eea309.slice.
Jan 13 20:35:05.279968 systemd-networkd[1418]: flannel.1: Link UP
Jan 13 20:35:05.279984 systemd-networkd[1418]: flannel.1: Gained carrier
Jan 13 20:35:05.443481 kubelet[1770]: I0113 20:35:05.442950    1770 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-qvlgk\" (UniqueName: \"kubernetes.io/projected/678d4265-987c-454b-9e88-456b77eea309-kube-api-access-qvlgk\") pod \"nginx-deployment-85f456d6dd-h74pt\" (UID: \"678d4265-987c-454b-9e88-456b77eea309\") " pod="default/nginx-deployment-85f456d6dd-h74pt"
Jan 13 20:35:05.580570 containerd[1487]: time="2025-01-13T20:35:05.580510660Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-85f456d6dd-h74pt,Uid:678d4265-987c-454b-9e88-456b77eea309,Namespace:default,Attempt:0,}"
Jan 13 20:35:05.621567 systemd-networkd[1418]: cni0: Link UP
Jan 13 20:35:05.621584 systemd-networkd[1418]: cni0: Gained carrier
Jan 13 20:35:05.623057 systemd-networkd[1418]: cni0: Lost carrier
Jan 13 20:35:05.654240 systemd-networkd[1418]: vethf986fcae: Link UP
Jan 13 20:35:05.660756 kernel: cni0: port 1(vethf986fcae) entered blocking state
Jan 13 20:35:05.660892 kernel: cni0: port 1(vethf986fcae) entered disabled state
Jan 13 20:35:05.660918 kernel: vethf986fcae: entered allmulticast mode
Jan 13 20:35:05.660938 kernel: vethf986fcae: entered promiscuous mode
Jan 13 20:35:05.660961 kernel: cni0: port 1(vethf986fcae) entered blocking state
Jan 13 20:35:05.668509 kernel: cni0: port 1(vethf986fcae) entered forwarding state
Jan 13 20:35:05.679638 kernel: cni0: port 1(vethf986fcae) entered disabled state
Jan 13 20:35:05.701752 kernel: cni0: port 1(vethf986fcae) entered blocking state
Jan 13 20:35:05.701960 kernel: cni0: port 1(vethf986fcae) entered forwarding state
Jan 13 20:35:05.701838 systemd-networkd[1418]: vethf986fcae: Gained carrier
Jan 13 20:35:05.703049 systemd-networkd[1418]: cni0: Gained carrier
Jan 13 20:35:05.716441 containerd[1487]: 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)(0xc00011c8e8), "name":"cbr0", "type":"bridge"}
Jan 13 20:35:05.716441 containerd[1487]: delegateAdd: netconf sent to delegate plugin:
Jan 13 20:35:05.770401 containerd[1487]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-01-13T20:35:05.770006625Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1
Jan 13 20:35:05.770401 containerd[1487]: time="2025-01-13T20:35:05.770073024Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1
Jan 13 20:35:05.770401 containerd[1487]: time="2025-01-13T20:35:05.770085949Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jan 13 20:35:05.770401 containerd[1487]: time="2025-01-13T20:35:05.770203416Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jan 13 20:35:05.812933 systemd[1]: Started cri-containerd-28b4dd4e985ba66d2d3e0f7f0c5290bd89350781a1d50ed87b4658f340070dbb.scope - libcontainer container 28b4dd4e985ba66d2d3e0f7f0c5290bd89350781a1d50ed87b4658f340070dbb.
Jan 13 20:35:05.830595 systemd-resolved[1329]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address
Jan 13 20:35:05.863008 containerd[1487]: time="2025-01-13T20:35:05.862935931Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-85f456d6dd-h74pt,Uid:678d4265-987c-454b-9e88-456b77eea309,Namespace:default,Attempt:0,} returns sandbox id \"28b4dd4e985ba66d2d3e0f7f0c5290bd89350781a1d50ed87b4658f340070dbb\""
Jan 13 20:35:05.864937 containerd[1487]: time="2025-01-13T20:35:05.864898005Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\""
Jan 13 20:35:06.096458 kubelet[1770]: E0113 20:35:06.096294    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:35:06.559800 systemd[1]: run-containerd-runc-k8s.io-28b4dd4e985ba66d2d3e0f7f0c5290bd89350781a1d50ed87b4658f340070dbb-runc.ixEIuw.mount: Deactivated successfully.
Jan 13 20:35:06.799360 systemd-networkd[1418]: flannel.1: Gained IPv6LL
Jan 13 20:35:06.927262 systemd-networkd[1418]: cni0: Gained IPv6LL
Jan 13 20:35:07.096730 kubelet[1770]: E0113 20:35:07.096661    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:35:07.568290 systemd-networkd[1418]: vethf986fcae: Gained IPv6LL
Jan 13 20:35:08.097512 kubelet[1770]: E0113 20:35:08.097465    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:35:08.191869 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount101341238.mount: Deactivated successfully.
Jan 13 20:35:09.098163 kubelet[1770]: E0113 20:35:09.098104    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:35:10.028673 containerd[1487]: time="2025-01-13T20:35:10.028588214Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\"  labels:{key:\"io.cri-containerd.image\"  value:\"managed\"}"
Jan 13 20:35:10.032985 containerd[1487]: time="2025-01-13T20:35:10.032931131Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=71036018"
Jan 13 20:35:10.043387 containerd[1487]: time="2025-01-13T20:35:10.043313328Z" level=info msg="ImageCreate event name:\"sha256:29ef6eaebfc53650f3a4609edbf9d35e866f56b2c5e01d32d93439031b300f0b\"  labels:{key:\"io.cri-containerd.image\"  value:\"managed\"}"
Jan 13 20:35:10.052844 containerd[1487]: time="2025-01-13T20:35:10.052781654Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:eca1d1ff18c7af45f86b7e0b572090f563a676ddca3da2ecff678390366335ad\"  labels:{key:\"io.cri-containerd.image\"  value:\"managed\"}"
Jan 13 20:35:10.053968 containerd[1487]: time="2025-01-13T20:35:10.053927612Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:29ef6eaebfc53650f3a4609edbf9d35e866f56b2c5e01d32d93439031b300f0b\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:eca1d1ff18c7af45f86b7e0b572090f563a676ddca3da2ecff678390366335ad\", size \"71035896\" in 4.188993757s"
Jan 13 20:35:10.053968 containerd[1487]: time="2025-01-13T20:35:10.053959353Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:29ef6eaebfc53650f3a4609edbf9d35e866f56b2c5e01d32d93439031b300f0b\""
Jan 13 20:35:10.055895 containerd[1487]: time="2025-01-13T20:35:10.055864676Z" level=info msg="CreateContainer within sandbox \"28b4dd4e985ba66d2d3e0f7f0c5290bd89350781a1d50ed87b4658f340070dbb\" for container &ContainerMetadata{Name:nginx,Attempt:0,}"
Jan 13 20:35:10.098499 kubelet[1770]: E0113 20:35:10.098448    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:35:10.176867 containerd[1487]: time="2025-01-13T20:35:10.176797788Z" level=info msg="CreateContainer within sandbox \"28b4dd4e985ba66d2d3e0f7f0c5290bd89350781a1d50ed87b4658f340070dbb\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"27a61aa41caac8bb62d86cd7da96dc428480d44fb17f10f9482629824ef79b6c\""
Jan 13 20:35:10.177392 containerd[1487]: time="2025-01-13T20:35:10.177336531Z" level=info msg="StartContainer for \"27a61aa41caac8bb62d86cd7da96dc428480d44fb17f10f9482629824ef79b6c\""
Jan 13 20:35:10.208254 systemd[1]: Started cri-containerd-27a61aa41caac8bb62d86cd7da96dc428480d44fb17f10f9482629824ef79b6c.scope - libcontainer container 27a61aa41caac8bb62d86cd7da96dc428480d44fb17f10f9482629824ef79b6c.
Jan 13 20:35:10.240005 containerd[1487]: time="2025-01-13T20:35:10.239917521Z" level=info msg="StartContainer for \"27a61aa41caac8bb62d86cd7da96dc428480d44fb17f10f9482629824ef79b6c\" returns successfully"
Jan 13 20:35:10.302422 kubelet[1770]: I0113 20:35:10.302240    1770 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-85f456d6dd-h74pt" podStartSLOduration=1.111986307 podStartE2EDuration="5.302220117s" podCreationTimestamp="2025-01-13 20:35:05 +0000 UTC" firstStartedPulling="2025-01-13 20:35:05.864542709 +0000 UTC m=+23.362438096" lastFinishedPulling="2025-01-13 20:35:10.054776509 +0000 UTC m=+27.552671906" observedRunningTime="2025-01-13 20:35:10.302158739 +0000 UTC m=+27.800054146" watchObservedRunningTime="2025-01-13 20:35:10.302220117 +0000 UTC m=+27.800115514"
Jan 13 20:35:11.099182 kubelet[1770]: E0113 20:35:11.098578    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:35:12.100100 kubelet[1770]: E0113 20:35:12.099856    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:35:12.696268 kubelet[1770]: I0113 20:35:12.696219    1770 topology_manager.go:215] "Topology Admit Handler" podUID="79064440-f60d-424a-b8da-225341511ff6" podNamespace="default" podName="nfs-server-provisioner-0"
Jan 13 20:35:12.701683 systemd[1]: Created slice kubepods-besteffort-pod79064440_f60d_424a_b8da_225341511ff6.slice - libcontainer container kubepods-besteffort-pod79064440_f60d_424a_b8da_225341511ff6.slice.
Jan 13 20:35:12.888869 kubelet[1770]: I0113 20:35:12.888817    1770 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/79064440-f60d-424a-b8da-225341511ff6-data\") pod \"nfs-server-provisioner-0\" (UID: \"79064440-f60d-424a-b8da-225341511ff6\") " pod="default/nfs-server-provisioner-0"
Jan 13 20:35:12.888869 kubelet[1770]: I0113 20:35:12.888881    1770 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-z2mzm\" (UniqueName: \"kubernetes.io/projected/79064440-f60d-424a-b8da-225341511ff6-kube-api-access-z2mzm\") pod \"nfs-server-provisioner-0\" (UID: \"79064440-f60d-424a-b8da-225341511ff6\") " pod="default/nfs-server-provisioner-0"
Jan 13 20:35:13.005803 containerd[1487]: time="2025-01-13T20:35:13.005683624Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:79064440-f60d-424a-b8da-225341511ff6,Namespace:default,Attempt:0,}"
Jan 13 20:35:13.036506 systemd-networkd[1418]: veth9b3a0f8c: Link UP
Jan 13 20:35:13.039157 kernel: cni0: port 2(veth9b3a0f8c) entered blocking state
Jan 13 20:35:13.039420 kernel: cni0: port 2(veth9b3a0f8c) entered disabled state
Jan 13 20:35:13.039453 kernel: veth9b3a0f8c: entered allmulticast mode
Jan 13 20:35:13.039475 kernel: veth9b3a0f8c: entered promiscuous mode
Jan 13 20:35:13.078624 kernel: cni0: port 2(veth9b3a0f8c) entered blocking state
Jan 13 20:35:13.078701 kernel: cni0: port 2(veth9b3a0f8c) entered forwarding state
Jan 13 20:35:13.078628 systemd-networkd[1418]: veth9b3a0f8c: Gained carrier
Jan 13 20:35:13.080897 containerd[1487]: 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)(0xc00011c8e8), "name":"cbr0", "type":"bridge"}
Jan 13 20:35:13.080897 containerd[1487]: delegateAdd: netconf sent to delegate plugin:
Jan 13 20:35:13.100253 kubelet[1770]: E0113 20:35:13.100206    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:35:13.101359 containerd[1487]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-01-13T20:35:13.101244475Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1
Jan 13 20:35:13.101359 containerd[1487]: time="2025-01-13T20:35:13.101307896Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1
Jan 13 20:35:13.101449 containerd[1487]: time="2025-01-13T20:35:13.101364454Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jan 13 20:35:13.101514 containerd[1487]: time="2025-01-13T20:35:13.101472290Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jan 13 20:35:13.136269 systemd[1]: Started cri-containerd-9ce195d59bf8a0cb8d47ab871bc4bce9e4028cecfe3a4e71e3910e753cd2a6da.scope - libcontainer container 9ce195d59bf8a0cb8d47ab871bc4bce9e4028cecfe3a4e71e3910e753cd2a6da.
Jan 13 20:35:13.150729 systemd-resolved[1329]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address
Jan 13 20:35:13.178740 containerd[1487]: time="2025-01-13T20:35:13.178683992Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:79064440-f60d-424a-b8da-225341511ff6,Namespace:default,Attempt:0,} returns sandbox id \"9ce195d59bf8a0cb8d47ab871bc4bce9e4028cecfe3a4e71e3910e753cd2a6da\""
Jan 13 20:35:13.180498 containerd[1487]: time="2025-01-13T20:35:13.180441378Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\""
Jan 13 20:35:14.101749 kubelet[1770]: E0113 20:35:14.101683    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:35:14.740195 systemd-networkd[1418]: veth9b3a0f8c: Gained IPv6LL
Jan 13 20:35:15.103366 kubelet[1770]: E0113 20:35:15.103187    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:35:15.178294 update_engine[1480]: I20250113 20:35:15.178176  1480 update_attempter.cc:509] Updating boot flags...
Jan 13 20:35:15.235222 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (2551)
Jan 13 20:35:15.303214 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (2551)
Jan 13 20:35:16.103954 kubelet[1770]: E0113 20:35:16.103861    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:35:16.408424 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3210166604.mount: Deactivated successfully.
Jan 13 20:35:17.107135 kubelet[1770]: E0113 20:35:17.107042    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:35:17.112509 kernel: hrtimer: interrupt took 3421288 ns
Jan 13 20:35:18.107967 kubelet[1770]: E0113 20:35:18.107819    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:35:19.108238 kubelet[1770]: E0113 20:35:19.108196    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:35:20.108726 kubelet[1770]: E0113 20:35:20.108671    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:35:20.748894 containerd[1487]: time="2025-01-13T20:35:20.748816405Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"  labels:{key:\"io.cri-containerd.image\"  value:\"managed\"}"
Jan 13 20:35:20.752098 containerd[1487]: time="2025-01-13T20:35:20.752057375Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=91039406"
Jan 13 20:35:20.753409 containerd[1487]: time="2025-01-13T20:35:20.753354917Z" level=info msg="ImageCreate event name:\"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\"  labels:{key:\"io.cri-containerd.image\"  value:\"managed\"}"
Jan 13 20:35:20.756405 containerd[1487]: time="2025-01-13T20:35:20.756373836Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\"  labels:{key:\"io.cri-containerd.image\"  value:\"managed\"}"
Jan 13 20:35:20.757570 containerd[1487]: time="2025-01-13T20:35:20.757521703Z" 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.577033344s"
Jan 13 20:35:20.757570 containerd[1487]: time="2025-01-13T20:35:20.757556660Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\""
Jan 13 20:35:20.759855 containerd[1487]: time="2025-01-13T20:35:20.759824763Z" level=info msg="CreateContainer within sandbox \"9ce195d59bf8a0cb8d47ab871bc4bce9e4028cecfe3a4e71e3910e753cd2a6da\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}"
Jan 13 20:35:20.774255 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3669564036.mount: Deactivated successfully.
Jan 13 20:35:20.776828 containerd[1487]: time="2025-01-13T20:35:20.776798200Z" level=info msg="CreateContainer within sandbox \"9ce195d59bf8a0cb8d47ab871bc4bce9e4028cecfe3a4e71e3910e753cd2a6da\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"14ea980b67447e5ee2e7b8159f876e047e3cd53f3c26dcfbc6570af5a4c5fef7\""
Jan 13 20:35:20.777215 containerd[1487]: time="2025-01-13T20:35:20.777176268Z" level=info msg="StartContainer for \"14ea980b67447e5ee2e7b8159f876e047e3cd53f3c26dcfbc6570af5a4c5fef7\""
Jan 13 20:35:20.809282 systemd[1]: Started cri-containerd-14ea980b67447e5ee2e7b8159f876e047e3cd53f3c26dcfbc6570af5a4c5fef7.scope - libcontainer container 14ea980b67447e5ee2e7b8159f876e047e3cd53f3c26dcfbc6570af5a4c5fef7.
Jan 13 20:35:20.839190 containerd[1487]: time="2025-01-13T20:35:20.839147977Z" level=info msg="StartContainer for \"14ea980b67447e5ee2e7b8159f876e047e3cd53f3c26dcfbc6570af5a4c5fef7\" returns successfully"
Jan 13 20:35:21.109056 kubelet[1770]: E0113 20:35:21.108780    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:35:22.109188 kubelet[1770]: E0113 20:35:22.109097    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:35:23.081414 kubelet[1770]: E0113 20:35:23.079619    1770 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:35:23.111008 kubelet[1770]: E0113 20:35:23.110931    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:35:24.111163 kubelet[1770]: E0113 20:35:24.111084    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:35:25.111530 kubelet[1770]: E0113 20:35:25.111467    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:35:26.112198 kubelet[1770]: E0113 20:35:26.112158    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:35:27.112545 kubelet[1770]: E0113 20:35:27.112481    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:35:28.112959 kubelet[1770]: E0113 20:35:28.112923    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:35:29.113776 kubelet[1770]: E0113 20:35:29.113718    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:35:30.114370 kubelet[1770]: E0113 20:35:30.114336    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:35:30.137176 kubelet[1770]: I0113 20:35:30.137102    1770 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=10.558704746 podStartE2EDuration="18.137086385s" podCreationTimestamp="2025-01-13 20:35:12 +0000 UTC" firstStartedPulling="2025-01-13 20:35:13.179999014 +0000 UTC m=+30.677894411" lastFinishedPulling="2025-01-13 20:35:20.758380653 +0000 UTC m=+38.256276050" observedRunningTime="2025-01-13 20:35:21.347213507 +0000 UTC m=+38.845108914" watchObservedRunningTime="2025-01-13 20:35:30.137086385 +0000 UTC m=+47.634981782"
Jan 13 20:35:30.137413 kubelet[1770]: I0113 20:35:30.137377    1770 topology_manager.go:215] "Topology Admit Handler" podUID="69cab95f-d2d0-4bef-a0de-4e3cce94a4d4" podNamespace="default" podName="test-pod-1"
Jan 13 20:35:30.142949 systemd[1]: Created slice kubepods-besteffort-pod69cab95f_d2d0_4bef_a0de_4e3cce94a4d4.slice - libcontainer container kubepods-besteffort-pod69cab95f_d2d0_4bef_a0de_4e3cce94a4d4.slice.
Jan 13 20:35:30.332370 kubelet[1770]: I0113 20:35:30.332288    1770 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-xtxxt\" (UniqueName: \"kubernetes.io/projected/69cab95f-d2d0-4bef-a0de-4e3cce94a4d4-kube-api-access-xtxxt\") pod \"test-pod-1\" (UID: \"69cab95f-d2d0-4bef-a0de-4e3cce94a4d4\") " pod="default/test-pod-1"
Jan 13 20:35:30.332370 kubelet[1770]: I0113 20:35:30.332350    1770 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-a6eef12c-2df2-41ad-a4c0-767987fc6964\" (UniqueName: \"kubernetes.io/nfs/69cab95f-d2d0-4bef-a0de-4e3cce94a4d4-pvc-a6eef12c-2df2-41ad-a4c0-767987fc6964\") pod \"test-pod-1\" (UID: \"69cab95f-d2d0-4bef-a0de-4e3cce94a4d4\") " pod="default/test-pod-1"
Jan 13 20:35:30.465145 kernel: FS-Cache: Loaded
Jan 13 20:35:30.532749 kernel: RPC: Registered named UNIX socket transport module.
Jan 13 20:35:30.532885 kernel: RPC: Registered udp transport module.
Jan 13 20:35:30.532912 kernel: RPC: Registered tcp transport module.
Jan 13 20:35:30.533383 kernel: RPC: Registered tcp-with-tls transport module.
Jan 13 20:35:30.534171 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module.
Jan 13 20:35:31.017503 kernel: NFS: Registering the id_resolver key type
Jan 13 20:35:31.017658 kernel: Key type id_resolver registered
Jan 13 20:35:31.017678 kernel: Key type id_legacy registered
Jan 13 20:35:31.107970 nfsidmap[2821]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain'
Jan 13 20:35:31.115571 kubelet[1770]: E0113 20:35:31.115489    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:35:31.117362 nfsidmap[2824]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain'
Jan 13 20:35:31.349384 containerd[1487]: time="2025-01-13T20:35:31.345579197Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:69cab95f-d2d0-4bef-a0de-4e3cce94a4d4,Namespace:default,Attempt:0,}"
Jan 13 20:35:31.385627 systemd-networkd[1418]: vethc738f217: Link UP
Jan 13 20:35:31.389953 kernel: cni0: port 3(vethc738f217) entered blocking state
Jan 13 20:35:31.390035 kernel: cni0: port 3(vethc738f217) entered disabled state
Jan 13 20:35:31.402343 kernel: vethc738f217: entered allmulticast mode
Jan 13 20:35:31.402463 kernel: vethc738f217: entered promiscuous mode
Jan 13 20:35:31.413075 kernel: cni0: port 3(vethc738f217) entered blocking state
Jan 13 20:35:31.413318 kernel: cni0: port 3(vethc738f217) entered forwarding state
Jan 13 20:35:31.413427 systemd-networkd[1418]: vethc738f217: Gained carrier
Jan 13 20:35:31.420693 containerd[1487]: 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)(0xc00001a938), "name":"cbr0", "type":"bridge"}
Jan 13 20:35:31.420693 containerd[1487]: delegateAdd: netconf sent to delegate plugin:
Jan 13 20:35:31.454621 containerd[1487]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-01-13T20:35:31.451995302Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1
Jan 13 20:35:31.454621 containerd[1487]: time="2025-01-13T20:35:31.452072427Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1
Jan 13 20:35:31.454621 containerd[1487]: time="2025-01-13T20:35:31.452087025Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jan 13 20:35:31.454621 containerd[1487]: time="2025-01-13T20:35:31.452224214Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jan 13 20:35:31.490978 systemd[1]: run-containerd-runc-k8s.io-56bca1f96c59ea6bbd78e013d5533e375ff886cfd9ed17bdb4065c965344aeae-runc.fFnCH4.mount: Deactivated successfully.
Jan 13 20:35:31.506473 systemd[1]: Started cri-containerd-56bca1f96c59ea6bbd78e013d5533e375ff886cfd9ed17bdb4065c965344aeae.scope - libcontainer container 56bca1f96c59ea6bbd78e013d5533e375ff886cfd9ed17bdb4065c965344aeae.
Jan 13 20:35:31.525880 systemd-resolved[1329]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address
Jan 13 20:35:31.573269 containerd[1487]: time="2025-01-13T20:35:31.571314756Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:69cab95f-d2d0-4bef-a0de-4e3cce94a4d4,Namespace:default,Attempt:0,} returns sandbox id \"56bca1f96c59ea6bbd78e013d5533e375ff886cfd9ed17bdb4065c965344aeae\""
Jan 13 20:35:31.575697 containerd[1487]: time="2025-01-13T20:35:31.575407797Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\""
Jan 13 20:35:32.076256 containerd[1487]: time="2025-01-13T20:35:32.076168064Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\"  labels:{key:\"io.cri-containerd.image\"  value:\"managed\"}"
Jan 13 20:35:32.080273 containerd[1487]: time="2025-01-13T20:35:32.077476621Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61"
Jan 13 20:35:32.088785 containerd[1487]: time="2025-01-13T20:35:32.088697078Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:29ef6eaebfc53650f3a4609edbf9d35e866f56b2c5e01d32d93439031b300f0b\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:eca1d1ff18c7af45f86b7e0b572090f563a676ddca3da2ecff678390366335ad\", size \"71035896\" in 513.236692ms"
Jan 13 20:35:32.088785 containerd[1487]: time="2025-01-13T20:35:32.088755498Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:29ef6eaebfc53650f3a4609edbf9d35e866f56b2c5e01d32d93439031b300f0b\""
Jan 13 20:35:32.093364 containerd[1487]: time="2025-01-13T20:35:32.093250153Z" level=info msg="CreateContainer within sandbox \"56bca1f96c59ea6bbd78e013d5533e375ff886cfd9ed17bdb4065c965344aeae\" for container &ContainerMetadata{Name:test,Attempt:0,}"
Jan 13 20:35:32.117552 kubelet[1770]: E0113 20:35:32.117471    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:35:32.133928 containerd[1487]: time="2025-01-13T20:35:32.133846800Z" level=info msg="CreateContainer within sandbox \"56bca1f96c59ea6bbd78e013d5533e375ff886cfd9ed17bdb4065c965344aeae\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"8741c442676fe10faef596fd504eb5322c57145665b2ad48765256c12928e872\""
Jan 13 20:35:32.134635 containerd[1487]: time="2025-01-13T20:35:32.134583058Z" level=info msg="StartContainer for \"8741c442676fe10faef596fd504eb5322c57145665b2ad48765256c12928e872\""
Jan 13 20:35:32.199464 systemd[1]: Started cri-containerd-8741c442676fe10faef596fd504eb5322c57145665b2ad48765256c12928e872.scope - libcontainer container 8741c442676fe10faef596fd504eb5322c57145665b2ad48765256c12928e872.
Jan 13 20:35:32.256730 containerd[1487]: time="2025-01-13T20:35:32.256655902Z" level=info msg="StartContainer for \"8741c442676fe10faef596fd504eb5322c57145665b2ad48765256c12928e872\" returns successfully"
Jan 13 20:35:32.373437 kubelet[1770]: I0113 20:35:32.370070    1770 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=18.855374487 podStartE2EDuration="19.37004837s" podCreationTimestamp="2025-01-13 20:35:13 +0000 UTC" firstStartedPulling="2025-01-13 20:35:31.575051605 +0000 UTC m=+49.072947012" lastFinishedPulling="2025-01-13 20:35:32.089725488 +0000 UTC m=+49.587620895" observedRunningTime="2025-01-13 20:35:32.36989519 +0000 UTC m=+49.867790597" watchObservedRunningTime="2025-01-13 20:35:32.37004837 +0000 UTC m=+49.867943767"
Jan 13 20:35:32.464132 systemd-networkd[1418]: vethc738f217: Gained IPv6LL
Jan 13 20:35:33.118633 kubelet[1770]: E0113 20:35:33.118508    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:35:34.119033 kubelet[1770]: E0113 20:35:34.118982    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:35:35.119304 kubelet[1770]: E0113 20:35:35.119248    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 13 20:35:36.120184 kubelet[1770]: E0113 20:35:36.120100    1770 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"