Jan 17 12:18:36.994689 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 p17) 13.3.1 20240614, GNU ld (Gentoo 2.42 p3) 2.42.0) #1 SMP PREEMPT_DYNAMIC Fri Jan 17 10:39:07 -00 2025
Jan 17 12:18:36.994752 kernel: Command line: BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200n8 console=tty0 flatcar.first_boot=detected flatcar.oem.id=digitalocean verity.usrhash=bf1e0d81a0170850ab02d370c1a7c7a3f5983c980b3730f748240a3bda2dbb2e
Jan 17 12:18:36.994778 kernel: BIOS-provided physical RAM map:
Jan 17 12:18:36.994792 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable
Jan 17 12:18:36.994804 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved
Jan 17 12:18:36.994818 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
Jan 17 12:18:36.994834 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000007ffd7fff] usable
Jan 17 12:18:36.994846 kernel: BIOS-e820: [mem 0x000000007ffd8000-0x000000007fffffff] reserved
Jan 17 12:18:36.994853 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
Jan 17 12:18:36.994865 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
Jan 17 12:18:36.994873 kernel: NX (Execute Disable) protection: active
Jan 17 12:18:36.994880 kernel: APIC: Static calls initialized
Jan 17 12:18:36.994888 kernel: SMBIOS 2.8 present.
Jan 17 12:18:36.994896 kernel: DMI: DigitalOcean Droplet/Droplet, BIOS 20171212 12/12/2017
Jan 17 12:18:36.994905 kernel: Hypervisor detected: KVM
Jan 17 12:18:36.994931 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00
Jan 17 12:18:36.996035 kernel: kvm-clock: using sched offset of 3295727373 cycles
Jan 17 12:18:36.996060 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
Jan 17 12:18:36.996075 kernel: tsc: Detected 2494.138 MHz processor
Jan 17 12:18:36.996091 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
Jan 17 12:18:36.996101 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable
Jan 17 12:18:36.996110 kernel: last_pfn = 0x7ffd8 max_arch_pfn = 0x400000000
Jan 17 12:18:36.996119 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs
Jan 17 12:18:36.996132 kernel: x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT  
Jan 17 12:18:36.996159 kernel: ACPI: Early table checksum verification disabled
Jan 17 12:18:36.996175 kernel: ACPI: RSDP 0x00000000000F5A50 000014 (v00 BOCHS )
Jan 17 12:18:36.996188 kernel: ACPI: RSDT 0x000000007FFE1986 000038 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
Jan 17 12:18:36.996204 kernel: ACPI: FACP 0x000000007FFE176A 000074 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
Jan 17 12:18:36.996219 kernel: ACPI: DSDT 0x000000007FFE0040 00172A (v01 BOCHS  BXPC     00000001 BXPC 00000001)
Jan 17 12:18:36.996227 kernel: ACPI: FACS 0x000000007FFE0000 000040
Jan 17 12:18:36.996236 kernel: ACPI: APIC 0x000000007FFE17DE 000080 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
Jan 17 12:18:36.996244 kernel: ACPI: HPET 0x000000007FFE185E 000038 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
Jan 17 12:18:36.996273 kernel: ACPI: SRAT 0x000000007FFE1896 0000C8 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
Jan 17 12:18:36.996294 kernel: ACPI: WAET 0x000000007FFE195E 000028 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
Jan 17 12:18:36.996309 kernel: ACPI: Reserving FACP table memory at [mem 0x7ffe176a-0x7ffe17dd]
Jan 17 12:18:36.996317 kernel: ACPI: Reserving DSDT table memory at [mem 0x7ffe0040-0x7ffe1769]
Jan 17 12:18:36.996325 kernel: ACPI: Reserving FACS table memory at [mem 0x7ffe0000-0x7ffe003f]
Jan 17 12:18:36.996334 kernel: ACPI: Reserving APIC table memory at [mem 0x7ffe17de-0x7ffe185d]
Jan 17 12:18:36.996342 kernel: ACPI: Reserving HPET table memory at [mem 0x7ffe185e-0x7ffe1895]
Jan 17 12:18:36.996350 kernel: ACPI: Reserving SRAT table memory at [mem 0x7ffe1896-0x7ffe195d]
Jan 17 12:18:36.996365 kernel: ACPI: Reserving WAET table memory at [mem 0x7ffe195e-0x7ffe1985]
Jan 17 12:18:36.996378 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0
Jan 17 12:18:36.996387 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0
Jan 17 12:18:36.996396 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x0009ffff]
Jan 17 12:18:36.996405 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00100000-0x7fffffff]
Jan 17 12:18:36.996414 kernel: NUMA: Node 0 [mem 0x00000000-0x0009ffff] + [mem 0x00100000-0x7ffd7fff] -> [mem 0x00000000-0x7ffd7fff]
Jan 17 12:18:36.996423 kernel: NODE_DATA(0) allocated [mem 0x7ffd2000-0x7ffd7fff]
Jan 17 12:18:36.996437 kernel: Zone ranges:
Jan 17 12:18:36.996446 kernel:   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
Jan 17 12:18:36.996455 kernel:   DMA32    [mem 0x0000000001000000-0x000000007ffd7fff]
Jan 17 12:18:36.996463 kernel:   Normal   empty
Jan 17 12:18:36.996472 kernel: Movable zone start for each node
Jan 17 12:18:36.996481 kernel: Early memory node ranges
Jan 17 12:18:36.996490 kernel:   node   0: [mem 0x0000000000001000-0x000000000009efff]
Jan 17 12:18:36.996498 kernel:   node   0: [mem 0x0000000000100000-0x000000007ffd7fff]
Jan 17 12:18:36.996507 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000007ffd7fff]
Jan 17 12:18:36.996521 kernel: On node 0, zone DMA: 1 pages in unavailable ranges
Jan 17 12:18:36.996529 kernel: On node 0, zone DMA: 97 pages in unavailable ranges
Jan 17 12:18:36.996538 kernel: On node 0, zone DMA32: 40 pages in unavailable ranges
Jan 17 12:18:36.996547 kernel: ACPI: PM-Timer IO Port: 0x608
Jan 17 12:18:36.996556 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
Jan 17 12:18:36.996565 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
Jan 17 12:18:36.996573 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
Jan 17 12:18:36.996582 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
Jan 17 12:18:36.996591 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
Jan 17 12:18:36.996604 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
Jan 17 12:18:36.996613 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
Jan 17 12:18:36.996622 kernel: ACPI: Using ACPI (MADT) for SMP configuration information
Jan 17 12:18:36.996630 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000
Jan 17 12:18:36.996639 kernel: TSC deadline timer available
Jan 17 12:18:36.996648 kernel: smpboot: Allowing 2 CPUs, 0 hotplug CPUs
Jan 17 12:18:36.996656 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()
Jan 17 12:18:36.996665 kernel: [mem 0x80000000-0xfeffbfff] available for PCI devices
Jan 17 12:18:36.996674 kernel: Booting paravirtualized kernel on KVM
Jan 17 12:18:36.996687 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
Jan 17 12:18:36.996696 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1
Jan 17 12:18:36.996705 kernel: percpu: Embedded 58 pages/cpu s197032 r8192 d32344 u1048576
Jan 17 12:18:36.996714 kernel: pcpu-alloc: s197032 r8192 d32344 u1048576 alloc=1*2097152
Jan 17 12:18:36.996722 kernel: pcpu-alloc: [0] 0 1 
Jan 17 12:18:36.996731 kernel: kvm-guest: PV spinlocks disabled, no host support
Jan 17 12:18:36.996741 kernel: Kernel command line: rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200n8 console=tty0 flatcar.first_boot=detected flatcar.oem.id=digitalocean verity.usrhash=bf1e0d81a0170850ab02d370c1a7c7a3f5983c980b3730f748240a3bda2dbb2e
Jan 17 12:18:36.996751 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space.
Jan 17 12:18:36.996764 kernel: random: crng init done
Jan 17 12:18:36.996773 kernel: Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)
Jan 17 12:18:36.996781 kernel: Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
Jan 17 12:18:36.996790 kernel: Fallback order for Node 0: 0 
Jan 17 12:18:36.996798 kernel: Built 1 zonelists, mobility grouping on.  Total pages: 515800
Jan 17 12:18:36.996807 kernel: Policy zone: DMA32
Jan 17 12:18:36.996816 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off
Jan 17 12:18:36.996825 kernel: Memory: 1971188K/2096600K available (12288K kernel code, 2299K rwdata, 22728K rodata, 42848K init, 2344K bss, 125152K reserved, 0K cma-reserved)
Jan 17 12:18:36.996834 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
Jan 17 12:18:36.996848 kernel: Kernel/User page tables isolation: enabled
Jan 17 12:18:36.996856 kernel: ftrace: allocating 37918 entries in 149 pages
Jan 17 12:18:36.996865 kernel: ftrace: allocated 149 pages with 4 groups
Jan 17 12:18:36.996874 kernel: Dynamic Preempt: voluntary
Jan 17 12:18:36.996882 kernel: rcu: Preemptible hierarchical RCU implementation.
Jan 17 12:18:36.996893 kernel: rcu:         RCU event tracing is enabled.
Jan 17 12:18:36.996902 kernel: rcu:         RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2.
Jan 17 12:18:36.996911 kernel:         Trampoline variant of Tasks RCU enabled.
Jan 17 12:18:36.996920 kernel:         Rude variant of Tasks RCU enabled.
Jan 17 12:18:36.996933 kernel:         Tracing variant of Tasks RCU enabled.
Jan 17 12:18:36.997964 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.
Jan 17 12:18:36.997978 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
Jan 17 12:18:36.997987 kernel: NR_IRQS: 33024, nr_irqs: 440, preallocated irqs: 16
Jan 17 12:18:36.997998 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention.
Jan 17 12:18:36.998007 kernel: Console: colour VGA+ 80x25
Jan 17 12:18:36.998016 kernel: printk: console [tty0] enabled
Jan 17 12:18:36.998025 kernel: printk: console [ttyS0] enabled
Jan 17 12:18:36.998036 kernel: ACPI: Core revision 20230628
Jan 17 12:18:36.998058 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
Jan 17 12:18:36.998072 kernel: APIC: Switch to symmetric I/O mode setup
Jan 17 12:18:36.998086 kernel: x2apic enabled
Jan 17 12:18:36.998099 kernel: APIC: Switched APIC routing to: physical x2apic
Jan 17 12:18:36.998112 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
Jan 17 12:18:36.998127 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x23f39838d43, max_idle_ns: 440795267131 ns
Jan 17 12:18:36.998142 kernel: Calibrating delay loop (skipped) preset value.. 4988.27 BogoMIPS (lpj=2494138)
Jan 17 12:18:36.998151 kernel: Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
Jan 17 12:18:36.998160 kernel: Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
Jan 17 12:18:36.998186 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization
Jan 17 12:18:36.998195 kernel: Spectre V2 : Mitigation: Retpolines
Jan 17 12:18:36.998205 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
Jan 17 12:18:36.998220 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT
Jan 17 12:18:36.998229 kernel: Spectre V2 : Enabling Restricted Speculation for firmware calls
Jan 17 12:18:36.998243 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier
Jan 17 12:18:36.998258 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl
Jan 17 12:18:36.998274 kernel: MDS: Mitigation: Clear CPU buffers
Jan 17 12:18:36.998289 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode
Jan 17 12:18:36.998310 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
Jan 17 12:18:36.998327 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
Jan 17 12:18:36.998339 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
Jan 17 12:18:36.998348 kernel: x86/fpu: xstate_offset[2]:  576, xstate_sizes[2]:  256
Jan 17 12:18:36.998357 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format.
Jan 17 12:18:36.998367 kernel: Freeing SMP alternatives memory: 32K
Jan 17 12:18:36.998376 kernel: pid_max: default: 32768 minimum: 301
Jan 17 12:18:36.998391 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity
Jan 17 12:18:36.998401 kernel: landlock: Up and running.
Jan 17 12:18:36.998410 kernel: SELinux:  Initializing.
Jan 17 12:18:36.998419 kernel: Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear)
Jan 17 12:18:36.998429 kernel: Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear)
Jan 17 12:18:36.998438 kernel: smpboot: CPU0: Intel DO-Regular (family: 0x6, model: 0x4f, stepping: 0x1)
Jan 17 12:18:36.998447 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2.
Jan 17 12:18:36.998457 kernel: RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2.
Jan 17 12:18:36.998466 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2.
Jan 17 12:18:36.998480 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only.
Jan 17 12:18:36.998490 kernel: signal: max sigframe size: 1776
Jan 17 12:18:36.998499 kernel: rcu: Hierarchical SRCU implementation.
Jan 17 12:18:36.998509 kernel: rcu:         Max phase no-delay instances is 400.
Jan 17 12:18:36.998518 kernel: NMI watchdog: Perf NMI watchdog permanently disabled
Jan 17 12:18:36.998527 kernel: smp: Bringing up secondary CPUs ...
Jan 17 12:18:36.998536 kernel: smpboot: x86: Booting SMP configuration:
Jan 17 12:18:36.998546 kernel: .... node  #0, CPUs:      #1
Jan 17 12:18:36.998555 kernel: smp: Brought up 1 node, 2 CPUs
Jan 17 12:18:36.998570 kernel: smpboot: Max logical packages: 1
Jan 17 12:18:36.998579 kernel: smpboot: Total of 2 processors activated (9976.55 BogoMIPS)
Jan 17 12:18:36.998589 kernel: devtmpfs: initialized
Jan 17 12:18:36.998598 kernel: x86/mm: Memory block size: 128MB
Jan 17 12:18:36.998608 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
Jan 17 12:18:36.998617 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear)
Jan 17 12:18:36.998626 kernel: pinctrl core: initialized pinctrl subsystem
Jan 17 12:18:36.998635 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family
Jan 17 12:18:36.998645 kernel: audit: initializing netlink subsys (disabled)
Jan 17 12:18:36.998658 kernel: audit: type=2000 audit(1737116316.586:1): state=initialized audit_enabled=0 res=1
Jan 17 12:18:36.998668 kernel: thermal_sys: Registered thermal governor 'step_wise'
Jan 17 12:18:36.998677 kernel: thermal_sys: Registered thermal governor 'user_space'
Jan 17 12:18:36.998686 kernel: cpuidle: using governor menu
Jan 17 12:18:36.998696 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
Jan 17 12:18:36.998705 kernel: dca service started, version 1.12.1
Jan 17 12:18:36.998714 kernel: PCI: Using configuration type 1 for base access
Jan 17 12:18:36.998723 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.
Jan 17 12:18:36.998733 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages
Jan 17 12:18:36.998746 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page
Jan 17 12:18:36.998755 kernel: ACPI: Added _OSI(Module Device)
Jan 17 12:18:36.998765 kernel: ACPI: Added _OSI(Processor Device)
Jan 17 12:18:36.998774 kernel: ACPI: Added _OSI(3.0 _SCP Extensions)
Jan 17 12:18:36.998783 kernel: ACPI: Added _OSI(Processor Aggregator Device)
Jan 17 12:18:36.998793 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded
Jan 17 12:18:36.998802 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC
Jan 17 12:18:36.998811 kernel: ACPI: Interpreter enabled
Jan 17 12:18:36.998820 kernel: ACPI: PM: (supports S0 S5)
Jan 17 12:18:36.998829 kernel: ACPI: Using IOAPIC for interrupt routing
Jan 17 12:18:36.998843 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
Jan 17 12:18:36.998853 kernel: PCI: Using E820 reservations for host bridge windows
Jan 17 12:18:36.998862 kernel: ACPI: Enabled 2 GPEs in block 00 to 0F
Jan 17 12:18:36.998871 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
Jan 17 12:18:37.000321 kernel: acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3]
Jan 17 12:18:37.000490 kernel: acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI]
Jan 17 12:18:37.000604 kernel: acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended configuration space under this bridge
Jan 17 12:18:37.000627 kernel: acpiphp: Slot [3] registered
Jan 17 12:18:37.000638 kernel: acpiphp: Slot [4] registered
Jan 17 12:18:37.000653 kernel: acpiphp: Slot [5] registered
Jan 17 12:18:37.000667 kernel: acpiphp: Slot [6] registered
Jan 17 12:18:37.000681 kernel: acpiphp: Slot [7] registered
Jan 17 12:18:37.000696 kernel: acpiphp: Slot [8] registered
Jan 17 12:18:37.000710 kernel: acpiphp: Slot [9] registered
Jan 17 12:18:37.000726 kernel: acpiphp: Slot [10] registered
Jan 17 12:18:37.000739 kernel: acpiphp: Slot [11] registered
Jan 17 12:18:37.000755 kernel: acpiphp: Slot [12] registered
Jan 17 12:18:37.000770 kernel: acpiphp: Slot [13] registered
Jan 17 12:18:37.000786 kernel: acpiphp: Slot [14] registered
Jan 17 12:18:37.000797 kernel: acpiphp: Slot [15] registered
Jan 17 12:18:37.000806 kernel: acpiphp: Slot [16] registered
Jan 17 12:18:37.000816 kernel: acpiphp: Slot [17] registered
Jan 17 12:18:37.000825 kernel: acpiphp: Slot [18] registered
Jan 17 12:18:37.000834 kernel: acpiphp: Slot [19] registered
Jan 17 12:18:37.000843 kernel: acpiphp: Slot [20] registered
Jan 17 12:18:37.000853 kernel: acpiphp: Slot [21] registered
Jan 17 12:18:37.000867 kernel: acpiphp: Slot [22] registered
Jan 17 12:18:37.000877 kernel: acpiphp: Slot [23] registered
Jan 17 12:18:37.000886 kernel: acpiphp: Slot [24] registered
Jan 17 12:18:37.000895 kernel: acpiphp: Slot [25] registered
Jan 17 12:18:37.000905 kernel: acpiphp: Slot [26] registered
Jan 17 12:18:37.000914 kernel: acpiphp: Slot [27] registered
Jan 17 12:18:37.000923 kernel: acpiphp: Slot [28] registered
Jan 17 12:18:37.001984 kernel: acpiphp: Slot [29] registered
Jan 17 12:18:37.001996 kernel: acpiphp: Slot [30] registered
Jan 17 12:18:37.002016 kernel: acpiphp: Slot [31] registered
Jan 17 12:18:37.002026 kernel: PCI host bridge to bus 0000:00
Jan 17 12:18:37.002281 kernel: pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
Jan 17 12:18:37.002475 kernel: pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
Jan 17 12:18:37.002654 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
Jan 17 12:18:37.002822 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xfebfffff window]
Jan 17 12:18:37.005056 kernel: pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window]
Jan 17 12:18:37.005187 kernel: pci_bus 0000:00: root bus resource [bus 00-ff]
Jan 17 12:18:37.005363 kernel: pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
Jan 17 12:18:37.005531 kernel: pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
Jan 17 12:18:37.005674 kernel: pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
Jan 17 12:18:37.005787 kernel: pci 0000:00:01.1: reg 0x20: [io  0xc1e0-0xc1ef]
Jan 17 12:18:37.005898 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
Jan 17 12:18:37.006117 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
Jan 17 12:18:37.006243 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
Jan 17 12:18:37.006351 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
Jan 17 12:18:37.006498 kernel: pci 0000:00:01.2: [8086:7020] type 00 class 0x0c0300
Jan 17 12:18:37.006611 kernel: pci 0000:00:01.2: reg 0x20: [io  0xc180-0xc19f]
Jan 17 12:18:37.006731 kernel: pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
Jan 17 12:18:37.006842 kernel: pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
Jan 17 12:18:37.007971 kernel: pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
Jan 17 12:18:37.008165 kernel: pci 0000:00:02.0: [1af4:1050] type 00 class 0x030000
Jan 17 12:18:37.008291 kernel: pci 0000:00:02.0: reg 0x10: [mem 0xfe000000-0xfe7fffff pref]
Jan 17 12:18:37.008401 kernel: pci 0000:00:02.0: reg 0x18: [mem 0xfe800000-0xfe803fff 64bit pref]
Jan 17 12:18:37.008511 kernel: pci 0000:00:02.0: reg 0x20: [mem 0xfebf0000-0xfebf0fff]
Jan 17 12:18:37.008619 kernel: pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref]
Jan 17 12:18:37.008726 kernel: pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
Jan 17 12:18:37.008858 kernel: pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000
Jan 17 12:18:37.010110 kernel: pci 0000:00:03.0: reg 0x10: [io  0xc1a0-0xc1bf]
Jan 17 12:18:37.010321 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xfebf1000-0xfebf1fff]
Jan 17 12:18:37.010507 kernel: pci 0000:00:03.0: reg 0x20: [mem 0xfe804000-0xfe807fff 64bit pref]
Jan 17 12:18:37.010681 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000
Jan 17 12:18:37.010794 kernel: pci 0000:00:04.0: reg 0x10: [io  0xc1c0-0xc1df]
Jan 17 12:18:37.012986 kernel: pci 0000:00:04.0: reg 0x14: [mem 0xfebf2000-0xfebf2fff]
Jan 17 12:18:37.013149 kernel: pci 0000:00:04.0: reg 0x20: [mem 0xfe808000-0xfe80bfff 64bit pref]
Jan 17 12:18:37.013276 kernel: pci 0000:00:05.0: [1af4:1004] type 00 class 0x010000
Jan 17 12:18:37.013391 kernel: pci 0000:00:05.0: reg 0x10: [io  0xc100-0xc13f]
Jan 17 12:18:37.013550 kernel: pci 0000:00:05.0: reg 0x14: [mem 0xfebf3000-0xfebf3fff]
Jan 17 12:18:37.013682 kernel: pci 0000:00:05.0: reg 0x20: [mem 0xfe80c000-0xfe80ffff 64bit pref]
Jan 17 12:18:37.013803 kernel: pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000
Jan 17 12:18:37.013979 kernel: pci 0000:00:06.0: reg 0x10: [io  0xc000-0xc07f]
Jan 17 12:18:37.014091 kernel: pci 0000:00:06.0: reg 0x14: [mem 0xfebf4000-0xfebf4fff]
Jan 17 12:18:37.014213 kernel: pci 0000:00:06.0: reg 0x20: [mem 0xfe810000-0xfe813fff 64bit pref]
Jan 17 12:18:37.014334 kernel: pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000
Jan 17 12:18:37.014443 kernel: pci 0000:00:07.0: reg 0x10: [io  0xc080-0xc0ff]
Jan 17 12:18:37.014551 kernel: pci 0000:00:07.0: reg 0x14: [mem 0xfebf5000-0xfebf5fff]
Jan 17 12:18:37.014660 kernel: pci 0000:00:07.0: reg 0x20: [mem 0xfe814000-0xfe817fff 64bit pref]
Jan 17 12:18:37.014820 kernel: pci 0000:00:08.0: [1af4:1002] type 00 class 0x00ff00
Jan 17 12:18:37.014955 kernel: pci 0000:00:08.0: reg 0x10: [io  0xc140-0xc17f]
Jan 17 12:18:37.015070 kernel: pci 0000:00:08.0: reg 0x20: [mem 0xfe818000-0xfe81bfff 64bit pref]
Jan 17 12:18:37.015083 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10
Jan 17 12:18:37.015093 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10
Jan 17 12:18:37.015103 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11
Jan 17 12:18:37.015113 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11
Jan 17 12:18:37.015129 kernel: ACPI: PCI: Interrupt link LNKS configured for IRQ 9
Jan 17 12:18:37.015139 kernel: iommu: Default domain type: Translated
Jan 17 12:18:37.015149 kernel: iommu: DMA domain TLB invalidation policy: lazy mode
Jan 17 12:18:37.015158 kernel: PCI: Using ACPI for IRQ routing
Jan 17 12:18:37.015168 kernel: PCI: pci_cache_line_size set to 64 bytes
Jan 17 12:18:37.015179 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff]
Jan 17 12:18:37.015188 kernel: e820: reserve RAM buffer [mem 0x7ffd8000-0x7fffffff]
Jan 17 12:18:37.015304 kernel: pci 0000:00:02.0: vgaarb: setting as boot VGA device
Jan 17 12:18:37.015415 kernel: pci 0000:00:02.0: vgaarb: bridge control possible
Jan 17 12:18:37.015532 kernel: pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
Jan 17 12:18:37.015545 kernel: vgaarb: loaded
Jan 17 12:18:37.015554 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
Jan 17 12:18:37.015564 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter
Jan 17 12:18:37.015573 kernel: clocksource: Switched to clocksource kvm-clock
Jan 17 12:18:37.015583 kernel: VFS: Disk quotas dquot_6.6.0
Jan 17 12:18:37.015593 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
Jan 17 12:18:37.015602 kernel: pnp: PnP ACPI init
Jan 17 12:18:37.015612 kernel: pnp: PnP ACPI: found 4 devices
Jan 17 12:18:37.015626 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
Jan 17 12:18:37.015636 kernel: NET: Registered PF_INET protocol family
Jan 17 12:18:37.015646 kernel: IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear)
Jan 17 12:18:37.015656 kernel: tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear)
Jan 17 12:18:37.015666 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
Jan 17 12:18:37.015675 kernel: TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear)
Jan 17 12:18:37.015685 kernel: TCP bind hash table entries: 16384 (order: 7, 524288 bytes, linear)
Jan 17 12:18:37.015694 kernel: TCP: Hash tables configured (established 16384 bind 16384)
Jan 17 12:18:37.015704 kernel: UDP hash table entries: 1024 (order: 3, 32768 bytes, linear)
Jan 17 12:18:37.015718 kernel: UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes, linear)
Jan 17 12:18:37.015728 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family
Jan 17 12:18:37.015738 kernel: NET: Registered PF_XDP protocol family
Jan 17 12:18:37.015851 kernel: pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
Jan 17 12:18:37.018099 kernel: pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
Jan 17 12:18:37.018300 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
Jan 17 12:18:37.018471 kernel: pci_bus 0000:00: resource 7 [mem 0x80000000-0xfebfffff window]
Jan 17 12:18:37.018631 kernel: pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window]
Jan 17 12:18:37.018843 kernel: pci 0000:00:01.0: PIIX3: Enabling Passive Release
Jan 17 12:18:37.021113 kernel: pci 0000:00:00.0: Limiting direct PCI/PCI transfers
Jan 17 12:18:37.021148 kernel: ACPI: \_SB_.LNKD: Enabled at IRQ 11
Jan 17 12:18:37.021275 kernel: pci 0000:00:01.2: quirk_usb_early_handoff+0x0/0x7a0 took 30658 usecs
Jan 17 12:18:37.021290 kernel: PCI: CLS 0 bytes, default 64
Jan 17 12:18:37.021301 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer
Jan 17 12:18:37.021311 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x23f39838d43, max_idle_ns: 440795267131 ns
Jan 17 12:18:37.021320 kernel: Initialise system trusted keyrings
Jan 17 12:18:37.021347 kernel: workingset: timestamp_bits=39 max_order=19 bucket_order=0
Jan 17 12:18:37.021357 kernel: Key type asymmetric registered
Jan 17 12:18:37.021367 kernel: Asymmetric key parser 'x509' registered
Jan 17 12:18:37.021377 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
Jan 17 12:18:37.021387 kernel: io scheduler mq-deadline registered
Jan 17 12:18:37.021397 kernel: io scheduler kyber registered
Jan 17 12:18:37.021406 kernel: io scheduler bfq registered
Jan 17 12:18:37.021416 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00
Jan 17 12:18:37.021426 kernel: ACPI: \_SB_.LNKB: Enabled at IRQ 10
Jan 17 12:18:37.021441 kernel: ACPI: \_SB_.LNKC: Enabled at IRQ 11
Jan 17 12:18:37.021469 kernel: ACPI: \_SB_.LNKA: Enabled at IRQ 10
Jan 17 12:18:37.021484 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
Jan 17 12:18:37.021500 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
Jan 17 12:18:37.021516 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
Jan 17 12:18:37.021531 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1
Jan 17 12:18:37.021542 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12
Jan 17 12:18:37.021730 kernel: rtc_cmos 00:03: RTC can wake from S4
Jan 17 12:18:37.021751 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0
Jan 17 12:18:37.021897 kernel: rtc_cmos 00:03: registered as rtc0
Jan 17 12:18:37.024114 kernel: rtc_cmos 00:03: setting system clock to 2025-01-17T12:18:36 UTC (1737116316)
Jan 17 12:18:37.024238 kernel: rtc_cmos 00:03: alarms up to one day, y3k, 242 bytes nvram
Jan 17 12:18:37.024253 kernel: intel_pstate: CPU model not supported
Jan 17 12:18:37.024263 kernel: NET: Registered PF_INET6 protocol family
Jan 17 12:18:37.024273 kernel: Segment Routing with IPv6
Jan 17 12:18:37.024283 kernel: In-situ OAM (IOAM) with IPv6
Jan 17 12:18:37.024293 kernel: NET: Registered PF_PACKET protocol family
Jan 17 12:18:37.024320 kernel: Key type dns_resolver registered
Jan 17 12:18:37.024330 kernel: IPI shorthand broadcast: enabled
Jan 17 12:18:37.024340 kernel: sched_clock: Marking stable (968002991, 96659299)->(1093527976, -28865686)
Jan 17 12:18:37.024350 kernel: registered taskstats version 1
Jan 17 12:18:37.024360 kernel: Loading compiled-in X.509 certificates
Jan 17 12:18:37.024370 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.71-flatcar: 6baa290b0089ed5c4c5f7248306af816ac8c7f80'
Jan 17 12:18:37.024380 kernel: Key type .fscrypt registered
Jan 17 12:18:37.024389 kernel: Key type fscrypt-provisioning registered
Jan 17 12:18:37.024399 kernel: ima: No TPM chip found, activating TPM-bypass!
Jan 17 12:18:37.024413 kernel: ima: Allocated hash algorithm: sha1
Jan 17 12:18:37.024423 kernel: ima: No architecture policies found
Jan 17 12:18:37.024432 kernel: clk: Disabling unused clocks
Jan 17 12:18:37.024441 kernel: Freeing unused kernel image (initmem) memory: 42848K
Jan 17 12:18:37.024452 kernel: Write protecting the kernel read-only data: 36864k
Jan 17 12:18:37.024489 kernel: Freeing unused kernel image (rodata/data gap) memory: 1848K
Jan 17 12:18:37.024504 kernel: Run /init as init process
Jan 17 12:18:37.024514 kernel:   with arguments:
Jan 17 12:18:37.024525 kernel:     /init
Jan 17 12:18:37.024542 kernel:   with environment:
Jan 17 12:18:37.024557 kernel:     HOME=/
Jan 17 12:18:37.024572 kernel:     TERM=linux
Jan 17 12:18:37.024587 kernel:     BOOT_IMAGE=/flatcar/vmlinuz-a
Jan 17 12:18:37.024607 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 17 12:18:37.024625 systemd[1]: Detected virtualization kvm.
Jan 17 12:18:37.024642 systemd[1]: Detected architecture x86-64.
Jan 17 12:18:37.024665 systemd[1]: Running in initrd.
Jan 17 12:18:37.024681 systemd[1]: No hostname configured, using default hostname.
Jan 17 12:18:37.024713 systemd[1]: Hostname set to <localhost>.
Jan 17 12:18:37.024731 systemd[1]: Initializing machine ID from VM UUID.
Jan 17 12:18:37.024748 systemd[1]: Queued start job for default target initrd.target.
Jan 17 12:18:37.024759 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch.
Jan 17 12:18:37.024770 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch.
Jan 17 12:18:37.024782 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM...
Jan 17 12:18:37.024799 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM...
Jan 17 12:18:37.024810 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT...
Jan 17 12:18:37.024820 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A...
Jan 17 12:18:37.024833 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132...
Jan 17 12:18:37.024844 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr...
Jan 17 12:18:37.024855 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre).
Jan 17 12:18:37.024865 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes.
Jan 17 12:18:37.024880 systemd[1]: Reached target paths.target - Path Units.
Jan 17 12:18:37.024891 systemd[1]: Reached target slices.target - Slice Units.
Jan 17 12:18:37.024902 systemd[1]: Reached target swap.target - Swaps.
Jan 17 12:18:37.024917 systemd[1]: Reached target timers.target - Timer Units.
Jan 17 12:18:37.024980 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket.
Jan 17 12:18:37.024991 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket.
Jan 17 12:18:37.025008 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log).
Jan 17 12:18:37.025019 systemd[1]: Listening on systemd-journald.socket - Journal Socket.
Jan 17 12:18:37.025030 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket.
Jan 17 12:18:37.025040 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket.
Jan 17 12:18:37.025051 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket.
Jan 17 12:18:37.025062 systemd[1]: Reached target sockets.target - Socket Units.
Jan 17 12:18:37.025073 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup...
Jan 17 12:18:37.025084 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes...
Jan 17 12:18:37.025100 systemd[1]: Finished network-cleanup.service - Network Cleanup.
Jan 17 12:18:37.025111 systemd[1]: Starting systemd-fsck-usr.service...
Jan 17 12:18:37.025121 systemd[1]: Starting systemd-journald.service - Journal Service...
Jan 17 12:18:37.025131 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules...
Jan 17 12:18:37.025142 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup...
Jan 17 12:18:37.025153 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup.
Jan 17 12:18:37.025163 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes.
Jan 17 12:18:37.025174 systemd[1]: Finished systemd-fsck-usr.service.
Jan 17 12:18:37.025190 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully...
Jan 17 12:18:37.025246 systemd-journald[183]: Collecting audit messages is disabled.
Jan 17 12:18:37.025279 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully.
Jan 17 12:18:37.025296 systemd-journald[183]: Journal started
Jan 17 12:18:37.025320 systemd-journald[183]: Runtime Journal (/run/log/journal/9f27d091b2c94e6facc13833ab86562c) is 4.9M, max 39.3M, 34.4M free.
Jan 17 12:18:36.988158 systemd-modules-load[184]: Inserted module 'overlay'
Jan 17 12:18:37.046361 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 17 12:18:37.046402 kernel: Bridge firewalling registered
Jan 17 12:18:37.046417 systemd[1]: Started systemd-journald.service - Journal Service.
Jan 17 12:18:37.036292 systemd-modules-load[184]: Inserted module 'br_netfilter'
Jan 17 12:18:37.047233 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules.
Jan 17 12:18:37.053924 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup.
Jan 17 12:18:37.061282 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters...
Jan 17 12:18:37.064265 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables...
Jan 17 12:18:37.072401 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev...
Jan 17 12:18:37.079303 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories...
Jan 17 12:18:37.105306 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables.
Jan 17 12:18:37.108749 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev.
Jan 17 12:18:37.111114 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters.
Jan 17 12:18:37.111916 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories.
Jan 17 12:18:37.118271 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook...
Jan 17 12:18:37.122185 systemd[1]: Starting systemd-resolved.service - Network Name Resolution...
Jan 17 12:18:37.137028 dracut-cmdline[216]: dracut-dracut-053
Jan 17 12:18:37.141968 dracut-cmdline[216]: Using kernel command line parameters: rd.driver.pre=btrfs rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200n8 console=tty0 flatcar.first_boot=detected flatcar.oem.id=digitalocean verity.usrhash=bf1e0d81a0170850ab02d370c1a7c7a3f5983c980b3730f748240a3bda2dbb2e
Jan 17 12:18:37.162590 systemd-resolved[219]: Positive Trust Anchors:
Jan 17 12:18:37.162605 systemd-resolved[219]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
Jan 17 12:18:37.162656 systemd-resolved[219]: 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 17 12:18:37.166143 systemd-resolved[219]: Defaulting to hostname 'linux'.
Jan 17 12:18:37.167626 systemd[1]: Started systemd-resolved.service - Network Name Resolution.
Jan 17 12:18:37.170422 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups.
Jan 17 12:18:37.240006 kernel: SCSI subsystem initialized
Jan 17 12:18:37.249967 kernel: Loading iSCSI transport class v2.0-870.
Jan 17 12:18:37.264051 kernel: iscsi: registered transport (tcp)
Jan 17 12:18:37.294111 kernel: iscsi: registered transport (qla4xxx)
Jan 17 12:18:37.294188 kernel: QLogic iSCSI HBA Driver
Jan 17 12:18:37.356068 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook.
Jan 17 12:18:37.361177 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook...
Jan 17 12:18:37.400300 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.
Jan 17 12:18:37.400388 kernel: device-mapper: uevent: version 1.0.3
Jan 17 12:18:37.400404 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com
Jan 17 12:18:37.448005 kernel: raid6: avx2x4   gen() 16291 MB/s
Jan 17 12:18:37.465006 kernel: raid6: avx2x2   gen() 16286 MB/s
Jan 17 12:18:37.482350 kernel: raid6: avx2x1   gen() 11745 MB/s
Jan 17 12:18:37.482445 kernel: raid6: using algorithm avx2x4 gen() 16291 MB/s
Jan 17 12:18:37.500362 kernel: raid6: .... xor() 8302 MB/s, rmw enabled
Jan 17 12:18:37.500472 kernel: raid6: using avx2x2 recovery algorithm
Jan 17 12:18:37.523993 kernel: xor: automatically using best checksumming function   avx       
Jan 17 12:18:37.709393 kernel: Btrfs loaded, zoned=no, fsverity=no
Jan 17 12:18:37.723095 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook.
Jan 17 12:18:37.730233 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files...
Jan 17 12:18:37.745370 systemd-udevd[402]: Using default interface naming scheme 'v255'.
Jan 17 12:18:37.750499 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files.
Jan 17 12:18:37.757135 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook...
Jan 17 12:18:37.784151 dracut-pre-trigger[408]: rd.md=0: removing MD RAID activation
Jan 17 12:18:37.820835 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook.
Jan 17 12:18:37.827190 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices...
Jan 17 12:18:37.888078 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices.
Jan 17 12:18:37.899443 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook...
Jan 17 12:18:37.927219 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook.
Jan 17 12:18:37.929697 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems.
Jan 17 12:18:37.930499 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes.
Jan 17 12:18:37.932175 systemd[1]: Reached target remote-fs.target - Remote File Systems.
Jan 17 12:18:37.938895 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook...
Jan 17 12:18:37.968297 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook.
Jan 17 12:18:37.977956 kernel: virtio_blk virtio4: 1/0/0 default/read/poll queues
Jan 17 12:18:38.008638 kernel: virtio_blk virtio4: [vda] 125829120 512-byte logical blocks (64.4 GB/60.0 GiB)
Jan 17 12:18:38.008774 kernel: scsi host0: Virtio SCSI HBA
Jan 17 12:18:38.008908 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk.
Jan 17 12:18:38.008923 kernel: GPT:9289727 != 125829119
Jan 17 12:18:38.008952 kernel: GPT:Alternate GPT header not at the end of the disk.
Jan 17 12:18:38.008964 kernel: GPT:9289727 != 125829119
Jan 17 12:18:38.008975 kernel: GPT: Use GNU Parted to correct GPT errors.
Jan 17 12:18:38.008986 kernel:  vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9
Jan 17 12:18:38.008997 kernel: cryptd: max_cpu_qlen set to 1000
Jan 17 12:18:38.009008 kernel: virtio_blk virtio5: 1/0/0 default/read/poll queues
Jan 17 12:18:38.028528 kernel: virtio_blk virtio5: [vdb] 920 512-byte logical blocks (471 kB/460 KiB)
Jan 17 12:18:38.040220 kernel: ACPI: bus type USB registered
Jan 17 12:18:38.040277 kernel: usbcore: registered new interface driver usbfs
Jan 17 12:18:38.041386 kernel: usbcore: registered new interface driver hub
Jan 17 12:18:38.042319 kernel: usbcore: registered new device driver usb
Jan 17 12:18:38.081397 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully.
Jan 17 12:18:38.081580 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters.
Jan 17 12:18:38.083437 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters...
Jan 17 12:18:38.083884 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
Jan 17 12:18:38.084272 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup.
Jan 17 12:18:38.086027 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup...
Jan 17 12:18:38.093301 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup...
Jan 17 12:18:38.105948 kernel: libata version 3.00 loaded.
Jan 17 12:18:38.110961 kernel: AVX2 version of gcm_enc/dec engaged.
Jan 17 12:18:38.111957 kernel: AES CTR mode by8 optimization enabled
Jan 17 12:18:38.115389 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT.
Jan 17 12:18:38.120953 kernel: BTRFS: device fsid e459b8ee-f1f7-4c3d-a087-3f1955f52c85 devid 1 transid 36 /dev/vda3 scanned by (udev-worker) (450)
Jan 17 12:18:38.132498 kernel: ata_piix 0000:00:01.1: version 2.13
Jan 17 12:18:38.158438 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by (udev-worker) (458)
Jan 17 12:18:38.158471 kernel: scsi host1: ata_piix
Jan 17 12:18:38.158636 kernel: scsi host2: ata_piix
Jan 17 12:18:38.158761 kernel: ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc1e0 irq 14
Jan 17 12:18:38.158775 kernel: ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc1e8 irq 15
Jan 17 12:18:38.140172 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM.
Jan 17 12:18:38.196710 kernel: uhci_hcd 0000:00:01.2: UHCI Host Controller
Jan 17 12:18:38.196951 kernel: uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1
Jan 17 12:18:38.197079 kernel: uhci_hcd 0000:00:01.2: detected 2 ports
Jan 17 12:18:38.197214 kernel: uhci_hcd 0000:00:01.2: irq 11, io port 0x0000c180
Jan 17 12:18:38.197329 kernel: hub 1-0:1.0: USB hub found
Jan 17 12:18:38.197501 kernel: hub 1-0:1.0: 2 ports detected
Jan 17 12:18:38.201125 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM.
Jan 17 12:18:38.202081 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup.
Jan 17 12:18:38.207564 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132.
Jan 17 12:18:38.208159 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A.
Jan 17 12:18:38.214200 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary...
Jan 17 12:18:38.216595 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters...
Jan 17 12:18:38.230978 kernel:  vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9
Jan 17 12:18:38.231069 disk-uuid[531]: Primary Header is updated.
Jan 17 12:18:38.231069 disk-uuid[531]: Secondary Entries is updated.
Jan 17 12:18:38.231069 disk-uuid[531]: Secondary Header is updated.
Jan 17 12:18:38.238501 kernel:  vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9
Jan 17 12:18:38.247966 kernel:  vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9
Jan 17 12:18:38.248601 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters.
Jan 17 12:18:39.245983 kernel:  vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9
Jan 17 12:18:39.247261 disk-uuid[533]: The operation has completed successfully.
Jan 17 12:18:39.292173 systemd[1]: disk-uuid.service: Deactivated successfully.
Jan 17 12:18:39.292282 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary.
Jan 17 12:18:39.303233 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr...
Jan 17 12:18:39.309696 sh[563]: Success
Jan 17 12:18:39.325955 kernel: device-mapper: verity: sha256 using implementation "sha256-avx2"
Jan 17 12:18:39.410191 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr.
Jan 17 12:18:39.412085 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr...
Jan 17 12:18:39.414736 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr.
Jan 17 12:18:39.445385 kernel: BTRFS info (device dm-0): first mount of filesystem e459b8ee-f1f7-4c3d-a087-3f1955f52c85
Jan 17 12:18:39.445557 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm
Jan 17 12:18:39.445579 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead
Jan 17 12:18:39.446681 kernel: BTRFS info (device dm-0): disabling log replay at mount time
Jan 17 12:18:39.447479 kernel: BTRFS info (device dm-0): using free space tree
Jan 17 12:18:39.454644 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr.
Jan 17 12:18:39.455684 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met.
Jan 17 12:18:39.468188 systemd[1]: Starting ignition-setup.service - Ignition (setup)...
Jan 17 12:18:39.471031 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline...
Jan 17 12:18:39.484221 kernel: BTRFS info (device vda6): first mount of filesystem a70a40d6-5ab2-4665-81b1-b8e9f58c5ff8
Jan 17 12:18:39.484285 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm
Jan 17 12:18:39.484308 kernel: BTRFS info (device vda6): using free space tree
Jan 17 12:18:39.489246 kernel: BTRFS info (device vda6): auto enabling async discard
Jan 17 12:18:39.500962 kernel: BTRFS info (device vda6): last unmount of filesystem a70a40d6-5ab2-4665-81b1-b8e9f58c5ff8
Jan 17 12:18:39.501102 systemd[1]: mnt-oem.mount: Deactivated successfully.
Jan 17 12:18:39.507434 systemd[1]: Finished ignition-setup.service - Ignition (setup).
Jan 17 12:18:39.514142 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)...
Jan 17 12:18:39.597588 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline.
Jan 17 12:18:39.607182 systemd[1]: Starting systemd-networkd.service - Network Configuration...
Jan 17 12:18:39.642702 systemd-networkd[747]: lo: Link UP
Jan 17 12:18:39.642711 systemd-networkd[747]: lo: Gained carrier
Jan 17 12:18:39.646584 systemd-networkd[747]: Enumeration completed
Jan 17 12:18:39.646727 systemd[1]: Started systemd-networkd.service - Network Configuration.
Jan 17 12:18:39.647066 systemd-networkd[747]: eth0: found matching network '/usr/lib/systemd/network/yy-digitalocean.network', based on potentially unpredictable interface name.
Jan 17 12:18:39.647070 systemd-networkd[747]: eth0: Configuring with /usr/lib/systemd/network/yy-digitalocean.network.
Jan 17 12:18:39.648963 systemd[1]: Reached target network.target - Network.
Jan 17 12:18:39.650097 systemd-networkd[747]: eth1: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name.
Jan 17 12:18:39.650101 systemd-networkd[747]: eth1: Configuring with /usr/lib/systemd/network/zz-default.network.
Jan 17 12:18:39.652767 systemd-networkd[747]: eth0: Link UP
Jan 17 12:18:39.652774 systemd-networkd[747]: eth0: Gained carrier
Jan 17 12:18:39.652786 systemd-networkd[747]: eth0: found matching network '/usr/lib/systemd/network/yy-digitalocean.network', based on potentially unpredictable interface name.
Jan 17 12:18:39.658985 systemd-networkd[747]: eth1: Link UP
Jan 17 12:18:39.658992 systemd-networkd[747]: eth1: Gained carrier
Jan 17 12:18:39.659007 systemd-networkd[747]: eth1: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name.
Jan 17 12:18:39.669344 ignition[653]: Ignition 2.19.0
Jan 17 12:18:39.669357 ignition[653]: Stage: fetch-offline
Jan 17 12:18:39.671242 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline).
Jan 17 12:18:39.669396 ignition[653]: no configs at "/usr/lib/ignition/base.d"
Jan 17 12:18:39.671993 systemd-networkd[747]: eth0: DHCPv4 address 64.227.109.251/20, gateway 64.227.96.1 acquired from 169.254.169.253
Jan 17 12:18:39.669409 ignition[653]: no config dir at "/usr/lib/ignition/base.platform.d/digitalocean"
Jan 17 12:18:39.669641 ignition[653]: parsed url from cmdline: ""
Jan 17 12:18:39.669645 ignition[653]: no config URL provided
Jan 17 12:18:39.669652 ignition[653]: reading system config file "/usr/lib/ignition/user.ign"
Jan 17 12:18:39.669661 ignition[653]: no config at "/usr/lib/ignition/user.ign"
Jan 17 12:18:39.669666 ignition[653]: failed to fetch config: resource requires networking
Jan 17 12:18:39.669903 ignition[653]: Ignition finished successfully
Jan 17 12:18:39.677030 systemd-networkd[747]: eth1: DHCPv4 address 10.124.0.21/20 acquired from 169.254.169.253
Jan 17 12:18:39.679142 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)...
Jan 17 12:18:39.701084 ignition[756]: Ignition 2.19.0
Jan 17 12:18:39.701095 ignition[756]: Stage: fetch
Jan 17 12:18:39.701325 ignition[756]: no configs at "/usr/lib/ignition/base.d"
Jan 17 12:18:39.701339 ignition[756]: no config dir at "/usr/lib/ignition/base.platform.d/digitalocean"
Jan 17 12:18:39.701482 ignition[756]: parsed url from cmdline: ""
Jan 17 12:18:39.701488 ignition[756]: no config URL provided
Jan 17 12:18:39.701501 ignition[756]: reading system config file "/usr/lib/ignition/user.ign"
Jan 17 12:18:39.701511 ignition[756]: no config at "/usr/lib/ignition/user.ign"
Jan 17 12:18:39.701542 ignition[756]: GET http://169.254.169.254/metadata/v1/user-data: attempt #1
Jan 17 12:18:39.732385 ignition[756]: GET result: OK
Jan 17 12:18:39.732514 ignition[756]: parsing config with SHA512: 84e0780f53f4544e9c99d30166e464d3a6c04bba9a123da37aa1b5933fbbbf33002012a2f127f5006f2ceaef051e1dcfd4f1e6e224f7af17e873f30776612276
Jan 17 12:18:39.736502 unknown[756]: fetched base config from "system"
Jan 17 12:18:39.736513 unknown[756]: fetched base config from "system"
Jan 17 12:18:39.736984 ignition[756]: fetch: fetch complete
Jan 17 12:18:39.736520 unknown[756]: fetched user config from "digitalocean"
Jan 17 12:18:39.736990 ignition[756]: fetch: fetch passed
Jan 17 12:18:39.737044 ignition[756]: Ignition finished successfully
Jan 17 12:18:39.739021 systemd[1]: Finished ignition-fetch.service - Ignition (fetch).
Jan 17 12:18:39.746210 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)...
Jan 17 12:18:39.774093 ignition[763]: Ignition 2.19.0
Jan 17 12:18:39.774108 ignition[763]: Stage: kargs
Jan 17 12:18:39.774335 ignition[763]: no configs at "/usr/lib/ignition/base.d"
Jan 17 12:18:39.774349 ignition[763]: no config dir at "/usr/lib/ignition/base.platform.d/digitalocean"
Jan 17 12:18:39.775337 ignition[763]: kargs: kargs passed
Jan 17 12:18:39.776654 systemd[1]: Finished ignition-kargs.service - Ignition (kargs).
Jan 17 12:18:39.775402 ignition[763]: Ignition finished successfully
Jan 17 12:18:39.783149 systemd[1]: Starting ignition-disks.service - Ignition (disks)...
Jan 17 12:18:39.805235 ignition[770]: Ignition 2.19.0
Jan 17 12:18:39.805259 ignition[770]: Stage: disks
Jan 17 12:18:39.805648 ignition[770]: no configs at "/usr/lib/ignition/base.d"
Jan 17 12:18:39.805663 ignition[770]: no config dir at "/usr/lib/ignition/base.platform.d/digitalocean"
Jan 17 12:18:39.813849 ignition[770]: disks: disks passed
Jan 17 12:18:39.814010 ignition[770]: Ignition finished successfully
Jan 17 12:18:39.815540 systemd[1]: Finished ignition-disks.service - Ignition (disks).
Jan 17 12:18:39.817064 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device.
Jan 17 12:18:39.817785 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems.
Jan 17 12:18:39.818735 systemd[1]: Reached target local-fs.target - Local File Systems.
Jan 17 12:18:39.819642 systemd[1]: Reached target sysinit.target - System Initialization.
Jan 17 12:18:39.820545 systemd[1]: Reached target basic.target - Basic System.
Jan 17 12:18:39.827234 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT...
Jan 17 12:18:39.852520 systemd-fsck[778]: ROOT: clean, 14/553520 files, 52654/553472 blocks
Jan 17 12:18:39.856676 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT.
Jan 17 12:18:39.861151 systemd[1]: Mounting sysroot.mount - /sysroot...
Jan 17 12:18:39.967951 kernel: EXT4-fs (vda9): mounted filesystem 0ba4fe0e-76d7-406f-b570-4642d86198f6 r/w with ordered data mode. Quota mode: none.
Jan 17 12:18:39.968977 systemd[1]: Mounted sysroot.mount - /sysroot.
Jan 17 12:18:39.970581 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System.
Jan 17 12:18:39.976148 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem...
Jan 17 12:18:39.979231 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr...
Jan 17 12:18:39.981098 systemd[1]: Starting flatcar-digitalocean-network.service - Flatcar DigitalOcean Network Agent...
Jan 17 12:18:39.990992 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/vda6 scanned by mount (786)
Jan 17 12:18:39.991057 kernel: BTRFS info (device vda6): first mount of filesystem a70a40d6-5ab2-4665-81b1-b8e9f58c5ff8
Jan 17 12:18:39.992247 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm
Jan 17 12:18:39.992298 kernel: BTRFS info (device vda6): using free space tree
Jan 17 12:18:39.994425 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent...
Jan 17 12:18:39.994920 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot).
Jan 17 12:18:39.994965 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup.
Jan 17 12:18:40.000369 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr.
Jan 17 12:18:40.005306 kernel: BTRFS info (device vda6): auto enabling async discard
Jan 17 12:18:40.013433 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup...
Jan 17 12:18:40.015293 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem.
Jan 17 12:18:40.090043 coreos-metadata[789]: Jan 17 12:18:40.090 INFO Fetching http://169.254.169.254/metadata/v1.json: Attempt #1
Jan 17 12:18:40.091096 coreos-metadata[788]: Jan 17 12:18:40.089 INFO Fetching http://169.254.169.254/metadata/v1.json: Attempt #1
Jan 17 12:18:40.099443 initrd-setup-root[816]: cut: /sysroot/etc/passwd: No such file or directory
Jan 17 12:18:40.103537 coreos-metadata[789]: Jan 17 12:18:40.103 INFO Fetch successful
Jan 17 12:18:40.105262 coreos-metadata[788]: Jan 17 12:18:40.103 INFO Fetch successful
Jan 17 12:18:40.105823 initrd-setup-root[823]: cut: /sysroot/etc/group: No such file or directory
Jan 17 12:18:40.113243 coreos-metadata[789]: Jan 17 12:18:40.110 INFO wrote hostname ci-4081.3.0-a-1e82b03d4c to /sysroot/etc/hostname
Jan 17 12:18:40.111918 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent.
Jan 17 12:18:40.115590 systemd[1]: flatcar-digitalocean-network.service: Deactivated successfully.
Jan 17 12:18:40.116685 systemd[1]: Finished flatcar-digitalocean-network.service - Flatcar DigitalOcean Network Agent.
Jan 17 12:18:40.119670 initrd-setup-root[831]: cut: /sysroot/etc/shadow: No such file or directory
Jan 17 12:18:40.122054 initrd-setup-root[839]: cut: /sysroot/etc/gshadow: No such file or directory
Jan 17 12:18:40.238899 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup.
Jan 17 12:18:40.245137 systemd[1]: Starting ignition-mount.service - Ignition (mount)...
Jan 17 12:18:40.246602 systemd[1]: Starting sysroot-boot.service - /sysroot/boot...
Jan 17 12:18:40.258982 kernel: BTRFS info (device vda6): last unmount of filesystem a70a40d6-5ab2-4665-81b1-b8e9f58c5ff8
Jan 17 12:18:40.284961 systemd[1]: Finished sysroot-boot.service - /sysroot/boot.
Jan 17 12:18:40.294402 ignition[907]: INFO     : Ignition 2.19.0
Jan 17 12:18:40.294402 ignition[907]: INFO     : Stage: mount
Jan 17 12:18:40.295465 ignition[907]: INFO     : no configs at "/usr/lib/ignition/base.d"
Jan 17 12:18:40.295465 ignition[907]: INFO     : no config dir at "/usr/lib/ignition/base.platform.d/digitalocean"
Jan 17 12:18:40.295465 ignition[907]: INFO     : mount: mount passed
Jan 17 12:18:40.295465 ignition[907]: INFO     : Ignition finished successfully
Jan 17 12:18:40.296344 systemd[1]: Finished ignition-mount.service - Ignition (mount).
Jan 17 12:18:40.302097 systemd[1]: Starting ignition-files.service - Ignition (files)...
Jan 17 12:18:40.444144 systemd[1]: sysroot-oem.mount: Deactivated successfully.
Jan 17 12:18:40.458283 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem...
Jan 17 12:18:40.469218 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by mount (918)
Jan 17 12:18:40.469275 kernel: BTRFS info (device vda6): first mount of filesystem a70a40d6-5ab2-4665-81b1-b8e9f58c5ff8
Jan 17 12:18:40.471111 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm
Jan 17 12:18:40.471162 kernel: BTRFS info (device vda6): using free space tree
Jan 17 12:18:40.477037 kernel: BTRFS info (device vda6): auto enabling async discard
Jan 17 12:18:40.479100 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem.
Jan 17 12:18:40.505982 ignition[934]: INFO     : Ignition 2.19.0
Jan 17 12:18:40.506963 ignition[934]: INFO     : Stage: files
Jan 17 12:18:40.507804 ignition[934]: INFO     : no configs at "/usr/lib/ignition/base.d"
Jan 17 12:18:40.509892 ignition[934]: INFO     : no config dir at "/usr/lib/ignition/base.platform.d/digitalocean"
Jan 17 12:18:40.509892 ignition[934]: DEBUG    : files: compiled without relabeling support, skipping
Jan 17 12:18:40.511044 ignition[934]: INFO     : files: ensureUsers: op(1): [started]  creating or modifying user "core"
Jan 17 12:18:40.511044 ignition[934]: DEBUG    : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core"
Jan 17 12:18:40.515410 ignition[934]: INFO     : files: ensureUsers: op(1): [finished] creating or modifying user "core"
Jan 17 12:18:40.515976 ignition[934]: INFO     : files: ensureUsers: op(2): [started]  adding ssh keys to user "core"
Jan 17 12:18:40.516541 ignition[934]: INFO     : files: ensureUsers: op(2): [finished] adding ssh keys to user "core"
Jan 17 12:18:40.515989 unknown[934]: wrote ssh authorized keys file for user: core
Jan 17 12:18:40.518222 ignition[934]: INFO     : files: createFilesystemsFiles: createFiles: op(3): [started]  writing file "/sysroot/etc/flatcar-cgroupv1"
Jan 17 12:18:40.518903 ignition[934]: INFO     : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar-cgroupv1"
Jan 17 12:18:40.518903 ignition[934]: INFO     : files: createFilesystemsFiles: createFiles: op(4): [started]  writing file "/sysroot/home/core/install.sh"
Jan 17 12:18:40.518903 ignition[934]: INFO     : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/home/core/install.sh"
Jan 17 12:18:40.518903 ignition[934]: INFO     : files: createFilesystemsFiles: createFiles: op(5): [started]  writing file "/sysroot/etc/flatcar/update.conf"
Jan 17 12:18:40.521520 ignition[934]: INFO     : files: createFilesystemsFiles: createFiles: op(5): [finished] writing file "/sysroot/etc/flatcar/update.conf"
Jan 17 12:18:40.521520 ignition[934]: INFO     : files: createFilesystemsFiles: createFiles: op(6): [started]  writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.29.2-x86-64.raw"
Jan 17 12:18:40.521520 ignition[934]: INFO     : files: createFilesystemsFiles: createFiles: op(6): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.29.2-x86-64.raw"
Jan 17 12:18:40.521520 ignition[934]: INFO     : files: createFilesystemsFiles: createFiles: op(7): [started]  writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.29.2-x86-64.raw"
Jan 17 12:18:40.521520 ignition[934]: INFO     : files: createFilesystemsFiles: createFiles: op(7): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.29.2-x86-64.raw: attempt #1
Jan 17 12:18:40.888846 ignition[934]: INFO     : files: createFilesystemsFiles: createFiles: op(7): GET result: OK
Jan 17 12:18:41.145083 ignition[934]: INFO     : files: createFilesystemsFiles: createFiles: op(7): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.29.2-x86-64.raw"
Jan 17 12:18:41.145083 ignition[934]: INFO     : files: op(8): [started]  processing unit "containerd.service"
Jan 17 12:18:41.146789 ignition[934]: INFO     : files: op(8): op(9): [started]  writing systemd drop-in "10-use-cgroupfs.conf" at "/sysroot/etc/systemd/system/containerd.service.d/10-use-cgroupfs.conf"
Jan 17 12:18:41.146789 ignition[934]: INFO     : files: op(8): op(9): [finished] writing systemd drop-in "10-use-cgroupfs.conf" at "/sysroot/etc/systemd/system/containerd.service.d/10-use-cgroupfs.conf"
Jan 17 12:18:41.146789 ignition[934]: INFO     : files: op(8): [finished] processing unit "containerd.service"
Jan 17 12:18:41.146789 ignition[934]: INFO     : files: createResultFile: createFiles: op(a): [started]  writing file "/sysroot/etc/.ignition-result.json"
Jan 17 12:18:41.146789 ignition[934]: INFO     : files: createResultFile: createFiles: op(a): [finished] writing file "/sysroot/etc/.ignition-result.json"
Jan 17 12:18:41.146789 ignition[934]: INFO     : files: files passed
Jan 17 12:18:41.154398 ignition[934]: INFO     : Ignition finished successfully
Jan 17 12:18:41.148202 systemd[1]: Finished ignition-files.service - Ignition (files).
Jan 17 12:18:41.164866 systemd[1]: Starting ignition-quench.service - Ignition (record completion)...
Jan 17 12:18:41.168164 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion...
Jan 17 12:18:41.170558 systemd[1]: ignition-quench.service: Deactivated successfully.
Jan 17 12:18:41.170704 systemd[1]: Finished ignition-quench.service - Ignition (record completion).
Jan 17 12:18:41.194962 initrd-setup-root-after-ignition[963]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory
Jan 17 12:18:41.194962 initrd-setup-root-after-ignition[963]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory
Jan 17 12:18:41.196870 initrd-setup-root-after-ignition[967]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory
Jan 17 12:18:41.199646 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion.
Jan 17 12:18:41.200371 systemd[1]: Reached target ignition-complete.target - Ignition Complete.
Jan 17 12:18:41.204103 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root...
Jan 17 12:18:41.239199 systemd[1]: initrd-parse-etc.service: Deactivated successfully.
Jan 17 12:18:41.239344 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root.
Jan 17 12:18:41.240681 systemd[1]: Reached target initrd-fs.target - Initrd File Systems.
Jan 17 12:18:41.241999 systemd[1]: Reached target initrd.target - Initrd Default Target.
Jan 17 12:18:41.243254 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met.
Jan 17 12:18:41.249254 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook...
Jan 17 12:18:41.269108 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook.
Jan 17 12:18:41.274204 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons...
Jan 17 12:18:41.301225 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups.
Jan 17 12:18:41.303128 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes.
Jan 17 12:18:41.305067 systemd[1]: Stopped target timers.target - Timer Units.
Jan 17 12:18:41.305756 systemd[1]: dracut-pre-pivot.service: Deactivated successfully.
Jan 17 12:18:41.305987 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook.
Jan 17 12:18:41.307121 systemd[1]: Stopped target initrd.target - Initrd Default Target.
Jan 17 12:18:41.308296 systemd[1]: Stopped target basic.target - Basic System.
Jan 17 12:18:41.309325 systemd[1]: Stopped target ignition-complete.target - Ignition Complete.
Jan 17 12:18:41.310230 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup.
Jan 17 12:18:41.311083 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device.
Jan 17 12:18:41.312012 systemd[1]: Stopped target remote-fs.target - Remote File Systems.
Jan 17 12:18:41.312889 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems.
Jan 17 12:18:41.313867 systemd[1]: Stopped target sysinit.target - System Initialization.
Jan 17 12:18:41.314895 systemd[1]: Stopped target local-fs.target - Local File Systems.
Jan 17 12:18:41.315783 systemd[1]: Stopped target swap.target - Swaps.
Jan 17 12:18:41.316657 systemd[1]: dracut-pre-mount.service: Deactivated successfully.
Jan 17 12:18:41.316981 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook.
Jan 17 12:18:41.318478 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes.
Jan 17 12:18:41.319157 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre).
Jan 17 12:18:41.320089 systemd[1]: clevis-luks-askpass.path: Deactivated successfully.
Jan 17 12:18:41.320285 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch.
Jan 17 12:18:41.321152 systemd[1]: dracut-initqueue.service: Deactivated successfully.
Jan 17 12:18:41.321347 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook.
Jan 17 12:18:41.322814 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully.
Jan 17 12:18:41.323043 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion.
Jan 17 12:18:41.324028 systemd[1]: ignition-files.service: Deactivated successfully.
Jan 17 12:18:41.324203 systemd[1]: Stopped ignition-files.service - Ignition (files).
Jan 17 12:18:41.325005 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully.
Jan 17 12:18:41.325176 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent.
Jan 17 12:18:41.334251 systemd[1]: Stopping ignition-mount.service - Ignition (mount)...
Jan 17 12:18:41.334715 systemd[1]: kmod-static-nodes.service: Deactivated successfully.
Jan 17 12:18:41.334888 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes.
Jan 17 12:18:41.341319 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot...
Jan 17 12:18:41.342472 systemd[1]: systemd-udev-trigger.service: Deactivated successfully.
Jan 17 12:18:41.343308 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices.
Jan 17 12:18:41.344503 systemd[1]: dracut-pre-trigger.service: Deactivated successfully.
Jan 17 12:18:41.344849 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook.
Jan 17 12:18:41.353690 systemd[1]: initrd-cleanup.service: Deactivated successfully.
Jan 17 12:18:41.354308 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons.
Jan 17 12:18:41.365783 ignition[987]: INFO     : Ignition 2.19.0
Jan 17 12:18:41.365783 ignition[987]: INFO     : Stage: umount
Jan 17 12:18:41.369973 ignition[987]: INFO     : no configs at "/usr/lib/ignition/base.d"
Jan 17 12:18:41.369973 ignition[987]: INFO     : no config dir at "/usr/lib/ignition/base.platform.d/digitalocean"
Jan 17 12:18:41.371061 ignition[987]: INFO     : umount: umount passed
Jan 17 12:18:41.371061 ignition[987]: INFO     : Ignition finished successfully
Jan 17 12:18:41.373394 systemd[1]: ignition-mount.service: Deactivated successfully.
Jan 17 12:18:41.373578 systemd[1]: Stopped ignition-mount.service - Ignition (mount).
Jan 17 12:18:41.375642 systemd[1]: ignition-disks.service: Deactivated successfully.
Jan 17 12:18:41.375730 systemd[1]: Stopped ignition-disks.service - Ignition (disks).
Jan 17 12:18:41.376759 systemd[1]: ignition-kargs.service: Deactivated successfully.
Jan 17 12:18:41.376814 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs).
Jan 17 12:18:41.378267 systemd[1]: ignition-fetch.service: Deactivated successfully.
Jan 17 12:18:41.378317 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch).
Jan 17 12:18:41.379035 systemd[1]: Stopped target network.target - Network.
Jan 17 12:18:41.379361 systemd[1]: ignition-fetch-offline.service: Deactivated successfully.
Jan 17 12:18:41.379408 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline).
Jan 17 12:18:41.379788 systemd[1]: Stopped target paths.target - Path Units.
Jan 17 12:18:41.380132 systemd[1]: systemd-ask-password-console.path: Deactivated successfully.
Jan 17 12:18:41.381584 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch.
Jan 17 12:18:41.384043 systemd[1]: Stopped target slices.target - Slice Units.
Jan 17 12:18:41.384670 systemd[1]: Stopped target sockets.target - Socket Units.
Jan 17 12:18:41.385070 systemd[1]: iscsid.socket: Deactivated successfully.
Jan 17 12:18:41.385119 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket.
Jan 17 12:18:41.391078 systemd[1]: iscsiuio.socket: Deactivated successfully.
Jan 17 12:18:41.391148 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket.
Jan 17 12:18:41.391752 systemd[1]: ignition-setup.service: Deactivated successfully.
Jan 17 12:18:41.391812 systemd[1]: Stopped ignition-setup.service - Ignition (setup).
Jan 17 12:18:41.392285 systemd[1]: ignition-setup-pre.service: Deactivated successfully.
Jan 17 12:18:41.392328 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup.
Jan 17 12:18:41.393789 systemd[1]: Stopping systemd-networkd.service - Network Configuration...
Jan 17 12:18:41.394616 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution...
Jan 17 12:18:41.396363 systemd[1]: sysroot-boot.mount: Deactivated successfully.
Jan 17 12:18:41.396914 systemd[1]: sysroot-boot.service: Deactivated successfully.
Jan 17 12:18:41.397030 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot.
Jan 17 12:18:41.398836 systemd[1]: initrd-setup-root.service: Deactivated successfully.
Jan 17 12:18:41.399120 systemd-networkd[747]: eth0: DHCPv6 lease lost
Jan 17 12:18:41.399714 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup.
Jan 17 12:18:41.403029 systemd-networkd[747]: eth1: DHCPv6 lease lost
Jan 17 12:18:41.407122 systemd[1]: systemd-resolved.service: Deactivated successfully.
Jan 17 12:18:41.407255 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution.
Jan 17 12:18:41.410365 systemd[1]: systemd-networkd.service: Deactivated successfully.
Jan 17 12:18:41.410660 systemd[1]: Stopped systemd-networkd.service - Network Configuration.
Jan 17 12:18:41.412512 systemd[1]: systemd-networkd.socket: Deactivated successfully.
Jan 17 12:18:41.412580 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket.
Jan 17 12:18:41.419116 systemd[1]: Stopping network-cleanup.service - Network Cleanup...
Jan 17 12:18:41.419725 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully.
Jan 17 12:18:41.419830 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline.
Jan 17 12:18:41.422680 systemd[1]: systemd-sysctl.service: Deactivated successfully.
Jan 17 12:18:41.423067 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables.
Jan 17 12:18:41.424138 systemd[1]: systemd-modules-load.service: Deactivated successfully.
Jan 17 12:18:41.424207 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules.
Jan 17 12:18:41.424627 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.
Jan 17 12:18:41.424665 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories.
Jan 17 12:18:41.425273 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files...
Jan 17 12:18:41.437300 systemd[1]: network-cleanup.service: Deactivated successfully.
Jan 17 12:18:41.437578 systemd[1]: Stopped network-cleanup.service - Network Cleanup.
Jan 17 12:18:41.439406 systemd[1]: systemd-udevd.service: Deactivated successfully.
Jan 17 12:18:41.439566 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files.
Jan 17 12:18:41.441348 systemd[1]: systemd-udevd-control.socket: Deactivated successfully.
Jan 17 12:18:41.441532 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket.
Jan 17 12:18:41.442474 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully.
Jan 17 12:18:41.442514 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket.
Jan 17 12:18:41.443469 systemd[1]: dracut-pre-udev.service: Deactivated successfully.
Jan 17 12:18:41.443522 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook.
Jan 17 12:18:41.444912 systemd[1]: dracut-cmdline.service: Deactivated successfully.
Jan 17 12:18:41.444987 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook.
Jan 17 12:18:41.445795 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully.
Jan 17 12:18:41.445851 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters.
Jan 17 12:18:41.455273 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database...
Jan 17 12:18:41.456154 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.
Jan 17 12:18:41.456227 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev.
Jan 17 12:18:41.459355 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
Jan 17 12:18:41.459430 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup.
Jan 17 12:18:41.466513 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.
Jan 17 12:18:41.466643 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database.
Jan 17 12:18:41.467992 systemd[1]: Reached target initrd-switch-root.target - Switch Root.
Jan 17 12:18:41.482231 systemd[1]: Starting initrd-switch-root.service - Switch Root...
Jan 17 12:18:41.494355 systemd[1]: Switching root.
Jan 17 12:18:41.526165 systemd-journald[183]: Journal stopped
Jan 17 12:18:42.795375 systemd-journald[183]: Received SIGTERM from PID 1 (systemd).
Jan 17 12:18:42.795457 kernel: SELinux:  policy capability network_peer_controls=1
Jan 17 12:18:42.795536 kernel: SELinux:  policy capability open_perms=1
Jan 17 12:18:42.795549 kernel: SELinux:  policy capability extended_socket_class=1
Jan 17 12:18:42.795584 kernel: SELinux:  policy capability always_check_network=0
Jan 17 12:18:42.795595 kernel: SELinux:  policy capability cgroup_seclabel=1
Jan 17 12:18:42.795608 kernel: SELinux:  policy capability nnp_nosuid_transition=1
Jan 17 12:18:42.795619 kernel: SELinux:  policy capability genfs_seclabel_symlinks=0
Jan 17 12:18:42.795637 kernel: SELinux:  policy capability ioctl_skip_cloexec=0
Jan 17 12:18:42.795648 kernel: audit: type=1403 audit(1737116321.793:2): auid=4294967295 ses=4294967295 lsm=selinux res=1
Jan 17 12:18:42.795662 systemd[1]: Successfully loaded SELinux policy in 40.675ms.
Jan 17 12:18:42.795687 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 11.756ms.
Jan 17 12:18:42.795701 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 17 12:18:42.795714 systemd[1]: Detected virtualization kvm.
Jan 17 12:18:42.795727 systemd[1]: Detected architecture x86-64.
Jan 17 12:18:42.795740 systemd[1]: Detected first boot.
Jan 17 12:18:42.795755 systemd[1]: Hostname set to <ci-4081.3.0-a-1e82b03d4c>.
Jan 17 12:18:42.795767 systemd[1]: Initializing machine ID from VM UUID.
Jan 17 12:18:42.795779 zram_generator::config[1047]: No configuration found.
Jan 17 12:18:42.795793 systemd[1]: Populated /etc with preset unit settings.
Jan 17 12:18:42.795805 systemd[1]: Queued start job for default target multi-user.target.
Jan 17 12:18:42.795817 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6.
Jan 17 12:18:42.795831 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config.
Jan 17 12:18:42.795844 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run.
Jan 17 12:18:42.795859 systemd[1]: Created slice system-getty.slice - Slice /system/getty.
Jan 17 12:18:42.795878 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe.
Jan 17 12:18:42.795891 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty.
Jan 17 12:18:42.795903 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit.
Jan 17 12:18:42.795916 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck.
Jan 17 12:18:42.795941 systemd[1]: Created slice user.slice - User and Session Slice.
Jan 17 12:18:42.795954 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch.
Jan 17 12:18:42.795966 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch.
Jan 17 12:18:42.795979 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch.
Jan 17 12:18:42.795994 systemd[1]: Set up automount boot.automount - Boot partition Automount Point.
Jan 17 12:18:42.796006 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point.
Jan 17 12:18:42.796120 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM...
Jan 17 12:18:42.796135 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0...
Jan 17 12:18:42.796149 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre).
Jan 17 12:18:42.796161 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes.
Jan 17 12:18:42.796173 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes.
Jan 17 12:18:42.796193 systemd[1]: Reached target remote-fs.target - Remote File Systems.
Jan 17 12:18:42.796207 systemd[1]: Reached target slices.target - Slice Units.
Jan 17 12:18:42.796220 systemd[1]: Reached target swap.target - Swaps.
Jan 17 12:18:42.796232 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes.
Jan 17 12:18:42.796264 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket.
Jan 17 12:18:42.796277 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log).
Jan 17 12:18:42.796297 systemd[1]: Listening on systemd-journald.socket - Journal Socket.
Jan 17 12:18:42.796310 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket.
Jan 17 12:18:42.796323 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket.
Jan 17 12:18:42.796338 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket.
Jan 17 12:18:42.796350 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket.
Jan 17 12:18:42.796362 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System...
Jan 17 12:18:42.796375 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System...
Jan 17 12:18:42.796387 systemd[1]: Mounting media.mount - External Media Directory...
Jan 17 12:18:42.796400 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen).
Jan 17 12:18:42.796413 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System...
Jan 17 12:18:42.796426 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System...
Jan 17 12:18:42.796443 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp...
Jan 17 12:18:42.796461 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files...
Jan 17 12:18:42.796478 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met.
Jan 17 12:18:42.796494 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes...
Jan 17 12:18:42.796507 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs...
Jan 17 12:18:42.796519 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod...
Jan 17 12:18:42.796531 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm...
Jan 17 12:18:42.796546 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore...
Jan 17 12:18:42.796558 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse...
Jan 17 12:18:42.796573 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop...
Jan 17 12:18:42.796595 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf).
Jan 17 12:18:42.796608 systemd[1]: systemd-journald.service: unit configures an IP firewall, but the local system does not support BPF/cgroup firewalling.
Jan 17 12:18:42.796621 systemd[1]: systemd-journald.service: (This warning is only shown for the first unit using IP firewalling.)
Jan 17 12:18:42.796634 systemd[1]: Starting systemd-journald.service - Journal Service...
Jan 17 12:18:42.796647 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules...
Jan 17 12:18:42.796659 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line...
Jan 17 12:18:42.796674 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems...
Jan 17 12:18:42.796690 kernel: fuse: init (API version 7.39)
Jan 17 12:18:42.796702 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices...
Jan 17 12:18:42.796720 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen).
Jan 17 12:18:42.796732 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System.
Jan 17 12:18:42.796745 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System.
Jan 17 12:18:42.796759 systemd[1]: Mounted media.mount - External Media Directory.
Jan 17 12:18:42.796771 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System.
Jan 17 12:18:42.796783 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System.
Jan 17 12:18:42.796795 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp.
Jan 17 12:18:42.796810 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes.
Jan 17 12:18:42.796823 systemd[1]: modprobe@configfs.service: Deactivated successfully.
Jan 17 12:18:42.796836 kernel: loop: module loaded
Jan 17 12:18:42.796848 kernel: ACPI: bus type drm_connector registered
Jan 17 12:18:42.796895 systemd-journald[1136]: Collecting audit messages is disabled.
Jan 17 12:18:42.796939 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs.
Jan 17 12:18:42.796955 systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
Jan 17 12:18:42.796972 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod.
Jan 17 12:18:42.796984 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
Jan 17 12:18:42.796996 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore.
Jan 17 12:18:42.797010 systemd-journald[1136]: Journal started
Jan 17 12:18:42.797040 systemd-journald[1136]: Runtime Journal (/run/log/journal/9f27d091b2c94e6facc13833ab86562c) is 4.9M, max 39.3M, 34.4M free.
Jan 17 12:18:42.803469 systemd[1]: Started systemd-journald.service - Journal Service.
Jan 17 12:18:42.801468 systemd[1]: modprobe@drm.service: Deactivated successfully.
Jan 17 12:18:42.801708 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm.
Jan 17 12:18:42.803807 systemd[1]: modprobe@fuse.service: Deactivated successfully.
Jan 17 12:18:42.804018 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse.
Jan 17 12:18:42.804765 systemd[1]: modprobe@loop.service: Deactivated successfully.
Jan 17 12:18:42.808353 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop.
Jan 17 12:18:42.810580 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules.
Jan 17 12:18:42.811463 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line.
Jan 17 12:18:42.816909 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems.
Jan 17 12:18:42.842232 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files.
Jan 17 12:18:42.844857 systemd[1]: Reached target network-pre.target - Preparation for Network.
Jan 17 12:18:42.854123 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System...
Jan 17 12:18:42.859091 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System...
Jan 17 12:18:42.859745 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).
Jan 17 12:18:42.869296 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database...
Jan 17 12:18:42.873962 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage...
Jan 17 12:18:42.874607 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
Jan 17 12:18:42.881042 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed...
Jan 17 12:18:42.884305 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met.
Jan 17 12:18:42.898122 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables...
Jan 17 12:18:42.902122 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully...
Jan 17 12:18:42.915529 systemd-journald[1136]: Time spent on flushing to /var/log/journal/9f27d091b2c94e6facc13833ab86562c is 39.990ms for 955 entries.
Jan 17 12:18:42.915529 systemd-journald[1136]: System Journal (/var/log/journal/9f27d091b2c94e6facc13833ab86562c) is 8.0M, max 195.6M, 187.6M free.
Jan 17 12:18:42.989084 systemd-journald[1136]: Received client request to flush runtime journal.
Jan 17 12:18:42.919986 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System.
Jan 17 12:18:42.923044 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System.
Jan 17 12:18:42.941592 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed.
Jan 17 12:18:42.942950 systemd[1]: Reached target first-boot-complete.target - First Boot Complete.
Jan 17 12:18:42.982847 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices.
Jan 17 12:18:42.993209 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization...
Jan 17 12:18:42.994699 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage.
Jan 17 12:18:43.004096 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables.
Jan 17 12:18:43.022090 systemd-tmpfiles[1189]: ACLs are not supported, ignoring.
Jan 17 12:18:43.022110 systemd-tmpfiles[1189]: ACLs are not supported, ignoring.
Jan 17 12:18:43.026209 udevadm[1200]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in.
Jan 17 12:18:43.031024 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully.
Jan 17 12:18:43.042248 systemd[1]: Starting systemd-sysusers.service - Create System Users...
Jan 17 12:18:43.083189 systemd[1]: Finished systemd-sysusers.service - Create System Users.
Jan 17 12:18:43.091293 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev...
Jan 17 12:18:43.113663 systemd-tmpfiles[1212]: ACLs are not supported, ignoring.
Jan 17 12:18:43.113694 systemd-tmpfiles[1212]: ACLs are not supported, ignoring.
Jan 17 12:18:43.119465 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev.
Jan 17 12:18:43.818314 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database.
Jan 17 12:18:43.829320 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files...
Jan 17 12:18:43.857717 systemd-udevd[1218]: Using default interface naming scheme 'v255'.
Jan 17 12:18:43.884024 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files.
Jan 17 12:18:43.892477 systemd[1]: Starting systemd-networkd.service - Network Configuration...
Jan 17 12:18:43.922329 systemd[1]: Starting systemd-userdbd.service - User Database Manager...
Jan 17 12:18:43.991416 systemd[1]: Started systemd-userdbd.service - User Database Manager.
Jan 17 12:18:44.002484 systemd[1]: Found device dev-ttyS0.device - /dev/ttyS0.
Jan 17 12:18:44.003583 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen).
Jan 17 12:18:44.003757 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met.
Jan 17 12:18:44.012109 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod...
Jan 17 12:18:44.022206 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore...
Jan 17 12:18:44.032511 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop...
Jan 17 12:18:44.032975 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).
Jan 17 12:18:44.033029 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf).
Jan 17 12:18:44.033077 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen).
Jan 17 12:18:44.038363 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
Jan 17 12:18:44.038737 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore.
Jan 17 12:18:44.039555 systemd[1]: modprobe@loop.service: Deactivated successfully.
Jan 17 12:18:44.039714 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop.
Jan 17 12:18:44.052734 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
Jan 17 12:18:44.064163 systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
Jan 17 12:18:44.064382 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod.
Jan 17 12:18:44.065288 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met.
Jan 17 12:18:44.081960 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (1220)
Jan 17 12:18:44.155082 systemd-networkd[1222]: lo: Link UP
Jan 17 12:18:44.155831 systemd-networkd[1222]: lo: Gained carrier
Jan 17 12:18:44.159479 systemd-networkd[1222]: Enumeration completed
Jan 17 12:18:44.160174 systemd[1]: Started systemd-networkd.service - Network Configuration.
Jan 17 12:18:44.162525 systemd-networkd[1222]: eth0: Configuring with /run/systemd/network/10-ae:a2:58:dd:67:d7.network.
Jan 17 12:18:44.165342 systemd-networkd[1222]: eth1: Configuring with /run/systemd/network/10-1e:dc:34:44:e4:05.network.
Jan 17 12:18:44.166746 systemd-networkd[1222]: eth0: Link UP
Jan 17 12:18:44.166757 systemd-networkd[1222]: eth0: Gained carrier
Jan 17 12:18:44.170464 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured...
Jan 17 12:18:44.171726 systemd-networkd[1222]: eth1: Link UP
Jan 17 12:18:44.171848 systemd-networkd[1222]: eth1: Gained carrier
Jan 17 12:18:44.203596 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM.
Jan 17 12:18:44.212006 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2
Jan 17 12:18:44.228972 kernel: ACPI: button: Power Button [PWRF]
Jan 17 12:18:44.235983 kernel: piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0
Jan 17 12:18:44.239001 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3
Jan 17 12:18:44.285954 kernel: mousedev: PS/2 mouse device common for all mice
Jan 17 12:18:44.308367 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup...
Jan 17 12:18:44.334955 kernel: [drm] pci: virtio-vga detected at 0000:00:02.0
Jan 17 12:18:44.335036 kernel: virtio-pci 0000:00:02.0: vgaarb: deactivate vga console
Jan 17 12:18:44.335285 kernel: Console: switching to colour dummy device 80x25
Jan 17 12:18:44.339956 kernel: [drm] features: -virgl +edid -resource_blob -host_visible
Jan 17 12:18:44.340040 kernel: [drm] features: -context_init
Jan 17 12:18:44.342309 kernel: [drm] number of scanouts: 1
Jan 17 12:18:44.342379 kernel: [drm] number of cap sets: 0
Jan 17 12:18:44.387740 kernel: [drm] Initialized virtio_gpu 0.1.0 0 for 0000:00:02.0 on minor 0
Jan 17 12:18:44.387812 kernel: fbcon: virtio_gpudrmfb (fb0) is primary device
Jan 17 12:18:44.387827 kernel: Console: switching to colour frame buffer device 128x48
Jan 17 12:18:44.387841 kernel: virtio-pci 0000:00:02.0: [drm] fb0: virtio_gpudrmfb frame buffer device
Jan 17 12:18:44.389422 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
Jan 17 12:18:44.389841 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup.
Jan 17 12:18:44.397400 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup...
Jan 17 12:18:44.453973 kernel: EDAC MC: Ver: 3.0.0
Jan 17 12:18:44.487828 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization.
Jan 17 12:18:44.498310 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes...
Jan 17 12:18:44.519308 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup.
Jan 17 12:18:44.524954 lvm[1277]:   WARNING: Failed to connect to lvmetad. Falling back to device scanning.
Jan 17 12:18:44.556192 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes.
Jan 17 12:18:44.558637 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes.
Jan 17 12:18:44.564187 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes...
Jan 17 12:18:44.579496 lvm[1283]:   WARNING: Failed to connect to lvmetad. Falling back to device scanning.
Jan 17 12:18:44.611531 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes.
Jan 17 12:18:44.613216 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems.
Jan 17 12:18:44.620234 systemd[1]: Mounting media-configdrive.mount - /media/configdrive...
Jan 17 12:18:44.620382 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 17 12:18:44.620448 systemd[1]: Reached target machines.target - Containers.
Jan 17 12:18:44.623471 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM...
Jan 17 12:18:44.643529 kernel: ISO 9660 Extensions: RRIP_1991A
Jan 17 12:18:44.643254 systemd[1]: Mounted media-configdrive.mount - /media/configdrive.
Jan 17 12:18:44.647144 systemd[1]: Reached target local-fs.target - Local File Systems.
Jan 17 12:18:44.648569 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink).
Jan 17 12:18:44.656181 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown...
Jan 17 12:18:44.665907 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache...
Jan 17 12:18:44.666159 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met.
Jan 17 12:18:44.669411 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk...
Jan 17 12:18:44.679335 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/...
Jan 17 12:18:44.682815 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM.
Jan 17 12:18:44.692890 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown.
Jan 17 12:18:44.709313 kernel: loop0: detected capacity change from 0 to 140768
Jan 17 12:18:44.742023 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher
Jan 17 12:18:44.757919 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
Jan 17 12:18:44.761742 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk.
Jan 17 12:18:44.770685 kernel: loop1: detected capacity change from 0 to 8
Jan 17 12:18:44.807226 kernel: loop2: detected capacity change from 0 to 142488
Jan 17 12:18:44.866510 kernel: loop3: detected capacity change from 0 to 211296
Jan 17 12:18:44.914739 kernel: loop4: detected capacity change from 0 to 140768
Jan 17 12:18:44.955002 kernel: loop5: detected capacity change from 0 to 8
Jan 17 12:18:44.959086 kernel: loop6: detected capacity change from 0 to 142488
Jan 17 12:18:44.985340 kernel: loop7: detected capacity change from 0 to 211296
Jan 17 12:18:45.005727 (sd-merge)[1309]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-digitalocean'.
Jan 17 12:18:45.006602 (sd-merge)[1309]: Merged extensions into '/usr'.
Jan 17 12:18:45.011834 systemd[1]: Reloading requested from client PID 1297 ('systemd-sysext') (unit systemd-sysext.service)...
Jan 17 12:18:45.012261 systemd[1]: Reloading...
Jan 17 12:18:45.103960 zram_generator::config[1338]: No configuration found.
Jan 17 12:18:45.295539 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 17 12:18:45.302238 systemd-networkd[1222]: eth1: Gained IPv6LL
Jan 17 12:18:45.367335 systemd[1]: Reloading finished in 354 ms.
Jan 17 12:18:45.382278 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured.
Jan 17 12:18:45.384719 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/.
Jan 17 12:18:45.396339 ldconfig[1294]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start.
Jan 17 12:18:45.397557 systemd[1]: Starting ensure-sysext.service...
Jan 17 12:18:45.401022 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories...
Jan 17 12:18:45.406416 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache.
Jan 17 12:18:45.414570 systemd[1]: Reloading requested from client PID 1388 ('systemctl') (unit ensure-sysext.service)...
Jan 17 12:18:45.414724 systemd[1]: Reloading...
Jan 17 12:18:45.447744 systemd-tmpfiles[1389]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring.
Jan 17 12:18:45.448235 systemd-tmpfiles[1389]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring.
Jan 17 12:18:45.449337 systemd-tmpfiles[1389]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring.
Jan 17 12:18:45.449735 systemd-tmpfiles[1389]: ACLs are not supported, ignoring.
Jan 17 12:18:45.449824 systemd-tmpfiles[1389]: ACLs are not supported, ignoring.
Jan 17 12:18:45.454433 systemd-tmpfiles[1389]: Detected autofs mount point /boot during canonicalization of boot.
Jan 17 12:18:45.454445 systemd-tmpfiles[1389]: Skipping /boot
Jan 17 12:18:45.478246 systemd-tmpfiles[1389]: Detected autofs mount point /boot during canonicalization of boot.
Jan 17 12:18:45.478264 systemd-tmpfiles[1389]: Skipping /boot
Jan 17 12:18:45.508572 zram_generator::config[1418]: No configuration found.
Jan 17 12:18:45.659604 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 17 12:18:45.732065 systemd[1]: Reloading finished in 316 ms.
Jan 17 12:18:45.748355 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories.
Jan 17 12:18:45.767188 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules...
Jan 17 12:18:45.776192 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs...
Jan 17 12:18:45.787639 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog...
Jan 17 12:18:45.798269 systemd[1]: Starting systemd-resolved.service - Network Name Resolution...
Jan 17 12:18:45.805673 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP...
Jan 17 12:18:45.822570 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen).
Jan 17 12:18:45.824147 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met.
Jan 17 12:18:45.831715 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod...
Jan 17 12:18:45.845181 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore...
Jan 17 12:18:45.853799 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop...
Jan 17 12:18:45.857786 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met.
Jan 17 12:18:45.857976 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen).
Jan 17 12:18:45.861905 systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
Jan 17 12:18:45.862162 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod.
Jan 17 12:18:45.875530 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog.
Jan 17 12:18:45.884328 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
Jan 17 12:18:45.884558 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore.
Jan 17 12:18:45.889855 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
Jan 17 12:18:45.899658 systemd[1]: Starting systemd-update-done.service - Update is Completed...
Jan 17 12:18:45.903949 systemd[1]: modprobe@loop.service: Deactivated successfully.
Jan 17 12:18:45.909146 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop.
Jan 17 12:18:45.910365 augenrules[1500]: No rules
Jan 17 12:18:45.916183 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules.
Jan 17 12:18:45.922752 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP.
Jan 17 12:18:45.937227 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen).
Jan 17 12:18:45.937507 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met.
Jan 17 12:18:45.942285 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod...
Jan 17 12:18:45.953238 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore...
Jan 17 12:18:45.971835 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop...
Jan 17 12:18:45.973592 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met.
Jan 17 12:18:45.973843 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen).
Jan 17 12:18:45.976570 systemd[1]: Finished systemd-update-done.service - Update is Completed.
Jan 17 12:18:45.977717 systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
Jan 17 12:18:45.977918 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod.
Jan 17 12:18:45.978880 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
Jan 17 12:18:45.979906 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore.
Jan 17 12:18:45.982609 systemd[1]: modprobe@loop.service: Deactivated successfully.
Jan 17 12:18:45.982825 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop.
Jan 17 12:18:45.998448 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen).
Jan 17 12:18:45.998884 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met.
Jan 17 12:18:46.007503 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod...
Jan 17 12:18:46.020466 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm...
Jan 17 12:18:46.031474 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore...
Jan 17 12:18:46.042230 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop...
Jan 17 12:18:46.044263 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met.
Jan 17 12:18:46.044546 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen).
Jan 17 12:18:46.055438 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs.
Jan 17 12:18:46.056456 systemd-resolved[1479]: Positive Trust Anchors:
Jan 17 12:18:46.056474 systemd-resolved[1479]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
Jan 17 12:18:46.056535 systemd-resolved[1479]: 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 17 12:18:46.061749 systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
Jan 17 12:18:46.061949 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod.
Jan 17 12:18:46.063846 systemd[1]: modprobe@drm.service: Deactivated successfully.
Jan 17 12:18:46.065231 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm.
Jan 17 12:18:46.067851 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
Jan 17 12:18:46.068055 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore.
Jan 17 12:18:46.076850 systemd[1]: Finished ensure-sysext.service.
Jan 17 12:18:46.081922 systemd-resolved[1479]: Using system hostname 'ci-4081.3.0-a-1e82b03d4c'.
Jan 17 12:18:46.082827 systemd[1]: modprobe@loop.service: Deactivated successfully.
Jan 17 12:18:46.084665 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop.
Jan 17 12:18:46.086785 systemd[1]: Started systemd-resolved.service - Network Name Resolution.
Jan 17 12:18:46.096549 systemd[1]: Reached target network.target - Network.
Jan 17 12:18:46.097443 systemd[1]: Reached target network-online.target - Network is Online.
Jan 17 12:18:46.099882 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups.
Jan 17 12:18:46.100395 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
Jan 17 12:18:46.100485 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met.
Jan 17 12:18:46.112227 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization...
Jan 17 12:18:46.112785 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 17 12:18:46.134324 systemd-networkd[1222]: eth0: Gained IPv6LL
Jan 17 12:18:46.176886 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization.
Jan 17 12:18:46.177807 systemd[1]: Reached target sysinit.target - System Initialization.
Jan 17 12:18:46.178512 systemd[1]: Started motdgen.path - Watch for update engine configuration changes.
Jan 17 12:18:46.180519 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 17 12:18:46.180981 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories.
Jan 17 12:18:46.181539 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate).
Jan 17 12:18:46.181569 systemd[1]: Reached target paths.target - Path Units.
Jan 17 12:18:46.182680 systemd[1]: Reached target time-set.target - System Time Set.
Jan 17 12:18:46.184514 systemd[1]: Started logrotate.timer - Daily rotation of log files.
Jan 17 12:18:46.186349 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information..
Jan 17 12:18:46.187620 systemd[1]: Reached target timers.target - Timer Units.
Jan 17 12:18:46.193069 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket.
Jan 17 12:18:46.197686 systemd[1]: Starting docker.socket - Docker Socket for the API...
Jan 17 12:18:46.202434 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket.
Jan 17 12:18:46.207179 systemd[1]: Listening on docker.socket - Docker Socket for the API.
Jan 17 12:18:46.208751 systemd[1]: Reached target sockets.target - Socket Units.
Jan 17 12:18:46.210376 systemd[1]: Reached target basic.target - Basic System.
Jan 17 12:18:46.211140 systemd[1]: System is tainted: cgroupsv1
Jan 17 12:18:46.211207 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met.
Jan 17 12:18:46.211236 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met.
Jan 17 12:18:46.216101 systemd[1]: Starting containerd.service - containerd container runtime...
Jan 17 12:18:46.229219 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent...
Jan 17 12:18:46.238211 systemd[1]: Starting dbus.service - D-Bus System Message Bus...
Jan 17 12:18:46.243095 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit...
Jan 17 12:18:46.256803 systemd[1]: Starting extend-filesystems.service - Extend Filesystems...
Jan 17 12:18:46.257829 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 17 12:18:46.263071 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent...
Jan 17 12:18:46.266561 jq[1549]: false
Jan 17 12:18:46.280110 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd...
Jan 17 12:18:47.320121 coreos-metadata[1546]: Jan 17 12:18:46.281 INFO Fetching http://169.254.169.254/metadata/v1.json: Attempt #1
Jan 17 12:18:47.319841 systemd-timesyncd[1541]: Contacted time server 24.229.44.105:123 (0.flatcar.pool.ntp.org).
Jan 17 12:18:47.319902 systemd-timesyncd[1541]: Initial clock synchronization to Fri 2025-01-17 12:18:47.319167 UTC.
Jan 17 12:18:47.322945 systemd-resolved[1479]: Clock change detected. Flushing caches.
Jan 17 12:18:47.330026 coreos-metadata[1546]: Jan 17 12:18:47.329 INFO Fetch successful
Jan 17 12:18:47.333765 systemd[1]: Starting nvidia.service - NVIDIA Configure Service...
Jan 17 12:18:47.345344 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline...
Jan 17 12:18:47.356349 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys...
Jan 17 12:18:47.360147 extend-filesystems[1552]: Found loop4
Jan 17 12:18:47.360147 extend-filesystems[1552]: Found loop5
Jan 17 12:18:47.360147 extend-filesystems[1552]: Found loop6
Jan 17 12:18:47.360147 extend-filesystems[1552]: Found loop7
Jan 17 12:18:47.360147 extend-filesystems[1552]: Found vda
Jan 17 12:18:47.360147 extend-filesystems[1552]: Found vda1
Jan 17 12:18:47.360147 extend-filesystems[1552]: Found vda2
Jan 17 12:18:47.360147 extend-filesystems[1552]: Found vda3
Jan 17 12:18:47.360147 extend-filesystems[1552]: Found usr
Jan 17 12:18:47.360147 extend-filesystems[1552]: Found vda4
Jan 17 12:18:47.360147 extend-filesystems[1552]: Found vda6
Jan 17 12:18:47.360147 extend-filesystems[1552]: Found vda7
Jan 17 12:18:47.360147 extend-filesystems[1552]: Found vda9
Jan 17 12:18:47.360147 extend-filesystems[1552]: Checking size of /dev/vda9
Jan 17 12:18:47.366765 dbus-daemon[1548]: [system] SELinux support is enabled
Jan 17 12:18:47.377126 systemd[1]: Starting systemd-logind.service - User Login Management...
Jan 17 12:18:47.386947 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0).
Jan 17 12:18:47.402763 systemd[1]: Starting update-engine.service - Update Engine...
Jan 17 12:18:47.412294 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition...
Jan 17 12:18:47.414946 systemd[1]: Started dbus.service - D-Bus System Message Bus.
Jan 17 12:18:47.445608 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'.
Jan 17 12:18:47.445880 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped.
Jan 17 12:18:47.450941 update_engine[1569]: I20250117 12:18:47.450801  1569 main.cc:92] Flatcar Update Engine starting
Jan 17 12:18:47.464417 extend-filesystems[1552]: Resized partition /dev/vda9
Jan 17 12:18:47.466665 systemd[1]: Finished nvidia.service - NVIDIA Configure Service.
Jan 17 12:18:47.471385 update_engine[1569]: I20250117 12:18:47.471323  1569 update_check_scheduler.cc:74] Next update check in 11m4s
Jan 17 12:18:47.477465 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully.
Jan 17 12:18:47.477800 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline.
Jan 17 12:18:47.484663 extend-filesystems[1588]: resize2fs 1.47.1 (20-May-2024)
Jan 17 12:18:47.498046 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 15121403 blocks
Jan 17 12:18:47.507280 jq[1575]: true
Jan 17 12:18:47.515612 (ntainerd)[1595]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR
Jan 17 12:18:47.528207 systemd[1]: Started update-engine.service - Update Engine.
Jan 17 12:18:47.535851 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 17 12:18:47.535913 systemd[1]: Reached target system-config.target - Load system-provided cloud configs.
Jan 17 12:18:47.539969 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 17 12:18:47.541204 systemd[1]: user-configdrive.service - Load cloud-config from /media/configdrive was skipped because of an unmet condition check (ConditionKernelCommandLine=!flatcar.oem.id=digitalocean).
Jan 17 12:18:47.541254 systemd[1]: Reached target user-config.target - Load user-provided cloud configs.
Jan 17 12:18:47.549142 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details.
Jan 17 12:18:47.555561 systemd[1]: Started locksmithd.service - Cluster reboot manager.
Jan 17 12:18:47.561263 systemd[1]: motdgen.service: Deactivated successfully.
Jan 17 12:18:47.561631 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd.
Jan 17 12:18:47.578874 jq[1599]: true
Jan 17 12:18:47.620675 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent.
Jan 17 12:18:47.628022 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met.
Jan 17 12:18:47.679782 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (1223)
Jan 17 12:18:47.782997 kernel: EXT4-fs (vda9): resized filesystem to 15121403
Jan 17 12:18:47.818817 extend-filesystems[1588]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required
Jan 17 12:18:47.818817 extend-filesystems[1588]: old_desc_blocks = 1, new_desc_blocks = 8
Jan 17 12:18:47.818817 extend-filesystems[1588]: The filesystem on /dev/vda9 is now 15121403 (4k) blocks long.
Jan 17 12:18:47.809877 systemd[1]: extend-filesystems.service: Deactivated successfully.
Jan 17 12:18:47.822986 extend-filesystems[1552]: Resized filesystem in /dev/vda9
Jan 17 12:18:47.822986 extend-filesystems[1552]: Found vdb
Jan 17 12:18:47.810301 systemd[1]: Finished extend-filesystems.service - Extend Filesystems.
Jan 17 12:18:47.856629 systemd-logind[1564]: New seat seat0.
Jan 17 12:18:47.858870 locksmithd[1603]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot"
Jan 17 12:18:47.863164 bash[1630]: Updated "/home/core/.ssh/authorized_keys"
Jan 17 12:18:47.862791 systemd-logind[1564]: Watching system buttons on /dev/input/event1 (Power Button)
Jan 17 12:18:47.862819 systemd-logind[1564]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)
Jan 17 12:18:47.865491 systemd[1]: Started systemd-logind.service - User Login Management.
Jan 17 12:18:47.868591 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition.
Jan 17 12:18:47.888550 systemd[1]: Starting sshkeys.service...
Jan 17 12:18:47.939221 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys.
Jan 17 12:18:47.951623 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)...
Jan 17 12:18:48.054586 coreos-metadata[1637]: Jan 17 12:18:48.054 INFO Fetching http://169.254.169.254/metadata/v1.json: Attempt #1
Jan 17 12:18:48.065410 coreos-metadata[1637]: Jan 17 12:18:48.065 INFO Fetch successful
Jan 17 12:18:48.095509 unknown[1637]: wrote ssh authorized keys file for user: core
Jan 17 12:18:48.136571 update-ssh-keys[1646]: Updated "/home/core/.ssh/authorized_keys"
Jan 17 12:18:48.139051 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys).
Jan 17 12:18:48.145022 systemd[1]: Finished sshkeys.service.
Jan 17 12:18:48.288978 sshd_keygen[1576]: ssh-keygen: generating new host keys: RSA ECDSA ED25519
Jan 17 12:18:48.301440 containerd[1595]: time="2025-01-17T12:18:48.300651803Z" level=info msg="starting containerd" revision=174e0d1785eeda18dc2beba45e1d5a188771636b version=v1.7.21
Jan 17 12:18:48.330810 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys.
Jan 17 12:18:48.346928 systemd[1]: Starting issuegen.service - Generate /run/issue...
Jan 17 12:18:48.366426 containerd[1595]: time="2025-01-17T12:18:48.366363400Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
Jan 17 12:18:48.370626 containerd[1595]: time="2025-01-17T12:18:48.370561195Z" 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 17 12:18:48.370626 containerd[1595]: time="2025-01-17T12:18:48.370610971Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
Jan 17 12:18:48.370626 containerd[1595]: time="2025-01-17T12:18:48.370629435Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
Jan 17 12:18:48.370797 containerd[1595]: time="2025-01-17T12:18:48.370782466Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1
Jan 17 12:18:48.370855 containerd[1595]: time="2025-01-17T12:18:48.370800987Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1
Jan 17 12:18:48.370879 containerd[1595]: time="2025-01-17T12:18:48.370855022Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1
Jan 17 12:18:48.370879 containerd[1595]: time="2025-01-17T12:18:48.370869668Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
Jan 17 12:18:48.371204 containerd[1595]: time="2025-01-17T12:18:48.371174978Z" 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 17 12:18:48.371261 containerd[1595]: time="2025-01-17T12:18:48.371205726Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
Jan 17 12:18:48.371261 containerd[1595]: time="2025-01-17T12:18:48.371223448Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1
Jan 17 12:18:48.371261 containerd[1595]: time="2025-01-17T12:18:48.371233590Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
Jan 17 12:18:48.371338 containerd[1595]: time="2025-01-17T12:18:48.371323704Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
Jan 17 12:18:48.371582 containerd[1595]: time="2025-01-17T12:18:48.371566410Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
Jan 17 12:18:48.371754 containerd[1595]: time="2025-01-17T12:18:48.371736511Z" 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 17 12:18:48.371784 containerd[1595]: time="2025-01-17T12:18:48.371753981Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
Jan 17 12:18:48.371848 containerd[1595]: time="2025-01-17T12:18:48.371829473Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
Jan 17 12:18:48.371923 containerd[1595]: time="2025-01-17T12:18:48.371905726Z" level=info msg="metadata content store policy set" policy=shared
Jan 17 12:18:48.374747 systemd[1]: issuegen.service: Deactivated successfully.
Jan 17 12:18:48.376971 systemd[1]: Finished issuegen.service - Generate /run/issue.
Jan 17 12:18:48.387325 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions...
Jan 17 12:18:48.404122 containerd[1595]: time="2025-01-17T12:18:48.402342239Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
Jan 17 12:18:48.404122 containerd[1595]: time="2025-01-17T12:18:48.402410896Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
Jan 17 12:18:48.404122 containerd[1595]: time="2025-01-17T12:18:48.402429165Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1
Jan 17 12:18:48.404122 containerd[1595]: time="2025-01-17T12:18:48.402469315Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1
Jan 17 12:18:48.404122 containerd[1595]: time="2025-01-17T12:18:48.402496008Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
Jan 17 12:18:48.404122 containerd[1595]: time="2025-01-17T12:18:48.402667457Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
Jan 17 12:18:48.404122 containerd[1595]: time="2025-01-17T12:18:48.403004765Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
Jan 17 12:18:48.404122 containerd[1595]: time="2025-01-17T12:18:48.403187301Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2
Jan 17 12:18:48.404122 containerd[1595]: time="2025-01-17T12:18:48.403208717Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1
Jan 17 12:18:48.404122 containerd[1595]: time="2025-01-17T12:18:48.403222028Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1
Jan 17 12:18:48.404122 containerd[1595]: time="2025-01-17T12:18:48.403260950Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
Jan 17 12:18:48.404122 containerd[1595]: time="2025-01-17T12:18:48.403305822Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
Jan 17 12:18:48.404122 containerd[1595]: time="2025-01-17T12:18:48.403322478Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
Jan 17 12:18:48.404122 containerd[1595]: time="2025-01-17T12:18:48.403336982Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
Jan 17 12:18:48.404779 containerd[1595]: time="2025-01-17T12:18:48.403351418Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
Jan 17 12:18:48.404779 containerd[1595]: time="2025-01-17T12:18:48.403365145Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
Jan 17 12:18:48.404779 containerd[1595]: time="2025-01-17T12:18:48.403377111Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
Jan 17 12:18:48.404779 containerd[1595]: time="2025-01-17T12:18:48.403387984Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
Jan 17 12:18:48.404779 containerd[1595]: time="2025-01-17T12:18:48.403410151Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
Jan 17 12:18:48.404779 containerd[1595]: time="2025-01-17T12:18:48.403423037Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
Jan 17 12:18:48.404779 containerd[1595]: time="2025-01-17T12:18:48.403434641Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
Jan 17 12:18:48.404779 containerd[1595]: time="2025-01-17T12:18:48.403446856Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
Jan 17 12:18:48.404779 containerd[1595]: time="2025-01-17T12:18:48.403458933Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
Jan 17 12:18:48.404779 containerd[1595]: time="2025-01-17T12:18:48.403471489Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
Jan 17 12:18:48.404779 containerd[1595]: time="2025-01-17T12:18:48.403482722Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
Jan 17 12:18:48.404779 containerd[1595]: time="2025-01-17T12:18:48.403496439Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
Jan 17 12:18:48.404779 containerd[1595]: time="2025-01-17T12:18:48.403508558Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1
Jan 17 12:18:48.404779 containerd[1595]: time="2025-01-17T12:18:48.403553010Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1
Jan 17 12:18:48.405072 containerd[1595]: time="2025-01-17T12:18:48.403567204Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
Jan 17 12:18:48.405072 containerd[1595]: time="2025-01-17T12:18:48.403581014Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1
Jan 17 12:18:48.405072 containerd[1595]: time="2025-01-17T12:18:48.403605465Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
Jan 17 12:18:48.405072 containerd[1595]: time="2025-01-17T12:18:48.403625263Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1
Jan 17 12:18:48.405072 containerd[1595]: time="2025-01-17T12:18:48.403646645Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1
Jan 17 12:18:48.405072 containerd[1595]: time="2025-01-17T12:18:48.403669719Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
Jan 17 12:18:48.405072 containerd[1595]: time="2025-01-17T12:18:48.403683368Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
Jan 17 12:18:48.405072 containerd[1595]: time="2025-01-17T12:18:48.403735402Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
Jan 17 12:18:48.405072 containerd[1595]: time="2025-01-17T12:18:48.403753365Z" 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 17 12:18:48.405072 containerd[1595]: time="2025-01-17T12:18:48.403764354Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
Jan 17 12:18:48.405072 containerd[1595]: time="2025-01-17T12:18:48.403776241Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1
Jan 17 12:18:48.405072 containerd[1595]: time="2025-01-17T12:18:48.403785615Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
Jan 17 12:18:48.405072 containerd[1595]: time="2025-01-17T12:18:48.403797567Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1
Jan 17 12:18:48.405072 containerd[1595]: time="2025-01-17T12:18:48.403807417Z" level=info msg="NRI interface is disabled by configuration."
Jan 17 12:18:48.405420 containerd[1595]: time="2025-01-17T12:18:48.403817207Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1
Jan 17 12:18:48.405608 containerd[1595]: time="2025-01-17T12:18:48.405507222Z" 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:false] 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:false 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 17 12:18:48.405983 containerd[1595]: time="2025-01-17T12:18:48.405960829Z" level=info msg="Connect containerd service"
Jan 17 12:18:48.406102 containerd[1595]: time="2025-01-17T12:18:48.406080047Z" level=info msg="using legacy CRI server"
Jan 17 12:18:48.406175 containerd[1595]: time="2025-01-17T12:18:48.406164058Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this"
Jan 17 12:18:48.406342 containerd[1595]: time="2025-01-17T12:18:48.406328804Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\""
Jan 17 12:18:48.407189 containerd[1595]: time="2025-01-17T12:18:48.407163383Z" 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 17 12:18:48.407420 containerd[1595]: time="2025-01-17T12:18:48.407375309Z" level=info msg="Start subscribing containerd event"
Jan 17 12:18:48.407525 containerd[1595]: time="2025-01-17T12:18:48.407511947Z" level=info msg="Start recovering state"
Jan 17 12:18:48.408043 containerd[1595]: time="2025-01-17T12:18:48.407882175Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc
Jan 17 12:18:48.408144 containerd[1595]: time="2025-01-17T12:18:48.408127710Z" level=info msg=serving... address=/run/containerd/containerd.sock
Jan 17 12:18:48.408173 containerd[1595]: time="2025-01-17T12:18:48.408015554Z" level=info msg="Start event monitor"
Jan 17 12:18:48.408173 containerd[1595]: time="2025-01-17T12:18:48.408164421Z" level=info msg="Start snapshots syncer"
Jan 17 12:18:48.409927 containerd[1595]: time="2025-01-17T12:18:48.408174906Z" level=info msg="Start cni network conf syncer for default"
Jan 17 12:18:48.409927 containerd[1595]: time="2025-01-17T12:18:48.408183419Z" level=info msg="Start streaming server"
Jan 17 12:18:48.409927 containerd[1595]: time="2025-01-17T12:18:48.408237045Z" level=info msg="containerd successfully booted in 0.111477s"
Jan 17 12:18:48.408394 systemd[1]: Started containerd.service - containerd container runtime.
Jan 17 12:18:48.425648 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions.
Jan 17 12:18:48.438564 systemd[1]: Started getty@tty1.service - Getty on tty1.
Jan 17 12:18:48.452461 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0.
Jan 17 12:18:48.453329 systemd[1]: Reached target getty.target - Login Prompts.
Jan 17 12:18:49.052338 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent.
Jan 17 12:18:49.055759 systemd[1]: Reached target multi-user.target - Multi-User System.
Jan 17 12:18:49.059029 (kubelet)[1684]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS
Jan 17 12:18:49.059617 systemd[1]: Startup finished in 6.198s (kernel) + 6.269s (userspace) = 12.467s.
Jan 17 12:18:49.952145 kubelet[1684]: E0117 12:18:49.951945    1684 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 17 12:18:49.956399 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE
Jan 17 12:18:49.956687 systemd[1]: kubelet.service: Failed with result 'exit-code'.
Jan 17 12:18:49.975241 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd.
Jan 17 12:18:49.980492 systemd[1]: Started sshd@0-64.227.109.251:22-139.178.68.195:38224.service - OpenSSH per-connection server daemon (139.178.68.195:38224).
Jan 17 12:18:50.104603 sshd[1697]: Accepted publickey for core from 139.178.68.195 port 38224 ssh2: RSA SHA256:r8mW/Iv+p7nZqo0WbSWD5Er765ayjb8xE8XAH1LjSMM
Jan 17 12:18:50.107436 sshd[1697]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Jan 17 12:18:50.123499 systemd-logind[1564]: New session 1 of user core.
Jan 17 12:18:50.123869 systemd[1]: Created slice user-500.slice - User Slice of UID 500.
Jan 17 12:18:50.130551 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500...
Jan 17 12:18:50.158510 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500.
Jan 17 12:18:50.172592 systemd[1]: Starting user@500.service - User Manager for UID 500...
Jan 17 12:18:50.178884 (systemd)[1703]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0)
Jan 17 12:18:50.322652 systemd[1703]: Queued start job for default target default.target.
Jan 17 12:18:50.323304 systemd[1703]: Created slice app.slice - User Application Slice.
Jan 17 12:18:50.323341 systemd[1703]: Reached target paths.target - Paths.
Jan 17 12:18:50.323361 systemd[1703]: Reached target timers.target - Timers.
Jan 17 12:18:50.331278 systemd[1703]: Starting dbus.socket - D-Bus User Message Bus Socket...
Jan 17 12:18:50.343901 systemd[1703]: Listening on dbus.socket - D-Bus User Message Bus Socket.
Jan 17 12:18:50.343975 systemd[1703]: Reached target sockets.target - Sockets.
Jan 17 12:18:50.343990 systemd[1703]: Reached target basic.target - Basic System.
Jan 17 12:18:50.344042 systemd[1703]: Reached target default.target - Main User Target.
Jan 17 12:18:50.344075 systemd[1703]: Startup finished in 156ms.
Jan 17 12:18:50.344643 systemd[1]: Started user@500.service - User Manager for UID 500.
Jan 17 12:18:50.352734 systemd[1]: Started session-1.scope - Session 1 of User core.
Jan 17 12:18:50.425581 systemd[1]: Started sshd@1-64.227.109.251:22-139.178.68.195:38234.service - OpenSSH per-connection server daemon (139.178.68.195:38234).
Jan 17 12:18:50.473715 sshd[1715]: Accepted publickey for core from 139.178.68.195 port 38234 ssh2: RSA SHA256:r8mW/Iv+p7nZqo0WbSWD5Er765ayjb8xE8XAH1LjSMM
Jan 17 12:18:50.476461 sshd[1715]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Jan 17 12:18:50.483185 systemd-logind[1564]: New session 2 of user core.
Jan 17 12:18:50.489865 systemd[1]: Started session-2.scope - Session 2 of User core.
Jan 17 12:18:50.556957 sshd[1715]: pam_unix(sshd:session): session closed for user core
Jan 17 12:18:50.567560 systemd[1]: Started sshd@2-64.227.109.251:22-139.178.68.195:38240.service - OpenSSH per-connection server daemon (139.178.68.195:38240).
Jan 17 12:18:50.568223 systemd[1]: sshd@1-64.227.109.251:22-139.178.68.195:38234.service: Deactivated successfully.
Jan 17 12:18:50.572384 systemd[1]: session-2.scope: Deactivated successfully.
Jan 17 12:18:50.574775 systemd-logind[1564]: Session 2 logged out. Waiting for processes to exit.
Jan 17 12:18:50.576819 systemd-logind[1564]: Removed session 2.
Jan 17 12:18:50.612255 sshd[1720]: Accepted publickey for core from 139.178.68.195 port 38240 ssh2: RSA SHA256:r8mW/Iv+p7nZqo0WbSWD5Er765ayjb8xE8XAH1LjSMM
Jan 17 12:18:50.614179 sshd[1720]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Jan 17 12:18:50.620946 systemd-logind[1564]: New session 3 of user core.
Jan 17 12:18:50.628647 systemd[1]: Started session-3.scope - Session 3 of User core.
Jan 17 12:18:50.688428 sshd[1720]: pam_unix(sshd:session): session closed for user core
Jan 17 12:18:50.699727 systemd[1]: Started sshd@3-64.227.109.251:22-139.178.68.195:38242.service - OpenSSH per-connection server daemon (139.178.68.195:38242).
Jan 17 12:18:50.700627 systemd[1]: sshd@2-64.227.109.251:22-139.178.68.195:38240.service: Deactivated successfully.
Jan 17 12:18:50.714937 systemd-logind[1564]: Session 3 logged out. Waiting for processes to exit.
Jan 17 12:18:50.715430 systemd[1]: session-3.scope: Deactivated successfully.
Jan 17 12:18:50.717876 systemd-logind[1564]: Removed session 3.
Jan 17 12:18:50.755699 sshd[1728]: Accepted publickey for core from 139.178.68.195 port 38242 ssh2: RSA SHA256:r8mW/Iv+p7nZqo0WbSWD5Er765ayjb8xE8XAH1LjSMM
Jan 17 12:18:50.757950 sshd[1728]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Jan 17 12:18:50.763809 systemd-logind[1564]: New session 4 of user core.
Jan 17 12:18:50.775631 systemd[1]: Started session-4.scope - Session 4 of User core.
Jan 17 12:18:50.843427 sshd[1728]: pam_unix(sshd:session): session closed for user core
Jan 17 12:18:50.848179 systemd[1]: sshd@3-64.227.109.251:22-139.178.68.195:38242.service: Deactivated successfully.
Jan 17 12:18:50.852070 systemd[1]: session-4.scope: Deactivated successfully.
Jan 17 12:18:50.853062 systemd-logind[1564]: Session 4 logged out. Waiting for processes to exit.
Jan 17 12:18:50.854309 systemd-logind[1564]: Removed session 4.
Jan 17 12:18:50.862432 systemd[1]: Started sshd@4-64.227.109.251:22-139.178.68.195:38250.service - OpenSSH per-connection server daemon (139.178.68.195:38250).
Jan 17 12:18:50.936940 sshd[1739]: Accepted publickey for core from 139.178.68.195 port 38250 ssh2: RSA SHA256:r8mW/Iv+p7nZqo0WbSWD5Er765ayjb8xE8XAH1LjSMM
Jan 17 12:18:50.939083 sshd[1739]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Jan 17 12:18:50.944672 systemd-logind[1564]: New session 5 of user core.
Jan 17 12:18:50.954555 systemd[1]: Started session-5.scope - Session 5 of User core.
Jan 17 12:18:51.040002 sudo[1743]:     core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh
Jan 17 12:18:51.040341 sudo[1743]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500)
Jan 17 12:18:51.918424 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent.
Jan 17 12:18:51.930454 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent...
Jan 17 12:18:51.959489 systemd[1]: Reloading requested from client PID 1782 ('systemctl') (unit session-5.scope)...
Jan 17 12:18:51.959508 systemd[1]: Reloading...
Jan 17 12:18:52.093131 zram_generator::config[1822]: No configuration found.
Jan 17 12:18:52.267779 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 17 12:18:52.351820 systemd[1]: Reloading finished in 391 ms.
Jan 17 12:18:52.408988 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM
Jan 17 12:18:52.409167 systemd[1]: kubelet.service: Failed with result 'signal'.
Jan 17 12:18:52.409833 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent.
Jan 17 12:18:52.423304 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent...
Jan 17 12:18:52.557350 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent.
Jan 17 12:18:52.564054 (kubelet)[1886]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS
Jan 17 12:18:52.630366 kubelet[1886]: 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 17 12:18:52.632120 kubelet[1886]: 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 17 12:18:52.632120 kubelet[1886]: 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 17 12:18:52.632120 kubelet[1886]: I0117 12:18:52.630839    1886 server.go:204] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime"
Jan 17 12:18:53.011117 kubelet[1886]: I0117 12:18:53.010972    1886 server.go:487] "Kubelet version" kubeletVersion="v1.29.2"
Jan 17 12:18:53.011255 kubelet[1886]: I0117 12:18:53.011242    1886 server.go:489] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK=""
Jan 17 12:18:53.011534 kubelet[1886]: I0117 12:18:53.011521    1886 server.go:919] "Client rotation is on, will bootstrap in background"
Jan 17 12:18:53.037238 kubelet[1886]: I0117 12:18:53.037201    1886 dynamic_cafile_content.go:157] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt"
Jan 17 12:18:53.052448 kubelet[1886]: I0117 12:18:53.052408    1886 server.go:745] "--cgroups-per-qos enabled, but --cgroup-root was not specified.  defaulting to /"
Jan 17 12:18:53.053825 kubelet[1886]: I0117 12:18:53.053764    1886 container_manager_linux.go:265] "Container manager verified user specified cgroup-root exists" cgroupRoot=[]
Jan 17 12:18:53.054813 kubelet[1886]: I0117 12:18:53.054132    1886 container_manager_linux.go:270] "Creating Container Manager object based on Node Config" nodeConfig={"RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"cgroupfs","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"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}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null}
Jan 17 12:18:53.054813 kubelet[1886]: I0117 12:18:53.054177    1886 topology_manager.go:138] "Creating topology manager with none policy"
Jan 17 12:18:53.054813 kubelet[1886]: I0117 12:18:53.054193    1886 container_manager_linux.go:301] "Creating device plugin manager"
Jan 17 12:18:53.054813 kubelet[1886]: I0117 12:18:53.054362    1886 state_mem.go:36] "Initialized new in-memory state store"
Jan 17 12:18:53.054813 kubelet[1886]: I0117 12:18:53.054497    1886 kubelet.go:396] "Attempting to sync node with API server"
Jan 17 12:18:53.054813 kubelet[1886]: I0117 12:18:53.054518    1886 kubelet.go:301] "Adding static pod path" path="/etc/kubernetes/manifests"
Jan 17 12:18:53.054813 kubelet[1886]: I0117 12:18:53.054555    1886 kubelet.go:312] "Adding apiserver pod source"
Jan 17 12:18:53.056176 kubelet[1886]: I0117 12:18:53.054575    1886 apiserver.go:42] "Waiting for node sync before watching apiserver pods"
Jan 17 12:18:53.056176 kubelet[1886]: E0117 12:18:53.055278    1886 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:18:53.056176 kubelet[1886]: E0117 12:18:53.055328    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:18:53.056697 kubelet[1886]: I0117 12:18:53.056604    1886 kuberuntime_manager.go:258] "Container runtime initialized" containerRuntime="containerd" version="v1.7.21" apiVersion="v1"
Jan 17 12:18:53.060203 kubelet[1886]: I0117 12:18:53.060151    1886 kubelet.go:809] "Not starting ClusterTrustBundle informer because we are in static kubelet mode"
Jan 17 12:18:53.060450 kubelet[1886]: W0117 12:18:53.060434    1886 probe.go:268] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating.
Jan 17 12:18:53.061203 kubelet[1886]: I0117 12:18:53.061183    1886 server.go:1256] "Started kubelet"
Jan 17 12:18:53.062584 kubelet[1886]: I0117 12:18:53.062559    1886 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer"
Jan 17 12:18:53.074691 kubelet[1886]: I0117 12:18:53.074435    1886 server.go:162] "Starting to listen" address="0.0.0.0" port=10250
Jan 17 12:18:53.075500 kubelet[1886]: I0117 12:18:53.075476    1886 server.go:461] "Adding debug handlers to kubelet server"
Jan 17 12:18:53.081859 kubelet[1886]: I0117 12:18:53.081816    1886 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10
Jan 17 12:18:53.083654 kubelet[1886]: I0117 12:18:53.083629    1886 server.go:233] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock"
Jan 17 12:18:53.088240 kubelet[1886]: I0117 12:18:53.088163    1886 volume_manager.go:291] "Starting Kubelet Volume Manager"
Jan 17 12:18:53.089731 kubelet[1886]: E0117 12:18:53.089697    1886 event.go:346] "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:{64.227.109.251.181b7a19eda3aa9a  default    0 0001-01-01 00:00:00 +0000 UTC <nil> <nil> map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:64.227.109.251,UID:64.227.109.251,APIVersion:,ResourceVersion:,FieldPath:,},Reason:Starting,Message:Starting kubelet.,Source:EventSource{Component:kubelet,Host:64.227.109.251,},FirstTimestamp:2025-01-17 12:18:53.061155482 +0000 UTC m=+0.490934875,LastTimestamp:2025-01-17 12:18:53.061155482 +0000 UTC m=+0.490934875,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:64.227.109.251,}"
Jan 17 12:18:53.090048 kubelet[1886]: W0117 12:18:53.090031    1886 reflector.go:539] vendor/k8s.io/client-go/informers/factory.go:159: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope
Jan 17 12:18:53.090140 kubelet[1886]: E0117 12:18:53.090132    1886 reflector.go:147] vendor/k8s.io/client-go/informers/factory.go:159: 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 17 12:18:53.090251 kubelet[1886]: W0117 12:18:53.090241    1886 reflector.go:539] vendor/k8s.io/client-go/informers/factory.go:159: failed to list *v1.Node: nodes "64.227.109.251" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope
Jan 17 12:18:53.090302 kubelet[1886]: E0117 12:18:53.090295    1886 reflector.go:147] vendor/k8s.io/client-go/informers/factory.go:159: Failed to watch *v1.Node: failed to list *v1.Node: nodes "64.227.109.251" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope
Jan 17 12:18:53.091883 kubelet[1886]: I0117 12:18:53.091846    1886 desired_state_of_world_populator.go:151] "Desired state populator starts to run"
Jan 17 12:18:53.092640 kubelet[1886]: E0117 12:18:53.092056    1886 kubelet.go:1462] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem"
Jan 17 12:18:53.092719 kubelet[1886]: I0117 12:18:53.092413    1886 reconciler_new.go:29] "Reconciler: start to sync state"
Jan 17 12:18:53.092989 kubelet[1886]: I0117 12:18:53.092974    1886 factory.go:221] Registration of the systemd container factory successfully
Jan 17 12:18:53.093228 kubelet[1886]: I0117 12:18:53.093205    1886 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 17 12:18:53.095884 kubelet[1886]: I0117 12:18:53.095853    1886 factory.go:221] Registration of the containerd container factory successfully
Jan 17 12:18:53.114332 kubelet[1886]: E0117 12:18:53.114298    1886 controller.go:145] "Failed to ensure lease exists, will retry" err="leases.coordination.k8s.io \"64.227.109.251\" is forbidden: User \"system:anonymous\" cannot get resource \"leases\" in API group \"coordination.k8s.io\" in the namespace \"kube-node-lease\"" interval="200ms"
Jan 17 12:18:53.139426 kubelet[1886]: W0117 12:18:53.139390    1886 reflector.go:539] vendor/k8s.io/client-go/informers/factory.go:159: 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 17 12:18:53.139605 kubelet[1886]: E0117 12:18:53.139592    1886 reflector.go:147] vendor/k8s.io/client-go/informers/factory.go:159: 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 17 12:18:53.142715 kubelet[1886]: I0117 12:18:53.142682    1886 cpu_manager.go:214] "Starting CPU manager" policy="none"
Jan 17 12:18:53.143274 kubelet[1886]: I0117 12:18:53.143253    1886 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s"
Jan 17 12:18:53.143413 kubelet[1886]: I0117 12:18:53.143403    1886 state_mem.go:36] "Initialized new in-memory state store"
Jan 17 12:18:53.148197 kubelet[1886]: I0117 12:18:53.148162    1886 policy_none.go:49] "None policy: Start"
Jan 17 12:18:53.153791 kubelet[1886]: I0117 12:18:53.153296    1886 memory_manager.go:170] "Starting memorymanager" policy="None"
Jan 17 12:18:53.153791 kubelet[1886]: I0117 12:18:53.153333    1886 state_mem.go:35] "Initializing new in-memory state store"
Jan 17 12:18:53.163174 kubelet[1886]: I0117 12:18:53.163056    1886 manager.go:479] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found"
Jan 17 12:18:53.169203 kubelet[1886]: I0117 12:18:53.169167    1886 plugin_manager.go:118] "Starting Kubelet Plugin Manager"
Jan 17 12:18:53.170744 kubelet[1886]: E0117 12:18:53.170660    1886 eviction_manager.go:282] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"64.227.109.251\" not found"
Jan 17 12:18:53.177611 kubelet[1886]: I0117 12:18:53.177311    1886 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4"
Jan 17 12:18:53.179262 kubelet[1886]: I0117 12:18:53.179237    1886 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6"
Jan 17 12:18:53.179405 kubelet[1886]: I0117 12:18:53.179397    1886 status_manager.go:217] "Starting to sync pod status with apiserver"
Jan 17 12:18:53.179815 kubelet[1886]: I0117 12:18:53.179459    1886 kubelet.go:2329] "Starting kubelet main sync loop"
Jan 17 12:18:53.179815 kubelet[1886]: E0117 12:18:53.179527    1886 kubelet.go:2353] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful"
Jan 17 12:18:53.190176 kubelet[1886]: I0117 12:18:53.190150    1886 kubelet_node_status.go:73] "Attempting to register node" node="64.227.109.251"
Jan 17 12:18:53.201037 kubelet[1886]: I0117 12:18:53.200991    1886 kubelet_node_status.go:76] "Successfully registered node" node="64.227.109.251"
Jan 17 12:18:53.224112 kubelet[1886]: E0117 12:18:53.224049    1886 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"64.227.109.251\" not found"
Jan 17 12:18:53.324486 kubelet[1886]: E0117 12:18:53.324337    1886 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"64.227.109.251\" not found"
Jan 17 12:18:53.424897 kubelet[1886]: E0117 12:18:53.424838    1886 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"64.227.109.251\" not found"
Jan 17 12:18:53.525709 kubelet[1886]: E0117 12:18:53.525658    1886 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"64.227.109.251\" not found"
Jan 17 12:18:53.625951 kubelet[1886]: E0117 12:18:53.625870    1886 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"64.227.109.251\" not found"
Jan 17 12:18:53.726866 kubelet[1886]: E0117 12:18:53.726799    1886 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"64.227.109.251\" not found"
Jan 17 12:18:53.827864 kubelet[1886]: E0117 12:18:53.827804    1886 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"64.227.109.251\" not found"
Jan 17 12:18:53.929169 kubelet[1886]: E0117 12:18:53.928991    1886 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"64.227.109.251\" not found"
Jan 17 12:18:54.016412 kubelet[1886]: I0117 12:18:54.016078    1886 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials"
Jan 17 12:18:54.016412 kubelet[1886]: W0117 12:18:54.016349    1886 reflector.go:462] vendor/k8s.io/client-go/informers/factory.go:159: watch of *v1.Node ended with: very short watch: vendor/k8s.io/client-go/informers/factory.go:159: Unexpected watch close - watch lasted less than a second and no items received
Jan 17 12:18:54.016412 kubelet[1886]: W0117 12:18:54.016385    1886 reflector.go:462] vendor/k8s.io/client-go/informers/factory.go:159: watch of *v1.RuntimeClass ended with: very short watch: vendor/k8s.io/client-go/informers/factory.go:159: Unexpected watch close - watch lasted less than a second and no items received
Jan 17 12:18:54.030576 kubelet[1886]: I0117 12:18:54.030336    1886 kuberuntime_manager.go:1529] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24"
Jan 17 12:18:54.030878 containerd[1595]: time="2025-01-17T12:18:54.030768983Z" level=info msg="No cni config template is specified, wait for other system components to drop the config."
Jan 17 12:18:54.032197 kubelet[1886]: I0117 12:18:54.031672    1886 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24"
Jan 17 12:18:54.055915 kubelet[1886]: I0117 12:18:54.055828    1886 apiserver.go:52] "Watching apiserver"
Jan 17 12:18:54.056196 kubelet[1886]: E0117 12:18:54.056153    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:18:54.067411 kubelet[1886]: I0117 12:18:54.067364    1886 topology_manager.go:215] "Topology Admit Handler" podUID="3529fb59-42fe-41c2-9ac8-618d1b93d2ad" podNamespace="kube-flannel" podName="kube-flannel-ds-lccvm"
Jan 17 12:18:54.067598 kubelet[1886]: I0117 12:18:54.067474    1886 topology_manager.go:215] "Topology Admit Handler" podUID="2a8a88b2-2206-40ab-9b7d-e48d44e9f4a3" podNamespace="kube-system" podName="kube-proxy-wtt8t"
Jan 17 12:18:54.091777 sudo[1743]: pam_unix(sudo:session): session closed for user root
Jan 17 12:18:54.093195 kubelet[1886]: I0117 12:18:54.092918    1886 desired_state_of_world_populator.go:159] "Finished populating initial desired state of world"
Jan 17 12:18:54.096232 sshd[1739]: pam_unix(sshd:session): session closed for user core
Jan 17 12:18:54.099048 kubelet[1886]: I0117 12:18:54.098689    1886 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/2a8a88b2-2206-40ab-9b7d-e48d44e9f4a3-lib-modules\") pod \"kube-proxy-wtt8t\" (UID: \"2a8a88b2-2206-40ab-9b7d-e48d44e9f4a3\") " pod="kube-system/kube-proxy-wtt8t"
Jan 17 12:18:54.099048 kubelet[1886]: I0117 12:18:54.098735    1886 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/3529fb59-42fe-41c2-9ac8-618d1b93d2ad-cni\") pod \"kube-flannel-ds-lccvm\" (UID: \"3529fb59-42fe-41c2-9ac8-618d1b93d2ad\") " pod="kube-flannel/kube-flannel-ds-lccvm"
Jan 17 12:18:54.099048 kubelet[1886]: I0117 12:18:54.098755    1886 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/3529fb59-42fe-41c2-9ac8-618d1b93d2ad-xtables-lock\") pod \"kube-flannel-ds-lccvm\" (UID: \"3529fb59-42fe-41c2-9ac8-618d1b93d2ad\") " pod="kube-flannel/kube-flannel-ds-lccvm"
Jan 17 12:18:54.099048 kubelet[1886]: I0117 12:18:54.098776    1886 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/2a8a88b2-2206-40ab-9b7d-e48d44e9f4a3-kube-proxy\") pod \"kube-proxy-wtt8t\" (UID: \"2a8a88b2-2206-40ab-9b7d-e48d44e9f4a3\") " pod="kube-system/kube-proxy-wtt8t"
Jan 17 12:18:54.099048 kubelet[1886]: I0117 12:18:54.098803    1886 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/2a8a88b2-2206-40ab-9b7d-e48d44e9f4a3-xtables-lock\") pod \"kube-proxy-wtt8t\" (UID: \"2a8a88b2-2206-40ab-9b7d-e48d44e9f4a3\") " pod="kube-system/kube-proxy-wtt8t"
Jan 17 12:18:54.099684 kubelet[1886]: I0117 12:18:54.098822    1886 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-xvvns\" (UniqueName: \"kubernetes.io/projected/2a8a88b2-2206-40ab-9b7d-e48d44e9f4a3-kube-api-access-xvvns\") pod \"kube-proxy-wtt8t\" (UID: \"2a8a88b2-2206-40ab-9b7d-e48d44e9f4a3\") " pod="kube-system/kube-proxy-wtt8t"
Jan 17 12:18:54.099684 kubelet[1886]: I0117 12:18:54.098839    1886 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/3529fb59-42fe-41c2-9ac8-618d1b93d2ad-run\") pod \"kube-flannel-ds-lccvm\" (UID: \"3529fb59-42fe-41c2-9ac8-618d1b93d2ad\") " pod="kube-flannel/kube-flannel-ds-lccvm"
Jan 17 12:18:54.099684 kubelet[1886]: I0117 12:18:54.098859    1886 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/3529fb59-42fe-41c2-9ac8-618d1b93d2ad-cni-plugin\") pod \"kube-flannel-ds-lccvm\" (UID: \"3529fb59-42fe-41c2-9ac8-618d1b93d2ad\") " pod="kube-flannel/kube-flannel-ds-lccvm"
Jan 17 12:18:54.099684 kubelet[1886]: I0117 12:18:54.098878    1886 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/3529fb59-42fe-41c2-9ac8-618d1b93d2ad-flannel-cfg\") pod \"kube-flannel-ds-lccvm\" (UID: \"3529fb59-42fe-41c2-9ac8-618d1b93d2ad\") " pod="kube-flannel/kube-flannel-ds-lccvm"
Jan 17 12:18:54.099684 kubelet[1886]: I0117 12:18:54.098898    1886 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-cxztb\" (UniqueName: \"kubernetes.io/projected/3529fb59-42fe-41c2-9ac8-618d1b93d2ad-kube-api-access-cxztb\") pod \"kube-flannel-ds-lccvm\" (UID: \"3529fb59-42fe-41c2-9ac8-618d1b93d2ad\") " pod="kube-flannel/kube-flannel-ds-lccvm"
Jan 17 12:18:54.101668 systemd[1]: sshd@4-64.227.109.251:22-139.178.68.195:38250.service: Deactivated successfully.
Jan 17 12:18:54.106675 systemd[1]: session-5.scope: Deactivated successfully.
Jan 17 12:18:54.108327 systemd-logind[1564]: Session 5 logged out. Waiting for processes to exit.
Jan 17 12:18:54.109716 systemd-logind[1564]: Removed session 5.
Jan 17 12:18:54.370920 kubelet[1886]: E0117 12:18:54.370326    1886 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.3 67.207.67.2 67.207.67.3"
Jan 17 12:18:54.371474 containerd[1595]: time="2025-01-17T12:18:54.371424606Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-wtt8t,Uid:2a8a88b2-2206-40ab-9b7d-e48d44e9f4a3,Namespace:kube-system,Attempt:0,}"
Jan 17 12:18:54.371562 kubelet[1886]: E0117 12:18:54.371476    1886 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.3 67.207.67.2 67.207.67.3"
Jan 17 12:18:54.371904 containerd[1595]: time="2025-01-17T12:18:54.371866790Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-lccvm,Uid:3529fb59-42fe-41c2-9ac8-618d1b93d2ad,Namespace:kube-flannel,Attempt:0,}"
Jan 17 12:18:54.976578 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2986657042.mount: Deactivated successfully.
Jan 17 12:18:54.988192 containerd[1595]: time="2025-01-17T12:18:54.988078910Z" 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 17 12:18:54.990116 containerd[1595]: time="2025-01-17T12:18:54.989622007Z" 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 17 12:18:54.990659 containerd[1595]: time="2025-01-17T12:18:54.990633226Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=312056"
Jan 17 12:18:54.990815 containerd[1595]: time="2025-01-17T12:18:54.990794095Z" 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 17 12:18:54.990951 containerd[1595]: time="2025-01-17T12:18:54.990938068Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0"
Jan 17 12:18:54.994696 containerd[1595]: time="2025-01-17T12:18:54.994637868Z" 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 17 12:18:54.997650 containerd[1595]: time="2025-01-17T12:18:54.995797400Z" 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 624.095959ms"
Jan 17 12:18:54.997650 containerd[1595]: time="2025-01-17T12:18:54.997411540Z" 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 625.469411ms"
Jan 17 12:18:55.057220 kubelet[1886]: E0117 12:18:55.056790    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:18:55.137046 containerd[1595]: time="2025-01-17T12:18:55.136938422Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1
Jan 17 12:18:55.137046 containerd[1595]: time="2025-01-17T12:18:55.136998929Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1
Jan 17 12:18:55.138389 containerd[1595]: time="2025-01-17T12:18:55.137987043Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jan 17 12:18:55.138578 containerd[1595]: time="2025-01-17T12:18:55.138249694Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jan 17 12:18:55.148406 containerd[1595]: time="2025-01-17T12:18:55.146603005Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1
Jan 17 12:18:55.148406 containerd[1595]: time="2025-01-17T12:18:55.146669647Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1
Jan 17 12:18:55.148406 containerd[1595]: time="2025-01-17T12:18:55.146709188Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jan 17 12:18:55.148896 containerd[1595]: time="2025-01-17T12:18:55.148780387Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jan 17 12:18:55.299082 containerd[1595]: time="2025-01-17T12:18:55.298840425Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-wtt8t,Uid:2a8a88b2-2206-40ab-9b7d-e48d44e9f4a3,Namespace:kube-system,Attempt:0,} returns sandbox id \"e85983eafc1367750eb42b597ee65d9e70d1998aac592c72414716186b924d7c\""
Jan 17 12:18:55.300573 containerd[1595]: time="2025-01-17T12:18:55.300538560Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-lccvm,Uid:3529fb59-42fe-41c2-9ac8-618d1b93d2ad,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"dc76632e7945e6fc5d4c8b3ec19445079aa8c9a7cd47367f0eb643c1df1a9cf9\""
Jan 17 12:18:55.303040 kubelet[1886]: E0117 12:18:55.302594    1886 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.3 67.207.67.2 67.207.67.3"
Jan 17 12:18:55.303965 kubelet[1886]: E0117 12:18:55.303827    1886 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.3 67.207.67.2 67.207.67.3"
Jan 17 12:18:55.306002 containerd[1595]: time="2025-01-17T12:18:55.305972093Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.29.13\""
Jan 17 12:18:56.057263 kubelet[1886]: E0117 12:18:56.057023    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:18:56.428000 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1163282111.mount: Deactivated successfully.
Jan 17 12:18:56.833879 containerd[1595]: time="2025-01-17T12:18:56.833234381Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.29.13\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 17 12:18:56.835319 containerd[1595]: time="2025-01-17T12:18:56.835259535Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.29.13: active requests=0, bytes read=28620941"
Jan 17 12:18:56.836507 containerd[1595]: time="2025-01-17T12:18:56.836256744Z" level=info msg="ImageCreate event name:\"sha256:f20cf1600da6cce7b7d3fdd3b5ff91243983ea8be3907cccaee1a956770a2f15\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 17 12:18:56.839558 containerd[1595]: time="2025-01-17T12:18:56.838957895Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:dd45846de733434501e436638a7a240f2d379bf0a6bb0404a7684e0cf52c4011\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 17 12:18:56.839558 containerd[1595]: time="2025-01-17T12:18:56.839401630Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.29.13\" with image id \"sha256:f20cf1600da6cce7b7d3fdd3b5ff91243983ea8be3907cccaee1a956770a2f15\", repo tag \"registry.k8s.io/kube-proxy:v1.29.13\", repo digest \"registry.k8s.io/kube-proxy@sha256:dd45846de733434501e436638a7a240f2d379bf0a6bb0404a7684e0cf52c4011\", size \"28619960\" in 1.533189457s"
Jan 17 12:18:56.839558 containerd[1595]: time="2025-01-17T12:18:56.839430568Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.29.13\" returns image reference \"sha256:f20cf1600da6cce7b7d3fdd3b5ff91243983ea8be3907cccaee1a956770a2f15\""
Jan 17 12:18:56.840831 containerd[1595]: time="2025-01-17T12:18:56.840810180Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\""
Jan 17 12:18:56.842535 containerd[1595]: time="2025-01-17T12:18:56.842504795Z" level=info msg="CreateContainer within sandbox \"e85983eafc1367750eb42b597ee65d9e70d1998aac592c72414716186b924d7c\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}"
Jan 17 12:18:56.864224 containerd[1595]: time="2025-01-17T12:18:56.864164353Z" level=info msg="CreateContainer within sandbox \"e85983eafc1367750eb42b597ee65d9e70d1998aac592c72414716186b924d7c\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"cf86d9358452cd9614e943fa0cde0e1722b2551d550b14b54e879505a5d3da92\""
Jan 17 12:18:56.865238 containerd[1595]: time="2025-01-17T12:18:56.865197397Z" level=info msg="StartContainer for \"cf86d9358452cd9614e943fa0cde0e1722b2551d550b14b54e879505a5d3da92\""
Jan 17 12:18:56.955063 containerd[1595]: time="2025-01-17T12:18:56.955005453Z" level=info msg="StartContainer for \"cf86d9358452cd9614e943fa0cde0e1722b2551d550b14b54e879505a5d3da92\" returns successfully"
Jan 17 12:18:57.058182 kubelet[1886]: E0117 12:18:57.058129    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:18:57.200291 kubelet[1886]: E0117 12:18:57.200249    1886 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.3 67.207.67.2 67.207.67.3"
Jan 17 12:18:57.426156 systemd[1]: run-containerd-runc-k8s.io-cf86d9358452cd9614e943fa0cde0e1722b2551d550b14b54e879505a5d3da92-runc.txSvy1.mount: Deactivated successfully.
Jan 17 12:18:58.058853 kubelet[1886]: E0117 12:18:58.058805    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:18:58.201855 kubelet[1886]: E0117 12:18:58.201803    1886 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.3 67.207.67.2 67.207.67.3"
Jan 17 12:18:58.798530 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2891431069.mount: Deactivated successfully.
Jan 17 12:18:58.852258 containerd[1595]: time="2025-01-17T12:18:58.852200587Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 17 12:18:58.854353 containerd[1595]: time="2025-01-17T12:18:58.854279923Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3852937"
Jan 17 12:18:58.856289 containerd[1595]: time="2025-01-17T12:18:58.855865778Z" level=info msg="ImageCreate event name:\"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 17 12:18:58.858225 containerd[1595]: time="2025-01-17T12:18:58.858169740Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 17 12:18:58.859312 containerd[1595]: time="2025-01-17T12:18:58.859156946Z" level=info msg="Pulled image \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" with image id \"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\", repo tag \"docker.io/flannel/flannel-cni-plugin:v1.1.2\", repo digest \"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\", size \"3842055\" in 2.01822524s"
Jan 17 12:18:58.859312 containerd[1595]: time="2025-01-17T12:18:58.859201924Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\""
Jan 17 12:18:58.861986 containerd[1595]: time="2025-01-17T12:18:58.861942267Z" level=info msg="CreateContainer within sandbox \"dc76632e7945e6fc5d4c8b3ec19445079aa8c9a7cd47367f0eb643c1df1a9cf9\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}"
Jan 17 12:18:58.880259 containerd[1595]: time="2025-01-17T12:18:58.880211876Z" level=info msg="CreateContainer within sandbox \"dc76632e7945e6fc5d4c8b3ec19445079aa8c9a7cd47367f0eb643c1df1a9cf9\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"b85459f7aa26f9e84c4912c25362bdc2762da6dc8d24e9125a7c650c74500533\""
Jan 17 12:18:58.881254 containerd[1595]: time="2025-01-17T12:18:58.881219003Z" level=info msg="StartContainer for \"b85459f7aa26f9e84c4912c25362bdc2762da6dc8d24e9125a7c650c74500533\""
Jan 17 12:18:58.952128 containerd[1595]: time="2025-01-17T12:18:58.951534693Z" level=info msg="StartContainer for \"b85459f7aa26f9e84c4912c25362bdc2762da6dc8d24e9125a7c650c74500533\" returns successfully"
Jan 17 12:18:59.095836 kubelet[1886]: E0117 12:18:59.059662    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:18:59.111635 containerd[1595]: time="2025-01-17T12:18:59.111529463Z" level=info msg="shim disconnected" id=b85459f7aa26f9e84c4912c25362bdc2762da6dc8d24e9125a7c650c74500533 namespace=k8s.io
Jan 17 12:18:59.111911 containerd[1595]: time="2025-01-17T12:18:59.111664418Z" level=warning msg="cleaning up after shim disconnected" id=b85459f7aa26f9e84c4912c25362bdc2762da6dc8d24e9125a7c650c74500533 namespace=k8s.io
Jan 17 12:18:59.111911 containerd[1595]: time="2025-01-17T12:18:59.111675321Z" level=info msg="cleaning up dead shim" namespace=k8s.io
Jan 17 12:18:59.204678 kubelet[1886]: E0117 12:18:59.204650    1886 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.3 67.207.67.2 67.207.67.3"
Jan 17 12:18:59.205827 containerd[1595]: time="2025-01-17T12:18:59.205794572Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\""
Jan 17 12:18:59.232969 kubelet[1886]: I0117 12:18:59.232929    1886 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-system/kube-proxy-wtt8t" podStartSLOduration=4.696689902 podStartE2EDuration="6.232864s" podCreationTimestamp="2025-01-17 12:18:53 +0000 UTC" firstStartedPulling="2025-01-17 12:18:55.303884456 +0000 UTC m=+2.733663836" lastFinishedPulling="2025-01-17 12:18:56.840058545 +0000 UTC m=+4.269837934" observedRunningTime="2025-01-17 12:18:57.221611546 +0000 UTC m=+4.651390945" watchObservedRunningTime="2025-01-17 12:18:59.232864 +0000 UTC m=+6.662643398"
Jan 17 12:18:59.698458 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-b85459f7aa26f9e84c4912c25362bdc2762da6dc8d24e9125a7c650c74500533-rootfs.mount: Deactivated successfully.
Jan 17 12:19:00.060029 kubelet[1886]: E0117 12:19:00.059828    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:00.546605 systemd-resolved[1479]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 67.207.67.2.
Jan 17 12:19:01.060620 kubelet[1886]: E0117 12:19:01.060546    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:01.423002 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1133350822.mount: Deactivated successfully.
Jan 17 12:19:02.061320 kubelet[1886]: E0117 12:19:02.061195    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:02.712553 containerd[1595]: time="2025-01-17T12:19:02.712427014Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 17 12:19:02.714557 containerd[1595]: time="2025-01-17T12:19:02.714467348Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26866358"
Jan 17 12:19:02.716065 containerd[1595]: time="2025-01-17T12:19:02.715974680Z" level=info msg="ImageCreate event name:\"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 17 12:19:02.720657 containerd[1595]: time="2025-01-17T12:19:02.720578276Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 17 12:19:02.723885 containerd[1595]: time="2025-01-17T12:19:02.722840759Z" level=info msg="Pulled image \"docker.io/flannel/flannel:v0.22.0\" with image id \"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\", repo tag \"docker.io/flannel/flannel:v0.22.0\", repo digest \"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\", size \"26855532\" in 3.51700427s"
Jan 17 12:19:02.723885 containerd[1595]: time="2025-01-17T12:19:02.722917922Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\""
Jan 17 12:19:02.726888 containerd[1595]: time="2025-01-17T12:19:02.726808295Z" level=info msg="CreateContainer within sandbox \"dc76632e7945e6fc5d4c8b3ec19445079aa8c9a7cd47367f0eb643c1df1a9cf9\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}"
Jan 17 12:19:02.752829 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4267713267.mount: Deactivated successfully.
Jan 17 12:19:02.760499 containerd[1595]: time="2025-01-17T12:19:02.760400851Z" level=info msg="CreateContainer within sandbox \"dc76632e7945e6fc5d4c8b3ec19445079aa8c9a7cd47367f0eb643c1df1a9cf9\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"d3ab1e8175e754ff7d5dd5036ff5a5c1fb4e3f6f8ecff37d07a479d4116810d7\""
Jan 17 12:19:02.762693 containerd[1595]: time="2025-01-17T12:19:02.761256709Z" level=info msg="StartContainer for \"d3ab1e8175e754ff7d5dd5036ff5a5c1fb4e3f6f8ecff37d07a479d4116810d7\""
Jan 17 12:19:02.815061 systemd[1]: run-containerd-runc-k8s.io-d3ab1e8175e754ff7d5dd5036ff5a5c1fb4e3f6f8ecff37d07a479d4116810d7-runc.eTUsvV.mount: Deactivated successfully.
Jan 17 12:19:02.877956 containerd[1595]: time="2025-01-17T12:19:02.877900647Z" level=info msg="StartContainer for \"d3ab1e8175e754ff7d5dd5036ff5a5c1fb4e3f6f8ecff37d07a479d4116810d7\" returns successfully"
Jan 17 12:19:02.895660 kubelet[1886]: I0117 12:19:02.895591    1886 kubelet_node_status.go:497] "Fast updating node status as it just became ready"
Jan 17 12:19:03.021975 containerd[1595]: time="2025-01-17T12:19:03.021002295Z" level=info msg="shim disconnected" id=d3ab1e8175e754ff7d5dd5036ff5a5c1fb4e3f6f8ecff37d07a479d4116810d7 namespace=k8s.io
Jan 17 12:19:03.021975 containerd[1595]: time="2025-01-17T12:19:03.021082969Z" level=warning msg="cleaning up after shim disconnected" id=d3ab1e8175e754ff7d5dd5036ff5a5c1fb4e3f6f8ecff37d07a479d4116810d7 namespace=k8s.io
Jan 17 12:19:03.021975 containerd[1595]: time="2025-01-17T12:19:03.021133046Z" level=info msg="cleaning up dead shim" namespace=k8s.io
Jan 17 12:19:03.063304 kubelet[1886]: E0117 12:19:03.063239    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:03.219135 kubelet[1886]: E0117 12:19:03.218083    1886 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.3 67.207.67.2 67.207.67.3"
Jan 17 12:19:03.220918 containerd[1595]: time="2025-01-17T12:19:03.220856905Z" level=info msg="CreateContainer within sandbox \"dc76632e7945e6fc5d4c8b3ec19445079aa8c9a7cd47367f0eb643c1df1a9cf9\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}"
Jan 17 12:19:03.241058 containerd[1595]: time="2025-01-17T12:19:03.240945223Z" level=info msg="CreateContainer within sandbox \"dc76632e7945e6fc5d4c8b3ec19445079aa8c9a7cd47367f0eb643c1df1a9cf9\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"c09c53aeeb41310b13a260e8dc3a3d18ad8e057b49e0153c830915ca57c9c743\""
Jan 17 12:19:03.243326 containerd[1595]: time="2025-01-17T12:19:03.242340910Z" level=info msg="StartContainer for \"c09c53aeeb41310b13a260e8dc3a3d18ad8e057b49e0153c830915ca57c9c743\""
Jan 17 12:19:03.324494 containerd[1595]: time="2025-01-17T12:19:03.324336377Z" level=info msg="StartContainer for \"c09c53aeeb41310b13a260e8dc3a3d18ad8e057b49e0153c830915ca57c9c743\" returns successfully"
Jan 17 12:19:03.618551 systemd-resolved[1479]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 67.207.67.3.
Jan 17 12:19:03.744442 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-d3ab1e8175e754ff7d5dd5036ff5a5c1fb4e3f6f8ecff37d07a479d4116810d7-rootfs.mount: Deactivated successfully.
Jan 17 12:19:04.064586 kubelet[1886]: E0117 12:19:04.064426    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:04.222838 kubelet[1886]: E0117 12:19:04.222770    1886 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.3 67.207.67.2 67.207.67.3"
Jan 17 12:19:04.409063 systemd-networkd[1222]: flannel.1: Link UP
Jan 17 12:19:04.409071 systemd-networkd[1222]: flannel.1: Gained carrier
Jan 17 12:19:05.064751 kubelet[1886]: E0117 12:19:05.064686    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:05.225838 kubelet[1886]: E0117 12:19:05.225803    1886 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.3 67.207.67.2 67.207.67.3"
Jan 17 12:19:05.632588 kubelet[1886]: I0117 12:19:05.632485    1886 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-lccvm" podStartSLOduration=5.213608939 podStartE2EDuration="12.632419871s" podCreationTimestamp="2025-01-17 12:18:53 +0000 UTC" firstStartedPulling="2025-01-17 12:18:55.304438405 +0000 UTC m=+2.734217780" lastFinishedPulling="2025-01-17 12:19:02.723249315 +0000 UTC m=+10.153028712" observedRunningTime="2025-01-17 12:19:04.253005053 +0000 UTC m=+11.682784431" watchObservedRunningTime="2025-01-17 12:19:05.632419871 +0000 UTC m=+13.062199259"
Jan 17 12:19:05.632915 kubelet[1886]: I0117 12:19:05.632742    1886 topology_manager.go:215] "Topology Admit Handler" podUID="499f71f2-da76-4f3d-b2db-8b1de9210e9e" podNamespace="default" podName="nginx-deployment-6d5f899847-xkdsg"
Jan 17 12:19:05.650002 kubelet[1886]: W0117 12:19:05.649912    1886 reflector.go:539] object-"default"/"kube-root-ca.crt": failed to list *v1.ConfigMap: configmaps "kube-root-ca.crt" is forbidden: User "system:node:64.227.109.251" cannot list resource "configmaps" in API group "" in the namespace "default": no relationship found between node '64.227.109.251' and this object
Jan 17 12:19:05.650002 kubelet[1886]: E0117 12:19:05.649966    1886 reflector.go:147] object-"default"/"kube-root-ca.crt": Failed to watch *v1.ConfigMap: failed to list *v1.ConfigMap: configmaps "kube-root-ca.crt" is forbidden: User "system:node:64.227.109.251" cannot list resource "configmaps" in API group "" in the namespace "default": no relationship found between node '64.227.109.251' and this object
Jan 17 12:19:05.674762 kubelet[1886]: I0117 12:19:05.674697    1886 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-5dwzk\" (UniqueName: \"kubernetes.io/projected/499f71f2-da76-4f3d-b2db-8b1de9210e9e-kube-api-access-5dwzk\") pod \"nginx-deployment-6d5f899847-xkdsg\" (UID: \"499f71f2-da76-4f3d-b2db-8b1de9210e9e\") " pod="default/nginx-deployment-6d5f899847-xkdsg"
Jan 17 12:19:06.065972 kubelet[1886]: E0117 12:19:06.065830    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:06.114386 systemd-networkd[1222]: flannel.1: Gained IPv6LL
Jan 17 12:19:06.836714 containerd[1595]: time="2025-01-17T12:19:06.836655956Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-6d5f899847-xkdsg,Uid:499f71f2-da76-4f3d-b2db-8b1de9210e9e,Namespace:default,Attempt:0,}"
Jan 17 12:19:06.868593 systemd-networkd[1222]: cni0: Link UP
Jan 17 12:19:06.868600 systemd-networkd[1222]: cni0: Gained carrier
Jan 17 12:19:06.868866 systemd-networkd[1222]: cni0: Lost carrier
Jan 17 12:19:06.880390 kernel: cni0: port 1(veth9f2f8d15) entered blocking state
Jan 17 12:19:06.883665 kernel: cni0: port 1(veth9f2f8d15) entered disabled state
Jan 17 12:19:06.883723 kernel: veth9f2f8d15: entered allmulticast mode
Jan 17 12:19:06.883742 kernel: veth9f2f8d15: entered promiscuous mode
Jan 17 12:19:06.885938 kernel: cni0: port 1(veth9f2f8d15) entered blocking state
Jan 17 12:19:06.886029 kernel: cni0: port 1(veth9f2f8d15) entered forwarding state
Jan 17 12:19:06.886049 kernel: cni0: port 1(veth9f2f8d15) entered disabled state
Jan 17 12:19:06.888575 systemd-networkd[1222]: veth9f2f8d15: Link UP
Jan 17 12:19:06.899124 kernel: cni0: port 1(veth9f2f8d15) entered blocking state
Jan 17 12:19:06.899227 kernel: cni0: port 1(veth9f2f8d15) entered forwarding state
Jan 17 12:19:06.899298 systemd-networkd[1222]: veth9f2f8d15: Gained carrier
Jan 17 12:19:06.899942 systemd-networkd[1222]: cni0: Gained carrier
Jan 17 12:19:06.902559 containerd[1595]: 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)(0xc00001c938), "name":"cbr0", "type":"bridge"}
Jan 17 12:19:06.902559 containerd[1595]: delegateAdd: netconf sent to delegate plugin:
Jan 17 12:19:06.928799 containerd[1595]: {"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-17T12:19:06.928644435Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1
Jan 17 12:19:06.928799 containerd[1595]: time="2025-01-17T12:19:06.928747376Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1
Jan 17 12:19:06.928799 containerd[1595]: time="2025-01-17T12:19:06.928770678Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jan 17 12:19:06.929423 containerd[1595]: time="2025-01-17T12:19:06.928879649Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jan 17 12:19:07.004588 containerd[1595]: time="2025-01-17T12:19:07.004541714Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-6d5f899847-xkdsg,Uid:499f71f2-da76-4f3d-b2db-8b1de9210e9e,Namespace:default,Attempt:0,} returns sandbox id \"4994a36f764ff6cdfdc9f416ceb19888ac720830d456eed44f17e86db8b78da4\""
Jan 17 12:19:07.007359 containerd[1595]: time="2025-01-17T12:19:07.007298944Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\""
Jan 17 12:19:07.066603 kubelet[1886]: E0117 12:19:07.066488    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:07.590043 systemd[1]: run-containerd-runc-k8s.io-4994a36f764ff6cdfdc9f416ceb19888ac720830d456eed44f17e86db8b78da4-runc.Lsvizg.mount: Deactivated successfully.
Jan 17 12:19:08.067470 kubelet[1886]: E0117 12:19:08.067293    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:08.419214 systemd-networkd[1222]: cni0: Gained IPv6LL
Jan 17 12:19:08.483395 systemd-networkd[1222]: veth9f2f8d15: Gained IPv6LL
Jan 17 12:19:09.068066 kubelet[1886]: E0117 12:19:09.068009    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:09.652244 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2613730759.mount: Deactivated successfully.
Jan 17 12:19:10.069734 kubelet[1886]: E0117 12:19:10.069554    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:10.082806 systemd-resolved[1479]: Using degraded feature set TCP instead of UDP for DNS server 67.207.67.2.
Jan 17 12:19:11.070599 kubelet[1886]: E0117 12:19:11.070278    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:11.394991 containerd[1595]: time="2025-01-17T12:19:11.394903206Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 17 12:19:11.396535 containerd[1595]: time="2025-01-17T12:19:11.396428677Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=71036018"
Jan 17 12:19:11.399129 containerd[1595]: time="2025-01-17T12:19:11.397756632Z" level=info msg="ImageCreate event name:\"sha256:29ef6eaebfc53650f3a4609edbf9d35e866f56b2c5e01d32d93439031b300f0b\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 17 12:19:11.403031 containerd[1595]: time="2025-01-17T12:19:11.402820049Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:eca1d1ff18c7af45f86b7e0b572090f563a676ddca3da2ecff678390366335ad\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 17 12:19:11.405437 containerd[1595]: time="2025-01-17T12:19:11.405175999Z" 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.397803796s"
Jan 17 12:19:11.405725 containerd[1595]: time="2025-01-17T12:19:11.405689871Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:29ef6eaebfc53650f3a4609edbf9d35e866f56b2c5e01d32d93439031b300f0b\""
Jan 17 12:19:11.408186 containerd[1595]: time="2025-01-17T12:19:11.408136110Z" level=info msg="CreateContainer within sandbox \"4994a36f764ff6cdfdc9f416ceb19888ac720830d456eed44f17e86db8b78da4\" for container &ContainerMetadata{Name:nginx,Attempt:0,}"
Jan 17 12:19:11.428579 containerd[1595]: time="2025-01-17T12:19:11.428502419Z" level=info msg="CreateContainer within sandbox \"4994a36f764ff6cdfdc9f416ceb19888ac720830d456eed44f17e86db8b78da4\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"6a71f1d4abd3fe26bc0b635408a87614a4b1311dbd11b1b41eef22a0476f93d0\""
Jan 17 12:19:11.430470 containerd[1595]: time="2025-01-17T12:19:11.429465045Z" level=info msg="StartContainer for \"6a71f1d4abd3fe26bc0b635408a87614a4b1311dbd11b1b41eef22a0476f93d0\""
Jan 17 12:19:11.473768 systemd[1]: run-containerd-runc-k8s.io-6a71f1d4abd3fe26bc0b635408a87614a4b1311dbd11b1b41eef22a0476f93d0-runc.qAqE36.mount: Deactivated successfully.
Jan 17 12:19:11.524901 containerd[1595]: time="2025-01-17T12:19:11.524840204Z" level=info msg="StartContainer for \"6a71f1d4abd3fe26bc0b635408a87614a4b1311dbd11b1b41eef22a0476f93d0\" returns successfully"
Jan 17 12:19:12.071527 kubelet[1886]: E0117 12:19:12.071455    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:13.055322 kubelet[1886]: E0117 12:19:13.055231    1886 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:13.071979 kubelet[1886]: E0117 12:19:13.071912    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:14.072785 kubelet[1886]: E0117 12:19:14.072719    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:15.073928 kubelet[1886]: E0117 12:19:15.073859    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:16.074193 kubelet[1886]: E0117 12:19:16.074125    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:17.075023 kubelet[1886]: E0117 12:19:17.074952    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:18.075573 kubelet[1886]: E0117 12:19:18.075510    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:19.076287 kubelet[1886]: E0117 12:19:19.076208    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:20.076758 kubelet[1886]: E0117 12:19:20.076674    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:20.082919 kubelet[1886]: I0117 12:19:20.082858    1886 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="default/nginx-deployment-6d5f899847-xkdsg" podStartSLOduration=10.682890244 podStartE2EDuration="15.082814698s" podCreationTimestamp="2025-01-17 12:19:05 +0000 UTC" firstStartedPulling="2025-01-17 12:19:07.006360759 +0000 UTC m=+14.436140136" lastFinishedPulling="2025-01-17 12:19:11.406285207 +0000 UTC m=+18.836064590" observedRunningTime="2025-01-17 12:19:12.27235441 +0000 UTC m=+19.702133807" watchObservedRunningTime="2025-01-17 12:19:20.082814698 +0000 UTC m=+27.512594092"
Jan 17 12:19:20.083449 kubelet[1886]: I0117 12:19:20.082987    1886 topology_manager.go:215] "Topology Admit Handler" podUID="cc7beec4-cccc-4c79-ac39-9114e005f9b3" podNamespace="default" podName="nfs-server-provisioner-0"
Jan 17 12:19:20.178288 kubelet[1886]: I0117 12:19:20.178221    1886 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-9cgf4\" (UniqueName: \"kubernetes.io/projected/cc7beec4-cccc-4c79-ac39-9114e005f9b3-kube-api-access-9cgf4\") pod \"nfs-server-provisioner-0\" (UID: \"cc7beec4-cccc-4c79-ac39-9114e005f9b3\") " pod="default/nfs-server-provisioner-0"
Jan 17 12:19:20.178736 kubelet[1886]: I0117 12:19:20.178667    1886 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/cc7beec4-cccc-4c79-ac39-9114e005f9b3-data\") pod \"nfs-server-provisioner-0\" (UID: \"cc7beec4-cccc-4c79-ac39-9114e005f9b3\") " pod="default/nfs-server-provisioner-0"
Jan 17 12:19:20.386974 containerd[1595]: time="2025-01-17T12:19:20.386933165Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:cc7beec4-cccc-4c79-ac39-9114e005f9b3,Namespace:default,Attempt:0,}"
Jan 17 12:19:20.421587 systemd-networkd[1222]: vetha06925db: Link UP
Jan 17 12:19:20.426452 kernel: cni0: port 2(vetha06925db) entered blocking state
Jan 17 12:19:20.426597 kernel: cni0: port 2(vetha06925db) entered disabled state
Jan 17 12:19:20.428414 kernel: vetha06925db: entered allmulticast mode
Jan 17 12:19:20.430846 kernel: vetha06925db: entered promiscuous mode
Jan 17 12:19:20.445464 kernel: cni0: port 2(vetha06925db) entered blocking state
Jan 17 12:19:20.445607 kernel: cni0: port 2(vetha06925db) entered forwarding state
Jan 17 12:19:20.445793 systemd-networkd[1222]: vetha06925db: Gained carrier
Jan 17 12:19:20.448754 containerd[1595]: 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)(0xc00001e938), "name":"cbr0", "type":"bridge"}
Jan 17 12:19:20.448754 containerd[1595]: delegateAdd: netconf sent to delegate plugin:
Jan 17 12:19:20.482384 containerd[1595]: {"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-17T12:19:20.480870943Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1
Jan 17 12:19:20.482384 containerd[1595]: time="2025-01-17T12:19:20.481698538Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1
Jan 17 12:19:20.482384 containerd[1595]: time="2025-01-17T12:19:20.481714092Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jan 17 12:19:20.482384 containerd[1595]: time="2025-01-17T12:19:20.481826043Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jan 17 12:19:20.566666 containerd[1595]: time="2025-01-17T12:19:20.566490504Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:cc7beec4-cccc-4c79-ac39-9114e005f9b3,Namespace:default,Attempt:0,} returns sandbox id \"e13b95089b05766c5309395bb94d9291716d9699e23599e6a0742cb6f522c489\""
Jan 17 12:19:20.569481 containerd[1595]: time="2025-01-17T12:19:20.569426201Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\""
Jan 17 12:19:20.933620 systemd[1]: Started sshd@5-64.227.109.251:22-218.92.0.134:16650.service - OpenSSH per-connection server daemon (218.92.0.134:16650).
Jan 17 12:19:21.077534 kubelet[1886]: E0117 12:19:21.077491    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:22.078447 kubelet[1886]: E0117 12:19:22.078393    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:22.139551 sshd[2745]: pam_unix(sshd:auth): authentication failure; logname= uid=0 euid=0 tty=ssh ruser= rhost=218.92.0.134  user=root
Jan 17 12:19:22.306308 systemd-networkd[1222]: vetha06925db: Gained IPv6LL
Jan 17 12:19:22.857534 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4264892902.mount: Deactivated successfully.
Jan 17 12:19:23.079320 kubelet[1886]: E0117 12:19:23.079259    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:24.053126 sshd[2739]: PAM: Permission denied for root from 218.92.0.134
Jan 17 12:19:24.080180 kubelet[1886]: E0117 12:19:24.080067    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:24.366830 sshd[2750]: pam_unix(sshd:auth): authentication failure; logname= uid=0 euid=0 tty=ssh ruser= rhost=218.92.0.134  user=root
Jan 17 12:19:25.080751 kubelet[1886]: E0117 12:19:25.080606    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:25.205603 containerd[1595]: time="2025-01-17T12:19:25.205526569Z" 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 17 12:19:25.207112 containerd[1595]: time="2025-01-17T12:19:25.207034117Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=91039406"
Jan 17 12:19:25.208108 containerd[1595]: time="2025-01-17T12:19:25.207999737Z" level=info msg="ImageCreate event name:\"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 17 12:19:25.212335 containerd[1595]: time="2025-01-17T12:19:25.212249332Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 17 12:19:25.214130 containerd[1595]: time="2025-01-17T12:19:25.213845194Z" 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 4.644360621s"
Jan 17 12:19:25.214130 containerd[1595]: time="2025-01-17T12:19:25.213909883Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\""
Jan 17 12:19:25.221706 containerd[1595]: time="2025-01-17T12:19:25.221055782Z" level=info msg="CreateContainer within sandbox \"e13b95089b05766c5309395bb94d9291716d9699e23599e6a0742cb6f522c489\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}"
Jan 17 12:19:25.245936 containerd[1595]: time="2025-01-17T12:19:25.245888316Z" level=info msg="CreateContainer within sandbox \"e13b95089b05766c5309395bb94d9291716d9699e23599e6a0742cb6f522c489\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"0d824770080317d1ea1d9d3963aab547ab32a7be3ed29902101bf01da7dcd016\""
Jan 17 12:19:25.246826 containerd[1595]: time="2025-01-17T12:19:25.246784212Z" level=info msg="StartContainer for \"0d824770080317d1ea1d9d3963aab547ab32a7be3ed29902101bf01da7dcd016\""
Jan 17 12:19:25.386868 containerd[1595]: time="2025-01-17T12:19:25.386033817Z" level=info msg="StartContainer for \"0d824770080317d1ea1d9d3963aab547ab32a7be3ed29902101bf01da7dcd016\" returns successfully"
Jan 17 12:19:26.081297 kubelet[1886]: E0117 12:19:26.081238    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:26.361121 sshd[2739]: PAM: Permission denied for root from 218.92.0.134
Jan 17 12:19:26.671081 sshd[2864]: pam_unix(sshd:auth): authentication failure; logname= uid=0 euid=0 tty=ssh ruser= rhost=218.92.0.134  user=root
Jan 17 12:19:27.081657 kubelet[1886]: E0117 12:19:27.081451    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:28.082486 kubelet[1886]: E0117 12:19:28.082427    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:28.604356 sshd[2739]: PAM: Permission denied for root from 218.92.0.134
Jan 17 12:19:28.759156 sshd[2739]: Received disconnect from 218.92.0.134 port 16650:11:  [preauth]
Jan 17 12:19:28.759156 sshd[2739]: Disconnected from authenticating user root 218.92.0.134 port 16650 [preauth]
Jan 17 12:19:28.761958 systemd[1]: sshd@5-64.227.109.251:22-218.92.0.134:16650.service: Deactivated successfully.
Jan 17 12:19:29.083620 kubelet[1886]: E0117 12:19:29.083471    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:30.083723 kubelet[1886]: E0117 12:19:30.083609    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:31.084876 kubelet[1886]: E0117 12:19:31.084794    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:32.085038 kubelet[1886]: E0117 12:19:32.084973    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:32.632183 update_engine[1569]: I20250117 12:19:32.631563  1569 update_attempter.cc:509] Updating boot flags...
Jan 17 12:19:32.669824 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (2898)
Jan 17 12:19:32.740219 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (2901)
Jan 17 12:19:33.055285 kubelet[1886]: E0117 12:19:33.055130    1886 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:33.085865 kubelet[1886]: E0117 12:19:33.085790    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:34.086702 kubelet[1886]: E0117 12:19:34.086640    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:35.086878 kubelet[1886]: E0117 12:19:35.086814    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:35.279542 kubelet[1886]: I0117 12:19:35.279500    1886 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=10.634007861 podStartE2EDuration="15.279411747s" podCreationTimestamp="2025-01-17 12:19:20 +0000 UTC" firstStartedPulling="2025-01-17 12:19:20.568822266 +0000 UTC m=+27.998601660" lastFinishedPulling="2025-01-17 12:19:25.214226168 +0000 UTC m=+32.644005546" observedRunningTime="2025-01-17 12:19:26.317900002 +0000 UTC m=+33.747679400" watchObservedRunningTime="2025-01-17 12:19:35.279411747 +0000 UTC m=+42.709191144"
Jan 17 12:19:35.280067 kubelet[1886]: I0117 12:19:35.280040    1886 topology_manager.go:215] "Topology Admit Handler" podUID="692419c2-aa87-4ddf-8dc6-d61f64d8ad08" podNamespace="default" podName="test-pod-1"
Jan 17 12:19:35.388283 kubelet[1886]: I0117 12:19:35.388068    1886 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-hfnhs\" (UniqueName: \"kubernetes.io/projected/692419c2-aa87-4ddf-8dc6-d61f64d8ad08-kube-api-access-hfnhs\") pod \"test-pod-1\" (UID: \"692419c2-aa87-4ddf-8dc6-d61f64d8ad08\") " pod="default/test-pod-1"
Jan 17 12:19:35.388447 kubelet[1886]: I0117 12:19:35.388361    1886 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-af14f2ac-c6e6-4801-80b5-830098057993\" (UniqueName: \"kubernetes.io/nfs/692419c2-aa87-4ddf-8dc6-d61f64d8ad08-pvc-af14f2ac-c6e6-4801-80b5-830098057993\") pod \"test-pod-1\" (UID: \"692419c2-aa87-4ddf-8dc6-d61f64d8ad08\") " pod="default/test-pod-1"
Jan 17 12:19:35.519816 kernel: FS-Cache: Loaded
Jan 17 12:19:35.599530 kernel: RPC: Registered named UNIX socket transport module.
Jan 17 12:19:35.599755 kernel: RPC: Registered udp transport module.
Jan 17 12:19:35.599785 kernel: RPC: Registered tcp transport module.
Jan 17 12:19:35.600313 kernel: RPC: Registered tcp-with-tls transport module.
Jan 17 12:19:35.601179 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module.
Jan 17 12:19:35.931142 kernel: NFS: Registering the id_resolver key type
Jan 17 12:19:35.931313 kernel: Key type id_resolver registered
Jan 17 12:19:35.934143 kernel: Key type id_legacy registered
Jan 17 12:19:35.969687 nfsidmap[2938]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain '3.0-a-1e82b03d4c'
Jan 17 12:19:35.975322 nfsidmap[2939]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain '3.0-a-1e82b03d4c'
Jan 17 12:19:36.087813 kubelet[1886]: E0117 12:19:36.087741    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:36.185481 containerd[1595]: time="2025-01-17T12:19:36.185248049Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:692419c2-aa87-4ddf-8dc6-d61f64d8ad08,Namespace:default,Attempt:0,}"
Jan 17 12:19:36.231185 kernel: cni0: port 3(vethcbfc6f78) entered blocking state
Jan 17 12:19:36.231307 kernel: cni0: port 3(vethcbfc6f78) entered disabled state
Jan 17 12:19:36.228945 systemd-networkd[1222]: vethcbfc6f78: Link UP
Jan 17 12:19:36.235894 kernel: vethcbfc6f78: entered allmulticast mode
Jan 17 12:19:36.236024 kernel: vethcbfc6f78: entered promiscuous mode
Jan 17 12:19:36.247206 kernel: cni0: port 3(vethcbfc6f78) entered blocking state
Jan 17 12:19:36.247313 kernel: cni0: port 3(vethcbfc6f78) entered forwarding state
Jan 17 12:19:36.247688 systemd-networkd[1222]: vethcbfc6f78: Gained carrier
Jan 17 12:19:36.250306 containerd[1595]: 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)(0xc00009c8e8), "name":"cbr0", "type":"bridge"}
Jan 17 12:19:36.250306 containerd[1595]: delegateAdd: netconf sent to delegate plugin:
Jan 17 12:19:36.277879 containerd[1595]: {"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-17T12:19:36.277536605Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1
Jan 17 12:19:36.277879 containerd[1595]: time="2025-01-17T12:19:36.277624530Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1
Jan 17 12:19:36.277879 containerd[1595]: time="2025-01-17T12:19:36.277639324Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jan 17 12:19:36.278264 containerd[1595]: time="2025-01-17T12:19:36.277804315Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1
Jan 17 12:19:36.344110 containerd[1595]: time="2025-01-17T12:19:36.343980818Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:692419c2-aa87-4ddf-8dc6-d61f64d8ad08,Namespace:default,Attempt:0,} returns sandbox id \"ac479fdfca767578ae03d920927cbb7701d06038d472a12d0a0519e6be595f96\""
Jan 17 12:19:36.346547 containerd[1595]: time="2025-01-17T12:19:36.346469519Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\""
Jan 17 12:19:36.700123 containerd[1595]: time="2025-01-17T12:19:36.697957060Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Jan 17 12:19:36.700123 containerd[1595]: time="2025-01-17T12:19:36.698849980Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61"
Jan 17 12:19:36.702136 containerd[1595]: time="2025-01-17T12:19:36.702039829Z" 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 355.53204ms"
Jan 17 12:19:36.702136 containerd[1595]: time="2025-01-17T12:19:36.702106373Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:29ef6eaebfc53650f3a4609edbf9d35e866f56b2c5e01d32d93439031b300f0b\""
Jan 17 12:19:36.704759 containerd[1595]: time="2025-01-17T12:19:36.704320070Z" level=info msg="CreateContainer within sandbox \"ac479fdfca767578ae03d920927cbb7701d06038d472a12d0a0519e6be595f96\" for container &ContainerMetadata{Name:test,Attempt:0,}"
Jan 17 12:19:36.723579 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2132912712.mount: Deactivated successfully.
Jan 17 12:19:36.725263 containerd[1595]: time="2025-01-17T12:19:36.723991526Z" level=info msg="CreateContainer within sandbox \"ac479fdfca767578ae03d920927cbb7701d06038d472a12d0a0519e6be595f96\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"a87266c09d6dfa3aaade95cdc2340f967aeb1eff0af0576cfda7a9e16df1552a\""
Jan 17 12:19:36.727289 containerd[1595]: time="2025-01-17T12:19:36.727239938Z" level=info msg="StartContainer for \"a87266c09d6dfa3aaade95cdc2340f967aeb1eff0af0576cfda7a9e16df1552a\""
Jan 17 12:19:36.796577 containerd[1595]: time="2025-01-17T12:19:36.796432670Z" level=info msg="StartContainer for \"a87266c09d6dfa3aaade95cdc2340f967aeb1eff0af0576cfda7a9e16df1552a\" returns successfully"
Jan 17 12:19:37.088796 kubelet[1886]: E0117 12:19:37.088723    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:37.730584 systemd-networkd[1222]: vethcbfc6f78: Gained IPv6LL
Jan 17 12:19:38.089984 kubelet[1886]: E0117 12:19:38.089893    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:39.091231 kubelet[1886]: E0117 12:19:39.091169    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:40.091787 kubelet[1886]: E0117 12:19:40.091716    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:41.092982 kubelet[1886]: E0117 12:19:41.092899    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:42.094060 kubelet[1886]: E0117 12:19:42.094005    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:43.094826 kubelet[1886]: E0117 12:19:43.094736    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:44.095981 kubelet[1886]: E0117 12:19:44.095895    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Jan 17 12:19:45.097126 kubelet[1886]: E0117 12:19:45.097059    1886 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"