Sep 4 00:01:39.177764 kernel: Linux version 6.12.44-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.44 p1) 2.44.0) #1 SMP PREEMPT_DYNAMIC Wed Sep 3 22:05:39 -00 2025 Sep 4 00:01:39.177793 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=c7fa427551c105672074cbcbe7e23c997f471a6e879d708e8d6cbfad2147666e Sep 4 00:01:39.177803 kernel: BIOS-provided physical RAM map: Sep 4 00:01:39.177810 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Sep 4 00:01:39.177817 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Sep 4 00:01:39.177823 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Sep 4 00:01:39.177831 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Sep 4 00:01:39.177843 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Sep 4 00:01:39.177850 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Sep 4 00:01:39.177857 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Sep 4 00:01:39.177864 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Sep 4 00:01:39.177871 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Sep 4 00:01:39.177878 kernel: printk: legacy bootconsole [earlyser0] enabled Sep 4 00:01:39.177885 kernel: NX (Execute Disable) protection: active Sep 4 00:01:39.177896 kernel: APIC: Static calls initialized Sep 4 00:01:39.177904 kernel: efi: EFI v2.7 by Microsoft Sep 4 00:01:39.177912 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Sep 4 00:01:39.177920 kernel: random: crng init done Sep 4 00:01:39.177928 kernel: secureboot: Secure boot disabled Sep 4 00:01:39.177935 kernel: SMBIOS 3.1.0 present. Sep 4 00:01:39.177943 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Sep 4 00:01:39.177951 kernel: DMI: Memory slots populated: 3/3 Sep 4 00:01:39.177958 kernel: Hypervisor detected: Microsoft Hyper-V Sep 4 00:01:39.177966 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Sep 4 00:01:39.177976 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Sep 4 00:01:39.177984 kernel: Hyper-V: Using hypercall for remote TLB flush Sep 4 00:01:39.177992 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Sep 4 00:01:39.177999 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Sep 4 00:01:39.178008 kernel: tsc: Marking TSC unstable due to running on Hyper-V Sep 4 00:01:39.178016 kernel: tsc: Detected 2593.994 MHz processor Sep 4 00:01:39.178024 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Sep 4 00:01:39.178033 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Sep 4 00:01:39.178040 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Sep 4 00:01:39.178051 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Sep 4 00:01:39.178059 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Sep 4 00:01:39.178066 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Sep 4 00:01:39.178074 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Sep 4 00:01:39.178082 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Sep 4 00:01:39.178090 kernel: Using GB pages for direct mapping Sep 4 00:01:39.178098 kernel: ACPI: Early table checksum verification disabled Sep 4 00:01:39.178109 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Sep 4 00:01:39.178120 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 00:01:39.178128 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 00:01:39.178144 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Sep 4 00:01:39.178153 kernel: ACPI: FACS 0x000000003FFFE000 000040 Sep 4 00:01:39.178161 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 00:01:39.178170 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 00:01:39.178182 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 00:01:39.178190 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 00:01:39.178199 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 00:01:39.178207 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 00:01:39.178215 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 00:01:39.178224 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Sep 4 00:01:39.178232 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Sep 4 00:01:39.178240 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Sep 4 00:01:39.178251 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Sep 4 00:01:39.178259 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Sep 4 00:01:39.178268 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Sep 4 00:01:39.178276 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Sep 4 00:01:39.178284 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Sep 4 00:01:39.178293 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Sep 4 00:01:39.178301 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Sep 4 00:01:39.178309 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Sep 4 00:01:39.178317 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Sep 4 00:01:39.178328 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Sep 4 00:01:39.178336 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Sep 4 00:01:39.178345 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Sep 4 00:01:39.178353 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Sep 4 00:01:39.178361 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Sep 4 00:01:39.178370 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Sep 4 00:01:39.178378 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] Sep 4 00:01:39.178387 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] Sep 4 00:01:39.178395 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] Sep 4 00:01:39.178406 kernel: Zone ranges: Sep 4 00:01:39.178414 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Sep 4 00:01:39.178423 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Sep 4 00:01:39.178431 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Sep 4 00:01:39.178439 kernel: Device empty Sep 4 00:01:39.178448 kernel: Movable zone start for each node Sep 4 00:01:39.178554 kernel: Early memory node ranges Sep 4 00:01:39.178563 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Sep 4 00:01:39.178572 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Sep 4 00:01:39.178585 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Sep 4 00:01:39.178593 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Sep 4 00:01:39.178601 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Sep 4 00:01:39.178610 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Sep 4 00:01:39.178618 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Sep 4 00:01:39.178626 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Sep 4 00:01:39.178634 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Sep 4 00:01:39.178643 kernel: ACPI: PM-Timer IO Port: 0x408 Sep 4 00:01:39.178651 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Sep 4 00:01:39.178662 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Sep 4 00:01:39.178670 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Sep 4 00:01:39.178679 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Sep 4 00:01:39.178687 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Sep 4 00:01:39.178695 kernel: CPU topo: Max. logical packages: 1 Sep 4 00:01:39.178703 kernel: CPU topo: Max. logical dies: 1 Sep 4 00:01:39.178712 kernel: CPU topo: Max. dies per package: 1 Sep 4 00:01:39.178720 kernel: CPU topo: Max. threads per core: 1 Sep 4 00:01:39.178728 kernel: CPU topo: Num. cores per package: 6 Sep 4 00:01:39.178739 kernel: CPU topo: Num. threads per package: 6 Sep 4 00:01:39.178747 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs Sep 4 00:01:39.178755 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Sep 4 00:01:39.178763 kernel: Booting paravirtualized kernel on Hyper-V Sep 4 00:01:39.178772 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Sep 4 00:01:39.178780 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Sep 4 00:01:39.178789 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 Sep 4 00:01:39.178797 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 Sep 4 00:01:39.178805 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Sep 4 00:01:39.178816 kernel: Hyper-V: PV spinlocks enabled Sep 4 00:01:39.178824 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Sep 4 00:01:39.178834 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=c7fa427551c105672074cbcbe7e23c997f471a6e879d708e8d6cbfad2147666e Sep 4 00:01:39.178842 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Sep 4 00:01:39.178851 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Sep 4 00:01:39.178859 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Sep 4 00:01:39.178867 kernel: Fallback order for Node 0: 0 Sep 4 00:01:39.178876 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 Sep 4 00:01:39.178886 kernel: Policy zone: Normal Sep 4 00:01:39.178902 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 4 00:01:39.178911 kernel: software IO TLB: area num 8. Sep 4 00:01:39.178922 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Sep 4 00:01:39.178931 kernel: Kernel/User page tables isolation: enabled Sep 4 00:01:39.178940 kernel: ftrace: allocating 40099 entries in 157 pages Sep 4 00:01:39.178949 kernel: ftrace: allocated 157 pages with 5 groups Sep 4 00:01:39.178958 kernel: Dynamic Preempt: voluntary Sep 4 00:01:39.178966 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 4 00:01:39.178976 kernel: rcu: RCU event tracing is enabled. Sep 4 00:01:39.178985 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Sep 4 00:01:39.178994 kernel: Trampoline variant of Tasks RCU enabled. Sep 4 00:01:39.179005 kernel: Rude variant of Tasks RCU enabled. Sep 4 00:01:39.179014 kernel: Tracing variant of Tasks RCU enabled. Sep 4 00:01:39.179023 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 4 00:01:39.179031 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Sep 4 00:01:39.179040 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Sep 4 00:01:39.179051 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Sep 4 00:01:39.179060 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Sep 4 00:01:39.179069 kernel: Using NULL legacy PIC Sep 4 00:01:39.179078 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Sep 4 00:01:39.179087 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 4 00:01:39.179095 kernel: Console: colour dummy device 80x25 Sep 4 00:01:39.179104 kernel: printk: legacy console [tty1] enabled Sep 4 00:01:39.179113 kernel: printk: legacy console [ttyS0] enabled Sep 4 00:01:39.179122 kernel: printk: legacy bootconsole [earlyser0] disabled Sep 4 00:01:39.179133 kernel: ACPI: Core revision 20240827 Sep 4 00:01:39.179141 kernel: Failed to register legacy timer interrupt Sep 4 00:01:39.179150 kernel: APIC: Switch to symmetric I/O mode setup Sep 4 00:01:39.179159 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Sep 4 00:01:39.179168 kernel: Hyper-V: enabling crash_kexec_post_notifiers Sep 4 00:01:39.179176 kernel: Hyper-V: Using IPI hypercalls Sep 4 00:01:39.179185 kernel: APIC: send_IPI() replaced with hv_send_ipi() Sep 4 00:01:39.179194 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Sep 4 00:01:39.179202 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Sep 4 00:01:39.179214 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Sep 4 00:01:39.179222 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Sep 4 00:01:39.179231 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Sep 4 00:01:39.179240 kernel: Hyper-V: Using enlightened APIC (xapic mode) Sep 4 00:01:39.179248 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Sep 4 00:01:39.179257 kernel: APIC: read() replaced with hv_apic_read() Sep 4 00:01:39.179266 kernel: APIC: write() replaced with hv_apic_write() Sep 4 00:01:39.179275 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Sep 4 00:01:39.179283 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Sep 4 00:01:39.179294 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593994) Sep 4 00:01:39.179303 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Sep 4 00:01:39.179312 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Sep 4 00:01:39.179321 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Sep 4 00:01:39.179329 kernel: Spectre V2 : Mitigation: Retpolines Sep 4 00:01:39.179338 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Sep 4 00:01:39.179346 kernel: Speculative Store Bypass: Vulnerable Sep 4 00:01:39.179355 kernel: MDS: Mitigation: Clear CPU buffers Sep 4 00:01:39.179363 kernel: TAA: Mitigation: Clear CPU buffers Sep 4 00:01:39.179372 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Sep 4 00:01:39.179383 kernel: active return thunk: its_return_thunk Sep 4 00:01:39.179391 kernel: ITS: Mitigation: Aligned branch/return thunks Sep 4 00:01:39.179400 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Sep 4 00:01:39.179409 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Sep 4 00:01:39.179417 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Sep 4 00:01:39.179426 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Sep 4 00:01:39.179434 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Sep 4 00:01:39.179443 kernel: Freeing SMP alternatives memory: 32K Sep 4 00:01:39.179452 kernel: pid_max: default: 32768 minimum: 301 Sep 4 00:01:39.179460 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Sep 4 00:01:39.179469 kernel: landlock: Up and running. Sep 4 00:01:39.179487 kernel: SELinux: Initializing. Sep 4 00:01:39.179497 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Sep 4 00:01:39.179505 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Sep 4 00:01:39.179514 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Sep 4 00:01:39.179538 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Sep 4 00:01:39.179547 kernel: signal: max sigframe size: 1776 Sep 4 00:01:39.179556 kernel: rcu: Hierarchical SRCU implementation. Sep 4 00:01:39.179565 kernel: rcu: Max phase no-delay instances is 400. Sep 4 00:01:39.179574 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Sep 4 00:01:39.179582 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Sep 4 00:01:39.179595 kernel: smp: Bringing up secondary CPUs ... Sep 4 00:01:39.179604 kernel: smpboot: x86: Booting SMP configuration: Sep 4 00:01:39.179613 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Sep 4 00:01:39.179621 kernel: smp: Brought up 1 node, 6 CPUs Sep 4 00:01:39.179630 kernel: smpboot: Total of 6 processors activated (31127.92 BogoMIPS) Sep 4 00:01:39.179639 kernel: Memory: 115341148K/117439364K available (14336K kernel code, 2428K rwdata, 9956K rodata, 53832K init, 1088K bss, 2087948K reserved, 0K cma-reserved) Sep 4 00:01:39.179648 kernel: devtmpfs: initialized Sep 4 00:01:39.179657 kernel: x86/mm: Memory block size: 1024MB Sep 4 00:01:39.179665 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Sep 4 00:01:39.179677 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 4 00:01:39.179686 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Sep 4 00:01:39.179694 kernel: pinctrl core: initialized pinctrl subsystem Sep 4 00:01:39.179703 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 4 00:01:39.179712 kernel: audit: initializing netlink subsys (disabled) Sep 4 00:01:39.179720 kernel: audit: type=2000 audit(1756944095.028:1): state=initialized audit_enabled=0 res=1 Sep 4 00:01:39.179729 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 4 00:01:39.179738 kernel: thermal_sys: Registered thermal governor 'user_space' Sep 4 00:01:39.179746 kernel: cpuidle: using governor menu Sep 4 00:01:39.179757 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 4 00:01:39.179766 kernel: dca service started, version 1.12.1 Sep 4 00:01:39.179775 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Sep 4 00:01:39.179783 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Sep 4 00:01:39.179792 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 4 00:01:39.179801 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Sep 4 00:01:39.179819 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 4 00:01:39.179829 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Sep 4 00:01:39.179838 kernel: ACPI: Added _OSI(Module Device) Sep 4 00:01:39.179850 kernel: ACPI: Added _OSI(Processor Device) Sep 4 00:01:39.179859 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 4 00:01:39.179867 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 4 00:01:39.179876 kernel: ACPI: Interpreter enabled Sep 4 00:01:39.179885 kernel: ACPI: PM: (supports S0 S5) Sep 4 00:01:39.179893 kernel: ACPI: Using IOAPIC for interrupt routing Sep 4 00:01:39.179902 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Sep 4 00:01:39.179911 kernel: PCI: Ignoring E820 reservations for host bridge windows Sep 4 00:01:39.179920 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Sep 4 00:01:39.179931 kernel: iommu: Default domain type: Translated Sep 4 00:01:39.179939 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Sep 4 00:01:39.179948 kernel: efivars: Registered efivars operations Sep 4 00:01:39.179957 kernel: PCI: Using ACPI for IRQ routing Sep 4 00:01:39.179966 kernel: PCI: System does not support PCI Sep 4 00:01:39.179974 kernel: vgaarb: loaded Sep 4 00:01:39.179983 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Sep 4 00:01:39.179992 kernel: VFS: Disk quotas dquot_6.6.0 Sep 4 00:01:39.180001 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 4 00:01:39.180012 kernel: pnp: PnP ACPI init Sep 4 00:01:39.180020 kernel: pnp: PnP ACPI: found 3 devices Sep 4 00:01:39.180029 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Sep 4 00:01:39.180038 kernel: NET: Registered PF_INET protocol family Sep 4 00:01:39.180047 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 4 00:01:39.180055 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Sep 4 00:01:39.180064 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 4 00:01:39.180073 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 4 00:01:39.180082 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Sep 4 00:01:39.180093 kernel: TCP: Hash tables configured (established 524288 bind 65536) Sep 4 00:01:39.180102 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Sep 4 00:01:39.180110 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Sep 4 00:01:39.180119 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 4 00:01:39.180128 kernel: NET: Registered PF_XDP protocol family Sep 4 00:01:39.180136 kernel: PCI: CLS 0 bytes, default 64 Sep 4 00:01:39.180145 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Sep 4 00:01:39.180154 kernel: software IO TLB: mapped [mem 0x000000003b5bf000-0x000000003f5bf000] (64MB) Sep 4 00:01:39.180163 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Sep 4 00:01:39.180174 kernel: Initialise system trusted keyrings Sep 4 00:01:39.180182 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Sep 4 00:01:39.180191 kernel: Key type asymmetric registered Sep 4 00:01:39.180199 kernel: Asymmetric key parser 'x509' registered Sep 4 00:01:39.180208 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Sep 4 00:01:39.180217 kernel: io scheduler mq-deadline registered Sep 4 00:01:39.180225 kernel: io scheduler kyber registered Sep 4 00:01:39.180234 kernel: io scheduler bfq registered Sep 4 00:01:39.180243 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Sep 4 00:01:39.180254 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 4 00:01:39.180262 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Sep 4 00:01:39.180271 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Sep 4 00:01:39.180280 kernel: i8042: PNP: No PS/2 controller found. Sep 4 00:01:39.180434 kernel: rtc_cmos 00:02: registered as rtc0 Sep 4 00:01:39.180557 kernel: rtc_cmos 00:02: setting system clock to 2025-09-04T00:01:38 UTC (1756944098) Sep 4 00:01:39.180656 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Sep 4 00:01:39.180673 kernel: intel_pstate: CPU model not supported Sep 4 00:01:39.180682 kernel: efifb: probing for efifb Sep 4 00:01:39.180691 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Sep 4 00:01:39.180700 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Sep 4 00:01:39.180709 kernel: efifb: scrolling: redraw Sep 4 00:01:39.180717 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Sep 4 00:01:39.180726 kernel: Console: switching to colour frame buffer device 128x48 Sep 4 00:01:39.180735 kernel: fb0: EFI VGA frame buffer device Sep 4 00:01:39.180744 kernel: pstore: Using crash dump compression: deflate Sep 4 00:01:39.180755 kernel: pstore: Registered efi_pstore as persistent store backend Sep 4 00:01:39.180764 kernel: NET: Registered PF_INET6 protocol family Sep 4 00:01:39.180772 kernel: Segment Routing with IPv6 Sep 4 00:01:39.180781 kernel: In-situ OAM (IOAM) with IPv6 Sep 4 00:01:39.180790 kernel: NET: Registered PF_PACKET protocol family Sep 4 00:01:39.180799 kernel: Key type dns_resolver registered Sep 4 00:01:39.180807 kernel: IPI shorthand broadcast: enabled Sep 4 00:01:39.180816 kernel: sched_clock: Marking stable (3642004300, 110499400)->(4095265900, -342762200) Sep 4 00:01:39.180825 kernel: registered taskstats version 1 Sep 4 00:01:39.180836 kernel: Loading compiled-in X.509 certificates Sep 4 00:01:39.180845 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.44-flatcar: 247a8159a15e16f8eb89737aa66cd9cf9bbb3c10' Sep 4 00:01:39.180853 kernel: Demotion targets for Node 0: null Sep 4 00:01:39.180862 kernel: Key type .fscrypt registered Sep 4 00:01:39.180871 kernel: Key type fscrypt-provisioning registered Sep 4 00:01:39.180879 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 4 00:01:39.180888 kernel: ima: Allocated hash algorithm: sha1 Sep 4 00:01:39.180897 kernel: ima: No architecture policies found Sep 4 00:01:39.180905 kernel: clk: Disabling unused clocks Sep 4 00:01:39.180916 kernel: Warning: unable to open an initial console. Sep 4 00:01:39.180925 kernel: Freeing unused kernel image (initmem) memory: 53832K Sep 4 00:01:39.180934 kernel: Write protecting the kernel read-only data: 24576k Sep 4 00:01:39.180943 kernel: Freeing unused kernel image (rodata/data gap) memory: 284K Sep 4 00:01:39.180952 kernel: Run /init as init process Sep 4 00:01:39.180961 kernel: with arguments: Sep 4 00:01:39.180969 kernel: /init Sep 4 00:01:39.180978 kernel: with environment: Sep 4 00:01:39.180986 kernel: HOME=/ Sep 4 00:01:39.180997 kernel: TERM=linux Sep 4 00:01:39.181006 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 4 00:01:39.181015 systemd[1]: Successfully made /usr/ read-only. Sep 4 00:01:39.181027 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) Sep 4 00:01:39.181037 systemd[1]: Detected virtualization microsoft. Sep 4 00:01:39.181046 systemd[1]: Detected architecture x86-64. Sep 4 00:01:39.181055 systemd[1]: Running in initrd. Sep 4 00:01:39.181064 systemd[1]: No hostname configured, using default hostname. Sep 4 00:01:39.181076 systemd[1]: Hostname set to . Sep 4 00:01:39.181085 systemd[1]: Initializing machine ID from random generator. Sep 4 00:01:39.181095 systemd[1]: Queued start job for default target initrd.target. Sep 4 00:01:39.181104 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 4 00:01:39.181113 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 4 00:01:39.181123 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 4 00:01:39.181133 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 4 00:01:39.181142 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 4 00:01:39.181155 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 4 00:01:39.181165 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Sep 4 00:01:39.181175 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 4 00:01:39.181184 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 4 00:01:39.181193 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 4 00:01:39.181203 systemd[1]: Reached target paths.target - Path Units. Sep 4 00:01:39.181214 systemd[1]: Reached target slices.target - Slice Units. Sep 4 00:01:39.181224 systemd[1]: Reached target swap.target - Swaps. Sep 4 00:01:39.181233 systemd[1]: Reached target timers.target - Timer Units. Sep 4 00:01:39.181243 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 4 00:01:39.181252 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 4 00:01:39.181261 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 4 00:01:39.181270 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Sep 4 00:01:39.181280 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 4 00:01:39.181289 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 4 00:01:39.181301 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 4 00:01:39.181310 systemd[1]: Reached target sockets.target - Socket Units. Sep 4 00:01:39.181320 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 4 00:01:39.181329 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 4 00:01:39.181338 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 4 00:01:39.181348 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). Sep 4 00:01:39.181357 systemd[1]: Starting systemd-fsck-usr.service... Sep 4 00:01:39.181367 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 4 00:01:39.181378 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 4 00:01:39.181406 systemd-journald[232]: Collecting audit messages is disabled. Sep 4 00:01:39.181429 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 00:01:39.181439 systemd-journald[232]: Journal started Sep 4 00:01:39.181463 systemd-journald[232]: Runtime Journal (/run/log/journal/e302a29941ea4e17b296f525e6c6cc66) is 8M, max 2.2G, 2.1G free. Sep 4 00:01:39.184380 systemd-modules-load[235]: Inserted module 'overlay' Sep 4 00:01:39.192977 systemd[1]: Started systemd-journald.service - Journal Service. Sep 4 00:01:39.194041 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 4 00:01:39.194588 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 4 00:01:39.195295 systemd[1]: Finished systemd-fsck-usr.service. Sep 4 00:01:39.197385 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 4 00:01:39.198709 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 4 00:01:39.218073 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 00:01:39.227786 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 4 00:01:39.230134 systemd-tmpfiles[245]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Sep 4 00:01:39.235999 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 4 00:01:39.284535 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Sep 4 00:01:39.288851 systemd-modules-load[235]: Inserted module 'br_netfilter' Sep 4 00:01:39.292401 kernel: Bridge firewalling registered Sep 4 00:01:39.302647 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 4 00:01:39.303697 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 4 00:01:39.305259 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 4 00:01:39.306208 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 4 00:01:39.318421 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 4 00:01:39.328893 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 4 00:01:39.330614 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 4 00:01:39.338461 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 4 00:01:39.348429 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 4 00:01:39.363674 dracut-cmdline[270]: 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=c7fa427551c105672074cbcbe7e23c997f471a6e879d708e8d6cbfad2147666e Sep 4 00:01:39.415012 systemd-resolved[274]: Positive Trust Anchors: Sep 4 00:01:39.415026 systemd-resolved[274]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 4 00:01:39.415057 systemd-resolved[274]: 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 Sep 4 00:01:39.417657 systemd-resolved[274]: Defaulting to hostname 'linux'. Sep 4 00:01:39.418673 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 4 00:01:39.428440 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 4 00:01:39.523549 kernel: SCSI subsystem initialized Sep 4 00:01:39.533540 kernel: Loading iSCSI transport class v2.0-870. Sep 4 00:01:39.545537 kernel: iscsi: registered transport (tcp) Sep 4 00:01:39.569168 kernel: iscsi: registered transport (qla4xxx) Sep 4 00:01:39.569204 kernel: QLogic iSCSI HBA Driver Sep 4 00:01:39.587565 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 4 00:01:39.618980 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 4 00:01:39.621505 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 4 00:01:39.671971 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 4 00:01:39.674662 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 4 00:01:39.750545 kernel: raid6: avx2x4 gen() 30123 MB/s Sep 4 00:01:39.769539 kernel: raid6: avx2x2 gen() 28705 MB/s Sep 4 00:01:39.791744 kernel: raid6: avx2x1 gen() 25885 MB/s Sep 4 00:01:39.791768 kernel: raid6: using algorithm avx2x4 gen() 30123 MB/s Sep 4 00:01:39.814762 kernel: raid6: .... xor() 10141 MB/s, rmw enabled Sep 4 00:01:39.814789 kernel: raid6: using avx2x2 recovery algorithm Sep 4 00:01:39.837539 kernel: xor: automatically using best checksumming function avx Sep 4 00:01:40.003549 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 4 00:01:40.009924 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 4 00:01:40.012895 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 4 00:01:40.050931 systemd-udevd[485]: Using default interface naming scheme 'v255'. Sep 4 00:01:40.057028 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 4 00:01:40.059333 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 4 00:01:40.092652 dracut-pre-trigger[493]: rd.md=0: removing MD RAID activation Sep 4 00:01:40.120459 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 4 00:01:40.123837 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 4 00:01:40.205717 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 4 00:01:40.208194 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 4 00:01:40.235540 kernel: cryptd: max_cpu_qlen set to 1000 Sep 4 00:01:40.244557 kernel: AES CTR mode by8 optimization enabled Sep 4 00:01:40.257376 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 4 00:01:40.270761 kernel: hv_vmbus: Vmbus version:4.0 Sep 4 00:01:40.257576 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 00:01:40.268253 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 00:01:40.304659 kernel: pps_core: LinuxPPS API ver. 1 registered Sep 4 00:01:40.304682 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Sep 4 00:01:40.304695 kernel: hv_vmbus: registering driver hyperv_keyboard Sep 4 00:01:40.304706 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 Sep 4 00:01:40.304718 kernel: hid: raw HID events driver (C) Jiri Kosina Sep 4 00:01:40.273061 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 00:01:40.312025 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 4 00:01:40.326451 kernel: hv_vmbus: registering driver hv_storvsc Sep 4 00:01:40.326471 kernel: hv_vmbus: registering driver hv_netvsc Sep 4 00:01:40.329543 kernel: PTP clock support registered Sep 4 00:01:40.329573 kernel: hv_vmbus: registering driver hid_hyperv Sep 4 00:01:40.332700 kernel: scsi host0: storvsc_host_t Sep 4 00:01:40.332746 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Sep 4 00:01:40.341313 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Sep 4 00:01:40.341413 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Sep 4 00:01:40.342538 kernel: scsi host1: storvsc_host_t Sep 4 00:01:40.342593 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Sep 4 00:01:40.343541 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Sep 4 00:01:40.354542 kernel: hv_vmbus: registering driver hv_pci Sep 4 00:01:40.364369 kernel: hv_utils: Registering HyperV Utility Driver Sep 4 00:01:40.370539 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Sep 4 00:01:40.370725 kernel: hv_vmbus: registering driver hv_utils Sep 4 00:01:40.374534 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Sep 4 00:01:40.374726 kernel: hv_utils: Heartbeat IC version 3.0 Sep 4 00:01:40.380059 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Sep 4 00:01:40.380848 kernel: hv_utils: Shutdown IC version 3.2 Sep 4 00:01:40.380866 kernel: hv_utils: TimeSync IC version 4.0 Sep 4 00:01:40.385998 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Sep 4 00:01:40.530818 systemd-resolved[274]: Clock change detected. Flushing caches. Sep 4 00:01:40.631094 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Sep 4 00:01:40.631256 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint Sep 4 00:01:40.631426 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Sep 4 00:01:40.631585 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Sep 4 00:01:40.631728 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] Sep 4 00:01:40.631859 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] Sep 4 00:01:40.631985 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] Sep 4 00:01:40.632110 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks Sep 4 00:01:40.632237 kernel: pci 0001:00:00.0: Enabling HDA controller Sep 4 00:01:40.632373 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Sep 4 00:01:40.632508 kernel: sd 0:0:0:1: [sdb] Write Protect is off Sep 4 00:01:40.632640 kernel: sd 0:0:0:0: [sda] Write Protect is off Sep 4 00:01:40.632767 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Sep 4 00:01:40.632892 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Sep 4 00:01:40.633011 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned Sep 4 00:01:40.633137 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Sep 4 00:01:40.633262 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned Sep 4 00:01:40.633401 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned Sep 4 00:01:40.633532 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Sep 4 00:01:40.633660 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Sep 4 00:01:40.633786 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#133 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 4 00:01:40.633961 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#140 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 4 00:01:40.634080 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#65 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 4 00:01:40.634192 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#76 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 4 00:01:40.638983 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 00:01:40.648473 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 4 00:01:40.648493 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Sep 4 00:01:40.648662 kernel: sdb: sdb1 Sep 4 00:01:40.653651 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk Sep 4 00:01:40.655372 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Sep 4 00:01:40.661161 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Sep 4 00:01:40.663380 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Sep 4 00:01:41.165263 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Sep 4 00:01:41.193343 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Sep 4 00:01:41.205002 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Sep 4 00:01:41.226537 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Sep 4 00:01:41.248267 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Sep 4 00:01:41.259738 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 4 00:01:41.299383 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#82 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 4 00:01:41.316368 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 4 00:01:41.325321 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#99 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 4 00:01:41.349391 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 4 00:01:42.357024 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#139 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 4 00:01:42.367945 disk-uuid[727]: The operation has completed successfully. Sep 4 00:01:42.371647 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 4 00:01:43.057331 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 4 00:01:43.057459 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 4 00:01:43.059980 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 4 00:01:43.081494 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 4 00:01:43.083828 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 4 00:01:43.091887 sh[890]: Success Sep 4 00:01:43.091286 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 4 00:01:43.105059 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 4 00:01:43.108842 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 4 00:01:43.143825 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 4 00:01:43.143862 kernel: device-mapper: uevent: version 1.0.3 Sep 4 00:01:43.148652 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Sep 4 00:01:43.160378 kernel: device-mapper: verity: sha256 using shash "sha256-avx2" Sep 4 00:01:43.160780 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 4 00:01:43.326203 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 4 00:01:43.329576 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 4 00:01:43.346660 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 4 00:01:43.366560 kernel: BTRFS: device fsid 8a9c2e34-3d3c-49a9-acce-59bf90003071 devid 1 transid 37 /dev/mapper/usr (254:0) scanned by mount (924) Sep 4 00:01:43.366585 kernel: BTRFS info (device dm-0): first mount of filesystem 8a9c2e34-3d3c-49a9-acce-59bf90003071 Sep 4 00:01:43.370759 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Sep 4 00:01:43.546685 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 4 00:01:43.546738 kernel: BTRFS info (device dm-0): enabling free space tree Sep 4 00:01:43.571329 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 4 00:01:43.573349 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Sep 4 00:01:43.579782 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 4 00:01:43.580683 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 4 00:01:43.620941 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 4 00:01:43.649387 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (955) Sep 4 00:01:43.649432 kernel: BTRFS info (device sda6): first mount of filesystem 75efd3be-3398-4525-8f67-b36cc847539d Sep 4 00:01:43.657788 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Sep 4 00:01:43.677087 kernel: BTRFS info (device sda6): turning on async discard Sep 4 00:01:43.677117 kernel: BTRFS info (device sda6): enabling free space tree Sep 4 00:01:43.685379 kernel: BTRFS info (device sda6): last unmount of filesystem 75efd3be-3398-4525-8f67-b36cc847539d Sep 4 00:01:43.686065 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 4 00:01:43.688487 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 4 00:01:43.736231 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 4 00:01:43.748034 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 4 00:01:43.803675 systemd-networkd[1093]: lo: Link UP Sep 4 00:01:43.803685 systemd-networkd[1093]: lo: Gained carrier Sep 4 00:01:43.804727 systemd-networkd[1093]: Enumeration completed Sep 4 00:01:43.804818 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 4 00:01:43.805093 systemd-networkd[1093]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 00:01:43.805097 systemd-networkd[1093]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 4 00:01:43.805869 systemd-networkd[1093]: eth0: Link UP Sep 4 00:01:43.806035 systemd-networkd[1093]: eth0: Gained carrier Sep 4 00:01:43.806045 systemd-networkd[1093]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 00:01:43.806199 systemd[1]: Reached target network.target - Network. Sep 4 00:01:43.873392 systemd-networkd[1093]: eth0: DHCPv4 address 10.200.8.11/24, gateway 10.200.8.1 acquired from 168.63.129.16 Sep 4 00:01:44.697640 ignition[1051]: Ignition 2.21.0 Sep 4 00:01:44.697654 ignition[1051]: Stage: fetch-offline Sep 4 00:01:44.697747 ignition[1051]: no configs at "/usr/lib/ignition/base.d" Sep 4 00:01:44.697757 ignition[1051]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 4 00:01:44.697857 ignition[1051]: parsed url from cmdline: "" Sep 4 00:01:44.697861 ignition[1051]: no config URL provided Sep 4 00:01:44.697866 ignition[1051]: reading system config file "/usr/lib/ignition/user.ign" Sep 4 00:01:44.697875 ignition[1051]: no config at "/usr/lib/ignition/user.ign" Sep 4 00:01:44.697880 ignition[1051]: failed to fetch config: resource requires networking Sep 4 00:01:44.698044 ignition[1051]: Ignition finished successfully Sep 4 00:01:44.728779 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 4 00:01:44.731286 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Sep 4 00:01:44.769338 ignition[1108]: Ignition 2.21.0 Sep 4 00:01:44.769351 ignition[1108]: Stage: fetch Sep 4 00:01:44.769625 ignition[1108]: no configs at "/usr/lib/ignition/base.d" Sep 4 00:01:44.769636 ignition[1108]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 4 00:01:44.769745 ignition[1108]: parsed url from cmdline: "" Sep 4 00:01:44.769749 ignition[1108]: no config URL provided Sep 4 00:01:44.769755 ignition[1108]: reading system config file "/usr/lib/ignition/user.ign" Sep 4 00:01:44.769764 ignition[1108]: no config at "/usr/lib/ignition/user.ign" Sep 4 00:01:44.769796 ignition[1108]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Sep 4 00:01:44.877577 ignition[1108]: GET result: OK Sep 4 00:01:44.877696 ignition[1108]: config has been read from IMDS userdata Sep 4 00:01:44.877727 ignition[1108]: parsing config with SHA512: 79e44cffb5e651c7d03892768ac009198dfe79b78b14d15b7bc429fa29ac53b3f2de21887b038c275009f6f04b2b8574e90d606dd965bf6b6ca368d1f31a7bf6 Sep 4 00:01:44.882176 unknown[1108]: fetched base config from "system" Sep 4 00:01:44.882349 ignition[1108]: fetch: fetch complete Sep 4 00:01:44.882183 unknown[1108]: fetched base config from "system" Sep 4 00:01:44.882370 ignition[1108]: fetch: fetch passed Sep 4 00:01:44.882188 unknown[1108]: fetched user config from "azure" Sep 4 00:01:44.882432 ignition[1108]: Ignition finished successfully Sep 4 00:01:44.890059 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Sep 4 00:01:44.892371 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 4 00:01:44.935962 ignition[1118]: Ignition 2.21.0 Sep 4 00:01:44.935979 ignition[1118]: Stage: kargs Sep 4 00:01:44.936257 ignition[1118]: no configs at "/usr/lib/ignition/base.d" Sep 4 00:01:44.936274 ignition[1118]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 4 00:01:44.945021 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 4 00:01:44.936837 ignition[1118]: kargs: kargs passed Sep 4 00:01:44.953866 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 4 00:01:44.936882 ignition[1118]: Ignition finished successfully Sep 4 00:01:44.986627 ignition[1128]: Ignition 2.21.0 Sep 4 00:01:44.986640 ignition[1128]: Stage: disks Sep 4 00:01:44.987311 ignition[1128]: no configs at "/usr/lib/ignition/base.d" Sep 4 00:01:44.987326 ignition[1128]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 4 00:01:44.988449 ignition[1128]: disks: disks passed Sep 4 00:01:44.995732 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 4 00:01:44.988510 ignition[1128]: Ignition finished successfully Sep 4 00:01:44.996238 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 4 00:01:45.004797 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 4 00:01:45.011748 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 4 00:01:45.038854 systemd[1]: Reached target sysinit.target - System Initialization. Sep 4 00:01:45.043375 systemd[1]: Reached target basic.target - Basic System. Sep 4 00:01:45.051937 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 4 00:01:45.146594 systemd-fsck[1139]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks Sep 4 00:01:45.154239 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 4 00:01:45.156682 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 4 00:01:45.388373 kernel: EXT4-fs (sda9): mounted filesystem c3518c93-f823-4477-a620-ff9666a59be5 r/w with ordered data mode. Quota mode: none. Sep 4 00:01:45.388779 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 4 00:01:45.390799 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 4 00:01:45.408482 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 4 00:01:45.415299 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 4 00:01:45.432920 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Sep 4 00:01:45.446268 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1153) Sep 4 00:01:45.434430 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Sep 4 00:01:45.471119 kernel: BTRFS info (device sda6): first mount of filesystem 75efd3be-3398-4525-8f67-b36cc847539d Sep 4 00:01:45.471142 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Sep 4 00:01:45.434458 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 4 00:01:45.448856 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 4 00:01:45.473780 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 4 00:01:45.497914 kernel: BTRFS info (device sda6): turning on async discard Sep 4 00:01:45.497945 kernel: BTRFS info (device sda6): enabling free space tree Sep 4 00:01:45.499625 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 4 00:01:45.695471 systemd-networkd[1093]: eth0: Gained IPv6LL Sep 4 00:01:46.022702 coreos-metadata[1155]: Sep 04 00:01:46.022 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Sep 4 00:01:46.029128 coreos-metadata[1155]: Sep 04 00:01:46.029 INFO Fetch successful Sep 4 00:01:46.029128 coreos-metadata[1155]: Sep 04 00:01:46.029 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Sep 4 00:01:46.041216 coreos-metadata[1155]: Sep 04 00:01:46.033 INFO Fetch successful Sep 4 00:01:46.041216 coreos-metadata[1155]: Sep 04 00:01:46.033 INFO wrote hostname ci-4372.1.0-n-5e9b5c3608 to /sysroot/etc/hostname Sep 4 00:01:46.042351 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Sep 4 00:01:46.126894 initrd-setup-root[1183]: cut: /sysroot/etc/passwd: No such file or directory Sep 4 00:01:46.186270 initrd-setup-root[1190]: cut: /sysroot/etc/group: No such file or directory Sep 4 00:01:46.229547 initrd-setup-root[1197]: cut: /sysroot/etc/shadow: No such file or directory Sep 4 00:01:46.248235 initrd-setup-root[1204]: cut: /sysroot/etc/gshadow: No such file or directory Sep 4 00:01:46.999033 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 4 00:01:47.004978 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 4 00:01:47.012071 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 4 00:01:47.029552 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 4 00:01:47.038824 kernel: BTRFS info (device sda6): last unmount of filesystem 75efd3be-3398-4525-8f67-b36cc847539d Sep 4 00:01:47.071138 ignition[1273]: INFO : Ignition 2.21.0 Sep 4 00:01:47.071138 ignition[1273]: INFO : Stage: mount Sep 4 00:01:47.076975 ignition[1273]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 4 00:01:47.076975 ignition[1273]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 4 00:01:47.076975 ignition[1273]: INFO : mount: mount passed Sep 4 00:01:47.076975 ignition[1273]: INFO : Ignition finished successfully Sep 4 00:01:47.078351 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 4 00:01:47.085962 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 4 00:01:47.107528 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 4 00:01:47.113844 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 4 00:01:47.138346 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1288) Sep 4 00:01:47.138387 kernel: BTRFS info (device sda6): first mount of filesystem 75efd3be-3398-4525-8f67-b36cc847539d Sep 4 00:01:47.142602 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Sep 4 00:01:47.150759 kernel: BTRFS info (device sda6): turning on async discard Sep 4 00:01:47.150784 kernel: BTRFS info (device sda6): enabling free space tree Sep 4 00:01:47.152878 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 4 00:01:47.186798 ignition[1305]: INFO : Ignition 2.21.0 Sep 4 00:01:47.186798 ignition[1305]: INFO : Stage: files Sep 4 00:01:47.192503 ignition[1305]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 4 00:01:47.192503 ignition[1305]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 4 00:01:47.192503 ignition[1305]: DEBUG : files: compiled without relabeling support, skipping Sep 4 00:01:47.206112 ignition[1305]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 4 00:01:47.206112 ignition[1305]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 4 00:01:47.257940 ignition[1305]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 4 00:01:47.263520 ignition[1305]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 4 00:01:47.268846 ignition[1305]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 4 00:01:47.264000 unknown[1305]: wrote ssh authorized keys file for user: core Sep 4 00:01:47.322416 ignition[1305]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 4 00:01:47.329664 ignition[1305]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 4 00:01:47.329664 ignition[1305]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 4 00:01:47.329664 ignition[1305]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 4 00:01:47.329664 ignition[1305]: INFO : files: files passed Sep 4 00:01:47.329664 ignition[1305]: INFO : Ignition finished successfully Sep 4 00:01:47.345870 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 4 00:01:47.353734 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 4 00:01:47.380633 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 4 00:01:47.383720 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 4 00:01:47.383811 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 4 00:01:47.411881 initrd-setup-root-after-ignition[1338]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 4 00:01:47.411881 initrd-setup-root-after-ignition[1338]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 4 00:01:47.424465 initrd-setup-root-after-ignition[1342]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 4 00:01:47.425954 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 4 00:01:47.435747 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 4 00:01:47.445620 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 4 00:01:47.516351 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 4 00:01:47.520895 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 4 00:01:47.522573 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 4 00:01:47.531301 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 4 00:01:47.542729 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 4 00:01:47.543531 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 4 00:01:47.583304 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 4 00:01:47.593740 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 4 00:01:47.631275 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 4 00:01:47.632935 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 4 00:01:47.641393 systemd[1]: Stopped target timers.target - Timer Units. Sep 4 00:01:47.655044 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 4 00:01:47.655187 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 4 00:01:47.666768 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 4 00:01:47.675309 systemd[1]: Stopped target basic.target - Basic System. Sep 4 00:01:47.682818 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 4 00:01:47.684263 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 4 00:01:47.704128 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 4 00:01:47.705685 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Sep 4 00:01:47.714339 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 4 00:01:47.722256 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 4 00:01:47.734219 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 4 00:01:47.743297 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 4 00:01:47.752044 systemd[1]: Stopped target swap.target - Swaps. Sep 4 00:01:47.759136 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 4 00:01:47.759279 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 4 00:01:47.770194 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 4 00:01:47.778497 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 4 00:01:47.787295 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 4 00:01:47.787425 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 4 00:01:47.793468 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 4 00:01:47.793590 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 4 00:01:47.810445 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 4 00:01:47.810577 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 4 00:01:47.819167 systemd[1]: ignition-files.service: Deactivated successfully. Sep 4 00:01:47.819287 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 4 00:01:47.827055 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Sep 4 00:01:47.827171 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Sep 4 00:01:47.837490 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 4 00:01:47.837984 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 4 00:01:47.838100 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 4 00:01:47.848045 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 4 00:01:47.896427 ignition[1362]: INFO : Ignition 2.21.0 Sep 4 00:01:47.896427 ignition[1362]: INFO : Stage: umount Sep 4 00:01:47.896427 ignition[1362]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 4 00:01:47.896427 ignition[1362]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 4 00:01:47.896427 ignition[1362]: INFO : umount: umount passed Sep 4 00:01:47.896427 ignition[1362]: INFO : Ignition finished successfully Sep 4 00:01:47.854828 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 4 00:01:47.854955 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 4 00:01:47.865791 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 4 00:01:47.865908 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 4 00:01:47.885061 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 4 00:01:47.885155 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 4 00:01:47.895796 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 4 00:01:47.895905 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 4 00:01:47.903752 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 4 00:01:47.904140 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 4 00:01:47.904185 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 4 00:01:47.911950 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 4 00:01:47.911997 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 4 00:01:47.919707 systemd[1]: ignition-fetch.service: Deactivated successfully. Sep 4 00:01:47.919752 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Sep 4 00:01:47.928519 systemd[1]: Stopped target network.target - Network. Sep 4 00:01:47.929942 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 4 00:01:47.929990 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 4 00:01:47.939949 systemd[1]: Stopped target paths.target - Path Units. Sep 4 00:01:47.948691 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 4 00:01:47.952427 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 4 00:01:47.956773 systemd[1]: Stopped target slices.target - Slice Units. Sep 4 00:01:47.972027 systemd[1]: Stopped target sockets.target - Socket Units. Sep 4 00:01:47.979633 systemd[1]: iscsid.socket: Deactivated successfully. Sep 4 00:01:47.979684 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 4 00:01:47.987933 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 4 00:01:47.987978 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 4 00:01:47.995470 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 4 00:01:47.995523 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 4 00:01:48.003023 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 4 00:01:48.003068 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 4 00:01:48.010729 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 4 00:01:48.018139 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 4 00:01:48.026092 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 4 00:01:48.026189 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 4 00:01:48.038849 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Sep 4 00:01:48.039115 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 4 00:01:48.039229 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 4 00:01:48.049822 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Sep 4 00:01:48.050054 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 4 00:01:48.050147 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 4 00:01:48.057347 systemd[1]: Stopped target network-pre.target - Preparation for Network. Sep 4 00:01:48.061565 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 4 00:01:48.061623 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 4 00:01:48.070349 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 4 00:01:48.070422 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 4 00:01:48.079187 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 4 00:01:48.086247 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 4 00:01:48.086304 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 4 00:01:48.100142 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 4 00:01:48.100191 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 4 00:01:48.111087 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 4 00:01:48.111133 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 4 00:01:48.118458 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 4 00:01:48.118512 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 4 00:01:48.130337 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 4 00:01:48.133471 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Sep 4 00:01:48.133536 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Sep 4 00:01:48.166037 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 4 00:01:48.166204 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 4 00:01:48.168940 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 4 00:01:48.168984 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 4 00:01:48.177276 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 4 00:01:48.177314 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 4 00:01:48.190073 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 4 00:01:48.190123 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 4 00:01:48.202038 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 4 00:01:48.202088 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 4 00:01:48.214580 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 4 00:01:48.214631 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 4 00:01:48.227752 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 4 00:01:48.234909 systemd[1]: systemd-network-generator.service: Deactivated successfully. Sep 4 00:01:48.234960 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Sep 4 00:01:48.252045 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 4 00:01:48.252116 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 4 00:01:48.265643 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 4 00:01:48.265694 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 00:01:48.278200 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Sep 4 00:01:48.278254 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Sep 4 00:01:48.278296 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 4 00:01:48.278661 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 4 00:01:48.443912 systemd-journald[232]: Received SIGTERM from PID 1 (systemd). Sep 4 00:01:48.278761 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 4 00:01:48.281647 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 4 00:01:48.281736 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 4 00:01:48.290117 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 4 00:01:48.297974 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 4 00:01:48.332481 systemd[1]: Switching root. Sep 4 00:01:48.456287 systemd-journald[232]: Journal stopped Sep 4 00:01:52.804442 kernel: SELinux: policy capability network_peer_controls=1 Sep 4 00:01:52.804473 kernel: SELinux: policy capability open_perms=1 Sep 4 00:01:52.804485 kernel: SELinux: policy capability extended_socket_class=1 Sep 4 00:01:52.804494 kernel: SELinux: policy capability always_check_network=0 Sep 4 00:01:52.804503 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 4 00:01:52.804512 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 4 00:01:52.804523 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 4 00:01:52.804535 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 4 00:01:52.804544 kernel: SELinux: policy capability userspace_initial_context=0 Sep 4 00:01:52.804554 kernel: audit: type=1403 audit(1756944109.289:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 4 00:01:52.804569 systemd[1]: Successfully loaded SELinux policy in 198.741ms. Sep 4 00:01:52.804581 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 15.456ms. Sep 4 00:01:52.804593 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) Sep 4 00:01:52.804604 systemd[1]: Detected virtualization microsoft. Sep 4 00:01:52.804618 systemd[1]: Detected architecture x86-64. Sep 4 00:01:52.804629 systemd[1]: Detected first boot. Sep 4 00:01:52.804640 systemd[1]: Hostname set to . Sep 4 00:01:52.804650 systemd[1]: Initializing machine ID from random generator. Sep 4 00:01:52.804661 zram_generator::config[1407]: No configuration found. Sep 4 00:01:52.804675 kernel: Guest personality initialized and is inactive Sep 4 00:01:52.804685 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Sep 4 00:01:52.804695 kernel: Initialized host personality Sep 4 00:01:52.804705 kernel: NET: Registered PF_VSOCK protocol family Sep 4 00:01:52.804715 systemd[1]: Populated /etc with preset unit settings. Sep 4 00:01:52.804726 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Sep 4 00:01:52.804737 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 4 00:01:52.804750 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 4 00:01:52.804761 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 4 00:01:52.804771 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 4 00:01:52.804782 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 4 00:01:52.804793 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 4 00:01:52.804807 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 4 00:01:52.804818 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 4 00:01:52.804828 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 4 00:01:52.804842 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 4 00:01:52.804853 systemd[1]: Created slice user.slice - User and Session Slice. Sep 4 00:01:52.804864 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 4 00:01:52.804875 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 4 00:01:52.804886 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 4 00:01:52.804897 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 4 00:01:52.804912 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 4 00:01:52.804923 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 4 00:01:52.804937 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Sep 4 00:01:52.804948 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 4 00:01:52.804959 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 4 00:01:52.804970 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 4 00:01:52.804981 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 4 00:01:52.804992 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 4 00:01:52.805003 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 4 00:01:52.805017 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 4 00:01:52.805028 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 4 00:01:52.805039 systemd[1]: Reached target slices.target - Slice Units. Sep 4 00:01:52.805053 systemd[1]: Reached target swap.target - Swaps. Sep 4 00:01:52.805064 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 4 00:01:52.805075 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 4 00:01:52.805090 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Sep 4 00:01:52.805101 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 4 00:01:52.805112 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 4 00:01:52.805123 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 4 00:01:52.805134 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 4 00:01:52.805146 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 4 00:01:52.805157 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 4 00:01:52.805171 systemd[1]: Mounting media.mount - External Media Directory... Sep 4 00:01:52.805183 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 4 00:01:52.805194 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 4 00:01:52.805205 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 4 00:01:52.805216 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 4 00:01:52.805228 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). Sep 4 00:01:52.805239 systemd[1]: Reached target machines.target - Containers. Sep 4 00:01:52.805250 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 4 00:01:52.805261 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 4 00:01:52.805275 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 4 00:01:52.805287 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 4 00:01:52.805302 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 4 00:01:52.805313 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 4 00:01:52.805325 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 4 00:01:52.805336 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 4 00:01:52.805347 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 4 00:01:52.805368 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 4 00:01:52.805385 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 4 00:01:52.805397 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 4 00:01:52.805408 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 4 00:01:52.805420 systemd[1]: Stopped systemd-fsck-usr.service. Sep 4 00:01:52.805431 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). Sep 4 00:01:52.805443 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 4 00:01:52.805454 kernel: loop: module loaded Sep 4 00:01:52.805464 kernel: fuse: init (API version 7.41) Sep 4 00:01:52.805477 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 4 00:01:52.805489 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 4 00:01:52.805500 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 4 00:01:52.805511 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Sep 4 00:01:52.805522 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 4 00:01:52.805533 systemd[1]: verity-setup.service: Deactivated successfully. Sep 4 00:01:52.805544 systemd[1]: Stopped verity-setup.service. Sep 4 00:01:52.805582 systemd-journald[1514]: Collecting audit messages is disabled. Sep 4 00:01:52.805613 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 4 00:01:52.805627 systemd-journald[1514]: Journal started Sep 4 00:01:52.805652 systemd-journald[1514]: Runtime Journal (/run/log/journal/3477c67211524ede9d98b97853e13c3e) is 8M, max 2.2G, 2.1G free. Sep 4 00:01:52.036957 systemd[1]: Queued start job for default target multi-user.target. Sep 4 00:01:52.050659 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Sep 4 00:01:52.051126 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 4 00:01:52.819860 systemd[1]: Started systemd-journald.service - Journal Service. Sep 4 00:01:52.823345 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 4 00:01:52.827697 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 4 00:01:52.832239 systemd[1]: Mounted media.mount - External Media Directory. Sep 4 00:01:52.836735 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 4 00:01:52.842375 kernel: ACPI: bus type drm_connector registered Sep 4 00:01:52.843727 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 4 00:01:52.848496 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 4 00:01:52.852762 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 4 00:01:52.857855 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 4 00:01:52.863066 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 4 00:01:52.863256 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 4 00:01:52.868205 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 4 00:01:52.868410 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 4 00:01:52.873238 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 4 00:01:52.873542 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 4 00:01:52.878061 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 4 00:01:52.878253 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 4 00:01:52.883585 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 4 00:01:52.883768 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 4 00:01:52.888382 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 4 00:01:52.888561 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 4 00:01:52.895964 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 4 00:01:52.901509 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 4 00:01:52.906795 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 4 00:01:52.912099 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Sep 4 00:01:52.917542 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 4 00:01:52.933516 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 4 00:01:52.939383 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 4 00:01:52.957630 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 4 00:01:52.962133 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 4 00:01:52.962170 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 4 00:01:52.967314 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Sep 4 00:01:52.974872 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 4 00:01:52.979541 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 4 00:01:52.980613 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 4 00:01:53.000611 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 4 00:01:53.005380 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 4 00:01:53.006317 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 4 00:01:53.010721 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 4 00:01:53.011680 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 4 00:01:53.016764 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 4 00:01:53.022253 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 4 00:01:53.028004 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 4 00:01:53.033004 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 4 00:01:53.039060 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 4 00:01:53.045544 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 4 00:01:53.046837 systemd-journald[1514]: Time spent on flushing to /var/log/journal/3477c67211524ede9d98b97853e13c3e is 14.980ms for 954 entries. Sep 4 00:01:53.046837 systemd-journald[1514]: System Journal (/var/log/journal/3477c67211524ede9d98b97853e13c3e) is 8M, max 2.6G, 2.6G free. Sep 4 00:01:53.091758 systemd-journald[1514]: Received client request to flush runtime journal. Sep 4 00:01:53.055724 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Sep 4 00:01:53.093886 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 4 00:01:53.113381 kernel: loop0: detected capacity change from 0 to 113872 Sep 4 00:01:53.127751 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 4 00:01:53.128447 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Sep 4 00:01:53.174471 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 4 00:01:53.192778 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 4 00:01:53.198444 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 4 00:01:53.266035 systemd-tmpfiles[1564]: ACLs are not supported, ignoring. Sep 4 00:01:53.266056 systemd-tmpfiles[1564]: ACLs are not supported, ignoring. Sep 4 00:01:53.270914 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 4 00:01:53.744382 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 4 00:01:53.788388 kernel: loop1: detected capacity change from 0 to 28504 Sep 4 00:01:54.033519 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 4 00:01:54.040105 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 4 00:01:54.080237 systemd-udevd[1571]: Using default interface naming scheme 'v255'. Sep 4 00:01:54.090380 kernel: loop2: detected capacity change from 0 to 146240 Sep 4 00:01:54.342410 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 4 00:01:54.351451 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 4 00:01:54.386313 kernel: loop3: detected capacity change from 0 to 113872 Sep 4 00:01:54.388437 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Sep 4 00:01:54.411406 kernel: loop4: detected capacity change from 0 to 28504 Sep 4 00:01:54.421389 kernel: loop5: detected capacity change from 0 to 146240 Sep 4 00:01:54.433106 (sd-merge)[1607]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Sep 4 00:01:54.433577 (sd-merge)[1607]: Merged extensions into '/usr'. Sep 4 00:01:54.437801 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 4 00:01:54.444292 systemd[1]: Starting ensure-sysext.service... Sep 4 00:01:54.449739 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 4 00:01:54.459580 kernel: hv_vmbus: registering driver hyperv_fb Sep 4 00:01:54.459637 kernel: mousedev: PS/2 mouse device common for all mice Sep 4 00:01:54.470374 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Sep 4 00:01:54.476428 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Sep 4 00:01:54.476482 kernel: hv_vmbus: registering driver hv_balloon Sep 4 00:01:54.479404 kernel: Console: switching to colour dummy device 80x25 Sep 4 00:01:54.479492 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 4 00:01:54.481140 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Sep 4 00:01:54.488385 kernel: Console: switching to colour frame buffer device 128x48 Sep 4 00:01:54.496057 systemd-tmpfiles[1629]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Sep 4 00:01:54.496107 systemd-tmpfiles[1629]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Sep 4 00:01:54.496446 systemd-tmpfiles[1629]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 4 00:01:54.496760 systemd-tmpfiles[1629]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 4 00:01:54.498080 systemd-tmpfiles[1629]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 4 00:01:54.498673 systemd-tmpfiles[1629]: ACLs are not supported, ignoring. Sep 4 00:01:54.498907 systemd-tmpfiles[1629]: ACLs are not supported, ignoring. Sep 4 00:01:54.503541 systemd-tmpfiles[1629]: Detected autofs mount point /boot during canonicalization of boot. Sep 4 00:01:54.503643 systemd-tmpfiles[1629]: Skipping /boot Sep 4 00:01:54.517271 systemd-tmpfiles[1629]: Detected autofs mount point /boot during canonicalization of boot. Sep 4 00:01:54.517379 systemd-tmpfiles[1629]: Skipping /boot Sep 4 00:01:54.533573 kernel: EDAC MC: Ver: 3.0.0 Sep 4 00:01:54.541520 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 4 00:01:54.553406 systemd[1]: Reload requested from client PID 1621 ('systemctl') (unit ensure-sysext.service)... Sep 4 00:01:54.553422 systemd[1]: Reloading... Sep 4 00:01:54.601390 zram_generator::config[1675]: No configuration found. Sep 4 00:01:54.718328 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Sep 4 00:01:54.772628 systemd-networkd[1596]: lo: Link UP Sep 4 00:01:54.772640 systemd-networkd[1596]: lo: Gained carrier Sep 4 00:01:54.773946 systemd-networkd[1596]: Enumeration completed Sep 4 00:01:54.774326 systemd-networkd[1596]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 00:01:54.774335 systemd-networkd[1596]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 4 00:01:54.774899 systemd-networkd[1596]: eth0: Link UP Sep 4 00:01:54.774908 systemd-networkd[1596]: eth0: Gained carrier Sep 4 00:01:54.774922 systemd-networkd[1596]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 00:01:54.812393 systemd-networkd[1596]: eth0: DHCPv4 address 10.200.8.11/24, gateway 10.200.8.1 acquired from 168.63.129.16 Sep 4 00:01:54.871872 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Sep 4 00:01:54.877449 systemd[1]: Reloading finished in 323 ms. Sep 4 00:01:54.916547 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 4 00:01:54.921541 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 4 00:01:55.005105 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 4 00:01:55.006347 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 4 00:01:55.022202 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 4 00:01:55.027054 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 4 00:01:55.028036 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 4 00:01:55.033185 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 4 00:01:55.038252 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 4 00:01:55.043764 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 4 00:01:55.048236 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 4 00:01:55.049209 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 4 00:01:55.053875 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). Sep 4 00:01:55.054924 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 4 00:01:55.060775 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Sep 4 00:01:55.066321 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 4 00:01:55.072940 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 4 00:01:55.077076 systemd[1]: Reached target time-set.target - System Time Set. Sep 4 00:01:55.083191 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 4 00:01:55.089277 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 00:01:55.093579 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 4 00:01:55.095124 systemd[1]: Finished ensure-sysext.service. Sep 4 00:01:55.098798 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 4 00:01:55.099014 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 4 00:01:55.105969 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 4 00:01:55.106169 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 4 00:01:55.110786 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 4 00:01:55.110971 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 4 00:01:55.112744 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 4 00:01:55.112925 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 4 00:01:55.120115 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 4 00:01:55.120214 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 4 00:01:55.121206 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 4 00:01:55.132843 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 4 00:01:55.142936 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Sep 4 00:01:55.163288 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 4 00:01:55.192990 augenrules[1851]: No rules Sep 4 00:01:55.194145 systemd[1]: audit-rules.service: Deactivated successfully. Sep 4 00:01:55.194408 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 4 00:01:55.218461 systemd-resolved[1822]: Positive Trust Anchors: Sep 4 00:01:55.218475 systemd-resolved[1822]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 4 00:01:55.218506 systemd-resolved[1822]: 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 Sep 4 00:01:55.232901 systemd-resolved[1822]: Using system hostname 'ci-4372.1.0-n-5e9b5c3608'. Sep 4 00:01:55.234914 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 4 00:01:55.236598 systemd[1]: Reached target network.target - Network. Sep 4 00:01:55.237252 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 4 00:01:55.349227 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 00:01:55.372837 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 4 00:01:55.378201 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). Sep 4 00:01:56.383509 systemd-networkd[1596]: eth0: Gained IPv6LL Sep 4 00:01:56.385843 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 4 00:01:56.391269 systemd[1]: Reached target network-online.target - Network is Online. Sep 4 00:01:58.342141 ldconfig[1544]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 4 00:01:58.353858 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 4 00:01:58.360029 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 4 00:01:58.394451 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 4 00:01:58.399171 systemd[1]: Reached target sysinit.target - System Initialization. Sep 4 00:01:58.404061 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 4 00:01:58.409140 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Sep 4 00:01:58.415241 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Sep 4 00:01:58.420006 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 4 00:01:58.424424 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 4 00:01:58.429730 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 4 00:01:58.434812 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 4 00:01:58.434850 systemd[1]: Reached target paths.target - Path Units. Sep 4 00:01:58.438742 systemd[1]: Reached target timers.target - Timer Units. Sep 4 00:01:58.443545 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 4 00:01:58.449428 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 4 00:01:58.455978 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Sep 4 00:01:58.461298 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Sep 4 00:01:58.466318 systemd[1]: Reached target ssh-access.target - SSH Access Available. Sep 4 00:01:58.472489 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 4 00:01:58.476874 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Sep 4 00:01:58.482289 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 4 00:01:58.487434 systemd[1]: Reached target sockets.target - Socket Units. Sep 4 00:01:58.491282 systemd[1]: Reached target basic.target - Basic System. Sep 4 00:01:58.495253 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 4 00:01:58.495290 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 4 00:01:58.497686 systemd[1]: Starting chronyd.service - NTP client/server... Sep 4 00:01:58.502049 systemd[1]: Starting containerd.service - containerd container runtime... Sep 4 00:01:58.517214 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Sep 4 00:01:58.522392 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 4 00:01:58.527224 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 4 00:01:58.533446 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 4 00:01:58.538875 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 4 00:01:58.539968 jq[1876]: false Sep 4 00:01:58.543263 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). Sep 4 00:01:58.544291 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Sep 4 00:01:58.545844 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). Sep 4 00:01:58.548729 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Sep 4 00:01:58.553013 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Sep 4 00:01:58.563326 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 4 00:01:58.564805 KVP[1879]: KVP starting; pid is:1879 Sep 4 00:01:58.567219 KVP[1879]: KVP LIC Version: 3.1 Sep 4 00:01:58.569376 kernel: hv_utils: KVP IC version 4.0 Sep 4 00:01:58.570424 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 4 00:01:58.575101 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 4 00:01:58.580765 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 4 00:01:58.581726 (chronyd)[1868]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Sep 4 00:01:58.582970 extend-filesystems[1877]: Found /dev/sda6 Sep 4 00:01:58.587567 extend-filesystems[1877]: Found /dev/sda9 Sep 4 00:01:58.587567 extend-filesystems[1877]: Checking size of /dev/sda9 Sep 4 00:01:58.586964 chronyd[1892]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) Sep 4 00:01:58.587292 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 4 00:01:58.606865 google_oslogin_nss_cache[1878]: oslogin_cache_refresh[1878]: Refreshing passwd entry cache Sep 4 00:01:58.606865 google_oslogin_nss_cache[1878]: oslogin_cache_refresh[1878]: Failure getting users, quitting Sep 4 00:01:58.606865 google_oslogin_nss_cache[1878]: oslogin_cache_refresh[1878]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Sep 4 00:01:58.606865 google_oslogin_nss_cache[1878]: oslogin_cache_refresh[1878]: Refreshing group entry cache Sep 4 00:01:58.593620 chronyd[1892]: Timezone right/UTC failed leap second check, ignoring Sep 4 00:01:58.589126 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 4 00:01:58.593951 chronyd[1892]: Loaded seccomp filter (level 2) Sep 4 00:01:58.589568 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 4 00:01:58.598183 oslogin_cache_refresh[1878]: Refreshing passwd entry cache Sep 4 00:01:58.590125 systemd[1]: Starting update-engine.service - Update Engine... Sep 4 00:01:58.606507 oslogin_cache_refresh[1878]: Failure getting users, quitting Sep 4 00:01:58.600923 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 4 00:01:58.606529 oslogin_cache_refresh[1878]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Sep 4 00:01:58.606572 oslogin_cache_refresh[1878]: Refreshing group entry cache Sep 4 00:01:58.608766 systemd[1]: Started chronyd.service - NTP client/server. Sep 4 00:01:58.611711 oslogin_cache_refresh[1878]: Failure getting groups, quitting Sep 4 00:01:58.613545 google_oslogin_nss_cache[1878]: oslogin_cache_refresh[1878]: Failure getting groups, quitting Sep 4 00:01:58.613545 google_oslogin_nss_cache[1878]: oslogin_cache_refresh[1878]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Sep 4 00:01:58.613599 jq[1901]: true Sep 4 00:01:58.611722 oslogin_cache_refresh[1878]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Sep 4 00:01:58.616058 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 4 00:01:58.621468 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 4 00:01:58.621685 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 4 00:01:58.622307 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Sep 4 00:01:58.622545 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Sep 4 00:01:58.622725 extend-filesystems[1877]: Old size kept for /dev/sda9 Sep 4 00:01:58.624712 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 4 00:01:58.633552 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 4 00:01:58.638534 systemd[1]: motdgen.service: Deactivated successfully. Sep 4 00:01:58.638746 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 4 00:01:58.643243 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 4 00:01:58.643473 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 4 00:01:58.657775 (ntainerd)[1916]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 4 00:01:58.660737 jq[1915]: true Sep 4 00:01:58.695713 systemd-logind[1894]: New seat seat0. Sep 4 00:01:58.698394 update_engine[1895]: I20250904 00:01:58.697649 1895 main.cc:92] Flatcar Update Engine starting Sep 4 00:01:58.700245 systemd-logind[1894]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Sep 4 00:01:58.701545 systemd[1]: Started systemd-logind.service - User Login Management. Sep 4 00:01:58.746508 bash[1998]: Updated "/home/core/.ssh/authorized_keys" Sep 4 00:01:58.748119 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 4 00:01:58.753759 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Sep 4 00:01:58.759091 dbus-daemon[1871]: [system] SELinux support is enabled Sep 4 00:01:58.759251 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 4 00:01:58.762048 update_engine[1895]: I20250904 00:01:58.761997 1895 update_check_scheduler.cc:74] Next update check in 10m13s Sep 4 00:01:58.765525 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). Sep 4 00:01:58.765558 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 4 00:01:58.766378 dbus-daemon[1871]: [system] Successfully activated service 'org.freedesktop.systemd1' Sep 4 00:01:58.770554 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). Sep 4 00:01:58.770581 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 4 00:01:58.775608 systemd[1]: Started update-engine.service - Update Engine. Sep 4 00:01:58.781372 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 4 00:01:58.786315 setup-nvidia[1884]: Downloading Flatcar Container Linux Developer Container for version: 4372.1.0+nightly-20250903-2100 Sep 4 00:01:58.826782 coreos-metadata[1870]: Sep 04 00:01:58.826 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Sep 4 00:01:58.829159 coreos-metadata[1870]: Sep 04 00:01:58.829 INFO Fetch successful Sep 4 00:01:58.829317 coreos-metadata[1870]: Sep 04 00:01:58.829 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Sep 4 00:01:58.833478 coreos-metadata[1870]: Sep 04 00:01:58.833 INFO Fetch successful Sep 4 00:01:58.833759 coreos-metadata[1870]: Sep 04 00:01:58.833 INFO Fetching http://168.63.129.16/machine/8288d08f-1470-4aa8-b230-3f147b37cd96/317d99f7%2Dbd78%2D431d%2Da4be%2D7a7103c50d96.%5Fci%2D4372.1.0%2Dn%2D5e9b5c3608?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Sep 4 00:01:58.865408 coreos-metadata[1870]: Sep 04 00:01:58.865 INFO Fetch successful Sep 4 00:01:58.865517 coreos-metadata[1870]: Sep 04 00:01:58.865 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Sep 4 00:01:58.872927 coreos-metadata[1870]: Sep 04 00:01:58.872 INFO Fetch successful Sep 4 00:01:58.906202 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Sep 4 00:01:58.911016 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 4 00:01:58.942128 locksmithd[2008]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 4 00:01:59.397381 setup-nvidia[2027]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 4 00:01:59.397700 setup-nvidia[2027]: Dload Upload Total Spent Left Speed Sep 4 00:01:59.412291 sshd_keygen[1905]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 4 00:01:59.433622 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 4 00:01:59.439750 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 4 00:01:59.470716 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Sep 4 00:01:59.476526 systemd[1]: issuegen.service: Deactivated successfully. Sep 4 00:01:59.476753 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 4 00:01:59.482497 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 4 00:01:59.521994 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Sep 4 00:01:59.526947 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 4 00:01:59.533798 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 4 00:01:59.538717 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Sep 4 00:01:59.543637 systemd[1]: Reached target getty.target - Login Prompts. Sep 4 00:01:59.741320 containerd[1916]: time="2025-09-04T00:01:59Z" 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 Sep 4 00:01:59.741878 containerd[1916]: time="2025-09-04T00:01:59.741847900Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 Sep 4 00:01:59.751250 containerd[1916]: time="2025-09-04T00:01:59.751212400Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="7.7µs" Sep 4 00:01:59.751250 containerd[1916]: time="2025-09-04T00:01:59.751241200Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Sep 4 00:01:59.751336 containerd[1916]: time="2025-09-04T00:01:59.751258500Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Sep 4 00:01:59.751462 containerd[1916]: time="2025-09-04T00:01:59.751436800Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Sep 4 00:01:59.751494 containerd[1916]: time="2025-09-04T00:01:59.751460600Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Sep 4 00:01:59.751494 containerd[1916]: time="2025-09-04T00:01:59.751486700Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 4 00:01:59.751571 containerd[1916]: time="2025-09-04T00:01:59.751547300Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 4 00:01:59.751571 containerd[1916]: time="2025-09-04T00:01:59.751565800Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 4 00:01:59.751789 containerd[1916]: time="2025-09-04T00:01:59.751764300Z" 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 Sep 4 00:01:59.751789 containerd[1916]: time="2025-09-04T00:01:59.751783700Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 4 00:01:59.751837 containerd[1916]: time="2025-09-04T00:01:59.751795200Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 4 00:01:59.751837 containerd[1916]: time="2025-09-04T00:01:59.751802700Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Sep 4 00:01:59.751888 containerd[1916]: time="2025-09-04T00:01:59.751876800Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Sep 4 00:01:59.752110 containerd[1916]: time="2025-09-04T00:01:59.752086700Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 4 00:01:59.752185 containerd[1916]: time="2025-09-04T00:01:59.752162100Z" 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 Sep 4 00:01:59.752185 containerd[1916]: time="2025-09-04T00:01:59.752180000Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Sep 4 00:01:59.752236 containerd[1916]: time="2025-09-04T00:01:59.752207400Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Sep 4 00:01:59.752427 containerd[1916]: time="2025-09-04T00:01:59.752403100Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Sep 4 00:01:59.752494 containerd[1916]: time="2025-09-04T00:01:59.752474000Z" level=info msg="metadata content store policy set" policy=shared Sep 4 00:01:59.767956 containerd[1916]: time="2025-09-04T00:01:59.767919000Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Sep 4 00:01:59.768019 containerd[1916]: time="2025-09-04T00:01:59.767960600Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Sep 4 00:01:59.768019 containerd[1916]: time="2025-09-04T00:01:59.767975400Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Sep 4 00:01:59.768019 containerd[1916]: time="2025-09-04T00:01:59.767987800Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Sep 4 00:01:59.768019 containerd[1916]: time="2025-09-04T00:01:59.767999400Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Sep 4 00:01:59.768113 containerd[1916]: time="2025-09-04T00:01:59.768025600Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Sep 4 00:01:59.768113 containerd[1916]: time="2025-09-04T00:01:59.768044300Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Sep 4 00:01:59.768113 containerd[1916]: time="2025-09-04T00:01:59.768056200Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Sep 4 00:01:59.768113 containerd[1916]: time="2025-09-04T00:01:59.768067100Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Sep 4 00:01:59.768113 containerd[1916]: time="2025-09-04T00:01:59.768076800Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Sep 4 00:01:59.768113 containerd[1916]: time="2025-09-04T00:01:59.768087300Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Sep 4 00:01:59.768113 containerd[1916]: time="2025-09-04T00:01:59.768098100Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Sep 4 00:01:59.768255 containerd[1916]: time="2025-09-04T00:01:59.768203100Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Sep 4 00:01:59.768255 containerd[1916]: time="2025-09-04T00:01:59.768224400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Sep 4 00:01:59.768255 containerd[1916]: time="2025-09-04T00:01:59.768239300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Sep 4 00:01:59.768255 containerd[1916]: time="2025-09-04T00:01:59.768250100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Sep 4 00:01:59.768333 containerd[1916]: time="2025-09-04T00:01:59.768259800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Sep 4 00:01:59.768333 containerd[1916]: time="2025-09-04T00:01:59.768275000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Sep 4 00:01:59.768333 containerd[1916]: time="2025-09-04T00:01:59.768286600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Sep 4 00:01:59.768333 containerd[1916]: time="2025-09-04T00:01:59.768298000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Sep 4 00:01:59.768333 containerd[1916]: time="2025-09-04T00:01:59.768309200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Sep 4 00:01:59.768333 containerd[1916]: time="2025-09-04T00:01:59.768319000Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Sep 4 00:01:59.768333 containerd[1916]: time="2025-09-04T00:01:59.768329800Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Sep 4 00:01:59.768510 containerd[1916]: time="2025-09-04T00:01:59.768430700Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Sep 4 00:01:59.768510 containerd[1916]: time="2025-09-04T00:01:59.768449800Z" level=info msg="Start snapshots syncer" Sep 4 00:01:59.768510 containerd[1916]: time="2025-09-04T00:01:59.768477500Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Sep 4 00:01:59.768763 containerd[1916]: time="2025-09-04T00:01:59.768714100Z" 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\"}" Sep 4 00:01:59.768875 containerd[1916]: time="2025-09-04T00:01:59.768782300Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Sep 4 00:01:59.768875 containerd[1916]: time="2025-09-04T00:01:59.768859600Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Sep 4 00:01:59.768987 containerd[1916]: time="2025-09-04T00:01:59.768962900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Sep 4 00:01:59.769022 containerd[1916]: time="2025-09-04T00:01:59.769004100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Sep 4 00:01:59.769047 containerd[1916]: time="2025-09-04T00:01:59.769020200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Sep 4 00:01:59.769047 containerd[1916]: time="2025-09-04T00:01:59.769032900Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Sep 4 00:01:59.769047 containerd[1916]: time="2025-09-04T00:01:59.769044400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Sep 4 00:01:59.769110 containerd[1916]: time="2025-09-04T00:01:59.769055500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Sep 4 00:01:59.769110 containerd[1916]: time="2025-09-04T00:01:59.769066200Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Sep 4 00:01:59.769110 containerd[1916]: time="2025-09-04T00:01:59.769086900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Sep 4 00:01:59.769110 containerd[1916]: time="2025-09-04T00:01:59.769099800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Sep 4 00:01:59.769197 containerd[1916]: time="2025-09-04T00:01:59.769110700Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Sep 4 00:01:59.769197 containerd[1916]: time="2025-09-04T00:01:59.769144100Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 4 00:01:59.769197 containerd[1916]: time="2025-09-04T00:01:59.769160000Z" 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 Sep 4 00:01:59.769197 containerd[1916]: time="2025-09-04T00:01:59.769169200Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 4 00:01:59.769197 containerd[1916]: time="2025-09-04T00:01:59.769178000Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 4 00:01:59.769197 containerd[1916]: time="2025-09-04T00:01:59.769185300Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Sep 4 00:01:59.769197 containerd[1916]: time="2025-09-04T00:01:59.769193800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Sep 4 00:01:59.769342 containerd[1916]: time="2025-09-04T00:01:59.769203500Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Sep 4 00:01:59.769342 containerd[1916]: time="2025-09-04T00:01:59.769220000Z" level=info msg="runtime interface created" Sep 4 00:01:59.769342 containerd[1916]: time="2025-09-04T00:01:59.769225600Z" level=info msg="created NRI interface" Sep 4 00:01:59.769342 containerd[1916]: time="2025-09-04T00:01:59.769233200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Sep 4 00:01:59.769342 containerd[1916]: time="2025-09-04T00:01:59.769243400Z" level=info msg="Connect containerd service" Sep 4 00:01:59.769342 containerd[1916]: time="2025-09-04T00:01:59.769267100Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 4 00:01:59.770023 containerd[1916]: time="2025-09-04T00:01:59.769962500Z" 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" Sep 4 00:02:00.667304 containerd[1916]: time="2025-09-04T00:02:00.667245700Z" level=info msg="Start subscribing containerd event" Sep 4 00:02:00.667463 containerd[1916]: time="2025-09-04T00:02:00.667381400Z" level=info msg="Start recovering state" Sep 4 00:02:00.667561 containerd[1916]: time="2025-09-04T00:02:00.667532300Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 4 00:02:00.667795 containerd[1916]: time="2025-09-04T00:02:00.667764800Z" level=info msg="Start event monitor" Sep 4 00:02:00.667854 containerd[1916]: time="2025-09-04T00:02:00.667795300Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 4 00:02:00.667854 containerd[1916]: time="2025-09-04T00:02:00.667799100Z" level=info msg="Start cni network conf syncer for default" Sep 4 00:02:00.667854 containerd[1916]: time="2025-09-04T00:02:00.667835100Z" level=info msg="Start streaming server" Sep 4 00:02:00.667854 containerd[1916]: time="2025-09-04T00:02:00.667844700Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Sep 4 00:02:00.667854 containerd[1916]: time="2025-09-04T00:02:00.667852600Z" level=info msg="runtime interface starting up..." Sep 4 00:02:00.667981 containerd[1916]: time="2025-09-04T00:02:00.667858500Z" level=info msg="starting plugins..." Sep 4 00:02:00.667981 containerd[1916]: time="2025-09-04T00:02:00.667881600Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Sep 4 00:02:00.668060 containerd[1916]: time="2025-09-04T00:02:00.668038400Z" level=info msg="containerd successfully booted in 0.927091s" Sep 4 00:02:00.668153 systemd[1]: Started containerd.service - containerd container runtime. Sep 4 00:02:00.896495 waagent[2047]: 2025-09-04T00:02:00.896413Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Sep 4 00:02:00.901394 waagent[2047]: 2025-09-04T00:02:00.901335Z INFO Daemon Daemon OS: flatcar 4372.1.0 Sep 4 00:02:00.905005 waagent[2047]: 2025-09-04T00:02:00.904961Z INFO Daemon Daemon Python: 3.11.12 Sep 4 00:02:00.908604 waagent[2047]: 2025-09-04T00:02:00.908561Z INFO Daemon Daemon Run daemon Sep 4 00:02:00.911803 waagent[2047]: 2025-09-04T00:02:00.911763Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4372.1.0' Sep 4 00:02:00.918760 waagent[2047]: 2025-09-04T00:02:00.918676Z INFO Daemon Daemon Using waagent for provisioning Sep 4 00:02:00.922776 waagent[2047]: 2025-09-04T00:02:00.922735Z INFO Daemon Daemon Activate resource disk Sep 4 00:02:00.926471 waagent[2047]: 2025-09-04T00:02:00.926429Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Sep 4 00:02:00.933772 waagent[2047]: 2025-09-04T00:02:00.933720Z INFO Daemon Daemon Found device: sdb Sep 4 00:02:00.942735 waagent[2047]: 2025-09-04T00:02:00.942688Z INFO Daemon Daemon Examining partition table Sep 4 00:02:00.980649 waagent[2047]: 2025-09-04T00:02:00.980590Z INFO Daemon Daemon GPT not detected, determining filesystem Sep 4 00:02:01.013245 waagent[2047]: 2025-09-04T00:02:01.013192Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Sep 4 00:02:01.017795 waagent[2047]: 2025-09-04T00:02:01.017701Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Sep 4 00:02:01.409380 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#109 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 4 00:02:01.421383 kernel: sdb: sdb1 Sep 4 00:02:01.429384 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#190 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 4 00:02:01.438444 waagent[2047]: 2025-09-04T00:02:01.438352Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Sep 4 00:02:01.449383 kernel: sdb: sdb1 Sep 4 00:02:01.458383 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#113 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 4 00:02:01.477859 waagent[2047]: 2025-09-04T00:02:01.477785Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Sep 4 00:02:01.478371 kernel: sdb: sdb1 Sep 4 00:02:06.997133 waagent[2047]: 2025-09-04T00:02:06.997055Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Sep 4 00:02:07.013010 login[2050]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Sep 4 00:02:07.013296 login[2049]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Sep 4 00:02:07.023545 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 4 00:02:07.024716 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 4 00:02:07.031436 systemd-logind[1894]: New session 2 of user core. Sep 4 00:02:07.069195 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 4 00:02:07.071832 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 4 00:02:07.097744 (systemd)[2155]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 4 00:02:07.100300 systemd-logind[1894]: New session c1 of user core. Sep 4 00:02:07.114019 waagent[2047]: 2025-09-04T00:02:07.113948Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Sep 4 00:02:07.116326 waagent[2047]: 2025-09-04T00:02:07.116179Z INFO Daemon Daemon Clean protocol and wireserver endpoint Sep 4 00:02:07.119456 waagent[2047]: 2025-09-04T00:02:07.119417Z INFO Daemon Daemon Running default provisioning handler Sep 4 00:02:07.125918 waagent[2047]: 2025-09-04T00:02:07.125868Z 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. Sep 4 00:02:07.127128 kernel: EXT4-fs (sdb1): mounted filesystem d84a4c46-c6b6-420b-86f5-ea669a525216 r/w with ordered data mode. Quota mode: none. Sep 4 00:02:07.129691 waagent[2047]: 2025-09-04T00:02:07.129653Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Sep 4 00:02:07.130049 waagent[2047]: 2025-09-04T00:02:07.130015Z INFO Daemon Daemon cloud-init is enabled: False Sep 4 00:02:07.131481 waagent[2047]: 2025-09-04T00:02:07.131448Z INFO Daemon Daemon Copying ovf-env.xml Sep 4 00:02:07.260634 waagent[2047]: 2025-09-04T00:02:07.260517Z INFO Daemon Daemon Successfully mounted dvd Sep 4 00:02:07.275204 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Sep 4 00:02:07.275401 waagent[2047]: 2025-09-04T00:02:07.275335Z INFO Daemon Daemon Detect protocol endpoint Sep 4 00:02:07.277058 waagent[2047]: 2025-09-04T00:02:07.277012Z INFO Daemon Daemon Clean protocol and wireserver endpoint Sep 4 00:02:07.278500 waagent[2047]: 2025-09-04T00:02:07.278463Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Sep 4 00:02:07.279200 waagent[2047]: 2025-09-04T00:02:07.279168Z INFO Daemon Daemon Test for route to 168.63.129.16 Sep 4 00:02:07.280760 waagent[2047]: 2025-09-04T00:02:07.280718Z INFO Daemon Daemon Route to 168.63.129.16 exists Sep 4 00:02:07.281404 waagent[2047]: 2025-09-04T00:02:07.281370Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Sep 4 00:02:07.306676 waagent[2047]: 2025-09-04T00:02:07.306631Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Sep 4 00:02:07.308439 waagent[2047]: 2025-09-04T00:02:07.308412Z INFO Daemon Daemon Wire protocol version:2012-11-30 Sep 4 00:02:07.309047 waagent[2047]: 2025-09-04T00:02:07.309013Z INFO Daemon Daemon Server preferred version:2015-04-05 Sep 4 00:02:07.350019 systemd[2155]: Queued start job for default target default.target. Sep 4 00:02:07.362899 systemd[2155]: Created slice app.slice - User Application Slice. Sep 4 00:02:07.362929 systemd[2155]: Reached target paths.target - Paths. Sep 4 00:02:07.362977 systemd[2155]: Reached target timers.target - Timers. Sep 4 00:02:07.366446 systemd[2155]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 4 00:02:07.375540 systemd[2155]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 4 00:02:07.375664 systemd[2155]: Reached target sockets.target - Sockets. Sep 4 00:02:07.375720 systemd[2155]: Reached target basic.target - Basic System. Sep 4 00:02:07.375767 systemd[2155]: Reached target default.target - Main User Target. Sep 4 00:02:07.375799 systemd[2155]: Startup finished in 268ms. Sep 4 00:02:07.375865 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 4 00:02:07.377264 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 4 00:02:07.451029 waagent[2047]: 2025-09-04T00:02:07.450951Z INFO Daemon Daemon Initializing goal state during protocol detection Sep 4 00:02:07.456117 waagent[2047]: 2025-09-04T00:02:07.456072Z INFO Daemon Daemon Forcing an update of the goal state. Sep 4 00:02:07.463561 waagent[2047]: 2025-09-04T00:02:07.463511Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Sep 4 00:02:07.499232 waagent[2047]: 2025-09-04T00:02:07.499185Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.175 Sep 4 00:02:07.501254 waagent[2047]: 2025-09-04T00:02:07.501214Z INFO Daemon Sep 4 00:02:07.502364 waagent[2047]: 2025-09-04T00:02:07.502314Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: bad5d277-4a93-4457-b137-531dbe243937 eTag: 12072640109511597426 source: Fabric] Sep 4 00:02:07.503336 waagent[2047]: 2025-09-04T00:02:07.503298Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Sep 4 00:02:07.504074 waagent[2047]: 2025-09-04T00:02:07.504037Z INFO Daemon Sep 4 00:02:07.505367 waagent[2047]: 2025-09-04T00:02:07.505315Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Sep 4 00:02:07.509473 waagent[2047]: 2025-09-04T00:02:07.509442Z INFO Daemon Daemon Downloading artifacts profile blob Sep 4 00:02:07.633078 waagent[2047]: 2025-09-04T00:02:07.632968Z INFO Daemon Downloaded certificate {'thumbprint': 'B61E550A767C4BD145056F7BFBA4CAC6479A3883', 'hasPrivateKey': True} Sep 4 00:02:07.634965 waagent[2047]: 2025-09-04T00:02:07.634921Z INFO Daemon Fetch goal state completed Sep 4 00:02:07.665471 waagent[2047]: 2025-09-04T00:02:07.665421Z INFO Daemon Daemon Starting provisioning Sep 4 00:02:07.667114 waagent[2047]: 2025-09-04T00:02:07.667061Z INFO Daemon Daemon Handle ovf-env.xml. Sep 4 00:02:07.668539 waagent[2047]: 2025-09-04T00:02:07.668502Z INFO Daemon Daemon Set hostname [ci-4372.1.0-n-5e9b5c3608] Sep 4 00:02:07.671149 waagent[2047]: 2025-09-04T00:02:07.671110Z INFO Daemon Daemon Publish hostname [ci-4372.1.0-n-5e9b5c3608] Sep 4 00:02:07.672310 waagent[2047]: 2025-09-04T00:02:07.672265Z INFO Daemon Daemon Examine /proc/net/route for primary interface Sep 4 00:02:07.673782 waagent[2047]: 2025-09-04T00:02:07.673746Z INFO Daemon Daemon Primary interface is [eth0] Sep 4 00:02:07.682787 systemd-networkd[1596]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 00:02:07.682797 systemd-networkd[1596]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 4 00:02:07.682822 systemd-networkd[1596]: eth0: DHCP lease lost Sep 4 00:02:07.683256 waagent[2047]: 2025-09-04T00:02:07.683210Z INFO Daemon Daemon Create user account if not exists Sep 4 00:02:07.684729 waagent[2047]: 2025-09-04T00:02:07.684694Z INFO Daemon Daemon User core already exists, skip useradd Sep 4 00:02:07.686167 waagent[2047]: 2025-09-04T00:02:07.686124Z INFO Daemon Daemon Configure sudoer Sep 4 00:02:07.691791 waagent[2047]: 2025-09-04T00:02:07.691750Z INFO Daemon Daemon Configure sshd Sep 4 00:02:07.695546 waagent[2047]: 2025-09-04T00:02:07.695464Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Sep 4 00:02:07.696326 waagent[2047]: 2025-09-04T00:02:07.696249Z INFO Daemon Daemon Deploy ssh public key. Sep 4 00:02:07.784404 systemd-networkd[1596]: eth0: DHCPv4 address 10.200.8.11/24, gateway 10.200.8.1 acquired from 168.63.129.16 Sep 4 00:02:08.014912 login[2050]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Sep 4 00:02:08.019549 systemd-logind[1894]: New session 1 of user core. Sep 4 00:02:08.033476 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 4 00:02:08.775108 waagent[2047]: 2025-09-04T00:02:08.775021Z INFO Daemon Daemon Provisioning complete Sep 4 00:02:08.787045 waagent[2047]: 2025-09-04T00:02:08.787001Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Sep 4 00:02:08.791765 waagent[2047]: 2025-09-04T00:02:08.791717Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Sep 4 00:02:08.800296 waagent[2047]: 2025-09-04T00:02:08.800244Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Sep 4 00:02:08.944344 waagent[2203]: 2025-09-04T00:02:08.944251Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Sep 4 00:02:08.944690 waagent[2203]: 2025-09-04T00:02:08.944409Z INFO ExtHandler ExtHandler OS: flatcar 4372.1.0 Sep 4 00:02:08.944690 waagent[2203]: 2025-09-04T00:02:08.944478Z INFO ExtHandler ExtHandler Python: 3.11.12 Sep 4 00:02:08.944690 waagent[2203]: 2025-09-04T00:02:08.944544Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Sep 4 00:02:08.989534 waagent[2203]: 2025-09-04T00:02:08.989467Z INFO ExtHandler ExtHandler Distro: flatcar-4372.1.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.12; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Sep 4 00:02:08.989733 waagent[2203]: 2025-09-04T00:02:08.989694Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 4 00:02:08.989811 waagent[2203]: 2025-09-04T00:02:08.989779Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 4 00:02:08.996800 waagent[2203]: 2025-09-04T00:02:08.996743Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Sep 4 00:02:09.001478 waagent[2203]: 2025-09-04T00:02:09.001437Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.175 Sep 4 00:02:09.001919 waagent[2203]: 2025-09-04T00:02:09.001881Z INFO ExtHandler Sep 4 00:02:09.002000 waagent[2203]: 2025-09-04T00:02:09.001967Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 4ff524b8-13d5-420c-bedd-802caa32fe83 eTag: 12072640109511597426 source: Fabric] Sep 4 00:02:09.002314 waagent[2203]: 2025-09-04T00:02:09.002275Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Sep 4 00:02:09.003279 waagent[2203]: 2025-09-04T00:02:09.003230Z INFO ExtHandler Sep 4 00:02:09.003347 waagent[2203]: 2025-09-04T00:02:09.003317Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Sep 4 00:02:09.006955 waagent[2203]: 2025-09-04T00:02:09.006922Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Sep 4 00:02:09.081282 waagent[2203]: 2025-09-04T00:02:09.081177Z INFO ExtHandler Downloaded certificate {'thumbprint': 'B61E550A767C4BD145056F7BFBA4CAC6479A3883', 'hasPrivateKey': True} Sep 4 00:02:09.081745 waagent[2203]: 2025-09-04T00:02:09.081705Z INFO ExtHandler Fetch goal state completed Sep 4 00:02:09.093349 waagent[2203]: 2025-09-04T00:02:09.093309Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.3.3 11 Feb 2025 (Library: OpenSSL 3.3.3 11 Feb 2025) Sep 4 00:02:09.098471 waagent[2203]: 2025-09-04T00:02:09.098430Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2203 Sep 4 00:02:09.098606 waagent[2203]: 2025-09-04T00:02:09.098573Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Sep 4 00:02:09.098928 waagent[2203]: 2025-09-04T00:02:09.098894Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Sep 4 00:02:09.100483 waagent[2203]: 2025-09-04T00:02:09.100443Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4372.1.0', '', 'Flatcar Container Linux by Kinvolk'] Sep 4 00:02:09.100913 waagent[2203]: 2025-09-04T00:02:09.100876Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4372.1.0', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Sep 4 00:02:09.101059 waagent[2203]: 2025-09-04T00:02:09.101027Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Sep 4 00:02:09.101661 waagent[2203]: 2025-09-04T00:02:09.101623Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Sep 4 00:02:09.166502 waagent[2203]: 2025-09-04T00:02:09.166468Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Sep 4 00:02:09.166677 waagent[2203]: 2025-09-04T00:02:09.166636Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Sep 4 00:02:09.172953 waagent[2203]: 2025-09-04T00:02:09.172928Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Sep 4 00:02:09.179579 systemd[1]: Reload requested from client PID 2218 ('systemctl') (unit waagent.service)... Sep 4 00:02:09.179596 systemd[1]: Reloading... Sep 4 00:02:09.243390 zram_generator::config[2260]: No configuration found. Sep 4 00:02:09.353713 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Sep 4 00:02:09.515693 systemd[1]: Reloading finished in 335 ms. Sep 4 00:02:09.544730 waagent[2203]: 2025-09-04T00:02:09.536752Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Sep 4 00:02:09.544730 waagent[2203]: 2025-09-04T00:02:09.536896Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Sep 4 00:02:09.912513 waagent[2203]: 2025-09-04T00:02:09.912432Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Sep 4 00:02:09.912813 waagent[2203]: 2025-09-04T00:02:09.912771Z 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] Sep 4 00:02:09.913619 waagent[2203]: 2025-09-04T00:02:09.913572Z INFO ExtHandler ExtHandler Starting env monitor service. Sep 4 00:02:09.913972 waagent[2203]: 2025-09-04T00:02:09.913924Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 4 00:02:09.914154 waagent[2203]: 2025-09-04T00:02:09.914112Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Sep 4 00:02:09.914405 waagent[2203]: 2025-09-04T00:02:09.914347Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 4 00:02:09.914788 waagent[2203]: 2025-09-04T00:02:09.914733Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Sep 4 00:02:09.914847 waagent[2203]: 2025-09-04T00:02:09.914805Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Sep 4 00:02:09.915283 waagent[2203]: 2025-09-04T00:02:09.915238Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 4 00:02:09.915893 waagent[2203]: 2025-09-04T00:02:09.915845Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Sep 4 00:02:09.916274 waagent[2203]: 2025-09-04T00:02:09.916229Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 4 00:02:09.916397 waagent[2203]: 2025-09-04T00:02:09.916325Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Sep 4 00:02:09.916575 waagent[2203]: 2025-09-04T00:02:09.916529Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Sep 4 00:02:09.916744 waagent[2203]: 2025-09-04T00:02:09.916700Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Sep 4 00:02:09.916744 waagent[2203]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Sep 4 00:02:09.916744 waagent[2203]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Sep 4 00:02:09.916744 waagent[2203]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Sep 4 00:02:09.916744 waagent[2203]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Sep 4 00:02:09.916744 waagent[2203]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Sep 4 00:02:09.916744 waagent[2203]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Sep 4 00:02:09.916999 waagent[2203]: 2025-09-04T00:02:09.916952Z INFO EnvHandler ExtHandler Configure routes Sep 4 00:02:09.917161 waagent[2203]: 2025-09-04T00:02:09.917123Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Sep 4 00:02:09.917302 waagent[2203]: 2025-09-04T00:02:09.917267Z INFO EnvHandler ExtHandler Gateway:None Sep 4 00:02:09.917845 waagent[2203]: 2025-09-04T00:02:09.917812Z INFO EnvHandler ExtHandler Routes:None Sep 4 00:02:09.932236 waagent[2203]: 2025-09-04T00:02:09.932192Z INFO ExtHandler ExtHandler Sep 4 00:02:09.932319 waagent[2203]: 2025-09-04T00:02:09.932284Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: b1e526c5-bb32-4e74-acb5-b6103cf41cc8 correlation 70660a36-99cf-4a2f-8e97-43fb052c867e created: 2025-09-04T00:00:51.461693Z] Sep 4 00:02:09.932697 waagent[2203]: 2025-09-04T00:02:09.932660Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Sep 4 00:02:09.933315 waagent[2203]: 2025-09-04T00:02:09.933279Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 1 ms] Sep 4 00:02:09.958899 waagent[2203]: 2025-09-04T00:02:09.958846Z 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 Sep 4 00:02:09.958899 waagent[2203]: Try `iptables -h' or 'iptables --help' for more information.) Sep 4 00:02:09.959268 waagent[2203]: 2025-09-04T00:02:09.959228Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: BA7DB7AF-746A-46AD-BB02-F9CF5E073381;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Sep 4 00:02:09.990197 waagent[2203]: 2025-09-04T00:02:09.990141Z INFO MonitorHandler ExtHandler Network interfaces: Sep 4 00:02:09.990197 waagent[2203]: Executing ['ip', '-a', '-o', 'link']: Sep 4 00:02:09.990197 waagent[2203]: 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 Sep 4 00:02:09.990197 waagent[2203]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:ed:8d:72:f7:b3 brd ff:ff:ff:ff:ff:ff Sep 4 00:02:09.990197 waagent[2203]: Executing ['ip', '-4', '-a', '-o', 'address']: Sep 4 00:02:09.990197 waagent[2203]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Sep 4 00:02:09.990197 waagent[2203]: 2: eth0 inet 10.200.8.11/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Sep 4 00:02:09.990197 waagent[2203]: Executing ['ip', '-6', '-a', '-o', 'address']: Sep 4 00:02:09.990197 waagent[2203]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Sep 4 00:02:09.990197 waagent[2203]: 2: eth0 inet6 fe80::7eed:8dff:fe72:f7b3/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Sep 4 00:02:10.053818 waagent[2203]: 2025-09-04T00:02:10.053758Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Sep 4 00:02:10.053818 waagent[2203]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Sep 4 00:02:10.053818 waagent[2203]: pkts bytes target prot opt in out source destination Sep 4 00:02:10.053818 waagent[2203]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Sep 4 00:02:10.053818 waagent[2203]: pkts bytes target prot opt in out source destination Sep 4 00:02:10.053818 waagent[2203]: Chain OUTPUT (policy ACCEPT 17 packets, 932 bytes) Sep 4 00:02:10.053818 waagent[2203]: pkts bytes target prot opt in out source destination Sep 4 00:02:10.053818 waagent[2203]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Sep 4 00:02:10.053818 waagent[2203]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Sep 4 00:02:10.053818 waagent[2203]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Sep 4 00:02:10.057777 waagent[2203]: 2025-09-04T00:02:10.057737Z INFO EnvHandler ExtHandler Current Firewall rules: Sep 4 00:02:10.057777 waagent[2203]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Sep 4 00:02:10.057777 waagent[2203]: pkts bytes target prot opt in out source destination Sep 4 00:02:10.057777 waagent[2203]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Sep 4 00:02:10.057777 waagent[2203]: pkts bytes target prot opt in out source destination Sep 4 00:02:10.057777 waagent[2203]: Chain OUTPUT (policy ACCEPT 21 packets, 1188 bytes) Sep 4 00:02:10.057777 waagent[2203]: pkts bytes target prot opt in out source destination Sep 4 00:02:10.057777 waagent[2203]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Sep 4 00:02:10.057777 waagent[2203]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Sep 4 00:02:10.057777 waagent[2203]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Sep 4 00:02:10.058025 waagent[2203]: 2025-09-04T00:02:10.057992Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Sep 4 00:02:10.058172 waagent[2203]: 2025-09-04T00:02:10.058142Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Sep 4 00:02:22.373398 chronyd[1892]: Selected source PHC0 Sep 4 00:02:24.909768 setup-nvidia[2027]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 544M 0 98304 0 0 109k 0 1:24:28 --:--:-- 1:24:28 109k\u000d 2 544M 2 13.7M 0 0 7135k 0 0:01:18 0:00:01 0:01:17 7133k\u000d 7 544M 7 38.6M 0 0 13.0M 0 0:00:41 0:00:02 0:00:39 13.0M\u000d 11 544M 11 62.9M 0 0 15.9M 0 0:00:34 0:00:03 0:00:31 15.9M\u000d 16 544M 16 87.2M 0 0 17.6M 0 0:00:30 0:00:04 0:00:26 17.6M\u000d 20 544M 20 111M 0 0 18.8M 0 0:00:28 0:00:05 0:00:23 22.0M\u000d 25 544M 25 136M 0 0 19.7M 0 0:00:27 0:00:06 0:00:21 24.8M\u000d 29 544M 29 159M 0 0 20.1M 0 0:00:26 0:00:07 0:00:19 24.4M\u000d 33 544M 33 181M 0 0 20.2M 0 0:00:26 0:00:08 0:00:18 23.6M\u000d 37 544M 37 205M 0 0 20.6M 0 0:00:26 0:00:09 0:00:17 23.6M\u000d 40 544M 40 221M 0 0 20.1M 0 0:00:26 0:00:10 0:00:16 21.8M\u000d 43 544M 43 235M 0 0 19.7M 0 0:00:27 0:00:11 0:00:16 19.6M\u000d 47 544M 47 258M 0 0 20.0M 0 0:00:27 0:00:12 0:00:15 19.8M\u000d 52 544M 52 283M 0 0 20.4M 0 0:00:26 0:00:13 0:00:13 20.7M\u000d 56 544M 56 307M 0 0 20.6M 0 0:00:26 0:00:14 0:00:12 20.6M\u000d 61 544M 61 332M 0 0 20.9M 0 0:00:26 0:00:15 0:00:11 22.4M\u000d 65 544M 65 357M 0 0 21.1M 0 0:00:25 0:00:16 0:00:09 24.6M\u000d 70 544M 70 382M 0 0 21.2M 0 0:00:25 0:00:17 0:00:08 24.3M\u000d 74 544M 74 403M 0 0 21.4M 0 0:00:25 0:00:18 0:00:07 24.2M\u000d 78 544M 78 428M 0 0 21.4M 0 0:00:25 0:00:19 0:00:06 23.7M\u000d 82 544M 82 446M 0 0 21.3M 0 0:00:25 0:00:20 0:00:05 22.5M\u000d 85 544M 85 467M 0 0 21.3M 0 0:00:25 0:00:21 0:00:04 21.7M\u000d 89 544M 89 487M 0 0 21.2M 0 0:00:25 0:00:22 0:00:03 21.4M\u000d 93 544M 93 508M 0 0 21.2M 0 0:00:25 0:00:23 0:00:02 20.8M\u000d 97 544M 97 530M 0 0 21.2M 0 0:00:25 0:00:24 0:00:01 20.5M\u000d100 544M 100 544M 0 0 21.3M 0 0:00:25 0:00:25 --:--:-- 21.3M Sep 4 00:02:36.827151 setup-nvidia[1884]: Downloading NVIDIA 535.230.02 Driver Sep 4 00:02:36.831496 setup-nvidia[2358]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 4 00:02:36.831724 setup-nvidia[2358]: Dload Upload Total Spent Left Speed Sep 4 00:02:38.408835 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 4 00:02:38.411719 systemd[1]: Started sshd@0-10.200.8.11:22-10.200.16.10:37642.service - OpenSSH per-connection server daemon (10.200.16.10:37642). Sep 4 00:02:39.359854 sshd[2360]: Accepted publickey for core from 10.200.16.10 port 37642 ssh2: RSA SHA256:5Jrr4CY4DiQCm3wS0brkuZBne5yEe8GvBoV7RW32xa4 Sep 4 00:02:39.361167 sshd-session[2360]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 00:02:39.365529 systemd-logind[1894]: New session 3 of user core. Sep 4 00:02:39.382607 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 4 00:02:39.926872 systemd[1]: Started sshd@1-10.200.8.11:22-10.200.16.10:37260.service - OpenSSH per-connection server daemon (10.200.16.10:37260). Sep 4 00:02:40.233745 setup-nvidia[2358]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 326M 0 16384 0 0 34428 0 2:45:30 --:--:-- 2:45:30 34420\u000d 16 326M 16 52.8M 0 0 37.0M 0 0:00:08 0:00:01 0:00:07 36.9M\u000d 55 326M 55 180M 0 0 74.3M 0 0:00:04 0:00:02 0:00:02 74.3M\u000d100 326M 100 326M 0 0 95.8M 0 0:00:03 0:00:03 --:--:-- 95.8M Sep 4 00:02:40.296244 setup-nvidia[1884]: Extract the NVIDIA Driver Installer 535.230.02 Sep 4 00:02:40.296244 setup-nvidia[1884]: /opt/nvidia/workdir/nvidia-workdir / Sep 4 00:02:40.345331 setup-nvidia[2369]: Creating directory NVIDIA-Linux-x86_64-535.230.02 Sep 4 00:02:40.571514 sshd[2365]: Accepted publickey for core from 10.200.16.10 port 37260 ssh2: RSA SHA256:5Jrr4CY4DiQCm3wS0brkuZBne5yEe8GvBoV7RW32xa4 Sep 4 00:02:40.572871 sshd-session[2365]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 00:02:40.577115 systemd-logind[1894]: New session 4 of user core. Sep 4 00:02:40.586478 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 4 00:02:40.594847 setup-nvidia[2369]: Verifying archive integrity... OK Sep 4 00:02:41.025150 sshd[2388]: Connection closed by 10.200.16.10 port 37260 Sep 4 00:02:41.025435 sshd-session[2365]: pam_unix(sshd:session): session closed for user core Sep 4 00:02:41.028853 systemd[1]: sshd@1-10.200.8.11:22-10.200.16.10:37260.service: Deactivated successfully. Sep 4 00:02:41.030409 systemd[1]: session-4.scope: Deactivated successfully. Sep 4 00:02:41.031075 systemd-logind[1894]: Session 4 logged out. Waiting for processes to exit. Sep 4 00:02:41.032045 systemd-logind[1894]: Removed session 4. Sep 4 00:02:41.141826 systemd[1]: Started sshd@2-10.200.8.11:22-10.200.16.10:37264.service - OpenSSH per-connection server daemon (10.200.16.10:37264). Sep 4 00:02:41.422955 setup-nvidia[2369]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.230.02 Sep 4 00:02:41.784883 sshd[2398]: Accepted publickey for core from 10.200.16.10 port 37264 ssh2: RSA SHA256:5Jrr4CY4DiQCm3wS0brkuZBne5yEe8GvBoV7RW32xa4 Sep 4 00:02:41.786262 sshd-session[2398]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 00:02:41.790733 systemd-logind[1894]: New session 5 of user core. Sep 4 00:02:41.800502 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 4 00:02:42.237376 sshd[2407]: Connection closed by 10.200.16.10 port 37264 Sep 4 00:02:42.237741 sshd-session[2398]: pam_unix(sshd:session): session closed for user core Sep 4 00:02:42.241708 systemd[1]: sshd@2-10.200.8.11:22-10.200.16.10:37264.service: Deactivated successfully. Sep 4 00:02:42.243529 systemd[1]: session-5.scope: Deactivated successfully. Sep 4 00:02:42.244251 systemd-logind[1894]: Session 5 logged out. Waiting for processes to exit. Sep 4 00:02:42.245408 systemd-logind[1894]: Removed session 5. Sep 4 00:02:42.573206 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Sep 4 00:02:43.193348 setup-nvidia[2403]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Sep 4 00:02:43.306547 setup-nvidia[1884]: / Sep 4 00:02:43.306821 setup-nvidia[1884]: Spawn system-nspawn container to install the NVIDIA drivers Sep 4 00:02:43.527381 sudo[2415]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4372.1.0+nightly-20250903-2100.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.230.02 Sep 4 00:02:43.529083 sudo[2415]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 4 00:02:43.535068 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Sep 4 00:02:43.537737 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Sep 4 00:02:43.565592 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Sep 4 00:02:43.569457 systemd[1]: Starting user@0.service - User Manager for UID 0... Sep 4 00:02:43.590575 (systemd)[2418]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Sep 4 00:02:43.592898 systemd-logind[1894]: New session c3 of user root. Sep 4 00:02:43.772126 systemd[2418]: Queued start job for default target default.target. Sep 4 00:02:43.794535 systemd[2418]: Created slice app.slice - User Application Slice. Sep 4 00:02:43.794565 systemd[2418]: Reached target paths.target - Paths. Sep 4 00:02:43.794611 systemd[2418]: Reached target timers.target - Timers. Sep 4 00:02:43.795846 systemd[2418]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 4 00:02:43.806197 systemd[2418]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 4 00:02:43.806254 systemd[2418]: Reached target sockets.target - Sockets. Sep 4 00:02:43.806298 systemd[2418]: Reached target basic.target - Basic System. Sep 4 00:02:43.806345 systemd[2418]: Reached target default.target - Main User Target. Sep 4 00:02:43.806393 systemd[2418]: Startup finished in 207ms. Sep 4 00:02:43.806480 systemd[1]: Started user@0.service - User Manager for UID 0. Sep 4 00:02:43.812142 systemd[1]: Started session-c2.scope - Session c2 of User root. Sep 4 00:02:43.920387 kernel: loop6: detected capacity change from 0 to 12644352 Sep 4 00:02:43.991382 kernel: loop6: p9 Sep 4 00:02:44.084720 update_engine[1895]: I20250904 00:02:44.084663 1895 update_attempter.cc:509] Updating boot flags... Sep 4 00:02:44.229463 setup-nvidia[2427]: ░ Spawning container flatcardevelopercontainer-4372.1.0nightly-20250903-2100.bin on /opt/nvidia/workdir/flatcar_developer_container-4372.1.0+nightly-20250903-2100.bin. Sep 4 00:02:46.794117 kernel: EXT4-fs (loop6p9): write access unavailable, skipping orphan cleanup Sep 4 00:02:46.794254 kernel: EXT4-fs (loop6p9): mounted filesystem dd3fcba5-16d8-4332-8af8-78f16c3a22d0 ro without journal. Quota mode: none. Sep 4 00:02:52.378387 systemd[1]: Started sshd@3-10.200.8.11:22-10.200.16.10:57886.service - OpenSSH per-connection server daemon (10.200.16.10:57886). Sep 4 00:02:53.027570 sshd[2625]: Accepted publickey for core from 10.200.16.10 port 57886 ssh2: RSA SHA256:5Jrr4CY4DiQCm3wS0brkuZBne5yEe8GvBoV7RW32xa4 Sep 4 00:02:53.028868 sshd-session[2625]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 00:02:53.033413 systemd-logind[1894]: New session 6 of user core. Sep 4 00:02:53.049511 systemd[1]: Started session-6.scope - Session 6 of User core. Sep 4 00:02:53.478377 sshd[2664]: Connection closed by 10.200.16.10 port 57886 Sep 4 00:02:53.478710 sshd-session[2625]: pam_unix(sshd:session): session closed for user core Sep 4 00:02:53.481870 systemd[1]: sshd@3-10.200.8.11:22-10.200.16.10:57886.service: Deactivated successfully. Sep 4 00:02:53.483476 systemd[1]: session-6.scope: Deactivated successfully. Sep 4 00:02:53.484259 systemd-logind[1894]: Session 6 logged out. Waiting for processes to exit. Sep 4 00:02:53.485236 systemd-logind[1894]: Removed session 6. Sep 4 00:02:53.595742 systemd[1]: Started sshd@4-10.200.8.11:22-10.200.16.10:57902.service - OpenSSH per-connection server daemon (10.200.16.10:57902). Sep 4 00:02:54.250549 sshd[2776]: Accepted publickey for core from 10.200.16.10 port 57902 ssh2: RSA SHA256:5Jrr4CY4DiQCm3wS0brkuZBne5yEe8GvBoV7RW32xa4 Sep 4 00:02:54.251856 sshd-session[2776]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 00:02:54.256116 systemd-logind[1894]: New session 7 of user core. Sep 4 00:02:54.266606 systemd[1]: Started session-7.scope - Session 7 of User core. Sep 4 00:02:54.701448 sshd[2950]: Connection closed by 10.200.16.10 port 57902 Sep 4 00:02:54.702094 sshd-session[2776]: pam_unix(sshd:session): session closed for user core Sep 4 00:02:54.705615 systemd[1]: sshd@4-10.200.8.11:22-10.200.16.10:57902.service: Deactivated successfully. Sep 4 00:02:54.707118 systemd[1]: session-7.scope: Deactivated successfully. Sep 4 00:02:54.707810 systemd-logind[1894]: Session 7 logged out. Waiting for processes to exit. Sep 4 00:02:54.709049 systemd-logind[1894]: Removed session 7. Sep 4 00:03:04.827851 systemd[1]: Started sshd@5-10.200.8.11:22-10.200.16.10:39862.service - OpenSSH per-connection server daemon (10.200.16.10:39862). Sep 4 00:03:05.472187 sshd[3284]: Accepted publickey for core from 10.200.16.10 port 39862 ssh2: RSA SHA256:5Jrr4CY4DiQCm3wS0brkuZBne5yEe8GvBoV7RW32xa4 Sep 4 00:03:05.473522 sshd-session[3284]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 00:03:05.477956 systemd-logind[1894]: New session 8 of user core. Sep 4 00:03:05.501482 systemd[1]: Started session-8.scope - Session 8 of User core. Sep 4 00:03:05.942928 sshd[3286]: Connection closed by 10.200.16.10 port 39862 Sep 4 00:03:05.943275 sshd-session[3284]: pam_unix(sshd:session): session closed for user core Sep 4 00:03:05.946603 systemd[1]: sshd@5-10.200.8.11:22-10.200.16.10:39862.service: Deactivated successfully. Sep 4 00:03:05.948115 systemd[1]: session-8.scope: Deactivated successfully. Sep 4 00:03:05.948802 systemd-logind[1894]: Session 8 logged out. Waiting for processes to exit. Sep 4 00:03:05.949717 systemd-logind[1894]: Removed session 8. Sep 4 00:03:06.059669 systemd[1]: Started sshd@6-10.200.8.11:22-10.200.16.10:39866.service - OpenSSH per-connection server daemon (10.200.16.10:39866). Sep 4 00:03:06.703725 sshd[3292]: Accepted publickey for core from 10.200.16.10 port 39866 ssh2: RSA SHA256:5Jrr4CY4DiQCm3wS0brkuZBne5yEe8GvBoV7RW32xa4 Sep 4 00:03:06.705024 sshd-session[3292]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 00:03:06.709440 systemd-logind[1894]: New session 9 of user core. Sep 4 00:03:06.722508 systemd[1]: Started session-9.scope - Session 9 of User core. Sep 4 00:03:07.155851 sshd[3294]: Connection closed by 10.200.16.10 port 39866 Sep 4 00:03:07.156206 sshd-session[3292]: pam_unix(sshd:session): session closed for user core Sep 4 00:03:07.159518 systemd[1]: sshd@6-10.200.8.11:22-10.200.16.10:39866.service: Deactivated successfully. Sep 4 00:03:07.161048 systemd[1]: session-9.scope: Deactivated successfully. Sep 4 00:03:07.161728 systemd-logind[1894]: Session 9 logged out. Waiting for processes to exit. Sep 4 00:03:07.162650 systemd-logind[1894]: Removed session 9. Sep 4 00:03:17.273600 systemd[1]: Started sshd@7-10.200.8.11:22-10.200.16.10:43844.service - OpenSSH per-connection server daemon (10.200.16.10:43844). Sep 4 00:03:17.925981 sshd[3345]: Accepted publickey for core from 10.200.16.10 port 43844 ssh2: RSA SHA256:5Jrr4CY4DiQCm3wS0brkuZBne5yEe8GvBoV7RW32xa4 Sep 4 00:03:17.927342 sshd-session[3345]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 00:03:17.931591 systemd-logind[1894]: New session 10 of user core. Sep 4 00:03:17.948493 systemd[1]: Started session-10.scope - Session 10 of User core. Sep 4 00:03:18.390289 sshd[3347]: Connection closed by 10.200.16.10 port 43844 Sep 4 00:03:18.390662 sshd-session[3345]: pam_unix(sshd:session): session closed for user core Sep 4 00:03:18.394011 systemd[1]: sshd@7-10.200.8.11:22-10.200.16.10:43844.service: Deactivated successfully. Sep 4 00:03:18.395532 systemd[1]: session-10.scope: Deactivated successfully. Sep 4 00:03:18.396176 systemd-logind[1894]: Session 10 logged out. Waiting for processes to exit. Sep 4 00:03:18.397089 systemd-logind[1894]: Removed session 10. Sep 4 00:03:18.506573 systemd[1]: Started sshd@8-10.200.8.11:22-10.200.16.10:43846.service - OpenSSH per-connection server daemon (10.200.16.10:43846). Sep 4 00:03:19.148746 sshd[3353]: Accepted publickey for core from 10.200.16.10 port 43846 ssh2: RSA SHA256:5Jrr4CY4DiQCm3wS0brkuZBne5yEe8GvBoV7RW32xa4 Sep 4 00:03:19.150101 sshd-session[3353]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 00:03:19.154405 systemd-logind[1894]: New session 11 of user core. Sep 4 00:03:19.166486 systemd[1]: Started session-11.scope - Session 11 of User core. Sep 4 00:03:19.600776 sshd[3355]: Connection closed by 10.200.16.10 port 43846 Sep 4 00:03:19.601126 sshd-session[3353]: pam_unix(sshd:session): session closed for user core Sep 4 00:03:19.604499 systemd[1]: sshd@8-10.200.8.11:22-10.200.16.10:43846.service: Deactivated successfully. Sep 4 00:03:19.606000 systemd[1]: session-11.scope: Deactivated successfully. Sep 4 00:03:19.606674 systemd-logind[1894]: Session 11 logged out. Waiting for processes to exit. Sep 4 00:03:19.607605 systemd-logind[1894]: Removed session 11. Sep 4 00:03:29.718789 systemd[1]: Started sshd@9-10.200.8.11:22-10.200.16.10:57072.service - OpenSSH per-connection server daemon (10.200.16.10:57072). Sep 4 00:03:30.381745 sshd[4131]: Accepted publickey for core from 10.200.16.10 port 57072 ssh2: RSA SHA256:5Jrr4CY4DiQCm3wS0brkuZBne5yEe8GvBoV7RW32xa4 Sep 4 00:03:30.383098 sshd-session[4131]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 00:03:30.387435 systemd-logind[1894]: New session 12 of user core. Sep 4 00:03:30.401506 systemd[1]: Started session-12.scope - Session 12 of User core. Sep 4 00:03:30.854420 sshd[4448]: Connection closed by 10.200.16.10 port 57072 Sep 4 00:03:30.854737 sshd-session[4131]: pam_unix(sshd:session): session closed for user core Sep 4 00:03:30.858119 systemd[1]: sshd@9-10.200.8.11:22-10.200.16.10:57072.service: Deactivated successfully. Sep 4 00:03:30.859642 systemd[1]: session-12.scope: Deactivated successfully. Sep 4 00:03:30.860308 systemd-logind[1894]: Session 12 logged out. Waiting for processes to exit. Sep 4 00:03:30.861536 systemd-logind[1894]: Removed session 12. Sep 4 00:03:30.968321 systemd[1]: Started sshd@10-10.200.8.11:22-10.200.16.10:41816.service - OpenSSH per-connection server daemon (10.200.16.10:41816). Sep 4 00:03:31.612573 sshd[4768]: Accepted publickey for core from 10.200.16.10 port 41816 ssh2: RSA SHA256:5Jrr4CY4DiQCm3wS0brkuZBne5yEe8GvBoV7RW32xa4 Sep 4 00:03:31.613946 sshd-session[4768]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 00:03:31.618144 systemd-logind[1894]: New session 13 of user core. Sep 4 00:03:31.631510 systemd[1]: Started session-13.scope - Session 13 of User core. Sep 4 00:03:32.086129 sshd[4973]: Connection closed by 10.200.16.10 port 41816 Sep 4 00:03:32.086475 sshd-session[4768]: pam_unix(sshd:session): session closed for user core Sep 4 00:03:32.089822 systemd[1]: sshd@10-10.200.8.11:22-10.200.16.10:41816.service: Deactivated successfully. Sep 4 00:03:32.091328 systemd[1]: session-13.scope: Deactivated successfully. Sep 4 00:03:32.092100 systemd-logind[1894]: Session 13 logged out. Waiting for processes to exit. Sep 4 00:03:32.093170 systemd-logind[1894]: Removed session 13. Sep 4 00:03:42.204575 systemd[1]: Started sshd@11-10.200.8.11:22-10.200.16.10:44210.service - OpenSSH per-connection server daemon (10.200.16.10:44210). Sep 4 00:03:42.847768 sshd[5938]: Accepted publickey for core from 10.200.16.10 port 44210 ssh2: RSA SHA256:5Jrr4CY4DiQCm3wS0brkuZBne5yEe8GvBoV7RW32xa4 Sep 4 00:03:42.849141 sshd-session[5938]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 00:03:42.854402 systemd-logind[1894]: New session 14 of user core. Sep 4 00:03:42.862489 systemd[1]: Started session-14.scope - Session 14 of User core. Sep 4 00:03:43.305811 sshd[6076]: Connection closed by 10.200.16.10 port 44210 Sep 4 00:03:43.307537 sshd-session[5938]: pam_unix(sshd:session): session closed for user core Sep 4 00:03:43.310756 systemd-logind[1894]: Session 14 logged out. Waiting for processes to exit. Sep 4 00:03:43.313154 systemd[1]: sshd@11-10.200.8.11:22-10.200.16.10:44210.service: Deactivated successfully. Sep 4 00:03:43.315291 systemd[1]: session-14.scope: Deactivated successfully. Sep 4 00:03:43.319020 systemd-logind[1894]: Removed session 14. Sep 4 00:03:43.426328 systemd[1]: Started sshd@12-10.200.8.11:22-10.200.16.10:44212.service - OpenSSH per-connection server daemon (10.200.16.10:44212). Sep 4 00:03:44.076874 sshd[6192]: Accepted publickey for core from 10.200.16.10 port 44212 ssh2: RSA SHA256:5Jrr4CY4DiQCm3wS0brkuZBne5yEe8GvBoV7RW32xa4 Sep 4 00:03:44.078586 sshd-session[6192]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 00:03:44.086733 systemd-logind[1894]: New session 15 of user core. Sep 4 00:03:44.090558 systemd[1]: Started session-15.scope - Session 15 of User core. Sep 4 00:03:44.529898 sshd[6427]: Connection closed by 10.200.16.10 port 44212 Sep 4 00:03:44.530168 sshd-session[6192]: pam_unix(sshd:session): session closed for user core Sep 4 00:03:44.534341 systemd-logind[1894]: Session 15 logged out. Waiting for processes to exit. Sep 4 00:03:44.536009 systemd[1]: sshd@12-10.200.8.11:22-10.200.16.10:44212.service: Deactivated successfully. Sep 4 00:03:44.538715 systemd[1]: session-15.scope: Deactivated successfully. Sep 4 00:03:44.541226 systemd-logind[1894]: Removed session 15. Sep 4 00:03:54.648157 systemd[1]: Started sshd@13-10.200.8.11:22-10.200.16.10:59998.service - OpenSSH per-connection server daemon (10.200.16.10:59998). Sep 4 00:03:55.309784 sshd[10132]: Accepted publickey for core from 10.200.16.10 port 59998 ssh2: RSA SHA256:5Jrr4CY4DiQCm3wS0brkuZBne5yEe8GvBoV7RW32xa4 Sep 4 00:03:55.311736 sshd-session[10132]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 00:03:55.319282 systemd-logind[1894]: New session 16 of user core. Sep 4 00:03:55.326989 systemd[1]: Started session-16.scope - Session 16 of User core. Sep 4 00:03:55.786960 sshd[10254]: Connection closed by 10.200.16.10 port 59998 Sep 4 00:03:55.787570 sshd-session[10132]: pam_unix(sshd:session): session closed for user core Sep 4 00:03:55.790635 systemd-logind[1894]: Session 16 logged out. Waiting for processes to exit. Sep 4 00:03:55.791283 systemd[1]: sshd@13-10.200.8.11:22-10.200.16.10:59998.service: Deactivated successfully. Sep 4 00:03:55.794014 systemd[1]: session-16.scope: Deactivated successfully. Sep 4 00:03:55.797009 systemd-logind[1894]: Removed session 16. Sep 4 00:03:55.919616 systemd[1]: Started sshd@14-10.200.8.11:22-10.200.16.10:60014.service - OpenSSH per-connection server daemon (10.200.16.10:60014). Sep 4 00:03:56.607927 sshd[10431]: Accepted publickey for core from 10.200.16.10 port 60014 ssh2: RSA SHA256:5Jrr4CY4DiQCm3wS0brkuZBne5yEe8GvBoV7RW32xa4 Sep 4 00:03:56.609865 sshd-session[10431]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 00:03:56.615611 systemd-logind[1894]: New session 17 of user core. Sep 4 00:03:56.624571 systemd[1]: Started session-17.scope - Session 17 of User core. Sep 4 00:03:57.068752 sshd[10673]: Connection closed by 10.200.16.10 port 60014 Sep 4 00:03:57.069120 sshd-session[10431]: pam_unix(sshd:session): session closed for user core Sep 4 00:03:57.073761 systemd-logind[1894]: Session 17 logged out. Waiting for processes to exit. Sep 4 00:03:57.076724 systemd[1]: sshd@14-10.200.8.11:22-10.200.16.10:60014.service: Deactivated successfully. Sep 4 00:03:57.078723 systemd[1]: session-17.scope: Deactivated successfully. Sep 4 00:03:57.081526 systemd-logind[1894]: Removed session 17. Sep 4 00:04:07.192740 systemd[1]: Started sshd@15-10.200.8.11:22-10.200.16.10:35188.service - OpenSSH per-connection server daemon (10.200.16.10:35188). Sep 4 00:04:07.851378 sshd[11762]: Accepted publickey for core from 10.200.16.10 port 35188 ssh2: RSA SHA256:5Jrr4CY4DiQCm3wS0brkuZBne5yEe8GvBoV7RW32xa4 Sep 4 00:04:07.853291 sshd-session[11762]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 00:04:07.861537 systemd-logind[1894]: New session 18 of user core. Sep 4 00:04:07.866996 systemd[1]: Started session-18.scope - Session 18 of User core. Sep 4 00:04:08.331139 sshd[11783]: Connection closed by 10.200.16.10 port 35188 Sep 4 00:04:08.333227 sshd-session[11762]: pam_unix(sshd:session): session closed for user core Sep 4 00:04:08.336824 systemd[1]: sshd@15-10.200.8.11:22-10.200.16.10:35188.service: Deactivated successfully. Sep 4 00:04:08.339096 systemd[1]: session-18.scope: Deactivated successfully. Sep 4 00:04:08.339132 systemd-logind[1894]: Session 18 logged out. Waiting for processes to exit. Sep 4 00:04:08.342794 systemd-logind[1894]: Removed session 18. Sep 4 00:04:08.451367 systemd[1]: Started sshd@16-10.200.8.11:22-10.200.16.10:35198.service - OpenSSH per-connection server daemon (10.200.16.10:35198). Sep 4 00:04:09.118822 sshd[11800]: Accepted publickey for core from 10.200.16.10 port 35198 ssh2: RSA SHA256:5Jrr4CY4DiQCm3wS0brkuZBne5yEe8GvBoV7RW32xa4 Sep 4 00:04:09.120624 sshd-session[11800]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 00:04:09.127045 systemd-logind[1894]: New session 19 of user core. Sep 4 00:04:09.137060 systemd[1]: Started session-19.scope - Session 19 of User core. Sep 4 00:04:09.572503 sshd[11832]: Connection closed by 10.200.16.10 port 35198 Sep 4 00:04:09.574468 sshd-session[11800]: pam_unix(sshd:session): session closed for user core Sep 4 00:04:09.577787 systemd-logind[1894]: Session 19 logged out. Waiting for processes to exit. Sep 4 00:04:09.580505 systemd[1]: sshd@16-10.200.8.11:22-10.200.16.10:35198.service: Deactivated successfully. Sep 4 00:04:09.582826 systemd[1]: session-19.scope: Deactivated successfully. Sep 4 00:04:09.586877 systemd-logind[1894]: Removed session 19. Sep 4 00:04:19.693606 systemd[1]: Started sshd@17-10.200.8.11:22-10.200.16.10:53146.service - OpenSSH per-connection server daemon (10.200.16.10:53146). Sep 4 00:04:20.365957 sshd[12174]: Accepted publickey for core from 10.200.16.10 port 53146 ssh2: RSA SHA256:5Jrr4CY4DiQCm3wS0brkuZBne5yEe8GvBoV7RW32xa4 Sep 4 00:04:20.367821 sshd-session[12174]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 00:04:20.376482 systemd-logind[1894]: New session 20 of user core. Sep 4 00:04:20.386660 systemd[1]: Started session-20.scope - Session 20 of User core. Sep 4 00:04:20.823942 sshd[12189]: Connection closed by 10.200.16.10 port 53146 Sep 4 00:04:20.825970 sshd-session[12174]: pam_unix(sshd:session): session closed for user core Sep 4 00:04:20.829379 systemd[1]: sshd@17-10.200.8.11:22-10.200.16.10:53146.service: Deactivated successfully. Sep 4 00:04:20.831395 systemd[1]: session-20.scope: Deactivated successfully. Sep 4 00:04:20.833837 systemd-logind[1894]: Session 20 logged out. Waiting for processes to exit. Sep 4 00:04:20.835180 systemd-logind[1894]: Removed session 20. Sep 4 00:04:20.939454 systemd[1]: Started sshd@18-10.200.8.11:22-10.200.16.10:38932.service - OpenSSH per-connection server daemon (10.200.16.10:38932). Sep 4 00:04:21.605842 sshd[12207]: Accepted publickey for core from 10.200.16.10 port 38932 ssh2: RSA SHA256:5Jrr4CY4DiQCm3wS0brkuZBne5yEe8GvBoV7RW32xa4 Sep 4 00:04:21.609900 sshd-session[12207]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 00:04:21.615033 systemd-logind[1894]: New session 21 of user core. Sep 4 00:04:21.628519 systemd[1]: Started session-21.scope - Session 21 of User core. Sep 4 00:04:22.076850 sshd[12244]: Connection closed by 10.200.16.10 port 38932 Sep 4 00:04:22.079154 sshd-session[12207]: pam_unix(sshd:session): session closed for user core Sep 4 00:04:22.083112 systemd-logind[1894]: Session 21 logged out. Waiting for processes to exit. Sep 4 00:04:22.084037 systemd[1]: sshd@18-10.200.8.11:22-10.200.16.10:38932.service: Deactivated successfully. Sep 4 00:04:22.086747 systemd[1]: session-21.scope: Deactivated successfully. Sep 4 00:04:22.087872 systemd-logind[1894]: Removed session 21. Sep 4 00:04:32.204632 systemd[1]: Started sshd@19-10.200.8.11:22-10.200.16.10:56870.service - OpenSSH per-connection server daemon (10.200.16.10:56870). Sep 4 00:04:32.847418 sshd[12565]: Accepted publickey for core from 10.200.16.10 port 56870 ssh2: RSA SHA256:5Jrr4CY4DiQCm3wS0brkuZBne5yEe8GvBoV7RW32xa4 Sep 4 00:04:32.848741 sshd-session[12565]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 00:04:32.853008 systemd-logind[1894]: New session 22 of user core. Sep 4 00:04:32.863491 systemd[1]: Started session-22.scope - Session 22 of User core. Sep 4 00:04:33.299820 sshd[12567]: Connection closed by 10.200.16.10 port 56870 Sep 4 00:04:33.300137 sshd-session[12565]: pam_unix(sshd:session): session closed for user core Sep 4 00:04:33.303516 systemd[1]: sshd@19-10.200.8.11:22-10.200.16.10:56870.service: Deactivated successfully. Sep 4 00:04:33.305049 systemd[1]: session-22.scope: Deactivated successfully. Sep 4 00:04:33.305764 systemd-logind[1894]: Session 22 logged out. Waiting for processes to exit. Sep 4 00:04:33.306708 systemd-logind[1894]: Removed session 22. Sep 4 00:04:33.418650 systemd[1]: Started sshd@20-10.200.8.11:22-10.200.16.10:56874.service - OpenSSH per-connection server daemon (10.200.16.10:56874). Sep 4 00:04:34.067635 sshd[12573]: Accepted publickey for core from 10.200.16.10 port 56874 ssh2: RSA SHA256:5Jrr4CY4DiQCm3wS0brkuZBne5yEe8GvBoV7RW32xa4 Sep 4 00:04:34.068954 sshd-session[12573]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 00:04:34.073289 systemd-logind[1894]: New session 23 of user core. Sep 4 00:04:34.087592 systemd[1]: Started session-23.scope - Session 23 of User core. Sep 4 00:04:34.521501 sshd[12575]: Connection closed by 10.200.16.10 port 56874 Sep 4 00:04:34.521786 sshd-session[12573]: pam_unix(sshd:session): session closed for user core Sep 4 00:04:34.525099 systemd[1]: sshd@20-10.200.8.11:22-10.200.16.10:56874.service: Deactivated successfully. Sep 4 00:04:34.526677 systemd[1]: session-23.scope: Deactivated successfully. Sep 4 00:04:34.527333 systemd-logind[1894]: Session 23 logged out. Waiting for processes to exit. Sep 4 00:04:34.528273 systemd-logind[1894]: Removed session 23. Sep 4 00:04:39.502667 setup-nvidia[2427]: \u000d Sep 4 00:04:39.502667 setup-nvidia[2427]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Sep 4 00:04:39.502667 setup-nvidia[2427]: frequently when this kernel module was built against the wrong or Sep 4 00:04:39.502667 setup-nvidia[2427]: improperly configured kernel sources, with a version of gcc that differs Sep 4 00:04:39.502667 setup-nvidia[2427]: from the one used to build the target kernel, or if another driver, such Sep 4 00:04:39.502667 setup-nvidia[2427]: as nouveau, is present and prevents the NVIDIA kernel module from Sep 4 00:04:39.502667 setup-nvidia[2427]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Sep 4 00:04:39.502667 setup-nvidia[2427]: installed in this system is supported by this NVIDIA Linux graphics Sep 4 00:04:39.502667 setup-nvidia[2427]: driver release. Sep 4 00:04:39.502667 setup-nvidia[2427]: \u000d Sep 4 00:04:39.502667 setup-nvidia[2427]: Please see the log entries 'Kernel module load error' and 'Kernel Sep 4 00:04:39.502667 setup-nvidia[2427]: messages' at the end of the file Sep 4 00:04:39.502667 setup-nvidia[2427]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more Sep 4 00:04:39.502667 setup-nvidia[2427]: information. Sep 4 00:04:39.502667 setup-nvidia[2427]: \u000d Sep 4 00:04:39.516787 setup-nvidia[2427]: dmesg: read kernel buffer failed: Operation not permitted Sep 4 00:04:39.535277 setup-nvidia[2427]: \u000d Sep 4 00:04:39.535277 setup-nvidia[2427]: ERROR: Installation has failed. Please see the file Sep 4 00:04:39.535277 setup-nvidia[2427]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for Sep 4 00:04:39.535277 setup-nvidia[2427]: details. You may find suggestions on fixing installation problems in Sep 4 00:04:39.535277 setup-nvidia[2427]: the README available on the Linux driver download page at Sep 4 00:04:39.535277 setup-nvidia[2427]: www.nvidia.com. Sep 4 00:04:39.535277 setup-nvidia[2427]: \u000d Sep 4 00:04:39.535641 setup-nvidia[2427]: Last 50 lines of nvidia-installer.log: Sep 4 00:04:39.536460 setup-nvidia[2427]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039000rm+0x53: 'naked' return found in MITIGATION_RETHUNK build Sep 4 00:04:39.536460 setup-nvidia[2427]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038997rm+0x90: 'naked' return found in MITIGATION_RETHUNK build Sep 4 00:04:39.536460 setup-nvidia[2427]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038990rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build Sep 4 00:04:39.536460 setup-nvidia[2427]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039046rm+0x93: 'naked' return found in MITIGATION_RETHUNK build Sep 4 00:04:39.536460 setup-nvidia[2427]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039042rm+0x36: 'naked' return found in MITIGATION_RETHUNK build Sep 4 00:04:39.536460 setup-nvidia[2427]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039041rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build Sep 4 00:04:39.536460 setup-nvidia[2427]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039043rm+0x77: 'naked' return found in MITIGATION_RETHUNK build Sep 4 00:04:39.536460 setup-nvidia[2427]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038987rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build Sep 4 00:04:39.536460 setup-nvidia[2427]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030558rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build Sep 4 00:04:39.536460 setup-nvidia[2427]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030557rm+0x27: 'naked' return found in MITIGATION_RETHUNK build Sep 4 00:04:39.536460 setup-nvidia[2427]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030561rm+0x37: 'naked' return found in MITIGATION_RETHUNK build Sep 4 00:04:39.536460 setup-nvidia[2427]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030560rm+0x24: 'naked' return found in MITIGATION_RETHUNK build Sep 4 00:04:39.536460 setup-nvidia[2427]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030559rm+0xca: 'naked' return found in MITIGATION_RETHUNK build Sep 4 00:04:39.536460 setup-nvidia[2427]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030562rm+0x193: 'naked' return found in MITIGATION_RETHUNK build Sep 4 00:04:39.536460 setup-nvidia[2427]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030564rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build Sep 4 00:04:39.536460 setup-nvidia[2427]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv011860rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build Sep 4 00:04:39.536460 setup-nvidia[2427]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039354rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build Sep 4 00:04:39.536460 setup-nvidia[2427]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/Module.symvers Sep 4 00:04:39.536460 setup-nvidia[2427]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.mod.o Sep 4 00:04:39.536460 setup-nvidia[2427]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/.module-common.o Sep 4 00:04:39.536460 setup-nvidia[2427]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.mod.o Sep 4 00:04:39.536460 setup-nvidia[2427]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.mod.o Sep 4 00:04:39.536460 setup-nvidia[2427]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.mod.o Sep 4 00:04:39.536460 setup-nvidia[2427]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.mod.o Sep 4 00:04:39.536460 setup-nvidia[2427]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Sep 4 00:04:39.536460 setup-nvidia[2427]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Sep 4 00:04:39.536460 setup-nvidia[2427]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Sep 4 00:04:39.536460 setup-nvidia[2427]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Sep 4 00:04:39.536460 setup-nvidia[2427]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Sep 4 00:04:39.536460 setup-nvidia[2427]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Sep 4 00:04:39.536460 setup-nvidia[2427]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko due to unavailability of vmlinux Sep 4 00:04:39.536460 setup-nvidia[2427]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Sep 4 00:04:39.536460 setup-nvidia[2427]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko due to unavailability of vmlinux Sep 4 00:04:39.536460 setup-nvidia[2427]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Sep 4 00:04:39.536460 setup-nvidia[2427]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko due to unavailability of vmlinux Sep 4 00:04:39.536460 setup-nvidia[2427]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Sep 4 00:04:39.536460 setup-nvidia[2427]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko due to unavailability of vmlinux Sep 4 00:04:39.536460 setup-nvidia[2427]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Sep 4 00:04:39.536460 setup-nvidia[2427]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko due to unavailability of vmlinux Sep 4 00:04:39.536460 setup-nvidia[2427]: make[2]: Leaving directory '/usr/lib/modules/6.12.44-flatcar/build' Sep 4 00:04:39.536460 setup-nvidia[2427]: make[1]: Leaving directory '/usr/lib/modules/6.12.44-flatcar/source' Sep 4 00:04:39.536460 setup-nvidia[2427]: -> done. Sep 4 00:04:39.536460 setup-nvidia[2427]: -> Kernel module compilation complete. Sep 4 00:04:39.536460 setup-nvidia[2427]: -> Unable to determine if Secure Boot is enabled: No such file or directory Sep 4 00:04:39.536460 setup-nvidia[2427]: 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. Sep 4 00:04:39.537602 setup-nvidia[2427]: \u000d Sep 4 00:04:39.537602 setup-nvidia[2427]: Please see the log entries 'Kernel module load error' and 'Kernel messages' at the end of the file '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more information. Sep 4 00:04:39.537602 setup-nvidia[2427]: -> Kernel module load error: Operation not permitted Sep 4 00:04:39.537602 setup-nvidia[2427]: -> Kernel messages: Sep 4 00:04:39.537602 setup-nvidia[2427]: ERROR: Installation has failed. Please see the file '/nvidia/NVIDIA-Linux-x86_64-535.230.02/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. Sep 4 00:04:44.639684 systemd[1]: Started sshd@21-10.200.8.11:22-10.200.16.10:52364.service - OpenSSH per-connection server daemon (10.200.16.10:52364). Sep 4 00:04:45.282656 sshd[12761]: Accepted publickey for core from 10.200.16.10 port 52364 ssh2: RSA SHA256:5Jrr4CY4DiQCm3wS0brkuZBne5yEe8GvBoV7RW32xa4 Sep 4 00:04:45.283972 sshd-session[12761]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 00:04:45.288442 systemd-logind[1894]: New session 24 of user core. Sep 4 00:04:45.299486 systemd[1]: Started session-24.scope - Session 24 of User core. Sep 4 00:04:45.735013 sshd[12763]: Connection closed by 10.200.16.10 port 52364 Sep 4 00:04:45.735354 sshd-session[12761]: pam_unix(sshd:session): session closed for user core Sep 4 00:04:45.738944 systemd[1]: sshd@21-10.200.8.11:22-10.200.16.10:52364.service: Deactivated successfully. Sep 4 00:04:45.740516 systemd[1]: session-24.scope: Deactivated successfully. Sep 4 00:04:45.741148 systemd-logind[1894]: Session 24 logged out. Waiting for processes to exit. Sep 4 00:04:45.742147 systemd-logind[1894]: Removed session 24. Sep 4 00:04:45.851975 systemd[1]: Started sshd@22-10.200.8.11:22-10.200.16.10:52366.service - OpenSSH per-connection server daemon (10.200.16.10:52366). Sep 4 00:04:46.492871 sshd[12769]: Accepted publickey for core from 10.200.16.10 port 52366 ssh2: RSA SHA256:5Jrr4CY4DiQCm3wS0brkuZBne5yEe8GvBoV7RW32xa4 Sep 4 00:04:46.494274 sshd-session[12769]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 00:04:46.498703 systemd-logind[1894]: New session 25 of user core. Sep 4 00:04:46.513485 systemd[1]: Started session-25.scope - Session 25 of User core. Sep 4 00:04:46.944052 sshd[12771]: Connection closed by 10.200.16.10 port 52366 Sep 4 00:04:46.944439 sshd-session[12769]: pam_unix(sshd:session): session closed for user core Sep 4 00:04:46.947825 systemd[1]: sshd@22-10.200.8.11:22-10.200.16.10:52366.service: Deactivated successfully. Sep 4 00:04:46.949416 systemd[1]: session-25.scope: Deactivated successfully. Sep 4 00:04:46.950082 systemd-logind[1894]: Session 25 logged out. Waiting for processes to exit. Sep 4 00:04:46.951100 systemd-logind[1894]: Removed session 25. Sep 4 00:04:53.027386 kernel: EXT4-fs (loop6p9): unmounting filesystem dd3fcba5-16d8-4332-8af8-78f16c3a22d0. Sep 4 00:04:53.027557 setup-nvidia[2427]: Container flatcardevelopercontainer-4372.1.0nightly-20250903-2100.bin exited successfully. Sep 4 00:04:53.030312 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4372.1.0nightly\x2d20250903\x2d2100.bin.mount: Deactivated successfully. Sep 4 00:04:53.091170 sudo[2415]: pam_unix(sudo:session): session closed for user root Sep 4 00:04:53.093102 systemd[1]: session-c2.scope: Deactivated successfully. Sep 4 00:04:53.093389 systemd[1]: session-c2.scope: Consumed 5min 9.129s CPU time, 893.5M memory peak. Sep 4 00:04:53.507552 setup-nvidia[1884]: /opt/nvidia / Sep 4 00:04:53.508514 setup-nvidia[1884]: / Sep 4 00:04:53.517406 kernel: IPMI message handler: version 39.2 Sep 4 00:04:53.521373 kernel: ipmi device interface Sep 4 00:04:53.533700 setup-nvidia[1884]: /opt/nvidia/current/usr/lib/modules/6.12.44-flatcar/video / Sep 4 00:04:53.592250 kernel: nvidia: loading out-of-tree module taints kernel. Sep 4 00:04:53.592411 kernel: nvidia: module license 'NVIDIA' taints kernel. Sep 4 00:04:53.592457 kernel: Disabling lock debugging due to kernel taint Sep 4 00:04:53.599829 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Sep 4 00:04:53.599871 kernel: nvidia: module license taints kernel. Sep 4 00:04:54.019388 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 Sep 4 00:04:54.425920 kernel: Sep 4 00:04:54.426074 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Sep 4 00:04:54.632380 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.230.02 Fri Dec 20 21:42:05 UTC 2024 Sep 4 00:04:54.653383 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.230.02 Fri Dec 20 21:52:34 UTC 2024 Sep 4 00:04:54.672394 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Sep 4 00:04:54.719980 setup-nvidia[1884]: / Sep 4 00:04:54.720384 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. Sep 4 00:04:54.734711 setup-nvidia[12811]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 4 00:04:56.481715 setup-nvidia[12828]: Thu Sep 4 00:04:56 2025 Sep 4 00:04:56.481715 setup-nvidia[12828]: +---------------------------------------------------------------------------------------+ Sep 4 00:04:56.481715 setup-nvidia[12828]: | NVIDIA-SMI 535.230.02 Driver Version: 535.230.02 CUDA Version: 12.2 | Sep 4 00:04:56.481715 setup-nvidia[12828]: |-----------------------------------------+----------------------+----------------------+ Sep 4 00:04:56.481715 setup-nvidia[12828]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Sep 4 00:04:56.481715 setup-nvidia[12828]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Sep 4 00:04:56.481715 setup-nvidia[12828]: | | | MIG M. | Sep 4 00:04:56.481715 setup-nvidia[12828]: |=========================================+======================+======================| Sep 4 00:04:56.485506 setup-nvidia[12828]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Sep 4 00:04:56.485506 setup-nvidia[12828]: | N/A 25C P0 36W / 250W | 0MiB / 16384MiB | 0% Default | Sep 4 00:04:56.485506 setup-nvidia[12828]: | | | N/A | Sep 4 00:04:56.485506 setup-nvidia[12828]: +-----------------------------------------+----------------------+----------------------+ Sep 4 00:04:56.485506 setup-nvidia[12828]: Sep 4 00:04:56.485506 setup-nvidia[12828]: +---------------------------------------------------------------------------------------+ Sep 4 00:04:56.485506 setup-nvidia[12828]: | Processes: | Sep 4 00:04:56.485506 setup-nvidia[12828]: | GPU GI CI PID Type Process name GPU Memory | Sep 4 00:04:56.485506 setup-nvidia[12828]: | ID ID Usage | Sep 4 00:04:56.485506 setup-nvidia[12828]: |=======================================================================================| Sep 4 00:04:56.485754 setup-nvidia[12828]: | No running processes found | Sep 4 00:04:56.485754 setup-nvidia[12828]: +---------------------------------------------------------------------------------------+ Sep 4 00:04:56.855117 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 4 00:04:56.857892 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 4 00:04:56.860439 systemd[1]: Startup finished in 875ms (firmware) + 23.152s (loader) + 3.774s (kernel) + 10.233s (initrd) + 3min 7.768s (userspace) = 3min 45.804s. Sep 4 00:04:57.062113 systemd[1]: Started sshd@23-10.200.8.11:22-10.200.16.10:38638.service - OpenSSH per-connection server daemon (10.200.16.10:38638). Sep 4 00:04:57.703470 sshd[12835]: Accepted publickey for core from 10.200.16.10 port 38638 ssh2: RSA SHA256:5Jrr4CY4DiQCm3wS0brkuZBne5yEe8GvBoV7RW32xa4 Sep 4 00:04:57.704757 sshd-session[12835]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 00:04:57.709107 systemd-logind[1894]: New session 26 of user core. Sep 4 00:04:57.721496 systemd[1]: Started session-26.scope - Session 26 of User core. Sep 4 00:04:58.155267 sshd[12837]: Connection closed by 10.200.16.10 port 38638 Sep 4 00:04:58.155619 sshd-session[12835]: pam_unix(sshd:session): session closed for user core Sep 4 00:04:58.158859 systemd[1]: sshd@23-10.200.8.11:22-10.200.16.10:38638.service: Deactivated successfully. Sep 4 00:04:58.160381 systemd[1]: session-26.scope: Deactivated successfully. Sep 4 00:04:58.161049 systemd-logind[1894]: Session 26 logged out. Waiting for processes to exit. Sep 4 00:04:58.161974 systemd-logind[1894]: Removed session 26. Sep 4 00:04:58.271369 systemd[1]: Started sshd@24-10.200.8.11:22-10.200.16.10:38640.service - OpenSSH per-connection server daemon (10.200.16.10:38640). Sep 4 00:04:58.912617 sshd[12843]: Accepted publickey for core from 10.200.16.10 port 38640 ssh2: RSA SHA256:5Jrr4CY4DiQCm3wS0brkuZBne5yEe8GvBoV7RW32xa4 Sep 4 00:04:58.913933 sshd-session[12843]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 00:04:58.918291 systemd-logind[1894]: New session 27 of user core. Sep 4 00:04:58.935495 systemd[1]: Started session-27.scope - Session 27 of User core. Sep 4 00:04:59.362354 sshd[12845]: Connection closed by 10.200.16.10 port 38640 Sep 4 00:04:59.362692 sshd-session[12843]: pam_unix(sshd:session): session closed for user core Sep 4 00:04:59.366149 systemd[1]: sshd@24-10.200.8.11:22-10.200.16.10:38640.service: Deactivated successfully. Sep 4 00:04:59.367671 systemd[1]: session-27.scope: Deactivated successfully. Sep 4 00:04:59.368339 systemd-logind[1894]: Session 27 logged out. Waiting for processes to exit. Sep 4 00:04:59.369251 systemd-logind[1894]: Removed session 27. Sep 4 00:04:59.479680 systemd[1]: Started sshd@25-10.200.8.11:22-10.200.16.10:38646.service - OpenSSH per-connection server daemon (10.200.16.10:38646). Sep 4 00:05:00.143308 sshd[12851]: Accepted publickey for core from 10.200.16.10 port 38646 ssh2: RSA SHA256:5Jrr4CY4DiQCm3wS0brkuZBne5yEe8GvBoV7RW32xa4 Sep 4 00:05:00.144818 sshd-session[12851]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 00:05:00.149235 systemd-logind[1894]: New session 28 of user core. Sep 4 00:05:00.161493 systemd[1]: Started session-28.scope - Session 28 of User core. Sep 4 00:05:00.594912 sshd[12853]: Connection closed by 10.200.16.10 port 38646 Sep 4 00:05:00.595273 sshd-session[12851]: pam_unix(sshd:session): session closed for user core Sep 4 00:05:00.598905 systemd[1]: sshd@25-10.200.8.11:22-10.200.16.10:38646.service: Deactivated successfully. Sep 4 00:05:00.600523 systemd[1]: session-28.scope: Deactivated successfully. Sep 4 00:05:00.601203 systemd-logind[1894]: Session 28 logged out. Waiting for processes to exit. Sep 4 00:05:00.602203 systemd-logind[1894]: Removed session 28. Sep 4 00:05:00.711558 systemd[1]: Started sshd@26-10.200.8.11:22-10.200.16.10:58584.service - OpenSSH per-connection server daemon (10.200.16.10:58584). Sep 4 00:05:01.366887 sshd[12859]: Accepted publickey for core from 10.200.16.10 port 58584 ssh2: RSA SHA256:5Jrr4CY4DiQCm3wS0brkuZBne5yEe8GvBoV7RW32xa4 Sep 4 00:05:01.368194 sshd-session[12859]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 00:05:01.372545 systemd-logind[1894]: New session 29 of user core. Sep 4 00:05:01.386480 systemd[1]: Started session-29.scope - Session 29 of User core. Sep 4 00:05:01.820809 sshd[12863]: Connection closed by 10.200.16.10 port 58584 Sep 4 00:05:01.821185 sshd-session[12859]: pam_unix(sshd:session): session closed for user core Sep 4 00:05:01.824688 systemd[1]: sshd@26-10.200.8.11:22-10.200.16.10:58584.service: Deactivated successfully. Sep 4 00:05:01.826312 systemd[1]: session-29.scope: Deactivated successfully. Sep 4 00:05:01.826990 systemd-logind[1894]: Session 29 logged out. Waiting for processes to exit. Sep 4 00:05:01.828516 systemd-logind[1894]: Removed session 29. Sep 4 00:05:01.939829 systemd[1]: Started sshd@27-10.200.8.11:22-10.200.16.10:58592.service - OpenSSH per-connection server daemon (10.200.16.10:58592). Sep 4 00:05:02.605119 sshd[12870]: Accepted publickey for core from 10.200.16.10 port 58592 ssh2: RSA SHA256:5Jrr4CY4DiQCm3wS0brkuZBne5yEe8GvBoV7RW32xa4 Sep 4 00:05:02.606479 sshd-session[12870]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 00:05:02.611189 systemd-logind[1894]: New session 30 of user core. Sep 4 00:05:02.622477 systemd[1]: Started session-30.scope - Session 30 of User core. Sep 4 00:05:03.311991 systemd[1]: Stopping user@0.service - User Manager for UID 0... Sep 4 00:05:03.312800 systemd[2418]: Activating special unit exit.target... Sep 4 00:05:03.312886 systemd[2418]: Stopped target default.target - Main User Target. Sep 4 00:05:03.312925 systemd[2418]: Stopped target basic.target - Basic System. Sep 4 00:05:03.312952 systemd[2418]: Stopped target paths.target - Paths. Sep 4 00:05:03.312977 systemd[2418]: Stopped target sockets.target - Sockets. Sep 4 00:05:03.313002 systemd[2418]: Stopped target timers.target - Timers. Sep 4 00:05:03.313353 systemd[2418]: Closed dbus.socket - D-Bus User Message Bus Socket. Sep 4 00:05:03.313585 systemd[2418]: Removed slice app.slice - User Application Slice. Sep 4 00:05:03.313618 systemd[2418]: Reached target shutdown.target - Shutdown. Sep 4 00:05:03.313759 systemd[2418]: Finished systemd-exit.service - Exit the Session. Sep 4 00:05:03.313795 systemd[2418]: Reached target exit.target - Exit the Session. Sep 4 00:05:03.323234 (sd-pam)[2419]: pam_unix(systemd-user:session): session closed for user root Sep 4 00:05:03.324534 systemd[1]: user@0.service: Deactivated successfully. Sep 4 00:05:03.324908 systemd[1]: Stopped user@0.service - User Manager for UID 0. Sep 4 00:05:03.327183 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Sep 4 00:05:03.350720 systemd[1]: run-user-0.mount: Deactivated successfully. Sep 4 00:05:03.351700 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Sep 4 00:05:03.351889 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Sep 4 00:05:03.353471 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Sep 4 00:05:03.353560 systemd[1]: user-0.slice: Consumed 5min 9.410s CPU time, 903.2M memory peak. Sep 4 00:05:03.354271 systemd-logind[1894]: Removed session c3. Sep 4 00:05:04.533451 sshd[12872]: Connection closed by 10.200.16.10 port 58592 Sep 4 00:05:04.533820 sshd-session[12870]: pam_unix(sshd:session): session closed for user core Sep 4 00:05:04.537209 systemd[1]: sshd@27-10.200.8.11:22-10.200.16.10:58592.service: Deactivated successfully. Sep 4 00:05:04.538764 systemd[1]: session-30.scope: Deactivated successfully. Sep 4 00:05:04.538962 systemd[1]: session-30.scope: Consumed 1.416s CPU time, 14M memory peak. Sep 4 00:05:04.539475 systemd-logind[1894]: Session 30 logged out. Waiting for processes to exit. Sep 4 00:05:04.540535 systemd-logind[1894]: Removed session 30.