Sep 12 17:13:11.241915 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:11.241945 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:11.241956 kernel: BIOS-provided physical RAM map: Sep 12 17:13:11.241964 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Sep 12 17:13:11.241971 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Sep 12 17:13:11.241979 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Sep 12 17:13:11.241987 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Sep 12 17:13:11.241998 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Sep 12 17:13:11.242006 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Sep 12 17:13:11.242014 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Sep 12 17:13:11.242022 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Sep 12 17:13:11.242029 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Sep 12 17:13:11.242037 kernel: printk: bootconsole [earlyser0] enabled Sep 12 17:13:11.242044 kernel: NX (Execute Disable) protection: active Sep 12 17:13:11.242057 kernel: APIC: Static calls initialized Sep 12 17:13:11.242066 kernel: efi: EFI v2.7 by Microsoft Sep 12 17:13:11.242074 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Sep 12 17:13:11.242083 kernel: random: crng init done Sep 12 17:13:11.242091 kernel: secureboot: Secure boot disabled Sep 12 17:13:11.242099 kernel: SMBIOS 3.1.0 present. Sep 12 17:13:11.242108 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Sep 12 17:13:11.242116 kernel: Hypervisor detected: Microsoft Hyper-V Sep 12 17:13:11.242125 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Sep 12 17:13:11.242133 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Sep 12 17:13:11.242144 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Sep 12 17:13:11.242153 kernel: Hyper-V: Using hypercall for remote TLB flush Sep 12 17:13:11.242161 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Sep 12 17:13:11.242170 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Sep 12 17:13:11.242179 kernel: tsc: Marking TSC unstable due to running on Hyper-V Sep 12 17:13:11.242188 kernel: tsc: Detected 2593.992 MHz processor Sep 12 17:13:11.242196 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Sep 12 17:13:11.242205 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Sep 12 17:13:11.242213 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Sep 12 17:13:11.242225 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Sep 12 17:13:11.242234 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Sep 12 17:13:11.242242 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Sep 12 17:13:11.242251 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Sep 12 17:13:11.242259 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Sep 12 17:13:11.242267 kernel: Using GB pages for direct mapping Sep 12 17:13:11.242276 kernel: ACPI: Early table checksum verification disabled Sep 12 17:13:11.242289 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Sep 12 17:13:11.242302 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 17:13:11.242311 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 17:13:11.242320 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Sep 12 17:13:11.242329 kernel: ACPI: FACS 0x000000003FFFE000 000040 Sep 12 17:13:11.242337 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 17:13:11.242347 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 17:13:11.242359 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 17:13:11.242368 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 17:13:11.242377 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 17:13:11.242386 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 17:13:11.242395 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 17:13:11.242403 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Sep 12 17:13:11.242412 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Sep 12 17:13:11.242421 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Sep 12 17:13:11.242433 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Sep 12 17:13:11.242443 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Sep 12 17:13:11.242452 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Sep 12 17:13:11.242460 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Sep 12 17:13:11.242469 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Sep 12 17:13:11.242478 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Sep 12 17:13:11.242487 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Sep 12 17:13:11.242496 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0 Sep 12 17:13:11.242505 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0 Sep 12 17:13:11.242523 kernel: SRAT: PXM 0 -> APIC 0x02 -> Node 0 Sep 12 17:13:11.242532 kernel: SRAT: PXM 0 -> APIC 0x03 -> Node 0 Sep 12 17:13:11.242541 kernel: SRAT: PXM 0 -> APIC 0x04 -> Node 0 Sep 12 17:13:11.242550 kernel: SRAT: PXM 0 -> APIC 0x05 -> Node 0 Sep 12 17:13:11.242559 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Sep 12 17:13:11.242568 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Sep 12 17:13:11.242577 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Sep 12 17:13:11.242586 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Sep 12 17:13:11.242595 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Sep 12 17:13:11.242607 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Sep 12 17:13:11.242616 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Sep 12 17:13:11.242625 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Sep 12 17:13:11.242634 kernel: NUMA: Node 0 [mem 0x00000000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00000000-0xfdfffffff] Sep 12 17:13:11.242643 kernel: NUMA: Node 0 [mem 0x00000000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00000000-0x5cbfffffff] Sep 12 17:13:11.242652 kernel: NODE_DATA(0) allocated [mem 0x5cbfff9000-0x5cbfffefff] Sep 12 17:13:11.242661 kernel: Zone ranges: Sep 12 17:13:11.242670 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Sep 12 17:13:11.242679 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Sep 12 17:13:11.242704 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Sep 12 17:13:11.242714 kernel: Movable zone start for each node Sep 12 17:13:11.242723 kernel: Early memory node ranges Sep 12 17:13:11.242732 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Sep 12 17:13:11.242740 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Sep 12 17:13:11.242749 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Sep 12 17:13:11.242758 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Sep 12 17:13:11.242767 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Sep 12 17:13:11.242776 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Sep 12 17:13:11.242788 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Sep 12 17:13:11.242798 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Sep 12 17:13:11.242807 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Sep 12 17:13:11.242815 kernel: ACPI: PM-Timer IO Port: 0x408 Sep 12 17:13:11.242824 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Sep 12 17:13:11.242833 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Sep 12 17:13:11.242842 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Sep 12 17:13:11.242851 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Sep 12 17:13:11.242860 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Sep 12 17:13:11.242873 kernel: smpboot: Allowing 6 CPUs, 0 hotplug CPUs Sep 12 17:13:11.242882 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Sep 12 17:13:11.242891 kernel: Booting paravirtualized kernel on Hyper-V Sep 12 17:13:11.242900 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Sep 12 17:13:11.242909 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Sep 12 17:13:11.242918 kernel: percpu: Embedded 58 pages/cpu s197160 r8192 d32216 u262144 Sep 12 17:13:11.242927 kernel: pcpu-alloc: s197160 r8192 d32216 u262144 alloc=1*2097152 Sep 12 17:13:11.242936 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Sep 12 17:13:11.242945 kernel: Hyper-V: PV spinlocks enabled Sep 12 17:13:11.242957 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Sep 12 17:13:11.242967 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:11.242977 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Sep 12 17:13:11.242986 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Sep 12 17:13:11.242995 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Sep 12 17:13:11.243004 kernel: Fallback order for Node 0: 0 Sep 12 17:13:11.243013 kernel: Built 1 zonelists, mobility grouping on. Total pages: 28899610 Sep 12 17:13:11.243021 kernel: Policy zone: Normal Sep 12 17:13:11.243034 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 12 17:13:11.243052 kernel: software IO TLB: area num 8. Sep 12 17:13:11.243062 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:11.243075 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Sep 12 17:13:11.243085 kernel: Kernel/User page tables isolation: enabled Sep 12 17:13:11.243094 kernel: ftrace: allocating 37948 entries in 149 pages Sep 12 17:13:11.243103 kernel: ftrace: allocated 149 pages with 4 groups Sep 12 17:13:11.243113 kernel: Dynamic Preempt: voluntary Sep 12 17:13:11.243122 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 12 17:13:11.243132 kernel: rcu: RCU event tracing is enabled. Sep 12 17:13:11.243142 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Sep 12 17:13:11.243151 kernel: Trampoline variant of Tasks RCU enabled. Sep 12 17:13:11.243164 kernel: Rude variant of Tasks RCU enabled. Sep 12 17:13:11.243174 kernel: Tracing variant of Tasks RCU enabled. Sep 12 17:13:11.243183 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 12 17:13:11.243193 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Sep 12 17:13:11.243206 kernel: Using NULL legacy PIC Sep 12 17:13:11.243215 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Sep 12 17:13:11.243224 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 12 17:13:11.243234 kernel: Console: colour dummy device 80x25 Sep 12 17:13:11.243243 kernel: printk: console [tty1] enabled Sep 12 17:13:11.243252 kernel: printk: console [ttyS0] enabled Sep 12 17:13:11.243262 kernel: printk: bootconsole [earlyser0] disabled Sep 12 17:13:11.243271 kernel: ACPI: Core revision 20230628 Sep 12 17:13:11.243280 kernel: Failed to register legacy timer interrupt Sep 12 17:13:11.243289 kernel: APIC: Switch to symmetric I/O mode setup Sep 12 17:13:11.243302 kernel: Hyper-V: enabling crash_kexec_post_notifiers Sep 12 17:13:11.243312 kernel: Hyper-V: Using IPI hypercalls Sep 12 17:13:11.243321 kernel: APIC: send_IPI() replaced with hv_send_ipi() Sep 12 17:13:11.243330 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Sep 12 17:13:11.243340 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Sep 12 17:13:11.243349 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Sep 12 17:13:11.243359 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Sep 12 17:13:11.243368 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Sep 12 17:13:11.243377 kernel: Hyper-V: Using enlightened APIC (xapic mode) Sep 12 17:13:11.243390 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Sep 12 17:13:11.243400 kernel: APIC: read() replaced with hv_apic_read() Sep 12 17:13:11.243413 kernel: APIC: write() replaced with hv_apic_write() Sep 12 17:13:11.243423 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Sep 12 17:13:11.243432 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Sep 12 17:13:11.243442 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593992) Sep 12 17:13:11.243451 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Sep 12 17:13:11.243461 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Sep 12 17:13:11.243470 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Sep 12 17:13:11.243483 kernel: Spectre V2 : Mitigation: Retpolines Sep 12 17:13:11.243492 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Sep 12 17:13:11.243501 kernel: Speculative Store Bypass: Vulnerable Sep 12 17:13:11.243511 kernel: MDS: Mitigation: Clear CPU buffers Sep 12 17:13:11.243520 kernel: TAA: Mitigation: Clear CPU buffers Sep 12 17:13:11.243529 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Sep 12 17:13:11.243538 kernel: active return thunk: its_return_thunk Sep 12 17:13:11.243547 kernel: ITS: Mitigation: Aligned branch/return thunks Sep 12 17:13:11.243557 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Sep 12 17:13:11.243566 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Sep 12 17:13:11.243575 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Sep 12 17:13:11.243588 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Sep 12 17:13:11.243598 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Sep 12 17:13:11.243607 kernel: Freeing SMP alternatives memory: 32K Sep 12 17:13:11.243616 kernel: pid_max: default: 32768 minimum: 301 Sep 12 17:13:11.243625 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Sep 12 17:13:11.243635 kernel: landlock: Up and running. Sep 12 17:13:11.243644 kernel: SELinux: Initializing. Sep 12 17:13:11.243653 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Sep 12 17:13:11.243663 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Sep 12 17:13:11.243676 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Sep 12 17:13:11.243696 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:11.243710 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:11.243719 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:11.243729 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Sep 12 17:13:11.243738 kernel: signal: max sigframe size: 1776 Sep 12 17:13:11.243748 kernel: rcu: Hierarchical SRCU implementation. Sep 12 17:13:11.243757 kernel: rcu: Max phase no-delay instances is 400. Sep 12 17:13:11.243767 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Sep 12 17:13:11.243776 kernel: smp: Bringing up secondary CPUs ... Sep 12 17:13:11.243785 kernel: smpboot: x86: Booting SMP configuration: Sep 12 17:13:11.243798 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Sep 12 17:13:11.243807 kernel: smp: Brought up 1 node, 6 CPUs Sep 12 17:13:11.243817 kernel: smpboot: Max logical packages: 1 Sep 12 17:13:11.243826 kernel: smpboot: Total of 6 processors activated (31127.90 BogoMIPS) Sep 12 17:13:11.243835 kernel: devtmpfs: initialized Sep 12 17:13:11.243845 kernel: x86/mm: Memory block size: 1024MB Sep 12 17:13:11.243854 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Sep 12 17:13:11.243864 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 12 17:13:11.243873 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Sep 12 17:13:11.243886 kernel: pinctrl core: initialized pinctrl subsystem Sep 12 17:13:11.243896 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 12 17:13:11.243905 kernel: audit: initializing netlink subsys (disabled) Sep 12 17:13:11.243914 kernel: audit: type=2000 audit(1757697189.029:1): state=initialized audit_enabled=0 res=1 Sep 12 17:13:11.243924 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 12 17:13:11.243933 kernel: thermal_sys: Registered thermal governor 'user_space' Sep 12 17:13:11.243942 kernel: cpuidle: using governor menu Sep 12 17:13:11.243952 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 12 17:13:11.243961 kernel: dca service started, version 1.12.1 Sep 12 17:13:11.243974 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Sep 12 17:13:11.243983 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Sep 12 17:13:11.243993 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 12 17:13:11.244002 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Sep 12 17:13:11.244012 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 12 17:13:11.244021 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Sep 12 17:13:11.244030 kernel: ACPI: Added _OSI(Module Device) Sep 12 17:13:11.244040 kernel: ACPI: Added _OSI(Processor Device) Sep 12 17:13:11.244049 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 12 17:13:11.244062 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 12 17:13:11.244071 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Sep 12 17:13:11.244081 kernel: ACPI: Interpreter enabled Sep 12 17:13:11.244090 kernel: ACPI: PM: (supports S0 S5) Sep 12 17:13:11.244099 kernel: ACPI: Using IOAPIC for interrupt routing Sep 12 17:13:11.244109 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Sep 12 17:13:11.244118 kernel: PCI: Ignoring E820 reservations for host bridge windows Sep 12 17:13:11.244128 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Sep 12 17:13:11.244137 kernel: iommu: Default domain type: Translated Sep 12 17:13:11.244150 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Sep 12 17:13:11.244159 kernel: efivars: Registered efivars operations Sep 12 17:13:11.244169 kernel: PCI: Using ACPI for IRQ routing Sep 12 17:13:11.244178 kernel: PCI: System does not support PCI Sep 12 17:13:11.244187 kernel: vgaarb: loaded Sep 12 17:13:11.244196 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Sep 12 17:13:11.244206 kernel: VFS: Disk quotas dquot_6.6.0 Sep 12 17:13:11.244215 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 12 17:13:11.244225 kernel: pnp: PnP ACPI init Sep 12 17:13:11.244237 kernel: pnp: PnP ACPI: found 3 devices Sep 12 17:13:11.244247 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Sep 12 17:13:11.244256 kernel: NET: Registered PF_INET protocol family Sep 12 17:13:11.244266 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 12 17:13:11.244275 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Sep 12 17:13:11.244284 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 12 17:13:11.244294 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 12 17:13:11.244303 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Sep 12 17:13:11.244313 kernel: TCP: Hash tables configured (established 524288 bind 65536) Sep 12 17:13:11.244326 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Sep 12 17:13:11.244335 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Sep 12 17:13:11.244345 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 12 17:13:11.244354 kernel: NET: Registered PF_XDP protocol family Sep 12 17:13:11.244363 kernel: PCI: CLS 0 bytes, default 64 Sep 12 17:13:11.244373 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Sep 12 17:13:11.244382 kernel: software IO TLB: mapped [mem 0x000000003b5bf000-0x000000003f5bf000] (64MB) Sep 12 17:13:11.244391 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Sep 12 17:13:11.244401 kernel: Initialise system trusted keyrings Sep 12 17:13:11.244413 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Sep 12 17:13:11.244423 kernel: Key type asymmetric registered Sep 12 17:13:11.244432 kernel: Asymmetric key parser 'x509' registered Sep 12 17:13:11.244441 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Sep 12 17:13:11.244450 kernel: io scheduler mq-deadline registered Sep 12 17:13:11.244460 kernel: io scheduler kyber registered Sep 12 17:13:11.244469 kernel: io scheduler bfq registered Sep 12 17:13:11.244478 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Sep 12 17:13:11.244487 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 12 17:13:11.244501 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Sep 12 17:13:11.244510 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Sep 12 17:13:11.244520 kernel: i8042: PNP: No PS/2 controller found. Sep 12 17:13:11.244756 kernel: rtc_cmos 00:02: registered as rtc0 Sep 12 17:13:11.244889 kernel: rtc_cmos 00:02: setting system clock to 2025-09-12T17:13:10 UTC (1757697190) Sep 12 17:13:11.245007 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Sep 12 17:13:11.245022 kernel: intel_pstate: CPU model not supported Sep 12 17:13:11.245037 kernel: efifb: probing for efifb Sep 12 17:13:11.245047 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Sep 12 17:13:11.245057 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Sep 12 17:13:11.245066 kernel: efifb: scrolling: redraw Sep 12 17:13:11.245075 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Sep 12 17:13:11.245085 kernel: Console: switching to colour frame buffer device 128x48 Sep 12 17:13:11.245094 kernel: fb0: EFI VGA frame buffer device Sep 12 17:13:11.245104 kernel: pstore: Using crash dump compression: deflate Sep 12 17:13:11.245113 kernel: pstore: Registered efi_pstore as persistent store backend Sep 12 17:13:11.245126 kernel: NET: Registered PF_INET6 protocol family Sep 12 17:13:11.245136 kernel: Segment Routing with IPv6 Sep 12 17:13:11.245145 kernel: In-situ OAM (IOAM) with IPv6 Sep 12 17:13:11.245155 kernel: NET: Registered PF_PACKET protocol family Sep 12 17:13:11.245164 kernel: Key type dns_resolver registered Sep 12 17:13:11.245173 kernel: IPI shorthand broadcast: enabled Sep 12 17:13:11.245183 kernel: sched_clock: Marking stable (1689005100, 112338300)->(2160316300, -358972900) Sep 12 17:13:11.245192 kernel: registered taskstats version 1 Sep 12 17:13:11.245201 kernel: Loading compiled-in X.509 certificates Sep 12 17:13:11.245211 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.106-flatcar: d1d9e065fdbec39026aa56a07626d6d91ab4fce4' Sep 12 17:13:11.245224 kernel: Key type .fscrypt registered Sep 12 17:13:11.245233 kernel: Key type fscrypt-provisioning registered Sep 12 17:13:11.245243 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 12 17:13:11.245252 kernel: ima: Allocated hash algorithm: sha1 Sep 12 17:13:11.245261 kernel: ima: No architecture policies found Sep 12 17:13:11.245271 kernel: clk: Disabling unused clocks Sep 12 17:13:11.245280 kernel: Freeing unused kernel image (initmem) memory: 43520K Sep 12 17:13:11.245289 kernel: Write protecting the kernel read-only data: 38912k Sep 12 17:13:11.245302 kernel: Freeing unused kernel image (rodata/data gap) memory: 1704K Sep 12 17:13:11.245312 kernel: Run /init as init process Sep 12 17:13:11.245321 kernel: with arguments: Sep 12 17:13:11.245330 kernel: /init Sep 12 17:13:11.245339 kernel: with environment: Sep 12 17:13:11.245348 kernel: HOME=/ Sep 12 17:13:11.245357 kernel: TERM=linux Sep 12 17:13:11.245366 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 12 17:13:11.245377 systemd[1]: Successfully made /usr/ read-only. Sep 12 17:13:11.245393 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:11.245404 systemd[1]: Detected virtualization microsoft. Sep 12 17:13:11.245414 systemd[1]: Detected architecture x86-64. Sep 12 17:13:11.245424 systemd[1]: Running in initrd. Sep 12 17:13:11.245434 systemd[1]: No hostname configured, using default hostname. Sep 12 17:13:11.245444 systemd[1]: Hostname set to . Sep 12 17:13:11.245454 systemd[1]: Initializing machine ID from random generator. Sep 12 17:13:11.245468 systemd[1]: Queued start job for default target initrd.target. Sep 12 17:13:11.245478 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 12 17:13:11.245488 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 12 17:13:11.245499 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 12 17:13:11.245510 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 12 17:13:11.245520 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 12 17:13:11.245531 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 12 17:13:11.245546 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:11.245557 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 12 17:13:11.245567 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 12 17:13:11.245577 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 12 17:13:11.245587 systemd[1]: Reached target paths.target - Path Units. Sep 12 17:13:11.245598 systemd[1]: Reached target slices.target - Slice Units. Sep 12 17:13:11.245608 systemd[1]: Reached target swap.target - Swaps. Sep 12 17:13:11.245618 systemd[1]: Reached target timers.target - Timer Units. Sep 12 17:13:11.245628 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 12 17:13:11.245642 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 12 17:13:11.245652 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 12 17:13:11.245663 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Sep 12 17:13:11.245673 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 12 17:13:11.245696 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 12 17:13:11.245708 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 12 17:13:11.245718 systemd[1]: Reached target sockets.target - Socket Units. Sep 12 17:13:11.245728 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 12 17:13:11.245743 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 12 17:13:11.245753 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 12 17:13:11.245763 systemd[1]: Starting systemd-fsck-usr.service... Sep 12 17:13:11.245773 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 12 17:13:11.245784 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 12 17:13:11.245794 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 12 17:13:11.245827 systemd-journald[204]: Collecting audit messages is disabled. Sep 12 17:13:11.245855 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 12 17:13:11.245866 systemd-journald[204]: Journal started Sep 12 17:13:11.245891 systemd-journald[204]: Runtime Journal (/run/log/journal/adfe2e8ede6245a8bc5780d39d6825cb) is 8M, max 2.2G, 2.1G free. Sep 12 17:13:11.257707 systemd[1]: Started systemd-journald.service - Journal Service. Sep 12 17:13:11.261910 systemd-modules-load[207]: Inserted module 'overlay' Sep 12 17:13:11.262223 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 12 17:13:11.263138 systemd[1]: Finished systemd-fsck-usr.service. Sep 12 17:13:11.282855 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 12 17:13:11.294725 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 12 17:13:11.295189 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 17:13:11.311039 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 12 17:13:11.320925 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 12 17:13:11.331486 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 12 17:13:11.359256 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 12 17:13:11.361710 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:11.365520 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 12 17:13:11.383860 systemd-modules-load[207]: Inserted module 'br_netfilter' Sep 12 17:13:11.384705 kernel: Bridge firewalling registered Sep 12 17:13:11.384774 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 12 17:13:11.386294 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 12 17:13:11.390141 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 12 17:13:11.391029 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 12 17:13:11.417517 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 12 17:13:11.419019 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 12 17:13:11.434996 dracut-cmdline[240]: dracut-dracut-053 Sep 12 17:13:11.434996 dracut-cmdline[240]: 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:11.487241 systemd-resolved[248]: Positive Trust Anchors: Sep 12 17:13:11.487261 systemd-resolved[248]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 12 17:13:11.487292 systemd-resolved[248]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Sep 12 17:13:11.489829 systemd-resolved[248]: Defaulting to hostname 'linux'. Sep 12 17:13:11.490903 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 12 17:13:11.496728 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 12 17:13:11.594712 kernel: SCSI subsystem initialized Sep 12 17:13:11.604717 kernel: Loading iSCSI transport class v2.0-870. Sep 12 17:13:11.617706 kernel: iscsi: registered transport (tcp) Sep 12 17:13:11.641482 kernel: iscsi: registered transport (qla4xxx) Sep 12 17:13:11.641523 kernel: QLogic iSCSI HBA Driver Sep 12 17:13:11.681801 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 12 17:13:11.704807 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 12 17:13:11.734713 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:11.734755 kernel: device-mapper: uevent: version 1.0.3 Sep 12 17:13:11.739300 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Sep 12 17:13:11.781702 kernel: raid6: avx2x4 gen() 31047 MB/s Sep 12 17:13:11.800695 kernel: raid6: avx2x2 gen() 30716 MB/s Sep 12 17:13:11.821992 kernel: raid6: avx2x1 gen() 28001 MB/s Sep 12 17:13:11.822018 kernel: raid6: using algorithm avx2x4 gen() 31047 MB/s Sep 12 17:13:11.845120 kernel: raid6: .... xor() 10073 MB/s, rmw enabled Sep 12 17:13:11.845148 kernel: raid6: using avx2x2 recovery algorithm Sep 12 17:13:11.868708 kernel: xor: automatically using best checksumming function avx Sep 12 17:13:12.031710 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 12 17:13:12.042825 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 12 17:13:12.052873 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 12 17:13:12.075309 systemd-udevd[429]: Using default interface naming scheme 'v255'. Sep 12 17:13:12.080779 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 12 17:13:12.098806 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 12 17:13:12.117050 dracut-pre-trigger[441]: rd.md=0: removing MD RAID activation Sep 12 17:13:12.148789 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 12 17:13:12.166887 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 12 17:13:12.227482 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 12 17:13:12.246835 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 12 17:13:12.261704 kernel: cryptd: max_cpu_qlen set to 1000 Sep 12 17:13:12.263203 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 12 17:13:12.268165 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 12 17:13:12.272325 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 12 17:13:12.282902 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 12 17:13:12.304701 kernel: AVX2 version of gcm_enc/dec engaged. Sep 12 17:13:12.305882 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 12 17:13:12.319550 kernel: AES CTR mode by8 optimization enabled Sep 12 17:13:12.319572 kernel: hv_vmbus: Vmbus version:4.0 Sep 12 17:13:12.319787 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 12 17:13:12.334341 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 12 17:13:12.336789 kernel: pps_core: LinuxPPS API ver. 1 registered Sep 12 17:13:12.336809 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Sep 12 17:13:12.334521 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 12 17:13:12.345783 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 12 17:13:12.355372 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 12 17:13:12.355580 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 17:13:12.374476 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 12 17:13:12.398469 kernel: hv_vmbus: registering driver hyperv_keyboard Sep 12 17:13:12.398508 kernel: hid: raw HID events driver (C) Jiri Kosina Sep 12 17:13:12.398521 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:12.403958 kernel: PTP clock support registered Sep 12 17:13:12.403989 kernel: hv_vmbus: registering driver hv_pci Sep 12 17:13:12.404004 kernel: hv_vmbus: registering driver hv_netvsc Sep 12 17:13:12.403962 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 12 17:13:12.410429 kernel: hv_vmbus: registering driver hid_hyperv Sep 12 17:13:12.426361 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Sep 12 17:13:12.426393 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Sep 12 17:13:12.426588 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Sep 12 17:13:12.432309 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Sep 12 17:13:12.442259 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Sep 12 17:13:12.442470 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Sep 12 17:13:12.442615 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Sep 12 17:13:12.447443 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 12 17:13:12.468112 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 Sep 12 17:13:12.468150 kernel: pci 0001:00:00.0: reg 0x10: [mem 0x41000000-0x41ffffff] Sep 12 17:13:12.468170 kernel: pci 0001:00:00.0: reg 0x14: [mem 0x1000000000-0x13ffffffff 64bit pref] Sep 12 17:13:12.468188 kernel: pci 0001:00:00.0: reg 0x1c: [mem 0x1400000000-0x1401ffffff 64bit pref] Sep 12 17:13:12.468783 kernel: hv_vmbus: registering driver hv_storvsc Sep 12 17:13:12.468813 kernel: hv_utils: Registering HyperV Utility Driver Sep 12 17:13:12.472694 kernel: pci 0001:00:00.0: Enabling HDA controller Sep 12 17:13:12.479960 kernel: hv_vmbus: registering driver hv_utils Sep 12 17:13:12.487316 kernel: hv_utils: Shutdown IC version 3.2 Sep 12 17:13:12.487351 kernel: hv_utils: Heartbeat IC version 3.0 Sep 12 17:13:12.490704 kernel: scsi host1: storvsc_host_t Sep 12 17:13:12.490754 kernel: hv_utils: TimeSync IC version 4.0 Sep 12 17:13:12.490769 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Sep 12 17:13:12.490935 kernel: pci 0001:00:00.0: BAR 1: assigned [mem 0x1000000000-0x13ffffffff 64bit pref] Sep 12 17:13:12.491103 kernel: pci 0001:00:00.0: BAR 3: assigned [mem 0x1400000000-0x1401ffffff 64bit pref] Sep 12 17:13:12.491398 kernel: pci 0001:00:00.0: BAR 0: assigned [mem 0x41000000-0x41ffffff] Sep 12 17:13:12.492715 kernel: scsi host0: storvsc_host_t Sep 12 17:13:13.458015 systemd-resolved[248]: Clock change detected. Flushing caches. Sep 12 17:13:13.480146 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Sep 12 17:13:13.486749 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Sep 12 17:13:13.491745 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Sep 12 17:13:13.492545 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 17:13:13.504882 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 12 17:13:13.524296 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Sep 12 17:13:13.524530 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Sep 12 17:13:13.525776 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Sep 12 17:13:13.527311 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 12 17:13:13.549461 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Sep 12 17:13:13.549662 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Sep 12 17:13:13.549841 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks Sep 12 17:13:13.549986 kernel: sd 0:0:0:1: [sdb] Write Protect is off Sep 12 17:13:13.550131 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Sep 12 17:13:13.550274 kernel: sd 0:0:0:0: [sda] Write Protect is off Sep 12 17:13:13.554782 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Sep 12 17:13:13.554996 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Sep 12 17:13:13.555151 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Sep 12 17:13:13.560758 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Sep 12 17:13:13.565749 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 12 17:13:13.575496 kernel: sdb: sdb1 Sep 12 17:13:13.575514 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Sep 12 17:13:13.575680 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk Sep 12 17:13:14.128062 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Sep 12 17:13:14.169758 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 scanned by (udev-worker) (475) Sep 12 17:13:14.193522 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Sep 12 17:13:14.208698 kernel: BTRFS: device fsid 8328a8c6-e42c-42bb-93d2-f755d7523d53 devid 1 transid 34 /dev/sda3 scanned by (udev-worker) (481) Sep 12 17:13:14.228143 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Sep 12 17:13:14.247351 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Sep 12 17:13:14.257872 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Sep 12 17:13:14.279852 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 12 17:13:14.294748 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 12 17:13:14.305748 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 12 17:13:15.317287 disk-uuid[642]: The operation has completed successfully. Sep 12 17:13:15.321271 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 12 17:13:16.003860 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 12 17:13:16.003978 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 12 17:13:16.028858 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 12 17:13:16.038471 sh[729]: Success Sep 12 17:13:16.074758 kernel: device-mapper: verity: sha256 using implementation "sha256-avx2" Sep 12 17:13:16.390383 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 12 17:13:16.416353 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 12 17:13:16.423769 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 12 17:13:16.462714 kernel: BTRFS info (device dm-0): first mount of filesystem 8328a8c6-e42c-42bb-93d2-f755d7523d53 Sep 12 17:13:16.462768 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Sep 12 17:13:16.467800 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Sep 12 17:13:16.471486 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 12 17:13:16.474672 kernel: BTRFS info (device dm-0): using free space tree Sep 12 17:13:16.791196 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 12 17:13:16.792029 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.816870 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 12 17:13:16.825695 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 12 17:13:16.865730 kernel: BTRFS info (device sda6): first mount of filesystem 27144f91-5d6e-4232-8594-aeebe7d5186d Sep 12 17:13:16.865785 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Sep 12 17:13:16.869151 kernel: BTRFS info (device sda6): using free space tree Sep 12 17:13:16.912754 kernel: BTRFS info (device sda6): auto enabling async discard Sep 12 17:13:16.924764 kernel: BTRFS info (device sda6): last unmount of filesystem 27144f91-5d6e-4232-8594-aeebe7d5186d Sep 12 17:13:16.929279 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 12 17:13:16.952874 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 12 17:13:16.953196 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 12 17:13:16.970756 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 12 17:13:17.006199 systemd-networkd[908]: lo: Link UP Sep 12 17:13:17.006211 systemd-networkd[908]: lo: Gained carrier Sep 12 17:13:17.007365 systemd-networkd[908]: Enumeration completed Sep 12 17:13:17.007452 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 12 17:13:17.007749 systemd-networkd[908]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 12 17:13:17.007753 systemd-networkd[908]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 12 17:13:17.008565 systemd-networkd[908]: eth0: Link UP Sep 12 17:13:17.008751 systemd-networkd[908]: eth0: Gained carrier Sep 12 17:13:17.008761 systemd-networkd[908]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 12 17:13:17.009564 systemd[1]: Reached target network.target - Network. Sep 12 17:13:17.080794 systemd-networkd[908]: eth0: DHCPv4 address 10.200.8.12/24, gateway 10.200.8.1 acquired from 168.63.129.16 Sep 12 17:13:18.383027 ignition[911]: Ignition 2.20.0 Sep 12 17:13:18.383044 ignition[911]: Stage: fetch-offline Sep 12 17:13:18.383110 ignition[911]: no configs at "/usr/lib/ignition/base.d" Sep 12 17:13:18.383125 ignition[911]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 12 17:13:18.383301 ignition[911]: parsed url from cmdline: "" Sep 12 17:13:18.383306 ignition[911]: no config URL provided Sep 12 17:13:18.383313 ignition[911]: reading system config file "/usr/lib/ignition/user.ign" Sep 12 17:13:18.383325 ignition[911]: no config at "/usr/lib/ignition/user.ign" Sep 12 17:13:18.383332 ignition[911]: failed to fetch config: resource requires networking Sep 12 17:13:18.383552 ignition[911]: Ignition finished successfully Sep 12 17:13:18.420615 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 12 17:13:18.443074 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Sep 12 17:13:18.456119 ignition[923]: Ignition 2.20.0 Sep 12 17:13:18.456132 ignition[923]: Stage: fetch Sep 12 17:13:18.456307 ignition[923]: no configs at "/usr/lib/ignition/base.d" Sep 12 17:13:18.456320 ignition[923]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 12 17:13:18.456423 ignition[923]: parsed url from cmdline: "" Sep 12 17:13:18.456427 ignition[923]: no config URL provided Sep 12 17:13:18.456433 ignition[923]: reading system config file "/usr/lib/ignition/user.ign" Sep 12 17:13:18.456444 ignition[923]: no config at "/usr/lib/ignition/user.ign" Sep 12 17:13:18.456477 ignition[923]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Sep 12 17:13:18.565471 ignition[923]: GET result: OK Sep 12 17:13:18.565546 ignition[923]: config has been read from IMDS userdata Sep 12 17:13:18.565559 ignition[923]: parsing config with SHA512: ea36dabbb7deab445eaab37343e3cc551b6acb7c91cff2b2814ab8d5e74f2da07231094ed317f2209b1d468f07f4fd68ca811d55ce2d7c062294eac572e043ec Sep 12 17:13:18.569898 unknown[923]: fetched base config from "system" Sep 12 17:13:18.570117 ignition[923]: fetch: fetch complete Sep 12 17:13:18.569906 unknown[923]: fetched base config from "system" Sep 12 17:13:18.570122 ignition[923]: fetch: fetch passed Sep 12 17:13:18.569911 unknown[923]: fetched user config from "azure" Sep 12 17:13:18.570171 ignition[923]: Ignition finished successfully Sep 12 17:13:18.572078 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Sep 12 17:13:18.598887 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 12 17:13:18.620208 ignition[933]: Ignition 2.20.0 Sep 12 17:13:18.620220 ignition[933]: Stage: kargs Sep 12 17:13:18.620400 ignition[933]: no configs at "/usr/lib/ignition/base.d" Sep 12 17:13:18.620414 ignition[933]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 12 17:13:18.621015 ignition[933]: kargs: kargs passed Sep 12 17:13:18.621065 ignition[933]: Ignition finished successfully Sep 12 17:13:18.638833 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 12 17:13:18.666887 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 12 17:13:18.680666 ignition[943]: Ignition 2.20.0 Sep 12 17:13:18.680679 ignition[943]: Stage: disks Sep 12 17:13:18.680875 ignition[943]: no configs at "/usr/lib/ignition/base.d" Sep 12 17:13:18.684700 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 12 17:13:18.680889 ignition[943]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 12 17:13:18.686491 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 12 17:13:18.681450 ignition[943]: disks: disks passed Sep 12 17:13:18.695095 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 12 17:13:18.681497 ignition[943]: Ignition finished successfully Sep 12 17:13:18.711069 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 12 17:13:18.721584 systemd[1]: Reached target sysinit.target - System Initialization. Sep 12 17:13:18.730848 systemd[1]: Reached target basic.target - Basic System. Sep 12 17:13:18.758868 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 12 17:13:18.832276 systemd-fsck[955]: ROOT: clean, 14/7326000 files, 477710/7359488 blocks Sep 12 17:13:18.837836 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 12 17:13:18.862818 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 12 17:13:18.965751 kernel: EXT4-fs (sda9): mounted filesystem 5378802a-8117-4ea8-949a-cd38005ba44a r/w with ordered data mode. Quota mode: none. Sep 12 17:13:18.966072 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 12 17:13:18.966730 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 12 17:13:19.005804 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 12 17:13:19.014047 systemd-networkd[908]: eth0: Gained IPv6LL Sep 12 17:13:19.027531 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/sda6 scanned by mount (967) Sep 12 17:13:19.027575 kernel: BTRFS info (device sda6): first mount of filesystem 27144f91-5d6e-4232-8594-aeebe7d5186d Sep 12 17:13:19.028414 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 12 17:13:19.045797 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Sep 12 17:13:19.045822 kernel: BTRFS info (device sda6): using free space tree Sep 12 17:13:19.045835 kernel: BTRFS info (device sda6): auto enabling async discard Sep 12 17:13:19.043270 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Sep 12 17:13:19.050669 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.050703 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 12 17:13:19.063865 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 12 17:13:19.078335 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 12 17:13:19.096870 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 12 17:13:20.389377 coreos-metadata[984]: Sep 12 17:13:20.389 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Sep 12 17:13:20.397720 coreos-metadata[984]: Sep 12 17:13:20.397 INFO Fetch successful Sep 12 17:13:20.401701 coreos-metadata[984]: Sep 12 17:13:20.397 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.410067 coreos-metadata[984]: Sep 12 17:13:20.405 INFO Fetch successful Sep 12 17:13:20.410067 coreos-metadata[984]: Sep 12 17:13:20.405 INFO wrote hostname ci-4230.2.3-a-b3af721f5f to /sysroot/etc/hostname Sep 12 17:13:20.421571 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Sep 12 17:13:20.710067 initrd-setup-root[1000]: cut: /sysroot/etc/passwd: No such file or directory Sep 12 17:13:20.767928 initrd-setup-root[1007]: cut: /sysroot/etc/group: No such file or directory Sep 12 17:13:20.788873 initrd-setup-root[1014]: cut: /sysroot/etc/shadow: No such file or directory Sep 12 17:13:20.807571 initrd-setup-root[1021]: cut: /sysroot/etc/gshadow: No such file or directory Sep 12 17:13:22.112677 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 12 17:13:22.129810 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 12 17:13:22.130590 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 12 17:13:22.149801 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 12 17:13:22.158771 kernel: BTRFS info (device sda6): last unmount of filesystem 27144f91-5d6e-4232-8594-aeebe7d5186d Sep 12 17:13:22.171784 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 12 17:13:22.182870 ignition[1090]: INFO : Ignition 2.20.0 Sep 12 17:13:22.182870 ignition[1090]: INFO : Stage: mount Sep 12 17:13:22.188896 ignition[1090]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 12 17:13:22.188896 ignition[1090]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 12 17:13:22.188896 ignition[1090]: INFO : mount: mount passed Sep 12 17:13:22.188896 ignition[1090]: INFO : Ignition finished successfully Sep 12 17:13:22.189826 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 12 17:13:22.226806 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 12 17:13:22.233467 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 12 17:13:22.263298 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/sda6 scanned by mount (1107) Sep 12 17:13:22.263333 kernel: BTRFS info (device sda6): first mount of filesystem 27144f91-5d6e-4232-8594-aeebe7d5186d Sep 12 17:13:22.267750 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Sep 12 17:13:22.271041 kernel: BTRFS info (device sda6): using free space tree Sep 12 17:13:22.277756 kernel: BTRFS info (device sda6): auto enabling async discard Sep 12 17:13:22.279847 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 12 17:13:22.306082 ignition[1124]: INFO : Ignition 2.20.0 Sep 12 17:13:22.306082 ignition[1124]: INFO : Stage: files Sep 12 17:13:22.313057 ignition[1124]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 12 17:13:22.313057 ignition[1124]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 12 17:13:22.313057 ignition[1124]: DEBUG : files: compiled without relabeling support, skipping Sep 12 17:13:22.332356 ignition[1124]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 12 17:13:22.332356 ignition[1124]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 12 17:13:22.408775 ignition[1124]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 12 17:13:22.414413 ignition[1124]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 12 17:13:22.414413 ignition[1124]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 12 17:13:22.409446 unknown[1124]: wrote ssh authorized keys file for user: core Sep 12 17:13:22.435354 ignition[1124]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 12 17:13:22.442811 ignition[1124]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 12 17:13:22.442811 ignition[1124]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 12 17:13:22.442811 ignition[1124]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 12 17:13:22.442811 ignition[1124]: INFO : files: files passed Sep 12 17:13:22.442811 ignition[1124]: INFO : Ignition finished successfully Sep 12 17:13:22.472530 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 12 17:13:22.487907 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 12 17:13:22.493020 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 12 17:13:22.497273 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 12 17:13:22.497367 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 12 17:13:22.525281 initrd-setup-root-after-ignition[1155]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 12 17:13:22.525281 initrd-setup-root-after-ignition[1155]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 12 17:13:22.511700 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 12 17:13:22.548150 initrd-setup-root-after-ignition[1159]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 12 17:13:22.521954 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 12 17:13:22.558882 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 12 17:13:22.581971 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 12 17:13:22.582084 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 12 17:13:22.586211 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 12 17:13:22.596004 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 12 17:13:22.604269 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 12 17:13:22.605130 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 12 17:13:22.639831 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 12 17:13:22.641518 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 12 17:13:22.660002 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 12 17:13:22.660203 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 12 17:13:22.670019 systemd[1]: Stopped target timers.target - Timer Units. Sep 12 17:13:22.679377 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 12 17:13:22.679498 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 12 17:13:22.695112 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 12 17:13:22.699978 systemd[1]: Stopped target basic.target - Basic System. Sep 12 17:13:22.708106 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 12 17:13:22.715216 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 12 17:13:22.723727 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 12 17:13:22.733353 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 12 17:13:22.742215 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 12 17:13:22.750277 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 12 17:13:22.760019 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 12 17:13:22.768173 systemd[1]: Stopped target swap.target - Swaps. Sep 12 17:13:22.776190 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 12 17:13:22.776323 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 12 17:13:22.790644 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 12 17:13:22.794816 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 12 17:13:22.806076 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 12 17:13:22.806214 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 12 17:13:22.815786 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 12 17:13:22.815918 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 12 17:13:22.837237 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 12 17:13:22.837376 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 12 17:13:22.841517 systemd[1]: ignition-files.service: Deactivated successfully. Sep 12 17:13:22.841646 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 12 17:13:22.850369 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Sep 12 17:13:22.850491 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Sep 12 17:13:22.882888 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 12 17:13:22.887517 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 12 17:13:22.887655 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 12 17:13:22.901104 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 12 17:13:22.907831 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 12 17:13:22.931620 ignition[1181]: INFO : Ignition 2.20.0 Sep 12 17:13:22.931620 ignition[1181]: INFO : Stage: umount Sep 12 17:13:22.931620 ignition[1181]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 12 17:13:22.931620 ignition[1181]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 12 17:13:22.931620 ignition[1181]: INFO : umount: umount passed Sep 12 17:13:22.931620 ignition[1181]: INFO : Ignition finished successfully Sep 12 17:13:22.907964 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 12 17:13:22.912862 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 12 17:13:22.912972 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 12 17:13:22.933410 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 12 17:13:22.933661 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 12 17:13:22.937875 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 12 17:13:22.937981 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 12 17:13:22.942338 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 12 17:13:22.942404 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 12 17:13:22.950987 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 12 17:13:22.951038 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 12 17:13:22.959900 systemd[1]: ignition-fetch.service: Deactivated successfully. Sep 12 17:13:22.959948 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Sep 12 17:13:22.967000 systemd[1]: Stopped target network.target - Network. Sep 12 17:13:22.974576 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 12 17:13:22.974632 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 12 17:13:22.987491 systemd[1]: Stopped target paths.target - Path Units. Sep 12 17:13:22.994952 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 12 17:13:22.995045 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 12 17:13:23.007991 systemd[1]: Stopped target slices.target - Slice Units. Sep 12 17:13:23.016071 systemd[1]: Stopped target sockets.target - Socket Units. Sep 12 17:13:23.023616 systemd[1]: iscsid.socket: Deactivated successfully. Sep 12 17:13:23.023660 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 12 17:13:23.028539 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 12 17:13:23.028580 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 12 17:13:23.036235 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 12 17:13:23.036291 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 12 17:13:23.044060 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 12 17:13:23.044106 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 12 17:13:23.052218 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 12 17:13:23.060163 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 12 17:13:23.070690 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 12 17:13:23.071283 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 12 17:13:23.071390 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 12 17:13:23.085647 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 12 17:13:23.085766 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 12 17:13:23.090505 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 12 17:13:23.090560 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 12 17:13:23.097819 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 12 17:13:23.097870 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 12 17:13:23.115726 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Sep 12 17:13:23.115842 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Sep 12 17:13:23.116105 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 12 17:13:23.116204 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 12 17:13:23.129950 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Sep 12 17:13:23.130371 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 12 17:13:23.130419 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 12 17:13:23.150847 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 12 17:13:23.152124 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 12 17:13:23.152179 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 12 17:13:23.159583 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 12 17:13:23.159631 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 12 17:13:23.172729 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 12 17:13:23.172801 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 12 17:13:23.181457 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 12 17:13:23.187211 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Sep 12 17:13:23.212402 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 12 17:13:23.212555 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 12 17:13:23.215040 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 12 17:13:23.215135 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 12 17:13:23.228347 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 12 17:13:23.228400 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 12 17:13:23.231659 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 12 17:13:23.231697 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 12 17:13:23.240061 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 12 17:13:23.240110 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 12 17:13:23.257300 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 12 17:13:23.257350 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 12 17:13:23.271786 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 12 17:13:23.271837 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 12 17:13:23.306875 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 12 17:13:23.308146 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 12 17:13:23.308200 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 12 17:13:23.322232 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 12 17:13:23.322283 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 17:13:23.333779 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Sep 12 17:13:23.333842 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 12 17:13:23.334222 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 12 17:13:23.334310 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 12 17:13:23.337148 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 12 17:13:23.346089 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 12 17:13:23.372323 systemd[1]: Switching root. Sep 12 17:13:23.587764 systemd-journald[204]: Journal stopped Sep 12 17:13:31.360346 systemd-journald[204]: Received SIGTERM from PID 1 (systemd). Sep 12 17:13:31.360384 kernel: SELinux: policy capability network_peer_controls=1 Sep 12 17:13:31.360397 kernel: SELinux: policy capability open_perms=1 Sep 12 17:13:31.360407 kernel: SELinux: policy capability extended_socket_class=1 Sep 12 17:13:31.360416 kernel: SELinux: policy capability always_check_network=0 Sep 12 17:13:31.360425 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 12 17:13:31.360435 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 12 17:13:31.360452 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 12 17:13:31.360462 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 12 17:13:31.360472 kernel: audit: type=1403 audit(1757697204.543:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 12 17:13:31.360482 systemd[1]: Successfully loaded SELinux policy in 201.583ms. Sep 12 17:13:31.360494 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 15.676ms. Sep 12 17:13:31.360505 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.360516 systemd[1]: Detected virtualization microsoft. Sep 12 17:13:31.360530 systemd[1]: Detected architecture x86-64. Sep 12 17:13:31.360541 systemd[1]: Detected first boot. Sep 12 17:13:31.360552 systemd[1]: Hostname set to . Sep 12 17:13:31.360562 systemd[1]: Initializing machine ID from random generator. Sep 12 17:13:31.360573 zram_generator::config[1226]: No configuration found. Sep 12 17:13:31.360587 kernel: Guest personality initialized and is inactive Sep 12 17:13:31.360597 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Sep 12 17:13:31.360607 kernel: Initialized host personality Sep 12 17:13:31.360616 kernel: NET: Registered PF_VSOCK protocol family Sep 12 17:13:31.360626 systemd[1]: Populated /etc with preset unit settings. Sep 12 17:13:31.360637 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Sep 12 17:13:31.360648 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 12 17:13:31.360658 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 12 17:13:31.360672 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 12 17:13:31.360686 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 12 17:13:31.360698 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 12 17:13:31.360709 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 12 17:13:31.360719 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 12 17:13:31.360731 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 12 17:13:31.360755 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 12 17:13:31.360771 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 12 17:13:31.360782 systemd[1]: Created slice user.slice - User and Session Slice. Sep 12 17:13:31.360793 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 12 17:13:31.360804 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 12 17:13:31.360815 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 12 17:13:31.360826 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 12 17:13:31.360841 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 12 17:13:31.360853 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 12 17:13:31.360867 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Sep 12 17:13:31.360879 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 12 17:13:31.360890 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 12 17:13:31.360901 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 12 17:13:31.360912 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 12 17:13:31.360923 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 12 17:13:31.360934 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 12 17:13:31.360948 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 12 17:13:31.360963 systemd[1]: Reached target slices.target - Slice Units. Sep 12 17:13:31.360974 systemd[1]: Reached target swap.target - Swaps. Sep 12 17:13:31.360985 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 12 17:13:31.360996 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 12 17:13:31.361007 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Sep 12 17:13:31.361022 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 12 17:13:31.361033 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 12 17:13:31.361045 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 12 17:13:31.361056 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 12 17:13:31.361067 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 12 17:13:31.361078 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 12 17:13:31.361089 systemd[1]: Mounting media.mount - External Media Directory... Sep 12 17:13:31.361100 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 12 17:13:31.361115 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 12 17:13:31.361126 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 12 17:13:31.361138 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 12 17:13:31.361149 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.361161 systemd[1]: Reached target machines.target - Containers. Sep 12 17:13:31.361172 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 12 17:13:31.361183 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 12 17:13:31.361197 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 12 17:13:31.361211 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 12 17:13:31.361223 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 12 17:13:31.361235 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 12 17:13:31.361246 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 12 17:13:31.361258 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 12 17:13:31.361269 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 12 17:13:31.361280 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.361292 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 12 17:13:31.361306 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 12 17:13:31.361318 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 12 17:13:31.361329 systemd[1]: Stopped systemd-fsck-usr.service. Sep 12 17:13:31.361341 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.361352 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 12 17:13:31.361363 kernel: loop: module loaded Sep 12 17:13:31.361374 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 12 17:13:31.361385 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 12 17:13:31.361399 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 12 17:13:31.361411 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Sep 12 17:13:31.361422 kernel: fuse: init (API version 7.39) Sep 12 17:13:31.361432 kernel: ACPI: bus type drm_connector registered Sep 12 17:13:31.361446 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 12 17:13:31.361458 systemd[1]: verity-setup.service: Deactivated successfully. Sep 12 17:13:31.361469 systemd[1]: Stopped verity-setup.service. Sep 12 17:13:31.361480 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.361494 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 12 17:13:31.361529 systemd-journald[1333]: Collecting audit messages is disabled. Sep 12 17:13:31.361554 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 12 17:13:31.361567 systemd-journald[1333]: Journal started Sep 12 17:13:31.361594 systemd-journald[1333]: Runtime Journal (/run/log/journal/83be458caa434157bb6fd8c8b3b1b1cd) is 8M, max 2.2G, 2.1G free. Sep 12 17:13:30.475514 systemd[1]: Queued start job for default target multi-user.target. Sep 12 17:13:30.487434 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Sep 12 17:13:30.487947 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 12 17:13:31.373750 systemd[1]: Started systemd-journald.service - Journal Service. Sep 12 17:13:31.378383 systemd[1]: Mounted media.mount - External Media Directory. Sep 12 17:13:31.382818 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 12 17:13:31.387592 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 12 17:13:31.392459 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 12 17:13:31.396938 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 12 17:13:31.402139 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 12 17:13:31.407728 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 12 17:13:31.407945 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 12 17:13:31.413333 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 12 17:13:31.413526 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 12 17:13:31.418667 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 12 17:13:31.418976 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 12 17:13:31.423709 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 12 17:13:31.423915 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 12 17:13:31.429463 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 12 17:13:31.429669 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 12 17:13:31.434564 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 12 17:13:31.434771 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 12 17:13:31.439692 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 12 17:13:31.444698 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 12 17:13:31.450178 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 12 17:13:31.455751 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Sep 12 17:13:31.470604 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 12 17:13:31.496836 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 12 17:13:31.502712 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 12 17:13:31.507389 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 12 17:13:31.507432 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 12 17:13:31.512917 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Sep 12 17:13:31.519283 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 12 17:13:31.525175 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 12 17:13:31.529632 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 12 17:13:31.562877 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 12 17:13:31.568481 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 12 17:13:31.573916 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.574993 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 12 17:13:31.579553 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 12 17:13:31.580570 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 12 17:13:31.585744 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 12 17:13:31.591708 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 12 17:13:31.598947 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 12 17:13:31.604067 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 12 17:13:31.609165 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 12 17:13:31.615986 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 12 17:13:31.620016 systemd-journald[1333]: Time spent on flushing to /var/log/journal/83be458caa434157bb6fd8c8b3b1b1cd is 14.653ms for 939 entries. Sep 12 17:13:31.620016 systemd-journald[1333]: System Journal (/var/log/journal/83be458caa434157bb6fd8c8b3b1b1cd) is 8M, max 2.6G, 2.6G free. Sep 12 17:13:31.669387 systemd-journald[1333]: Received client request to flush runtime journal. Sep 12 17:13:31.625954 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 12 17:13:31.633754 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 12 17:13:31.655899 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Sep 12 17:13:31.662317 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Sep 12 17:13:31.670723 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 12 17:13:31.686117 udevadm[1379]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Sep 12 17:13:31.730303 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 12 17:13:31.731075 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Sep 12 17:13:31.742759 kernel: loop0: detected capacity change from 0 to 147912 Sep 12 17:13:31.772793 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 12 17:13:32.268655 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 12 17:13:32.287889 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 12 17:13:32.440008 systemd-tmpfiles[1385]: ACLs are not supported, ignoring. Sep 12 17:13:32.440029 systemd-tmpfiles[1385]: ACLs are not supported, ignoring. Sep 12 17:13:32.445792 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 12 17:13:32.691769 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 12 17:13:32.784772 kernel: loop1: detected capacity change from 0 to 28272 Sep 12 17:13:33.393764 kernel: loop2: detected capacity change from 0 to 138176 Sep 12 17:13:34.131828 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 12 17:13:34.148957 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 12 17:13:34.176617 systemd-udevd[1392]: Using default interface naming scheme 'v255'. Sep 12 17:13:34.534764 kernel: loop3: detected capacity change from 0 to 147912 Sep 12 17:13:34.554805 kernel: loop4: detected capacity change from 0 to 28272 Sep 12 17:13:34.570774 kernel: loop5: detected capacity change from 0 to 138176 Sep 12 17:13:34.587589 (sd-merge)[1394]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Sep 12 17:13:34.588092 (sd-merge)[1394]: Merged extensions into '/usr'. Sep 12 17:13:34.591506 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 12 17:13:34.607954 systemd[1]: Starting ensure-sysext.service... Sep 12 17:13:34.612082 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 12 17:13:34.656640 systemd-tmpfiles[1397]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 12 17:13:34.656961 systemd-tmpfiles[1397]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 12 17:13:34.657886 systemd-tmpfiles[1397]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 12 17:13:34.658174 systemd-tmpfiles[1397]: ACLs are not supported, ignoring. Sep 12 17:13:34.658246 systemd-tmpfiles[1397]: ACLs are not supported, ignoring. Sep 12 17:13:34.701319 systemd[1]: Reload requested from client PID 1396 ('systemctl') (unit ensure-sysext.service)... Sep 12 17:13:34.701336 systemd[1]: Reloading... Sep 12 17:13:34.727931 systemd-tmpfiles[1397]: Detected autofs mount point /boot during canonicalization of boot. Sep 12 17:13:34.727945 systemd-tmpfiles[1397]: Skipping /boot Sep 12 17:13:34.743038 systemd-tmpfiles[1397]: Detected autofs mount point /boot during canonicalization of boot. Sep 12 17:13:34.743052 systemd-tmpfiles[1397]: Skipping /boot Sep 12 17:13:34.753755 zram_generator::config[1426]: No configuration found. Sep 12 17:13:34.888985 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.989109 systemd[1]: Reloading finished in 287 ms. Sep 12 17:13:35.010712 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 12 17:13:35.032402 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 12 17:13:35.054094 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Sep 12 17:13:35.056481 systemd[1]: Expecting device dev-ptp_hyperv.device - /dev/ptp_hyperv... Sep 12 17:13:35.084501 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 12 17:13:35.093969 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 12 17:13:35.138757 kernel: hv_vmbus: registering driver hv_balloon Sep 12 17:13:35.138829 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Sep 12 17:13:35.149752 kernel: mousedev: PS/2 mouse device common for all mice Sep 12 17:13:35.149809 kernel: hv_vmbus: registering driver hyperv_fb Sep 12 17:13:35.152749 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Sep 12 17:13:35.158007 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 12 17:13:35.160358 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Sep 12 17:13:35.164385 kernel: Console: switching to colour dummy device 80x25 Sep 12 17:13:35.165755 kernel: Console: switching to colour frame buffer device 128x48 Sep 12 17:13:35.171694 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 12 17:13:35.173009 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 12 17:13:35.179065 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 12 17:13:35.184269 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 12 17:13:35.191469 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 12 17:13:35.195844 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 12 17:13:35.195975 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.197299 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 12 17:13:35.204301 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 12 17:13:35.210980 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 12 17:13:35.211756 kernel: EDAC MC: Ver: 3.0.0 Sep 12 17:13:35.215440 systemd[1]: Reached target time-set.target - System Time Set. Sep 12 17:13:35.221669 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 12 17:13:35.226473 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.229618 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 12 17:13:35.229851 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 12 17:13:35.235154 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 12 17:13:35.235348 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 12 17:13:35.240249 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 12 17:13:35.240447 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 12 17:13:35.245967 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 12 17:13:35.246156 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 12 17:13:35.256240 systemd[1]: Finished ensure-sysext.service. Sep 12 17:13:35.259760 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 34 scanned by (udev-worker) (1511) Sep 12 17:13:35.270064 systemd[1]: Condition check resulted in dev-ptp_hyperv.device - /dev/ptp_hyperv being skipped. Sep 12 17:13:35.316583 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 12 17:13:35.339765 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Sep 12 17:13:35.350917 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Sep 12 17:13:35.369924 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Sep 12 17:13:35.375604 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.380398 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.380462 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 12 17:13:35.381467 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 12 17:13:35.388102 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 12 17:13:35.442966 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 12 17:13:35.490569 augenrules[1647]: No rules Sep 12 17:13:35.492083 systemd[1]: audit-rules.service: Deactivated successfully. Sep 12 17:13:35.492330 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 12 17:13:35.512567 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.576219 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 12 17:13:35.596003 lvm[1628]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Sep 12 17:13:35.645587 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Sep 12 17:13:35.651498 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 12 17:13:35.655671 systemd-resolved[1547]: Positive Trust Anchors: Sep 12 17:13:35.655687 systemd-resolved[1547]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 12 17:13:35.655718 systemd-resolved[1547]: 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.667880 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Sep 12 17:13:35.672560 lvm[1658]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Sep 12 17:13:35.710265 systemd-resolved[1547]: Using system hostname 'ci-4230.2.3-a-b3af721f5f'. Sep 12 17:13:35.714452 systemd-networkd[1546]: lo: Link UP Sep 12 17:13:35.714460 systemd-networkd[1546]: lo: Gained carrier Sep 12 17:13:35.715894 systemd-networkd[1546]: Enumeration completed Sep 12 17:13:35.716018 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 12 17:13:35.716277 systemd-networkd[1546]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 12 17:13:35.716288 systemd-networkd[1546]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 12 17:13:35.716842 systemd-networkd[1546]: eth0: Link UP Sep 12 17:13:35.716851 systemd-networkd[1546]: eth0: Gained carrier Sep 12 17:13:35.716865 systemd-networkd[1546]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 12 17:13:35.731882 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Sep 12 17:13:35.733173 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 12 17:13:35.733529 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 12 17:13:35.734493 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Sep 12 17:13:35.735846 systemd[1]: Reached target network.target - Network. Sep 12 17:13:35.736391 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 12 17:13:35.778781 systemd-networkd[1546]: eth0: DHCPv4 address 10.200.8.12/24, gateway 10.200.8.1 acquired from 168.63.129.16 Sep 12 17:13:35.791309 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Sep 12 17:13:36.754236 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 17:13:36.805842 systemd-networkd[1546]: eth0: Gained IPv6LL Sep 12 17:13:36.807833 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 12 17:13:36.813228 systemd[1]: Reached target network-online.target - Network is Online. Sep 12 17:13:37.898023 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 12 17:13:37.904464 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.202998 ldconfig[1363]: /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.215769 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 12 17:13:42.234942 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 12 17:13:42.255113 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 12 17:13:42.260024 systemd[1]: Reached target sysinit.target - System Initialization. Sep 12 17:13:42.264667 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 12 17:13:42.269975 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.275497 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 12 17:13:42.280085 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 12 17:13:42.285437 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 12 17:13:42.290701 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.290742 systemd[1]: Reached target paths.target - Path Units. Sep 12 17:13:42.294580 systemd[1]: Reached target timers.target - Timer Units. Sep 12 17:13:42.323000 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 12 17:13:42.328841 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 12 17:13:42.335436 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Sep 12 17:13:42.340859 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Sep 12 17:13:42.346383 systemd[1]: Reached target ssh-access.target - SSH Access Available. Sep 12 17:13:42.352720 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 12 17:13:42.357435 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Sep 12 17:13:42.363066 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 12 17:13:42.367701 systemd[1]: Reached target sockets.target - Socket Units. Sep 12 17:13:42.371836 systemd[1]: Reached target basic.target - Basic System. Sep 12 17:13:42.375815 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 12 17:13:42.375854 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 12 17:13:42.395832 systemd[1]: Starting chronyd.service - NTP client/server... Sep 12 17:13:42.400442 systemd[1]: Starting containerd.service - containerd container runtime... Sep 12 17:13:42.406067 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Sep 12 17:13:42.411335 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 12 17:13:42.416077 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 12 17:13:42.421257 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 12 17:13:42.425371 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.425410 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.426374 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Sep 12 17:13:42.430846 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.431847 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 12 17:13:42.436194 jq[1681]: false Sep 12 17:13:42.437219 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 12 17:13:42.442308 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 12 17:13:42.448188 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 12 17:13:42.449039 KVP[1683]: KVP starting; pid is:1683 Sep 12 17:13:42.449437 (chronyd)[1674]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Sep 12 17:13:42.451771 KVP[1683]: KVP LIC Version: 3.1 Sep 12 17:13:42.453748 kernel: hv_utils: KVP IC version 4.0 Sep 12 17:13:42.455421 chronyd[1692]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) Sep 12 17:13:42.457131 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 12 17:13:42.462103 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 12 17:13:42.462654 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 12 17:13:42.463281 systemd[1]: Starting update-engine.service - Update Engine... Sep 12 17:13:42.468124 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 12 17:13:42.474954 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 12 17:13:42.475178 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 12 17:13:42.475612 systemd[1]: motdgen.service: Deactivated successfully. Sep 12 17:13:42.475844 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 12 17:13:42.479143 jq[1701]: true Sep 12 17:13:42.480635 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 12 17:13:42.480862 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 12 17:13:42.492692 (ntainerd)[1704]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 12 17:13:42.495810 jq[1703]: true Sep 12 17:13:42.537615 extend-filesystems[1682]: Found loop3 Sep 12 17:13:42.540814 extend-filesystems[1682]: Found loop4 Sep 12 17:13:42.540814 extend-filesystems[1682]: Found loop5 Sep 12 17:13:42.540814 extend-filesystems[1682]: Found sda Sep 12 17:13:42.540814 extend-filesystems[1682]: Found sda1 Sep 12 17:13:42.540814 extend-filesystems[1682]: Found sda2 Sep 12 17:13:42.540814 extend-filesystems[1682]: Found sda3 Sep 12 17:13:42.540814 extend-filesystems[1682]: Found usr Sep 12 17:13:42.540814 extend-filesystems[1682]: Found sda4 Sep 12 17:13:42.540814 extend-filesystems[1682]: Found sda6 Sep 12 17:13:42.567381 extend-filesystems[1682]: Found sda7 Sep 12 17:13:42.567381 extend-filesystems[1682]: Found sda9 Sep 12 17:13:42.567381 extend-filesystems[1682]: Checking size of /dev/sda9 Sep 12 17:13:42.574202 systemd[1]: Started chronyd.service - NTP client/server. Sep 12 17:13:42.571931 chronyd[1692]: Timezone right/UTC failed leap second check, ignoring Sep 12 17:13:42.572111 chronyd[1692]: Loaded seccomp filter (level 2) Sep 12 17:13:42.585065 update_engine[1699]: I20250912 17:13:42.584992 1699 main.cc:92] Flatcar Update Engine starting Sep 12 17:13:42.612179 extend-filesystems[1682]: Old size kept for /dev/sda9 Sep 12 17:13:42.615823 extend-filesystems[1682]: Found sdb Sep 12 17:13:42.615823 extend-filesystems[1682]: Found sdb1 Sep 12 17:13:42.615823 extend-filesystems[1682]: Found sr0 Sep 12 17:13:42.613359 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 12 17:13:42.613600 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 12 17:13:42.632868 systemd-logind[1694]: New seat seat0. Sep 12 17:13:42.634811 sshd_keygen[1730]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 12 17:13:42.634915 systemd-logind[1694]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Sep 12 17:13:42.635535 systemd[1]: Started systemd-logind.service - User Login Management. Sep 12 17:13:42.654740 setup-nvidia[1685]: Downloading Flatcar Container Linux Developer Container for version: 4230.2.3 Sep 12 17:13:42.661617 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 12 17:13:42.668823 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 34 scanned by (udev-worker) (1746) Sep 12 17:13:42.684019 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 12 17:13:42.688797 bash[1729]: Updated "/home/core/.ssh/authorized_keys" Sep 12 17:13:42.689332 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Sep 12 17:13:42.694041 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 12 17:13:42.700419 systemd[1]: issuegen.service: Deactivated successfully. Sep 12 17:13:42.700619 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 12 17:13:42.722872 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Sep 12 17:13:42.746801 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Sep 12 17:13:42.748022 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 12 17:13:42.774556 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 12 17:13:42.792936 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 12 17:13:42.797877 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Sep 12 17:13:42.802647 systemd[1]: Reached target getty.target - Login Prompts. Sep 12 17:13:42.830021 dbus-daemon[1677]: [system] SELinux support is enabled Sep 12 17:13:42.830186 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 12 17:13:42.835298 update_engine[1699]: I20250912 17:13:42.835244 1699 update_check_scheduler.cc:74] Next update check in 6m44s Sep 12 17:13:42.836899 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.836931 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 12 17:13:42.837554 dbus-daemon[1677]: [system] Successfully activated service 'org.freedesktop.systemd1' Sep 12 17:13:42.842359 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.842388 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 12 17:13:42.847763 systemd[1]: Started update-engine.service - Update Engine. Sep 12 17:13:42.864960 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 12 17:13:42.872219 coreos-metadata[1676]: Sep 12 17:13:42.872 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Sep 12 17:13:42.874517 coreos-metadata[1676]: Sep 12 17:13:42.874 INFO Fetch successful Sep 12 17:13:42.874670 coreos-metadata[1676]: Sep 12 17:13:42.874 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Sep 12 17:13:42.878809 coreos-metadata[1676]: Sep 12 17:13:42.878 INFO Fetch successful Sep 12 17:13:42.879128 coreos-metadata[1676]: Sep 12 17:13:42.879 INFO Fetching http://168.63.129.16/machine/a8324aa7-8821-497f-a20f-e3386f095873/6deba718%2D5239%2D43d1%2D8866%2D868ce7f9f1c9.%5Fci%2D4230.2.3%2Da%2Db3af721f5f?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Sep 12 17:13:42.880673 coreos-metadata[1676]: Sep 12 17:13:42.880 INFO Fetch successful Sep 12 17:13:42.880847 coreos-metadata[1676]: Sep 12 17:13:42.880 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.887822 coreos-metadata[1676]: Sep 12 17:13:42.887 INFO Fetch successful Sep 12 17:13:42.904346 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Sep 12 17:13:42.909466 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 12 17:13:43.519357 locksmithd[1829]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 12 17:13:43.827929 containerd[1704]: time="2025-09-12T17:13:43.827845900Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Sep 12 17:13:43.847225 containerd[1704]: time="2025-09-12T17:13:43.847180500Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Sep 12 17:13:43.848711 containerd[1704]: time="2025-09-12T17:13:43.848671000Z" 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.848711 containerd[1704]: time="2025-09-12T17:13:43.848700400Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Sep 12 17:13:43.848811 containerd[1704]: time="2025-09-12T17:13:43.848717600Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Sep 12 17:13:43.848902 containerd[1704]: time="2025-09-12T17:13:43.848881600Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Sep 12 17:13:43.848927 setup-nvidia[1848]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 12 17:13:43.849186 containerd[1704]: time="2025-09-12T17:13:43.848915500Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Sep 12 17:13:43.849186 containerd[1704]: time="2025-09-12T17:13:43.848988500Z" 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.849186 containerd[1704]: time="2025-09-12T17:13:43.849003500Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Sep 12 17:13:43.849253 setup-nvidia[1848]: Dload Upload Total Spent Left Speed Sep 12 17:13:43.849336 containerd[1704]: time="2025-09-12T17:13:43.849191100Z" 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.849336 containerd[1704]: time="2025-09-12T17:13:43.849207600Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Sep 12 17:13:43.849336 containerd[1704]: time="2025-09-12T17:13:43.849220300Z" 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.849336 containerd[1704]: time="2025-09-12T17:13:43.849229800Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Sep 12 17:13:43.849336 containerd[1704]: time="2025-09-12T17:13:43.849312200Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Sep 12 17:13:43.849551 containerd[1704]: time="2025-09-12T17:13:43.849526900Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Sep 12 17:13:43.849678 containerd[1704]: time="2025-09-12T17:13:43.849657400Z" 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.849709 containerd[1704]: time="2025-09-12T17:13:43.849676700Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Sep 12 17:13:43.849800 containerd[1704]: time="2025-09-12T17:13:43.849780600Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Sep 12 17:13:43.849869 containerd[1704]: time="2025-09-12T17:13:43.849852600Z" level=info msg="metadata content store policy set" policy=shared Sep 12 17:13:43.872108 containerd[1704]: time="2025-09-12T17:13:43.872075900Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Sep 12 17:13:43.872164 containerd[1704]: time="2025-09-12T17:13:43.872130600Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Sep 12 17:13:43.872164 containerd[1704]: time="2025-09-12T17:13:43.872148400Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Sep 12 17:13:43.872208 containerd[1704]: time="2025-09-12T17:13:43.872177200Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Sep 12 17:13:43.872208 containerd[1704]: time="2025-09-12T17:13:43.872195300Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Sep 12 17:13:43.872336 containerd[1704]: time="2025-09-12T17:13:43.872313900Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Sep 12 17:13:43.872569 containerd[1704]: time="2025-09-12T17:13:43.872540700Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Sep 12 17:13:43.872698 containerd[1704]: time="2025-09-12T17:13:43.872676500Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Sep 12 17:13:43.872730 containerd[1704]: time="2025-09-12T17:13:43.872700500Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Sep 12 17:13:43.872730 containerd[1704]: time="2025-09-12T17:13:43.872715800Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Sep 12 17:13:43.872803 containerd[1704]: time="2025-09-12T17:13:43.872729900Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Sep 12 17:13:43.872803 containerd[1704]: time="2025-09-12T17:13:43.872764300Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Sep 12 17:13:43.872803 containerd[1704]: time="2025-09-12T17:13:43.872790700Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Sep 12 17:13:43.872868 containerd[1704]: time="2025-09-12T17:13:43.872806900Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Sep 12 17:13:43.872868 containerd[1704]: time="2025-09-12T17:13:43.872821200Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Sep 12 17:13:43.872868 containerd[1704]: time="2025-09-12T17:13:43.872834700Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Sep 12 17:13:43.872868 containerd[1704]: time="2025-09-12T17:13:43.872846200Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Sep 12 17:13:43.872868 containerd[1704]: time="2025-09-12T17:13:43.872858400Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Sep 12 17:13:43.872971 containerd[1704]: time="2025-09-12T17:13:43.872877700Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Sep 12 17:13:43.872971 containerd[1704]: time="2025-09-12T17:13:43.872891000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Sep 12 17:13:43.872971 containerd[1704]: time="2025-09-12T17:13:43.872902800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Sep 12 17:13:43.872971 containerd[1704]: time="2025-09-12T17:13:43.872914300Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Sep 12 17:13:43.872971 containerd[1704]: time="2025-09-12T17:13:43.872926200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Sep 12 17:13:43.872971 containerd[1704]: time="2025-09-12T17:13:43.872938000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Sep 12 17:13:43.872971 containerd[1704]: time="2025-09-12T17:13:43.872948900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Sep 12 17:13:43.872971 containerd[1704]: time="2025-09-12T17:13:43.872960700Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Sep 12 17:13:43.873134 containerd[1704]: time="2025-09-12T17:13:43.872973000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Sep 12 17:13:43.873134 containerd[1704]: time="2025-09-12T17:13:43.872986800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Sep 12 17:13:43.873134 containerd[1704]: time="2025-09-12T17:13:43.872997800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Sep 12 17:13:43.873134 containerd[1704]: time="2025-09-12T17:13:43.873009000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Sep 12 17:13:43.873134 containerd[1704]: time="2025-09-12T17:13:43.873021200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Sep 12 17:13:43.873134 containerd[1704]: time="2025-09-12T17:13:43.873034200Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Sep 12 17:13:43.873134 containerd[1704]: time="2025-09-12T17:13:43.873052500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Sep 12 17:13:43.873134 containerd[1704]: time="2025-09-12T17:13:43.873065800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Sep 12 17:13:43.873134 containerd[1704]: time="2025-09-12T17:13:43.873076300Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Sep 12 17:13:43.873134 containerd[1704]: time="2025-09-12T17:13:43.873130700Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Sep 12 17:13:43.873331 containerd[1704]: time="2025-09-12T17:13:43.873150300Z" 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.873331 containerd[1704]: time="2025-09-12T17:13:43.873161600Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Sep 12 17:13:43.873331 containerd[1704]: time="2025-09-12T17:13:43.873172800Z" 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.873331 containerd[1704]: time="2025-09-12T17:13:43.873181700Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Sep 12 17:13:43.873331 containerd[1704]: time="2025-09-12T17:13:43.873194000Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Sep 12 17:13:43.873331 containerd[1704]: time="2025-09-12T17:13:43.873203300Z" level=info msg="NRI interface is disabled by configuration." Sep 12 17:13:43.873331 containerd[1704]: time="2025-09-12T17:13:43.873213800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Sep 12 17:13:43.873827 containerd[1704]: time="2025-09-12T17:13:43.873649200Z" 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.873827 containerd[1704]: time="2025-09-12T17:13:43.873718900Z" level=info msg="Connect containerd service" Sep 12 17:13:43.873827 containerd[1704]: time="2025-09-12T17:13:43.873790600Z" level=info msg="using legacy CRI server" Sep 12 17:13:43.874078 containerd[1704]: time="2025-09-12T17:13:43.873861500Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 12 17:13:43.874240 containerd[1704]: time="2025-09-12T17:13:43.874213300Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Sep 12 17:13:43.874806 containerd[1704]: time="2025-09-12T17:13:43.874778500Z" 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.874958 containerd[1704]: time="2025-09-12T17:13:43.874919400Z" level=info msg="Start subscribing containerd event" Sep 12 17:13:43.875004 containerd[1704]: time="2025-09-12T17:13:43.874975000Z" level=info msg="Start recovering state" Sep 12 17:13:43.875062 containerd[1704]: time="2025-09-12T17:13:43.875042500Z" level=info msg="Start event monitor" Sep 12 17:13:43.875094 containerd[1704]: time="2025-09-12T17:13:43.875079700Z" level=info msg="Start snapshots syncer" Sep 12 17:13:43.875117 containerd[1704]: time="2025-09-12T17:13:43.875092000Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 12 17:13:43.875140 containerd[1704]: time="2025-09-12T17:13:43.875094400Z" level=info msg="Start cni network conf syncer for default" Sep 12 17:13:43.875165 containerd[1704]: time="2025-09-12T17:13:43.875147000Z" level=info msg="Start streaming server" Sep 12 17:13:43.875226 containerd[1704]: time="2025-09-12T17:13:43.875150800Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 12 17:13:43.875317 containerd[1704]: time="2025-09-12T17:13:43.875294800Z" level=info msg="containerd successfully booted in 0.048279s" Sep 12 17:13:43.875370 systemd[1]: Started containerd.service - containerd container runtime. Sep 12 17:13:45.872108 waagent[1821]: 2025-09-12T17:13:45.872008Z INFO Daemon Daemon Azure Linux Agent Version: 2.9.1.1 Sep 12 17:13:45.876925 waagent[1821]: 2025-09-12T17:13:45.876857Z INFO Daemon Daemon OS: flatcar 4230.2.3 Sep 12 17:13:45.880581 waagent[1821]: 2025-09-12T17:13:45.880524Z INFO Daemon Daemon Python: 3.11.11 Sep 12 17:13:45.884451 waagent[1821]: 2025-09-12T17:13:45.884374Z INFO Daemon Daemon Run daemon Sep 12 17:13:45.888264 waagent[1821]: 2025-09-12T17:13:45.888202Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4230.2.3' Sep 12 17:13:45.896872 waagent[1821]: 2025-09-12T17:13:45.896813Z INFO Daemon Daemon Using waagent for provisioning Sep 12 17:13:45.902016 waagent[1821]: 2025-09-12T17:13:45.901966Z INFO Daemon Daemon Activate resource disk Sep 12 17:13:45.907238 waagent[1821]: 2025-09-12T17:13:45.907178Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Sep 12 17:13:45.916419 waagent[1821]: 2025-09-12T17:13:45.916365Z INFO Daemon Daemon Found device: sdb Sep 12 17:13:45.926725 waagent[1821]: 2025-09-12T17:13:45.926653Z INFO Daemon Daemon Examining partition table Sep 12 17:13:46.037628 waagent[1821]: 2025-09-12T17:13:46.037550Z INFO Daemon Daemon GPT not detected, determining filesystem Sep 12 17:13:46.100861 waagent[1821]: 2025-09-12T17:13:46.100765Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Sep 12 17:13:46.106651 waagent[1821]: 2025-09-12T17:13:46.106593Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Sep 12 17:13:46.624766 kernel: sdb: sdb1 Sep 12 17:13:46.658887 waagent[1821]: 2025-09-12T17:13:46.658791Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Sep 12 17:13:46.677291 waagent[1821]: 2025-09-12T17:13:46.677215Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Sep 12 17:13:46.677773 kernel: sdb: sdb1 Sep 12 17:13:50.932775 login[1825]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Sep 12 17:13:50.934500 login[1826]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Sep 12 17:13:50.945596 systemd-logind[1694]: New session 1 of user core. Sep 12 17:13:50.946671 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 12 17:13:50.959952 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 12 17:13:51.036255 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 12 17:13:51.051997 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 12 17:13:51.132592 (systemd)[1921]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 12 17:13:51.135024 systemd-logind[1694]: New session c1 of user core. Sep 12 17:13:51.851515 waagent[1821]: 2025-09-12T17:13:51.851415Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Sep 12 17:13:51.875478 waagent[1821]: 2025-09-12T17:13:51.875417Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Sep 12 17:13:51.875751 kernel: EXT4-fs (sdb1): mounted filesystem 5215caf0-790e-4cb5-befd-0e6bcf9d9424 r/w with ordered data mode. Quota mode: none. Sep 12 17:13:51.881583 waagent[1821]: 2025-09-12T17:13:51.881531Z INFO Daemon Daemon Clean protocol and wireserver endpoint Sep 12 17:13:51.887972 waagent[1821]: 2025-09-12T17:13:51.887919Z INFO Daemon Daemon Running default provisioning handler Sep 12 17:13:51.900128 waagent[1821]: 2025-09-12T17:13:51.899013Z 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.910436 waagent[1821]: 2025-09-12T17:13:51.910381Z 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.910641 waagent[1821]: 2025-09-12T17:13:51.910594Z INFO Daemon Daemon cloud-init is enabled: False Sep 12 17:13:51.912151 waagent[1821]: 2025-09-12T17:13:51.912107Z INFO Daemon Daemon Copying ovf-env.xml Sep 12 17:13:51.933208 login[1825]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Sep 12 17:13:51.937343 systemd-logind[1694]: New session 2 of user core. Sep 12 17:13:52.045822 waagent[1821]: 2025-09-12T17:13:52.045709Z INFO Daemon Daemon Successfully mounted dvd Sep 12 17:13:52.051939 systemd[1921]: Queued start job for default target default.target. Sep 12 17:13:52.064359 systemd[1921]: Created slice app.slice - User Application Slice. Sep 12 17:13:52.064389 systemd[1921]: Reached target paths.target - Paths. Sep 12 17:13:52.064439 systemd[1921]: Reached target timers.target - Timers. Sep 12 17:13:52.065719 systemd[1921]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 12 17:13:52.071340 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Sep 12 17:13:52.071578 waagent[1821]: 2025-09-12T17:13:52.071404Z INFO Daemon Daemon Detect protocol endpoint Sep 12 17:13:52.071726 waagent[1821]: 2025-09-12T17:13:52.071674Z INFO Daemon Daemon Clean protocol and wireserver endpoint Sep 12 17:13:52.072529 waagent[1821]: 2025-09-12T17:13:52.072480Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Sep 12 17:13:52.073986 waagent[1821]: 2025-09-12T17:13:52.073946Z INFO Daemon Daemon Test for route to 168.63.129.16 Sep 12 17:13:52.074816 waagent[1821]: 2025-09-12T17:13:52.074772Z INFO Daemon Daemon Route to 168.63.129.16 exists Sep 12 17:13:52.076290 waagent[1821]: 2025-09-12T17:13:52.076236Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Sep 12 17:13:52.077603 systemd[1921]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 12 17:13:52.077730 systemd[1921]: Reached target sockets.target - Sockets. Sep 12 17:13:52.077801 systemd[1921]: Reached target basic.target - Basic System. Sep 12 17:13:52.077856 systemd[1921]: Reached target default.target - Main User Target. Sep 12 17:13:52.077891 systemd[1921]: Startup finished in 936ms. Sep 12 17:13:52.078094 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 12 17:13:52.079677 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 12 17:13:52.080430 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 12 17:13:52.113381 waagent[1821]: 2025-09-12T17:13:52.113283Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Sep 12 17:13:52.113745 waagent[1821]: 2025-09-12T17:13:52.113699Z INFO Daemon Daemon Wire protocol version:2012-11-30 Sep 12 17:13:52.114239 waagent[1821]: 2025-09-12T17:13:52.114199Z INFO Daemon Daemon Server preferred version:2015-04-05 Sep 12 17:13:52.277320 waagent[1821]: 2025-09-12T17:13:52.277223Z INFO Daemon Daemon Initializing goal state during protocol detection Sep 12 17:13:52.277685 waagent[1821]: 2025-09-12T17:13:52.277631Z INFO Daemon Daemon Forcing an update of the goal state. Sep 12 17:13:52.281678 waagent[1821]: 2025-09-12T17:13:52.281613Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Sep 12 17:13:52.318462 waagent[1821]: 2025-09-12T17:13:52.318410Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.175 Sep 12 17:13:52.319091 waagent[1821]: 2025-09-12T17:13:52.319042Z INFO Daemon Sep 12 17:13:52.319435 waagent[1821]: 2025-09-12T17:13:52.319390Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: f5414e3a-07f4-4dbe-91a4-3c1540de4ca9 eTag: 14098748708986356371 source: Fabric] Sep 12 17:13:52.321226 waagent[1821]: 2025-09-12T17:13:52.321174Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Sep 12 17:13:52.322291 waagent[1821]: 2025-09-12T17:13:52.322241Z INFO Daemon Sep 12 17:13:52.322478 waagent[1821]: 2025-09-12T17:13:52.322438Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Sep 12 17:13:52.327705 waagent[1821]: 2025-09-12T17:13:52.327664Z INFO Daemon Daemon Downloading artifacts profile blob Sep 12 17:13:52.467024 waagent[1821]: 2025-09-12T17:13:52.466920Z INFO Daemon Downloaded certificate {'thumbprint': 'DEC9ADD2F201ED4A0D51B21B3BF854935EED6C22', 'hasPrivateKey': True} Sep 12 17:13:52.467638 waagent[1821]: 2025-09-12T17:13:52.467586Z INFO Daemon Fetch goal state completed Sep 12 17:13:52.505641 waagent[1821]: 2025-09-12T17:13:52.505591Z INFO Daemon Daemon Starting provisioning Sep 12 17:13:52.505880 waagent[1821]: 2025-09-12T17:13:52.505825Z INFO Daemon Daemon Handle ovf-env.xml. Sep 12 17:13:52.506630 waagent[1821]: 2025-09-12T17:13:52.506544Z INFO Daemon Daemon Set hostname [ci-4230.2.3-a-b3af721f5f] Sep 12 17:13:52.524076 waagent[1821]: 2025-09-12T17:13:52.524021Z INFO Daemon Daemon Publish hostname [ci-4230.2.3-a-b3af721f5f] Sep 12 17:13:52.536845 waagent[1821]: 2025-09-12T17:13:52.524386Z INFO Daemon Daemon Examine /proc/net/route for primary interface Sep 12 17:13:52.536845 waagent[1821]: 2025-09-12T17:13:52.525908Z INFO Daemon Daemon Primary interface is [eth0] Sep 12 17:13:52.551553 systemd-networkd[1546]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 12 17:13:52.551562 systemd-networkd[1546]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 12 17:13:52.551589 systemd-networkd[1546]: eth0: DHCP lease lost Sep 12 17:13:52.552054 waagent[1821]: 2025-09-12T17:13:52.551999Z INFO Daemon Daemon Create user account if not exists Sep 12 17:13:52.552307 waagent[1821]: 2025-09-12T17:13:52.552262Z INFO Daemon Daemon User core already exists, skip useradd Sep 12 17:13:52.553790 waagent[1821]: 2025-09-12T17:13:52.553748Z INFO Daemon Daemon Configure sudoer Sep 12 17:13:52.554815 waagent[1821]: 2025-09-12T17:13:52.554767Z INFO Daemon Daemon Configure sshd Sep 12 17:13:52.555489 waagent[1821]: 2025-09-12T17:13:52.555444Z 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.556801 waagent[1821]: 2025-09-12T17:13:52.556760Z INFO Daemon Daemon Deploy ssh public key. Sep 12 17:13:52.615777 systemd-networkd[1546]: eth0: DHCPv4 address 10.200.8.12/24, gateway 10.200.8.1 acquired from 168.63.129.16 Sep 12 17:13:53.713260 waagent[1821]: 2025-09-12T17:13:53.713189Z INFO Daemon Daemon Provisioning complete Sep 12 17:13:53.722964 waagent[1821]: 2025-09-12T17:13:53.722883Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Sep 12 17:13:53.734871 waagent[1821]: 2025-09-12T17:13:53.734819Z 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.735619 waagent[1821]: 2025-09-12T17:13:53.735575Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.9.1.1 is the most current agent Sep 12 17:13:53.888541 waagent[1980]: 2025-09-12T17:13:53.888445Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.9.1.1) Sep 12 17:13:53.888935 waagent[1980]: 2025-09-12T17:13:53.888602Z INFO ExtHandler ExtHandler OS: flatcar 4230.2.3 Sep 12 17:13:53.888935 waagent[1980]: 2025-09-12T17:13:53.888684Z INFO ExtHandler ExtHandler Python: 3.11.11 Sep 12 17:13:53.967616 waagent[1980]: 2025-09-12T17:13:53.967483Z 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.967784 waagent[1980]: 2025-09-12T17:13:53.967718Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 12 17:13:53.967877 waagent[1980]: 2025-09-12T17:13:53.967834Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 12 17:13:53.975610 waagent[1980]: 2025-09-12T17:13:53.975545Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Sep 12 17:13:53.980578 waagent[1980]: 2025-09-12T17:13:53.980529Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.175 Sep 12 17:13:53.981039 waagent[1980]: 2025-09-12T17:13:53.980990Z INFO ExtHandler Sep 12 17:13:53.981179 waagent[1980]: 2025-09-12T17:13:53.981128Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: ca660ecd-2c27-4881-9323-79a71ddc1575 eTag: 14098748708986356371 source: Fabric] Sep 12 17:13:53.981519 waagent[1980]: 2025-09-12T17:13:53.981471Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Sep 12 17:13:53.982188 waagent[1980]: 2025-09-12T17:13:53.982138Z INFO ExtHandler Sep 12 17:13:53.982271 waagent[1980]: 2025-09-12T17:13:53.982233Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Sep 12 17:13:53.985876 waagent[1980]: 2025-09-12T17:13:53.985832Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Sep 12 17:13:54.058782 waagent[1980]: 2025-09-12T17:13:54.058708Z INFO ExtHandler Downloaded certificate {'thumbprint': 'DEC9ADD2F201ED4A0D51B21B3BF854935EED6C22', 'hasPrivateKey': True} Sep 12 17:13:54.059250 waagent[1980]: 2025-09-12T17:13:54.059200Z INFO ExtHandler Fetch goal state completed Sep 12 17:13:54.070123 waagent[1980]: 2025-09-12T17:13:54.070070Z INFO ExtHandler ExtHandler WALinuxAgent-2.9.1.1 running as process 1980 Sep 12 17:13:54.070270 waagent[1980]: 2025-09-12T17:13:54.070226Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Sep 12 17:13:54.072048 waagent[1980]: 2025-09-12T17:13:54.071997Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '4230.2.3', '', 'Flatcar Container Linux by Kinvolk'] Sep 12 17:13:54.072423 waagent[1980]: 2025-09-12T17:13:54.072378Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Sep 12 17:13:54.277169 waagent[1980]: 2025-09-12T17:13:54.277125Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Sep 12 17:13:54.277390 waagent[1980]: 2025-09-12T17:13:54.277346Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Sep 12 17:13:54.283982 waagent[1980]: 2025-09-12T17:13:54.283944Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Sep 12 17:13:54.291268 systemd[1]: Reload requested from client PID 1993 ('systemctl') (unit waagent.service)... Sep 12 17:13:54.291285 systemd[1]: Reloading... Sep 12 17:13:54.356818 zram_generator::config[2033]: No configuration found. Sep 12 17:13:54.500145 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.645117 systemd[1]: Reloading finished in 353 ms. Sep 12 17:13:54.656897 waagent[1980]: 2025-09-12T17:13:54.656586Z INFO ExtHandler ExtHandler Executing systemctl daemon-reload for setting up waagent-network-setup.service Sep 12 17:13:54.662521 systemd[1]: Reload requested from client PID 2090 ('systemctl') (unit waagent.service)... Sep 12 17:13:54.662536 systemd[1]: Reloading... Sep 12 17:13:54.724763 zram_generator::config[2133]: No configuration found. Sep 12 17:13:54.854135 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.997713 systemd[1]: Reloading finished in 334 ms. Sep 12 17:13:55.015541 waagent[1980]: 2025-09-12T17:13:55.015457Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Sep 12 17:13:55.015901 waagent[1980]: 2025-09-12T17:13:55.015615Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Sep 12 17:13:55.672629 waagent[1980]: 2025-09-12T17:13:55.672536Z 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.673333 waagent[1980]: 2025-09-12T17:13:55.673147Z 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.674055 waagent[1980]: 2025-09-12T17:13:55.674001Z INFO ExtHandler ExtHandler Starting env monitor service. Sep 12 17:13:55.674856 waagent[1980]: 2025-09-12T17:13:55.674800Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 12 17:13:55.675042 waagent[1980]: 2025-09-12T17:13:55.674992Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Sep 12 17:13:55.675319 waagent[1980]: 2025-09-12T17:13:55.675273Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 12 17:13:55.675628 waagent[1980]: 2025-09-12T17:13:55.675576Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 12 17:13:55.675842 waagent[1980]: 2025-09-12T17:13:55.675774Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Sep 12 17:13:55.676042 waagent[1980]: 2025-09-12T17:13:55.675986Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Sep 12 17:13:55.676098 waagent[1980]: 2025-09-12T17:13:55.676042Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Sep 12 17:13:55.676451 waagent[1980]: 2025-09-12T17:13:55.676406Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 12 17:13:55.676945 waagent[1980]: 2025-09-12T17:13:55.676878Z INFO EnvHandler ExtHandler Configure routes Sep 12 17:13:55.677054 waagent[1980]: 2025-09-12T17:13:55.676986Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Sep 12 17:13:55.677138 waagent[1980]: 2025-09-12T17:13:55.677089Z 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.677504 waagent[1980]: 2025-09-12T17:13:55.677459Z INFO EnvHandler ExtHandler Gateway:None Sep 12 17:13:55.677654 waagent[1980]: 2025-09-12T17:13:55.677594Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Sep 12 17:13:55.677726 waagent[1980]: 2025-09-12T17:13:55.677674Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Sep 12 17:13:55.677726 waagent[1980]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Sep 12 17:13:55.677726 waagent[1980]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Sep 12 17:13:55.677726 waagent[1980]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Sep 12 17:13:55.677726 waagent[1980]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Sep 12 17:13:55.677726 waagent[1980]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Sep 12 17:13:55.677726 waagent[1980]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Sep 12 17:13:55.678379 waagent[1980]: 2025-09-12T17:13:55.678335Z INFO EnvHandler ExtHandler Routes:None Sep 12 17:13:55.683948 waagent[1980]: 2025-09-12T17:13:55.683893Z INFO ExtHandler ExtHandler Sep 12 17:13:55.684326 waagent[1980]: 2025-09-12T17:13:55.684279Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 9e4d3bf1-a472-4b41-acc8-8a3f1c22642a correlation 43ea0747-b4c1-4e10-80d7-98e39fcec8fb created: 2025-09-12T17:12:16.061340Z] Sep 12 17:13:55.685381 waagent[1980]: 2025-09-12T17:13:55.685332Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Sep 12 17:13:55.687307 waagent[1980]: 2025-09-12T17:13:55.687258Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Sep 12 17:13:55.734882 waagent[1980]: 2025-09-12T17:13:55.734816Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.9.1.1 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: C9F7595E-BA74-498F-A23F-9FB7A9AB47D8;DroppedPackets: 0;UpdateGSErrors: 0;AutoUpdate: 0] Sep 12 17:13:55.754094 waagent[1980]: 2025-09-12T17:13:55.754028Z INFO MonitorHandler ExtHandler Network interfaces: Sep 12 17:13:55.754094 waagent[1980]: Executing ['ip', '-a', '-o', 'link']: Sep 12 17:13:55.754094 waagent[1980]: 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.754094 waagent[1980]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:1e:52:35:d6:b5 brd ff:ff:ff:ff:ff:ff Sep 12 17:13:55.754094 waagent[1980]: Executing ['ip', '-4', '-a', '-o', 'address']: Sep 12 17:13:55.754094 waagent[1980]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Sep 12 17:13:55.754094 waagent[1980]: 2: eth0 inet 10.200.8.12/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Sep 12 17:13:55.754094 waagent[1980]: Executing ['ip', '-6', '-a', '-o', 'address']: Sep 12 17:13:55.754094 waagent[1980]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Sep 12 17:13:55.754094 waagent[1980]: 2: eth0 inet6 fe80::7e1e:52ff:fe35:d6b5/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Sep 12 17:13:55.870604 waagent[1980]: 2025-09-12T17:13:55.870531Z INFO EnvHandler ExtHandler Successfully added Azure fabric firewall rules. Current Firewall rules: Sep 12 17:13:55.870604 waagent[1980]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Sep 12 17:13:55.870604 waagent[1980]: pkts bytes target prot opt in out source destination Sep 12 17:13:55.870604 waagent[1980]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Sep 12 17:13:55.870604 waagent[1980]: pkts bytes target prot opt in out source destination Sep 12 17:13:55.870604 waagent[1980]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Sep 12 17:13:55.870604 waagent[1980]: pkts bytes target prot opt in out source destination Sep 12 17:13:55.870604 waagent[1980]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Sep 12 17:13:55.870604 waagent[1980]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Sep 12 17:13:55.870604 waagent[1980]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Sep 12 17:13:55.874132 waagent[1980]: 2025-09-12T17:13:55.874077Z INFO EnvHandler ExtHandler Current Firewall rules: Sep 12 17:13:55.874132 waagent[1980]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Sep 12 17:13:55.874132 waagent[1980]: pkts bytes target prot opt in out source destination Sep 12 17:13:55.874132 waagent[1980]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Sep 12 17:13:55.874132 waagent[1980]: pkts bytes target prot opt in out source destination Sep 12 17:13:55.874132 waagent[1980]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Sep 12 17:13:55.874132 waagent[1980]: pkts bytes target prot opt in out source destination Sep 12 17:13:55.874132 waagent[1980]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Sep 12 17:13:55.874132 waagent[1980]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Sep 12 17:13:55.874132 waagent[1980]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Sep 12 17:13:55.874390 waagent[1980]: 2025-09-12T17:13:55.874349Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Sep 12 17:13:55.874546 waagent[1980]: 2025-09-12T17:13:55.874508Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Sep 12 17:14:03.209945 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 12 17:14:03.224038 systemd[1]: Started sshd@0-10.200.8.12:22-10.200.16.10:55802.service - OpenSSH per-connection server daemon (10.200.16.10:55802). Sep 12 17:14:04.080280 sshd[2221]: Accepted publickey for core from 10.200.16.10 port 55802 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:14:04.081706 sshd-session[2221]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:14:04.085890 systemd-logind[1694]: New session 3 of user core. Sep 12 17:14:04.104864 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 12 17:14:04.637411 systemd[1]: Started sshd@1-10.200.8.12:22-10.200.16.10:55812.service - OpenSSH per-connection server daemon (10.200.16.10:55812). Sep 12 17:14:05.456410 sshd[2226]: Accepted publickey for core from 10.200.16.10 port 55812 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:14:05.457723 sshd-session[2226]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:14:05.461967 systemd-logind[1694]: New session 4 of user core. Sep 12 17:14:05.474860 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 12 17:14:05.905904 sshd[2228]: Connection closed by 10.200.16.10 port 55812 Sep 12 17:14:05.906313 sshd-session[2226]: pam_unix(sshd:session): session closed for user core Sep 12 17:14:05.909684 systemd[1]: sshd@1-10.200.8.12:22-10.200.16.10:55812.service: Deactivated successfully. Sep 12 17:14:05.911389 systemd[1]: session-4.scope: Deactivated successfully. Sep 12 17:14:05.912073 systemd-logind[1694]: Session 4 logged out. Waiting for processes to exit. Sep 12 17:14:05.912989 systemd-logind[1694]: Removed session 4. Sep 12 17:14:06.024202 systemd[1]: Started sshd@2-10.200.8.12:22-10.200.16.10:55814.service - OpenSSH per-connection server daemon (10.200.16.10:55814). Sep 12 17:14:06.216859 setup-nvidia[1848]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 482M 0 816k 0 0 671k 0 0:12:15 0:00:01 0:12:14 671k\u000d 3 482M 3 17.8M 0 0 8328k 0 0:00:59 0:00:02 0:00:57 8327k\u000d 8 482M 8 38.9M 0 0 12.1M 0 0:00:39 0:00:03 0:00:36 12.1M\u000d 12 482M 12 59.0M 0 0 14.0M 0 0:00:34 0:00:04 0:00:30 14.0M\u000d 17 482M 17 83.1M 0 0 15.8M 0 0:00:30 0:00:05 0:00:25 16.9M\u000d 22 482M 22 108M 0 0 17.3M 0 0:00:27 0:00:06 0:00:21 21.3M\u000d 27 482M 27 133M 0 0 18.4M 0 0:00:26 0:00:07 0:00:19 22.8M\u000d 32 482M 32 155M 0 0 18.9M 0 0:00:25 0:00:08 0:00:17 23.3M\u000d 36 482M 36 176M 0 0 19.1M 0 0:00:25 0:00:09 0:00:16 23.4M\u000d 41 482M 41 200M 0 0 19.5M 0 0:00:24 0:00:10 0:00:14 23.4M\u000d 46 482M 46 224M 0 0 19.8M 0 0:00:24 0:00:11 0:00:13 23.0M\u000d 51 482M 51 247M 0 0 20.1M 0 0:00:23 0:00:12 0:00:11 22.7M\u000d 56 482M 56 270M 0 0 20.3M 0 0:00:23 0:00:13 0:00:10 22.4M\u000d 60 482M 60 294M 0 0 20.7M 0 0:00:23 0:00:14 0:00:09 23.5M\u000d 65 482M 65 314M 0 0 20.7M 0 0:00:23 0:00:15 0:00:08 22.9M\u000d 70 482M 70 338M 0 0 20.8M 0 0:00:23 0:00:16 0:00:07 23.0M\u000d 75 482M 75 362M 0 0 21.0M 0 0:00:22 0:00:17 0:00:05 23.1M\u000d 79 482M 79 385M 0 0 21.0M 0 0:00:22 0:00:18 0:00:04 23.2M\u000d 84 482M 84 408M 0 0 21.1M 0 0:00:22 0:00:19 0:00:03 22.5M\u000d 89 482M 89 431M 0 0 21.2M 0 0:00:22 0:00:20 0:00:02 22.8M\u000d 94 482M 94 454M 0 0 21.4M 0 0:00:22 0:00:21 0:00:01 23.3M\u000d 98 482M 98 476M 0 0 21.4M 0 0:00:22 0:00:22 --:--:-- 22.9M\u000d100 482M 100 482M 0 0 21.5M 0 0:00:22 0:00:22 --:--:-- 23.6M Sep 12 17:14:06.351821 chronyd[1692]: Selected source PHC0 Sep 12 17:14:06.657811 sshd[2234]: Accepted publickey for core from 10.200.16.10 port 55814 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:14:06.659630 sshd-session[2234]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:14:06.677323 systemd-logind[1694]: New session 5 of user core. Sep 12 17:14:06.683870 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 12 17:14:07.114809 sshd[2246]: Connection closed by 10.200.16.10 port 55814 Sep 12 17:14:07.113778 sshd-session[2234]: pam_unix(sshd:session): session closed for user core Sep 12 17:14:07.119645 systemd[1]: sshd@2-10.200.8.12:22-10.200.16.10:55814.service: Deactivated successfully. Sep 12 17:14:07.120965 systemd-logind[1694]: Session 5 logged out. Waiting for processes to exit. Sep 12 17:14:07.125508 systemd[1]: session-5.scope: Deactivated successfully. Sep 12 17:14:07.127248 systemd-logind[1694]: Removed session 5. Sep 12 17:14:16.986727 setup-nvidia[1685]: Downloading NVIDIA 535.230.02 Driver Sep 12 17:14:16.991211 setup-nvidia[2252]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 12 17:14:16.991453 setup-nvidia[2252]: Dload Upload Total Spent Left Speed Sep 12 17:14:17.225300 systemd[1]: Started sshd@3-10.200.8.12:22-10.200.16.10:56992.service - OpenSSH per-connection server daemon (10.200.16.10:56992). Sep 12 17:14:17.854523 sshd[2254]: Accepted publickey for core from 10.200.16.10 port 56992 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:14:17.855918 sshd-session[2254]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:14:17.860133 systemd-logind[1694]: New session 6 of user core. Sep 12 17:14:17.873867 systemd[1]: Started session-6.scope - Session 6 of User core. Sep 12 17:14:18.296579 sshd[2256]: Connection closed by 10.200.16.10 port 56992 Sep 12 17:14:18.297001 sshd-session[2254]: pam_unix(sshd:session): session closed for user core Sep 12 17:14:18.300593 systemd[1]: sshd@3-10.200.8.12:22-10.200.16.10:56992.service: Deactivated successfully. Sep 12 17:14:18.302536 systemd[1]: session-6.scope: Deactivated successfully. Sep 12 17:14:18.303264 systemd-logind[1694]: Session 6 logged out. Waiting for processes to exit. Sep 12 17:14:18.304110 systemd-logind[1694]: Removed session 6. Sep 12 17:14:18.412361 systemd[1]: Started sshd@4-10.200.8.12:22-10.200.16.10:57002.service - OpenSSH per-connection server daemon (10.200.16.10:57002). Sep 12 17:14:18.836008 setup-nvidia[2252]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 48 326M 48 158M 0 0 150M 0 0:00:02 0:00:01 0:00:01 150M\u000d100 326M 100 326M 0 0 176M 0 0:00:01 0:00:01 --:--:-- 176M Sep 12 17:14:19.043317 sshd[2262]: Accepted publickey for core from 10.200.16.10 port 57002 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:14:19.044687 sshd-session[2262]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:14:19.049109 systemd-logind[1694]: New session 7 of user core. Sep 12 17:14:19.072935 systemd[1]: Started session-7.scope - Session 7 of User core. Sep 12 17:14:19.494201 sshd[2264]: Connection closed by 10.200.16.10 port 57002 Sep 12 17:14:19.494577 sshd-session[2262]: pam_unix(sshd:session): session closed for user core Sep 12 17:14:19.497698 systemd[1]: sshd@4-10.200.8.12:22-10.200.16.10:57002.service: Deactivated successfully. Sep 12 17:14:19.499326 systemd[1]: session-7.scope: Deactivated successfully. Sep 12 17:14:19.500071 systemd-logind[1694]: Session 7 logged out. Waiting for processes to exit. Sep 12 17:14:19.500898 systemd-logind[1694]: Removed session 7. Sep 12 17:14:20.337808 setup-nvidia[1685]: Extract the NVIDIA Driver Installer 535.230.02 Sep 12 17:14:20.337808 setup-nvidia[1685]: /opt/nvidia/workdir/nvidia-workdir / Sep 12 17:14:20.398799 setup-nvidia[2271]: Creating directory NVIDIA-Linux-x86_64-535.230.02 Sep 12 17:14:20.767159 setup-nvidia[2271]: Verifying archive integrity... OK Sep 12 17:14:23.236835 setup-nvidia[2271]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.230.02 Sep 12 17:14:23.255696 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Sep 12 17:14:25.114578 setup-nvidia[2297]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Sep 12 17:14:26.821343 setup-nvidia[1685]: / Sep 12 17:14:26.821626 setup-nvidia[1685]: Spawn system-nspawn container to install the NVIDIA drivers Sep 12 17:14:27.381381 sudo[2304]: 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.383248 sudo[2304]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 12 17:14:27.391761 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Sep 12 17:14:27.406975 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Sep 12 17:14:27.424060 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Sep 12 17:14:27.427344 systemd[1]: Starting user@0.service - User Manager for UID 0... Sep 12 17:14:27.433945 (systemd)[2307]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Sep 12 17:14:27.436143 systemd-logind[1694]: New session c3 of user root. Sep 12 17:14:27.632973 systemd[2307]: Queued start job for default target default.target. Sep 12 17:14:27.642029 systemd[2307]: Created slice app.slice - User Application Slice. Sep 12 17:14:27.642063 systemd[2307]: Reached target paths.target - Paths. Sep 12 17:14:27.642120 systemd[2307]: Reached target timers.target - Timers. Sep 12 17:14:27.643455 systemd[2307]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 12 17:14:27.654337 systemd[2307]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 12 17:14:27.654465 systemd[2307]: Reached target sockets.target - Sockets. Sep 12 17:14:27.654525 systemd[2307]: Reached target basic.target - Basic System. Sep 12 17:14:27.654575 systemd[2307]: Reached target default.target - Main User Target. Sep 12 17:14:27.654610 systemd[2307]: Startup finished in 211ms. Sep 12 17:14:27.654699 systemd[1]: Started user@0.service - User Manager for UID 0. Sep 12 17:14:27.656515 systemd[1]: Started session-c2.scope - Session c2 of User root. Sep 12 17:14:27.853766 kernel: loop6: detected capacity change from 0 to 12644352 Sep 12 17:14:27.863754 kernel: loop6: p9 Sep 12 17:14:27.867217 update_engine[1699]: I20250912 17:14:27.867149 1699 update_attempter.cc:509] Updating boot flags... Sep 12 17:14:27.876422 setup-nvidia[2316]: ░ Spawning container flatcardevelopercontainer-4230.2.3.bin on /opt/nvidia/workdir/flatcar_developer_container-4230.2.3.bin. Sep 12 17:14:27.923780 kernel: EXT4-fs (loop6p9): mounted filesystem c1840b4a-9258-4a60-88d6-cf15a9c9a78c ro without journal. Quota mode: none. Sep 12 17:14:27.950807 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 34 scanned by (udev-worker) (2335) Sep 12 17:14:28.052761 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 34 scanned by (udev-worker) (2338) Sep 12 17:14:29.608455 systemd[1]: Started sshd@5-10.200.8.12:22-10.200.16.10:56980.service - OpenSSH per-connection server daemon (10.200.16.10:56980). Sep 12 17:14:30.242492 sshd[2522]: Accepted publickey for core from 10.200.16.10 port 56980 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:14:30.243861 sshd-session[2522]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:14:30.248438 systemd-logind[1694]: New session 8 of user core. Sep 12 17:14:30.260860 systemd[1]: Started session-8.scope - Session 8 of User core. Sep 12 17:14:30.687178 sshd[2547]: Connection closed by 10.200.16.10 port 56980 Sep 12 17:14:30.687578 sshd-session[2522]: pam_unix(sshd:session): session closed for user core Sep 12 17:14:30.691036 systemd[1]: sshd@5-10.200.8.12:22-10.200.16.10:56980.service: Deactivated successfully. Sep 12 17:14:30.692652 systemd[1]: session-8.scope: Deactivated successfully. Sep 12 17:14:30.693312 systemd-logind[1694]: Session 8 logged out. Waiting for processes to exit. Sep 12 17:14:30.694116 systemd-logind[1694]: Removed session 8. Sep 12 17:14:30.809275 systemd[1]: Started sshd@6-10.200.8.12:22-10.200.16.10:34340.service - OpenSSH per-connection server daemon (10.200.16.10:34340). Sep 12 17:14:31.436274 sshd[2592]: Accepted publickey for core from 10.200.16.10 port 34340 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:14:31.438080 sshd-session[2592]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:14:31.442697 systemd-logind[1694]: New session 9 of user core. Sep 12 17:14:31.451927 systemd[1]: Started session-9.scope - Session 9 of User core. Sep 12 17:14:31.882115 sshd[2792]: Connection closed by 10.200.16.10 port 34340 Sep 12 17:14:31.882498 sshd-session[2592]: pam_unix(sshd:session): session closed for user core Sep 12 17:14:31.885826 systemd[1]: sshd@6-10.200.8.12:22-10.200.16.10:34340.service: Deactivated successfully. Sep 12 17:14:31.887581 systemd[1]: session-9.scope: Deactivated successfully. Sep 12 17:14:31.888320 systemd-logind[1694]: Session 9 logged out. Waiting for processes to exit. Sep 12 17:14:31.889282 systemd-logind[1694]: Removed session 9. Sep 12 17:14:41.996222 systemd[1]: Started sshd@7-10.200.8.12:22-10.200.16.10:50292.service - OpenSSH per-connection server daemon (10.200.16.10:50292). Sep 12 17:14:42.623256 sshd[3513]: Accepted publickey for core from 10.200.16.10 port 50292 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:14:42.624623 sshd-session[3513]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:14:42.628960 systemd-logind[1694]: New session 10 of user core. Sep 12 17:14:42.641884 systemd[1]: Started session-10.scope - Session 10 of User core. Sep 12 17:14:43.068807 sshd[3587]: Connection closed by 10.200.16.10 port 50292 Sep 12 17:14:43.069255 sshd-session[3513]: pam_unix(sshd:session): session closed for user core Sep 12 17:14:43.072929 systemd[1]: sshd@7-10.200.8.12:22-10.200.16.10:50292.service: Deactivated successfully. Sep 12 17:14:43.074628 systemd[1]: session-10.scope: Deactivated successfully. Sep 12 17:14:43.078486 systemd-logind[1694]: Session 10 logged out. Waiting for processes to exit. Sep 12 17:14:43.079372 systemd-logind[1694]: Removed session 10. Sep 12 17:14:43.190509 systemd[1]: Started sshd@8-10.200.8.12:22-10.200.16.10:50306.service - OpenSSH per-connection server daemon (10.200.16.10:50306). Sep 12 17:14:43.819728 sshd[3618]: Accepted publickey for core from 10.200.16.10 port 50306 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:14:43.821290 sshd-session[3618]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:14:43.825524 systemd-logind[1694]: New session 11 of user core. Sep 12 17:14:43.836864 systemd[1]: Started session-11.scope - Session 11 of User core. Sep 12 17:14:44.267029 sshd[3620]: Connection closed by 10.200.16.10 port 50306 Sep 12 17:14:44.267341 sshd-session[3618]: pam_unix(sshd:session): session closed for user core Sep 12 17:14:44.270579 systemd[1]: sshd@8-10.200.8.12:22-10.200.16.10:50306.service: Deactivated successfully. Sep 12 17:14:44.272199 systemd[1]: session-11.scope: Deactivated successfully. Sep 12 17:14:44.272841 systemd-logind[1694]: Session 11 logged out. Waiting for processes to exit. Sep 12 17:14:44.273714 systemd-logind[1694]: Removed session 11. Sep 12 17:14:54.388103 systemd[1]: Started sshd@9-10.200.8.12:22-10.200.16.10:56264.service - OpenSSH per-connection server daemon (10.200.16.10:56264). Sep 12 17:14:55.024445 sshd[7513]: Accepted publickey for core from 10.200.16.10 port 56264 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:14:55.026007 sshd-session[7513]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:14:55.031654 systemd-logind[1694]: New session 12 of user core. Sep 12 17:14:55.042861 systemd[1]: Started session-12.scope - Session 12 of User core. Sep 12 17:14:55.471856 sshd[7716]: Connection closed by 10.200.16.10 port 56264 Sep 12 17:14:55.472617 sshd-session[7513]: pam_unix(sshd:session): session closed for user core Sep 12 17:14:55.477242 systemd-logind[1694]: Session 12 logged out. Waiting for processes to exit. Sep 12 17:14:55.480188 systemd[1]: sshd@9-10.200.8.12:22-10.200.16.10:56264.service: Deactivated successfully. Sep 12 17:14:55.482286 systemd[1]: session-12.scope: Deactivated successfully. Sep 12 17:14:55.484388 systemd-logind[1694]: Removed session 12. Sep 12 17:14:55.594065 systemd[1]: Started sshd@10-10.200.8.12:22-10.200.16.10:56278.service - OpenSSH per-connection server daemon (10.200.16.10:56278). Sep 12 17:14:56.235459 sshd[7904]: Accepted publickey for core from 10.200.16.10 port 56278 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:14:56.237251 sshd-session[7904]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:14:56.247744 systemd-logind[1694]: New session 13 of user core. Sep 12 17:14:56.253475 systemd[1]: Started session-13.scope - Session 13 of User core. Sep 12 17:14:56.680905 sshd[7992]: Connection closed by 10.200.16.10 port 56278 Sep 12 17:14:56.683922 sshd-session[7904]: pam_unix(sshd:session): session closed for user core Sep 12 17:14:56.686891 systemd-logind[1694]: Session 13 logged out. Waiting for processes to exit. Sep 12 17:14:56.688511 systemd[1]: sshd@10-10.200.8.12:22-10.200.16.10:56278.service: Deactivated successfully. Sep 12 17:14:56.690629 systemd[1]: session-13.scope: Deactivated successfully. Sep 12 17:14:56.692160 systemd-logind[1694]: Removed session 13. Sep 12 17:15:06.795971 systemd[1]: Started sshd@11-10.200.8.12:22-10.200.16.10:41864.service - OpenSSH per-connection server daemon (10.200.16.10:41864). Sep 12 17:15:07.435294 sshd[11020]: Accepted publickey for core from 10.200.16.10 port 41864 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:15:07.437206 sshd-session[11020]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:15:07.444777 systemd-logind[1694]: New session 14 of user core. Sep 12 17:15:07.449417 systemd[1]: Started session-14.scope - Session 14 of User core. Sep 12 17:15:07.881677 sshd[11328]: Connection closed by 10.200.16.10 port 41864 Sep 12 17:15:07.882067 sshd-session[11020]: pam_unix(sshd:session): session closed for user core Sep 12 17:15:07.885428 systemd[1]: sshd@11-10.200.8.12:22-10.200.16.10:41864.service: Deactivated successfully. Sep 12 17:15:07.887095 systemd[1]: session-14.scope: Deactivated successfully. Sep 12 17:15:07.887730 systemd-logind[1694]: Session 14 logged out. Waiting for processes to exit. Sep 12 17:15:07.888534 systemd-logind[1694]: Removed session 14. Sep 12 17:15:08.009967 systemd[1]: Started sshd@12-10.200.8.12:22-10.200.16.10:41872.service - OpenSSH per-connection server daemon (10.200.16.10:41872). Sep 12 17:15:08.643766 sshd[11520]: Accepted publickey for core from 10.200.16.10 port 41872 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:15:08.642440 sshd-session[11520]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:15:08.647612 systemd-logind[1694]: New session 15 of user core. Sep 12 17:15:08.659938 systemd[1]: Started session-15.scope - Session 15 of User core. Sep 12 17:15:09.090872 sshd[11552]: Connection closed by 10.200.16.10 port 41872 Sep 12 17:15:09.091305 sshd-session[11520]: pam_unix(sshd:session): session closed for user core Sep 12 17:15:09.095790 systemd-logind[1694]: Session 15 logged out. Waiting for processes to exit. Sep 12 17:15:09.097557 systemd[1]: sshd@12-10.200.8.12:22-10.200.16.10:41872.service: Deactivated successfully. Sep 12 17:15:09.099767 systemd[1]: session-15.scope: Deactivated successfully. Sep 12 17:15:09.101328 systemd-logind[1694]: Removed session 15. Sep 12 17:15:19.209010 systemd[1]: Started sshd@13-10.200.8.12:22-10.200.16.10:37828.service - OpenSSH per-connection server daemon (10.200.16.10:37828). Sep 12 17:15:19.846666 sshd[11949]: Accepted publickey for core from 10.200.16.10 port 37828 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:15:19.847695 sshd-session[11949]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:15:19.854084 systemd-logind[1694]: New session 16 of user core. Sep 12 17:15:19.860444 systemd[1]: Started session-16.scope - Session 16 of User core. Sep 12 17:15:20.294161 sshd[11974]: Connection closed by 10.200.16.10 port 37828 Sep 12 17:15:20.294711 sshd-session[11949]: pam_unix(sshd:session): session closed for user core Sep 12 17:15:20.298223 systemd-logind[1694]: Session 16 logged out. Waiting for processes to exit. Sep 12 17:15:20.299032 systemd[1]: sshd@13-10.200.8.12:22-10.200.16.10:37828.service: Deactivated successfully. Sep 12 17:15:20.301673 systemd[1]: session-16.scope: Deactivated successfully. Sep 12 17:15:20.304087 systemd-logind[1694]: Removed session 16. Sep 12 17:15:20.418348 systemd[1]: Started sshd@14-10.200.8.12:22-10.200.16.10:60158.service - OpenSSH per-connection server daemon (10.200.16.10:60158). Sep 12 17:15:21.061838 sshd[11988]: Accepted publickey for core from 10.200.16.10 port 60158 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:15:21.063730 sshd-session[11988]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:15:21.073551 systemd-logind[1694]: New session 17 of user core. Sep 12 17:15:21.083680 systemd[1]: Started session-17.scope - Session 17 of User core. Sep 12 17:15:21.505196 sshd[12012]: Connection closed by 10.200.16.10 port 60158 Sep 12 17:15:21.505497 sshd-session[11988]: pam_unix(sshd:session): session closed for user core Sep 12 17:15:21.508873 systemd[1]: sshd@14-10.200.8.12:22-10.200.16.10:60158.service: Deactivated successfully. Sep 12 17:15:21.510297 systemd-logind[1694]: Session 17 logged out. Waiting for processes to exit. Sep 12 17:15:21.512087 systemd[1]: session-17.scope: Deactivated successfully. Sep 12 17:15:21.516354 systemd-logind[1694]: Removed session 17. Sep 12 17:15:31.629288 systemd[1]: Started sshd@15-10.200.8.12:22-10.200.16.10:34636.service - OpenSSH per-connection server daemon (10.200.16.10:34636). Sep 12 17:15:32.262098 sshd[12330]: Accepted publickey for core from 10.200.16.10 port 34636 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:15:32.264791 sshd-session[12330]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:15:32.272181 systemd-logind[1694]: New session 18 of user core. Sep 12 17:15:32.280208 systemd[1]: Started session-18.scope - Session 18 of User core. Sep 12 17:15:32.710341 sshd[12332]: Connection closed by 10.200.16.10 port 34636 Sep 12 17:15:32.711221 sshd-session[12330]: pam_unix(sshd:session): session closed for user core Sep 12 17:15:32.715160 systemd[1]: sshd@15-10.200.8.12:22-10.200.16.10:34636.service: Deactivated successfully. Sep 12 17:15:32.717350 systemd[1]: session-18.scope: Deactivated successfully. Sep 12 17:15:32.718915 systemd-logind[1694]: Session 18 logged out. Waiting for processes to exit. Sep 12 17:15:32.719925 systemd-logind[1694]: Removed session 18. Sep 12 17:15:32.835416 systemd[1]: Started sshd@16-10.200.8.12:22-10.200.16.10:34642.service - OpenSSH per-connection server daemon (10.200.16.10:34642). Sep 12 17:15:33.467382 sshd[12356]: Accepted publickey for core from 10.200.16.10 port 34642 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:15:33.470349 sshd-session[12356]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:15:33.479229 systemd-logind[1694]: New session 19 of user core. Sep 12 17:15:33.488223 systemd[1]: Started session-19.scope - Session 19 of User core. Sep 12 17:15:33.914820 sshd[12382]: Connection closed by 10.200.16.10 port 34642 Sep 12 17:15:33.916427 sshd-session[12356]: pam_unix(sshd:session): session closed for user core Sep 12 17:15:33.919799 systemd[1]: sshd@16-10.200.8.12:22-10.200.16.10:34642.service: Deactivated successfully. Sep 12 17:15:33.930910 systemd[1]: session-19.scope: Deactivated successfully. Sep 12 17:15:33.932275 systemd-logind[1694]: Session 19 logged out. Waiting for processes to exit. Sep 12 17:15:33.935591 systemd-logind[1694]: Removed session 19. Sep 12 17:15:44.027262 systemd[1]: Started sshd@17-10.200.8.12:22-10.200.16.10:54220.service - OpenSSH per-connection server daemon (10.200.16.10:54220). Sep 12 17:15:44.657047 sshd[12715]: Accepted publickey for core from 10.200.16.10 port 54220 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:15:44.658602 sshd-session[12715]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:15:44.663216 systemd-logind[1694]: New session 20 of user core. Sep 12 17:15:44.680864 systemd[1]: Started session-20.scope - Session 20 of User core. Sep 12 17:15:45.100433 sshd[12717]: Connection closed by 10.200.16.10 port 54220 Sep 12 17:15:45.100840 sshd-session[12715]: pam_unix(sshd:session): session closed for user core Sep 12 17:15:45.104299 systemd[1]: sshd@17-10.200.8.12:22-10.200.16.10:54220.service: Deactivated successfully. Sep 12 17:15:45.106079 systemd[1]: session-20.scope: Deactivated successfully. Sep 12 17:15:45.106752 systemd-logind[1694]: Session 20 logged out. Waiting for processes to exit. Sep 12 17:15:45.107622 systemd-logind[1694]: Removed session 20. Sep 12 17:15:45.213140 systemd[1]: Started sshd@18-10.200.8.12:22-10.200.16.10:54222.service - OpenSSH per-connection server daemon (10.200.16.10:54222). Sep 12 17:15:45.843207 sshd[12723]: Accepted publickey for core from 10.200.16.10 port 54222 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:15:45.844565 sshd-session[12723]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:15:45.848849 systemd-logind[1694]: New session 21 of user core. Sep 12 17:15:45.859853 systemd[1]: Started session-21.scope - Session 21 of User core. Sep 12 17:15:46.287453 sshd[12725]: Connection closed by 10.200.16.10 port 54222 Sep 12 17:15:46.287871 sshd-session[12723]: pam_unix(sshd:session): session closed for user core Sep 12 17:15:46.291317 systemd[1]: sshd@18-10.200.8.12:22-10.200.16.10:54222.service: Deactivated successfully. Sep 12 17:15:46.293134 systemd[1]: session-21.scope: Deactivated successfully. Sep 12 17:15:46.293856 systemd-logind[1694]: Session 21 logged out. Waiting for processes to exit. Sep 12 17:15:46.294776 systemd-logind[1694]: Removed session 21. Sep 12 17:15:51.902096 setup-nvidia[2316]: \u000d Sep 12 17:15:51.902096 setup-nvidia[2316]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Sep 12 17:15:51.902096 setup-nvidia[2316]: frequently when this kernel module was built against the wrong or Sep 12 17:15:51.902096 setup-nvidia[2316]: improperly configured kernel sources, with a version of gcc that differs Sep 12 17:15:51.902096 setup-nvidia[2316]: from the one used to build the target kernel, or if another driver, such Sep 12 17:15:51.902096 setup-nvidia[2316]: as nouveau, is present and prevents the NVIDIA kernel module from Sep 12 17:15:51.902096 setup-nvidia[2316]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Sep 12 17:15:51.902096 setup-nvidia[2316]: installed in this system is supported by this NVIDIA Linux graphics Sep 12 17:15:51.902096 setup-nvidia[2316]: driver release. Sep 12 17:15:51.902096 setup-nvidia[2316]: \u000d Sep 12 17:15:51.902096 setup-nvidia[2316]: Please see the log entries 'Kernel module load error' and 'Kernel Sep 12 17:15:51.902096 setup-nvidia[2316]: messages' at the end of the file Sep 12 17:15:51.902096 setup-nvidia[2316]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more Sep 12 17:15:51.902096 setup-nvidia[2316]: information. Sep 12 17:15:51.902096 setup-nvidia[2316]: \u000d Sep 12 17:15:51.918317 setup-nvidia[2316]: dmesg: read kernel buffer failed: Operation not permitted Sep 12 17:15:51.940427 setup-nvidia[2316]: \u000d Sep 12 17:15:51.940427 setup-nvidia[2316]: ERROR: Installation has failed. Please see the file Sep 12 17:15:51.940427 setup-nvidia[2316]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for Sep 12 17:15:51.940427 setup-nvidia[2316]: details. You may find suggestions on fixing installation problems in Sep 12 17:15:51.940427 setup-nvidia[2316]: the README available on the Linux driver download page at Sep 12 17:15:51.940427 setup-nvidia[2316]: www.nvidia.com. Sep 12 17:15:51.940427 setup-nvidia[2316]: \u000d Sep 12 17:15:51.942536 setup-nvidia[2316]: Last 50 lines of nvidia-installer.log: Sep 12 17:15:51.943408 setup-nvidia[2316]: /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:51.943408 setup-nvidia[2316]: /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:51.943408 setup-nvidia[2316]: /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:51.943408 setup-nvidia[2316]: /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:51.943408 setup-nvidia[2316]: /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:51.943408 setup-nvidia[2316]: /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:51.943408 setup-nvidia[2316]: /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:51.943408 setup-nvidia[2316]: /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:51.943408 setup-nvidia[2316]: /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:51.943408 setup-nvidia[2316]: /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:51.943408 setup-nvidia[2316]: /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:51.943408 setup-nvidia[2316]: /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:51.943408 setup-nvidia[2316]: /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:51.943408 setup-nvidia[2316]: /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:51.943408 setup-nvidia[2316]: /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:51.943408 setup-nvidia[2316]: /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:51.943408 setup-nvidia[2316]: /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:51.943408 setup-nvidia[2316]: /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:51.943408 setup-nvidia[2316]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/Module.symvers Sep 12 17:15:51.943408 setup-nvidia[2316]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.mod.o Sep 12 17:15:51.943408 setup-nvidia[2316]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.mod.o Sep 12 17:15:51.943408 setup-nvidia[2316]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.mod.o Sep 12 17:15:51.943408 setup-nvidia[2316]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.mod.o Sep 12 17:15:51.943408 setup-nvidia[2316]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.mod.o Sep 12 17:15:51.943408 setup-nvidia[2316]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Sep 12 17:15:51.943408 setup-nvidia[2316]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Sep 12 17:15:51.943408 setup-nvidia[2316]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Sep 12 17:15:51.943408 setup-nvidia[2316]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Sep 12 17:15:51.943408 setup-nvidia[2316]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Sep 12 17:15:51.943408 setup-nvidia[2316]: 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:51.943408 setup-nvidia[2316]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Sep 12 17:15:51.943408 setup-nvidia[2316]: 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:51.943408 setup-nvidia[2316]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Sep 12 17:15:51.943408 setup-nvidia[2316]: 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:51.943408 setup-nvidia[2316]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Sep 12 17:15:51.943408 setup-nvidia[2316]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Sep 12 17:15:51.943408 setup-nvidia[2316]: 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:51.943408 setup-nvidia[2316]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Sep 12 17:15:51.943408 setup-nvidia[2316]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko due to unavailability of vmlinux Sep 12 17:15:51.943408 setup-nvidia[2316]: make[2]: Leaving directory '/usr/lib/modules/6.6.106-flatcar/build' Sep 12 17:15:51.943408 setup-nvidia[2316]: make[1]: Leaving directory '/usr/lib/modules/6.6.106-flatcar/source' Sep 12 17:15:51.943408 setup-nvidia[2316]: -> done. Sep 12 17:15:51.943408 setup-nvidia[2316]: -> Kernel module compilation complete. Sep 12 17:15:51.943408 setup-nvidia[2316]: -> Unable to determine if Secure Boot is enabled: No such file or directory Sep 12 17:15:51.943408 setup-nvidia[2316]: 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:51.943408 setup-nvidia[2316]: \u000d Sep 12 17:15:51.944588 setup-nvidia[2316]: 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:51.944588 setup-nvidia[2316]: -> Kernel module load error: Operation not permitted Sep 12 17:15:51.944588 setup-nvidia[2316]: -> Kernel messages: Sep 12 17:15:51.944588 setup-nvidia[2316]: 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.405385 systemd[1]: Started sshd@19-10.200.8.12:22-10.200.16.10:45564.service - OpenSSH per-connection server daemon (10.200.16.10:45564). Sep 12 17:15:57.033930 sshd[12940]: Accepted publickey for core from 10.200.16.10 port 45564 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:15:57.035289 sshd-session[12940]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:15:57.039452 systemd-logind[1694]: New session 22 of user core. Sep 12 17:15:57.057865 systemd[1]: Started session-22.scope - Session 22 of User core. Sep 12 17:15:57.478866 sshd[12942]: Connection closed by 10.200.16.10 port 45564 Sep 12 17:15:57.479165 sshd-session[12940]: pam_unix(sshd:session): session closed for user core Sep 12 17:15:57.482334 systemd[1]: sshd@19-10.200.8.12:22-10.200.16.10:45564.service: Deactivated successfully. Sep 12 17:15:57.483972 systemd[1]: session-22.scope: Deactivated successfully. Sep 12 17:15:57.484622 systemd-logind[1694]: Session 22 logged out. Waiting for processes to exit. Sep 12 17:15:57.485635 systemd-logind[1694]: Removed session 22. Sep 12 17:15:57.595034 systemd[1]: Started sshd@20-10.200.8.12:22-10.200.16.10:45574.service - OpenSSH per-connection server daemon (10.200.16.10:45574). Sep 12 17:15:58.227187 sshd[12948]: Accepted publickey for core from 10.200.16.10 port 45574 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:15:58.228538 sshd-session[12948]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:15:58.233083 systemd-logind[1694]: New session 23 of user core. Sep 12 17:15:58.245857 systemd[1]: Started session-23.scope - Session 23 of User core. Sep 12 17:15:58.669350 sshd[12950]: Connection closed by 10.200.16.10 port 45574 Sep 12 17:15:58.669730 sshd-session[12948]: pam_unix(sshd:session): session closed for user core Sep 12 17:15:58.672804 systemd[1]: sshd@20-10.200.8.12:22-10.200.16.10:45574.service: Deactivated successfully. Sep 12 17:15:58.674633 systemd[1]: session-23.scope: Deactivated successfully. Sep 12 17:15:58.675273 systemd-logind[1694]: Session 23 logged out. Waiting for processes to exit. Sep 12 17:15:58.676106 systemd-logind[1694]: Removed session 23. Sep 12 17:16:08.795081 systemd[1]: Started sshd@21-10.200.8.12:22-10.200.16.10:52864.service - OpenSSH per-connection server daemon (10.200.16.10:52864). Sep 12 17:16:09.426937 sshd[12956]: Accepted publickey for core from 10.200.16.10 port 52864 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:16:09.428268 sshd-session[12956]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:16:09.432451 systemd-logind[1694]: New session 24 of user core. Sep 12 17:16:09.441852 systemd[1]: Started session-24.scope - Session 24 of User core. Sep 12 17:16:09.871813 sshd[12958]: Connection closed by 10.200.16.10 port 52864 Sep 12 17:16:09.872211 sshd-session[12956]: pam_unix(sshd:session): session closed for user core Sep 12 17:16:09.875554 systemd[1]: sshd@21-10.200.8.12:22-10.200.16.10:52864.service: Deactivated successfully. Sep 12 17:16:09.877401 systemd[1]: session-24.scope: Deactivated successfully. Sep 12 17:16:09.878147 systemd-logind[1694]: Session 24 logged out. Waiting for processes to exit. Sep 12 17:16:09.878979 systemd-logind[1694]: Removed session 24. Sep 12 17:16:09.985635 systemd[1]: Started sshd@22-10.200.8.12:22-10.200.16.10:42536.service - OpenSSH per-connection server daemon (10.200.16.10:42536). Sep 12 17:16:10.613601 sshd[12964]: Accepted publickey for core from 10.200.16.10 port 42536 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:16:10.614978 sshd-session[12964]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:16:10.620569 systemd-logind[1694]: New session 25 of user core. Sep 12 17:16:10.626865 systemd[1]: Started session-25.scope - Session 25 of User core. Sep 12 17:16:11.057538 sshd[12966]: Connection closed by 10.200.16.10 port 42536 Sep 12 17:16:11.058015 sshd-session[12964]: pam_unix(sshd:session): session closed for user core Sep 12 17:16:11.061215 systemd[1]: sshd@22-10.200.8.12:22-10.200.16.10:42536.service: Deactivated successfully. Sep 12 17:16:11.062850 systemd[1]: session-25.scope: Deactivated successfully. Sep 12 17:16:11.063679 systemd-logind[1694]: Session 25 logged out. Waiting for processes to exit. Sep 12 17:16:11.064886 systemd-logind[1694]: Removed session 25. Sep 12 17:16:14.918767 kernel: EXT4-fs (loop6p9): unmounting filesystem c1840b4a-9258-4a60-88d6-cf15a9c9a78c. Sep 12 17:16:14.919990 setup-nvidia[2316]: Container flatcardevelopercontainer-4230.2.3.bin exited successfully. Sep 12 17:16:14.922671 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4230.2.3.bin.mount: Deactivated successfully. Sep 12 17:16:14.993529 sudo[2304]: pam_unix(sudo:session): session closed for user root Sep 12 17:16:14.995309 systemd[1]: session-c2.scope: Deactivated successfully. Sep 12 17:16:14.995574 systemd[1]: session-c2.scope: Consumed 5min 26.522s CPU time, 883.1M memory peak. Sep 12 17:16:15.403920 setup-nvidia[1685]: /opt/nvidia / Sep 12 17:16:15.404958 setup-nvidia[1685]: / Sep 12 17:16:15.421471 setup-nvidia[12989]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 12 17:16:15.445760 kernel: IPMI message handler: version 39.2 Sep 12 17:16:15.449760 kernel: ipmi device interface Sep 12 17:16:15.450431 setup-nvidia[1685]: /opt/nvidia/current/usr/lib/modules/6.6.106-flatcar/video / Sep 12 17:16:15.502780 kernel: nvidia: loading out-of-tree module taints kernel. Sep 12 17:16:15.502913 kernel: nvidia: module license 'NVIDIA' taints kernel. Sep 12 17:16:15.502937 kernel: Disabling lock debugging due to kernel taint Sep 12 17:16:15.510157 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Sep 12 17:16:15.510218 kernel: nvidia: module license taints kernel. Sep 12 17:16:15.911764 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 245 Sep 12 17:16:16.322390 kernel: Sep 12 17:16:16.322511 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Sep 12 17:16:16.530759 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.230.02 Fri Dec 20 21:42:05 UTC 2024 Sep 12 17:16:16.551752 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:16.572775 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Sep 12 17:16:16.626752 kernel: nvidia-uvm: Loaded the UVM driver, major device number 243. Sep 12 17:16:16.627099 setup-nvidia[1685]: / Sep 12 17:16:18.434427 setup-nvidia[13019]: Fri Sep 12 17:16:18 2025 Sep 12 17:16:18.434427 setup-nvidia[13019]: +---------------------------------------------------------------------------------------+ Sep 12 17:16:18.434427 setup-nvidia[13019]: | NVIDIA-SMI 535.230.02 Driver Version: 535.230.02 CUDA Version: 12.2 | Sep 12 17:16:18.434427 setup-nvidia[13019]: |-----------------------------------------+----------------------+----------------------+ Sep 12 17:16:18.434427 setup-nvidia[13019]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Sep 12 17:16:18.434427 setup-nvidia[13019]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Sep 12 17:16:18.434427 setup-nvidia[13019]: | | | MIG M. | Sep 12 17:16:18.434427 setup-nvidia[13019]: |=========================================+======================+======================| Sep 12 17:16:18.439920 setup-nvidia[13019]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Sep 12 17:16:18.439920 setup-nvidia[13019]: | N/A 24C P0 37W / 250W | 0MiB / 16384MiB | 0% Default | Sep 12 17:16:18.439920 setup-nvidia[13019]: | | | N/A | Sep 12 17:16:18.439920 setup-nvidia[13019]: +-----------------------------------------+----------------------+----------------------+ Sep 12 17:16:18.439920 setup-nvidia[13019]: Sep 12 17:16:18.439920 setup-nvidia[13019]: +---------------------------------------------------------------------------------------+ Sep 12 17:16:18.439920 setup-nvidia[13019]: | Processes: | Sep 12 17:16:18.439920 setup-nvidia[13019]: | GPU GI CI PID Type Process name GPU Memory | Sep 12 17:16:18.439920 setup-nvidia[13019]: | ID ID Usage | Sep 12 17:16:18.439920 setup-nvidia[13019]: |=======================================================================================| Sep 12 17:16:18.440170 setup-nvidia[13019]: | No running processes found | Sep 12 17:16:18.440170 setup-nvidia[13019]: +---------------------------------------------------------------------------------------+ Sep 12 17:16:18.814469 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 12 17:16:18.815167 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 12 17:16:18.818820 systemd[1]: Startup finished in 1.097s (firmware) + 30.531s (loader) + 1.873s (kernel) + 12.604s (initrd) + 2min 54.474s (userspace) = 3min 40.580s. Sep 12 17:16:21.173501 systemd[1]: Started sshd@23-10.200.8.12:22-10.200.16.10:45628.service - OpenSSH per-connection server daemon (10.200.16.10:45628). Sep 12 17:16:21.800262 sshd[13026]: Accepted publickey for core from 10.200.16.10 port 45628 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:16:21.801584 sshd-session[13026]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:16:21.805939 systemd-logind[1694]: New session 26 of user core. Sep 12 17:16:21.814924 systemd[1]: Started session-26.scope - Session 26 of User core. Sep 12 17:16:22.242444 sshd[13028]: Connection closed by 10.200.16.10 port 45628 Sep 12 17:16:22.242809 sshd-session[13026]: pam_unix(sshd:session): session closed for user core Sep 12 17:16:22.246134 systemd[1]: sshd@23-10.200.8.12:22-10.200.16.10:45628.service: Deactivated successfully. Sep 12 17:16:22.247774 systemd[1]: session-26.scope: Deactivated successfully. Sep 12 17:16:22.248388 systemd-logind[1694]: Session 26 logged out. Waiting for processes to exit. Sep 12 17:16:22.249204 systemd-logind[1694]: Removed session 26. Sep 12 17:16:22.357885 systemd[1]: Started sshd@24-10.200.8.12:22-10.200.16.10:45642.service - OpenSSH per-connection server daemon (10.200.16.10:45642). Sep 12 17:16:22.985800 sshd[13034]: Accepted publickey for core from 10.200.16.10 port 45642 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:16:22.987235 sshd-session[13034]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:16:22.991710 systemd-logind[1694]: New session 27 of user core. Sep 12 17:16:23.003859 systemd[1]: Started session-27.scope - Session 27 of User core. Sep 12 17:16:23.427715 sshd[13036]: Connection closed by 10.200.16.10 port 45642 Sep 12 17:16:23.428117 sshd-session[13034]: pam_unix(sshd:session): session closed for user core Sep 12 17:16:23.431408 systemd[1]: sshd@24-10.200.8.12:22-10.200.16.10:45642.service: Deactivated successfully. Sep 12 17:16:23.433017 systemd[1]: session-27.scope: Deactivated successfully. Sep 12 17:16:23.433646 systemd-logind[1694]: Session 27 logged out. Waiting for processes to exit. Sep 12 17:16:23.434445 systemd-logind[1694]: Removed session 27. Sep 12 17:16:23.547358 systemd[1]: Started sshd@25-10.200.8.12:22-10.200.16.10:45658.service - OpenSSH per-connection server daemon (10.200.16.10:45658). Sep 12 17:16:24.174752 sshd[13042]: Accepted publickey for core from 10.200.16.10 port 45658 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:16:24.177012 sshd-session[13042]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:16:24.181283 systemd-logind[1694]: New session 28 of user core. Sep 12 17:16:24.197865 systemd[1]: Started session-28.scope - Session 28 of User core. Sep 12 17:16:24.617972 sshd[13044]: Connection closed by 10.200.16.10 port 45658 Sep 12 17:16:24.618376 sshd-session[13042]: pam_unix(sshd:session): session closed for user core Sep 12 17:16:24.621874 systemd[1]: sshd@25-10.200.8.12:22-10.200.16.10:45658.service: Deactivated successfully. Sep 12 17:16:24.623460 systemd[1]: session-28.scope: Deactivated successfully. Sep 12 17:16:24.624101 systemd-logind[1694]: Session 28 logged out. Waiting for processes to exit. Sep 12 17:16:24.624916 systemd-logind[1694]: Removed session 28. Sep 12 17:16:24.729240 systemd[1]: Started sshd@26-10.200.8.12:22-10.200.16.10:45668.service - OpenSSH per-connection server daemon (10.200.16.10:45668). Sep 12 17:16:25.021833 systemd[1]: Stopping user@0.service - User Manager for UID 0... Sep 12 17:16:25.022585 systemd[2307]: Activating special unit exit.target... Sep 12 17:16:25.022680 systemd[2307]: Stopped target default.target - Main User Target. Sep 12 17:16:25.022764 systemd[2307]: Stopped target basic.target - Basic System. Sep 12 17:16:25.022802 systemd[2307]: Stopped target paths.target - Paths. Sep 12 17:16:25.022831 systemd[2307]: Stopped target sockets.target - Sockets. Sep 12 17:16:25.022861 systemd[2307]: Stopped target timers.target - Timers. Sep 12 17:16:25.023266 systemd[2307]: Closed dbus.socket - D-Bus User Message Bus Socket. Sep 12 17:16:25.023490 systemd[2307]: Removed slice app.slice - User Application Slice. Sep 12 17:16:25.023525 systemd[2307]: Reached target shutdown.target - Shutdown. Sep 12 17:16:25.023683 systemd[2307]: Finished systemd-exit.service - Exit the Session. Sep 12 17:16:25.023723 systemd[2307]: Reached target exit.target - Exit the Session. Sep 12 17:16:25.040536 (sd-pam)[2308]: pam_unix(systemd-user:session): session closed for user root Sep 12 17:16:25.042080 systemd[1]: user@0.service: Deactivated successfully. Sep 12 17:16:25.042472 systemd[1]: Stopped user@0.service - User Manager for UID 0. Sep 12 17:16:25.051912 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Sep 12 17:16:25.060807 systemd[1]: run-user-0.mount: Deactivated successfully. Sep 12 17:16:25.061636 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Sep 12 17:16:25.061840 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Sep 12 17:16:25.065786 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Sep 12 17:16:25.065884 systemd[1]: user-0.slice: Consumed 5min 26.814s CPU time, 891.8M memory peak. Sep 12 17:16:25.066965 systemd-logind[1694]: Removed session c3. Sep 12 17:16:25.363244 sshd[13050]: Accepted publickey for core from 10.200.16.10 port 45668 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:16:25.364624 sshd-session[13050]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:16:25.368936 systemd-logind[1694]: New session 29 of user core. Sep 12 17:16:25.380855 systemd[1]: Started session-29.scope - Session 29 of User core. Sep 12 17:16:25.808566 sshd[13056]: Connection closed by 10.200.16.10 port 45668 Sep 12 17:16:25.808992 sshd-session[13050]: pam_unix(sshd:session): session closed for user core Sep 12 17:16:25.812300 systemd[1]: sshd@26-10.200.8.12:22-10.200.16.10:45668.service: Deactivated successfully. Sep 12 17:16:25.813940 systemd[1]: session-29.scope: Deactivated successfully. Sep 12 17:16:25.814572 systemd-logind[1694]: Session 29 logged out. Waiting for processes to exit. Sep 12 17:16:25.815374 systemd-logind[1694]: Removed session 29. Sep 12 17:16:25.920413 systemd[1]: Started sshd@27-10.200.8.12:22-10.200.16.10:45672.service - OpenSSH per-connection server daemon (10.200.16.10:45672). Sep 12 17:16:26.548352 sshd[13062]: Accepted publickey for core from 10.200.16.10 port 45672 ssh2: RSA SHA256:WGuqcYt2K0Ks+Fa7UlyuheHeRe/YPJysGK2sdee5KbU Sep 12 17:16:26.549879 sshd-session[13062]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:16:26.555368 systemd-logind[1694]: New session 30 of user core. Sep 12 17:16:26.571861 systemd[1]: Started session-30.scope - Session 30 of User core. Sep 12 17:16:28.513440 sshd[13064]: Connection closed by 10.200.16.10 port 45672 Sep 12 17:16:28.513877 sshd-session[13062]: pam_unix(sshd:session): session closed for user core Sep 12 17:16:28.517135 systemd[1]: sshd@27-10.200.8.12:22-10.200.16.10:45672.service: Deactivated successfully. Sep 12 17:16:28.518802 systemd[1]: session-30.scope: Deactivated successfully. Sep 12 17:16:28.518997 systemd[1]: session-30.scope: Consumed 1.466s CPU time, 13.8M memory peak. Sep 12 17:16:28.519441 systemd-logind[1694]: Session 30 logged out. Waiting for processes to exit. Sep 12 17:16:28.520247 systemd-logind[1694]: Removed session 30.