Jan 23 18:53:49.183720 kernel: Linux version 6.12.66-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.0 p8) 14.3.0, GNU ld (Gentoo 2.44 p4) 2.44.0) #1 SMP PREEMPT_DYNAMIC Fri Jan 23 16:02:29 -00 2026 Jan 23 18:53:49.183749 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=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=e498a861432c458392bc8ae0919597d8f4554cdcc46b00c7f3d7a634c3492c81 Jan 23 18:53:49.183759 kernel: BIOS-provided physical RAM map: Jan 23 18:53:49.183766 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Jan 23 18:53:49.183772 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Jan 23 18:53:49.183779 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Jan 23 18:53:49.183787 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Jan 23 18:53:49.183796 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Jan 23 18:53:49.183803 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Jan 23 18:53:49.183810 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Jan 23 18:53:49.183817 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Jan 23 18:53:49.183823 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Jan 23 18:53:49.183830 kernel: printk: legacy bootconsole [earlyser0] enabled Jan 23 18:53:49.183837 kernel: NX (Execute Disable) protection: active Jan 23 18:53:49.183848 kernel: APIC: Static calls initialized Jan 23 18:53:49.183855 kernel: efi: EFI v2.7 by Microsoft Jan 23 18:53:49.183863 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Jan 23 18:53:49.183871 kernel: random: crng init done Jan 23 18:53:49.183878 kernel: secureboot: Secure boot disabled Jan 23 18:53:49.183885 kernel: SMBIOS 3.1.0 present. Jan 23 18:53:49.183893 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Jan 23 18:53:49.183901 kernel: DMI: Memory slots populated: 3/3 Jan 23 18:53:49.183908 kernel: Hypervisor detected: Microsoft Hyper-V Jan 23 18:53:49.183916 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Jan 23 18:53:49.183925 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Jan 23 18:53:49.183933 kernel: Hyper-V: Using hypercall for remote TLB flush Jan 23 18:53:49.183940 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Jan 23 18:53:49.183948 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Jan 23 18:53:49.183956 kernel: tsc: Marking TSC unstable due to running on Hyper-V Jan 23 18:53:49.183964 kernel: tsc: Detected 2593.992 MHz processor Jan 23 18:53:49.183972 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Jan 23 18:53:49.183980 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Jan 23 18:53:49.183988 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Jan 23 18:53:49.183998 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Jan 23 18:53:49.184005 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Jan 23 18:53:49.184013 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Jan 23 18:53:49.184020 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Jan 23 18:53:49.184028 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Jan 23 18:53:49.184035 kernel: Using GB pages for direct mapping Jan 23 18:53:49.184043 kernel: ACPI: Early table checksum verification disabled Jan 23 18:53:49.184055 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Jan 23 18:53:49.184065 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 23 18:53:49.184074 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 23 18:53:49.184082 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Jan 23 18:53:49.184090 kernel: ACPI: FACS 0x000000003FFFE000 000040 Jan 23 18:53:49.184098 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 23 18:53:49.184106 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 23 18:53:49.184116 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 23 18:53:49.184124 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 23 18:53:49.184132 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 23 18:53:49.184140 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 23 18:53:49.184149 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 23 18:53:49.184157 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Jan 23 18:53:49.184165 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Jan 23 18:53:49.184173 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Jan 23 18:53:49.184181 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Jan 23 18:53:49.184192 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Jan 23 18:53:49.184200 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Jan 23 18:53:49.184208 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Jan 23 18:53:49.184216 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Jan 23 18:53:49.184224 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Jan 23 18:53:49.184232 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Jan 23 18:53:49.184240 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Jan 23 18:53:49.184248 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Jan 23 18:53:49.184259 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Jan 23 18:53:49.184267 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Jan 23 18:53:49.184275 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Jan 23 18:53:49.184283 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Jan 23 18:53:49.184291 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Jan 23 18:53:49.184299 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Jan 23 18:53:49.184307 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] Jan 23 18:53:49.184316 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] Jan 23 18:53:49.184324 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] Jan 23 18:53:49.184334 kernel: Zone ranges: Jan 23 18:53:49.184342 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Jan 23 18:53:49.184351 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Jan 23 18:53:49.184359 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Jan 23 18:53:49.184367 kernel: Device empty Jan 23 18:53:49.184375 kernel: Movable zone start for each node Jan 23 18:53:49.184383 kernel: Early memory node ranges Jan 23 18:53:49.184391 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Jan 23 18:53:49.184399 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Jan 23 18:53:49.184413 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Jan 23 18:53:49.184421 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Jan 23 18:53:49.184429 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Jan 23 18:53:49.184437 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Jan 23 18:53:49.184445 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Jan 23 18:53:49.184453 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Jan 23 18:53:49.184461 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Jan 23 18:53:49.184469 kernel: ACPI: PM-Timer IO Port: 0x408 Jan 23 18:53:49.184477 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Jan 23 18:53:49.184488 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Jan 23 18:53:49.184496 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Jan 23 18:53:49.184504 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Jan 23 18:53:49.184512 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Jan 23 18:53:49.184520 kernel: CPU topo: Max. logical packages: 1 Jan 23 18:53:49.184528 kernel: CPU topo: Max. logical dies: 1 Jan 23 18:53:49.184536 kernel: CPU topo: Max. dies per package: 1 Jan 23 18:53:49.184544 kernel: CPU topo: Max. threads per core: 1 Jan 23 18:53:49.184552 kernel: CPU topo: Num. cores per package: 6 Jan 23 18:53:49.184562 kernel: CPU topo: Num. threads per package: 6 Jan 23 18:53:49.184570 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs Jan 23 18:53:49.184578 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Jan 23 18:53:49.184586 kernel: Booting paravirtualized kernel on Hyper-V Jan 23 18:53:49.184595 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Jan 23 18:53:49.184603 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Jan 23 18:53:49.184611 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 Jan 23 18:53:49.184619 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 Jan 23 18:53:49.184627 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Jan 23 18:53:49.184637 kernel: Hyper-V: PV spinlocks enabled Jan 23 18:53:49.184667 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Jan 23 18:53:49.184676 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=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=e498a861432c458392bc8ae0919597d8f4554cdcc46b00c7f3d7a634c3492c81 Jan 23 18:53:49.184685 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Jan 23 18:53:49.184693 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Jan 23 18:53:49.184701 kernel: Fallback order for Node 0: 0 Jan 23 18:53:49.184709 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 Jan 23 18:53:49.184717 kernel: Policy zone: Normal Jan 23 18:53:49.184728 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jan 23 18:53:49.184744 kernel: software IO TLB: area num 8. Jan 23 18:53:49.184753 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Jan 23 18:53:49.184764 kernel: Kernel/User page tables isolation: enabled Jan 23 18:53:49.184772 kernel: ftrace: allocating 40097 entries in 157 pages Jan 23 18:53:49.184781 kernel: ftrace: allocated 157 pages with 5 groups Jan 23 18:53:49.184789 kernel: Dynamic Preempt: voluntary Jan 23 18:53:49.184798 kernel: rcu: Preemptible hierarchical RCU implementation. Jan 23 18:53:49.184807 kernel: rcu: RCU event tracing is enabled. Jan 23 18:53:49.184816 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Jan 23 18:53:49.184824 kernel: Trampoline variant of Tasks RCU enabled. Jan 23 18:53:49.184835 kernel: Rude variant of Tasks RCU enabled. Jan 23 18:53:49.184844 kernel: Tracing variant of Tasks RCU enabled. Jan 23 18:53:49.184853 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Jan 23 18:53:49.184861 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Jan 23 18:53:49.184870 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jan 23 18:53:49.184878 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jan 23 18:53:49.184889 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jan 23 18:53:49.184898 kernel: Using NULL legacy PIC Jan 23 18:53:49.184907 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Jan 23 18:53:49.184915 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Jan 23 18:53:49.184923 kernel: Console: colour dummy device 80x25 Jan 23 18:53:49.184932 kernel: printk: legacy console [tty1] enabled Jan 23 18:53:49.184940 kernel: printk: legacy console [ttyS0] enabled Jan 23 18:53:49.184949 kernel: printk: legacy bootconsole [earlyser0] disabled Jan 23 18:53:49.184957 kernel: ACPI: Core revision 20240827 Jan 23 18:53:49.184968 kernel: Failed to register legacy timer interrupt Jan 23 18:53:49.184977 kernel: APIC: Switch to symmetric I/O mode setup Jan 23 18:53:49.184985 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Jan 23 18:53:49.184994 kernel: Hyper-V: enabling crash_kexec_post_notifiers Jan 23 18:53:49.185002 kernel: Hyper-V: Using IPI hypercalls Jan 23 18:53:49.185011 kernel: APIC: send_IPI() replaced with hv_send_ipi() Jan 23 18:53:49.185019 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Jan 23 18:53:49.185028 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Jan 23 18:53:49.185036 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Jan 23 18:53:49.185055 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Jan 23 18:53:49.185064 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Jan 23 18:53:49.185073 kernel: Hyper-V: Using enlightened APIC (xapic mode) Jan 23 18:53:49.185081 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Jan 23 18:53:49.185090 kernel: APIC: read() replaced with hv_apic_read() Jan 23 18:53:49.185098 kernel: APIC: write() replaced with hv_apic_write() Jan 23 18:53:49.185107 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Jan 23 18:53:49.185115 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Jan 23 18:53:49.185124 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593992) Jan 23 18:53:49.185135 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Jan 23 18:53:49.185144 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Jan 23 18:53:49.185152 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Jan 23 18:53:49.185161 kernel: Spectre V2 : Mitigation: Retpolines Jan 23 18:53:49.185169 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Jan 23 18:53:49.185177 kernel: Speculative Store Bypass: Vulnerable Jan 23 18:53:49.185186 kernel: MDS: Mitigation: Clear CPU buffers Jan 23 18:53:49.185194 kernel: TAA: Mitigation: Clear CPU buffers Jan 23 18:53:49.185202 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Jan 23 18:53:49.185210 kernel: active return thunk: its_return_thunk Jan 23 18:53:49.185219 kernel: ITS: Mitigation: Aligned branch/return thunks Jan 23 18:53:49.185230 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Jan 23 18:53:49.185238 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Jan 23 18:53:49.185246 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Jan 23 18:53:49.185255 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Jan 23 18:53:49.185264 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Jan 23 18:53:49.185272 kernel: Freeing SMP alternatives memory: 32K Jan 23 18:53:49.185280 kernel: pid_max: default: 32768 minimum: 301 Jan 23 18:53:49.185288 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Jan 23 18:53:49.185297 kernel: landlock: Up and running. Jan 23 18:53:49.185305 kernel: SELinux: Initializing. Jan 23 18:53:49.185314 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Jan 23 18:53:49.185325 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Jan 23 18:53:49.185333 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Jan 23 18:53:49.185342 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Jan 23 18:53:49.185350 kernel: signal: max sigframe size: 1776 Jan 23 18:53:49.185359 kernel: rcu: Hierarchical SRCU implementation. Jan 23 18:53:49.185368 kernel: rcu: Max phase no-delay instances is 400. Jan 23 18:53:49.185376 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Jan 23 18:53:49.185385 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Jan 23 18:53:49.185393 kernel: smp: Bringing up secondary CPUs ... Jan 23 18:53:49.185404 kernel: smpboot: x86: Booting SMP configuration: Jan 23 18:53:49.185413 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Jan 23 18:53:49.185421 kernel: smp: Brought up 1 node, 6 CPUs Jan 23 18:53:49.185430 kernel: smpboot: Total of 6 processors activated (31127.90 BogoMIPS) Jan 23 18:53:49.185439 kernel: Memory: 115330892K/117439364K available (14336K kernel code, 2445K rwdata, 26064K rodata, 46200K init, 2560K bss, 2098196K reserved, 0K cma-reserved) Jan 23 18:53:49.185447 kernel: devtmpfs: initialized Jan 23 18:53:49.185456 kernel: x86/mm: Memory block size: 1024MB Jan 23 18:53:49.185464 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Jan 23 18:53:49.185473 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Jan 23 18:53:49.185484 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Jan 23 18:53:49.185492 kernel: pinctrl core: initialized pinctrl subsystem Jan 23 18:53:49.185501 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jan 23 18:53:49.185510 kernel: audit: initializing netlink subsys (disabled) Jan 23 18:53:49.185518 kernel: audit: type=2000 audit(1769194425.028:1): state=initialized audit_enabled=0 res=1 Jan 23 18:53:49.185526 kernel: thermal_sys: Registered thermal governor 'step_wise' Jan 23 18:53:49.185535 kernel: thermal_sys: Registered thermal governor 'user_space' Jan 23 18:53:49.185543 kernel: cpuidle: using governor menu Jan 23 18:53:49.185552 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Jan 23 18:53:49.185563 kernel: dca service started, version 1.12.1 Jan 23 18:53:49.185571 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Jan 23 18:53:49.185580 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Jan 23 18:53:49.185588 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Jan 23 18:53:49.185597 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Jan 23 18:53:49.185605 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Jan 23 18:53:49.185614 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Jan 23 18:53:49.185622 kernel: ACPI: Added _OSI(Module Device) Jan 23 18:53:49.185630 kernel: ACPI: Added _OSI(Processor Device) Jan 23 18:53:49.185650 kernel: ACPI: Added _OSI(Processor Aggregator Device) Jan 23 18:53:49.185659 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Jan 23 18:53:49.185667 kernel: ACPI: Interpreter enabled Jan 23 18:53:49.185676 kernel: ACPI: PM: (supports S0 S5) Jan 23 18:53:49.185684 kernel: ACPI: Using IOAPIC for interrupt routing Jan 23 18:53:49.185693 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Jan 23 18:53:49.185702 kernel: PCI: Ignoring E820 reservations for host bridge windows Jan 23 18:53:49.185710 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Jan 23 18:53:49.185719 kernel: iommu: Default domain type: Translated Jan 23 18:53:49.185730 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Jan 23 18:53:49.185738 kernel: efivars: Registered efivars operations Jan 23 18:53:49.185747 kernel: PCI: Using ACPI for IRQ routing Jan 23 18:53:49.185755 kernel: PCI: System does not support PCI Jan 23 18:53:49.185764 kernel: vgaarb: loaded Jan 23 18:53:49.185772 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Jan 23 18:53:49.185781 kernel: VFS: Disk quotas dquot_6.6.0 Jan 23 18:53:49.185789 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jan 23 18:53:49.185798 kernel: pnp: PnP ACPI init Jan 23 18:53:49.185808 kernel: pnp: PnP ACPI: found 3 devices Jan 23 18:53:49.185817 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Jan 23 18:53:49.185826 kernel: NET: Registered PF_INET protocol family Jan 23 18:53:49.185834 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Jan 23 18:53:49.185843 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Jan 23 18:53:49.185852 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jan 23 18:53:49.185860 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Jan 23 18:53:49.185869 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jan 23 18:53:49.185877 kernel: TCP: Hash tables configured (established 524288 bind 65536) Jan 23 18:53:49.185888 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jan 23 18:53:49.185897 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jan 23 18:53:49.185905 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jan 23 18:53:49.185914 kernel: NET: Registered PF_XDP protocol family Jan 23 18:53:49.185922 kernel: PCI: CLS 0 bytes, default 64 Jan 23 18:53:49.185931 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Jan 23 18:53:49.185939 kernel: software IO TLB: mapped [mem 0x000000003ae61000-0x000000003ee61000] (64MB) Jan 23 18:53:49.185948 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Jan 23 18:53:49.185956 kernel: Initialise system trusted keyrings Jan 23 18:53:49.185967 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Jan 23 18:53:49.185976 kernel: Key type asymmetric registered Jan 23 18:53:49.185984 kernel: Asymmetric key parser 'x509' registered Jan 23 18:53:49.185992 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Jan 23 18:53:49.186001 kernel: io scheduler mq-deadline registered Jan 23 18:53:49.186010 kernel: io scheduler kyber registered Jan 23 18:53:49.186018 kernel: io scheduler bfq registered Jan 23 18:53:49.186027 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Jan 23 18:53:49.186035 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Jan 23 18:53:49.186046 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Jan 23 18:53:49.186055 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Jan 23 18:53:49.186063 kernel: i8042: PNP: No PS/2 controller found. Jan 23 18:53:49.186211 kernel: rtc_cmos 00:02: registered as rtc0 Jan 23 18:53:49.186317 kernel: rtc_cmos 00:02: setting system clock to 2026-01-23T18:53:48 UTC (1769194428) Jan 23 18:53:49.186417 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Jan 23 18:53:49.186430 kernel: intel_pstate: CPU model not supported Jan 23 18:53:49.186444 kernel: efifb: probing for efifb Jan 23 18:53:49.186452 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Jan 23 18:53:49.186461 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Jan 23 18:53:49.186469 kernel: efifb: scrolling: redraw Jan 23 18:53:49.186478 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Jan 23 18:53:49.186486 kernel: Console: switching to colour frame buffer device 128x48 Jan 23 18:53:49.186495 kernel: fb0: EFI VGA frame buffer device Jan 23 18:53:49.186504 kernel: pstore: Using crash dump compression: deflate Jan 23 18:53:49.186512 kernel: pstore: Registered efi_pstore as persistent store backend Jan 23 18:53:49.186523 kernel: NET: Registered PF_INET6 protocol family Jan 23 18:53:49.186532 kernel: Segment Routing with IPv6 Jan 23 18:53:49.186540 kernel: In-situ OAM (IOAM) with IPv6 Jan 23 18:53:49.186548 kernel: NET: Registered PF_PACKET protocol family Jan 23 18:53:49.186557 kernel: Key type dns_resolver registered Jan 23 18:53:49.186565 kernel: IPI shorthand broadcast: enabled Jan 23 18:53:49.186574 kernel: sched_clock: Marking stable (3585007200, 111468300)->(4037611100, -341135600) Jan 23 18:53:49.186582 kernel: registered taskstats version 1 Jan 23 18:53:49.186591 kernel: Loading compiled-in X.509 certificates Jan 23 18:53:49.186600 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.66-flatcar: 2aec04a968f0111235eb989789145bc2b989f0c6' Jan 23 18:53:49.186610 kernel: Demotion targets for Node 0: null Jan 23 18:53:49.186619 kernel: Key type .fscrypt registered Jan 23 18:53:49.186627 kernel: Key type fscrypt-provisioning registered Jan 23 18:53:49.186636 kernel: ima: No TPM chip found, activating TPM-bypass! Jan 23 18:53:49.186656 kernel: ima: Allocated hash algorithm: sha1 Jan 23 18:53:49.186665 kernel: ima: No architecture policies found Jan 23 18:53:49.186674 kernel: clk: Disabling unused clocks Jan 23 18:53:49.186682 kernel: Warning: unable to open an initial console. Jan 23 18:53:49.186691 kernel: Freeing unused kernel image (initmem) memory: 46200K Jan 23 18:53:49.186702 kernel: Write protecting the kernel read-only data: 40960k Jan 23 18:53:49.186711 kernel: Freeing unused kernel image (rodata/data gap) memory: 560K Jan 23 18:53:49.186719 kernel: Run /init as init process Jan 23 18:53:49.186728 kernel: with arguments: Jan 23 18:53:49.186736 kernel: /init Jan 23 18:53:49.186744 kernel: with environment: Jan 23 18:53:49.186753 kernel: HOME=/ Jan 23 18:53:49.186761 kernel: TERM=linux Jan 23 18:53:49.186771 systemd[1]: Successfully made /usr/ read-only. Jan 23 18:53:49.186785 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) Jan 23 18:53:49.186794 systemd[1]: Detected virtualization microsoft. Jan 23 18:53:49.186803 systemd[1]: Detected architecture x86-64. Jan 23 18:53:49.186812 systemd[1]: Running in initrd. Jan 23 18:53:49.186821 systemd[1]: No hostname configured, using default hostname. Jan 23 18:53:49.186830 systemd[1]: Hostname set to . Jan 23 18:53:49.186839 systemd[1]: Initializing machine ID from random generator. Jan 23 18:53:49.186851 systemd[1]: Queued start job for default target initrd.target. Jan 23 18:53:49.186860 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jan 23 18:53:49.186869 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jan 23 18:53:49.186879 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Jan 23 18:53:49.186888 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jan 23 18:53:49.186897 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Jan 23 18:53:49.186907 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Jan 23 18:53:49.186919 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Jan 23 18:53:49.186929 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Jan 23 18:53:49.186938 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jan 23 18:53:49.186947 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jan 23 18:53:49.186956 systemd[1]: Reached target paths.target - Path Units. Jan 23 18:53:49.186965 systemd[1]: Reached target slices.target - Slice Units. Jan 23 18:53:49.186974 systemd[1]: Reached target swap.target - Swaps. Jan 23 18:53:49.186984 systemd[1]: Reached target timers.target - Timer Units. Jan 23 18:53:49.186995 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Jan 23 18:53:49.187004 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jan 23 18:53:49.187014 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Jan 23 18:53:49.187023 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Jan 23 18:53:49.187032 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jan 23 18:53:49.187041 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jan 23 18:53:49.187050 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jan 23 18:53:49.187059 systemd[1]: Reached target sockets.target - Socket Units. Jan 23 18:53:49.187068 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Jan 23 18:53:49.187080 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jan 23 18:53:49.187089 systemd[1]: Finished network-cleanup.service - Network Cleanup. Jan 23 18:53:49.187098 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). Jan 23 18:53:49.187107 systemd[1]: Starting systemd-fsck-usr.service... Jan 23 18:53:49.187116 systemd[1]: Starting systemd-journald.service - Journal Service... Jan 23 18:53:49.187125 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jan 23 18:53:49.187155 systemd-journald[214]: Collecting audit messages is disabled. Jan 23 18:53:49.187182 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jan 23 18:53:49.187192 systemd-journald[214]: Journal started Jan 23 18:53:49.187214 systemd-journald[214]: Runtime Journal (/run/log/journal/6246f2b9919343d58c67b977b427e5a7) is 8M, max 2.2G, 2.1G free. Jan 23 18:53:49.190296 systemd-modules-load[216]: Inserted module 'overlay' Jan 23 18:53:49.197321 systemd[1]: Started systemd-journald.service - Journal Service. Jan 23 18:53:49.199104 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Jan 23 18:53:49.199609 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jan 23 18:53:49.200337 systemd[1]: Finished systemd-fsck-usr.service. Jan 23 18:53:49.202510 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jan 23 18:53:49.203692 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jan 23 18:53:49.248871 systemd-tmpfiles[224]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Jan 23 18:53:49.255572 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jan 23 18:53:49.257351 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jan 23 18:53:49.268013 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jan 23 18:53:49.278734 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jan 23 18:53:49.287213 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jan 23 18:53:49.313662 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Jan 23 18:53:49.318108 systemd-modules-load[216]: Inserted module 'br_netfilter' Jan 23 18:53:49.321690 kernel: Bridge firewalling registered Jan 23 18:53:49.323785 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jan 23 18:53:49.326165 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jan 23 18:53:49.346079 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jan 23 18:53:49.347800 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jan 23 18:53:49.350009 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jan 23 18:53:49.380973 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jan 23 18:53:49.383274 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Jan 23 18:53:49.399579 systemd-resolved[248]: Positive Trust Anchors: Jan 23 18:53:49.399593 systemd-resolved[248]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jan 23 18:53:49.399625 systemd-resolved[248]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Jan 23 18:53:49.402164 systemd-resolved[248]: Defaulting to hostname 'linux'. Jan 23 18:53:49.403198 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jan 23 18:53:49.413022 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jan 23 18:53:49.464501 dracut-cmdline[256]: Using kernel command line parameters: rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1 rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=e498a861432c458392bc8ae0919597d8f4554cdcc46b00c7f3d7a634c3492c81 Jan 23 18:53:49.582675 kernel: SCSI subsystem initialized Jan 23 18:53:49.595668 kernel: Loading iSCSI transport class v2.0-870. Jan 23 18:53:49.607666 kernel: iscsi: registered transport (tcp) Jan 23 18:53:49.630539 kernel: iscsi: registered transport (qla4xxx) Jan 23 18:53:49.630571 kernel: QLogic iSCSI HBA Driver Jan 23 18:53:49.649224 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jan 23 18:53:49.676055 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jan 23 18:53:49.678637 systemd[1]: Reached target network-pre.target - Preparation for Network. Jan 23 18:53:49.741119 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Jan 23 18:53:49.743916 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Jan 23 18:53:49.807664 kernel: raid6: avx2x4 gen() 30925 MB/s Jan 23 18:53:49.826656 kernel: raid6: avx2x2 gen() 31412 MB/s Jan 23 18:53:49.851076 kernel: raid6: avx2x1 gen() 31312 MB/s Jan 23 18:53:49.851105 kernel: raid6: using algorithm avx2x2 gen() 31412 MB/s Jan 23 18:53:49.872942 kernel: raid6: .... xor() 18473 MB/s, rmw enabled Jan 23 18:53:49.872965 kernel: raid6: using avx2x2 recovery algorithm Jan 23 18:53:49.897665 kernel: xor: automatically using best checksumming function avx Jan 23 18:53:50.059674 kernel: Btrfs loaded, zoned=no, fsverity=no Jan 23 18:53:50.066939 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Jan 23 18:53:50.070328 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jan 23 18:53:50.105246 systemd-udevd[465]: Using default interface naming scheme 'v255'. Jan 23 18:53:50.111441 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jan 23 18:53:50.113739 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Jan 23 18:53:50.149109 dracut-pre-trigger[473]: rd.md=0: removing MD RAID activation Jan 23 18:53:50.176519 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Jan 23 18:53:50.179144 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jan 23 18:53:50.253494 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jan 23 18:53:50.268757 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Jan 23 18:53:50.282684 kernel: cryptd: max_cpu_qlen set to 1000 Jan 23 18:53:50.292662 kernel: AES CTR mode by8 optimization enabled Jan 23 18:53:50.307665 kernel: hv_vmbus: Vmbus version:4.0 Jan 23 18:53:50.309864 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jan 23 18:53:50.312307 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jan 23 18:53:50.330396 kernel: pps_core: LinuxPPS API ver. 1 registered Jan 23 18:53:50.330417 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Jan 23 18:53:50.330521 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Jan 23 18:53:50.341128 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jan 23 18:53:50.346001 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jan 23 18:53:50.365216 kernel: hv_vmbus: registering driver hyperv_keyboard Jan 23 18:53:50.365236 kernel: input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/VMBUS:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input0 Jan 23 18:53:50.367662 kernel: PTP clock support registered Jan 23 18:53:50.370108 kernel: hv_vmbus: registering driver hv_pci Jan 23 18:53:50.370138 kernel: hv_vmbus: registering driver hv_netvsc Jan 23 18:53:50.370198 kernel: hid: raw HID events driver (C) Jiri Kosina Jan 23 18:53:50.372663 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Jan 23 18:53:50.382898 kernel: hv_vmbus: registering driver hid_hyperv Jan 23 18:53:50.388744 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Jan 23 18:53:50.388920 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Jan 23 18:53:50.394661 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Jan 23 18:53:50.394845 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Jan 23 18:53:50.399659 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Jan 23 18:53:50.410324 kernel: hv_utils: Registering HyperV Utility Driver Jan 23 18:53:50.410354 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Jan 23 18:53:50.410629 kernel: hv_vmbus: registering driver hv_utils Jan 23 18:53:50.410659 kernel: hv_utils: Heartbeat IC version 3.0 Jan 23 18:53:50.416781 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint Jan 23 18:53:50.419780 kernel: hv_utils: Shutdown IC version 3.2 Jan 23 18:53:50.419808 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] Jan 23 18:53:50.424665 kernel: hv_utils: TimeSync IC version 4.0 Jan 23 18:53:50.427660 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] Jan 23 18:53:50.499469 systemd-resolved[248]: Clock change detected. Flushing caches. Jan 23 18:53:50.520480 kernel: hv_vmbus: registering driver hv_storvsc Jan 23 18:53:50.520518 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] Jan 23 18:53:50.524545 kernel: pci 0001:00:00.0: Enabling HDA controller Jan 23 18:53:50.527195 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jan 23 18:53:50.549297 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Jan 23 18:53:50.549518 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned Jan 23 18:53:50.556391 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned Jan 23 18:53:50.556576 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned Jan 23 18:53:50.567286 kernel: scsi host0: storvsc_host_t Jan 23 18:53:50.568513 kernel: scsi host1: storvsc_host_t Jan 23 18:53:50.568551 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Jan 23 18:53:50.577525 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Jan 23 18:53:50.583502 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Jan 23 18:53:50.598946 kernel: sd 0:0:0:1: [sda] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Jan 23 18:53:50.599199 kernel: sd 0:0:0:0: [sdb] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Jan 23 18:53:50.599355 kernel: sd 0:0:0:1: [sda] 4096-byte physical blocks Jan 23 18:53:50.599505 kernel: sd 0:0:0:0: [sdb] 4096-byte physical blocks Jan 23 18:53:50.604261 kernel: sd 0:0:0:1: [sda] Write Protect is off Jan 23 18:53:50.604469 kernel: sd 0:0:0:0: [sdb] Write Protect is off Jan 23 18:53:50.609151 kernel: sd 0:0:0:1: [sda] Mode Sense: 0f 00 10 00 Jan 23 18:53:50.609313 kernel: sd 0:0:0:1: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Jan 23 18:53:50.609447 kernel: sd 0:0:0:0: [sdb] Mode Sense: 0f 00 10 00 Jan 23 18:53:50.611500 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#133 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jan 23 18:53:50.626623 kernel: sd 0:0:0:0: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Jan 23 18:53:50.632298 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#0 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jan 23 18:53:50.632440 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#140 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jan 23 18:53:50.644547 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#325 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jan 23 18:53:50.658223 kernel: sda: sda1 Jan 23 18:53:50.658287 kernel: sd 0:0:0:1: [sda] Attached SCSI disk Jan 23 18:53:50.670498 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Jan 23 18:53:50.673507 kernel: sd 0:0:0:0: [sdb] Attached SCSI disk Jan 23 18:53:50.673710 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Jan 23 18:53:50.678811 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Jan 23 18:53:50.680551 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Jan 23 18:53:51.217712 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Jan 23 18:53:51.234153 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Jan 23 18:53:51.294828 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Jan 23 18:53:51.326127 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Jan 23 18:53:51.327583 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Jan 23 18:53:51.347963 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Jan 23 18:53:51.389502 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#166 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jan 23 18:53:51.418513 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Jan 23 18:53:51.427510 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#203 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jan 23 18:53:51.451538 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Jan 23 18:53:52.402325 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Jan 23 18:53:52.411825 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Jan 23 18:53:52.413350 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jan 23 18:53:52.422474 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jan 23 18:53:52.436523 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Jan 23 18:53:52.458510 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#378 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jan 23 18:53:52.469715 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Jan 23 18:53:52.480773 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Jan 23 18:53:52.480797 disk-uuid[704]: The operation has completed successfully. Jan 23 18:53:52.543739 systemd[1]: disk-uuid.service: Deactivated successfully. Jan 23 18:53:52.543848 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Jan 23 18:53:52.606495 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Jan 23 18:53:52.636608 sh[876]: Success Jan 23 18:53:52.716359 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Jan 23 18:53:52.716428 kernel: device-mapper: uevent: version 1.0.3 Jan 23 18:53:52.721317 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Jan 23 18:53:52.734506 kernel: device-mapper: verity: sha256 using shash "sha256-avx2" Jan 23 18:53:53.013117 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Jan 23 18:53:53.023339 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Jan 23 18:53:53.042900 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Jan 23 18:53:53.059507 kernel: BTRFS: device fsid 4711e7dc-9586-49d4-8dcc-466f082e7841 devid 1 transid 37 /dev/mapper/usr (254:0) scanned by mount (894) Jan 23 18:53:53.059544 kernel: BTRFS info (device dm-0): first mount of filesystem 4711e7dc-9586-49d4-8dcc-466f082e7841 Jan 23 18:53:53.064515 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Jan 23 18:53:53.660793 kernel: BTRFS info (device dm-0): disabling log replay at mount time Jan 23 18:53:53.660869 kernel: BTRFS info (device dm-0): enabling free space tree Jan 23 18:53:53.709269 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Jan 23 18:53:53.711246 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Jan 23 18:53:53.717622 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Jan 23 18:53:53.718405 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Jan 23 18:53:53.750054 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Jan 23 18:53:53.785506 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sdb6 (8:22) scanned by mount (925) Jan 23 18:53:53.795121 kernel: BTRFS info (device sdb6): first mount of filesystem a15cc984-6718-480b-8520-c0d724ebf6fe Jan 23 18:53:53.795151 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Jan 23 18:53:53.825378 kernel: BTRFS info (device sdb6): turning on async discard Jan 23 18:53:53.825410 kernel: BTRFS info (device sdb6): enabling free space tree Jan 23 18:53:53.833504 kernel: BTRFS info (device sdb6): last unmount of filesystem a15cc984-6718-480b-8520-c0d724ebf6fe Jan 23 18:53:53.834498 systemd[1]: Finished ignition-setup.service - Ignition (setup). Jan 23 18:53:53.836226 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Jan 23 18:53:53.853595 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jan 23 18:53:53.855877 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jan 23 18:53:53.906036 systemd-networkd[1064]: lo: Link UP Jan 23 18:53:53.906047 systemd-networkd[1064]: lo: Gained carrier Jan 23 18:53:53.907071 systemd-networkd[1064]: Enumeration completed Jan 23 18:53:53.907447 systemd-networkd[1064]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jan 23 18:53:53.907452 systemd-networkd[1064]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jan 23 18:53:53.908264 systemd-networkd[1064]: eth0: Link UP Jan 23 18:53:53.908427 systemd-networkd[1064]: eth0: Gained carrier Jan 23 18:53:53.908437 systemd-networkd[1064]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jan 23 18:53:53.908955 systemd[1]: Started systemd-networkd.service - Network Configuration. Jan 23 18:53:53.913367 systemd[1]: Reached target network.target - Network. Jan 23 18:53:53.962520 systemd-networkd[1064]: eth0: DHCPv4 address 10.200.4.29/24, gateway 10.200.4.1 acquired from 168.63.129.16 Jan 23 18:53:55.570559 ignition[1054]: Ignition 2.22.0 Jan 23 18:53:55.570575 ignition[1054]: Stage: fetch-offline Jan 23 18:53:55.570709 ignition[1054]: no configs at "/usr/lib/ignition/base.d" Jan 23 18:53:55.570719 ignition[1054]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jan 23 18:53:55.570816 ignition[1054]: parsed url from cmdline: "" Jan 23 18:53:55.570820 ignition[1054]: no config URL provided Jan 23 18:53:55.570826 ignition[1054]: reading system config file "/usr/lib/ignition/user.ign" Jan 23 18:53:55.570834 ignition[1054]: no config at "/usr/lib/ignition/user.ign" Jan 23 18:53:55.570839 ignition[1054]: failed to fetch config: resource requires networking Jan 23 18:53:55.570999 ignition[1054]: Ignition finished successfully Jan 23 18:53:55.602909 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Jan 23 18:53:55.605472 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Jan 23 18:53:55.654513 ignition[1076]: Ignition 2.22.0 Jan 23 18:53:55.654524 ignition[1076]: Stage: fetch Jan 23 18:53:55.654751 ignition[1076]: no configs at "/usr/lib/ignition/base.d" Jan 23 18:53:55.654764 ignition[1076]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jan 23 18:53:55.654853 ignition[1076]: parsed url from cmdline: "" Jan 23 18:53:55.654857 ignition[1076]: no config URL provided Jan 23 18:53:55.654862 ignition[1076]: reading system config file "/usr/lib/ignition/user.ign" Jan 23 18:53:55.654871 ignition[1076]: no config at "/usr/lib/ignition/user.ign" Jan 23 18:53:55.654890 ignition[1076]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Jan 23 18:53:55.786580 ignition[1076]: GET result: OK Jan 23 18:53:55.786662 ignition[1076]: config has been read from IMDS userdata Jan 23 18:53:55.786687 ignition[1076]: parsing config with SHA512: 2b5143f974f673baff8af9cfdc4e5d680f94d9efdeb07ccb1c5abce7868cb055f1df11955913bee365a5438f3f094b454794c68dc5f017a087ee7308f2133ae1 Jan 23 18:53:55.791902 unknown[1076]: fetched base config from "system" Jan 23 18:53:55.792139 ignition[1076]: fetch: fetch complete Jan 23 18:53:55.791914 unknown[1076]: fetched base config from "system" Jan 23 18:53:55.792148 ignition[1076]: fetch: fetch passed Jan 23 18:53:55.791920 unknown[1076]: fetched user config from "azure" Jan 23 18:53:55.792195 ignition[1076]: Ignition finished successfully Jan 23 18:53:55.801895 systemd-networkd[1064]: eth0: Gained IPv6LL Jan 23 18:53:55.815263 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Jan 23 18:53:55.817775 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Jan 23 18:53:55.852468 ignition[1086]: Ignition 2.22.0 Jan 23 18:53:55.852492 ignition[1086]: Stage: kargs Jan 23 18:53:55.852742 ignition[1086]: no configs at "/usr/lib/ignition/base.d" Jan 23 18:53:55.852753 ignition[1086]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jan 23 18:53:55.858583 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Jan 23 18:53:55.853374 ignition[1086]: kargs: kargs passed Jan 23 18:53:55.868367 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Jan 23 18:53:55.853417 ignition[1086]: Ignition finished successfully Jan 23 18:53:55.926039 ignition[1096]: Ignition 2.22.0 Jan 23 18:53:55.926052 ignition[1096]: Stage: disks Jan 23 18:53:55.926320 ignition[1096]: no configs at "/usr/lib/ignition/base.d" Jan 23 18:53:55.926334 ignition[1096]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jan 23 18:53:55.932715 systemd[1]: Finished ignition-disks.service - Ignition (disks). Jan 23 18:53:55.926848 ignition[1096]: disks: disks passed Jan 23 18:53:55.939035 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Jan 23 18:53:55.926895 ignition[1096]: Ignition finished successfully Jan 23 18:53:55.947632 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Jan 23 18:53:55.948976 systemd[1]: Reached target local-fs.target - Local File Systems. Jan 23 18:53:55.959406 systemd[1]: Reached target sysinit.target - System Initialization. Jan 23 18:53:55.975041 systemd[1]: Reached target basic.target - Basic System. Jan 23 18:53:55.982494 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Jan 23 18:53:56.088855 systemd-fsck[1107]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks Jan 23 18:53:56.094234 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Jan 23 18:53:56.105645 systemd[1]: Mounting sysroot.mount - /sysroot... Jan 23 18:53:56.366534 kernel: EXT4-fs (sdb9): mounted filesystem dcb97a38-a4f5-43e7-bcb0-85a5c1e2a446 r/w with ordered data mode. Quota mode: none. Jan 23 18:53:56.367329 systemd[1]: Mounted sysroot.mount - /sysroot. Jan 23 18:53:56.369441 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Jan 23 18:53:56.389528 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jan 23 18:53:56.391341 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Jan 23 18:53:56.422075 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Jan 23 18:53:56.431777 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Jan 23 18:53:56.431813 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Jan 23 18:53:56.456586 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sdb6 (8:22) scanned by mount (1121) Jan 23 18:53:56.456610 kernel: BTRFS info (device sdb6): first mount of filesystem a15cc984-6718-480b-8520-c0d724ebf6fe Jan 23 18:53:56.456623 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Jan 23 18:53:56.451092 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Jan 23 18:53:56.475644 kernel: BTRFS info (device sdb6): turning on async discard Jan 23 18:53:56.475666 kernel: BTRFS info (device sdb6): enabling free space tree Jan 23 18:53:56.462426 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Jan 23 18:53:56.478551 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jan 23 18:53:57.554201 coreos-metadata[1123]: Jan 23 18:53:57.554 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Jan 23 18:53:57.560963 coreos-metadata[1123]: Jan 23 18:53:57.560 INFO Fetch successful Jan 23 18:53:57.565143 coreos-metadata[1123]: Jan 23 18:53:57.564 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Jan 23 18:53:57.573345 coreos-metadata[1123]: Jan 23 18:53:57.573 INFO Fetch successful Jan 23 18:53:57.589943 coreos-metadata[1123]: Jan 23 18:53:57.589 INFO wrote hostname ci-4459.2.3-a-c4c88d9723 to /sysroot/etc/hostname Jan 23 18:53:57.597456 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Jan 23 18:53:57.744068 initrd-setup-root[1152]: cut: /sysroot/etc/passwd: No such file or directory Jan 23 18:53:57.807832 initrd-setup-root[1159]: cut: /sysroot/etc/group: No such file or directory Jan 23 18:53:57.815522 initrd-setup-root[1166]: cut: /sysroot/etc/shadow: No such file or directory Jan 23 18:53:57.822682 initrd-setup-root[1173]: cut: /sysroot/etc/gshadow: No such file or directory Jan 23 18:53:59.360337 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Jan 23 18:53:59.363138 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Jan 23 18:53:59.388978 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Jan 23 18:53:59.394406 systemd[1]: sysroot-oem.mount: Deactivated successfully. Jan 23 18:53:59.405546 kernel: BTRFS info (device sdb6): last unmount of filesystem a15cc984-6718-480b-8520-c0d724ebf6fe Jan 23 18:53:59.428015 ignition[1241]: INFO : Ignition 2.22.0 Jan 23 18:53:59.428015 ignition[1241]: INFO : Stage: mount Jan 23 18:53:59.433898 ignition[1241]: INFO : no configs at "/usr/lib/ignition/base.d" Jan 23 18:53:59.433898 ignition[1241]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jan 23 18:53:59.433898 ignition[1241]: INFO : mount: mount passed Jan 23 18:53:59.433898 ignition[1241]: INFO : Ignition finished successfully Jan 23 18:53:59.444676 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Jan 23 18:53:59.446268 systemd[1]: Finished ignition-mount.service - Ignition (mount). Jan 23 18:53:59.457368 systemd[1]: Starting ignition-files.service - Ignition (files)... Jan 23 18:53:59.488122 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jan 23 18:53:59.516501 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sdb6 (8:22) scanned by mount (1255) Jan 23 18:53:59.525759 kernel: BTRFS info (device sdb6): first mount of filesystem a15cc984-6718-480b-8520-c0d724ebf6fe Jan 23 18:53:59.525789 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Jan 23 18:53:59.536632 kernel: BTRFS info (device sdb6): turning on async discard Jan 23 18:53:59.536659 kernel: BTRFS info (device sdb6): enabling free space tree Jan 23 18:53:59.538924 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jan 23 18:53:59.576981 ignition[1272]: INFO : Ignition 2.22.0 Jan 23 18:53:59.576981 ignition[1272]: INFO : Stage: files Jan 23 18:53:59.582803 ignition[1272]: INFO : no configs at "/usr/lib/ignition/base.d" Jan 23 18:53:59.582803 ignition[1272]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jan 23 18:53:59.582803 ignition[1272]: DEBUG : files: compiled without relabeling support, skipping Jan 23 18:53:59.596183 ignition[1272]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Jan 23 18:53:59.596183 ignition[1272]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Jan 23 18:53:59.647162 ignition[1272]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Jan 23 18:53:59.652688 ignition[1272]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Jan 23 18:53:59.652688 ignition[1272]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Jan 23 18:53:59.647835 unknown[1272]: wrote ssh authorized keys file for user: core Jan 23 18:53:59.700698 ignition[1272]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Jan 23 18:53:59.707795 ignition[1272]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Jan 23 18:53:59.707795 ignition[1272]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Jan 23 18:53:59.707795 ignition[1272]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Jan 23 18:53:59.707795 ignition[1272]: INFO : files: files passed Jan 23 18:53:59.707795 ignition[1272]: INFO : Ignition finished successfully Jan 23 18:53:59.717144 systemd[1]: Finished ignition-files.service - Ignition (files). Jan 23 18:53:59.724341 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Jan 23 18:53:59.756850 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Jan 23 18:53:59.758744 systemd[1]: ignition-quench.service: Deactivated successfully. Jan 23 18:53:59.758840 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Jan 23 18:53:59.822475 initrd-setup-root-after-ignition[1305]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jan 23 18:53:59.822475 initrd-setup-root-after-ignition[1305]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Jan 23 18:53:59.835322 initrd-setup-root-after-ignition[1309]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jan 23 18:53:59.842527 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Jan 23 18:53:59.843553 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Jan 23 18:53:59.857800 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Jan 23 18:53:59.909667 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Jan 23 18:53:59.909819 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Jan 23 18:53:59.912158 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Jan 23 18:53:59.926503 systemd[1]: Reached target initrd.target - Initrd Default Target. Jan 23 18:53:59.934636 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Jan 23 18:53:59.935561 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Jan 23 18:53:59.981235 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jan 23 18:53:59.984033 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Jan 23 18:54:00.025670 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Jan 23 18:54:00.027378 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Jan 23 18:54:00.036675 systemd[1]: Stopped target timers.target - Timer Units. Jan 23 18:54:00.045901 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Jan 23 18:54:00.046038 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jan 23 18:54:00.055556 systemd[1]: Stopped target initrd.target - Initrd Default Target. Jan 23 18:54:00.066627 systemd[1]: Stopped target basic.target - Basic System. Jan 23 18:54:00.080070 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Jan 23 18:54:00.085150 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Jan 23 18:54:00.087334 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Jan 23 18:54:00.099422 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Jan 23 18:54:00.112026 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Jan 23 18:54:00.120492 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Jan 23 18:54:00.129236 systemd[1]: Stopped target sysinit.target - System Initialization. Jan 23 18:54:00.138013 systemd[1]: Stopped target local-fs.target - Local File Systems. Jan 23 18:54:00.147342 systemd[1]: Stopped target swap.target - Swaps. Jan 23 18:54:00.154385 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Jan 23 18:54:00.154547 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Jan 23 18:54:00.165539 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Jan 23 18:54:00.167053 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jan 23 18:54:00.179396 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Jan 23 18:54:00.179522 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jan 23 18:54:00.188863 systemd[1]: dracut-initqueue.service: Deactivated successfully. Jan 23 18:54:00.188989 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Jan 23 18:54:00.205807 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Jan 23 18:54:00.205936 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Jan 23 18:54:00.208071 systemd[1]: ignition-files.service: Deactivated successfully. Jan 23 18:54:00.208197 systemd[1]: Stopped ignition-files.service - Ignition (files). Jan 23 18:54:00.219403 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Jan 23 18:54:00.219537 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Jan 23 18:54:00.233236 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Jan 23 18:54:00.260816 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Jan 23 18:54:00.263441 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Jan 23 18:54:00.263583 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Jan 23 18:54:00.272683 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Jan 23 18:54:00.272805 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Jan 23 18:54:00.290091 systemd[1]: initrd-cleanup.service: Deactivated successfully. Jan 23 18:54:00.290192 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Jan 23 18:54:00.301662 systemd[1]: sysroot-boot.mount: Deactivated successfully. Jan 23 18:54:00.317740 ignition[1329]: INFO : Ignition 2.22.0 Jan 23 18:54:00.317740 ignition[1329]: INFO : Stage: umount Jan 23 18:54:00.323578 ignition[1329]: INFO : no configs at "/usr/lib/ignition/base.d" Jan 23 18:54:00.323578 ignition[1329]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jan 23 18:54:00.323578 ignition[1329]: INFO : umount: umount passed Jan 23 18:54:00.323578 ignition[1329]: INFO : Ignition finished successfully Jan 23 18:54:00.325652 systemd[1]: ignition-mount.service: Deactivated successfully. Jan 23 18:54:00.325788 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Jan 23 18:54:00.329294 systemd[1]: ignition-disks.service: Deactivated successfully. Jan 23 18:54:00.329347 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Jan 23 18:54:00.337861 systemd[1]: ignition-kargs.service: Deactivated successfully. Jan 23 18:54:00.337909 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Jan 23 18:54:00.350954 systemd[1]: ignition-fetch.service: Deactivated successfully. Jan 23 18:54:00.351005 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Jan 23 18:54:00.358617 systemd[1]: Stopped target network.target - Network. Jan 23 18:54:00.360023 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Jan 23 18:54:00.360072 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Jan 23 18:54:00.371196 systemd[1]: Stopped target paths.target - Path Units. Jan 23 18:54:00.379629 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Jan 23 18:54:00.383539 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jan 23 18:54:00.386289 systemd[1]: Stopped target slices.target - Slice Units. Jan 23 18:54:00.397912 systemd[1]: Stopped target sockets.target - Socket Units. Jan 23 18:54:00.405571 systemd[1]: iscsid.socket: Deactivated successfully. Jan 23 18:54:00.405615 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Jan 23 18:54:00.413082 systemd[1]: iscsiuio.socket: Deactivated successfully. Jan 23 18:54:00.413116 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jan 23 18:54:00.420384 systemd[1]: ignition-setup.service: Deactivated successfully. Jan 23 18:54:00.420438 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Jan 23 18:54:00.428394 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Jan 23 18:54:00.428440 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Jan 23 18:54:00.437210 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Jan 23 18:54:00.444230 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Jan 23 18:54:00.446734 systemd[1]: sysroot-boot.service: Deactivated successfully. Jan 23 18:54:00.446833 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Jan 23 18:54:00.469456 systemd[1]: systemd-resolved.service: Deactivated successfully. Jan 23 18:54:00.469571 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Jan 23 18:54:00.482446 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Jan 23 18:54:00.482753 systemd[1]: systemd-networkd.service: Deactivated successfully. Jan 23 18:54:00.482890 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Jan 23 18:54:00.496324 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Jan 23 18:54:00.497846 systemd[1]: Stopped target network-pre.target - Preparation for Network. Jan 23 18:54:00.500510 systemd[1]: systemd-networkd.socket: Deactivated successfully. Jan 23 18:54:00.500575 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Jan 23 18:54:00.508434 systemd[1]: initrd-setup-root.service: Deactivated successfully. Jan 23 18:54:00.508561 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Jan 23 18:54:00.517824 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Jan 23 18:54:00.525385 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Jan 23 18:54:00.525436 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jan 23 18:54:00.533386 systemd[1]: systemd-sysctl.service: Deactivated successfully. Jan 23 18:54:00.533432 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Jan 23 18:54:00.552495 systemd[1]: systemd-modules-load.service: Deactivated successfully. Jan 23 18:54:00.552547 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Jan 23 18:54:00.555447 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Jan 23 18:54:00.555513 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Jan 23 18:54:00.574756 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Jan 23 18:54:00.577428 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Jan 23 18:54:00.577507 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Jan 23 18:54:00.620864 systemd[1]: systemd-udevd.service: Deactivated successfully. Jan 23 18:54:00.621074 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Jan 23 18:54:00.634723 systemd[1]: network-cleanup.service: Deactivated successfully. Jan 23 18:54:00.634843 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Jan 23 18:54:00.642853 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Jan 23 18:54:00.642909 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Jan 23 18:54:00.646575 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Jan 23 18:54:00.646614 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Jan 23 18:54:00.658344 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Jan 23 18:54:00.658399 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Jan 23 18:54:00.674021 systemd[1]: dracut-cmdline.service: Deactivated successfully. Jan 23 18:54:00.674072 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Jan 23 18:54:00.688342 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jan 23 18:54:00.688406 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jan 23 18:54:00.699390 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Jan 23 18:54:00.708339 systemd[1]: systemd-network-generator.service: Deactivated successfully. Jan 23 18:54:00.708396 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Jan 23 18:54:00.722458 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Jan 23 18:54:00.722533 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jan 23 18:54:00.731910 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Jan 23 18:54:00.731958 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jan 23 18:54:00.740829 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Jan 23 18:54:00.740878 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Jan 23 18:54:00.749074 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jan 23 18:54:00.749118 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jan 23 18:54:00.762767 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Jan 23 18:54:00.762825 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev\x2dearly.service.mount: Deactivated successfully. Jan 23 18:54:00.762864 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Jan 23 18:54:00.762905 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jan 23 18:54:00.763312 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Jan 23 18:54:00.763411 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Jan 23 18:54:00.766920 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Jan 23 18:54:00.778851 systemd[1]: Starting initrd-switch-root.service - Switch Root... Jan 23 18:54:00.814588 systemd[1]: Switching root. Jan 23 18:54:00.966316 systemd-journald[214]: Journal stopped Jan 23 18:54:07.185589 systemd-journald[214]: Received SIGTERM from PID 1 (systemd). Jan 23 18:54:07.185623 kernel: SELinux: policy capability network_peer_controls=1 Jan 23 18:54:07.185636 kernel: SELinux: policy capability open_perms=1 Jan 23 18:54:07.185645 kernel: SELinux: policy capability extended_socket_class=1 Jan 23 18:54:07.185654 kernel: SELinux: policy capability always_check_network=0 Jan 23 18:54:07.185663 kernel: SELinux: policy capability cgroup_seclabel=1 Jan 23 18:54:07.185673 kernel: SELinux: policy capability nnp_nosuid_transition=1 Jan 23 18:54:07.185685 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Jan 23 18:54:07.185695 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Jan 23 18:54:07.185708 kernel: SELinux: policy capability userspace_initial_context=0 Jan 23 18:54:07.185721 kernel: audit: type=1403 audit(1769194442.421:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Jan 23 18:54:07.185732 systemd[1]: Successfully loaded SELinux policy in 281.374ms. Jan 23 18:54:07.185743 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 7.736ms. Jan 23 18:54:07.185755 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) Jan 23 18:54:07.185769 systemd[1]: Detected virtualization microsoft. Jan 23 18:54:07.185780 systemd[1]: Detected architecture x86-64. Jan 23 18:54:07.185790 systemd[1]: Detected first boot. Jan 23 18:54:07.185801 systemd[1]: Hostname set to . Jan 23 18:54:07.185811 systemd[1]: Initializing machine ID from random generator. Jan 23 18:54:07.185825 zram_generator::config[1374]: No configuration found. Jan 23 18:54:07.185836 kernel: Guest personality initialized and is inactive Jan 23 18:54:07.185846 kernel: VMCI host device registered (name=vmci, major=10, minor=259) Jan 23 18:54:07.185856 kernel: Initialized host personality Jan 23 18:54:07.185865 kernel: NET: Registered PF_VSOCK protocol family Jan 23 18:54:07.185876 systemd[1]: Populated /etc with preset unit settings. Jan 23 18:54:07.185887 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Jan 23 18:54:07.185900 systemd[1]: initrd-switch-root.service: Deactivated successfully. Jan 23 18:54:07.185910 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Jan 23 18:54:07.185920 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Jan 23 18:54:07.185930 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Jan 23 18:54:07.185945 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Jan 23 18:54:07.185956 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Jan 23 18:54:07.185966 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Jan 23 18:54:07.185979 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Jan 23 18:54:07.185990 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Jan 23 18:54:07.186001 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Jan 23 18:54:07.186011 systemd[1]: Created slice user.slice - User and Session Slice. Jan 23 18:54:07.186022 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jan 23 18:54:07.186032 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jan 23 18:54:07.186043 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Jan 23 18:54:07.186057 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Jan 23 18:54:07.186069 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Jan 23 18:54:07.186082 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jan 23 18:54:07.186093 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Jan 23 18:54:07.186104 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jan 23 18:54:07.186115 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jan 23 18:54:07.186125 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Jan 23 18:54:07.186136 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Jan 23 18:54:07.186147 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Jan 23 18:54:07.186160 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Jan 23 18:54:07.186171 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jan 23 18:54:07.186186 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jan 23 18:54:07.186196 systemd[1]: Reached target slices.target - Slice Units. Jan 23 18:54:07.186207 systemd[1]: Reached target swap.target - Swaps. Jan 23 18:54:07.186218 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Jan 23 18:54:07.186229 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Jan 23 18:54:07.186243 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Jan 23 18:54:07.186254 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jan 23 18:54:07.186266 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jan 23 18:54:07.186277 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jan 23 18:54:07.186287 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Jan 23 18:54:07.186299 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Jan 23 18:54:07.186312 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Jan 23 18:54:07.186323 systemd[1]: Mounting media.mount - External Media Directory... Jan 23 18:54:07.186335 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jan 23 18:54:07.186346 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Jan 23 18:54:07.186356 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Jan 23 18:54:07.186367 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Jan 23 18:54:07.186379 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Jan 23 18:54:07.186390 systemd[1]: Reached target machines.target - Containers. Jan 23 18:54:07.186404 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Jan 23 18:54:07.186415 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jan 23 18:54:07.186429 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jan 23 18:54:07.186441 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Jan 23 18:54:07.186452 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jan 23 18:54:07.186463 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jan 23 18:54:07.186474 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jan 23 18:54:07.186494 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Jan 23 18:54:07.186510 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jan 23 18:54:07.186522 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Jan 23 18:54:07.186533 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Jan 23 18:54:07.186544 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Jan 23 18:54:07.186555 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Jan 23 18:54:07.186566 systemd[1]: Stopped systemd-fsck-usr.service. Jan 23 18:54:07.186578 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). Jan 23 18:54:07.186589 systemd[1]: Starting systemd-journald.service - Journal Service... Jan 23 18:54:07.186600 kernel: fuse: init (API version 7.41) Jan 23 18:54:07.186613 kernel: loop: module loaded Jan 23 18:54:07.186623 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jan 23 18:54:07.186634 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jan 23 18:54:07.186645 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Jan 23 18:54:07.186656 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Jan 23 18:54:07.186668 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jan 23 18:54:07.186682 systemd[1]: verity-setup.service: Deactivated successfully. Jan 23 18:54:07.186718 systemd-journald[1481]: Collecting audit messages is disabled. Jan 23 18:54:07.186747 systemd[1]: Stopped verity-setup.service. Jan 23 18:54:07.186759 systemd-journald[1481]: Journal started Jan 23 18:54:07.186784 systemd-journald[1481]: Runtime Journal (/run/log/journal/33c50de3e1ab4c79bb4a32988b9ac2f6) is 8M, max 2.2G, 2.1G free. Jan 23 18:54:06.443682 systemd[1]: Queued start job for default target multi-user.target. Jan 23 18:54:06.457856 systemd[1]: Unnecessary job was removed for dev-sdb6.device - /dev/sdb6. Jan 23 18:54:06.458354 systemd[1]: systemd-journald.service: Deactivated successfully. Jan 23 18:54:07.197506 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jan 23 18:54:07.201496 kernel: ACPI: bus type drm_connector registered Jan 23 18:54:07.201525 systemd[1]: Started systemd-journald.service - Journal Service. Jan 23 18:54:07.210016 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Jan 23 18:54:07.214411 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Jan 23 18:54:07.218979 systemd[1]: Mounted media.mount - External Media Directory. Jan 23 18:54:07.223062 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Jan 23 18:54:07.227664 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Jan 23 18:54:07.232298 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Jan 23 18:54:07.236476 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Jan 23 18:54:07.241525 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jan 23 18:54:07.246939 systemd[1]: modprobe@configfs.service: Deactivated successfully. Jan 23 18:54:07.247135 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Jan 23 18:54:07.252255 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jan 23 18:54:07.252459 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jan 23 18:54:07.257416 systemd[1]: modprobe@drm.service: Deactivated successfully. Jan 23 18:54:07.257623 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jan 23 18:54:07.262139 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jan 23 18:54:07.262323 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jan 23 18:54:07.267804 systemd[1]: modprobe@fuse.service: Deactivated successfully. Jan 23 18:54:07.267995 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Jan 23 18:54:07.272834 systemd[1]: modprobe@loop.service: Deactivated successfully. Jan 23 18:54:07.273052 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jan 23 18:54:07.277845 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jan 23 18:54:07.282777 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jan 23 18:54:07.288341 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Jan 23 18:54:07.293844 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Jan 23 18:54:07.308518 systemd[1]: Reached target network-pre.target - Preparation for Network. Jan 23 18:54:07.314261 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Jan 23 18:54:07.337704 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Jan 23 18:54:07.342194 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Jan 23 18:54:07.342232 systemd[1]: Reached target local-fs.target - Local File Systems. Jan 23 18:54:07.347371 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Jan 23 18:54:07.353518 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Jan 23 18:54:07.357667 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jan 23 18:54:07.358680 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Jan 23 18:54:07.363947 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Jan 23 18:54:07.368657 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jan 23 18:54:07.369683 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Jan 23 18:54:07.374256 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jan 23 18:54:07.375225 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jan 23 18:54:07.380319 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Jan 23 18:54:07.386635 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jan 23 18:54:07.393315 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jan 23 18:54:07.395165 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Jan 23 18:54:07.403734 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Jan 23 18:54:07.417664 systemd-journald[1481]: Time spent on flushing to /var/log/journal/33c50de3e1ab4c79bb4a32988b9ac2f6 is 13.821ms for 953 entries. Jan 23 18:54:07.417664 systemd-journald[1481]: System Journal (/var/log/journal/33c50de3e1ab4c79bb4a32988b9ac2f6) is 8M, max 2.6G, 2.6G free. Jan 23 18:54:07.462560 systemd-journald[1481]: Received client request to flush runtime journal. Jan 23 18:54:07.433682 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Jan 23 18:54:07.438763 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Jan 23 18:54:07.444730 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Jan 23 18:54:07.464154 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Jan 23 18:54:07.511191 systemd-tmpfiles[1517]: ACLs are not supported, ignoring. Jan 23 18:54:07.511212 systemd-tmpfiles[1517]: ACLs are not supported, ignoring. Jan 23 18:54:07.515539 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jan 23 18:54:07.518500 kernel: loop0: detected capacity change from 0 to 110984 Jan 23 18:54:07.524890 systemd[1]: Starting systemd-sysusers.service - Create System Users... Jan 23 18:54:07.547759 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jan 23 18:54:07.572579 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Jan 23 18:54:07.573354 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Jan 23 18:54:07.743098 systemd[1]: Finished systemd-sysusers.service - Create System Users. Jan 23 18:54:07.748812 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jan 23 18:54:07.775967 systemd-tmpfiles[1534]: ACLs are not supported, ignoring. Jan 23 18:54:07.775989 systemd-tmpfiles[1534]: ACLs are not supported, ignoring. Jan 23 18:54:07.779372 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jan 23 18:54:08.157513 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jan 23 18:54:08.192510 kernel: loop1: detected capacity change from 0 to 27936 Jan 23 18:54:08.293837 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Jan 23 18:54:08.300481 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jan 23 18:54:08.351473 systemd-udevd[1541]: Using default interface naming scheme 'v255'. Jan 23 18:54:08.674014 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jan 23 18:54:08.681089 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jan 23 18:54:08.751407 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Jan 23 18:54:08.765508 kernel: loop2: detected capacity change from 0 to 128560 Jan 23 18:54:08.771296 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Jan 23 18:54:08.779524 kernel: hv_vmbus: registering driver hyperv_fb Jan 23 18:54:08.779592 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Jan 23 18:54:08.782910 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Jan 23 18:54:08.791735 kernel: Console: switching to colour dummy device 80x25 Jan 23 18:54:08.796896 kernel: Console: switching to colour frame buffer device 128x48 Jan 23 18:54:08.800505 kernel: hv_vmbus: registering driver hv_balloon Jan 23 18:54:08.804503 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Jan 23 18:54:08.830513 kernel: mousedev: PS/2 mouse device common for all mice Jan 23 18:54:08.863114 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jan 23 18:54:08.875007 systemd[1]: Started systemd-userdbd.service - User Database Manager. Jan 23 18:54:08.883545 kernel: EDAC MC: Ver: 3.0.0 Jan 23 18:54:08.884646 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jan 23 18:54:08.885009 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jan 23 18:54:08.890832 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jan 23 18:54:08.892767 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jan 23 18:54:09.085279 systemd-networkd[1548]: lo: Link UP Jan 23 18:54:09.085289 systemd-networkd[1548]: lo: Gained carrier Jan 23 18:54:09.086678 systemd-networkd[1548]: Enumeration completed Jan 23 18:54:09.086806 systemd[1]: Started systemd-networkd.service - Network Configuration. Jan 23 18:54:09.087066 systemd-networkd[1548]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jan 23 18:54:09.087077 systemd-networkd[1548]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jan 23 18:54:09.087626 systemd-networkd[1548]: eth0: Link UP Jan 23 18:54:09.087636 systemd-networkd[1548]: eth0: Gained carrier Jan 23 18:54:09.087650 systemd-networkd[1548]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jan 23 18:54:09.089712 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Jan 23 18:54:09.090786 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Jan 23 18:54:09.134910 systemd-networkd[1548]: eth0: DHCPv4 address 10.200.4.29/24, gateway 10.200.4.1 acquired from 168.63.129.16 Jan 23 18:54:09.173532 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Jan 23 18:54:09.178702 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Jan 23 18:54:09.194644 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Jan 23 18:54:09.221536 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Jan 23 18:54:09.410591 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jan 23 18:54:09.568508 kernel: loop3: detected capacity change from 0 to 110984 Jan 23 18:54:09.593504 kernel: loop4: detected capacity change from 0 to 27936 Jan 23 18:54:09.613505 kernel: loop5: detected capacity change from 0 to 128560 Jan 23 18:54:09.628511 (sd-merge)[1701]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Jan 23 18:54:09.628978 (sd-merge)[1701]: Merged extensions into '/usr'. Jan 23 18:54:09.632391 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Jan 23 18:54:09.638974 systemd[1]: Starting ensure-sysext.service... Jan 23 18:54:09.642642 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jan 23 18:54:09.684788 systemd[1]: Reload requested from client PID 1703 ('systemctl') (unit ensure-sysext.service)... Jan 23 18:54:09.684805 systemd[1]: Reloading... Jan 23 18:54:09.694065 systemd-tmpfiles[1704]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Jan 23 18:54:09.706244 systemd-tmpfiles[1704]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Jan 23 18:54:09.706601 systemd-tmpfiles[1704]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Jan 23 18:54:09.706891 systemd-tmpfiles[1704]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Jan 23 18:54:09.707792 systemd-tmpfiles[1704]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Jan 23 18:54:09.708077 systemd-tmpfiles[1704]: ACLs are not supported, ignoring. Jan 23 18:54:09.708146 systemd-tmpfiles[1704]: ACLs are not supported, ignoring. Jan 23 18:54:09.713006 systemd-tmpfiles[1704]: Detected autofs mount point /boot during canonicalization of boot. Jan 23 18:54:09.713021 systemd-tmpfiles[1704]: Skipping /boot Jan 23 18:54:09.723511 zram_generator::config[1734]: No configuration found. Jan 23 18:54:09.723558 systemd-tmpfiles[1704]: Detected autofs mount point /boot during canonicalization of boot. Jan 23 18:54:09.723566 systemd-tmpfiles[1704]: Skipping /boot Jan 23 18:54:09.987388 systemd[1]: Reloading finished in 302 ms. Jan 23 18:54:10.039710 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jan 23 18:54:10.056576 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jan 23 18:54:10.057781 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jan 23 18:54:10.081378 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Jan 23 18:54:10.086289 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jan 23 18:54:10.087619 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jan 23 18:54:10.092861 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jan 23 18:54:10.098582 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jan 23 18:54:10.102840 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jan 23 18:54:10.102959 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). Jan 23 18:54:10.104098 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Jan 23 18:54:10.110430 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jan 23 18:54:10.115762 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Jan 23 18:54:10.120510 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jan 23 18:54:10.122511 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jan 23 18:54:10.122719 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jan 23 18:54:10.124694 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jan 23 18:54:10.124888 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jan 23 18:54:10.133005 systemd[1]: modprobe@loop.service: Deactivated successfully. Jan 23 18:54:10.133195 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jan 23 18:54:10.142458 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jan 23 18:54:10.142681 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jan 23 18:54:10.143835 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jan 23 18:54:10.149072 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jan 23 18:54:10.154793 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jan 23 18:54:10.159059 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jan 23 18:54:10.159176 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). Jan 23 18:54:10.159280 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jan 23 18:54:10.162215 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jan 23 18:54:10.162407 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jan 23 18:54:10.164322 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jan 23 18:54:10.164522 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jan 23 18:54:10.172332 systemd[1]: modprobe@loop.service: Deactivated successfully. Jan 23 18:54:10.172540 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jan 23 18:54:10.183510 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jan 23 18:54:10.183874 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jan 23 18:54:10.185156 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jan 23 18:54:10.196953 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jan 23 18:54:10.201852 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jan 23 18:54:10.207603 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jan 23 18:54:10.211768 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jan 23 18:54:10.211953 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). Jan 23 18:54:10.212223 systemd[1]: Reached target time-set.target - System Time Set. Jan 23 18:54:10.216428 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jan 23 18:54:10.218942 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jan 23 18:54:10.225625 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jan 23 18:54:10.231257 systemd[1]: modprobe@drm.service: Deactivated successfully. Jan 23 18:54:10.231456 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jan 23 18:54:10.236236 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jan 23 18:54:10.236433 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jan 23 18:54:10.241968 systemd[1]: modprobe@loop.service: Deactivated successfully. Jan 23 18:54:10.242164 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jan 23 18:54:10.249283 systemd[1]: Finished ensure-sysext.service. Jan 23 18:54:10.253302 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Jan 23 18:54:10.263631 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jan 23 18:54:10.263690 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jan 23 18:54:10.310332 systemd-resolved[1802]: Positive Trust Anchors: Jan 23 18:54:10.310348 systemd-resolved[1802]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jan 23 18:54:10.310379 systemd-resolved[1802]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Jan 23 18:54:10.315966 systemd-resolved[1802]: Using system hostname 'ci-4459.2.3-a-c4c88d9723'. Jan 23 18:54:10.317558 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jan 23 18:54:10.322244 systemd[1]: Reached target network.target - Network. Jan 23 18:54:10.325906 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jan 23 18:54:10.337145 augenrules[1842]: No rules Jan 23 18:54:10.338803 systemd[1]: audit-rules.service: Deactivated successfully. Jan 23 18:54:10.339058 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jan 23 18:54:10.398624 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Jan 23 18:54:10.520612 systemd-networkd[1548]: eth0: Gained IPv6LL Jan 23 18:54:10.522922 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Jan 23 18:54:10.528390 systemd[1]: Reached target network-online.target - Network is Online. Jan 23 18:54:10.928307 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Jan 23 18:54:10.933764 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Jan 23 18:54:16.473899 ldconfig[1510]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jan 23 18:54:16.484078 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Jan 23 18:54:16.490835 systemd[1]: Starting systemd-update-done.service - Update is Completed... Jan 23 18:54:16.547831 systemd[1]: Finished systemd-update-done.service - Update is Completed. Jan 23 18:54:16.552691 systemd[1]: Reached target sysinit.target - System Initialization. Jan 23 18:54:16.557186 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Jan 23 18:54:16.562451 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Jan 23 18:54:16.567661 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Jan 23 18:54:16.572623 systemd[1]: Started logrotate.timer - Daily rotation of log files. Jan 23 18:54:16.577047 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Jan 23 18:54:16.582301 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Jan 23 18:54:16.587566 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Jan 23 18:54:16.587612 systemd[1]: Reached target paths.target - Path Units. Jan 23 18:54:16.591289 systemd[1]: Reached target timers.target - Timer Units. Jan 23 18:54:16.609128 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Jan 23 18:54:16.614684 systemd[1]: Starting docker.socket - Docker Socket for the API... Jan 23 18:54:16.620630 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Jan 23 18:54:16.625984 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Jan 23 18:54:16.631169 systemd[1]: Reached target ssh-access.target - SSH Access Available. Jan 23 18:54:16.637301 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Jan 23 18:54:16.641641 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Jan 23 18:54:16.647510 systemd[1]: Listening on docker.socket - Docker Socket for the API. Jan 23 18:54:16.652934 systemd[1]: Reached target sockets.target - Socket Units. Jan 23 18:54:16.656954 systemd[1]: Reached target basic.target - Basic System. Jan 23 18:54:16.660857 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Jan 23 18:54:16.660893 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Jan 23 18:54:16.663757 systemd[1]: Starting chronyd.service - NTP client/server... Jan 23 18:54:16.668112 systemd[1]: Starting containerd.service - containerd container runtime... Jan 23 18:54:16.686307 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Jan 23 18:54:16.691458 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Jan 23 18:54:16.696103 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Jan 23 18:54:16.701859 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Jan 23 18:54:16.706929 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Jan 23 18:54:16.711076 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Jan 23 18:54:16.712100 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Jan 23 18:54:16.716182 systemd[1]: hv_fcopy_uio_daemon.service - Hyper-V FCOPY UIO daemon was skipped because of an unmet condition check (ConditionPathExists=/sys/bus/vmbus/devices/eb765408-105f-49b6-b4aa-c123b64d17d4/uio). Jan 23 18:54:16.717172 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Jan 23 18:54:16.721513 jq[1864]: false Jan 23 18:54:16.721359 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Jan 23 18:54:16.722406 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Jan 23 18:54:16.729599 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Jan 23 18:54:16.734254 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Jan 23 18:54:16.741456 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Jan 23 18:54:16.748780 systemd[1]: Starting systemd-logind.service - User Login Management... Jan 23 18:54:16.753998 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Jan 23 18:54:16.754424 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Jan 23 18:54:16.754996 systemd[1]: Starting update-engine.service - Update Engine... Jan 23 18:54:16.760179 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Jan 23 18:54:16.769259 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Jan 23 18:54:16.775283 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Jan 23 18:54:16.775524 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Jan 23 18:54:16.776188 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Jan 23 18:54:16.776409 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Jan 23 18:54:16.776754 jq[1881]: true Jan 23 18:54:16.777327 extend-filesystems[1865]: Found /dev/sdb6 Jan 23 18:54:16.788512 jq[1884]: true Jan 23 18:54:16.789301 chronyd[1856]: chronyd version 4.7 starting (+CMDMON +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +NTS +SECHASH +IPV6 -DEBUG) Jan 23 18:54:16.789337 KVP[1867]: KVP starting; pid is:1867 Jan 23 18:54:16.794537 KVP[1867]: KVP LIC Version: 3.1 Jan 23 18:54:16.795499 kernel: hv_utils: KVP IC version 4.0 Jan 23 18:54:16.804353 chronyd[1856]: Timezone right/UTC failed leap second check, ignoring Jan 23 18:54:16.804653 chronyd[1856]: Loaded seccomp filter (level 2) Jan 23 18:54:16.804767 systemd[1]: Started chronyd.service - NTP client/server. Jan 23 18:54:16.806389 extend-filesystems[1865]: Found /dev/sdb9 Jan 23 18:54:16.811412 extend-filesystems[1865]: Checking size of /dev/sdb9 Jan 23 18:54:16.835275 google_oslogin_nss_cache[1866]: oslogin_cache_refresh[1866]: Refreshing passwd entry cache Jan 23 18:54:16.835277 oslogin_cache_refresh[1866]: Refreshing passwd entry cache Jan 23 18:54:16.839379 extend-filesystems[1865]: Old size kept for /dev/sdb9 Jan 23 18:54:16.840422 systemd[1]: extend-filesystems.service: Deactivated successfully. Jan 23 18:54:16.840670 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Jan 23 18:54:16.851457 systemd[1]: motdgen.service: Deactivated successfully. Jan 23 18:54:16.851766 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Jan 23 18:54:16.854480 google_oslogin_nss_cache[1866]: oslogin_cache_refresh[1866]: Failure getting users, quitting Jan 23 18:54:16.854480 google_oslogin_nss_cache[1866]: oslogin_cache_refresh[1866]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Jan 23 18:54:16.854480 google_oslogin_nss_cache[1866]: oslogin_cache_refresh[1866]: Refreshing group entry cache Jan 23 18:54:16.854400 oslogin_cache_refresh[1866]: Failure getting users, quitting Jan 23 18:54:16.854421 oslogin_cache_refresh[1866]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Jan 23 18:54:16.854468 oslogin_cache_refresh[1866]: Refreshing group entry cache Jan 23 18:54:16.865509 update_engine[1879]: I20260123 18:54:16.865427 1879 main.cc:92] Flatcar Update Engine starting Jan 23 18:54:16.868896 (ntainerd)[1918]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Jan 23 18:54:16.871736 google_oslogin_nss_cache[1866]: oslogin_cache_refresh[1866]: Failure getting groups, quitting Jan 23 18:54:16.871736 google_oslogin_nss_cache[1866]: oslogin_cache_refresh[1866]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Jan 23 18:54:16.871724 oslogin_cache_refresh[1866]: Failure getting groups, quitting Jan 23 18:54:16.871738 oslogin_cache_refresh[1866]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Jan 23 18:54:16.873367 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Jan 23 18:54:16.873657 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Jan 23 18:54:16.896454 systemd-logind[1876]: New seat seat0. Jan 23 18:54:16.897606 systemd-logind[1876]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Jan 23 18:54:16.897814 systemd[1]: Started systemd-logind.service - User Login Management. Jan 23 18:54:16.937570 bash[1909]: Updated "/home/core/.ssh/authorized_keys" Jan 23 18:54:16.939032 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Jan 23 18:54:16.944772 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Jan 23 18:54:17.036464 dbus-daemon[1859]: [system] SELinux support is enabled Jan 23 18:54:17.036648 systemd[1]: Started dbus.service - D-Bus System Message Bus. Jan 23 18:54:17.038985 update_engine[1879]: I20260123 18:54:17.038930 1879 update_check_scheduler.cc:74] Next update check in 4m15s Jan 23 18:54:17.043353 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Jan 23 18:54:17.043387 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Jan 23 18:54:17.044207 dbus-daemon[1859]: [system] Successfully activated service 'org.freedesktop.systemd1' Jan 23 18:54:17.048700 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Jan 23 18:54:17.048727 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Jan 23 18:54:17.053841 systemd[1]: Started update-engine.service - Update Engine. Jan 23 18:54:17.059970 systemd[1]: Started locksmithd.service - Cluster reboot manager. Jan 23 18:54:17.080507 setup-nvidia[1872]: Downloading Flatcar Container Linux Developer Container for version: 4459.2.3 Jan 23 18:54:17.086402 coreos-metadata[1858]: Jan 23 18:54:17.086 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Jan 23 18:54:17.088242 coreos-metadata[1858]: Jan 23 18:54:17.088 INFO Fetch successful Jan 23 18:54:17.088396 coreos-metadata[1858]: Jan 23 18:54:17.088 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Jan 23 18:54:17.091861 coreos-metadata[1858]: Jan 23 18:54:17.091 INFO Fetch successful Jan 23 18:54:17.092167 coreos-metadata[1858]: Jan 23 18:54:17.092 INFO Fetching http://168.63.129.16/machine/e9563a95-aa5b-4532-adb9-0193a6cc5eb5/c3712aba%2D89fa%2D4993%2Da4bb%2D85b9ec23197b.%5Fci%2D4459.2.3%2Da%2Dc4c88d9723?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Jan 23 18:54:17.117183 coreos-metadata[1858]: Jan 23 18:54:17.117 INFO Fetch successful Jan 23 18:54:17.117323 coreos-metadata[1858]: Jan 23 18:54:17.117 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Jan 23 18:54:17.123026 coreos-metadata[1858]: Jan 23 18:54:17.123 INFO Fetch successful Jan 23 18:54:17.144905 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Jan 23 18:54:17.149209 sshd_keygen[1880]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Jan 23 18:54:17.150111 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Jan 23 18:54:17.170869 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Jan 23 18:54:17.181264 systemd[1]: Starting issuegen.service - Generate /run/issue... Jan 23 18:54:17.198771 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Jan 23 18:54:17.204383 systemd[1]: issuegen.service: Deactivated successfully. Jan 23 18:54:17.204632 systemd[1]: Finished issuegen.service - Generate /run/issue. Jan 23 18:54:17.211282 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Jan 23 18:54:17.232426 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Jan 23 18:54:17.237363 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Jan 23 18:54:17.244300 systemd[1]: Started getty@tty1.service - Getty on tty1. Jan 23 18:54:17.249886 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Jan 23 18:54:17.254645 systemd[1]: Reached target getty.target - Login Prompts. Jan 23 18:54:17.447203 locksmithd[1997]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Jan 23 18:54:17.694650 setup-nvidia[2036]: % Total % Received % Xferd Average Speed Time Time Time Current Jan 23 18:54:17.694991 setup-nvidia[2036]: Dload Upload Total Spent Left Speed Jan 23 18:54:18.461474 containerd[1918]: time="2026-01-23T18:54:18Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Jan 23 18:54:18.462140 containerd[1918]: time="2026-01-23T18:54:18.462095800Z" level=info msg="starting containerd" revision=4ac6c20c7bbf8177f29e46bbdc658fec02ffb8ad version=v2.0.7 Jan 23 18:54:18.470066 containerd[1918]: time="2026-01-23T18:54:18.470018100Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="8µs" Jan 23 18:54:18.470066 containerd[1918]: time="2026-01-23T18:54:18.470059500Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Jan 23 18:54:18.470156 containerd[1918]: time="2026-01-23T18:54:18.470078500Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Jan 23 18:54:18.470245 containerd[1918]: time="2026-01-23T18:54:18.470220300Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Jan 23 18:54:18.470245 containerd[1918]: time="2026-01-23T18:54:18.470242100Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Jan 23 18:54:18.470297 containerd[1918]: time="2026-01-23T18:54:18.470266200Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jan 23 18:54:18.470349 containerd[1918]: time="2026-01-23T18:54:18.470323300Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jan 23 18:54:18.470349 containerd[1918]: time="2026-01-23T18:54:18.470342000Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Jan 23 18:54:18.470577 containerd[1918]: time="2026-01-23T18:54:18.470552000Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Jan 23 18:54:18.470577 containerd[1918]: time="2026-01-23T18:54:18.470571900Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jan 23 18:54:18.470630 containerd[1918]: time="2026-01-23T18:54:18.470585400Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jan 23 18:54:18.470630 containerd[1918]: time="2026-01-23T18:54:18.470593500Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Jan 23 18:54:18.470708 containerd[1918]: time="2026-01-23T18:54:18.470682900Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Jan 23 18:54:18.470937 containerd[1918]: time="2026-01-23T18:54:18.470913400Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Jan 23 18:54:18.470996 containerd[1918]: time="2026-01-23T18:54:18.470949600Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Jan 23 18:54:18.470996 containerd[1918]: time="2026-01-23T18:54:18.470961000Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Jan 23 18:54:18.470996 containerd[1918]: time="2026-01-23T18:54:18.470986900Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Jan 23 18:54:18.471813 containerd[1918]: time="2026-01-23T18:54:18.471698000Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Jan 23 18:54:18.471879 containerd[1918]: time="2026-01-23T18:54:18.471863500Z" level=info msg="metadata content store policy set" policy=shared Jan 23 18:54:18.498445 containerd[1918]: time="2026-01-23T18:54:18.498403500Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Jan 23 18:54:18.498519 containerd[1918]: time="2026-01-23T18:54:18.498453400Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Jan 23 18:54:18.498519 containerd[1918]: time="2026-01-23T18:54:18.498470100Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Jan 23 18:54:18.498519 containerd[1918]: time="2026-01-23T18:54:18.498498200Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Jan 23 18:54:18.498519 containerd[1918]: time="2026-01-23T18:54:18.498513500Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Jan 23 18:54:18.498621 containerd[1918]: time="2026-01-23T18:54:18.498524000Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Jan 23 18:54:18.498621 containerd[1918]: time="2026-01-23T18:54:18.498535700Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Jan 23 18:54:18.498621 containerd[1918]: time="2026-01-23T18:54:18.498546800Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Jan 23 18:54:18.498621 containerd[1918]: time="2026-01-23T18:54:18.498573600Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Jan 23 18:54:18.498621 containerd[1918]: time="2026-01-23T18:54:18.498589900Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Jan 23 18:54:18.498621 containerd[1918]: time="2026-01-23T18:54:18.498598800Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Jan 23 18:54:18.498621 containerd[1918]: time="2026-01-23T18:54:18.498610900Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Jan 23 18:54:18.498762 containerd[1918]: time="2026-01-23T18:54:18.498721900Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Jan 23 18:54:18.498762 containerd[1918]: time="2026-01-23T18:54:18.498743100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Jan 23 18:54:18.498762 containerd[1918]: time="2026-01-23T18:54:18.498758400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Jan 23 18:54:18.498828 containerd[1918]: time="2026-01-23T18:54:18.498770600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Jan 23 18:54:18.498828 containerd[1918]: time="2026-01-23T18:54:18.498781500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Jan 23 18:54:18.498828 containerd[1918]: time="2026-01-23T18:54:18.498791600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Jan 23 18:54:18.498828 containerd[1918]: time="2026-01-23T18:54:18.498802000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Jan 23 18:54:18.498828 containerd[1918]: time="2026-01-23T18:54:18.498811600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Jan 23 18:54:18.498828 containerd[1918]: time="2026-01-23T18:54:18.498823000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Jan 23 18:54:18.498961 containerd[1918]: time="2026-01-23T18:54:18.498847500Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Jan 23 18:54:18.498961 containerd[1918]: time="2026-01-23T18:54:18.498873700Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Jan 23 18:54:18.498961 containerd[1918]: time="2026-01-23T18:54:18.498919700Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Jan 23 18:54:18.498961 containerd[1918]: time="2026-01-23T18:54:18.498933800Z" level=info msg="Start snapshots syncer" Jan 23 18:54:18.499045 containerd[1918]: time="2026-01-23T18:54:18.498961100Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Jan 23 18:54:18.499259 containerd[1918]: time="2026-01-23T18:54:18.499202700Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Jan 23 18:54:18.499382 containerd[1918]: time="2026-01-23T18:54:18.499280400Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Jan 23 18:54:18.499382 containerd[1918]: time="2026-01-23T18:54:18.499335100Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Jan 23 18:54:18.499458 containerd[1918]: time="2026-01-23T18:54:18.499437700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Jan 23 18:54:18.499502 containerd[1918]: time="2026-01-23T18:54:18.499464800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Jan 23 18:54:18.499502 containerd[1918]: time="2026-01-23T18:54:18.499477700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Jan 23 18:54:18.499606 containerd[1918]: time="2026-01-23T18:54:18.499510400Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Jan 23 18:54:18.499606 containerd[1918]: time="2026-01-23T18:54:18.499524800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Jan 23 18:54:18.499606 containerd[1918]: time="2026-01-23T18:54:18.499534800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Jan 23 18:54:18.499606 containerd[1918]: time="2026-01-23T18:54:18.499544700Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Jan 23 18:54:18.499606 containerd[1918]: time="2026-01-23T18:54:18.499564800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Jan 23 18:54:18.499606 containerd[1918]: time="2026-01-23T18:54:18.499590900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Jan 23 18:54:18.499726 containerd[1918]: time="2026-01-23T18:54:18.499625000Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Jan 23 18:54:18.499726 containerd[1918]: time="2026-01-23T18:54:18.499657500Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Jan 23 18:54:18.499726 containerd[1918]: time="2026-01-23T18:54:18.499672800Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Jan 23 18:54:18.499726 containerd[1918]: time="2026-01-23T18:54:18.499681500Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jan 23 18:54:18.499726 containerd[1918]: time="2026-01-23T18:54:18.499691300Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jan 23 18:54:18.499726 containerd[1918]: time="2026-01-23T18:54:18.499698800Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Jan 23 18:54:18.499726 containerd[1918]: time="2026-01-23T18:54:18.499707600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Jan 23 18:54:18.499726 containerd[1918]: time="2026-01-23T18:54:18.499724700Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Jan 23 18:54:18.499887 containerd[1918]: time="2026-01-23T18:54:18.499740800Z" level=info msg="runtime interface created" Jan 23 18:54:18.499887 containerd[1918]: time="2026-01-23T18:54:18.499746800Z" level=info msg="created NRI interface" Jan 23 18:54:18.499887 containerd[1918]: time="2026-01-23T18:54:18.499754700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Jan 23 18:54:18.499887 containerd[1918]: time="2026-01-23T18:54:18.499764900Z" level=info msg="Connect containerd service" Jan 23 18:54:18.499887 containerd[1918]: time="2026-01-23T18:54:18.499804300Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Jan 23 18:54:18.500533 containerd[1918]: time="2026-01-23T18:54:18.500503100Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Jan 23 18:54:19.354082 containerd[1918]: time="2026-01-23T18:54:19.354026000Z" level=info msg="Start subscribing containerd event" Jan 23 18:54:19.354197 containerd[1918]: time="2026-01-23T18:54:19.354094200Z" level=info msg="Start recovering state" Jan 23 18:54:19.354197 containerd[1918]: time="2026-01-23T18:54:19.354192500Z" level=info msg="Start event monitor" Jan 23 18:54:19.354266 containerd[1918]: time="2026-01-23T18:54:19.354215100Z" level=info msg="Start cni network conf syncer for default" Jan 23 18:54:19.354266 containerd[1918]: time="2026-01-23T18:54:19.354223600Z" level=info msg="Start streaming server" Jan 23 18:54:19.354266 containerd[1918]: time="2026-01-23T18:54:19.354234200Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Jan 23 18:54:19.354266 containerd[1918]: time="2026-01-23T18:54:19.354241400Z" level=info msg="runtime interface starting up..." Jan 23 18:54:19.354266 containerd[1918]: time="2026-01-23T18:54:19.354247800Z" level=info msg="starting plugins..." Jan 23 18:54:19.354375 containerd[1918]: time="2026-01-23T18:54:19.354244600Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jan 23 18:54:19.354375 containerd[1918]: time="2026-01-23T18:54:19.354266500Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Jan 23 18:54:19.354375 containerd[1918]: time="2026-01-23T18:54:19.354343000Z" level=info msg=serving... address=/run/containerd/containerd.sock Jan 23 18:54:19.354514 containerd[1918]: time="2026-01-23T18:54:19.354477900Z" level=info msg="containerd successfully booted in 0.893469s" Jan 23 18:54:19.354598 systemd[1]: Started containerd.service - containerd container runtime. Jan 23 18:54:19.939845 waagent[2028]: 2026-01-23T18:54:19.939762Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Jan 23 18:54:19.944308 waagent[2028]: 2026-01-23T18:54:19.944202Z INFO Daemon Daemon OS: flatcar 4459.2.3 Jan 23 18:54:19.947747 waagent[2028]: 2026-01-23T18:54:19.947695Z INFO Daemon Daemon Python: 3.11.13 Jan 23 18:54:19.951108 waagent[2028]: 2026-01-23T18:54:19.951061Z INFO Daemon Daemon Run daemon Jan 23 18:54:19.954167 waagent[2028]: 2026-01-23T18:54:19.954118Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4459.2.3' Jan 23 18:54:19.962476 waagent[2028]: 2026-01-23T18:54:19.962425Z INFO Daemon Daemon Using waagent for provisioning Jan 23 18:54:19.966681 waagent[2028]: 2026-01-23T18:54:19.966624Z INFO Daemon Daemon Activate resource disk Jan 23 18:54:19.970324 waagent[2028]: 2026-01-23T18:54:19.970276Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Jan 23 18:54:19.977859 waagent[2028]: 2026-01-23T18:54:19.977811Z INFO Daemon Daemon Found device: sda Jan 23 18:54:19.988122 waagent[2028]: 2026-01-23T18:54:19.988066Z INFO Daemon Daemon Examining partition table Jan 23 18:54:20.039577 waagent[2028]: 2026-01-23T18:54:20.039523Z INFO Daemon Daemon GPT not detected, determining filesystem Jan 23 18:54:20.067946 waagent[2028]: 2026-01-23T18:54:20.067885Z INFO Daemon Daemon sfdisk --part-type -f /dev/sda 1 -n succeeded Jan 23 18:54:20.072613 waagent[2028]: 2026-01-23T18:54:20.072559Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Jan 23 18:54:20.979040 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#140 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jan 23 18:54:20.991516 kernel: sda: sda1 Jan 23 18:54:21.174954 waagent[2028]: 2026-01-23T18:54:21.174849Z INFO Daemon Daemon sfdisk --part-type /dev/sda 1 83 succeeded Jan 23 18:54:21.193504 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#542 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jan 23 18:54:21.210959 waagent[2028]: 2026-01-23T18:54:21.210901Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sda1] Jan 23 18:54:21.211507 kernel: sda: sda1 Jan 23 18:54:25.660237 login[2030]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Jan 23 18:54:25.757442 login[2031]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Jan 23 18:54:25.763994 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Jan 23 18:54:25.765026 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Jan 23 18:54:25.771430 systemd-logind[1876]: New session 2 of user core. Jan 23 18:54:25.889707 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Jan 23 18:54:25.892254 systemd[1]: Starting user@500.service - User Manager for UID 500... Jan 23 18:54:26.047628 (systemd)[2130]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Jan 23 18:54:26.050029 systemd-logind[1876]: New session c1 of user core. Jan 23 18:54:26.660662 login[2030]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Jan 23 18:54:26.665178 systemd-logind[1876]: New session 1 of user core. Jan 23 18:54:27.010578 waagent[2028]: 2026-01-23T18:54:27.010439Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sda1 /mnt/resource] Jan 23 18:54:27.079474 systemd[2130]: Queued start job for default target default.target. Jan 23 18:54:27.099366 systemd[2130]: Created slice app.slice - User Application Slice. Jan 23 18:54:27.099408 systemd[2130]: Reached target paths.target - Paths. Jan 23 18:54:27.099456 systemd[2130]: Reached target timers.target - Timers. Jan 23 18:54:27.100754 systemd[2130]: Starting dbus.socket - D-Bus User Message Bus Socket... Jan 23 18:54:27.111766 systemd[2130]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jan 23 18:54:27.111898 systemd[2130]: Reached target sockets.target - Sockets. Jan 23 18:54:27.111950 systemd[2130]: Reached target basic.target - Basic System. Jan 23 18:54:27.111994 systemd[2130]: Reached target default.target - Main User Target. Jan 23 18:54:27.112026 systemd[2130]: Startup finished in 1.055s. Jan 23 18:54:27.112061 systemd[1]: Started user@500.service - User Manager for UID 500. Jan 23 18:54:27.113393 systemd[1]: Started session-1.scope - Session 1 of User core. Jan 23 18:54:27.114118 systemd[1]: Started session-2.scope - Session 2 of User core. Jan 23 18:54:27.124067 waagent[2028]: 2026-01-23T18:54:27.123993Z INFO Daemon Daemon Resource disk /dev/sda is mounted at /mnt/resource with ext4 Jan 23 18:54:27.124503 kernel: EXT4-fs (sda1): mounted filesystem 970b229c-e8e0-493e-80ee-e859fe9bff78 r/w with ordered data mode. Quota mode: none. Jan 23 18:54:27.125038 waagent[2028]: 2026-01-23T18:54:27.124991Z INFO Daemon Daemon Clean protocol and wireserver endpoint Jan 23 18:54:27.127417 waagent[2028]: 2026-01-23T18:54:27.126550Z INFO Daemon Daemon Running default provisioning handler Jan 23 18:54:27.133038 waagent[2028]: 2026-01-23T18:54:27.132990Z INFO Daemon Daemon Unable to get cloud-init enabled status from systemctl: Command '['systemctl', 'is-enabled', 'cloud-init-local.service']' returned non-zero exit status 4. Jan 23 18:54:27.133657 waagent[2028]: 2026-01-23T18:54:27.133616Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Jan 23 18:54:27.134077 waagent[2028]: 2026-01-23T18:54:27.134044Z INFO Daemon Daemon cloud-init is enabled: False Jan 23 18:54:27.134783 waagent[2028]: 2026-01-23T18:54:27.134752Z INFO Daemon Daemon Copying ovf-env.xml Jan 23 18:54:27.197862 waagent[2028]: 2026-01-23T18:54:27.197785Z INFO Daemon Daemon Successfully mounted dvd Jan 23 18:54:27.226037 waagent[2028]: 2026-01-23T18:54:27.225989Z INFO Daemon Daemon Detect protocol endpoint Jan 23 18:54:27.226178 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Jan 23 18:54:27.229967 waagent[2028]: 2026-01-23T18:54:27.229916Z INFO Daemon Daemon Clean protocol and wireserver endpoint Jan 23 18:54:27.231610 waagent[2028]: 2026-01-23T18:54:27.231567Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Jan 23 18:54:27.232299 waagent[2028]: 2026-01-23T18:54:27.232266Z INFO Daemon Daemon Test for route to 168.63.129.16 Jan 23 18:54:27.233877 waagent[2028]: 2026-01-23T18:54:27.233841Z INFO Daemon Daemon Route to 168.63.129.16 exists Jan 23 18:54:27.235271 waagent[2028]: 2026-01-23T18:54:27.235234Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Jan 23 18:54:27.246294 waagent[2028]: 2026-01-23T18:54:27.246257Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Jan 23 18:54:27.247284 waagent[2028]: 2026-01-23T18:54:27.247259Z INFO Daemon Daemon Wire protocol version:2012-11-30 Jan 23 18:54:27.248628 waagent[2028]: 2026-01-23T18:54:27.248595Z INFO Daemon Daemon Server preferred version:2015-04-05 Jan 23 18:54:27.401506 waagent[2028]: 2026-01-23T18:54:27.401351Z INFO Daemon Daemon Initializing goal state during protocol detection Jan 23 18:54:27.403048 waagent[2028]: 2026-01-23T18:54:27.402965Z INFO Daemon Daemon Forcing an update of the goal state. Jan 23 18:54:27.407198 waagent[2028]: 2026-01-23T18:54:27.407120Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Jan 23 18:54:27.440317 waagent[2028]: 2026-01-23T18:54:27.440266Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.179 Jan 23 18:54:27.442467 waagent[2028]: 2026-01-23T18:54:27.442413Z INFO Daemon Jan 23 18:54:27.442767 waagent[2028]: 2026-01-23T18:54:27.442730Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 9de1f83b-fb1c-4ccf-9ecf-24f948fe5d59 eTag: 17890773573286445012 source: Fabric] Jan 23 18:54:27.443755 waagent[2028]: 2026-01-23T18:54:27.443717Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Jan 23 18:54:27.445271 waagent[2028]: 2026-01-23T18:54:27.445227Z INFO Daemon Jan 23 18:54:27.446526 waagent[2028]: 2026-01-23T18:54:27.446477Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Jan 23 18:54:27.469656 waagent[2028]: 2026-01-23T18:54:27.469601Z INFO Daemon Daemon Downloading artifacts profile blob Jan 23 18:54:27.586325 waagent[2028]: 2026-01-23T18:54:27.586259Z INFO Daemon Downloaded certificate {'thumbprint': '7E4B51FD42BAE87E0697C753B44A88FFD06CD5E5', 'hasPrivateKey': True} Jan 23 18:54:27.590185 waagent[2028]: 2026-01-23T18:54:27.590140Z INFO Daemon Fetch goal state completed Jan 23 18:54:27.627322 waagent[2028]: 2026-01-23T18:54:27.627267Z INFO Daemon Daemon Starting provisioning Jan 23 18:54:27.630599 waagent[2028]: 2026-01-23T18:54:27.630530Z INFO Daemon Daemon Handle ovf-env.xml. Jan 23 18:54:27.632450 waagent[2028]: 2026-01-23T18:54:27.632403Z INFO Daemon Daemon Set hostname [ci-4459.2.3-a-c4c88d9723] Jan 23 18:54:27.652122 setup-nvidia[2036]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 2 470M 2 13.7M 0 0 25.3M 0 0:00:18 --:--:-- 0:00:18 25.3M\u000d 10 470M 10 50.5M 0 0 32.6M 0 0:00:14 0:00:01 0:00:13 32.6M\u000d 18 470M 18 87.0M 0 0 34.2M 0 0:00:13 0:00:02 0:00:11 34.2M\u000d 28 470M 28 133M 0 0 37.7M 0 0:00:12 0:00:03 0:00:09 37.7M\u000d 33 470M 33 158M 0 0 34.8M 0 0:00:13 0:00:04 0:00:09 34.8M\u000d 43 470M 43 202M 0 0 36.4M 0 0:00:12 0:00:05 0:00:07 37.6M\u000d 51 470M 51 242M 0 0 36.9M 0 0:00:12 0:00:06 0:00:06 38.3M\u000d 58 470M 58 277M 0 0 36.6M 0 0:00:12 0:00:07 0:00:05 37.9M\u000d 64 470M 64 302M 0 0 35.2M 0 0:00:13 0:00:08 0:00:05 33.5M\u000d 91 470M 91 431M 0 0 45.1M 0 0:00:10 0:00:09 0:00:01 54.4M\u000d100 470M 100 470M 0 0 47.2M 0 0:00:09 0:00:09 --:--:-- 60.8M Jan 23 18:54:27.660335 waagent[2028]: 2026-01-23T18:54:27.660294Z INFO Daemon Daemon Publish hostname [ci-4459.2.3-a-c4c88d9723] Jan 23 18:54:27.662155 waagent[2028]: 2026-01-23T18:54:27.662114Z INFO Daemon Daemon Examine /proc/net/route for primary interface Jan 23 18:54:27.663663 waagent[2028]: 2026-01-23T18:54:27.663624Z INFO Daemon Daemon Primary interface is [eth0] Jan 23 18:54:27.680743 systemd-networkd[1548]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jan 23 18:54:27.680754 systemd-networkd[1548]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jan 23 18:54:27.680778 systemd-networkd[1548]: eth0: DHCP lease lost Jan 23 18:54:27.681169 waagent[2028]: 2026-01-23T18:54:27.681126Z INFO Daemon Daemon Create user account if not exists Jan 23 18:54:27.682903 waagent[2028]: 2026-01-23T18:54:27.682820Z INFO Daemon Daemon User core already exists, skip useradd Jan 23 18:54:27.684315 waagent[2028]: 2026-01-23T18:54:27.684279Z INFO Daemon Daemon Configure sudoer Jan 23 18:54:27.716455 waagent[2028]: 2026-01-23T18:54:27.716400Z INFO Daemon Daemon Configure sshd Jan 23 18:54:27.721647 waagent[2028]: 2026-01-23T18:54:27.721599Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Jan 23 18:54:27.723266 waagent[2028]: 2026-01-23T18:54:27.723221Z INFO Daemon Daemon Deploy ssh public key. Jan 23 18:54:27.739522 systemd-networkd[1548]: eth0: DHCPv4 address 10.200.4.29/24, gateway 10.200.4.1 acquired from 168.63.129.16 Jan 23 18:54:28.904637 waagent[2028]: 2026-01-23T18:54:28.904581Z INFO Daemon Daemon Provisioning complete Jan 23 18:54:28.914881 waagent[2028]: 2026-01-23T18:54:28.914831Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Jan 23 18:54:28.916591 waagent[2028]: 2026-01-23T18:54:28.916550Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Jan 23 18:54:28.917611 waagent[2028]: 2026-01-23T18:54:28.917267Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Jan 23 18:54:29.095323 waagent[2198]: 2026-01-23T18:54:29.095213Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Jan 23 18:54:29.097293 waagent[2198]: 2026-01-23T18:54:29.095813Z INFO ExtHandler ExtHandler OS: flatcar 4459.2.3 Jan 23 18:54:29.097293 waagent[2198]: 2026-01-23T18:54:29.095895Z INFO ExtHandler ExtHandler Python: 3.11.13 Jan 23 18:54:29.097293 waagent[2198]: 2026-01-23T18:54:29.095967Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Jan 23 18:54:29.159233 waagent[2198]: 2026-01-23T18:54:29.159113Z INFO ExtHandler ExtHandler Distro: flatcar-4459.2.3; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.13; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Jan 23 18:54:29.159633 waagent[2198]: 2026-01-23T18:54:29.159596Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jan 23 18:54:29.159852 waagent[2198]: 2026-01-23T18:54:29.159817Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Jan 23 18:54:29.171527 waagent[2198]: 2026-01-23T18:54:29.171450Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Jan 23 18:54:29.180116 waagent[2198]: 2026-01-23T18:54:29.180065Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.179 Jan 23 18:54:29.184503 waagent[2198]: 2026-01-23T18:54:29.183030Z INFO ExtHandler Jan 23 18:54:29.184503 waagent[2198]: 2026-01-23T18:54:29.183116Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 9e2d597f-85f4-40e5-98c1-97f80b3c8392 eTag: 17890773573286445012 source: Fabric] Jan 23 18:54:29.184503 waagent[2198]: 2026-01-23T18:54:29.183417Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Jan 23 18:54:29.184503 waagent[2198]: 2026-01-23T18:54:29.184026Z INFO ExtHandler Jan 23 18:54:29.184503 waagent[2198]: 2026-01-23T18:54:29.184099Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Jan 23 18:54:29.187204 waagent[2198]: 2026-01-23T18:54:29.187176Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Jan 23 18:54:29.257516 waagent[2198]: 2026-01-23T18:54:29.256478Z INFO ExtHandler Downloaded certificate {'thumbprint': '7E4B51FD42BAE87E0697C753B44A88FFD06CD5E5', 'hasPrivateKey': True} Jan 23 18:54:29.257516 waagent[2198]: 2026-01-23T18:54:29.257150Z INFO ExtHandler Fetch goal state completed Jan 23 18:54:29.272498 waagent[2198]: 2026-01-23T18:54:29.271926Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.4.4-dev (Library: OpenSSL 3.4.4-dev ) Jan 23 18:54:29.278657 waagent[2198]: 2026-01-23T18:54:29.278605Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2198 Jan 23 18:54:29.278807 waagent[2198]: 2026-01-23T18:54:29.278772Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Jan 23 18:54:29.279169 waagent[2198]: 2026-01-23T18:54:29.279131Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Jan 23 18:54:29.280879 waagent[2198]: 2026-01-23T18:54:29.280835Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4459.2.3', '', 'Flatcar Container Linux by Kinvolk'] Jan 23 18:54:29.281308 waagent[2198]: 2026-01-23T18:54:29.281269Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4459.2.3', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Jan 23 18:54:29.281456 waagent[2198]: 2026-01-23T18:54:29.281422Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Jan 23 18:54:29.282130 waagent[2198]: 2026-01-23T18:54:29.282089Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Jan 23 18:54:29.389516 waagent[2198]: 2026-01-23T18:54:29.389145Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Jan 23 18:54:29.389516 waagent[2198]: 2026-01-23T18:54:29.389365Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Jan 23 18:54:29.396292 waagent[2198]: 2026-01-23T18:54:29.396263Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Jan 23 18:54:29.404069 systemd[1]: Reload requested from client PID 2213 ('systemctl') (unit waagent.service)... Jan 23 18:54:29.404085 systemd[1]: Reloading... Jan 23 18:54:29.489649 zram_generator::config[2252]: No configuration found. Jan 23 18:54:29.815044 systemd[1]: Reloading finished in 410 ms. Jan 23 18:54:29.840612 waagent[2198]: 2026-01-23T18:54:29.839663Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Jan 23 18:54:29.840612 waagent[2198]: 2026-01-23T18:54:29.839822Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Jan 23 18:54:30.520054 waagent[2198]: 2026-01-23T18:54:30.519972Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Jan 23 18:54:30.520416 waagent[2198]: 2026-01-23T18:54:30.520318Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: 1. configuration enabled [True], 2. cgroups v1 enabled [False] OR cgroups v2 is in use and v2 resource limiting configuration enabled [False], 3. python supported: [True] Jan 23 18:54:30.521162 waagent[2198]: 2026-01-23T18:54:30.521111Z INFO ExtHandler ExtHandler Starting env monitor service. Jan 23 18:54:30.521567 waagent[2198]: 2026-01-23T18:54:30.521518Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Jan 23 18:54:30.521692 waagent[2198]: 2026-01-23T18:54:30.521650Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jan 23 18:54:30.522015 waagent[2198]: 2026-01-23T18:54:30.521981Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Jan 23 18:54:30.522262 waagent[2198]: 2026-01-23T18:54:30.522224Z INFO EnvHandler ExtHandler Configure routes Jan 23 18:54:30.522405 waagent[2198]: 2026-01-23T18:54:30.522375Z INFO EnvHandler ExtHandler Gateway:None Jan 23 18:54:30.522561 waagent[2198]: 2026-01-23T18:54:30.522531Z INFO EnvHandler ExtHandler Routes:None Jan 23 18:54:30.524305 waagent[2198]: 2026-01-23T18:54:30.523558Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jan 23 18:54:30.524305 waagent[2198]: 2026-01-23T18:54:30.523662Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Jan 23 18:54:30.524305 waagent[2198]: 2026-01-23T18:54:30.523858Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Jan 23 18:54:30.524305 waagent[2198]: 2026-01-23T18:54:30.524051Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Jan 23 18:54:30.524305 waagent[2198]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Jan 23 18:54:30.524305 waagent[2198]: eth0 00000000 0104C80A 0003 0 0 1024 00000000 0 0 0 Jan 23 18:54:30.524305 waagent[2198]: eth0 0004C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Jan 23 18:54:30.524305 waagent[2198]: eth0 0104C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Jan 23 18:54:30.524305 waagent[2198]: eth0 10813FA8 0104C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jan 23 18:54:30.524305 waagent[2198]: eth0 FEA9FEA9 0104C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jan 23 18:54:30.524630 waagent[2198]: 2026-01-23T18:54:30.524578Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Jan 23 18:54:30.524956 waagent[2198]: 2026-01-23T18:54:30.524712Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Jan 23 18:54:30.525063 waagent[2198]: 2026-01-23T18:54:30.525004Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Jan 23 18:54:30.525189 waagent[2198]: 2026-01-23T18:54:30.525153Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Jan 23 18:54:30.525737 waagent[2198]: 2026-01-23T18:54:30.525703Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Jan 23 18:54:30.534348 waagent[2198]: 2026-01-23T18:54:30.534309Z INFO ExtHandler ExtHandler Jan 23 18:54:30.539063 waagent[2198]: 2026-01-23T18:54:30.539016Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 7fd24ae5-06fb-47e2-82ca-92c70c05f2ba correlation a5bbf82b-f3f9-49be-a41f-ba736dd5f26f created: 2026-01-23T18:53:02.373428Z] Jan 23 18:54:30.539813 waagent[2198]: 2026-01-23T18:54:30.539774Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Jan 23 18:54:30.540560 waagent[2198]: 2026-01-23T18:54:30.540524Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 6 ms] Jan 23 18:54:30.569886 waagent[2198]: 2026-01-23T18:54:30.569837Z WARNING ExtHandler ExtHandler Failed to get firewall packets: 'iptables -w -t security -L OUTPUT --zero OUTPUT -nxv' failed: 2 (iptables v1.8.11 (nf_tables): Illegal option `--numeric' with this command Jan 23 18:54:30.569886 waagent[2198]: Try `iptables -h' or 'iptables --help' for more information.) Jan 23 18:54:30.570471 waagent[2198]: 2026-01-23T18:54:30.570435Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 1AA06797-5775-4855-9166-4244F97123A6;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Jan 23 18:54:30.674794 waagent[2198]: 2026-01-23T18:54:30.674723Z INFO MonitorHandler ExtHandler Network interfaces: Jan 23 18:54:30.674794 waagent[2198]: Executing ['ip', '-a', '-o', 'link']: Jan 23 18:54:30.674794 waagent[2198]: 1: lo: mtu 65536 qdisc noqueue state UNKNOWN mode DEFAULT group default qlen 1000\ link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00 Jan 23 18:54:30.674794 waagent[2198]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:0d:3a:d4:c9:d5 brd ff:ff:ff:ff:ff:ff Jan 23 18:54:30.674794 waagent[2198]: Executing ['ip', '-4', '-a', '-o', 'address']: Jan 23 18:54:30.674794 waagent[2198]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Jan 23 18:54:30.674794 waagent[2198]: 2: eth0 inet 10.200.4.29/24 metric 1024 brd 10.200.4.255 scope global eth0\ valid_lft forever preferred_lft forever Jan 23 18:54:30.674794 waagent[2198]: Executing ['ip', '-6', '-a', '-o', 'address']: Jan 23 18:54:30.674794 waagent[2198]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Jan 23 18:54:30.674794 waagent[2198]: 2: eth0 inet6 fe80::20d:3aff:fed4:c9d5/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Jan 23 18:54:30.747881 waagent[2198]: 2026-01-23T18:54:30.747825Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Jan 23 18:54:30.747881 waagent[2198]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Jan 23 18:54:30.747881 waagent[2198]: pkts bytes target prot opt in out source destination Jan 23 18:54:30.747881 waagent[2198]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Jan 23 18:54:30.747881 waagent[2198]: pkts bytes target prot opt in out source destination Jan 23 18:54:30.747881 waagent[2198]: Chain OUTPUT (policy ACCEPT 9 packets, 1180 bytes) Jan 23 18:54:30.747881 waagent[2198]: pkts bytes target prot opt in out source destination Jan 23 18:54:30.747881 waagent[2198]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Jan 23 18:54:30.747881 waagent[2198]: 1 52 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Jan 23 18:54:30.747881 waagent[2198]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Jan 23 18:54:30.754974 waagent[2198]: 2026-01-23T18:54:30.754403Z INFO EnvHandler ExtHandler Current Firewall rules: Jan 23 18:54:30.754974 waagent[2198]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Jan 23 18:54:30.754974 waagent[2198]: pkts bytes target prot opt in out source destination Jan 23 18:54:30.754974 waagent[2198]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Jan 23 18:54:30.754974 waagent[2198]: pkts bytes target prot opt in out source destination Jan 23 18:54:30.754974 waagent[2198]: Chain OUTPUT (policy ACCEPT 11 packets, 1284 bytes) Jan 23 18:54:30.754974 waagent[2198]: pkts bytes target prot opt in out source destination Jan 23 18:54:30.754974 waagent[2198]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Jan 23 18:54:30.754974 waagent[2198]: 5 647 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Jan 23 18:54:30.754974 waagent[2198]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Jan 23 18:54:30.754974 waagent[2198]: 2026-01-23T18:54:30.754706Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Jan 23 18:54:30.754974 waagent[2198]: 2026-01-23T18:54:30.754855Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Jan 23 18:54:37.704632 setup-nvidia[1872]: Downloading NVIDIA 535.261.03 Driver Jan 23 18:54:37.708836 setup-nvidia[2347]: % Total % Received % Xferd Average Speed Time Time Time Current Jan 23 18:54:37.709063 setup-nvidia[2347]: Dload Upload Total Spent Left Speed Jan 23 18:54:40.585745 chronyd[1856]: Selected source PHC0 Jan 23 18:54:43.804915 setup-nvidia[2347]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 325M 0 1326 0 0 2245 0 42:17:18 --:--:-- 42:17:18 2243\u000d 14 325M 14 46.0M 0 0 30.0M 0 0:00:10 0:00:01 0:00:09 30.0M\u000d 31 325M 31 104M 0 0 41.0M 0 0:00:07 0:00:02 0:00:05 41.0M\u000d 50 325M 50 164M 0 0 46.3M 0 0:00:07 0:00:03 0:00:04 46.3M\u000d 68 325M 68 222M 0 0 49.0M 0 0:00:06 0:00:04 0:00:02 49.0M\u000d 87 325M 87 286M 0 0 51.8M 0 0:00:06 0:00:05 0:00:01 58.0M\u000d100 325M 100 325M 0 0 53.4M 0 0:00:06 0:00:06 --:--:-- 61.2M Jan 23 18:54:43.874468 setup-nvidia[1872]: Extract the NVIDIA Driver Installer 535.261.03 Jan 23 18:54:43.874713 setup-nvidia[1872]: /opt/nvidia/workdir/nvidia-workdir / Jan 23 18:54:43.892590 setup-nvidia[2350]: Creating directory NVIDIA-Linux-x86_64-535.261.03 Jan 23 18:54:44.180722 setup-nvidia[2350]: Verifying archive integrity... OK Jan 23 18:54:44.928453 setup-nvidia[2350]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.261.03 Jan 23 18:54:46.760111 setup-nvidia[2376]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Jan 23 18:54:46.833356 setup-nvidia[1872]: / Jan 23 18:54:46.833633 setup-nvidia[1872]: Spawn system-nspawn container to install the NVIDIA drivers Jan 23 18:54:47.114128 sudo[2383]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4459.2.3.bin --overlay=/usr/lib/modules::/usr/lib/modules --bind=/opt/nvidia/workdir/nvidia-workdir:/nvidia --bind=/usr/lib/nvidia/bin:/app/bin/ /app/bin/install-nvidia NVIDIA-Linux-x86_64-535.261.03 Jan 23 18:54:47.115861 sudo[2383]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 23 18:54:47.121341 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Jan 23 18:54:47.126727 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Jan 23 18:54:47.164589 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Jan 23 18:54:47.172437 systemd[1]: Starting user@0.service - User Manager for UID 0... Jan 23 18:54:47.193597 (systemd)[2386]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Jan 23 18:54:47.195895 systemd-logind[1876]: New session c3 of user root. Jan 23 18:54:47.385746 systemd[2386]: Queued start job for default target default.target. Jan 23 18:54:47.408596 systemd[2386]: Created slice app.slice - User Application Slice. Jan 23 18:54:47.408625 systemd[2386]: Reached target paths.target - Paths. Jan 23 18:54:47.408673 systemd[2386]: Reached target timers.target - Timers. Jan 23 18:54:47.409899 systemd[2386]: Starting dbus.socket - D-Bus User Message Bus Socket... Jan 23 18:54:47.420750 systemd[2386]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jan 23 18:54:47.420870 systemd[2386]: Reached target sockets.target - Sockets. Jan 23 18:54:47.420926 systemd[2386]: Reached target basic.target - Basic System. Jan 23 18:54:47.420973 systemd[2386]: Reached target default.target - Main User Target. Jan 23 18:54:47.421007 systemd[2386]: Startup finished in 219ms. Jan 23 18:54:47.421033 systemd[1]: Started user@0.service - User Manager for UID 0. Jan 23 18:54:47.424105 systemd[1]: Started session-c2.scope - Session c2 of User root. Jan 23 18:54:47.529512 kernel: loop6: detected capacity change from 0 to 12644352 Jan 23 18:54:47.584504 kernel: loop6: p9 Jan 23 18:54:47.957325 setup-nvidia[2395]: ░ Spawning container flatcardevelopercontainer-4459.2.3.bin on /opt/nvidia/workdir/flatcar_developer_container-4459.2.3.bin. Jan 23 18:54:48.885301 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Jan 23 18:54:48.889963 systemd[1]: Started sshd@0-10.200.4.29:22-10.200.16.10:34768.service - OpenSSH per-connection server daemon (10.200.16.10:34768). Jan 23 18:54:49.675981 kernel: EXT4-fs (loop6p9): write access unavailable, skipping orphan cleanup Jan 23 18:54:49.676088 kernel: EXT4-fs (loop6p9): mounted filesystem 94bea6f2-4f04-4451-9ae2-f9d206317999 ro without journal. Quota mode: none. Jan 23 18:54:49.750555 sshd[2401]: Accepted publickey for core from 10.200.16.10 port 34768 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:54:49.751769 sshd-session[2401]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:54:49.756387 systemd-logind[1876]: New session 3 of user core. Jan 23 18:54:49.766616 systemd[1]: Started session-3.scope - Session 3 of User core. Jan 23 18:54:50.292864 systemd[1]: Started sshd@1-10.200.4.29:22-10.200.16.10:46424.service - OpenSSH per-connection server daemon (10.200.16.10:46424). Jan 23 18:54:50.907808 sshd[2409]: Accepted publickey for core from 10.200.16.10 port 46424 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:54:50.909036 sshd-session[2409]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:54:50.913250 systemd-logind[1876]: New session 4 of user core. Jan 23 18:54:50.925658 systemd[1]: Started session-4.scope - Session 4 of User core. Jan 23 18:54:51.342538 sshd[2412]: Connection closed by 10.200.16.10 port 46424 Jan 23 18:54:51.342823 sshd-session[2409]: pam_unix(sshd:session): session closed for user core Jan 23 18:54:51.345826 systemd[1]: sshd@1-10.200.4.29:22-10.200.16.10:46424.service: Deactivated successfully. Jan 23 18:54:51.347437 systemd[1]: session-4.scope: Deactivated successfully. Jan 23 18:54:51.348765 systemd-logind[1876]: Session 4 logged out. Waiting for processes to exit. Jan 23 18:54:51.349724 systemd-logind[1876]: Removed session 4. Jan 23 18:54:51.452649 systemd[1]: Started sshd@2-10.200.4.29:22-10.200.16.10:46428.service - OpenSSH per-connection server daemon (10.200.16.10:46428). Jan 23 18:54:52.073623 sshd[2418]: Accepted publickey for core from 10.200.16.10 port 46428 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:54:52.074862 sshd-session[2418]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:54:52.079020 systemd-logind[1876]: New session 5 of user core. Jan 23 18:54:52.091610 systemd[1]: Started session-5.scope - Session 5 of User core. Jan 23 18:54:52.511467 sshd[2421]: Connection closed by 10.200.16.10 port 46428 Jan 23 18:54:52.511843 sshd-session[2418]: pam_unix(sshd:session): session closed for user core Jan 23 18:54:52.515424 systemd[1]: sshd@2-10.200.4.29:22-10.200.16.10:46428.service: Deactivated successfully. Jan 23 18:54:52.517011 systemd[1]: session-5.scope: Deactivated successfully. Jan 23 18:54:52.517728 systemd-logind[1876]: Session 5 logged out. Waiting for processes to exit. Jan 23 18:54:52.518715 systemd-logind[1876]: Removed session 5. Jan 23 18:54:56.913545 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Jan 23 18:55:02.080191 update_engine[1879]: I20260123 18:55:02.080092 1879 update_attempter.cc:509] Updating boot flags... Jan 23 18:55:02.619809 systemd[1]: Started sshd@3-10.200.4.29:22-10.200.16.10:53294.service - OpenSSH per-connection server daemon (10.200.16.10:53294). Jan 23 18:55:03.241014 sshd[2521]: Accepted publickey for core from 10.200.16.10 port 53294 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:55:03.242272 sshd-session[2521]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:55:03.246406 systemd-logind[1876]: New session 6 of user core. Jan 23 18:55:03.256611 systemd[1]: Started session-6.scope - Session 6 of User core. Jan 23 18:55:03.685192 sshd[2630]: Connection closed by 10.200.16.10 port 53294 Jan 23 18:55:03.685584 sshd-session[2521]: pam_unix(sshd:session): session closed for user core Jan 23 18:55:03.688968 systemd[1]: sshd@3-10.200.4.29:22-10.200.16.10:53294.service: Deactivated successfully. Jan 23 18:55:03.690812 systemd[1]: session-6.scope: Deactivated successfully. Jan 23 18:55:03.691616 systemd-logind[1876]: Session 6 logged out. Waiting for processes to exit. Jan 23 18:55:03.692517 systemd-logind[1876]: Removed session 6. Jan 23 18:55:03.795125 systemd[1]: Started sshd@4-10.200.4.29:22-10.200.16.10:53300.service - OpenSSH per-connection server daemon (10.200.16.10:53300). Jan 23 18:55:04.415062 sshd[2660]: Accepted publickey for core from 10.200.16.10 port 53300 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:55:04.416326 sshd-session[2660]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:55:04.420743 systemd-logind[1876]: New session 7 of user core. Jan 23 18:55:04.431631 systemd[1]: Started session-7.scope - Session 7 of User core. Jan 23 18:55:04.851789 sshd[2663]: Connection closed by 10.200.16.10 port 53300 Jan 23 18:55:04.852099 sshd-session[2660]: pam_unix(sshd:session): session closed for user core Jan 23 18:55:04.855578 systemd[1]: sshd@4-10.200.4.29:22-10.200.16.10:53300.service: Deactivated successfully. Jan 23 18:55:04.857112 systemd[1]: session-7.scope: Deactivated successfully. Jan 23 18:55:04.858532 systemd-logind[1876]: Session 7 logged out. Waiting for processes to exit. Jan 23 18:55:04.859552 systemd-logind[1876]: Removed session 7. Jan 23 18:55:14.962901 systemd[1]: Started sshd@5-10.200.4.29:22-10.200.16.10:43346.service - OpenSSH per-connection server daemon (10.200.16.10:43346). Jan 23 18:55:15.597112 sshd[2714]: Accepted publickey for core from 10.200.16.10 port 43346 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:55:15.598347 sshd-session[2714]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:55:15.602665 systemd-logind[1876]: New session 8 of user core. Jan 23 18:55:15.610646 systemd[1]: Started session-8.scope - Session 8 of User core. Jan 23 18:55:16.034636 sshd[2724]: Connection closed by 10.200.16.10 port 43346 Jan 23 18:55:16.035030 sshd-session[2714]: pam_unix(sshd:session): session closed for user core Jan 23 18:55:16.038669 systemd[1]: sshd@5-10.200.4.29:22-10.200.16.10:43346.service: Deactivated successfully. Jan 23 18:55:16.040252 systemd[1]: session-8.scope: Deactivated successfully. Jan 23 18:55:16.040944 systemd-logind[1876]: Session 8 logged out. Waiting for processes to exit. Jan 23 18:55:16.041872 systemd-logind[1876]: Removed session 8. Jan 23 18:55:16.151775 systemd[1]: Started sshd@6-10.200.4.29:22-10.200.16.10:43356.service - OpenSSH per-connection server daemon (10.200.16.10:43356). Jan 23 18:55:16.784770 sshd[2730]: Accepted publickey for core from 10.200.16.10 port 43356 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:55:16.785998 sshd-session[2730]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:55:16.790392 systemd-logind[1876]: New session 9 of user core. Jan 23 18:55:16.798604 systemd[1]: Started session-9.scope - Session 9 of User core. Jan 23 18:55:17.220793 sshd[2775]: Connection closed by 10.200.16.10 port 43356 Jan 23 18:55:17.221146 sshd-session[2730]: pam_unix(sshd:session): session closed for user core Jan 23 18:55:17.224710 systemd[1]: sshd@6-10.200.4.29:22-10.200.16.10:43356.service: Deactivated successfully. Jan 23 18:55:17.226285 systemd[1]: session-9.scope: Deactivated successfully. Jan 23 18:55:17.226997 systemd-logind[1876]: Session 9 logged out. Waiting for processes to exit. Jan 23 18:55:17.227949 systemd-logind[1876]: Removed session 9. Jan 23 18:55:27.334926 systemd[1]: Started sshd@7-10.200.4.29:22-10.200.16.10:51842.service - OpenSSH per-connection server daemon (10.200.16.10:51842). Jan 23 18:55:27.953077 sshd[4047]: Accepted publickey for core from 10.200.16.10 port 51842 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:55:27.954333 sshd-session[4047]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:55:27.958608 systemd-logind[1876]: New session 10 of user core. Jan 23 18:55:27.966612 systemd[1]: Started session-10.scope - Session 10 of User core. Jan 23 18:55:28.393191 sshd[4393]: Connection closed by 10.200.16.10 port 51842 Jan 23 18:55:28.393460 sshd-session[4047]: pam_unix(sshd:session): session closed for user core Jan 23 18:55:28.396982 systemd[1]: sshd@7-10.200.4.29:22-10.200.16.10:51842.service: Deactivated successfully. Jan 23 18:55:28.399136 systemd[1]: session-10.scope: Deactivated successfully. Jan 23 18:55:28.399883 systemd-logind[1876]: Session 10 logged out. Waiting for processes to exit. Jan 23 18:55:28.401309 systemd-logind[1876]: Removed session 10. Jan 23 18:55:28.506935 systemd[1]: Started sshd@8-10.200.4.29:22-10.200.16.10:51856.service - OpenSSH per-connection server daemon (10.200.16.10:51856). Jan 23 18:55:29.134647 sshd[4708]: Accepted publickey for core from 10.200.16.10 port 51856 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:55:29.135813 sshd-session[4708]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:55:29.140162 systemd-logind[1876]: New session 11 of user core. Jan 23 18:55:29.151620 systemd[1]: Started session-11.scope - Session 11 of User core. Jan 23 18:55:29.573421 sshd[5091]: Connection closed by 10.200.16.10 port 51856 Jan 23 18:55:29.573781 sshd-session[4708]: pam_unix(sshd:session): session closed for user core Jan 23 18:55:29.577318 systemd[1]: sshd@8-10.200.4.29:22-10.200.16.10:51856.service: Deactivated successfully. Jan 23 18:55:29.578921 systemd[1]: session-11.scope: Deactivated successfully. Jan 23 18:55:29.579625 systemd-logind[1876]: Session 11 logged out. Waiting for processes to exit. Jan 23 18:55:29.580766 systemd-logind[1876]: Removed session 11. Jan 23 18:55:39.691606 systemd[1]: Started sshd@9-10.200.4.29:22-10.200.16.10:60534.service - OpenSSH per-connection server daemon (10.200.16.10:60534). Jan 23 18:55:40.325766 sshd[9207]: Accepted publickey for core from 10.200.16.10 port 60534 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:55:40.326296 sshd-session[9207]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:55:40.334261 systemd-logind[1876]: New session 12 of user core. Jan 23 18:55:40.346245 systemd[1]: Started session-12.scope - Session 12 of User core. Jan 23 18:55:40.766156 sshd[9341]: Connection closed by 10.200.16.10 port 60534 Jan 23 18:55:40.767966 sshd-session[9207]: pam_unix(sshd:session): session closed for user core Jan 23 18:55:40.771335 systemd-logind[1876]: Session 12 logged out. Waiting for processes to exit. Jan 23 18:55:40.772976 systemd[1]: sshd@9-10.200.4.29:22-10.200.16.10:60534.service: Deactivated successfully. Jan 23 18:55:40.775720 systemd[1]: session-12.scope: Deactivated successfully. Jan 23 18:55:40.778629 systemd-logind[1876]: Removed session 12. Jan 23 18:55:40.890778 systemd[1]: Started sshd@10-10.200.4.29:22-10.200.16.10:60544.service - OpenSSH per-connection server daemon (10.200.16.10:60544). Jan 23 18:55:41.528347 sshd[9547]: Accepted publickey for core from 10.200.16.10 port 60544 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:55:41.529600 sshd-session[9547]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:55:41.533997 systemd-logind[1876]: New session 13 of user core. Jan 23 18:55:41.543662 systemd[1]: Started session-13.scope - Session 13 of User core. Jan 23 18:55:41.965235 sshd[9898]: Connection closed by 10.200.16.10 port 60544 Jan 23 18:55:41.965590 sshd-session[9547]: pam_unix(sshd:session): session closed for user core Jan 23 18:55:41.969186 systemd[1]: sshd@10-10.200.4.29:22-10.200.16.10:60544.service: Deactivated successfully. Jan 23 18:55:41.970767 systemd[1]: session-13.scope: Deactivated successfully. Jan 23 18:55:41.971456 systemd-logind[1876]: Session 13 logged out. Waiting for processes to exit. Jan 23 18:55:41.972347 systemd-logind[1876]: Removed session 13. Jan 23 18:55:52.087087 systemd[1]: Started sshd@11-10.200.4.29:22-10.200.16.10:41372.service - OpenSSH per-connection server daemon (10.200.16.10:41372). Jan 23 18:55:52.705793 sshd[14047]: Accepted publickey for core from 10.200.16.10 port 41372 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:55:52.707152 sshd-session[14047]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:55:52.715638 systemd-logind[1876]: New session 14 of user core. Jan 23 18:55:52.724574 systemd[1]: Started session-14.scope - Session 14 of User core. Jan 23 18:55:53.147238 sshd[14075]: Connection closed by 10.200.16.10 port 41372 Jan 23 18:55:53.147552 sshd-session[14047]: pam_unix(sshd:session): session closed for user core Jan 23 18:55:53.152922 systemd[1]: sshd@11-10.200.4.29:22-10.200.16.10:41372.service: Deactivated successfully. Jan 23 18:55:53.154767 systemd[1]: session-14.scope: Deactivated successfully. Jan 23 18:55:53.155759 systemd-logind[1876]: Session 14 logged out. Waiting for processes to exit. Jan 23 18:55:53.158827 systemd-logind[1876]: Removed session 14. Jan 23 18:55:53.257029 systemd[1]: Started sshd@12-10.200.4.29:22-10.200.16.10:41378.service - OpenSSH per-connection server daemon (10.200.16.10:41378). Jan 23 18:55:53.894644 sshd[14099]: Accepted publickey for core from 10.200.16.10 port 41378 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:55:53.896480 sshd-session[14099]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:55:53.904389 systemd-logind[1876]: New session 15 of user core. Jan 23 18:55:53.908104 systemd[1]: Started session-15.scope - Session 15 of User core. Jan 23 18:55:54.332423 sshd[14115]: Connection closed by 10.200.16.10 port 41378 Jan 23 18:55:54.333223 sshd-session[14099]: pam_unix(sshd:session): session closed for user core Jan 23 18:55:54.337671 systemd-logind[1876]: Session 15 logged out. Waiting for processes to exit. Jan 23 18:55:54.338353 systemd[1]: sshd@12-10.200.4.29:22-10.200.16.10:41378.service: Deactivated successfully. Jan 23 18:55:54.342166 systemd[1]: session-15.scope: Deactivated successfully. Jan 23 18:55:54.344806 systemd-logind[1876]: Removed session 15. Jan 23 18:56:04.447578 systemd[1]: Started sshd@13-10.200.4.29:22-10.200.16.10:51228.service - OpenSSH per-connection server daemon (10.200.16.10:51228). Jan 23 18:56:05.084719 sshd[14548]: Accepted publickey for core from 10.200.16.10 port 51228 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:56:05.086551 sshd-session[14548]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:56:05.093400 systemd-logind[1876]: New session 16 of user core. Jan 23 18:56:05.098667 systemd[1]: Started session-16.scope - Session 16 of User core. Jan 23 18:56:05.521293 sshd[14568]: Connection closed by 10.200.16.10 port 51228 Jan 23 18:56:05.521662 sshd-session[14548]: pam_unix(sshd:session): session closed for user core Jan 23 18:56:05.526587 systemd[1]: sshd@13-10.200.4.29:22-10.200.16.10:51228.service: Deactivated successfully. Jan 23 18:56:05.528958 systemd[1]: session-16.scope: Deactivated successfully. Jan 23 18:56:05.530195 systemd-logind[1876]: Session 16 logged out. Waiting for processes to exit. Jan 23 18:56:05.532683 systemd-logind[1876]: Removed session 16. Jan 23 18:56:05.637827 systemd[1]: Started sshd@14-10.200.4.29:22-10.200.16.10:51238.service - OpenSSH per-connection server daemon (10.200.16.10:51238). Jan 23 18:56:06.277598 sshd[14597]: Accepted publickey for core from 10.200.16.10 port 51238 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:56:06.279346 sshd-session[14597]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:56:06.286469 systemd-logind[1876]: New session 17 of user core. Jan 23 18:56:06.294200 systemd[1]: Started session-17.scope - Session 17 of User core. Jan 23 18:56:06.719322 sshd[14612]: Connection closed by 10.200.16.10 port 51238 Jan 23 18:56:06.720122 sshd-session[14597]: pam_unix(sshd:session): session closed for user core Jan 23 18:56:06.723687 systemd-logind[1876]: Session 17 logged out. Waiting for processes to exit. Jan 23 18:56:06.725064 systemd[1]: sshd@14-10.200.4.29:22-10.200.16.10:51238.service: Deactivated successfully. Jan 23 18:56:06.726895 systemd[1]: session-17.scope: Deactivated successfully. Jan 23 18:56:06.729675 systemd-logind[1876]: Removed session 17. Jan 23 18:56:16.831750 systemd[1]: Started sshd@15-10.200.4.29:22-10.200.16.10:41518.service - OpenSSH per-connection server daemon (10.200.16.10:41518). Jan 23 18:56:17.469318 sshd[14968]: Accepted publickey for core from 10.200.16.10 port 41518 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:56:17.470804 sshd-session[14968]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:56:17.478759 systemd-logind[1876]: New session 18 of user core. Jan 23 18:56:17.483706 systemd[1]: Started session-18.scope - Session 18 of User core. Jan 23 18:56:17.910940 sshd[14984]: Connection closed by 10.200.16.10 port 41518 Jan 23 18:56:17.914665 sshd-session[14968]: pam_unix(sshd:session): session closed for user core Jan 23 18:56:17.919882 systemd[1]: sshd@15-10.200.4.29:22-10.200.16.10:41518.service: Deactivated successfully. Jan 23 18:56:17.923924 systemd[1]: session-18.scope: Deactivated successfully. Jan 23 18:56:17.925993 systemd-logind[1876]: Session 18 logged out. Waiting for processes to exit. Jan 23 18:56:17.927477 systemd-logind[1876]: Removed session 18. Jan 23 18:56:18.022268 systemd[1]: Started sshd@16-10.200.4.29:22-10.200.16.10:41526.service - OpenSSH per-connection server daemon (10.200.16.10:41526). Jan 23 18:56:18.664831 sshd[15006]: Accepted publickey for core from 10.200.16.10 port 41526 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:56:18.666277 sshd-session[15006]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:56:18.675538 systemd-logind[1876]: New session 19 of user core. Jan 23 18:56:18.681145 systemd[1]: Started session-19.scope - Session 19 of User core. Jan 23 18:56:19.102282 sshd[15017]: Connection closed by 10.200.16.10 port 41526 Jan 23 18:56:19.102584 sshd-session[15006]: pam_unix(sshd:session): session closed for user core Jan 23 18:56:19.109097 systemd-logind[1876]: Session 19 logged out. Waiting for processes to exit. Jan 23 18:56:19.110925 systemd[1]: sshd@16-10.200.4.29:22-10.200.16.10:41526.service: Deactivated successfully. Jan 23 18:56:19.114471 systemd[1]: session-19.scope: Deactivated successfully. Jan 23 18:56:19.118457 systemd-logind[1876]: Removed session 19. Jan 23 18:56:29.217783 systemd[1]: Started sshd@17-10.200.4.29:22-10.200.16.10:39374.service - OpenSSH per-connection server daemon (10.200.16.10:39374). Jan 23 18:56:29.834013 sshd[15252]: Accepted publickey for core from 10.200.16.10 port 39374 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:56:29.835270 sshd-session[15252]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:56:29.839507 systemd-logind[1876]: New session 20 of user core. Jan 23 18:56:29.850614 systemd[1]: Started session-20.scope - Session 20 of User core. Jan 23 18:56:30.268796 sshd[15255]: Connection closed by 10.200.16.10 port 39374 Jan 23 18:56:30.269193 sshd-session[15252]: pam_unix(sshd:session): session closed for user core Jan 23 18:56:30.272754 systemd[1]: sshd@17-10.200.4.29:22-10.200.16.10:39374.service: Deactivated successfully. Jan 23 18:56:30.274478 systemd[1]: session-20.scope: Deactivated successfully. Jan 23 18:56:30.275248 systemd-logind[1876]: Session 20 logged out. Waiting for processes to exit. Jan 23 18:56:30.276155 systemd-logind[1876]: Removed session 20. Jan 23 18:56:30.381990 systemd[1]: Started sshd@18-10.200.4.29:22-10.200.16.10:33296.service - OpenSSH per-connection server daemon (10.200.16.10:33296). Jan 23 18:56:30.998086 sshd[15261]: Accepted publickey for core from 10.200.16.10 port 33296 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:56:30.999317 sshd-session[15261]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:56:31.003767 systemd-logind[1876]: New session 21 of user core. Jan 23 18:56:31.014613 systemd[1]: Started session-21.scope - Session 21 of User core. Jan 23 18:56:31.432413 sshd[15296]: Connection closed by 10.200.16.10 port 33296 Jan 23 18:56:31.432789 sshd-session[15261]: pam_unix(sshd:session): session closed for user core Jan 23 18:56:31.436325 systemd[1]: sshd@18-10.200.4.29:22-10.200.16.10:33296.service: Deactivated successfully. Jan 23 18:56:31.437959 systemd[1]: session-21.scope: Deactivated successfully. Jan 23 18:56:31.438665 systemd-logind[1876]: Session 21 logged out. Waiting for processes to exit. Jan 23 18:56:31.439572 systemd-logind[1876]: Removed session 21. Jan 23 18:56:33.367547 setup-nvidia[2395]: \u000d Jan 23 18:56:33.367547 setup-nvidia[2395]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Jan 23 18:56:33.367547 setup-nvidia[2395]: frequently when this kernel module was built against the wrong or Jan 23 18:56:33.367547 setup-nvidia[2395]: improperly configured kernel sources, with a version of gcc that differs Jan 23 18:56:33.367547 setup-nvidia[2395]: from the one used to build the target kernel, or if another driver, such Jan 23 18:56:33.367547 setup-nvidia[2395]: as nouveau, is present and prevents the NVIDIA kernel module from Jan 23 18:56:33.367547 setup-nvidia[2395]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Jan 23 18:56:33.367547 setup-nvidia[2395]: installed in this system is supported by this NVIDIA Linux graphics Jan 23 18:56:33.367547 setup-nvidia[2395]: driver release. Jan 23 18:56:33.367547 setup-nvidia[2395]: \u000d Jan 23 18:56:33.367547 setup-nvidia[2395]: Please see the log entries 'Kernel module load error' and 'Kernel Jan 23 18:56:33.367547 setup-nvidia[2395]: messages' at the end of the file Jan 23 18:56:33.367547 setup-nvidia[2395]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for more Jan 23 18:56:33.367547 setup-nvidia[2395]: information. Jan 23 18:56:33.367547 setup-nvidia[2395]: \u000d Jan 23 18:56:33.386755 setup-nvidia[2395]: dmesg: read kernel buffer failed: Operation not permitted Jan 23 18:56:33.406772 setup-nvidia[2395]: \u000d Jan 23 18:56:33.406772 setup-nvidia[2395]: ERROR: Installation has failed. Please see the file Jan 23 18:56:33.406772 setup-nvidia[2395]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for Jan 23 18:56:33.406772 setup-nvidia[2395]: details. You may find suggestions on fixing installation problems in Jan 23 18:56:33.406772 setup-nvidia[2395]: the README available on the Linux driver download page at Jan 23 18:56:33.406772 setup-nvidia[2395]: www.nvidia.com. Jan 23 18:56:33.406772 setup-nvidia[2395]: \u000d Jan 23 18:56:33.407145 setup-nvidia[2395]: Last 50 lines of nvidia-installer.log: Jan 23 18:56:33.407945 setup-nvidia[2395]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039059rm+0x53: 'naked' return found in MITIGATION_RETHUNK build Jan 23 18:56:33.407945 setup-nvidia[2395]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039056rm+0x90: 'naked' return found in MITIGATION_RETHUNK build Jan 23 18:56:33.407945 setup-nvidia[2395]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039049rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build Jan 23 18:56:33.407945 setup-nvidia[2395]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039105rm+0x93: 'naked' return found in MITIGATION_RETHUNK build Jan 23 18:56:33.407945 setup-nvidia[2395]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039101rm+0x36: 'naked' return found in MITIGATION_RETHUNK build Jan 23 18:56:33.407945 setup-nvidia[2395]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039100rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build Jan 23 18:56:33.407945 setup-nvidia[2395]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039102rm+0x77: 'naked' return found in MITIGATION_RETHUNK build Jan 23 18:56:33.407945 setup-nvidia[2395]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039046rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build Jan 23 18:56:33.407945 setup-nvidia[2395]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030615rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build Jan 23 18:56:33.407945 setup-nvidia[2395]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030614rm+0x27: 'naked' return found in MITIGATION_RETHUNK build Jan 23 18:56:33.408227 setup-nvidia[2395]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030618rm+0x37: 'naked' return found in MITIGATION_RETHUNK build Jan 23 18:56:33.408227 setup-nvidia[2395]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030617rm+0x24: 'naked' return found in MITIGATION_RETHUNK build Jan 23 18:56:33.408227 setup-nvidia[2395]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030616rm+0xca: 'naked' return found in MITIGATION_RETHUNK build Jan 23 18:56:33.408227 setup-nvidia[2395]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030619rm+0x193: 'naked' return found in MITIGATION_RETHUNK build Jan 23 18:56:33.408227 setup-nvidia[2395]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030621rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build Jan 23 18:56:33.408227 setup-nvidia[2395]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv011864rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build Jan 23 18:56:33.408227 setup-nvidia[2395]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039413rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build Jan 23 18:56:33.408227 setup-nvidia[2395]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/Module.symvers Jan 23 18:56:33.408227 setup-nvidia[2395]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.mod.o Jan 23 18:56:33.408227 setup-nvidia[2395]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/.module-common.o Jan 23 18:56:33.408227 setup-nvidia[2395]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.mod.o Jan 23 18:56:33.408227 setup-nvidia[2395]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.mod.o Jan 23 18:56:33.408227 setup-nvidia[2395]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.mod.o Jan 23 18:56:33.408227 setup-nvidia[2395]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.mod.o Jan 23 18:56:33.408227 setup-nvidia[2395]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Jan 23 18:56:33.408227 setup-nvidia[2395]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Jan 23 18:56:33.408227 setup-nvidia[2395]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Jan 23 18:56:33.408227 setup-nvidia[2395]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Jan 23 18:56:33.408227 setup-nvidia[2395]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Jan 23 18:56:33.408227 setup-nvidia[2395]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Jan 23 18:56:33.408751 setup-nvidia[2395]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko due to unavailability of vmlinux Jan 23 18:56:33.408751 setup-nvidia[2395]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Jan 23 18:56:33.408751 setup-nvidia[2395]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko due to unavailability of vmlinux Jan 23 18:56:33.408751 setup-nvidia[2395]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Jan 23 18:56:33.408751 setup-nvidia[2395]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko due to unavailability of vmlinux Jan 23 18:56:33.408751 setup-nvidia[2395]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Jan 23 18:56:33.408751 setup-nvidia[2395]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko due to unavailability of vmlinux Jan 23 18:56:33.408751 setup-nvidia[2395]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Jan 23 18:56:33.408751 setup-nvidia[2395]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko due to unavailability of vmlinux Jan 23 18:56:33.408751 setup-nvidia[2395]: make[2]: Leaving directory '/usr/lib/modules/6.12.66-flatcar/build' Jan 23 18:56:33.408751 setup-nvidia[2395]: make[1]: Leaving directory '/usr/lib/modules/6.12.66-flatcar/source' Jan 23 18:56:33.408751 setup-nvidia[2395]: -> done. Jan 23 18:56:33.408751 setup-nvidia[2395]: -> Kernel module compilation complete. Jan 23 18:56:33.408751 setup-nvidia[2395]: -> Unable to determine if Secure Boot is enabled: No such file or directory Jan 23 18:56:33.408751 setup-nvidia[2395]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most frequently when this kernel module was built against the wrong or improperly configured kernel sources, with a version of gcc that differs from the one used to build the target kernel, or if another driver, such as nouveau, is present and prevents the NVIDIA kernel module from obtaining ownership of the NVIDIA device(s), or no NVIDIA device installed in this system is supported by this NVIDIA Linux graphics driver release. Jan 23 18:56:33.408751 setup-nvidia[2395]: \u000d Jan 23 18:56:33.408751 setup-nvidia[2395]: Please see the log entries 'Kernel module load error' and 'Kernel messages' at the end of the file '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for more information. Jan 23 18:56:33.408751 setup-nvidia[2395]: -> Kernel module load error: Operation not permitted Jan 23 18:56:33.408751 setup-nvidia[2395]: -> Kernel messages: Jan 23 18:56:33.409187 setup-nvidia[2395]: ERROR: Installation has failed. Please see the file '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for details. You may find suggestions on fixing installation problems in the README available on the Linux driver download page at www.nvidia.com. Jan 23 18:56:37.503400 waagent[2198]: 2026-01-23T18:56:37.503338Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 2] Jan 23 18:56:37.510559 waagent[2198]: 2026-01-23T18:56:37.510511Z INFO ExtHandler Jan 23 18:56:37.510639 waagent[2198]: 2026-01-23T18:56:37.510602Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 2] Jan 23 18:56:37.599587 waagent[2198]: 2026-01-23T18:56:37.599534Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Jan 23 18:56:37.657065 waagent[2198]: 2026-01-23T18:56:37.657003Z INFO ExtHandler Downloaded certificate {'thumbprint': '7E4B51FD42BAE87E0697C753B44A88FFD06CD5E5', 'hasPrivateKey': True} Jan 23 18:56:37.657688 waagent[2198]: 2026-01-23T18:56:37.657645Z INFO ExtHandler Fetch goal state completed Jan 23 18:56:37.658066 waagent[2198]: 2026-01-23T18:56:37.658030Z INFO ExtHandler ExtHandler Jan 23 18:56:37.658142 waagent[2198]: 2026-01-23T18:56:37.658111Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_2 channel: WireServer source: Fabric activity: 20cd3c74-3da7-4086-8ce2-ccd7e47507d5 correlation a5bbf82b-f3f9-49be-a41f-ba736dd5f26f created: 2026-01-23T18:56:32.174896Z] Jan 23 18:56:37.658458 waagent[2198]: 2026-01-23T18:56:37.658419Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Jan 23 18:56:37.658998 waagent[2198]: 2026-01-23T18:56:37.658963Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_2 0 ms] Jan 23 18:56:41.554420 systemd[1]: Started sshd@19-10.200.4.29:22-10.200.16.10:46484.service - OpenSSH per-connection server daemon (10.200.16.10:46484). Jan 23 18:56:42.188426 sshd[15456]: Accepted publickey for core from 10.200.16.10 port 46484 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:56:42.189682 sshd-session[15456]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:56:42.193843 systemd-logind[1876]: New session 22 of user core. Jan 23 18:56:42.212602 systemd[1]: Started session-22.scope - Session 22 of User core. Jan 23 18:56:42.626134 sshd[15459]: Connection closed by 10.200.16.10 port 46484 Jan 23 18:56:42.626424 sshd-session[15456]: pam_unix(sshd:session): session closed for user core Jan 23 18:56:42.630000 systemd[1]: sshd@19-10.200.4.29:22-10.200.16.10:46484.service: Deactivated successfully. Jan 23 18:56:42.631894 systemd[1]: session-22.scope: Deactivated successfully. Jan 23 18:56:42.632882 systemd-logind[1876]: Session 22 logged out. Waiting for processes to exit. Jan 23 18:56:42.633772 systemd-logind[1876]: Removed session 22. Jan 23 18:56:42.735176 systemd[1]: Started sshd@20-10.200.4.29:22-10.200.16.10:46494.service - OpenSSH per-connection server daemon (10.200.16.10:46494). Jan 23 18:56:43.368663 sshd[15465]: Accepted publickey for core from 10.200.16.10 port 46494 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:56:43.369865 sshd-session[15465]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:56:43.374093 systemd-logind[1876]: New session 23 of user core. Jan 23 18:56:43.386625 systemd[1]: Started session-23.scope - Session 23 of User core. Jan 23 18:56:43.680897 waagent[2198]: 2026-01-23T18:56:43.680845Z INFO ExtHandler Jan 23 18:56:43.681277 waagent[2198]: 2026-01-23T18:56:43.680969Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 3f82ec77-c853-424c-b7cf-2c2b049acefd eTag: 15509697629973500949 source: Fabric] Jan 23 18:56:43.681380 waagent[2198]: 2026-01-23T18:56:43.681340Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Jan 23 18:56:43.804153 sshd[15468]: Connection closed by 10.200.16.10 port 46494 Jan 23 18:56:43.804534 sshd-session[15465]: pam_unix(sshd:session): session closed for user core Jan 23 18:56:43.808022 systemd[1]: sshd@20-10.200.4.29:22-10.200.16.10:46494.service: Deactivated successfully. Jan 23 18:56:43.809682 systemd[1]: session-23.scope: Deactivated successfully. Jan 23 18:56:43.810344 systemd-logind[1876]: Session 23 logged out. Waiting for processes to exit. Jan 23 18:56:43.811639 systemd-logind[1876]: Removed session 23. Jan 23 18:56:52.847509 kernel: EXT4-fs (loop6p9): unmounting filesystem 94bea6f2-4f04-4451-9ae2-f9d206317999. Jan 23 18:56:52.848034 setup-nvidia[2395]: Container flatcardevelopercontainer-4459.2.3.bin exited successfully. Jan 23 18:56:52.850500 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4459.2.3.bin.mount: Deactivated successfully. Jan 23 18:56:52.908320 sudo[2383]: pam_unix(sudo:session): session closed for user root Jan 23 18:56:52.910279 systemd[1]: session-c2.scope: Deactivated successfully. Jan 23 18:56:52.910575 systemd[1]: session-c2.scope: Consumed 5min 20.463s CPU time, 889.4M memory peak. Jan 23 18:56:53.340641 setup-nvidia[1872]: /opt/nvidia / Jan 23 18:56:53.341635 setup-nvidia[1872]: / Jan 23 18:56:53.351508 kernel: IPMI message handler: version 39.2 Jan 23 18:56:53.354506 kernel: ipmi device interface Jan 23 18:56:53.367336 setup-nvidia[1872]: /opt/nvidia/current/usr/lib/modules/6.12.66-flatcar/video / Jan 23 18:56:53.425921 kernel: nvidia: loading out-of-tree module taints kernel. Jan 23 18:56:53.426061 kernel: nvidia: module license 'NVIDIA' taints kernel. Jan 23 18:56:53.426084 kernel: Disabling lock debugging due to kernel taint Jan 23 18:56:53.433331 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Jan 23 18:56:53.433374 kernel: nvidia: module license taints kernel. Jan 23 18:56:53.827516 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 Jan 23 18:56:54.244811 kernel: Jan 23 18:56:54.244950 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Jan 23 18:56:54.270277 systemd[1]: Started sshd@21-10.200.4.29:22-10.200.16.10:56994.service - OpenSSH per-connection server daemon (10.200.16.10:56994). Jan 23 18:56:54.303502 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.261.03 Sat Jun 14 16:05:50 UTC 2025 Jan 23 18:56:54.324506 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.261.03 Sat Jun 14 15:39:16 UTC 2025 Jan 23 18:56:54.346520 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Jan 23 18:56:54.396061 setup-nvidia[1872]: / Jan 23 18:56:54.396506 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. Jan 23 18:56:54.411996 setup-nvidia[15512]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jan 23 18:56:54.886156 sshd[15502]: Accepted publickey for core from 10.200.16.10 port 56994 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:56:54.887380 sshd-session[15502]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:56:54.891568 systemd-logind[1876]: New session 24 of user core. Jan 23 18:56:54.911608 systemd[1]: Started session-24.scope - Session 24 of User core. Jan 23 18:56:55.321163 sshd[15533]: Connection closed by 10.200.16.10 port 56994 Jan 23 18:56:55.321602 sshd-session[15502]: pam_unix(sshd:session): session closed for user core Jan 23 18:56:55.325304 systemd[1]: sshd@21-10.200.4.29:22-10.200.16.10:56994.service: Deactivated successfully. Jan 23 18:56:55.326885 systemd[1]: session-24.scope: Deactivated successfully. Jan 23 18:56:55.327676 systemd-logind[1876]: Session 24 logged out. Waiting for processes to exit. Jan 23 18:56:55.328755 systemd-logind[1876]: Removed session 24. Jan 23 18:56:55.430928 systemd[1]: Started sshd@22-10.200.4.29:22-10.200.16.10:56996.service - OpenSSH per-connection server daemon (10.200.16.10:56996). Jan 23 18:56:56.010866 setup-nvidia[15529]: Fri Jan 23 18:56:56 2026 Jan 23 18:56:56.010866 setup-nvidia[15529]: +---------------------------------------------------------------------------------------+ Jan 23 18:56:56.010866 setup-nvidia[15529]: | NVIDIA-SMI 535.261.03 Driver Version: 535.261.03 CUDA Version: 12.2 | Jan 23 18:56:56.010866 setup-nvidia[15529]: |-----------------------------------------+----------------------+----------------------+ Jan 23 18:56:56.010866 setup-nvidia[15529]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Jan 23 18:56:56.010866 setup-nvidia[15529]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Jan 23 18:56:56.010866 setup-nvidia[15529]: | | | MIG M. | Jan 23 18:56:56.010866 setup-nvidia[15529]: |=========================================+======================+======================| Jan 23 18:56:56.016970 setup-nvidia[15529]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Jan 23 18:56:56.016970 setup-nvidia[15529]: | N/A 29C P0 34W / 250W | 0MiB / 16384MiB | 1% Default | Jan 23 18:56:56.016970 setup-nvidia[15529]: | | | N/A | Jan 23 18:56:56.016970 setup-nvidia[15529]: +-----------------------------------------+----------------------+----------------------+ Jan 23 18:56:56.017083 setup-nvidia[15529]: Jan 23 18:56:56.017083 setup-nvidia[15529]: +---------------------------------------------------------------------------------------+ Jan 23 18:56:56.017083 setup-nvidia[15529]: | Processes: | Jan 23 18:56:56.017083 setup-nvidia[15529]: | GPU GI CI PID Type Process name GPU Memory | Jan 23 18:56:56.017083 setup-nvidia[15529]: | ID ID Usage | Jan 23 18:56:56.017083 setup-nvidia[15529]: |=======================================================================================| Jan 23 18:56:56.017223 setup-nvidia[15529]: | No running processes found | Jan 23 18:56:56.017223 setup-nvidia[15529]: +---------------------------------------------------------------------------------------+ Jan 23 18:56:56.077281 sshd[15539]: Accepted publickey for core from 10.200.16.10 port 56996 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:56:56.078570 sshd-session[15539]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:56:56.082757 systemd-logind[1876]: New session 25 of user core. Jan 23 18:56:56.105621 systemd[1]: Started session-25.scope - Session 25 of User core. Jan 23 18:56:56.474641 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Jan 23 18:56:56.476641 systemd[1]: Reached target multi-user.target - Multi-User System. Jan 23 18:56:56.476969 systemd[1]: Startup finished in 847ms (firmware) + 19.502s (loader) + 3.702s (kernel) + 13.367s (initrd) + 2min 54.336s (userspace) = 3min 31.755s. Jan 23 18:56:56.515930 sshd[15542]: Connection closed by 10.200.16.10 port 56996 Jan 23 18:56:56.516271 sshd-session[15539]: pam_unix(sshd:session): session closed for user core Jan 23 18:56:56.519551 systemd[1]: sshd@22-10.200.4.29:22-10.200.16.10:56996.service: Deactivated successfully. Jan 23 18:56:56.521100 systemd[1]: session-25.scope: Deactivated successfully. Jan 23 18:56:56.521819 systemd-logind[1876]: Session 25 logged out. Waiting for processes to exit. Jan 23 18:56:56.522714 systemd-logind[1876]: Removed session 25. Jan 23 18:57:03.154443 systemd[1]: Stopping user@0.service - User Manager for UID 0... Jan 23 18:57:03.155219 systemd[2386]: Activating special unit exit.target... Jan 23 18:57:03.155303 systemd[2386]: Stopped target default.target - Main User Target. Jan 23 18:57:03.155344 systemd[2386]: Stopped target basic.target - Basic System. Jan 23 18:57:03.155373 systemd[2386]: Stopped target paths.target - Paths. Jan 23 18:57:03.155399 systemd[2386]: Stopped target sockets.target - Sockets. Jan 23 18:57:03.155424 systemd[2386]: Stopped target timers.target - Timers. Jan 23 18:57:03.155779 systemd[2386]: Closed dbus.socket - D-Bus User Message Bus Socket. Jan 23 18:57:03.155990 systemd[2386]: Removed slice app.slice - User Application Slice. Jan 23 18:57:03.156022 systemd[2386]: Reached target shutdown.target - Shutdown. Jan 23 18:57:03.156164 systemd[2386]: Finished systemd-exit.service - Exit the Session. Jan 23 18:57:03.156199 systemd[2386]: Reached target exit.target - Exit the Session. Jan 23 18:57:03.173541 (sd-pam)[2387]: pam_unix(systemd-user:session): session closed for user root Jan 23 18:57:03.174816 systemd[1]: user@0.service: Deactivated successfully. Jan 23 18:57:03.175178 systemd[1]: Stopped user@0.service - User Manager for UID 0. Jan 23 18:57:03.177425 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Jan 23 18:57:03.215063 systemd[1]: run-user-0.mount: Deactivated successfully. Jan 23 18:57:03.215975 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Jan 23 18:57:03.216172 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Jan 23 18:57:03.217815 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Jan 23 18:57:03.217903 systemd[1]: user-0.slice: Consumed 5min 20.766s CPU time, 895.4M memory peak. Jan 23 18:57:03.218295 systemd-logind[1876]: Removed session c3. Jan 23 18:57:06.630498 systemd[1]: Started sshd@23-10.200.4.29:22-10.200.16.10:34022.service - OpenSSH per-connection server daemon (10.200.16.10:34022). Jan 23 18:57:07.272076 sshd[15554]: Accepted publickey for core from 10.200.16.10 port 34022 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:57:07.273253 sshd-session[15554]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:57:07.277360 systemd-logind[1876]: New session 26 of user core. Jan 23 18:57:07.287600 systemd[1]: Started session-26.scope - Session 26 of User core. Jan 23 18:57:07.712344 sshd[15557]: Connection closed by 10.200.16.10 port 34022 Jan 23 18:57:07.712723 sshd-session[15554]: pam_unix(sshd:session): session closed for user core Jan 23 18:57:07.716338 systemd[1]: sshd@23-10.200.4.29:22-10.200.16.10:34022.service: Deactivated successfully. Jan 23 18:57:07.718146 systemd[1]: session-26.scope: Deactivated successfully. Jan 23 18:57:07.719000 systemd-logind[1876]: Session 26 logged out. Waiting for processes to exit. Jan 23 18:57:07.719955 systemd-logind[1876]: Removed session 26. Jan 23 18:57:07.834986 systemd[1]: Started sshd@24-10.200.4.29:22-10.200.16.10:34028.service - OpenSSH per-connection server daemon (10.200.16.10:34028). Jan 23 18:57:08.486597 sshd[15563]: Accepted publickey for core from 10.200.16.10 port 34028 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:57:08.487859 sshd-session[15563]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:57:08.492117 systemd-logind[1876]: New session 27 of user core. Jan 23 18:57:08.508615 systemd[1]: Started session-27.scope - Session 27 of User core. Jan 23 18:57:08.921225 sshd[15566]: Connection closed by 10.200.16.10 port 34028 Jan 23 18:57:08.921601 sshd-session[15563]: pam_unix(sshd:session): session closed for user core Jan 23 18:57:08.924991 systemd[1]: sshd@24-10.200.4.29:22-10.200.16.10:34028.service: Deactivated successfully. Jan 23 18:57:08.926665 systemd[1]: session-27.scope: Deactivated successfully. Jan 23 18:57:08.927350 systemd-logind[1876]: Session 27 logged out. Waiting for processes to exit. Jan 23 18:57:08.928259 systemd-logind[1876]: Removed session 27. Jan 23 18:57:09.029817 systemd[1]: Started sshd@25-10.200.4.29:22-10.200.16.10:34042.service - OpenSSH per-connection server daemon (10.200.16.10:34042). Jan 23 18:57:09.665646 sshd[15572]: Accepted publickey for core from 10.200.16.10 port 34042 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:57:09.666878 sshd-session[15572]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:57:09.673695 systemd-logind[1876]: New session 28 of user core. Jan 23 18:57:09.688607 systemd[1]: Started session-28.scope - Session 28 of User core. Jan 23 18:57:10.102845 sshd[15575]: Connection closed by 10.200.16.10 port 34042 Jan 23 18:57:10.103137 sshd-session[15572]: pam_unix(sshd:session): session closed for user core Jan 23 18:57:10.107058 systemd[1]: sshd@25-10.200.4.29:22-10.200.16.10:34042.service: Deactivated successfully. Jan 23 18:57:10.108738 systemd[1]: session-28.scope: Deactivated successfully. Jan 23 18:57:10.109468 systemd-logind[1876]: Session 28 logged out. Waiting for processes to exit. Jan 23 18:57:10.110369 systemd-logind[1876]: Removed session 28. Jan 23 18:57:10.216867 systemd[1]: Started sshd@26-10.200.4.29:22-10.200.16.10:36454.service - OpenSSH per-connection server daemon (10.200.16.10:36454). Jan 23 18:57:10.857650 sshd[15581]: Accepted publickey for core from 10.200.16.10 port 36454 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:57:10.858879 sshd-session[15581]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:57:10.863073 systemd-logind[1876]: New session 29 of user core. Jan 23 18:57:10.878600 systemd[1]: Started session-29.scope - Session 29 of User core. Jan 23 18:57:11.229604 sudo[15586]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Jan 23 18:57:11.229911 sudo[15586]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jan 23 18:57:11.278260 sudo[15586]: pam_unix(sudo:session): session closed for user root Jan 23 18:57:11.376799 sshd[15585]: Connection closed by 10.200.16.10 port 36454 Jan 23 18:57:11.377248 sshd-session[15581]: pam_unix(sshd:session): session closed for user core Jan 23 18:57:11.381059 systemd[1]: sshd@26-10.200.4.29:22-10.200.16.10:36454.service: Deactivated successfully. Jan 23 18:57:11.382708 systemd[1]: session-29.scope: Deactivated successfully. Jan 23 18:57:11.383431 systemd-logind[1876]: Session 29 logged out. Waiting for processes to exit. Jan 23 18:57:11.384523 systemd-logind[1876]: Removed session 29. Jan 23 18:57:11.493084 systemd[1]: Started sshd@27-10.200.4.29:22-10.200.16.10:36462.service - OpenSSH per-connection server daemon (10.200.16.10:36462). Jan 23 18:57:12.109021 sshd[15592]: Accepted publickey for core from 10.200.16.10 port 36462 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:57:12.110405 sshd-session[15592]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:57:12.114760 systemd-logind[1876]: New session 30 of user core. Jan 23 18:57:12.125598 systemd[1]: Started session-30.scope - Session 30 of User core. Jan 23 18:57:12.449074 sudo[15599]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Jan 23 18:57:12.449374 sudo[15599]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jan 23 18:57:12.456069 sudo[15599]: pam_unix(sudo:session): session closed for user root Jan 23 18:57:12.461541 sudo[15598]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Jan 23 18:57:12.461815 sudo[15598]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jan 23 18:57:12.470959 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jan 23 18:57:12.515036 augenrules[15621]: No rules Jan 23 18:57:12.516259 systemd[1]: audit-rules.service: Deactivated successfully. Jan 23 18:57:12.516558 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jan 23 18:57:12.517391 sudo[15598]: pam_unix(sudo:session): session closed for user root Jan 23 18:57:12.615258 sshd[15597]: Connection closed by 10.200.16.10 port 36462 Jan 23 18:57:12.615642 sshd-session[15592]: pam_unix(sshd:session): session closed for user core Jan 23 18:57:12.619306 systemd[1]: sshd@27-10.200.4.29:22-10.200.16.10:36462.service: Deactivated successfully. Jan 23 18:57:12.621007 systemd[1]: session-30.scope: Deactivated successfully. Jan 23 18:57:12.621672 systemd-logind[1876]: Session 30 logged out. Waiting for processes to exit. Jan 23 18:57:12.622564 systemd-logind[1876]: Removed session 30. Jan 23 18:57:12.729955 systemd[1]: Started sshd@28-10.200.4.29:22-10.200.16.10:36478.service - OpenSSH per-connection server daemon (10.200.16.10:36478). Jan 23 18:57:13.373269 sshd[15630]: Accepted publickey for core from 10.200.16.10 port 36478 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:57:13.374528 sshd-session[15630]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:57:13.378697 systemd-logind[1876]: New session 31 of user core. Jan 23 18:57:13.391622 systemd[1]: Started session-31.scope - Session 31 of User core. Jan 23 18:57:13.715320 sudo[15634]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/mkdir -p . Jan 23 18:57:13.715633 sudo[15634]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jan 23 18:57:13.719101 sudo[15634]: pam_unix(sudo:session): session closed for user root Jan 23 18:57:13.817816 sshd[15633]: Connection closed by 10.200.16.10 port 36478 Jan 23 18:57:13.818156 sshd-session[15630]: pam_unix(sshd:session): session closed for user core Jan 23 18:57:13.821401 systemd[1]: sshd@28-10.200.4.29:22-10.200.16.10:36478.service: Deactivated successfully. Jan 23 18:57:13.822986 systemd[1]: session-31.scope: Deactivated successfully. Jan 23 18:57:13.823727 systemd-logind[1876]: Session 31 logged out. Waiting for processes to exit. Jan 23 18:57:13.824625 systemd-logind[1876]: Removed session 31. Jan 23 18:57:13.930718 systemd[1]: Started sshd@29-10.200.4.29:22-10.200.16.10:36494.service - OpenSSH per-connection server daemon (10.200.16.10:36494). Jan 23 18:57:14.568445 sshd[15640]: Accepted publickey for core from 10.200.16.10 port 36494 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:57:14.569730 sshd-session[15640]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:57:14.573982 systemd-logind[1876]: New session 32 of user core. Jan 23 18:57:14.594606 systemd[1]: Started session-32.scope - Session 32 of User core. Jan 23 18:57:14.909128 sudo[15644]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/install -m 0755 /dev/stdin kolet Jan 23 18:57:14.909418 sudo[15644]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jan 23 18:57:18.232605 sudo[15644]: pam_unix(sudo:session): session closed for user root Jan 23 18:57:18.330541 sshd[15643]: Connection closed by 10.200.16.10 port 36494 Jan 23 18:57:18.330796 sshd-session[15640]: pam_unix(sshd:session): session closed for user core Jan 23 18:57:18.334321 systemd[1]: sshd@29-10.200.4.29:22-10.200.16.10:36494.service: Deactivated successfully. Jan 23 18:57:18.335949 systemd[1]: session-32.scope: Deactivated successfully. Jan 23 18:57:18.336643 systemd-logind[1876]: Session 32 logged out. Waiting for processes to exit. Jan 23 18:57:18.337580 systemd-logind[1876]: Removed session 32. Jan 23 18:57:18.444770 systemd[1]: Started sshd@30-10.200.4.29:22-10.200.16.10:36502.service - OpenSSH per-connection server daemon (10.200.16.10:36502). Jan 23 18:57:19.065371 sshd[15650]: Accepted publickey for core from 10.200.16.10 port 36502 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:57:19.066662 sshd-session[15650]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:57:19.070787 systemd-logind[1876]: New session 33 of user core. Jan 23 18:57:19.087600 systemd[1]: Started session-33.scope - Session 33 of User core. Jan 23 18:57:19.584384 sshd[15653]: Connection closed by 10.200.16.10 port 36502 Jan 23 18:57:19.584773 sshd-session[15650]: pam_unix(sshd:session): session closed for user core Jan 23 18:57:19.588179 systemd[1]: sshd@30-10.200.4.29:22-10.200.16.10:36502.service: Deactivated successfully. Jan 23 18:57:19.589766 systemd[1]: session-33.scope: Deactivated successfully. Jan 23 18:57:19.590429 systemd-logind[1876]: Session 33 logged out. Waiting for processes to exit. Jan 23 18:57:19.591328 systemd-logind[1876]: Removed session 33. Jan 23 18:57:19.697583 systemd[1]: Started sshd@31-10.200.4.29:22-10.200.16.10:37330.service - OpenSSH per-connection server daemon (10.200.16.10:37330). Jan 23 18:57:20.317075 sshd[15670]: Accepted publickey for core from 10.200.16.10 port 37330 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:57:20.318315 sshd-session[15670]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:57:20.322476 systemd-logind[1876]: New session 34 of user core. Jan 23 18:57:20.337645 systemd[1]: Started session-34.scope - Session 34 of User core. Jan 23 18:57:21.812513 systemd[1]: Starting docker.service - Docker Application Container Engine... Jan 23 18:57:21.840871 (dockerd)[15696]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPTS, DOCKER_OPT_BIP, DOCKER_OPT_IPMASQ, DOCKER_OPT_MTU Jan 23 18:57:24.059005 dockerd[15696]: time="2026-01-23T18:57:24.058942656Z" level=info msg="Starting up" Jan 23 18:57:24.059619 dockerd[15696]: time="2026-01-23T18:57:24.059595736Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Jan 23 18:57:24.070841 dockerd[15696]: time="2026-01-23T18:57:24.070795998Z" level=info msg="Creating a containerd client" address=/var/run/docker/libcontainerd/docker-containerd.sock timeout=1m0s Jan 23 18:57:24.554999 dockerd[15696]: time="2026-01-23T18:57:24.554955087Z" level=info msg="Loading containers: start." Jan 23 18:57:24.570497 kernel: Initializing XFRM netlink socket Jan 23 18:57:24.847926 systemd-networkd[1548]: docker0: Link UP Jan 23 18:57:24.868967 dockerd[15696]: time="2026-01-23T18:57:24.868924712Z" level=info msg="Loading containers: done." Jan 23 18:57:24.882239 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck2782494869-merged.mount: Deactivated successfully. Jan 23 18:57:24.893775 dockerd[15696]: time="2026-01-23T18:57:24.893697964Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2 Jan 23 18:57:24.893915 dockerd[15696]: time="2026-01-23T18:57:24.893879759Z" level=info msg="Docker daemon" commit=6430e49a55babd9b8f4d08e70ecb2b68900770fe containerd-snapshotter=false storage-driver=overlay2 version=28.0.4 Jan 23 18:57:24.894019 dockerd[15696]: time="2026-01-23T18:57:24.893997855Z" level=info msg="Initializing buildkit" Jan 23 18:57:24.942495 dockerd[15696]: time="2026-01-23T18:57:24.942454793Z" level=info msg="Completed buildkit initialization" Jan 23 18:57:24.948292 dockerd[15696]: time="2026-01-23T18:57:24.948262417Z" level=info msg="Daemon has completed initialization" Jan 23 18:57:24.948374 dockerd[15696]: time="2026-01-23T18:57:24.948329115Z" level=info msg="API listen on /run/docker.sock" Jan 23 18:57:24.948526 systemd[1]: Started docker.service - Docker Application Container Engine. Jan 23 18:57:25.981414 systemd[1]: var-lib-docker-overlay2-3d3ecac69281b319e6dd716a8131c559f6e328b4b0c1e645fcc0f59bff033645\x2dinit-merged.mount: Deactivated successfully. Jan 23 18:57:26.111852 containerd[1918]: time="2026-01-23T18:57:26.111805903Z" level=info msg="connecting to shim cee4f31dc3dd48df57987da896d1d6b4a60c9a2d16a4a8062bc5c069dc8613d7" address="unix:///run/containerd/s/751a7bedfde57fa393bafe173554864280ea489f6d3616536b4c9783747c102c" namespace=moby protocol=ttrpc version=3 Jan 23 18:57:26.141649 systemd[1]: Started docker-cee4f31dc3dd48df57987da896d1d6b4a60c9a2d16a4a8062bc5c069dc8613d7.scope - libcontainer container cee4f31dc3dd48df57987da896d1d6b4a60c9a2d16a4a8062bc5c069dc8613d7. Jan 23 18:57:26.190051 kernel: docker0: port 1(veth17f03bc) entered blocking state Jan 23 18:57:26.190139 kernel: docker0: port 1(veth17f03bc) entered disabled state Jan 23 18:57:26.190162 kernel: veth17f03bc: entered allmulticast mode Jan 23 18:57:26.195046 kernel: veth17f03bc: entered promiscuous mode Jan 23 18:57:26.195529 systemd-networkd[1548]: veth17f03bc: Link UP Jan 23 18:57:26.225500 kernel: eth0: renamed from veth023fe48 Jan 23 18:57:26.232507 kernel: docker0: port 1(veth17f03bc) entered blocking state Jan 23 18:57:26.232569 kernel: docker0: port 1(veth17f03bc) entered forwarding state Jan 23 18:57:26.232879 systemd-networkd[1548]: veth17f03bc: Gained carrier Jan 23 18:57:26.233113 systemd-networkd[1548]: docker0: Gained carrier Jan 23 18:57:27.832653 systemd-networkd[1548]: veth17f03bc: Gained IPv6LL Jan 23 18:57:28.088657 systemd-networkd[1548]: docker0: Gained IPv6LL Jan 23 18:57:29.482540 systemd[1]: docker-cee4f31dc3dd48df57987da896d1d6b4a60c9a2d16a4a8062bc5c069dc8613d7.scope: Deactivated successfully. Jan 23 18:57:29.495409 containerd[1918]: time="2026-01-23T18:57:29.495371566Z" level=info msg="shim disconnected" id=cee4f31dc3dd48df57987da896d1d6b4a60c9a2d16a4a8062bc5c069dc8613d7 namespace=moby Jan 23 18:57:29.495409 containerd[1918]: time="2026-01-23T18:57:29.495402165Z" level=warning msg="cleaning up after shim disconnected" id=cee4f31dc3dd48df57987da896d1d6b4a60c9a2d16a4a8062bc5c069dc8613d7 namespace=moby Jan 23 18:57:29.495725 containerd[1918]: time="2026-01-23T18:57:29.495410464Z" level=info msg="cleaning up dead shim" namespace=moby Jan 23 18:57:29.495756 dockerd[15696]: time="2026-01-23T18:57:29.495431864Z" level=info msg="ignoring event" container=cee4f31dc3dd48df57987da896d1d6b4a60c9a2d16a4a8062bc5c069dc8613d7 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Jan 23 18:57:29.525244 systemd-networkd[1548]: veth17f03bc: Lost carrier Jan 23 18:57:29.525552 kernel: docker0: port 1(veth17f03bc) entered disabled state Jan 23 18:57:29.525587 kernel: veth023fe48: renamed from eth0 Jan 23 18:57:29.554364 systemd-networkd[1548]: veth17f03bc: Link DOWN Jan 23 18:57:29.561591 kernel: docker0: port 1(veth17f03bc) entered disabled state Jan 23 18:57:29.561643 kernel: veth17f03bc (unregistering): left allmulticast mode Jan 23 18:57:29.564917 kernel: veth17f03bc (unregistering): left promiscuous mode Jan 23 18:57:29.568589 kernel: docker0: port 1(veth17f03bc) entered disabled state Jan 23 18:57:29.590572 systemd[1]: run-docker-netns-ac7102570eaa.mount: Deactivated successfully. Jan 23 18:57:29.593941 systemd[1]: var-lib-docker-overlay2-3d3ecac69281b319e6dd716a8131c559f6e328b4b0c1e645fcc0f59bff033645-merged.mount: Deactivated successfully. Jan 23 18:57:29.722144 sshd[15673]: Connection closed by 10.200.16.10 port 37330 Jan 23 18:57:29.722584 sshd-session[15670]: pam_unix(sshd:session): session closed for user core Jan 23 18:57:29.726298 systemd[1]: sshd@31-10.200.4.29:22-10.200.16.10:37330.service: Deactivated successfully. Jan 23 18:57:29.728329 systemd[1]: session-34.scope: Deactivated successfully. Jan 23 18:57:29.729129 systemd-logind[1876]: Session 34 logged out. Waiting for processes to exit. Jan 23 18:57:29.730137 systemd-logind[1876]: Removed session 34. Jan 23 18:57:29.835085 systemd[1]: Started sshd@32-10.200.4.29:22-10.200.16.10:55794.service - OpenSSH per-connection server daemon (10.200.16.10:55794). Jan 23 18:57:30.584733 systemd-networkd[1548]: docker0: Lost carrier Jan 23 18:57:30.629449 sshd[15988]: Accepted publickey for core from 10.200.16.10 port 55794 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:57:30.630690 sshd-session[15988]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:57:30.634795 systemd-logind[1876]: New session 35 of user core. Jan 23 18:57:30.654620 systemd[1]: Started session-35.scope - Session 35 of User core. Jan 23 18:57:31.143317 systemd[1]: var-lib-docker-overlay2-479710748c9fb040a57efd15b0622c6d42c4be3ec716f063407ca905df3c1a16\x2dinit-merged.mount: Deactivated successfully. Jan 23 18:57:31.238625 containerd[1918]: time="2026-01-23T18:57:31.238575791Z" level=info msg="connecting to shim 2393f395afa9c147c95dae9f8b8f354e504396f8985a89f3f62b96901bf19243" address="unix:///run/containerd/s/2eddfa294b9549d6ff4646ef5d8f03bf7391406337baa9a694a479f9d7c3df01" namespace=moby protocol=ttrpc version=3 Jan 23 18:57:31.270678 systemd[1]: Started docker-2393f395afa9c147c95dae9f8b8f354e504396f8985a89f3f62b96901bf19243.scope - libcontainer container 2393f395afa9c147c95dae9f8b8f354e504396f8985a89f3f62b96901bf19243. Jan 23 18:57:31.315434 kernel: docker0: port 1(vethe92c859) entered blocking state Jan 23 18:57:31.315541 kernel: docker0: port 1(vethe92c859) entered disabled state Jan 23 18:57:31.318381 kernel: vethe92c859: entered allmulticast mode Jan 23 18:57:31.321285 kernel: vethe92c859: entered promiscuous mode Jan 23 18:57:31.321838 systemd-networkd[1548]: vethe92c859: Link UP Jan 23 18:57:31.347510 kernel: eth0: renamed from veth08f1d70 Jan 23 18:57:31.347602 kernel: docker0: port 1(vethe92c859) entered blocking state Jan 23 18:57:31.353865 kernel: docker0: port 1(vethe92c859) entered forwarding state Jan 23 18:57:31.354299 systemd-networkd[1548]: vethe92c859: Gained carrier Jan 23 18:57:31.354596 systemd-networkd[1548]: docker0: Gained carrier Jan 23 18:57:31.385675 systemd[1]: docker-2393f395afa9c147c95dae9f8b8f354e504396f8985a89f3f62b96901bf19243.scope: Deactivated successfully. Jan 23 18:57:31.403521 dockerd[15696]: time="2026-01-23T18:57:31.403450662Z" level=info msg="ignoring event" container=2393f395afa9c147c95dae9f8b8f354e504396f8985a89f3f62b96901bf19243 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Jan 23 18:57:31.403843 containerd[1918]: time="2026-01-23T18:57:31.403592358Z" level=info msg="shim disconnected" id=2393f395afa9c147c95dae9f8b8f354e504396f8985a89f3f62b96901bf19243 namespace=moby Jan 23 18:57:31.403843 containerd[1918]: time="2026-01-23T18:57:31.403619357Z" level=warning msg="cleaning up after shim disconnected" id=2393f395afa9c147c95dae9f8b8f354e504396f8985a89f3f62b96901bf19243 namespace=moby Jan 23 18:57:31.403843 containerd[1918]: time="2026-01-23T18:57:31.403627557Z" level=info msg="cleaning up dead shim" namespace=moby Jan 23 18:57:31.430862 systemd-networkd[1548]: vethe92c859: Lost carrier Jan 23 18:57:31.431499 kernel: docker0: port 1(vethe92c859) entered disabled state Jan 23 18:57:31.431541 kernel: veth08f1d70: renamed from eth0 Jan 23 18:57:31.460719 systemd-networkd[1548]: vethe92c859: Link DOWN Jan 23 18:57:31.464501 kernel: docker0: port 1(vethe92c859) entered disabled state Jan 23 18:57:31.471019 kernel: vethe92c859 (unregistering): left allmulticast mode Jan 23 18:57:31.471070 kernel: vethe92c859 (unregistering): left promiscuous mode Jan 23 18:57:31.474384 kernel: docker0: port 1(vethe92c859) entered disabled state Jan 23 18:57:31.592780 systemd-networkd[1548]: docker0: Lost carrier Jan 23 18:57:31.615398 sshd[15991]: Connection closed by 10.200.16.10 port 55794 Jan 23 18:57:31.615844 sshd-session[15988]: pam_unix(sshd:session): session closed for user core Jan 23 18:57:31.619507 systemd[1]: sshd@32-10.200.4.29:22-10.200.16.10:55794.service: Deactivated successfully. Jan 23 18:57:31.621152 systemd[1]: session-35.scope: Deactivated successfully. Jan 23 18:57:31.621950 systemd-logind[1876]: Session 35 logged out. Waiting for processes to exit. Jan 23 18:57:31.623556 systemd-logind[1876]: Removed session 35. Jan 23 18:57:31.734051 systemd[1]: Started sshd@33-10.200.4.29:22-10.200.16.10:55808.service - OpenSSH per-connection server daemon (10.200.16.10:55808). Jan 23 18:57:32.140046 systemd[1]: var-lib-docker-overlay2-479710748c9fb040a57efd15b0622c6d42c4be3ec716f063407ca905df3c1a16-merged.mount: Deactivated successfully. Jan 23 18:57:32.361865 sshd[16094]: Accepted publickey for core from 10.200.16.10 port 55808 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:57:32.363132 sshd-session[16094]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:57:32.367296 systemd-logind[1876]: New session 36 of user core. Jan 23 18:57:32.387626 systemd[1]: Started session-36.scope - Session 36 of User core. Jan 23 18:57:32.751311 ntpdate[16107]: ntpdate 4.2.8p18@1.4062-o Fri Jan 23 15:26:40 UTC 2026 (1) Jan 23 18:57:39.459135 ntpdate[16107]: adjust time server 172.237.96.114 offset -0.001619 sec Jan 23 18:57:39.557391 sshd[16097]: Connection closed by 10.200.16.10 port 55808 Jan 23 18:57:39.557800 sshd-session[16094]: pam_unix(sshd:session): session closed for user core Jan 23 18:57:39.561316 systemd[1]: sshd@33-10.200.4.29:22-10.200.16.10:55808.service: Deactivated successfully. Jan 23 18:57:39.562949 systemd[1]: session-36.scope: Deactivated successfully. Jan 23 18:57:39.563664 systemd-logind[1876]: Session 36 logged out. Waiting for processes to exit. Jan 23 18:57:39.564605 systemd-logind[1876]: Removed session 36.