May 8 00:07:28.086486 kernel: Linux version 6.6.88-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.43 p3) 2.43.1) #1 SMP PREEMPT_DYNAMIC Wed May 7 22:19:27 -00 2025 May 8 00:07:28.086533 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=90f0413c3d850985bb1e645e67699e9890362068cb417837636fe4022f4be979 May 8 00:07:28.086553 kernel: BIOS-provided physical RAM map: May 8 00:07:28.086567 kernel: BIOS-e820: [mem 0x0000000000000000-0x0000000000000fff] reserved May 8 00:07:28.086589 kernel: BIOS-e820: [mem 0x0000000000001000-0x0000000000054fff] usable May 8 00:07:28.086603 kernel: BIOS-e820: [mem 0x0000000000055000-0x000000000005ffff] reserved May 8 00:07:28.086621 kernel: BIOS-e820: [mem 0x0000000000060000-0x0000000000097fff] usable May 8 00:07:28.086636 kernel: BIOS-e820: [mem 0x0000000000098000-0x000000000009ffff] reserved May 8 00:07:28.086655 kernel: BIOS-e820: [mem 0x0000000000100000-0x00000000bd326fff] usable May 8 00:07:28.086670 kernel: BIOS-e820: [mem 0x00000000bd327000-0x00000000bd32efff] ACPI data May 8 00:07:28.086685 kernel: BIOS-e820: [mem 0x00000000bd32f000-0x00000000bf8ecfff] usable May 8 00:07:28.086700 kernel: BIOS-e820: [mem 0x00000000bf8ed000-0x00000000bfb6cfff] reserved May 8 00:07:28.086715 kernel: BIOS-e820: [mem 0x00000000bfb6d000-0x00000000bfb7efff] ACPI data May 8 00:07:28.086730 kernel: BIOS-e820: [mem 0x00000000bfb7f000-0x00000000bfbfefff] ACPI NVS May 8 00:07:28.086753 kernel: BIOS-e820: [mem 0x00000000bfbff000-0x00000000bffdffff] usable May 8 00:07:28.086790 kernel: BIOS-e820: [mem 0x00000000bffe0000-0x00000000bfffffff] reserved May 8 00:07:28.086805 kernel: BIOS-e820: [mem 0x0000000100000000-0x000000021fffffff] usable May 8 00:07:28.086818 kernel: NX (Execute Disable) protection: active May 8 00:07:28.086833 kernel: APIC: Static calls initialized May 8 00:07:28.086848 kernel: efi: EFI v2.7 by EDK II May 8 00:07:28.086862 kernel: efi: TPMFinalLog=0xbfbf7000 ACPI=0xbfb7e000 ACPI 2.0=0xbfb7e014 SMBIOS=0xbf9e8000 RNG=0xbfb73018 TPMEventLog=0xbd327018 May 8 00:07:28.086877 kernel: random: crng init done May 8 00:07:28.086893 kernel: secureboot: Secure boot disabled May 8 00:07:28.086908 kernel: SMBIOS 2.4 present. May 8 00:07:28.086929 kernel: DMI: Google Google Compute Engine/Google Compute Engine, BIOS Google 02/12/2025 May 8 00:07:28.086944 kernel: Hypervisor detected: KVM May 8 00:07:28.086960 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 May 8 00:07:28.086975 kernel: kvm-clock: using sched offset of 13369930470 cycles May 8 00:07:28.086992 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns May 8 00:07:28.087007 kernel: tsc: Detected 2299.998 MHz processor May 8 00:07:28.087023 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved May 8 00:07:28.087040 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable May 8 00:07:28.087056 kernel: last_pfn = 0x220000 max_arch_pfn = 0x400000000 May 8 00:07:28.087072 kernel: MTRR map: 3 entries (2 fixed + 1 variable; max 18), built from 8 variable MTRRs May 8 00:07:28.087092 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT May 8 00:07:28.087107 kernel: last_pfn = 0xbffe0 max_arch_pfn = 0x400000000 May 8 00:07:28.087123 kernel: Using GB pages for direct mapping May 8 00:07:28.087139 kernel: ACPI: Early table checksum verification disabled May 8 00:07:28.087154 kernel: ACPI: RSDP 0x00000000BFB7E014 000024 (v02 Google) May 8 00:07:28.087171 kernel: ACPI: XSDT 0x00000000BFB7D0E8 00005C (v01 Google GOOGFACP 00000001 01000013) May 8 00:07:28.087194 kernel: ACPI: FACP 0x00000000BFB78000 0000F4 (v02 Google GOOGFACP 00000001 GOOG 00000001) May 8 00:07:28.087215 kernel: ACPI: DSDT 0x00000000BFB79000 001A64 (v01 Google GOOGDSDT 00000001 GOOG 00000001) May 8 00:07:28.087232 kernel: ACPI: FACS 0x00000000BFBF2000 000040 May 8 00:07:28.087250 kernel: ACPI: SSDT 0x00000000BFB7C000 000316 (v02 GOOGLE Tpm2Tabl 00001000 INTL 20240322) May 8 00:07:28.087268 kernel: ACPI: TPM2 0x00000000BFB7B000 000034 (v04 GOOGLE 00000001 GOOG 00000001) May 8 00:07:28.087285 kernel: ACPI: SRAT 0x00000000BFB77000 0000C8 (v03 Google GOOGSRAT 00000001 GOOG 00000001) May 8 00:07:28.087301 kernel: ACPI: APIC 0x00000000BFB76000 000076 (v05 Google GOOGAPIC 00000001 GOOG 00000001) May 8 00:07:28.087317 kernel: ACPI: SSDT 0x00000000BFB75000 000980 (v01 Google GOOGSSDT 00000001 GOOG 00000001) May 8 00:07:28.087338 kernel: ACPI: WAET 0x00000000BFB74000 000028 (v01 Google GOOGWAET 00000001 GOOG 00000001) May 8 00:07:28.087355 kernel: ACPI: Reserving FACP table memory at [mem 0xbfb78000-0xbfb780f3] May 8 00:07:28.087372 kernel: ACPI: Reserving DSDT table memory at [mem 0xbfb79000-0xbfb7aa63] May 8 00:07:28.087390 kernel: ACPI: Reserving FACS table memory at [mem 0xbfbf2000-0xbfbf203f] May 8 00:07:28.087406 kernel: ACPI: Reserving SSDT table memory at [mem 0xbfb7c000-0xbfb7c315] May 8 00:07:28.087423 kernel: ACPI: Reserving TPM2 table memory at [mem 0xbfb7b000-0xbfb7b033] May 8 00:07:28.087440 kernel: ACPI: Reserving SRAT table memory at [mem 0xbfb77000-0xbfb770c7] May 8 00:07:28.087457 kernel: ACPI: Reserving APIC table memory at [mem 0xbfb76000-0xbfb76075] May 8 00:07:28.087474 kernel: ACPI: Reserving SSDT table memory at [mem 0xbfb75000-0xbfb7597f] May 8 00:07:28.087496 kernel: ACPI: Reserving WAET table memory at [mem 0xbfb74000-0xbfb74027] May 8 00:07:28.087513 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0 May 8 00:07:28.087530 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0 May 8 00:07:28.087547 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x0009ffff] May 8 00:07:28.087563 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00100000-0xbfffffff] May 8 00:07:28.087588 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0x21fffffff] May 8 00:07:28.087605 kernel: NUMA: Node 0 [mem 0x00000000-0x0009ffff] + [mem 0x00100000-0xbfffffff] -> [mem 0x00000000-0xbfffffff] May 8 00:07:28.087623 kernel: NUMA: Node 0 [mem 0x00000000-0xbfffffff] + [mem 0x100000000-0x21fffffff] -> [mem 0x00000000-0x21fffffff] May 8 00:07:28.087640 kernel: NODE_DATA(0) allocated [mem 0x21fffa000-0x21fffffff] May 8 00:07:28.087662 kernel: Zone ranges: May 8 00:07:28.087679 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] May 8 00:07:28.087695 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] May 8 00:07:28.087712 kernel: Normal [mem 0x0000000100000000-0x000000021fffffff] May 8 00:07:28.087729 kernel: Movable zone start for each node May 8 00:07:28.087745 kernel: Early memory node ranges May 8 00:07:28.087784 kernel: node 0: [mem 0x0000000000001000-0x0000000000054fff] May 8 00:07:28.087801 kernel: node 0: [mem 0x0000000000060000-0x0000000000097fff] May 8 00:07:28.087817 kernel: node 0: [mem 0x0000000000100000-0x00000000bd326fff] May 8 00:07:28.087839 kernel: node 0: [mem 0x00000000bd32f000-0x00000000bf8ecfff] May 8 00:07:28.087856 kernel: node 0: [mem 0x00000000bfbff000-0x00000000bffdffff] May 8 00:07:28.087873 kernel: node 0: [mem 0x0000000100000000-0x000000021fffffff] May 8 00:07:28.087890 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000021fffffff] May 8 00:07:28.087907 kernel: On node 0, zone DMA: 1 pages in unavailable ranges May 8 00:07:28.087924 kernel: On node 0, zone DMA: 11 pages in unavailable ranges May 8 00:07:28.087941 kernel: On node 0, zone DMA: 104 pages in unavailable ranges May 8 00:07:28.087958 kernel: On node 0, zone DMA32: 8 pages in unavailable ranges May 8 00:07:28.087975 kernel: On node 0, zone DMA32: 786 pages in unavailable ranges May 8 00:07:28.087996 kernel: On node 0, zone Normal: 32 pages in unavailable ranges May 8 00:07:28.088013 kernel: ACPI: PM-Timer IO Port: 0xb008 May 8 00:07:28.088029 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) May 8 00:07:28.088047 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 May 8 00:07:28.088063 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) May 8 00:07:28.088079 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) May 8 00:07:28.088095 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) May 8 00:07:28.088112 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) May 8 00:07:28.088130 kernel: ACPI: Using ACPI (MADT) for SMP configuration information May 8 00:07:28.088151 kernel: smpboot: Allowing 2 CPUs, 0 hotplug CPUs May 8 00:07:28.088169 kernel: [mem 0xc0000000-0xffffffff] available for PCI devices May 8 00:07:28.088187 kernel: Booting paravirtualized kernel on KVM May 8 00:07:28.088205 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns May 8 00:07:28.088223 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 May 8 00:07:28.088240 kernel: percpu: Embedded 58 pages/cpu s197096 r8192 d32280 u1048576 May 8 00:07:28.088258 kernel: pcpu-alloc: s197096 r8192 d32280 u1048576 alloc=1*2097152 May 8 00:07:28.088275 kernel: pcpu-alloc: [0] 0 1 May 8 00:07:28.088292 kernel: kvm-guest: PV spinlocks enabled May 8 00:07:28.088314 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) May 8 00:07:28.088335 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=90f0413c3d850985bb1e645e67699e9890362068cb417837636fe4022f4be979 May 8 00:07:28.088352 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. May 8 00:07:28.088370 kernel: Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear) May 8 00:07:28.088388 kernel: Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) May 8 00:07:28.088406 kernel: Fallback order for Node 0: 0 May 8 00:07:28.088424 kernel: Built 1 zonelists, mobility grouping on. Total pages: 1932272 May 8 00:07:28.088442 kernel: Policy zone: Normal May 8 00:07:28.088463 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off May 8 00:07:28.088481 kernel: software IO TLB: area num 2. May 8 00:07:28.088500 kernel: Memory: 7511320K/7860552K available (14336K kernel code, 2295K rwdata, 22864K rodata, 43484K init, 1592K bss, 348976K reserved, 0K cma-reserved) May 8 00:07:28.088518 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 May 8 00:07:28.088536 kernel: Kernel/User page tables isolation: enabled May 8 00:07:28.088553 kernel: ftrace: allocating 37918 entries in 149 pages May 8 00:07:28.088571 kernel: ftrace: allocated 149 pages with 4 groups May 8 00:07:28.088598 kernel: Dynamic Preempt: voluntary May 8 00:07:28.088634 kernel: rcu: Preemptible hierarchical RCU implementation. May 8 00:07:28.088654 kernel: rcu: RCU event tracing is enabled. May 8 00:07:28.088672 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. May 8 00:07:28.088692 kernel: Trampoline variant of Tasks RCU enabled. May 8 00:07:28.088716 kernel: Rude variant of Tasks RCU enabled. May 8 00:07:28.088735 kernel: Tracing variant of Tasks RCU enabled. May 8 00:07:28.088754 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. May 8 00:07:28.088791 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 May 8 00:07:28.088809 kernel: NR_IRQS: 33024, nr_irqs: 440, preallocated irqs: 16 May 8 00:07:28.088829 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. May 8 00:07:28.088847 kernel: Console: colour dummy device 80x25 May 8 00:07:28.088862 kernel: printk: console [ttyS0] enabled May 8 00:07:28.088878 kernel: ACPI: Core revision 20230628 May 8 00:07:28.088895 kernel: APIC: Switch to symmetric I/O mode setup May 8 00:07:28.088910 kernel: x2apic enabled May 8 00:07:28.088926 kernel: APIC: Switched APIC routing to: physical x2apic May 8 00:07:28.088943 kernel: ..TIMER: vector=0x30 apic1=0 pin1=0 apic2=-1 pin2=-1 May 8 00:07:28.088960 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x212733415c7, max_idle_ns: 440795236380 ns May 8 00:07:28.088983 kernel: Calibrating delay loop (skipped) preset value.. 4599.99 BogoMIPS (lpj=2299998) May 8 00:07:28.089002 kernel: Last level iTLB entries: 4KB 1024, 2MB 1024, 4MB 1024 May 8 00:07:28.089019 kernel: Last level dTLB entries: 4KB 1024, 2MB 1024, 4MB 1024, 1GB 4 May 8 00:07:28.089036 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization May 8 00:07:28.089054 kernel: Spectre V2 : Spectre BHI mitigation: SW BHB clearing on vm exit May 8 00:07:28.089072 kernel: Spectre V2 : Spectre BHI mitigation: SW BHB clearing on syscall May 8 00:07:28.089090 kernel: Spectre V2 : Mitigation: IBRS May 8 00:07:28.089107 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch May 8 00:07:28.089124 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT May 8 00:07:28.089158 kernel: RETBleed: Mitigation: IBRS May 8 00:07:28.089176 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier May 8 00:07:28.089194 kernel: Spectre V2 : User space: Mitigation: STIBP via prctl May 8 00:07:28.089213 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl May 8 00:07:28.089232 kernel: MDS: Mitigation: Clear CPU buffers May 8 00:07:28.089250 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode May 8 00:07:28.089267 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' May 8 00:07:28.089292 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' May 8 00:07:28.089310 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' May 8 00:07:28.089332 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 May 8 00:07:28.089350 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. May 8 00:07:28.089369 kernel: Freeing SMP alternatives memory: 32K May 8 00:07:28.089387 kernel: pid_max: default: 32768 minimum: 301 May 8 00:07:28.089404 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity May 8 00:07:28.089422 kernel: landlock: Up and running. May 8 00:07:28.089441 kernel: SELinux: Initializing. May 8 00:07:28.089458 kernel: Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, linear) May 8 00:07:28.089475 kernel: Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, linear) May 8 00:07:28.089497 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU @ 2.30GHz (family: 0x6, model: 0x3f, stepping: 0x0) May 8 00:07:28.089513 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. May 8 00:07:28.089530 kernel: RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. May 8 00:07:28.089549 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. May 8 00:07:28.089566 kernel: Performance Events: unsupported p6 CPU model 63 no PMU driver, software events only. May 8 00:07:28.089636 kernel: signal: max sigframe size: 1776 May 8 00:07:28.089656 kernel: rcu: Hierarchical SRCU implementation. May 8 00:07:28.089676 kernel: rcu: Max phase no-delay instances is 400. May 8 00:07:28.089696 kernel: NMI watchdog: Perf NMI watchdog permanently disabled May 8 00:07:28.089720 kernel: smp: Bringing up secondary CPUs ... May 8 00:07:28.089739 kernel: smpboot: x86: Booting SMP configuration: May 8 00:07:28.089758 kernel: .... node #0, CPUs: #1 May 8 00:07:28.089796 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. May 8 00:07:28.089817 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. May 8 00:07:28.089834 kernel: smp: Brought up 1 node, 2 CPUs May 8 00:07:28.089853 kernel: smpboot: Max logical packages: 1 May 8 00:07:28.089873 kernel: smpboot: Total of 2 processors activated (9199.99 BogoMIPS) May 8 00:07:28.089897 kernel: devtmpfs: initialized May 8 00:07:28.089916 kernel: x86/mm: Memory block size: 128MB May 8 00:07:28.089935 kernel: ACPI: PM: Registering ACPI NVS region [mem 0xbfb7f000-0xbfbfefff] (524288 bytes) May 8 00:07:28.089955 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns May 8 00:07:28.089974 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) May 8 00:07:28.089994 kernel: pinctrl core: initialized pinctrl subsystem May 8 00:07:28.090013 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family May 8 00:07:28.090031 kernel: audit: initializing netlink subsys (disabled) May 8 00:07:28.090050 kernel: audit: type=2000 audit(1746662846.535:1): state=initialized audit_enabled=0 res=1 May 8 00:07:28.090073 kernel: thermal_sys: Registered thermal governor 'step_wise' May 8 00:07:28.090092 kernel: thermal_sys: Registered thermal governor 'user_space' May 8 00:07:28.090111 kernel: cpuidle: using governor menu May 8 00:07:28.090130 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 May 8 00:07:28.090150 kernel: dca service started, version 1.12.1 May 8 00:07:28.090169 kernel: PCI: Using configuration type 1 for base access May 8 00:07:28.090189 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. May 8 00:07:28.090207 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages May 8 00:07:28.090226 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page May 8 00:07:28.090249 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages May 8 00:07:28.090268 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page May 8 00:07:28.090287 kernel: ACPI: Added _OSI(Module Device) May 8 00:07:28.090307 kernel: ACPI: Added _OSI(Processor Device) May 8 00:07:28.090326 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) May 8 00:07:28.090344 kernel: ACPI: Added _OSI(Processor Aggregator Device) May 8 00:07:28.090364 kernel: ACPI: 3 ACPI AML tables successfully acquired and loaded May 8 00:07:28.090383 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC May 8 00:07:28.090402 kernel: ACPI: Interpreter enabled May 8 00:07:28.090425 kernel: ACPI: PM: (supports S0 S3 S5) May 8 00:07:28.090444 kernel: ACPI: Using IOAPIC for interrupt routing May 8 00:07:28.090462 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug May 8 00:07:28.090482 kernel: PCI: Ignoring E820 reservations for host bridge windows May 8 00:07:28.090505 kernel: ACPI: Enabled 16 GPEs in block 00 to 0F May 8 00:07:28.090524 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) May 8 00:07:28.090818 kernel: acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] May 8 00:07:28.091027 kernel: acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] May 8 00:07:28.091252 kernel: acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended configuration space under this bridge May 8 00:07:28.091279 kernel: PCI host bridge to bus 0000:00 May 8 00:07:28.091470 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] May 8 00:07:28.091651 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] May 8 00:07:28.091834 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] May 8 00:07:28.092001 kernel: pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfefff window] May 8 00:07:28.092164 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] May 8 00:07:28.092371 kernel: pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 May 8 00:07:28.092585 kernel: pci 0000:00:01.0: [8086:7110] type 00 class 0x060100 May 8 00:07:28.092790 kernel: pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 May 8 00:07:28.092978 kernel: pci 0000:00:01.3: quirk: [io 0xb000-0xb03f] claimed by PIIX4 ACPI May 8 00:07:28.093172 kernel: pci 0000:00:03.0: [1af4:1004] type 00 class 0x000000 May 8 00:07:28.093356 kernel: pci 0000:00:03.0: reg 0x10: [io 0xc040-0xc07f] May 8 00:07:28.093546 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xc0001000-0xc000107f] May 8 00:07:28.093747 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 May 8 00:07:28.093948 kernel: pci 0000:00:04.0: reg 0x10: [io 0xc000-0xc03f] May 8 00:07:28.094131 kernel: pci 0000:00:04.0: reg 0x14: [mem 0xc0000000-0xc000007f] May 8 00:07:28.094320 kernel: pci 0000:00:05.0: [1af4:1005] type 00 class 0x00ff00 May 8 00:07:28.094503 kernel: pci 0000:00:05.0: reg 0x10: [io 0xc080-0xc09f] May 8 00:07:28.094699 kernel: pci 0000:00:05.0: reg 0x14: [mem 0xc0002000-0xc000203f] May 8 00:07:28.094722 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 May 8 00:07:28.094741 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 May 8 00:07:28.094771 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 May 8 00:07:28.094790 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 May 8 00:07:28.094808 kernel: ACPI: PCI: Interrupt link LNKS configured for IRQ 9 May 8 00:07:28.094826 kernel: iommu: Default domain type: Translated May 8 00:07:28.094845 kernel: iommu: DMA domain TLB invalidation policy: lazy mode May 8 00:07:28.094864 kernel: efivars: Registered efivars operations May 8 00:07:28.094887 kernel: PCI: Using ACPI for IRQ routing May 8 00:07:28.094905 kernel: PCI: pci_cache_line_size set to 64 bytes May 8 00:07:28.094923 kernel: e820: reserve RAM buffer [mem 0x00055000-0x0005ffff] May 8 00:07:28.094941 kernel: e820: reserve RAM buffer [mem 0x00098000-0x0009ffff] May 8 00:07:28.094959 kernel: e820: reserve RAM buffer [mem 0xbd327000-0xbfffffff] May 8 00:07:28.094977 kernel: e820: reserve RAM buffer [mem 0xbf8ed000-0xbfffffff] May 8 00:07:28.094995 kernel: e820: reserve RAM buffer [mem 0xbffe0000-0xbfffffff] May 8 00:07:28.095013 kernel: vgaarb: loaded May 8 00:07:28.095031 kernel: clocksource: Switched to clocksource kvm-clock May 8 00:07:28.095053 kernel: VFS: Disk quotas dquot_6.6.0 May 8 00:07:28.095072 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) May 8 00:07:28.095089 kernel: pnp: PnP ACPI init May 8 00:07:28.095108 kernel: pnp: PnP ACPI: found 7 devices May 8 00:07:28.095126 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns May 8 00:07:28.095144 kernel: NET: Registered PF_INET protocol family May 8 00:07:28.095163 kernel: IP idents hash table entries: 131072 (order: 8, 1048576 bytes, linear) May 8 00:07:28.095181 kernel: tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, linear) May 8 00:07:28.095199 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) May 8 00:07:28.095222 kernel: TCP established hash table entries: 65536 (order: 7, 524288 bytes, linear) May 8 00:07:28.095240 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) May 8 00:07:28.095259 kernel: TCP: Hash tables configured (established 65536 bind 65536) May 8 00:07:28.095277 kernel: UDP hash table entries: 4096 (order: 5, 131072 bytes, linear) May 8 00:07:28.095295 kernel: UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, linear) May 8 00:07:28.095314 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family May 8 00:07:28.095332 kernel: NET: Registered PF_XDP protocol family May 8 00:07:28.095502 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] May 8 00:07:28.095680 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] May 8 00:07:28.095864 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] May 8 00:07:28.096029 kernel: pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfefff window] May 8 00:07:28.096216 kernel: pci 0000:00:00.0: Limiting direct PCI/PCI transfers May 8 00:07:28.096238 kernel: PCI: CLS 0 bytes, default 64 May 8 00:07:28.096255 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) May 8 00:07:28.096274 kernel: software IO TLB: mapped [mem 0x00000000b7f7f000-0x00000000bbf7f000] (64MB) May 8 00:07:28.096292 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer May 8 00:07:28.096318 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x212733415c7, max_idle_ns: 440795236380 ns May 8 00:07:28.096336 kernel: clocksource: Switched to clocksource tsc May 8 00:07:28.096353 kernel: Initialise system trusted keyrings May 8 00:07:28.096371 kernel: workingset: timestamp_bits=39 max_order=21 bucket_order=0 May 8 00:07:28.096389 kernel: Key type asymmetric registered May 8 00:07:28.096407 kernel: Asymmetric key parser 'x509' registered May 8 00:07:28.096425 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) May 8 00:07:28.096442 kernel: io scheduler mq-deadline registered May 8 00:07:28.096460 kernel: io scheduler kyber registered May 8 00:07:28.096481 kernel: io scheduler bfq registered May 8 00:07:28.096499 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 May 8 00:07:28.096518 kernel: ACPI: \_SB_.LNKC: Enabled at IRQ 11 May 8 00:07:28.096716 kernel: virtio-pci 0000:00:03.0: virtio_pci: leaving for legacy driver May 8 00:07:28.096739 kernel: ACPI: \_SB_.LNKD: Enabled at IRQ 10 May 8 00:07:28.096937 kernel: virtio-pci 0000:00:04.0: virtio_pci: leaving for legacy driver May 8 00:07:28.096959 kernel: ACPI: \_SB_.LNKA: Enabled at IRQ 10 May 8 00:07:28.097153 kernel: virtio-pci 0000:00:05.0: virtio_pci: leaving for legacy driver May 8 00:07:28.097177 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled May 8 00:07:28.097200 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A May 8 00:07:28.097219 kernel: 00:04: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A May 8 00:07:28.097237 kernel: 00:05: ttyS2 at I/O 0x3e8 (irq = 6, base_baud = 115200) is a 16550A May 8 00:07:28.097255 kernel: 00:06: ttyS3 at I/O 0x2e8 (irq = 7, base_baud = 115200) is a 16550A May 8 00:07:28.097465 kernel: tpm_tis MSFT0101:00: 2.0 TPM (device-id 0x9009, rev-id 0) May 8 00:07:28.097492 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 May 8 00:07:28.097512 kernel: i8042: Warning: Keylock active May 8 00:07:28.097531 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 May 8 00:07:28.097554 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 May 8 00:07:28.097778 kernel: rtc_cmos 00:00: RTC can wake from S4 May 8 00:07:28.097975 kernel: rtc_cmos 00:00: registered as rtc0 May 8 00:07:28.098164 kernel: rtc_cmos 00:00: setting system clock to 2025-05-08T00:07:27 UTC (1746662847) May 8 00:07:28.098342 kernel: rtc_cmos 00:00: alarms up to one day, 114 bytes nvram May 8 00:07:28.098365 kernel: intel_pstate: CPU model not supported May 8 00:07:28.098384 kernel: pstore: Using crash dump compression: deflate May 8 00:07:28.098402 kernel: pstore: Registered efi_pstore as persistent store backend May 8 00:07:28.098426 kernel: NET: Registered PF_INET6 protocol family May 8 00:07:28.098444 kernel: Segment Routing with IPv6 May 8 00:07:28.098461 kernel: In-situ OAM (IOAM) with IPv6 May 8 00:07:28.098479 kernel: NET: Registered PF_PACKET protocol family May 8 00:07:28.098497 kernel: Key type dns_resolver registered May 8 00:07:28.098515 kernel: IPI shorthand broadcast: enabled May 8 00:07:28.098533 kernel: sched_clock: Marking stable (848004378, 144520092)->(1008655733, -16131263) May 8 00:07:28.098551 kernel: registered taskstats version 1 May 8 00:07:28.098569 kernel: Loading compiled-in X.509 certificates May 8 00:07:28.098601 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.88-flatcar: dac8423f6f9fa2fb5f636925d45d7c2572b3a9b6' May 8 00:07:28.098619 kernel: Key type .fscrypt registered May 8 00:07:28.098637 kernel: Key type fscrypt-provisioning registered May 8 00:07:28.098655 kernel: ima: Allocated hash algorithm: sha1 May 8 00:07:28.098673 kernel: ima: No architecture policies found May 8 00:07:28.098691 kernel: clk: Disabling unused clocks May 8 00:07:28.098709 kernel: Freeing unused kernel image (initmem) memory: 43484K May 8 00:07:28.098727 kernel: Write protecting the kernel read-only data: 38912k May 8 00:07:28.098750 kernel: Freeing unused kernel image (rodata/data gap) memory: 1712K May 8 00:07:28.098794 kernel: Run /init as init process May 8 00:07:28.098812 kernel: with arguments: May 8 00:07:28.098828 kernel: /init May 8 00:07:28.098844 kernel: with environment: May 8 00:07:28.098861 kernel: HOME=/ May 8 00:07:28.098879 kernel: TERM=linux May 8 00:07:28.098897 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a May 8 00:07:28.098915 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 May 8 00:07:28.098940 systemd[1]: Successfully made /usr/ read-only. May 8 00:07:28.098963 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) May 8 00:07:28.098983 systemd[1]: Detected virtualization google. May 8 00:07:28.099002 systemd[1]: Detected architecture x86-64. May 8 00:07:28.099020 systemd[1]: Running in initrd. May 8 00:07:28.099039 systemd[1]: No hostname configured, using default hostname. May 8 00:07:28.099058 systemd[1]: Hostname set to . May 8 00:07:28.099079 systemd[1]: Initializing machine ID from random generator. May 8 00:07:28.099097 systemd[1]: Queued start job for default target initrd.target. May 8 00:07:28.099116 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 8 00:07:28.099134 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 8 00:07:28.099154 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... May 8 00:07:28.099174 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 8 00:07:28.099194 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... May 8 00:07:28.099220 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... May 8 00:07:28.099257 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... May 8 00:07:28.099287 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... May 8 00:07:28.099307 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 8 00:07:28.099327 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 8 00:07:28.099347 systemd[1]: Reached target paths.target - Path Units. May 8 00:07:28.099371 systemd[1]: Reached target slices.target - Slice Units. May 8 00:07:28.099390 systemd[1]: Reached target swap.target - Swaps. May 8 00:07:28.099410 systemd[1]: Reached target timers.target - Timer Units. May 8 00:07:28.099429 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. May 8 00:07:28.099449 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 8 00:07:28.099469 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). May 8 00:07:28.099490 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. May 8 00:07:28.099510 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 8 00:07:28.099535 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 8 00:07:28.099555 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 8 00:07:28.099580 systemd[1]: Reached target sockets.target - Socket Units. May 8 00:07:28.099600 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... May 8 00:07:28.099624 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 8 00:07:28.099644 systemd[1]: Finished network-cleanup.service - Network Cleanup. May 8 00:07:28.099664 systemd[1]: Starting systemd-fsck-usr.service... May 8 00:07:28.099684 systemd[1]: Starting systemd-journald.service - Journal Service... May 8 00:07:28.099704 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 8 00:07:28.099727 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 8 00:07:28.099902 systemd-journald[184]: Collecting audit messages is disabled. May 8 00:07:28.099948 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. May 8 00:07:28.099967 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 8 00:07:28.099992 systemd-journald[184]: Journal started May 8 00:07:28.100031 systemd-journald[184]: Runtime Journal (/run/log/journal/36dde0c1d6594aaca64e77af07af48dc) is 8M, max 148.6M, 140.6M free. May 8 00:07:28.102638 systemd-modules-load[185]: Inserted module 'overlay' May 8 00:07:28.107913 systemd[1]: Finished systemd-fsck-usr.service. May 8 00:07:28.113935 systemd[1]: Started systemd-journald.service - Journal Service. May 8 00:07:28.126569 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 8 00:07:28.130957 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 8 00:07:28.139047 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 8 00:07:28.153926 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 8 00:07:28.156274 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. May 8 00:07:28.157126 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 8 00:07:28.165790 kernel: Bridge firewalling registered May 8 00:07:28.164850 systemd-modules-load[185]: Inserted module 'br_netfilter' May 8 00:07:28.170952 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 8 00:07:28.171595 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 8 00:07:28.184565 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 8 00:07:28.186615 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 8 00:07:28.195172 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 8 00:07:28.204183 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 8 00:07:28.208153 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 8 00:07:28.217067 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... May 8 00:07:28.226962 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 8 00:07:28.248961 dracut-cmdline[218]: dracut-dracut-053 May 8 00:07:28.253906 dracut-cmdline[218]: Using kernel command line parameters: rd.driver.pre=btrfs rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200n8 flatcar.first_boot=detected flatcar.oem.id=gce verity.usrhash=90f0413c3d850985bb1e645e67699e9890362068cb417837636fe4022f4be979 May 8 00:07:28.284533 systemd-resolved[219]: Positive Trust Anchors: May 8 00:07:28.284937 systemd-resolved[219]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 8 00:07:28.285016 systemd-resolved[219]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test May 8 00:07:28.289970 systemd-resolved[219]: Defaulting to hostname 'linux'. May 8 00:07:28.291735 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 8 00:07:28.316021 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 8 00:07:28.360798 kernel: SCSI subsystem initialized May 8 00:07:28.371810 kernel: Loading iSCSI transport class v2.0-870. May 8 00:07:28.383798 kernel: iscsi: registered transport (tcp) May 8 00:07:28.406832 kernel: iscsi: registered transport (qla4xxx) May 8 00:07:28.406913 kernel: QLogic iSCSI HBA Driver May 8 00:07:28.459023 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. May 8 00:07:28.467039 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... May 8 00:07:28.494861 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. May 8 00:07:28.494932 kernel: device-mapper: uevent: version 1.0.3 May 8 00:07:28.494961 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com May 8 00:07:28.539811 kernel: raid6: avx2x4 gen() 18221 MB/s May 8 00:07:28.556801 kernel: raid6: avx2x2 gen() 18180 MB/s May 8 00:07:28.574228 kernel: raid6: avx2x1 gen() 14220 MB/s May 8 00:07:28.574273 kernel: raid6: using algorithm avx2x4 gen() 18221 MB/s May 8 00:07:28.592251 kernel: raid6: .... xor() 7074 MB/s, rmw enabled May 8 00:07:28.592316 kernel: raid6: using avx2x2 recovery algorithm May 8 00:07:28.614800 kernel: xor: automatically using best checksumming function avx May 8 00:07:28.777805 kernel: Btrfs loaded, zoned=no, fsverity=no May 8 00:07:28.791464 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. May 8 00:07:28.798054 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 8 00:07:28.837618 systemd-udevd[402]: Using default interface naming scheme 'v255'. May 8 00:07:28.846079 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 8 00:07:28.876986 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... May 8 00:07:28.892635 dracut-pre-trigger[414]: rd.md=0: removing MD RAID activation May 8 00:07:28.929855 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. May 8 00:07:28.954002 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 8 00:07:29.063101 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 8 00:07:29.083005 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... May 8 00:07:29.137535 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. May 8 00:07:29.160517 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. May 8 00:07:29.179912 kernel: cryptd: max_cpu_qlen set to 1000 May 8 00:07:29.192811 kernel: scsi host0: Virtio SCSI HBA May 8 00:07:29.196920 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 8 00:07:29.222380 kernel: AVX2 version of gcm_enc/dec engaged. May 8 00:07:29.222480 kernel: AES CTR mode by8 optimization enabled May 8 00:07:29.227921 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 8 00:07:29.247010 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... May 8 00:07:29.286916 kernel: scsi 0:0:1:0: Direct-Access Google PersistentDisk 1 PQ: 0 ANSI: 6 May 8 00:07:29.305702 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 8 00:07:29.306097 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 8 00:07:29.339143 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 8 00:07:29.378936 kernel: sd 0:0:1:0: [sda] 25165824 512-byte logical blocks: (12.9 GB/12.0 GiB) May 8 00:07:29.423850 kernel: sd 0:0:1:0: [sda] 4096-byte physical blocks May 8 00:07:29.424129 kernel: sd 0:0:1:0: [sda] Write Protect is off May 8 00:07:29.424373 kernel: sd 0:0:1:0: [sda] Mode Sense: 1f 00 00 08 May 8 00:07:29.424710 kernel: sd 0:0:1:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA May 8 00:07:29.424980 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. May 8 00:07:29.425021 kernel: GPT:17805311 != 25165823 May 8 00:07:29.425045 kernel: GPT:Alternate GPT header not at the end of the disk. May 8 00:07:29.425068 kernel: GPT:17805311 != 25165823 May 8 00:07:29.425089 kernel: GPT: Use GNU Parted to correct GPT errors. May 8 00:07:29.425109 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 May 8 00:07:29.425131 kernel: sd 0:0:1:0: [sda] Attached SCSI disk May 8 00:07:29.425887 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 8 00:07:29.426164 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 8 00:07:29.444105 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... May 8 00:07:29.459221 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 8 00:07:29.488790 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/sda6 scanned by (udev-worker) (448) May 8 00:07:29.501904 kernel: BTRFS: device fsid 1c9931ea-0995-4065-8a57-32743027822a devid 1 transid 42 /dev/sda3 scanned by (udev-worker) (460) May 8 00:07:29.513544 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. May 8 00:07:29.534780 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 8 00:07:29.566316 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - PersistentDisk EFI-SYSTEM. May 8 00:07:29.607587 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - PersistentDisk ROOT. May 8 00:07:29.638852 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - PersistentDisk OEM. May 8 00:07:29.649911 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - PersistentDisk USR-A. May 8 00:07:29.658098 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - PersistentDisk USR-A. May 8 00:07:29.685988 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... May 8 00:07:29.720788 disk-uuid[542]: Primary Header is updated. May 8 00:07:29.720788 disk-uuid[542]: Secondary Entries is updated. May 8 00:07:29.720788 disk-uuid[542]: Secondary Header is updated. May 8 00:07:29.766444 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 May 8 00:07:29.766487 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 May 8 00:07:29.729014 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 8 00:07:29.816706 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 8 00:07:30.780819 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 May 8 00:07:30.781244 disk-uuid[543]: The operation has completed successfully. May 8 00:07:30.862980 systemd[1]: disk-uuid.service: Deactivated successfully. May 8 00:07:30.863145 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. May 8 00:07:30.922985 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... May 8 00:07:30.943218 sh[566]: Success May 8 00:07:30.955902 kernel: device-mapper: verity: sha256 using implementation "sha256-avx2" May 8 00:07:31.038685 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. May 8 00:07:31.045500 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... May 8 00:07:31.073260 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. May 8 00:07:31.119844 kernel: BTRFS info (device dm-0): first mount of filesystem 1c9931ea-0995-4065-8a57-32743027822a May 8 00:07:31.119887 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm May 8 00:07:31.119910 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead May 8 00:07:31.119938 kernel: BTRFS info (device dm-0): disabling log replay at mount time May 8 00:07:31.132203 kernel: BTRFS info (device dm-0): using free space tree May 8 00:07:31.159809 kernel: BTRFS info (device dm-0): enabling ssd optimizations May 8 00:07:31.167634 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. May 8 00:07:31.176600 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. May 8 00:07:31.182942 systemd[1]: Starting ignition-setup.service - Ignition (setup)... May 8 00:07:31.252660 kernel: BTRFS info (device sda6): first mount of filesystem 13774eeb-24b8-4f6d-a245-c0facb6e43f9 May 8 00:07:31.252705 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm May 8 00:07:31.252733 kernel: BTRFS info (device sda6): using free space tree May 8 00:07:31.252791 kernel: BTRFS info (device sda6): enabling ssd optimizations May 8 00:07:31.252818 kernel: BTRFS info (device sda6): auto enabling async discard May 8 00:07:31.232068 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... May 8 00:07:31.274934 kernel: BTRFS info (device sda6): last unmount of filesystem 13774eeb-24b8-4f6d-a245-c0facb6e43f9 May 8 00:07:31.287536 systemd[1]: Finished ignition-setup.service - Ignition (setup). May 8 00:07:31.303091 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... May 8 00:07:31.364830 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 8 00:07:31.397098 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 8 00:07:31.468345 systemd-networkd[746]: lo: Link UP May 8 00:07:31.468358 systemd-networkd[746]: lo: Gained carrier May 8 00:07:31.472898 systemd-networkd[746]: Enumeration completed May 8 00:07:31.473171 systemd[1]: Started systemd-networkd.service - Network Configuration. May 8 00:07:31.473704 systemd-networkd[746]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 8 00:07:31.473712 systemd-networkd[746]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 8 00:07:31.512731 ignition[684]: Ignition 2.20.0 May 8 00:07:31.475996 systemd-networkd[746]: eth0: Link UP May 8 00:07:31.512741 ignition[684]: Stage: fetch-offline May 8 00:07:31.476003 systemd-networkd[746]: eth0: Gained carrier May 8 00:07:31.512816 ignition[684]: no configs at "/usr/lib/ignition/base.d" May 8 00:07:31.476015 systemd-networkd[746]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 8 00:07:31.512831 ignition[684]: no config dir at "/usr/lib/ignition/base.platform.d/gcp" May 8 00:07:31.488923 systemd-networkd[746]: eth0: Overlong DHCP hostname received, shortened from 'ci-4230-1-1-nightly-20250507-2100-43d8d852515e52776b3d.c.flatcar-212911.internal' to 'ci-4230-1-1-nightly-20250507-2100-43d8d852515e52776b3d' May 8 00:07:31.512961 ignition[684]: parsed url from cmdline: "" May 8 00:07:31.488939 systemd-networkd[746]: eth0: DHCPv4 address 10.128.0.69/32, gateway 10.128.0.1 acquired from 169.254.169.254 May 8 00:07:31.512968 ignition[684]: no config URL provided May 8 00:07:31.517340 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). May 8 00:07:31.512977 ignition[684]: reading system config file "/usr/lib/ignition/user.ign" May 8 00:07:31.535813 systemd[1]: Reached target network.target - Network. May 8 00:07:31.512989 ignition[684]: no config at "/usr/lib/ignition/user.ign" May 8 00:07:31.570010 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... May 8 00:07:31.512997 ignition[684]: failed to fetch config: resource requires networking May 8 00:07:31.610142 unknown[756]: fetched base config from "system" May 8 00:07:31.513319 ignition[684]: Ignition finished successfully May 8 00:07:31.610156 unknown[756]: fetched base config from "system" May 8 00:07:31.602347 ignition[756]: Ignition 2.20.0 May 8 00:07:31.610166 unknown[756]: fetched user config from "gcp" May 8 00:07:31.602355 ignition[756]: Stage: fetch May 8 00:07:31.612937 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). May 8 00:07:31.602571 ignition[756]: no configs at "/usr/lib/ignition/base.d" May 8 00:07:31.643982 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... May 8 00:07:31.602583 ignition[756]: no config dir at "/usr/lib/ignition/base.platform.d/gcp" May 8 00:07:31.678187 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). May 8 00:07:31.602711 ignition[756]: parsed url from cmdline: "" May 8 00:07:31.705988 systemd[1]: Starting ignition-disks.service - Ignition (disks)... May 8 00:07:31.602716 ignition[756]: no config URL provided May 8 00:07:31.740714 systemd[1]: Finished ignition-disks.service - Ignition (disks). May 8 00:07:31.602725 ignition[756]: reading system config file "/usr/lib/ignition/user.ign" May 8 00:07:31.744194 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. May 8 00:07:31.602738 ignition[756]: no config at "/usr/lib/ignition/user.ign" May 8 00:07:31.770992 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. May 8 00:07:31.602789 ignition[756]: GET http://169.254.169.254/computeMetadata/v1/instance/attributes/user-data: attempt #1 May 8 00:07:31.781074 systemd[1]: Reached target local-fs.target - Local File Systems. May 8 00:07:31.605920 ignition[756]: GET result: OK May 8 00:07:31.808997 systemd[1]: Reached target sysinit.target - System Initialization. May 8 00:07:31.605989 ignition[756]: parsing config with SHA512: f4cac3ef04dccdfc4d341cda5e33b5a76d2ac3cedbbc2ef61d04339576cba447749fa70d1ca28ed914f4297857703fedd3a80b0ee08d999bd39d55048e5c64da May 8 00:07:31.825996 systemd[1]: Reached target basic.target - Basic System. May 8 00:07:31.610522 ignition[756]: fetch: fetch complete May 8 00:07:31.840038 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... May 8 00:07:31.610529 ignition[756]: fetch: fetch passed May 8 00:07:31.610580 ignition[756]: Ignition finished successfully May 8 00:07:31.665804 ignition[762]: Ignition 2.20.0 May 8 00:07:31.665815 ignition[762]: Stage: kargs May 8 00:07:31.666014 ignition[762]: no configs at "/usr/lib/ignition/base.d" May 8 00:07:31.666025 ignition[762]: no config dir at "/usr/lib/ignition/base.platform.d/gcp" May 8 00:07:31.666736 ignition[762]: kargs: kargs passed May 8 00:07:31.666816 ignition[762]: Ignition finished successfully May 8 00:07:31.738089 ignition[767]: Ignition 2.20.0 May 8 00:07:31.738102 ignition[767]: Stage: disks May 8 00:07:31.738366 ignition[767]: no configs at "/usr/lib/ignition/base.d" May 8 00:07:31.738384 ignition[767]: no config dir at "/usr/lib/ignition/base.platform.d/gcp" May 8 00:07:31.739588 ignition[767]: disks: disks passed May 8 00:07:31.739665 ignition[767]: Ignition finished successfully May 8 00:07:31.893323 systemd-fsck[776]: ROOT: clean, 14/1628000 files, 120691/1617920 blocks May 8 00:07:32.091743 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. May 8 00:07:32.096899 systemd[1]: Mounting sysroot.mount - /sysroot... May 8 00:07:32.240892 kernel: EXT4-fs (sda9): mounted filesystem 369e2962-701e-4244-8c1c-27f8fa83bc64 r/w with ordered data mode. Quota mode: none. May 8 00:07:32.242016 systemd[1]: Mounted sysroot.mount - /sysroot. May 8 00:07:32.242853 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. May 8 00:07:32.273900 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 8 00:07:32.278805 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... May 8 00:07:32.308526 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. May 8 00:07:32.364986 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/sda6 scanned by mount (784) May 8 00:07:32.365044 kernel: BTRFS info (device sda6): first mount of filesystem 13774eeb-24b8-4f6d-a245-c0facb6e43f9 May 8 00:07:32.365070 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm May 8 00:07:32.365092 kernel: BTRFS info (device sda6): using free space tree May 8 00:07:32.308631 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). May 8 00:07:32.404048 kernel: BTRFS info (device sda6): enabling ssd optimizations May 8 00:07:32.404085 kernel: BTRFS info (device sda6): auto enabling async discard May 8 00:07:32.308677 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. May 8 00:07:32.328383 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. May 8 00:07:32.387621 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 8 00:07:32.417992 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... May 8 00:07:32.552385 initrd-setup-root[808]: cut: /sysroot/etc/passwd: No such file or directory May 8 00:07:32.563368 initrd-setup-root[815]: cut: /sysroot/etc/group: No such file or directory May 8 00:07:32.573902 initrd-setup-root[822]: cut: /sysroot/etc/shadow: No such file or directory May 8 00:07:32.583902 initrd-setup-root[829]: cut: /sysroot/etc/gshadow: No such file or directory May 8 00:07:32.629927 systemd-networkd[746]: eth0: Gained IPv6LL May 8 00:07:32.725383 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. May 8 00:07:32.730915 systemd[1]: Starting ignition-mount.service - Ignition (mount)... May 8 00:07:32.758572 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... May 8 00:07:32.780909 kernel: BTRFS info (device sda6): last unmount of filesystem 13774eeb-24b8-4f6d-a245-c0facb6e43f9 May 8 00:07:32.792007 systemd[1]: sysroot-oem.mount: Deactivated successfully. May 8 00:07:32.827550 ignition[896]: INFO : Ignition 2.20.0 May 8 00:07:32.827550 ignition[896]: INFO : Stage: mount May 8 00:07:32.849891 ignition[896]: INFO : no configs at "/usr/lib/ignition/base.d" May 8 00:07:32.849891 ignition[896]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/gcp" May 8 00:07:32.849891 ignition[896]: INFO : mount: mount passed May 8 00:07:32.849891 ignition[896]: INFO : Ignition finished successfully May 8 00:07:32.830405 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. May 8 00:07:32.836289 systemd[1]: Finished ignition-mount.service - Ignition (mount). May 8 00:07:32.856905 systemd[1]: Starting ignition-files.service - Ignition (files)... May 8 00:07:33.255005 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 8 00:07:33.288223 kernel: BTRFS: device label OEM devid 1 transid 17 /dev/sda6 scanned by mount (908) May 8 00:07:33.288275 kernel: BTRFS info (device sda6): first mount of filesystem 13774eeb-24b8-4f6d-a245-c0facb6e43f9 May 8 00:07:33.288301 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm May 8 00:07:33.301650 kernel: BTRFS info (device sda6): using free space tree May 8 00:07:33.317594 kernel: BTRFS info (device sda6): enabling ssd optimizations May 8 00:07:33.317661 kernel: BTRFS info (device sda6): auto enabling async discard May 8 00:07:33.320837 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 8 00:07:33.358380 ignition[925]: INFO : Ignition 2.20.0 May 8 00:07:33.358380 ignition[925]: INFO : Stage: files May 8 00:07:33.373885 ignition[925]: INFO : no configs at "/usr/lib/ignition/base.d" May 8 00:07:33.373885 ignition[925]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/gcp" May 8 00:07:33.373885 ignition[925]: DEBUG : files: compiled without relabeling support, skipping May 8 00:07:33.373885 ignition[925]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" May 8 00:07:33.373885 ignition[925]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" May 8 00:07:33.373885 ignition[925]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" May 8 00:07:33.373885 ignition[925]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" May 8 00:07:33.373885 ignition[925]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" May 8 00:07:33.373885 ignition[925]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" May 8 00:07:33.373885 ignition[925]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" May 8 00:07:33.373885 ignition[925]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" May 8 00:07:33.373885 ignition[925]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" May 8 00:07:33.373885 ignition[925]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.0-x86-64.raw" May 8 00:07:33.373885 ignition[925]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.0-x86-64.raw" May 8 00:07:33.373885 ignition[925]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.0-x86-64.raw" May 8 00:07:33.373885 ignition[925]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.32.0-x86-64.raw: attempt #1 May 8 00:07:33.366173 unknown[925]: wrote ssh authorized keys file for user: core May 8 00:07:33.707541 ignition[925]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK May 8 00:07:34.085041 ignition[925]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.0-x86-64.raw" May 8 00:07:34.102945 ignition[925]: INFO : files: createResultFile: createFiles: op(7): [started] writing file "/sysroot/etc/.ignition-result.json" May 8 00:07:34.102945 ignition[925]: INFO : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json" May 8 00:07:34.102945 ignition[925]: INFO : files: files passed May 8 00:07:34.102945 ignition[925]: INFO : Ignition finished successfully May 8 00:07:34.086937 systemd[1]: Finished ignition-files.service - Ignition (files). May 8 00:07:34.110969 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... May 8 00:07:34.144971 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... May 8 00:07:34.166450 systemd[1]: ignition-quench.service: Deactivated successfully. May 8 00:07:34.246899 initrd-setup-root-after-ignition[952]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 8 00:07:34.246899 initrd-setup-root-after-ignition[952]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory May 8 00:07:34.166598 systemd[1]: Finished ignition-quench.service - Ignition (record completion). May 8 00:07:34.292968 initrd-setup-root-after-ignition[956]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 8 00:07:34.197137 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. May 8 00:07:34.217512 systemd[1]: Reached target ignition-complete.target - Ignition Complete. May 8 00:07:34.244935 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... May 8 00:07:34.310045 systemd[1]: initrd-parse-etc.service: Deactivated successfully. May 8 00:07:34.310164 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. May 8 00:07:34.329753 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. May 8 00:07:34.349036 systemd[1]: Reached target initrd.target - Initrd Default Target. May 8 00:07:34.370113 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. May 8 00:07:34.376940 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... May 8 00:07:34.422116 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 8 00:07:34.450970 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... May 8 00:07:34.487787 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. May 8 00:07:34.499122 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. May 8 00:07:34.509211 systemd[1]: Stopped target timers.target - Timer Units. May 8 00:07:34.528227 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. May 8 00:07:34.528421 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 8 00:07:34.561201 systemd[1]: Stopped target initrd.target - Initrd Default Target. May 8 00:07:34.572207 systemd[1]: Stopped target basic.target - Basic System. May 8 00:07:34.589177 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. May 8 00:07:34.604168 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. May 8 00:07:34.622167 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. May 8 00:07:34.641182 systemd[1]: Stopped target remote-fs.target - Remote File Systems. May 8 00:07:34.659186 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. May 8 00:07:34.676207 systemd[1]: Stopped target sysinit.target - System Initialization. May 8 00:07:34.697193 systemd[1]: Stopped target local-fs.target - Local File Systems. May 8 00:07:34.714184 systemd[1]: Stopped target swap.target - Swaps. May 8 00:07:34.731128 systemd[1]: dracut-pre-mount.service: Deactivated successfully. May 8 00:07:34.731336 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. May 8 00:07:34.771949 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. May 8 00:07:34.772306 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 8 00:07:34.790112 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. May 8 00:07:34.790284 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 8 00:07:34.809130 systemd[1]: dracut-initqueue.service: Deactivated successfully. May 8 00:07:34.809335 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. May 8 00:07:34.849276 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. May 8 00:07:34.849518 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. May 8 00:07:34.858400 systemd[1]: ignition-files.service: Deactivated successfully. May 8 00:07:34.858597 systemd[1]: Stopped ignition-files.service - Ignition (files). May 8 00:07:34.927923 ignition[977]: INFO : Ignition 2.20.0 May 8 00:07:34.927923 ignition[977]: INFO : Stage: umount May 8 00:07:34.927923 ignition[977]: INFO : no configs at "/usr/lib/ignition/base.d" May 8 00:07:34.927923 ignition[977]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/gcp" May 8 00:07:34.927923 ignition[977]: INFO : umount: umount passed May 8 00:07:34.927923 ignition[977]: INFO : Ignition finished successfully May 8 00:07:34.884067 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... May 8 00:07:34.942023 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... May 8 00:07:34.968043 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. May 8 00:07:34.968290 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. May 8 00:07:34.997322 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. May 8 00:07:34.997526 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. May 8 00:07:35.029826 systemd[1]: sysroot-boot.mount: Deactivated successfully. May 8 00:07:35.031180 systemd[1]: ignition-mount.service: Deactivated successfully. May 8 00:07:35.031292 systemd[1]: Stopped ignition-mount.service - Ignition (mount). May 8 00:07:35.045499 systemd[1]: sysroot-boot.service: Deactivated successfully. May 8 00:07:35.045608 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. May 8 00:07:35.068299 systemd[1]: initrd-cleanup.service: Deactivated successfully. May 8 00:07:35.068426 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. May 8 00:07:35.089123 systemd[1]: ignition-disks.service: Deactivated successfully. May 8 00:07:35.089188 systemd[1]: Stopped ignition-disks.service - Ignition (disks). May 8 00:07:35.107054 systemd[1]: ignition-kargs.service: Deactivated successfully. May 8 00:07:35.107128 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). May 8 00:07:35.117088 systemd[1]: ignition-fetch.service: Deactivated successfully. May 8 00:07:35.117150 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). May 8 00:07:35.132117 systemd[1]: Stopped target network.target - Network. May 8 00:07:35.149059 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. May 8 00:07:35.149132 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). May 8 00:07:35.164124 systemd[1]: Stopped target paths.target - Path Units. May 8 00:07:35.182080 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. May 8 00:07:35.185855 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 8 00:07:35.207875 systemd[1]: Stopped target slices.target - Slice Units. May 8 00:07:35.216044 systemd[1]: Stopped target sockets.target - Socket Units. May 8 00:07:35.234135 systemd[1]: iscsid.socket: Deactivated successfully. May 8 00:07:35.234197 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. May 8 00:07:35.249115 systemd[1]: iscsiuio.socket: Deactivated successfully. May 8 00:07:35.249175 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 8 00:07:35.266104 systemd[1]: ignition-setup.service: Deactivated successfully. May 8 00:07:35.266183 systemd[1]: Stopped ignition-setup.service - Ignition (setup). May 8 00:07:35.283202 systemd[1]: ignition-setup-pre.service: Deactivated successfully. May 8 00:07:35.283271 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. May 8 00:07:35.300148 systemd[1]: initrd-setup-root.service: Deactivated successfully. May 8 00:07:35.300226 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. May 8 00:07:35.317319 systemd[1]: Stopping systemd-networkd.service - Network Configuration... May 8 00:07:35.344031 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... May 8 00:07:35.362337 systemd[1]: systemd-resolved.service: Deactivated successfully. May 8 00:07:35.362480 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. May 8 00:07:35.385255 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. May 8 00:07:35.385512 systemd[1]: systemd-networkd.service: Deactivated successfully. May 8 00:07:35.385635 systemd[1]: Stopped systemd-networkd.service - Network Configuration. May 8 00:07:35.393545 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. May 8 00:07:35.395039 systemd[1]: systemd-networkd.socket: Deactivated successfully. May 8 00:07:35.395098 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. May 8 00:07:35.415871 systemd[1]: Stopping network-cleanup.service - Network Cleanup... May 8 00:07:35.448857 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. May 8 00:07:35.448966 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 8 00:07:35.468003 systemd[1]: systemd-sysctl.service: Deactivated successfully. May 8 00:07:35.468092 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. May 8 00:07:35.486185 systemd[1]: systemd-modules-load.service: Deactivated successfully. May 8 00:07:35.486252 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. May 8 00:07:35.503940 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. May 8 00:07:35.504027 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. May 8 00:07:35.523129 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... May 8 00:07:35.543283 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. May 8 00:07:35.543475 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. May 8 00:07:35.965890 systemd-journald[184]: Received SIGTERM from PID 1 (systemd). May 8 00:07:35.543981 systemd[1]: systemd-udevd.service: Deactivated successfully. May 8 00:07:35.544146 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. May 8 00:07:35.575972 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. May 8 00:07:35.576104 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. May 8 00:07:35.589089 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. May 8 00:07:35.589137 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. May 8 00:07:35.607093 systemd[1]: dracut-pre-udev.service: Deactivated successfully. May 8 00:07:35.607156 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. May 8 00:07:35.644137 systemd[1]: dracut-cmdline.service: Deactivated successfully. May 8 00:07:35.644213 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. May 8 00:07:35.672176 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 8 00:07:35.672267 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 8 00:07:35.706048 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... May 8 00:07:35.737897 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. May 8 00:07:35.738104 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 8 00:07:35.760256 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. May 8 00:07:35.760337 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 8 00:07:35.768158 systemd[1]: kmod-static-nodes.service: Deactivated successfully. May 8 00:07:35.768225 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. May 8 00:07:35.787186 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 8 00:07:35.787258 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 8 00:07:35.806431 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. May 8 00:07:35.806524 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. May 8 00:07:35.807048 systemd[1]: network-cleanup.service: Deactivated successfully. May 8 00:07:35.807179 systemd[1]: Stopped network-cleanup.service - Network Cleanup. May 8 00:07:35.823458 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. May 8 00:07:35.823563 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. May 8 00:07:35.844276 systemd[1]: Reached target initrd-switch-root.target - Switch Root. May 8 00:07:35.866934 systemd[1]: Starting initrd-switch-root.service - Switch Root... May 8 00:07:35.911277 systemd[1]: Switching root. May 8 00:07:36.267880 systemd-journald[184]: Journal stopped May 8 00:07:38.769928 kernel: SELinux: policy capability network_peer_controls=1 May 8 00:07:38.770117 kernel: SELinux: policy capability open_perms=1 May 8 00:07:38.770142 kernel: SELinux: policy capability extended_socket_class=1 May 8 00:07:38.770160 kernel: SELinux: policy capability always_check_network=0 May 8 00:07:38.770179 kernel: SELinux: policy capability cgroup_seclabel=1 May 8 00:07:38.770198 kernel: SELinux: policy capability nnp_nosuid_transition=1 May 8 00:07:38.770218 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 May 8 00:07:38.770238 kernel: SELinux: policy capability ioctl_skip_cloexec=0 May 8 00:07:38.770261 kernel: audit: type=1403 audit(1746662856.463:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 May 8 00:07:38.770284 systemd[1]: Successfully loaded SELinux policy in 89.934ms. May 8 00:07:38.770306 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 12.612ms. May 8 00:07:38.770327 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) May 8 00:07:38.770347 systemd[1]: Detected virtualization google. May 8 00:07:38.770368 systemd[1]: Detected architecture x86-64. May 8 00:07:38.770394 systemd[1]: Detected first boot. May 8 00:07:38.770417 systemd[1]: Initializing machine ID from random generator. May 8 00:07:38.770439 zram_generator::config[1021]: No configuration found. May 8 00:07:38.770462 kernel: Guest personality initialized and is inactive May 8 00:07:38.770482 kernel: VMCI host device registered (name=vmci, major=10, minor=125) May 8 00:07:38.770507 kernel: Initialized host personality May 8 00:07:38.770528 kernel: NET: Registered PF_VSOCK protocol family May 8 00:07:38.770549 systemd[1]: Populated /etc with preset unit settings. May 8 00:07:38.770572 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. May 8 00:07:38.770594 systemd[1]: initrd-switch-root.service: Deactivated successfully. May 8 00:07:38.770621 systemd[1]: Stopped initrd-switch-root.service - Switch Root. May 8 00:07:38.770643 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. May 8 00:07:38.770665 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. May 8 00:07:38.770687 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. May 8 00:07:38.770715 systemd[1]: Created slice system-getty.slice - Slice /system/getty. May 8 00:07:38.770737 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. May 8 00:07:38.770781 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. May 8 00:07:38.770806 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. May 8 00:07:38.770830 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. May 8 00:07:38.770861 systemd[1]: Created slice user.slice - User and Session Slice. May 8 00:07:38.770883 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 8 00:07:38.770910 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 8 00:07:38.770931 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. May 8 00:07:38.770954 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. May 8 00:07:38.770976 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. May 8 00:07:38.770999 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 8 00:07:38.771029 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... May 8 00:07:38.771051 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 8 00:07:38.771074 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. May 8 00:07:38.771103 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. May 8 00:07:38.771126 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. May 8 00:07:38.771148 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. May 8 00:07:38.771171 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 8 00:07:38.771194 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 8 00:07:38.771216 systemd[1]: Reached target slices.target - Slice Units. May 8 00:07:38.771239 systemd[1]: Reached target swap.target - Swaps. May 8 00:07:38.771262 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. May 8 00:07:38.771288 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. May 8 00:07:38.771309 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. May 8 00:07:38.771330 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 8 00:07:38.771478 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 8 00:07:38.771512 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 8 00:07:38.771534 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. May 8 00:07:38.771555 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... May 8 00:07:38.771577 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... May 8 00:07:38.771618 systemd[1]: Mounting media.mount - External Media Directory... May 8 00:07:38.771641 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 8 00:07:38.771664 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... May 8 00:07:38.771686 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... May 8 00:07:38.771714 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... May 8 00:07:38.771741 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). May 8 00:07:38.771786 systemd[1]: Reached target machines.target - Containers. May 8 00:07:38.771808 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... May 8 00:07:38.771830 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 8 00:07:38.771862 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 8 00:07:38.771884 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... May 8 00:07:38.771906 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 8 00:07:38.771929 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 8 00:07:38.771956 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 8 00:07:38.771978 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... May 8 00:07:38.772000 kernel: ACPI: bus type drm_connector registered May 8 00:07:38.772020 kernel: fuse: init (API version 7.39) May 8 00:07:38.772041 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 8 00:07:38.772065 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). May 8 00:07:38.772087 kernel: loop: module loaded May 8 00:07:38.772113 systemd[1]: systemd-fsck-root.service: Deactivated successfully. May 8 00:07:38.772136 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. May 8 00:07:38.772158 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. May 8 00:07:38.772180 systemd[1]: Stopped systemd-fsck-usr.service. May 8 00:07:38.772203 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). May 8 00:07:38.772226 systemd[1]: Starting systemd-journald.service - Journal Service... May 8 00:07:38.772247 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 8 00:07:38.772273 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 8 00:07:38.772303 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... May 8 00:07:38.772325 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... May 8 00:07:38.772384 systemd-journald[1109]: Collecting audit messages is disabled. May 8 00:07:38.772430 systemd-journald[1109]: Journal started May 8 00:07:38.772476 systemd-journald[1109]: Runtime Journal (/run/log/journal/41f0341059964728873f81b17b2378af) is 8M, max 148.6M, 140.6M free. May 8 00:07:37.468396 systemd[1]: Queued start job for default target multi-user.target. May 8 00:07:37.482361 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. May 8 00:07:37.482998 systemd[1]: systemd-journald.service: Deactivated successfully. May 8 00:07:38.793809 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 8 00:07:38.811824 systemd[1]: verity-setup.service: Deactivated successfully. May 8 00:07:38.817798 systemd[1]: Stopped verity-setup.service. May 8 00:07:38.843787 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 8 00:07:38.854821 systemd[1]: Started systemd-journald.service - Journal Service. May 8 00:07:38.865371 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. May 8 00:07:38.875154 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. May 8 00:07:38.885156 systemd[1]: Mounted media.mount - External Media Directory. May 8 00:07:38.895090 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. May 8 00:07:38.905124 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. May 8 00:07:38.915101 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. May 8 00:07:38.925245 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. May 8 00:07:38.937266 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 8 00:07:38.949282 systemd[1]: modprobe@configfs.service: Deactivated successfully. May 8 00:07:38.949551 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. May 8 00:07:38.961245 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 8 00:07:38.961500 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 8 00:07:38.973252 systemd[1]: modprobe@drm.service: Deactivated successfully. May 8 00:07:38.973520 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 8 00:07:38.983246 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 8 00:07:38.983507 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 8 00:07:38.995258 systemd[1]: modprobe@fuse.service: Deactivated successfully. May 8 00:07:38.995514 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. May 8 00:07:39.005227 systemd[1]: modprobe@loop.service: Deactivated successfully. May 8 00:07:39.005482 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 8 00:07:39.015270 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 8 00:07:39.025305 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 8 00:07:39.037334 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. May 8 00:07:39.049252 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. May 8 00:07:39.061198 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 8 00:07:39.083877 systemd[1]: Reached target network-pre.target - Preparation for Network. May 8 00:07:39.099901 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... May 8 00:07:39.122928 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... May 8 00:07:39.132950 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). May 8 00:07:39.133182 systemd[1]: Reached target local-fs.target - Local File Systems. May 8 00:07:39.144317 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. May 8 00:07:39.161983 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... May 8 00:07:39.183986 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... May 8 00:07:39.194036 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 8 00:07:39.202964 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... May 8 00:07:39.221325 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... May 8 00:07:39.233193 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 8 00:07:39.233914 systemd-journald[1109]: Time spent on flushing to /var/log/journal/41f0341059964728873f81b17b2378af is 138.921ms for 928 entries. May 8 00:07:39.233914 systemd-journald[1109]: System Journal (/var/log/journal/41f0341059964728873f81b17b2378af) is 8M, max 584.8M, 576.8M free. May 8 00:07:39.415177 systemd-journald[1109]: Received client request to flush runtime journal. May 8 00:07:39.415268 kernel: loop0: detected capacity change from 0 to 138176 May 8 00:07:39.247013 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... May 8 00:07:39.255813 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 8 00:07:39.268263 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 8 00:07:39.285968 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... May 8 00:07:39.307983 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 8 00:07:39.327812 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... May 8 00:07:39.356281 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. May 8 00:07:39.368083 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. May 8 00:07:39.379289 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. May 8 00:07:39.391441 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. May 8 00:07:39.403339 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 8 00:07:39.421851 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. May 8 00:07:39.433112 systemd-tmpfiles[1147]: ACLs are not supported, ignoring. May 8 00:07:39.433146 systemd-tmpfiles[1147]: ACLs are not supported, ignoring. May 8 00:07:39.436479 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher May 8 00:07:39.446395 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 8 00:07:39.461073 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. May 8 00:07:39.473796 kernel: loop1: detected capacity change from 0 to 147912 May 8 00:07:39.488990 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... May 8 00:07:39.509127 systemd[1]: Starting systemd-sysusers.service - Create System Users... May 8 00:07:39.521011 udevadm[1149]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. May 8 00:07:39.529987 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. May 8 00:07:39.534157 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. May 8 00:07:39.556176 kernel: loop2: detected capacity change from 0 to 218376 May 8 00:07:39.644993 systemd[1]: Finished systemd-sysusers.service - Create System Users. May 8 00:07:39.667277 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 8 00:07:39.684068 kernel: loop3: detected capacity change from 0 to 52152 May 8 00:07:39.714631 systemd-tmpfiles[1169]: ACLs are not supported, ignoring. May 8 00:07:39.714676 systemd-tmpfiles[1169]: ACLs are not supported, ignoring. May 8 00:07:39.724871 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 8 00:07:39.762829 kernel: loop4: detected capacity change from 0 to 138176 May 8 00:07:39.830443 kernel: loop5: detected capacity change from 0 to 147912 May 8 00:07:39.919799 kernel: loop6: detected capacity change from 0 to 218376 May 8 00:07:39.968796 kernel: loop7: detected capacity change from 0 to 52152 May 8 00:07:39.996217 (sd-merge)[1173]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-gce'. May 8 00:07:39.997333 (sd-merge)[1173]: Merged extensions into '/usr'. May 8 00:07:40.010391 systemd[1]: Reload requested from client PID 1146 ('systemd-sysext') (unit systemd-sysext.service)... May 8 00:07:40.010801 systemd[1]: Reloading... May 8 00:07:40.142548 zram_generator::config[1198]: No configuration found. May 8 00:07:40.364483 ldconfig[1140]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. May 8 00:07:40.430242 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. May 8 00:07:40.585988 systemd[1]: Reloading finished in 574 ms. May 8 00:07:40.607351 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. May 8 00:07:40.617572 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. May 8 00:07:40.636302 systemd[1]: Starting ensure-sysext.service... May 8 00:07:40.649393 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 8 00:07:40.683927 systemd[1]: Reload requested from client PID 1241 ('systemctl') (unit ensure-sysext.service)... May 8 00:07:40.683947 systemd[1]: Reloading... May 8 00:07:40.725718 systemd-tmpfiles[1242]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. May 8 00:07:40.726285 systemd-tmpfiles[1242]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. May 8 00:07:40.728117 systemd-tmpfiles[1242]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. May 8 00:07:40.728674 systemd-tmpfiles[1242]: ACLs are not supported, ignoring. May 8 00:07:40.728839 systemd-tmpfiles[1242]: ACLs are not supported, ignoring. May 8 00:07:40.740574 systemd-tmpfiles[1242]: Detected autofs mount point /boot during canonicalization of boot. May 8 00:07:40.740594 systemd-tmpfiles[1242]: Skipping /boot May 8 00:07:40.764791 zram_generator::config[1268]: No configuration found. May 8 00:07:40.780072 systemd-tmpfiles[1242]: Detected autofs mount point /boot during canonicalization of boot. May 8 00:07:40.780740 systemd-tmpfiles[1242]: Skipping /boot May 8 00:07:40.963172 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. May 8 00:07:41.054435 systemd[1]: Reloading finished in 369 ms. May 8 00:07:41.070413 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. May 8 00:07:41.102299 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 8 00:07:41.126183 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 8 00:07:41.144153 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... May 8 00:07:41.166630 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... May 8 00:07:41.189533 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 8 00:07:41.209138 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 8 00:07:41.229385 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... May 8 00:07:41.231439 augenrules[1338]: No rules May 8 00:07:41.245388 systemd[1]: audit-rules.service: Deactivated successfully. May 8 00:07:41.245694 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 8 00:07:41.254942 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. May 8 00:07:41.278643 systemd-udevd[1334]: Using default interface naming scheme 'v255'. May 8 00:07:41.281069 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 8 00:07:41.281455 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 8 00:07:41.288893 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 8 00:07:41.306401 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 8 00:07:41.327111 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 8 00:07:41.338027 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 8 00:07:41.338253 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). May 8 00:07:41.345903 systemd[1]: Starting systemd-update-done.service - Update is Completed... May 8 00:07:41.364901 systemd[1]: Starting systemd-userdbd.service - User Database Manager... May 8 00:07:41.376840 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 8 00:07:41.379599 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 8 00:07:41.392673 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. May 8 00:07:41.407140 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. May 8 00:07:41.418736 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 8 00:07:41.419748 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 8 00:07:41.432915 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 8 00:07:41.433230 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 8 00:07:41.445629 systemd[1]: modprobe@loop.service: Deactivated successfully. May 8 00:07:41.445946 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 8 00:07:41.457528 systemd[1]: Finished systemd-update-done.service - Update is Completed. May 8 00:07:41.518949 systemd[1]: Finished ensure-sysext.service. May 8 00:07:41.527088 systemd[1]: Started systemd-userdbd.service - User Database Manager. May 8 00:07:41.550579 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 8 00:07:41.560183 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 8 00:07:41.569196 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 8 00:07:41.576979 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 8 00:07:41.594998 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 8 00:07:41.613009 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 8 00:07:41.632087 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 8 00:07:41.642796 augenrules[1384]: /sbin/augenrules: No change May 8 00:07:41.650329 systemd[1]: Starting setup-oem.service - Setup OEM... May 8 00:07:41.659037 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 8 00:07:41.659110 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). May 8 00:07:41.670237 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 8 00:07:41.679702 augenrules[1411]: No rules May 8 00:07:41.679932 systemd[1]: Reached target time-set.target - System Time Set. May 8 00:07:41.689919 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). May 8 00:07:41.689968 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 8 00:07:41.697899 systemd[1]: audit-rules.service: Deactivated successfully. May 8 00:07:41.698230 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 8 00:07:41.708356 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 8 00:07:41.710220 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 8 00:07:41.721367 systemd[1]: modprobe@drm.service: Deactivated successfully. May 8 00:07:41.722870 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 8 00:07:41.733341 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 8 00:07:41.734106 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 8 00:07:41.743981 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 May 8 00:07:41.763948 kernel: piix4_smbus 0000:00:01.3: SMBus base address uninitialized - upgrade BIOS or use force_addr=0xaddr May 8 00:07:41.772828 kernel: ACPI: button: Power Button [PWRF] May 8 00:07:41.772893 kernel: input: Sleep Button as /devices/LNXSYSTM:00/LNXSLPBN:00/input/input4 May 8 00:07:41.780786 kernel: ACPI: button: Sleep Button [SLPF] May 8 00:07:41.779525 systemd[1]: modprobe@loop.service: Deactivated successfully. May 8 00:07:41.780064 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 8 00:07:41.802786 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input5 May 8 00:07:41.815250 systemd[1]: Finished setup-oem.service - Setup OEM. May 8 00:07:41.834077 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. May 8 00:07:41.840080 systemd[1]: Condition check resulted in dev-tpmrm0.device - /dev/tpmrm0 being skipped. May 8 00:07:41.844730 systemd-resolved[1329]: Positive Trust Anchors: May 8 00:07:41.846290 systemd[1]: Reached target tpm2.target - Trusted Platform Module. May 8 00:07:41.846579 systemd-resolved[1329]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 8 00:07:41.846740 systemd-resolved[1329]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test May 8 00:07:41.862018 systemd[1]: Starting oem-gce-enable-oslogin.service - Enable GCE OS Login... May 8 00:07:41.864311 systemd-resolved[1329]: Defaulting to hostname 'linux'. May 8 00:07:41.872900 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 8 00:07:41.873002 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 8 00:07:41.881339 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 8 00:07:41.908790 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 42 scanned by (udev-worker) (1364) May 8 00:07:41.905127 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 8 00:07:41.982349 systemd[1]: Finished oem-gce-enable-oslogin.service - Enable GCE OS Login. May 8 00:07:41.993459 systemd-networkd[1410]: lo: Link UP May 8 00:07:41.997730 systemd-networkd[1410]: lo: Gained carrier May 8 00:07:42.003127 systemd-networkd[1410]: Enumeration completed May 8 00:07:42.003910 systemd-networkd[1410]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 8 00:07:42.003926 systemd-networkd[1410]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 8 00:07:42.004529 systemd-networkd[1410]: eth0: Link UP May 8 00:07:42.004547 systemd-networkd[1410]: eth0: Gained carrier May 8 00:07:42.004572 systemd-networkd[1410]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 8 00:07:42.011983 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - PersistentDisk OEM. May 8 00:07:42.017920 systemd-networkd[1410]: eth0: Overlong DHCP hostname received, shortened from 'ci-4230-1-1-nightly-20250507-2100-43d8d852515e52776b3d.c.flatcar-212911.internal' to 'ci-4230-1-1-nightly-20250507-2100-43d8d852515e52776b3d' May 8 00:07:42.017953 systemd-networkd[1410]: eth0: DHCPv4 address 10.128.0.69/32, gateway 10.128.0.1 acquired from 169.254.169.254 May 8 00:07:42.023001 systemd[1]: Started systemd-networkd.service - Network Configuration. May 8 00:07:42.038834 systemd[1]: Reached target network.target - Network. May 8 00:07:42.054066 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... May 8 00:07:42.074995 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... May 8 00:07:42.092473 kernel: EDAC MC: Ver: 3.0.0 May 8 00:07:42.097100 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... May 8 00:07:42.107836 kernel: mousedev: PS/2 mouse device common for all mice May 8 00:07:42.127223 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. May 8 00:07:42.149033 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 8 00:07:42.160270 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. May 8 00:07:42.175506 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. May 8 00:07:42.194040 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... May 8 00:07:42.211375 lvm[1454]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. May 8 00:07:42.248153 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. May 8 00:07:42.249224 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 8 00:07:42.257073 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... May 8 00:07:42.266782 lvm[1457]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. May 8 00:07:42.280821 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 8 00:07:42.293659 systemd[1]: Reached target sysinit.target - System Initialization. May 8 00:07:42.304049 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. May 8 00:07:42.314941 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. May 8 00:07:42.326126 systemd[1]: Started logrotate.timer - Daily rotation of log files. May 8 00:07:42.336035 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. May 8 00:07:42.346973 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. May 8 00:07:42.357929 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). May 8 00:07:42.357989 systemd[1]: Reached target paths.target - Path Units. May 8 00:07:42.366905 systemd[1]: Reached target timers.target - Timer Units. May 8 00:07:42.377000 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. May 8 00:07:42.388501 systemd[1]: Starting docker.socket - Docker Socket for the API... May 8 00:07:42.399197 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). May 8 00:07:42.411061 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). May 8 00:07:42.421883 systemd[1]: Reached target ssh-access.target - SSH Access Available. May 8 00:07:42.443555 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. May 8 00:07:42.453338 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. May 8 00:07:42.465984 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. May 8 00:07:42.477193 systemd[1]: Listening on docker.socket - Docker Socket for the API. May 8 00:07:42.487613 systemd[1]: Reached target sockets.target - Socket Units. May 8 00:07:42.497936 systemd[1]: Reached target basic.target - Basic System. May 8 00:07:42.505985 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. May 8 00:07:42.506040 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. May 8 00:07:42.517899 systemd[1]: Starting containerd.service - containerd container runtime... May 8 00:07:42.532998 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... May 8 00:07:42.549998 systemd[1]: Starting dbus.service - D-Bus System Message Bus... May 8 00:07:42.584635 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... May 8 00:07:42.603967 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... May 8 00:07:42.613274 jq[1466]: false May 8 00:07:42.613891 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). May 8 00:07:42.621001 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... May 8 00:07:42.630001 coreos-metadata[1464]: May 08 00:07:42.629 INFO Fetching http://169.254.169.254/computeMetadata/v1/instance/hostname: Attempt #1 May 8 00:07:42.633673 coreos-metadata[1464]: May 08 00:07:42.633 INFO Fetch successful May 8 00:07:42.633794 coreos-metadata[1464]: May 08 00:07:42.633 INFO Fetching http://169.254.169.254/computeMetadata/v1/instance/network-interfaces/0/access-configs/0/external-ip: Attempt #1 May 8 00:07:42.634804 coreos-metadata[1464]: May 08 00:07:42.634 INFO Fetch successful May 8 00:07:42.634804 coreos-metadata[1464]: May 08 00:07:42.634 INFO Fetching http://169.254.169.254/computeMetadata/v1/instance/network-interfaces/0/ip: Attempt #1 May 8 00:07:42.634934 coreos-metadata[1464]: May 08 00:07:42.634 INFO Fetch successful May 8 00:07:42.634934 coreos-metadata[1464]: May 08 00:07:42.634 INFO Fetching http://169.254.169.254/computeMetadata/v1/instance/machine-type: Attempt #1 May 8 00:07:42.636088 systemd[1]: Started ntpd.service - Network Time Service. May 8 00:07:42.638238 coreos-metadata[1464]: May 08 00:07:42.638 INFO Fetch successful May 8 00:07:42.653350 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... May 8 00:07:42.672872 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... May 8 00:07:42.683884 dbus-daemon[1465]: [system] SELinux support is enabled May 8 00:07:42.689026 extend-filesystems[1469]: Found loop4 May 8 00:07:42.689026 extend-filesystems[1469]: Found loop5 May 8 00:07:42.689026 extend-filesystems[1469]: Found loop6 May 8 00:07:42.689026 extend-filesystems[1469]: Found loop7 May 8 00:07:42.689026 extend-filesystems[1469]: Found sda May 8 00:07:42.689026 extend-filesystems[1469]: Found sda1 May 8 00:07:42.689026 extend-filesystems[1469]: Found sda2 May 8 00:07:42.689026 extend-filesystems[1469]: Found sda3 May 8 00:07:42.689026 extend-filesystems[1469]: Found usr May 8 00:07:42.822729 kernel: EXT4-fs (sda9): resizing filesystem from 1617920 to 2538491 blocks May 8 00:07:42.823657 kernel: EXT4-fs (sda9): resized filesystem to 2538491 May 8 00:07:42.823715 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 42 scanned by (udev-worker) (1361) May 8 00:07:42.823875 ntpd[1472]: 8 May 00:07:42 ntpd[1472]: ntpd 4.2.8p17@1.4004-o Wed May 7 21:38:23 UTC 2025 (1): Starting May 8 00:07:42.823875 ntpd[1472]: 8 May 00:07:42 ntpd[1472]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp May 8 00:07:42.823875 ntpd[1472]: 8 May 00:07:42 ntpd[1472]: ---------------------------------------------------- May 8 00:07:42.823875 ntpd[1472]: 8 May 00:07:42 ntpd[1472]: ntp-4 is maintained by Network Time Foundation, May 8 00:07:42.823875 ntpd[1472]: 8 May 00:07:42 ntpd[1472]: Inc. (NTF), a non-profit 501(c)(3) public-benefit May 8 00:07:42.823875 ntpd[1472]: 8 May 00:07:42 ntpd[1472]: corporation. Support and training for ntp-4 are May 8 00:07:42.823875 ntpd[1472]: 8 May 00:07:42 ntpd[1472]: available at https://www.nwtime.org/support May 8 00:07:42.823875 ntpd[1472]: 8 May 00:07:42 ntpd[1472]: ---------------------------------------------------- May 8 00:07:42.823875 ntpd[1472]: 8 May 00:07:42 ntpd[1472]: proto: precision = 0.072 usec (-24) May 8 00:07:42.823875 ntpd[1472]: 8 May 00:07:42 ntpd[1472]: basedate set to 2025-04-25 May 8 00:07:42.823875 ntpd[1472]: 8 May 00:07:42 ntpd[1472]: gps base set to 2025-04-27 (week 2364) May 8 00:07:42.823875 ntpd[1472]: 8 May 00:07:42 ntpd[1472]: Listen and drop on 0 v6wildcard [::]:123 May 8 00:07:42.823875 ntpd[1472]: 8 May 00:07:42 ntpd[1472]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 8 00:07:42.823875 ntpd[1472]: 8 May 00:07:42 ntpd[1472]: Listen normally on 2 lo 127.0.0.1:123 May 8 00:07:42.823875 ntpd[1472]: 8 May 00:07:42 ntpd[1472]: Listen normally on 3 eth0 10.128.0.69:123 May 8 00:07:42.823875 ntpd[1472]: 8 May 00:07:42 ntpd[1472]: Listen normally on 4 lo [::1]:123 May 8 00:07:42.823875 ntpd[1472]: 8 May 00:07:42 ntpd[1472]: bind(21) AF_INET6 fe80::4001:aff:fe80:45%2#123 flags 0x11 failed: Cannot assign requested address May 8 00:07:42.823875 ntpd[1472]: 8 May 00:07:42 ntpd[1472]: unable to create socket on eth0 (5) for fe80::4001:aff:fe80:45%2#123 May 8 00:07:42.823875 ntpd[1472]: 8 May 00:07:42 ntpd[1472]: failed to init interface for address fe80::4001:aff:fe80:45%2 May 8 00:07:42.823875 ntpd[1472]: 8 May 00:07:42 ntpd[1472]: Listening on routing socket on fd #21 for interface updates May 8 00:07:42.823875 ntpd[1472]: 8 May 00:07:42 ntpd[1472]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 8 00:07:42.823875 ntpd[1472]: 8 May 00:07:42 ntpd[1472]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 8 00:07:42.695955 systemd[1]: Starting systemd-logind.service - User Login Management... May 8 00:07:42.825328 extend-filesystems[1469]: Found sda4 May 8 00:07:42.825328 extend-filesystems[1469]: Found sda6 May 8 00:07:42.825328 extend-filesystems[1469]: Found sda7 May 8 00:07:42.825328 extend-filesystems[1469]: Found sda9 May 8 00:07:42.825328 extend-filesystems[1469]: Checking size of /dev/sda9 May 8 00:07:42.825328 extend-filesystems[1469]: Resized partition /dev/sda9 May 8 00:07:42.699073 dbus-daemon[1465]: [system] Activating systemd to hand-off: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.1' (uid=244 pid=1410 comm="/usr/lib/systemd/systemd-networkd" label="system_u:system_r:kernel_t:s0") May 8 00:07:42.711250 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionSecurity=!tpm2). May 8 00:07:42.902295 extend-filesystems[1493]: resize2fs 1.47.1 (20-May-2024) May 8 00:07:42.902295 extend-filesystems[1493]: Filesystem at /dev/sda9 is mounted on /; on-line resizing required May 8 00:07:42.902295 extend-filesystems[1493]: old_desc_blocks = 1, new_desc_blocks = 2 May 8 00:07:42.902295 extend-filesystems[1493]: The filesystem on /dev/sda9 is now 2538491 (4k) blocks long. May 8 00:07:42.734449 ntpd[1472]: ntpd 4.2.8p17@1.4004-o Wed May 7 21:38:23 UTC 2025 (1): Starting May 8 00:07:42.712142 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. May 8 00:07:42.954502 extend-filesystems[1469]: Resized filesystem in /dev/sda9 May 8 00:07:42.734514 ntpd[1472]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp May 8 00:07:42.967099 jq[1489]: true May 8 00:07:42.717959 systemd[1]: Starting update-engine.service - Update Engine... May 8 00:07:42.734531 ntpd[1472]: ---------------------------------------------------- May 8 00:07:42.967680 update_engine[1487]: I20250508 00:07:42.899420 1487 main.cc:92] Flatcar Update Engine starting May 8 00:07:42.967680 update_engine[1487]: I20250508 00:07:42.931144 1487 update_check_scheduler.cc:74] Next update check in 11m14s May 8 00:07:42.726035 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... May 8 00:07:42.734544 ntpd[1472]: ntp-4 is maintained by Network Time Foundation, May 8 00:07:42.797592 systemd[1]: Started dbus.service - D-Bus System Message Bus. May 8 00:07:42.734562 ntpd[1472]: Inc. (NTF), a non-profit 501(c)(3) public-benefit May 8 00:07:42.822334 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. May 8 00:07:42.734576 ntpd[1472]: corporation. Support and training for ntp-4 are May 8 00:07:42.822842 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. May 8 00:07:42.734590 ntpd[1472]: available at https://www.nwtime.org/support May 8 00:07:42.982106 jq[1500]: true May 8 00:07:42.823305 systemd[1]: extend-filesystems.service: Deactivated successfully. May 8 00:07:42.734603 ntpd[1472]: ---------------------------------------------------- May 8 00:07:42.824848 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. May 8 00:07:42.739162 ntpd[1472]: proto: precision = 0.072 usec (-24) May 8 00:07:42.836599 systemd[1]: motdgen.service: Deactivated successfully. May 8 00:07:42.741068 ntpd[1472]: basedate set to 2025-04-25 May 8 00:07:42.837096 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. May 8 00:07:42.741091 ntpd[1472]: gps base set to 2025-04-27 (week 2364) May 8 00:07:42.844575 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. May 8 00:07:42.752884 ntpd[1472]: Listen and drop on 0 v6wildcard [::]:123 May 8 00:07:42.844973 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. May 8 00:07:42.752945 ntpd[1472]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 8 00:07:42.932423 (ntainerd)[1501]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR May 8 00:07:42.753178 ntpd[1472]: Listen normally on 2 lo 127.0.0.1:123 May 8 00:07:42.966869 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. May 8 00:07:42.753231 ntpd[1472]: Listen normally on 3 eth0 10.128.0.69:123 May 8 00:07:42.995531 systemd[1]: Started update-engine.service - Update Engine. May 8 00:07:42.753286 ntpd[1472]: Listen normally on 4 lo [::1]:123 May 8 00:07:42.753342 ntpd[1472]: bind(21) AF_INET6 fe80::4001:aff:fe80:45%2#123 flags 0x11 failed: Cannot assign requested address May 8 00:07:42.753371 ntpd[1472]: unable to create socket on eth0 (5) for fe80::4001:aff:fe80:45%2#123 May 8 00:07:42.753394 ntpd[1472]: failed to init interface for address fe80::4001:aff:fe80:45%2 May 8 00:07:42.753434 ntpd[1472]: Listening on routing socket on fd #21 for interface updates May 8 00:07:42.755535 ntpd[1472]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 8 00:07:42.755571 ntpd[1472]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 8 00:07:42.911248 dbus-daemon[1465]: [system] Successfully activated service 'org.freedesktop.systemd1' May 8 00:07:43.005881 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. May 8 00:07:43.016407 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. May 8 00:07:43.016538 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). May 8 00:07:43.016572 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. May 8 00:07:43.041938 systemd[1]: Starting systemd-hostnamed.service - Hostname Service... May 8 00:07:43.049283 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). May 8 00:07:43.049326 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. May 8 00:07:43.061021 systemd-logind[1482]: Watching system buttons on /dev/input/event1 (Power Button) May 8 00:07:43.061057 systemd-logind[1482]: Watching system buttons on /dev/input/event2 (Sleep Button) May 8 00:07:43.061085 systemd-logind[1482]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) May 8 00:07:43.061348 systemd-logind[1482]: New seat seat0. May 8 00:07:43.069008 systemd[1]: Started locksmithd.service - Cluster reboot manager. May 8 00:07:43.070524 systemd-networkd[1410]: eth0: Gained IPv6LL May 8 00:07:43.079962 systemd[1]: Started systemd-logind.service - User Login Management. May 8 00:07:43.091551 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. May 8 00:07:43.106311 systemd[1]: Reached target network-online.target - Network is Online. May 8 00:07:43.115580 bash[1530]: Updated "/home/core/.ssh/authorized_keys" May 8 00:07:43.121049 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... May 8 00:07:43.142152 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... May 8 00:07:43.159356 systemd[1]: Starting oem-gce.service - GCE Linux Agent... May 8 00:07:43.169504 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. May 8 00:07:43.200130 systemd[1]: Starting sshkeys.service... May 8 00:07:43.227148 init.sh[1535]: + '[' -e /etc/default/instance_configs.cfg.template ']' May 8 00:07:43.227475 init.sh[1535]: + echo -e '[InstanceSetup]\nset_host_keys = false' May 8 00:07:43.227548 init.sh[1535]: + /usr/bin/google_instance_setup May 8 00:07:43.262289 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. May 8 00:07:43.289000 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... May 8 00:07:43.345299 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. May 8 00:07:43.482291 coreos-metadata[1545]: May 08 00:07:43.482 INFO Fetching http://169.254.169.254/computeMetadata/v1/instance/attributes/sshKeys: Attempt #1 May 8 00:07:43.492852 coreos-metadata[1545]: May 08 00:07:43.492 INFO Fetch failed with 404: resource not found May 8 00:07:43.493200 coreos-metadata[1545]: May 08 00:07:43.493 INFO Fetching http://169.254.169.254/computeMetadata/v1/instance/attributes/ssh-keys: Attempt #1 May 8 00:07:43.499842 coreos-metadata[1545]: May 08 00:07:43.498 INFO Fetch successful May 8 00:07:43.499842 coreos-metadata[1545]: May 08 00:07:43.498 INFO Fetching http://169.254.169.254/computeMetadata/v1/instance/attributes/block-project-ssh-keys: Attempt #1 May 8 00:07:43.500239 coreos-metadata[1545]: May 08 00:07:43.500 INFO Fetch failed with 404: resource not found May 8 00:07:43.500239 coreos-metadata[1545]: May 08 00:07:43.500 INFO Fetching http://169.254.169.254/computeMetadata/v1/project/attributes/sshKeys: Attempt #1 May 8 00:07:43.500940 coreos-metadata[1545]: May 08 00:07:43.500 INFO Fetch failed with 404: resource not found May 8 00:07:43.500940 coreos-metadata[1545]: May 08 00:07:43.500 INFO Fetching http://169.254.169.254/computeMetadata/v1/project/attributes/ssh-keys: Attempt #1 May 8 00:07:43.505605 coreos-metadata[1545]: May 08 00:07:43.504 INFO Fetch successful May 8 00:07:43.507669 systemd[1]: Started systemd-hostnamed.service - Hostname Service. May 8 00:07:43.508680 dbus-daemon[1465]: [system] Successfully activated service 'org.freedesktop.hostname1' May 8 00:07:43.509436 dbus-daemon[1465]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.5' (uid=0 pid=1526 comm="/usr/lib/systemd/systemd-hostnamed" label="system_u:system_r:kernel_t:s0") May 8 00:07:43.512615 unknown[1545]: wrote ssh authorized keys file for user: core May 8 00:07:43.512681 locksmithd[1531]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" May 8 00:07:43.542624 systemd[1]: Starting polkit.service - Authorization Manager... May 8 00:07:43.578828 update-ssh-keys[1560]: Updated "/home/core/.ssh/authorized_keys" May 8 00:07:43.583402 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). May 8 00:07:43.600099 systemd[1]: Finished sshkeys.service. May 8 00:07:43.691826 polkitd[1562]: Started polkitd version 121 May 8 00:07:43.725646 polkitd[1562]: Loading rules from directory /etc/polkit-1/rules.d May 8 00:07:43.725756 polkitd[1562]: Loading rules from directory /usr/share/polkit-1/rules.d May 8 00:07:43.733218 polkitd[1562]: Finished loading, compiling and executing 2 rules May 8 00:07:43.737305 dbus-daemon[1465]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' May 8 00:07:43.737535 systemd[1]: Started polkit.service - Authorization Manager. May 8 00:07:43.738168 polkitd[1562]: Acquired the name org.freedesktop.PolicyKit1 on the system bus May 8 00:07:43.812862 systemd-hostnamed[1526]: Hostname set to (transient) May 8 00:07:43.813211 systemd-resolved[1329]: System hostname changed to 'ci-4230-1-1-nightly-20250507-2100-43d8d852515e52776b3d'. May 8 00:07:43.848789 containerd[1501]: time="2025-05-08T00:07:43.844573706Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 May 8 00:07:43.902110 containerd[1501]: time="2025-05-08T00:07:43.901965483Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 May 8 00:07:43.904956 containerd[1501]: time="2025-05-08T00:07:43.904895109Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.88-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 May 8 00:07:43.905110 containerd[1501]: time="2025-05-08T00:07:43.905086602Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 May 8 00:07:43.905229 containerd[1501]: time="2025-05-08T00:07:43.905209725Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 May 8 00:07:43.905599 containerd[1501]: time="2025-05-08T00:07:43.905572428Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 May 8 00:07:43.905786 containerd[1501]: time="2025-05-08T00:07:43.905744256Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 May 8 00:07:43.906056 containerd[1501]: time="2025-05-08T00:07:43.906009017Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 May 8 00:07:43.906192 containerd[1501]: time="2025-05-08T00:07:43.906170208Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 May 8 00:07:43.906791 containerd[1501]: time="2025-05-08T00:07:43.906721429Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 May 8 00:07:43.906913 containerd[1501]: time="2025-05-08T00:07:43.906891613Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 May 8 00:07:43.907026 containerd[1501]: time="2025-05-08T00:07:43.907005846Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 May 8 00:07:43.907123 containerd[1501]: time="2025-05-08T00:07:43.907106857Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 May 8 00:07:43.907374 containerd[1501]: time="2025-05-08T00:07:43.907344222Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 May 8 00:07:43.908577 containerd[1501]: time="2025-05-08T00:07:43.908452526Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 May 8 00:07:43.908968 containerd[1501]: time="2025-05-08T00:07:43.908921672Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 May 8 00:07:43.909127 containerd[1501]: time="2025-05-08T00:07:43.908951341Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 May 8 00:07:43.909357 containerd[1501]: time="2025-05-08T00:07:43.909292055Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 May 8 00:07:43.909675 containerd[1501]: time="2025-05-08T00:07:43.909459536Z" level=info msg="metadata content store policy set" policy=shared May 8 00:07:43.924000 containerd[1501]: time="2025-05-08T00:07:43.923309859Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 May 8 00:07:43.924000 containerd[1501]: time="2025-05-08T00:07:43.923469591Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 May 8 00:07:43.924000 containerd[1501]: time="2025-05-08T00:07:43.923508127Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 May 8 00:07:43.924000 containerd[1501]: time="2025-05-08T00:07:43.923555607Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 May 8 00:07:43.924000 containerd[1501]: time="2025-05-08T00:07:43.923579074Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 May 8 00:07:43.924000 containerd[1501]: time="2025-05-08T00:07:43.923857941Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 May 8 00:07:43.926697 containerd[1501]: time="2025-05-08T00:07:43.925833234Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 May 8 00:07:43.926697 containerd[1501]: time="2025-05-08T00:07:43.926010732Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 May 8 00:07:43.926697 containerd[1501]: time="2025-05-08T00:07:43.926038719Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 May 8 00:07:43.926697 containerd[1501]: time="2025-05-08T00:07:43.926071852Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 May 8 00:07:43.926697 containerd[1501]: time="2025-05-08T00:07:43.926095078Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 May 8 00:07:43.926697 containerd[1501]: time="2025-05-08T00:07:43.926115196Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 May 8 00:07:43.926697 containerd[1501]: time="2025-05-08T00:07:43.926134632Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 May 8 00:07:43.926697 containerd[1501]: time="2025-05-08T00:07:43.926155362Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 May 8 00:07:43.926697 containerd[1501]: time="2025-05-08T00:07:43.926176694Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 May 8 00:07:43.926697 containerd[1501]: time="2025-05-08T00:07:43.926196801Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 May 8 00:07:43.926697 containerd[1501]: time="2025-05-08T00:07:43.926218888Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 May 8 00:07:43.926697 containerd[1501]: time="2025-05-08T00:07:43.926236666Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 May 8 00:07:43.926697 containerd[1501]: time="2025-05-08T00:07:43.926267406Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 May 8 00:07:43.926697 containerd[1501]: time="2025-05-08T00:07:43.926289186Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 May 8 00:07:43.927439 containerd[1501]: time="2025-05-08T00:07:43.926313006Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 May 8 00:07:43.927439 containerd[1501]: time="2025-05-08T00:07:43.926336966Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 May 8 00:07:43.927439 containerd[1501]: time="2025-05-08T00:07:43.926356163Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 May 8 00:07:43.927439 containerd[1501]: time="2025-05-08T00:07:43.926392048Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 May 8 00:07:43.927439 containerd[1501]: time="2025-05-08T00:07:43.926411986Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 May 8 00:07:43.927439 containerd[1501]: time="2025-05-08T00:07:43.926433383Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 May 8 00:07:43.927439 containerd[1501]: time="2025-05-08T00:07:43.926454656Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 May 8 00:07:43.927439 containerd[1501]: time="2025-05-08T00:07:43.926477198Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 May 8 00:07:43.927439 containerd[1501]: time="2025-05-08T00:07:43.926496647Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 May 8 00:07:43.927439 containerd[1501]: time="2025-05-08T00:07:43.926515788Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 May 8 00:07:43.927439 containerd[1501]: time="2025-05-08T00:07:43.926535473Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 May 8 00:07:43.927439 containerd[1501]: time="2025-05-08T00:07:43.926558799Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 May 8 00:07:43.927439 containerd[1501]: time="2025-05-08T00:07:43.926590298Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 May 8 00:07:43.927439 containerd[1501]: time="2025-05-08T00:07:43.926610380Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 May 8 00:07:43.927439 containerd[1501]: time="2025-05-08T00:07:43.926628257Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 May 8 00:07:43.929802 containerd[1501]: time="2025-05-08T00:07:43.926696485Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 May 8 00:07:43.929802 containerd[1501]: time="2025-05-08T00:07:43.926722260Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 May 8 00:07:43.929802 containerd[1501]: time="2025-05-08T00:07:43.926739956Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 May 8 00:07:43.929802 containerd[1501]: time="2025-05-08T00:07:43.927929031Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 May 8 00:07:43.929802 containerd[1501]: time="2025-05-08T00:07:43.928180809Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 May 8 00:07:43.929802 containerd[1501]: time="2025-05-08T00:07:43.928211530Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 May 8 00:07:43.929802 containerd[1501]: time="2025-05-08T00:07:43.928230707Z" level=info msg="NRI interface is disabled by configuration." May 8 00:07:43.929802 containerd[1501]: time="2025-05-08T00:07:43.928248441Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 May 8 00:07:43.930971 containerd[1501]: time="2025-05-08T00:07:43.928733690Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" May 8 00:07:43.930971 containerd[1501]: time="2025-05-08T00:07:43.928827274Z" level=info msg="Connect containerd service" May 8 00:07:43.930971 containerd[1501]: time="2025-05-08T00:07:43.928885748Z" level=info msg="using legacy CRI server" May 8 00:07:43.930971 containerd[1501]: time="2025-05-08T00:07:43.928899181Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" May 8 00:07:43.930971 containerd[1501]: time="2025-05-08T00:07:43.929076749Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" May 8 00:07:43.938806 containerd[1501]: time="2025-05-08T00:07:43.937939203Z" 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" May 8 00:07:43.938806 containerd[1501]: time="2025-05-08T00:07:43.938111068Z" level=info msg="Start subscribing containerd event" May 8 00:07:43.938806 containerd[1501]: time="2025-05-08T00:07:43.938168198Z" level=info msg="Start recovering state" May 8 00:07:43.938806 containerd[1501]: time="2025-05-08T00:07:43.938259106Z" level=info msg="Start event monitor" May 8 00:07:43.938806 containerd[1501]: time="2025-05-08T00:07:43.938403088Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc May 8 00:07:43.938806 containerd[1501]: time="2025-05-08T00:07:43.938469927Z" level=info msg=serving... address=/run/containerd/containerd.sock May 8 00:07:43.945872 containerd[1501]: time="2025-05-08T00:07:43.942955355Z" level=info msg="Start snapshots syncer" May 8 00:07:43.945872 containerd[1501]: time="2025-05-08T00:07:43.943010741Z" level=info msg="Start cni network conf syncer for default" May 8 00:07:43.945872 containerd[1501]: time="2025-05-08T00:07:43.943026101Z" level=info msg="Start streaming server" May 8 00:07:43.945872 containerd[1501]: time="2025-05-08T00:07:43.943544821Z" level=info msg="containerd successfully booted in 0.103096s" May 8 00:07:43.943252 systemd[1]: Started containerd.service - containerd container runtime. May 8 00:07:44.262349 sshd_keygen[1492]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 May 8 00:07:44.307697 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. May 8 00:07:44.330209 systemd[1]: Starting issuegen.service - Generate /run/issue... May 8 00:07:44.336910 systemd[1]: Started sshd@0-10.128.0.69:22-139.178.89.65:34168.service - OpenSSH per-connection server daemon (139.178.89.65:34168). May 8 00:07:44.366946 systemd[1]: issuegen.service: Deactivated successfully. May 8 00:07:44.367303 systemd[1]: Finished issuegen.service - Generate /run/issue. May 8 00:07:44.385175 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... May 8 00:07:44.405803 instance-setup[1542]: INFO Running google_set_multiqueue. May 8 00:07:44.425132 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. May 8 00:07:44.445168 systemd[1]: Started getty@tty1.service - Getty on tty1. May 8 00:07:44.446975 instance-setup[1542]: INFO Set channels for eth0 to 2. May 8 00:07:44.451837 instance-setup[1542]: INFO Setting /proc/irq/31/smp_affinity_list to 0 for device virtio1. May 8 00:07:44.453564 instance-setup[1542]: INFO /proc/irq/31/smp_affinity_list: real affinity 0 May 8 00:07:44.456927 instance-setup[1542]: INFO Setting /proc/irq/32/smp_affinity_list to 0 for device virtio1. May 8 00:07:44.458411 instance-setup[1542]: INFO /proc/irq/32/smp_affinity_list: real affinity 0 May 8 00:07:44.459086 instance-setup[1542]: INFO Setting /proc/irq/33/smp_affinity_list to 1 for device virtio1. May 8 00:07:44.461161 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. May 8 00:07:44.464424 instance-setup[1542]: INFO /proc/irq/33/smp_affinity_list: real affinity 1 May 8 00:07:44.464946 instance-setup[1542]: INFO Setting /proc/irq/34/smp_affinity_list to 1 for device virtio1. May 8 00:07:44.468510 instance-setup[1542]: INFO /proc/irq/34/smp_affinity_list: real affinity 1 May 8 00:07:44.471166 systemd[1]: Reached target getty.target - Login Prompts. May 8 00:07:44.477079 instance-setup[1542]: INFO /usr/sbin/google_set_multiqueue: line 133: echo: write error: Value too large for defined data type May 8 00:07:44.482049 instance-setup[1542]: INFO /usr/sbin/google_set_multiqueue: line 133: echo: write error: Value too large for defined data type May 8 00:07:44.484491 instance-setup[1542]: INFO Queue 0 XPS=1 for /sys/class/net/eth0/queues/tx-0/xps_cpus May 8 00:07:44.484545 instance-setup[1542]: INFO Queue 1 XPS=2 for /sys/class/net/eth0/queues/tx-1/xps_cpus May 8 00:07:44.505002 init.sh[1535]: + /usr/bin/google_metadata_script_runner --script-type startup May 8 00:07:44.657750 startup-script[1625]: INFO Starting startup scripts. May 8 00:07:44.663011 startup-script[1625]: INFO No startup scripts found in metadata. May 8 00:07:44.663090 startup-script[1625]: INFO Finished running startup scripts. May 8 00:07:44.688490 init.sh[1535]: + trap 'stopping=1 ; kill "${daemon_pids[@]}" || :' SIGTERM May 8 00:07:44.688490 init.sh[1535]: + daemon_pids=() May 8 00:07:44.688714 init.sh[1535]: + for d in accounts clock_skew network May 8 00:07:44.688876 init.sh[1535]: + daemon_pids+=($!) May 8 00:07:44.690010 init.sh[1535]: + for d in accounts clock_skew network May 8 00:07:44.690010 init.sh[1535]: + daemon_pids+=($!) May 8 00:07:44.690010 init.sh[1535]: + for d in accounts clock_skew network May 8 00:07:44.690010 init.sh[1535]: + daemon_pids+=($!) May 8 00:07:44.690010 init.sh[1535]: + NOTIFY_SOCKET=/run/systemd/notify May 8 00:07:44.690010 init.sh[1535]: + /usr/bin/systemd-notify --ready May 8 00:07:44.691055 init.sh[1629]: + /usr/bin/google_clock_skew_daemon May 8 00:07:44.691697 init.sh[1630]: + /usr/bin/google_network_daemon May 8 00:07:44.692697 init.sh[1628]: + /usr/bin/google_accounts_daemon May 8 00:07:44.732098 systemd[1]: Started oem-gce.service - GCE Linux Agent. May 8 00:07:44.753666 init.sh[1535]: + wait -n 1628 1629 1630 May 8 00:07:44.794367 sshd[1588]: Accepted publickey for core from 139.178.89.65 port 34168 ssh2: RSA SHA256:9GUCYeiHsuerOrm/1cLq/NaVjYsAYFTUObbiljb4Wx8 May 8 00:07:44.797250 sshd-session[1588]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 8 00:07:44.822575 systemd[1]: Created slice user-500.slice - User Slice of UID 500. May 8 00:07:44.843345 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... May 8 00:07:44.890636 systemd-logind[1482]: New session 1 of user core. May 8 00:07:44.904103 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. May 8 00:07:44.928681 systemd[1]: Starting user@500.service - User Manager for UID 500... May 8 00:07:44.967953 (systemd)[1634]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) May 8 00:07:44.978711 systemd-logind[1482]: New session c1 of user core. May 8 00:07:45.286711 groupadd[1646]: group added to /etc/group: name=google-sudoers, GID=1000 May 8 00:07:45.290823 google-clock-skew[1629]: INFO Starting Google Clock Skew daemon. May 8 00:07:45.292286 groupadd[1646]: group added to /etc/gshadow: name=google-sudoers May 8 00:07:45.302526 google-clock-skew[1629]: INFO Clock drift token has changed: 0. May 8 00:07:45.309063 google-networking[1630]: INFO Starting Google Networking daemon. May 8 00:07:45.371859 systemd[1634]: Queued start job for default target default.target. May 8 00:07:45.377783 groupadd[1646]: new group: name=google-sudoers, GID=1000 May 8 00:07:45.378812 systemd[1634]: Created slice app.slice - User Application Slice. May 8 00:07:45.378857 systemd[1634]: Reached target paths.target - Paths. May 8 00:07:45.378935 systemd[1634]: Reached target timers.target - Timers. May 8 00:07:45.383930 systemd[1634]: Starting dbus.socket - D-Bus User Message Bus Socket... May 8 00:07:45.400183 systemd[1634]: Listening on dbus.socket - D-Bus User Message Bus Socket. May 8 00:07:45.401878 systemd[1634]: Reached target sockets.target - Sockets. May 8 00:07:45.401964 systemd[1634]: Reached target basic.target - Basic System. May 8 00:07:45.402037 systemd[1634]: Reached target default.target - Main User Target. May 8 00:07:45.402087 systemd[1634]: Startup finished in 404ms. May 8 00:07:45.402348 systemd[1]: Started user@500.service - User Manager for UID 500. May 8 00:07:45.419987 systemd[1]: Started session-1.scope - Session 1 of User core. May 8 00:07:45.441311 google-accounts[1628]: INFO Starting Google Accounts daemon. May 8 00:07:45.455156 google-accounts[1628]: WARNING OS Login not installed. May 8 00:07:45.456651 google-accounts[1628]: INFO Creating a new user account for 0. May 8 00:07:45.461885 init.sh[1659]: useradd: invalid user name '0': use --badname to ignore May 8 00:07:45.461090 google-accounts[1628]: WARNING Could not create user 0. Command '['useradd', '-m', '-s', '/bin/bash', '-p', '*', '0']' returned non-zero exit status 3.. May 8 00:07:45.669673 systemd[1]: Started sshd@1-10.128.0.69:22-139.178.89.65:34174.service - OpenSSH per-connection server daemon (139.178.89.65:34174). May 8 00:07:45.690373 (kubelet)[1668]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS May 8 00:07:45.691482 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. May 8 00:07:45.703655 systemd[1]: Reached target multi-user.target - Multi-User System. May 8 00:07:45.714080 systemd[1]: Startup finished in 1.022s (kernel) + 8.688s (initrd) + 9.329s (userspace) = 19.040s. May 8 00:07:45.739108 ntpd[1472]: Listen normally on 6 eth0 [fe80::4001:aff:fe80:45%2]:123 May 8 00:07:45.743828 ntpd[1472]: 8 May 00:07:45 ntpd[1472]: Listen normally on 6 eth0 [fe80::4001:aff:fe80:45%2]:123 May 8 00:07:45.988858 sshd[1667]: Accepted publickey for core from 139.178.89.65 port 34174 ssh2: RSA SHA256:9GUCYeiHsuerOrm/1cLq/NaVjYsAYFTUObbiljb4Wx8 May 8 00:07:45.991152 sshd-session[1667]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 8 00:07:45.999071 systemd-logind[1482]: New session 2 of user core. May 8 00:07:46.002965 systemd[1]: Started session-2.scope - Session 2 of User core. May 8 00:07:46.205503 sshd[1679]: Connection closed by 139.178.89.65 port 34174 May 8 00:07:46.206357 sshd-session[1667]: pam_unix(sshd:session): session closed for user core May 8 00:07:46.213059 systemd[1]: sshd@1-10.128.0.69:22-139.178.89.65:34174.service: Deactivated successfully. May 8 00:07:46.215925 systemd[1]: session-2.scope: Deactivated successfully. May 8 00:07:46.217191 systemd-logind[1482]: Session 2 logged out. Waiting for processes to exit. May 8 00:07:46.218819 systemd-logind[1482]: Removed session 2. May 8 00:07:46.268666 systemd[1]: Started sshd@2-10.128.0.69:22-139.178.89.65:34188.service - OpenSSH per-connection server daemon (139.178.89.65:34188). May 8 00:07:46.020832 systemd-journald[1109]: Time jumped backwards, rotating. May 8 00:07:46.000593 google-clock-skew[1629]: INFO Synced system time with hardware clock. May 8 00:07:46.001884 systemd-resolved[1329]: Clock change detected. Flushing caches. May 8 00:07:46.311224 sshd[1685]: Accepted publickey for core from 139.178.89.65 port 34188 ssh2: RSA SHA256:9GUCYeiHsuerOrm/1cLq/NaVjYsAYFTUObbiljb4Wx8 May 8 00:07:46.312915 sshd-session[1685]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 8 00:07:46.322271 kubelet[1668]: E0508 00:07:46.319781 1668 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" May 8 00:07:46.321748 systemd-logind[1482]: New session 3 of user core. May 8 00:07:46.325066 systemd[1]: Started session-3.scope - Session 3 of User core. May 8 00:07:46.325587 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE May 8 00:07:46.325849 systemd[1]: kubelet.service: Failed with result 'exit-code'. May 8 00:07:46.326281 systemd[1]: kubelet.service: Consumed 1.219s CPU time, 251M memory peak. May 8 00:07:46.521295 sshd[1690]: Connection closed by 139.178.89.65 port 34188 May 8 00:07:46.522123 sshd-session[1685]: pam_unix(sshd:session): session closed for user core May 8 00:07:46.526404 systemd[1]: sshd@2-10.128.0.69:22-139.178.89.65:34188.service: Deactivated successfully. May 8 00:07:46.528706 systemd[1]: session-3.scope: Deactivated successfully. May 8 00:07:46.530416 systemd-logind[1482]: Session 3 logged out. Waiting for processes to exit. May 8 00:07:46.531953 systemd-logind[1482]: Removed session 3. May 8 00:07:46.578185 systemd[1]: Started sshd@3-10.128.0.69:22-139.178.89.65:34198.service - OpenSSH per-connection server daemon (139.178.89.65:34198). May 8 00:07:46.874224 sshd[1696]: Accepted publickey for core from 139.178.89.65 port 34198 ssh2: RSA SHA256:9GUCYeiHsuerOrm/1cLq/NaVjYsAYFTUObbiljb4Wx8 May 8 00:07:46.875939 sshd-session[1696]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 8 00:07:46.881787 systemd-logind[1482]: New session 4 of user core. May 8 00:07:46.889027 systemd[1]: Started session-4.scope - Session 4 of User core. May 8 00:07:47.088599 sshd[1698]: Connection closed by 139.178.89.65 port 34198 May 8 00:07:47.089415 sshd-session[1696]: pam_unix(sshd:session): session closed for user core May 8 00:07:47.094399 systemd[1]: sshd@3-10.128.0.69:22-139.178.89.65:34198.service: Deactivated successfully. May 8 00:07:47.096540 systemd[1]: session-4.scope: Deactivated successfully. May 8 00:07:47.097598 systemd-logind[1482]: Session 4 logged out. Waiting for processes to exit. May 8 00:07:47.099010 systemd-logind[1482]: Removed session 4. May 8 00:07:47.153202 systemd[1]: Started sshd@4-10.128.0.69:22-139.178.89.65:51086.service - OpenSSH per-connection server daemon (139.178.89.65:51086). May 8 00:07:47.439120 sshd[1704]: Accepted publickey for core from 139.178.89.65 port 51086 ssh2: RSA SHA256:9GUCYeiHsuerOrm/1cLq/NaVjYsAYFTUObbiljb4Wx8 May 8 00:07:47.440940 sshd-session[1704]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 8 00:07:47.447894 systemd-logind[1482]: New session 5 of user core. May 8 00:07:47.457009 systemd[1]: Started session-5.scope - Session 5 of User core. May 8 00:07:47.633807 sudo[1707]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh May 8 00:07:47.634331 sudo[1707]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 8 00:07:48.474011 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. May 8 00:07:48.474403 systemd[1]: kubelet.service: Consumed 1.219s CPU time, 251M memory peak. May 8 00:07:48.481196 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... May 8 00:07:48.535456 systemd[1]: Reload requested from client PID 1740 ('systemctl') (unit session-5.scope)... May 8 00:07:48.535490 systemd[1]: Reloading... May 8 00:07:48.725905 zram_generator::config[1794]: No configuration found. May 8 00:07:48.859806 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. May 8 00:07:49.003316 systemd[1]: Reloading finished in 467 ms. May 8 00:07:49.072770 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. May 8 00:07:49.080198 systemd[1]: Stopping kubelet.service - kubelet: The Kubernetes Node Agent... May 8 00:07:49.082737 systemd[1]: kubelet.service: Deactivated successfully. May 8 00:07:49.083066 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. May 8 00:07:49.083144 systemd[1]: kubelet.service: Consumed 145ms CPU time, 91.8M memory peak. May 8 00:07:49.088273 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... May 8 00:07:49.353657 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. May 8 00:07:49.365501 (kubelet)[1838]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS May 8 00:07:49.416396 kubelet[1838]: 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. May 8 00:07:49.416396 kubelet[1838]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. May 8 00:07:49.416396 kubelet[1838]: 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. May 8 00:07:49.416949 kubelet[1838]: I0508 00:07:49.416527 1838 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" May 8 00:07:50.017485 kubelet[1838]: I0508 00:07:50.017429 1838 server.go:520] "Kubelet version" kubeletVersion="v1.32.0" May 8 00:07:50.017485 kubelet[1838]: I0508 00:07:50.017464 1838 server.go:522] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" May 8 00:07:50.017992 kubelet[1838]: I0508 00:07:50.017951 1838 server.go:954] "Client rotation is on, will bootstrap in background" May 8 00:07:50.065833 kubelet[1838]: I0508 00:07:50.065450 1838 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" May 8 00:07:50.082253 kubelet[1838]: E0508 00:07:50.082198 1838 log.go:32] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" May 8 00:07:50.082253 kubelet[1838]: I0508 00:07:50.082251 1838 server.go:1421] "CRI implementation should be updated to support RuntimeConfig when KubeletCgroupDriverFromCRI feature gate has been enabled. Falling back to using cgroupDriver from kubelet config." May 8 00:07:50.086304 kubelet[1838]: I0508 00:07:50.086276 1838 server.go:772] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" May 8 00:07:50.086677 kubelet[1838]: I0508 00:07:50.086621 1838 container_manager_linux.go:268] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] May 8 00:07:50.086950 kubelet[1838]: I0508 00:07:50.086664 1838 container_manager_linux.go:273] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.128.0.69","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":"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},{"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}],"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} May 8 00:07:50.087133 kubelet[1838]: I0508 00:07:50.086948 1838 topology_manager.go:138] "Creating topology manager with none policy" May 8 00:07:50.087133 kubelet[1838]: I0508 00:07:50.086976 1838 container_manager_linux.go:304] "Creating device plugin manager" May 8 00:07:50.087248 kubelet[1838]: I0508 00:07:50.087179 1838 state_mem.go:36] "Initialized new in-memory state store" May 8 00:07:50.093530 kubelet[1838]: I0508 00:07:50.093410 1838 kubelet.go:446] "Attempting to sync node with API server" May 8 00:07:50.093530 kubelet[1838]: I0508 00:07:50.093445 1838 kubelet.go:341] "Adding static pod path" path="/etc/kubernetes/manifests" May 8 00:07:50.093530 kubelet[1838]: I0508 00:07:50.093475 1838 kubelet.go:352] "Adding apiserver pod source" May 8 00:07:50.093530 kubelet[1838]: I0508 00:07:50.093493 1838 apiserver.go:42] "Waiting for node sync before watching apiserver pods" May 8 00:07:50.097381 kubelet[1838]: E0508 00:07:50.097180 1838 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:07:50.097381 kubelet[1838]: E0508 00:07:50.097296 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:07:50.100852 kubelet[1838]: I0508 00:07:50.100053 1838 kuberuntime_manager.go:269] "Container runtime initialized" containerRuntime="containerd" version="v1.7.23" apiVersion="v1" May 8 00:07:50.100852 kubelet[1838]: I0508 00:07:50.100723 1838 kubelet.go:890] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" May 8 00:07:50.102188 kubelet[1838]: W0508 00:07:50.102147 1838 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. May 8 00:07:50.104962 kubelet[1838]: I0508 00:07:50.104916 1838 watchdog_linux.go:99] "Systemd watchdog is not enabled" May 8 00:07:50.105067 kubelet[1838]: I0508 00:07:50.104988 1838 server.go:1287] "Started kubelet" May 8 00:07:50.105234 kubelet[1838]: I0508 00:07:50.105159 1838 server.go:169] "Starting to listen" address="0.0.0.0" port=10250 May 8 00:07:50.106860 kubelet[1838]: I0508 00:07:50.106435 1838 server.go:490] "Adding debug handlers to kubelet server" May 8 00:07:50.109743 kubelet[1838]: I0508 00:07:50.109674 1838 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" May 8 00:07:50.111720 kubelet[1838]: I0508 00:07:50.111034 1838 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 May 8 00:07:50.111720 kubelet[1838]: I0508 00:07:50.111348 1838 server.go:243] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" May 8 00:07:50.118367 kubelet[1838]: E0508 00:07:50.116335 1838 event.go:359] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{10.128.0.69.183d64a04af80c6a default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.128.0.69,UID:10.128.0.69,APIVersion:,ResourceVersion:,FieldPath:,},Reason:Starting,Message:Starting kubelet.,Source:EventSource{Component:kubelet,Host:10.128.0.69,},FirstTimestamp:2025-05-08 00:07:50.10493553 +0000 UTC m=+0.733452338,LastTimestamp:2025-05-08 00:07:50.10493553 +0000 UTC m=+0.733452338,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.128.0.69,}" May 8 00:07:50.121598 kubelet[1838]: I0508 00:07:50.121568 1838 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" May 8 00:07:50.127002 kubelet[1838]: E0508 00:07:50.125960 1838 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.128.0.69\" not found" May 8 00:07:50.127002 kubelet[1838]: I0508 00:07:50.126000 1838 volume_manager.go:297] "Starting Kubelet Volume Manager" May 8 00:07:50.127002 kubelet[1838]: I0508 00:07:50.126271 1838 desired_state_of_world_populator.go:149] "Desired state populator starts to run" May 8 00:07:50.127002 kubelet[1838]: I0508 00:07:50.126331 1838 reconciler.go:26] "Reconciler: start to sync state" May 8 00:07:50.131063 kubelet[1838]: I0508 00:07:50.131037 1838 factory.go:221] Registration of the systemd container factory successfully May 8 00:07:50.131415 kubelet[1838]: I0508 00:07:50.131275 1838 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 May 8 00:07:50.132569 kubelet[1838]: W0508 00:07:50.130809 1838 reflector.go:569] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Node: nodes "10.128.0.69" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope May 8 00:07:50.132798 kubelet[1838]: E0508 00:07:50.132757 1838 reflector.go:166] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Node: failed to list *v1.Node: nodes \"10.128.0.69\" is forbidden: User \"system:anonymous\" cannot list resource \"nodes\" in API group \"\" at the cluster scope" logger="UnhandledError" May 8 00:07:50.133034 kubelet[1838]: W0508 00:07:50.133015 1838 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 May 8 00:07:50.133409 kubelet[1838]: E0508 00:07:50.133382 1838 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" May 8 00:07:50.133785 kubelet[1838]: E0508 00:07:50.133765 1838 kubelet.go:1561] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" May 8 00:07:50.137554 kubelet[1838]: I0508 00:07:50.137513 1838 factory.go:221] Registration of the containerd container factory successfully May 8 00:07:50.182486 kubelet[1838]: E0508 00:07:50.182437 1838 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.128.0.69\" not found" node="10.128.0.69" May 8 00:07:50.183867 kubelet[1838]: I0508 00:07:50.183610 1838 cpu_manager.go:221] "Starting CPU manager" policy="none" May 8 00:07:50.183867 kubelet[1838]: I0508 00:07:50.183631 1838 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" May 8 00:07:50.183867 kubelet[1838]: I0508 00:07:50.183657 1838 state_mem.go:36] "Initialized new in-memory state store" May 8 00:07:50.186901 kubelet[1838]: I0508 00:07:50.186508 1838 policy_none.go:49] "None policy: Start" May 8 00:07:50.186901 kubelet[1838]: I0508 00:07:50.186541 1838 memory_manager.go:186] "Starting memorymanager" policy="None" May 8 00:07:50.186901 kubelet[1838]: I0508 00:07:50.186561 1838 state_mem.go:35] "Initializing new in-memory state store" May 8 00:07:50.195893 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. May 8 00:07:50.207598 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. May 8 00:07:50.212922 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. May 8 00:07:50.220840 kubelet[1838]: I0508 00:07:50.219959 1838 manager.go:519] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" May 8 00:07:50.220840 kubelet[1838]: I0508 00:07:50.220213 1838 eviction_manager.go:189] "Eviction manager: starting control loop" May 8 00:07:50.220840 kubelet[1838]: I0508 00:07:50.220231 1838 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" May 8 00:07:50.221177 kubelet[1838]: I0508 00:07:50.221154 1838 plugin_manager.go:118] "Starting Kubelet Plugin Manager" May 8 00:07:50.224831 kubelet[1838]: E0508 00:07:50.224786 1838 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" May 8 00:07:50.224921 kubelet[1838]: E0508 00:07:50.224862 1838 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.128.0.69\" not found" May 8 00:07:50.241128 kubelet[1838]: I0508 00:07:50.240934 1838 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" May 8 00:07:50.243096 kubelet[1838]: I0508 00:07:50.243067 1838 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" May 8 00:07:50.243218 kubelet[1838]: I0508 00:07:50.243124 1838 status_manager.go:227] "Starting to sync pod status with apiserver" May 8 00:07:50.243218 kubelet[1838]: I0508 00:07:50.243153 1838 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." May 8 00:07:50.243218 kubelet[1838]: I0508 00:07:50.243164 1838 kubelet.go:2388] "Starting kubelet main sync loop" May 8 00:07:50.243361 kubelet[1838]: E0508 00:07:50.243244 1838 kubelet.go:2412] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" May 8 00:07:50.322670 kubelet[1838]: I0508 00:07:50.321938 1838 kubelet_node_status.go:76] "Attempting to register node" node="10.128.0.69" May 8 00:07:50.331389 kubelet[1838]: I0508 00:07:50.331339 1838 kubelet_node_status.go:79] "Successfully registered node" node="10.128.0.69" May 8 00:07:50.331389 kubelet[1838]: E0508 00:07:50.331376 1838 kubelet_node_status.go:549] "Error updating node status, will retry" err="error getting node \"10.128.0.69\": node \"10.128.0.69\" not found" May 8 00:07:50.341972 kubelet[1838]: E0508 00:07:50.341941 1838 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.128.0.69\" not found" May 8 00:07:50.414063 sudo[1707]: pam_unix(sudo:session): session closed for user root May 8 00:07:50.443117 kubelet[1838]: E0508 00:07:50.443069 1838 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.128.0.69\" not found" May 8 00:07:50.456287 sshd[1706]: Connection closed by 139.178.89.65 port 51086 May 8 00:07:50.457078 sshd-session[1704]: pam_unix(sshd:session): session closed for user core May 8 00:07:50.462494 systemd[1]: sshd@4-10.128.0.69:22-139.178.89.65:51086.service: Deactivated successfully. May 8 00:07:50.465752 systemd[1]: session-5.scope: Deactivated successfully. May 8 00:07:50.466187 systemd[1]: session-5.scope: Consumed 608ms CPU time, 75.5M memory peak. May 8 00:07:50.467971 systemd-logind[1482]: Session 5 logged out. Waiting for processes to exit. May 8 00:07:50.469591 systemd-logind[1482]: Removed session 5. May 8 00:07:50.543833 kubelet[1838]: E0508 00:07:50.543755 1838 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.128.0.69\" not found" May 8 00:07:50.645040 kubelet[1838]: E0508 00:07:50.644892 1838 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.128.0.69\" not found" May 8 00:07:50.745895 kubelet[1838]: E0508 00:07:50.745807 1838 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.128.0.69\" not found" May 8 00:07:50.846917 kubelet[1838]: E0508 00:07:50.846810 1838 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.128.0.69\" not found" May 8 00:07:50.947964 kubelet[1838]: E0508 00:07:50.947792 1838 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.128.0.69\" not found" May 8 00:07:51.020139 kubelet[1838]: I0508 00:07:51.020071 1838 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" May 8 00:07:51.020397 kubelet[1838]: W0508 00:07:51.020340 1838 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 May 8 00:07:51.020554 kubelet[1838]: W0508 00:07:51.020402 1838 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 May 8 00:07:51.049032 kubelet[1838]: E0508 00:07:51.048921 1838 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.128.0.69\" not found" May 8 00:07:51.097520 kubelet[1838]: E0508 00:07:51.097438 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:07:51.149052 kubelet[1838]: E0508 00:07:51.149002 1838 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.128.0.69\" not found" May 8 00:07:51.250093 kubelet[1838]: E0508 00:07:51.249931 1838 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.128.0.69\" not found" May 8 00:07:51.350916 kubelet[1838]: E0508 00:07:51.350837 1838 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.128.0.69\" not found" May 8 00:07:51.451917 kubelet[1838]: I0508 00:07:51.451877 1838 kuberuntime_manager.go:1702] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" May 8 00:07:51.452631 containerd[1501]: time="2025-05-08T00:07:51.452371481Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." May 8 00:07:51.453115 kubelet[1838]: I0508 00:07:51.452704 1838 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" May 8 00:07:52.098407 kubelet[1838]: I0508 00:07:52.098347 1838 apiserver.go:52] "Watching apiserver" May 8 00:07:52.098632 kubelet[1838]: E0508 00:07:52.098342 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:07:52.110082 systemd[1]: Created slice kubepods-burstable-pod690c7041_d188_4c88_a5ed_38c917f39a5e.slice - libcontainer container kubepods-burstable-pod690c7041_d188_4c88_a5ed_38c917f39a5e.slice. May 8 00:07:52.127470 kubelet[1838]: I0508 00:07:52.127436 1838 desired_state_of_world_populator.go:157] "Finished populating initial desired state of world" May 8 00:07:52.128419 systemd[1]: Created slice kubepods-besteffort-pod2b9ebca1_bb8b_4171_acac_bb1c95d73f0d.slice - libcontainer container kubepods-besteffort-pod2b9ebca1_bb8b_4171_acac_bb1c95d73f0d.slice. May 8 00:07:52.138085 kubelet[1838]: I0508 00:07:52.137576 1838 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/690c7041-d188-4c88-a5ed-38c917f39a5e-flannel-cfg\") pod \"kube-flannel-ds-b9lw2\" (UID: \"690c7041-d188-4c88-a5ed-38c917f39a5e\") " pod="kube-flannel/kube-flannel-ds-b9lw2" May 8 00:07:52.138085 kubelet[1838]: I0508 00:07:52.137621 1838 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/690c7041-d188-4c88-a5ed-38c917f39a5e-xtables-lock\") pod \"kube-flannel-ds-b9lw2\" (UID: \"690c7041-d188-4c88-a5ed-38c917f39a5e\") " pod="kube-flannel/kube-flannel-ds-b9lw2" May 8 00:07:52.138085 kubelet[1838]: I0508 00:07:52.137651 1838 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/2b9ebca1-bb8b-4171-acac-bb1c95d73f0d-kube-proxy\") pod \"kube-proxy-kmcbf\" (UID: \"2b9ebca1-bb8b-4171-acac-bb1c95d73f0d\") " pod="kube-system/kube-proxy-kmcbf" May 8 00:07:52.138085 kubelet[1838]: I0508 00:07:52.137680 1838 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/2b9ebca1-bb8b-4171-acac-bb1c95d73f0d-lib-modules\") pod \"kube-proxy-kmcbf\" (UID: \"2b9ebca1-bb8b-4171-acac-bb1c95d73f0d\") " pod="kube-system/kube-proxy-kmcbf" May 8 00:07:52.138085 kubelet[1838]: I0508 00:07:52.137708 1838 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/690c7041-d188-4c88-a5ed-38c917f39a5e-run\") pod \"kube-flannel-ds-b9lw2\" (UID: \"690c7041-d188-4c88-a5ed-38c917f39a5e\") " pod="kube-flannel/kube-flannel-ds-b9lw2" May 8 00:07:52.138340 kubelet[1838]: I0508 00:07:52.137734 1838 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/690c7041-d188-4c88-a5ed-38c917f39a5e-cni-plugin\") pod \"kube-flannel-ds-b9lw2\" (UID: \"690c7041-d188-4c88-a5ed-38c917f39a5e\") " pod="kube-flannel/kube-flannel-ds-b9lw2" May 8 00:07:52.138340 kubelet[1838]: I0508 00:07:52.137759 1838 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/690c7041-d188-4c88-a5ed-38c917f39a5e-cni\") pod \"kube-flannel-ds-b9lw2\" (UID: \"690c7041-d188-4c88-a5ed-38c917f39a5e\") " pod="kube-flannel/kube-flannel-ds-b9lw2" May 8 00:07:52.138340 kubelet[1838]: I0508 00:07:52.137788 1838 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-g57w6\" (UniqueName: \"kubernetes.io/projected/690c7041-d188-4c88-a5ed-38c917f39a5e-kube-api-access-g57w6\") pod \"kube-flannel-ds-b9lw2\" (UID: \"690c7041-d188-4c88-a5ed-38c917f39a5e\") " pod="kube-flannel/kube-flannel-ds-b9lw2" May 8 00:07:52.138340 kubelet[1838]: I0508 00:07:52.137834 1838 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/2b9ebca1-bb8b-4171-acac-bb1c95d73f0d-xtables-lock\") pod \"kube-proxy-kmcbf\" (UID: \"2b9ebca1-bb8b-4171-acac-bb1c95d73f0d\") " pod="kube-system/kube-proxy-kmcbf" May 8 00:07:52.138340 kubelet[1838]: I0508 00:07:52.137874 1838 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-p2mvx\" (UniqueName: \"kubernetes.io/projected/2b9ebca1-bb8b-4171-acac-bb1c95d73f0d-kube-api-access-p2mvx\") pod \"kube-proxy-kmcbf\" (UID: \"2b9ebca1-bb8b-4171-acac-bb1c95d73f0d\") " pod="kube-system/kube-proxy-kmcbf" May 8 00:07:52.425854 containerd[1501]: time="2025-05-08T00:07:52.425693636Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-b9lw2,Uid:690c7041-d188-4c88-a5ed-38c917f39a5e,Namespace:kube-flannel,Attempt:0,}" May 8 00:07:52.437717 containerd[1501]: time="2025-05-08T00:07:52.437549498Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-kmcbf,Uid:2b9ebca1-bb8b-4171-acac-bb1c95d73f0d,Namespace:kube-system,Attempt:0,}" May 8 00:07:52.870077 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3327502180.mount: Deactivated successfully. May 8 00:07:52.878111 containerd[1501]: time="2025-05-08T00:07:52.878047782Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" May 8 00:07:52.880215 containerd[1501]: time="2025-05-08T00:07:52.880163956Z" level=info msg="ImageUpdate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" May 8 00:07:52.881321 containerd[1501]: time="2025-05-08T00:07:52.881281723Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=313954" May 8 00:07:52.882764 containerd[1501]: time="2025-05-08T00:07:52.882708630Z" level=info msg="ImageCreate event name:\"sha256:4873874c08efc72e9729683a83ffbb7502ee729e9a5ac097723806ea7fa13517\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" May 8 00:07:52.883400 containerd[1501]: time="2025-05-08T00:07:52.883344672Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" May 8 00:07:52.885931 containerd[1501]: time="2025-05-08T00:07:52.885895052Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" May 8 00:07:52.887985 containerd[1501]: time="2025-05-08T00:07:52.887948836Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.8\" with image id \"sha256:4873874c08efc72e9729683a83ffbb7502ee729e9a5ac097723806ea7fa13517\", repo tag \"registry.k8s.io/pause:3.8\", repo digest \"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\", size \"311286\" in 450.278897ms" May 8 00:07:52.891691 containerd[1501]: time="2025-05-08T00:07:52.891641998Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.8\" with image id \"sha256:4873874c08efc72e9729683a83ffbb7502ee729e9a5ac097723806ea7fa13517\", repo tag \"registry.k8s.io/pause:3.8\", repo digest \"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\", size \"311286\" in 465.769146ms" May 8 00:07:53.051373 containerd[1501]: time="2025-05-08T00:07:53.048707669Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 8 00:07:53.051373 containerd[1501]: time="2025-05-08T00:07:53.051047161Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 8 00:07:53.051373 containerd[1501]: time="2025-05-08T00:07:53.051084713Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 8 00:07:53.051373 containerd[1501]: time="2025-05-08T00:07:53.051231475Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 8 00:07:53.055444 containerd[1501]: time="2025-05-08T00:07:53.054785403Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 8 00:07:53.055765 containerd[1501]: time="2025-05-08T00:07:53.055354116Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 8 00:07:53.055765 containerd[1501]: time="2025-05-08T00:07:53.055625679Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 8 00:07:53.056896 containerd[1501]: time="2025-05-08T00:07:53.056769704Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 8 00:07:53.099978 kubelet[1838]: E0508 00:07:53.099894 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:07:53.163203 systemd[1]: Started cri-containerd-3870eed950f8c0327d5815017c2a243d159171535b0e0b451c767017923040f6.scope - libcontainer container 3870eed950f8c0327d5815017c2a243d159171535b0e0b451c767017923040f6. May 8 00:07:53.173509 systemd[1]: Started cri-containerd-96bf7669556d456bd6a69c86cbf6e85e2c3af949c5a2a42df341119c9133993c.scope - libcontainer container 96bf7669556d456bd6a69c86cbf6e85e2c3af949c5a2a42df341119c9133993c. May 8 00:07:53.222442 containerd[1501]: time="2025-05-08T00:07:53.222334910Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-kmcbf,Uid:2b9ebca1-bb8b-4171-acac-bb1c95d73f0d,Namespace:kube-system,Attempt:0,} returns sandbox id \"3870eed950f8c0327d5815017c2a243d159171535b0e0b451c767017923040f6\"" May 8 00:07:53.237504 containerd[1501]: time="2025-05-08T00:07:53.237458598Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.4\"" May 8 00:07:53.253206 containerd[1501]: time="2025-05-08T00:07:53.252893588Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-b9lw2,Uid:690c7041-d188-4c88-a5ed-38c917f39a5e,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"96bf7669556d456bd6a69c86cbf6e85e2c3af949c5a2a42df341119c9133993c\"" May 8 00:07:54.101003 kubelet[1838]: E0508 00:07:54.100901 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:07:54.382763 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount757400903.mount: Deactivated successfully. May 8 00:07:55.024684 containerd[1501]: time="2025-05-08T00:07:55.024614594Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.32.4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 8 00:07:55.025894 containerd[1501]: time="2025-05-08T00:07:55.025832612Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.32.4: active requests=0, bytes read=30919751" May 8 00:07:55.027327 containerd[1501]: time="2025-05-08T00:07:55.027260538Z" level=info msg="ImageCreate event name:\"sha256:608f0c8bf7f9651ca79f170235ea5eefb978a0c1da132e7477a88ad37d171ad3\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 8 00:07:55.029951 containerd[1501]: time="2025-05-08T00:07:55.029885635Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:152638222ecf265eb8e5352e3c50e8fc520994e8ffcff1ee1490c975f7fc2b36\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 8 00:07:55.030773 containerd[1501]: time="2025-05-08T00:07:55.030726578Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.32.4\" with image id \"sha256:608f0c8bf7f9651ca79f170235ea5eefb978a0c1da132e7477a88ad37d171ad3\", repo tag \"registry.k8s.io/kube-proxy:v1.32.4\", repo digest \"registry.k8s.io/kube-proxy@sha256:152638222ecf265eb8e5352e3c50e8fc520994e8ffcff1ee1490c975f7fc2b36\", size \"30916875\" in 1.792769931s" May 8 00:07:55.030880 containerd[1501]: time="2025-05-08T00:07:55.030779490Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.4\" returns image reference \"sha256:608f0c8bf7f9651ca79f170235ea5eefb978a0c1da132e7477a88ad37d171ad3\"" May 8 00:07:55.032851 containerd[1501]: time="2025-05-08T00:07:55.032637591Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" May 8 00:07:55.034239 containerd[1501]: time="2025-05-08T00:07:55.034041910Z" level=info msg="CreateContainer within sandbox \"3870eed950f8c0327d5815017c2a243d159171535b0e0b451c767017923040f6\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" May 8 00:07:55.054183 containerd[1501]: time="2025-05-08T00:07:55.054111501Z" level=info msg="CreateContainer within sandbox \"3870eed950f8c0327d5815017c2a243d159171535b0e0b451c767017923040f6\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"0b6466e7026dcbedb9218813ab6ca9dc67f2e28e02933b1d3ee75a1d21ff5940\"" May 8 00:07:55.054742 containerd[1501]: time="2025-05-08T00:07:55.054694630Z" level=info msg="StartContainer for \"0b6466e7026dcbedb9218813ab6ca9dc67f2e28e02933b1d3ee75a1d21ff5940\"" May 8 00:07:55.101015 systemd[1]: Started cri-containerd-0b6466e7026dcbedb9218813ab6ca9dc67f2e28e02933b1d3ee75a1d21ff5940.scope - libcontainer container 0b6466e7026dcbedb9218813ab6ca9dc67f2e28e02933b1d3ee75a1d21ff5940. May 8 00:07:55.101422 kubelet[1838]: E0508 00:07:55.101234 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:07:55.144141 containerd[1501]: time="2025-05-08T00:07:55.144064985Z" level=info msg="StartContainer for \"0b6466e7026dcbedb9218813ab6ca9dc67f2e28e02933b1d3ee75a1d21ff5940\" returns successfully" May 8 00:07:55.296710 kubelet[1838]: I0508 00:07:55.294596 1838 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-kmcbf" podStartSLOduration=3.493815921 podStartE2EDuration="5.294573747s" podCreationTimestamp="2025-05-08 00:07:50 +0000 UTC" firstStartedPulling="2025-05-08 00:07:53.23140146 +0000 UTC m=+3.859918256" lastFinishedPulling="2025-05-08 00:07:55.032159278 +0000 UTC m=+5.660676082" observedRunningTime="2025-05-08 00:07:55.294040938 +0000 UTC m=+5.922557752" watchObservedRunningTime="2025-05-08 00:07:55.294573747 +0000 UTC m=+5.923090556" May 8 00:07:56.101859 kubelet[1838]: E0508 00:07:56.101388 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:07:56.180743 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4195208247.mount: Deactivated successfully. May 8 00:07:56.230570 containerd[1501]: time="2025-05-08T00:07:56.230504726Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 8 00:07:56.231882 containerd[1501]: time="2025-05-08T00:07:56.231809808Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3852937" May 8 00:07:56.233220 containerd[1501]: time="2025-05-08T00:07:56.233157188Z" level=info msg="ImageCreate event name:\"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 8 00:07:56.235965 containerd[1501]: time="2025-05-08T00:07:56.235903376Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 8 00:07:56.237403 containerd[1501]: time="2025-05-08T00:07:56.237203925Z" 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.2045241s" May 8 00:07:56.237403 containerd[1501]: time="2025-05-08T00:07:56.237247995Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\"" May 8 00:07:56.240015 containerd[1501]: time="2025-05-08T00:07:56.239968193Z" level=info msg="CreateContainer within sandbox \"96bf7669556d456bd6a69c86cbf6e85e2c3af949c5a2a42df341119c9133993c\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" May 8 00:07:56.258017 containerd[1501]: time="2025-05-08T00:07:56.257963543Z" level=info msg="CreateContainer within sandbox \"96bf7669556d456bd6a69c86cbf6e85e2c3af949c5a2a42df341119c9133993c\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"8a3edb6f620a4082bc9ee44166fc446e2643b100a608cc8a3885b0de4eb3ced5\"" May 8 00:07:56.258598 containerd[1501]: time="2025-05-08T00:07:56.258566618Z" level=info msg="StartContainer for \"8a3edb6f620a4082bc9ee44166fc446e2643b100a608cc8a3885b0de4eb3ced5\"" May 8 00:07:56.300090 systemd[1]: Started cri-containerd-8a3edb6f620a4082bc9ee44166fc446e2643b100a608cc8a3885b0de4eb3ced5.scope - libcontainer container 8a3edb6f620a4082bc9ee44166fc446e2643b100a608cc8a3885b0de4eb3ced5. May 8 00:07:56.332487 containerd[1501]: time="2025-05-08T00:07:56.332435889Z" level=info msg="StartContainer for \"8a3edb6f620a4082bc9ee44166fc446e2643b100a608cc8a3885b0de4eb3ced5\" returns successfully" May 8 00:07:56.333764 systemd[1]: cri-containerd-8a3edb6f620a4082bc9ee44166fc446e2643b100a608cc8a3885b0de4eb3ced5.scope: Deactivated successfully. May 8 00:07:56.734725 containerd[1501]: time="2025-05-08T00:07:56.734636743Z" level=info msg="shim disconnected" id=8a3edb6f620a4082bc9ee44166fc446e2643b100a608cc8a3885b0de4eb3ced5 namespace=k8s.io May 8 00:07:56.734725 containerd[1501]: time="2025-05-08T00:07:56.734719949Z" level=warning msg="cleaning up after shim disconnected" id=8a3edb6f620a4082bc9ee44166fc446e2643b100a608cc8a3885b0de4eb3ced5 namespace=k8s.io May 8 00:07:56.734725 containerd[1501]: time="2025-05-08T00:07:56.734738029Z" level=info msg="cleaning up dead shim" namespace=k8s.io May 8 00:07:57.066104 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-8a3edb6f620a4082bc9ee44166fc446e2643b100a608cc8a3885b0de4eb3ced5-rootfs.mount: Deactivated successfully. May 8 00:07:57.101830 kubelet[1838]: E0508 00:07:57.101746 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:07:57.272855 containerd[1501]: time="2025-05-08T00:07:57.272796273Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" May 8 00:07:58.102662 kubelet[1838]: E0508 00:07:58.102593 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:07:58.134777 systemd[1]: Started sshd@5-10.128.0.69:22-218.92.0.195:19970.service - OpenSSH per-connection server daemon (218.92.0.195:19970). May 8 00:07:58.547255 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4271565529.mount: Deactivated successfully. May 8 00:07:59.103378 kubelet[1838]: E0508 00:07:59.103303 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:07:59.364077 sshd[2210]: PAM: Permission denied for root from 218.92.0.195 May 8 00:07:59.405715 containerd[1501]: time="2025-05-08T00:07:59.405651841Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 8 00:07:59.407276 containerd[1501]: time="2025-05-08T00:07:59.407214235Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26866358" May 8 00:07:59.408701 containerd[1501]: time="2025-05-08T00:07:59.408617238Z" level=info msg="ImageCreate event name:\"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 8 00:07:59.412486 containerd[1501]: time="2025-05-08T00:07:59.412421001Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 8 00:07:59.414234 containerd[1501]: time="2025-05-08T00:07:59.414180899Z" 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.140733538s" May 8 00:07:59.414234 containerd[1501]: time="2025-05-08T00:07:59.414223889Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\"" May 8 00:07:59.417242 containerd[1501]: time="2025-05-08T00:07:59.417183848Z" level=info msg="CreateContainer within sandbox \"96bf7669556d456bd6a69c86cbf6e85e2c3af949c5a2a42df341119c9133993c\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" May 8 00:07:59.436043 containerd[1501]: time="2025-05-08T00:07:59.436000005Z" level=info msg="CreateContainer within sandbox \"96bf7669556d456bd6a69c86cbf6e85e2c3af949c5a2a42df341119c9133993c\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"927cbda23b98181492af89e7858003587ff53bd63c7ad9f9a224fb21a19a0e2d\"" May 8 00:07:59.436866 containerd[1501]: time="2025-05-08T00:07:59.436826889Z" level=info msg="StartContainer for \"927cbda23b98181492af89e7858003587ff53bd63c7ad9f9a224fb21a19a0e2d\"" May 8 00:07:59.483104 systemd[1]: Started cri-containerd-927cbda23b98181492af89e7858003587ff53bd63c7ad9f9a224fb21a19a0e2d.scope - libcontainer container 927cbda23b98181492af89e7858003587ff53bd63c7ad9f9a224fb21a19a0e2d. May 8 00:07:59.517327 systemd[1]: cri-containerd-927cbda23b98181492af89e7858003587ff53bd63c7ad9f9a224fb21a19a0e2d.scope: Deactivated successfully. May 8 00:07:59.521067 containerd[1501]: time="2025-05-08T00:07:59.520999744Z" level=info msg="StartContainer for \"927cbda23b98181492af89e7858003587ff53bd63c7ad9f9a224fb21a19a0e2d\" returns successfully" May 8 00:07:59.547907 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-927cbda23b98181492af89e7858003587ff53bd63c7ad9f9a224fb21a19a0e2d-rootfs.mount: Deactivated successfully. May 8 00:07:59.561673 sshd[2210]: PAM: Permission denied for root from 218.92.0.195 May 8 00:07:59.581025 kubelet[1838]: I0508 00:07:59.574833 1838 kubelet_node_status.go:502] "Fast updating node status as it just became ready" May 8 00:07:59.761775 sshd[2210]: PAM: Permission denied for root from 218.92.0.195 May 8 00:07:59.764755 containerd[1501]: time="2025-05-08T00:07:59.764644256Z" level=info msg="shim disconnected" id=927cbda23b98181492af89e7858003587ff53bd63c7ad9f9a224fb21a19a0e2d namespace=k8s.io May 8 00:07:59.764755 containerd[1501]: time="2025-05-08T00:07:59.764722628Z" level=warning msg="cleaning up after shim disconnected" id=927cbda23b98181492af89e7858003587ff53bd63c7ad9f9a224fb21a19a0e2d namespace=k8s.io May 8 00:07:59.764755 containerd[1501]: time="2025-05-08T00:07:59.764737893Z" level=info msg="cleaning up dead shim" namespace=k8s.io May 8 00:07:59.956346 sshd[2210]: Received disconnect from 218.92.0.195 port 19970:11: [preauth] May 8 00:07:59.956346 sshd[2210]: Disconnected from authenticating user root 218.92.0.195 port 19970 [preauth] May 8 00:07:59.959463 systemd[1]: sshd@5-10.128.0.69:22-218.92.0.195:19970.service: Deactivated successfully. May 8 00:08:00.103689 kubelet[1838]: E0508 00:08:00.103541 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:08:00.286329 containerd[1501]: time="2025-05-08T00:08:00.286262221Z" level=info msg="CreateContainer within sandbox \"96bf7669556d456bd6a69c86cbf6e85e2c3af949c5a2a42df341119c9133993c\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" May 8 00:08:00.302777 containerd[1501]: time="2025-05-08T00:08:00.302715154Z" level=info msg="CreateContainer within sandbox \"96bf7669556d456bd6a69c86cbf6e85e2c3af949c5a2a42df341119c9133993c\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"13238f2e9a7f8672aa50918881c2c573d30fa17bb01961962eef8890f00a25f0\"" May 8 00:08:00.303365 containerd[1501]: time="2025-05-08T00:08:00.303270429Z" level=info msg="StartContainer for \"13238f2e9a7f8672aa50918881c2c573d30fa17bb01961962eef8890f00a25f0\"" May 8 00:08:00.337194 systemd[1]: Started cri-containerd-13238f2e9a7f8672aa50918881c2c573d30fa17bb01961962eef8890f00a25f0.scope - libcontainer container 13238f2e9a7f8672aa50918881c2c573d30fa17bb01961962eef8890f00a25f0. May 8 00:08:00.381927 containerd[1501]: time="2025-05-08T00:08:00.381553711Z" level=info msg="StartContainer for \"13238f2e9a7f8672aa50918881c2c573d30fa17bb01961962eef8890f00a25f0\" returns successfully" May 8 00:08:01.104745 kubelet[1838]: E0508 00:08:01.104672 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:08:01.456747 systemd-networkd[1410]: flannel.1: Link UP May 8 00:08:01.456763 systemd-networkd[1410]: flannel.1: Gained carrier May 8 00:08:02.105733 kubelet[1838]: E0508 00:08:02.105663 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:08:02.825131 systemd-networkd[1410]: flannel.1: Gained IPv6LL May 8 00:08:03.095853 kubelet[1838]: I0508 00:08:03.095663 1838 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-b9lw2" podStartSLOduration=6.934240315 podStartE2EDuration="13.095637873s" podCreationTimestamp="2025-05-08 00:07:50 +0000 UTC" firstStartedPulling="2025-05-08 00:07:53.254087693 +0000 UTC m=+3.882604488" lastFinishedPulling="2025-05-08 00:07:59.415485248 +0000 UTC m=+10.044002046" observedRunningTime="2025-05-08 00:08:01.312012227 +0000 UTC m=+11.940529035" watchObservedRunningTime="2025-05-08 00:08:03.095637873 +0000 UTC m=+13.724154656" May 8 00:08:03.103161 kubelet[1838]: I0508 00:08:03.102939 1838 status_manager.go:890] "Failed to get status for pod" podUID="6fc68733-726e-4e6f-8f46-70bd796df8a8" pod="default/nginx-deployment-7fcdb87857-md776" err="pods \"nginx-deployment-7fcdb87857-md776\" is forbidden: User \"system:node:10.128.0.69\" cannot get resource \"pods\" in API group \"\" in the namespace \"default\": no relationship found between node '10.128.0.69' and this object" May 8 00:08:03.103161 kubelet[1838]: W0508 00:08:03.103069 1838 reflector.go:569] object-"default"/"kube-root-ca.crt": failed to list *v1.ConfigMap: configmaps "kube-root-ca.crt" is forbidden: User "system:node:10.128.0.69" cannot list resource "configmaps" in API group "" in the namespace "default": no relationship found between node '10.128.0.69' and this object May 8 00:08:03.103161 kubelet[1838]: E0508 00:08:03.103105 1838 reflector.go:166] "Unhandled Error" err="object-\"default\"/\"kube-root-ca.crt\": Failed to watch *v1.ConfigMap: failed to list *v1.ConfigMap: configmaps \"kube-root-ca.crt\" is forbidden: User \"system:node:10.128.0.69\" cannot list resource \"configmaps\" in API group \"\" in the namespace \"default\": no relationship found between node '10.128.0.69' and this object" logger="UnhandledError" May 8 00:08:03.103504 systemd[1]: Created slice kubepods-besteffort-pod6fc68733_726e_4e6f_8f46_70bd796df8a8.slice - libcontainer container kubepods-besteffort-pod6fc68733_726e_4e6f_8f46_70bd796df8a8.slice. May 8 00:08:03.106869 kubelet[1838]: E0508 00:08:03.106801 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:08:03.208665 kubelet[1838]: I0508 00:08:03.208579 1838 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-fnjj8\" (UniqueName: \"kubernetes.io/projected/6fc68733-726e-4e6f-8f46-70bd796df8a8-kube-api-access-fnjj8\") pod \"nginx-deployment-7fcdb87857-md776\" (UID: \"6fc68733-726e-4e6f-8f46-70bd796df8a8\") " pod="default/nginx-deployment-7fcdb87857-md776" May 8 00:08:04.107503 kubelet[1838]: E0508 00:08:04.107430 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:08:04.318402 kubelet[1838]: E0508 00:08:04.318341 1838 projected.go:288] Couldn't get configMap default/kube-root-ca.crt: failed to sync configmap cache: timed out waiting for the condition May 8 00:08:04.318402 kubelet[1838]: E0508 00:08:04.318392 1838 projected.go:194] Error preparing data for projected volume kube-api-access-fnjj8 for pod default/nginx-deployment-7fcdb87857-md776: failed to sync configmap cache: timed out waiting for the condition May 8 00:08:04.318650 kubelet[1838]: E0508 00:08:04.318497 1838 nestedpendingoperations.go:348] Operation for "{volumeName:kubernetes.io/projected/6fc68733-726e-4e6f-8f46-70bd796df8a8-kube-api-access-fnjj8 podName:6fc68733-726e-4e6f-8f46-70bd796df8a8 nodeName:}" failed. No retries permitted until 2025-05-08 00:08:04.818469689 +0000 UTC m=+15.446986489 (durationBeforeRetry 500ms). Error: MountVolume.SetUp failed for volume "kube-api-access-fnjj8" (UniqueName: "kubernetes.io/projected/6fc68733-726e-4e6f-8f46-70bd796df8a8-kube-api-access-fnjj8") pod "nginx-deployment-7fcdb87857-md776" (UID: "6fc68733-726e-4e6f-8f46-70bd796df8a8") : failed to sync configmap cache: timed out waiting for the condition May 8 00:08:04.908854 containerd[1501]: time="2025-05-08T00:08:04.908766987Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-md776,Uid:6fc68733-726e-4e6f-8f46-70bd796df8a8,Namespace:default,Attempt:0,}" May 8 00:08:04.942358 systemd-networkd[1410]: cni0: Link UP May 8 00:08:04.942371 systemd-networkd[1410]: cni0: Gained carrier May 8 00:08:04.952058 systemd-networkd[1410]: cni0: Lost carrier May 8 00:08:04.957229 systemd-networkd[1410]: vetha6fcaa68: Link UP May 8 00:08:04.973386 kernel: cni0: port 1(vetha6fcaa68) entered blocking state May 8 00:08:04.973470 kernel: cni0: port 1(vetha6fcaa68) entered disabled state May 8 00:08:04.973505 kernel: vetha6fcaa68: entered allmulticast mode May 8 00:08:04.981839 kernel: vetha6fcaa68: entered promiscuous mode May 8 00:08:04.981922 kernel: cni0: port 1(vetha6fcaa68) entered blocking state May 8 00:08:04.993809 kernel: cni0: port 1(vetha6fcaa68) entered forwarding state May 8 00:08:05.000846 kernel: cni0: port 1(vetha6fcaa68) entered disabled state May 8 00:08:05.022301 kernel: cni0: port 1(vetha6fcaa68) entered blocking state May 8 00:08:05.022395 kernel: cni0: port 1(vetha6fcaa68) entered forwarding state May 8 00:08:05.022607 systemd-networkd[1410]: vetha6fcaa68: Gained carrier May 8 00:08:05.023783 systemd-networkd[1410]: cni0: Gained carrier May 8 00:08:05.026573 containerd[1501]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc000018938), "name":"cbr0", "type":"bridge"} May 8 00:08:05.026573 containerd[1501]: delegateAdd: netconf sent to delegate plugin: May 8 00:08:05.056715 containerd[1501]: {"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-05-08T00:08:05.056582943Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 8 00:08:05.056715 containerd[1501]: time="2025-05-08T00:08:05.056654086Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 8 00:08:05.056715 containerd[1501]: time="2025-05-08T00:08:05.056673798Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 8 00:08:05.057188 containerd[1501]: time="2025-05-08T00:08:05.056778823Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 8 00:08:05.086066 systemd[1]: Started cri-containerd-31a9e3e5ead0acb77fea8c88cde6d83f96a33ed1352d38f70f6cdcc749acd769.scope - libcontainer container 31a9e3e5ead0acb77fea8c88cde6d83f96a33ed1352d38f70f6cdcc749acd769. May 8 00:08:05.108134 kubelet[1838]: E0508 00:08:05.108096 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:08:05.137308 containerd[1501]: time="2025-05-08T00:08:05.137216116Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-md776,Uid:6fc68733-726e-4e6f-8f46-70bd796df8a8,Namespace:default,Attempt:0,} returns sandbox id \"31a9e3e5ead0acb77fea8c88cde6d83f96a33ed1352d38f70f6cdcc749acd769\"" May 8 00:08:05.138865 containerd[1501]: time="2025-05-08T00:08:05.138767077Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" May 8 00:08:06.109134 kubelet[1838]: E0508 00:08:06.109059 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:08:06.473010 systemd-networkd[1410]: vetha6fcaa68: Gained IPv6LL May 8 00:08:06.537127 systemd-networkd[1410]: cni0: Gained IPv6LL May 8 00:08:07.109888 kubelet[1838]: E0508 00:08:07.109840 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:08:07.660257 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2146797507.mount: Deactivated successfully. May 8 00:08:08.111853 kubelet[1838]: E0508 00:08:08.111123 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:08:09.112043 kubelet[1838]: E0508 00:08:09.111927 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:08:09.179479 containerd[1501]: time="2025-05-08T00:08:09.179394981Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 8 00:08:09.180931 containerd[1501]: time="2025-05-08T00:08:09.180858422Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=73306220" May 8 00:08:09.182302 containerd[1501]: time="2025-05-08T00:08:09.182229371Z" level=info msg="ImageCreate event name:\"sha256:7e2dd24abce21cd256091445aca4b7eb00774264c2b0a8714701dd7091509efa\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 8 00:08:09.185660 containerd[1501]: time="2025-05-08T00:08:09.185581493Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:beabce8f1782671ba500ddff99dd260fbf9c5ec85fb9c3162e35a3c40bafd023\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 8 00:08:09.187088 containerd[1501]: time="2025-05-08T00:08:09.186917087Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:7e2dd24abce21cd256091445aca4b7eb00774264c2b0a8714701dd7091509efa\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:beabce8f1782671ba500ddff99dd260fbf9c5ec85fb9c3162e35a3c40bafd023\", size \"73306098\" in 4.048025367s" May 8 00:08:09.187088 containerd[1501]: time="2025-05-08T00:08:09.186963949Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:7e2dd24abce21cd256091445aca4b7eb00774264c2b0a8714701dd7091509efa\"" May 8 00:08:09.189967 containerd[1501]: time="2025-05-08T00:08:09.189904188Z" level=info msg="CreateContainer within sandbox \"31a9e3e5ead0acb77fea8c88cde6d83f96a33ed1352d38f70f6cdcc749acd769\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" May 8 00:08:09.209804 containerd[1501]: time="2025-05-08T00:08:09.209744299Z" level=info msg="CreateContainer within sandbox \"31a9e3e5ead0acb77fea8c88cde6d83f96a33ed1352d38f70f6cdcc749acd769\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"d4513ca1e8778676ec1a22e5bcd7d9e12d31667a00be96154ab6d39b99d874f8\"" May 8 00:08:09.210731 containerd[1501]: time="2025-05-08T00:08:09.210688963Z" level=info msg="StartContainer for \"d4513ca1e8778676ec1a22e5bcd7d9e12d31667a00be96154ab6d39b99d874f8\"" May 8 00:08:09.261097 systemd[1]: Started cri-containerd-d4513ca1e8778676ec1a22e5bcd7d9e12d31667a00be96154ab6d39b99d874f8.scope - libcontainer container d4513ca1e8778676ec1a22e5bcd7d9e12d31667a00be96154ab6d39b99d874f8. May 8 00:08:09.300441 containerd[1501]: time="2025-05-08T00:08:09.300380359Z" level=info msg="StartContainer for \"d4513ca1e8778676ec1a22e5bcd7d9e12d31667a00be96154ab6d39b99d874f8\" returns successfully" May 8 00:08:09.466155 ntpd[1472]: Listen normally on 7 flannel.1 192.168.1.0:123 May 8 00:08:09.466856 ntpd[1472]: 8 May 00:08:09 ntpd[1472]: Listen normally on 7 flannel.1 192.168.1.0:123 May 8 00:08:09.466856 ntpd[1472]: 8 May 00:08:09 ntpd[1472]: Listen normally on 8 cni0 192.168.1.1:123 May 8 00:08:09.466856 ntpd[1472]: 8 May 00:08:09 ntpd[1472]: Listen normally on 9 flannel.1 [fe80::747f:a4ff:feb0:6318%3]:123 May 8 00:08:09.466856 ntpd[1472]: 8 May 00:08:09 ntpd[1472]: Listen normally on 10 cni0 [fe80::343a:69ff:feeb:47c5%4]:123 May 8 00:08:09.466856 ntpd[1472]: 8 May 00:08:09 ntpd[1472]: Listen normally on 11 vetha6fcaa68 [fe80::e0e1:c9ff:fe3c:e2d%5]:123 May 8 00:08:09.466263 ntpd[1472]: Listen normally on 8 cni0 192.168.1.1:123 May 8 00:08:09.466356 ntpd[1472]: Listen normally on 9 flannel.1 [fe80::747f:a4ff:feb0:6318%3]:123 May 8 00:08:09.466434 ntpd[1472]: Listen normally on 10 cni0 [fe80::343a:69ff:feeb:47c5%4]:123 May 8 00:08:09.466497 ntpd[1472]: Listen normally on 11 vetha6fcaa68 [fe80::e0e1:c9ff:fe3c:e2d%5]:123 May 8 00:08:10.094214 kubelet[1838]: E0508 00:08:10.094135 1838 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:08:10.112203 kubelet[1838]: E0508 00:08:10.112132 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:08:11.113228 kubelet[1838]: E0508 00:08:11.113151 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:08:11.444567 kubelet[1838]: I0508 00:08:11.444210 1838 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-md776" podStartSLOduration=4.394021146 podStartE2EDuration="8.444184519s" podCreationTimestamp="2025-05-08 00:08:03 +0000 UTC" firstStartedPulling="2025-05-08 00:08:05.138244087 +0000 UTC m=+15.766760883" lastFinishedPulling="2025-05-08 00:08:09.18840746 +0000 UTC m=+19.816924256" observedRunningTime="2025-05-08 00:08:09.333810559 +0000 UTC m=+19.962327368" watchObservedRunningTime="2025-05-08 00:08:11.444184519 +0000 UTC m=+22.072701340" May 8 00:08:11.452661 systemd[1]: Created slice kubepods-besteffort-podf0adff73_d84a_4d89_904e_8bf23023a186.slice - libcontainer container kubepods-besteffort-podf0adff73_d84a_4d89_904e_8bf23023a186.slice. May 8 00:08:11.462393 kubelet[1838]: I0508 00:08:11.462324 1838 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/f0adff73-d84a-4d89-904e-8bf23023a186-data\") pod \"nfs-server-provisioner-0\" (UID: \"f0adff73-d84a-4d89-904e-8bf23023a186\") " pod="default/nfs-server-provisioner-0" May 8 00:08:11.462393 kubelet[1838]: I0508 00:08:11.462380 1838 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-hs8ss\" (UniqueName: \"kubernetes.io/projected/f0adff73-d84a-4d89-904e-8bf23023a186-kube-api-access-hs8ss\") pod \"nfs-server-provisioner-0\" (UID: \"f0adff73-d84a-4d89-904e-8bf23023a186\") " pod="default/nfs-server-provisioner-0" May 8 00:08:11.757269 containerd[1501]: time="2025-05-08T00:08:11.757107623Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:f0adff73-d84a-4d89-904e-8bf23023a186,Namespace:default,Attempt:0,}" May 8 00:08:11.790309 systemd-networkd[1410]: veth11eddd33: Link UP May 8 00:08:11.808410 kernel: cni0: port 2(veth11eddd33) entered blocking state May 8 00:08:11.808522 kernel: cni0: port 2(veth11eddd33) entered disabled state May 8 00:08:11.808557 kernel: veth11eddd33: entered allmulticast mode May 8 00:08:11.817297 kernel: veth11eddd33: entered promiscuous mode May 8 00:08:11.833614 kernel: cni0: port 2(veth11eddd33) entered blocking state May 8 00:08:11.833913 kernel: cni0: port 2(veth11eddd33) entered forwarding state May 8 00:08:11.833707 systemd-networkd[1410]: veth11eddd33: Gained carrier May 8 00:08:11.836515 containerd[1501]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc000018938), "name":"cbr0", "type":"bridge"} May 8 00:08:11.836515 containerd[1501]: delegateAdd: netconf sent to delegate plugin: May 8 00:08:11.868639 containerd[1501]: {"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-05-08T00:08:11.868328444Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 8 00:08:11.868639 containerd[1501]: time="2025-05-08T00:08:11.868408219Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 8 00:08:11.868639 containerd[1501]: time="2025-05-08T00:08:11.868434349Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 8 00:08:11.868936 containerd[1501]: time="2025-05-08T00:08:11.868570963Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 8 00:08:11.908071 systemd[1]: Started cri-containerd-1e34a08f466341ca98135df4ed48f68f92208edb48a5a87dd117a0020a22983f.scope - libcontainer container 1e34a08f466341ca98135df4ed48f68f92208edb48a5a87dd117a0020a22983f. May 8 00:08:11.961686 containerd[1501]: time="2025-05-08T00:08:11.961616591Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:f0adff73-d84a-4d89-904e-8bf23023a186,Namespace:default,Attempt:0,} returns sandbox id \"1e34a08f466341ca98135df4ed48f68f92208edb48a5a87dd117a0020a22983f\"" May 8 00:08:11.963545 containerd[1501]: time="2025-05-08T00:08:11.963476559Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" May 8 00:08:12.114117 kubelet[1838]: E0508 00:08:12.113904 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:08:12.581221 systemd[1]: run-containerd-runc-k8s.io-1e34a08f466341ca98135df4ed48f68f92208edb48a5a87dd117a0020a22983f-runc.2dHT28.mount: Deactivated successfully. May 8 00:08:13.114643 kubelet[1838]: E0508 00:08:13.114578 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:08:13.577418 systemd-networkd[1410]: veth11eddd33: Gained IPv6LL May 8 00:08:13.584059 systemd[1]: systemd-hostnamed.service: Deactivated successfully. May 8 00:08:14.115658 kubelet[1838]: E0508 00:08:14.115574 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:08:14.298930 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1628902859.mount: Deactivated successfully. May 8 00:08:15.115775 kubelet[1838]: E0508 00:08:15.115719 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:08:16.117925 kubelet[1838]: E0508 00:08:16.117866 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:08:16.466496 ntpd[1472]: Listen normally on 12 veth11eddd33 [fe80::3c49:d9ff:fe54:2186%6]:123 May 8 00:08:16.467432 ntpd[1472]: 8 May 00:08:16 ntpd[1472]: Listen normally on 12 veth11eddd33 [fe80::3c49:d9ff:fe54:2186%6]:123 May 8 00:08:16.687978 containerd[1501]: time="2025-05-08T00:08:16.687906734Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 8 00:08:16.689525 containerd[1501]: time="2025-05-08T00:08:16.689462852Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=91045236" May 8 00:08:16.690848 containerd[1501]: time="2025-05-08T00:08:16.690769221Z" level=info msg="ImageCreate event name:\"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 8 00:08:16.694288 containerd[1501]: time="2025-05-08T00:08:16.694206017Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 8 00:08:16.696237 containerd[1501]: time="2025-05-08T00:08:16.695656393Z" level=info msg="Pulled image \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" with image id \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\", repo tag \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\", repo digest \"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\", size \"91036984\" in 4.73212734s" May 8 00:08:16.696237 containerd[1501]: time="2025-05-08T00:08:16.695707209Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\"" May 8 00:08:16.699075 containerd[1501]: time="2025-05-08T00:08:16.699024645Z" level=info msg="CreateContainer within sandbox \"1e34a08f466341ca98135df4ed48f68f92208edb48a5a87dd117a0020a22983f\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" May 8 00:08:16.719969 containerd[1501]: time="2025-05-08T00:08:16.719723298Z" level=info msg="CreateContainer within sandbox \"1e34a08f466341ca98135df4ed48f68f92208edb48a5a87dd117a0020a22983f\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"e1f58073ab3aec15dbd2351a55dce426898b5d77fa2668999f3c21ab7cf318b4\"" May 8 00:08:16.720800 containerd[1501]: time="2025-05-08T00:08:16.720743253Z" level=info msg="StartContainer for \"e1f58073ab3aec15dbd2351a55dce426898b5d77fa2668999f3c21ab7cf318b4\"" May 8 00:08:16.760051 systemd[1]: Started cri-containerd-e1f58073ab3aec15dbd2351a55dce426898b5d77fa2668999f3c21ab7cf318b4.scope - libcontainer container e1f58073ab3aec15dbd2351a55dce426898b5d77fa2668999f3c21ab7cf318b4. May 8 00:08:16.800655 containerd[1501]: time="2025-05-08T00:08:16.800489230Z" level=info msg="StartContainer for \"e1f58073ab3aec15dbd2351a55dce426898b5d77fa2668999f3c21ab7cf318b4\" returns successfully" May 8 00:08:17.119159 kubelet[1838]: E0508 00:08:17.118994 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:08:18.119608 kubelet[1838]: E0508 00:08:18.119528 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:08:19.119794 kubelet[1838]: E0508 00:08:19.119712 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:08:20.120838 kubelet[1838]: E0508 00:08:20.120748 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:08:21.120988 kubelet[1838]: E0508 00:08:21.120926 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:08:22.122077 kubelet[1838]: E0508 00:08:22.122003 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:08:23.122754 kubelet[1838]: E0508 00:08:23.122685 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:08:24.123325 kubelet[1838]: E0508 00:08:24.123243 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:08:25.124319 kubelet[1838]: E0508 00:08:25.124244 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:08:26.125024 kubelet[1838]: E0508 00:08:26.124942 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:08:26.757623 kubelet[1838]: I0508 00:08:26.757548 1838 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=11.023588106 podStartE2EDuration="15.757527832s" podCreationTimestamp="2025-05-08 00:08:11 +0000 UTC" firstStartedPulling="2025-05-08 00:08:11.963192207 +0000 UTC m=+22.591708988" lastFinishedPulling="2025-05-08 00:08:16.697131914 +0000 UTC m=+27.325648714" observedRunningTime="2025-05-08 00:08:17.350164024 +0000 UTC m=+27.978680806" watchObservedRunningTime="2025-05-08 00:08:26.757527832 +0000 UTC m=+37.386044665" May 8 00:08:26.765483 systemd[1]: Created slice kubepods-besteffort-podae308fe6_9a9c_4811_b636_fad263439cfe.slice - libcontainer container kubepods-besteffort-podae308fe6_9a9c_4811_b636_fad263439cfe.slice. May 8 00:08:26.869201 kubelet[1838]: I0508 00:08:26.869129 1838 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-24a19ce0-d685-48cb-b4bb-1090c33b6be5\" (UniqueName: \"kubernetes.io/nfs/ae308fe6-9a9c-4811-b636-fad263439cfe-pvc-24a19ce0-d685-48cb-b4bb-1090c33b6be5\") pod \"test-pod-1\" (UID: \"ae308fe6-9a9c-4811-b636-fad263439cfe\") " pod="default/test-pod-1" May 8 00:08:26.869201 kubelet[1838]: I0508 00:08:26.869202 1838 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-z67xv\" (UniqueName: \"kubernetes.io/projected/ae308fe6-9a9c-4811-b636-fad263439cfe-kube-api-access-z67xv\") pod \"test-pod-1\" (UID: \"ae308fe6-9a9c-4811-b636-fad263439cfe\") " pod="default/test-pod-1" May 8 00:08:27.010870 kernel: FS-Cache: Loaded May 8 00:08:27.093878 kernel: RPC: Registered named UNIX socket transport module. May 8 00:08:27.094045 kernel: RPC: Registered udp transport module. May 8 00:08:27.094081 kernel: RPC: Registered tcp transport module. May 8 00:08:27.098568 kernel: RPC: Registered tcp-with-tls transport module. May 8 00:08:27.104282 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. May 8 00:08:27.125591 kubelet[1838]: E0508 00:08:27.125500 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:08:27.383904 kernel: NFS: Registering the id_resolver key type May 8 00:08:27.384070 kernel: Key type id_resolver registered May 8 00:08:27.384124 kernel: Key type id_legacy registered May 8 00:08:27.496076 nfsidmap[2891]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'c.flatcar-212911.internal' May 8 00:08:27.508239 nfsidmap[2892]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'c.flatcar-212911.internal' May 8 00:08:27.670106 containerd[1501]: time="2025-05-08T00:08:27.670040096Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:ae308fe6-9a9c-4811-b636-fad263439cfe,Namespace:default,Attempt:0,}" May 8 00:08:27.703941 systemd-networkd[1410]: vethfb25cfbe: Link UP May 8 00:08:27.716838 kernel: cni0: port 3(vethfb25cfbe) entered blocking state May 8 00:08:27.716951 kernel: cni0: port 3(vethfb25cfbe) entered disabled state May 8 00:08:27.716987 kernel: vethfb25cfbe: entered allmulticast mode May 8 00:08:27.727408 kernel: vethfb25cfbe: entered promiscuous mode May 8 00:08:27.744384 kernel: cni0: port 3(vethfb25cfbe) entered blocking state May 8 00:08:27.744665 kernel: cni0: port 3(vethfb25cfbe) entered forwarding state May 8 00:08:27.745011 systemd-networkd[1410]: vethfb25cfbe: Gained carrier May 8 00:08:27.747049 containerd[1501]: 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"} May 8 00:08:27.747049 containerd[1501]: delegateAdd: netconf sent to delegate plugin: May 8 00:08:27.779781 containerd[1501]: {"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-05-08T00:08:27.779451841Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 8 00:08:27.779781 containerd[1501]: time="2025-05-08T00:08:27.779545847Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 8 00:08:27.779781 containerd[1501]: time="2025-05-08T00:08:27.779569612Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 8 00:08:27.779781 containerd[1501]: time="2025-05-08T00:08:27.779699208Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 8 00:08:27.810119 systemd[1]: Started cri-containerd-212e4d263c0eec2f2a6114c872938de67f7c1f5c228621bc7ad88c93077b2bc7.scope - libcontainer container 212e4d263c0eec2f2a6114c872938de67f7c1f5c228621bc7ad88c93077b2bc7. May 8 00:08:27.866694 containerd[1501]: time="2025-05-08T00:08:27.866645679Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:ae308fe6-9a9c-4811-b636-fad263439cfe,Namespace:default,Attempt:0,} returns sandbox id \"212e4d263c0eec2f2a6114c872938de67f7c1f5c228621bc7ad88c93077b2bc7\"" May 8 00:08:27.868334 containerd[1501]: time="2025-05-08T00:08:27.868030307Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" May 8 00:08:28.103805 containerd[1501]: time="2025-05-08T00:08:28.103654404Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 8 00:08:28.105198 containerd[1501]: time="2025-05-08T00:08:28.105129247Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" May 8 00:08:28.108492 containerd[1501]: time="2025-05-08T00:08:28.108438950Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:7e2dd24abce21cd256091445aca4b7eb00774264c2b0a8714701dd7091509efa\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:beabce8f1782671ba500ddff99dd260fbf9c5ec85fb9c3162e35a3c40bafd023\", size \"73306098\" in 240.364575ms" May 8 00:08:28.108492 containerd[1501]: time="2025-05-08T00:08:28.108484906Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:7e2dd24abce21cd256091445aca4b7eb00774264c2b0a8714701dd7091509efa\"" May 8 00:08:28.111071 containerd[1501]: time="2025-05-08T00:08:28.111034718Z" level=info msg="CreateContainer within sandbox \"212e4d263c0eec2f2a6114c872938de67f7c1f5c228621bc7ad88c93077b2bc7\" for container &ContainerMetadata{Name:test,Attempt:0,}" May 8 00:08:28.125785 kubelet[1838]: E0508 00:08:28.125742 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:08:28.129887 containerd[1501]: time="2025-05-08T00:08:28.129805602Z" level=info msg="CreateContainer within sandbox \"212e4d263c0eec2f2a6114c872938de67f7c1f5c228621bc7ad88c93077b2bc7\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"f5e4544518897aa2c141a9cacbddf61fa29228f42ee2d1248c04f13b76bcb601\"" May 8 00:08:28.130519 containerd[1501]: time="2025-05-08T00:08:28.130487397Z" level=info msg="StartContainer for \"f5e4544518897aa2c141a9cacbddf61fa29228f42ee2d1248c04f13b76bcb601\"" May 8 00:08:28.172031 systemd[1]: Started cri-containerd-f5e4544518897aa2c141a9cacbddf61fa29228f42ee2d1248c04f13b76bcb601.scope - libcontainer container f5e4544518897aa2c141a9cacbddf61fa29228f42ee2d1248c04f13b76bcb601. May 8 00:08:28.204651 containerd[1501]: time="2025-05-08T00:08:28.204603938Z" level=info msg="StartContainer for \"f5e4544518897aa2c141a9cacbddf61fa29228f42ee2d1248c04f13b76bcb601\" returns successfully" May 8 00:08:28.370673 kubelet[1838]: I0508 00:08:28.370496 1838 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=16.128768892 podStartE2EDuration="16.370475233s" podCreationTimestamp="2025-05-08 00:08:12 +0000 UTC" firstStartedPulling="2025-05-08 00:08:27.867632385 +0000 UTC m=+38.496149177" lastFinishedPulling="2025-05-08 00:08:28.109338731 +0000 UTC m=+38.737855518" observedRunningTime="2025-05-08 00:08:28.370356959 +0000 UTC m=+38.998873766" watchObservedRunningTime="2025-05-08 00:08:28.370475233 +0000 UTC m=+38.998992040" May 8 00:08:28.405404 update_engine[1487]: I20250508 00:08:28.405234 1487 update_attempter.cc:509] Updating boot flags... May 8 00:08:28.468434 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 42 scanned by (udev-worker) (2879) May 8 00:08:28.614883 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 42 scanned by (udev-worker) (3027) May 8 00:08:29.126838 kubelet[1838]: E0508 00:08:29.126728 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:08:29.769231 systemd-networkd[1410]: vethfb25cfbe: Gained IPv6LL May 8 00:08:30.094258 kubelet[1838]: E0508 00:08:30.094108 1838 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:08:30.127194 kubelet[1838]: E0508 00:08:30.127121 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:08:31.127844 kubelet[1838]: E0508 00:08:31.127743 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:08:32.128360 kubelet[1838]: E0508 00:08:32.128283 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:08:32.466218 ntpd[1472]: Listen normally on 13 vethfb25cfbe [fe80::c49c:91ff:fe49:26a%7]:123 May 8 00:08:32.466778 ntpd[1472]: 8 May 00:08:32 ntpd[1472]: Listen normally on 13 vethfb25cfbe [fe80::c49c:91ff:fe49:26a%7]:123 May 8 00:08:33.129110 kubelet[1838]: E0508 00:08:33.129019 1838 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"