Sep 12 17:13:12.221068 kernel: Linux version 6.6.106-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.43 p3) 2.43.1) #1 SMP PREEMPT_DYNAMIC Fri Sep 12 15:35:29 -00 2025 Sep 12 17:13:12.221111 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=ea81bd4228a6b9fed11f4ec3af9a6e9673be062592f47971c283403bcba44656 Sep 12 17:13:12.221121 kernel: BIOS-provided physical RAM map: Sep 12 17:13:12.221128 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Sep 12 17:13:12.221134 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Sep 12 17:13:12.221141 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Sep 12 17:13:12.221149 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Sep 12 17:13:12.221158 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Sep 12 17:13:12.221165 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Sep 12 17:13:12.221172 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Sep 12 17:13:12.221179 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Sep 12 17:13:12.221185 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Sep 12 17:13:12.221192 kernel: printk: bootconsole [earlyser0] enabled Sep 12 17:13:12.221199 kernel: NX (Execute Disable) protection: active Sep 12 17:13:12.221210 kernel: APIC: Static calls initialized Sep 12 17:13:12.221218 kernel: efi: EFI v2.7 by Microsoft Sep 12 17:13:12.221226 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Sep 12 17:13:12.221233 kernel: random: crng init done Sep 12 17:13:12.221243 kernel: secureboot: Secure boot disabled Sep 12 17:13:12.221250 kernel: SMBIOS 3.1.0 present. Sep 12 17:13:12.221258 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Sep 12 17:13:12.221265 kernel: Hypervisor detected: Microsoft Hyper-V Sep 12 17:13:12.221273 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Sep 12 17:13:12.221280 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Sep 12 17:13:12.221290 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Sep 12 17:13:12.221298 kernel: Hyper-V: Using hypercall for remote TLB flush Sep 12 17:13:12.221305 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Sep 12 17:13:12.221313 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Sep 12 17:13:12.221321 kernel: tsc: Marking TSC unstable due to running on Hyper-V Sep 12 17:13:12.221329 kernel: tsc: Detected 2593.993 MHz processor Sep 12 17:13:12.221336 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Sep 12 17:13:12.221344 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Sep 12 17:13:12.221352 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Sep 12 17:13:12.221362 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Sep 12 17:13:12.221370 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Sep 12 17:13:12.221378 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Sep 12 17:13:12.221385 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Sep 12 17:13:12.221393 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Sep 12 17:13:12.221400 kernel: Using GB pages for direct mapping Sep 12 17:13:12.221408 kernel: ACPI: Early table checksum verification disabled Sep 12 17:13:12.221420 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Sep 12 17:13:12.221431 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 17:13:12.221439 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 17:13:12.221447 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Sep 12 17:13:12.221455 kernel: ACPI: FACS 0x000000003FFFE000 000040 Sep 12 17:13:12.221464 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 17:13:12.221472 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 17:13:12.221483 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 17:13:12.221491 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 17:13:12.221499 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 17:13:12.221508 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 17:13:12.221516 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 17:13:12.221524 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Sep 12 17:13:12.221532 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Sep 12 17:13:12.221540 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Sep 12 17:13:12.221551 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Sep 12 17:13:12.221559 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Sep 12 17:13:12.221567 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Sep 12 17:13:12.221575 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Sep 12 17:13:12.221583 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Sep 12 17:13:12.221591 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Sep 12 17:13:12.221600 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Sep 12 17:13:12.221608 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0 Sep 12 17:13:12.221616 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0 Sep 12 17:13:12.221627 kernel: SRAT: PXM 0 -> APIC 0x02 -> Node 0 Sep 12 17:13:12.221635 kernel: SRAT: PXM 0 -> APIC 0x03 -> Node 0 Sep 12 17:13:12.221643 kernel: SRAT: PXM 0 -> APIC 0x04 -> Node 0 Sep 12 17:13:12.221651 kernel: SRAT: PXM 0 -> APIC 0x05 -> Node 0 Sep 12 17:13:12.221659 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Sep 12 17:13:12.221667 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Sep 12 17:13:12.221675 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Sep 12 17:13:12.221683 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Sep 12 17:13:12.221691 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Sep 12 17:13:12.221702 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Sep 12 17:13:12.221710 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Sep 12 17:13:12.221718 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Sep 12 17:13:12.221726 kernel: NUMA: Node 0 [mem 0x00000000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00000000-0xfdfffffff] Sep 12 17:13:12.221735 kernel: NUMA: Node 0 [mem 0x00000000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00000000-0x5cbfffffff] Sep 12 17:13:12.221743 kernel: NODE_DATA(0) allocated [mem 0x5cbfff9000-0x5cbfffefff] Sep 12 17:13:12.221751 kernel: Zone ranges: Sep 12 17:13:12.221759 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Sep 12 17:13:12.221768 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Sep 12 17:13:12.221778 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Sep 12 17:13:12.221787 kernel: Movable zone start for each node Sep 12 17:13:12.221795 kernel: Early memory node ranges Sep 12 17:13:12.221803 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Sep 12 17:13:12.221811 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Sep 12 17:13:12.221819 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Sep 12 17:13:12.221827 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Sep 12 17:13:12.221835 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Sep 12 17:13:12.221844 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Sep 12 17:13:12.221854 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Sep 12 17:13:12.221863 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Sep 12 17:13:12.221871 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Sep 12 17:13:12.221879 kernel: ACPI: PM-Timer IO Port: 0x408 Sep 12 17:13:12.221887 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Sep 12 17:13:12.221895 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Sep 12 17:13:12.221903 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Sep 12 17:13:12.221912 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Sep 12 17:13:12.221920 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Sep 12 17:13:12.221931 kernel: smpboot: Allowing 6 CPUs, 0 hotplug CPUs Sep 12 17:13:12.221939 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Sep 12 17:13:12.221947 kernel: Booting paravirtualized kernel on Hyper-V Sep 12 17:13:12.221955 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Sep 12 17:13:12.221963 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Sep 12 17:13:12.221971 kernel: percpu: Embedded 58 pages/cpu s197160 r8192 d32216 u262144 Sep 12 17:13:12.221980 kernel: pcpu-alloc: s197160 r8192 d32216 u262144 alloc=1*2097152 Sep 12 17:13:12.221988 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Sep 12 17:13:12.221996 kernel: Hyper-V: PV spinlocks enabled Sep 12 17:13:12.222007 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Sep 12 17:13:12.222016 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=ea81bd4228a6b9fed11f4ec3af9a6e9673be062592f47971c283403bcba44656 Sep 12 17:13:12.222025 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Sep 12 17:13:12.222033 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Sep 12 17:13:12.222041 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Sep 12 17:13:12.222049 kernel: Fallback order for Node 0: 0 Sep 12 17:13:12.222057 kernel: Built 1 zonelists, mobility grouping on. Total pages: 28899610 Sep 12 17:13:12.222065 kernel: Policy zone: Normal Sep 12 17:13:12.222076 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 12 17:13:12.222113 kernel: software IO TLB: area num 8. Sep 12 17:13:12.222123 kernel: Memory: 115335988K/117439364K available (14336K kernel code, 2293K rwdata, 22872K rodata, 43520K init, 1556K bss, 2103120K reserved, 0K cma-reserved) Sep 12 17:13:12.222134 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Sep 12 17:13:12.222143 kernel: Kernel/User page tables isolation: enabled Sep 12 17:13:12.222151 kernel: ftrace: allocating 37948 entries in 149 pages Sep 12 17:13:12.222160 kernel: ftrace: allocated 149 pages with 4 groups Sep 12 17:13:12.222176 kernel: Dynamic Preempt: voluntary Sep 12 17:13:12.222185 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 12 17:13:12.222202 kernel: rcu: RCU event tracing is enabled. Sep 12 17:13:12.222219 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Sep 12 17:13:12.222237 kernel: Trampoline variant of Tasks RCU enabled. Sep 12 17:13:12.222251 kernel: Rude variant of Tasks RCU enabled. Sep 12 17:13:12.222260 kernel: Tracing variant of Tasks RCU enabled. Sep 12 17:13:12.222268 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 12 17:13:12.222277 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Sep 12 17:13:12.222288 kernel: Using NULL legacy PIC Sep 12 17:13:12.222297 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Sep 12 17:13:12.222305 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 12 17:13:12.222314 kernel: Console: colour dummy device 80x25 Sep 12 17:13:12.222322 kernel: printk: console [tty1] enabled Sep 12 17:13:12.222331 kernel: printk: console [ttyS0] enabled Sep 12 17:13:12.222340 kernel: printk: bootconsole [earlyser0] disabled Sep 12 17:13:12.222348 kernel: ACPI: Core revision 20230628 Sep 12 17:13:12.222357 kernel: Failed to register legacy timer interrupt Sep 12 17:13:12.222365 kernel: APIC: Switch to symmetric I/O mode setup Sep 12 17:13:12.222377 kernel: Hyper-V: enabling crash_kexec_post_notifiers Sep 12 17:13:12.222385 kernel: Hyper-V: Using IPI hypercalls Sep 12 17:13:12.222394 kernel: APIC: send_IPI() replaced with hv_send_ipi() Sep 12 17:13:12.222402 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Sep 12 17:13:12.222411 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Sep 12 17:13:12.222420 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Sep 12 17:13:12.222428 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Sep 12 17:13:12.222437 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Sep 12 17:13:12.222446 kernel: Hyper-V: Using enlightened APIC (xapic mode) Sep 12 17:13:12.222457 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Sep 12 17:13:12.222466 kernel: APIC: read() replaced with hv_apic_read() Sep 12 17:13:12.222474 kernel: APIC: write() replaced with hv_apic_write() Sep 12 17:13:12.222483 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Sep 12 17:13:12.222491 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Sep 12 17:13:12.222500 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593993) Sep 12 17:13:12.222509 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Sep 12 17:13:12.222517 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Sep 12 17:13:12.222526 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Sep 12 17:13:12.222537 kernel: Spectre V2 : Mitigation: Retpolines Sep 12 17:13:12.222545 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Sep 12 17:13:12.222554 kernel: Speculative Store Bypass: Vulnerable Sep 12 17:13:12.222562 kernel: MDS: Mitigation: Clear CPU buffers Sep 12 17:13:12.222571 kernel: TAA: Mitigation: Clear CPU buffers Sep 12 17:13:12.222579 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Sep 12 17:13:12.222587 kernel: active return thunk: its_return_thunk Sep 12 17:13:12.222596 kernel: ITS: Mitigation: Aligned branch/return thunks Sep 12 17:13:12.222604 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Sep 12 17:13:12.222612 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Sep 12 17:13:12.222621 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Sep 12 17:13:12.222632 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Sep 12 17:13:12.222640 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Sep 12 17:13:12.222649 kernel: Freeing SMP alternatives memory: 32K Sep 12 17:13:12.222657 kernel: pid_max: default: 32768 minimum: 301 Sep 12 17:13:12.222666 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Sep 12 17:13:12.222674 kernel: landlock: Up and running. Sep 12 17:13:12.222682 kernel: SELinux: Initializing. Sep 12 17:13:12.222691 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Sep 12 17:13:12.222699 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Sep 12 17:13:12.222708 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Sep 12 17:13:12.222717 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Sep 12 17:13:12.222728 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Sep 12 17:13:12.222737 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Sep 12 17:13:12.222746 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Sep 12 17:13:12.222754 kernel: signal: max sigframe size: 1776 Sep 12 17:13:12.222763 kernel: rcu: Hierarchical SRCU implementation. Sep 12 17:13:12.222772 kernel: rcu: Max phase no-delay instances is 400. Sep 12 17:13:12.222780 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Sep 12 17:13:12.222789 kernel: smp: Bringing up secondary CPUs ... Sep 12 17:13:12.222798 kernel: smpboot: x86: Booting SMP configuration: Sep 12 17:13:12.222809 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Sep 12 17:13:12.222817 kernel: smp: Brought up 1 node, 6 CPUs Sep 12 17:13:12.222826 kernel: smpboot: Max logical packages: 1 Sep 12 17:13:12.222834 kernel: smpboot: Total of 6 processors activated (31127.91 BogoMIPS) Sep 12 17:13:12.222843 kernel: devtmpfs: initialized Sep 12 17:13:12.222852 kernel: x86/mm: Memory block size: 1024MB Sep 12 17:13:12.222860 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Sep 12 17:13:12.222869 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 12 17:13:12.222878 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Sep 12 17:13:12.222889 kernel: pinctrl core: initialized pinctrl subsystem Sep 12 17:13:12.222898 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 12 17:13:12.222906 kernel: audit: initializing netlink subsys (disabled) Sep 12 17:13:12.222915 kernel: audit: type=2000 audit(1757697190.029:1): state=initialized audit_enabled=0 res=1 Sep 12 17:13:12.222923 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 12 17:13:12.222932 kernel: thermal_sys: Registered thermal governor 'user_space' Sep 12 17:13:12.222941 kernel: cpuidle: using governor menu Sep 12 17:13:12.222949 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 12 17:13:12.222958 kernel: dca service started, version 1.12.1 Sep 12 17:13:12.222975 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Sep 12 17:13:12.222985 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Sep 12 17:13:12.222993 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 12 17:13:12.223002 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Sep 12 17:13:12.223011 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 12 17:13:12.223019 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Sep 12 17:13:12.223028 kernel: ACPI: Added _OSI(Module Device) Sep 12 17:13:12.223036 kernel: ACPI: Added _OSI(Processor Device) Sep 12 17:13:12.223045 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 12 17:13:12.223057 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 12 17:13:12.223066 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Sep 12 17:13:12.223075 kernel: ACPI: Interpreter enabled Sep 12 17:13:12.223091 kernel: ACPI: PM: (supports S0 S5) Sep 12 17:13:12.223100 kernel: ACPI: Using IOAPIC for interrupt routing Sep 12 17:13:12.223109 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Sep 12 17:13:12.223118 kernel: PCI: Ignoring E820 reservations for host bridge windows Sep 12 17:13:12.223127 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Sep 12 17:13:12.223135 kernel: iommu: Default domain type: Translated Sep 12 17:13:12.223147 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Sep 12 17:13:12.223155 kernel: efivars: Registered efivars operations Sep 12 17:13:12.223164 kernel: PCI: Using ACPI for IRQ routing Sep 12 17:13:12.223173 kernel: PCI: System does not support PCI Sep 12 17:13:12.223181 kernel: vgaarb: loaded Sep 12 17:13:12.223190 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Sep 12 17:13:12.223199 kernel: VFS: Disk quotas dquot_6.6.0 Sep 12 17:13:12.223207 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 12 17:13:12.223216 kernel: pnp: PnP ACPI init Sep 12 17:13:12.223227 kernel: pnp: PnP ACPI: found 3 devices Sep 12 17:13:12.223236 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Sep 12 17:13:12.223245 kernel: NET: Registered PF_INET protocol family Sep 12 17:13:12.223253 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 12 17:13:12.223262 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Sep 12 17:13:12.223271 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 12 17:13:12.223279 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 12 17:13:12.223288 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Sep 12 17:13:12.223297 kernel: TCP: Hash tables configured (established 524288 bind 65536) Sep 12 17:13:12.223308 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Sep 12 17:13:12.223317 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Sep 12 17:13:12.223325 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 12 17:13:12.223334 kernel: NET: Registered PF_XDP protocol family Sep 12 17:13:12.223342 kernel: PCI: CLS 0 bytes, default 64 Sep 12 17:13:12.223351 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Sep 12 17:13:12.223360 kernel: software IO TLB: mapped [mem 0x000000003b5bf000-0x000000003f5bf000] (64MB) Sep 12 17:13:12.223368 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Sep 12 17:13:12.223377 kernel: Initialise system trusted keyrings Sep 12 17:13:12.223388 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Sep 12 17:13:12.223397 kernel: Key type asymmetric registered Sep 12 17:13:12.223405 kernel: Asymmetric key parser 'x509' registered Sep 12 17:13:12.223414 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Sep 12 17:13:12.223422 kernel: io scheduler mq-deadline registered Sep 12 17:13:12.223431 kernel: io scheduler kyber registered Sep 12 17:13:12.223439 kernel: io scheduler bfq registered Sep 12 17:13:12.223448 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Sep 12 17:13:12.223457 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 12 17:13:12.223468 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Sep 12 17:13:12.223477 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Sep 12 17:13:12.223486 kernel: i8042: PNP: No PS/2 controller found. Sep 12 17:13:12.223639 kernel: rtc_cmos 00:02: registered as rtc0 Sep 12 17:13:12.223753 kernel: rtc_cmos 00:02: setting system clock to 2025-09-12T17:13:11 UTC (1757697191) Sep 12 17:13:12.223860 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Sep 12 17:13:12.223873 kernel: intel_pstate: CPU model not supported Sep 12 17:13:12.223887 kernel: efifb: probing for efifb Sep 12 17:13:12.223896 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Sep 12 17:13:12.223904 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Sep 12 17:13:12.223913 kernel: efifb: scrolling: redraw Sep 12 17:13:12.223921 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Sep 12 17:13:12.223930 kernel: Console: switching to colour frame buffer device 128x48 Sep 12 17:13:12.223939 kernel: fb0: EFI VGA frame buffer device Sep 12 17:13:12.223947 kernel: pstore: Using crash dump compression: deflate Sep 12 17:13:12.223956 kernel: pstore: Registered efi_pstore as persistent store backend Sep 12 17:13:12.223967 kernel: NET: Registered PF_INET6 protocol family Sep 12 17:13:12.223976 kernel: Segment Routing with IPv6 Sep 12 17:13:12.223985 kernel: In-situ OAM (IOAM) with IPv6 Sep 12 17:13:12.223993 kernel: NET: Registered PF_PACKET protocol family Sep 12 17:13:12.224002 kernel: Key type dns_resolver registered Sep 12 17:13:12.224010 kernel: IPI shorthand broadcast: enabled Sep 12 17:13:12.224019 kernel: sched_clock: Marking stable (1614005500, 103375500)->(2054648500, -337267500) Sep 12 17:13:12.224028 kernel: registered taskstats version 1 Sep 12 17:13:12.224036 kernel: Loading compiled-in X.509 certificates Sep 12 17:13:12.224045 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.106-flatcar: d1d9e065fdbec39026aa56a07626d6d91ab4fce4' Sep 12 17:13:12.224056 kernel: Key type .fscrypt registered Sep 12 17:13:12.224065 kernel: Key type fscrypt-provisioning registered Sep 12 17:13:12.224073 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 12 17:13:12.224093 kernel: ima: Allocated hash algorithm: sha1 Sep 12 17:13:12.224102 kernel: ima: No architecture policies found Sep 12 17:13:12.224111 kernel: clk: Disabling unused clocks Sep 12 17:13:12.224119 kernel: Freeing unused kernel image (initmem) memory: 43520K Sep 12 17:13:12.224128 kernel: Write protecting the kernel read-only data: 38912k Sep 12 17:13:12.224140 kernel: Freeing unused kernel image (rodata/data gap) memory: 1704K Sep 12 17:13:12.224149 kernel: Run /init as init process Sep 12 17:13:12.224157 kernel: with arguments: Sep 12 17:13:12.224166 kernel: /init Sep 12 17:13:12.224174 kernel: with environment: Sep 12 17:13:12.224182 kernel: HOME=/ Sep 12 17:13:12.224191 kernel: TERM=linux Sep 12 17:13:12.224199 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 12 17:13:12.224209 systemd[1]: Successfully made /usr/ read-only. Sep 12 17:13:12.224223 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 12 17:13:12.224233 systemd[1]: Detected virtualization microsoft. Sep 12 17:13:12.224241 systemd[1]: Detected architecture x86-64. Sep 12 17:13:12.224250 systemd[1]: Running in initrd. Sep 12 17:13:12.224259 systemd[1]: No hostname configured, using default hostname. Sep 12 17:13:12.224268 systemd[1]: Hostname set to . Sep 12 17:13:12.224277 systemd[1]: Initializing machine ID from random generator. Sep 12 17:13:12.224288 systemd[1]: Queued start job for default target initrd.target. Sep 12 17:13:12.224297 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 12 17:13:12.224306 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 12 17:13:12.224316 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 12 17:13:12.224325 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 12 17:13:12.224334 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 12 17:13:12.224344 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 12 17:13:12.224357 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Sep 12 17:13:12.224367 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 12 17:13:12.224376 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 12 17:13:12.224385 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 12 17:13:12.224394 systemd[1]: Reached target paths.target - Path Units. Sep 12 17:13:12.224403 systemd[1]: Reached target slices.target - Slice Units. Sep 12 17:13:12.224412 systemd[1]: Reached target swap.target - Swaps. Sep 12 17:13:12.224421 systemd[1]: Reached target timers.target - Timer Units. Sep 12 17:13:12.224430 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 12 17:13:12.224442 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 12 17:13:12.224451 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 12 17:13:12.224460 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Sep 12 17:13:12.224469 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 12 17:13:12.224478 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 12 17:13:12.224487 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 12 17:13:12.224496 systemd[1]: Reached target sockets.target - Socket Units. Sep 12 17:13:12.224505 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 12 17:13:12.224517 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 12 17:13:12.224526 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 12 17:13:12.224535 systemd[1]: Starting systemd-fsck-usr.service... Sep 12 17:13:12.224544 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 12 17:13:12.224553 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 12 17:13:12.224585 systemd-journald[204]: Collecting audit messages is disabled. Sep 12 17:13:12.224611 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 12 17:13:12.224622 systemd-journald[204]: Journal started Sep 12 17:13:12.224645 systemd-journald[204]: Runtime Journal (/run/log/journal/18d1d72685e7410f80432855a066b6a0) is 8M, max 2.2G, 2.1G free. Sep 12 17:13:12.233339 systemd[1]: Started systemd-journald.service - Journal Service. Sep 12 17:13:12.236864 systemd-modules-load[207]: Inserted module 'overlay' Sep 12 17:13:12.237400 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 12 17:13:12.238727 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 12 17:13:12.239446 systemd[1]: Finished systemd-fsck-usr.service. Sep 12 17:13:12.241722 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 12 17:13:12.242931 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 12 17:13:12.250146 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 12 17:13:12.251509 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 12 17:13:12.309816 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 12 17:13:12.311537 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 17:13:12.321634 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 12 17:13:12.354101 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 12 17:13:12.358041 systemd-modules-load[207]: Inserted module 'br_netfilter' Sep 12 17:13:12.361635 kernel: Bridge firewalling registered Sep 12 17:13:12.363217 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 12 17:13:12.368162 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 12 17:13:12.370354 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 12 17:13:12.395062 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 12 17:13:12.397141 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 12 17:13:12.414942 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 12 17:13:12.417181 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 12 17:13:12.443265 dracut-cmdline[244]: dracut-dracut-053 Sep 12 17:13:12.448799 dracut-cmdline[244]: Using kernel command line parameters: rd.driver.pre=btrfs rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=ea81bd4228a6b9fed11f4ec3af9a6e9673be062592f47971c283403bcba44656 Sep 12 17:13:12.456014 systemd-resolved[241]: Positive Trust Anchors: Sep 12 17:13:12.456024 systemd-resolved[241]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 12 17:13:12.456055 systemd-resolved[241]: 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 12 17:13:12.458578 systemd-resolved[241]: Defaulting to hostname 'linux'. Sep 12 17:13:12.459618 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 12 17:13:12.473968 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 12 17:13:12.601103 kernel: SCSI subsystem initialized Sep 12 17:13:12.611102 kernel: Loading iSCSI transport class v2.0-870. Sep 12 17:13:12.624099 kernel: iscsi: registered transport (tcp) Sep 12 17:13:12.648959 kernel: iscsi: registered transport (qla4xxx) Sep 12 17:13:12.648987 kernel: QLogic iSCSI HBA Driver Sep 12 17:13:12.690602 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 12 17:13:12.708271 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 12 17:13:12.738044 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 12 17:13:12.738097 kernel: device-mapper: uevent: version 1.0.3 Sep 12 17:13:12.742711 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Sep 12 17:13:12.784099 kernel: raid6: avx2x4 gen() 31152 MB/s Sep 12 17:13:12.804100 kernel: raid6: avx2x2 gen() 31420 MB/s Sep 12 17:13:12.825381 kernel: raid6: avx2x1 gen() 28016 MB/s Sep 12 17:13:12.825410 kernel: raid6: using algorithm avx2x2 gen() 31420 MB/s Sep 12 17:13:12.847356 kernel: raid6: .... xor() 18531 MB/s, rmw enabled Sep 12 17:13:12.847381 kernel: raid6: using avx2x2 recovery algorithm Sep 12 17:13:12.872108 kernel: xor: automatically using best checksumming function avx Sep 12 17:13:13.031140 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 12 17:13:13.044226 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 12 17:13:13.062263 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 12 17:13:13.084850 systemd-udevd[428]: Using default interface naming scheme 'v255'. Sep 12 17:13:13.090420 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 12 17:13:13.115225 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 12 17:13:13.132132 dracut-pre-trigger[436]: rd.md=0: removing MD RAID activation Sep 12 17:13:13.159632 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 12 17:13:13.174211 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 12 17:13:13.232097 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 12 17:13:13.251268 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 12 17:13:13.262449 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 12 17:13:13.273852 kernel: cryptd: max_cpu_qlen set to 1000 Sep 12 17:13:13.267682 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 12 17:13:13.275320 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 12 17:13:13.288543 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 12 17:13:13.310104 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 12 17:13:13.323730 kernel: AVX2 version of gcm_enc/dec engaged. Sep 12 17:13:13.323755 kernel: hv_vmbus: Vmbus version:4.0 Sep 12 17:13:13.323768 kernel: AES CTR mode by8 optimization enabled Sep 12 17:13:13.327538 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 12 17:13:13.333478 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 12 17:13:13.353160 kernel: hv_vmbus: registering driver hyperv_keyboard Sep 12 17:13:13.333604 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 12 17:13:13.348245 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 12 17:13:13.376880 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 12 17:13:13.376913 kernel: pps_core: LinuxPPS API ver. 1 registered Sep 12 17:13:13.353030 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 12 17:13:13.392807 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Sep 12 17:13:13.353288 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 17:13:13.376925 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 12 17:13:13.406272 kernel: PTP clock support registered Sep 12 17:13:13.406299 kernel: hid: raw HID events driver (C) Jiri Kosina Sep 12 17:13:13.414107 kernel: hv_vmbus: registering driver hid_hyperv Sep 12 17:13:13.414138 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Sep 12 17:13:13.417604 kernel: hv_vmbus: registering driver hv_storvsc Sep 12 17:13:13.417634 kernel: hv_utils: Registering HyperV Utility Driver Sep 12 17:13:13.417647 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Sep 12 17:13:13.417842 kernel: hv_vmbus: registering driver hv_pci Sep 12 17:13:13.418094 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Sep 12 17:13:13.426962 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Sep 12 17:13:13.427156 kernel: hv_vmbus: registering driver hv_utils Sep 12 17:13:13.420311 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 12 17:13:13.364331 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Sep 12 17:13:13.371087 kernel: scsi host1: storvsc_host_t Sep 12 17:13:13.371249 kernel: hv_utils: Heartbeat IC version 3.0 Sep 12 17:13:13.371261 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Sep 12 17:13:13.371393 kernel: hv_utils: TimeSync IC version 4.0 Sep 12 17:13:13.371405 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Sep 12 17:13:13.371558 kernel: hv_utils: Shutdown IC version 3.2 Sep 12 17:13:13.371570 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 Sep 12 17:13:13.371593 kernel: scsi host0: storvsc_host_t Sep 12 17:13:13.371728 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Sep 12 17:13:13.371897 kernel: pci 0001:00:00.0: reg 0x10: [mem 0x41000000-0x41ffffff] Sep 12 17:13:13.371917 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Sep 12 17:13:13.372080 kernel: pci 0001:00:00.0: reg 0x14: [mem 0x1000000000-0x13ffffffff 64bit pref] Sep 12 17:13:13.372101 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Sep 12 17:13:13.372265 kernel: pci 0001:00:00.0: reg 0x1c: [mem 0x1400000000-0x1401ffffff 64bit pref] Sep 12 17:13:13.372293 systemd-journald[204]: Time jumped backwards, rotating. Sep 12 17:13:13.372341 kernel: pci 0001:00:00.0: Enabling HDA controller Sep 12 17:13:13.310550 systemd-resolved[241]: Clock change detected. Flushing caches. Sep 12 17:13:13.374329 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 17:13:13.376141 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 12 17:13:13.399521 kernel: hv_vmbus: registering driver hv_netvsc Sep 12 17:13:13.406475 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Sep 12 17:13:13.406822 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Sep 12 17:13:13.410520 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Sep 12 17:13:13.412365 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 12 17:13:13.437681 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Sep 12 17:13:13.437848 kernel: pci 0001:00:00.0: BAR 1: assigned [mem 0x1000000000-0x13ffffffff 64bit pref] Sep 12 17:13:13.438035 kernel: pci 0001:00:00.0: BAR 3: assigned [mem 0x1400000000-0x1401ffffff 64bit pref] Sep 12 17:13:13.438198 kernel: pci 0001:00:00.0: BAR 0: assigned [mem 0x41000000-0x41ffffff] Sep 12 17:13:13.449521 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Sep 12 17:13:13.449711 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Sep 12 17:13:13.449865 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks Sep 12 17:13:13.455479 kernel: sd 0:0:0:1: [sdb] Write Protect is off Sep 12 17:13:13.455679 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Sep 12 17:13:13.455833 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Sep 12 17:13:13.461534 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Sep 12 17:13:13.467526 kernel: sd 0:0:0:0: [sda] Write Protect is off Sep 12 17:13:13.470591 kernel: sdb: sdb1 Sep 12 17:13:13.470617 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Sep 12 17:13:13.470803 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk Sep 12 17:13:13.474544 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Sep 12 17:13:13.485521 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 12 17:13:13.485550 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Sep 12 17:13:14.081526 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 scanned by (udev-worker) (477) Sep 12 17:13:14.107411 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Sep 12 17:13:14.120248 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Sep 12 17:13:14.167527 kernel: BTRFS: device fsid 8328a8c6-e42c-42bb-93d2-f755d7523d53 devid 1 transid 34 /dev/sda3 scanned by (udev-worker) (475) Sep 12 17:13:14.185408 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Sep 12 17:13:14.199663 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Sep 12 17:13:14.200493 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Sep 12 17:13:14.228628 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 12 17:13:14.250524 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 12 17:13:14.262523 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 12 17:13:15.272464 disk-uuid[639]: The operation has completed successfully. Sep 12 17:13:15.276403 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 12 17:13:15.353791 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 12 17:13:15.353898 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 12 17:13:15.390633 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 12 17:13:15.402055 sh[726]: Success Sep 12 17:13:15.432526 kernel: device-mapper: verity: sha256 using implementation "sha256-avx2" Sep 12 17:13:15.800826 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 12 17:13:15.823871 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 12 17:13:15.825981 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 12 17:13:15.857136 kernel: BTRFS info (device dm-0): first mount of filesystem 8328a8c6-e42c-42bb-93d2-f755d7523d53 Sep 12 17:13:15.857168 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Sep 12 17:13:15.862102 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Sep 12 17:13:15.865927 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 12 17:13:15.869030 kernel: BTRFS info (device dm-0): using free space tree Sep 12 17:13:16.706828 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 12 17:13:16.711072 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 12 17:13:16.727624 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 12 17:13:16.735579 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 12 17:13:16.775776 kernel: BTRFS info (device sda6): first mount of filesystem 27144f91-5d6e-4232-8594-aeebe7d5186d Sep 12 17:13:16.775810 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Sep 12 17:13:16.778999 kernel: BTRFS info (device sda6): using free space tree Sep 12 17:13:16.821526 kernel: BTRFS info (device sda6): auto enabling async discard Sep 12 17:13:16.828210 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 12 17:13:16.839311 kernel: BTRFS info (device sda6): last unmount of filesystem 27144f91-5d6e-4232-8594-aeebe7d5186d Sep 12 17:13:16.847670 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 12 17:13:16.852033 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 12 17:13:16.859754 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 12 17:13:16.892472 systemd-networkd[905]: lo: Link UP Sep 12 17:13:16.892483 systemd-networkd[905]: lo: Gained carrier Sep 12 17:13:16.893717 systemd-networkd[905]: Enumeration completed Sep 12 17:13:16.893816 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 12 17:13:16.894088 systemd-networkd[905]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 12 17:13:16.894093 systemd-networkd[905]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 12 17:13:16.894907 systemd-networkd[905]: eth0: Link UP Sep 12 17:13:16.895074 systemd-networkd[905]: eth0: Gained carrier Sep 12 17:13:16.895082 systemd-networkd[905]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 12 17:13:16.899729 systemd[1]: Reached target network.target - Network. Sep 12 17:13:16.953542 systemd-networkd[905]: eth0: DHCPv4 address 10.200.8.11/24, gateway 10.200.8.1 acquired from 168.63.129.16 Sep 12 17:13:18.024627 systemd-networkd[905]: eth0: Gained IPv6LL Sep 12 17:13:18.381092 ignition[908]: Ignition 2.20.0 Sep 12 17:13:18.381105 ignition[908]: Stage: fetch-offline Sep 12 17:13:18.382746 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 12 17:13:18.381143 ignition[908]: no configs at "/usr/lib/ignition/base.d" Sep 12 17:13:18.397645 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Sep 12 17:13:18.381154 ignition[908]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 12 17:13:18.381260 ignition[908]: parsed url from cmdline: "" Sep 12 17:13:18.381265 ignition[908]: no config URL provided Sep 12 17:13:18.381270 ignition[908]: reading system config file "/usr/lib/ignition/user.ign" Sep 12 17:13:18.381280 ignition[908]: no config at "/usr/lib/ignition/user.ign" Sep 12 17:13:18.381286 ignition[908]: failed to fetch config: resource requires networking Sep 12 17:13:18.381463 ignition[908]: Ignition finished successfully Sep 12 17:13:18.412297 ignition[920]: Ignition 2.20.0 Sep 12 17:13:18.412304 ignition[920]: Stage: fetch Sep 12 17:13:18.412450 ignition[920]: no configs at "/usr/lib/ignition/base.d" Sep 12 17:13:18.412461 ignition[920]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 12 17:13:18.412582 ignition[920]: parsed url from cmdline: "" Sep 12 17:13:18.412587 ignition[920]: no config URL provided Sep 12 17:13:18.412593 ignition[920]: reading system config file "/usr/lib/ignition/user.ign" Sep 12 17:13:18.412605 ignition[920]: no config at "/usr/lib/ignition/user.ign" Sep 12 17:13:18.412632 ignition[920]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Sep 12 17:13:18.993289 ignition[920]: GET result: OK Sep 12 17:13:18.993339 ignition[920]: config has been read from IMDS userdata Sep 12 17:13:18.993351 ignition[920]: parsing config with SHA512: ea36dabbb7deab445eaab37343e3cc551b6acb7c91cff2b2814ab8d5e74f2da07231094ed317f2209b1d468f07f4fd68ca811d55ce2d7c062294eac572e043ec Sep 12 17:13:18.996818 unknown[920]: fetched base config from "system" Sep 12 17:13:18.996988 ignition[920]: fetch: fetch complete Sep 12 17:13:18.996826 unknown[920]: fetched base config from "system" Sep 12 17:13:18.996993 ignition[920]: fetch: fetch passed Sep 12 17:13:18.996832 unknown[920]: fetched user config from "azure" Sep 12 17:13:18.997075 ignition[920]: Ignition finished successfully Sep 12 17:13:19.018532 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Sep 12 17:13:19.040632 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 12 17:13:19.061939 ignition[929]: Ignition 2.20.0 Sep 12 17:13:19.061959 ignition[929]: Stage: kargs Sep 12 17:13:19.062147 ignition[929]: no configs at "/usr/lib/ignition/base.d" Sep 12 17:13:19.067288 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 12 17:13:19.062160 ignition[929]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 12 17:13:19.062731 ignition[929]: kargs: kargs passed Sep 12 17:13:19.062777 ignition[929]: Ignition finished successfully Sep 12 17:13:19.090017 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 12 17:13:19.112847 ignition[939]: Ignition 2.20.0 Sep 12 17:13:19.112859 ignition[939]: Stage: disks Sep 12 17:13:19.113014 ignition[939]: no configs at "/usr/lib/ignition/base.d" Sep 12 17:13:19.113026 ignition[939]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 12 17:13:19.117975 ignition[939]: disks: disks passed Sep 12 17:13:19.118019 ignition[939]: Ignition finished successfully Sep 12 17:13:19.126469 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 12 17:13:19.131994 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 12 17:13:19.139379 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 12 17:13:19.148070 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 12 17:13:19.156574 systemd[1]: Reached target sysinit.target - System Initialization. Sep 12 17:13:19.158127 systemd[1]: Reached target basic.target - Basic System. Sep 12 17:13:19.189645 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 12 17:13:19.259338 systemd-fsck[951]: ROOT: clean, 14/7326000 files, 477710/7359488 blocks Sep 12 17:13:19.267387 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 12 17:13:19.289571 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 12 17:13:19.390519 kernel: EXT4-fs (sda9): mounted filesystem 5378802a-8117-4ea8-949a-cd38005ba44a r/w with ordered data mode. Quota mode: none. Sep 12 17:13:19.390655 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 12 17:13:19.392639 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 12 17:13:19.432606 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 12 17:13:19.448565 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/sda6 scanned by mount (962) Sep 12 17:13:19.449235 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 12 17:13:19.463173 kernel: BTRFS info (device sda6): first mount of filesystem 27144f91-5d6e-4232-8594-aeebe7d5186d Sep 12 17:13:19.463192 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Sep 12 17:13:19.463203 kernel: BTRFS info (device sda6): using free space tree Sep 12 17:13:19.464137 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Sep 12 17:13:19.477911 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Sep 12 17:13:19.477950 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 12 17:13:19.493147 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 12 17:13:19.510522 kernel: BTRFS info (device sda6): auto enabling async discard Sep 12 17:13:19.512634 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 12 17:13:19.515403 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 12 17:13:20.396477 coreos-metadata[966]: Sep 12 17:13:20.396 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Sep 12 17:13:20.404240 coreos-metadata[966]: Sep 12 17:13:20.404 INFO Fetch successful Sep 12 17:13:20.408075 coreos-metadata[966]: Sep 12 17:13:20.404 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Sep 12 17:13:20.423517 coreos-metadata[966]: Sep 12 17:13:20.423 INFO Fetch successful Sep 12 17:13:20.427271 coreos-metadata[966]: Sep 12 17:13:20.423 INFO wrote hostname ci-4230.2.3-a-29a8281301 to /sysroot/etc/hostname Sep 12 17:13:20.435125 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Sep 12 17:13:20.713626 initrd-setup-root[995]: cut: /sysroot/etc/passwd: No such file or directory Sep 12 17:13:20.767081 initrd-setup-root[1002]: cut: /sysroot/etc/group: No such file or directory Sep 12 17:13:20.793681 initrd-setup-root[1009]: cut: /sysroot/etc/shadow: No such file or directory Sep 12 17:13:20.808287 initrd-setup-root[1016]: cut: /sysroot/etc/gshadow: No such file or directory Sep 12 17:13:22.110806 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 12 17:13:22.135640 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 12 17:13:22.144227 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 12 17:13:22.157081 kernel: BTRFS info (device sda6): last unmount of filesystem 27144f91-5d6e-4232-8594-aeebe7d5186d Sep 12 17:13:22.152182 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 12 17:13:22.177803 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 12 17:13:22.180706 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 12 17:13:22.193208 ignition[1085]: INFO : Ignition 2.20.0 Sep 12 17:13:22.193208 ignition[1085]: INFO : Stage: mount Sep 12 17:13:22.193208 ignition[1085]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 12 17:13:22.193208 ignition[1085]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 12 17:13:22.193208 ignition[1085]: INFO : mount: mount passed Sep 12 17:13:22.193208 ignition[1085]: INFO : Ignition finished successfully Sep 12 17:13:22.205620 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 12 17:13:22.212817 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 12 17:13:22.241524 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/sda6 scanned by mount (1100) Sep 12 17:13:22.250567 kernel: BTRFS info (device sda6): first mount of filesystem 27144f91-5d6e-4232-8594-aeebe7d5186d Sep 12 17:13:22.250585 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Sep 12 17:13:22.253646 kernel: BTRFS info (device sda6): using free space tree Sep 12 17:13:22.261520 kernel: BTRFS info (device sda6): auto enabling async discard Sep 12 17:13:22.263364 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 12 17:13:22.291759 ignition[1117]: INFO : Ignition 2.20.0 Sep 12 17:13:22.291759 ignition[1117]: INFO : Stage: files Sep 12 17:13:22.297606 ignition[1117]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 12 17:13:22.297606 ignition[1117]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 12 17:13:22.297606 ignition[1117]: DEBUG : files: compiled without relabeling support, skipping Sep 12 17:13:22.326945 ignition[1117]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 12 17:13:22.326945 ignition[1117]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 12 17:13:22.410594 ignition[1117]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 12 17:13:22.416121 ignition[1117]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 12 17:13:22.416121 ignition[1117]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 12 17:13:22.411313 unknown[1117]: wrote ssh authorized keys file for user: core Sep 12 17:13:22.442360 ignition[1117]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 12 17:13:22.449685 ignition[1117]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 12 17:13:22.449685 ignition[1117]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 12 17:13:22.449685 ignition[1117]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 12 17:13:22.449685 ignition[1117]: INFO : files: files passed Sep 12 17:13:22.449685 ignition[1117]: INFO : Ignition finished successfully Sep 12 17:13:22.465240 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 12 17:13:22.486629 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 12 17:13:22.488801 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 12 17:13:22.497160 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 12 17:13:22.497252 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 12 17:13:22.519166 initrd-setup-root-after-ignition[1149]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 12 17:13:22.525614 initrd-setup-root-after-ignition[1149]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 12 17:13:22.532430 initrd-setup-root-after-ignition[1153]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 12 17:13:22.533422 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 12 17:13:22.544070 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 12 17:13:22.568624 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 12 17:13:22.592124 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 12 17:13:22.592231 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 12 17:13:22.601452 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 12 17:13:22.604013 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 12 17:13:22.615711 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 12 17:13:22.628625 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 12 17:13:22.643204 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 12 17:13:22.654085 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 12 17:13:22.672840 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 12 17:13:22.674466 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 12 17:13:22.683707 systemd[1]: Stopped target timers.target - Timer Units. Sep 12 17:13:22.692356 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 12 17:13:22.692477 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 12 17:13:22.707578 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 12 17:13:22.709103 systemd[1]: Stopped target basic.target - Basic System. Sep 12 17:13:22.721366 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 12 17:13:22.732036 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 12 17:13:22.740821 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 12 17:13:22.749910 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 12 17:13:22.758456 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 12 17:13:22.767290 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 12 17:13:22.769437 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 12 17:13:22.777862 systemd[1]: Stopped target swap.target - Swaps. Sep 12 17:13:22.785772 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 12 17:13:22.785898 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 12 17:13:22.798831 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 12 17:13:22.807002 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 12 17:13:22.815951 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 12 17:13:22.816062 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 12 17:13:22.825233 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 12 17:13:22.825354 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 12 17:13:22.837972 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 12 17:13:22.838102 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 12 17:13:22.848427 systemd[1]: ignition-files.service: Deactivated successfully. Sep 12 17:13:22.848555 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 12 17:13:22.856210 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Sep 12 17:13:22.856327 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Sep 12 17:13:22.884651 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 12 17:13:22.902002 ignition[1173]: INFO : Ignition 2.20.0 Sep 12 17:13:22.902002 ignition[1173]: INFO : Stage: umount Sep 12 17:13:22.902002 ignition[1173]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 12 17:13:22.902002 ignition[1173]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 12 17:13:22.902002 ignition[1173]: INFO : umount: umount passed Sep 12 17:13:22.902002 ignition[1173]: INFO : Ignition finished successfully Sep 12 17:13:22.887499 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 12 17:13:22.887640 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 12 17:13:22.901390 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 12 17:13:22.907835 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 12 17:13:22.907971 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 12 17:13:22.915388 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 12 17:13:22.915571 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 12 17:13:22.929078 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 12 17:13:22.929175 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 12 17:13:22.934851 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 12 17:13:22.934966 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 12 17:13:22.940933 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 12 17:13:22.940982 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 12 17:13:22.951183 systemd[1]: ignition-fetch.service: Deactivated successfully. Sep 12 17:13:22.951231 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Sep 12 17:13:22.952694 systemd[1]: Stopped target network.target - Network. Sep 12 17:13:22.960545 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 12 17:13:22.960598 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 12 17:13:22.968924 systemd[1]: Stopped target paths.target - Path Units. Sep 12 17:13:22.977477 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 12 17:13:22.982545 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 12 17:13:22.985912 systemd[1]: Stopped target slices.target - Slice Units. Sep 12 17:13:22.998713 systemd[1]: Stopped target sockets.target - Socket Units. Sep 12 17:13:23.006290 systemd[1]: iscsid.socket: Deactivated successfully. Sep 12 17:13:23.006343 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 12 17:13:23.013539 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 12 17:13:23.013586 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 12 17:13:23.024624 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 12 17:13:23.024680 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 12 17:13:23.036147 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 12 17:13:23.039899 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 12 17:13:23.047918 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 12 17:13:23.055404 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 12 17:13:23.064171 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 12 17:13:23.064897 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 12 17:13:23.064999 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 12 17:13:23.075008 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 12 17:13:23.075158 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 12 17:13:23.087673 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 12 17:13:23.087769 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 12 17:13:23.095866 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 12 17:13:23.095960 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 12 17:13:23.106654 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 12 17:13:23.106740 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 12 17:13:23.111455 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 12 17:13:23.111519 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 12 17:13:23.153577 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 12 17:13:23.156371 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 12 17:13:23.156425 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 12 17:13:23.168647 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 12 17:13:23.168695 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 12 17:13:23.178194 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 12 17:13:23.178242 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 12 17:13:23.185343 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 12 17:13:23.185389 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 12 17:13:23.194283 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 12 17:13:23.196849 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Sep 12 17:13:23.196909 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Sep 12 17:13:23.196951 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Sep 12 17:13:23.196995 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Sep 12 17:13:23.207646 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 12 17:13:23.207757 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 12 17:13:23.235798 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 12 17:13:23.235938 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 12 17:13:23.244880 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 12 17:13:23.244925 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 12 17:13:23.252734 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 12 17:13:23.252770 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 12 17:13:23.261093 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 12 17:13:23.261141 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 12 17:13:23.273277 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 12 17:13:23.273348 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 12 17:13:23.290316 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 12 17:13:23.290370 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 12 17:13:23.315627 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 12 17:13:23.324563 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 12 17:13:23.324618 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 12 17:13:23.339776 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 12 17:13:23.339826 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 17:13:23.350941 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Sep 12 17:13:23.351000 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 12 17:13:23.351326 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 12 17:13:23.351417 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 12 17:13:23.356550 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 12 17:13:23.375636 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 12 17:13:23.401793 systemd[1]: Switching root. Sep 12 17:13:23.586339 systemd-journald[204]: Journal stopped Sep 12 17:13:31.342580 systemd-journald[204]: Received SIGTERM from PID 1 (systemd). Sep 12 17:13:31.342612 kernel: SELinux: policy capability network_peer_controls=1 Sep 12 17:13:31.342626 kernel: SELinux: policy capability open_perms=1 Sep 12 17:13:31.342636 kernel: SELinux: policy capability extended_socket_class=1 Sep 12 17:13:31.342646 kernel: SELinux: policy capability always_check_network=0 Sep 12 17:13:31.342655 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 12 17:13:31.342666 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 12 17:13:31.342679 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 12 17:13:31.342689 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 12 17:13:31.342698 kernel: audit: type=1403 audit(1757697204.551:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 12 17:13:31.342709 systemd[1]: Successfully loaded SELinux policy in 207.689ms. Sep 12 17:13:31.342721 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 15.544ms. Sep 12 17:13:31.342732 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 12 17:13:31.342743 systemd[1]: Detected virtualization microsoft. Sep 12 17:13:31.342758 systemd[1]: Detected architecture x86-64. Sep 12 17:13:31.342768 systemd[1]: Detected first boot. Sep 12 17:13:31.342782 systemd[1]: Hostname set to . Sep 12 17:13:31.342794 systemd[1]: Initializing machine ID from random generator. Sep 12 17:13:31.342804 zram_generator::config[1222]: No configuration found. Sep 12 17:13:31.342823 kernel: Guest personality initialized and is inactive Sep 12 17:13:31.342834 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Sep 12 17:13:31.342844 kernel: Initialized host personality Sep 12 17:13:31.342854 kernel: NET: Registered PF_VSOCK protocol family Sep 12 17:13:31.342864 systemd[1]: Populated /etc with preset unit settings. Sep 12 17:13:31.342876 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Sep 12 17:13:31.342887 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 12 17:13:31.342898 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 12 17:13:31.342912 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 12 17:13:31.342923 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 12 17:13:31.342935 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 12 17:13:31.342946 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 12 17:13:31.342957 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 12 17:13:31.342967 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 12 17:13:31.342978 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 12 17:13:31.342992 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 12 17:13:31.343004 systemd[1]: Created slice user.slice - User and Session Slice. Sep 12 17:13:31.343015 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 12 17:13:31.343026 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 12 17:13:31.343037 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 12 17:13:31.343048 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 12 17:13:31.343067 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 12 17:13:31.343079 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 12 17:13:31.343093 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Sep 12 17:13:31.343106 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 12 17:13:31.343117 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 12 17:13:31.343128 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 12 17:13:31.343140 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 12 17:13:31.343151 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 12 17:13:31.343163 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 12 17:13:31.343174 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 12 17:13:31.343188 systemd[1]: Reached target slices.target - Slice Units. Sep 12 17:13:31.343200 systemd[1]: Reached target swap.target - Swaps. Sep 12 17:13:31.343211 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 12 17:13:31.343222 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 12 17:13:31.343234 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Sep 12 17:13:31.343249 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 12 17:13:31.343260 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 12 17:13:31.343272 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 12 17:13:31.343283 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 12 17:13:31.343295 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 12 17:13:31.343306 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 12 17:13:31.343320 systemd[1]: Mounting media.mount - External Media Directory... Sep 12 17:13:31.343332 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 12 17:13:31.343347 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 12 17:13:31.343358 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 12 17:13:31.343370 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 12 17:13:31.343382 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 12 17:13:31.343394 systemd[1]: Reached target machines.target - Containers. Sep 12 17:13:31.343405 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 12 17:13:31.343417 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 12 17:13:31.343428 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 12 17:13:31.343443 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 12 17:13:31.343454 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 12 17:13:31.343466 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 12 17:13:31.343478 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 12 17:13:31.343489 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 12 17:13:31.343501 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 12 17:13:31.343657 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 12 17:13:31.343678 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 12 17:13:31.343696 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 12 17:13:31.343708 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 12 17:13:31.343720 systemd[1]: Stopped systemd-fsck-usr.service. Sep 12 17:13:31.343733 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 12 17:13:31.343745 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 12 17:13:31.343756 kernel: loop: module loaded Sep 12 17:13:31.343768 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 12 17:13:31.343779 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 12 17:13:31.343794 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 12 17:13:31.343806 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Sep 12 17:13:31.343818 kernel: fuse: init (API version 7.39) Sep 12 17:13:31.343833 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 12 17:13:31.343844 systemd[1]: verity-setup.service: Deactivated successfully. Sep 12 17:13:31.343856 systemd[1]: Stopped verity-setup.service. Sep 12 17:13:31.343868 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 12 17:13:31.343903 systemd-journald[1329]: Collecting audit messages is disabled. Sep 12 17:13:31.343932 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 12 17:13:31.343944 kernel: ACPI: bus type drm_connector registered Sep 12 17:13:31.343956 systemd-journald[1329]: Journal started Sep 12 17:13:31.343982 systemd-journald[1329]: Runtime Journal (/run/log/journal/1fe5ee9ae802415696b8a2c1c26df2ad) is 8M, max 2.2G, 2.1G free. Sep 12 17:13:30.474015 systemd[1]: Queued start job for default target multi-user.target. Sep 12 17:13:30.489824 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Sep 12 17:13:30.490294 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 12 17:13:31.354837 systemd[1]: Started systemd-journald.service - Journal Service. Sep 12 17:13:31.358976 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 12 17:13:31.363557 systemd[1]: Mounted media.mount - External Media Directory. Sep 12 17:13:31.367733 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 12 17:13:31.372201 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 12 17:13:31.376635 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 12 17:13:31.380855 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 12 17:13:31.385807 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 12 17:13:31.391083 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 12 17:13:31.391277 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 12 17:13:31.396390 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 12 17:13:31.396760 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 12 17:13:31.401823 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 12 17:13:31.402009 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 12 17:13:31.406470 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 12 17:13:31.406669 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 12 17:13:31.411945 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 12 17:13:31.412133 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 12 17:13:31.416828 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 12 17:13:31.417014 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 12 17:13:31.421793 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 12 17:13:31.426737 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 12 17:13:31.432144 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 12 17:13:31.437590 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Sep 12 17:13:31.451895 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 12 17:13:31.468602 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 12 17:13:31.473999 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 12 17:13:31.478516 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 12 17:13:31.478555 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 12 17:13:31.483647 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Sep 12 17:13:31.489714 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 12 17:13:31.495521 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 12 17:13:31.499713 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 12 17:13:31.534621 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 12 17:13:31.539933 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 12 17:13:31.544663 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 12 17:13:31.545749 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 12 17:13:31.550171 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 12 17:13:31.551161 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 12 17:13:31.556280 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 12 17:13:31.562146 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 12 17:13:31.569092 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 12 17:13:31.574260 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 12 17:13:31.580673 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 12 17:13:31.586498 systemd-journald[1329]: Time spent on flushing to /var/log/journal/1fe5ee9ae802415696b8a2c1c26df2ad is 68.884ms for 938 entries. Sep 12 17:13:31.586498 systemd-journald[1329]: System Journal (/var/log/journal/1fe5ee9ae802415696b8a2c1c26df2ad) is 11.8M, max 2.6G, 2.6G free. Sep 12 17:13:31.797772 systemd-journald[1329]: Received client request to flush runtime journal. Sep 12 17:13:31.797826 systemd-journald[1329]: /var/log/journal/1fe5ee9ae802415696b8a2c1c26df2ad/system.journal: Realtime clock jumped backwards relative to last journal entry, rotating. Sep 12 17:13:31.797868 systemd-journald[1329]: Rotating system journal. Sep 12 17:13:31.797898 kernel: loop0: detected capacity change from 0 to 28272 Sep 12 17:13:31.591102 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 12 17:13:31.597569 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 12 17:13:31.605312 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 12 17:13:31.632023 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Sep 12 17:13:31.637611 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Sep 12 17:13:31.652430 udevadm[1374]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Sep 12 17:13:31.790449 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 12 17:13:31.798745 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 12 17:13:31.810196 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 12 17:13:31.810944 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Sep 12 17:13:32.185149 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 12 17:13:32.201662 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 12 17:13:32.400356 systemd-tmpfiles[1382]: ACLs are not supported, ignoring. Sep 12 17:13:32.400379 systemd-tmpfiles[1382]: ACLs are not supported, ignoring. Sep 12 17:13:32.405989 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 12 17:13:32.485550 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 12 17:13:32.630544 kernel: loop1: detected capacity change from 0 to 147912 Sep 12 17:13:33.178532 kernel: loop2: detected capacity change from 0 to 138176 Sep 12 17:13:34.163691 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 12 17:13:34.180753 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 12 17:13:34.203647 systemd-udevd[1389]: Using default interface naming scheme 'v255'. Sep 12 17:13:34.533525 kernel: loop3: detected capacity change from 0 to 28272 Sep 12 17:13:34.550534 kernel: loop4: detected capacity change from 0 to 147912 Sep 12 17:13:34.569526 kernel: loop5: detected capacity change from 0 to 138176 Sep 12 17:13:34.584592 (sd-merge)[1391]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Sep 12 17:13:34.585133 (sd-merge)[1391]: Merged extensions into '/usr'. Sep 12 17:13:34.588464 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 12 17:13:34.605668 systemd[1]: Starting ensure-sysext.service... Sep 12 17:13:34.609471 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 12 17:13:34.654725 systemd-tmpfiles[1394]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 12 17:13:34.655014 systemd-tmpfiles[1394]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 12 17:13:34.655924 systemd-tmpfiles[1394]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 12 17:13:34.656206 systemd-tmpfiles[1394]: ACLs are not supported, ignoring. Sep 12 17:13:34.656274 systemd-tmpfiles[1394]: ACLs are not supported, ignoring. Sep 12 17:13:34.699355 systemd[1]: Reload requested from client PID 1393 ('systemctl') (unit ensure-sysext.service)... Sep 12 17:13:34.699372 systemd[1]: Reloading... Sep 12 17:13:34.726150 systemd-tmpfiles[1394]: Detected autofs mount point /boot during canonicalization of boot. Sep 12 17:13:34.726165 systemd-tmpfiles[1394]: Skipping /boot Sep 12 17:13:34.740599 systemd-tmpfiles[1394]: Detected autofs mount point /boot during canonicalization of boot. Sep 12 17:13:34.740615 systemd-tmpfiles[1394]: Skipping /boot Sep 12 17:13:34.742516 zram_generator::config[1423]: No configuration found. Sep 12 17:13:34.878701 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 12 17:13:34.975079 systemd[1]: Reloading finished in 275 ms. Sep 12 17:13:34.993701 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 12 17:13:35.016883 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 12 17:13:35.036232 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Sep 12 17:13:35.038570 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 12 17:13:35.049856 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 12 17:13:35.118527 kernel: hv_vmbus: registering driver hv_balloon Sep 12 17:13:35.118617 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Sep 12 17:13:35.122529 kernel: mousedev: PS/2 mouse device common for all mice Sep 12 17:13:35.150659 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 12 17:13:35.154358 kernel: hv_vmbus: registering driver hyperv_fb Sep 12 17:13:35.154414 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Sep 12 17:13:35.159391 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Sep 12 17:13:35.161527 kernel: Console: switching to colour dummy device 80x25 Sep 12 17:13:35.165670 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 12 17:13:35.166914 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 12 17:13:35.168667 kernel: Console: switching to colour frame buffer device 128x48 Sep 12 17:13:35.174074 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 12 17:13:35.180941 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 12 17:13:35.186030 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 12 17:13:35.186170 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 12 17:13:35.187354 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 12 17:13:35.190456 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 12 17:13:35.195450 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 12 17:13:35.201757 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 12 17:13:35.206607 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 12 17:13:35.208517 kernel: EDAC MC: Ver: 3.0.0 Sep 12 17:13:35.208981 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 12 17:13:35.209447 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 12 17:13:35.218795 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 12 17:13:35.218986 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 12 17:13:35.224434 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 12 17:13:35.224628 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 12 17:13:35.243861 systemd[1]: Condition check resulted in dev-ptp_hyperv.device - /dev/ptp_hyperv being skipped. Sep 12 17:13:35.248526 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 12 17:13:35.254722 systemd[1]: Finished ensure-sysext.service. Sep 12 17:13:35.261570 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 34 scanned by (udev-worker) (1512) Sep 12 17:13:35.290300 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 12 17:13:35.290593 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 12 17:13:35.301818 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 12 17:13:35.307566 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 12 17:13:35.312915 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 12 17:13:35.318881 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 12 17:13:35.323056 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 12 17:13:35.323186 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 12 17:13:35.323377 systemd[1]: Reached target time-set.target - System Time Set. Sep 12 17:13:35.328611 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 12 17:13:35.330126 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 12 17:13:35.331872 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 12 17:13:35.332054 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 12 17:13:35.338367 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 12 17:13:35.338564 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 12 17:13:35.345218 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 12 17:13:35.345397 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 12 17:13:35.350975 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 12 17:13:35.351153 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 12 17:13:35.374677 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Sep 12 17:13:35.389999 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Sep 12 17:13:35.405684 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Sep 12 17:13:35.410651 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 12 17:13:35.412172 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 12 17:13:35.412235 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 12 17:13:35.413695 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 12 17:13:35.465250 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 12 17:13:35.479216 augenrules[1647]: No rules Sep 12 17:13:35.480422 systemd[1]: audit-rules.service: Deactivated successfully. Sep 12 17:13:35.480704 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 12 17:13:35.528200 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 12 17:13:35.581829 lvm[1637]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Sep 12 17:13:35.612787 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 12 17:13:35.646127 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Sep 12 17:13:35.647516 systemd-resolved[1543]: Positive Trust Anchors: Sep 12 17:13:35.647534 systemd-resolved[1543]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 12 17:13:35.647565 systemd-resolved[1543]: 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 12 17:13:35.647929 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 12 17:13:35.671738 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Sep 12 17:13:35.676307 lvm[1661]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Sep 12 17:13:35.708883 systemd-resolved[1543]: Using system hostname 'ci-4230.2.3-a-29a8281301'. Sep 12 17:13:35.712461 systemd-networkd[1542]: lo: Link UP Sep 12 17:13:35.712472 systemd-networkd[1542]: lo: Gained carrier Sep 12 17:13:35.713194 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Sep 12 17:13:35.713912 systemd-networkd[1542]: Enumeration completed Sep 12 17:13:35.714310 systemd-networkd[1542]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 12 17:13:35.714321 systemd-networkd[1542]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 12 17:13:35.714986 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 12 17:13:35.715030 systemd-networkd[1542]: eth0: Link UP Sep 12 17:13:35.715034 systemd-networkd[1542]: eth0: Gained carrier Sep 12 17:13:35.715048 systemd-networkd[1542]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 12 17:13:35.727722 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Sep 12 17:13:35.730439 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 12 17:13:35.737739 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 12 17:13:35.739349 systemd[1]: Reached target network.target - Network. Sep 12 17:13:35.740030 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 12 17:13:35.772548 systemd-networkd[1542]: eth0: DHCPv4 address 10.200.8.11/24, gateway 10.200.8.1 acquired from 168.63.129.16 Sep 12 17:13:35.789682 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Sep 12 17:13:36.752056 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 17:13:36.904631 systemd-networkd[1542]: eth0: Gained IPv6LL Sep 12 17:13:36.907157 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 12 17:13:36.912688 systemd[1]: Reached target network-online.target - Network is Online. Sep 12 17:13:37.869165 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 12 17:13:37.874786 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 12 17:13:42.211105 ldconfig[1359]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 12 17:13:42.223305 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 12 17:13:42.246672 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 12 17:13:42.271606 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 12 17:13:42.276360 systemd[1]: Reached target sysinit.target - System Initialization. Sep 12 17:13:42.280827 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 12 17:13:42.285857 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 12 17:13:42.291129 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 12 17:13:42.295502 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 12 17:13:42.300677 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 12 17:13:42.305870 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 12 17:13:42.305905 systemd[1]: Reached target paths.target - Path Units. Sep 12 17:13:42.309598 systemd[1]: Reached target timers.target - Timer Units. Sep 12 17:13:42.339982 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 12 17:13:42.345832 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 12 17:13:42.352603 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Sep 12 17:13:42.358046 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Sep 12 17:13:42.363264 systemd[1]: Reached target ssh-access.target - SSH Access Available. Sep 12 17:13:42.369522 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 12 17:13:42.374120 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Sep 12 17:13:42.379802 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 12 17:13:42.384313 systemd[1]: Reached target sockets.target - Socket Units. Sep 12 17:13:42.388337 systemd[1]: Reached target basic.target - Basic System. Sep 12 17:13:42.392682 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 12 17:13:42.392714 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 12 17:13:42.421585 systemd[1]: Starting chronyd.service - NTP client/server... Sep 12 17:13:42.425999 systemd[1]: Starting containerd.service - containerd container runtime... Sep 12 17:13:42.431404 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Sep 12 17:13:42.436838 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 12 17:13:42.441328 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 12 17:13:42.447768 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 12 17:13:42.451971 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 12 17:13:42.452013 systemd[1]: hv_fcopy_daemon.service - Hyper-V FCOPY daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_fcopy). Sep 12 17:13:42.453017 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Sep 12 17:13:42.457292 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Sep 12 17:13:42.458279 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 12 17:13:42.461579 jq[1683]: false Sep 12 17:13:42.463229 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 12 17:13:42.468204 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 12 17:13:42.470746 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 12 17:13:42.474682 (chronyd)[1676]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Sep 12 17:13:42.479568 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 12 17:13:42.481970 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 12 17:13:42.482372 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 12 17:13:42.482970 systemd[1]: Starting update-engine.service - Update Engine... Sep 12 17:13:42.485404 KVP[1685]: KVP starting; pid is:1685 Sep 12 17:13:42.487663 chronyd[1697]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) Sep 12 17:13:42.488564 KVP[1685]: KVP LIC Version: 3.1 Sep 12 17:13:42.490524 kernel: hv_utils: KVP IC version 4.0 Sep 12 17:13:42.490871 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 12 17:13:42.497883 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 12 17:13:42.500308 jq[1699]: true Sep 12 17:13:42.498125 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 12 17:13:42.498446 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 12 17:13:42.498697 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 12 17:13:42.512032 jq[1701]: true Sep 12 17:13:42.514288 (ntainerd)[1703]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 12 17:13:42.538095 systemd[1]: motdgen.service: Deactivated successfully. Sep 12 17:13:42.538329 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 12 17:13:42.550363 extend-filesystems[1684]: Found loop3 Sep 12 17:13:42.553284 extend-filesystems[1684]: Found loop4 Sep 12 17:13:42.553284 extend-filesystems[1684]: Found loop5 Sep 12 17:13:42.553284 extend-filesystems[1684]: Found sda Sep 12 17:13:42.553284 extend-filesystems[1684]: Found sda1 Sep 12 17:13:42.553284 extend-filesystems[1684]: Found sda2 Sep 12 17:13:42.553284 extend-filesystems[1684]: Found sda3 Sep 12 17:13:42.553284 extend-filesystems[1684]: Found usr Sep 12 17:13:42.553284 extend-filesystems[1684]: Found sda4 Sep 12 17:13:42.553284 extend-filesystems[1684]: Found sda6 Sep 12 17:13:42.553284 extend-filesystems[1684]: Found sda7 Sep 12 17:13:42.553284 extend-filesystems[1684]: Found sda9 Sep 12 17:13:42.553284 extend-filesystems[1684]: Checking size of /dev/sda9 Sep 12 17:13:42.571307 chronyd[1697]: Timezone right/UTC failed leap second check, ignoring Sep 12 17:13:42.589846 update_engine[1694]: I20250912 17:13:42.581682 1694 main.cc:92] Flatcar Update Engine starting Sep 12 17:13:42.572802 systemd[1]: Started chronyd.service - NTP client/server. Sep 12 17:13:42.571464 chronyd[1697]: Loaded seccomp filter (level 2) Sep 12 17:13:42.610559 extend-filesystems[1684]: Old size kept for /dev/sda9 Sep 12 17:13:42.612142 extend-filesystems[1684]: Found sdb Sep 12 17:13:42.612142 extend-filesystems[1684]: Found sdb1 Sep 12 17:13:42.612142 extend-filesystems[1684]: Found sr0 Sep 12 17:13:42.611688 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 12 17:13:42.611911 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 12 17:13:42.646827 systemd-logind[1693]: New seat seat0. Sep 12 17:13:42.649447 systemd-logind[1693]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Sep 12 17:13:42.649750 systemd[1]: Started systemd-logind.service - User Login Management. Sep 12 17:13:42.656936 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 34 scanned by (udev-worker) (1750) Sep 12 17:13:42.671609 setup-nvidia[1687]: Downloading Flatcar Container Linux Developer Container for version: 4230.2.3 Sep 12 17:13:42.691524 bash[1726]: Updated "/home/core/.ssh/authorized_keys" Sep 12 17:13:42.692029 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 12 17:13:42.709601 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Sep 12 17:13:42.781654 sshd_keygen[1733]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 12 17:13:42.803441 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 12 17:13:42.806634 dbus-daemon[1679]: [system] SELinux support is enabled Sep 12 17:13:42.808067 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 12 17:13:42.809107 update_engine[1694]: I20250912 17:13:42.809052 1694 update_check_scheduler.cc:74] Next update check in 3m1s Sep 12 17:13:42.815271 dbus-daemon[1679]: [system] Successfully activated service 'org.freedesktop.systemd1' Sep 12 17:13:42.829656 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 12 17:13:42.833327 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 12 17:13:42.833362 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 12 17:13:42.838541 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 12 17:13:42.838568 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 12 17:13:42.844632 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Sep 12 17:13:42.849051 systemd[1]: issuegen.service: Deactivated successfully. Sep 12 17:13:42.849254 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 12 17:13:42.851770 coreos-metadata[1678]: Sep 12 17:13:42.851 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Sep 12 17:13:42.853927 coreos-metadata[1678]: Sep 12 17:13:42.853 INFO Fetch successful Sep 12 17:13:42.854740 coreos-metadata[1678]: Sep 12 17:13:42.854 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Sep 12 17:13:42.855788 systemd[1]: Started update-engine.service - Update Engine. Sep 12 17:13:42.862417 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 12 17:13:42.868131 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 12 17:13:42.873726 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Sep 12 17:13:42.890024 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 12 17:13:42.896586 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 12 17:13:42.901759 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Sep 12 17:13:42.906043 coreos-metadata[1678]: Sep 12 17:13:42.906 INFO Fetch successful Sep 12 17:13:42.906377 coreos-metadata[1678]: Sep 12 17:13:42.906 INFO Fetching http://168.63.129.16/machine/c3afae0a-6231-423b-aaed-6bd192077689/6e7e28e5%2Dd4a5%2D4e1b%2Da4e1%2Dc89eb680b65a.%5Fci%2D4230.2.3%2Da%2D29a8281301?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Sep 12 17:13:42.906564 systemd[1]: Reached target getty.target - Login Prompts. Sep 12 17:13:42.931270 coreos-metadata[1678]: Sep 12 17:13:42.931 INFO Fetch successful Sep 12 17:13:42.931422 coreos-metadata[1678]: Sep 12 17:13:42.931 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Sep 12 17:13:42.938715 coreos-metadata[1678]: Sep 12 17:13:42.938 INFO Fetch successful Sep 12 17:13:42.966816 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Sep 12 17:13:42.971882 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 12 17:13:43.129460 locksmithd[1835]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 12 17:13:43.322988 setup-nvidia[1850]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 12 17:13:43.323302 setup-nvidia[1850]: Dload Upload Total Spent Left Speed Sep 12 17:13:43.840212 containerd[1703]: time="2025-09-12T17:13:43.840127400Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Sep 12 17:13:43.858222 containerd[1703]: time="2025-09-12T17:13:43.858178700Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Sep 12 17:13:43.859739 containerd[1703]: time="2025-09-12T17:13:43.859698100Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.106-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Sep 12 17:13:43.859739 containerd[1703]: time="2025-09-12T17:13:43.859727900Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Sep 12 17:13:43.859823 containerd[1703]: time="2025-09-12T17:13:43.859743500Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Sep 12 17:13:43.859903 containerd[1703]: time="2025-09-12T17:13:43.859881400Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Sep 12 17:13:43.859934 containerd[1703]: time="2025-09-12T17:13:43.859903800Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Sep 12 17:13:43.859985 containerd[1703]: time="2025-09-12T17:13:43.859965500Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Sep 12 17:13:43.860012 containerd[1703]: time="2025-09-12T17:13:43.859983700Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Sep 12 17:13:43.860170 containerd[1703]: time="2025-09-12T17:13:43.860148700Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Sep 12 17:13:43.860208 containerd[1703]: time="2025-09-12T17:13:43.860169600Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Sep 12 17:13:43.860208 containerd[1703]: time="2025-09-12T17:13:43.860183800Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Sep 12 17:13:43.860208 containerd[1703]: time="2025-09-12T17:13:43.860192600Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Sep 12 17:13:43.860335 containerd[1703]: time="2025-09-12T17:13:43.860313300Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Sep 12 17:13:43.860596 containerd[1703]: time="2025-09-12T17:13:43.860573300Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Sep 12 17:13:43.860727 containerd[1703]: time="2025-09-12T17:13:43.860707300Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Sep 12 17:13:43.860756 containerd[1703]: time="2025-09-12T17:13:43.860726600Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Sep 12 17:13:43.860828 containerd[1703]: time="2025-09-12T17:13:43.860809600Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Sep 12 17:13:43.860883 containerd[1703]: time="2025-09-12T17:13:43.860865900Z" level=info msg="metadata content store policy set" policy=shared Sep 12 17:13:43.876603 containerd[1703]: time="2025-09-12T17:13:43.876571300Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Sep 12 17:13:43.876671 containerd[1703]: time="2025-09-12T17:13:43.876629800Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Sep 12 17:13:43.876671 containerd[1703]: time="2025-09-12T17:13:43.876650500Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Sep 12 17:13:43.876671 containerd[1703]: time="2025-09-12T17:13:43.876666200Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Sep 12 17:13:43.876737 containerd[1703]: time="2025-09-12T17:13:43.876678900Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Sep 12 17:13:43.876818 containerd[1703]: time="2025-09-12T17:13:43.876793800Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Sep 12 17:13:43.877005 containerd[1703]: time="2025-09-12T17:13:43.876984100Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Sep 12 17:13:43.877114 containerd[1703]: time="2025-09-12T17:13:43.877092300Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Sep 12 17:13:43.877144 containerd[1703]: time="2025-09-12T17:13:43.877114700Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Sep 12 17:13:43.877144 containerd[1703]: time="2025-09-12T17:13:43.877130000Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Sep 12 17:13:43.877194 containerd[1703]: time="2025-09-12T17:13:43.877142400Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Sep 12 17:13:43.877194 containerd[1703]: time="2025-09-12T17:13:43.877154700Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Sep 12 17:13:43.877194 containerd[1703]: time="2025-09-12T17:13:43.877165500Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Sep 12 17:13:43.877194 containerd[1703]: time="2025-09-12T17:13:43.877177100Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Sep 12 17:13:43.877194 containerd[1703]: time="2025-09-12T17:13:43.877189400Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Sep 12 17:13:43.877345 containerd[1703]: time="2025-09-12T17:13:43.877200500Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Sep 12 17:13:43.877345 containerd[1703]: time="2025-09-12T17:13:43.877211000Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Sep 12 17:13:43.877345 containerd[1703]: time="2025-09-12T17:13:43.877221600Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Sep 12 17:13:43.877345 containerd[1703]: time="2025-09-12T17:13:43.877238600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Sep 12 17:13:43.877345 containerd[1703]: time="2025-09-12T17:13:43.877250300Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Sep 12 17:13:43.877345 containerd[1703]: time="2025-09-12T17:13:43.877261700Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Sep 12 17:13:43.877345 containerd[1703]: time="2025-09-12T17:13:43.877273400Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Sep 12 17:13:43.877514 containerd[1703]: time="2025-09-12T17:13:43.877343400Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Sep 12 17:13:43.877514 containerd[1703]: time="2025-09-12T17:13:43.877364900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Sep 12 17:13:43.877514 containerd[1703]: time="2025-09-12T17:13:43.877375900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Sep 12 17:13:43.877514 containerd[1703]: time="2025-09-12T17:13:43.877387500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Sep 12 17:13:43.877514 containerd[1703]: time="2025-09-12T17:13:43.877410500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Sep 12 17:13:43.877514 containerd[1703]: time="2025-09-12T17:13:43.877427900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Sep 12 17:13:43.877514 containerd[1703]: time="2025-09-12T17:13:43.877439100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Sep 12 17:13:43.877514 containerd[1703]: time="2025-09-12T17:13:43.877450900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Sep 12 17:13:43.877514 containerd[1703]: time="2025-09-12T17:13:43.877462300Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Sep 12 17:13:43.877514 containerd[1703]: time="2025-09-12T17:13:43.877475900Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Sep 12 17:13:43.877514 containerd[1703]: time="2025-09-12T17:13:43.877495200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Sep 12 17:13:43.877785 containerd[1703]: time="2025-09-12T17:13:43.877529900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Sep 12 17:13:43.877785 containerd[1703]: time="2025-09-12T17:13:43.877542600Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Sep 12 17:13:43.877785 containerd[1703]: time="2025-09-12T17:13:43.877587500Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Sep 12 17:13:43.877785 containerd[1703]: time="2025-09-12T17:13:43.877604400Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Sep 12 17:13:43.877785 containerd[1703]: time="2025-09-12T17:13:43.877614600Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Sep 12 17:13:43.877785 containerd[1703]: time="2025-09-12T17:13:43.877626000Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Sep 12 17:13:43.877785 containerd[1703]: time="2025-09-12T17:13:43.877635500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Sep 12 17:13:43.877785 containerd[1703]: time="2025-09-12T17:13:43.877648300Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Sep 12 17:13:43.877785 containerd[1703]: time="2025-09-12T17:13:43.877658300Z" level=info msg="NRI interface is disabled by configuration." Sep 12 17:13:43.877785 containerd[1703]: time="2025-09-12T17:13:43.877668800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Sep 12 17:13:43.878188 containerd[1703]: time="2025-09-12T17:13:43.878074300Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Sep 12 17:13:43.878344 containerd[1703]: time="2025-09-12T17:13:43.878192800Z" level=info msg="Connect containerd service" Sep 12 17:13:43.878344 containerd[1703]: time="2025-09-12T17:13:43.878243600Z" level=info msg="using legacy CRI server" Sep 12 17:13:43.878344 containerd[1703]: time="2025-09-12T17:13:43.878257400Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 12 17:13:43.878410 containerd[1703]: time="2025-09-12T17:13:43.878372400Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Sep 12 17:13:43.879334 containerd[1703]: time="2025-09-12T17:13:43.879300900Z" 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 12 17:13:43.879471 containerd[1703]: time="2025-09-12T17:13:43.879432600Z" level=info msg="Start subscribing containerd event" Sep 12 17:13:43.879531 containerd[1703]: time="2025-09-12T17:13:43.879488500Z" level=info msg="Start recovering state" Sep 12 17:13:43.879587 containerd[1703]: time="2025-09-12T17:13:43.879568800Z" level=info msg="Start event monitor" Sep 12 17:13:43.879618 containerd[1703]: time="2025-09-12T17:13:43.879602700Z" level=info msg="Start snapshots syncer" Sep 12 17:13:43.879648 containerd[1703]: time="2025-09-12T17:13:43.879614200Z" level=info msg="Start cni network conf syncer for default" Sep 12 17:13:43.879648 containerd[1703]: time="2025-09-12T17:13:43.879607600Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 12 17:13:43.879716 containerd[1703]: time="2025-09-12T17:13:43.879627700Z" level=info msg="Start streaming server" Sep 12 17:13:43.879749 containerd[1703]: time="2025-09-12T17:13:43.879716800Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 12 17:13:43.879813 containerd[1703]: time="2025-09-12T17:13:43.879795400Z" level=info msg="containerd successfully booted in 0.040505s" Sep 12 17:13:43.879884 systemd[1]: Started containerd.service - containerd container runtime. Sep 12 17:13:45.871432 waagent[1836]: 2025-09-12T17:13:45.871338Z INFO Daemon Daemon Azure Linux Agent Version: 2.9.1.1 Sep 12 17:13:45.875793 waagent[1836]: 2025-09-12T17:13:45.875724Z INFO Daemon Daemon OS: flatcar 4230.2.3 Sep 12 17:13:45.879186 waagent[1836]: 2025-09-12T17:13:45.879128Z INFO Daemon Daemon Python: 3.11.11 Sep 12 17:13:45.882654 waagent[1836]: 2025-09-12T17:13:45.882587Z INFO Daemon Daemon Run daemon Sep 12 17:13:45.885803 waagent[1836]: 2025-09-12T17:13:45.885746Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4230.2.3' Sep 12 17:13:45.892539 waagent[1836]: 2025-09-12T17:13:45.892471Z INFO Daemon Daemon Using waagent for provisioning Sep 12 17:13:45.896608 waagent[1836]: 2025-09-12T17:13:45.896561Z INFO Daemon Daemon Activate resource disk Sep 12 17:13:45.900279 waagent[1836]: 2025-09-12T17:13:45.900220Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Sep 12 17:13:45.907853 waagent[1836]: 2025-09-12T17:13:45.907784Z INFO Daemon Daemon Found device: sdb Sep 12 17:13:45.917015 waagent[1836]: 2025-09-12T17:13:45.916955Z INFO Daemon Daemon Examining partition table Sep 12 17:13:46.060890 waagent[1836]: 2025-09-12T17:13:46.060818Z INFO Daemon Daemon GPT not detected, determining filesystem Sep 12 17:13:46.109025 waagent[1836]: 2025-09-12T17:13:46.108950Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Sep 12 17:13:46.113737 waagent[1836]: 2025-09-12T17:13:46.113679Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Sep 12 17:13:46.654531 kernel: sdb: sdb1 Sep 12 17:13:46.654902 waagent[1836]: 2025-09-12T17:13:46.654813Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Sep 12 17:13:46.669527 kernel: sdb: sdb1 Sep 12 17:13:46.669619 waagent[1836]: 2025-09-12T17:13:46.669566Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Sep 12 17:13:48.842857 login[1840]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Sep 12 17:13:48.843142 login[1839]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Sep 12 17:13:48.853958 systemd-logind[1693]: New session 1 of user core. Sep 12 17:13:48.855018 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 12 17:13:48.863708 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 12 17:13:48.900281 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 12 17:13:48.909728 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 12 17:13:48.926432 (systemd)[1932]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 12 17:13:48.928622 systemd-logind[1693]: New session c1 of user core. Sep 12 17:13:49.399323 systemd[1932]: Queued start job for default target default.target. Sep 12 17:13:49.407530 systemd[1932]: Created slice app.slice - User Application Slice. Sep 12 17:13:49.407559 systemd[1932]: Reached target paths.target - Paths. Sep 12 17:13:49.407609 systemd[1932]: Reached target timers.target - Timers. Sep 12 17:13:49.408882 systemd[1932]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 12 17:13:49.420007 systemd[1932]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 12 17:13:49.420138 systemd[1932]: Reached target sockets.target - Sockets. Sep 12 17:13:49.420196 systemd[1932]: Reached target basic.target - Basic System. Sep 12 17:13:49.420245 systemd[1932]: Reached target default.target - Main User Target. Sep 12 17:13:49.420278 systemd[1932]: Startup finished in 485ms. Sep 12 17:13:49.420519 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 12 17:13:49.422442 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 12 17:13:49.844647 login[1840]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Sep 12 17:13:49.848832 systemd-logind[1693]: New session 2 of user core. Sep 12 17:13:49.868628 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 12 17:13:51.789867 waagent[1836]: 2025-09-12T17:13:51.789763Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Sep 12 17:13:51.809539 kernel: EXT4-fs (sdb1): mounted filesystem 065424e3-695a-4fb2-a18f-2aa20199308a r/w with ordered data mode. Quota mode: none. Sep 12 17:13:51.809845 waagent[1836]: 2025-09-12T17:13:51.809785Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Sep 12 17:13:51.811947 waagent[1836]: 2025-09-12T17:13:51.811896Z INFO Daemon Daemon Clean protocol and wireserver endpoint Sep 12 17:13:51.812193 waagent[1836]: 2025-09-12T17:13:51.812151Z INFO Daemon Daemon Running default provisioning handler Sep 12 17:13:51.820574 waagent[1836]: 2025-09-12T17:13:51.819492Z 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 12 17:13:51.822423 waagent[1836]: 2025-09-12T17:13:51.822372Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Sep 12 17:13:51.823558 waagent[1836]: 2025-09-12T17:13:51.823514Z INFO Daemon Daemon cloud-init is enabled: False Sep 12 17:13:51.824320 waagent[1836]: 2025-09-12T17:13:51.824283Z INFO Daemon Daemon Copying ovf-env.xml Sep 12 17:13:51.961763 waagent[1836]: 2025-09-12T17:13:51.961692Z INFO Daemon Daemon Successfully mounted dvd Sep 12 17:13:52.004552 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Sep 12 17:13:52.004818 waagent[1836]: 2025-09-12T17:13:52.004520Z INFO Daemon Daemon Detect protocol endpoint Sep 12 17:13:52.008495 waagent[1836]: 2025-09-12T17:13:52.008439Z INFO Daemon Daemon Clean protocol and wireserver endpoint Sep 12 17:13:52.013142 waagent[1836]: 2025-09-12T17:13:52.013087Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Sep 12 17:13:52.018459 waagent[1836]: 2025-09-12T17:13:52.018405Z INFO Daemon Daemon Test for route to 168.63.129.16 Sep 12 17:13:52.022805 waagent[1836]: 2025-09-12T17:13:52.022757Z INFO Daemon Daemon Route to 168.63.129.16 exists Sep 12 17:13:52.026941 waagent[1836]: 2025-09-12T17:13:52.026893Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Sep 12 17:13:52.100637 waagent[1836]: 2025-09-12T17:13:52.100582Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Sep 12 17:13:52.102457 waagent[1836]: 2025-09-12T17:13:52.102424Z INFO Daemon Daemon Wire protocol version:2012-11-30 Sep 12 17:13:52.103014 waagent[1836]: 2025-09-12T17:13:52.102975Z INFO Daemon Daemon Server preferred version:2015-04-05 Sep 12 17:13:52.289398 waagent[1836]: 2025-09-12T17:13:52.289308Z INFO Daemon Daemon Initializing goal state during protocol detection Sep 12 17:13:52.291081 waagent[1836]: 2025-09-12T17:13:52.291026Z INFO Daemon Daemon Forcing an update of the goal state. Sep 12 17:13:52.295318 waagent[1836]: 2025-09-12T17:13:52.295268Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Sep 12 17:13:52.325632 waagent[1836]: 2025-09-12T17:13:52.325582Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.175 Sep 12 17:13:52.327686 waagent[1836]: 2025-09-12T17:13:52.327637Z INFO Daemon Sep 12 17:13:52.328026 waagent[1836]: 2025-09-12T17:13:52.327985Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 41ffc09a-931d-4a70-82f5-018148543429 eTag: 2939906312340630277 source: Fabric] Sep 12 17:13:52.329784 waagent[1836]: 2025-09-12T17:13:52.329736Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Sep 12 17:13:52.331608 waagent[1836]: 2025-09-12T17:13:52.331554Z INFO Daemon Sep 12 17:13:52.332430 waagent[1836]: 2025-09-12T17:13:52.332386Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Sep 12 17:13:52.337058 waagent[1836]: 2025-09-12T17:13:52.337021Z INFO Daemon Daemon Downloading artifacts profile blob Sep 12 17:13:52.460228 waagent[1836]: 2025-09-12T17:13:52.460123Z INFO Daemon Downloaded certificate {'thumbprint': 'DEC9ADD2F201ED4A0D51B21B3BF854935EED6C22', 'hasPrivateKey': True} Sep 12 17:13:52.462266 waagent[1836]: 2025-09-12T17:13:52.462207Z INFO Daemon Fetch goal state completed Sep 12 17:13:52.492340 waagent[1836]: 2025-09-12T17:13:52.492280Z INFO Daemon Daemon Starting provisioning Sep 12 17:13:52.495605 waagent[1836]: 2025-09-12T17:13:52.495536Z INFO Daemon Daemon Handle ovf-env.xml. Sep 12 17:13:52.496316 waagent[1836]: 2025-09-12T17:13:52.496272Z INFO Daemon Daemon Set hostname [ci-4230.2.3-a-29a8281301] Sep 12 17:13:52.510278 waagent[1836]: 2025-09-12T17:13:52.510228Z INFO Daemon Daemon Publish hostname [ci-4230.2.3-a-29a8281301] Sep 12 17:13:52.512080 waagent[1836]: 2025-09-12T17:13:52.512025Z INFO Daemon Daemon Examine /proc/net/route for primary interface Sep 12 17:13:52.513589 waagent[1836]: 2025-09-12T17:13:52.513542Z INFO Daemon Daemon Primary interface is [eth0] Sep 12 17:13:52.542783 systemd-networkd[1542]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 12 17:13:52.542792 systemd-networkd[1542]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 12 17:13:52.542816 systemd-networkd[1542]: eth0: DHCP lease lost Sep 12 17:13:52.543192 waagent[1836]: 2025-09-12T17:13:52.543133Z INFO Daemon Daemon Create user account if not exists Sep 12 17:13:52.544871 waagent[1836]: 2025-09-12T17:13:52.544820Z INFO Daemon Daemon User core already exists, skip useradd Sep 12 17:13:52.546295 waagent[1836]: 2025-09-12T17:13:52.546253Z INFO Daemon Daemon Configure sudoer Sep 12 17:13:52.547338 waagent[1836]: 2025-09-12T17:13:52.547291Z INFO Daemon Daemon Configure sshd Sep 12 17:13:52.548728 waagent[1836]: 2025-09-12T17:13:52.548682Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Sep 12 17:13:52.549284 waagent[1836]: 2025-09-12T17:13:52.549244Z INFO Daemon Daemon Deploy ssh public key. Sep 12 17:13:52.606539 systemd-networkd[1542]: eth0: DHCPv4 address 10.200.8.11/24, gateway 10.200.8.1 acquired from 168.63.129.16 Sep 12 17:13:53.732825 waagent[1836]: 2025-09-12T17:13:53.732753Z INFO Daemon Daemon Provisioning complete Sep 12 17:13:53.744815 waagent[1836]: 2025-09-12T17:13:53.744760Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Sep 12 17:13:53.747922 waagent[1836]: 2025-09-12T17:13:53.747861Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Sep 12 17:13:53.750214 waagent[1836]: 2025-09-12T17:13:53.749937Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.9.1.1 is the most current agent Sep 12 17:13:53.896429 waagent[1991]: 2025-09-12T17:13:53.896347Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.9.1.1) Sep 12 17:13:53.896781 waagent[1991]: 2025-09-12T17:13:53.896486Z INFO ExtHandler ExtHandler OS: flatcar 4230.2.3 Sep 12 17:13:53.896781 waagent[1991]: 2025-09-12T17:13:53.896588Z INFO ExtHandler ExtHandler Python: 3.11.11 Sep 12 17:13:53.964475 waagent[1991]: 2025-09-12T17:13:53.964419Z INFO ExtHandler ExtHandler Distro: flatcar-4230.2.3; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.11; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; Sep 12 17:13:53.964680 waagent[1991]: 2025-09-12T17:13:53.964636Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 12 17:13:53.964764 waagent[1991]: 2025-09-12T17:13:53.964726Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 12 17:13:53.971656 waagent[1991]: 2025-09-12T17:13:53.971591Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Sep 12 17:13:53.976912 waagent[1991]: 2025-09-12T17:13:53.976866Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.175 Sep 12 17:13:53.977407 waagent[1991]: 2025-09-12T17:13:53.977361Z INFO ExtHandler Sep 12 17:13:53.977496 waagent[1991]: 2025-09-12T17:13:53.977457Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: b38a26b4-c7f9-4315-af6a-00b0c3e56fa2 eTag: 2939906312340630277 source: Fabric] Sep 12 17:13:53.977859 waagent[1991]: 2025-09-12T17:13:53.977814Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Sep 12 17:13:53.978513 waagent[1991]: 2025-09-12T17:13:53.978464Z INFO ExtHandler Sep 12 17:13:53.978613 waagent[1991]: 2025-09-12T17:13:53.978576Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Sep 12 17:13:53.982061 waagent[1991]: 2025-09-12T17:13:53.982025Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Sep 12 17:13:54.050345 waagent[1991]: 2025-09-12T17:13:54.050284Z INFO ExtHandler Downloaded certificate {'thumbprint': 'DEC9ADD2F201ED4A0D51B21B3BF854935EED6C22', 'hasPrivateKey': True} Sep 12 17:13:54.050825 waagent[1991]: 2025-09-12T17:13:54.050778Z INFO ExtHandler Fetch goal state completed Sep 12 17:13:54.061732 waagent[1991]: 2025-09-12T17:13:54.061678Z INFO ExtHandler ExtHandler WALinuxAgent-2.9.1.1 running as process 1991 Sep 12 17:13:54.061875 waagent[1991]: 2025-09-12T17:13:54.061832Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Sep 12 17:13:54.063752 waagent[1991]: 2025-09-12T17:13:54.063706Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '4230.2.3', '', 'Flatcar Container Linux by Kinvolk'] Sep 12 17:13:54.064127 waagent[1991]: 2025-09-12T17:13:54.064084Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Sep 12 17:13:54.283406 waagent[1991]: 2025-09-12T17:13:54.283351Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Sep 12 17:13:54.283616 waagent[1991]: 2025-09-12T17:13:54.283573Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Sep 12 17:13:54.289954 waagent[1991]: 2025-09-12T17:13:54.289921Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Sep 12 17:13:54.297105 systemd[1]: Reload requested from client PID 2004 ('systemctl') (unit waagent.service)... Sep 12 17:13:54.297122 systemd[1]: Reloading... Sep 12 17:13:54.361537 zram_generator::config[2047]: No configuration found. Sep 12 17:13:54.492412 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 12 17:13:54.637690 systemd[1]: Reloading finished in 340 ms. Sep 12 17:13:54.651220 waagent[1991]: 2025-09-12T17:13:54.651139Z INFO ExtHandler ExtHandler Executing systemctl daemon-reload for setting up waagent-network-setup.service Sep 12 17:13:54.656911 systemd[1]: Reload requested from client PID 2101 ('systemctl') (unit waagent.service)... Sep 12 17:13:54.656926 systemd[1]: Reloading... Sep 12 17:13:54.713535 zram_generator::config[2141]: No configuration found. Sep 12 17:13:54.849563 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 12 17:13:54.992092 systemd[1]: Reloading finished in 334 ms. Sep 12 17:13:55.006322 waagent[1991]: 2025-09-12T17:13:55.006233Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Sep 12 17:13:55.006633 waagent[1991]: 2025-09-12T17:13:55.006394Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Sep 12 17:13:55.670650 waagent[1991]: 2025-09-12T17:13:55.670562Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Sep 12 17:13:55.671217 waagent[1991]: 2025-09-12T17:13:55.671157Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: configuration enabled [True], cgroups enabled [False], python supported: [True] Sep 12 17:13:55.672010 waagent[1991]: 2025-09-12T17:13:55.671959Z INFO ExtHandler ExtHandler Starting env monitor service. Sep 12 17:13:55.672390 waagent[1991]: 2025-09-12T17:13:55.672336Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 12 17:13:55.672612 waagent[1991]: 2025-09-12T17:13:55.672563Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Sep 12 17:13:55.672924 waagent[1991]: 2025-09-12T17:13:55.672879Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 12 17:13:55.673360 waagent[1991]: 2025-09-12T17:13:55.673306Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Sep 12 17:13:55.673558 waagent[1991]: 2025-09-12T17:13:55.673460Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Sep 12 17:13:55.673654 waagent[1991]: 2025-09-12T17:13:55.673606Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Sep 12 17:13:55.673733 waagent[1991]: 2025-09-12T17:13:55.673674Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 12 17:13:55.674342 waagent[1991]: 2025-09-12T17:13:55.674275Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Sep 12 17:13:55.674458 waagent[1991]: 2025-09-12T17:13:55.674411Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 12 17:13:55.674545 waagent[1991]: 2025-09-12T17:13:55.674486Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Sep 12 17:13:55.675039 waagent[1991]: 2025-09-12T17:13:55.674990Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Sep 12 17:13:55.675429 waagent[1991]: 2025-09-12T17:13:55.675374Z INFO EnvHandler ExtHandler Configure routes Sep 12 17:13:55.675768 waagent[1991]: 2025-09-12T17:13:55.675724Z INFO EnvHandler ExtHandler Gateway:None Sep 12 17:13:55.675924 waagent[1991]: 2025-09-12T17:13:55.675873Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Sep 12 17:13:55.675924 waagent[1991]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Sep 12 17:13:55.675924 waagent[1991]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Sep 12 17:13:55.675924 waagent[1991]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Sep 12 17:13:55.675924 waagent[1991]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Sep 12 17:13:55.675924 waagent[1991]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Sep 12 17:13:55.675924 waagent[1991]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Sep 12 17:13:55.676609 waagent[1991]: 2025-09-12T17:13:55.676566Z INFO EnvHandler ExtHandler Routes:None Sep 12 17:13:55.681395 waagent[1991]: 2025-09-12T17:13:55.681322Z INFO ExtHandler ExtHandler Sep 12 17:13:55.681843 waagent[1991]: 2025-09-12T17:13:55.681794Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 4ab8d8d7-6481-41e1-93ff-810f2eaa9a38 correlation 1d9d9b30-d771-4a04-bd02-62db14293d25 created: 2025-09-12T17:12:16.514458Z] Sep 12 17:13:55.683320 waagent[1991]: 2025-09-12T17:13:55.683268Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Sep 12 17:13:55.686001 waagent[1991]: 2025-09-12T17:13:55.685947Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 4 ms] Sep 12 17:13:55.727237 waagent[1991]: 2025-09-12T17:13:55.727179Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.9.1.1 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 43ECA2BA-A917-4DE2-B131-4CD176558CD5;DroppedPackets: 0;UpdateGSErrors: 0;AutoUpdate: 0] Sep 12 17:13:55.749597 waagent[1991]: 2025-09-12T17:13:55.749538Z INFO MonitorHandler ExtHandler Network interfaces: Sep 12 17:13:55.749597 waagent[1991]: Executing ['ip', '-a', '-o', 'link']: Sep 12 17:13:55.749597 waagent[1991]: 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 12 17:13:55.749597 waagent[1991]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:ed:8d:40:9d:e3 brd ff:ff:ff:ff:ff:ff Sep 12 17:13:55.749597 waagent[1991]: Executing ['ip', '-4', '-a', '-o', 'address']: Sep 12 17:13:55.749597 waagent[1991]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Sep 12 17:13:55.749597 waagent[1991]: 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 12 17:13:55.749597 waagent[1991]: Executing ['ip', '-6', '-a', '-o', 'address']: Sep 12 17:13:55.749597 waagent[1991]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Sep 12 17:13:55.749597 waagent[1991]: 2: eth0 inet6 fe80::7eed:8dff:fe40:9de3/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Sep 12 17:13:55.895998 waagent[1991]: 2025-09-12T17:13:55.895924Z INFO EnvHandler ExtHandler Successfully added Azure fabric firewall rules. Current Firewall rules: Sep 12 17:13:55.895998 waagent[1991]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Sep 12 17:13:55.895998 waagent[1991]: pkts bytes target prot opt in out source destination Sep 12 17:13:55.895998 waagent[1991]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Sep 12 17:13:55.895998 waagent[1991]: pkts bytes target prot opt in out source destination Sep 12 17:13:55.895998 waagent[1991]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Sep 12 17:13:55.895998 waagent[1991]: pkts bytes target prot opt in out source destination Sep 12 17:13:55.895998 waagent[1991]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Sep 12 17:13:55.895998 waagent[1991]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Sep 12 17:13:55.895998 waagent[1991]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Sep 12 17:13:55.899500 waagent[1991]: 2025-09-12T17:13:55.899451Z INFO EnvHandler ExtHandler Current Firewall rules: Sep 12 17:13:55.899500 waagent[1991]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Sep 12 17:13:55.899500 waagent[1991]: pkts bytes target prot opt in out source destination Sep 12 17:13:55.899500 waagent[1991]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Sep 12 17:13:55.899500 waagent[1991]: pkts bytes target prot opt in out source destination Sep 12 17:13:55.899500 waagent[1991]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Sep 12 17:13:55.899500 waagent[1991]: pkts bytes target prot opt in out source destination Sep 12 17:13:55.899500 waagent[1991]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Sep 12 17:13:55.899500 waagent[1991]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Sep 12 17:13:55.899500 waagent[1991]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Sep 12 17:13:55.899778 waagent[1991]: 2025-09-12T17:13:55.899737Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Sep 12 17:13:55.899939 waagent[1991]: 2025-09-12T17:13:55.899901Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Sep 12 17:14:03.571213 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 12 17:14:03.586732 systemd[1]: Started sshd@0-10.200.8.11:22-10.200.16.10:44148.service - OpenSSH per-connection server daemon (10.200.16.10:44148). Sep 12 17:14:04.479275 sshd[2232]: Accepted publickey for core from 10.200.16.10 port 44148 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:14:04.480537 sshd-session[2232]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:14:04.484716 systemd-logind[1693]: New session 3 of user core. Sep 12 17:14:04.498621 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 12 17:14:05.149053 systemd[1]: Started sshd@1-10.200.8.11:22-10.200.16.10:44160.service - OpenSSH per-connection server daemon (10.200.16.10:44160). Sep 12 17:14:05.330637 setup-nvidia[1850]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 482M 0 81920 0 0 109k 0 1:15:09 --:--:-- 1:15:09 109k\u000d 1 482M 1 5744k 0 0 3333k 0 0:02:28 0:00:01 0:02:27 3333k\u000d 6 482M 6 30.4M 0 0 11.0M 0 0:00:43 0:00:02 0:00:41 11.0M\u000d 10 482M 10 51.9M 0 0 14.3M 0 0:00:33 0:00:03 0:00:30 14.3M\u000d 15 482M 15 74.0M 0 0 15.9M 0 0:00:30 0:00:04 0:00:26 15.9M\u000d 20 482M 20 99.1M 0 0 17.5M 0 0:00:27 0:00:05 0:00:22 20.1M\u000d 25 482M 25 122M 0 0 18.3M 0 0:00:26 0:00:06 0:00:20 23.6M\u000d 30 482M 30 145M 0 0 18.9M 0 0:00:25 0:00:07 0:00:18 23.3M\u000d 35 482M 35 170M 0 0 19.5M 0 0:00:24 0:00:08 0:00:16 23.3M\u000d 40 482M 40 195M 0 0 20.0M 0 0:00:24 0:00:09 0:00:15 23.8M\u000d 45 482M 45 217M 0 0 20.4M 0 0:00:23 0:00:10 0:00:13 23.8M\u000d 49 482M 49 239M 0 0 20.5M 0 0:00:23 0:00:11 0:00:12 23.5M\u000d 54 482M 54 262M 0 0 20.7M 0 0:00:23 0:00:12 0:00:11 23.6M\u000d 59 482M 59 285M 0 0 20.8M 0 0:00:23 0:00:13 0:00:10 23.2M\u000d 64 482M 64 309M 0 0 21.1M 0 0:00:22 0:00:14 0:00:08 23.1M\u000d 69 482M 69 335M 0 0 21.3M 0 0:00:22 0:00:15 0:00:07 23.0M\u000d 74 482M 74 358M 0 0 21.4M 0 0:00:22 0:00:16 0:00:06 23.2M\u000d 78 482M 78 380M 0 0 21.5M 0 0:00:22 0:00:17 0:00:05 23.6M\u000d 83 482M 83 402M 0 0 21.5M 0 0:00:22 0:00:18 0:00:04 23.4M\u000d 87 482M 87 424M 0 0 21.5M 0 0:00:22 0:00:19 0:00:03 22.9M\u000d 92 482M 92 448M 0 0 21.6M 0 0:00:22 0:00:20 0:00:02 22.7M\u000d 97 482M 97 472M 0 0 21.7M 0 0:00:22 0:00:21 0:00:01 22.9M\u000d100 482M 100 482M 0 0 21.9M 0 0:00:22 0:00:22 --:--:-- 23.2M Sep 12 17:14:05.797044 sshd[2237]: Accepted publickey for core from 10.200.16.10 port 44160 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:14:05.798466 sshd-session[2237]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:14:05.811054 systemd-logind[1693]: New session 4 of user core. Sep 12 17:14:05.817662 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 12 17:14:06.246285 sshd[2249]: Connection closed by 10.200.16.10 port 44160 Sep 12 17:14:06.246696 sshd-session[2237]: pam_unix(sshd:session): session closed for user core Sep 12 17:14:06.250064 systemd[1]: sshd@1-10.200.8.11:22-10.200.16.10:44160.service: Deactivated successfully. Sep 12 17:14:06.251687 systemd[1]: session-4.scope: Deactivated successfully. Sep 12 17:14:06.252304 systemd-logind[1693]: Session 4 logged out. Waiting for processes to exit. Sep 12 17:14:06.253123 systemd-logind[1693]: Removed session 4. Sep 12 17:14:06.354190 chronyd[1697]: Selected source PHC0 Sep 12 17:14:06.356235 systemd[1]: Started sshd@2-10.200.8.11:22-10.200.16.10:44176.service - OpenSSH per-connection server daemon (10.200.16.10:44176). Sep 12 17:14:06.987184 sshd[2255]: Accepted publickey for core from 10.200.16.10 port 44176 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:14:06.986982 sshd-session[2255]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:14:06.991440 systemd-logind[1693]: New session 5 of user core. Sep 12 17:14:07.003088 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 12 17:14:07.433931 sshd[2257]: Connection closed by 10.200.16.10 port 44176 Sep 12 17:14:07.434845 sshd-session[2255]: pam_unix(sshd:session): session closed for user core Sep 12 17:14:07.438334 systemd-logind[1693]: Session 5 logged out. Waiting for processes to exit. Sep 12 17:14:07.438580 systemd[1]: sshd@2-10.200.8.11:22-10.200.16.10:44176.service: Deactivated successfully. Sep 12 17:14:07.440142 systemd[1]: session-5.scope: Deactivated successfully. Sep 12 17:14:07.440935 systemd-logind[1693]: Removed session 5. Sep 12 17:14:16.246165 setup-nvidia[1687]: Downloading NVIDIA 535.230.02 Driver Sep 12 17:14:16.250884 setup-nvidia[2263]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 12 17:14:16.251120 setup-nvidia[2263]: Dload Upload Total Spent Left Speed Sep 12 17:14:17.545988 systemd[1]: Started sshd@3-10.200.8.11:22-10.200.16.10:43690.service - OpenSSH per-connection server daemon (10.200.16.10:43690). Sep 12 17:14:18.175784 sshd[2265]: Accepted publickey for core from 10.200.16.10 port 43690 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:14:18.177143 sshd-session[2265]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:14:18.181431 systemd-logind[1693]: New session 6 of user core. Sep 12 17:14:18.196640 systemd[1]: Started session-6.scope - Session 6 of User core. Sep 12 17:14:18.619630 sshd[2267]: Connection closed by 10.200.16.10 port 43690 Sep 12 17:14:18.620018 sshd-session[2265]: pam_unix(sshd:session): session closed for user core Sep 12 17:14:18.623455 systemd[1]: sshd@3-10.200.8.11:22-10.200.16.10:43690.service: Deactivated successfully. Sep 12 17:14:18.625114 systemd[1]: session-6.scope: Deactivated successfully. Sep 12 17:14:18.625758 systemd-logind[1693]: Session 6 logged out. Waiting for processes to exit. Sep 12 17:14:18.626570 systemd-logind[1693]: Removed session 6. Sep 12 17:14:18.744056 systemd[1]: Started sshd@4-10.200.8.11:22-10.200.16.10:43700.service - OpenSSH per-connection server daemon (10.200.16.10:43700). Sep 12 17:14:19.039378 setup-nvidia[2263]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 4 326M 4 16.0M 0 0 21.5M 0 0:00:15 --:--:-- 0:00:15 21.5M\u000d 31 326M 31 103M 0 0 60.5M 0 0:00:05 0:00:01 0:00:04 60.5M\u000d 94 326M 94 308M 0 0 114M 0 0:00:02 0:00:02 --:--:-- 114M\u000d100 326M 100 326M 0 0 116M 0 0:00:02 0:00:02 --:--:-- 116M Sep 12 17:14:19.380851 sshd[2273]: Accepted publickey for core from 10.200.16.10 port 43700 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:14:19.382101 sshd-session[2273]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:14:19.386130 systemd-logind[1693]: New session 7 of user core. Sep 12 17:14:19.396635 systemd[1]: Started session-7.scope - Session 7 of User core. Sep 12 17:14:19.823073 sshd[2275]: Connection closed by 10.200.16.10 port 43700 Sep 12 17:14:19.823536 sshd-session[2273]: pam_unix(sshd:session): session closed for user core Sep 12 17:14:19.826740 systemd[1]: sshd@4-10.200.8.11:22-10.200.16.10:43700.service: Deactivated successfully. Sep 12 17:14:19.828339 systemd[1]: session-7.scope: Deactivated successfully. Sep 12 17:14:19.828975 systemd-logind[1693]: Session 7 logged out. Waiting for processes to exit. Sep 12 17:14:19.829787 systemd-logind[1693]: Removed session 7. Sep 12 17:14:20.487101 setup-nvidia[1687]: Extract the NVIDIA Driver Installer 535.230.02 Sep 12 17:14:20.487101 setup-nvidia[1687]: /opt/nvidia/workdir/nvidia-workdir / Sep 12 17:14:20.538024 setup-nvidia[2282]: Creating directory NVIDIA-Linux-x86_64-535.230.02 Sep 12 17:14:20.870898 setup-nvidia[2282]: Verifying archive integrity... OK Sep 12 17:14:23.218256 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Sep 12 17:14:23.267754 setup-nvidia[2282]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.230.02 Sep 12 17:14:25.151299 setup-nvidia[2308]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Sep 12 17:14:26.932275 setup-nvidia[1687]: / Sep 12 17:14:26.932576 setup-nvidia[1687]: Spawn system-nspawn container to install the NVIDIA drivers Sep 12 17:14:27.673037 sudo[2315]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4230.2.3.bin --overlay=/usr/lib/modules::/usr/lib/modules --bind=/opt/nvidia/workdir/nvidia-workdir:/nvidia --bind=/usr/lib/nvidia/bin:/app/bin/ /app/bin/install-nvidia NVIDIA-Linux-x86_64-535.230.02 Sep 12 17:14:27.674789 sudo[2315]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 12 17:14:27.682424 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Sep 12 17:14:27.700726 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Sep 12 17:14:27.711057 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Sep 12 17:14:27.714857 systemd[1]: Starting user@0.service - User Manager for UID 0... Sep 12 17:14:27.721157 (systemd)[2318]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Sep 12 17:14:27.723534 systemd-logind[1693]: New session c3 of user root. Sep 12 17:14:27.836050 update_engine[1694]: I20250912 17:14:27.835990 1694 update_attempter.cc:509] Updating boot flags... Sep 12 17:14:27.890594 systemd[2318]: Queued start job for default target default.target. Sep 12 17:14:27.902611 systemd[2318]: Created slice app.slice - User Application Slice. Sep 12 17:14:27.902642 systemd[2318]: Reached target paths.target - Paths. Sep 12 17:14:27.902695 systemd[2318]: Reached target timers.target - Timers. Sep 12 17:14:27.903853 systemd[2318]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 12 17:14:27.913975 systemd[2318]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 12 17:14:27.914035 systemd[2318]: Reached target sockets.target - Sockets. Sep 12 17:14:27.914081 systemd[2318]: Reached target basic.target - Basic System. Sep 12 17:14:27.914128 systemd[2318]: Reached target default.target - Main User Target. Sep 12 17:14:27.914160 systemd[2318]: Startup finished in 184ms. Sep 12 17:14:27.914248 systemd[1]: Started user@0.service - User Manager for UID 0. Sep 12 17:14:27.918065 systemd[1]: Started session-c2.scope - Session c2 of User root. Sep 12 17:14:27.999528 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 34 scanned by (udev-worker) (2342) Sep 12 17:14:28.030528 kernel: loop6: detected capacity change from 0 to 12644352 Sep 12 17:14:28.463544 kernel: loop6: p9 Sep 12 17:14:28.544545 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 34 scanned by (udev-worker) (2341) Sep 12 17:14:28.573472 setup-nvidia[2330]: ░ Spawning container flatcardevelopercontainer-4230.2.3.bin on /opt/nvidia/workdir/flatcar_developer_container-4230.2.3.bin. Sep 12 17:14:28.583532 kernel: EXT4-fs (loop6p9): mounted filesystem c1840b4a-9258-4a60-88d6-cf15a9c9a78c ro without journal. Quota mode: none. Sep 12 17:14:28.655531 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 34 scanned by (udev-worker) (2341) Sep 12 17:14:29.939937 systemd[1]: Started sshd@5-10.200.8.11:22-10.200.16.10:51118.service - OpenSSH per-connection server daemon (10.200.16.10:51118). Sep 12 17:14:30.568767 sshd[2557]: Accepted publickey for core from 10.200.16.10 port 51118 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:14:30.570014 sshd-session[2557]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:14:30.574125 systemd-logind[1693]: New session 8 of user core. Sep 12 17:14:30.582622 systemd[1]: Started session-8.scope - Session 8 of User core. Sep 12 17:14:31.013898 sshd[2609]: Connection closed by 10.200.16.10 port 51118 Sep 12 17:14:31.014236 sshd-session[2557]: pam_unix(sshd:session): session closed for user core Sep 12 17:14:31.017236 systemd[1]: sshd@5-10.200.8.11:22-10.200.16.10:51118.service: Deactivated successfully. Sep 12 17:14:31.018906 systemd[1]: session-8.scope: Deactivated successfully. Sep 12 17:14:31.019532 systemd-logind[1693]: Session 8 logged out. Waiting for processes to exit. Sep 12 17:14:31.020278 systemd-logind[1693]: Removed session 8. Sep 12 17:14:31.123687 systemd[1]: Started sshd@6-10.200.8.11:22-10.200.16.10:51122.service - OpenSSH per-connection server daemon (10.200.16.10:51122). Sep 12 17:14:31.756321 sshd[2634]: Accepted publickey for core from 10.200.16.10 port 51122 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:14:31.757596 sshd-session[2634]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:14:31.761766 systemd-logind[1693]: New session 9 of user core. Sep 12 17:14:31.773642 systemd[1]: Started session-9.scope - Session 9 of User core. Sep 12 17:14:32.198192 sshd[2874]: Connection closed by 10.200.16.10 port 51122 Sep 12 17:14:32.198484 sshd-session[2634]: pam_unix(sshd:session): session closed for user core Sep 12 17:14:32.201604 systemd[1]: sshd@6-10.200.8.11:22-10.200.16.10:51122.service: Deactivated successfully. Sep 12 17:14:32.203195 systemd[1]: session-9.scope: Deactivated successfully. Sep 12 17:14:32.203894 systemd-logind[1693]: Session 9 logged out. Waiting for processes to exit. Sep 12 17:14:32.204749 systemd-logind[1693]: Removed session 9. Sep 12 17:14:42.311019 systemd[1]: Started sshd@7-10.200.8.11:22-10.200.16.10:37018.service - OpenSSH per-connection server daemon (10.200.16.10:37018). Sep 12 17:14:42.940421 sshd[3622]: Accepted publickey for core from 10.200.16.10 port 37018 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:14:42.941746 sshd-session[3622]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:14:42.945714 systemd-logind[1693]: New session 10 of user core. Sep 12 17:14:42.956677 systemd[1]: Started session-10.scope - Session 10 of User core. Sep 12 17:14:43.383935 sshd[3674]: Connection closed by 10.200.16.10 port 37018 Sep 12 17:14:43.384283 sshd-session[3622]: pam_unix(sshd:session): session closed for user core Sep 12 17:14:43.387369 systemd[1]: sshd@7-10.200.8.11:22-10.200.16.10:37018.service: Deactivated successfully. Sep 12 17:14:43.388996 systemd[1]: session-10.scope: Deactivated successfully. Sep 12 17:14:43.389664 systemd-logind[1693]: Session 10 logged out. Waiting for processes to exit. Sep 12 17:14:43.390452 systemd-logind[1693]: Removed session 10. Sep 12 17:14:43.494677 systemd[1]: Started sshd@8-10.200.8.11:22-10.200.16.10:37032.service - OpenSSH per-connection server daemon (10.200.16.10:37032). Sep 12 17:14:44.123554 sshd[3681]: Accepted publickey for core from 10.200.16.10 port 37032 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:14:44.124915 sshd-session[3681]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:14:44.128945 systemd-logind[1693]: New session 11 of user core. Sep 12 17:14:44.147629 systemd[1]: Started session-11.scope - Session 11 of User core. Sep 12 17:14:44.567641 sshd[3708]: Connection closed by 10.200.16.10 port 37032 Sep 12 17:14:44.568085 sshd-session[3681]: pam_unix(sshd:session): session closed for user core Sep 12 17:14:44.570976 systemd-logind[1693]: Session 11 logged out. Waiting for processes to exit. Sep 12 17:14:44.571572 systemd[1]: sshd@8-10.200.8.11:22-10.200.16.10:37032.service: Deactivated successfully. Sep 12 17:14:44.573900 systemd[1]: session-11.scope: Deactivated successfully. Sep 12 17:14:44.576498 systemd-logind[1693]: Removed session 11. Sep 12 17:14:54.694781 systemd[1]: Started sshd@9-10.200.8.11:22-10.200.16.10:49658.service - OpenSSH per-connection server daemon (10.200.16.10:49658). Sep 12 17:14:55.333895 sshd[7752]: Accepted publickey for core from 10.200.16.10 port 49658 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:14:55.337771 sshd-session[7752]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:14:55.344233 systemd-logind[1693]: New session 12 of user core. Sep 12 17:14:55.352094 systemd[1]: Started session-12.scope - Session 12 of User core. Sep 12 17:14:55.783612 sshd[7961]: Connection closed by 10.200.16.10 port 49658 Sep 12 17:14:55.785549 sshd-session[7752]: pam_unix(sshd:session): session closed for user core Sep 12 17:14:55.788358 systemd[1]: sshd@9-10.200.8.11:22-10.200.16.10:49658.service: Deactivated successfully. Sep 12 17:14:55.790091 systemd[1]: session-12.scope: Deactivated successfully. Sep 12 17:14:55.792287 systemd-logind[1693]: Session 12 logged out. Waiting for processes to exit. Sep 12 17:14:55.793462 systemd-logind[1693]: Removed session 12. Sep 12 17:14:55.903750 systemd[1]: Started sshd@10-10.200.8.11:22-10.200.16.10:49672.service - OpenSSH per-connection server daemon (10.200.16.10:49672). Sep 12 17:14:56.540423 sshd[8050]: Accepted publickey for core from 10.200.16.10 port 49672 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:14:56.544127 sshd-session[8050]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:14:56.549065 systemd-logind[1693]: New session 13 of user core. Sep 12 17:14:56.560377 systemd[1]: Started session-13.scope - Session 13 of User core. Sep 12 17:14:56.987122 sshd[8164]: Connection closed by 10.200.16.10 port 49672 Sep 12 17:14:56.987639 sshd-session[8050]: pam_unix(sshd:session): session closed for user core Sep 12 17:14:56.990832 systemd-logind[1693]: Session 13 logged out. Waiting for processes to exit. Sep 12 17:14:56.992499 systemd[1]: sshd@10-10.200.8.11:22-10.200.16.10:49672.service: Deactivated successfully. Sep 12 17:14:56.994771 systemd[1]: session-13.scope: Deactivated successfully. Sep 12 17:14:56.996269 systemd-logind[1693]: Removed session 13. Sep 12 17:15:07.107344 systemd[1]: Started sshd@11-10.200.8.11:22-10.200.16.10:39616.service - OpenSSH per-connection server daemon (10.200.16.10:39616). Sep 12 17:15:07.756758 sshd[11447]: Accepted publickey for core from 10.200.16.10 port 39616 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:15:07.758056 sshd-session[11447]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:15:07.762118 systemd-logind[1693]: New session 14 of user core. Sep 12 17:15:07.774634 systemd[1]: Started session-14.scope - Session 14 of User core. Sep 12 17:15:08.208130 sshd[11581]: Connection closed by 10.200.16.10 port 39616 Sep 12 17:15:08.207052 sshd-session[11447]: pam_unix(sshd:session): session closed for user core Sep 12 17:15:08.213249 systemd-logind[1693]: Session 14 logged out. Waiting for processes to exit. Sep 12 17:15:08.214088 systemd[1]: sshd@11-10.200.8.11:22-10.200.16.10:39616.service: Deactivated successfully. Sep 12 17:15:08.217826 systemd[1]: session-14.scope: Deactivated successfully. Sep 12 17:15:08.219049 systemd-logind[1693]: Removed session 14. Sep 12 17:15:08.329803 systemd[1]: Started sshd@12-10.200.8.11:22-10.200.16.10:39626.service - OpenSSH per-connection server daemon (10.200.16.10:39626). Sep 12 17:15:08.967647 sshd[11612]: Accepted publickey for core from 10.200.16.10 port 39626 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:15:08.969419 sshd-session[11612]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:15:08.974716 systemd-logind[1693]: New session 15 of user core. Sep 12 17:15:08.983133 systemd[1]: Started session-15.scope - Session 15 of User core. Sep 12 17:15:09.428988 sshd[11617]: Connection closed by 10.200.16.10 port 39626 Sep 12 17:15:09.430936 sshd-session[11612]: pam_unix(sshd:session): session closed for user core Sep 12 17:15:09.433937 systemd-logind[1693]: Session 15 logged out. Waiting for processes to exit. Sep 12 17:15:09.437668 systemd[1]: sshd@12-10.200.8.11:22-10.200.16.10:39626.service: Deactivated successfully. Sep 12 17:15:09.439267 systemd[1]: session-15.scope: Deactivated successfully. Sep 12 17:15:09.440937 systemd-logind[1693]: Removed session 15. Sep 12 17:15:19.546436 systemd[1]: Started sshd@13-10.200.8.11:22-10.200.16.10:52932.service - OpenSSH per-connection server daemon (10.200.16.10:52932). Sep 12 17:15:20.209880 sshd[12064]: Accepted publickey for core from 10.200.16.10 port 52932 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:15:20.211745 sshd-session[12064]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:15:20.220060 systemd-logind[1693]: New session 16 of user core. Sep 12 17:15:20.231597 systemd[1]: Started session-16.scope - Session 16 of User core. Sep 12 17:15:20.658616 sshd[12073]: Connection closed by 10.200.16.10 port 52932 Sep 12 17:15:20.660667 sshd-session[12064]: pam_unix(sshd:session): session closed for user core Sep 12 17:15:20.666353 systemd-logind[1693]: Session 16 logged out. Waiting for processes to exit. Sep 12 17:15:20.667854 systemd[1]: sshd@13-10.200.8.11:22-10.200.16.10:52932.service: Deactivated successfully. Sep 12 17:15:20.669894 systemd[1]: session-16.scope: Deactivated successfully. Sep 12 17:15:20.671202 systemd-logind[1693]: Removed session 16. Sep 12 17:15:20.782898 systemd[1]: Started sshd@14-10.200.8.11:22-10.200.16.10:55984.service - OpenSSH per-connection server daemon (10.200.16.10:55984). Sep 12 17:15:21.414185 sshd[12091]: Accepted publickey for core from 10.200.16.10 port 55984 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:15:21.415994 sshd-session[12091]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:15:21.422610 systemd-logind[1693]: New session 17 of user core. Sep 12 17:15:21.431676 systemd[1]: Started session-17.scope - Session 17 of User core. Sep 12 17:15:21.861899 sshd[12110]: Connection closed by 10.200.16.10 port 55984 Sep 12 17:15:21.862672 sshd-session[12091]: pam_unix(sshd:session): session closed for user core Sep 12 17:15:21.865671 systemd-logind[1693]: Session 17 logged out. Waiting for processes to exit. Sep 12 17:15:21.867177 systemd[1]: sshd@14-10.200.8.11:22-10.200.16.10:55984.service: Deactivated successfully. Sep 12 17:15:21.868848 systemd[1]: session-17.scope: Deactivated successfully. Sep 12 17:15:21.870314 systemd-logind[1693]: Removed session 17. Sep 12 17:15:31.992808 systemd[1]: Started sshd@15-10.200.8.11:22-10.200.16.10:32836.service - OpenSSH per-connection server daemon (10.200.16.10:32836). Sep 12 17:15:32.627116 sshd[12446]: Accepted publickey for core from 10.200.16.10 port 32836 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:15:32.629979 sshd-session[12446]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:15:32.638902 systemd-logind[1693]: New session 18 of user core. Sep 12 17:15:32.645083 systemd[1]: Started session-18.scope - Session 18 of User core. Sep 12 17:15:33.080861 sshd[12478]: Connection closed by 10.200.16.10 port 32836 Sep 12 17:15:33.082371 sshd-session[12446]: pam_unix(sshd:session): session closed for user core Sep 12 17:15:33.087780 systemd-logind[1693]: Session 18 logged out. Waiting for processes to exit. Sep 12 17:15:33.088642 systemd[1]: sshd@15-10.200.8.11:22-10.200.16.10:32836.service: Deactivated successfully. Sep 12 17:15:33.090748 systemd[1]: session-18.scope: Deactivated successfully. Sep 12 17:15:33.094122 systemd-logind[1693]: Removed session 18. Sep 12 17:15:33.201803 systemd[1]: Started sshd@16-10.200.8.11:22-10.200.16.10:32848.service - OpenSSH per-connection server daemon (10.200.16.10:32848). Sep 12 17:15:33.840043 sshd[12496]: Accepted publickey for core from 10.200.16.10 port 32848 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:15:33.841836 sshd-session[12496]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:15:33.847816 systemd-logind[1693]: New session 19 of user core. Sep 12 17:15:33.859698 systemd[1]: Started session-19.scope - Session 19 of User core. Sep 12 17:15:34.291186 sshd[12510]: Connection closed by 10.200.16.10 port 32848 Sep 12 17:15:34.293118 sshd-session[12496]: pam_unix(sshd:session): session closed for user core Sep 12 17:15:34.295852 systemd[1]: sshd@16-10.200.8.11:22-10.200.16.10:32848.service: Deactivated successfully. Sep 12 17:15:34.298134 systemd[1]: session-19.scope: Deactivated successfully. Sep 12 17:15:34.299820 systemd-logind[1693]: Session 19 logged out. Waiting for processes to exit. Sep 12 17:15:34.301266 systemd-logind[1693]: Removed session 19. Sep 12 17:15:44.404129 systemd[1]: Started sshd@17-10.200.8.11:22-10.200.16.10:52420.service - OpenSSH per-connection server daemon (10.200.16.10:52420). Sep 12 17:15:45.040009 sshd[12777]: Accepted publickey for core from 10.200.16.10 port 52420 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:15:45.041340 sshd-session[12777]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:15:45.045531 systemd-logind[1693]: New session 20 of user core. Sep 12 17:15:45.061656 systemd[1]: Started session-20.scope - Session 20 of User core. Sep 12 17:15:45.486364 sshd[12779]: Connection closed by 10.200.16.10 port 52420 Sep 12 17:15:45.486672 sshd-session[12777]: pam_unix(sshd:session): session closed for user core Sep 12 17:15:45.489864 systemd[1]: sshd@17-10.200.8.11:22-10.200.16.10:52420.service: Deactivated successfully. Sep 12 17:15:45.491475 systemd[1]: session-20.scope: Deactivated successfully. Sep 12 17:15:45.492111 systemd-logind[1693]: Session 20 logged out. Waiting for processes to exit. Sep 12 17:15:45.492898 systemd-logind[1693]: Removed session 20. Sep 12 17:15:45.605000 systemd[1]: Started sshd@18-10.200.8.11:22-10.200.16.10:52426.service - OpenSSH per-connection server daemon (10.200.16.10:52426). Sep 12 17:15:46.241168 sshd[12785]: Accepted publickey for core from 10.200.16.10 port 52426 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:15:46.242557 sshd-session[12785]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:15:46.246781 systemd-logind[1693]: New session 21 of user core. Sep 12 17:15:46.259637 systemd[1]: Started session-21.scope - Session 21 of User core. Sep 12 17:15:46.685108 sshd[12787]: Connection closed by 10.200.16.10 port 52426 Sep 12 17:15:46.685479 sshd-session[12785]: pam_unix(sshd:session): session closed for user core Sep 12 17:15:46.688882 systemd[1]: sshd@18-10.200.8.11:22-10.200.16.10:52426.service: Deactivated successfully. Sep 12 17:15:46.690560 systemd[1]: session-21.scope: Deactivated successfully. Sep 12 17:15:46.691195 systemd-logind[1693]: Session 21 logged out. Waiting for processes to exit. Sep 12 17:15:46.692081 systemd-logind[1693]: Removed session 21. Sep 12 17:15:49.604444 setup-nvidia[2330]: \u000d Sep 12 17:15:49.604444 setup-nvidia[2330]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Sep 12 17:15:49.604444 setup-nvidia[2330]: frequently when this kernel module was built against the wrong or Sep 12 17:15:49.604444 setup-nvidia[2330]: improperly configured kernel sources, with a version of gcc that differs Sep 12 17:15:49.604444 setup-nvidia[2330]: from the one used to build the target kernel, or if another driver, such Sep 12 17:15:49.604444 setup-nvidia[2330]: as nouveau, is present and prevents the NVIDIA kernel module from Sep 12 17:15:49.604444 setup-nvidia[2330]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Sep 12 17:15:49.604444 setup-nvidia[2330]: installed in this system is supported by this NVIDIA Linux graphics Sep 12 17:15:49.604444 setup-nvidia[2330]: driver release. Sep 12 17:15:49.604444 setup-nvidia[2330]: \u000d Sep 12 17:15:49.604444 setup-nvidia[2330]: Please see the log entries 'Kernel module load error' and 'Kernel Sep 12 17:15:49.604444 setup-nvidia[2330]: messages' at the end of the file Sep 12 17:15:49.604444 setup-nvidia[2330]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more Sep 12 17:15:49.604444 setup-nvidia[2330]: information. Sep 12 17:15:49.604444 setup-nvidia[2330]: \u000d Sep 12 17:15:49.619177 setup-nvidia[2330]: dmesg: read kernel buffer failed: Operation not permitted Sep 12 17:15:49.640389 setup-nvidia[2330]: \u000d Sep 12 17:15:49.640389 setup-nvidia[2330]: ERROR: Installation has failed. Please see the file Sep 12 17:15:49.640389 setup-nvidia[2330]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for Sep 12 17:15:49.640389 setup-nvidia[2330]: details. You may find suggestions on fixing installation problems in Sep 12 17:15:49.640389 setup-nvidia[2330]: the README available on the Linux driver download page at Sep 12 17:15:49.640389 setup-nvidia[2330]: www.nvidia.com. Sep 12 17:15:49.640389 setup-nvidia[2330]: \u000d Sep 12 17:15:49.640806 setup-nvidia[2330]: Last 50 lines of nvidia-installer.log: Sep 12 17:15:49.641687 setup-nvidia[2330]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038989rm+0x32: 'naked' return found in RETHUNK build Sep 12 17:15:49.641687 setup-nvidia[2330]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039000rm+0x53: 'naked' return found in RETHUNK build Sep 12 17:15:49.641687 setup-nvidia[2330]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038997rm+0x90: 'naked' return found in RETHUNK build Sep 12 17:15:49.641687 setup-nvidia[2330]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038990rm+0x2b: 'naked' return found in RETHUNK build Sep 12 17:15:49.641687 setup-nvidia[2330]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039046rm+0x93: 'naked' return found in RETHUNK build Sep 12 17:15:49.641687 setup-nvidia[2330]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039042rm+0x36: 'naked' return found in RETHUNK build Sep 12 17:15:49.641687 setup-nvidia[2330]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039041rm+0x9d: 'naked' return found in RETHUNK build Sep 12 17:15:49.641687 setup-nvidia[2330]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039043rm+0x77: 'naked' return found in RETHUNK build Sep 12 17:15:49.641687 setup-nvidia[2330]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038987rm+0x7f: 'naked' return found in RETHUNK build Sep 12 17:15:49.641687 setup-nvidia[2330]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030558rm+0x3e: 'naked' return found in RETHUNK build Sep 12 17:15:49.641687 setup-nvidia[2330]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030557rm+0x27: 'naked' return found in RETHUNK build Sep 12 17:15:49.641687 setup-nvidia[2330]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030561rm+0x37: 'naked' return found in RETHUNK build Sep 12 17:15:49.641687 setup-nvidia[2330]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030560rm+0x24: 'naked' return found in RETHUNK build Sep 12 17:15:49.641687 setup-nvidia[2330]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030559rm+0xca: 'naked' return found in RETHUNK build Sep 12 17:15:49.641687 setup-nvidia[2330]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030562rm+0x193: 'naked' return found in RETHUNK build Sep 12 17:15:49.641687 setup-nvidia[2330]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030564rm+0x17f: 'naked' return found in RETHUNK build Sep 12 17:15:49.641687 setup-nvidia[2330]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv011860rm+0x5d: 'naked' return found in RETHUNK build Sep 12 17:15:49.641687 setup-nvidia[2330]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039354rm+0x12f: 'naked' return found in RETHUNK build Sep 12 17:15:49.641687 setup-nvidia[2330]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/Module.symvers Sep 12 17:15:49.641687 setup-nvidia[2330]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.mod.o Sep 12 17:15:49.641687 setup-nvidia[2330]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.mod.o Sep 12 17:15:49.641687 setup-nvidia[2330]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.mod.o Sep 12 17:15:49.641687 setup-nvidia[2330]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.mod.o Sep 12 17:15:49.641687 setup-nvidia[2330]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.mod.o Sep 12 17:15:49.641687 setup-nvidia[2330]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Sep 12 17:15:49.641687 setup-nvidia[2330]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Sep 12 17:15:49.641687 setup-nvidia[2330]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Sep 12 17:15:49.641687 setup-nvidia[2330]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Sep 12 17:15:49.641687 setup-nvidia[2330]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Sep 12 17:15:49.641687 setup-nvidia[2330]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Sep 12 17:15:49.641687 setup-nvidia[2330]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko due to unavailability of vmlinux Sep 12 17:15:49.641687 setup-nvidia[2330]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Sep 12 17:15:49.641687 setup-nvidia[2330]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko due to unavailability of vmlinux Sep 12 17:15:49.641687 setup-nvidia[2330]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Sep 12 17:15:49.641687 setup-nvidia[2330]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko due to unavailability of vmlinux Sep 12 17:15:49.641687 setup-nvidia[2330]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Sep 12 17:15:49.641687 setup-nvidia[2330]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko due to unavailability of vmlinux Sep 12 17:15:49.641687 setup-nvidia[2330]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Sep 12 17:15:49.641687 setup-nvidia[2330]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko due to unavailability of vmlinux Sep 12 17:15:49.641687 setup-nvidia[2330]: make[2]: Leaving directory '/usr/lib/modules/6.6.106-flatcar/build' Sep 12 17:15:49.641687 setup-nvidia[2330]: make[1]: Leaving directory '/usr/lib/modules/6.6.106-flatcar/source' Sep 12 17:15:49.641687 setup-nvidia[2330]: -> done. Sep 12 17:15:49.641687 setup-nvidia[2330]: -> Kernel module compilation complete. Sep 12 17:15:49.641687 setup-nvidia[2330]: -> Unable to determine if Secure Boot is enabled: No such file or directory Sep 12 17:15:49.641687 setup-nvidia[2330]: 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 12 17:15:49.641687 setup-nvidia[2330]: \u000d Sep 12 17:15:49.642821 setup-nvidia[2330]: 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 12 17:15:49.642821 setup-nvidia[2330]: -> Kernel module load error: Operation not permitted Sep 12 17:15:49.642821 setup-nvidia[2330]: -> Kernel messages: Sep 12 17:15:49.642821 setup-nvidia[2330]: 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 12 17:15:56.796984 systemd[1]: Started sshd@19-10.200.8.11:22-10.200.16.10:35540.service - OpenSSH per-connection server daemon (10.200.16.10:35540). Sep 12 17:15:57.428305 sshd[13002]: Accepted publickey for core from 10.200.16.10 port 35540 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:15:57.429594 sshd-session[13002]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:15:57.433716 systemd-logind[1693]: New session 22 of user core. Sep 12 17:15:57.445642 systemd[1]: Started session-22.scope - Session 22 of User core. Sep 12 17:15:57.870144 sshd[13004]: Connection closed by 10.200.16.10 port 35540 Sep 12 17:15:57.870573 sshd-session[13002]: pam_unix(sshd:session): session closed for user core Sep 12 17:15:57.873452 systemd[1]: sshd@19-10.200.8.11:22-10.200.16.10:35540.service: Deactivated successfully. Sep 12 17:15:57.875025 systemd[1]: session-22.scope: Deactivated successfully. Sep 12 17:15:57.875636 systemd-logind[1693]: Session 22 logged out. Waiting for processes to exit. Sep 12 17:15:57.876363 systemd-logind[1693]: Removed session 22. Sep 12 17:15:57.983850 systemd[1]: Started sshd@20-10.200.8.11:22-10.200.16.10:35550.service - OpenSSH per-connection server daemon (10.200.16.10:35550). Sep 12 17:15:58.611798 sshd[13010]: Accepted publickey for core from 10.200.16.10 port 35550 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:15:58.613013 sshd-session[13010]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:15:58.617094 systemd-logind[1693]: New session 23 of user core. Sep 12 17:15:58.632755 systemd[1]: Started session-23.scope - Session 23 of User core. Sep 12 17:15:59.052968 sshd[13012]: Connection closed by 10.200.16.10 port 35550 Sep 12 17:15:59.057947 sshd-session[13010]: pam_unix(sshd:session): session closed for user core Sep 12 17:15:59.060926 systemd[1]: sshd@20-10.200.8.11:22-10.200.16.10:35550.service: Deactivated successfully. Sep 12 17:15:59.062522 systemd[1]: session-23.scope: Deactivated successfully. Sep 12 17:15:59.063134 systemd-logind[1693]: Session 23 logged out. Waiting for processes to exit. Sep 12 17:15:59.063909 systemd-logind[1693]: Removed session 23. Sep 12 17:16:09.164768 systemd[1]: Started sshd@21-10.200.8.11:22-10.200.16.10:38216.service - OpenSSH per-connection server daemon (10.200.16.10:38216). Sep 12 17:16:09.794758 sshd[13018]: Accepted publickey for core from 10.200.16.10 port 38216 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:16:09.796118 sshd-session[13018]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:16:09.800093 systemd-logind[1693]: New session 24 of user core. Sep 12 17:16:09.813619 systemd[1]: Started session-24.scope - Session 24 of User core. Sep 12 17:16:10.239938 sshd[13020]: Connection closed by 10.200.16.10 port 38216 Sep 12 17:16:10.240225 sshd-session[13018]: pam_unix(sshd:session): session closed for user core Sep 12 17:16:10.243367 systemd[1]: sshd@21-10.200.8.11:22-10.200.16.10:38216.service: Deactivated successfully. Sep 12 17:16:10.245208 systemd[1]: session-24.scope: Deactivated successfully. Sep 12 17:16:10.245944 systemd-logind[1693]: Session 24 logged out. Waiting for processes to exit. Sep 12 17:16:10.246707 systemd-logind[1693]: Removed session 24. Sep 12 17:16:10.351883 systemd[1]: Started sshd@22-10.200.8.11:22-10.200.16.10:55298.service - OpenSSH per-connection server daemon (10.200.16.10:55298). Sep 12 17:16:10.980599 sshd[13026]: Accepted publickey for core from 10.200.16.10 port 55298 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:16:10.981883 sshd-session[13026]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:16:10.986072 systemd-logind[1693]: New session 25 of user core. Sep 12 17:16:10.996629 systemd[1]: Started session-25.scope - Session 25 of User core. Sep 12 17:16:11.424358 sshd[13028]: Connection closed by 10.200.16.10 port 55298 Sep 12 17:16:11.424696 sshd-session[13026]: pam_unix(sshd:session): session closed for user core Sep 12 17:16:11.427635 systemd[1]: sshd@22-10.200.8.11:22-10.200.16.10:55298.service: Deactivated successfully. Sep 12 17:16:11.429738 systemd[1]: session-25.scope: Deactivated successfully. Sep 12 17:16:11.430348 systemd-logind[1693]: Session 25 logged out. Waiting for processes to exit. Sep 12 17:16:11.431108 systemd-logind[1693]: Removed session 25. Sep 12 17:16:16.689456 setup-nvidia[2330]: Container flatcardevelopercontainer-4230.2.3.bin exited successfully. Sep 12 17:16:16.689945 kernel: EXT4-fs (loop6p9): unmounting filesystem c1840b4a-9258-4a60-88d6-cf15a9c9a78c. Sep 12 17:16:16.692394 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4230.2.3.bin.mount: Deactivated successfully. Sep 12 17:16:16.744272 sudo[2315]: pam_unix(sudo:session): session closed for user root Sep 12 17:16:16.745904 systemd[1]: session-c2.scope: Deactivated successfully. Sep 12 17:16:16.746172 systemd[1]: session-c2.scope: Consumed 5min 9.106s CPU time, 883.2M memory peak. Sep 12 17:16:17.149743 setup-nvidia[1687]: /opt/nvidia / Sep 12 17:16:17.150718 setup-nvidia[1687]: / Sep 12 17:16:17.166933 setup-nvidia[13051]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 12 17:16:17.191524 kernel: IPMI message handler: version 39.2 Sep 12 17:16:17.194529 kernel: ipmi device interface Sep 12 17:16:17.195390 setup-nvidia[1687]: /opt/nvidia/current/usr/lib/modules/6.6.106-flatcar/video / Sep 12 17:16:17.246959 kernel: nvidia: loading out-of-tree module taints kernel. Sep 12 17:16:17.247086 kernel: nvidia: module license 'NVIDIA' taints kernel. Sep 12 17:16:17.247109 kernel: Disabling lock debugging due to kernel taint Sep 12 17:16:17.254249 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Sep 12 17:16:17.254295 kernel: nvidia: module license taints kernel. Sep 12 17:16:17.655554 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 245 Sep 12 17:16:18.053127 kernel: Sep 12 17:16:18.053257 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Sep 12 17:16:18.261527 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.230.02 Fri Dec 20 21:42:05 UTC 2024 Sep 12 17:16:18.284531 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.230.02 Fri Dec 20 21:52:34 UTC 2024 Sep 12 17:16:18.305553 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Sep 12 17:16:18.351525 kernel: nvidia-uvm: Loaded the UVM driver, major device number 243. Sep 12 17:16:18.351842 setup-nvidia[1687]: / Sep 12 17:16:20.055983 setup-nvidia[13081]: Fri Sep 12 17:16:20 2025 Sep 12 17:16:20.055983 setup-nvidia[13081]: +---------------------------------------------------------------------------------------+ Sep 12 17:16:20.055983 setup-nvidia[13081]: | NVIDIA-SMI 535.230.02 Driver Version: 535.230.02 CUDA Version: 12.2 | Sep 12 17:16:20.055983 setup-nvidia[13081]: |-----------------------------------------+----------------------+----------------------+ Sep 12 17:16:20.055983 setup-nvidia[13081]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Sep 12 17:16:20.055983 setup-nvidia[13081]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Sep 12 17:16:20.055983 setup-nvidia[13081]: | | | MIG M. | Sep 12 17:16:20.055983 setup-nvidia[13081]: |=========================================+======================+======================| Sep 12 17:16:20.059431 setup-nvidia[13081]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Sep 12 17:16:20.059431 setup-nvidia[13081]: | N/A 24C P0 35W / 250W | 0MiB / 16384MiB | 0% Default | Sep 12 17:16:20.059431 setup-nvidia[13081]: | | | N/A | Sep 12 17:16:20.059431 setup-nvidia[13081]: +-----------------------------------------+----------------------+----------------------+ Sep 12 17:16:20.059557 setup-nvidia[13081]: Sep 12 17:16:20.059557 setup-nvidia[13081]: +---------------------------------------------------------------------------------------+ Sep 12 17:16:20.059557 setup-nvidia[13081]: | Processes: | Sep 12 17:16:20.059557 setup-nvidia[13081]: | GPU GI CI PID Type Process name GPU Memory | Sep 12 17:16:20.059557 setup-nvidia[13081]: | ID ID Usage | Sep 12 17:16:20.059557 setup-nvidia[13081]: |=======================================================================================| Sep 12 17:16:20.059696 setup-nvidia[13081]: | No running processes found | Sep 12 17:16:20.059696 setup-nvidia[13081]: +---------------------------------------------------------------------------------------+ Sep 12 17:16:20.428558 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 12 17:16:20.433590 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 12 17:16:20.438155 systemd[1]: Startup finished in 1.152s (firmware) + 33.734s (loader) + 1.795s (kernel) + 12.714s (initrd) + 2min 56.092s (userspace) = 3min 45.489s. Sep 12 17:16:21.540093 systemd[1]: Started sshd@23-10.200.8.11:22-10.200.16.10:36728.service - OpenSSH per-connection server daemon (10.200.16.10:36728). Sep 12 17:16:22.169646 sshd[13088]: Accepted publickey for core from 10.200.16.10 port 36728 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:16:22.171013 sshd-session[13088]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:16:22.175135 systemd-logind[1693]: New session 26 of user core. Sep 12 17:16:22.193636 systemd[1]: Started session-26.scope - Session 26 of User core. Sep 12 17:16:22.614352 sshd[13090]: Connection closed by 10.200.16.10 port 36728 Sep 12 17:16:22.614685 sshd-session[13088]: pam_unix(sshd:session): session closed for user core Sep 12 17:16:22.617921 systemd[1]: sshd@23-10.200.8.11:22-10.200.16.10:36728.service: Deactivated successfully. Sep 12 17:16:22.619571 systemd[1]: session-26.scope: Deactivated successfully. Sep 12 17:16:22.620166 systemd-logind[1693]: Session 26 logged out. Waiting for processes to exit. Sep 12 17:16:22.620903 systemd-logind[1693]: Removed session 26. Sep 12 17:16:22.724701 systemd[1]: Started sshd@24-10.200.8.11:22-10.200.16.10:36734.service - OpenSSH per-connection server daemon (10.200.16.10:36734). Sep 12 17:16:23.352331 sshd[13097]: Accepted publickey for core from 10.200.16.10 port 36734 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:16:23.353637 sshd-session[13097]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:16:23.357678 systemd-logind[1693]: New session 27 of user core. Sep 12 17:16:23.368630 systemd[1]: Started session-27.scope - Session 27 of User core. Sep 12 17:16:23.791557 sshd[13099]: Connection closed by 10.200.16.10 port 36734 Sep 12 17:16:23.791884 sshd-session[13097]: pam_unix(sshd:session): session closed for user core Sep 12 17:16:23.795014 systemd[1]: sshd@24-10.200.8.11:22-10.200.16.10:36734.service: Deactivated successfully. Sep 12 17:16:23.796661 systemd[1]: session-27.scope: Deactivated successfully. Sep 12 17:16:23.797306 systemd-logind[1693]: Session 27 logged out. Waiting for processes to exit. Sep 12 17:16:23.798099 systemd-logind[1693]: Removed session 27. Sep 12 17:16:23.904745 systemd[1]: Started sshd@25-10.200.8.11:22-10.200.16.10:36738.service - OpenSSH per-connection server daemon (10.200.16.10:36738). Sep 12 17:16:24.536281 sshd[13105]: Accepted publickey for core from 10.200.16.10 port 36738 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:16:24.537516 sshd-session[13105]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:16:24.541495 systemd-logind[1693]: New session 28 of user core. Sep 12 17:16:24.551644 systemd[1]: Started session-28.scope - Session 28 of User core. Sep 12 17:16:24.978553 sshd[13107]: Connection closed by 10.200.16.10 port 36738 Sep 12 17:16:24.978843 sshd-session[13105]: pam_unix(sshd:session): session closed for user core Sep 12 17:16:24.982056 systemd[1]: sshd@25-10.200.8.11:22-10.200.16.10:36738.service: Deactivated successfully. Sep 12 17:16:24.983719 systemd[1]: session-28.scope: Deactivated successfully. Sep 12 17:16:24.984342 systemd-logind[1693]: Session 28 logged out. Waiting for processes to exit. Sep 12 17:16:24.985101 systemd-logind[1693]: Removed session 28. Sep 12 17:16:25.092049 systemd[1]: Started sshd@26-10.200.8.11:22-10.200.16.10:36746.service - OpenSSH per-connection server daemon (10.200.16.10:36746). Sep 12 17:16:25.720530 sshd[13113]: Accepted publickey for core from 10.200.16.10 port 36746 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:16:25.721773 sshd-session[13113]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:16:25.725831 systemd-logind[1693]: New session 29 of user core. Sep 12 17:16:25.740643 systemd[1]: Started session-29.scope - Session 29 of User core. Sep 12 17:16:26.065373 sudo[13116]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Sep 12 17:16:26.065716 sudo[13116]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 12 17:16:26.107828 sudo[13116]: pam_unix(sudo:session): session closed for user root Sep 12 17:16:26.212993 sshd[13115]: Connection closed by 10.200.16.10 port 36746 Sep 12 17:16:26.213465 sshd-session[13113]: pam_unix(sshd:session): session closed for user core Sep 12 17:16:26.216715 systemd[1]: sshd@26-10.200.8.11:22-10.200.16.10:36746.service: Deactivated successfully. Sep 12 17:16:26.218316 systemd[1]: session-29.scope: Deactivated successfully. Sep 12 17:16:26.218982 systemd-logind[1693]: Session 29 logged out. Waiting for processes to exit. Sep 12 17:16:26.219783 systemd-logind[1693]: Removed session 29. Sep 12 17:16:26.323772 systemd[1]: Started sshd@27-10.200.8.11:22-10.200.16.10:36758.service - OpenSSH per-connection server daemon (10.200.16.10:36758). Sep 12 17:16:26.952752 sshd[13122]: Accepted publickey for core from 10.200.16.10 port 36758 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:16:26.954165 sshd-session[13122]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:16:26.958215 systemd-logind[1693]: New session 30 of user core. Sep 12 17:16:26.967679 systemd[1]: Started session-30.scope - Session 30 of User core. Sep 12 17:16:26.968724 systemd[1]: Stopping user@0.service - User Manager for UID 0... Sep 12 17:16:26.969525 systemd[2318]: Activating special unit exit.target... Sep 12 17:16:26.969596 systemd[2318]: Stopped target default.target - Main User Target. Sep 12 17:16:26.969637 systemd[2318]: Stopped target basic.target - Basic System. Sep 12 17:16:26.969668 systemd[2318]: Stopped target paths.target - Paths. Sep 12 17:16:26.969697 systemd[2318]: Stopped target sockets.target - Sockets. Sep 12 17:16:26.969726 systemd[2318]: Stopped target timers.target - Timers. Sep 12 17:16:26.970074 systemd[2318]: Closed dbus.socket - D-Bus User Message Bus Socket. Sep 12 17:16:26.970286 systemd[2318]: Removed slice app.slice - User Application Slice. Sep 12 17:16:26.970321 systemd[2318]: Reached target shutdown.target - Shutdown. Sep 12 17:16:26.970475 systemd[2318]: Finished systemd-exit.service - Exit the Session. Sep 12 17:16:26.970557 systemd[2318]: Reached target exit.target - Exit the Session. Sep 12 17:16:26.983739 (sd-pam)[2319]: pam_unix(systemd-user:session): session closed for user root Sep 12 17:16:26.985132 systemd[1]: user@0.service: Deactivated successfully. Sep 12 17:16:26.985488 systemd[1]: Stopped user@0.service - User Manager for UID 0. Sep 12 17:16:26.996699 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Sep 12 17:16:27.005440 systemd[1]: run-user-0.mount: Deactivated successfully. Sep 12 17:16:27.006285 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Sep 12 17:16:27.006471 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Sep 12 17:16:27.008078 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Sep 12 17:16:27.008170 systemd[1]: user-0.slice: Consumed 5min 9.405s CPU time, 892.4M memory peak. Sep 12 17:16:27.008557 systemd-logind[1693]: Removed session c3. Sep 12 17:16:27.297991 sudo[13130]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Sep 12 17:16:27.298303 sudo[13130]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 12 17:16:27.301594 sudo[13130]: pam_unix(sudo:session): session closed for user root Sep 12 17:16:27.307202 sudo[13129]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Sep 12 17:16:27.307499 sudo[13129]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 12 17:16:27.325811 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 12 17:16:27.376796 augenrules[13152]: No rules Sep 12 17:16:27.378131 systemd[1]: audit-rules.service: Deactivated successfully. Sep 12 17:16:27.378397 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 12 17:16:27.379234 sudo[13129]: pam_unix(sudo:session): session closed for user root Sep 12 17:16:27.481786 sshd[13124]: Connection closed by 10.200.16.10 port 36758 Sep 12 17:16:27.482151 sshd-session[13122]: pam_unix(sshd:session): session closed for user core Sep 12 17:16:27.485479 systemd[1]: sshd@27-10.200.8.11:22-10.200.16.10:36758.service: Deactivated successfully. Sep 12 17:16:27.487117 systemd[1]: session-30.scope: Deactivated successfully. Sep 12 17:16:27.487761 systemd-logind[1693]: Session 30 logged out. Waiting for processes to exit. Sep 12 17:16:27.488489 systemd-logind[1693]: Removed session 30. Sep 12 17:16:27.592895 systemd[1]: Started sshd@28-10.200.8.11:22-10.200.16.10:36768.service - OpenSSH per-connection server daemon (10.200.16.10:36768). Sep 12 17:16:28.223609 sshd[13161]: Accepted publickey for core from 10.200.16.10 port 36768 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:16:28.225015 sshd-session[13161]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:16:28.229196 systemd-logind[1693]: New session 31 of user core. Sep 12 17:16:28.241627 systemd[1]: Started session-31.scope - Session 31 of User core. Sep 12 17:16:28.569042 sudo[13164]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/mkdir -p . Sep 12 17:16:28.569354 sudo[13164]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 12 17:16:28.572486 sudo[13164]: pam_unix(sudo:session): session closed for user root Sep 12 17:16:28.674873 sshd[13163]: Connection closed by 10.200.16.10 port 36768 Sep 12 17:16:28.675279 sshd-session[13161]: pam_unix(sshd:session): session closed for user core Sep 12 17:16:28.678593 systemd[1]: sshd@28-10.200.8.11:22-10.200.16.10:36768.service: Deactivated successfully. Sep 12 17:16:28.680239 systemd[1]: session-31.scope: Deactivated successfully. Sep 12 17:16:28.680913 systemd-logind[1693]: Session 31 logged out. Waiting for processes to exit. Sep 12 17:16:28.681738 systemd-logind[1693]: Removed session 31. Sep 12 17:16:28.789102 systemd[1]: Started sshd@29-10.200.8.11:22-10.200.16.10:36776.service - OpenSSH per-connection server daemon (10.200.16.10:36776). Sep 12 17:16:29.418869 sshd[13170]: Accepted publickey for core from 10.200.16.10 port 36776 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:16:29.420250 sshd-session[13170]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:16:29.424282 systemd-logind[1693]: New session 32 of user core. Sep 12 17:16:29.435633 systemd[1]: Started session-32.scope - Session 32 of User core. Sep 12 17:16:29.764871 sudo[13173]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/install -m 0755 /dev/stdin kolet Sep 12 17:16:29.765188 sudo[13173]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 12 17:16:33.454985 sudo[13173]: pam_unix(sudo:session): session closed for user root Sep 12 17:16:33.557522 sshd[13172]: Connection closed by 10.200.16.10 port 36776 Sep 12 17:16:33.557786 sshd-session[13170]: pam_unix(sshd:session): session closed for user core Sep 12 17:16:33.560953 systemd[1]: sshd@29-10.200.8.11:22-10.200.16.10:36776.service: Deactivated successfully. Sep 12 17:16:33.562627 systemd[1]: session-32.scope: Deactivated successfully. Sep 12 17:16:33.563246 systemd-logind[1693]: Session 32 logged out. Waiting for processes to exit. Sep 12 17:16:33.563999 systemd-logind[1693]: Removed session 32. Sep 12 17:16:33.667734 systemd[1]: Started sshd@30-10.200.8.11:22-10.200.16.10:39436.service - OpenSSH per-connection server daemon (10.200.16.10:39436). Sep 12 17:16:34.296569 sshd[13179]: Accepted publickey for core from 10.200.16.10 port 39436 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:16:34.297869 sshd-session[13179]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:16:34.301872 systemd-logind[1693]: New session 33 of user core. Sep 12 17:16:34.310634 systemd[1]: Started session-33.scope - Session 33 of User core. Sep 12 17:16:34.802190 sshd[13181]: Connection closed by 10.200.16.10 port 39436 Sep 12 17:16:34.802582 sshd-session[13179]: pam_unix(sshd:session): session closed for user core Sep 12 17:16:34.805667 systemd[1]: sshd@30-10.200.8.11:22-10.200.16.10:39436.service: Deactivated successfully. Sep 12 17:16:34.807267 systemd[1]: session-33.scope: Deactivated successfully. Sep 12 17:16:34.807952 systemd-logind[1693]: Session 33 logged out. Waiting for processes to exit. Sep 12 17:16:34.808714 systemd-logind[1693]: Removed session 33. Sep 12 17:16:34.912834 systemd[1]: Started sshd@31-10.200.8.11:22-10.200.16.10:39450.service - OpenSSH per-connection server daemon (10.200.16.10:39450). Sep 12 17:16:35.550395 sshd[13196]: Accepted publickey for core from 10.200.16.10 port 39450 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:16:35.551713 sshd-session[13196]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:16:35.555980 systemd-logind[1693]: New session 34 of user core. Sep 12 17:16:35.569639 systemd[1]: Started session-34.scope - Session 34 of User core. Sep 12 17:16:37.154727 systemd[1]: Starting docker.service - Docker Application Container Engine... Sep 12 17:16:37.154880 (dockerd)[13220]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPTS, DOCKER_OPT_BIP, DOCKER_OPT_IPMASQ, DOCKER_OPT_MTU Sep 12 17:16:39.519157 dockerd[13220]: time="2025-09-12T17:16:39.519098844Z" level=info msg="Starting up" Sep 12 17:16:40.452443 dockerd[13220]: time="2025-09-12T17:16:40.452391971Z" level=info msg="Loading containers: start." Sep 12 17:16:40.661530 kernel: Initializing XFRM netlink socket Sep 12 17:16:40.766142 systemd-networkd[1542]: docker0: Link UP Sep 12 17:16:40.827979 dockerd[13220]: time="2025-09-12T17:16:40.827933821Z" level=info msg="Loading containers: done." Sep 12 17:16:40.843348 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck3048612870-merged.mount: Deactivated successfully. Sep 12 17:16:40.858189 dockerd[13220]: time="2025-09-12T17:16:40.858152278Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2 Sep 12 17:16:40.858262 dockerd[13220]: time="2025-09-12T17:16:40.858234577Z" level=info msg="Docker daemon" commit=41ca978a0a5400cc24b274137efa9f25517fcc0b containerd-snapshotter=false storage-driver=overlay2 version=27.3.1 Sep 12 17:16:40.858362 dockerd[13220]: time="2025-09-12T17:16:40.858340475Z" level=info msg="Daemon has completed initialization" Sep 12 17:16:40.927501 dockerd[13220]: time="2025-09-12T17:16:40.927443933Z" level=info msg="API listen on /run/docker.sock" Sep 12 17:16:40.927586 systemd[1]: Started docker.service - Docker Application Container Engine. Sep 12 17:16:42.169370 systemd[1]: var-lib-docker-overlay2-f2b0bd73fc1c9a4c553e69f3939e8f3b77dda5190a38da14405c270b5cfa88a3\x2dinit-merged.mount: Deactivated successfully. Sep 12 17:16:42.253641 kernel: docker0: port 1(vethbdade63) entered blocking state Sep 12 17:16:42.253742 kernel: docker0: port 1(vethbdade63) entered disabled state Sep 12 17:16:42.253773 kernel: vethbdade63: entered allmulticast mode Sep 12 17:16:42.258735 kernel: vethbdade63: entered promiscuous mode Sep 12 17:16:42.259253 systemd-networkd[1542]: vethbdade63: Link UP Sep 12 17:16:43.027546 containerd[1703]: time="2025-09-12T17:16:43.027418192Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 12 17:16:43.027961 containerd[1703]: time="2025-09-12T17:16:43.027567390Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 12 17:16:43.028186 containerd[1703]: time="2025-09-12T17:16:43.027622089Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 12 17:16:43.028375 containerd[1703]: time="2025-09-12T17:16:43.028330676Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 12 17:16:43.064645 systemd[1]: Started docker-e7a156710fedc3ce5f8ccbc1b14d0c67af130fc31e040483dfe6c72ee9f9fa80.scope - libcontainer container e7a156710fedc3ce5f8ccbc1b14d0c67af130fc31e040483dfe6c72ee9f9fa80. Sep 12 17:16:43.117529 kernel: eth0: renamed from vethff59d04 Sep 12 17:16:43.131148 kernel: docker0: port 1(vethbdade63) entered blocking state Sep 12 17:16:43.131208 kernel: docker0: port 1(vethbdade63) entered forwarding state Sep 12 17:16:43.131257 systemd-networkd[1542]: vethbdade63: Gained carrier Sep 12 17:16:43.131592 systemd-networkd[1542]: docker0: Gained carrier Sep 12 17:16:43.837295 update_engine[1694]: I20250912 17:16:43.837230 1694 prefs.cc:52] certificate-report-to-send-update not present in /var/lib/update_engine/prefs Sep 12 17:16:43.837295 update_engine[1694]: I20250912 17:16:43.837285 1694 prefs.cc:52] certificate-report-to-send-download not present in /var/lib/update_engine/prefs Sep 12 17:16:43.837729 update_engine[1694]: I20250912 17:16:43.837460 1694 prefs.cc:52] aleph-version not present in /var/lib/update_engine/prefs Sep 12 17:16:43.837966 update_engine[1694]: I20250912 17:16:43.837939 1694 omaha_request_params.cc:62] Current group set to stable Sep 12 17:16:43.838075 update_engine[1694]: I20250912 17:16:43.838053 1694 update_attempter.cc:499] Already updated boot flags. Skipping. Sep 12 17:16:43.838075 update_engine[1694]: I20250912 17:16:43.838069 1694 update_attempter.cc:643] Scheduling an action processor start. Sep 12 17:16:43.838139 update_engine[1694]: I20250912 17:16:43.838088 1694 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Sep 12 17:16:43.838139 update_engine[1694]: I20250912 17:16:43.838119 1694 prefs.cc:52] previous-version not present in /var/lib/update_engine/prefs Sep 12 17:16:43.838188 update_engine[1694]: I20250912 17:16:43.838176 1694 omaha_request_action.cc:271] Posting an Omaha request to disabled Sep 12 17:16:43.838214 update_engine[1694]: I20250912 17:16:43.838187 1694 omaha_request_action.cc:272] Request: Sep 12 17:16:43.838214 update_engine[1694]: Sep 12 17:16:43.838214 update_engine[1694]: Sep 12 17:16:43.838214 update_engine[1694]: Sep 12 17:16:43.838214 update_engine[1694]: Sep 12 17:16:43.838214 update_engine[1694]: Sep 12 17:16:43.838214 update_engine[1694]: Sep 12 17:16:43.838214 update_engine[1694]: Sep 12 17:16:43.838214 update_engine[1694]: Sep 12 17:16:43.838214 update_engine[1694]: I20250912 17:16:43.838194 1694 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Sep 12 17:16:43.838445 locksmithd[1835]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_CHECKING_FOR_UPDATE" NewVersion=0.0.0 NewSize=0 Sep 12 17:16:43.839381 update_engine[1694]: I20250912 17:16:43.839354 1694 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Sep 12 17:16:43.839706 update_engine[1694]: I20250912 17:16:43.839677 1694 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Sep 12 17:16:43.856808 update_engine[1694]: E20250912 17:16:43.856770 1694 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Sep 12 17:16:43.856860 update_engine[1694]: I20250912 17:16:43.856837 1694 libcurl_http_fetcher.cc:283] No HTTP response, retry 1 Sep 12 17:16:44.744641 systemd-networkd[1542]: docker0: Gained IPv6LL Sep 12 17:16:44.872588 systemd-networkd[1542]: vethbdade63: Gained IPv6LL Sep 12 17:16:46.412555 systemd[1]: docker-e7a156710fedc3ce5f8ccbc1b14d0c67af130fc31e040483dfe6c72ee9f9fa80.scope: Deactivated successfully. Sep 12 17:16:46.425319 dockerd[13220]: time="2025-09-12T17:16:46.425273790Z" level=info msg="ignoring event" container=e7a156710fedc3ce5f8ccbc1b14d0c67af130fc31e040483dfe6c72ee9f9fa80 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Sep 12 17:16:46.425624 containerd[1703]: time="2025-09-12T17:16:46.425265290Z" level=info msg="shim disconnected" id=e7a156710fedc3ce5f8ccbc1b14d0c67af130fc31e040483dfe6c72ee9f9fa80 namespace=moby Sep 12 17:16:46.425624 containerd[1703]: time="2025-09-12T17:16:46.425315089Z" level=warning msg="cleaning up after shim disconnected" id=e7a156710fedc3ce5f8ccbc1b14d0c67af130fc31e040483dfe6c72ee9f9fa80 namespace=moby Sep 12 17:16:46.425624 containerd[1703]: time="2025-09-12T17:16:46.425323688Z" level=info msg="cleaning up dead shim" namespace=moby Sep 12 17:16:46.458786 systemd-networkd[1542]: vethbdade63: Lost carrier Sep 12 17:16:46.459523 kernel: docker0: port 1(vethbdade63) entered disabled state Sep 12 17:16:46.459556 kernel: vethff59d04: renamed from eth0 Sep 12 17:16:46.482353 systemd-networkd[1542]: vethbdade63: Link DOWN Sep 12 17:16:46.486531 kernel: docker0: port 1(vethbdade63) entered disabled state Sep 12 17:16:46.493304 kernel: vethbdade63 (unregistering): left allmulticast mode Sep 12 17:16:46.493350 kernel: vethbdade63 (unregistering): left promiscuous mode Sep 12 17:16:46.496638 kernel: docker0: port 1(vethbdade63) entered disabled state Sep 12 17:16:46.525502 systemd[1]: run-docker-netns-7f70a008dca8.mount: Deactivated successfully. Sep 12 17:16:46.529012 systemd[1]: var-lib-docker-overlay2-f2b0bd73fc1c9a4c553e69f3939e8f3b77dda5190a38da14405c270b5cfa88a3-merged.mount: Deactivated successfully. Sep 12 17:16:46.672292 sshd[13198]: Connection closed by 10.200.16.10 port 39450 Sep 12 17:16:46.672648 sshd-session[13196]: pam_unix(sshd:session): session closed for user core Sep 12 17:16:46.675791 systemd[1]: sshd@31-10.200.8.11:22-10.200.16.10:39450.service: Deactivated successfully. Sep 12 17:16:46.677362 systemd[1]: session-34.scope: Deactivated successfully. Sep 12 17:16:46.678063 systemd-logind[1693]: Session 34 logged out. Waiting for processes to exit. Sep 12 17:16:46.678822 systemd-logind[1693]: Removed session 34. Sep 12 17:16:46.782862 systemd[1]: Started sshd@32-10.200.8.11:22-10.200.16.10:54224.service - OpenSSH per-connection server daemon (10.200.16.10:54224). Sep 12 17:16:47.411922 sshd[13495]: Accepted publickey for core from 10.200.16.10 port 54224 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:16:47.413178 sshd-session[13495]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:16:47.417240 systemd-logind[1693]: New session 35 of user core. Sep 12 17:16:47.428629 systemd[1]: Started session-35.scope - Session 35 of User core. Sep 12 17:16:47.496716 systemd-networkd[1542]: docker0: Lost carrier Sep 12 17:16:47.817861 systemd[1]: var-lib-docker-overlay2-0625ccc731bb3ebe550edc74c4aae19fc90e24782929d8770b376d634c27f9af\x2dinit-merged.mount: Deactivated successfully. Sep 12 17:16:47.897923 kernel: docker0: port 1(vethb28668d) entered blocking state Sep 12 17:16:47.898061 kernel: docker0: port 1(vethb28668d) entered disabled state Sep 12 17:16:47.900679 kernel: vethb28668d: entered allmulticast mode Sep 12 17:16:47.903535 kernel: vethb28668d: entered promiscuous mode Sep 12 17:16:47.906995 kernel: docker0: port 1(vethb28668d) entered blocking state Sep 12 17:16:47.910452 kernel: docker0: port 1(vethb28668d) entered forwarding state Sep 12 17:16:47.911531 kernel: docker0: port 1(vethb28668d) entered disabled state Sep 12 17:16:47.914738 systemd-networkd[1542]: vethb28668d: Link UP Sep 12 17:16:48.006121 containerd[1703]: time="2025-09-12T17:16:48.006033224Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 12 17:16:48.006121 containerd[1703]: time="2025-09-12T17:16:48.006097623Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 12 17:16:48.006121 containerd[1703]: time="2025-09-12T17:16:48.006111222Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 12 17:16:48.006587 containerd[1703]: time="2025-09-12T17:16:48.006190521Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 12 17:16:48.025645 systemd[1]: Started docker-dafe92f8680c72fdd3ddb1abae87f8d0828899bb16f67f1bf8a2a99d601ce98b.scope - libcontainer container dafe92f8680c72fdd3ddb1abae87f8d0828899bb16f67f1bf8a2a99d601ce98b. Sep 12 17:16:48.080529 kernel: eth0: renamed from veth83f97bc Sep 12 17:16:48.093627 kernel: docker0: port 1(vethb28668d) entered blocking state Sep 12 17:16:48.093690 kernel: docker0: port 1(vethb28668d) entered forwarding state Sep 12 17:16:48.094022 systemd-networkd[1542]: vethb28668d: Gained carrier Sep 12 17:16:48.094274 systemd-networkd[1542]: docker0: Gained carrier Sep 12 17:16:48.136084 systemd[1]: docker-dafe92f8680c72fdd3ddb1abae87f8d0828899bb16f67f1bf8a2a99d601ce98b.scope: Deactivated successfully. Sep 12 17:16:48.163031 containerd[1703]: time="2025-09-12T17:16:48.162848270Z" level=info msg="shim disconnected" id=dafe92f8680c72fdd3ddb1abae87f8d0828899bb16f67f1bf8a2a99d601ce98b namespace=moby Sep 12 17:16:48.163031 containerd[1703]: time="2025-09-12T17:16:48.162900469Z" level=warning msg="cleaning up after shim disconnected" id=dafe92f8680c72fdd3ddb1abae87f8d0828899bb16f67f1bf8a2a99d601ce98b namespace=moby Sep 12 17:16:48.163031 containerd[1703]: time="2025-09-12T17:16:48.162909468Z" level=info msg="cleaning up dead shim" namespace=moby Sep 12 17:16:48.163170 dockerd[13220]: time="2025-09-12T17:16:48.162889669Z" level=info msg="ignoring event" container=dafe92f8680c72fdd3ddb1abae87f8d0828899bb16f67f1bf8a2a99d601ce98b module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Sep 12 17:16:48.195253 systemd-networkd[1542]: vethb28668d: Lost carrier Sep 12 17:16:48.195544 kernel: docker0: port 1(vethb28668d) entered disabled state Sep 12 17:16:48.195605 kernel: veth83f97bc: renamed from eth0 Sep 12 17:16:48.221428 systemd-networkd[1542]: vethb28668d: Link DOWN Sep 12 17:16:48.228526 kernel: docker0: port 1(vethb28668d) entered disabled state Sep 12 17:16:48.228577 kernel: vethb28668d (unregistering): left allmulticast mode Sep 12 17:16:48.231699 kernel: vethb28668d (unregistering): left promiscuous mode Sep 12 17:16:48.235071 kernel: docker0: port 1(vethb28668d) entered disabled state Sep 12 17:16:48.385453 sshd[13497]: Connection closed by 10.200.16.10 port 54224 Sep 12 17:16:48.385801 sshd-session[13495]: pam_unix(sshd:session): session closed for user core Sep 12 17:16:48.389126 systemd[1]: sshd@32-10.200.8.11:22-10.200.16.10:54224.service: Deactivated successfully. Sep 12 17:16:48.390831 systemd[1]: session-35.scope: Deactivated successfully. Sep 12 17:16:48.391451 systemd-logind[1693]: Session 35 logged out. Waiting for processes to exit. Sep 12 17:16:48.392221 systemd-logind[1693]: Removed session 35. Sep 12 17:16:48.495742 systemd[1]: Started sshd@33-10.200.8.11:22-10.200.16.10:54238.service - OpenSSH per-connection server daemon (10.200.16.10:54238). Sep 12 17:16:48.504703 systemd-networkd[1542]: docker0: Lost carrier Sep 12 17:16:48.814395 systemd[1]: var-lib-docker-overlay2-0625ccc731bb3ebe550edc74c4aae19fc90e24782929d8770b376d634c27f9af-merged.mount: Deactivated successfully. Sep 12 17:16:49.124202 sshd[13602]: Accepted publickey for core from 10.200.16.10 port 54238 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:16:49.125570 sshd-session[13602]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:16:49.129522 systemd-logind[1693]: New session 36 of user core. Sep 12 17:16:49.139648 systemd[1]: Started session-36.scope - Session 36 of User core. Sep 12 17:16:49.579819 ntpdate[13615]: ntpdate 4.2.8p17@1.4004-o Fri Sep 12 15:01:00 UTC 2025 (1) Sep 12 17:16:53.841211 update_engine[1694]: I20250912 17:16:53.841115 1694 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Sep 12 17:16:53.841701 update_engine[1694]: I20250912 17:16:53.841384 1694 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Sep 12 17:16:53.841701 update_engine[1694]: I20250912 17:16:53.841651 1694 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Sep 12 17:16:53.862800 update_engine[1694]: E20250912 17:16:53.862758 1694 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Sep 12 17:16:53.862869 update_engine[1694]: I20250912 17:16:53.862814 1694 libcurl_http_fetcher.cc:283] No HTTP response, retry 2 Sep 12 17:16:56.475987 ntpdate[13615]: adjust time server 193.1.12.167 offset -0.008698 sec Sep 12 17:16:56.578372 sshd[13604]: Connection closed by 10.200.16.10 port 54238 Sep 12 17:16:56.578786 sshd-session[13602]: pam_unix(sshd:session): session closed for user core Sep 12 17:16:56.581996 systemd[1]: sshd@33-10.200.8.11:22-10.200.16.10:54238.service: Deactivated successfully. Sep 12 17:16:56.583661 systemd[1]: session-36.scope: Deactivated successfully. Sep 12 17:16:56.584292 systemd-logind[1693]: Session 36 logged out. Waiting for processes to exit. Sep 12 17:16:56.585052 systemd-logind[1693]: Removed session 36.