Oct 30 00:14:09.193918 kernel: Linux version 6.12.54-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.0 p8) 14.3.0, GNU ld (Gentoo 2.44 p4) 2.44.0) #1 SMP PREEMPT_DYNAMIC Wed Oct 29 22:07:32 -00 2025
Oct 30 00:14:09.194011 kernel: Command line: BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200n8 flatcar.first_boot=detected flatcar.oem.id=gce verity.usrhash=e5fe4ef982f4bbc75df9f63e805c4ec086c6d95878919f55fe8c638c4d2b3b13
Oct 30 00:14:09.194044 kernel: BIOS-provided physical RAM map:
Oct 30 00:14:09.194062 kernel: BIOS-e820: [mem 0x0000000000000000-0x0000000000000fff] reserved
Oct 30 00:14:09.194079 kernel: BIOS-e820: [mem 0x0000000000001000-0x0000000000054fff] usable
Oct 30 00:14:09.194097 kernel: BIOS-e820: [mem 0x0000000000055000-0x000000000005ffff] reserved
Oct 30 00:14:09.194118 kernel: BIOS-e820: [mem 0x0000000000060000-0x0000000000097fff] usable
Oct 30 00:14:09.194137 kernel: BIOS-e820: [mem 0x0000000000098000-0x000000000009ffff] reserved
Oct 30 00:14:09.194162 kernel: BIOS-e820: [mem 0x0000000000100000-0x00000000bd318fff] usable
Oct 30 00:14:09.194180 kernel: BIOS-e820: [mem 0x00000000bd319000-0x00000000bd322fff] ACPI data
Oct 30 00:14:09.194198 kernel: BIOS-e820: [mem 0x00000000bd323000-0x00000000bf8ecfff] usable
Oct 30 00:14:09.194216 kernel: BIOS-e820: [mem 0x00000000bf8ed000-0x00000000bfb6cfff] reserved
Oct 30 00:14:09.194233 kernel: BIOS-e820: [mem 0x00000000bfb6d000-0x00000000bfb7efff] ACPI data
Oct 30 00:14:09.194252 kernel: BIOS-e820: [mem 0x00000000bfb7f000-0x00000000bfbfefff] ACPI NVS
Oct 30 00:14:09.194279 kernel: BIOS-e820: [mem 0x00000000bfbff000-0x00000000bffdffff] usable
Oct 30 00:14:09.194301 kernel: BIOS-e820: [mem 0x00000000bffe0000-0x00000000bfffffff] reserved
Oct 30 00:14:09.194321 kernel: BIOS-e820: [mem 0x0000000100000000-0x000000021fffffff] usable
Oct 30 00:14:09.194341 kernel: NX (Execute Disable) protection: active
Oct 30 00:14:09.194360 kernel: APIC: Static calls initialized
Oct 30 00:14:09.194379 kernel: efi: EFI v2.7 by EDK II
Oct 30 00:14:09.194402 kernel: efi: TPMFinalLog=0xbfbf7000 ACPI=0xbfb7e000 ACPI 2.0=0xbfb7e014 SMBIOS=0xbf9ca000 MEMATTR=0xbd323018 RNG=0xbfb73018 TPMEventLog=0xbd319018 
Oct 30 00:14:09.194421 kernel: random: crng init done
Oct 30 00:14:09.194440 kernel: secureboot: Secure boot disabled
Oct 30 00:14:09.194460 kernel: SMBIOS 2.4 present.
Oct 30 00:14:09.194479 kernel: DMI: Google Google Compute Engine/Google Compute Engine, BIOS Google 10/02/2025
Oct 30 00:14:09.194505 kernel: DMI: Memory slots populated: 1/1
Oct 30 00:14:09.194525 kernel: Hypervisor detected: KVM
Oct 30 00:14:09.194544 kernel: last_pfn = 0xbffe0 max_arch_pfn = 0x400000000
Oct 30 00:14:09.194574 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00
Oct 30 00:14:09.194596 kernel: kvm-clock: using sched offset of 15961179694 cycles
Oct 30 00:14:09.194616 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
Oct 30 00:14:09.194638 kernel: tsc: Detected 2299.998 MHz processor
Oct 30 00:14:09.194658 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
Oct 30 00:14:09.194681 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable
Oct 30 00:14:09.194701 kernel: last_pfn = 0x220000 max_arch_pfn = 0x400000000
Oct 30 00:14:09.194729 kernel: MTRR map: 3 entries (2 fixed + 1 variable; max 18), built from 8 variable MTRRs
Oct 30 00:14:09.194750 kernel: x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT  
Oct 30 00:14:09.194770 kernel: last_pfn = 0xbffe0 max_arch_pfn = 0x400000000
Oct 30 00:14:09.194789 kernel: Using GB pages for direct mapping
Oct 30 00:14:09.194822 kernel: ACPI: Early table checksum verification disabled
Oct 30 00:14:09.194850 kernel: ACPI: RSDP 0x00000000BFB7E014 000024 (v02 Google)
Oct 30 00:14:09.194870 kernel: ACPI: XSDT 0x00000000BFB7D0E8 00005C (v01 Google GOOGFACP 00000001      01000013)
Oct 30 00:14:09.194894 kernel: ACPI: FACP 0x00000000BFB78000 0000F4 (v02 Google GOOGFACP 00000001 GOOG 00000001)
Oct 30 00:14:09.194917 kernel: ACPI: DSDT 0x00000000BFB79000 001A64 (v01 Google GOOGDSDT 00000001 GOOG 00000001)
Oct 30 00:14:09.194940 kernel: ACPI: FACS 0x00000000BFBF2000 000040
Oct 30 00:14:09.194979 kernel: ACPI: SSDT 0x00000000BFB7C000 000316 (v02 GOOGLE Tpm2Tabl 00001000 INTL 20250404)
Oct 30 00:14:09.195003 kernel: ACPI: TPM2 0x00000000BFB7B000 000034 (v04 GOOGLE          00000001 GOOG 00000001)
Oct 30 00:14:09.195024 kernel: ACPI: SRAT 0x00000000BFB77000 0000C8 (v03 Google GOOGSRAT 00000001 GOOG 00000001)
Oct 30 00:14:09.195047 kernel: ACPI: APIC 0x00000000BFB76000 000076 (v05 Google GOOGAPIC 00000001 GOOG 00000001)
Oct 30 00:14:09.195075 kernel: ACPI: SSDT 0x00000000BFB75000 000980 (v01 Google GOOGSSDT 00000001 GOOG 00000001)
Oct 30 00:14:09.195097 kernel: ACPI: WAET 0x00000000BFB74000 000028 (v01 Google GOOGWAET 00000001 GOOG 00000001)
Oct 30 00:14:09.195117 kernel: ACPI: Reserving FACP table memory at [mem 0xbfb78000-0xbfb780f3]
Oct 30 00:14:09.195140 kernel: ACPI: Reserving DSDT table memory at [mem 0xbfb79000-0xbfb7aa63]
Oct 30 00:14:09.195161 kernel: ACPI: Reserving FACS table memory at [mem 0xbfbf2000-0xbfbf203f]
Oct 30 00:14:09.195185 kernel: ACPI: Reserving SSDT table memory at [mem 0xbfb7c000-0xbfb7c315]
Oct 30 00:14:09.195206 kernel: ACPI: Reserving TPM2 table memory at [mem 0xbfb7b000-0xbfb7b033]
Oct 30 00:14:09.195246 kernel: ACPI: Reserving SRAT table memory at [mem 0xbfb77000-0xbfb770c7]
Oct 30 00:14:09.195263 kernel: ACPI: Reserving APIC table memory at [mem 0xbfb76000-0xbfb76075]
Oct 30 00:14:09.195286 kernel: ACPI: Reserving SSDT table memory at [mem 0xbfb75000-0xbfb7597f]
Oct 30 00:14:09.195302 kernel: ACPI: Reserving WAET table memory at [mem 0xbfb74000-0xbfb74027]
Oct 30 00:14:09.195318 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x0009ffff]
Oct 30 00:14:09.195336 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00100000-0xbfffffff]
Oct 30 00:14:09.195353 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0x21fffffff]
Oct 30 00:14:09.195370 kernel: NUMA: Node 0 [mem 0x00001000-0x0009ffff] + [mem 0x00100000-0xbfffffff] -> [mem 0x00001000-0xbfffffff]
Oct 30 00:14:09.195388 kernel: NUMA: Node 0 [mem 0x00001000-0xbfffffff] + [mem 0x100000000-0x21fffffff] -> [mem 0x00001000-0x21fffffff]
Oct 30 00:14:09.195407 kernel: NODE_DATA(0) allocated [mem 0x21fff8dc0-0x21fffffff]
Oct 30 00:14:09.195426 kernel: Zone ranges:
Oct 30 00:14:09.195448 kernel:   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
Oct 30 00:14:09.195465 kernel:   DMA32    [mem 0x0000000001000000-0x00000000ffffffff]
Oct 30 00:14:09.195482 kernel:   Normal   [mem 0x0000000100000000-0x000000021fffffff]
Oct 30 00:14:09.195501 kernel:   Device   empty
Oct 30 00:14:09.195518 kernel: Movable zone start for each node
Oct 30 00:14:09.195536 kernel: Early memory node ranges
Oct 30 00:14:09.195562 kernel:   node   0: [mem 0x0000000000001000-0x0000000000054fff]
Oct 30 00:14:09.195579 kernel:   node   0: [mem 0x0000000000060000-0x0000000000097fff]
Oct 30 00:14:09.195595 kernel:   node   0: [mem 0x0000000000100000-0x00000000bd318fff]
Oct 30 00:14:09.195617 kernel:   node   0: [mem 0x00000000bd323000-0x00000000bf8ecfff]
Oct 30 00:14:09.195634 kernel:   node   0: [mem 0x00000000bfbff000-0x00000000bffdffff]
Oct 30 00:14:09.195651 kernel:   node   0: [mem 0x0000000100000000-0x000000021fffffff]
Oct 30 00:14:09.195668 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000021fffffff]
Oct 30 00:14:09.195687 kernel: On node 0, zone DMA: 1 pages in unavailable ranges
Oct 30 00:14:09.195704 kernel: On node 0, zone DMA: 11 pages in unavailable ranges
Oct 30 00:14:09.195721 kernel: On node 0, zone DMA: 104 pages in unavailable ranges
Oct 30 00:14:09.195739 kernel: On node 0, zone DMA32: 10 pages in unavailable ranges
Oct 30 00:14:09.195756 kernel: On node 0, zone DMA32: 786 pages in unavailable ranges
Oct 30 00:14:09.195781 kernel: On node 0, zone Normal: 32 pages in unavailable ranges
Oct 30 00:14:09.195798 kernel: ACPI: PM-Timer IO Port: 0xb008
Oct 30 00:14:09.195814 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
Oct 30 00:14:09.195832 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
Oct 30 00:14:09.195849 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
Oct 30 00:14:09.195869 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
Oct 30 00:14:09.195886 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
Oct 30 00:14:09.195904 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
Oct 30 00:14:09.195921 kernel: ACPI: Using ACPI (MADT) for SMP configuration information
Oct 30 00:14:09.195942 kernel: CPU topo: Max. logical packages:   1
Oct 30 00:14:09.195985 kernel: CPU topo: Max. logical dies:       1
Oct 30 00:14:09.196003 kernel: CPU topo: Max. dies per package:   1
Oct 30 00:14:09.196020 kernel: CPU topo: Max. threads per core:   2
Oct 30 00:14:09.196039 kernel: CPU topo: Num. cores per package:     1
Oct 30 00:14:09.196056 kernel: CPU topo: Num. threads per package:   2
Oct 30 00:14:09.196073 kernel: CPU topo: Allowing 2 present CPUs plus 0 hotplug CPUs
Oct 30 00:14:09.196091 kernel: [mem 0xc0000000-0xffffffff] available for PCI devices
Oct 30 00:14:09.196108 kernel: Booting paravirtualized kernel on KVM
Oct 30 00:14:09.196128 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
Oct 30 00:14:09.196150 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1
Oct 30 00:14:09.196169 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u1048576
Oct 30 00:14:09.196187 kernel: pcpu-alloc: s207832 r8192 d29736 u1048576 alloc=1*2097152
Oct 30 00:14:09.196204 kernel: pcpu-alloc: [0] 0 1 
Oct 30 00:14:09.196220 kernel: kvm-guest: PV spinlocks enabled
Oct 30 00:14:09.196238 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear)
Oct 30 00:14:09.196258 kernel: Kernel command line: rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200n8 flatcar.first_boot=detected flatcar.oem.id=gce verity.usrhash=e5fe4ef982f4bbc75df9f63e805c4ec086c6d95878919f55fe8c638c4d2b3b13
Oct 30 00:14:09.196275 kernel: Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear)
Oct 30 00:14:09.196300 kernel: Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)
Oct 30 00:14:09.196317 kernel: Fallback order for Node 0: 0 
Oct 30 00:14:09.196335 kernel: Built 1 zonelists, mobility grouping on.  Total pages: 1965136
Oct 30 00:14:09.196354 kernel: Policy zone: Normal
Oct 30 00:14:09.196373 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off
Oct 30 00:14:09.196391 kernel: software IO TLB: area num 2.
Oct 30 00:14:09.196425 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
Oct 30 00:14:09.196448 kernel: Kernel/User page tables isolation: enabled
Oct 30 00:14:09.196466 kernel: ftrace: allocating 40021 entries in 157 pages
Oct 30 00:14:09.196484 kernel: ftrace: allocated 157 pages with 5 groups
Oct 30 00:14:09.196502 kernel: Dynamic Preempt: voluntary
Oct 30 00:14:09.196522 kernel: rcu: Preemptible hierarchical RCU implementation.
Oct 30 00:14:09.196622 kernel: rcu:         RCU event tracing is enabled.
Oct 30 00:14:09.196729 kernel: rcu:         RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2.
Oct 30 00:14:09.196757 kernel:         Trampoline variant of Tasks RCU enabled.
Oct 30 00:14:09.196776 kernel:         Rude variant of Tasks RCU enabled.
Oct 30 00:14:09.196808 kernel:         Tracing variant of Tasks RCU enabled.
Oct 30 00:14:09.196828 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.
Oct 30 00:14:09.196846 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
Oct 30 00:14:09.196865 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2.
Oct 30 00:14:09.196883 kernel: RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2.
Oct 30 00:14:09.196902 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2.
Oct 30 00:14:09.196920 kernel: NR_IRQS: 33024, nr_irqs: 440, preallocated irqs: 16
Oct 30 00:14:09.196938 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention.
Oct 30 00:14:09.197000 kernel: Console: colour dummy device 80x25
Oct 30 00:14:09.197031 kernel: printk: legacy console [ttyS0] enabled
Oct 30 00:14:09.197052 kernel: ACPI: Core revision 20240827
Oct 30 00:14:09.197076 kernel: APIC: Switch to symmetric I/O mode setup
Oct 30 00:14:09.197100 kernel: x2apic enabled
Oct 30 00:14:09.197120 kernel: APIC: Switched APIC routing to: physical x2apic
Oct 30 00:14:09.197141 kernel: ..TIMER: vector=0x30 apic1=0 pin1=0 apic2=-1 pin2=-1
Oct 30 00:14:09.197165 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x212733415c7, max_idle_ns: 440795236380 ns
Oct 30 00:14:09.197191 kernel: Calibrating delay loop (skipped) preset value.. 4599.99 BogoMIPS (lpj=2299998)
Oct 30 00:14:09.197215 kernel: Last level iTLB entries: 4KB 1024, 2MB 1024, 4MB 1024
Oct 30 00:14:09.197244 kernel: Last level dTLB entries: 4KB 1024, 2MB 1024, 4MB 1024, 1GB 4
Oct 30 00:14:09.197269 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization
Oct 30 00:14:09.197297 kernel: Spectre V2 : Spectre BHI mitigation: SW BHB clearing on syscall and VM exit
Oct 30 00:14:09.197321 kernel: Spectre V2 : Mitigation: IBRS
Oct 30 00:14:09.197346 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT
Oct 30 00:14:09.197366 kernel: RETBleed: Mitigation: IBRS
Oct 30 00:14:09.197389 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier
Oct 30 00:14:09.197413 kernel: Spectre V2 : User space: Mitigation: STIBP via prctl
Oct 30 00:14:09.197438 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl
Oct 30 00:14:09.197489 kernel: MDS: Mitigation: Clear CPU buffers
Oct 30 00:14:09.197515 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode
Oct 30 00:14:09.197540 kernel: active return thunk: its_return_thunk
Oct 30 00:14:09.197563 kernel: ITS: Mitigation: Aligned branch/return thunks
Oct 30 00:14:09.197589 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
Oct 30 00:14:09.197617 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
Oct 30 00:14:09.197641 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
Oct 30 00:14:09.197672 kernel: x86/fpu: xstate_offset[2]:  576, xstate_sizes[2]:  256
Oct 30 00:14:09.197695 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format.
Oct 30 00:14:09.197729 kernel: Freeing SMP alternatives memory: 32K
Oct 30 00:14:09.197752 kernel: pid_max: default: 32768 minimum: 301
Oct 30 00:14:09.197775 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima
Oct 30 00:14:09.197797 kernel: landlock: Up and running.
Oct 30 00:14:09.197821 kernel: SELinux:  Initializing.
Oct 30 00:14:09.197848 kernel: Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)
Oct 30 00:14:09.197873 kernel: Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)
Oct 30 00:14:09.197895 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU @ 2.30GHz (family: 0x6, model: 0x3f, stepping: 0x0)
Oct 30 00:14:09.197918 kernel: Performance Events: unsupported p6 CPU model 63 no PMU driver, software events only.
Oct 30 00:14:09.197971 kernel: signal: max sigframe size: 1776
Oct 30 00:14:09.198011 kernel: rcu: Hierarchical SRCU implementation.
Oct 30 00:14:09.198039 kernel: rcu:         Max phase no-delay instances is 400.
Oct 30 00:14:09.198065 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level
Oct 30 00:14:09.198089 kernel: NMI watchdog: Perf NMI watchdog permanently disabled
Oct 30 00:14:09.198108 kernel: smp: Bringing up secondary CPUs ...
Oct 30 00:14:09.198127 kernel: smpboot: x86: Booting SMP configuration:
Oct 30 00:14:09.198150 kernel: .... node  #0, CPUs:      #1
Oct 30 00:14:09.198172 kernel: MDS CPU bug present and SMT on, data leak possible. See https://www.kernel.org/doc/html/latest/admin-guide/hw-vuln/mds.html for more details.
Oct 30 00:14:09.198204 kernel: MMIO Stale Data CPU bug present and SMT on, data leak possible. See https://www.kernel.org/doc/html/latest/admin-guide/hw-vuln/processor_mmio_stale_data.html for more details.
Oct 30 00:14:09.198226 kernel: smp: Brought up 1 node, 2 CPUs
Oct 30 00:14:09.198248 kernel: smpboot: Total of 2 processors activated (9199.99 BogoMIPS)
Oct 30 00:14:09.198270 kernel: Memory: 7557860K/7860544K available (14336K kernel code, 2436K rwdata, 26048K rodata, 45544K init, 1184K bss, 296852K reserved, 0K cma-reserved)
Oct 30 00:14:09.198293 kernel: devtmpfs: initialized
Oct 30 00:14:09.198315 kernel: x86/mm: Memory block size: 128MB
Oct 30 00:14:09.198337 kernel: ACPI: PM: Registering ACPI NVS region [mem 0xbfb7f000-0xbfbfefff] (524288 bytes)
Oct 30 00:14:09.198359 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
Oct 30 00:14:09.198387 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear)
Oct 30 00:14:09.198412 kernel: pinctrl core: initialized pinctrl subsystem
Oct 30 00:14:09.198436 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family
Oct 30 00:14:09.198459 kernel: audit: initializing netlink subsys (disabled)
Oct 30 00:14:09.198482 kernel: audit: type=2000 audit(1761783243.487:1): state=initialized audit_enabled=0 res=1
Oct 30 00:14:09.198505 kernel: thermal_sys: Registered thermal governor 'step_wise'
Oct 30 00:14:09.198528 kernel: thermal_sys: Registered thermal governor 'user_space'
Oct 30 00:14:09.198548 kernel: cpuidle: using governor menu
Oct 30 00:14:09.198571 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
Oct 30 00:14:09.198600 kernel: dca service started, version 1.12.1
Oct 30 00:14:09.198624 kernel: PCI: Using configuration type 1 for base access
Oct 30 00:14:09.198646 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.
Oct 30 00:14:09.198677 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages
Oct 30 00:14:09.198698 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page
Oct 30 00:14:09.198719 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages
Oct 30 00:14:09.198742 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page
Oct 30 00:14:09.198764 kernel: ACPI: Added _OSI(Module Device)
Oct 30 00:14:09.198784 kernel: ACPI: Added _OSI(Processor Device)
Oct 30 00:14:09.198811 kernel: ACPI: Added _OSI(Processor Aggregator Device)
Oct 30 00:14:09.198834 kernel: ACPI: 3 ACPI AML tables successfully acquired and loaded
Oct 30 00:14:09.198856 kernel: ACPI: Interpreter enabled
Oct 30 00:14:09.198877 kernel: ACPI: PM: (supports S0 S3 S5)
Oct 30 00:14:09.198901 kernel: ACPI: Using IOAPIC for interrupt routing
Oct 30 00:14:09.198921 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
Oct 30 00:14:09.198942 kernel: PCI: Ignoring E820 reservations for host bridge windows
Oct 30 00:14:09.198986 kernel: ACPI: Enabled 16 GPEs in block 00 to 0F
Oct 30 00:14:09.199005 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
Oct 30 00:14:09.199325 kernel: acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3]
Oct 30 00:14:09.199540 kernel: acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI]
Oct 30 00:14:09.199759 kernel: acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended configuration space under this bridge
Oct 30 00:14:09.199785 kernel: PCI host bridge to bus 0000:00
Oct 30 00:14:09.200018 kernel: pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
Oct 30 00:14:09.200218 kernel: pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
Oct 30 00:14:09.200414 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
Oct 30 00:14:09.200601 kernel: pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfefff window]
Oct 30 00:14:09.200796 kernel: pci_bus 0000:00: root bus resource [bus 00-ff]
Oct 30 00:14:09.201058 kernel: pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 conventional PCI endpoint
Oct 30 00:14:09.201300 kernel: pci 0000:00:01.0: [8086:7110] type 00 class 0x060100 conventional PCI endpoint
Oct 30 00:14:09.201531 kernel: pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 conventional PCI endpoint
Oct 30 00:14:09.201795 kernel: pci 0000:00:01.3: quirk: [io  0xb000-0xb03f] claimed by PIIX4 ACPI
Oct 30 00:14:09.202104 kernel: pci 0000:00:03.0: [1af4:1004] type 00 class 0x000000 conventional PCI endpoint
Oct 30 00:14:09.202374 kernel: pci 0000:00:03.0: BAR 0 [io  0xc040-0xc07f]
Oct 30 00:14:09.202639 kernel: pci 0000:00:03.0: BAR 1 [mem 0xc0001000-0xc000107f]
Oct 30 00:14:09.202934 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint
Oct 30 00:14:09.203430 kernel: pci 0000:00:04.0: BAR 0 [io  0xc000-0xc03f]
Oct 30 00:14:09.204069 kernel: pci 0000:00:04.0: BAR 1 [mem 0xc0000000-0xc000007f]
Oct 30 00:14:09.205030 kernel: pci 0000:00:05.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint
Oct 30 00:14:09.205466 kernel: pci 0000:00:05.0: BAR 0 [io  0xc080-0xc09f]
Oct 30 00:14:09.205703 kernel: pci 0000:00:05.0: BAR 1 [mem 0xc0002000-0xc000203f]
Oct 30 00:14:09.205730 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10
Oct 30 00:14:09.205749 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10
Oct 30 00:14:09.205768 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11
Oct 30 00:14:09.205787 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11
Oct 30 00:14:09.205805 kernel: ACPI: PCI: Interrupt link LNKS configured for IRQ 9
Oct 30 00:14:09.205831 kernel: iommu: Default domain type: Translated
Oct 30 00:14:09.205850 kernel: iommu: DMA domain TLB invalidation policy: lazy mode
Oct 30 00:14:09.205868 kernel: efivars: Registered efivars operations
Oct 30 00:14:09.205886 kernel: PCI: Using ACPI for IRQ routing
Oct 30 00:14:09.205905 kernel: PCI: pci_cache_line_size set to 64 bytes
Oct 30 00:14:09.205922 kernel: e820: reserve RAM buffer [mem 0x00055000-0x0005ffff]
Oct 30 00:14:09.205940 kernel: e820: reserve RAM buffer [mem 0x00098000-0x0009ffff]
Oct 30 00:14:09.205981 kernel: e820: reserve RAM buffer [mem 0xbd319000-0xbfffffff]
Oct 30 00:14:09.206010 kernel: e820: reserve RAM buffer [mem 0xbf8ed000-0xbfffffff]
Oct 30 00:14:09.206044 kernel: e820: reserve RAM buffer [mem 0xbffe0000-0xbfffffff]
Oct 30 00:14:09.206069 kernel: vgaarb: loaded
Oct 30 00:14:09.206093 kernel: clocksource: Switched to clocksource kvm-clock
Oct 30 00:14:09.206117 kernel: VFS: Disk quotas dquot_6.6.0
Oct 30 00:14:09.206139 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
Oct 30 00:14:09.206163 kernel: pnp: PnP ACPI init
Oct 30 00:14:09.206189 kernel: pnp: PnP ACPI: found 7 devices
Oct 30 00:14:09.206212 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
Oct 30 00:14:09.206232 kernel: NET: Registered PF_INET protocol family
Oct 30 00:14:09.206260 kernel: IP idents hash table entries: 131072 (order: 8, 1048576 bytes, linear)
Oct 30 00:14:09.206286 kernel: tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, linear)
Oct 30 00:14:09.206306 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
Oct 30 00:14:09.206333 kernel: TCP established hash table entries: 65536 (order: 7, 524288 bytes, linear)
Oct 30 00:14:09.206350 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear)
Oct 30 00:14:09.206368 kernel: TCP: Hash tables configured (established 65536 bind 65536)
Oct 30 00:14:09.206385 kernel: UDP hash table entries: 4096 (order: 5, 131072 bytes, linear)
Oct 30 00:14:09.206405 kernel: UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, linear)
Oct 30 00:14:09.206430 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family
Oct 30 00:14:09.206457 kernel: NET: Registered PF_XDP protocol family
Oct 30 00:14:09.207165 kernel: pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
Oct 30 00:14:09.207366 kernel: pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
Oct 30 00:14:09.207556 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
Oct 30 00:14:09.207761 kernel: pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfefff window]
Oct 30 00:14:09.209025 kernel: pci 0000:00:00.0: Limiting direct PCI/PCI transfers
Oct 30 00:14:09.209060 kernel: PCI: CLS 0 bytes, default 64
Oct 30 00:14:09.209090 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB)
Oct 30 00:14:09.209111 kernel: software IO TLB: mapped [mem 0x00000000b7f7f000-0x00000000bbf7f000] (64MB)
Oct 30 00:14:09.209131 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer
Oct 30 00:14:09.209149 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x212733415c7, max_idle_ns: 440795236380 ns
Oct 30 00:14:09.209167 kernel: clocksource: Switched to clocksource tsc
Oct 30 00:14:09.209186 kernel: Initialise system trusted keyrings
Oct 30 00:14:09.209211 kernel: workingset: timestamp_bits=39 max_order=21 bucket_order=0
Oct 30 00:14:09.209233 kernel: Key type asymmetric registered
Oct 30 00:14:09.209253 kernel: Asymmetric key parser 'x509' registered
Oct 30 00:14:09.209275 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)
Oct 30 00:14:09.209294 kernel: io scheduler mq-deadline registered
Oct 30 00:14:09.209318 kernel: io scheduler kyber registered
Oct 30 00:14:09.209343 kernel: io scheduler bfq registered
Oct 30 00:14:09.209367 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00
Oct 30 00:14:09.209395 kernel: ACPI: \_SB_.LNKC: Enabled at IRQ 11
Oct 30 00:14:09.209682 kernel: virtio-pci 0000:00:03.0: virtio_pci: leaving for legacy driver
Oct 30 00:14:09.209712 kernel: ACPI: \_SB_.LNKD: Enabled at IRQ 10
Oct 30 00:14:09.209985 kernel: virtio-pci 0000:00:04.0: virtio_pci: leaving for legacy driver
Oct 30 00:14:09.210019 kernel: ACPI: \_SB_.LNKA: Enabled at IRQ 10
Oct 30 00:14:09.210272 kernel: virtio-pci 0000:00:05.0: virtio_pci: leaving for legacy driver
Oct 30 00:14:09.210303 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
Oct 30 00:14:09.210326 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
Oct 30 00:14:09.210351 kernel: 00:04: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A
Oct 30 00:14:09.210376 kernel: 00:05: ttyS2 at I/O 0x3e8 (irq = 6, base_baud = 115200) is a 16550A
Oct 30 00:14:09.210401 kernel: 00:06: ttyS3 at I/O 0x2e8 (irq = 7, base_baud = 115200) is a 16550A
Oct 30 00:14:09.210667 kernel: tpm_tis MSFT0101:00: 2.0 TPM (device-id 0x9009, rev-id 0)
Oct 30 00:14:09.210706 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
Oct 30 00:14:09.210732 kernel: i8042: Warning: Keylock active
Oct 30 00:14:09.210755 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1
Oct 30 00:14:09.210779 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12
Oct 30 00:14:09.213084 kernel: rtc_cmos 00:00: RTC can wake from S4
Oct 30 00:14:09.213303 kernel: rtc_cmos 00:00: registered as rtc0
Oct 30 00:14:09.213501 kernel: rtc_cmos 00:00: setting system clock to 2025-10-30T00:14:08 UTC (1761783248)
Oct 30 00:14:09.213714 kernel: rtc_cmos 00:00: alarms up to one day, 114 bytes nvram
Oct 30 00:14:09.213740 kernel: intel_pstate: CPU model not supported
Oct 30 00:14:09.213760 kernel: pstore: Using crash dump compression: deflate
Oct 30 00:14:09.213782 kernel: pstore: Registered efi_pstore as persistent store backend
Oct 30 00:14:09.213802 kernel: NET: Registered PF_INET6 protocol family
Oct 30 00:14:09.213822 kernel: Segment Routing with IPv6
Oct 30 00:14:09.213842 kernel: In-situ OAM (IOAM) with IPv6
Oct 30 00:14:09.213861 kernel: NET: Registered PF_PACKET protocol family
Oct 30 00:14:09.213880 kernel: Key type dns_resolver registered
Oct 30 00:14:09.213902 kernel: IPI shorthand broadcast: enabled
Oct 30 00:14:09.213926 kernel: sched_clock: Marking stable (3922005884, 970302360)->(5227235543, -334927299)
Oct 30 00:14:09.213945 kernel: registered taskstats version 1
Oct 30 00:14:09.213994 kernel: Loading compiled-in X.509 certificates
Oct 30 00:14:09.214023 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.54-flatcar: 815fc40077fbc06b8d9e8a6016fea83aecff0a2a'
Oct 30 00:14:09.214044 kernel: Demotion targets for Node 0: null
Oct 30 00:14:09.214063 kernel: Key type .fscrypt registered
Oct 30 00:14:09.214306 kernel: Key type fscrypt-provisioning registered
Oct 30 00:14:09.214328 kernel: ima: Allocated hash algorithm: sha1
Oct 30 00:14:09.214539 kernel: ima: No architecture policies found
Oct 30 00:14:09.214565 kernel: clk: Disabling unused clocks
Oct 30 00:14:09.214785 kernel: Warning: unable to open an initial console.
Oct 30 00:14:09.214814 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0
Oct 30 00:14:09.215399 kernel: Freeing unused kernel image (initmem) memory: 45544K
Oct 30 00:14:09.215433 kernel: Write protecting the kernel read-only data: 40960k
Oct 30 00:14:09.215701 kernel: Freeing unused kernel image (rodata/data gap) memory: 576K
Oct 30 00:14:09.215810 kernel: Run /init as init process
Oct 30 00:14:09.215841 kernel:   with arguments:
Oct 30 00:14:09.215871 kernel:     /init
Oct 30 00:14:09.215908 kernel:   with environment:
Oct 30 00:14:09.215933 kernel:     HOME=/
Oct 30 00:14:09.215978 kernel:     TERM=linux
Oct 30 00:14:09.216012 systemd[1]: Successfully made /usr/ read-only.
Oct 30 00:14:09.216049 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE)
Oct 30 00:14:09.216084 systemd[1]: Detected virtualization google.
Oct 30 00:14:09.216116 systemd[1]: Detected architecture x86-64.
Oct 30 00:14:09.216153 systemd[1]: Running in initrd.
Oct 30 00:14:09.216185 systemd[1]: No hostname configured, using default hostname.
Oct 30 00:14:09.216219 systemd[1]: Hostname set to <localhost>.
Oct 30 00:14:09.216252 systemd[1]: Initializing machine ID from random generator.
Oct 30 00:14:09.216285 systemd[1]: Queued start job for default target initrd.target.
Oct 30 00:14:09.216319 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch.
Oct 30 00:14:09.216374 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch.
Oct 30 00:14:09.216413 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM...
Oct 30 00:14:09.216448 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM...
Oct 30 00:14:09.216482 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT...
Oct 30 00:14:09.216519 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A...
Oct 30 00:14:09.216555 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132...
Oct 30 00:14:09.216593 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr...
Oct 30 00:14:09.216628 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre).
Oct 30 00:14:09.216668 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes.
Oct 30 00:14:09.216703 systemd[1]: Reached target paths.target - Path Units.
Oct 30 00:14:09.216737 systemd[1]: Reached target slices.target - Slice Units.
Oct 30 00:14:09.216772 systemd[1]: Reached target swap.target - Swaps.
Oct 30 00:14:09.216806 systemd[1]: Reached target timers.target - Timer Units.
Oct 30 00:14:09.216840 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket.
Oct 30 00:14:09.216875 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket.
Oct 30 00:14:09.216916 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log).
Oct 30 00:14:09.218977 systemd[1]: Listening on systemd-journald.socket - Journal Sockets.
Oct 30 00:14:09.219021 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket.
Oct 30 00:14:09.219057 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket.
Oct 30 00:14:09.219092 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket.
Oct 30 00:14:09.219126 systemd[1]: Reached target sockets.target - Socket Units.
Oct 30 00:14:09.219161 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup...
Oct 30 00:14:09.219195 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes...
Oct 30 00:14:09.219235 systemd[1]: Finished network-cleanup.service - Network Cleanup.
Oct 30 00:14:09.219270 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply).
Oct 30 00:14:09.219305 systemd[1]: Starting systemd-fsck-usr.service...
Oct 30 00:14:09.219339 systemd[1]: Starting systemd-journald.service - Journal Service...
Oct 30 00:14:09.219373 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules...
Oct 30 00:14:09.219408 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup...
Oct 30 00:14:09.219442 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup.
Oct 30 00:14:09.219483 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes.
Oct 30 00:14:09.219518 systemd[1]: Finished systemd-fsck-usr.service.
Oct 30 00:14:09.219554 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully...
Oct 30 00:14:09.219644 systemd-journald[191]: Collecting audit messages is disabled.
Oct 30 00:14:09.219872 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup.
Oct 30 00:14:09.219913 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters...
Oct 30 00:14:09.219999 systemd-journald[191]: Journal started
Oct 30 00:14:09.220086 systemd-journald[191]: Runtime Journal (/run/log/journal/afc910de6f344d7bab81f5359578647c) is 8M, max 148.6M, 140.6M free.
Oct 30 00:14:09.174562 systemd-modules-load[193]: Inserted module 'overlay'
Oct 30 00:14:09.227294 systemd[1]: Started systemd-journald.service - Journal Service.
Oct 30 00:14:09.233507 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully.
Oct 30 00:14:09.244039 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
Oct 30 00:14:09.245216 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev...
Oct 30 00:14:09.249696 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories...
Oct 30 00:14:09.256986 kernel: Bridge firewalling registered
Oct 30 00:14:09.258086 systemd-modules-load[193]: Inserted module 'br_netfilter'
Oct 30 00:14:09.263124 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules.
Oct 30 00:14:09.267823 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables...
Oct 30 00:14:09.292061 systemd-tmpfiles[210]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring.
Oct 30 00:14:09.296665 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev.
Oct 30 00:14:09.308999 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables.
Oct 30 00:14:09.313367 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters.
Oct 30 00:14:09.319441 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories.
Oct 30 00:14:09.326418 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook...
Oct 30 00:14:09.333608 systemd[1]: Starting systemd-resolved.service - Network Name Resolution...
Oct 30 00:14:09.371425 dracut-cmdline[229]: Using kernel command line parameters:  rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1   rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200n8 flatcar.first_boot=detected flatcar.oem.id=gce verity.usrhash=e5fe4ef982f4bbc75df9f63e805c4ec086c6d95878919f55fe8c638c4d2b3b13
Oct 30 00:14:09.417140 systemd-resolved[230]: Positive Trust Anchors:
Oct 30 00:14:09.417867 systemd-resolved[230]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
Oct 30 00:14:09.418628 systemd-resolved[230]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test
Oct 30 00:14:09.434823 systemd-resolved[230]: Defaulting to hostname 'linux'.
Oct 30 00:14:09.437929 systemd[1]: Started systemd-resolved.service - Network Name Resolution.
Oct 30 00:14:09.448278 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups.
Oct 30 00:14:09.514013 kernel: SCSI subsystem initialized
Oct 30 00:14:09.528990 kernel: Loading iSCSI transport class v2.0-870.
Oct 30 00:14:09.541990 kernel: iscsi: registered transport (tcp)
Oct 30 00:14:09.570004 kernel: iscsi: registered transport (qla4xxx)
Oct 30 00:14:09.570105 kernel: QLogic iSCSI HBA Driver
Oct 30 00:14:09.595322 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line...
Oct 30 00:14:09.618218 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line.
Oct 30 00:14:09.626452 systemd[1]: Reached target network-pre.target - Preparation for Network.
Oct 30 00:14:09.693902 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook.
Oct 30 00:14:09.698635 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook...
Oct 30 00:14:09.764058 kernel: raid6: avx2x4   gen() 18382 MB/s
Oct 30 00:14:09.781040 kernel: raid6: avx2x2   gen() 17652 MB/s
Oct 30 00:14:09.798603 kernel: raid6: avx2x1   gen() 14314 MB/s
Oct 30 00:14:09.798673 kernel: raid6: using algorithm avx2x4 gen() 18382 MB/s
Oct 30 00:14:09.816522 kernel: raid6: .... xor() 7787 MB/s, rmw enabled
Oct 30 00:14:09.816586 kernel: raid6: using avx2x2 recovery algorithm
Oct 30 00:14:09.841020 kernel: xor: automatically using best checksumming function   avx       
Oct 30 00:14:10.050014 kernel: Btrfs loaded, zoned=no, fsverity=no
Oct 30 00:14:10.060187 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook.
Oct 30 00:14:10.068328 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files...
Oct 30 00:14:10.115914 systemd-udevd[439]: Using default interface naming scheme 'v255'.
Oct 30 00:14:10.127056 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files.
Oct 30 00:14:10.130213 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook...
Oct 30 00:14:10.163131 dracut-pre-trigger[440]: rd.md=0: removing MD RAID activation
Oct 30 00:14:10.199517 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook.
Oct 30 00:14:10.209410 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices...
Oct 30 00:14:10.322283 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices.
Oct 30 00:14:10.329906 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook...
Oct 30 00:14:10.431065 kernel: virtio_scsi virtio0: 1/0/0 default/read/poll queues
Oct 30 00:14:10.441992 kernel: scsi host0: Virtio SCSI HBA
Oct 30 00:14:10.448991 kernel: scsi 0:0:1:0: Direct-Access     Google   PersistentDisk   1    PQ: 0 ANSI: 6
Oct 30 00:14:10.494017 kernel: cryptd: max_cpu_qlen set to 1000
Oct 30 00:14:10.503900 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3
Oct 30 00:14:10.577005 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
Oct 30 00:14:10.577257 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup.
Oct 30 00:14:10.584154 kernel: AES CTR mode by8 optimization enabled
Oct 30 00:14:10.582675 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup...
Oct 30 00:14:10.601620 kernel: sd 0:0:1:0: [sda] 33554432 512-byte logical blocks: (17.2 GB/16.0 GiB)
Oct 30 00:14:10.602098 kernel: sd 0:0:1:0: [sda] 4096-byte physical blocks
Oct 30 00:14:10.604202 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup...
Oct 30 00:14:10.616631 kernel: sd 0:0:1:0: [sda] Write Protect is off
Oct 30 00:14:10.617036 kernel: sd 0:0:1:0: [sda] Mode Sense: 1f 00 00 08
Oct 30 00:14:10.612901 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully.
Oct 30 00:14:10.622975 kernel: sd 0:0:1:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Oct 30 00:14:10.645977 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk.
Oct 30 00:14:10.646064 kernel: GPT:17805311 != 33554431
Oct 30 00:14:10.646096 kernel: GPT:Alternate GPT header not at the end of the disk.
Oct 30 00:14:10.646127 kernel: GPT:17805311 != 33554431
Oct 30 00:14:10.646156 kernel: GPT: Use GNU Parted to correct GPT errors.
Oct 30 00:14:10.646188 kernel:  sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9
Oct 30 00:14:10.654035 kernel: sd 0:0:1:0: [sda] Attached SCSI disk
Oct 30 00:14:10.673897 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup.
Oct 30 00:14:10.766867 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - PersistentDisk EFI-SYSTEM.
Oct 30 00:14:10.781288 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - PersistentDisk ROOT.
Oct 30 00:14:10.782136 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook.
Oct 30 00:14:10.818014 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - PersistentDisk USR-A.
Oct 30 00:14:10.818388 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - PersistentDisk USR-A.
Oct 30 00:14:10.838303 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - PersistentDisk OEM.
Oct 30 00:14:10.842382 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems.
Oct 30 00:14:10.842602 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes.
Oct 30 00:14:10.850131 systemd[1]: Reached target remote-fs.target - Remote File Systems.
Oct 30 00:14:10.856012 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary...
Oct 30 00:14:10.878181 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook...
Oct 30 00:14:10.894762 disk-uuid[593]: Primary Header is updated.
Oct 30 00:14:10.894762 disk-uuid[593]: Secondary Entries is updated.
Oct 30 00:14:10.894762 disk-uuid[593]: Secondary Header is updated.
Oct 30 00:14:10.910981 kernel:  sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9
Oct 30 00:14:10.916606 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook.
Oct 30 00:14:11.944873 disk-uuid[594]: The operation has completed successfully.
Oct 30 00:14:11.949122 kernel:  sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9
Oct 30 00:14:12.037457 systemd[1]: disk-uuid.service: Deactivated successfully.
Oct 30 00:14:12.037657 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary.
Oct 30 00:14:12.086244 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr...
Oct 30 00:14:12.109630 sh[615]: Success
Oct 30 00:14:12.135108 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.
Oct 30 00:14:12.135502 kernel: device-mapper: uevent: version 1.0.3
Oct 30 00:14:12.135546 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev
Oct 30 00:14:12.151986 kernel: device-mapper: verity: sha256 using shash "sha256-avx2"
Oct 30 00:14:12.235331 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr.
Oct 30 00:14:12.242078 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr...
Oct 30 00:14:12.256257 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr.
Oct 30 00:14:12.277979 kernel: BTRFS: device fsid ad8523d8-35e6-44b9-a685-e8d871101da4 devid 1 transid 35 /dev/mapper/usr (254:0) scanned by mount (627)
Oct 30 00:14:12.282331 kernel: BTRFS info (device dm-0): first mount of filesystem ad8523d8-35e6-44b9-a685-e8d871101da4
Oct 30 00:14:12.282420 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm
Oct 30 00:14:12.309975 kernel: BTRFS info (device dm-0): enabling ssd optimizations
Oct 30 00:14:12.310115 kernel: BTRFS info (device dm-0): disabling log replay at mount time
Oct 30 00:14:12.310150 kernel: BTRFS info (device dm-0): enabling free space tree
Oct 30 00:14:12.315923 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr.
Oct 30 00:14:12.317846 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System.
Oct 30 00:14:12.322879 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met.
Oct 30 00:14:12.325850 systemd[1]: Starting ignition-setup.service - Ignition (setup)...
Oct 30 00:14:12.335173 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline...
Oct 30 00:14:12.383997 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (658)
Oct 30 00:14:12.387212 kernel: BTRFS info (device sda6): first mount of filesystem 20cadb25-62ee-49b8-9ff8-7ba27828b77e
Oct 30 00:14:12.387294 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm
Oct 30 00:14:12.398000 kernel: BTRFS info (device sda6): enabling ssd optimizations
Oct 30 00:14:12.398091 kernel: BTRFS info (device sda6): turning on async discard
Oct 30 00:14:12.398131 kernel: BTRFS info (device sda6): enabling free space tree
Oct 30 00:14:12.406978 kernel: BTRFS info (device sda6): last unmount of filesystem 20cadb25-62ee-49b8-9ff8-7ba27828b77e
Oct 30 00:14:12.409505 systemd[1]: Finished ignition-setup.service - Ignition (setup).
Oct 30 00:14:12.419178 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)...
Oct 30 00:14:12.519200 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline.
Oct 30 00:14:12.530291 systemd[1]: Starting systemd-networkd.service - Network Configuration...
Oct 30 00:14:12.633341 systemd-networkd[796]: lo: Link UP
Oct 30 00:14:12.633830 systemd-networkd[796]: lo: Gained carrier
Oct 30 00:14:12.638440 systemd-networkd[796]: Enumeration completed
Oct 30 00:14:12.640678 systemd-networkd[796]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name.
Oct 30 00:14:12.641183 systemd[1]: Started systemd-networkd.service - Network Configuration.
Oct 30 00:14:12.644316 systemd-networkd[796]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network.
Oct 30 00:14:12.647995 systemd[1]: Reached target network.target - Network.
Oct 30 00:14:12.651413 systemd-networkd[796]: eth0: Link UP
Oct 30 00:14:12.653190 systemd-networkd[796]: eth0: Gained carrier
Oct 30 00:14:12.653213 systemd-networkd[796]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name.
Oct 30 00:14:12.677254 systemd-networkd[796]: eth0: Overlong DHCP hostname received, shortened from 'ci-4459-1-0-nightly-20251029-2100-f6179af3e514b1c6b030.c.flatcar-212911.internal' to 'ci-4459-1-0-nightly-20251029-2100-f6179af3e514b1c6b030'
Oct 30 00:14:12.677282 systemd-networkd[796]: eth0: DHCPv4 address 10.128.0.4/32, gateway 10.128.0.1 acquired from 169.254.169.254
Oct 30 00:14:12.720308 ignition[723]: Ignition 2.22.0
Oct 30 00:14:12.720329 ignition[723]: Stage: fetch-offline
Oct 30 00:14:12.720386 ignition[723]: no configs at "/usr/lib/ignition/base.d"
Oct 30 00:14:12.724481 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline).
Oct 30 00:14:12.720400 ignition[723]: no config dir at "/usr/lib/ignition/base.platform.d/gcp"
Oct 30 00:14:12.727713 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)...
Oct 30 00:14:12.720570 ignition[723]: parsed url from cmdline: ""
Oct 30 00:14:12.720579 ignition[723]: no config URL provided
Oct 30 00:14:12.720591 ignition[723]: reading system config file "/usr/lib/ignition/user.ign"
Oct 30 00:14:12.720607 ignition[723]: no config at "/usr/lib/ignition/user.ign"
Oct 30 00:14:12.720619 ignition[723]: failed to fetch config: resource requires networking
Oct 30 00:14:12.721161 ignition[723]: Ignition finished successfully
Oct 30 00:14:12.772232 ignition[806]: Ignition 2.22.0
Oct 30 00:14:12.772251 ignition[806]: Stage: fetch
Oct 30 00:14:12.772518 ignition[806]: no configs at "/usr/lib/ignition/base.d"
Oct 30 00:14:12.772538 ignition[806]: no config dir at "/usr/lib/ignition/base.platform.d/gcp"
Oct 30 00:14:12.772695 ignition[806]: parsed url from cmdline: ""
Oct 30 00:14:12.782758 unknown[806]: fetched base config from "system"
Oct 30 00:14:12.772702 ignition[806]: no config URL provided
Oct 30 00:14:12.782767 unknown[806]: fetched base config from "system"
Oct 30 00:14:12.772712 ignition[806]: reading system config file "/usr/lib/ignition/user.ign"
Oct 30 00:14:12.782773 unknown[806]: fetched user config from "gcp"
Oct 30 00:14:12.772726 ignition[806]: no config at "/usr/lib/ignition/user.ign"
Oct 30 00:14:12.788150 systemd[1]: Finished ignition-fetch.service - Ignition (fetch).
Oct 30 00:14:12.772764 ignition[806]: GET http://169.254.169.254/computeMetadata/v1/instance/attributes/user-data: attempt #1
Oct 30 00:14:12.795523 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)...
Oct 30 00:14:12.777942 ignition[806]: GET result: OK
Oct 30 00:14:12.778051 ignition[806]: parsing config with SHA512: c6dd1ca46154047735654f675fcac136d92e54d8c5ef0d2521804ce3eac128fd4530c4f340214e2e814d7289c000c9c0032e0938dc18584cc45b77c8b5540a86
Oct 30 00:14:12.783065 ignition[806]: fetch: fetch complete
Oct 30 00:14:12.783075 ignition[806]: fetch: fetch passed
Oct 30 00:14:12.783167 ignition[806]: Ignition finished successfully
Oct 30 00:14:12.845755 ignition[812]: Ignition 2.22.0
Oct 30 00:14:12.845778 ignition[812]: Stage: kargs
Oct 30 00:14:12.849493 systemd[1]: Finished ignition-kargs.service - Ignition (kargs).
Oct 30 00:14:12.846104 ignition[812]: no configs at "/usr/lib/ignition/base.d"
Oct 30 00:14:12.856064 systemd[1]: Starting ignition-disks.service - Ignition (disks)...
Oct 30 00:14:12.846125 ignition[812]: no config dir at "/usr/lib/ignition/base.platform.d/gcp"
Oct 30 00:14:12.847434 ignition[812]: kargs: kargs passed
Oct 30 00:14:12.847496 ignition[812]: Ignition finished successfully
Oct 30 00:14:12.898638 ignition[819]: Ignition 2.22.0
Oct 30 00:14:12.898662 ignition[819]: Stage: disks
Oct 30 00:14:12.902795 systemd[1]: Finished ignition-disks.service - Ignition (disks).
Oct 30 00:14:12.898921 ignition[819]: no configs at "/usr/lib/ignition/base.d"
Oct 30 00:14:12.906500 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device.
Oct 30 00:14:12.898942 ignition[819]: no config dir at "/usr/lib/ignition/base.platform.d/gcp"
Oct 30 00:14:12.910153 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems.
Oct 30 00:14:12.900435 ignition[819]: disks: disks passed
Oct 30 00:14:12.915267 systemd[1]: Reached target local-fs.target - Local File Systems.
Oct 30 00:14:12.900491 ignition[819]: Ignition finished successfully
Oct 30 00:14:12.920176 systemd[1]: Reached target sysinit.target - System Initialization.
Oct 30 00:14:12.925182 systemd[1]: Reached target basic.target - Basic System.
Oct 30 00:14:12.932200 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT...
Oct 30 00:14:12.983090 systemd-fsck[828]: ROOT: clean, 15/1628000 files, 120826/1617920 blocks
Oct 30 00:14:12.998131 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT.
Oct 30 00:14:13.004837 systemd[1]: Mounting sysroot.mount - /sysroot...
Oct 30 00:14:13.199982 kernel: EXT4-fs (sda9): mounted filesystem 02607114-2ead-44bc-a76e-2d51f82b108e r/w with ordered data mode. Quota mode: none.
Oct 30 00:14:13.201468 systemd[1]: Mounted sysroot.mount - /sysroot.
Oct 30 00:14:13.204168 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System.
Oct 30 00:14:13.212400 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem...
Oct 30 00:14:13.216602 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr...
Oct 30 00:14:13.222465 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met.
Oct 30 00:14:13.223214 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot).
Oct 30 00:14:13.223261 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup.
Oct 30 00:14:13.251101 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr.
Oct 30 00:14:13.254504 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup...
Oct 30 00:14:13.265981 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (836)
Oct 30 00:14:13.268047 kernel: BTRFS info (device sda6): first mount of filesystem 20cadb25-62ee-49b8-9ff8-7ba27828b77e
Oct 30 00:14:13.270001 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm
Oct 30 00:14:13.280079 kernel: BTRFS info (device sda6): enabling ssd optimizations
Oct 30 00:14:13.280297 kernel: BTRFS info (device sda6): turning on async discard
Oct 30 00:14:13.280370 kernel: BTRFS info (device sda6): enabling free space tree
Oct 30 00:14:13.283185 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem.
Oct 30 00:14:13.393531 initrd-setup-root[860]: cut: /sysroot/etc/passwd: No such file or directory
Oct 30 00:14:13.407390 initrd-setup-root[867]: cut: /sysroot/etc/group: No such file or directory
Oct 30 00:14:13.417055 initrd-setup-root[874]: cut: /sysroot/etc/shadow: No such file or directory
Oct 30 00:14:13.425723 initrd-setup-root[881]: cut: /sysroot/etc/gshadow: No such file or directory
Oct 30 00:14:13.584374 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup.
Oct 30 00:14:13.591005 systemd[1]: Starting ignition-mount.service - Ignition (mount)...
Oct 30 00:14:13.595587 systemd[1]: Starting sysroot-boot.service - /sysroot/boot...
Oct 30 00:14:13.622796 systemd[1]: sysroot-oem.mount: Deactivated successfully.
Oct 30 00:14:13.628044 kernel: BTRFS info (device sda6): last unmount of filesystem 20cadb25-62ee-49b8-9ff8-7ba27828b77e
Oct 30 00:14:13.658804 systemd[1]: Finished sysroot-boot.service - /sysroot/boot.
Oct 30 00:14:13.675737 ignition[949]: INFO     : Ignition 2.22.0
Oct 30 00:14:13.679165 ignition[949]: INFO     : Stage: mount
Oct 30 00:14:13.679165 ignition[949]: INFO     : no configs at "/usr/lib/ignition/base.d"
Oct 30 00:14:13.679165 ignition[949]: INFO     : no config dir at "/usr/lib/ignition/base.platform.d/gcp"
Oct 30 00:14:13.679165 ignition[949]: INFO     : mount: mount passed
Oct 30 00:14:13.679165 ignition[949]: INFO     : Ignition finished successfully
Oct 30 00:14:13.680807 systemd[1]: Finished ignition-mount.service - Ignition (mount).
Oct 30 00:14:13.686160 systemd[1]: Starting ignition-files.service - Ignition (files)...
Oct 30 00:14:13.720863 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem...
Oct 30 00:14:13.761055 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (960)
Oct 30 00:14:13.764503 kernel: BTRFS info (device sda6): first mount of filesystem 20cadb25-62ee-49b8-9ff8-7ba27828b77e
Oct 30 00:14:13.764596 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm
Oct 30 00:14:13.772103 kernel: BTRFS info (device sda6): enabling ssd optimizations
Oct 30 00:14:13.772234 kernel: BTRFS info (device sda6): turning on async discard
Oct 30 00:14:13.772273 kernel: BTRFS info (device sda6): enabling free space tree
Oct 30 00:14:13.776455 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem.
Oct 30 00:14:13.819185 ignition[977]: INFO     : Ignition 2.22.0
Oct 30 00:14:13.822127 ignition[977]: INFO     : Stage: files
Oct 30 00:14:13.822127 ignition[977]: INFO     : no configs at "/usr/lib/ignition/base.d"
Oct 30 00:14:13.822127 ignition[977]: INFO     : no config dir at "/usr/lib/ignition/base.platform.d/gcp"
Oct 30 00:14:13.822127 ignition[977]: DEBUG    : files: compiled without relabeling support, skipping
Oct 30 00:14:13.833130 ignition[977]: INFO     : files: ensureUsers: op(1): [started]  creating or modifying user "core"
Oct 30 00:14:13.833130 ignition[977]: DEBUG    : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core"
Oct 30 00:14:13.833130 ignition[977]: INFO     : files: ensureUsers: op(1): [finished] creating or modifying user "core"
Oct 30 00:14:13.833130 ignition[977]: INFO     : files: ensureUsers: op(2): [started]  adding ssh keys to user "core"
Oct 30 00:14:13.844135 ignition[977]: INFO     : files: ensureUsers: op(2): [finished] adding ssh keys to user "core"
Oct 30 00:14:13.844135 ignition[977]: INFO     : files: createFilesystemsFiles: createFiles: op(3): [started]  writing file "/sysroot/home/core/install.sh"
Oct 30 00:14:13.844135 ignition[977]: INFO     : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh"
Oct 30 00:14:13.844135 ignition[977]: INFO     : files: createFilesystemsFiles: createFiles: op(4): [started]  writing file "/sysroot/etc/flatcar/update.conf"
Oct 30 00:14:13.844135 ignition[977]: INFO     : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf"
Oct 30 00:14:13.844135 ignition[977]: INFO     : files: createFilesystemsFiles: createFiles: op(5): [started]  writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.4-x86-64.raw"
Oct 30 00:14:13.833209 unknown[977]: wrote ssh authorized keys file for user: core
Oct 30 00:14:13.870152 ignition[977]: INFO     : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.4-x86-64.raw"
Oct 30 00:14:13.870152 ignition[977]: INFO     : files: createFilesystemsFiles: createFiles: op(6): [started]  writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.4-x86-64.raw"
Oct 30 00:14:13.870152 ignition[977]: INFO     : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.32.4-x86-64.raw: attempt #1
Oct 30 00:14:14.247216 systemd-networkd[796]: eth0: Gained IPv6LL
Oct 30 00:14:14.505785 ignition[977]: INFO     : files: createFilesystemsFiles: createFiles: op(6): GET result: OK
Oct 30 00:14:14.912478 ignition[977]: INFO     : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.4-x86-64.raw"
Oct 30 00:14:14.919457 ignition[977]: INFO     : files: createResultFile: createFiles: op(7): [started]  writing file "/sysroot/etc/.ignition-result.json"
Oct 30 00:14:14.919457 ignition[977]: INFO     : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json"
Oct 30 00:14:14.919457 ignition[977]: INFO     : files: files passed
Oct 30 00:14:14.919457 ignition[977]: INFO     : Ignition finished successfully
Oct 30 00:14:14.917819 systemd[1]: Finished ignition-files.service - Ignition (files).
Oct 30 00:14:14.923562 systemd[1]: Starting ignition-quench.service - Ignition (record completion)...
Oct 30 00:14:14.936748 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion...
Oct 30 00:14:14.957411 systemd[1]: ignition-quench.service: Deactivated successfully.
Oct 30 00:14:14.957625 systemd[1]: Finished ignition-quench.service - Ignition (record completion).
Oct 30 00:14:14.970199 initrd-setup-root-after-ignition[1007]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory
Oct 30 00:14:14.970199 initrd-setup-root-after-ignition[1007]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory
Oct 30 00:14:14.977211 initrd-setup-root-after-ignition[1011]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory
Oct 30 00:14:14.976114 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion.
Oct 30 00:14:14.979766 systemd[1]: Reached target ignition-complete.target - Ignition Complete.
Oct 30 00:14:14.988912 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root...
Oct 30 00:14:15.080084 systemd[1]: initrd-parse-etc.service: Deactivated successfully.
Oct 30 00:14:15.080289 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root.
Oct 30 00:14:15.086098 systemd[1]: Reached target initrd-fs.target - Initrd File Systems.
Oct 30 00:14:15.087358 systemd[1]: Reached target initrd.target - Initrd Default Target.
Oct 30 00:14:15.091840 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met.
Oct 30 00:14:15.094596 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook...
Oct 30 00:14:15.128565 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook.
Oct 30 00:14:15.131565 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons...
Oct 30 00:14:15.166660 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups.
Oct 30 00:14:15.167281 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes.
Oct 30 00:14:15.174383 systemd[1]: Stopped target timers.target - Timer Units.
Oct 30 00:14:15.178846 systemd[1]: dracut-pre-pivot.service: Deactivated successfully.
Oct 30 00:14:15.179685 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook.
Oct 30 00:14:15.188082 systemd[1]: Stopped target initrd.target - Initrd Default Target.
Oct 30 00:14:15.193193 systemd[1]: Stopped target basic.target - Basic System.
Oct 30 00:14:15.196382 systemd[1]: Stopped target ignition-complete.target - Ignition Complete.
Oct 30 00:14:15.199872 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup.
Oct 30 00:14:15.203778 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device.
Oct 30 00:14:15.208748 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System.
Oct 30 00:14:15.212726 systemd[1]: Stopped target remote-fs.target - Remote File Systems.
Oct 30 00:14:15.217406 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems.
Oct 30 00:14:15.221641 systemd[1]: Stopped target sysinit.target - System Initialization.
Oct 30 00:14:15.227051 systemd[1]: Stopped target local-fs.target - Local File Systems.
Oct 30 00:14:15.230702 systemd[1]: Stopped target swap.target - Swaps.
Oct 30 00:14:15.234076 systemd[1]: dracut-pre-mount.service: Deactivated successfully.
Oct 30 00:14:15.235064 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook.
Oct 30 00:14:15.242728 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes.
Oct 30 00:14:15.245822 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre).
Oct 30 00:14:15.250944 systemd[1]: clevis-luks-askpass.path: Deactivated successfully.
Oct 30 00:14:15.251823 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch.
Oct 30 00:14:15.256577 systemd[1]: dracut-initqueue.service: Deactivated successfully.
Oct 30 00:14:15.258041 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook.
Oct 30 00:14:15.264943 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully.
Oct 30 00:14:15.265637 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion.
Oct 30 00:14:15.267850 systemd[1]: ignition-files.service: Deactivated successfully.
Oct 30 00:14:15.268060 systemd[1]: Stopped ignition-files.service - Ignition (files).
Oct 30 00:14:15.276819 systemd[1]: Stopping ignition-mount.service - Ignition (mount)...
Oct 30 00:14:15.289973 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot...
Oct 30 00:14:15.301167 systemd[1]: systemd-udev-trigger.service: Deactivated successfully.
Oct 30 00:14:15.302777 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices.
Oct 30 00:14:15.313340 systemd[1]: dracut-pre-trigger.service: Deactivated successfully.
Oct 30 00:14:15.313627 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook.
Oct 30 00:14:15.331608 systemd[1]: initrd-cleanup.service: Deactivated successfully.
Oct 30 00:14:15.333544 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons.
Oct 30 00:14:15.343468 ignition[1031]: INFO     : Ignition 2.22.0
Oct 30 00:14:15.343468 ignition[1031]: INFO     : Stage: umount
Oct 30 00:14:15.348143 ignition[1031]: INFO     : no configs at "/usr/lib/ignition/base.d"
Oct 30 00:14:15.348143 ignition[1031]: INFO     : no config dir at "/usr/lib/ignition/base.platform.d/gcp"
Oct 30 00:14:15.348143 ignition[1031]: INFO     : umount: umount passed
Oct 30 00:14:15.348143 ignition[1031]: INFO     : Ignition finished successfully
Oct 30 00:14:15.349553 systemd[1]: ignition-mount.service: Deactivated successfully.
Oct 30 00:14:15.349887 systemd[1]: Stopped ignition-mount.service - Ignition (mount).
Oct 30 00:14:15.360675 systemd[1]: ignition-disks.service: Deactivated successfully.
Oct 30 00:14:15.360793 systemd[1]: Stopped ignition-disks.service - Ignition (disks).
Oct 30 00:14:15.365708 systemd[1]: ignition-kargs.service: Deactivated successfully.
Oct 30 00:14:15.365860 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs).
Oct 30 00:14:15.373292 systemd[1]: ignition-fetch.service: Deactivated successfully.
Oct 30 00:14:15.373402 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch).
Oct 30 00:14:15.378457 systemd[1]: Stopped target network.target - Network.
Oct 30 00:14:15.378676 systemd[1]: ignition-fetch-offline.service: Deactivated successfully.
Oct 30 00:14:15.378833 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline).
Oct 30 00:14:15.386351 systemd[1]: Stopped target paths.target - Path Units.
Oct 30 00:14:15.386525 systemd[1]: systemd-ask-password-console.path: Deactivated successfully.
Oct 30 00:14:15.386869 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch.
Oct 30 00:14:15.394403 systemd[1]: Stopped target slices.target - Slice Units.
Oct 30 00:14:15.396294 systemd[1]: Stopped target sockets.target - Socket Units.
Oct 30 00:14:15.400753 systemd[1]: iscsid.socket: Deactivated successfully.
Oct 30 00:14:15.401050 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket.
Oct 30 00:14:15.408923 systemd[1]: iscsiuio.socket: Deactivated successfully.
Oct 30 00:14:15.409059 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket.
Oct 30 00:14:15.413111 systemd[1]: ignition-setup.service: Deactivated successfully.
Oct 30 00:14:15.413270 systemd[1]: Stopped ignition-setup.service - Ignition (setup).
Oct 30 00:14:15.416165 systemd[1]: ignition-setup-pre.service: Deactivated successfully.
Oct 30 00:14:15.416276 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup.
Oct 30 00:14:15.422717 systemd[1]: Stopping systemd-networkd.service - Network Configuration...
Oct 30 00:14:15.429284 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution...
Oct 30 00:14:15.434916 systemd[1]: sysroot-boot.mount: Deactivated successfully.
Oct 30 00:14:15.436118 systemd[1]: systemd-networkd.service: Deactivated successfully.
Oct 30 00:14:15.436314 systemd[1]: Stopped systemd-networkd.service - Network Configuration.
Oct 30 00:14:15.445460 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully.
Oct 30 00:14:15.445831 systemd[1]: systemd-resolved.service: Deactivated successfully.
Oct 30 00:14:15.446139 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution.
Oct 30 00:14:15.453388 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully.
Oct 30 00:14:15.453837 systemd[1]: sysroot-boot.service: Deactivated successfully.
Oct 30 00:14:15.454046 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot.
Oct 30 00:14:15.461181 systemd[1]: Stopped target network-pre.target - Preparation for Network.
Oct 30 00:14:15.466906 systemd[1]: systemd-networkd.socket: Deactivated successfully.
Oct 30 00:14:15.467446 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket.
Oct 30 00:14:15.469654 systemd[1]: initrd-setup-root.service: Deactivated successfully.
Oct 30 00:14:15.470830 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup.
Oct 30 00:14:15.478831 systemd[1]: Stopping network-cleanup.service - Network Cleanup...
Oct 30 00:14:15.485734 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully.
Oct 30 00:14:15.485921 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline.
Oct 30 00:14:15.491437 systemd[1]: systemd-sysctl.service: Deactivated successfully.
Oct 30 00:14:15.491594 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables.
Oct 30 00:14:15.498533 systemd[1]: systemd-modules-load.service: Deactivated successfully.
Oct 30 00:14:15.498649 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules.
Oct 30 00:14:15.507423 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.
Oct 30 00:14:15.507584 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories.
Oct 30 00:14:15.517060 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files...
Oct 30 00:14:15.526409 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully.
Oct 30 00:14:15.526587 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully.
Oct 30 00:14:15.545062 systemd[1]: systemd-udevd.service: Deactivated successfully.
Oct 30 00:14:15.545446 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files.
Oct 30 00:14:15.551790 systemd[1]: network-cleanup.service: Deactivated successfully.
Oct 30 00:14:15.552128 systemd[1]: Stopped network-cleanup.service - Network Cleanup.
Oct 30 00:14:15.558603 systemd[1]: systemd-udevd-control.socket: Deactivated successfully.
Oct 30 00:14:15.558740 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket.
Oct 30 00:14:15.559237 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully.
Oct 30 00:14:15.559318 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket.
Oct 30 00:14:15.559441 systemd[1]: dracut-pre-udev.service: Deactivated successfully.
Oct 30 00:14:15.559511 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook.
Oct 30 00:14:15.560081 systemd[1]: dracut-cmdline.service: Deactivated successfully.
Oct 30 00:14:15.560170 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook.
Oct 30 00:14:15.570227 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully.
Oct 30 00:14:15.570696 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters.
Oct 30 00:14:15.587164 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database...
Oct 30 00:14:15.598175 systemd[1]: systemd-network-generator.service: Deactivated successfully.
Oct 30 00:14:15.598440 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line.
Oct 30 00:14:15.603393 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.
Oct 30 00:14:15.603498 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev.
Oct 30 00:14:15.609459 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.
Oct 30 00:14:15.609713 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully.
Oct 30 00:14:15.618767 systemd[1]: kmod-static-nodes.service: Deactivated successfully.
Oct 30 00:14:15.618866 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes.
Oct 30 00:14:15.624380 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
Oct 30 00:14:15.624505 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup.
Oct 30 00:14:15.631808 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully.
Oct 30 00:14:15.631884 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev\x2dearly.service.mount: Deactivated successfully.
Oct 30 00:14:15.631936 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully.
Oct 30 00:14:15.632061 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully.
Oct 30 00:14:15.632908 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.
Oct 30 00:14:15.713150 systemd-journald[191]: Received SIGTERM from PID 1 (systemd).
Oct 30 00:14:15.633097 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database.
Oct 30 00:14:15.639876 systemd[1]: Reached target initrd-switch-root.target - Switch Root.
Oct 30 00:14:15.645973 systemd[1]: Starting initrd-switch-root.service - Switch Root...
Oct 30 00:14:15.679996 systemd[1]: Switching root.
Oct 30 00:14:15.723233 systemd-journald[191]: Journal stopped
Oct 30 00:14:17.948839 kernel: SELinux:  policy capability network_peer_controls=1
Oct 30 00:14:17.948900 kernel: SELinux:  policy capability open_perms=1
Oct 30 00:14:17.948936 kernel: SELinux:  policy capability extended_socket_class=1
Oct 30 00:14:17.948982 kernel: SELinux:  policy capability always_check_network=0
Oct 30 00:14:17.949008 kernel: SELinux:  policy capability cgroup_seclabel=1
Oct 30 00:14:17.949033 kernel: SELinux:  policy capability nnp_nosuid_transition=1
Oct 30 00:14:17.949059 kernel: SELinux:  policy capability genfs_seclabel_symlinks=0
Oct 30 00:14:17.949078 kernel: SELinux:  policy capability ioctl_skip_cloexec=0
Oct 30 00:14:17.949106 kernel: SELinux:  policy capability userspace_initial_context=0
Oct 30 00:14:17.949126 kernel: audit: type=1403 audit(1761783256.253:2): auid=4294967295 ses=4294967295 lsm=selinux res=1
Oct 30 00:14:17.949149 systemd[1]: Successfully loaded SELinux policy in 75.785ms.
Oct 30 00:14:17.949182 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 9.596ms.
Oct 30 00:14:17.949213 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE)
Oct 30 00:14:17.949238 systemd[1]: Detected virtualization google.
Oct 30 00:14:17.949288 systemd[1]: Detected architecture x86-64.
Oct 30 00:14:17.949320 systemd[1]: Detected first boot.
Oct 30 00:14:17.949347 systemd[1]: Initializing machine ID from random generator.
Oct 30 00:14:17.949374 zram_generator::config[1075]: No configuration found.
Oct 30 00:14:17.949404 kernel: Guest personality initialized and is inactive
Oct 30 00:14:17.949436 kernel: VMCI host device registered (name=vmci, major=10, minor=125)
Oct 30 00:14:17.949464 kernel: Initialized host personality
Oct 30 00:14:17.949491 kernel: NET: Registered PF_VSOCK protocol family
Oct 30 00:14:17.949523 systemd[1]: Populated /etc with preset unit settings.
Oct 30 00:14:17.949556 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully.
Oct 30 00:14:17.949586 systemd[1]: initrd-switch-root.service: Deactivated successfully.
Oct 30 00:14:17.949617 systemd[1]: Stopped initrd-switch-root.service - Switch Root.
Oct 30 00:14:17.949646 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.
Oct 30 00:14:17.949683 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config.
Oct 30 00:14:17.949713 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run.
Oct 30 00:14:17.949746 systemd[1]: Created slice system-getty.slice - Slice /system/getty.
Oct 30 00:14:17.949777 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe.
Oct 30 00:14:17.949809 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty.
Oct 30 00:14:17.949841 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit.
Oct 30 00:14:17.949874 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck.
Oct 30 00:14:17.949911 systemd[1]: Created slice user.slice - User and Session Slice.
Oct 30 00:14:17.949943 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch.
Oct 30 00:14:17.952655 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch.
Oct 30 00:14:17.952893 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch.
Oct 30 00:14:17.952928 systemd[1]: Set up automount boot.automount - Boot partition Automount Point.
Oct 30 00:14:17.953160 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point.
Oct 30 00:14:17.953209 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM...
Oct 30 00:14:17.953243 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0...
Oct 30 00:14:17.953275 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre).
Oct 30 00:14:17.953321 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes.
Oct 30 00:14:17.953349 systemd[1]: Stopped target initrd-switch-root.target - Switch Root.
Oct 30 00:14:17.953377 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems.
Oct 30 00:14:17.953402 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System.
Oct 30 00:14:17.953423 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes.
Oct 30 00:14:17.953447 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes.
Oct 30 00:14:17.953469 systemd[1]: Reached target remote-fs.target - Remote File Systems.
Oct 30 00:14:17.953496 systemd[1]: Reached target slices.target - Slice Units.
Oct 30 00:14:17.953517 systemd[1]: Reached target swap.target - Swaps.
Oct 30 00:14:17.953538 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes.
Oct 30 00:14:17.953560 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket.
Oct 30 00:14:17.953581 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption.
Oct 30 00:14:17.953603 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket.
Oct 30 00:14:17.953629 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket.
Oct 30 00:14:17.953651 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket.
Oct 30 00:14:17.953673 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket.
Oct 30 00:14:17.953696 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System...
Oct 30 00:14:17.953722 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System...
Oct 30 00:14:17.953745 systemd[1]: Mounting media.mount - External Media Directory...
Oct 30 00:14:17.953767 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen).
Oct 30 00:14:17.953797 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System...
Oct 30 00:14:17.953828 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System...
Oct 30 00:14:17.953860 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp...
Oct 30 00:14:17.953892 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw).
Oct 30 00:14:17.953923 systemd[1]: Reached target machines.target - Containers.
Oct 30 00:14:17.953999 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files...
Oct 30 00:14:17.954032 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met.
Oct 30 00:14:17.954063 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes...
Oct 30 00:14:17.954100 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs...
Oct 30 00:14:17.954133 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod...
Oct 30 00:14:17.954166 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm...
Oct 30 00:14:17.954199 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore...
Oct 30 00:14:17.954232 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse...
Oct 30 00:14:17.954263 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop...
Oct 30 00:14:17.954305 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf).
Oct 30 00:14:17.954337 systemd[1]: systemd-fsck-root.service: Deactivated successfully.
Oct 30 00:14:17.954367 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device.
Oct 30 00:14:17.954407 systemd[1]: systemd-fsck-usr.service: Deactivated successfully.
Oct 30 00:14:17.954439 systemd[1]: Stopped systemd-fsck-usr.service.
Oct 30 00:14:17.954473 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67).
Oct 30 00:14:17.954506 systemd[1]: Starting systemd-journald.service - Journal Service...
Oct 30 00:14:17.954539 kernel: fuse: init (API version 7.41)
Oct 30 00:14:17.954570 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules...
Oct 30 00:14:17.954603 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line...
Oct 30 00:14:17.954635 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems...
Oct 30 00:14:17.954673 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials...
Oct 30 00:14:17.954705 kernel: loop: module loaded
Oct 30 00:14:17.954737 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices...
Oct 30 00:14:17.954768 systemd[1]: verity-setup.service: Deactivated successfully.
Oct 30 00:14:17.954801 systemd[1]: Stopped verity-setup.service.
Oct 30 00:14:17.954834 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen).
Oct 30 00:14:17.954867 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System.
Oct 30 00:14:17.954899 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System.
Oct 30 00:14:17.954936 systemd[1]: Mounted media.mount - External Media Directory.
Oct 30 00:14:17.957012 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System.
Oct 30 00:14:17.957051 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System.
Oct 30 00:14:17.957083 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp.
Oct 30 00:14:17.957117 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes.
Oct 30 00:14:17.957149 systemd[1]: modprobe@configfs.service: Deactivated successfully.
Oct 30 00:14:17.957180 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs.
Oct 30 00:14:17.957210 systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
Oct 30 00:14:17.957242 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod.
Oct 30 00:14:17.957292 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
Oct 30 00:14:17.957326 kernel: ACPI: bus type drm_connector registered
Oct 30 00:14:17.957356 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore.
Oct 30 00:14:17.957385 systemd[1]: modprobe@drm.service: Deactivated successfully.
Oct 30 00:14:17.957419 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm.
Oct 30 00:14:17.957452 systemd[1]: modprobe@fuse.service: Deactivated successfully.
Oct 30 00:14:17.957488 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse.
Oct 30 00:14:17.957524 systemd[1]: modprobe@loop.service: Deactivated successfully.
Oct 30 00:14:17.957555 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop.
Oct 30 00:14:17.957590 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules.
Oct 30 00:14:17.957621 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems.
Oct 30 00:14:17.957650 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System...
Oct 30 00:14:17.957681 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System...
Oct 30 00:14:17.957726 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).
Oct 30 00:14:17.957764 systemd[1]: Reached target local-fs.target - Local File Systems.
Oct 30 00:14:17.957794 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management.
Oct 30 00:14:17.957834 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache...
Oct 30 00:14:17.957870 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met.
Oct 30 00:14:17.957903 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database...
Oct 30 00:14:17.957934 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
Oct 30 00:14:17.958011 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed...
Oct 30 00:14:17.958088 systemd-journald[1146]: Collecting audit messages is disabled.
Oct 30 00:14:17.958148 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met.
Oct 30 00:14:17.958173 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables...
Oct 30 00:14:17.958202 systemd-journald[1146]: Journal started
Oct 30 00:14:17.958263 systemd-journald[1146]: Runtime Journal (/run/log/journal/5c81e105f2e44ec5986abb2f739e9401) is 8M, max 148.6M, 140.6M free.
Oct 30 00:14:17.246127 systemd[1]: Queued start job for default target multi-user.target.
Oct 30 00:14:17.260466 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6.
Oct 30 00:14:17.261190 systemd[1]: systemd-journald.service: Deactivated successfully.
Oct 30 00:14:17.964381 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/...
Oct 30 00:14:17.980706 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully...
Oct 30 00:14:17.995175 systemd[1]: Started systemd-journald.service - Journal Service.
Oct 30 00:14:17.993574 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line.
Oct 30 00:14:17.998504 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials.
Oct 30 00:14:18.002585 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System.
Oct 30 00:14:18.005341 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System.
Oct 30 00:14:18.054243 systemd[1]: Reached target network-pre.target - Preparation for Network.
Oct 30 00:14:18.065227 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage...
Oct 30 00:14:18.071273 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files.
Oct 30 00:14:18.088044 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed.
Oct 30 00:14:18.095876 systemd[1]: Reached target first-boot-complete.target - First Boot Complete.
Oct 30 00:14:18.102902 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk...
Oct 30 00:14:18.113337 kernel: loop0: detected capacity change from 0 to 110984
Oct 30 00:14:18.128210 systemd-journald[1146]: Time spent on flushing to /var/log/journal/5c81e105f2e44ec5986abb2f739e9401 is 168.159ms for 950 entries.
Oct 30 00:14:18.128210 systemd-journald[1146]: System Journal (/var/log/journal/5c81e105f2e44ec5986abb2f739e9401) is 8M, max 584.8M, 576.8M free.
Oct 30 00:14:18.334457 systemd-journald[1146]: Received client request to flush runtime journal.
Oct 30 00:14:18.336049 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher
Oct 30 00:14:18.336110 kernel: loop1: detected capacity change from 0 to 128016
Oct 30 00:14:18.160808 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables.
Oct 30 00:14:18.179342 systemd-tmpfiles[1177]: ACLs are not supported, ignoring.
Oct 30 00:14:18.179379 systemd-tmpfiles[1177]: ACLs are not supported, ignoring.
Oct 30 00:14:18.210057 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk.
Oct 30 00:14:18.235325 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully.
Oct 30 00:14:18.251407 systemd[1]: Starting systemd-sysusers.service - Create System Users...
Oct 30 00:14:18.266884 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
Oct 30 00:14:18.303860 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices.
Oct 30 00:14:18.338408 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage.
Oct 30 00:14:18.359187 kernel: loop2: detected capacity change from 0 to 224512
Oct 30 00:14:18.390415 systemd[1]: Finished systemd-sysusers.service - Create System Users.
Oct 30 00:14:18.396913 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev...
Oct 30 00:14:18.471407 kernel: loop3: detected capacity change from 0 to 50736
Oct 30 00:14:18.495239 systemd-tmpfiles[1219]: ACLs are not supported, ignoring.
Oct 30 00:14:18.495276 systemd-tmpfiles[1219]: ACLs are not supported, ignoring.
Oct 30 00:14:18.512023 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev.
Oct 30 00:14:18.546018 kernel: loop4: detected capacity change from 0 to 110984
Oct 30 00:14:18.587001 kernel: loop5: detected capacity change from 0 to 128016
Oct 30 00:14:18.642001 kernel: loop6: detected capacity change from 0 to 224512
Oct 30 00:14:18.672539 kernel: loop7: detected capacity change from 0 to 50736
Oct 30 00:14:18.730698 (sd-merge)[1224]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-gce'.
Oct 30 00:14:18.732045 (sd-merge)[1224]: Merged extensions into '/usr'.
Oct 30 00:14:18.751134 systemd[1]: Reload requested from client PID 1176 ('systemd-sysext') (unit systemd-sysext.service)...
Oct 30 00:14:18.751171 systemd[1]: Reloading...
Oct 30 00:14:18.934988 zram_generator::config[1250]: No configuration found.
Oct 30 00:14:19.267043 ldconfig[1172]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start.
Oct 30 00:14:19.472505 systemd[1]: Reloading finished in 719 ms.
Oct 30 00:14:19.490486 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache.
Oct 30 00:14:19.495005 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/.
Oct 30 00:14:19.511923 systemd[1]: Starting ensure-sysext.service...
Oct 30 00:14:19.518229 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories...
Oct 30 00:14:19.571832 systemd[1]: Reload requested from client PID 1290 ('systemctl') (unit ensure-sysext.service)...
Oct 30 00:14:19.571862 systemd[1]: Reloading...
Oct 30 00:14:19.581691 systemd-tmpfiles[1291]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring.
Oct 30 00:14:19.581748 systemd-tmpfiles[1291]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring.
Oct 30 00:14:19.582849 systemd-tmpfiles[1291]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring.
Oct 30 00:14:19.583581 systemd-tmpfiles[1291]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring.
Oct 30 00:14:19.585600 systemd-tmpfiles[1291]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring.
Oct 30 00:14:19.586391 systemd-tmpfiles[1291]: ACLs are not supported, ignoring.
Oct 30 00:14:19.586644 systemd-tmpfiles[1291]: ACLs are not supported, ignoring.
Oct 30 00:14:19.604424 systemd-tmpfiles[1291]: Detected autofs mount point /boot during canonicalization of boot.
Oct 30 00:14:19.604447 systemd-tmpfiles[1291]: Skipping /boot
Oct 30 00:14:19.627392 systemd-tmpfiles[1291]: Detected autofs mount point /boot during canonicalization of boot.
Oct 30 00:14:19.627418 systemd-tmpfiles[1291]: Skipping /boot
Oct 30 00:14:19.731037 zram_generator::config[1321]: No configuration found.
Oct 30 00:14:20.010092 systemd[1]: Reloading finished in 437 ms.
Oct 30 00:14:20.032466 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database.
Oct 30 00:14:20.053267 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories.
Oct 30 00:14:20.066449 systemd[1]: Starting audit-rules.service - Load Audit Rules...
Oct 30 00:14:20.073400 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs...
Oct 30 00:14:20.082014 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog...
Oct 30 00:14:20.094820 systemd[1]: Starting systemd-resolved.service - Network Name Resolution...
Oct 30 00:14:20.099482 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files...
Oct 30 00:14:20.107403 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP...
Oct 30 00:14:20.116570 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen).
Oct 30 00:14:20.117461 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met.
Oct 30 00:14:20.121174 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod...
Oct 30 00:14:20.132107 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore...
Oct 30 00:14:20.138508 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop...
Oct 30 00:14:20.142257 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met.
Oct 30 00:14:20.143247 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67).
Oct 30 00:14:20.143444 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen).
Oct 30 00:14:20.154452 systemd[1]: Starting systemd-userdbd.service - User Database Manager...
Oct 30 00:14:20.164372 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen).
Oct 30 00:14:20.165673 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met.
Oct 30 00:14:20.166440 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met.
Oct 30 00:14:20.166666 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67).
Oct 30 00:14:20.166848 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen).
Oct 30 00:14:20.188573 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen).
Oct 30 00:14:20.190512 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met.
Oct 30 00:14:20.201709 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm...
Oct 30 00:14:20.208363 systemd[1]: Starting setup-oem.service - Setup OEM...
Oct 30 00:14:20.211282 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met.
Oct 30 00:14:20.211577 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67).
Oct 30 00:14:20.211998 systemd[1]: Reached target time-set.target - System Time Set.
Oct 30 00:14:20.215247 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen).
Oct 30 00:14:20.219807 systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
Oct 30 00:14:20.220561 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod.
Oct 30 00:14:20.239105 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog.
Oct 30 00:14:20.244664 systemd[1]: Finished ensure-sysext.service.
Oct 30 00:14:20.247215 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
Oct 30 00:14:20.247910 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore.
Oct 30 00:14:20.252027 systemd[1]: modprobe@loop.service: Deactivated successfully.
Oct 30 00:14:20.252395 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop.
Oct 30 00:14:20.276032 systemd[1]: modprobe@drm.service: Deactivated successfully.
Oct 30 00:14:20.278058 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm.
Oct 30 00:14:20.283726 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
Oct 30 00:14:20.284262 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met.
Oct 30 00:14:20.289108 systemd[1]: Starting systemd-update-done.service - Update is Completed...
Oct 30 00:14:20.294351 systemd[1]: Finished setup-oem.service - Setup OEM.
Oct 30 00:14:20.305403 systemd[1]: Starting oem-gce-enable-oslogin.service - Enable GCE OS Login...
Oct 30 00:14:20.315501 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP.
Oct 30 00:14:20.368150 systemd[1]: Started systemd-userdbd.service - User Database Manager.
Oct 30 00:14:20.397089 systemd[1]: Finished systemd-update-done.service - Update is Completed.
Oct 30 00:14:20.405079 systemd-udevd[1365]: Using default interface naming scheme 'v255'.
Oct 30 00:14:20.420523 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs.
Oct 30 00:14:20.431462 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt).
Oct 30 00:14:20.450163 augenrules[1418]: No rules
Oct 30 00:14:20.453940 systemd[1]: audit-rules.service: Deactivated successfully.
Oct 30 00:14:20.454441 systemd[1]: Finished audit-rules.service - Load Audit Rules.
Oct 30 00:14:20.464027 systemd[1]: Finished oem-gce-enable-oslogin.service - Enable GCE OS Login.
Oct 30 00:14:20.501317 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files.
Oct 30 00:14:20.517232 systemd[1]: Starting systemd-networkd.service - Network Configuration...
Oct 30 00:14:20.554660 systemd-resolved[1363]: Positive Trust Anchors:
Oct 30 00:14:20.556653 systemd-resolved[1363]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
Oct 30 00:14:20.556757 systemd-resolved[1363]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test
Oct 30 00:14:20.579357 systemd-resolved[1363]: Defaulting to hostname 'linux'.
Oct 30 00:14:20.582788 systemd[1]: Started systemd-resolved.service - Network Name Resolution.
Oct 30 00:14:20.592411 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups.
Oct 30 00:14:20.603216 systemd[1]: Reached target sysinit.target - System Initialization.
Oct 30 00:14:20.613363 systemd[1]: Started motdgen.path - Watch for update engine configuration changes.
Oct 30 00:14:20.623237 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data.
Oct 30 00:14:20.634154 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer.
Oct 30 00:14:20.645431 systemd[1]: Started logrotate.timer - Daily rotation of log files.
Oct 30 00:14:20.654544 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information..
Oct 30 00:14:20.665181 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories.
Oct 30 00:14:20.675175 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate).
Oct 30 00:14:20.675421 systemd[1]: Reached target paths.target - Path Units.
Oct 30 00:14:20.683122 systemd[1]: Reached target timers.target - Timer Units.
Oct 30 00:14:20.695222 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket.
Oct 30 00:14:20.707575 systemd[1]: Starting docker.socket - Docker Socket for the API...
Oct 30 00:14:20.726986 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).
Oct 30 00:14:20.738415 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK).
Oct 30 00:14:20.750127 systemd[1]: Reached target ssh-access.target - SSH Access Available.
Oct 30 00:14:20.770100 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket.
Oct 30 00:14:20.779780 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket.
Oct 30 00:14:20.799438 systemd[1]: Listening on docker.socket - Docker Socket for the API.
Oct 30 00:14:20.813325 systemd-networkd[1434]: lo: Link UP
Oct 30 00:14:20.813349 systemd-networkd[1434]: lo: Gained carrier
Oct 30 00:14:20.814716 systemd-networkd[1434]: Enumeration completed
Oct 30 00:14:20.814863 systemd[1]: Started systemd-networkd.service - Network Configuration.
Oct 30 00:14:20.830761 systemd[1]: Condition check resulted in dev-tpmrm0.device - /dev/tpmrm0 being skipped.
Oct 30 00:14:20.833668 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped.
Oct 30 00:14:20.834070 systemd[1]: Reached target network.target - Network.
Oct 30 00:14:20.843099 systemd[1]: Reached target sockets.target - Socket Units.
Oct 30 00:14:20.852165 systemd[1]: Reached target basic.target - Basic System.
Oct 30 00:14:20.860217 systemd[1]: Reached target tpm2.target - Trusted Platform Module.
Oct 30 00:14:20.869162 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met.
Oct 30 00:14:20.869209 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met.
Oct 30 00:14:20.872227 systemd[1]: Starting containerd.service - containerd container runtime...
Oct 30 00:14:20.889207 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent...
Oct 30 00:14:20.900844 systemd[1]: Starting dbus.service - D-Bus System Message Bus...
Oct 30 00:14:20.912537 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown...
Oct 30 00:14:20.924215 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit...
Oct 30 00:14:20.943924 systemd[1]: Starting extend-filesystems.service - Extend Filesystems...
Oct 30 00:14:20.953140 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment).
Oct 30 00:14:20.970295 jq[1466]: false
Oct 30 00:14:20.971163 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh...
Oct 30 00:14:20.986039 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd...
Oct 30 00:14:21.000314 systemd[1]: Started ntpd.service - Network Time Service.
Oct 30 00:14:21.015239 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline...
Oct 30 00:14:21.034577 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys...
Oct 30 00:14:21.061906 systemd[1]: Starting systemd-logind.service - User Login Management...
Oct 30 00:14:21.076297 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd...
Oct 30 00:14:21.090480 google_oslogin_nss_cache[1468]: oslogin_cache_refresh[1468]: Refreshing passwd entry cache
Oct 30 00:14:21.087251 oslogin_cache_refresh[1468]: Refreshing passwd entry cache
Oct 30 00:14:21.095133 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured...
Oct 30 00:14:21.106686 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionSecurity=!tpm2).
Oct 30 00:14:21.108342 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details.
Oct 30 00:14:21.113360 systemd[1]: Starting update-engine.service - Update Engine...
Oct 30 00:14:21.125032 google_oslogin_nss_cache[1468]: oslogin_cache_refresh[1468]: Failure getting users, quitting
Oct 30 00:14:21.125032 google_oslogin_nss_cache[1468]: oslogin_cache_refresh[1468]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak.
Oct 30 00:14:21.125032 google_oslogin_nss_cache[1468]: oslogin_cache_refresh[1468]: Refreshing group entry cache
Oct 30 00:14:21.125032 google_oslogin_nss_cache[1468]: oslogin_cache_refresh[1468]: Failure getting groups, quitting
Oct 30 00:14:21.125032 google_oslogin_nss_cache[1468]: oslogin_cache_refresh[1468]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak.
Oct 30 00:14:21.118319 oslogin_cache_refresh[1468]: Failure getting users, quitting
Oct 30 00:14:21.118348 oslogin_cache_refresh[1468]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak.
Oct 30 00:14:21.118425 oslogin_cache_refresh[1468]: Refreshing group entry cache
Oct 30 00:14:21.124608 oslogin_cache_refresh[1468]: Failure getting groups, quitting
Oct 30 00:14:21.124629 oslogin_cache_refresh[1468]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak.
Oct 30 00:14:21.127222 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition...
Oct 30 00:14:21.145266 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown.
Oct 30 00:14:21.156799 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'.
Oct 30 00:14:21.157467 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped.
Oct 30 00:14:21.159116 systemd[1]: google-oslogin-cache.service: Deactivated successfully.
Oct 30 00:14:21.162394 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh.
Oct 30 00:14:21.168020 extend-filesystems[1467]: Found /dev/sda6
Oct 30 00:14:21.168885 ntpd[1472]: ntpd 4.2.8p18@1.4062-o Wed Oct 29 21:31:58 UTC 2025 (1): Starting
Oct 30 00:14:21.173134 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully.
Oct 30 00:14:21.210886 ntpd[1472]: 30 Oct 00:14:21 ntpd[1472]: ntpd 4.2.8p18@1.4062-o Wed Oct 29 21:31:58 UTC 2025 (1): Starting
Oct 30 00:14:21.210886 ntpd[1472]: 30 Oct 00:14:21 ntpd[1472]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp
Oct 30 00:14:21.210886 ntpd[1472]: 30 Oct 00:14:21 ntpd[1472]: ----------------------------------------------------
Oct 30 00:14:21.210886 ntpd[1472]: 30 Oct 00:14:21 ntpd[1472]: ntp-4 is maintained by Network Time Foundation,
Oct 30 00:14:21.210886 ntpd[1472]: 30 Oct 00:14:21 ntpd[1472]: Inc. (NTF), a non-profit 501(c)(3) public-benefit
Oct 30 00:14:21.210886 ntpd[1472]: 30 Oct 00:14:21 ntpd[1472]: corporation.  Support and training for ntp-4 are
Oct 30 00:14:21.210886 ntpd[1472]: 30 Oct 00:14:21 ntpd[1472]: available at https://www.nwtime.org/support
Oct 30 00:14:21.210886 ntpd[1472]: 30 Oct 00:14:21 ntpd[1472]: ----------------------------------------------------
Oct 30 00:14:21.210886 ntpd[1472]: 30 Oct 00:14:21 ntpd[1472]: proto: precision = 0.083 usec (-23)
Oct 30 00:14:21.177630 ntpd[1472]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp
Oct 30 00:14:21.174429 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline.
Oct 30 00:14:21.240664 extend-filesystems[1467]: Found /dev/sda9
Oct 30 00:14:21.249175 ntpd[1472]: 30 Oct 00:14:21 ntpd[1472]: basedate set to 2025-10-17
Oct 30 00:14:21.249175 ntpd[1472]: 30 Oct 00:14:21 ntpd[1472]: gps base set to 2025-10-19 (week 2389)
Oct 30 00:14:21.249175 ntpd[1472]: 30 Oct 00:14:21 ntpd[1472]: Listen and drop on 0 v6wildcard [::]:123
Oct 30 00:14:21.249175 ntpd[1472]: 30 Oct 00:14:21 ntpd[1472]: Listen and drop on 1 v4wildcard 0.0.0.0:123
Oct 30 00:14:21.249175 ntpd[1472]: 30 Oct 00:14:21 ntpd[1472]: Listen normally on 2 lo 127.0.0.1:123
Oct 30 00:14:21.249175 ntpd[1472]: 30 Oct 00:14:21 ntpd[1472]: Listen normally on 3 lo [::1]:123
Oct 30 00:14:21.249175 ntpd[1472]: 30 Oct 00:14:21 ntpd[1472]: Listening on routing socket on fd #20 for interface updates
Oct 30 00:14:21.179263 ntpd[1472]: ----------------------------------------------------
Oct 30 00:14:21.186122 systemd[1]: motdgen.service: Deactivated successfully.
Oct 30 00:14:21.180707 ntpd[1472]: ntp-4 is maintained by Network Time Foundation,
Oct 30 00:14:21.187106 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd.
Oct 30 00:14:21.180724 ntpd[1472]: Inc. (NTF), a non-profit 501(c)(3) public-benefit
Oct 30 00:14:21.219701 (ntainerd)[1499]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR
Oct 30 00:14:21.180738 ntpd[1472]: corporation.  Support and training for ntp-4 are
Oct 30 00:14:21.180752 ntpd[1472]: available at https://www.nwtime.org/support
Oct 30 00:14:21.270000 extend-filesystems[1467]: Checking size of /dev/sda9
Oct 30 00:14:21.266848 systemd-networkd[1434]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name.
Oct 30 00:14:21.180765 ntpd[1472]: ----------------------------------------------------
Oct 30 00:14:21.277342 ntpd[1472]: 30 Oct 00:14:21 ntpd[1472]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
Oct 30 00:14:21.277342 ntpd[1472]: 30 Oct 00:14:21 ntpd[1472]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
Oct 30 00:14:21.266858 systemd-networkd[1434]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network.
Oct 30 00:14:21.193539 ntpd[1472]: proto: precision = 0.083 usec (-23)
Oct 30 00:14:21.267637 systemd-networkd[1434]: eth0: Link UP
Oct 30 00:14:21.222715 ntpd[1472]: basedate set to 2025-10-17
Oct 30 00:14:21.268004 systemd-networkd[1434]: eth0: Gained carrier
Oct 30 00:14:21.222746 ntpd[1472]: gps base set to 2025-10-19 (week 2389)
Oct 30 00:14:21.268038 systemd-networkd[1434]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name.
Oct 30 00:14:21.230018 ntpd[1472]: Listen and drop on 0 v6wildcard [::]:123
Oct 30 00:14:21.230092 ntpd[1472]: Listen and drop on 1 v4wildcard 0.0.0.0:123
Oct 30 00:14:21.230339 ntpd[1472]: Listen normally on 2 lo 127.0.0.1:123
Oct 30 00:14:21.230388 ntpd[1472]: Listen normally on 3 lo [::1]:123
Oct 30 00:14:21.230424 ntpd[1472]: Listening on routing socket on fd #20 for interface updates
Oct 30 00:14:21.275508 ntpd[1472]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
Oct 30 00:14:21.275558 ntpd[1472]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
Oct 30 00:14:21.279057 systemd-networkd[1434]: eth0: Overlong DHCP hostname received, shortened from 'ci-4459-1-0-nightly-20251029-2100-f6179af3e514b1c6b030.c.flatcar-212911.internal' to 'ci-4459-1-0-nightly-20251029-2100-f6179af3e514b1c6b030'
Oct 30 00:14:21.279084 systemd-networkd[1434]: eth0: DHCPv4 address 10.128.0.4/32, gateway 10.128.0.1 acquired from 169.254.169.254
Oct 30 00:14:21.315156 jq[1491]: true
Oct 30 00:14:21.384302 update_engine[1487]: I20251030 00:14:21.384166  1487 main.cc:92] Flatcar Update Engine starting
Oct 30 00:14:21.414277 jq[1513]: true
Oct 30 00:14:21.436405 kernel: mousedev: PS/2 mouse device common for all mice
Oct 30 00:14:21.435357 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - PersistentDisk OEM.
Oct 30 00:14:21.444759 extend-filesystems[1467]: Resized partition /dev/sda9
Oct 30 00:14:21.470255 coreos-metadata[1463]: Oct 30 00:14:21.470 INFO Fetching http://169.254.169.254/computeMetadata/v1/instance/hostname: Attempt #1
Oct 30 00:14:21.479125 coreos-metadata[1463]: Oct 30 00:14:21.479 INFO Fetch successful
Oct 30 00:14:21.483042 extend-filesystems[1519]: resize2fs 1.47.3 (8-Jul-2025)
Oct 30 00:14:21.498506 kernel: EXT4-fs (sda9): resizing filesystem from 1617920 to 3587067 blocks
Oct 30 00:14:21.498580 coreos-metadata[1463]: Oct 30 00:14:21.485 INFO Fetching http://169.254.169.254/computeMetadata/v1/instance/network-interfaces/0/access-configs/0/external-ip: Attempt #1
Oct 30 00:14:21.495903 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM...
Oct 30 00:14:21.500981 coreos-metadata[1463]: Oct 30 00:14:21.499 INFO Fetch successful
Oct 30 00:14:21.500981 coreos-metadata[1463]: Oct 30 00:14:21.499 INFO Fetching http://169.254.169.254/computeMetadata/v1/instance/network-interfaces/0/ip: Attempt #1
Oct 30 00:14:21.500981 coreos-metadata[1463]: Oct 30 00:14:21.502 INFO Fetch successful
Oct 30 00:14:21.500981 coreos-metadata[1463]: Oct 30 00:14:21.503 INFO Fetching http://169.254.169.254/computeMetadata/v1/instance/machine-type: Attempt #1
Oct 30 00:14:21.500981 coreos-metadata[1463]: Oct 30 00:14:21.509 INFO Fetch successful
Oct 30 00:14:21.554167 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input4
Oct 30 00:14:21.571874 kernel: ACPI: button: Power Button [PWRF]
Oct 30 00:14:21.572058 kernel: input: Sleep Button as /devices/LNXSYSTM:00/LNXSLPBN:00/input/input5
Oct 30 00:14:21.582027 kernel: ACPI: button: Sleep Button [SLPF]
Oct 30 00:14:21.614126 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd.
Oct 30 00:14:21.624989 kernel: piix4_smbus 0000:00:01.3: SMBus base address uninitialized - upgrade BIOS or use force_addr=0xaddr
Oct 30 00:14:21.734822 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM.
Oct 30 00:14:21.766299 kernel: EXT4-fs (sda9): resized filesystem to 3587067
Oct 30 00:14:21.765538 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent.
Oct 30 00:14:21.774660 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met.
Oct 30 00:14:21.816230 extend-filesystems[1519]: Filesystem at /dev/sda9 is mounted on /; on-line resizing required
Oct 30 00:14:21.816230 extend-filesystems[1519]: old_desc_blocks = 1, new_desc_blocks = 2
Oct 30 00:14:21.816230 extend-filesystems[1519]: The filesystem on /dev/sda9 is now 3587067 (4k) blocks long.
Oct 30 00:14:21.871825 extend-filesystems[1467]: Resized filesystem in /dev/sda9
Oct 30 00:14:21.882387 bash[1555]: Updated "/home/core/.ssh/authorized_keys"
Oct 30 00:14:21.819645 systemd[1]: extend-filesystems.service: Deactivated successfully.
Oct 30 00:14:21.820071 systemd[1]: Finished extend-filesystems.service - Extend Filesystems.
Oct 30 00:14:21.845048 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition.
Oct 30 00:14:21.867220 systemd[1]: Starting sshkeys.service...
Oct 30 00:14:21.885915 dbus-daemon[1464]: [system] SELinux support is enabled
Oct 30 00:14:21.886435 systemd[1]: Started dbus.service - D-Bus System Message Bus.
Oct 30 00:14:21.905307 dbus-daemon[1464]: [system] Activating systemd to hand-off: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.1' (uid=244 pid=1434 comm="/usr/lib/systemd/systemd-networkd" label="system_u:system_r:kernel_t:s0")
Oct 30 00:14:21.921798 kernel: EDAC MC: Ver: 3.0.0
Oct 30 00:14:21.923401 update_engine[1487]: I20251030 00:14:21.923276  1487 update_check_scheduler.cc:74] Next update check in 2m55s
Oct 30 00:14:21.925087 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml).
Oct 30 00:14:21.925425 systemd[1]: Reached target system-config.target - Load system-provided cloud configs.
Oct 30 00:14:21.936509 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url).
Oct 30 00:14:21.937571 dbus-daemon[1464]: [system] Successfully activated service 'org.freedesktop.systemd1'
Oct 30 00:14:21.937130 systemd[1]: Reached target user-config.target - Load user-provided cloud configs.
Oct 30 00:14:22.019563 systemd[1]: Started update-engine.service - Update Engine.
Oct 30 00:14:22.056846 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys.
Oct 30 00:14:22.070466 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)...
Oct 30 00:14:22.084650 systemd-logind[1477]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)
Oct 30 00:14:22.086686 systemd[1]: Starting systemd-hostnamed.service - Hostname Service...
Oct 30 00:14:22.091254 systemd-logind[1477]: New seat seat0.
Oct 30 00:14:22.116851 systemd[1]: Started locksmithd.service - Cluster reboot manager.
Oct 30 00:14:22.127644 systemd[1]: Started systemd-logind.service - User Login Management.
Oct 30 00:14:22.147267 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup...
Oct 30 00:14:22.163671 systemd-logind[1477]: Watching system buttons on /dev/input/event2 (Power Button)
Oct 30 00:14:22.204516 systemd-logind[1477]: Watching system buttons on /dev/input/event3 (Sleep Button)
Oct 30 00:14:22.268796 containerd[1499]: time="2025-10-30T00:14:22Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8
Oct 30 00:14:22.289039 containerd[1499]: time="2025-10-30T00:14:22.288175100Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5
Oct 30 00:14:22.291980 coreos-metadata[1569]: Oct 30 00:14:22.291 INFO Fetching http://169.254.169.254/computeMetadata/v1/instance/attributes/sshKeys: Attempt #1
Oct 30 00:14:22.294469 coreos-metadata[1569]: Oct 30 00:14:22.294 INFO Fetch failed with 404: resource not found
Oct 30 00:14:22.298979 coreos-metadata[1569]: Oct 30 00:14:22.296 INFO Fetching http://169.254.169.254/computeMetadata/v1/instance/attributes/ssh-keys: Attempt #1
Oct 30 00:14:22.299383 coreos-metadata[1569]: Oct 30 00:14:22.299 INFO Fetch successful
Oct 30 00:14:22.299383 coreos-metadata[1569]: Oct 30 00:14:22.299 INFO Fetching http://169.254.169.254/computeMetadata/v1/instance/attributes/block-project-ssh-keys: Attempt #1
Oct 30 00:14:22.301268 coreos-metadata[1569]: Oct 30 00:14:22.301 INFO Fetch failed with 404: resource not found
Oct 30 00:14:22.301268 coreos-metadata[1569]: Oct 30 00:14:22.301 INFO Fetching http://169.254.169.254/computeMetadata/v1/project/attributes/sshKeys: Attempt #1
Oct 30 00:14:22.301693 coreos-metadata[1569]: Oct 30 00:14:22.301 INFO Fetch failed with 404: resource not found
Oct 30 00:14:22.301693 coreos-metadata[1569]: Oct 30 00:14:22.301 INFO Fetching http://169.254.169.254/computeMetadata/v1/project/attributes/ssh-keys: Attempt #1
Oct 30 00:14:22.303427 coreos-metadata[1569]: Oct 30 00:14:22.303 INFO Fetch successful
Oct 30 00:14:22.308072 unknown[1569]: wrote ssh authorized keys file for user: core
Oct 30 00:14:22.378992 update-ssh-keys[1577]: Updated "/home/core/.ssh/authorized_keys"
Oct 30 00:14:22.381106 containerd[1499]: time="2025-10-30T00:14:22.380797709Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="16.577µs"
Oct 30 00:14:22.381106 containerd[1499]: time="2025-10-30T00:14:22.380852182Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1
Oct 30 00:14:22.381106 containerd[1499]: time="2025-10-30T00:14:22.380885647Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1
Oct 30 00:14:22.381330 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys).
Oct 30 00:14:22.385353 containerd[1499]: time="2025-10-30T00:14:22.384175371Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1
Oct 30 00:14:22.385353 containerd[1499]: time="2025-10-30T00:14:22.384222974Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1
Oct 30 00:14:22.385353 containerd[1499]: time="2025-10-30T00:14:22.384466476Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1
Oct 30 00:14:22.385353 containerd[1499]: time="2025-10-30T00:14:22.384598752Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1
Oct 30 00:14:22.385353 containerd[1499]: time="2025-10-30T00:14:22.384622272Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1
Oct 30 00:14:22.383893 systemd[1]: Finished sshkeys.service.
Oct 30 00:14:22.397015 containerd[1499]: time="2025-10-30T00:14:22.396040074Z" level=info msg="skip loading plugin" 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" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1
Oct 30 00:14:22.397015 containerd[1499]: time="2025-10-30T00:14:22.396085894Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1
Oct 30 00:14:22.397015 containerd[1499]: time="2025-10-30T00:14:22.396109627Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1
Oct 30 00:14:22.397015 containerd[1499]: time="2025-10-30T00:14:22.396126868Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1
Oct 30 00:14:22.399496 containerd[1499]: time="2025-10-30T00:14:22.397442934Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1
Oct 30 00:14:22.399496 containerd[1499]: time="2025-10-30T00:14:22.397783509Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1
Oct 30 00:14:22.399496 containerd[1499]: time="2025-10-30T00:14:22.397865779Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1
Oct 30 00:14:22.399496 containerd[1499]: time="2025-10-30T00:14:22.397884154Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1
Oct 30 00:14:22.399496 containerd[1499]: time="2025-10-30T00:14:22.397934741Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1
Oct 30 00:14:22.400159 containerd[1499]: time="2025-10-30T00:14:22.400129341Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1
Oct 30 00:14:22.400581 containerd[1499]: time="2025-10-30T00:14:22.400544563Z" level=info msg="metadata content store policy set" policy=shared
Oct 30 00:14:22.409494 containerd[1499]: time="2025-10-30T00:14:22.408767627Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1
Oct 30 00:14:22.409494 containerd[1499]: time="2025-10-30T00:14:22.408864942Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1
Oct 30 00:14:22.409494 containerd[1499]: time="2025-10-30T00:14:22.408889912Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1
Oct 30 00:14:22.409494 containerd[1499]: time="2025-10-30T00:14:22.409030412Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1
Oct 30 00:14:22.409494 containerd[1499]: time="2025-10-30T00:14:22.409072497Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1
Oct 30 00:14:22.409494 containerd[1499]: time="2025-10-30T00:14:22.409090790Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1
Oct 30 00:14:22.409494 containerd[1499]: time="2025-10-30T00:14:22.409109826Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1
Oct 30 00:14:22.409494 containerd[1499]: time="2025-10-30T00:14:22.409131407Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1
Oct 30 00:14:22.409494 containerd[1499]: time="2025-10-30T00:14:22.409168100Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1
Oct 30 00:14:22.409494 containerd[1499]: time="2025-10-30T00:14:22.409184775Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1
Oct 30 00:14:22.409494 containerd[1499]: time="2025-10-30T00:14:22.409200200Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1
Oct 30 00:14:22.409494 containerd[1499]: time="2025-10-30T00:14:22.409332549Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2
Oct 30 00:14:22.411002 containerd[1499]: time="2025-10-30T00:14:22.410134192Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1
Oct 30 00:14:22.411002 containerd[1499]: time="2025-10-30T00:14:22.410190687Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1
Oct 30 00:14:22.411002 containerd[1499]: time="2025-10-30T00:14:22.410216234Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1
Oct 30 00:14:22.411002 containerd[1499]: time="2025-10-30T00:14:22.410620195Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1
Oct 30 00:14:22.411002 containerd[1499]: time="2025-10-30T00:14:22.410684426Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1
Oct 30 00:14:22.411002 containerd[1499]: time="2025-10-30T00:14:22.410705427Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1
Oct 30 00:14:22.411002 containerd[1499]: time="2025-10-30T00:14:22.410724100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1
Oct 30 00:14:22.411002 containerd[1499]: time="2025-10-30T00:14:22.410741930Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1
Oct 30 00:14:22.411002 containerd[1499]: time="2025-10-30T00:14:22.410761297Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1
Oct 30 00:14:22.411002 containerd[1499]: time="2025-10-30T00:14:22.410779283Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1
Oct 30 00:14:22.411002 containerd[1499]: time="2025-10-30T00:14:22.410813965Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1
Oct 30 00:14:22.411002 containerd[1499]: time="2025-10-30T00:14:22.410925731Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\""
Oct 30 00:14:22.412710 containerd[1499]: time="2025-10-30T00:14:22.410945868Z" level=info msg="Start snapshots syncer"
Oct 30 00:14:22.413304 containerd[1499]: time="2025-10-30T00:14:22.412784826Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1
Oct 30 00:14:22.414121 containerd[1499]: time="2025-10-30T00:14:22.413939457Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}"
Oct 30 00:14:22.414752 containerd[1499]: time="2025-10-30T00:14:22.414722309Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1
Oct 30 00:14:22.416006 containerd[1499]: time="2025-10-30T00:14:22.415665948Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1
Oct 30 00:14:22.416243 containerd[1499]: time="2025-10-30T00:14:22.416216906Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1
Oct 30 00:14:22.417026 containerd[1499]: time="2025-10-30T00:14:22.416680624Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1
Oct 30 00:14:22.417026 containerd[1499]: time="2025-10-30T00:14:22.416716720Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1
Oct 30 00:14:22.417026 containerd[1499]: time="2025-10-30T00:14:22.416735810Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1
Oct 30 00:14:22.417026 containerd[1499]: time="2025-10-30T00:14:22.416755853Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1
Oct 30 00:14:22.417026 containerd[1499]: time="2025-10-30T00:14:22.416774230Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1
Oct 30 00:14:22.417026 containerd[1499]: time="2025-10-30T00:14:22.416791893Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1
Oct 30 00:14:22.417026 containerd[1499]: time="2025-10-30T00:14:22.416829150Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1
Oct 30 00:14:22.417026 containerd[1499]: time="2025-10-30T00:14:22.416846633Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1
Oct 30 00:14:22.417026 containerd[1499]: time="2025-10-30T00:14:22.416863478Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1
Oct 30 00:14:22.418478 containerd[1499]: time="2025-10-30T00:14:22.417461687Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1
Oct 30 00:14:22.418478 containerd[1499]: time="2025-10-30T00:14:22.417744980Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1
Oct 30 00:14:22.418478 containerd[1499]: time="2025-10-30T00:14:22.417763860Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1
Oct 30 00:14:22.418478 containerd[1499]: time="2025-10-30T00:14:22.417780085Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1
Oct 30 00:14:22.418478 containerd[1499]: time="2025-10-30T00:14:22.417793625Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1
Oct 30 00:14:22.418478 containerd[1499]: time="2025-10-30T00:14:22.417810773Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1
Oct 30 00:14:22.418478 containerd[1499]: time="2025-10-30T00:14:22.418307651Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1
Oct 30 00:14:22.418478 containerd[1499]: time="2025-10-30T00:14:22.418340890Z" level=info msg="runtime interface created"
Oct 30 00:14:22.418478 containerd[1499]: time="2025-10-30T00:14:22.418350527Z" level=info msg="created NRI interface"
Oct 30 00:14:22.418478 containerd[1499]: time="2025-10-30T00:14:22.418367183Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1
Oct 30 00:14:22.418478 containerd[1499]: time="2025-10-30T00:14:22.418386964Z" level=info msg="Connect containerd service"
Oct 30 00:14:22.418478 containerd[1499]: time="2025-10-30T00:14:22.418442380Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this"
Oct 30 00:14:22.422638 containerd[1499]: time="2025-10-30T00:14:22.422043098Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config"
Oct 30 00:14:22.517868 sshd_keygen[1498]: ssh-keygen: generating new host keys: RSA ECDSA ED25519
Oct 30 00:14:22.577008 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup.
Oct 30 00:14:22.743689 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys.
Oct 30 00:14:22.751581 locksmithd[1571]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot"
Oct 30 00:14:22.760883 systemd[1]: Starting issuegen.service - Generate /run/issue...
Oct 30 00:14:22.823202 systemd-networkd[1434]: eth0: Gained IPv6LL
Oct 30 00:14:22.837373 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured.
Oct 30 00:14:22.848832 systemd[1]: Reached target network-online.target - Network is Online.
Oct 30 00:14:22.864367 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent...
Oct 30 00:14:22.871817 containerd[1499]: time="2025-10-30T00:14:22.871771380Z" level=info msg="Start subscribing containerd event"
Oct 30 00:14:22.874992 containerd[1499]: time="2025-10-30T00:14:22.872151127Z" level=info msg="Start recovering state"
Oct 30 00:14:22.875819 systemd[1]: Starting nvidia.service - NVIDIA Configure Service...
Oct 30 00:14:22.877892 containerd[1499]: time="2025-10-30T00:14:22.877601146Z" level=info msg="Start event monitor"
Oct 30 00:14:22.880300 containerd[1499]: time="2025-10-30T00:14:22.878008871Z" level=info msg="Start cni network conf syncer for default"
Oct 30 00:14:22.880300 containerd[1499]: time="2025-10-30T00:14:22.878041842Z" level=info msg="Start streaming server"
Oct 30 00:14:22.880300 containerd[1499]: time="2025-10-30T00:14:22.878352994Z" level=info msg="Registered namespace \"k8s.io\" with NRI"
Oct 30 00:14:22.880300 containerd[1499]: time="2025-10-30T00:14:22.878377649Z" level=info msg="runtime interface starting up..."
Oct 30 00:14:22.880300 containerd[1499]: time="2025-10-30T00:14:22.878392233Z" level=info msg="starting plugins..."
Oct 30 00:14:22.880300 containerd[1499]: time="2025-10-30T00:14:22.878429765Z" level=info msg="Synchronizing NRI (plugin) with current runtime state"
Oct 30 00:14:22.887837 systemd[1]: Starting oem-gce.service - GCE Linux Agent...
Oct 30 00:14:22.891929 containerd[1499]: time="2025-10-30T00:14:22.886391424Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc
Oct 30 00:14:22.897350 systemd[1]: issuegen.service: Deactivated successfully.
Oct 30 00:14:22.904056 containerd[1499]: time="2025-10-30T00:14:22.899995246Z" level=info msg=serving... address=/run/containerd/containerd.sock
Oct 30 00:14:22.905391 containerd[1499]: time="2025-10-30T00:14:22.904680506Z" level=info msg="containerd successfully booted in 0.636549s"
Oct 30 00:14:22.905833 systemd[1]: Finished issuegen.service - Generate /run/issue.
Oct 30 00:14:22.916164 systemd[1]: Started containerd.service - containerd container runtime.
Oct 30 00:14:22.918918 init.sh[1618]: + '[' -e /etc/default/instance_configs.cfg.template ']'
Oct 30 00:14:22.919424 init.sh[1618]: + echo -e '[InstanceSetup]\nset_host_keys = false'
Oct 30 00:14:22.922329 init.sh[1618]: + /usr/bin/google_instance_setup
Oct 30 00:14:22.940739 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions...
Oct 30 00:14:22.991168 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions.
Oct 30 00:14:23.008492 dbus-daemon[1464]: [system] Successfully activated service 'org.freedesktop.hostname1'
Oct 30 00:14:23.015306 dbus-daemon[1464]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.7' (uid=0 pid=1570 comm="/usr/lib/systemd/systemd-hostnamed" label="system_u:system_r:kernel_t:s0")
Oct 30 00:14:23.024301 systemd[1]: Started systemd-hostnamed.service - Hostname Service.
Oct 30 00:14:23.035645 systemd[1]: Finished nvidia.service - NVIDIA Configure Service.
Oct 30 00:14:23.058173 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd.
Oct 30 00:14:23.075381 systemd[1]: Started getty@tty1.service - Getty on tty1.
Oct 30 00:14:23.090072 systemd[1]: Starting polkit.service - Authorization Manager...
Oct 30 00:14:23.102625 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0.
Oct 30 00:14:23.112612 systemd[1]: Reached target getty.target - Login Prompts.
Oct 30 00:14:23.124344 systemd[1]: Started sshd@0-10.128.0.4:22-139.178.89.65:36034.service - OpenSSH per-connection server daemon (139.178.89.65:36034).
Oct 30 00:14:23.362587 polkitd[1635]: Started polkitd version 126
Oct 30 00:14:23.376586 polkitd[1635]: Loading rules from directory /etc/polkit-1/rules.d
Oct 30 00:14:23.377245 polkitd[1635]: Loading rules from directory /run/polkit-1/rules.d
Oct 30 00:14:23.377335 polkitd[1635]: Error opening rules directory: Error opening directory “/run/polkit-1/rules.d”: No such file or directory (g-file-error-quark, 4)
Oct 30 00:14:23.380420 polkitd[1635]: Loading rules from directory /usr/local/share/polkit-1/rules.d
Oct 30 00:14:23.380488 polkitd[1635]: Error opening rules directory: Error opening directory “/usr/local/share/polkit-1/rules.d”: No such file or directory (g-file-error-quark, 4)
Oct 30 00:14:23.380572 polkitd[1635]: Loading rules from directory /usr/share/polkit-1/rules.d
Oct 30 00:14:23.382905 polkitd[1635]: Finished loading, compiling and executing 2 rules
Oct 30 00:14:23.383384 systemd[1]: Started polkit.service - Authorization Manager.
Oct 30 00:14:23.384803 dbus-daemon[1464]: [system] Successfully activated service 'org.freedesktop.PolicyKit1'
Oct 30 00:14:23.386183 polkitd[1635]: Acquired the name org.freedesktop.PolicyKit1 on the system bus
Oct 30 00:14:23.434569 systemd-hostnamed[1570]: Hostname set to <ci-4459-1-0-nightly-20251029-2100-f6179af3e514b1c6b030> (transient)
Oct 30 00:14:23.435605 systemd-resolved[1363]: System hostname changed to 'ci-4459-1-0-nightly-20251029-2100-f6179af3e514b1c6b030'.
Oct 30 00:14:23.565872 sshd[1637]: Accepted publickey for core from 139.178.89.65 port 36034 ssh2: RSA SHA256:cd4BroKjj9biZXF9zqqwXFm4iGhXl03Qh7zF4IiT+a4
Oct 30 00:14:23.569475 sshd-session[1637]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Oct 30 00:14:23.586894 systemd[1]: Created slice user-500.slice - User Slice of UID 500.
Oct 30 00:14:23.598919 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500...
Oct 30 00:14:23.636119 systemd-logind[1477]: New session 1 of user core.
Oct 30 00:14:23.657658 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500.
Oct 30 00:14:23.677355 systemd[1]: Starting user@500.service - User Manager for UID 500...
Oct 30 00:14:23.717043 (systemd)[1652]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0)
Oct 30 00:14:23.723228 systemd-logind[1477]: New session c1 of user core.
Oct 30 00:14:23.933730 instance-setup[1620]: INFO Running google_set_multiqueue.
Oct 30 00:14:23.958186 instance-setup[1620]: INFO Set channels for eth0 to 2.
Oct 30 00:14:23.963969 instance-setup[1620]: INFO Setting /proc/irq/31/smp_affinity_list to 0 for device virtio1.
Oct 30 00:14:23.967254 instance-setup[1620]: INFO /proc/irq/31/smp_affinity_list: real affinity 0
Oct 30 00:14:23.968134 instance-setup[1620]: INFO Setting /proc/irq/32/smp_affinity_list to 0 for device virtio1.
Oct 30 00:14:23.970356 instance-setup[1620]: INFO /proc/irq/32/smp_affinity_list: real affinity 0
Oct 30 00:14:23.971034 instance-setup[1620]: INFO Setting /proc/irq/33/smp_affinity_list to 1 for device virtio1.
Oct 30 00:14:23.973314 instance-setup[1620]: INFO /proc/irq/33/smp_affinity_list: real affinity 1
Oct 30 00:14:23.973929 instance-setup[1620]: INFO Setting /proc/irq/34/smp_affinity_list to 1 for device virtio1.
Oct 30 00:14:23.976160 instance-setup[1620]: INFO /proc/irq/34/smp_affinity_list: real affinity 1
Oct 30 00:14:23.987858 instance-setup[1620]: INFO /usr/sbin/google_set_multiqueue: line 133: echo: write error: Value too large for defined data type
Oct 30 00:14:23.993254 instance-setup[1620]: INFO /usr/sbin/google_set_multiqueue: line 133: echo: write error: Value too large for defined data type
Oct 30 00:14:23.995928 instance-setup[1620]: INFO Queue 0 XPS=1 for /sys/class/net/eth0/queues/tx-0/xps_cpus
Oct 30 00:14:23.996509 instance-setup[1620]: INFO Queue 1 XPS=2 for /sys/class/net/eth0/queues/tx-1/xps_cpus
Oct 30 00:14:24.026302 init.sh[1618]: + /usr/bin/google_metadata_script_runner --script-type startup
Oct 30 00:14:24.157576 systemd[1652]: Queued start job for default target default.target.
Oct 30 00:14:24.164927 systemd[1652]: Created slice app.slice - User Application Slice.
Oct 30 00:14:24.165006 systemd[1652]: Reached target paths.target - Paths.
Oct 30 00:14:24.165101 systemd[1652]: Reached target timers.target - Timers.
Oct 30 00:14:24.169533 systemd[1652]: Starting dbus.socket - D-Bus User Message Bus Socket...
Oct 30 00:14:24.198425 systemd[1652]: Listening on dbus.socket - D-Bus User Message Bus Socket.
Oct 30 00:14:24.198884 systemd[1652]: Reached target sockets.target - Sockets.
Oct 30 00:14:24.199092 systemd[1652]: Reached target basic.target - Basic System.
Oct 30 00:14:24.199171 systemd[1652]: Reached target default.target - Main User Target.
Oct 30 00:14:24.199230 systemd[1652]: Startup finished in 458ms.
Oct 30 00:14:24.199731 systemd[1]: Started user@500.service - User Manager for UID 500.
Oct 30 00:14:24.217378 systemd[1]: Started session-1.scope - Session 1 of User core.
Oct 30 00:14:24.324807 startup-script[1686]: INFO Starting startup scripts.
Oct 30 00:14:24.332442 startup-script[1686]: INFO No startup scripts found in metadata.
Oct 30 00:14:24.332533 startup-script[1686]: INFO Finished running startup scripts.
Oct 30 00:14:24.365022 init.sh[1618]: + trap 'stopping=1 ; kill "${daemon_pids[@]}" || :' SIGTERM
Oct 30 00:14:24.365022 init.sh[1618]: + daemon_pids=()
Oct 30 00:14:24.365022 init.sh[1618]: + for d in accounts clock_skew network
Oct 30 00:14:24.365022 init.sh[1618]: + daemon_pids+=($!)
Oct 30 00:14:24.365022 init.sh[1618]: + for d in accounts clock_skew network
Oct 30 00:14:24.365022 init.sh[1618]: + daemon_pids+=($!)
Oct 30 00:14:24.365022 init.sh[1618]: + for d in accounts clock_skew network
Oct 30 00:14:24.365424 init.sh[1693]: + /usr/bin/google_accounts_daemon
Oct 30 00:14:24.365823 init.sh[1694]: + /usr/bin/google_clock_skew_daemon
Oct 30 00:14:24.368343 init.sh[1695]: + /usr/bin/google_network_daemon
Oct 30 00:14:24.368664 init.sh[1618]: + daemon_pids+=($!)
Oct 30 00:14:24.368664 init.sh[1618]: + NOTIFY_SOCKET=/run/systemd/notify
Oct 30 00:14:24.368664 init.sh[1618]: + /usr/bin/systemd-notify --ready
Oct 30 00:14:24.393158 systemd[1]: Started oem-gce.service - GCE Linux Agent.
Oct 30 00:14:24.406992 init.sh[1618]: + wait -n 1693 1694 1695
Oct 30 00:14:24.471149 systemd[1]: Started sshd@1-10.128.0.4:22-139.178.89.65:36038.service - OpenSSH per-connection server daemon (139.178.89.65:36038).
Oct 30 00:14:24.856313 sshd[1698]: Accepted publickey for core from 139.178.89.65 port 36038 ssh2: RSA SHA256:cd4BroKjj9biZXF9zqqwXFm4iGhXl03Qh7zF4IiT+a4
Oct 30 00:14:24.859047 sshd-session[1698]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Oct 30 00:14:24.877672 systemd-logind[1477]: New session 2 of user core.
Oct 30 00:14:24.882192 systemd[1]: Started session-2.scope - Session 2 of User core.
Oct 30 00:14:24.897732 google-clock-skew[1694]: INFO Starting Google Clock Skew daemon.
Oct 30 00:14:24.916079 google-clock-skew[1694]: INFO Clock drift token has changed: 0.
Oct 30 00:14:24.965533 google-networking[1695]: INFO Starting Google Networking daemon.
Oct 30 00:14:25.057507 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent.
Oct 30 00:14:25.069485 systemd[1]: Reached target multi-user.target - Multi-User System.
Oct 30 00:14:25.074705 (kubelet)[1717]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS
Oct 30 00:14:25.079023 systemd[1]: Startup finished in 4.096s (kernel) + 7.446s (initrd) + 8.897s (userspace) = 20.439s.
Oct 30 00:14:25.080930 groupadd[1712]: group added to /etc/group: name=google-sudoers, GID=1000
Oct 30 00:14:25.089083 groupadd[1712]: group added to /etc/gshadow: name=google-sudoers
Oct 30 00:14:25.098980 sshd[1707]: Connection closed by 139.178.89.65 port 36038
Oct 30 00:14:25.098241 sshd-session[1698]: pam_unix(sshd:session): session closed for user core
Oct 30 00:14:25.128145 systemd[1]: sshd@1-10.128.0.4:22-139.178.89.65:36038.service: Deactivated successfully.
Oct 30 00:14:25.133663 systemd[1]: session-2.scope: Deactivated successfully.
Oct 30 00:14:25.139852 systemd-logind[1477]: Session 2 logged out. Waiting for processes to exit.
Oct 30 00:14:25.158390 systemd[1]: Started sshd@2-10.128.0.4:22-139.178.89.65:36050.service - OpenSSH per-connection server daemon (139.178.89.65:36050).
Oct 30 00:14:25.164849 systemd-logind[1477]: Removed session 2.
Oct 30 00:14:25.181645 ntpd[1472]: Listen normally on 4 eth0 10.128.0.4:123
Oct 30 00:14:25.182759 ntpd[1472]: 30 Oct 00:14:25 ntpd[1472]: Listen normally on 4 eth0 10.128.0.4:123
Oct 30 00:14:25.182759 ntpd[1472]: 30 Oct 00:14:25 ntpd[1472]: Listen normally on 5 eth0 [fe80::4001:aff:fe80:4%2]:123
Oct 30 00:14:25.182759 ntpd[1472]: 30 Oct 00:14:25 ntpd[1472]: 141.11.89.193 local addr <null> -> 10.128.0.4
Oct 30 00:14:25.182759 ntpd[1472]: 30 Oct 00:14:25 ntpd[1472]: 23.186.168.127 local addr <null> -> 10.128.0.4
Oct 30 00:14:25.182759 ntpd[1472]: 30 Oct 00:14:25 ntpd[1472]: 172.233.157.223 local addr <null> -> 10.128.0.4
Oct 30 00:14:25.182759 ntpd[1472]: 30 Oct 00:14:25 ntpd[1472]: 198.46.254.130 local addr <null> -> 10.128.0.4
Oct 30 00:14:25.182759 ntpd[1472]: 30 Oct 00:14:25 ntpd[1472]: 169.254.169.254 local addr <null> -> 10.128.0.4
Oct 30 00:14:25.182759 ntpd[1472]: 30 Oct 00:14:25 ntpd[1472]: 169.254.169.123 local addr <null> -> 10.128.0.4
Oct 30 00:14:25.181726 ntpd[1472]: Listen normally on 5 eth0 [fe80::4001:aff:fe80:4%2]:123
Oct 30 00:14:25.181772 ntpd[1472]: 141.11.89.193 local addr <null> -> 10.128.0.4
Oct 30 00:14:25.181822 ntpd[1472]: 23.186.168.127 local addr <null> -> 10.128.0.4
Oct 30 00:14:25.181874 ntpd[1472]: 172.233.157.223 local addr <null> -> 10.128.0.4
Oct 30 00:14:25.181908 ntpd[1472]: 198.46.254.130 local addr <null> -> 10.128.0.4
Oct 30 00:14:25.181964 ntpd[1472]: 169.254.169.254 local addr <null> -> 10.128.0.4
Oct 30 00:14:25.182031 ntpd[1472]: 169.254.169.123 local addr <null> -> 10.128.0.4
Oct 30 00:14:25.185391 groupadd[1712]: new group: name=google-sudoers, GID=1000
Oct 30 00:14:25.001454 google-clock-skew[1694]: INFO Synced system time with hardware clock.
Oct 30 00:14:25.016539 systemd-journald[1146]: Time jumped backwards, rotating.
Oct 30 00:14:25.001763 systemd-resolved[1363]: Clock change detected. Flushing caches.
Oct 30 00:14:25.038085 google-accounts[1693]: INFO Starting Google Accounts daemon.
Oct 30 00:14:25.056876 google-accounts[1693]: WARNING OS Login not installed.
Oct 30 00:14:25.059198 google-accounts[1693]: INFO Creating a new user account for 0.
Oct 30 00:14:25.064860 init.sh[1737]: useradd: invalid user name '0': use --badname to ignore
Oct 30 00:14:25.065180 google-accounts[1693]: WARNING Could not create user 0. Command '['useradd', '-m', '-s', '/bin/bash', '-p', '*', '0']' returned non-zero exit status 3..
Oct 30 00:14:25.309865 sshd[1729]: Accepted publickey for core from 139.178.89.65 port 36050 ssh2: RSA SHA256:cd4BroKjj9biZXF9zqqwXFm4iGhXl03Qh7zF4IiT+a4
Oct 30 00:14:25.312920 sshd-session[1729]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Oct 30 00:14:25.321930 systemd-logind[1477]: New session 3 of user core.
Oct 30 00:14:25.330946 systemd[1]: Started session-3.scope - Session 3 of User core.
Oct 30 00:14:25.529346 sshd[1743]: Connection closed by 139.178.89.65 port 36050
Oct 30 00:14:25.531142 sshd-session[1729]: pam_unix(sshd:session): session closed for user core
Oct 30 00:14:25.538060 systemd[1]: sshd@2-10.128.0.4:22-139.178.89.65:36050.service: Deactivated successfully.
Oct 30 00:14:25.542445 systemd[1]: session-3.scope: Deactivated successfully.
Oct 30 00:14:25.545371 systemd-logind[1477]: Session 3 logged out. Waiting for processes to exit.
Oct 30 00:14:25.549127 systemd-logind[1477]: Removed session 3.
Oct 30 00:14:25.811085 kubelet[1717]: E1030 00:14:25.811019    1717 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory"
Oct 30 00:14:25.814759 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE
Oct 30 00:14:25.815022 systemd[1]: kubelet.service: Failed with result 'exit-code'.
Oct 30 00:14:25.815620 systemd[1]: kubelet.service: Consumed 1.315s CPU time, 265M memory peak.
Oct 30 00:14:31.769041 systemd[1]: Started sshd@3-10.128.0.4:22-20.74.129.185:39124.service - OpenSSH per-connection server daemon (20.74.129.185:39124).
Oct 30 00:14:33.068300 sshd[1751]: Invalid user user from 20.74.129.185 port 39124
Oct 30 00:14:33.310891 sshd[1751]: Received disconnect from 20.74.129.185 port 39124:11: Bye Bye [preauth]
Oct 30 00:14:33.310891 sshd[1751]: Disconnected from invalid user user 20.74.129.185 port 39124 [preauth]
Oct 30 00:14:33.313964 systemd[1]: sshd@3-10.128.0.4:22-20.74.129.185:39124.service: Deactivated successfully.
Oct 30 00:14:35.589464 systemd[1]: Started sshd@4-10.128.0.4:22-139.178.89.65:40788.service - OpenSSH per-connection server daemon (139.178.89.65:40788).
Oct 30 00:14:35.840571 systemd[1]: kubelet.service: Scheduled restart job, restart counter is at 1.
Oct 30 00:14:35.846008 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent...
Oct 30 00:14:35.901418 sshd[1757]: Accepted publickey for core from 139.178.89.65 port 40788 ssh2: RSA SHA256:cd4BroKjj9biZXF9zqqwXFm4iGhXl03Qh7zF4IiT+a4
Oct 30 00:14:35.903380 sshd-session[1757]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Oct 30 00:14:35.914772 systemd-logind[1477]: New session 4 of user core.
Oct 30 00:14:35.922967 systemd[1]: Started session-4.scope - Session 4 of User core.
Oct 30 00:14:36.121804 sshd[1763]: Connection closed by 139.178.89.65 port 40788
Oct 30 00:14:36.122797 sshd-session[1757]: pam_unix(sshd:session): session closed for user core
Oct 30 00:14:36.130051 systemd[1]: sshd@4-10.128.0.4:22-139.178.89.65:40788.service: Deactivated successfully.
Oct 30 00:14:36.132819 systemd[1]: session-4.scope: Deactivated successfully.
Oct 30 00:14:36.134151 systemd-logind[1477]: Session 4 logged out. Waiting for processes to exit.
Oct 30 00:14:36.137254 systemd-logind[1477]: Removed session 4.
Oct 30 00:14:36.180588 systemd[1]: Started sshd@5-10.128.0.4:22-139.178.89.65:51582.service - OpenSSH per-connection server daemon (139.178.89.65:51582).
Oct 30 00:14:36.249651 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent.
Oct 30 00:14:36.261216 (kubelet)[1777]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS
Oct 30 00:14:36.322471 kubelet[1777]: E1030 00:14:36.322390    1777 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory"
Oct 30 00:14:36.327734 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE
Oct 30 00:14:36.327992 systemd[1]: kubelet.service: Failed with result 'exit-code'.
Oct 30 00:14:36.328599 systemd[1]: kubelet.service: Consumed 237ms CPU time, 110.6M memory peak.
Oct 30 00:14:36.500744 sshd[1769]: Accepted publickey for core from 139.178.89.65 port 51582 ssh2: RSA SHA256:cd4BroKjj9biZXF9zqqwXFm4iGhXl03Qh7zF4IiT+a4
Oct 30 00:14:36.501682 sshd-session[1769]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Oct 30 00:14:36.509724 systemd-logind[1477]: New session 5 of user core.
Oct 30 00:14:36.519025 systemd[1]: Started session-5.scope - Session 5 of User core.
Oct 30 00:14:36.712022 sshd[1784]: Connection closed by 139.178.89.65 port 51582
Oct 30 00:14:36.712962 sshd-session[1769]: pam_unix(sshd:session): session closed for user core
Oct 30 00:14:36.719630 systemd[1]: sshd@5-10.128.0.4:22-139.178.89.65:51582.service: Deactivated successfully.
Oct 30 00:14:36.722324 systemd[1]: session-5.scope: Deactivated successfully.
Oct 30 00:14:36.723944 systemd-logind[1477]: Session 5 logged out. Waiting for processes to exit.
Oct 30 00:14:36.726197 systemd-logind[1477]: Removed session 5.
Oct 30 00:14:36.768217 systemd[1]: Started sshd@6-10.128.0.4:22-139.178.89.65:51592.service - OpenSSH per-connection server daemon (139.178.89.65:51592).
Oct 30 00:14:37.086358 sshd[1790]: Accepted publickey for core from 139.178.89.65 port 51592 ssh2: RSA SHA256:cd4BroKjj9biZXF9zqqwXFm4iGhXl03Qh7zF4IiT+a4
Oct 30 00:14:37.088256 sshd-session[1790]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Oct 30 00:14:37.095758 systemd-logind[1477]: New session 6 of user core.
Oct 30 00:14:37.102940 systemd[1]: Started session-6.scope - Session 6 of User core.
Oct 30 00:14:37.301900 sshd[1793]: Connection closed by 139.178.89.65 port 51592
Oct 30 00:14:37.302819 sshd-session[1790]: pam_unix(sshd:session): session closed for user core
Oct 30 00:14:37.309439 systemd[1]: sshd@6-10.128.0.4:22-139.178.89.65:51592.service: Deactivated successfully.
Oct 30 00:14:37.312081 systemd[1]: session-6.scope: Deactivated successfully.
Oct 30 00:14:37.313633 systemd-logind[1477]: Session 6 logged out. Waiting for processes to exit.
Oct 30 00:14:37.315930 systemd-logind[1477]: Removed session 6.
Oct 30 00:14:37.355547 systemd[1]: Started sshd@7-10.128.0.4:22-139.178.89.65:51604.service - OpenSSH per-connection server daemon (139.178.89.65:51604).
Oct 30 00:14:37.661323 sshd[1799]: Accepted publickey for core from 139.178.89.65 port 51604 ssh2: RSA SHA256:cd4BroKjj9biZXF9zqqwXFm4iGhXl03Qh7zF4IiT+a4
Oct 30 00:14:37.663096 sshd-session[1799]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Oct 30 00:14:37.670808 systemd-logind[1477]: New session 7 of user core.
Oct 30 00:14:37.678017 systemd[1]: Started session-7.scope - Session 7 of User core.
Oct 30 00:14:37.857617 sudo[1803]:     core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh
Oct 30 00:14:37.858204 sudo[1803]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500)
Oct 30 00:14:38.776643 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent.
Oct 30 00:14:38.777076 systemd[1]: kubelet.service: Consumed 237ms CPU time, 110.6M memory peak.
Oct 30 00:14:38.782109 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent...
Oct 30 00:14:38.848394 systemd[1]: Reload requested from client PID 1836 ('systemctl') (unit session-7.scope)...
Oct 30 00:14:38.848426 systemd[1]: Reloading...
Oct 30 00:14:39.048756 zram_generator::config[1880]: No configuration found.
Oct 30 00:14:39.371389 systemd[1]: Reloading finished in 521 ms.
Oct 30 00:14:39.462321 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM
Oct 30 00:14:39.462494 systemd[1]: kubelet.service: Failed with result 'signal'.
Oct 30 00:14:39.463086 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent.
Oct 30 00:14:39.463175 systemd[1]: kubelet.service: Consumed 187ms CPU time, 98.3M memory peak.
Oct 30 00:14:39.465971 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent...
Oct 30 00:14:39.792406 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent.
Oct 30 00:14:39.808951 (kubelet)[1931]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS
Oct 30 00:14:39.870030 kubelet[1931]: Flag --container-runtime-endpoint has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information.
Oct 30 00:14:39.870030 kubelet[1931]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI.
Oct 30 00:14:39.870030 kubelet[1931]: Flag --volume-plugin-dir has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information.
Oct 30 00:14:39.870972 kubelet[1931]: I1030 00:14:39.870216    1931 server.go:215] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime"
Oct 30 00:14:40.783606 kubelet[1931]: I1030 00:14:40.783331    1931 server.go:520] "Kubelet version" kubeletVersion="v1.32.4"
Oct 30 00:14:40.783606 kubelet[1931]: I1030 00:14:40.783372    1931 server.go:522] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK=""
Oct 30 00:14:40.783872 kubelet[1931]: I1030 00:14:40.783834    1931 server.go:954] "Client rotation is on, will bootstrap in background"
Oct 30 00:14:40.841749 kubelet[1931]: I1030 00:14:40.841574    1931 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt"
Oct 30 00:14:40.854733 kubelet[1931]: I1030 00:14:40.853442    1931 server.go:1444] "Using cgroup driver setting received from the CRI runtime" cgroupDriver="systemd"
Oct 30 00:14:40.860149 kubelet[1931]: I1030 00:14:40.860072    1931 server.go:772] "--cgroups-per-qos enabled, but --cgroup-root was not specified.  defaulting to /"
Oct 30 00:14:40.861652 kubelet[1931]: I1030 00:14:40.861582    1931 container_manager_linux.go:268] "Container manager verified user specified cgroup-root exists" cgroupRoot=[]
Oct 30 00:14:40.861917 kubelet[1931]: I1030 00:14:40.861632    1931 container_manager_linux.go:273] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.128.0.4","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2}
Oct 30 00:14:40.862150 kubelet[1931]: I1030 00:14:40.861925    1931 topology_manager.go:138] "Creating topology manager with none policy"
Oct 30 00:14:40.862150 kubelet[1931]: I1030 00:14:40.861946    1931 container_manager_linux.go:304] "Creating device plugin manager"
Oct 30 00:14:40.862150 kubelet[1931]: I1030 00:14:40.862121    1931 state_mem.go:36] "Initialized new in-memory state store"
Oct 30 00:14:40.868968 kubelet[1931]: I1030 00:14:40.868914    1931 kubelet.go:446] "Attempting to sync node with API server"
Oct 30 00:14:40.868968 kubelet[1931]: I1030 00:14:40.868965    1931 kubelet.go:341] "Adding static pod path" path="/etc/kubernetes/manifests"
Oct 30 00:14:40.869165 kubelet[1931]: I1030 00:14:40.869019    1931 kubelet.go:352] "Adding apiserver pod source"
Oct 30 00:14:40.869165 kubelet[1931]: I1030 00:14:40.869043    1931 apiserver.go:42] "Waiting for node sync before watching apiserver pods"
Oct 30 00:14:40.871865 kubelet[1931]: E1030 00:14:40.871780    1931 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:14:40.871865 kubelet[1931]: E1030 00:14:40.871843    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:14:40.875669 kubelet[1931]: I1030 00:14:40.875557    1931 kuberuntime_manager.go:269] "Container runtime initialized" containerRuntime="containerd" version="v2.0.5" apiVersion="v1"
Oct 30 00:14:40.876345 kubelet[1931]: I1030 00:14:40.876281    1931 kubelet.go:890] "Not starting ClusterTrustBundle informer because we are in static kubelet mode"
Oct 30 00:14:40.877531 kubelet[1931]: W1030 00:14:40.877456    1931 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating.
Oct 30 00:14:40.881713 kubelet[1931]: I1030 00:14:40.881076    1931 watchdog_linux.go:99] "Systemd watchdog is not enabled"
Oct 30 00:14:40.881713 kubelet[1931]: I1030 00:14:40.881125    1931 server.go:1287] "Started kubelet"
Oct 30 00:14:40.882666 kubelet[1931]: W1030 00:14:40.882638    1931 reflector.go:569] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope
Oct 30 00:14:40.882874 kubelet[1931]: E1030 00:14:40.882849    1931 reflector.go:166] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Service: failed to list *v1.Service: services is forbidden: User \"system:anonymous\" cannot list resource \"services\" in API group \"\" at the cluster scope" logger="UnhandledError"
Oct 30 00:14:40.883476 kubelet[1931]: I1030 00:14:40.883373    1931 server.go:169] "Starting to listen" address="0.0.0.0" port=10250
Oct 30 00:14:40.889483 kubelet[1931]: I1030 00:14:40.887987    1931 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10
Oct 30 00:14:40.889483 kubelet[1931]: I1030 00:14:40.888543    1931 server.go:243] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock"
Oct 30 00:14:40.892200 kubelet[1931]: I1030 00:14:40.891568    1931 server.go:479] "Adding debug handlers to kubelet server"
Oct 30 00:14:40.899120 kubelet[1931]: I1030 00:14:40.899084    1931 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer"
Oct 30 00:14:40.905763 kubelet[1931]: I1030 00:14:40.905732    1931 volume_manager.go:297] "Starting Kubelet Volume Manager"
Oct 30 00:14:40.906065 kubelet[1931]: E1030 00:14:40.906027    1931 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.128.0.4\" not found"
Oct 30 00:14:40.906319 kubelet[1931]: I1030 00:14:40.906294    1931 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key"
Oct 30 00:14:40.918273 kubelet[1931]: I1030 00:14:40.918240    1931 factory.go:221] Registration of the systemd container factory successfully
Oct 30 00:14:40.918583 kubelet[1931]: I1030 00:14:40.918557    1931 factory.go:219] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory
Oct 30 00:14:40.921037 kubelet[1931]: E1030 00:14:40.921011    1931 kubelet.go:1555] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem"
Oct 30 00:14:40.921503 kubelet[1931]: I1030 00:14:40.921481    1931 factory.go:221] Registration of the containerd container factory successfully
Oct 30 00:14:40.922123 kubelet[1931]: I1030 00:14:40.922084    1931 desired_state_of_world_populator.go:150] "Desired state populator starts to run"
Oct 30 00:14:40.922317 kubelet[1931]: I1030 00:14:40.922291    1931 reconciler.go:26] "Reconciler: start to sync state"
Oct 30 00:14:40.923247 kubelet[1931]: E1030 00:14:40.923199    1931 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.128.0.4\" not found" node="10.128.0.4"
Oct 30 00:14:40.950981 kubelet[1931]: I1030 00:14:40.950568    1931 cpu_manager.go:221] "Starting CPU manager" policy="none"
Oct 30 00:14:40.950981 kubelet[1931]: I1030 00:14:40.950613    1931 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s"
Oct 30 00:14:40.950981 kubelet[1931]: I1030 00:14:40.950639    1931 state_mem.go:36] "Initialized new in-memory state store"
Oct 30 00:14:40.954914 kubelet[1931]: I1030 00:14:40.954887    1931 policy_none.go:49] "None policy: Start"
Oct 30 00:14:40.955313 kubelet[1931]: I1030 00:14:40.955274    1931 memory_manager.go:186] "Starting memorymanager" policy="None"
Oct 30 00:14:40.955737 kubelet[1931]: I1030 00:14:40.955510    1931 state_mem.go:35] "Initializing new in-memory state store"
Oct 30 00:14:40.968371 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice.
Oct 30 00:14:40.986757 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice.
Oct 30 00:14:40.998127 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice.
Oct 30 00:14:41.007182 kubelet[1931]: E1030 00:14:41.007037    1931 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.128.0.4\" not found"
Oct 30 00:14:41.008750 kubelet[1931]: I1030 00:14:41.008266    1931 manager.go:519] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found"
Oct 30 00:14:41.009313 kubelet[1931]: I1030 00:14:41.009293    1931 eviction_manager.go:189] "Eviction manager: starting control loop"
Oct 30 00:14:41.010159 kubelet[1931]: I1030 00:14:41.009535    1931 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s"
Oct 30 00:14:41.010159 kubelet[1931]: I1030 00:14:41.010034    1931 plugin_manager.go:118] "Starting Kubelet Plugin Manager"
Oct 30 00:14:41.014897 kubelet[1931]: E1030 00:14:41.014751    1931 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime"
Oct 30 00:14:41.015025 kubelet[1931]: E1030 00:14:41.014999    1931 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.128.0.4\" not found"
Oct 30 00:14:41.040151 kubelet[1931]: I1030 00:14:41.039999    1931 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4"
Oct 30 00:14:41.043926 kubelet[1931]: I1030 00:14:41.043177    1931 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6"
Oct 30 00:14:41.043926 kubelet[1931]: I1030 00:14:41.043259    1931 status_manager.go:227] "Starting to sync pod status with apiserver"
Oct 30 00:14:41.043926 kubelet[1931]: I1030 00:14:41.043290    1931 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started."
Oct 30 00:14:41.043926 kubelet[1931]: I1030 00:14:41.043302    1931 kubelet.go:2382] "Starting kubelet main sync loop"
Oct 30 00:14:41.043926 kubelet[1931]: E1030 00:14:41.043427    1931 kubelet.go:2406] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful"
Oct 30 00:14:41.111428 kubelet[1931]: I1030 00:14:41.111341    1931 kubelet_node_status.go:75] "Attempting to register node" node="10.128.0.4"
Oct 30 00:14:41.116898 kubelet[1931]: I1030 00:14:41.116847    1931 kubelet_node_status.go:78] "Successfully registered node" node="10.128.0.4"
Oct 30 00:14:41.130266 kubelet[1931]: I1030 00:14:41.130228    1931 kuberuntime_manager.go:1702] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24"
Oct 30 00:14:41.131163 containerd[1499]: time="2025-10-30T00:14:41.130906075Z" level=info msg="No cni config template is specified, wait for other system components to drop the config."
Oct 30 00:14:41.131785 kubelet[1931]: I1030 00:14:41.131561    1931 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24"
Oct 30 00:14:41.335345 sudo[1803]: pam_unix(sudo:session): session closed for user root
Oct 30 00:14:41.378533 sshd[1802]: Connection closed by 139.178.89.65 port 51604
Oct 30 00:14:41.379139 sshd-session[1799]: pam_unix(sshd:session): session closed for user core
Oct 30 00:14:41.386903 systemd[1]: sshd@7-10.128.0.4:22-139.178.89.65:51604.service: Deactivated successfully.
Oct 30 00:14:41.391309 systemd[1]: session-7.scope: Deactivated successfully.
Oct 30 00:14:41.391641 systemd[1]: session-7.scope: Consumed 659ms CPU time, 76.9M memory peak.
Oct 30 00:14:41.395000 systemd-logind[1477]: Session 7 logged out. Waiting for processes to exit.
Oct 30 00:14:41.398364 systemd-logind[1477]: Removed session 7.
Oct 30 00:14:41.786474 kubelet[1931]: I1030 00:14:41.786423    1931 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials"
Oct 30 00:14:41.787450 kubelet[1931]: W1030 00:14:41.786721    1931 reflector.go:492] k8s.io/client-go/informers/factory.go:160: watch of *v1.CSIDriver ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received
Oct 30 00:14:41.787450 kubelet[1931]: W1030 00:14:41.786770    1931 reflector.go:492] k8s.io/client-go/informers/factory.go:160: watch of *v1.RuntimeClass ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received
Oct 30 00:14:41.872658 kubelet[1931]: I1030 00:14:41.872613    1931 apiserver.go:52] "Watching apiserver"
Oct 30 00:14:41.872658 kubelet[1931]: E1030 00:14:41.872633    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:14:41.891419 systemd[1]: Created slice kubepods-besteffort-pod9c7b371f_6b5f_4eea_ae7c_bddcfb16eebc.slice - libcontainer container kubepods-besteffort-pod9c7b371f_6b5f_4eea_ae7c_bddcfb16eebc.slice.
Oct 30 00:14:41.907406 systemd[1]: Created slice kubepods-burstable-pod179fbfb8_eb13_46c6_91f0_9ccbd48b7074.slice - libcontainer container kubepods-burstable-pod179fbfb8_eb13_46c6_91f0_9ccbd48b7074.slice.
Oct 30 00:14:41.923291 kubelet[1931]: I1030 00:14:41.923241    1931 desired_state_of_world_populator.go:158] "Finished populating initial desired state of world"
Oct 30 00:14:41.929344 kubelet[1931]: I1030 00:14:41.929267    1931 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/9c7b371f-6b5f-4eea-ae7c-bddcfb16eebc-kube-proxy\") pod \"kube-proxy-jlppx\" (UID: \"9c7b371f-6b5f-4eea-ae7c-bddcfb16eebc\") " pod="kube-system/kube-proxy-jlppx"
Oct 30 00:14:41.929344 kubelet[1931]: I1030 00:14:41.929339    1931 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/9c7b371f-6b5f-4eea-ae7c-bddcfb16eebc-lib-modules\") pod \"kube-proxy-jlppx\" (UID: \"9c7b371f-6b5f-4eea-ae7c-bddcfb16eebc\") " pod="kube-system/kube-proxy-jlppx"
Oct 30 00:14:41.929570 kubelet[1931]: I1030 00:14:41.929375    1931 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/179fbfb8-eb13-46c6-91f0-9ccbd48b7074-run\") pod \"kube-flannel-ds-2v56c\" (UID: \"179fbfb8-eb13-46c6-91f0-9ccbd48b7074\") " pod="kube-flannel/kube-flannel-ds-2v56c"
Oct 30 00:14:41.929570 kubelet[1931]: I1030 00:14:41.929401    1931 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/179fbfb8-eb13-46c6-91f0-9ccbd48b7074-cni\") pod \"kube-flannel-ds-2v56c\" (UID: \"179fbfb8-eb13-46c6-91f0-9ccbd48b7074\") " pod="kube-flannel/kube-flannel-ds-2v56c"
Oct 30 00:14:41.929570 kubelet[1931]: I1030 00:14:41.929429    1931 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/179fbfb8-eb13-46c6-91f0-9ccbd48b7074-flannel-cfg\") pod \"kube-flannel-ds-2v56c\" (UID: \"179fbfb8-eb13-46c6-91f0-9ccbd48b7074\") " pod="kube-flannel/kube-flannel-ds-2v56c"
Oct 30 00:14:41.929570 kubelet[1931]: I1030 00:14:41.929458    1931 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/9c7b371f-6b5f-4eea-ae7c-bddcfb16eebc-xtables-lock\") pod \"kube-proxy-jlppx\" (UID: \"9c7b371f-6b5f-4eea-ae7c-bddcfb16eebc\") " pod="kube-system/kube-proxy-jlppx"
Oct 30 00:14:41.929570 kubelet[1931]: I1030 00:14:41.929502    1931 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-swqzr\" (UniqueName: \"kubernetes.io/projected/9c7b371f-6b5f-4eea-ae7c-bddcfb16eebc-kube-api-access-swqzr\") pod \"kube-proxy-jlppx\" (UID: \"9c7b371f-6b5f-4eea-ae7c-bddcfb16eebc\") " pod="kube-system/kube-proxy-jlppx"
Oct 30 00:14:41.929854 kubelet[1931]: I1030 00:14:41.929539    1931 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/179fbfb8-eb13-46c6-91f0-9ccbd48b7074-cni-plugin\") pod \"kube-flannel-ds-2v56c\" (UID: \"179fbfb8-eb13-46c6-91f0-9ccbd48b7074\") " pod="kube-flannel/kube-flannel-ds-2v56c"
Oct 30 00:14:41.929854 kubelet[1931]: I1030 00:14:41.929624    1931 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/179fbfb8-eb13-46c6-91f0-9ccbd48b7074-xtables-lock\") pod \"kube-flannel-ds-2v56c\" (UID: \"179fbfb8-eb13-46c6-91f0-9ccbd48b7074\") " pod="kube-flannel/kube-flannel-ds-2v56c"
Oct 30 00:14:41.929854 kubelet[1931]: I1030 00:14:41.929663    1931 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-l95nm\" (UniqueName: \"kubernetes.io/projected/179fbfb8-eb13-46c6-91f0-9ccbd48b7074-kube-api-access-l95nm\") pod \"kube-flannel-ds-2v56c\" (UID: \"179fbfb8-eb13-46c6-91f0-9ccbd48b7074\") " pod="kube-flannel/kube-flannel-ds-2v56c"
Oct 30 00:14:42.204480 containerd[1499]: time="2025-10-30T00:14:42.204412718Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-jlppx,Uid:9c7b371f-6b5f-4eea-ae7c-bddcfb16eebc,Namespace:kube-system,Attempt:0,}"
Oct 30 00:14:42.218818 containerd[1499]: time="2025-10-30T00:14:42.218740757Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-2v56c,Uid:179fbfb8-eb13-46c6-91f0-9ccbd48b7074,Namespace:kube-flannel,Attempt:0,}"
Oct 30 00:14:42.695884 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2444354985.mount: Deactivated successfully.
Oct 30 00:14:42.707547 containerd[1499]: time="2025-10-30T00:14:42.707464559Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause:3.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}"
Oct 30 00:14:42.710483 containerd[1499]: time="2025-10-30T00:14:42.710132851Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=322072"
Oct 30 00:14:42.712735 containerd[1499]: time="2025-10-30T00:14:42.712662332Z" level=info msg="ImageUpdate event name:\"registry.k8s.io/pause:3.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}"
Oct 30 00:14:42.714747 containerd[1499]: time="2025-10-30T00:14:42.714660815Z" level=info msg="ImageCreate event name:\"sha256:873ed75102791e5b0b8a7fcd41606c92fcec98d56d05ead4ac5131650004c136\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}"
Oct 30 00:14:42.715407 containerd[1499]: time="2025-10-30T00:14:42.715352134Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=0"
Oct 30 00:14:42.717573 containerd[1499]: time="2025-10-30T00:14:42.717529291Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}"
Oct 30 00:14:42.719294 containerd[1499]: time="2025-10-30T00:14:42.718554980Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.10\" with image id \"sha256:873ed75102791e5b0b8a7fcd41606c92fcec98d56d05ead4ac5131650004c136\", repo tag \"registry.k8s.io/pause:3.10\", repo digest \"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\", size \"320368\" in 505.60129ms"
Oct 30 00:14:42.721677 containerd[1499]: time="2025-10-30T00:14:42.721610229Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.10\" with image id \"sha256:873ed75102791e5b0b8a7fcd41606c92fcec98d56d05ead4ac5131650004c136\", repo tag \"registry.k8s.io/pause:3.10\", repo digest \"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\", size \"320368\" in 495.276002ms"
Oct 30 00:14:42.759323 containerd[1499]: time="2025-10-30T00:14:42.758389167Z" level=info msg="connecting to shim 2103126e0c77dbde0a047a14a9a2db2d3bcd893336af557971effada64733097" address="unix:///run/containerd/s/1ef4ae3e07c1c707f14aa739e80bb6cfdb09adb7709ef98d407d648e91882306" namespace=k8s.io protocol=ttrpc version=3
Oct 30 00:14:42.764016 containerd[1499]: time="2025-10-30T00:14:42.763936551Z" level=info msg="connecting to shim bca6b1f69b1cf3700a6042ba020731503cc30154fa20940ceb75e7e1c7fa522d" address="unix:///run/containerd/s/3704e5db822ec855e3d45aaa6e31206cb958f723ed2ce531455ac6fa26d33d91" namespace=k8s.io protocol=ttrpc version=3
Oct 30 00:14:42.805195 systemd[1]: Started cri-containerd-2103126e0c77dbde0a047a14a9a2db2d3bcd893336af557971effada64733097.scope - libcontainer container 2103126e0c77dbde0a047a14a9a2db2d3bcd893336af557971effada64733097.
Oct 30 00:14:42.820513 systemd[1]: Started cri-containerd-bca6b1f69b1cf3700a6042ba020731503cc30154fa20940ceb75e7e1c7fa522d.scope - libcontainer container bca6b1f69b1cf3700a6042ba020731503cc30154fa20940ceb75e7e1c7fa522d.
Oct 30 00:14:42.874716 kubelet[1931]: E1030 00:14:42.872944    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:14:42.881718 containerd[1499]: time="2025-10-30T00:14:42.880289166Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-jlppx,Uid:9c7b371f-6b5f-4eea-ae7c-bddcfb16eebc,Namespace:kube-system,Attempt:0,} returns sandbox id \"2103126e0c77dbde0a047a14a9a2db2d3bcd893336af557971effada64733097\""
Oct 30 00:14:42.884836 containerd[1499]: time="2025-10-30T00:14:42.884711718Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.9\""
Oct 30 00:14:42.913669 containerd[1499]: time="2025-10-30T00:14:42.913536520Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-2v56c,Uid:179fbfb8-eb13-46c6-91f0-9ccbd48b7074,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"bca6b1f69b1cf3700a6042ba020731503cc30154fa20940ceb75e7e1c7fa522d\""
Oct 30 00:14:43.873381 kubelet[1931]: E1030 00:14:43.873306    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:14:44.083400 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2602742586.mount: Deactivated successfully.
Oct 30 00:14:44.838018 containerd[1499]: time="2025-10-30T00:14:44.837920848Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.32.9\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Oct 30 00:14:44.839798 containerd[1499]: time="2025-10-30T00:14:44.839508095Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.32.9: active requests=0, bytes read=30926101"
Oct 30 00:14:44.841141 containerd[1499]: time="2025-10-30T00:14:44.841091948Z" level=info msg="ImageCreate event name:\"sha256:fa3fdca615a501743d8deb39729a96e731312aac8d96accec061d5265360332f\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Oct 30 00:14:44.845467 containerd[1499]: time="2025-10-30T00:14:44.844272806Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:886af02535dc34886e4618b902f8c140d89af57233a245621d29642224516064\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Oct 30 00:14:44.845467 containerd[1499]: time="2025-10-30T00:14:44.845258968Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.32.9\" with image id \"sha256:fa3fdca615a501743d8deb39729a96e731312aac8d96accec061d5265360332f\", repo tag \"registry.k8s.io/kube-proxy:v1.32.9\", repo digest \"registry.k8s.io/kube-proxy@sha256:886af02535dc34886e4618b902f8c140d89af57233a245621d29642224516064\", size \"30923225\" in 1.960500238s"
Oct 30 00:14:44.845467 containerd[1499]: time="2025-10-30T00:14:44.845301794Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.9\" returns image reference \"sha256:fa3fdca615a501743d8deb39729a96e731312aac8d96accec061d5265360332f\""
Oct 30 00:14:44.847669 containerd[1499]: time="2025-10-30T00:14:44.847633789Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\""
Oct 30 00:14:44.848807 containerd[1499]: time="2025-10-30T00:14:44.848767083Z" level=info msg="CreateContainer within sandbox \"2103126e0c77dbde0a047a14a9a2db2d3bcd893336af557971effada64733097\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}"
Oct 30 00:14:44.862725 containerd[1499]: time="2025-10-30T00:14:44.861537079Z" level=info msg="Container e84d2ef298ab7c852db69e3e1151ee2f88dc3814f5a2709c85fee3ffbe369bd7: CDI devices from CRI Config.CDIDevices: []"
Oct 30 00:14:44.872795 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1229166537.mount: Deactivated successfully.
Oct 30 00:14:44.874259 kubelet[1931]: E1030 00:14:44.873830    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:14:44.881167 containerd[1499]: time="2025-10-30T00:14:44.881100800Z" level=info msg="CreateContainer within sandbox \"2103126e0c77dbde0a047a14a9a2db2d3bcd893336af557971effada64733097\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"e84d2ef298ab7c852db69e3e1151ee2f88dc3814f5a2709c85fee3ffbe369bd7\""
Oct 30 00:14:44.882260 containerd[1499]: time="2025-10-30T00:14:44.882218875Z" level=info msg="StartContainer for \"e84d2ef298ab7c852db69e3e1151ee2f88dc3814f5a2709c85fee3ffbe369bd7\""
Oct 30 00:14:44.884978 containerd[1499]: time="2025-10-30T00:14:44.884936382Z" level=info msg="connecting to shim e84d2ef298ab7c852db69e3e1151ee2f88dc3814f5a2709c85fee3ffbe369bd7" address="unix:///run/containerd/s/1ef4ae3e07c1c707f14aa739e80bb6cfdb09adb7709ef98d407d648e91882306" protocol=ttrpc version=3
Oct 30 00:14:44.917870 systemd[1]: Started cri-containerd-e84d2ef298ab7c852db69e3e1151ee2f88dc3814f5a2709c85fee3ffbe369bd7.scope - libcontainer container e84d2ef298ab7c852db69e3e1151ee2f88dc3814f5a2709c85fee3ffbe369bd7.
Oct 30 00:14:44.988886 containerd[1499]: time="2025-10-30T00:14:44.988805637Z" level=info msg="StartContainer for \"e84d2ef298ab7c852db69e3e1151ee2f88dc3814f5a2709c85fee3ffbe369bd7\" returns successfully"
Oct 30 00:14:45.874917 kubelet[1931]: E1030 00:14:45.874854    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:14:46.254986 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2025407340.mount: Deactivated successfully.
Oct 30 00:14:46.315901 containerd[1499]: time="2025-10-30T00:14:46.315806682Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Oct 30 00:14:46.317184 containerd[1499]: time="2025-10-30T00:14:46.317119157Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3852937"
Oct 30 00:14:46.318756 containerd[1499]: time="2025-10-30T00:14:46.318658092Z" level=info msg="ImageCreate event name:\"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Oct 30 00:14:46.321602 containerd[1499]: time="2025-10-30T00:14:46.321534746Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Oct 30 00:14:46.323042 containerd[1499]: time="2025-10-30T00:14:46.322558494Z" 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 1.474869158s"
Oct 30 00:14:46.323042 containerd[1499]: time="2025-10-30T00:14:46.322606393Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\""
Oct 30 00:14:46.325768 containerd[1499]: time="2025-10-30T00:14:46.325724343Z" level=info msg="CreateContainer within sandbox \"bca6b1f69b1cf3700a6042ba020731503cc30154fa20940ceb75e7e1c7fa522d\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}"
Oct 30 00:14:46.337361 containerd[1499]: time="2025-10-30T00:14:46.335302872Z" level=info msg="Container 783d40a62710b32e2e2e4c9515de080cecb8c14cb40ea5a4bd9d958db612f3e8: CDI devices from CRI Config.CDIDevices: []"
Oct 30 00:14:46.347385 containerd[1499]: time="2025-10-30T00:14:46.347323823Z" level=info msg="CreateContainer within sandbox \"bca6b1f69b1cf3700a6042ba020731503cc30154fa20940ceb75e7e1c7fa522d\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"783d40a62710b32e2e2e4c9515de080cecb8c14cb40ea5a4bd9d958db612f3e8\""
Oct 30 00:14:46.349916 containerd[1499]: time="2025-10-30T00:14:46.348268768Z" level=info msg="StartContainer for \"783d40a62710b32e2e2e4c9515de080cecb8c14cb40ea5a4bd9d958db612f3e8\""
Oct 30 00:14:46.349916 containerd[1499]: time="2025-10-30T00:14:46.349411311Z" level=info msg="connecting to shim 783d40a62710b32e2e2e4c9515de080cecb8c14cb40ea5a4bd9d958db612f3e8" address="unix:///run/containerd/s/3704e5db822ec855e3d45aaa6e31206cb958f723ed2ce531455ac6fa26d33d91" protocol=ttrpc version=3
Oct 30 00:14:46.378954 systemd[1]: Started cri-containerd-783d40a62710b32e2e2e4c9515de080cecb8c14cb40ea5a4bd9d958db612f3e8.scope - libcontainer container 783d40a62710b32e2e2e4c9515de080cecb8c14cb40ea5a4bd9d958db612f3e8.
Oct 30 00:14:46.438279 systemd[1]: cri-containerd-783d40a62710b32e2e2e4c9515de080cecb8c14cb40ea5a4bd9d958db612f3e8.scope: Deactivated successfully.
Oct 30 00:14:46.442679 containerd[1499]: time="2025-10-30T00:14:46.442617439Z" level=info msg="TaskExit event in podsandbox handler container_id:\"783d40a62710b32e2e2e4c9515de080cecb8c14cb40ea5a4bd9d958db612f3e8\" id:\"783d40a62710b32e2e2e4c9515de080cecb8c14cb40ea5a4bd9d958db612f3e8\" pid:2260 exited_at:{seconds:1761783286 nanos:442149324}"
Oct 30 00:14:46.442836 containerd[1499]: time="2025-10-30T00:14:46.442809372Z" level=info msg="received exit event container_id:\"783d40a62710b32e2e2e4c9515de080cecb8c14cb40ea5a4bd9d958db612f3e8\" id:\"783d40a62710b32e2e2e4c9515de080cecb8c14cb40ea5a4bd9d958db612f3e8\" pid:2260 exited_at:{seconds:1761783286 nanos:442149324}"
Oct 30 00:14:46.443428 containerd[1499]: time="2025-10-30T00:14:46.443302025Z" level=info msg="StartContainer for \"783d40a62710b32e2e2e4c9515de080cecb8c14cb40ea5a4bd9d958db612f3e8\" returns successfully"
Oct 30 00:14:46.875092 kubelet[1931]: E1030 00:14:46.875003    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:14:47.086220 containerd[1499]: time="2025-10-30T00:14:47.086174750Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\""
Oct 30 00:14:47.097967 kubelet[1931]: I1030 00:14:47.097855    1931 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-jlppx" podStartSLOduration=4.13464391 podStartE2EDuration="6.097831447s" podCreationTimestamp="2025-10-30 00:14:41 +0000 UTC" firstStartedPulling="2025-10-30 00:14:42.883457287 +0000 UTC m=+3.067705561" lastFinishedPulling="2025-10-30 00:14:44.846644818 +0000 UTC m=+5.030893098" observedRunningTime="2025-10-30 00:14:45.091919034 +0000 UTC m=+5.276167320" watchObservedRunningTime="2025-10-30 00:14:47.097831447 +0000 UTC m=+7.282079756"
Oct 30 00:14:47.111561 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-783d40a62710b32e2e2e4c9515de080cecb8c14cb40ea5a4bd9d958db612f3e8-rootfs.mount: Deactivated successfully.
Oct 30 00:14:47.875861 kubelet[1931]: E1030 00:14:47.875792    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:14:48.673736 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3212052756.mount: Deactivated successfully.
Oct 30 00:14:48.876758 kubelet[1931]: E1030 00:14:48.876683    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:14:49.772118 containerd[1499]: time="2025-10-30T00:14:49.772041480Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Oct 30 00:14:49.773898 containerd[1499]: time="2025-10-30T00:14:49.773732066Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26866358"
Oct 30 00:14:49.775209 containerd[1499]: time="2025-10-30T00:14:49.775167679Z" level=info msg="ImageCreate event name:\"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Oct 30 00:14:49.778752 containerd[1499]: time="2025-10-30T00:14:49.778655689Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Oct 30 00:14:49.780722 containerd[1499]: time="2025-10-30T00:14:49.779983268Z" 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 2.693757381s"
Oct 30 00:14:49.780722 containerd[1499]: time="2025-10-30T00:14:49.780029650Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\""
Oct 30 00:14:49.783648 containerd[1499]: time="2025-10-30T00:14:49.783597225Z" level=info msg="CreateContainer within sandbox \"bca6b1f69b1cf3700a6042ba020731503cc30154fa20940ceb75e7e1c7fa522d\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}"
Oct 30 00:14:49.794613 containerd[1499]: time="2025-10-30T00:14:49.793564474Z" level=info msg="Container 10ab73774ac1db8aebfd193e7f1792e71491ab9c4557eba24d0da9a4da98854a: CDI devices from CRI Config.CDIDevices: []"
Oct 30 00:14:49.803412 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount859285031.mount: Deactivated successfully.
Oct 30 00:14:49.811035 containerd[1499]: time="2025-10-30T00:14:49.810959359Z" level=info msg="CreateContainer within sandbox \"bca6b1f69b1cf3700a6042ba020731503cc30154fa20940ceb75e7e1c7fa522d\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"10ab73774ac1db8aebfd193e7f1792e71491ab9c4557eba24d0da9a4da98854a\""
Oct 30 00:14:49.811933 containerd[1499]: time="2025-10-30T00:14:49.811857371Z" level=info msg="StartContainer for \"10ab73774ac1db8aebfd193e7f1792e71491ab9c4557eba24d0da9a4da98854a\""
Oct 30 00:14:49.813388 containerd[1499]: time="2025-10-30T00:14:49.813336721Z" level=info msg="connecting to shim 10ab73774ac1db8aebfd193e7f1792e71491ab9c4557eba24d0da9a4da98854a" address="unix:///run/containerd/s/3704e5db822ec855e3d45aaa6e31206cb958f723ed2ce531455ac6fa26d33d91" protocol=ttrpc version=3
Oct 30 00:14:49.854220 systemd[1]: Started cri-containerd-10ab73774ac1db8aebfd193e7f1792e71491ab9c4557eba24d0da9a4da98854a.scope - libcontainer container 10ab73774ac1db8aebfd193e7f1792e71491ab9c4557eba24d0da9a4da98854a.
Oct 30 00:14:49.877096 kubelet[1931]: E1030 00:14:49.876924    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:14:49.900330 systemd[1]: cri-containerd-10ab73774ac1db8aebfd193e7f1792e71491ab9c4557eba24d0da9a4da98854a.scope: Deactivated successfully.
Oct 30 00:14:49.904156 containerd[1499]: time="2025-10-30T00:14:49.904104832Z" level=info msg="TaskExit event in podsandbox handler container_id:\"10ab73774ac1db8aebfd193e7f1792e71491ab9c4557eba24d0da9a4da98854a\" id:\"10ab73774ac1db8aebfd193e7f1792e71491ab9c4557eba24d0da9a4da98854a\" pid:2333 exited_at:{seconds:1761783289 nanos:903576413}"
Oct 30 00:14:49.908073 containerd[1499]: time="2025-10-30T00:14:49.907086274Z" level=info msg="received exit event container_id:\"10ab73774ac1db8aebfd193e7f1792e71491ab9c4557eba24d0da9a4da98854a\" id:\"10ab73774ac1db8aebfd193e7f1792e71491ab9c4557eba24d0da9a4da98854a\" pid:2333 exited_at:{seconds:1761783289 nanos:903576413}"
Oct 30 00:14:49.909164 kubelet[1931]: I1030 00:14:49.909135    1931 kubelet_node_status.go:501] "Fast updating node status as it just became ready"
Oct 30 00:14:49.922061 containerd[1499]: time="2025-10-30T00:14:49.922012304Z" level=info msg="StartContainer for \"10ab73774ac1db8aebfd193e7f1792e71491ab9c4557eba24d0da9a4da98854a\" returns successfully"
Oct 30 00:14:49.945995 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-10ab73774ac1db8aebfd193e7f1792e71491ab9c4557eba24d0da9a4da98854a-rootfs.mount: Deactivated successfully.
Oct 30 00:14:50.877762 kubelet[1931]: E1030 00:14:50.877663    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:14:51.102727 containerd[1499]: time="2025-10-30T00:14:51.102477160Z" level=info msg="CreateContainer within sandbox \"bca6b1f69b1cf3700a6042ba020731503cc30154fa20940ceb75e7e1c7fa522d\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}"
Oct 30 00:14:51.122769 containerd[1499]: time="2025-10-30T00:14:51.122112513Z" level=info msg="Container aaa5649cef871d44f643d4c77b6eeb25cb140cd946740d2359d6ce2683307084: CDI devices from CRI Config.CDIDevices: []"
Oct 30 00:14:51.132575 containerd[1499]: time="2025-10-30T00:14:51.132409045Z" level=info msg="CreateContainer within sandbox \"bca6b1f69b1cf3700a6042ba020731503cc30154fa20940ceb75e7e1c7fa522d\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"aaa5649cef871d44f643d4c77b6eeb25cb140cd946740d2359d6ce2683307084\""
Oct 30 00:14:51.133784 containerd[1499]: time="2025-10-30T00:14:51.133742257Z" level=info msg="StartContainer for \"aaa5649cef871d44f643d4c77b6eeb25cb140cd946740d2359d6ce2683307084\""
Oct 30 00:14:51.134878 containerd[1499]: time="2025-10-30T00:14:51.134818429Z" level=info msg="connecting to shim aaa5649cef871d44f643d4c77b6eeb25cb140cd946740d2359d6ce2683307084" address="unix:///run/containerd/s/3704e5db822ec855e3d45aaa6e31206cb958f723ed2ce531455ac6fa26d33d91" protocol=ttrpc version=3
Oct 30 00:14:51.174065 systemd[1]: Started cri-containerd-aaa5649cef871d44f643d4c77b6eeb25cb140cd946740d2359d6ce2683307084.scope - libcontainer container aaa5649cef871d44f643d4c77b6eeb25cb140cd946740d2359d6ce2683307084.
Oct 30 00:14:51.225846 containerd[1499]: time="2025-10-30T00:14:51.225788656Z" level=info msg="StartContainer for \"aaa5649cef871d44f643d4c77b6eeb25cb140cd946740d2359d6ce2683307084\" returns successfully"
Oct 30 00:14:51.877897 kubelet[1931]: E1030 00:14:51.877814    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:14:52.123346 kubelet[1931]: I1030 00:14:52.123262    1931 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-2v56c" podStartSLOduration=4.256818887 podStartE2EDuration="11.123239578s" podCreationTimestamp="2025-10-30 00:14:41 +0000 UTC" firstStartedPulling="2025-10-30 00:14:42.915297821 +0000 UTC m=+3.099546103" lastFinishedPulling="2025-10-30 00:14:49.781718517 +0000 UTC m=+9.965966794" observedRunningTime="2025-10-30 00:14:52.123186914 +0000 UTC m=+12.307435199" watchObservedRunningTime="2025-10-30 00:14:52.123239578 +0000 UTC m=+12.307487862"
Oct 30 00:14:52.298944 systemd-networkd[1434]: flannel.1: Link UP
Oct 30 00:14:52.298995 systemd-networkd[1434]: flannel.1: Gained carrier
Oct 30 00:14:52.878261 kubelet[1931]: E1030 00:14:52.878188    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:14:53.250271 systemd[1]: systemd-hostnamed.service: Deactivated successfully.
Oct 30 00:14:53.847218 systemd-networkd[1434]: flannel.1: Gained IPv6LL
Oct 30 00:14:53.879365 kubelet[1931]: E1030 00:14:53.879277    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:14:54.117016 systemd[1]: Created slice kubepods-besteffort-poddd53384f_9535_432a_9324_4730e0f4557c.slice - libcontainer container kubepods-besteffort-poddd53384f_9535_432a_9324_4730e0f4557c.slice.
Oct 30 00:14:54.219480 kubelet[1931]: I1030 00:14:54.219407    1931 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-5vg7g\" (UniqueName: \"kubernetes.io/projected/dd53384f-9535-432a-9324-4730e0f4557c-kube-api-access-5vg7g\") pod \"nginx-deployment-7fcdb87857-bqt5b\" (UID: \"dd53384f-9535-432a-9324-4730e0f4557c\") " pod="default/nginx-deployment-7fcdb87857-bqt5b"
Oct 30 00:14:54.421802 containerd[1499]: time="2025-10-30T00:14:54.421645396Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-bqt5b,Uid:dd53384f-9535-432a-9324-4730e0f4557c,Namespace:default,Attempt:0,}"
Oct 30 00:14:54.442185 systemd-networkd[1434]: cni0: Link UP
Oct 30 00:14:54.442199 systemd-networkd[1434]: cni0: Gained carrier
Oct 30 00:14:54.442770 systemd-networkd[1434]: cni0: Lost carrier
Oct 30 00:14:54.456480 systemd-networkd[1434]: veth859039bd: Link UP
Oct 30 00:14:54.470733 kernel: cni0: port 1(veth859039bd) entered blocking state
Oct 30 00:14:54.470872 kernel: cni0: port 1(veth859039bd) entered disabled state
Oct 30 00:14:54.477725 kernel: veth859039bd: entered allmulticast mode
Oct 30 00:14:54.484742 kernel: veth859039bd: entered promiscuous mode
Oct 30 00:14:54.505069 kernel: cni0: port 1(veth859039bd) entered blocking state
Oct 30 00:14:54.505316 kernel: cni0: port 1(veth859039bd) entered forwarding state
Oct 30 00:14:54.505798 systemd-networkd[1434]: veth859039bd: Gained carrier
Oct 30 00:14:54.506660 systemd-networkd[1434]: cni0: Gained carrier
Oct 30 00:14:54.510900 containerd[1499]: 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)(0xc0000a48e8), "name":"cbr0", "type":"bridge"}
Oct 30 00:14:54.510900 containerd[1499]: delegateAdd: netconf sent to delegate plugin:
Oct 30 00:14:54.556809 containerd[1499]: {"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":1410,"name":"cbr0","type":"bridge"}time="2025-10-30T00:14:54.556736576Z" level=info msg="connecting to shim 62e6190e52da6108423d3c3a13221e0a39e10111d3a6296e68452b1be16acc8d" address="unix:///run/containerd/s/7df2f097063326bb82725e68fd73702a8e59a818f85ac9a0a75bb6fcd15a9d0b" namespace=k8s.io protocol=ttrpc version=3
Oct 30 00:14:54.604080 systemd[1]: Started cri-containerd-62e6190e52da6108423d3c3a13221e0a39e10111d3a6296e68452b1be16acc8d.scope - libcontainer container 62e6190e52da6108423d3c3a13221e0a39e10111d3a6296e68452b1be16acc8d.
Oct 30 00:14:54.678051 containerd[1499]: time="2025-10-30T00:14:54.677606298Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-bqt5b,Uid:dd53384f-9535-432a-9324-4730e0f4557c,Namespace:default,Attempt:0,} returns sandbox id \"62e6190e52da6108423d3c3a13221e0a39e10111d3a6296e68452b1be16acc8d\""
Oct 30 00:14:54.679333 containerd[1499]: time="2025-10-30T00:14:54.679197130Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\""
Oct 30 00:14:54.880264 kubelet[1931]: E1030 00:14:54.880204    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:14:55.880493 kubelet[1931]: E1030 00:14:55.880390    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:14:55.895059 systemd-networkd[1434]: veth859039bd: Gained IPv6LL
Oct 30 00:14:56.408511 systemd-networkd[1434]: cni0: Gained IPv6LL
Oct 30 00:14:56.881594 kubelet[1931]: E1030 00:14:56.881522    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:14:57.570922 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3068771916.mount: Deactivated successfully.
Oct 30 00:14:57.882573 kubelet[1931]: E1030 00:14:57.882419    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:14:58.875326 systemd[1]: Started sshd@8-10.128.0.4:22-162.241.127.152:40316.service - OpenSSH per-connection server daemon (162.241.127.152:40316).
Oct 30 00:14:58.882858 kubelet[1931]: E1030 00:14:58.882791    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:14:58.973095 ntpd[1472]: Listen normally on 6 flannel.1 192.168.1.0:123
Oct 30 00:14:58.974091 ntpd[1472]: 30 Oct 00:14:58 ntpd[1472]: Listen normally on 6 flannel.1 192.168.1.0:123
Oct 30 00:14:58.974091 ntpd[1472]: 30 Oct 00:14:58 ntpd[1472]: Listen normally on 7 cni0 192.168.1.1:123
Oct 30 00:14:58.974091 ntpd[1472]: 30 Oct 00:14:58 ntpd[1472]: Listen normally on 8 flannel.1 [fe80::886c:a5ff:fec3:af50%3]:123
Oct 30 00:14:58.974091 ntpd[1472]: 30 Oct 00:14:58 ntpd[1472]: Listen normally on 9 cni0 [fe80::1405:74ff:fe6a:644c%4]:123
Oct 30 00:14:58.974091 ntpd[1472]: 30 Oct 00:14:58 ntpd[1472]: Listen normally on 10 veth859039bd [fe80::5cc8:27ff:fe34:43de%5]:123
Oct 30 00:14:58.973190 ntpd[1472]: Listen normally on 7 cni0 192.168.1.1:123
Oct 30 00:14:58.973250 ntpd[1472]: Listen normally on 8 flannel.1 [fe80::886c:a5ff:fec3:af50%3]:123
Oct 30 00:14:58.973299 ntpd[1472]: Listen normally on 9 cni0 [fe80::1405:74ff:fe6a:644c%4]:123
Oct 30 00:14:58.973349 ntpd[1472]: Listen normally on 10 veth859039bd [fe80::5cc8:27ff:fe34:43de%5]:123
Oct 30 00:14:59.173985 sshd[2590]: Invalid user centos from 162.241.127.152 port 40316
Oct 30 00:14:59.219581 sshd[2590]: Received disconnect from 162.241.127.152 port 40316:11: Bye Bye [preauth]
Oct 30 00:14:59.219581 sshd[2590]: Disconnected from invalid user centos 162.241.127.152 port 40316 [preauth]
Oct 30 00:14:59.223205 systemd[1]: sshd@8-10.128.0.4:22-162.241.127.152:40316.service: Deactivated successfully.
Oct 30 00:14:59.319240 containerd[1499]: time="2025-10-30T00:14:59.319169375Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Oct 30 00:14:59.320786 containerd[1499]: time="2025-10-30T00:14:59.320724319Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=73311946"
Oct 30 00:14:59.322742 containerd[1499]: time="2025-10-30T00:14:59.322185215Z" level=info msg="ImageCreate event name:\"sha256:8d14817f00613fe76ef7459f977ad93e7b71a3948346b7ac4d50e35f3dd518e9\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Oct 30 00:14:59.325897 containerd[1499]: time="2025-10-30T00:14:59.325823160Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Oct 30 00:14:59.327250 containerd[1499]: time="2025-10-30T00:14:59.327091363Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:8d14817f00613fe76ef7459f977ad93e7b71a3948346b7ac4d50e35f3dd518e9\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\", size \"73311824\" in 4.647855733s"
Oct 30 00:14:59.327250 containerd[1499]: time="2025-10-30T00:14:59.327137613Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:8d14817f00613fe76ef7459f977ad93e7b71a3948346b7ac4d50e35f3dd518e9\""
Oct 30 00:14:59.330029 containerd[1499]: time="2025-10-30T00:14:59.329967342Z" level=info msg="CreateContainer within sandbox \"62e6190e52da6108423d3c3a13221e0a39e10111d3a6296e68452b1be16acc8d\" for container &ContainerMetadata{Name:nginx,Attempt:0,}"
Oct 30 00:14:59.344940 containerd[1499]: time="2025-10-30T00:14:59.344877621Z" level=info msg="Container 33179b33f27025514325f189a5f87486c53c2e490af4b6e34e2af763e2b9c777: CDI devices from CRI Config.CDIDevices: []"
Oct 30 00:14:59.363213 containerd[1499]: time="2025-10-30T00:14:59.363136883Z" level=info msg="CreateContainer within sandbox \"62e6190e52da6108423d3c3a13221e0a39e10111d3a6296e68452b1be16acc8d\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"33179b33f27025514325f189a5f87486c53c2e490af4b6e34e2af763e2b9c777\""
Oct 30 00:14:59.364274 containerd[1499]: time="2025-10-30T00:14:59.364232866Z" level=info msg="StartContainer for \"33179b33f27025514325f189a5f87486c53c2e490af4b6e34e2af763e2b9c777\""
Oct 30 00:14:59.365703 containerd[1499]: time="2025-10-30T00:14:59.365603619Z" level=info msg="connecting to shim 33179b33f27025514325f189a5f87486c53c2e490af4b6e34e2af763e2b9c777" address="unix:///run/containerd/s/7df2f097063326bb82725e68fd73702a8e59a818f85ac9a0a75bb6fcd15a9d0b" protocol=ttrpc version=3
Oct 30 00:14:59.402064 systemd[1]: Started cri-containerd-33179b33f27025514325f189a5f87486c53c2e490af4b6e34e2af763e2b9c777.scope - libcontainer container 33179b33f27025514325f189a5f87486c53c2e490af4b6e34e2af763e2b9c777.
Oct 30 00:14:59.457848 containerd[1499]: time="2025-10-30T00:14:59.457797129Z" level=info msg="StartContainer for \"33179b33f27025514325f189a5f87486c53c2e490af4b6e34e2af763e2b9c777\" returns successfully"
Oct 30 00:14:59.883539 kubelet[1931]: E1030 00:14:59.883363    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:15:00.139973 kubelet[1931]: I1030 00:15:00.139773    1931 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-bqt5b" podStartSLOduration=1.490037526 podStartE2EDuration="6.139753094s" podCreationTimestamp="2025-10-30 00:14:54 +0000 UTC" firstStartedPulling="2025-10-30 00:14:54.678724819 +0000 UTC m=+14.862973089" lastFinishedPulling="2025-10-30 00:14:59.328440376 +0000 UTC m=+19.512688657" observedRunningTime="2025-10-30 00:15:00.13959729 +0000 UTC m=+20.323845574" watchObservedRunningTime="2025-10-30 00:15:00.139753094 +0000 UTC m=+20.324001379"
Oct 30 00:15:00.869928 kubelet[1931]: E1030 00:15:00.869837    1931 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:15:00.884436 kubelet[1931]: E1030 00:15:00.884350    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:15:01.885526 kubelet[1931]: E1030 00:15:01.885416    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:15:02.476133 systemd[1]: Created slice kubepods-besteffort-pod59400dfc_21ed_4289_803f_782d65e3faef.slice - libcontainer container kubepods-besteffort-pod59400dfc_21ed_4289_803f_782d65e3faef.slice.
Oct 30 00:15:02.584663 kubelet[1931]: I1030 00:15:02.584559    1931 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/59400dfc-21ed-4289-803f-782d65e3faef-data\") pod \"nfs-server-provisioner-0\" (UID: \"59400dfc-21ed-4289-803f-782d65e3faef\") " pod="default/nfs-server-provisioner-0"
Oct 30 00:15:02.584663 kubelet[1931]: I1030 00:15:02.584660    1931 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-vvjm2\" (UniqueName: \"kubernetes.io/projected/59400dfc-21ed-4289-803f-782d65e3faef-kube-api-access-vvjm2\") pod \"nfs-server-provisioner-0\" (UID: \"59400dfc-21ed-4289-803f-782d65e3faef\") " pod="default/nfs-server-provisioner-0"
Oct 30 00:15:02.786599 containerd[1499]: time="2025-10-30T00:15:02.786167088Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:59400dfc-21ed-4289-803f-782d65e3faef,Namespace:default,Attempt:0,}"
Oct 30 00:15:02.807621 systemd-networkd[1434]: veth77e25882: Link UP
Oct 30 00:15:02.821201 kernel: cni0: port 2(veth77e25882) entered blocking state
Oct 30 00:15:02.821338 kernel: cni0: port 2(veth77e25882) entered disabled state
Oct 30 00:15:02.829554 kernel: veth77e25882: entered allmulticast mode
Oct 30 00:15:02.835905 kernel: veth77e25882: entered promiscuous mode
Oct 30 00:15:02.855577 kernel: cni0: port 2(veth77e25882) entered blocking state
Oct 30 00:15:02.855766 kernel: cni0: port 2(veth77e25882) entered forwarding state
Oct 30 00:15:02.855859 systemd-networkd[1434]: veth77e25882: Gained carrier
Oct 30 00:15:02.861010 containerd[1499]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc00009a8e8), "name":"cbr0", "type":"bridge"}
Oct 30 00:15:02.861010 containerd[1499]: delegateAdd: netconf sent to delegate plugin:
Oct 30 00:15:02.885673 kubelet[1931]: E1030 00:15:02.885616    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:15:02.900746 containerd[1499]: {"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":1410,"name":"cbr0","type":"bridge"}time="2025-10-30T00:15:02.899846540Z" level=info msg="connecting to shim 40788d2d0d1af18af776a6530bc3da9b42661ca91f428de26817c846dcfbb989" address="unix:///run/containerd/s/a436d984ab3ae038af39b712d71bd2cc073cf50c42ed6a402e41232b836351e7" namespace=k8s.io protocol=ttrpc version=3
Oct 30 00:15:02.943003 systemd[1]: Started cri-containerd-40788d2d0d1af18af776a6530bc3da9b42661ca91f428de26817c846dcfbb989.scope - libcontainer container 40788d2d0d1af18af776a6530bc3da9b42661ca91f428de26817c846dcfbb989.
Oct 30 00:15:03.011900 containerd[1499]: time="2025-10-30T00:15:03.011852283Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:59400dfc-21ed-4289-803f-782d65e3faef,Namespace:default,Attempt:0,} returns sandbox id \"40788d2d0d1af18af776a6530bc3da9b42661ca91f428de26817c846dcfbb989\""
Oct 30 00:15:03.014090 containerd[1499]: time="2025-10-30T00:15:03.014049338Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\""
Oct 30 00:15:03.886055 kubelet[1931]: E1030 00:15:03.885962    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:15:04.534946 systemd-networkd[1434]: veth77e25882: Gained IPv6LL
Oct 30 00:15:04.886528 kubelet[1931]: E1030 00:15:04.886289    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:15:05.520368 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3714229589.mount: Deactivated successfully.
Oct 30 00:15:05.886889 kubelet[1931]: E1030 00:15:05.886557    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:15:06.888042 kubelet[1931]: E1030 00:15:06.887969    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:15:06.973232 ntpd[1472]: Listen normally on 11 veth77e25882 [fe80::18f9:19ff:feae:ff4c%6]:123
Oct 30 00:15:06.973810 ntpd[1472]: 30 Oct 00:15:06 ntpd[1472]: Listen normally on 11 veth77e25882 [fe80::18f9:19ff:feae:ff4c%6]:123
Oct 30 00:15:07.435620 update_engine[1487]: I20251030 00:15:07.434769  1487 update_attempter.cc:509] Updating boot flags...
Oct 30 00:15:07.890466 kubelet[1931]: E1030 00:15:07.889255    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:15:08.848659 containerd[1499]: time="2025-10-30T00:15:08.848577329Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Oct 30 00:15:08.850319 containerd[1499]: time="2025-10-30T00:15:08.850126750Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=91045236"
Oct 30 00:15:08.851467 containerd[1499]: time="2025-10-30T00:15:08.851421280Z" level=info msg="ImageCreate event name:\"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Oct 30 00:15:08.855147 containerd[1499]: time="2025-10-30T00:15:08.855104602Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Oct 30 00:15:08.857448 containerd[1499]: time="2025-10-30T00:15:08.856438603Z" 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 5.842316094s"
Oct 30 00:15:08.857448 containerd[1499]: time="2025-10-30T00:15:08.856495410Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\""
Oct 30 00:15:08.860533 containerd[1499]: time="2025-10-30T00:15:08.860488630Z" level=info msg="CreateContainer within sandbox \"40788d2d0d1af18af776a6530bc3da9b42661ca91f428de26817c846dcfbb989\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}"
Oct 30 00:15:08.874810 containerd[1499]: time="2025-10-30T00:15:08.874747807Z" level=info msg="Container f2a43586cf084de7879894d9cc4e349db682664baeef2607d4c3a2affdf2b298: CDI devices from CRI Config.CDIDevices: []"
Oct 30 00:15:08.881934 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount618321143.mount: Deactivated successfully.
Oct 30 00:15:08.890109 kubelet[1931]: E1030 00:15:08.890061    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:15:08.892913 containerd[1499]: time="2025-10-30T00:15:08.892857381Z" level=info msg="CreateContainer within sandbox \"40788d2d0d1af18af776a6530bc3da9b42661ca91f428de26817c846dcfbb989\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"f2a43586cf084de7879894d9cc4e349db682664baeef2607d4c3a2affdf2b298\""
Oct 30 00:15:08.896868 containerd[1499]: time="2025-10-30T00:15:08.896828131Z" level=info msg="StartContainer for \"f2a43586cf084de7879894d9cc4e349db682664baeef2607d4c3a2affdf2b298\""
Oct 30 00:15:08.899414 containerd[1499]: time="2025-10-30T00:15:08.899366701Z" level=info msg="connecting to shim f2a43586cf084de7879894d9cc4e349db682664baeef2607d4c3a2affdf2b298" address="unix:///run/containerd/s/a436d984ab3ae038af39b712d71bd2cc073cf50c42ed6a402e41232b836351e7" protocol=ttrpc version=3
Oct 30 00:15:08.934006 systemd[1]: Started cri-containerd-f2a43586cf084de7879894d9cc4e349db682664baeef2607d4c3a2affdf2b298.scope - libcontainer container f2a43586cf084de7879894d9cc4e349db682664baeef2607d4c3a2affdf2b298.
Oct 30 00:15:08.981019 containerd[1499]: time="2025-10-30T00:15:08.980872525Z" level=info msg="StartContainer for \"f2a43586cf084de7879894d9cc4e349db682664baeef2607d4c3a2affdf2b298\" returns successfully"
Oct 30 00:15:09.193665 kubelet[1931]: I1030 00:15:09.193593    1931 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.34962075 podStartE2EDuration="7.193571392s" podCreationTimestamp="2025-10-30 00:15:02 +0000 UTC" firstStartedPulling="2025-10-30 00:15:03.013614624 +0000 UTC m=+23.197862884" lastFinishedPulling="2025-10-30 00:15:08.857565255 +0000 UTC m=+29.041813526" observedRunningTime="2025-10-30 00:15:09.193415052 +0000 UTC m=+29.377663336" watchObservedRunningTime="2025-10-30 00:15:09.193571392 +0000 UTC m=+29.377819676"
Oct 30 00:15:09.891270 kubelet[1931]: E1030 00:15:09.891186    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:15:10.892090 kubelet[1931]: E1030 00:15:10.892012    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:15:11.893348 kubelet[1931]: E1030 00:15:11.893284    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:15:12.893566 kubelet[1931]: E1030 00:15:12.893480    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:15:13.894427 kubelet[1931]: E1030 00:15:13.894334    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:15:14.894586 kubelet[1931]: E1030 00:15:14.894513    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:15:15.895785 kubelet[1931]: E1030 00:15:15.895705    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:15:16.896618 kubelet[1931]: E1030 00:15:16.896536    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:15:17.897308 kubelet[1931]: E1030 00:15:17.897220    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:15:18.897901 kubelet[1931]: E1030 00:15:18.897820    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:15:19.067367 systemd[1]: Created slice kubepods-besteffort-podf5a8c0e7_da99_4278_bf7e_a2e0f4cb3b0f.slice - libcontainer container kubepods-besteffort-podf5a8c0e7_da99_4278_bf7e_a2e0f4cb3b0f.slice.
Oct 30 00:15:19.100801 kubelet[1931]: I1030 00:15:19.100654    1931 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-f9rzm\" (UniqueName: \"kubernetes.io/projected/f5a8c0e7-da99-4278-bf7e-a2e0f4cb3b0f-kube-api-access-f9rzm\") pod \"test-pod-1\" (UID: \"f5a8c0e7-da99-4278-bf7e-a2e0f4cb3b0f\") " pod="default/test-pod-1"
Oct 30 00:15:19.100801 kubelet[1931]: I1030 00:15:19.100752    1931 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-8c625737-2c83-4f37-b1a5-61e8c4d99d0c\" (UniqueName: \"kubernetes.io/nfs/f5a8c0e7-da99-4278-bf7e-a2e0f4cb3b0f-pvc-8c625737-2c83-4f37-b1a5-61e8c4d99d0c\") pod \"test-pod-1\" (UID: \"f5a8c0e7-da99-4278-bf7e-a2e0f4cb3b0f\") " pod="default/test-pod-1"
Oct 30 00:15:19.258757 kernel: netfs: FS-Cache loaded
Oct 30 00:15:19.353832 kernel: RPC: Registered named UNIX socket transport module.
Oct 30 00:15:19.354034 kernel: RPC: Registered udp transport module.
Oct 30 00:15:19.354101 kernel: RPC: Registered tcp transport module.
Oct 30 00:15:19.358675 kernel: RPC: Registered tcp-with-tls transport module.
Oct 30 00:15:19.364319 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module.
Oct 30 00:15:19.676831 kernel: NFS: Registering the id_resolver key type
Oct 30 00:15:19.677017 kernel: Key type id_resolver registered
Oct 30 00:15:19.677065 kernel: Key type id_legacy registered
Oct 30 00:15:19.725182 nfsidmap[2980]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'c.flatcar-212911.internal'
Oct 30 00:15:19.737049 nfsidmap[2981]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'c.flatcar-212911.internal'
Oct 30 00:15:19.757848 nfsrahead[2983]: setting /var/lib/kubelet/pods/f5a8c0e7-da99-4278-bf7e-a2e0f4cb3b0f/volumes/kubernetes.io~nfs/pvc-8c625737-2c83-4f37-b1a5-61e8c4d99d0c readahead to 128
Oct 30 00:15:19.898187 kubelet[1931]: E1030 00:15:19.898074    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:15:19.973751 containerd[1499]: time="2025-10-30T00:15:19.973670289Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:f5a8c0e7-da99-4278-bf7e-a2e0f4cb3b0f,Namespace:default,Attempt:0,}"
Oct 30 00:15:19.997302 systemd-networkd[1434]: veth785de974: Link UP
Oct 30 00:15:20.013021 kernel: cni0: port 3(veth785de974) entered blocking state
Oct 30 00:15:20.013126 kernel: cni0: port 3(veth785de974) entered disabled state
Oct 30 00:15:20.019214 kernel: veth785de974: entered allmulticast mode
Oct 30 00:15:20.024722 kernel: veth785de974: entered promiscuous mode
Oct 30 00:15:20.044276 kernel: cni0: port 3(veth785de974) entered blocking state
Oct 30 00:15:20.044401 kernel: cni0: port 3(veth785de974) entered forwarding state
Oct 30 00:15:20.043799 systemd-networkd[1434]: veth785de974: Gained carrier
Oct 30 00:15:20.046901 containerd[1499]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc00009a8e8), "name":"cbr0", "type":"bridge"}
Oct 30 00:15:20.046901 containerd[1499]: delegateAdd: netconf sent to delegate plugin:
Oct 30 00:15:20.091896 containerd[1499]: {"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":1410,"name":"cbr0","type":"bridge"}time="2025-10-30T00:15:20.091776222Z" level=info msg="connecting to shim c4baa49b4863ec00ceac9eca506950b710bc68c1d3303a5584d0f03d0c59818e" address="unix:///run/containerd/s/5972cd5b79f3bd990a75f0893a58c24da627351086027fe5ab1255fbc266f2c4" namespace=k8s.io protocol=ttrpc version=3
Oct 30 00:15:20.128026 systemd[1]: Started cri-containerd-c4baa49b4863ec00ceac9eca506950b710bc68c1d3303a5584d0f03d0c59818e.scope - libcontainer container c4baa49b4863ec00ceac9eca506950b710bc68c1d3303a5584d0f03d0c59818e.
Oct 30 00:15:20.195008 containerd[1499]: time="2025-10-30T00:15:20.194942245Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:f5a8c0e7-da99-4278-bf7e-a2e0f4cb3b0f,Namespace:default,Attempt:0,} returns sandbox id \"c4baa49b4863ec00ceac9eca506950b710bc68c1d3303a5584d0f03d0c59818e\""
Oct 30 00:15:20.196521 containerd[1499]: time="2025-10-30T00:15:20.196480515Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\""
Oct 30 00:15:20.401811 containerd[1499]: time="2025-10-30T00:15:20.401433217Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}"
Oct 30 00:15:20.402893 containerd[1499]: time="2025-10-30T00:15:20.402839631Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61"
Oct 30 00:15:20.408640 containerd[1499]: time="2025-10-30T00:15:20.408579490Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:8d14817f00613fe76ef7459f977ad93e7b71a3948346b7ac4d50e35f3dd518e9\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\", size \"73311824\" in 211.993848ms"
Oct 30 00:15:20.408892 containerd[1499]: time="2025-10-30T00:15:20.408645342Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:8d14817f00613fe76ef7459f977ad93e7b71a3948346b7ac4d50e35f3dd518e9\""
Oct 30 00:15:20.411899 containerd[1499]: time="2025-10-30T00:15:20.411849980Z" level=info msg="CreateContainer within sandbox \"c4baa49b4863ec00ceac9eca506950b710bc68c1d3303a5584d0f03d0c59818e\" for container &ContainerMetadata{Name:test,Attempt:0,}"
Oct 30 00:15:20.424714 containerd[1499]: time="2025-10-30T00:15:20.422119713Z" level=info msg="Container a975f95ae8c956a42b3cd07d0a6449385e203724eab5bea397cbecbeeb298a2f: CDI devices from CRI Config.CDIDevices: []"
Oct 30 00:15:20.438272 containerd[1499]: time="2025-10-30T00:15:20.438198326Z" level=info msg="CreateContainer within sandbox \"c4baa49b4863ec00ceac9eca506950b710bc68c1d3303a5584d0f03d0c59818e\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"a975f95ae8c956a42b3cd07d0a6449385e203724eab5bea397cbecbeeb298a2f\""
Oct 30 00:15:20.439132 containerd[1499]: time="2025-10-30T00:15:20.439074080Z" level=info msg="StartContainer for \"a975f95ae8c956a42b3cd07d0a6449385e203724eab5bea397cbecbeeb298a2f\""
Oct 30 00:15:20.440990 containerd[1499]: time="2025-10-30T00:15:20.440939915Z" level=info msg="connecting to shim a975f95ae8c956a42b3cd07d0a6449385e203724eab5bea397cbecbeeb298a2f" address="unix:///run/containerd/s/5972cd5b79f3bd990a75f0893a58c24da627351086027fe5ab1255fbc266f2c4" protocol=ttrpc version=3
Oct 30 00:15:20.478989 systemd[1]: Started cri-containerd-a975f95ae8c956a42b3cd07d0a6449385e203724eab5bea397cbecbeeb298a2f.scope - libcontainer container a975f95ae8c956a42b3cd07d0a6449385e203724eab5bea397cbecbeeb298a2f.
Oct 30 00:15:20.526322 containerd[1499]: time="2025-10-30T00:15:20.526174531Z" level=info msg="StartContainer for \"a975f95ae8c956a42b3cd07d0a6449385e203724eab5bea397cbecbeeb298a2f\" returns successfully"
Oct 30 00:15:20.870008 kubelet[1931]: E1030 00:15:20.869939    1931 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:15:20.899276 kubelet[1931]: E1030 00:15:20.899213    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:15:21.223236 kubelet[1931]: I1030 00:15:21.223145    1931 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=18.00925894 podStartE2EDuration="18.223119412s" podCreationTimestamp="2025-10-30 00:15:03 +0000 UTC" firstStartedPulling="2025-10-30 00:15:20.196102187 +0000 UTC m=+40.380350456" lastFinishedPulling="2025-10-30 00:15:20.409962664 +0000 UTC m=+40.594210928" observedRunningTime="2025-10-30 00:15:21.222826753 +0000 UTC m=+41.407075039" watchObservedRunningTime="2025-10-30 00:15:21.223119412 +0000 UTC m=+41.407367700"
Oct 30 00:15:21.750989 systemd-networkd[1434]: veth785de974: Gained IPv6LL
Oct 30 00:15:21.899813 kubelet[1931]: E1030 00:15:21.899748    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:15:22.900654 kubelet[1931]: E1030 00:15:22.900566    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:15:23.901933 kubelet[1931]: E1030 00:15:23.901829    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:15:23.973124 ntpd[1472]: Listen normally on 12 veth785de974 [fe80::2c9a:4bff:fe9f:3135%7]:123
Oct 30 00:15:23.973628 ntpd[1472]: 30 Oct 00:15:23 ntpd[1472]: Listen normally on 12 veth785de974 [fe80::2c9a:4bff:fe9f:3135%7]:123
Oct 30 00:15:24.902652 kubelet[1931]: E1030 00:15:24.902564    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:15:25.903204 kubelet[1931]: E1030 00:15:25.903111    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:15:26.903331 kubelet[1931]: E1030 00:15:26.903263    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:15:27.903933 kubelet[1931]: E1030 00:15:27.903833    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"
Oct 30 00:15:28.904556 kubelet[1931]: E1030 00:15:28.904480    1931 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"