May 14 23:59:34.100809 kernel: Linux version 6.6.89-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 14 22:19:37 -00 2025 May 14 23:59:34.100854 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=23d816f2beca10c7a75ccdd203c170f89f29125f08ff6f3fdf90f8fa61b342cc May 14 23:59:34.100873 kernel: BIOS-provided physical RAM map: May 14 23:59:34.100887 kernel: BIOS-e820: [mem 0x0000000000000000-0x0000000000000fff] reserved May 14 23:59:34.100901 kernel: BIOS-e820: [mem 0x0000000000001000-0x0000000000054fff] usable May 14 23:59:34.100915 kernel: BIOS-e820: [mem 0x0000000000055000-0x000000000005ffff] reserved May 14 23:59:34.100932 kernel: BIOS-e820: [mem 0x0000000000060000-0x0000000000097fff] usable May 14 23:59:34.100947 kernel: BIOS-e820: [mem 0x0000000000098000-0x000000000009ffff] reserved May 14 23:59:34.100966 kernel: BIOS-e820: [mem 0x0000000000100000-0x00000000bd326fff] usable May 14 23:59:34.100980 kernel: BIOS-e820: [mem 0x00000000bd327000-0x00000000bd32efff] ACPI data May 14 23:59:34.100995 kernel: BIOS-e820: [mem 0x00000000bd32f000-0x00000000bf8ecfff] usable May 14 23:59:34.101010 kernel: BIOS-e820: [mem 0x00000000bf8ed000-0x00000000bfb6cfff] reserved May 14 23:59:34.101025 kernel: BIOS-e820: [mem 0x00000000bfb6d000-0x00000000bfb7efff] ACPI data May 14 23:59:34.101040 kernel: BIOS-e820: [mem 0x00000000bfb7f000-0x00000000bfbfefff] ACPI NVS May 14 23:59:34.101070 kernel: BIOS-e820: [mem 0x00000000bfbff000-0x00000000bffdffff] usable May 14 23:59:34.101087 kernel: BIOS-e820: [mem 0x00000000bffe0000-0x00000000bfffffff] reserved May 14 23:59:34.101103 kernel: BIOS-e820: [mem 0x0000000100000000-0x000000021fffffff] usable May 14 23:59:34.101120 kernel: NX (Execute Disable) protection: active May 14 23:59:34.101136 kernel: APIC: Static calls initialized May 14 23:59:34.101152 kernel: efi: EFI v2.7 by EDK II May 14 23:59:34.101168 kernel: efi: TPMFinalLog=0xbfbf7000 ACPI=0xbfb7e000 ACPI 2.0=0xbfb7e014 SMBIOS=0xbf9e8000 RNG=0xbfb73018 TPMEventLog=0xbd327018 May 14 23:59:34.101185 kernel: random: crng init done May 14 23:59:34.101201 kernel: secureboot: Secure boot disabled May 14 23:59:34.101217 kernel: SMBIOS 2.4 present. May 14 23:59:34.101237 kernel: DMI: Google Google Compute Engine/Google Compute Engine, BIOS Google 02/12/2025 May 14 23:59:34.101254 kernel: Hypervisor detected: KVM May 14 23:59:34.101270 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 May 14 23:59:34.102432 kernel: kvm-clock: using sched offset of 12955455525 cycles May 14 23:59:34.102454 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns May 14 23:59:34.102471 kernel: tsc: Detected 2299.998 MHz processor May 14 23:59:34.102489 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved May 14 23:59:34.102506 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable May 14 23:59:34.102523 kernel: last_pfn = 0x220000 max_arch_pfn = 0x400000000 May 14 23:59:34.102540 kernel: MTRR map: 3 entries (2 fixed + 1 variable; max 18), built from 8 variable MTRRs May 14 23:59:34.102564 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT May 14 23:59:34.102581 kernel: last_pfn = 0xbffe0 max_arch_pfn = 0x400000000 May 14 23:59:34.102598 kernel: Using GB pages for direct mapping May 14 23:59:34.102615 kernel: ACPI: Early table checksum verification disabled May 14 23:59:34.102631 kernel: ACPI: RSDP 0x00000000BFB7E014 000024 (v02 Google) May 14 23:59:34.102649 kernel: ACPI: XSDT 0x00000000BFB7D0E8 00005C (v01 Google GOOGFACP 00000001 01000013) May 14 23:59:34.102674 kernel: ACPI: FACP 0x00000000BFB78000 0000F4 (v02 Google GOOGFACP 00000001 GOOG 00000001) May 14 23:59:34.102696 kernel: ACPI: DSDT 0x00000000BFB79000 001A64 (v01 Google GOOGDSDT 00000001 GOOG 00000001) May 14 23:59:34.102713 kernel: ACPI: FACS 0x00000000BFBF2000 000040 May 14 23:59:34.102731 kernel: ACPI: SSDT 0x00000000BFB7C000 000316 (v02 GOOGLE Tpm2Tabl 00001000 INTL 20240322) May 14 23:59:34.102748 kernel: ACPI: TPM2 0x00000000BFB7B000 000034 (v04 GOOGLE 00000001 GOOG 00000001) May 14 23:59:34.102767 kernel: ACPI: SRAT 0x00000000BFB77000 0000C8 (v03 Google GOOGSRAT 00000001 GOOG 00000001) May 14 23:59:34.102785 kernel: ACPI: APIC 0x00000000BFB76000 000076 (v05 Google GOOGAPIC 00000001 GOOG 00000001) May 14 23:59:34.102803 kernel: ACPI: SSDT 0x00000000BFB75000 000980 (v01 Google GOOGSSDT 00000001 GOOG 00000001) May 14 23:59:34.102825 kernel: ACPI: WAET 0x00000000BFB74000 000028 (v01 Google GOOGWAET 00000001 GOOG 00000001) May 14 23:59:34.102843 kernel: ACPI: Reserving FACP table memory at [mem 0xbfb78000-0xbfb780f3] May 14 23:59:34.102860 kernel: ACPI: Reserving DSDT table memory at [mem 0xbfb79000-0xbfb7aa63] May 14 23:59:34.102878 kernel: ACPI: Reserving FACS table memory at [mem 0xbfbf2000-0xbfbf203f] May 14 23:59:34.102896 kernel: ACPI: Reserving SSDT table memory at [mem 0xbfb7c000-0xbfb7c315] May 14 23:59:34.102914 kernel: ACPI: Reserving TPM2 table memory at [mem 0xbfb7b000-0xbfb7b033] May 14 23:59:34.102932 kernel: ACPI: Reserving SRAT table memory at [mem 0xbfb77000-0xbfb770c7] May 14 23:59:34.102949 kernel: ACPI: Reserving APIC table memory at [mem 0xbfb76000-0xbfb76075] May 14 23:59:34.102966 kernel: ACPI: Reserving SSDT table memory at [mem 0xbfb75000-0xbfb7597f] May 14 23:59:34.102988 kernel: ACPI: Reserving WAET table memory at [mem 0xbfb74000-0xbfb74027] May 14 23:59:34.103006 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0 May 14 23:59:34.103024 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0 May 14 23:59:34.103042 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x0009ffff] May 14 23:59:34.103060 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00100000-0xbfffffff] May 14 23:59:34.103084 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0x21fffffff] May 14 23:59:34.103103 kernel: NUMA: Node 0 [mem 0x00000000-0x0009ffff] + [mem 0x00100000-0xbfffffff] -> [mem 0x00000000-0xbfffffff] May 14 23:59:34.103121 kernel: NUMA: Node 0 [mem 0x00000000-0xbfffffff] + [mem 0x100000000-0x21fffffff] -> [mem 0x00000000-0x21fffffff] May 14 23:59:34.103139 kernel: NODE_DATA(0) allocated [mem 0x21fffa000-0x21fffffff] May 14 23:59:34.103162 kernel: Zone ranges: May 14 23:59:34.103178 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] May 14 23:59:34.103195 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] May 14 23:59:34.103213 kernel: Normal [mem 0x0000000100000000-0x000000021fffffff] May 14 23:59:34.103231 kernel: Movable zone start for each node May 14 23:59:34.103249 kernel: Early memory node ranges May 14 23:59:34.103267 kernel: node 0: [mem 0x0000000000001000-0x0000000000054fff] May 14 23:59:34.103306 kernel: node 0: [mem 0x0000000000060000-0x0000000000097fff] May 14 23:59:34.103324 kernel: node 0: [mem 0x0000000000100000-0x00000000bd326fff] May 14 23:59:34.103346 kernel: node 0: [mem 0x00000000bd32f000-0x00000000bf8ecfff] May 14 23:59:34.103364 kernel: node 0: [mem 0x00000000bfbff000-0x00000000bffdffff] May 14 23:59:34.103382 kernel: node 0: [mem 0x0000000100000000-0x000000021fffffff] May 14 23:59:34.103400 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000021fffffff] May 14 23:59:34.103418 kernel: On node 0, zone DMA: 1 pages in unavailable ranges May 14 23:59:34.103436 kernel: On node 0, zone DMA: 11 pages in unavailable ranges May 14 23:59:34.103453 kernel: On node 0, zone DMA: 104 pages in unavailable ranges May 14 23:59:34.103471 kernel: On node 0, zone DMA32: 8 pages in unavailable ranges May 14 23:59:34.103489 kernel: On node 0, zone DMA32: 786 pages in unavailable ranges May 14 23:59:34.103511 kernel: On node 0, zone Normal: 32 pages in unavailable ranges May 14 23:59:34.103529 kernel: ACPI: PM-Timer IO Port: 0xb008 May 14 23:59:34.103546 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) May 14 23:59:34.103564 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 May 14 23:59:34.103582 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) May 14 23:59:34.103599 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) May 14 23:59:34.103616 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) May 14 23:59:34.103634 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) May 14 23:59:34.103652 kernel: ACPI: Using ACPI (MADT) for SMP configuration information May 14 23:59:34.103674 kernel: smpboot: Allowing 2 CPUs, 0 hotplug CPUs May 14 23:59:34.103690 kernel: [mem 0xc0000000-0xffffffff] available for PCI devices May 14 23:59:34.103708 kernel: Booting paravirtualized kernel on KVM May 14 23:59:34.103724 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns May 14 23:59:34.103741 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 May 14 23:59:34.103757 kernel: percpu: Embedded 58 pages/cpu s197032 r8192 d32344 u1048576 May 14 23:59:34.103774 kernel: pcpu-alloc: s197032 r8192 d32344 u1048576 alloc=1*2097152 May 14 23:59:34.103788 kernel: pcpu-alloc: [0] 0 1 May 14 23:59:34.103810 kernel: kvm-guest: PV spinlocks enabled May 14 23:59:34.103838 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) May 14 23:59:34.103861 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=23d816f2beca10c7a75ccdd203c170f89f29125f08ff6f3fdf90f8fa61b342cc May 14 23:59:34.103879 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. May 14 23:59:34.103896 kernel: Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear) May 14 23:59:34.103913 kernel: Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) May 14 23:59:34.103930 kernel: Fallback order for Node 0: 0 May 14 23:59:34.103947 kernel: Built 1 zonelists, mobility grouping on. Total pages: 1932272 May 14 23:59:34.103965 kernel: Policy zone: Normal May 14 23:59:34.103986 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off May 14 23:59:34.104003 kernel: software IO TLB: area num 2. May 14 23:59:34.104021 kernel: Memory: 7511320K/7860552K available (14336K kernel code, 2295K rwdata, 22864K rodata, 43484K init, 1592K bss, 348976K reserved, 0K cma-reserved) May 14 23:59:34.104038 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 May 14 23:59:34.104055 kernel: Kernel/User page tables isolation: enabled May 14 23:59:34.104084 kernel: ftrace: allocating 37918 entries in 149 pages May 14 23:59:34.104101 kernel: ftrace: allocated 149 pages with 4 groups May 14 23:59:34.104119 kernel: Dynamic Preempt: voluntary May 14 23:59:34.104154 kernel: rcu: Preemptible hierarchical RCU implementation. May 14 23:59:34.104173 kernel: rcu: RCU event tracing is enabled. May 14 23:59:34.104192 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. May 14 23:59:34.104210 kernel: Trampoline variant of Tasks RCU enabled. May 14 23:59:34.104232 kernel: Rude variant of Tasks RCU enabled. May 14 23:59:34.104250 kernel: Tracing variant of Tasks RCU enabled. May 14 23:59:34.104268 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. May 14 23:59:34.104550 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 May 14 23:59:34.104572 kernel: NR_IRQS: 33024, nr_irqs: 440, preallocated irqs: 16 May 14 23:59:34.104596 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. May 14 23:59:34.104771 kernel: Console: colour dummy device 80x25 May 14 23:59:34.104791 kernel: printk: console [ttyS0] enabled May 14 23:59:34.104809 kernel: ACPI: Core revision 20230628 May 14 23:59:34.104828 kernel: APIC: Switch to symmetric I/O mode setup May 14 23:59:34.104974 kernel: x2apic enabled May 14 23:59:34.104993 kernel: APIC: Switched APIC routing to: physical x2apic May 14 23:59:34.105011 kernel: ..TIMER: vector=0x30 apic1=0 pin1=0 apic2=-1 pin2=-1 May 14 23:59:34.105029 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x212733415c7, max_idle_ns: 440795236380 ns May 14 23:59:34.105185 kernel: Calibrating delay loop (skipped) preset value.. 4599.99 BogoMIPS (lpj=2299998) May 14 23:59:34.105204 kernel: Last level iTLB entries: 4KB 1024, 2MB 1024, 4MB 1024 May 14 23:59:34.105222 kernel: Last level dTLB entries: 4KB 1024, 2MB 1024, 4MB 1024, 1GB 4 May 14 23:59:34.105241 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization May 14 23:59:34.105259 kernel: Spectre V2 : Spectre BHI mitigation: SW BHB clearing on vm exit May 14 23:59:34.105419 kernel: Spectre V2 : Spectre BHI mitigation: SW BHB clearing on syscall May 14 23:59:34.105438 kernel: Spectre V2 : Mitigation: IBRS May 14 23:59:34.105456 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT May 14 23:59:34.105474 kernel: RETBleed: Mitigation: IBRS May 14 23:59:34.105621 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier May 14 23:59:34.105640 kernel: Spectre V2 : User space: Mitigation: STIBP via prctl May 14 23:59:34.105658 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl May 14 23:59:34.105677 kernel: MDS: Mitigation: Clear CPU buffers May 14 23:59:34.105695 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode May 14 23:59:34.105713 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' May 14 23:59:34.105731 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' May 14 23:59:34.105750 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' May 14 23:59:34.105772 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 May 14 23:59:34.105791 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. May 14 23:59:34.105810 kernel: Freeing SMP alternatives memory: 32K May 14 23:59:34.105828 kernel: pid_max: default: 32768 minimum: 301 May 14 23:59:34.105846 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity May 14 23:59:34.105864 kernel: landlock: Up and running. May 14 23:59:34.105882 kernel: SELinux: Initializing. May 14 23:59:34.105900 kernel: Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, linear) May 14 23:59:34.105918 kernel: Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, linear) May 14 23:59:34.105941 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU @ 2.30GHz (family: 0x6, model: 0x3f, stepping: 0x0) May 14 23:59:34.105959 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. May 14 23:59:34.105977 kernel: RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. May 14 23:59:34.105995 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. May 14 23:59:34.106014 kernel: Performance Events: unsupported p6 CPU model 63 no PMU driver, software events only. May 14 23:59:34.106032 kernel: signal: max sigframe size: 1776 May 14 23:59:34.106050 kernel: rcu: Hierarchical SRCU implementation. May 14 23:59:34.106075 kernel: rcu: Max phase no-delay instances is 400. May 14 23:59:34.106097 kernel: NMI watchdog: Perf NMI watchdog permanently disabled May 14 23:59:34.106119 kernel: smp: Bringing up secondary CPUs ... May 14 23:59:34.106137 kernel: smpboot: x86: Booting SMP configuration: May 14 23:59:34.106155 kernel: .... node #0, CPUs: #1 May 14 23:59:34.106175 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 14 23:59:34.106195 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 14 23:59:34.106213 kernel: smp: Brought up 1 node, 2 CPUs May 14 23:59:34.106231 kernel: smpboot: Max logical packages: 1 May 14 23:59:34.106254 kernel: smpboot: Total of 2 processors activated (9199.99 BogoMIPS) May 14 23:59:34.106272 kernel: devtmpfs: initialized May 14 23:59:34.106305 kernel: x86/mm: Memory block size: 128MB May 14 23:59:34.106324 kernel: ACPI: PM: Registering ACPI NVS region [mem 0xbfb7f000-0xbfbfefff] (524288 bytes) May 14 23:59:34.106343 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns May 14 23:59:34.106361 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) May 14 23:59:34.106380 kernel: pinctrl core: initialized pinctrl subsystem May 14 23:59:34.106398 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family May 14 23:59:34.106416 kernel: audit: initializing netlink subsys (disabled) May 14 23:59:34.106435 kernel: audit: type=2000 audit(1747267173.279:1): state=initialized audit_enabled=0 res=1 May 14 23:59:34.106457 kernel: thermal_sys: Registered thermal governor 'step_wise' May 14 23:59:34.106476 kernel: thermal_sys: Registered thermal governor 'user_space' May 14 23:59:34.106494 kernel: cpuidle: using governor menu May 14 23:59:34.106512 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 May 14 23:59:34.106530 kernel: dca service started, version 1.12.1 May 14 23:59:34.106549 kernel: PCI: Using configuration type 1 for base access May 14 23:59:34.106568 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. May 14 23:59:34.106586 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages May 14 23:59:34.106604 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page May 14 23:59:34.106626 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages May 14 23:59:34.106645 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page May 14 23:59:34.106662 kernel: ACPI: Added _OSI(Module Device) May 14 23:59:34.106681 kernel: ACPI: Added _OSI(Processor Device) May 14 23:59:34.106699 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) May 14 23:59:34.106717 kernel: ACPI: Added _OSI(Processor Aggregator Device) May 14 23:59:34.106735 kernel: ACPI: 3 ACPI AML tables successfully acquired and loaded May 14 23:59:34.106753 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC May 14 23:59:34.106771 kernel: ACPI: Interpreter enabled May 14 23:59:34.106789 kernel: ACPI: PM: (supports S0 S3 S5) May 14 23:59:34.106811 kernel: ACPI: Using IOAPIC for interrupt routing May 14 23:59:34.106829 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug May 14 23:59:34.106847 kernel: PCI: Ignoring E820 reservations for host bridge windows May 14 23:59:34.106865 kernel: ACPI: Enabled 16 GPEs in block 00 to 0F May 14 23:59:34.106883 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) May 14 23:59:34.107153 kernel: acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] May 14 23:59:34.107365 kernel: acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] May 14 23:59:34.107551 kernel: acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended configuration space under this bridge May 14 23:59:34.107573 kernel: PCI host bridge to bus 0000:00 May 14 23:59:34.107744 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] May 14 23:59:34.107908 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] May 14 23:59:34.108075 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] May 14 23:59:34.108237 kernel: pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfefff window] May 14 23:59:34.110789 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] May 14 23:59:34.111013 kernel: pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 May 14 23:59:34.111254 kernel: pci 0000:00:01.0: [8086:7110] type 00 class 0x060100 May 14 23:59:34.111844 kernel: pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 May 14 23:59:34.112482 kernel: pci 0000:00:01.3: quirk: [io 0xb000-0xb03f] claimed by PIIX4 ACPI May 14 23:59:34.112943 kernel: pci 0000:00:03.0: [1af4:1004] type 00 class 0x000000 May 14 23:59:34.113144 kernel: pci 0000:00:03.0: reg 0x10: [io 0xc040-0xc07f] May 14 23:59:34.113414 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xc0001000-0xc000107f] May 14 23:59:34.113630 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 May 14 23:59:34.113817 kernel: pci 0000:00:04.0: reg 0x10: [io 0xc000-0xc03f] May 14 23:59:34.114006 kernel: pci 0000:00:04.0: reg 0x14: [mem 0xc0000000-0xc000007f] May 14 23:59:34.114226 kernel: pci 0000:00:05.0: [1af4:1005] type 00 class 0x00ff00 May 14 23:59:34.114444 kernel: pci 0000:00:05.0: reg 0x10: [io 0xc080-0xc09f] May 14 23:59:34.114643 kernel: pci 0000:00:05.0: reg 0x14: [mem 0xc0002000-0xc000203f] May 14 23:59:34.114674 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 May 14 23:59:34.114694 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 May 14 23:59:34.114713 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 May 14 23:59:34.114732 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 May 14 23:59:34.114750 kernel: ACPI: PCI: Interrupt link LNKS configured for IRQ 9 May 14 23:59:34.114769 kernel: iommu: Default domain type: Translated May 14 23:59:34.114788 kernel: iommu: DMA domain TLB invalidation policy: lazy mode May 14 23:59:34.114806 kernel: efivars: Registered efivars operations May 14 23:59:34.114824 kernel: PCI: Using ACPI for IRQ routing May 14 23:59:34.114849 kernel: PCI: pci_cache_line_size set to 64 bytes May 14 23:59:34.114866 kernel: e820: reserve RAM buffer [mem 0x00055000-0x0005ffff] May 14 23:59:34.114885 kernel: e820: reserve RAM buffer [mem 0x00098000-0x0009ffff] May 14 23:59:34.114904 kernel: e820: reserve RAM buffer [mem 0xbd327000-0xbfffffff] May 14 23:59:34.114923 kernel: e820: reserve RAM buffer [mem 0xbf8ed000-0xbfffffff] May 14 23:59:34.114940 kernel: e820: reserve RAM buffer [mem 0xbffe0000-0xbfffffff] May 14 23:59:34.114959 kernel: vgaarb: loaded May 14 23:59:34.114978 kernel: clocksource: Switched to clocksource kvm-clock May 14 23:59:34.114996 kernel: VFS: Disk quotas dquot_6.6.0 May 14 23:59:34.115020 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) May 14 23:59:34.115039 kernel: pnp: PnP ACPI init May 14 23:59:34.115057 kernel: pnp: PnP ACPI: found 7 devices May 14 23:59:34.115086 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns May 14 23:59:34.115105 kernel: NET: Registered PF_INET protocol family May 14 23:59:34.115124 kernel: IP idents hash table entries: 131072 (order: 8, 1048576 bytes, linear) May 14 23:59:34.115143 kernel: tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, linear) May 14 23:59:34.115162 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) May 14 23:59:34.115180 kernel: TCP established hash table entries: 65536 (order: 7, 524288 bytes, linear) May 14 23:59:34.115204 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) May 14 23:59:34.115222 kernel: TCP: Hash tables configured (established 65536 bind 65536) May 14 23:59:34.115241 kernel: UDP hash table entries: 4096 (order: 5, 131072 bytes, linear) May 14 23:59:34.115260 kernel: UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, linear) May 14 23:59:34.115278 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family May 14 23:59:34.116890 kernel: NET: Registered PF_XDP protocol family May 14 23:59:34.117100 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] May 14 23:59:34.117274 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] May 14 23:59:34.117555 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] May 14 23:59:34.117722 kernel: pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfefff window] May 14 23:59:34.117919 kernel: pci 0000:00:00.0: Limiting direct PCI/PCI transfers May 14 23:59:34.117945 kernel: PCI: CLS 0 bytes, default 64 May 14 23:59:34.117964 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) May 14 23:59:34.117982 kernel: software IO TLB: mapped [mem 0x00000000b7f7f000-0x00000000bbf7f000] (64MB) May 14 23:59:34.118001 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer May 14 23:59:34.118026 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x212733415c7, max_idle_ns: 440795236380 ns May 14 23:59:34.118044 kernel: clocksource: Switched to clocksource tsc May 14 23:59:34.118072 kernel: Initialise system trusted keyrings May 14 23:59:34.118091 kernel: workingset: timestamp_bits=39 max_order=21 bucket_order=0 May 14 23:59:34.118109 kernel: Key type asymmetric registered May 14 23:59:34.118127 kernel: Asymmetric key parser 'x509' registered May 14 23:59:34.118146 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) May 14 23:59:34.118164 kernel: io scheduler mq-deadline registered May 14 23:59:34.118182 kernel: io scheduler kyber registered May 14 23:59:34.118201 kernel: io scheduler bfq registered May 14 23:59:34.118223 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 May 14 23:59:34.118243 kernel: ACPI: \_SB_.LNKC: Enabled at IRQ 11 May 14 23:59:34.118956 kernel: virtio-pci 0000:00:03.0: virtio_pci: leaving for legacy driver May 14 23:59:34.118988 kernel: ACPI: \_SB_.LNKD: Enabled at IRQ 10 May 14 23:59:34.120409 kernel: virtio-pci 0000:00:04.0: virtio_pci: leaving for legacy driver May 14 23:59:34.120443 kernel: ACPI: \_SB_.LNKA: Enabled at IRQ 10 May 14 23:59:34.120646 kernel: virtio-pci 0000:00:05.0: virtio_pci: leaving for legacy driver May 14 23:59:34.120672 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled May 14 23:59:34.120698 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A May 14 23:59:34.120715 kernel: 00:04: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A May 14 23:59:34.120732 kernel: 00:05: ttyS2 at I/O 0x3e8 (irq = 6, base_baud = 115200) is a 16550A May 14 23:59:34.120751 kernel: 00:06: ttyS3 at I/O 0x2e8 (irq = 7, base_baud = 115200) is a 16550A May 14 23:59:34.120960 kernel: tpm_tis MSFT0101:00: 2.0 TPM (device-id 0x9009, rev-id 0) May 14 23:59:34.120987 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 May 14 23:59:34.121005 kernel: i8042: Warning: Keylock active May 14 23:59:34.121024 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 May 14 23:59:34.121048 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 May 14 23:59:34.121264 kernel: rtc_cmos 00:00: RTC can wake from S4 May 14 23:59:34.121469 kernel: rtc_cmos 00:00: registered as rtc0 May 14 23:59:34.121658 kernel: rtc_cmos 00:00: setting system clock to 2025-05-14T23:59:33 UTC (1747267173) May 14 23:59:34.121841 kernel: rtc_cmos 00:00: alarms up to one day, 114 bytes nvram May 14 23:59:34.121871 kernel: intel_pstate: CPU model not supported May 14 23:59:34.121889 kernel: pstore: Using crash dump compression: deflate May 14 23:59:34.121908 kernel: pstore: Registered efi_pstore as persistent store backend May 14 23:59:34.121933 kernel: NET: Registered PF_INET6 protocol family May 14 23:59:34.121949 kernel: Segment Routing with IPv6 May 14 23:59:34.121967 kernel: In-situ OAM (IOAM) with IPv6 May 14 23:59:34.121985 kernel: NET: Registered PF_PACKET protocol family May 14 23:59:34.122003 kernel: Key type dns_resolver registered May 14 23:59:34.122019 kernel: IPI shorthand broadcast: enabled May 14 23:59:34.122038 kernel: sched_clock: Marking stable (844004208, 159014787)->(1030312802, -27293807) May 14 23:59:34.122058 kernel: registered taskstats version 1 May 14 23:59:34.122085 kernel: Loading compiled-in X.509 certificates May 14 23:59:34.122109 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.89-flatcar: e21d6dc0691a7e1e8bef90d9217bc8c09d6860f3' May 14 23:59:34.122126 kernel: Key type .fscrypt registered May 14 23:59:34.122145 kernel: Key type fscrypt-provisioning registered May 14 23:59:34.122162 kernel: ima: Allocated hash algorithm: sha1 May 14 23:59:34.122181 kernel: ima: No architecture policies found May 14 23:59:34.122199 kernel: clk: Disabling unused clocks May 14 23:59:34.122219 kernel: Freeing unused kernel image (initmem) memory: 43484K May 14 23:59:34.122238 kernel: Write protecting the kernel read-only data: 38912k May 14 23:59:34.122257 kernel: Freeing unused kernel image (rodata/data gap) memory: 1712K May 14 23:59:34.125311 kernel: Run /init as init process May 14 23:59:34.125341 kernel: with arguments: May 14 23:59:34.125360 kernel: /init May 14 23:59:34.125380 kernel: with environment: May 14 23:59:34.125398 kernel: HOME=/ May 14 23:59:34.125418 kernel: TERM=linux May 14 23:59:34.125437 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a May 14 23:59:34.125456 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 May 14 23:59:34.125478 systemd[1]: Successfully made /usr/ read-only. May 14 23:59:34.125509 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 14 23:59:34.125530 systemd[1]: Detected virtualization google. May 14 23:59:34.125550 systemd[1]: Detected architecture x86-64. May 14 23:59:34.125569 systemd[1]: Running in initrd. May 14 23:59:34.125589 systemd[1]: No hostname configured, using default hostname. May 14 23:59:34.125609 systemd[1]: Hostname set to . May 14 23:59:34.125629 systemd[1]: Initializing machine ID from random generator. May 14 23:59:34.125653 systemd[1]: Queued start job for default target initrd.target. May 14 23:59:34.125673 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 14 23:59:34.125694 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 14 23:59:34.125715 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... May 14 23:59:34.125735 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 14 23:59:34.125755 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... May 14 23:59:34.125777 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... May 14 23:59:34.125804 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... May 14 23:59:34.125844 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... May 14 23:59:34.125869 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 14 23:59:34.125890 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 14 23:59:34.125911 systemd[1]: Reached target paths.target - Path Units. May 14 23:59:34.125936 systemd[1]: Reached target slices.target - Slice Units. May 14 23:59:34.125957 systemd[1]: Reached target swap.target - Swaps. May 14 23:59:34.125978 systemd[1]: Reached target timers.target - Timer Units. May 14 23:59:34.125999 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. May 14 23:59:34.126019 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 14 23:59:34.126040 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). May 14 23:59:34.126070 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. May 14 23:59:34.126091 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 14 23:59:34.126112 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 14 23:59:34.126137 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 14 23:59:34.126158 systemd[1]: Reached target sockets.target - Socket Units. May 14 23:59:34.126179 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... May 14 23:59:34.126200 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 14 23:59:34.126221 systemd[1]: Finished network-cleanup.service - Network Cleanup. May 14 23:59:34.126243 systemd[1]: Starting systemd-fsck-usr.service... May 14 23:59:34.126264 systemd[1]: Starting systemd-journald.service - Journal Service... May 14 23:59:34.126298 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 14 23:59:34.126318 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 14 23:59:34.126381 systemd-journald[184]: Collecting audit messages is disabled. May 14 23:59:34.126428 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. May 14 23:59:34.126449 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 14 23:59:34.126476 systemd[1]: Finished systemd-fsck-usr.service. May 14 23:59:34.126498 systemd-journald[184]: Journal started May 14 23:59:34.126539 systemd-journald[184]: Runtime Journal (/run/log/journal/502e6cfdb6d041c099241144f146a742) is 8M, max 148.6M, 140.6M free. May 14 23:59:34.129261 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 14 23:59:34.118947 systemd-modules-load[185]: Inserted module 'overlay' May 14 23:59:34.134365 systemd[1]: Started systemd-journald.service - Journal Service. May 14 23:59:34.142072 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 14 23:59:34.152789 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 14 23:59:34.168299 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 14 23:59:34.169571 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 14 23:59:34.172731 kernel: Bridge firewalling registered May 14 23:59:34.172129 systemd-modules-load[185]: Inserted module 'br_netfilter' May 14 23:59:34.175705 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 14 23:59:34.177501 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 14 23:59:34.178361 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 14 23:59:34.192072 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 14 23:59:34.210327 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 14 23:59:34.216328 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 14 23:59:34.218806 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 14 23:59:34.225537 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 14 23:59:34.229997 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 14 23:59:34.248534 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... May 14 23:59:34.274167 systemd-resolved[217]: Positive Trust Anchors: May 14 23:59:34.274186 systemd-resolved[217]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 14 23:59:34.284693 dracut-cmdline[220]: dracut-dracut-053 May 14 23:59:34.284693 dracut-cmdline[220]: 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=23d816f2beca10c7a75ccdd203c170f89f29125f08ff6f3fdf90f8fa61b342cc May 14 23:59:34.274264 systemd-resolved[217]: 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 14 23:59:34.281085 systemd-resolved[217]: Defaulting to hostname 'linux'. May 14 23:59:34.282841 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 14 23:59:34.289499 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 14 23:59:34.379336 kernel: SCSI subsystem initialized May 14 23:59:34.390333 kernel: Loading iSCSI transport class v2.0-870. May 14 23:59:34.402330 kernel: iscsi: registered transport (tcp) May 14 23:59:34.425329 kernel: iscsi: registered transport (qla4xxx) May 14 23:59:34.425411 kernel: QLogic iSCSI HBA Driver May 14 23:59:34.476911 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. May 14 23:59:34.482640 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... May 14 23:59:34.523037 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. May 14 23:59:34.523110 kernel: device-mapper: uevent: version 1.0.3 May 14 23:59:34.523139 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com May 14 23:59:34.567323 kernel: raid6: avx2x4 gen() 17994 MB/s May 14 23:59:34.584324 kernel: raid6: avx2x2 gen() 18002 MB/s May 14 23:59:34.601836 kernel: raid6: avx2x1 gen() 13882 MB/s May 14 23:59:34.601876 kernel: raid6: using algorithm avx2x2 gen() 18002 MB/s May 14 23:59:34.619838 kernel: raid6: .... xor() 18702 MB/s, rmw enabled May 14 23:59:34.619889 kernel: raid6: using avx2x2 recovery algorithm May 14 23:59:34.642330 kernel: xor: automatically using best checksumming function avx May 14 23:59:34.808334 kernel: Btrfs loaded, zoned=no, fsverity=no May 14 23:59:34.823149 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. May 14 23:59:34.833535 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 14 23:59:34.853252 systemd-udevd[402]: Using default interface naming scheme 'v255'. May 14 23:59:34.862091 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 14 23:59:34.872263 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... May 14 23:59:34.905646 dracut-pre-trigger[408]: rd.md=0: removing MD RAID activation May 14 23:59:34.944983 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. May 14 23:59:34.952590 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 14 23:59:35.038894 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 14 23:59:35.056539 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... May 14 23:59:35.101049 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. May 14 23:59:35.125989 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. May 14 23:59:35.153247 kernel: scsi host0: Virtio SCSI HBA May 14 23:59:35.162700 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 14 23:59:35.199471 kernel: cryptd: max_cpu_qlen set to 1000 May 14 23:59:35.199520 kernel: scsi 0:0:1:0: Direct-Access Google PersistentDisk 1 PQ: 0 ANSI: 6 May 14 23:59:35.171432 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 14 23:59:35.180218 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... May 14 23:59:35.264301 kernel: AVX2 version of gcm_enc/dec engaged. May 14 23:59:35.279398 kernel: AES CTR mode by8 optimization enabled May 14 23:59:35.296375 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. May 14 23:59:35.328240 kernel: sd 0:0:1:0: [sda] 25165824 512-byte logical blocks: (12.9 GB/12.0 GiB) May 14 23:59:35.328628 kernel: sd 0:0:1:0: [sda] 4096-byte physical blocks May 14 23:59:35.328230 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 14 23:59:35.398472 kernel: sd 0:0:1:0: [sda] Write Protect is off May 14 23:59:35.398805 kernel: sd 0:0:1:0: [sda] Mode Sense: 1f 00 00 08 May 14 23:59:35.399054 kernel: sd 0:0:1:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA May 14 23:59:35.399300 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. May 14 23:59:35.399326 kernel: GPT:17805311 != 25165823 May 14 23:59:35.399348 kernel: GPT:Alternate GPT header not at the end of the disk. May 14 23:59:35.399371 kernel: GPT:17805311 != 25165823 May 14 23:59:35.399393 kernel: GPT: Use GNU Parted to correct GPT errors. May 14 23:59:35.399423 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 May 14 23:59:35.399448 kernel: sd 0:0:1:0: [sda] Attached SCSI disk May 14 23:59:35.328454 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 14 23:59:35.383897 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 14 23:59:35.483582 kernel: BTRFS: device fsid 11358d57-dfa4-4197-9524-595753ed5512 devid 1 transid 38 /dev/sda3 scanned by (udev-worker) (455) May 14 23:59:35.483620 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/sda6 scanned by (udev-worker) (452) May 14 23:59:35.422439 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 14 23:59:35.422758 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 14 23:59:35.436509 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... May 14 23:59:35.478737 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 14 23:59:35.495121 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. May 14 23:59:35.547500 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - PersistentDisk EFI-SYSTEM. May 14 23:59:35.565443 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - PersistentDisk ROOT. May 14 23:59:35.582049 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - PersistentDisk USR-A. May 14 23:59:35.601671 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - PersistentDisk USR-A. May 14 23:59:35.628022 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 14 23:59:35.663801 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - PersistentDisk OEM. May 14 23:59:35.682593 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... May 14 23:59:35.703651 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 14 23:59:35.723012 disk-uuid[543]: Primary Header is updated. May 14 23:59:35.723012 disk-uuid[543]: Secondary Entries is updated. May 14 23:59:35.723012 disk-uuid[543]: Secondary Header is updated. May 14 23:59:35.742368 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 May 14 23:59:35.758341 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 May 14 23:59:35.772724 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 14 23:59:36.774953 disk-uuid[544]: The operation has completed successfully. May 14 23:59:36.783472 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 May 14 23:59:36.868519 systemd[1]: disk-uuid.service: Deactivated successfully. May 14 23:59:36.868678 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. May 14 23:59:36.914489 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... May 14 23:59:36.945420 sh[566]: Success May 14 23:59:36.971499 kernel: device-mapper: verity: sha256 using implementation "sha256-avx2" May 14 23:59:37.073603 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. May 14 23:59:37.080420 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... May 14 23:59:37.109171 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. May 14 23:59:37.162828 kernel: BTRFS info (device dm-0): first mount of filesystem 11358d57-dfa4-4197-9524-595753ed5512 May 14 23:59:37.162915 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm May 14 23:59:37.162942 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead May 14 23:59:37.172383 kernel: BTRFS info (device dm-0): disabling log replay at mount time May 14 23:59:37.179205 kernel: BTRFS info (device dm-0): using free space tree May 14 23:59:37.212373 kernel: BTRFS info (device dm-0): enabling ssd optimizations May 14 23:59:37.218916 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. May 14 23:59:37.219893 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. May 14 23:59:37.229529 systemd[1]: Starting ignition-setup.service - Ignition (setup)... May 14 23:59:37.238560 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... May 14 23:59:37.313344 kernel: BTRFS info (device sda6): first mount of filesystem 26320528-a534-4245-a65e-42f09448b5f1 May 14 23:59:37.313417 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm May 14 23:59:37.313445 kernel: BTRFS info (device sda6): using free space tree May 14 23:59:37.338193 kernel: BTRFS info (device sda6): enabling ssd optimizations May 14 23:59:37.338277 kernel: BTRFS info (device sda6): auto enabling async discard May 14 23:59:37.353336 kernel: BTRFS info (device sda6): last unmount of filesystem 26320528-a534-4245-a65e-42f09448b5f1 May 14 23:59:37.362341 systemd[1]: Finished ignition-setup.service - Ignition (setup). May 14 23:59:37.382707 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... May 14 23:59:37.457459 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 14 23:59:37.480485 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 14 23:59:37.581923 systemd-networkd[747]: lo: Link UP May 14 23:59:37.581945 systemd-networkd[747]: lo: Gained carrier May 14 23:59:37.584252 ignition[687]: Ignition 2.20.0 May 14 23:59:37.584821 systemd-networkd[747]: Enumeration completed May 14 23:59:37.584265 ignition[687]: Stage: fetch-offline May 14 23:59:37.585561 systemd[1]: Started systemd-networkd.service - Network Configuration. May 14 23:59:37.584377 ignition[687]: no configs at "/usr/lib/ignition/base.d" May 14 23:59:37.585856 systemd-networkd[747]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 14 23:59:37.584395 ignition[687]: no config dir at "/usr/lib/ignition/base.platform.d/gcp" May 14 23:59:37.585864 systemd-networkd[747]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 14 23:59:37.584549 ignition[687]: parsed url from cmdline: "" May 14 23:59:37.588352 systemd-networkd[747]: eth0: Link UP May 14 23:59:37.584556 ignition[687]: no config URL provided May 14 23:59:37.588359 systemd-networkd[747]: eth0: Gained carrier May 14 23:59:37.584566 ignition[687]: reading system config file "/usr/lib/ignition/user.ign" May 14 23:59:37.588373 systemd-networkd[747]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 14 23:59:37.584579 ignition[687]: no config at "/usr/lib/ignition/user.ign" May 14 23:59:37.589970 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). May 14 23:59:37.584590 ignition[687]: failed to fetch config: resource requires networking May 14 23:59:37.598455 systemd-networkd[747]: eth0: Overlong DHCP hostname received, shortened from 'ci-4230-1-1-nightly-20250514-2100-3f0309ee793f4b5001c9.c.flatcar-212911.internal' to 'ci-4230-1-1-nightly-20250514-2100-3f0309ee793f4b5001c9' May 14 23:59:37.584886 ignition[687]: Ignition finished successfully May 14 23:59:37.598480 systemd-networkd[747]: eth0: DHCPv4 address 10.128.0.45/32, gateway 10.128.0.1 acquired from 169.254.169.254 May 14 23:59:37.674807 ignition[757]: Ignition 2.20.0 May 14 23:59:37.616397 systemd[1]: Reached target network.target - Network. May 14 23:59:37.674819 ignition[757]: Stage: fetch May 14 23:59:37.635518 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... May 14 23:59:37.675050 ignition[757]: no configs at "/usr/lib/ignition/base.d" May 14 23:59:37.684161 unknown[757]: fetched base config from "system" May 14 23:59:37.675062 ignition[757]: no config dir at "/usr/lib/ignition/base.platform.d/gcp" May 14 23:59:37.684174 unknown[757]: fetched base config from "system" May 14 23:59:37.675187 ignition[757]: parsed url from cmdline: "" May 14 23:59:37.684184 unknown[757]: fetched user config from "gcp" May 14 23:59:37.675194 ignition[757]: no config URL provided May 14 23:59:37.686882 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). May 14 23:59:37.675204 ignition[757]: reading system config file "/usr/lib/ignition/user.ign" May 14 23:59:37.702605 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... May 14 23:59:37.675217 ignition[757]: no config at "/usr/lib/ignition/user.ign" May 14 23:59:37.757754 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). May 14 23:59:37.675244 ignition[757]: GET http://169.254.169.254/computeMetadata/v1/instance/attributes/user-data: attempt #1 May 14 23:59:37.791493 systemd[1]: Starting ignition-disks.service - Ignition (disks)... May 14 23:59:37.678669 ignition[757]: GET result: OK May 14 23:59:37.820834 systemd[1]: Finished ignition-disks.service - Ignition (disks). May 14 23:59:37.678740 ignition[757]: parsing config with SHA512: 15bac3cd4fca1c08329ba6bacf2868b277750eecd9c97c4d70a50fb4ed9a11d2e096b6847187aa71ac45e46325cd87ed9600241e3c9100370d03044010f7ba18 May 14 23:59:37.854783 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. May 14 23:59:37.684488 ignition[757]: fetch: fetch complete May 14 23:59:37.872576 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. May 14 23:59:37.684494 ignition[757]: fetch: fetch passed May 14 23:59:37.895572 systemd[1]: Reached target local-fs.target - Local File Systems. May 14 23:59:37.684554 ignition[757]: Ignition finished successfully May 14 23:59:37.910507 systemd[1]: Reached target sysinit.target - System Initialization. May 14 23:59:37.755419 ignition[763]: Ignition 2.20.0 May 14 23:59:37.926493 systemd[1]: Reached target basic.target - Basic System. May 14 23:59:37.755431 ignition[763]: Stage: kargs May 14 23:59:37.946602 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... May 14 23:59:37.755720 ignition[763]: no configs at "/usr/lib/ignition/base.d" May 14 23:59:37.755736 ignition[763]: no config dir at "/usr/lib/ignition/base.platform.d/gcp" May 14 23:59:37.756482 ignition[763]: kargs: kargs passed May 14 23:59:37.756539 ignition[763]: Ignition finished successfully May 14 23:59:37.812187 ignition[770]: Ignition 2.20.0 May 14 23:59:37.812212 ignition[770]: Stage: disks May 14 23:59:37.812762 ignition[770]: no configs at "/usr/lib/ignition/base.d" May 14 23:59:37.812778 ignition[770]: no config dir at "/usr/lib/ignition/base.platform.d/gcp" May 14 23:59:37.813632 ignition[770]: disks: disks passed May 14 23:59:37.813689 ignition[770]: Ignition finished successfully May 14 23:59:38.000097 systemd-fsck[779]: ROOT: clean, 14/1628000 files, 120691/1617920 blocks May 14 23:59:38.198483 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. May 14 23:59:38.227441 systemd[1]: Mounting sysroot.mount - /sysroot... May 14 23:59:38.342677 kernel: EXT4-fs (sda9): mounted filesystem 36fdaeac-383d-468b-a0a4-9f47e3957a15 r/w with ordered data mode. Quota mode: none. May 14 23:59:38.343889 systemd[1]: Mounted sysroot.mount - /sysroot. May 14 23:59:38.344811 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. May 14 23:59:38.376411 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 14 23:59:38.394452 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... May 14 23:59:38.407344 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. May 14 23:59:38.461518 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/sda6 scanned by mount (787) May 14 23:59:38.461573 kernel: BTRFS info (device sda6): first mount of filesystem 26320528-a534-4245-a65e-42f09448b5f1 May 14 23:59:38.461614 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm May 14 23:59:38.461634 kernel: BTRFS info (device sda6): using free space tree May 14 23:59:38.407442 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). May 14 23:59:38.504650 kernel: BTRFS info (device sda6): enabling ssd optimizations May 14 23:59:38.504689 kernel: BTRFS info (device sda6): auto enabling async discard May 14 23:59:38.407485 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. May 14 23:59:38.487843 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 14 23:59:38.513788 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. May 14 23:59:38.537513 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... May 14 23:59:38.656569 initrd-setup-root[811]: cut: /sysroot/etc/passwd: No such file or directory May 14 23:59:38.666433 initrd-setup-root[818]: cut: /sysroot/etc/group: No such file or directory May 14 23:59:38.676927 initrd-setup-root[825]: cut: /sysroot/etc/shadow: No such file or directory May 14 23:59:38.687525 initrd-setup-root[832]: cut: /sysroot/etc/gshadow: No such file or directory May 14 23:59:38.834816 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. May 14 23:59:38.842431 systemd[1]: Starting ignition-mount.service - Ignition (mount)... May 14 23:59:38.877316 kernel: BTRFS info (device sda6): last unmount of filesystem 26320528-a534-4245-a65e-42f09448b5f1 May 14 23:59:38.886559 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... May 14 23:59:38.896852 systemd[1]: sysroot-oem.mount: Deactivated successfully. May 14 23:59:38.897477 systemd-networkd[747]: eth0: Gained IPv6LL May 14 23:59:38.925601 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. May 14 23:59:38.943469 ignition[903]: INFO : Ignition 2.20.0 May 14 23:59:38.943469 ignition[903]: INFO : Stage: mount May 14 23:59:38.943469 ignition[903]: INFO : no configs at "/usr/lib/ignition/base.d" May 14 23:59:38.943469 ignition[903]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/gcp" May 14 23:59:38.946342 systemd[1]: Finished ignition-mount.service - Ignition (mount). May 14 23:59:39.012472 ignition[903]: INFO : mount: mount passed May 14 23:59:39.012472 ignition[903]: INFO : Ignition finished successfully May 14 23:59:38.968519 systemd[1]: Starting ignition-files.service - Ignition (files)... May 14 23:59:39.070473 kernel: BTRFS: device label OEM devid 1 transid 17 /dev/sda6 scanned by mount (914) May 14 23:59:39.070509 kernel: BTRFS info (device sda6): first mount of filesystem 26320528-a534-4245-a65e-42f09448b5f1 May 14 23:59:39.070526 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm May 14 23:59:39.070541 kernel: BTRFS info (device sda6): using free space tree May 14 23:59:38.993585 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 14 23:59:39.091593 kernel: BTRFS info (device sda6): enabling ssd optimizations May 14 23:59:39.091631 kernel: BTRFS info (device sda6): auto enabling async discard May 14 23:59:39.090505 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 14 23:59:39.135467 ignition[931]: INFO : Ignition 2.20.0 May 14 23:59:39.135467 ignition[931]: INFO : Stage: files May 14 23:59:39.135467 ignition[931]: INFO : no configs at "/usr/lib/ignition/base.d" May 14 23:59:39.135467 ignition[931]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/gcp" May 14 23:59:39.135467 ignition[931]: DEBUG : files: compiled without relabeling support, skipping May 14 23:59:39.181429 ignition[931]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" May 14 23:59:39.181429 ignition[931]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" May 14 23:59:39.181429 ignition[931]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" May 14 23:59:39.181429 ignition[931]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" May 14 23:59:39.181429 ignition[931]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" May 14 23:59:39.181429 ignition[931]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" May 14 23:59:39.181429 ignition[931]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" May 14 23:59:39.181429 ignition[931]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" May 14 23:59:39.181429 ignition[931]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" May 14 23:59:39.181429 ignition[931]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.0-x86-64.raw" May 14 23:59:39.181429 ignition[931]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.0-x86-64.raw" May 14 23:59:39.181429 ignition[931]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.0-x86-64.raw" May 14 23:59:39.181429 ignition[931]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.31.0-x86-64.raw: attempt #1 May 14 23:59:39.146028 unknown[931]: wrote ssh authorized keys file for user: core May 14 23:59:39.476582 ignition[931]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK May 14 23:59:39.825004 ignition[931]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.0-x86-64.raw" May 14 23:59:39.843489 ignition[931]: INFO : files: createResultFile: createFiles: op(7): [started] writing file "/sysroot/etc/.ignition-result.json" May 14 23:59:39.843489 ignition[931]: INFO : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json" May 14 23:59:39.843489 ignition[931]: INFO : files: files passed May 14 23:59:39.843489 ignition[931]: INFO : Ignition finished successfully May 14 23:59:39.828200 systemd[1]: Finished ignition-files.service - Ignition (files). May 14 23:59:39.850633 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... May 14 23:59:39.872561 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... May 14 23:59:39.926892 systemd[1]: ignition-quench.service: Deactivated successfully. May 14 23:59:39.977559 initrd-setup-root-after-ignition[960]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 14 23:59:39.977559 initrd-setup-root-after-ignition[960]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory May 14 23:59:39.927021 systemd[1]: Finished ignition-quench.service - Ignition (record completion). May 14 23:59:40.033489 initrd-setup-root-after-ignition[964]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 14 23:59:39.947835 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. May 14 23:59:39.970834 systemd[1]: Reached target ignition-complete.target - Ignition Complete. May 14 23:59:39.992487 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... May 14 23:59:40.078788 systemd[1]: initrd-parse-etc.service: Deactivated successfully. May 14 23:59:40.078917 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. May 14 23:59:40.092718 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. May 14 23:59:40.110539 systemd[1]: Reached target initrd.target - Initrd Default Target. May 14 23:59:40.121721 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. May 14 23:59:40.128602 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... May 14 23:59:40.201330 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 14 23:59:40.226501 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... May 14 23:59:40.269495 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. May 14 23:59:40.281726 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. May 14 23:59:40.291812 systemd[1]: Stopped target timers.target - Timer Units. May 14 23:59:40.311756 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. May 14 23:59:40.311954 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 14 23:59:40.344804 systemd[1]: Stopped target initrd.target - Initrd Default Target. May 14 23:59:40.355796 systemd[1]: Stopped target basic.target - Basic System. May 14 23:59:40.372768 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. May 14 23:59:40.387950 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. May 14 23:59:40.405865 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. May 14 23:59:40.425818 systemd[1]: Stopped target remote-fs.target - Remote File Systems. May 14 23:59:40.454737 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. May 14 23:59:40.464754 systemd[1]: Stopped target sysinit.target - System Initialization. May 14 23:59:40.486046 systemd[1]: Stopped target local-fs.target - Local File Systems. May 14 23:59:40.502857 systemd[1]: Stopped target swap.target - Swaps. May 14 23:59:40.519731 systemd[1]: dracut-pre-mount.service: Deactivated successfully. May 14 23:59:40.519945 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. May 14 23:59:40.560501 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. May 14 23:59:40.560884 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 14 23:59:40.578727 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. May 14 23:59:40.578885 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 14 23:59:40.598744 systemd[1]: dracut-initqueue.service: Deactivated successfully. May 14 23:59:40.598950 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. May 14 23:59:40.655515 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. May 14 23:59:40.655914 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. May 14 23:59:40.665816 systemd[1]: ignition-files.service: Deactivated successfully. May 14 23:59:40.665989 systemd[1]: Stopped ignition-files.service - Ignition (files). May 14 23:59:40.703529 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... May 14 23:59:40.713600 systemd[1]: kmod-static-nodes.service: Deactivated successfully. May 14 23:59:40.747546 ignition[985]: INFO : Ignition 2.20.0 May 14 23:59:40.747546 ignition[985]: INFO : Stage: umount May 14 23:59:40.747546 ignition[985]: INFO : no configs at "/usr/lib/ignition/base.d" May 14 23:59:40.747546 ignition[985]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/gcp" May 14 23:59:40.747546 ignition[985]: INFO : umount: umount passed May 14 23:59:40.747546 ignition[985]: INFO : Ignition finished successfully May 14 23:59:40.713854 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. May 14 23:59:40.736715 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... May 14 23:59:40.756476 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. May 14 23:59:40.756718 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. May 14 23:59:40.767825 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. May 14 23:59:40.768142 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. May 14 23:59:40.793752 systemd[1]: sysroot-boot.mount: Deactivated successfully. May 14 23:59:40.795148 systemd[1]: ignition-mount.service: Deactivated successfully. May 14 23:59:40.795320 systemd[1]: Stopped ignition-mount.service - Ignition (mount). May 14 23:59:40.810108 systemd[1]: sysroot-boot.service: Deactivated successfully. May 14 23:59:40.810226 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. May 14 23:59:40.832462 systemd[1]: ignition-disks.service: Deactivated successfully. May 14 23:59:40.832659 systemd[1]: Stopped ignition-disks.service - Ignition (disks). May 14 23:59:40.847511 systemd[1]: ignition-kargs.service: Deactivated successfully. May 14 23:59:40.847600 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). May 14 23:59:40.865693 systemd[1]: ignition-fetch.service: Deactivated successfully. May 14 23:59:40.865792 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). May 14 23:59:40.887551 systemd[1]: Stopped target network.target - Network. May 14 23:59:40.903558 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. May 14 23:59:40.903721 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). May 14 23:59:40.924525 systemd[1]: Stopped target paths.target - Path Units. May 14 23:59:40.941452 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. May 14 23:59:40.943471 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 14 23:59:40.962472 systemd[1]: Stopped target slices.target - Slice Units. May 14 23:59:40.979593 systemd[1]: Stopped target sockets.target - Socket Units. May 14 23:59:40.995508 systemd[1]: iscsid.socket: Deactivated successfully. May 14 23:59:40.995597 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. May 14 23:59:41.013494 systemd[1]: iscsiuio.socket: Deactivated successfully. May 14 23:59:41.013573 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 14 23:59:41.031480 systemd[1]: ignition-setup.service: Deactivated successfully. May 14 23:59:41.031583 systemd[1]: Stopped ignition-setup.service - Ignition (setup). May 14 23:59:41.049639 systemd[1]: ignition-setup-pre.service: Deactivated successfully. May 14 23:59:41.049760 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. May 14 23:59:41.068524 systemd[1]: initrd-setup-root.service: Deactivated successfully. May 14 23:59:41.068621 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. May 14 23:59:41.086667 systemd[1]: Stopping systemd-networkd.service - Network Configuration... May 14 23:59:41.105675 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... May 14 23:59:41.124367 systemd[1]: systemd-resolved.service: Deactivated successfully. May 14 23:59:41.124541 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. May 14 23:59:41.135931 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. May 14 23:59:41.136230 systemd[1]: systemd-networkd.service: Deactivated successfully. May 14 23:59:41.136432 systemd[1]: Stopped systemd-networkd.service - Network Configuration. May 14 23:59:41.151405 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. May 14 23:59:41.151764 systemd[1]: initrd-cleanup.service: Deactivated successfully. May 14 23:59:41.151873 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. May 14 23:59:41.170908 systemd[1]: systemd-networkd.socket: Deactivated successfully. May 14 23:59:41.170966 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. May 14 23:59:41.192526 systemd[1]: Stopping network-cleanup.service - Network Cleanup... May 14 23:59:41.203595 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. May 14 23:59:41.678437 systemd-journald[184]: Received SIGTERM from PID 1 (systemd). May 14 23:59:41.203672 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 14 23:59:41.228651 systemd[1]: systemd-sysctl.service: Deactivated successfully. May 14 23:59:41.228709 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. May 14 23:59:41.248729 systemd[1]: systemd-modules-load.service: Deactivated successfully. May 14 23:59:41.248785 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. May 14 23:59:41.279628 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. May 14 23:59:41.279704 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. May 14 23:59:41.287738 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... May 14 23:59:41.307013 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. May 14 23:59:41.307092 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. May 14 23:59:41.307584 systemd[1]: systemd-udevd.service: Deactivated successfully. May 14 23:59:41.307742 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. May 14 23:59:41.330565 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. May 14 23:59:41.330631 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. May 14 23:59:41.358518 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. May 14 23:59:41.358588 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. May 14 23:59:41.378478 systemd[1]: dracut-pre-udev.service: Deactivated successfully. May 14 23:59:41.378579 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. May 14 23:59:41.405455 systemd[1]: dracut-cmdline.service: Deactivated successfully. May 14 23:59:41.405574 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. May 14 23:59:41.435424 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 14 23:59:41.435536 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 14 23:59:41.470725 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... May 14 23:59:41.493418 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. May 14 23:59:41.493602 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 14 23:59:41.512902 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 14 23:59:41.512991 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 14 23:59:41.534938 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. May 14 23:59:41.535043 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. May 14 23:59:41.535600 systemd[1]: network-cleanup.service: Deactivated successfully. May 14 23:59:41.535720 systemd[1]: Stopped network-cleanup.service - Network Cleanup. May 14 23:59:41.554760 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. May 14 23:59:41.554882 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. May 14 23:59:41.576256 systemd[1]: Reached target initrd-switch-root.target - Switch Root. May 14 23:59:41.589727 systemd[1]: Starting initrd-switch-root.service - Switch Root... May 14 23:59:41.630988 systemd[1]: Switching root. May 14 23:59:42.027564 systemd-journald[184]: Journal stopped May 14 23:59:44.496841 kernel: SELinux: policy capability network_peer_controls=1 May 14 23:59:44.496897 kernel: SELinux: policy capability open_perms=1 May 14 23:59:44.496919 kernel: SELinux: policy capability extended_socket_class=1 May 14 23:59:44.496937 kernel: SELinux: policy capability always_check_network=0 May 14 23:59:44.496955 kernel: SELinux: policy capability cgroup_seclabel=1 May 14 23:59:44.496972 kernel: SELinux: policy capability nnp_nosuid_transition=1 May 14 23:59:44.496993 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 May 14 23:59:44.497012 kernel: SELinux: policy capability ioctl_skip_cloexec=0 May 14 23:59:44.497036 kernel: audit: type=1403 audit(1747267182.162:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 May 14 23:59:44.497059 systemd[1]: Successfully loaded SELinux policy in 92.916ms. May 14 23:59:44.497082 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 14.548ms. May 14 23:59:44.497105 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 14 23:59:44.497126 systemd[1]: Detected virtualization google. May 14 23:59:44.497146 systemd[1]: Detected architecture x86-64. May 14 23:59:44.497174 systemd[1]: Detected first boot. May 14 23:59:44.497198 systemd[1]: Initializing machine ID from random generator. May 14 23:59:44.497221 zram_generator::config[1028]: No configuration found. May 14 23:59:44.497244 kernel: Guest personality initialized and is inactive May 14 23:59:44.497265 kernel: VMCI host device registered (name=vmci, major=10, minor=125) May 14 23:59:44.497311 kernel: Initialized host personality May 14 23:59:44.497332 kernel: NET: Registered PF_VSOCK protocol family May 14 23:59:44.497354 systemd[1]: Populated /etc with preset unit settings. May 14 23:59:44.497378 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. May 14 23:59:44.497409 systemd[1]: initrd-switch-root.service: Deactivated successfully. May 14 23:59:44.497431 systemd[1]: Stopped initrd-switch-root.service - Switch Root. May 14 23:59:44.497454 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. May 14 23:59:44.497473 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. May 14 23:59:44.497493 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. May 14 23:59:44.497518 systemd[1]: Created slice system-getty.slice - Slice /system/getty. May 14 23:59:44.497536 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. May 14 23:59:44.497557 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. May 14 23:59:44.497578 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. May 14 23:59:44.497600 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. May 14 23:59:44.497622 systemd[1]: Created slice user.slice - User and Session Slice. May 14 23:59:44.497643 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 14 23:59:44.497668 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 14 23:59:44.497690 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. May 14 23:59:44.497710 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. May 14 23:59:44.497732 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. May 14 23:59:44.497753 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 14 23:59:44.497779 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... May 14 23:59:44.497800 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 14 23:59:44.497822 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. May 14 23:59:44.497848 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. May 14 23:59:44.497870 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. May 14 23:59:44.497889 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. May 14 23:59:44.497909 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 14 23:59:44.497930 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 14 23:59:44.497954 systemd[1]: Reached target slices.target - Slice Units. May 14 23:59:44.497977 systemd[1]: Reached target swap.target - Swaps. May 14 23:59:44.498000 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. May 14 23:59:44.498028 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. May 14 23:59:44.498051 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. May 14 23:59:44.498074 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 14 23:59:44.498101 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 14 23:59:44.498129 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 14 23:59:44.498151 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. May 14 23:59:44.498173 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... May 14 23:59:44.498196 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... May 14 23:59:44.498220 systemd[1]: Mounting media.mount - External Media Directory... May 14 23:59:44.498243 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 14 23:59:44.498266 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... May 14 23:59:44.498356 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... May 14 23:59:44.498399 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... May 14 23:59:44.498425 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 14 23:59:44.498447 systemd[1]: Reached target machines.target - Containers. May 14 23:59:44.498470 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... May 14 23:59:44.498495 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 14 23:59:44.498519 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 14 23:59:44.498541 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... May 14 23:59:44.498565 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 14 23:59:44.498588 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 14 23:59:44.498618 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 14 23:59:44.498640 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... May 14 23:59:44.498828 kernel: ACPI: bus type drm_connector registered May 14 23:59:44.498863 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 14 23:59:44.498889 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). May 14 23:59:44.498914 systemd[1]: systemd-fsck-root.service: Deactivated successfully. May 14 23:59:44.498938 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. May 14 23:59:44.498973 kernel: fuse: init (API version 7.39) May 14 23:59:44.498996 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. May 14 23:59:44.499019 kernel: loop: module loaded May 14 23:59:44.499040 systemd[1]: Stopped systemd-fsck-usr.service. May 14 23:59:44.499068 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 14 23:59:44.499092 systemd[1]: Starting systemd-journald.service - Journal Service... May 14 23:59:44.499116 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 14 23:59:44.499140 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 14 23:59:44.499169 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... May 14 23:59:44.499251 systemd-journald[1116]: Collecting audit messages is disabled. May 14 23:59:44.499325 systemd-journald[1116]: Journal started May 14 23:59:44.499376 systemd-journald[1116]: Runtime Journal (/run/log/journal/5b6990300e684083b3d5ab4e69d2ffde) is 8M, max 148.6M, 140.6M free. May 14 23:59:43.199466 systemd[1]: Queued start job for default target multi-user.target. May 14 23:59:43.211934 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. May 14 23:59:43.212540 systemd[1]: systemd-journald.service: Deactivated successfully. May 14 23:59:44.516379 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... May 14 23:59:44.545313 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 14 23:59:44.570560 systemd[1]: verity-setup.service: Deactivated successfully. May 14 23:59:44.570656 systemd[1]: Stopped verity-setup.service. May 14 23:59:44.596402 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 14 23:59:44.608345 systemd[1]: Started systemd-journald.service - Journal Service. May 14 23:59:44.618861 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. May 14 23:59:44.628643 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. May 14 23:59:44.638637 systemd[1]: Mounted media.mount - External Media Directory. May 14 23:59:44.648583 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. May 14 23:59:44.658580 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. May 14 23:59:44.668566 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. May 14 23:59:44.679014 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. May 14 23:59:44.690881 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 14 23:59:44.703838 systemd[1]: modprobe@configfs.service: Deactivated successfully. May 14 23:59:44.704144 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. May 14 23:59:44.715745 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 14 23:59:44.716026 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 14 23:59:44.727796 systemd[1]: modprobe@drm.service: Deactivated successfully. May 14 23:59:44.728056 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 14 23:59:44.738755 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 14 23:59:44.739015 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 14 23:59:44.750739 systemd[1]: modprobe@fuse.service: Deactivated successfully. May 14 23:59:44.751020 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. May 14 23:59:44.761747 systemd[1]: modprobe@loop.service: Deactivated successfully. May 14 23:59:44.762011 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 14 23:59:44.771787 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 14 23:59:44.781793 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 14 23:59:44.793765 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. May 14 23:59:44.805793 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. May 14 23:59:44.817787 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 14 23:59:44.841336 systemd[1]: Reached target network-pre.target - Preparation for Network. May 14 23:59:44.856430 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... May 14 23:59:44.876326 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... May 14 23:59:44.886450 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). May 14 23:59:44.886520 systemd[1]: Reached target local-fs.target - Local File Systems. May 14 23:59:44.898774 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. May 14 23:59:44.917496 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... May 14 23:59:44.934602 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... May 14 23:59:44.944699 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 14 23:59:44.954141 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... May 14 23:59:44.972202 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... May 14 23:59:44.983616 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 14 23:59:44.991633 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... May 14 23:59:45.003361 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 14 23:59:45.014512 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 14 23:59:45.020475 systemd-journald[1116]: Time spent on flushing to /var/log/journal/5b6990300e684083b3d5ab4e69d2ffde is 153.320ms for 929 entries. May 14 23:59:45.020475 systemd-journald[1116]: System Journal (/var/log/journal/5b6990300e684083b3d5ab4e69d2ffde) is 8M, max 584.8M, 576.8M free. May 14 23:59:45.228374 systemd-journald[1116]: Received client request to flush runtime journal. May 14 23:59:45.228519 kernel: loop0: detected capacity change from 0 to 147912 May 14 23:59:45.228569 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher May 14 23:59:45.047505 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... May 14 23:59:45.068789 systemd[1]: Starting systemd-sysusers.service - Create System Users... May 14 23:59:45.087695 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... May 14 23:59:45.112539 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. May 14 23:59:45.127743 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. May 14 23:59:45.141074 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. May 14 23:59:45.153077 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. May 14 23:59:45.191023 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. May 14 23:59:45.222001 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... May 14 23:59:45.241842 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. May 14 23:59:45.254519 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 14 23:59:45.270374 kernel: loop1: detected capacity change from 0 to 138176 May 14 23:59:45.271778 udevadm[1154]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. May 14 23:59:45.282140 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. May 14 23:59:45.286582 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. May 14 23:59:45.326592 systemd[1]: Finished systemd-sysusers.service - Create System Users. May 14 23:59:45.347329 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 14 23:59:45.364392 kernel: loop2: detected capacity change from 0 to 205544 May 14 23:59:45.453171 systemd-tmpfiles[1172]: ACLs are not supported, ignoring. May 14 23:59:45.453208 systemd-tmpfiles[1172]: ACLs are not supported, ignoring. May 14 23:59:45.470556 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 14 23:59:45.517318 kernel: loop3: detected capacity change from 0 to 52152 May 14 23:59:45.604914 kernel: loop4: detected capacity change from 0 to 147912 May 14 23:59:45.667393 kernel: loop5: detected capacity change from 0 to 138176 May 14 23:59:45.728087 kernel: loop6: detected capacity change from 0 to 205544 May 14 23:59:45.772612 kernel: loop7: detected capacity change from 0 to 52152 May 14 23:59:45.800907 (sd-merge)[1177]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-gce'. May 14 23:59:45.803903 (sd-merge)[1177]: Merged extensions into '/usr'. May 14 23:59:45.818205 systemd[1]: Reload requested from client PID 1152 ('systemd-sysext') (unit systemd-sysext.service)... May 14 23:59:45.818235 systemd[1]: Reloading... May 14 23:59:45.991365 zram_generator::config[1201]: No configuration found. May 14 23:59:46.285686 ldconfig[1147]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. May 14 23:59:46.291002 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 14 23:59:46.441518 systemd[1]: Reloading finished in 622 ms. May 14 23:59:46.460683 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. May 14 23:59:46.471224 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. May 14 23:59:46.499600 systemd[1]: Starting ensure-sysext.service... May 14 23:59:46.515570 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 14 23:59:46.548934 systemd[1]: Reload requested from client PID 1245 ('systemctl') (unit ensure-sysext.service)... May 14 23:59:46.548967 systemd[1]: Reloading... May 14 23:59:46.574878 systemd-tmpfiles[1246]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. May 14 23:59:46.576938 systemd-tmpfiles[1246]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. May 14 23:59:46.581106 systemd-tmpfiles[1246]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. May 14 23:59:46.583903 systemd-tmpfiles[1246]: ACLs are not supported, ignoring. May 14 23:59:46.584026 systemd-tmpfiles[1246]: ACLs are not supported, ignoring. May 14 23:59:46.593154 systemd-tmpfiles[1246]: Detected autofs mount point /boot during canonicalization of boot. May 14 23:59:46.596650 systemd-tmpfiles[1246]: Skipping /boot May 14 23:59:46.632755 systemd-tmpfiles[1246]: Detected autofs mount point /boot during canonicalization of boot. May 14 23:59:46.633006 systemd-tmpfiles[1246]: Skipping /boot May 14 23:59:46.744466 zram_generator::config[1278]: No configuration found. May 14 23:59:46.884339 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 14 23:59:46.980910 systemd[1]: Reloading finished in 431 ms. May 14 23:59:46.995265 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. May 14 23:59:47.027703 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 14 23:59:47.057015 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 14 23:59:47.073764 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... May 14 23:59:47.094822 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... May 14 23:59:47.114464 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 14 23:59:47.131660 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 14 23:59:47.138616 augenrules[1341]: No rules May 14 23:59:47.149676 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... May 14 23:59:47.163644 systemd[1]: audit-rules.service: Deactivated successfully. May 14 23:59:47.163988 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 14 23:59:47.183534 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 14 23:59:47.183986 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 14 23:59:47.193694 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 14 23:59:47.210685 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 14 23:59:47.221233 systemd-udevd[1339]: Using default interface naming scheme 'v255'. May 14 23:59:47.225943 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 14 23:59:47.236531 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 14 23:59:47.236691 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 14 23:59:47.248026 systemd[1]: Starting systemd-userdbd.service - User Database Manager... May 14 23:59:47.257404 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 14 23:59:47.265400 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. May 14 23:59:47.279538 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. May 14 23:59:47.291237 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 14 23:59:47.291849 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 14 23:59:47.303887 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 14 23:59:47.316243 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 14 23:59:47.316593 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 14 23:59:47.328141 systemd[1]: modprobe@loop.service: Deactivated successfully. May 14 23:59:47.328460 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 14 23:59:47.354500 systemd[1]: Started systemd-userdbd.service - User Database Manager. May 14 23:59:47.384486 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. May 14 23:59:47.422755 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 14 23:59:47.434883 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 14 23:59:47.443702 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 14 23:59:47.454483 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 14 23:59:47.472520 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 14 23:59:47.493536 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 14 23:59:47.515537 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 14 23:59:47.535546 systemd[1]: Starting setup-oem.service - Setup OEM... May 14 23:59:47.544556 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 14 23:59:47.544641 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 14 23:59:47.555514 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 14 23:59:47.565458 systemd[1]: Reached target time-set.target - System Time Set. May 14 23:59:47.583522 systemd[1]: Starting systemd-update-done.service - Update is Completed... May 14 23:59:47.593438 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 14 23:59:47.593496 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 14 23:59:47.596362 systemd[1]: Finished ensure-sysext.service. May 14 23:59:47.598028 augenrules[1384]: /sbin/augenrules: No change May 14 23:59:47.604885 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 14 23:59:47.605188 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 14 23:59:47.616931 systemd[1]: modprobe@drm.service: Deactivated successfully. May 14 23:59:47.617257 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 14 23:59:47.619446 augenrules[1409]: No rules May 14 23:59:47.627934 systemd[1]: audit-rules.service: Deactivated successfully. May 14 23:59:47.628313 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 14 23:59:47.647310 kernel: piix4_smbus 0000:00:01.3: SMBus base address uninitialized - upgrade BIOS or use force_addr=0xaddr May 14 23:59:47.659895 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 May 14 23:59:47.659804 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 14 23:59:47.660091 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 14 23:59:47.679591 systemd[1]: modprobe@loop.service: Deactivated successfully. May 14 23:59:47.682046 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 14 23:59:47.701740 systemd-resolved[1336]: Positive Trust Anchors: May 14 23:59:47.701999 systemd-resolved[1336]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 14 23:59:47.702077 systemd-resolved[1336]: 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 14 23:59:47.710952 systemd-resolved[1336]: Defaulting to hostname 'linux'. May 14 23:59:47.716383 systemd[1]: Finished setup-oem.service - Setup OEM. May 14 23:59:47.725930 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 14 23:59:47.732311 kernel: ACPI: button: Power Button [PWRF] May 14 23:59:47.741022 systemd[1]: Finished systemd-update-done.service - Update is Completed. May 14 23:59:47.763329 kernel: input: Sleep Button as /devices/LNXSYSTM:00/LNXSLPBN:00/input/input5 May 14 23:59:47.764698 systemd[1]: Condition check resulted in dev-tpmrm0.device - /dev/tpmrm0 being skipped. May 14 23:59:47.777312 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input4 May 14 23:59:47.783227 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. May 14 23:59:47.804357 kernel: ACPI: button: Sleep Button [SLPF] May 14 23:59:47.807189 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 14 23:59:47.815321 kernel: EDAC MC: Ver: 3.0.0 May 14 23:59:47.821458 systemd[1]: Reached target tpm2.target - Trusted Platform Module. May 14 23:59:47.840086 systemd[1]: Starting oem-gce-enable-oslogin.service - Enable GCE OS Login... May 14 23:59:47.850471 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 14 23:59:47.850572 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 14 23:59:47.903098 systemd-networkd[1397]: lo: Link UP May 14 23:59:47.903738 systemd-networkd[1397]: lo: Gained carrier May 14 23:59:47.905151 systemd-networkd[1397]: Enumeration completed May 14 23:59:47.905317 systemd[1]: Started systemd-networkd.service - Network Configuration. May 14 23:59:47.916594 systemd[1]: Reached target network.target - Network. May 14 23:59:47.938789 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... May 14 23:59:47.940262 systemd-networkd[1397]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 14 23:59:47.940274 systemd-networkd[1397]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 14 23:59:47.942665 systemd-networkd[1397]: eth0: Link UP May 14 23:59:47.942686 systemd-networkd[1397]: eth0: Gained carrier May 14 23:59:47.942713 systemd-networkd[1397]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 14 23:59:47.956172 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 38 scanned by (udev-worker) (1368) May 14 23:59:47.964364 systemd-networkd[1397]: eth0: Overlong DHCP hostname received, shortened from 'ci-4230-1-1-nightly-20250514-2100-3f0309ee793f4b5001c9.c.flatcar-212911.internal' to 'ci-4230-1-1-nightly-20250514-2100-3f0309ee793f4b5001c9' May 14 23:59:47.964389 systemd-networkd[1397]: eth0: DHCPv4 address 10.128.0.45/32, gateway 10.128.0.1 acquired from 169.254.169.254 May 14 23:59:47.974692 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... May 14 23:59:47.993394 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 14 23:59:48.004655 systemd[1]: Finished oem-gce-enable-oslogin.service - Enable GCE OS Login. May 14 23:59:48.009491 kernel: mousedev: PS/2 mouse device common for all mice May 14 23:59:48.057534 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. May 14 23:59:48.097354 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. May 14 23:59:48.113802 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - PersistentDisk OEM. May 14 23:59:48.122634 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... May 14 23:59:48.125216 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... May 14 23:59:48.146311 lvm[1454]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. May 14 23:59:48.163421 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. May 14 23:59:48.188798 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. May 14 23:59:48.201000 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 14 23:59:48.214518 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 14 23:59:48.224476 systemd[1]: Reached target sysinit.target - System Initialization. May 14 23:59:48.234623 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. May 14 23:59:48.245625 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 14 23:59:48.256854 systemd[1]: Started logrotate.timer - Daily rotation of log files. May 14 23:59:48.266757 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. May 14 23:59:48.278515 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. May 14 23:59:48.289507 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). May 14 23:59:48.289584 systemd[1]: Reached target paths.target - Path Units. May 14 23:59:48.298509 systemd[1]: Reached target timers.target - Timer Units. May 14 23:59:48.309731 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. May 14 23:59:48.321661 systemd[1]: Starting docker.socket - Docker Socket for the API... May 14 23:59:48.333131 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). May 14 23:59:48.344716 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). May 14 23:59:48.356517 systemd[1]: Reached target ssh-access.target - SSH Access Available. May 14 23:59:48.380526 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. May 14 23:59:48.391267 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. May 14 23:59:48.408653 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... May 14 23:59:48.430700 systemd[1]: Listening on docker.socket - Docker Socket for the API. May 14 23:59:48.437857 lvm[1464]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. May 14 23:59:48.441888 systemd[1]: Reached target sockets.target - Socket Units. May 14 23:59:48.451517 systemd[1]: Reached target basic.target - Basic System. May 14 23:59:48.460519 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. May 14 23:59:48.460578 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. May 14 23:59:48.464459 systemd[1]: Starting containerd.service - containerd container runtime... May 14 23:59:48.484466 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... May 14 23:59:48.503782 systemd[1]: Starting dbus.service - D-Bus System Message Bus... May 14 23:59:48.541662 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... May 14 23:59:48.560372 jq[1468]: false May 14 23:59:48.560871 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... May 14 23:59:48.570469 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 14 23:59:48.580658 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... May 14 23:59:48.599177 systemd[1]: Started ntpd.service - Network Time Service. May 14 23:59:48.601419 coreos-metadata[1466]: May 14 23:59:48.600 INFO Fetching http://169.254.169.254/computeMetadata/v1/instance/hostname: Attempt #1 May 14 23:59:48.601419 coreos-metadata[1466]: May 14 23:59:48.601 INFO Fetch successful May 14 23:59:48.601419 coreos-metadata[1466]: May 14 23:59:48.601 INFO Fetching http://169.254.169.254/computeMetadata/v1/instance/network-interfaces/0/access-configs/0/external-ip: Attempt #1 May 14 23:59:48.601419 coreos-metadata[1466]: May 14 23:59:48.601 INFO Fetch successful May 14 23:59:48.602258 coreos-metadata[1466]: May 14 23:59:48.601 INFO Fetching http://169.254.169.254/computeMetadata/v1/instance/network-interfaces/0/ip: Attempt #1 May 14 23:59:48.602700 coreos-metadata[1466]: May 14 23:59:48.602 INFO Fetch successful May 14 23:59:48.602911 coreos-metadata[1466]: May 14 23:59:48.602 INFO Fetching http://169.254.169.254/computeMetadata/v1/instance/machine-type: Attempt #1 May 14 23:59:48.610341 coreos-metadata[1466]: May 14 23:59:48.603 INFO Fetch successful May 14 23:59:48.618583 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... May 14 23:59:48.640578 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... May 14 23:59:48.655968 dbus-daemon[1467]: [system] SELinux support is enabled May 14 23:59:48.664545 systemd[1]: Starting systemd-logind.service - User Login Management... May 14 23:59:48.666492 extend-filesystems[1471]: Found loop4 May 14 23:59:48.686574 extend-filesystems[1471]: Found loop5 May 14 23:59:48.686574 extend-filesystems[1471]: Found loop6 May 14 23:59:48.686574 extend-filesystems[1471]: Found loop7 May 14 23:59:48.686574 extend-filesystems[1471]: Found sda May 14 23:59:48.686574 extend-filesystems[1471]: Found sda1 May 14 23:59:48.686574 extend-filesystems[1471]: Found sda2 May 14 23:59:48.686574 extend-filesystems[1471]: Found sda3 May 14 23:59:48.686574 extend-filesystems[1471]: Found usr May 14 23:59:48.686574 extend-filesystems[1471]: Found sda4 May 14 23:59:48.686574 extend-filesystems[1471]: Found sda6 May 14 23:59:48.686574 extend-filesystems[1471]: Found sda7 May 14 23:59:48.686574 extend-filesystems[1471]: Found sda9 May 14 23:59:48.686574 extend-filesystems[1471]: Checking size of /dev/sda9 May 14 23:59:48.937512 kernel: EXT4-fs (sda9): resizing filesystem from 1617920 to 2538491 blocks May 14 23:59:48.937576 kernel: EXT4-fs (sda9): resized filesystem to 2538491 May 14 23:59:48.937618 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 38 scanned by (udev-worker) (1358) May 14 23:59:48.666973 dbus-daemon[1467]: [system] Activating systemd to hand-off: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.1' (uid=244 pid=1397 comm="/usr/lib/systemd/systemd-networkd" label="system_u:system_r:kernel_t:s0") May 14 23:59:48.676643 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionSecurity=!tpm2). May 14 23:59:48.938219 sshd_keygen[1489]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 May 14 23:59:48.938410 ntpd[1473]: 14 May 23:59:48 ntpd[1473]: ntpd 4.2.8p17@1.4004-o Wed May 14 21:38:42 UTC 2025 (1): Starting May 14 23:59:48.938410 ntpd[1473]: 14 May 23:59:48 ntpd[1473]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp May 14 23:59:48.938410 ntpd[1473]: 14 May 23:59:48 ntpd[1473]: ---------------------------------------------------- May 14 23:59:48.938410 ntpd[1473]: 14 May 23:59:48 ntpd[1473]: ntp-4 is maintained by Network Time Foundation, May 14 23:59:48.938410 ntpd[1473]: 14 May 23:59:48 ntpd[1473]: Inc. (NTF), a non-profit 501(c)(3) public-benefit May 14 23:59:48.938410 ntpd[1473]: 14 May 23:59:48 ntpd[1473]: corporation. Support and training for ntp-4 are May 14 23:59:48.938410 ntpd[1473]: 14 May 23:59:48 ntpd[1473]: available at https://www.nwtime.org/support May 14 23:59:48.938410 ntpd[1473]: 14 May 23:59:48 ntpd[1473]: ---------------------------------------------------- May 14 23:59:48.938410 ntpd[1473]: 14 May 23:59:48 ntpd[1473]: proto: precision = 0.090 usec (-23) May 14 23:59:48.938410 ntpd[1473]: 14 May 23:59:48 ntpd[1473]: basedate set to 2025-05-02 May 14 23:59:48.938410 ntpd[1473]: 14 May 23:59:48 ntpd[1473]: gps base set to 2025-05-04 (week 2365) May 14 23:59:48.938410 ntpd[1473]: 14 May 23:59:48 ntpd[1473]: Listen and drop on 0 v6wildcard [::]:123 May 14 23:59:48.938410 ntpd[1473]: 14 May 23:59:48 ntpd[1473]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 14 23:59:48.938410 ntpd[1473]: 14 May 23:59:48 ntpd[1473]: Listen normally on 2 lo 127.0.0.1:123 May 14 23:59:48.938410 ntpd[1473]: 14 May 23:59:48 ntpd[1473]: Listen normally on 3 eth0 10.128.0.45:123 May 14 23:59:48.938410 ntpd[1473]: 14 May 23:59:48 ntpd[1473]: Listen normally on 4 lo [::1]:123 May 14 23:59:48.938410 ntpd[1473]: 14 May 23:59:48 ntpd[1473]: bind(21) AF_INET6 fe80::4001:aff:fe80:2d%2#123 flags 0x11 failed: Cannot assign requested address May 14 23:59:48.938410 ntpd[1473]: 14 May 23:59:48 ntpd[1473]: unable to create socket on eth0 (5) for fe80::4001:aff:fe80:2d%2#123 May 14 23:59:48.938410 ntpd[1473]: 14 May 23:59:48 ntpd[1473]: failed to init interface for address fe80::4001:aff:fe80:2d%2 May 14 23:59:48.938410 ntpd[1473]: 14 May 23:59:48 ntpd[1473]: Listening on routing socket on fd #21 for interface updates May 14 23:59:48.938410 ntpd[1473]: 14 May 23:59:48 ntpd[1473]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 14 23:59:48.938410 ntpd[1473]: 14 May 23:59:48 ntpd[1473]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 14 23:59:48.943997 extend-filesystems[1471]: Resized partition /dev/sda9 May 14 23:59:48.726725 ntpd[1473]: ntpd 4.2.8p17@1.4004-o Wed May 14 21:38:42 UTC 2025 (1): Starting May 14 23:59:48.677665 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. May 14 23:59:48.954516 update_engine[1487]: I20250514 23:59:48.834201 1487 main.cc:92] Flatcar Update Engine starting May 14 23:59:48.954516 update_engine[1487]: I20250514 23:59:48.849637 1487 update_check_scheduler.cc:74] Next update check in 3m41s May 14 23:59:48.955045 extend-filesystems[1495]: resize2fs 1.47.1 (20-May-2024) May 14 23:59:48.955045 extend-filesystems[1495]: Filesystem at /dev/sda9 is mounted on /; on-line resizing required May 14 23:59:48.955045 extend-filesystems[1495]: old_desc_blocks = 1, new_desc_blocks = 2 May 14 23:59:48.955045 extend-filesystems[1495]: The filesystem on /dev/sda9 is now 2538491 (4k) blocks long. May 14 23:59:48.726761 ntpd[1473]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp May 14 23:59:48.682624 systemd[1]: Starting update-engine.service - Update Engine... May 14 23:59:49.065939 jq[1494]: true May 14 23:59:49.071005 extend-filesystems[1471]: Resized filesystem in /dev/sda9 May 14 23:59:48.726776 ntpd[1473]: ---------------------------------------------------- May 14 23:59:48.721438 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... May 14 23:59:48.726789 ntpd[1473]: ntp-4 is maintained by Network Time Foundation, May 14 23:59:48.735663 systemd[1]: Started dbus.service - D-Bus System Message Bus. May 14 23:59:48.726803 ntpd[1473]: Inc. (NTF), a non-profit 501(c)(3) public-benefit May 14 23:59:48.765359 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. May 14 23:59:48.726816 ntpd[1473]: corporation. Support and training for ntp-4 are May 14 23:59:48.792921 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. May 14 23:59:49.089196 jq[1501]: true May 14 23:59:48.726830 ntpd[1473]: available at https://www.nwtime.org/support May 14 23:59:48.794565 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. May 14 23:59:48.726843 ntpd[1473]: ---------------------------------------------------- May 14 23:59:48.795129 systemd[1]: extend-filesystems.service: Deactivated successfully. May 14 23:59:48.732615 ntpd[1473]: proto: precision = 0.090 usec (-23) May 14 23:59:48.796498 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. May 14 23:59:48.736609 ntpd[1473]: basedate set to 2025-05-02 May 14 23:59:48.813802 systemd[1]: motdgen.service: Deactivated successfully. May 14 23:59:48.736640 ntpd[1473]: gps base set to 2025-05-04 (week 2365) May 14 23:59:48.814136 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. May 14 23:59:48.741434 ntpd[1473]: Listen and drop on 0 v6wildcard [::]:123 May 14 23:59:48.826726 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. May 14 23:59:48.741508 ntpd[1473]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 14 23:59:48.827062 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. May 14 23:59:48.741779 ntpd[1473]: Listen normally on 2 lo 127.0.0.1:123 May 14 23:59:48.923149 systemd[1]: Started update-engine.service - Update Engine. May 14 23:59:48.741833 ntpd[1473]: Listen normally on 3 eth0 10.128.0.45:123 May 14 23:59:48.947168 (ntainerd)[1503]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR May 14 23:59:48.741890 ntpd[1473]: Listen normally on 4 lo [::1]:123 May 14 23:59:48.949339 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. May 14 23:59:48.741960 ntpd[1473]: bind(21) AF_INET6 fe80::4001:aff:fe80:2d%2#123 flags 0x11 failed: Cannot assign requested address May 14 23:59:48.969755 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 14 23:59:48.741994 ntpd[1473]: unable to create socket on eth0 (5) for fe80::4001:aff:fe80:2d%2#123 May 14 23:59:48.969808 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. May 14 23:59:48.742015 ntpd[1473]: failed to init interface for address fe80::4001:aff:fe80:2d%2 May 14 23:59:48.994702 systemd[1]: Starting systemd-hostnamed.service - Hostname Service... May 14 23:59:48.742058 ntpd[1473]: Listening on routing socket on fd #21 for interface updates May 14 23:59:49.014507 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 14 23:59:48.751265 ntpd[1473]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 14 23:59:49.014573 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. May 14 23:59:48.751335 ntpd[1473]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 14 23:59:49.042657 systemd[1]: Started locksmithd.service - Cluster reboot manager. May 14 23:59:48.904872 dbus-daemon[1467]: [system] Successfully activated service 'org.freedesktop.systemd1' May 14 23:59:49.053886 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. May 14 23:59:49.074411 systemd-networkd[1397]: eth0: Gained IPv6LL May 14 23:59:49.086434 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. May 14 23:59:49.101390 systemd-logind[1483]: Watching system buttons on /dev/input/event1 (Power Button) May 14 23:59:49.101426 systemd-logind[1483]: Watching system buttons on /dev/input/event2 (Sleep Button) May 14 23:59:49.101461 systemd-logind[1483]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) May 14 23:59:49.103543 systemd-logind[1483]: New seat seat0. May 14 23:59:49.108098 systemd[1]: Started systemd-logind.service - User Login Management. May 14 23:59:49.128966 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. May 14 23:59:49.243209 systemd[1]: Started systemd-hostnamed.service - Hostname Service. May 14 23:59:49.264611 dbus-daemon[1467]: [system] Successfully activated service 'org.freedesktop.hostname1' May 14 23:59:49.272586 systemd[1]: Reached target network-online.target - Network is Online. May 14 23:59:49.275396 dbus-daemon[1467]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.5' (uid=0 pid=1518 comm="/usr/lib/systemd/systemd-hostnamed" label="system_u:system_r:kernel_t:s0") May 14 23:59:49.296679 systemd[1]: Starting issuegen.service - Generate /run/issue... May 14 23:59:49.312267 locksmithd[1520]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" May 14 23:59:49.323871 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... May 14 23:59:49.344068 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... May 14 23:59:49.364453 systemd[1]: Starting oem-gce.service - GCE Linux Agent... May 14 23:59:49.370244 bash[1548]: Updated "/home/core/.ssh/authorized_keys" May 14 23:59:49.373615 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. May 14 23:59:49.386174 systemd[1]: Started sshd@0-10.128.0.45:22-147.75.109.163:53066.service - OpenSSH per-connection server daemon (147.75.109.163:53066). May 14 23:59:49.407313 init.sh[1554]: + '[' -e /etc/default/instance_configs.cfg.template ']' May 14 23:59:49.408878 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. May 14 23:59:49.411530 init.sh[1554]: + echo -e '[InstanceSetup]\nset_host_keys = false' May 14 23:59:49.412041 init.sh[1554]: + /usr/bin/google_instance_setup May 14 23:59:49.422401 systemd[1]: issuegen.service: Deactivated successfully. May 14 23:59:49.422839 systemd[1]: Finished issuegen.service - Generate /run/issue. May 14 23:59:49.464783 systemd[1]: Starting polkit.service - Authorization Manager... May 14 23:59:49.486740 systemd[1]: Starting sshkeys.service... May 14 23:59:49.500443 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... May 14 23:59:49.514639 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. May 14 23:59:49.578611 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. May 14 23:59:49.599867 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... May 14 23:59:49.607338 polkitd[1569]: Started polkitd version 121 May 14 23:59:49.627033 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. May 14 23:59:49.634382 polkitd[1569]: Loading rules from directory /etc/polkit-1/rules.d May 14 23:59:49.634526 polkitd[1569]: Loading rules from directory /usr/share/polkit-1/rules.d May 14 23:59:49.637549 polkitd[1569]: Finished loading, compiling and executing 2 rules May 14 23:59:49.638354 dbus-daemon[1467]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' May 14 23:59:49.640851 systemd[1]: Started polkit.service - Authorization Manager. May 14 23:59:49.641409 polkitd[1569]: Acquired the name org.freedesktop.PolicyKit1 on the system bus May 14 23:59:49.660044 systemd[1]: Started getty@tty1.service - Getty on tty1. May 14 23:59:49.683782 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. May 14 23:59:49.693982 systemd[1]: Reached target getty.target - Login Prompts. May 14 23:59:49.725423 systemd-hostnamed[1518]: Hostname set to (transient) May 14 23:59:49.733449 systemd-resolved[1336]: System hostname changed to 'ci-4230-1-1-nightly-20250514-2100-3f0309ee793f4b5001c9'. May 14 23:59:49.741905 coreos-metadata[1576]: May 14 23:59:49.741 INFO Fetching http://169.254.169.254/computeMetadata/v1/instance/attributes/sshKeys: Attempt #1 May 14 23:59:49.746664 coreos-metadata[1576]: May 14 23:59:49.746 INFO Fetch failed with 404: resource not found May 14 23:59:49.746664 coreos-metadata[1576]: May 14 23:59:49.746 INFO Fetching http://169.254.169.254/computeMetadata/v1/instance/attributes/ssh-keys: Attempt #1 May 14 23:59:49.746664 coreos-metadata[1576]: May 14 23:59:49.746 INFO Fetch successful May 14 23:59:49.746664 coreos-metadata[1576]: May 14 23:59:49.746 INFO Fetching http://169.254.169.254/computeMetadata/v1/instance/attributes/block-project-ssh-keys: Attempt #1 May 14 23:59:49.746664 coreos-metadata[1576]: May 14 23:59:49.746 INFO Fetch failed with 404: resource not found May 14 23:59:49.746664 coreos-metadata[1576]: May 14 23:59:49.746 INFO Fetching http://169.254.169.254/computeMetadata/v1/project/attributes/sshKeys: Attempt #1 May 14 23:59:49.747143 coreos-metadata[1576]: May 14 23:59:49.746 INFO Fetch failed with 404: resource not found May 14 23:59:49.747143 coreos-metadata[1576]: May 14 23:59:49.746 INFO Fetching http://169.254.169.254/computeMetadata/v1/project/attributes/ssh-keys: Attempt #1 May 14 23:59:49.751971 coreos-metadata[1576]: May 14 23:59:49.750 INFO Fetch successful May 14 23:59:49.760684 unknown[1576]: wrote ssh authorized keys file for user: core May 14 23:59:49.814945 containerd[1503]: time="2025-05-14T23:59:49.814252320Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 May 14 23:59:49.829309 update-ssh-keys[1591]: Updated "/home/core/.ssh/authorized_keys" May 14 23:59:49.831404 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). May 14 23:59:49.852062 systemd[1]: Finished sshkeys.service. May 14 23:59:49.884886 containerd[1503]: time="2025-05-14T23:59:49.884806408Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 May 14 23:59:49.888069 containerd[1503]: time="2025-05-14T23:59:49.887996035Z" 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.89-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 May 14 23:59:49.888311 containerd[1503]: time="2025-05-14T23:59:49.888269419Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 May 14 23:59:49.888414 containerd[1503]: time="2025-05-14T23:59:49.888394882Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 May 14 23:59:49.890316 containerd[1503]: time="2025-05-14T23:59:49.888712678Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 May 14 23:59:49.890316 containerd[1503]: time="2025-05-14T23:59:49.888757193Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 May 14 23:59:49.890316 containerd[1503]: time="2025-05-14T23:59:49.888874947Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 May 14 23:59:49.890316 containerd[1503]: time="2025-05-14T23:59:49.888898812Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 May 14 23:59:49.890316 containerd[1503]: time="2025-05-14T23:59:49.889275468Z" 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 14 23:59:49.890316 containerd[1503]: time="2025-05-14T23:59:49.889323285Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 May 14 23:59:49.890316 containerd[1503]: time="2025-05-14T23:59:49.889351152Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 May 14 23:59:49.890316 containerd[1503]: time="2025-05-14T23:59:49.889369349Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 May 14 23:59:49.890316 containerd[1503]: time="2025-05-14T23:59:49.889513124Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 May 14 23:59:49.890316 containerd[1503]: time="2025-05-14T23:59:49.889837006Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 May 14 23:59:49.890316 containerd[1503]: time="2025-05-14T23:59:49.890118381Z" 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 14 23:59:49.890846 containerd[1503]: time="2025-05-14T23:59:49.890143379Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 May 14 23:59:49.890964 containerd[1503]: time="2025-05-14T23:59:49.890933562Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 May 14 23:59:49.891162 containerd[1503]: time="2025-05-14T23:59:49.891133841Z" level=info msg="metadata content store policy set" policy=shared May 14 23:59:49.899035 containerd[1503]: time="2025-05-14T23:59:49.898989341Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 May 14 23:59:49.899226 sshd[1559]: Accepted publickey for core from 147.75.109.163 port 53066 ssh2: RSA SHA256:nB3rfAjpYEbSq8n6PQrtMylFAWgbUednLoUiXVQVfZs May 14 23:59:49.899860 containerd[1503]: time="2025-05-14T23:59:49.899815605Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 May 14 23:59:49.900985 containerd[1503]: time="2025-05-14T23:59:49.900776973Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 May 14 23:59:49.900985 containerd[1503]: time="2025-05-14T23:59:49.900847833Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 May 14 23:59:49.900985 containerd[1503]: time="2025-05-14T23:59:49.900901834Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 May 14 23:59:49.901507 containerd[1503]: time="2025-05-14T23:59:49.901455614Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 May 14 23:59:49.902037 containerd[1503]: time="2025-05-14T23:59:49.902003837Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 May 14 23:59:49.902259 containerd[1503]: time="2025-05-14T23:59:49.902207977Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 May 14 23:59:49.902259 containerd[1503]: time="2025-05-14T23:59:49.902245096Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 May 14 23:59:49.902404 containerd[1503]: time="2025-05-14T23:59:49.902276283Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 May 14 23:59:49.903218 sshd-session[1559]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 23:59:49.905210 containerd[1503]: time="2025-05-14T23:59:49.905179001Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 May 14 23:59:49.905325 containerd[1503]: time="2025-05-14T23:59:49.905225555Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 May 14 23:59:49.905325 containerd[1503]: time="2025-05-14T23:59:49.905251648Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 May 14 23:59:49.905529 containerd[1503]: time="2025-05-14T23:59:49.905490710Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 May 14 23:59:49.905589 containerd[1503]: time="2025-05-14T23:59:49.905540911Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 May 14 23:59:49.905589 containerd[1503]: time="2025-05-14T23:59:49.905572371Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 May 14 23:59:49.905722 containerd[1503]: time="2025-05-14T23:59:49.905614668Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 May 14 23:59:49.905722 containerd[1503]: time="2025-05-14T23:59:49.905642647Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 May 14 23:59:49.905722 containerd[1503]: time="2025-05-14T23:59:49.905683351Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 May 14 23:59:49.905916 containerd[1503]: time="2025-05-14T23:59:49.905718493Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 May 14 23:59:49.905916 containerd[1503]: time="2025-05-14T23:59:49.905755417Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 May 14 23:59:49.905916 containerd[1503]: time="2025-05-14T23:59:49.905782839Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 May 14 23:59:49.905916 containerd[1503]: time="2025-05-14T23:59:49.905814499Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 May 14 23:59:49.905916 containerd[1503]: time="2025-05-14T23:59:49.905841524Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 May 14 23:59:49.905916 containerd[1503]: time="2025-05-14T23:59:49.905864396Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 May 14 23:59:49.905916 containerd[1503]: time="2025-05-14T23:59:49.905889046Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 May 14 23:59:49.905916 containerd[1503]: time="2025-05-14T23:59:49.905915085Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 May 14 23:59:49.906398 containerd[1503]: time="2025-05-14T23:59:49.905942871Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 May 14 23:59:49.906398 containerd[1503]: time="2025-05-14T23:59:49.905967337Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 May 14 23:59:49.906398 containerd[1503]: time="2025-05-14T23:59:49.905989663Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 May 14 23:59:49.906398 containerd[1503]: time="2025-05-14T23:59:49.906015285Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 May 14 23:59:49.906398 containerd[1503]: time="2025-05-14T23:59:49.906042319Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 May 14 23:59:49.906398 containerd[1503]: time="2025-05-14T23:59:49.906085089Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 May 14 23:59:49.906398 containerd[1503]: time="2025-05-14T23:59:49.906131179Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 May 14 23:59:49.906398 containerd[1503]: time="2025-05-14T23:59:49.906153941Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 May 14 23:59:49.907432 containerd[1503]: time="2025-05-14T23:59:49.907366465Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 May 14 23:59:49.909316 containerd[1503]: time="2025-05-14T23:59:49.907633004Z" 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 14 23:59:49.909316 containerd[1503]: time="2025-05-14T23:59:49.907662953Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 May 14 23:59:49.909316 containerd[1503]: time="2025-05-14T23:59:49.907686736Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 May 14 23:59:49.909316 containerd[1503]: time="2025-05-14T23:59:49.907704049Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 May 14 23:59:49.909316 containerd[1503]: time="2025-05-14T23:59:49.907726938Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 May 14 23:59:49.909316 containerd[1503]: time="2025-05-14T23:59:49.907746050Z" level=info msg="NRI interface is disabled by configuration." May 14 23:59:49.909316 containerd[1503]: time="2025-05-14T23:59:49.907765311Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 May 14 23:59:49.909673 containerd[1503]: time="2025-05-14T23:59:49.908321596Z" 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 14 23:59:49.909673 containerd[1503]: time="2025-05-14T23:59:49.908480726Z" level=info msg="Connect containerd service" May 14 23:59:49.909673 containerd[1503]: time="2025-05-14T23:59:49.908543177Z" level=info msg="using legacy CRI server" May 14 23:59:49.909673 containerd[1503]: time="2025-05-14T23:59:49.908555223Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" May 14 23:59:49.909673 containerd[1503]: time="2025-05-14T23:59:49.908760448Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" May 14 23:59:49.910715 containerd[1503]: time="2025-05-14T23:59:49.910681299Z" 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 14 23:59:49.911005 containerd[1503]: time="2025-05-14T23:59:49.910955328Z" level=info msg="Start subscribing containerd event" May 14 23:59:49.911154 containerd[1503]: time="2025-05-14T23:59:49.911132419Z" level=info msg="Start recovering state" May 14 23:59:49.911346 containerd[1503]: time="2025-05-14T23:59:49.911323709Z" level=info msg="Start event monitor" May 14 23:59:49.911520 containerd[1503]: time="2025-05-14T23:59:49.911501050Z" level=info msg="Start snapshots syncer" May 14 23:59:49.911609 containerd[1503]: time="2025-05-14T23:59:49.911592207Z" level=info msg="Start cni network conf syncer for default" May 14 23:59:49.911687 containerd[1503]: time="2025-05-14T23:59:49.911671880Z" level=info msg="Start streaming server" May 14 23:59:49.912648 containerd[1503]: time="2025-05-14T23:59:49.912616282Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc May 14 23:59:49.912831 containerd[1503]: time="2025-05-14T23:59:49.912810078Z" level=info msg=serving... address=/run/containerd/containerd.sock May 14 23:59:49.913043 containerd[1503]: time="2025-05-14T23:59:49.913021952Z" level=info msg="containerd successfully booted in 0.101907s" May 14 23:59:49.913150 systemd[1]: Started containerd.service - containerd container runtime. May 14 23:59:49.938459 systemd[1]: Created slice user-500.slice - User Slice of UID 500. May 14 23:59:49.957857 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... May 14 23:59:50.007232 systemd-logind[1483]: New session 1 of user core. May 14 23:59:50.019832 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. May 14 23:59:50.044691 systemd[1]: Starting user@500.service - User Manager for UID 500... May 14 23:59:50.082673 (systemd)[1598]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) May 14 23:59:50.088490 systemd-logind[1483]: New session c1 of user core. May 14 23:59:50.413544 systemd[1598]: Queued start job for default target default.target. May 14 23:59:50.422842 systemd[1598]: Created slice app.slice - User Application Slice. May 14 23:59:50.423385 systemd[1598]: Reached target paths.target - Paths. May 14 23:59:50.423736 systemd[1598]: Reached target timers.target - Timers. May 14 23:59:50.428133 systemd[1598]: Starting dbus.socket - D-Bus User Message Bus Socket... May 14 23:59:50.462456 systemd[1598]: Listening on dbus.socket - D-Bus User Message Bus Socket. May 14 23:59:50.464181 systemd[1598]: Reached target sockets.target - Sockets. May 14 23:59:50.464277 systemd[1598]: Reached target basic.target - Basic System. May 14 23:59:50.464530 systemd[1598]: Reached target default.target - Main User Target. May 14 23:59:50.464587 systemd[1598]: Startup finished in 360ms. May 14 23:59:50.464731 systemd[1]: Started user@500.service - User Manager for UID 500. May 14 23:59:50.481520 systemd[1]: Started session-1.scope - Session 1 of User core. May 14 23:59:50.518334 instance-setup[1562]: INFO Running google_set_multiqueue. May 14 23:59:50.537727 instance-setup[1562]: INFO Set channels for eth0 to 2. May 14 23:59:50.542512 instance-setup[1562]: INFO Setting /proc/irq/31/smp_affinity_list to 0 for device virtio1. May 14 23:59:50.544421 instance-setup[1562]: INFO /proc/irq/31/smp_affinity_list: real affinity 0 May 14 23:59:50.544654 instance-setup[1562]: INFO Setting /proc/irq/32/smp_affinity_list to 0 for device virtio1. May 14 23:59:50.546525 instance-setup[1562]: INFO /proc/irq/32/smp_affinity_list: real affinity 0 May 14 23:59:50.546900 instance-setup[1562]: INFO Setting /proc/irq/33/smp_affinity_list to 1 for device virtio1. May 14 23:59:50.549112 instance-setup[1562]: INFO /proc/irq/33/smp_affinity_list: real affinity 1 May 14 23:59:50.549168 instance-setup[1562]: INFO Setting /proc/irq/34/smp_affinity_list to 1 for device virtio1. May 14 23:59:50.550711 instance-setup[1562]: INFO /proc/irq/34/smp_affinity_list: real affinity 1 May 14 23:59:50.560028 instance-setup[1562]: INFO /usr/sbin/google_set_multiqueue: line 133: echo: write error: Value too large for defined data type May 14 23:59:50.564443 instance-setup[1562]: INFO /usr/sbin/google_set_multiqueue: line 133: echo: write error: Value too large for defined data type May 14 23:59:50.566831 instance-setup[1562]: INFO Queue 0 XPS=1 for /sys/class/net/eth0/queues/tx-0/xps_cpus May 14 23:59:50.566890 instance-setup[1562]: INFO Queue 1 XPS=2 for /sys/class/net/eth0/queues/tx-1/xps_cpus May 14 23:59:50.595767 init.sh[1554]: + /usr/bin/google_metadata_script_runner --script-type startup May 14 23:59:50.736883 systemd[1]: Started sshd@1-10.128.0.45:22-147.75.109.163:53070.service - OpenSSH per-connection server daemon (147.75.109.163:53070). May 14 23:59:50.817214 startup-script[1637]: INFO Starting startup scripts. May 14 23:59:50.823539 startup-script[1637]: INFO No startup scripts found in metadata. May 14 23:59:50.823616 startup-script[1637]: INFO Finished running startup scripts. May 14 23:59:50.850341 init.sh[1554]: + trap 'stopping=1 ; kill "${daemon_pids[@]}" || :' SIGTERM May 14 23:59:50.850947 init.sh[1554]: + daemon_pids=() May 14 23:59:50.850947 init.sh[1554]: + for d in accounts clock_skew network May 14 23:59:50.851627 init.sh[1554]: + daemon_pids+=($!) May 14 23:59:50.851627 init.sh[1554]: + for d in accounts clock_skew network May 14 23:59:50.851987 init.sh[1644]: + /usr/bin/google_accounts_daemon May 14 23:59:50.853537 init.sh[1554]: + daemon_pids+=($!) May 14 23:59:50.853537 init.sh[1554]: + for d in accounts clock_skew network May 14 23:59:50.853537 init.sh[1554]: + daemon_pids+=($!) May 14 23:59:50.853537 init.sh[1554]: + NOTIFY_SOCKET=/run/systemd/notify May 14 23:59:50.853537 init.sh[1554]: + /usr/bin/systemd-notify --ready May 14 23:59:50.854039 init.sh[1645]: + /usr/bin/google_clock_skew_daemon May 14 23:59:50.854380 init.sh[1646]: + /usr/bin/google_network_daemon May 14 23:59:50.883599 systemd[1]: Started oem-gce.service - GCE Linux Agent. May 14 23:59:50.914403 init.sh[1554]: + wait -n 1644 1645 1646 May 14 23:59:51.111786 sshd[1640]: Accepted publickey for core from 147.75.109.163 port 53070 ssh2: RSA SHA256:nB3rfAjpYEbSq8n6PQrtMylFAWgbUednLoUiXVQVfZs May 14 23:59:51.114571 sshd-session[1640]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 23:59:51.129548 systemd-logind[1483]: New session 2 of user core. May 14 23:59:51.135587 systemd[1]: Started session-2.scope - Session 2 of User core. May 14 23:59:51.324983 google-clock-skew[1645]: INFO Starting Google Clock Skew daemon. May 14 23:59:51.338464 google-clock-skew[1645]: INFO Clock drift token has changed: 0. May 14 23:59:51.347153 sshd[1650]: Connection closed by 147.75.109.163 port 53070 May 14 23:59:51.347801 sshd-session[1640]: pam_unix(sshd:session): session closed for user core May 14 23:59:51.360232 systemd[1]: sshd@1-10.128.0.45:22-147.75.109.163:53070.service: Deactivated successfully. May 14 23:59:51.365630 systemd[1]: session-2.scope: Deactivated successfully. May 14 23:59:51.367714 systemd-logind[1483]: Session 2 logged out. Waiting for processes to exit. May 14 23:59:51.371738 systemd-logind[1483]: Removed session 2. May 14 23:59:51.380176 google-networking[1646]: INFO Starting Google Networking daemon. May 14 23:59:51.411327 systemd[1]: Started sshd@2-10.128.0.45:22-147.75.109.163:53074.service - OpenSSH per-connection server daemon (147.75.109.163:53074). May 14 23:59:51.432857 groupadd[1657]: group added to /etc/group: name=google-sudoers, GID=1000 May 14 23:59:51.438962 groupadd[1657]: group added to /etc/gshadow: name=google-sudoers May 14 23:59:52.000765 systemd-resolved[1336]: Clock change detected. Flushing caches. May 14 23:59:52.002790 google-clock-skew[1645]: INFO Synced system time with hardware clock. May 14 23:59:52.003118 groupadd[1657]: new group: name=google-sudoers, GID=1000 May 14 23:59:52.050121 google-accounts[1644]: INFO Starting Google Accounts daemon. May 14 23:59:52.065921 google-accounts[1644]: WARNING OS Login not installed. May 14 23:59:52.068076 google-accounts[1644]: INFO Creating a new user account for 0. May 14 23:59:52.070637 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. May 14 23:59:52.074012 init.sh[1678]: useradd: invalid user name '0': use --badname to ignore May 14 23:59:52.073870 google-accounts[1644]: WARNING Could not create user 0. Command '['useradd', '-m', '-s', '/bin/bash', '-p', '*', '0']' returned non-zero exit status 3.. May 14 23:59:52.087494 systemd[1]: Reached target multi-user.target - Multi-User System. May 14 23:59:52.088026 (kubelet)[1676]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS May 14 23:59:52.098188 systemd[1]: Startup finished in 1.017s (kernel) + 8.380s (initrd) + 9.518s (userspace) = 18.917s. May 14 23:59:52.226105 ntpd[1473]: Listen normally on 6 eth0 [fe80::4001:aff:fe80:2d%2]:123 May 14 23:59:52.226706 ntpd[1473]: 14 May 23:59:52 ntpd[1473]: Listen normally on 6 eth0 [fe80::4001:aff:fe80:2d%2]:123 May 14 23:59:52.239579 sshd[1663]: Accepted publickey for core from 147.75.109.163 port 53074 ssh2: RSA SHA256:nB3rfAjpYEbSq8n6PQrtMylFAWgbUednLoUiXVQVfZs May 14 23:59:52.242023 sshd-session[1663]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 23:59:52.251027 systemd-logind[1483]: New session 3 of user core. May 14 23:59:52.259291 systemd[1]: Started session-3.scope - Session 3 of User core. May 14 23:59:52.459351 sshd[1684]: Connection closed by 147.75.109.163 port 53074 May 14 23:59:52.460419 sshd-session[1663]: pam_unix(sshd:session): session closed for user core May 14 23:59:52.469146 systemd[1]: sshd@2-10.128.0.45:22-147.75.109.163:53074.service: Deactivated successfully. May 14 23:59:52.472412 systemd[1]: session-3.scope: Deactivated successfully. May 14 23:59:52.473498 systemd-logind[1483]: Session 3 logged out. Waiting for processes to exit. May 14 23:59:52.475370 systemd-logind[1483]: Removed session 3. May 14 23:59:52.977151 kubelet[1676]: E0514 23:59:52.977066 1676 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 14 23:59:52.979999 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE May 14 23:59:52.980274 systemd[1]: kubelet.service: Failed with result 'exit-code'. May 14 23:59:52.980907 systemd[1]: kubelet.service: Consumed 1.271s CPU time, 239.7M memory peak. May 15 00:00:02.530540 systemd[1]: Started logrotate.service - Rotate and Compress System Logs. May 15 00:00:02.533464 systemd[1]: Started sshd@3-10.128.0.45:22-147.75.109.163:54196.service - OpenSSH per-connection server daemon (147.75.109.163:54196). May 15 00:00:02.546593 systemd[1]: logrotate.service: Deactivated successfully. May 15 00:00:02.840045 sshd[1697]: Accepted publickey for core from 147.75.109.163 port 54196 ssh2: RSA SHA256:nB3rfAjpYEbSq8n6PQrtMylFAWgbUednLoUiXVQVfZs May 15 00:00:02.842285 sshd-session[1697]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 00:00:02.850058 systemd-logind[1483]: New session 4 of user core. May 15 00:00:02.861374 systemd[1]: Started session-4.scope - Session 4 of User core. May 15 00:00:03.013948 systemd[1]: kubelet.service: Scheduled restart job, restart counter is at 1. May 15 00:00:03.019255 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... May 15 00:00:03.057994 sshd[1700]: Connection closed by 147.75.109.163 port 54196 May 15 00:00:03.058883 sshd-session[1697]: pam_unix(sshd:session): session closed for user core May 15 00:00:03.065744 systemd[1]: sshd@3-10.128.0.45:22-147.75.109.163:54196.service: Deactivated successfully. May 15 00:00:03.070570 systemd[1]: session-4.scope: Deactivated successfully. May 15 00:00:03.072557 systemd-logind[1483]: Session 4 logged out. Waiting for processes to exit. May 15 00:00:03.074160 systemd-logind[1483]: Removed session 4. May 15 00:00:03.115333 systemd[1]: Started sshd@4-10.128.0.45:22-147.75.109.163:54198.service - OpenSSH per-connection server daemon (147.75.109.163:54198). May 15 00:00:03.286705 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. May 15 00:00:03.299530 (kubelet)[1716]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS May 15 00:00:03.360385 kubelet[1716]: E0515 00:00:03.360327 1716 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 15 00:00:03.365007 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE May 15 00:00:03.365262 systemd[1]: kubelet.service: Failed with result 'exit-code'. May 15 00:00:03.365757 systemd[1]: kubelet.service: Consumed 197ms CPU time, 98.1M memory peak. May 15 00:00:03.413534 sshd[1709]: Accepted publickey for core from 147.75.109.163 port 54198 ssh2: RSA SHA256:nB3rfAjpYEbSq8n6PQrtMylFAWgbUednLoUiXVQVfZs May 15 00:00:03.415624 sshd-session[1709]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 00:00:03.422682 systemd-logind[1483]: New session 5 of user core. May 15 00:00:03.429251 systemd[1]: Started session-5.scope - Session 5 of User core. May 15 00:00:03.621203 sshd[1723]: Connection closed by 147.75.109.163 port 54198 May 15 00:00:03.622445 sshd-session[1709]: pam_unix(sshd:session): session closed for user core May 15 00:00:03.628461 systemd[1]: sshd@4-10.128.0.45:22-147.75.109.163:54198.service: Deactivated successfully. May 15 00:00:03.631420 systemd[1]: session-5.scope: Deactivated successfully. May 15 00:00:03.634126 systemd-logind[1483]: Session 5 logged out. Waiting for processes to exit. May 15 00:00:03.635685 systemd-logind[1483]: Removed session 5. May 15 00:00:03.687604 systemd[1]: Started sshd@5-10.128.0.45:22-147.75.109.163:54206.service - OpenSSH per-connection server daemon (147.75.109.163:54206). May 15 00:00:03.980062 sshd[1729]: Accepted publickey for core from 147.75.109.163 port 54206 ssh2: RSA SHA256:nB3rfAjpYEbSq8n6PQrtMylFAWgbUednLoUiXVQVfZs May 15 00:00:03.981861 sshd-session[1729]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 00:00:03.988925 systemd-logind[1483]: New session 6 of user core. May 15 00:00:04.000324 systemd[1]: Started session-6.scope - Session 6 of User core. May 15 00:00:04.196542 sshd[1731]: Connection closed by 147.75.109.163 port 54206 May 15 00:00:04.197819 sshd-session[1729]: pam_unix(sshd:session): session closed for user core May 15 00:00:04.203649 systemd[1]: sshd@5-10.128.0.45:22-147.75.109.163:54206.service: Deactivated successfully. May 15 00:00:04.207003 systemd[1]: session-6.scope: Deactivated successfully. May 15 00:00:04.208225 systemd-logind[1483]: Session 6 logged out. Waiting for processes to exit. May 15 00:00:04.209857 systemd-logind[1483]: Removed session 6. May 15 00:00:04.258794 systemd[1]: Started sshd@6-10.128.0.45:22-147.75.109.163:54216.service - OpenSSH per-connection server daemon (147.75.109.163:54216). May 15 00:00:04.557380 sshd[1737]: Accepted publickey for core from 147.75.109.163 port 54216 ssh2: RSA SHA256:nB3rfAjpYEbSq8n6PQrtMylFAWgbUednLoUiXVQVfZs May 15 00:00:04.559272 sshd-session[1737]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 00:00:04.565278 systemd-logind[1483]: New session 7 of user core. May 15 00:00:04.573243 systemd[1]: Started session-7.scope - Session 7 of User core. May 15 00:00:04.751783 sudo[1740]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh May 15 00:00:04.752532 sudo[1740]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 15 00:00:05.604487 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. May 15 00:00:05.604797 systemd[1]: kubelet.service: Consumed 197ms CPU time, 98.1M memory peak. May 15 00:00:05.613826 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... May 15 00:00:05.661942 systemd[1]: Reload requested from client PID 1773 ('systemctl') (unit session-7.scope)... May 15 00:00:05.661996 systemd[1]: Reloading... May 15 00:00:05.843080 zram_generator::config[1818]: No configuration found. May 15 00:00:06.010630 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 15 00:00:06.166189 systemd[1]: Reloading finished in 503 ms. May 15 00:00:06.248232 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. May 15 00:00:06.249003 (kubelet)[1860]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS May 15 00:00:06.259591 systemd[1]: Stopping kubelet.service - kubelet: The Kubernetes Node Agent... May 15 00:00:06.260490 systemd[1]: kubelet.service: Deactivated successfully. May 15 00:00:06.260824 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. May 15 00:00:06.261070 systemd[1]: kubelet.service: Consumed 142ms CPU time, 85M memory peak. May 15 00:00:06.267751 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... May 15 00:00:06.557221 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. May 15 00:00:06.567071 (kubelet)[1876]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS May 15 00:00:06.623646 kubelet[1876]: 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 15 00:00:06.623646 kubelet[1876]: Flag --pod-infra-container-image has been deprecated, will be removed in a future release. Image garbage collector will get sandbox image information from CRI. May 15 00:00:06.623646 kubelet[1876]: 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 15 00:00:06.624244 kubelet[1876]: I0515 00:00:06.623794 1876 server.go:206] "--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 15 00:00:07.233680 kubelet[1876]: I0515 00:00:07.233619 1876 server.go:486] "Kubelet version" kubeletVersion="v1.31.0" May 15 00:00:07.233680 kubelet[1876]: I0515 00:00:07.233656 1876 server.go:488] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" May 15 00:00:07.234109 kubelet[1876]: I0515 00:00:07.234075 1876 server.go:929] "Client rotation is on, will bootstrap in background" May 15 00:00:07.290999 kubelet[1876]: I0515 00:00:07.290526 1876 dynamic_cafile_content.go:160] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" May 15 00:00:07.313511 kubelet[1876]: E0515 00:00:07.313446 1876 log.go:32] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" May 15 00:00:07.313996 kubelet[1876]: I0515 00:00:07.313911 1876 server.go:1403] "CRI implementation should be updated to support RuntimeConfig when KubeletCgroupDriverFromCRI feature gate has been enabled. Falling back to using cgroupDriver from kubelet config." May 15 00:00:07.321145 kubelet[1876]: I0515 00:00:07.321105 1876 server.go:744] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" May 15 00:00:07.323367 kubelet[1876]: I0515 00:00:07.323312 1876 swap_util.go:113] "Swap is on" /proc/swaps contents="Filename\t\t\t\tType\t\tSize\t\tUsed\t\tPriority" May 15 00:00:07.323726 kubelet[1876]: I0515 00:00:07.323664 1876 container_manager_linux.go:264] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] May 15 00:00:07.323974 kubelet[1876]: I0515 00:00:07.323707 1876 container_manager_linux.go:269] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.128.0.45","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.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"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 15 00:00:07.324165 kubelet[1876]: I0515 00:00:07.324006 1876 topology_manager.go:138] "Creating topology manager with none policy" May 15 00:00:07.324165 kubelet[1876]: I0515 00:00:07.324029 1876 container_manager_linux.go:300] "Creating device plugin manager" May 15 00:00:07.324346 kubelet[1876]: I0515 00:00:07.324188 1876 state_mem.go:36] "Initialized new in-memory state store" May 15 00:00:07.327293 kubelet[1876]: I0515 00:00:07.326914 1876 kubelet.go:408] "Attempting to sync node with API server" May 15 00:00:07.327293 kubelet[1876]: I0515 00:00:07.326984 1876 kubelet.go:303] "Adding static pod path" path="/etc/kubernetes/manifests" May 15 00:00:07.327293 kubelet[1876]: I0515 00:00:07.327035 1876 kubelet.go:314] "Adding apiserver pod source" May 15 00:00:07.327293 kubelet[1876]: I0515 00:00:07.327058 1876 apiserver.go:42] "Waiting for node sync before watching apiserver pods" May 15 00:00:07.327627 kubelet[1876]: E0515 00:00:07.327582 1876 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:07.327754 kubelet[1876]: E0515 00:00:07.327644 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:07.334950 kubelet[1876]: I0515 00:00:07.334916 1876 kuberuntime_manager.go:262] "Container runtime initialized" containerRuntime="containerd" version="v1.7.23" apiVersion="v1" May 15 00:00:07.337498 kubelet[1876]: I0515 00:00:07.337438 1876 kubelet.go:837] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" May 15 00:00:07.339642 kubelet[1876]: W0515 00:00:07.338641 1876 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. May 15 00:00:07.339642 kubelet[1876]: I0515 00:00:07.339443 1876 server.go:1269] "Started kubelet" May 15 00:00:07.344095 kubelet[1876]: I0515 00:00:07.343379 1876 server.go:163] "Starting to listen" address="0.0.0.0" port=10250 May 15 00:00:07.344095 kubelet[1876]: I0515 00:00:07.343609 1876 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" May 15 00:00:07.344704 kubelet[1876]: I0515 00:00:07.344657 1876 server.go:460] "Adding debug handlers to kubelet server" May 15 00:00:07.346070 kubelet[1876]: I0515 00:00:07.345995 1876 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 May 15 00:00:07.346296 kubelet[1876]: I0515 00:00:07.346261 1876 server.go:236] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" May 15 00:00:07.356863 kubelet[1876]: I0515 00:00:07.356813 1876 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 15 00:00:07.358494 kubelet[1876]: I0515 00:00:07.357017 1876 volume_manager.go:289] "Starting Kubelet Volume Manager" May 15 00:00:07.358494 kubelet[1876]: E0515 00:00:07.357307 1876 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.128.0.45\" not found" May 15 00:00:07.358494 kubelet[1876]: I0515 00:00:07.357615 1876 desired_state_of_world_populator.go:146] "Desired state populator starts to run" May 15 00:00:07.358494 kubelet[1876]: I0515 00:00:07.357706 1876 reconciler.go:26] "Reconciler: start to sync state" May 15 00:00:07.360733 kubelet[1876]: I0515 00:00:07.360581 1876 factory.go:221] Registration of the systemd container factory successfully May 15 00:00:07.360733 kubelet[1876]: I0515 00:00:07.360725 1876 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 15 00:00:07.363374 kubelet[1876]: I0515 00:00:07.363349 1876 factory.go:221] Registration of the containerd container factory successfully May 15 00:00:07.366611 kubelet[1876]: E0515 00:00:07.366586 1876 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.128.0.45\" not found" node="10.128.0.45" May 15 00:00:07.376743 kubelet[1876]: E0515 00:00:07.376707 1876 kubelet.go:1478] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" May 15 00:00:07.398234 kubelet[1876]: I0515 00:00:07.398095 1876 cpu_manager.go:214] "Starting CPU manager" policy="none" May 15 00:00:07.398504 kubelet[1876]: I0515 00:00:07.398123 1876 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" May 15 00:00:07.398504 kubelet[1876]: I0515 00:00:07.398343 1876 state_mem.go:36] "Initialized new in-memory state store" May 15 00:00:07.405320 kubelet[1876]: I0515 00:00:07.404874 1876 policy_none.go:49] "None policy: Start" May 15 00:00:07.407117 kubelet[1876]: I0515 00:00:07.406818 1876 memory_manager.go:170] "Starting memorymanager" policy="None" May 15 00:00:07.407117 kubelet[1876]: I0515 00:00:07.406851 1876 state_mem.go:35] "Initializing new in-memory state store" May 15 00:00:07.420076 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. May 15 00:00:07.447162 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. May 15 00:00:07.460976 kubelet[1876]: E0515 00:00:07.457605 1876 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.128.0.45\" not found" May 15 00:00:07.458079 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. May 15 00:00:07.471445 kubelet[1876]: I0515 00:00:07.471414 1876 manager.go:510] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" May 15 00:00:07.472008 kubelet[1876]: I0515 00:00:07.471984 1876 eviction_manager.go:189] "Eviction manager: starting control loop" May 15 00:00:07.472196 kubelet[1876]: I0515 00:00:07.472012 1876 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" May 15 00:00:07.474787 kubelet[1876]: I0515 00:00:07.474528 1876 plugin_manager.go:118] "Starting Kubelet Plugin Manager" May 15 00:00:07.477644 kubelet[1876]: E0515 00:00:07.477606 1876 eviction_manager.go:285] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.128.0.45\" not found" May 15 00:00:07.486154 kubelet[1876]: I0515 00:00:07.484161 1876 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" May 15 00:00:07.486154 kubelet[1876]: I0515 00:00:07.486092 1876 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" May 15 00:00:07.486154 kubelet[1876]: I0515 00:00:07.486117 1876 status_manager.go:217] "Starting to sync pod status with apiserver" May 15 00:00:07.486154 kubelet[1876]: I0515 00:00:07.486141 1876 kubelet.go:2321] "Starting kubelet main sync loop" May 15 00:00:07.486376 kubelet[1876]: E0515 00:00:07.486198 1876 kubelet.go:2345] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" May 15 00:00:07.573936 kubelet[1876]: I0515 00:00:07.573860 1876 kubelet_node_status.go:72] "Attempting to register node" node="10.128.0.45" May 15 00:00:07.586188 kubelet[1876]: I0515 00:00:07.586153 1876 kubelet_node_status.go:75] "Successfully registered node" node="10.128.0.45" May 15 00:00:07.602160 kubelet[1876]: I0515 00:00:07.601981 1876 kuberuntime_manager.go:1633] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" May 15 00:00:07.603643 containerd[1503]: time="2025-05-15T00:00:07.603581807Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." May 15 00:00:07.604202 kubelet[1876]: I0515 00:00:07.603849 1876 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" May 15 00:00:07.618537 sudo[1740]: pam_unix(sudo:session): session closed for user root May 15 00:00:07.661195 sshd[1739]: Connection closed by 147.75.109.163 port 54216 May 15 00:00:07.662077 sshd-session[1737]: pam_unix(sshd:session): session closed for user core May 15 00:00:07.666628 systemd[1]: sshd@6-10.128.0.45:22-147.75.109.163:54216.service: Deactivated successfully. May 15 00:00:07.670168 systemd[1]: session-7.scope: Deactivated successfully. May 15 00:00:07.670469 systemd[1]: session-7.scope: Consumed 632ms CPU time, 76.7M memory peak. May 15 00:00:07.673091 systemd-logind[1483]: Session 7 logged out. Waiting for processes to exit. May 15 00:00:07.674837 systemd-logind[1483]: Removed session 7. May 15 00:00:08.236494 kubelet[1876]: I0515 00:00:08.236450 1876 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" May 15 00:00:08.237513 kubelet[1876]: W0515 00:00:08.236701 1876 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.Service 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 15 00:00:08.237513 kubelet[1876]: W0515 00:00:08.236751 1876 reflector.go:484] 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 15 00:00:08.237513 kubelet[1876]: W0515 00:00:08.236781 1876 reflector.go:484] 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 15 00:00:08.328179 kubelet[1876]: I0515 00:00:08.328107 1876 apiserver.go:52] "Watching apiserver" May 15 00:00:08.328598 kubelet[1876]: E0515 00:00:08.328095 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:08.345664 systemd[1]: Created slice kubepods-burstable-poda1513ab5_ed1f_4ad1_9321_c3f902fcc331.slice - libcontainer container kubepods-burstable-poda1513ab5_ed1f_4ad1_9321_c3f902fcc331.slice. May 15 00:00:08.358043 kubelet[1876]: I0515 00:00:08.358016 1876 desired_state_of_world_populator.go:154] "Finished populating initial desired state of world" May 15 00:00:08.363163 kubelet[1876]: I0515 00:00:08.363128 1876 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/a1513ab5-ed1f-4ad1-9321-c3f902fcc331-cni-plugin\") pod \"kube-flannel-ds-lsh6j\" (UID: \"a1513ab5-ed1f-4ad1-9321-c3f902fcc331\") " pod="kube-flannel/kube-flannel-ds-lsh6j" May 15 00:00:08.363163 kubelet[1876]: I0515 00:00:08.363177 1876 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/a1513ab5-ed1f-4ad1-9321-c3f902fcc331-cni\") pod \"kube-flannel-ds-lsh6j\" (UID: \"a1513ab5-ed1f-4ad1-9321-c3f902fcc331\") " pod="kube-flannel/kube-flannel-ds-lsh6j" May 15 00:00:08.363514 kubelet[1876]: I0515 00:00:08.363220 1876 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-kjm9c\" (UniqueName: \"kubernetes.io/projected/a1513ab5-ed1f-4ad1-9321-c3f902fcc331-kube-api-access-kjm9c\") pod \"kube-flannel-ds-lsh6j\" (UID: \"a1513ab5-ed1f-4ad1-9321-c3f902fcc331\") " pod="kube-flannel/kube-flannel-ds-lsh6j" May 15 00:00:08.363514 kubelet[1876]: I0515 00:00:08.363249 1876 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/8bbdd121-b198-4406-8b4d-14f854fef711-kube-proxy\") pod \"kube-proxy-z78hf\" (UID: \"8bbdd121-b198-4406-8b4d-14f854fef711\") " pod="kube-system/kube-proxy-z78hf" May 15 00:00:08.363514 kubelet[1876]: I0515 00:00:08.363284 1876 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/8bbdd121-b198-4406-8b4d-14f854fef711-xtables-lock\") pod \"kube-proxy-z78hf\" (UID: \"8bbdd121-b198-4406-8b4d-14f854fef711\") " pod="kube-system/kube-proxy-z78hf" May 15 00:00:08.363514 kubelet[1876]: I0515 00:00:08.363327 1876 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/8bbdd121-b198-4406-8b4d-14f854fef711-lib-modules\") pod \"kube-proxy-z78hf\" (UID: \"8bbdd121-b198-4406-8b4d-14f854fef711\") " pod="kube-system/kube-proxy-z78hf" May 15 00:00:08.363514 kubelet[1876]: I0515 00:00:08.363366 1876 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/a1513ab5-ed1f-4ad1-9321-c3f902fcc331-run\") pod \"kube-flannel-ds-lsh6j\" (UID: \"a1513ab5-ed1f-4ad1-9321-c3f902fcc331\") " pod="kube-flannel/kube-flannel-ds-lsh6j" May 15 00:00:08.363734 kubelet[1876]: I0515 00:00:08.363404 1876 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/a1513ab5-ed1f-4ad1-9321-c3f902fcc331-xtables-lock\") pod \"kube-flannel-ds-lsh6j\" (UID: \"a1513ab5-ed1f-4ad1-9321-c3f902fcc331\") " pod="kube-flannel/kube-flannel-ds-lsh6j" May 15 00:00:08.363734 kubelet[1876]: I0515 00:00:08.363438 1876 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-8z2dd\" (UniqueName: \"kubernetes.io/projected/8bbdd121-b198-4406-8b4d-14f854fef711-kube-api-access-8z2dd\") pod \"kube-proxy-z78hf\" (UID: \"8bbdd121-b198-4406-8b4d-14f854fef711\") " pod="kube-system/kube-proxy-z78hf" May 15 00:00:08.363734 kubelet[1876]: I0515 00:00:08.363465 1876 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/a1513ab5-ed1f-4ad1-9321-c3f902fcc331-flannel-cfg\") pod \"kube-flannel-ds-lsh6j\" (UID: \"a1513ab5-ed1f-4ad1-9321-c3f902fcc331\") " pod="kube-flannel/kube-flannel-ds-lsh6j" May 15 00:00:08.371068 systemd[1]: Created slice kubepods-besteffort-pod8bbdd121_b198_4406_8b4d_14f854fef711.slice - libcontainer container kubepods-besteffort-pod8bbdd121_b198_4406_8b4d_14f854fef711.slice. May 15 00:00:08.668629 containerd[1503]: time="2025-05-15T00:00:08.668472444Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-lsh6j,Uid:a1513ab5-ed1f-4ad1-9321-c3f902fcc331,Namespace:kube-flannel,Attempt:0,}" May 15 00:00:08.696524 containerd[1503]: time="2025-05-15T00:00:08.696378225Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-z78hf,Uid:8bbdd121-b198-4406-8b4d-14f854fef711,Namespace:kube-system,Attempt:0,}" May 15 00:00:09.147548 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2258235919.mount: Deactivated successfully. May 15 00:00:09.160264 containerd[1503]: time="2025-05-15T00:00:09.160174233Z" 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 15 00:00:09.162507 containerd[1503]: time="2025-05-15T00:00:09.162434636Z" 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 15 00:00:09.163781 containerd[1503]: time="2025-05-15T00:00:09.163718388Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=313954" May 15 00:00:09.166987 containerd[1503]: time="2025-05-15T00:00:09.165031649Z" 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 15 00:00:09.166987 containerd[1503]: time="2025-05-15T00:00:09.165663154Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" May 15 00:00:09.170225 containerd[1503]: time="2025-05-15T00:00:09.170175885Z" 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 15 00:00:09.171459 containerd[1503]: time="2025-05-15T00:00:09.171416207Z" 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 501.87797ms" May 15 00:00:09.172949 containerd[1503]: time="2025-05-15T00:00:09.172901477Z" 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 476.179835ms" May 15 00:00:09.329930 kubelet[1876]: E0515 00:00:09.329855 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:09.374546 containerd[1503]: time="2025-05-15T00:00:09.374365554Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 15 00:00:09.374546 containerd[1503]: time="2025-05-15T00:00:09.374465064Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 15 00:00:09.374937 containerd[1503]: time="2025-05-15T00:00:09.374526246Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 15 00:00:09.374937 containerd[1503]: time="2025-05-15T00:00:09.374811711Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 15 00:00:09.382785 containerd[1503]: time="2025-05-15T00:00:09.382601764Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 15 00:00:09.382785 containerd[1503]: time="2025-05-15T00:00:09.382697685Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 15 00:00:09.382785 containerd[1503]: time="2025-05-15T00:00:09.382727531Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 15 00:00:09.384212 containerd[1503]: time="2025-05-15T00:00:09.383802235Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 15 00:00:09.519242 systemd[1]: Started cri-containerd-f563130ce6ec34cc6d52ad08be6431c31a472f218594dfe99d62229f414991a0.scope - libcontainer container f563130ce6ec34cc6d52ad08be6431c31a472f218594dfe99d62229f414991a0. May 15 00:00:09.542260 systemd[1]: Started cri-containerd-c63f337d3d971abd523246b393b140bd99ed46499a609be111f9ca1a5fe8a2eb.scope - libcontainer container c63f337d3d971abd523246b393b140bd99ed46499a609be111f9ca1a5fe8a2eb. May 15 00:00:09.603848 containerd[1503]: time="2025-05-15T00:00:09.603652187Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-z78hf,Uid:8bbdd121-b198-4406-8b4d-14f854fef711,Namespace:kube-system,Attempt:0,} returns sandbox id \"c63f337d3d971abd523246b393b140bd99ed46499a609be111f9ca1a5fe8a2eb\"" May 15 00:00:09.611291 containerd[1503]: time="2025-05-15T00:00:09.610619763Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.8\"" May 15 00:00:09.630122 containerd[1503]: time="2025-05-15T00:00:09.630062703Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-lsh6j,Uid:a1513ab5-ed1f-4ad1-9321-c3f902fcc331,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"f563130ce6ec34cc6d52ad08be6431c31a472f218594dfe99d62229f414991a0\"" May 15 00:00:10.331100 kubelet[1876]: E0515 00:00:10.330841 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:10.749297 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount656361202.mount: Deactivated successfully. May 15 00:00:11.332073 kubelet[1876]: E0515 00:00:11.331997 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:11.431940 containerd[1503]: time="2025-05-15T00:00:11.431857005Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.31.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 15 00:00:11.438000 containerd[1503]: time="2025-05-15T00:00:11.436673240Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.31.8: active requests=0, bytes read=30356520" May 15 00:00:11.442732 containerd[1503]: time="2025-05-15T00:00:11.441850234Z" level=info msg="ImageCreate event name:\"sha256:7d73f013cedcf301aef42272c93e4c1174dab1a8eccd96840091ef04b63480f2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 15 00:00:11.444787 containerd[1503]: time="2025-05-15T00:00:11.444734578Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:dd0c9a37670f209947b1ed880f06a2e93e1d41da78c037f52f94b13858769838\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 15 00:00:11.446585 containerd[1503]: time="2025-05-15T00:00:11.445921453Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.31.8\" with image id \"sha256:7d73f013cedcf301aef42272c93e4c1174dab1a8eccd96840091ef04b63480f2\", repo tag \"registry.k8s.io/kube-proxy:v1.31.8\", repo digest \"registry.k8s.io/kube-proxy@sha256:dd0c9a37670f209947b1ed880f06a2e93e1d41da78c037f52f94b13858769838\", size \"30353644\" in 1.835240481s" May 15 00:00:11.446585 containerd[1503]: time="2025-05-15T00:00:11.445995426Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.8\" returns image reference \"sha256:7d73f013cedcf301aef42272c93e4c1174dab1a8eccd96840091ef04b63480f2\"" May 15 00:00:11.447774 containerd[1503]: time="2025-05-15T00:00:11.447736699Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" May 15 00:00:11.450451 containerd[1503]: time="2025-05-15T00:00:11.450396614Z" level=info msg="CreateContainer within sandbox \"c63f337d3d971abd523246b393b140bd99ed46499a609be111f9ca1a5fe8a2eb\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" May 15 00:00:11.473552 containerd[1503]: time="2025-05-15T00:00:11.473452503Z" level=info msg="CreateContainer within sandbox \"c63f337d3d971abd523246b393b140bd99ed46499a609be111f9ca1a5fe8a2eb\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"fd72a447eebd5263285de494ed5907beb6accdac0ea519b84d66403edb83f41c\"" May 15 00:00:11.476207 containerd[1503]: time="2025-05-15T00:00:11.474859103Z" level=info msg="StartContainer for \"fd72a447eebd5263285de494ed5907beb6accdac0ea519b84d66403edb83f41c\"" May 15 00:00:11.539537 systemd[1]: Started cri-containerd-fd72a447eebd5263285de494ed5907beb6accdac0ea519b84d66403edb83f41c.scope - libcontainer container fd72a447eebd5263285de494ed5907beb6accdac0ea519b84d66403edb83f41c. May 15 00:00:11.591627 containerd[1503]: time="2025-05-15T00:00:11.591386036Z" level=info msg="StartContainer for \"fd72a447eebd5263285de494ed5907beb6accdac0ea519b84d66403edb83f41c\" returns successfully" May 15 00:00:12.332282 kubelet[1876]: E0515 00:00:12.332197 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:12.539983 kubelet[1876]: I0515 00:00:12.539849 1876 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-z78hf" podStartSLOduration=3.701544506 podStartE2EDuration="5.539817696s" podCreationTimestamp="2025-05-15 00:00:07 +0000 UTC" firstStartedPulling="2025-05-15 00:00:09.609208014 +0000 UTC m=+3.034180416" lastFinishedPulling="2025-05-15 00:00:11.447481205 +0000 UTC m=+4.872453606" observedRunningTime="2025-05-15 00:00:12.53960503 +0000 UTC m=+5.964577466" watchObservedRunningTime="2025-05-15 00:00:12.539817696 +0000 UTC m=+5.964790121" May 15 00:00:12.744868 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2636797670.mount: Deactivated successfully. May 15 00:00:12.799386 containerd[1503]: time="2025-05-15T00:00:12.799322517Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 15 00:00:12.800802 containerd[1503]: time="2025-05-15T00:00:12.800685984Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3852935" May 15 00:00:12.803006 containerd[1503]: time="2025-05-15T00:00:12.801904641Z" level=info msg="ImageCreate event name:\"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 15 00:00:12.805136 containerd[1503]: time="2025-05-15T00:00:12.805046887Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 15 00:00:12.806882 containerd[1503]: time="2025-05-15T00:00:12.806146074Z" 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.358359021s" May 15 00:00:12.806882 containerd[1503]: time="2025-05-15T00:00:12.806191976Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\"" May 15 00:00:12.809156 containerd[1503]: time="2025-05-15T00:00:12.809107788Z" level=info msg="CreateContainer within sandbox \"f563130ce6ec34cc6d52ad08be6431c31a472f218594dfe99d62229f414991a0\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" May 15 00:00:12.832362 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2183619749.mount: Deactivated successfully. May 15 00:00:12.834240 containerd[1503]: time="2025-05-15T00:00:12.834196186Z" level=info msg="CreateContainer within sandbox \"f563130ce6ec34cc6d52ad08be6431c31a472f218594dfe99d62229f414991a0\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"5d79a1a92e2fbefc03824fd6e0a6283520879f07fc9a10e085cf8025c33f7f97\"" May 15 00:00:12.835221 containerd[1503]: time="2025-05-15T00:00:12.835153002Z" level=info msg="StartContainer for \"5d79a1a92e2fbefc03824fd6e0a6283520879f07fc9a10e085cf8025c33f7f97\"" May 15 00:00:12.879332 systemd[1]: Started cri-containerd-5d79a1a92e2fbefc03824fd6e0a6283520879f07fc9a10e085cf8025c33f7f97.scope - libcontainer container 5d79a1a92e2fbefc03824fd6e0a6283520879f07fc9a10e085cf8025c33f7f97. May 15 00:00:12.919986 containerd[1503]: time="2025-05-15T00:00:12.919520454Z" level=info msg="StartContainer for \"5d79a1a92e2fbefc03824fd6e0a6283520879f07fc9a10e085cf8025c33f7f97\" returns successfully" May 15 00:00:12.922462 systemd[1]: cri-containerd-5d79a1a92e2fbefc03824fd6e0a6283520879f07fc9a10e085cf8025c33f7f97.scope: Deactivated successfully. May 15 00:00:13.311273 containerd[1503]: time="2025-05-15T00:00:13.311135673Z" level=info msg="shim disconnected" id=5d79a1a92e2fbefc03824fd6e0a6283520879f07fc9a10e085cf8025c33f7f97 namespace=k8s.io May 15 00:00:13.311273 containerd[1503]: time="2025-05-15T00:00:13.311268763Z" level=warning msg="cleaning up after shim disconnected" id=5d79a1a92e2fbefc03824fd6e0a6283520879f07fc9a10e085cf8025c33f7f97 namespace=k8s.io May 15 00:00:13.311273 containerd[1503]: time="2025-05-15T00:00:13.311283709Z" level=info msg="cleaning up dead shim" namespace=k8s.io May 15 00:00:13.333369 kubelet[1876]: E0515 00:00:13.333316 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:13.528351 containerd[1503]: time="2025-05-15T00:00:13.528300284Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" May 15 00:00:14.334006 kubelet[1876]: E0515 00:00:14.333938 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:14.837733 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4229359066.mount: Deactivated successfully. May 15 00:00:15.335543 kubelet[1876]: E0515 00:00:15.334991 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:15.796142 containerd[1503]: time="2025-05-15T00:00:15.795891328Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 15 00:00:15.798190 containerd[1503]: time="2025-05-15T00:00:15.798094250Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26866358" May 15 00:00:15.799810 containerd[1503]: time="2025-05-15T00:00:15.799718376Z" level=info msg="ImageCreate event name:\"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 15 00:00:15.803782 containerd[1503]: time="2025-05-15T00:00:15.803707747Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 15 00:00:15.805270 containerd[1503]: time="2025-05-15T00:00:15.805215000Z" 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.27685927s" May 15 00:00:15.805395 containerd[1503]: time="2025-05-15T00:00:15.805278147Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\"" May 15 00:00:15.808987 containerd[1503]: time="2025-05-15T00:00:15.808929004Z" level=info msg="CreateContainer within sandbox \"f563130ce6ec34cc6d52ad08be6431c31a472f218594dfe99d62229f414991a0\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" May 15 00:00:15.833550 containerd[1503]: time="2025-05-15T00:00:15.833454572Z" level=info msg="CreateContainer within sandbox \"f563130ce6ec34cc6d52ad08be6431c31a472f218594dfe99d62229f414991a0\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"5fe1605e188aba9b296528af8a2e8c6cd8f13fe099fc5886962920387e648c51\"" May 15 00:00:15.834587 containerd[1503]: time="2025-05-15T00:00:15.834544292Z" level=info msg="StartContainer for \"5fe1605e188aba9b296528af8a2e8c6cd8f13fe099fc5886962920387e648c51\"" May 15 00:00:15.892358 systemd[1]: Started cri-containerd-5fe1605e188aba9b296528af8a2e8c6cd8f13fe099fc5886962920387e648c51.scope - libcontainer container 5fe1605e188aba9b296528af8a2e8c6cd8f13fe099fc5886962920387e648c51. May 15 00:00:15.935434 systemd[1]: cri-containerd-5fe1605e188aba9b296528af8a2e8c6cd8f13fe099fc5886962920387e648c51.scope: Deactivated successfully. May 15 00:00:15.937604 containerd[1503]: time="2025-05-15T00:00:15.937556058Z" level=info msg="StartContainer for \"5fe1605e188aba9b296528af8a2e8c6cd8f13fe099fc5886962920387e648c51\" returns successfully" May 15 00:00:15.969103 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-5fe1605e188aba9b296528af8a2e8c6cd8f13fe099fc5886962920387e648c51-rootfs.mount: Deactivated successfully. May 15 00:00:15.983577 kubelet[1876]: I0515 00:00:15.983535 1876 kubelet_node_status.go:488] "Fast updating node status as it just became ready" May 15 00:00:16.189593 containerd[1503]: time="2025-05-15T00:00:16.189351035Z" level=info msg="shim disconnected" id=5fe1605e188aba9b296528af8a2e8c6cd8f13fe099fc5886962920387e648c51 namespace=k8s.io May 15 00:00:16.189593 containerd[1503]: time="2025-05-15T00:00:16.189444723Z" level=warning msg="cleaning up after shim disconnected" id=5fe1605e188aba9b296528af8a2e8c6cd8f13fe099fc5886962920387e648c51 namespace=k8s.io May 15 00:00:16.189593 containerd[1503]: time="2025-05-15T00:00:16.189458955Z" level=info msg="cleaning up dead shim" namespace=k8s.io May 15 00:00:16.335322 kubelet[1876]: E0515 00:00:16.335249 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:16.538464 containerd[1503]: time="2025-05-15T00:00:16.538313185Z" level=info msg="CreateContainer within sandbox \"f563130ce6ec34cc6d52ad08be6431c31a472f218594dfe99d62229f414991a0\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" May 15 00:00:16.556208 containerd[1503]: time="2025-05-15T00:00:16.556145626Z" level=info msg="CreateContainer within sandbox \"f563130ce6ec34cc6d52ad08be6431c31a472f218594dfe99d62229f414991a0\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"fe22196d7cb022cc22163ba3a8b071fe745ae0369632dfb5125151f2b18f17e7\"" May 15 00:00:16.557492 containerd[1503]: time="2025-05-15T00:00:16.557334560Z" level=info msg="StartContainer for \"fe22196d7cb022cc22163ba3a8b071fe745ae0369632dfb5125151f2b18f17e7\"" May 15 00:00:16.599227 systemd[1]: Started cri-containerd-fe22196d7cb022cc22163ba3a8b071fe745ae0369632dfb5125151f2b18f17e7.scope - libcontainer container fe22196d7cb022cc22163ba3a8b071fe745ae0369632dfb5125151f2b18f17e7. May 15 00:00:16.639383 containerd[1503]: time="2025-05-15T00:00:16.639328638Z" level=info msg="StartContainer for \"fe22196d7cb022cc22163ba3a8b071fe745ae0369632dfb5125151f2b18f17e7\" returns successfully" May 15 00:00:17.336396 kubelet[1876]: E0515 00:00:17.336306 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:17.718378 systemd-networkd[1397]: flannel.1: Link UP May 15 00:00:17.718394 systemd-networkd[1397]: flannel.1: Gained carrier May 15 00:00:18.337257 kubelet[1876]: E0515 00:00:18.337180 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:19.338204 kubelet[1876]: E0515 00:00:19.338119 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:19.715450 systemd-networkd[1397]: flannel.1: Gained IPv6LL May 15 00:00:20.242522 systemd[1]: systemd-hostnamed.service: Deactivated successfully. May 15 00:00:20.339117 kubelet[1876]: E0515 00:00:20.339052 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:20.364473 kubelet[1876]: I0515 00:00:20.364361 1876 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-lsh6j" podStartSLOduration=7.189760826 podStartE2EDuration="13.364324046s" podCreationTimestamp="2025-05-15 00:00:07 +0000 UTC" firstStartedPulling="2025-05-15 00:00:09.632291336 +0000 UTC m=+3.057263735" lastFinishedPulling="2025-05-15 00:00:15.806854553 +0000 UTC m=+9.231826955" observedRunningTime="2025-05-15 00:00:17.558449534 +0000 UTC m=+10.983421948" watchObservedRunningTime="2025-05-15 00:00:20.364324046 +0000 UTC m=+13.789296522" May 15 00:00:20.374942 systemd[1]: Created slice kubepods-besteffort-pod55a6367b_a233_4764_8842_45e22fda69f0.slice - libcontainer container kubepods-besteffort-pod55a6367b_a233_4764_8842_45e22fda69f0.slice. May 15 00:00:20.451081 kubelet[1876]: I0515 00:00:20.450991 1876 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-7v6vq\" (UniqueName: \"kubernetes.io/projected/55a6367b-a233-4764-8842-45e22fda69f0-kube-api-access-7v6vq\") pod \"nginx-deployment-8587fbcb89-drncn\" (UID: \"55a6367b-a233-4764-8842-45e22fda69f0\") " pod="default/nginx-deployment-8587fbcb89-drncn" May 15 00:00:20.679993 containerd[1503]: time="2025-05-15T00:00:20.679432282Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-drncn,Uid:55a6367b-a233-4764-8842-45e22fda69f0,Namespace:default,Attempt:0,}" May 15 00:00:20.711895 systemd-networkd[1397]: cni0: Link UP May 15 00:00:20.711908 systemd-networkd[1397]: cni0: Gained carrier May 15 00:00:20.722812 systemd-networkd[1397]: vetha816c0db: Link UP May 15 00:00:20.725354 systemd-networkd[1397]: cni0: Lost carrier May 15 00:00:20.740939 kernel: cni0: port 1(vetha816c0db) entered blocking state May 15 00:00:20.741079 kernel: cni0: port 1(vetha816c0db) entered disabled state May 15 00:00:20.747075 kernel: vetha816c0db: entered allmulticast mode May 15 00:00:20.754330 kernel: vetha816c0db: entered promiscuous mode May 15 00:00:20.785005 kernel: cni0: port 1(vetha816c0db) entered blocking state May 15 00:00:20.785335 kernel: cni0: port 1(vetha816c0db) entered forwarding state May 15 00:00:20.785706 systemd-networkd[1397]: vetha816c0db: Gained carrier May 15 00:00:20.787136 systemd-networkd[1397]: cni0: Gained carrier May 15 00:00:20.789776 containerd[1503]: 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 15 00:00:20.789776 containerd[1503]: delegateAdd: netconf sent to delegate plugin: May 15 00:00:20.818020 containerd[1503]: {"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-15T00:00:20.817849807Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 15 00:00:20.818020 containerd[1503]: time="2025-05-15T00:00:20.817926191Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 15 00:00:20.818020 containerd[1503]: time="2025-05-15T00:00:20.817944752Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 15 00:00:20.818584 containerd[1503]: time="2025-05-15T00:00:20.818151160Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 15 00:00:20.854146 systemd[1]: Started cri-containerd-fa897a52bc68d85c802f98e2c720433db16447656ea62a6a19b82d57082afa37.scope - libcontainer container fa897a52bc68d85c802f98e2c720433db16447656ea62a6a19b82d57082afa37. May 15 00:00:20.911883 containerd[1503]: time="2025-05-15T00:00:20.911821900Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-drncn,Uid:55a6367b-a233-4764-8842-45e22fda69f0,Namespace:default,Attempt:0,} returns sandbox id \"fa897a52bc68d85c802f98e2c720433db16447656ea62a6a19b82d57082afa37\"" May 15 00:00:20.914400 containerd[1503]: time="2025-05-15T00:00:20.914064909Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" May 15 00:00:21.340308 kubelet[1876]: E0515 00:00:21.340241 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:21.891413 systemd-networkd[1397]: cni0: Gained IPv6LL May 15 00:00:22.341555 kubelet[1876]: E0515 00:00:22.341322 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:22.659266 systemd-networkd[1397]: vetha816c0db: Gained IPv6LL May 15 00:00:23.341847 kubelet[1876]: E0515 00:00:23.341757 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:24.342144 kubelet[1876]: E0515 00:00:24.342056 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:25.226131 ntpd[1473]: Listen normally on 7 flannel.1 192.168.1.0:123 May 15 00:00:25.226233 ntpd[1473]: Listen normally on 8 cni0 192.168.1.1:123 May 15 00:00:25.226752 ntpd[1473]: 15 May 00:00:25 ntpd[1473]: Listen normally on 7 flannel.1 192.168.1.0:123 May 15 00:00:25.226752 ntpd[1473]: 15 May 00:00:25 ntpd[1473]: Listen normally on 8 cni0 192.168.1.1:123 May 15 00:00:25.226752 ntpd[1473]: 15 May 00:00:25 ntpd[1473]: Listen normally on 9 flannel.1 [fe80::fc50:8eff:fea9:4c05%3]:123 May 15 00:00:25.226752 ntpd[1473]: 15 May 00:00:25 ntpd[1473]: Listen normally on 10 cni0 [fe80::e0d4:3bff:fe70:9201%4]:123 May 15 00:00:25.226752 ntpd[1473]: 15 May 00:00:25 ntpd[1473]: Listen normally on 11 vetha816c0db [fe80::102a:4eff:fe8f:6ec0%5]:123 May 15 00:00:25.226330 ntpd[1473]: Listen normally on 9 flannel.1 [fe80::fc50:8eff:fea9:4c05%3]:123 May 15 00:00:25.226420 ntpd[1473]: Listen normally on 10 cni0 [fe80::e0d4:3bff:fe70:9201%4]:123 May 15 00:00:25.226482 ntpd[1473]: Listen normally on 11 vetha816c0db [fe80::102a:4eff:fe8f:6ec0%5]:123 May 15 00:00:25.343136 kubelet[1876]: E0515 00:00:25.343063 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:26.343939 kubelet[1876]: E0515 00:00:26.343864 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:27.327807 kubelet[1876]: E0515 00:00:27.327725 1876 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:27.344874 kubelet[1876]: E0515 00:00:27.344792 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:28.345980 kubelet[1876]: E0515 00:00:28.345908 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:29.347953 kubelet[1876]: E0515 00:00:29.347068 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:30.334084 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4261996564.mount: Deactivated successfully. May 15 00:00:30.348113 kubelet[1876]: E0515 00:00:30.347954 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:31.349221 kubelet[1876]: E0515 00:00:31.349069 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:31.957618 containerd[1503]: time="2025-05-15T00:00:31.957553683Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 15 00:00:31.959268 containerd[1503]: time="2025-05-15T00:00:31.959217544Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=73306220" May 15 00:00:31.960853 containerd[1503]: time="2025-05-15T00:00:31.960785604Z" level=info msg="ImageCreate event name:\"sha256:7e2dd24abce21cd256091445aca4b7eb00774264c2b0a8714701dd7091509efa\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 15 00:00:31.965665 containerd[1503]: time="2025-05-15T00:00:31.965603107Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:beabce8f1782671ba500ddff99dd260fbf9c5ec85fb9c3162e35a3c40bafd023\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 15 00:00:31.968519 containerd[1503]: time="2025-05-15T00:00:31.968445739Z" 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 11.054337979s" May 15 00:00:31.968519 containerd[1503]: time="2025-05-15T00:00:31.968508048Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:7e2dd24abce21cd256091445aca4b7eb00774264c2b0a8714701dd7091509efa\"" May 15 00:00:31.972568 containerd[1503]: time="2025-05-15T00:00:31.972521291Z" level=info msg="CreateContainer within sandbox \"fa897a52bc68d85c802f98e2c720433db16447656ea62a6a19b82d57082afa37\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" May 15 00:00:31.992058 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1724931710.mount: Deactivated successfully. May 15 00:00:31.994221 containerd[1503]: time="2025-05-15T00:00:31.994165094Z" level=info msg="CreateContainer within sandbox \"fa897a52bc68d85c802f98e2c720433db16447656ea62a6a19b82d57082afa37\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"245f75c6dca772e9e79bc22b5978b891708dba7402565ab56fb0a07926d7465e\"" May 15 00:00:31.995165 containerd[1503]: time="2025-05-15T00:00:31.994983630Z" level=info msg="StartContainer for \"245f75c6dca772e9e79bc22b5978b891708dba7402565ab56fb0a07926d7465e\"" May 15 00:00:32.040338 systemd[1]: run-containerd-runc-k8s.io-245f75c6dca772e9e79bc22b5978b891708dba7402565ab56fb0a07926d7465e-runc.WFxpCe.mount: Deactivated successfully. May 15 00:00:32.051221 systemd[1]: Started cri-containerd-245f75c6dca772e9e79bc22b5978b891708dba7402565ab56fb0a07926d7465e.scope - libcontainer container 245f75c6dca772e9e79bc22b5978b891708dba7402565ab56fb0a07926d7465e. May 15 00:00:32.092510 containerd[1503]: time="2025-05-15T00:00:32.092452837Z" level=info msg="StartContainer for \"245f75c6dca772e9e79bc22b5978b891708dba7402565ab56fb0a07926d7465e\" returns successfully" May 15 00:00:32.350166 kubelet[1876]: E0515 00:00:32.350098 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:32.583349 kubelet[1876]: I0515 00:00:32.583276 1876 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-8587fbcb89-drncn" podStartSLOduration=1.526105463 podStartE2EDuration="12.583256299s" podCreationTimestamp="2025-05-15 00:00:20 +0000 UTC" firstStartedPulling="2025-05-15 00:00:20.91353477 +0000 UTC m=+14.338507172" lastFinishedPulling="2025-05-15 00:00:31.970685609 +0000 UTC m=+25.395658008" observedRunningTime="2025-05-15 00:00:32.583082875 +0000 UTC m=+26.008055289" watchObservedRunningTime="2025-05-15 00:00:32.583256299 +0000 UTC m=+26.008228709" May 15 00:00:33.350993 kubelet[1876]: E0515 00:00:33.350888 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:34.184149 update_engine[1487]: I20250515 00:00:34.184018 1487 update_attempter.cc:509] Updating boot flags... May 15 00:00:34.252039 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 38 scanned by (udev-worker) (2681) May 15 00:00:34.351187 kubelet[1876]: E0515 00:00:34.351137 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:34.398057 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 38 scanned by (udev-worker) (2680) May 15 00:00:34.570213 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 38 scanned by (udev-worker) (2680) May 15 00:00:35.352311 kubelet[1876]: E0515 00:00:35.352230 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:36.353273 kubelet[1876]: E0515 00:00:36.353191 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:37.353655 kubelet[1876]: E0515 00:00:37.353575 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:38.354118 kubelet[1876]: E0515 00:00:38.354029 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:39.355083 kubelet[1876]: E0515 00:00:39.355014 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:40.355947 kubelet[1876]: E0515 00:00:40.355869 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:40.817819 systemd[1]: Created slice kubepods-besteffort-podeae87f2e_c154_48bc_ba9a_1def4c76fcab.slice - libcontainer container kubepods-besteffort-podeae87f2e_c154_48bc_ba9a_1def4c76fcab.slice. May 15 00:00:40.903346 kubelet[1876]: I0515 00:00:40.903282 1876 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/eae87f2e-c154-48bc-ba9a-1def4c76fcab-data\") pod \"nfs-server-provisioner-0\" (UID: \"eae87f2e-c154-48bc-ba9a-1def4c76fcab\") " pod="default/nfs-server-provisioner-0" May 15 00:00:40.903346 kubelet[1876]: I0515 00:00:40.903347 1876 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-7w4c5\" (UniqueName: \"kubernetes.io/projected/eae87f2e-c154-48bc-ba9a-1def4c76fcab-kube-api-access-7w4c5\") pod \"nfs-server-provisioner-0\" (UID: \"eae87f2e-c154-48bc-ba9a-1def4c76fcab\") " pod="default/nfs-server-provisioner-0" May 15 00:00:41.122741 containerd[1503]: time="2025-05-15T00:00:41.122581331Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:eae87f2e-c154-48bc-ba9a-1def4c76fcab,Namespace:default,Attempt:0,}" May 15 00:00:41.156452 systemd-networkd[1397]: veth4128b622: Link UP May 15 00:00:41.166890 kernel: cni0: port 2(veth4128b622) entered blocking state May 15 00:00:41.167031 kernel: cni0: port 2(veth4128b622) entered disabled state May 15 00:00:41.167069 kernel: veth4128b622: entered allmulticast mode May 15 00:00:41.179223 kernel: veth4128b622: entered promiscuous mode May 15 00:00:41.196698 kernel: cni0: port 2(veth4128b622) entered blocking state May 15 00:00:41.196781 kernel: cni0: port 2(veth4128b622) entered forwarding state May 15 00:00:41.197682 systemd-networkd[1397]: veth4128b622: Gained carrier May 15 00:00:41.236419 containerd[1503]: 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)(0xc000106628), "name":"cbr0", "type":"bridge"} May 15 00:00:41.236419 containerd[1503]: delegateAdd: netconf sent to delegate plugin: May 15 00:00:41.261668 containerd[1503]: {"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-15T00:00:41.261541010Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 15 00:00:41.261668 containerd[1503]: time="2025-05-15T00:00:41.261620402Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 15 00:00:41.262635 containerd[1503]: time="2025-05-15T00:00:41.261645325Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 15 00:00:41.263239 containerd[1503]: time="2025-05-15T00:00:41.262883543Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 15 00:00:41.292170 systemd[1]: run-containerd-runc-k8s.io-08eb94cedc145069e91ae22b4554e3d4285b2fa82b596d19d1dff71c4a2e9f29-runc.xv42aH.mount: Deactivated successfully. May 15 00:00:41.299196 systemd[1]: Started cri-containerd-08eb94cedc145069e91ae22b4554e3d4285b2fa82b596d19d1dff71c4a2e9f29.scope - libcontainer container 08eb94cedc145069e91ae22b4554e3d4285b2fa82b596d19d1dff71c4a2e9f29. May 15 00:00:41.356539 containerd[1503]: time="2025-05-15T00:00:41.356484539Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:eae87f2e-c154-48bc-ba9a-1def4c76fcab,Namespace:default,Attempt:0,} returns sandbox id \"08eb94cedc145069e91ae22b4554e3d4285b2fa82b596d19d1dff71c4a2e9f29\"" May 15 00:00:41.356849 kubelet[1876]: E0515 00:00:41.356809 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:41.361236 containerd[1503]: time="2025-05-15T00:00:41.361197211Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" May 15 00:00:42.359122 kubelet[1876]: E0515 00:00:42.358381 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:42.435502 systemd-networkd[1397]: veth4128b622: Gained IPv6LL May 15 00:00:43.359328 kubelet[1876]: E0515 00:00:43.359230 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:43.919803 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2165538386.mount: Deactivated successfully. May 15 00:00:44.360153 kubelet[1876]: E0515 00:00:44.360071 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:45.226139 ntpd[1473]: Listen normally on 12 veth4128b622 [fe80::4c15:13ff:fef2:8a75%6]:123 May 15 00:00:45.226886 ntpd[1473]: 15 May 00:00:45 ntpd[1473]: Listen normally on 12 veth4128b622 [fe80::4c15:13ff:fef2:8a75%6]:123 May 15 00:00:45.361420 kubelet[1876]: E0515 00:00:45.361275 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:46.362444 kubelet[1876]: E0515 00:00:46.362378 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:46.782034 containerd[1503]: time="2025-05-15T00:00:46.781783239Z" 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 15 00:00:46.784037 containerd[1503]: time="2025-05-15T00:00:46.783931119Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=91045236" May 15 00:00:46.786057 containerd[1503]: time="2025-05-15T00:00:46.785974431Z" level=info msg="ImageCreate event name:\"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 15 00:00:46.790428 containerd[1503]: time="2025-05-15T00:00:46.790323544Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 15 00:00:46.792481 containerd[1503]: time="2025-05-15T00:00:46.791733112Z" level=info msg="Pulled image \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" with image id \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\", repo tag \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\", repo digest \"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\", size \"91036984\" in 5.430484052s" May 15 00:00:46.792481 containerd[1503]: time="2025-05-15T00:00:46.791791135Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\"" May 15 00:00:46.795674 containerd[1503]: time="2025-05-15T00:00:46.795607309Z" level=info msg="CreateContainer within sandbox \"08eb94cedc145069e91ae22b4554e3d4285b2fa82b596d19d1dff71c4a2e9f29\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" May 15 00:00:46.822257 containerd[1503]: time="2025-05-15T00:00:46.822180758Z" level=info msg="CreateContainer within sandbox \"08eb94cedc145069e91ae22b4554e3d4285b2fa82b596d19d1dff71c4a2e9f29\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"4a5e4b3637ab3a76cf3ffa723a60b89e0baafc2044ced5a1a04a4ca79a86aca9\"" May 15 00:00:46.823505 containerd[1503]: time="2025-05-15T00:00:46.823444974Z" level=info msg="StartContainer for \"4a5e4b3637ab3a76cf3ffa723a60b89e0baafc2044ced5a1a04a4ca79a86aca9\"" May 15 00:00:46.881258 systemd[1]: Started cri-containerd-4a5e4b3637ab3a76cf3ffa723a60b89e0baafc2044ced5a1a04a4ca79a86aca9.scope - libcontainer container 4a5e4b3637ab3a76cf3ffa723a60b89e0baafc2044ced5a1a04a4ca79a86aca9. May 15 00:00:46.943392 containerd[1503]: time="2025-05-15T00:00:46.943283213Z" level=info msg="StartContainer for \"4a5e4b3637ab3a76cf3ffa723a60b89e0baafc2044ced5a1a04a4ca79a86aca9\" returns successfully" May 15 00:00:47.327857 kubelet[1876]: E0515 00:00:47.327775 1876 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:47.364564 kubelet[1876]: E0515 00:00:47.364455 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:47.631468 kubelet[1876]: I0515 00:00:47.631170 1876 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=2.197557986 podStartE2EDuration="7.631142146s" podCreationTimestamp="2025-05-15 00:00:40 +0000 UTC" firstStartedPulling="2025-05-15 00:00:41.359761283 +0000 UTC m=+34.784733683" lastFinishedPulling="2025-05-15 00:00:46.793345435 +0000 UTC m=+40.218317843" observedRunningTime="2025-05-15 00:00:47.630814945 +0000 UTC m=+41.055787358" watchObservedRunningTime="2025-05-15 00:00:47.631142146 +0000 UTC m=+41.056114557" May 15 00:00:48.365563 kubelet[1876]: E0515 00:00:48.365398 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:49.365803 kubelet[1876]: E0515 00:00:49.365690 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:50.366458 kubelet[1876]: E0515 00:00:50.366371 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:51.367437 kubelet[1876]: E0515 00:00:51.367351 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:52.368224 kubelet[1876]: E0515 00:00:52.368149 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:53.369087 kubelet[1876]: E0515 00:00:53.369019 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:54.370277 kubelet[1876]: E0515 00:00:54.370168 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:55.371160 kubelet[1876]: E0515 00:00:55.371076 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:56.371662 kubelet[1876]: E0515 00:00:56.371593 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:56.706844 systemd[1]: Created slice kubepods-besteffort-podebd5b2bc_95bf_4dd1_b13d_446d134dbf70.slice - libcontainer container kubepods-besteffort-podebd5b2bc_95bf_4dd1_b13d_446d134dbf70.slice. May 15 00:00:56.821340 kubelet[1876]: I0515 00:00:56.821271 1876 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-863f5288-e2a5-4c5e-b501-1555a24e9322\" (UniqueName: \"kubernetes.io/nfs/ebd5b2bc-95bf-4dd1-b13d-446d134dbf70-pvc-863f5288-e2a5-4c5e-b501-1555a24e9322\") pod \"test-pod-1\" (UID: \"ebd5b2bc-95bf-4dd1-b13d-446d134dbf70\") " pod="default/test-pod-1" May 15 00:00:56.821340 kubelet[1876]: I0515 00:00:56.821338 1876 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-xrdqz\" (UniqueName: \"kubernetes.io/projected/ebd5b2bc-95bf-4dd1-b13d-446d134dbf70-kube-api-access-xrdqz\") pod \"test-pod-1\" (UID: \"ebd5b2bc-95bf-4dd1-b13d-446d134dbf70\") " pod="default/test-pod-1" May 15 00:00:56.965439 kernel: FS-Cache: Loaded May 15 00:00:57.048677 kernel: RPC: Registered named UNIX socket transport module. May 15 00:00:57.048859 kernel: RPC: Registered udp transport module. May 15 00:00:57.048899 kernel: RPC: Registered tcp transport module. May 15 00:00:57.053414 kernel: RPC: Registered tcp-with-tls transport module. May 15 00:00:57.059073 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. May 15 00:00:57.339321 kernel: NFS: Registering the id_resolver key type May 15 00:00:57.339571 kernel: Key type id_resolver registered May 15 00:00:57.339613 kernel: Key type id_legacy registered May 15 00:00:57.372122 kubelet[1876]: E0515 00:00:57.372078 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:57.385681 nfsidmap[2971]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'c.flatcar-212911.internal' May 15 00:00:57.400256 nfsidmap[2972]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'c.flatcar-212911.internal' May 15 00:00:57.612300 containerd[1503]: time="2025-05-15T00:00:57.612121861Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:ebd5b2bc-95bf-4dd1-b13d-446d134dbf70,Namespace:default,Attempt:0,}" May 15 00:00:57.650560 systemd-networkd[1397]: veth7c7f00ac: Link UP May 15 00:00:57.664017 kernel: cni0: port 3(veth7c7f00ac) entered blocking state May 15 00:00:57.664101 kernel: cni0: port 3(veth7c7f00ac) entered disabled state May 15 00:00:57.671001 kernel: veth7c7f00ac: entered allmulticast mode May 15 00:00:57.671099 kernel: veth7c7f00ac: entered promiscuous mode May 15 00:00:57.692777 kernel: cni0: port 3(veth7c7f00ac) entered blocking state May 15 00:00:57.692875 kernel: cni0: port 3(veth7c7f00ac) entered forwarding state May 15 00:00:57.693374 systemd-networkd[1397]: veth7c7f00ac: Gained carrier May 15 00:00:57.695128 containerd[1503]: 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)(0xc000020938), "name":"cbr0", "type":"bridge"} May 15 00:00:57.695128 containerd[1503]: delegateAdd: netconf sent to delegate plugin: May 15 00:00:57.721365 containerd[1503]: {"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-15T00:00:57.720998593Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 15 00:00:57.721365 containerd[1503]: time="2025-05-15T00:00:57.721074203Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 15 00:00:57.721365 containerd[1503]: time="2025-05-15T00:00:57.721104283Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 15 00:00:57.721365 containerd[1503]: time="2025-05-15T00:00:57.721280598Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 15 00:00:57.752209 systemd[1]: Started cri-containerd-16d8c5a9d1e73387d3c891b9d33310328152ac15aef8d93147521fd29e593c88.scope - libcontainer container 16d8c5a9d1e73387d3c891b9d33310328152ac15aef8d93147521fd29e593c88. May 15 00:00:57.808744 containerd[1503]: time="2025-05-15T00:00:57.808669807Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:ebd5b2bc-95bf-4dd1-b13d-446d134dbf70,Namespace:default,Attempt:0,} returns sandbox id \"16d8c5a9d1e73387d3c891b9d33310328152ac15aef8d93147521fd29e593c88\"" May 15 00:00:57.811281 containerd[1503]: time="2025-05-15T00:00:57.811233411Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" May 15 00:00:58.153466 containerd[1503]: time="2025-05-15T00:00:58.153389740Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 15 00:00:58.154527 containerd[1503]: time="2025-05-15T00:00:58.154439182Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" May 15 00:00:58.159087 containerd[1503]: time="2025-05-15T00:00:58.159045236Z" 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 347.479055ms" May 15 00:00:58.159289 containerd[1503]: time="2025-05-15T00:00:58.159091611Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:7e2dd24abce21cd256091445aca4b7eb00774264c2b0a8714701dd7091509efa\"" May 15 00:00:58.161708 containerd[1503]: time="2025-05-15T00:00:58.161670205Z" level=info msg="CreateContainer within sandbox \"16d8c5a9d1e73387d3c891b9d33310328152ac15aef8d93147521fd29e593c88\" for container &ContainerMetadata{Name:test,Attempt:0,}" May 15 00:00:58.182456 containerd[1503]: time="2025-05-15T00:00:58.182406822Z" level=info msg="CreateContainer within sandbox \"16d8c5a9d1e73387d3c891b9d33310328152ac15aef8d93147521fd29e593c88\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"9a6a7243a0a7e5a35f9576b226c7a3315fef4062712c1fe01df7d8a798893ad4\"" May 15 00:00:58.183704 containerd[1503]: time="2025-05-15T00:00:58.183642275Z" level=info msg="StartContainer for \"9a6a7243a0a7e5a35f9576b226c7a3315fef4062712c1fe01df7d8a798893ad4\"" May 15 00:00:58.235187 systemd[1]: Started cri-containerd-9a6a7243a0a7e5a35f9576b226c7a3315fef4062712c1fe01df7d8a798893ad4.scope - libcontainer container 9a6a7243a0a7e5a35f9576b226c7a3315fef4062712c1fe01df7d8a798893ad4. May 15 00:00:58.274267 containerd[1503]: time="2025-05-15T00:00:58.274081982Z" level=info msg="StartContainer for \"9a6a7243a0a7e5a35f9576b226c7a3315fef4062712c1fe01df7d8a798893ad4\" returns successfully" May 15 00:00:58.373350 kubelet[1876]: E0515 00:00:58.373263 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:00:58.667836 kubelet[1876]: I0515 00:00:58.667707 1876 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=17.318520539 podStartE2EDuration="17.667676804s" podCreationTimestamp="2025-05-15 00:00:41 +0000 UTC" firstStartedPulling="2025-05-15 00:00:57.810850006 +0000 UTC m=+51.235822404" lastFinishedPulling="2025-05-15 00:00:58.160006268 +0000 UTC m=+51.584978669" observedRunningTime="2025-05-15 00:00:58.667007992 +0000 UTC m=+52.091980403" watchObservedRunningTime="2025-05-15 00:00:58.667676804 +0000 UTC m=+52.092649247" May 15 00:00:58.819283 systemd-networkd[1397]: veth7c7f00ac: Gained IPv6LL May 15 00:00:58.939059 systemd[1]: run-containerd-runc-k8s.io-9a6a7243a0a7e5a35f9576b226c7a3315fef4062712c1fe01df7d8a798893ad4-runc.3S4fj7.mount: Deactivated successfully. May 15 00:00:59.374117 kubelet[1876]: E0515 00:00:59.374030 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:01:00.374610 kubelet[1876]: E0515 00:01:00.374499 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:01:01.226396 ntpd[1473]: Listen normally on 13 veth7c7f00ac [fe80::bc89:6cff:fe44:fc41%7]:123 May 15 00:01:01.227070 ntpd[1473]: 15 May 00:01:01 ntpd[1473]: Listen normally on 13 veth7c7f00ac [fe80::bc89:6cff:fe44:fc41%7]:123 May 15 00:01:01.375837 kubelet[1876]: E0515 00:01:01.375740 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:01:02.376037 kubelet[1876]: E0515 00:01:02.375979 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 00:01:03.376604 kubelet[1876]: E0515 00:01:03.376506 1876 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"