Sep 12 10:11:26.236253 kernel: Linux version 6.6.105-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 08:42:12 -00 2025 Sep 12 10:11:26.236282 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=87e444606a7368354f582e8f746f078f97e75cf74b35edd9ec39d0d73a54ead2 Sep 12 10:11:26.236293 kernel: BIOS-provided physical RAM map: Sep 12 10:11:26.236300 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Sep 12 10:11:26.236306 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Sep 12 10:11:26.236313 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Sep 12 10:11:26.236321 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Sep 12 10:11:26.236331 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Sep 12 10:11:26.236338 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Sep 12 10:11:26.236345 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Sep 12 10:11:26.236352 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Sep 12 10:11:26.236359 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Sep 12 10:11:26.236365 kernel: printk: bootconsole [earlyser0] enabled Sep 12 10:11:26.236414 kernel: NX (Execute Disable) protection: active Sep 12 10:11:26.236435 kernel: APIC: Static calls initialized Sep 12 10:11:26.236443 kernel: efi: EFI v2.7 by Microsoft Sep 12 10:11:26.236451 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bea98 RNG=0x3ffd1018 Sep 12 10:11:26.236459 kernel: random: crng init done Sep 12 10:11:26.236466 kernel: secureboot: Secure boot disabled Sep 12 10:11:26.236474 kernel: SMBIOS 3.1.0 present. Sep 12 10:11:26.236482 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Sep 12 10:11:26.236501 kernel: Hypervisor detected: Microsoft Hyper-V Sep 12 10:11:26.236510 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Sep 12 10:11:26.236517 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Sep 12 10:11:26.236528 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Sep 12 10:11:26.236535 kernel: Hyper-V: Using hypercall for remote TLB flush Sep 12 10:11:26.236543 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Sep 12 10:11:26.236551 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Sep 12 10:11:26.236559 kernel: tsc: Marking TSC unstable due to running on Hyper-V Sep 12 10:11:26.236568 kernel: tsc: Detected 2593.992 MHz processor Sep 12 10:11:26.236575 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Sep 12 10:11:26.236584 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Sep 12 10:11:26.236591 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Sep 12 10:11:26.236602 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Sep 12 10:11:26.236610 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Sep 12 10:11:26.236618 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Sep 12 10:11:26.236625 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Sep 12 10:11:26.236633 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Sep 12 10:11:26.236640 kernel: Using GB pages for direct mapping Sep 12 10:11:26.236648 kernel: ACPI: Early table checksum verification disabled Sep 12 10:11:26.236660 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Sep 12 10:11:26.236671 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 10:11:26.236679 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 10:11:26.236687 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Sep 12 10:11:26.236695 kernel: ACPI: FACS 0x000000003FFFE000 000040 Sep 12 10:11:26.236704 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 10:11:26.236712 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 10:11:26.236723 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 10:11:26.236731 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 10:11:26.236739 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 10:11:26.236748 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 10:11:26.236756 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 10:11:26.236764 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Sep 12 10:11:26.236772 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Sep 12 10:11:26.236780 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Sep 12 10:11:26.236791 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Sep 12 10:11:26.236799 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Sep 12 10:11:26.236807 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Sep 12 10:11:26.236815 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Sep 12 10:11:26.236823 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Sep 12 10:11:26.236832 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Sep 12 10:11:26.236840 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Sep 12 10:11:26.236848 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0 Sep 12 10:11:26.236856 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0 Sep 12 10:11:26.236867 kernel: SRAT: PXM 0 -> APIC 0x02 -> Node 0 Sep 12 10:11:26.236875 kernel: SRAT: PXM 0 -> APIC 0x03 -> Node 0 Sep 12 10:11:26.236883 kernel: SRAT: PXM 0 -> APIC 0x04 -> Node 0 Sep 12 10:11:26.236891 kernel: SRAT: PXM 0 -> APIC 0x05 -> Node 0 Sep 12 10:11:26.236899 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Sep 12 10:11:26.236907 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Sep 12 10:11:26.236916 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Sep 12 10:11:26.236924 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Sep 12 10:11:26.236932 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Sep 12 10:11:26.236943 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Sep 12 10:11:26.236951 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Sep 12 10:11:26.236959 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Sep 12 10:11:26.236967 kernel: NUMA: Node 0 [mem 0x00000000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00000000-0xfdfffffff] Sep 12 10:11:26.236976 kernel: NUMA: Node 0 [mem 0x00000000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00000000-0x5cbfffffff] Sep 12 10:11:26.236984 kernel: NODE_DATA(0) allocated [mem 0x5cbfff9000-0x5cbfffefff] Sep 12 10:11:26.236992 kernel: Zone ranges: Sep 12 10:11:26.237000 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Sep 12 10:11:26.237009 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Sep 12 10:11:26.237019 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Sep 12 10:11:26.237028 kernel: Movable zone start for each node Sep 12 10:11:26.237036 kernel: Early memory node ranges Sep 12 10:11:26.237044 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Sep 12 10:11:26.237052 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Sep 12 10:11:26.237060 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Sep 12 10:11:26.237068 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Sep 12 10:11:26.237077 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Sep 12 10:11:26.237085 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Sep 12 10:11:26.237096 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Sep 12 10:11:26.237104 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Sep 12 10:11:26.237112 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Sep 12 10:11:26.237121 kernel: ACPI: PM-Timer IO Port: 0x408 Sep 12 10:11:26.237129 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Sep 12 10:11:26.237137 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Sep 12 10:11:26.237145 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Sep 12 10:11:26.237153 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Sep 12 10:11:26.237162 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Sep 12 10:11:26.237172 kernel: smpboot: Allowing 6 CPUs, 0 hotplug CPUs Sep 12 10:11:26.237181 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Sep 12 10:11:26.237189 kernel: Booting paravirtualized kernel on Hyper-V Sep 12 10:11:26.237197 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Sep 12 10:11:26.237205 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Sep 12 10:11:26.237214 kernel: percpu: Embedded 58 pages/cpu s197160 r8192 d32216 u262144 Sep 12 10:11:26.237222 kernel: pcpu-alloc: s197160 r8192 d32216 u262144 alloc=1*2097152 Sep 12 10:11:26.237230 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Sep 12 10:11:26.237238 kernel: Hyper-V: PV spinlocks enabled Sep 12 10:11:26.237249 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Sep 12 10:11:26.237258 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=87e444606a7368354f582e8f746f078f97e75cf74b35edd9ec39d0d73a54ead2 Sep 12 10:11:26.237267 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Sep 12 10:11:26.237275 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Sep 12 10:11:26.237283 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Sep 12 10:11:26.237291 kernel: Fallback order for Node 0: 0 Sep 12 10:11:26.237300 kernel: Built 1 zonelists, mobility grouping on. Total pages: 28899610 Sep 12 10:11:26.237308 kernel: Policy zone: Normal Sep 12 10:11:26.237319 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 12 10:11:26.237336 kernel: software IO TLB: area num 8. Sep 12 10:11:26.237345 kernel: Memory: 115335988K/117439364K available (14336K kernel code, 2293K rwdata, 22868K rodata, 43508K init, 1568K bss, 2103120K reserved, 0K cma-reserved) Sep 12 10:11:26.237356 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Sep 12 10:11:26.237365 kernel: Kernel/User page tables isolation: enabled Sep 12 10:11:26.237374 kernel: ftrace: allocating 37946 entries in 149 pages Sep 12 10:11:26.237382 kernel: ftrace: allocated 149 pages with 4 groups Sep 12 10:11:26.237390 kernel: Dynamic Preempt: voluntary Sep 12 10:11:26.237399 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 12 10:11:26.237409 kernel: rcu: RCU event tracing is enabled. Sep 12 10:11:26.237417 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Sep 12 10:11:26.237426 kernel: Trampoline variant of Tasks RCU enabled. Sep 12 10:11:26.237438 kernel: Rude variant of Tasks RCU enabled. Sep 12 10:11:26.237447 kernel: Tracing variant of Tasks RCU enabled. Sep 12 10:11:26.237456 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 12 10:11:26.237464 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Sep 12 10:11:26.237475 kernel: Using NULL legacy PIC Sep 12 10:11:26.237484 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Sep 12 10:11:26.237501 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 12 10:11:26.237510 kernel: Console: colour dummy device 80x25 Sep 12 10:11:26.237518 kernel: printk: console [tty1] enabled Sep 12 10:11:26.237527 kernel: printk: console [ttyS0] enabled Sep 12 10:11:26.237535 kernel: printk: bootconsole [earlyser0] disabled Sep 12 10:11:26.237544 kernel: ACPI: Core revision 20230628 Sep 12 10:11:26.237552 kernel: Failed to register legacy timer interrupt Sep 12 10:11:26.237561 kernel: APIC: Switch to symmetric I/O mode setup Sep 12 10:11:26.237573 kernel: Hyper-V: enabling crash_kexec_post_notifiers Sep 12 10:11:26.237581 kernel: Hyper-V: Using IPI hypercalls Sep 12 10:11:26.237590 kernel: APIC: send_IPI() replaced with hv_send_ipi() Sep 12 10:11:26.237598 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Sep 12 10:11:26.237607 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Sep 12 10:11:26.237616 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Sep 12 10:11:26.237624 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Sep 12 10:11:26.237633 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Sep 12 10:11:26.237641 kernel: Hyper-V: Using enlightened APIC (xapic mode) Sep 12 10:11:26.237652 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Sep 12 10:11:26.237661 kernel: APIC: read() replaced with hv_apic_read() Sep 12 10:11:26.237669 kernel: APIC: write() replaced with hv_apic_write() Sep 12 10:11:26.237678 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Sep 12 10:11:26.237687 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Sep 12 10:11:26.237695 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593992) Sep 12 10:11:26.237704 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Sep 12 10:11:26.237713 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Sep 12 10:11:26.237721 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Sep 12 10:11:26.237732 kernel: Spectre V2 : Mitigation: Retpolines Sep 12 10:11:26.237741 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Sep 12 10:11:26.237749 kernel: Speculative Store Bypass: Vulnerable Sep 12 10:11:26.237758 kernel: MDS: Mitigation: Clear CPU buffers Sep 12 10:11:26.237766 kernel: TAA: Mitigation: Clear CPU buffers Sep 12 10:11:26.237774 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Sep 12 10:11:26.237783 kernel: active return thunk: its_return_thunk Sep 12 10:11:26.237791 kernel: ITS: Mitigation: Aligned branch/return thunks Sep 12 10:11:26.237799 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Sep 12 10:11:26.237808 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Sep 12 10:11:26.237816 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Sep 12 10:11:26.237827 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Sep 12 10:11:26.237836 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Sep 12 10:11:26.237844 kernel: Freeing SMP alternatives memory: 32K Sep 12 10:11:26.237853 kernel: pid_max: default: 32768 minimum: 301 Sep 12 10:11:26.237861 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Sep 12 10:11:26.237869 kernel: landlock: Up and running. Sep 12 10:11:26.237878 kernel: SELinux: Initializing. Sep 12 10:11:26.237886 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Sep 12 10:11:26.237895 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Sep 12 10:11:26.237903 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Sep 12 10:11:26.237912 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Sep 12 10:11:26.237924 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Sep 12 10:11:26.237933 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Sep 12 10:11:26.237942 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Sep 12 10:11:26.237950 kernel: signal: max sigframe size: 1776 Sep 12 10:11:26.237959 kernel: rcu: Hierarchical SRCU implementation. Sep 12 10:11:26.237968 kernel: rcu: Max phase no-delay instances is 400. Sep 12 10:11:26.237976 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Sep 12 10:11:26.237985 kernel: smp: Bringing up secondary CPUs ... Sep 12 10:11:26.237993 kernel: smpboot: x86: Booting SMP configuration: Sep 12 10:11:26.238004 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Sep 12 10:11:26.238013 kernel: smp: Brought up 1 node, 6 CPUs Sep 12 10:11:26.238022 kernel: smpboot: Max logical packages: 1 Sep 12 10:11:26.238030 kernel: smpboot: Total of 6 processors activated (31127.90 BogoMIPS) Sep 12 10:11:26.238039 kernel: devtmpfs: initialized Sep 12 10:11:26.238047 kernel: x86/mm: Memory block size: 1024MB Sep 12 10:11:26.238056 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Sep 12 10:11:26.238065 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 12 10:11:26.238073 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Sep 12 10:11:26.238085 kernel: pinctrl core: initialized pinctrl subsystem Sep 12 10:11:26.238093 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 12 10:11:26.238102 kernel: audit: initializing netlink subsys (disabled) Sep 12 10:11:26.238110 kernel: audit: type=2000 audit(1757671884.029:1): state=initialized audit_enabled=0 res=1 Sep 12 10:11:26.238119 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 12 10:11:26.238127 kernel: thermal_sys: Registered thermal governor 'user_space' Sep 12 10:11:26.238136 kernel: cpuidle: using governor menu Sep 12 10:11:26.238144 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 12 10:11:26.238153 kernel: dca service started, version 1.12.1 Sep 12 10:11:26.238164 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Sep 12 10:11:26.238173 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Sep 12 10:11:26.238181 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 12 10:11:26.238190 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Sep 12 10:11:26.238199 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 12 10:11:26.238208 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Sep 12 10:11:26.238216 kernel: ACPI: Added _OSI(Module Device) Sep 12 10:11:26.238225 kernel: ACPI: Added _OSI(Processor Device) Sep 12 10:11:26.238233 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 12 10:11:26.238245 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 12 10:11:26.238253 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Sep 12 10:11:26.238262 kernel: ACPI: Interpreter enabled Sep 12 10:11:26.238270 kernel: ACPI: PM: (supports S0 S5) Sep 12 10:11:26.238279 kernel: ACPI: Using IOAPIC for interrupt routing Sep 12 10:11:26.238287 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Sep 12 10:11:26.238296 kernel: PCI: Ignoring E820 reservations for host bridge windows Sep 12 10:11:26.238305 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Sep 12 10:11:26.238313 kernel: iommu: Default domain type: Translated Sep 12 10:11:26.238324 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Sep 12 10:11:26.238333 kernel: efivars: Registered efivars operations Sep 12 10:11:26.238341 kernel: PCI: Using ACPI for IRQ routing Sep 12 10:11:26.238350 kernel: PCI: System does not support PCI Sep 12 10:11:26.238359 kernel: vgaarb: loaded Sep 12 10:11:26.238367 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Sep 12 10:11:26.238376 kernel: VFS: Disk quotas dquot_6.6.0 Sep 12 10:11:26.238384 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 12 10:11:26.238393 kernel: pnp: PnP ACPI init Sep 12 10:11:26.238404 kernel: pnp: PnP ACPI: found 3 devices Sep 12 10:11:26.238413 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Sep 12 10:11:26.238421 kernel: NET: Registered PF_INET protocol family Sep 12 10:11:26.238430 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 12 10:11:26.238439 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Sep 12 10:11:26.238447 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 12 10:11:26.238456 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 12 10:11:26.238464 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Sep 12 10:11:26.238473 kernel: TCP: Hash tables configured (established 524288 bind 65536) Sep 12 10:11:26.238484 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Sep 12 10:11:26.238500 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Sep 12 10:11:26.238509 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 12 10:11:26.238517 kernel: NET: Registered PF_XDP protocol family Sep 12 10:11:26.238526 kernel: PCI: CLS 0 bytes, default 64 Sep 12 10:11:26.238534 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Sep 12 10:11:26.238543 kernel: software IO TLB: mapped [mem 0x000000003b5be000-0x000000003f5be000] (64MB) Sep 12 10:11:26.238552 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Sep 12 10:11:26.238560 kernel: Initialise system trusted keyrings Sep 12 10:11:26.238572 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Sep 12 10:11:26.238581 kernel: Key type asymmetric registered Sep 12 10:11:26.238589 kernel: Asymmetric key parser 'x509' registered Sep 12 10:11:26.238597 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Sep 12 10:11:26.238606 kernel: io scheduler mq-deadline registered Sep 12 10:11:26.238615 kernel: io scheduler kyber registered Sep 12 10:11:26.238623 kernel: io scheduler bfq registered Sep 12 10:11:26.238632 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Sep 12 10:11:26.238640 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 12 10:11:26.238651 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Sep 12 10:11:26.238660 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Sep 12 10:11:26.238669 kernel: i8042: PNP: No PS/2 controller found. Sep 12 10:11:26.238828 kernel: rtc_cmos 00:02: registered as rtc0 Sep 12 10:11:26.238943 kernel: rtc_cmos 00:02: setting system clock to 2025-09-12T10:11:25 UTC (1757671885) Sep 12 10:11:26.239048 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Sep 12 10:11:26.239061 kernel: intel_pstate: CPU model not supported Sep 12 10:11:26.239075 kernel: efifb: probing for efifb Sep 12 10:11:26.239084 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Sep 12 10:11:26.239092 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Sep 12 10:11:26.239101 kernel: efifb: scrolling: redraw Sep 12 10:11:26.239109 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Sep 12 10:11:26.239118 kernel: Console: switching to colour frame buffer device 128x48 Sep 12 10:11:26.239126 kernel: fb0: EFI VGA frame buffer device Sep 12 10:11:26.239135 kernel: pstore: Using crash dump compression: deflate Sep 12 10:11:26.239144 kernel: pstore: Registered efi_pstore as persistent store backend Sep 12 10:11:26.239155 kernel: NET: Registered PF_INET6 protocol family Sep 12 10:11:26.239164 kernel: Segment Routing with IPv6 Sep 12 10:11:26.239172 kernel: In-situ OAM (IOAM) with IPv6 Sep 12 10:11:26.239181 kernel: NET: Registered PF_PACKET protocol family Sep 12 10:11:26.239189 kernel: Key type dns_resolver registered Sep 12 10:11:26.239198 kernel: IPI shorthand broadcast: enabled Sep 12 10:11:26.239206 kernel: sched_clock: Marking stable (1690005900, 99626000)->(2118645100, -329013200) Sep 12 10:11:26.239215 kernel: registered taskstats version 1 Sep 12 10:11:26.239223 kernel: Loading compiled-in X.509 certificates Sep 12 10:11:26.239232 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.105-flatcar: 0972efc09ee0bcd53f8cdb5573e11871ce7b16a9' Sep 12 10:11:26.239243 kernel: Key type .fscrypt registered Sep 12 10:11:26.239252 kernel: Key type fscrypt-provisioning registered Sep 12 10:11:26.239260 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 12 10:11:26.239269 kernel: ima: Allocated hash algorithm: sha1 Sep 12 10:11:26.239277 kernel: ima: No architecture policies found Sep 12 10:11:26.239286 kernel: clk: Disabling unused clocks Sep 12 10:11:26.239294 kernel: Freeing unused kernel image (initmem) memory: 43508K Sep 12 10:11:26.239303 kernel: Write protecting the kernel read-only data: 38912k Sep 12 10:11:26.239314 kernel: Freeing unused kernel image (rodata/data gap) memory: 1708K Sep 12 10:11:26.239323 kernel: Run /init as init process Sep 12 10:11:26.239331 kernel: with arguments: Sep 12 10:11:26.239340 kernel: /init Sep 12 10:11:26.239348 kernel: with environment: Sep 12 10:11:26.239356 kernel: HOME=/ Sep 12 10:11:26.239365 kernel: TERM=linux Sep 12 10:11:26.239373 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 12 10:11:26.239383 systemd[1]: Successfully made /usr/ read-only. Sep 12 10:11:26.239397 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 10:11:26.239407 systemd[1]: Detected virtualization microsoft. Sep 12 10:11:26.239416 systemd[1]: Detected architecture x86-64. Sep 12 10:11:26.239425 systemd[1]: Running in initrd. Sep 12 10:11:26.239434 systemd[1]: No hostname configured, using default hostname. Sep 12 10:11:26.239443 systemd[1]: Hostname set to . Sep 12 10:11:26.239452 systemd[1]: Initializing machine ID from random generator. Sep 12 10:11:26.239463 systemd[1]: Queued start job for default target initrd.target. Sep 12 10:11:26.239473 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 12 10:11:26.239482 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 12 10:11:26.239504 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 12 10:11:26.239514 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 12 10:11:26.239523 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 12 10:11:26.239533 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 12 10:11:26.239546 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 10:11:26.239556 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 12 10:11:26.239565 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 12 10:11:26.239574 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 12 10:11:26.239583 systemd[1]: Reached target paths.target - Path Units. Sep 12 10:11:26.239593 systemd[1]: Reached target slices.target - Slice Units. Sep 12 10:11:26.239602 systemd[1]: Reached target swap.target - Swaps. Sep 12 10:11:26.239611 systemd[1]: Reached target timers.target - Timer Units. Sep 12 10:11:26.239620 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 12 10:11:26.239631 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 12 10:11:26.239641 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 12 10:11:26.239650 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Sep 12 10:11:26.239659 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 12 10:11:26.239668 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 12 10:11:26.239677 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 12 10:11:26.239686 systemd[1]: Reached target sockets.target - Socket Units. Sep 12 10:11:26.239695 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 12 10:11:26.239707 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 12 10:11:26.239716 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 12 10:11:26.239725 systemd[1]: Starting systemd-fsck-usr.service... Sep 12 10:11:26.239734 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 12 10:11:26.239743 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 12 10:11:26.239772 systemd-journald[204]: Collecting audit messages is disabled. Sep 12 10:11:26.239798 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 12 10:11:26.239808 systemd-journald[204]: Journal started Sep 12 10:11:26.239828 systemd-journald[204]: Runtime Journal (/run/log/journal/7a2c8bedf3bb469d8b1b55826681ced5) is 8M, max 2.2G, 2.1G free. Sep 12 10:11:26.247646 systemd[1]: Started systemd-journald.service - Journal Service. Sep 12 10:11:26.248772 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 12 10:11:26.249362 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 12 10:11:26.250042 systemd[1]: Finished systemd-fsck-usr.service. Sep 12 10:11:26.252813 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 12 10:11:26.254699 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 12 10:11:26.295250 systemd-modules-load[207]: Inserted module 'overlay' Sep 12 10:11:26.295300 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 10:11:26.303445 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 12 10:11:26.306031 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 12 10:11:26.318355 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 12 10:11:26.329063 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 12 10:11:26.344981 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 12 10:11:26.349380 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 12 10:11:26.356348 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 12 10:11:26.372004 dracut-cmdline[232]: dracut-dracut-053 Sep 12 10:11:26.375981 dracut-cmdline[232]: 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=87e444606a7368354f582e8f746f078f97e75cf74b35edd9ec39d0d73a54ead2 Sep 12 10:11:26.407513 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 10:11:26.411761 systemd-modules-load[207]: Inserted module 'br_netfilter' Sep 12 10:11:26.413142 kernel: Bridge firewalling registered Sep 12 10:11:26.412535 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 12 10:11:26.430635 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 12 10:11:26.447092 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 12 10:11:26.451470 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 12 10:11:26.490811 systemd-resolved[304]: Positive Trust Anchors: Sep 12 10:11:26.490826 systemd-resolved[304]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 12 10:11:26.490857 systemd-resolved[304]: 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 10:11:26.493460 systemd-resolved[304]: Defaulting to hostname 'linux'. Sep 12 10:11:26.494737 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 12 10:11:26.504195 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 12 10:11:26.552507 kernel: SCSI subsystem initialized Sep 12 10:11:26.564512 kernel: Loading iSCSI transport class v2.0-870. Sep 12 10:11:26.578511 kernel: iscsi: registered transport (tcp) Sep 12 10:11:26.602876 kernel: iscsi: registered transport (qla4xxx) Sep 12 10:11:26.602911 kernel: QLogic iSCSI HBA Driver Sep 12 10:11:26.643594 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 12 10:11:26.661665 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 12 10:11:26.693387 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 12 10:11:26.693428 kernel: device-mapper: uevent: version 1.0.3 Sep 12 10:11:26.697861 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Sep 12 10:11:26.739511 kernel: raid6: avx2x4 gen() 30496 MB/s Sep 12 10:11:26.759504 kernel: raid6: avx2x2 gen() 31356 MB/s Sep 12 10:11:26.780696 kernel: raid6: avx2x1 gen() 27811 MB/s Sep 12 10:11:26.780724 kernel: raid6: using algorithm avx2x2 gen() 31356 MB/s Sep 12 10:11:26.802534 kernel: raid6: .... xor() 18319 MB/s, rmw enabled Sep 12 10:11:26.802564 kernel: raid6: using avx2x2 recovery algorithm Sep 12 10:11:26.826511 kernel: xor: automatically using best checksumming function avx Sep 12 10:11:26.986523 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 12 10:11:26.998747 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 12 10:11:27.022620 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 12 10:11:27.044123 systemd-udevd[428]: Using default interface naming scheme 'v255'. Sep 12 10:11:27.049737 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 12 10:11:27.055647 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 12 10:11:27.081264 dracut-pre-trigger[435]: rd.md=0: removing MD RAID activation Sep 12 10:11:27.111180 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 12 10:11:27.130664 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 12 10:11:27.191004 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 12 10:11:27.207839 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 12 10:11:27.223554 kernel: cryptd: max_cpu_qlen set to 1000 Sep 12 10:11:27.223588 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 12 10:11:27.232565 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 12 10:11:27.242147 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 12 10:11:27.247277 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 12 10:11:27.265512 kernel: hv_vmbus: Vmbus version:4.0 Sep 12 10:11:27.272544 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 12 10:11:27.347112 kernel: pps_core: LinuxPPS API ver. 1 registered Sep 12 10:11:27.347153 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Sep 12 10:11:27.347168 kernel: hv_vmbus: registering driver hv_pci Sep 12 10:11:27.347180 kernel: hv_vmbus: registering driver hv_netvsc Sep 12 10:11:27.347192 kernel: hv_vmbus: registering driver hyperv_keyboard Sep 12 10:11:27.347203 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 10:11:27.347216 kernel: hid: raw HID events driver (C) Jiri Kosina Sep 12 10:11:27.347235 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Sep 12 10:11:27.227663 kernel: AVX2 version of gcm_enc/dec engaged. Sep 12 10:11:27.237835 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Sep 12 10:11:27.238210 kernel: PTP clock support registered Sep 12 10:11:27.238225 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Sep 12 10:11:27.238404 kernel: AES CTR mode by8 optimization enabled Sep 12 10:11:27.238416 kernel: hv_vmbus: registering driver hid_hyperv Sep 12 10:11:27.238433 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Sep 12 10:11:27.238561 kernel: hv_utils: Registering HyperV Utility Driver Sep 12 10:11:27.238572 kernel: hv_vmbus: registering driver hv_utils Sep 12 10:11:27.238582 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Sep 12 10:11:27.238704 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Sep 12 10:11:27.238715 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 Sep 12 10:11:27.238864 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Sep 12 10:11:27.239223 kernel: hv_utils: Shutdown IC version 3.2 Sep 12 10:11:27.239239 kernel: hv_utils: TimeSync IC version 4.0 Sep 12 10:11:27.239249 kernel: hv_utils: Heartbeat IC version 3.0 Sep 12 10:11:27.239259 kernel: pci 0001:00:00.0: reg 0x10: [mem 0x41000000-0x41ffffff] Sep 12 10:11:27.239410 kernel: pci 0001:00:00.0: reg 0x14: [mem 0x1000000000-0x13ffffffff 64bit pref] Sep 12 10:11:27.239550 kernel: pci 0001:00:00.0: reg 0x1c: [mem 0x1400000000-0x1401ffffff 64bit pref] Sep 12 10:11:27.239687 kernel: pci 0001:00:00.0: Enabling HDA controller Sep 12 10:11:27.239823 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Sep 12 10:11:27.239947 systemd-journald[204]: Time jumped backwards, rotating. Sep 12 10:11:27.240013 kernel: pci 0001:00:00.0: BAR 1: assigned [mem 0x1000000000-0x13ffffffff 64bit pref] Sep 12 10:11:27.227260 systemd-resolved[304]: Clock change detected. Flushing caches. Sep 12 10:11:27.260789 kernel: pci 0001:00:00.0: BAR 3: assigned [mem 0x1400000000-0x1401ffffff 64bit pref] Sep 12 10:11:27.260966 kernel: pci 0001:00:00.0: BAR 0: assigned [mem 0x41000000-0x41ffffff] Sep 12 10:11:27.261128 kernel: hv_vmbus: registering driver hv_storvsc Sep 12 10:11:27.253262 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 12 10:11:27.269693 kernel: scsi host0: storvsc_host_t Sep 12 10:11:27.276054 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Sep 12 10:11:27.282984 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Sep 12 10:11:27.283023 kernel: scsi host1: storvsc_host_t Sep 12 10:11:27.284361 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 12 10:11:27.289322 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Sep 12 10:11:27.284644 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 12 10:11:27.302221 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 12 10:11:27.303681 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 12 10:11:27.306121 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 10:11:27.320221 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 12 10:11:27.335302 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 12 10:11:27.345770 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 12 10:11:27.345884 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 10:11:27.358522 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 12 10:11:27.370669 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Sep 12 10:11:27.371119 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Sep 12 10:11:27.372403 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Sep 12 10:11:27.375163 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 12 10:11:27.391645 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Sep 12 10:11:27.392179 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Sep 12 10:11:27.392743 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Sep 12 10:11:27.392917 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks Sep 12 10:11:27.396833 kernel: sd 0:0:0:0: [sda] Write Protect is off Sep 12 10:11:27.397026 kernel: sd 0:0:0:1: [sdb] Write Protect is off Sep 12 10:11:27.400054 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Sep 12 10:11:27.400241 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Sep 12 10:11:27.409024 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 12 10:11:27.409058 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Sep 12 10:11:27.410101 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Sep 12 10:11:27.412054 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Sep 12 10:11:27.426800 kernel: sdb: sdb1 Sep 12 10:11:27.426837 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk Sep 12 10:11:27.428392 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 10:11:27.443269 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 12 10:11:27.460333 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 12 10:11:27.919068 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 scanned by (udev-worker) (484) Sep 12 10:11:27.943549 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Sep 12 10:11:27.957552 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Sep 12 10:11:27.981713 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Sep 12 10:11:27.995087 kernel: BTRFS: device fsid 2566299d-dd4a-4826-ba43-7397a17991fb devid 1 transid 35 /dev/sda3 scanned by (udev-worker) (493) Sep 12 10:11:28.007009 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Sep 12 10:11:28.007803 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Sep 12 10:11:28.037171 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 12 10:11:28.063057 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 12 10:11:28.076053 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 12 10:11:29.085652 disk-uuid[642]: The operation has completed successfully. Sep 12 10:11:29.091748 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 12 10:11:30.478942 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 12 10:11:30.479070 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 12 10:11:30.496160 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 12 10:11:30.504575 sh[729]: Success Sep 12 10:11:30.527053 kernel: device-mapper: verity: sha256 using implementation "sha256-avx2" Sep 12 10:11:30.856756 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 12 10:11:30.875609 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 12 10:11:30.884304 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 12 10:11:30.906097 kernel: BTRFS info (device dm-0): first mount of filesystem 2566299d-dd4a-4826-ba43-7397a17991fb Sep 12 10:11:30.906130 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Sep 12 10:11:30.917141 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Sep 12 10:11:30.920719 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 12 10:11:30.923784 kernel: BTRFS info (device dm-0): using free space tree Sep 12 10:11:31.403710 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 12 10:11:31.406085 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 12 10:11:31.427155 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 12 10:11:31.434280 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 12 10:11:31.468053 kernel: BTRFS info (device sda6): first mount of filesystem 36a15e30-b48e-4687-be9c-f68c3ae1825b Sep 12 10:11:31.468096 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Sep 12 10:11:31.474531 kernel: BTRFS info (device sda6): using free space tree Sep 12 10:11:31.517059 kernel: BTRFS info (device sda6): auto enabling async discard Sep 12 10:11:31.525266 kernel: BTRFS info (device sda6): last unmount of filesystem 36a15e30-b48e-4687-be9c-f68c3ae1825b Sep 12 10:11:31.531790 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 12 10:11:31.533531 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 12 10:11:31.557160 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 12 10:11:31.566204 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 12 10:11:31.597690 systemd-networkd[910]: lo: Link UP Sep 12 10:11:31.597700 systemd-networkd[910]: lo: Gained carrier Sep 12 10:11:31.598825 systemd-networkd[910]: Enumeration completed Sep 12 10:11:31.598906 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 12 10:11:31.599207 systemd-networkd[910]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 12 10:11:31.599211 systemd-networkd[910]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 12 10:11:31.599995 systemd-networkd[910]: eth0: Link UP Sep 12 10:11:31.600189 systemd-networkd[910]: eth0: Gained carrier Sep 12 10:11:31.600198 systemd-networkd[910]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 12 10:11:31.604454 systemd[1]: Reached target network.target - Network. Sep 12 10:11:31.652068 systemd-networkd[910]: eth0: DHCPv4 address 10.200.8.23/24, gateway 10.200.8.1 acquired from 168.63.129.16 Sep 12 10:11:32.711210 ignition[909]: Ignition 2.20.0 Sep 12 10:11:32.711223 ignition[909]: Stage: fetch-offline Sep 12 10:11:32.714275 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 12 10:11:32.711275 ignition[909]: no configs at "/usr/lib/ignition/base.d" Sep 12 10:11:32.711286 ignition[909]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 12 10:11:32.711393 ignition[909]: parsed url from cmdline: "" Sep 12 10:11:32.711397 ignition[909]: no config URL provided Sep 12 10:11:32.711402 ignition[909]: reading system config file "/usr/lib/ignition/user.ign" Sep 12 10:11:32.711412 ignition[909]: no config at "/usr/lib/ignition/user.ign" Sep 12 10:11:32.737228 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Sep 12 10:11:32.711418 ignition[909]: failed to fetch config: resource requires networking Sep 12 10:11:32.711589 ignition[909]: Ignition finished successfully Sep 12 10:11:32.757825 ignition[923]: Ignition 2.20.0 Sep 12 10:11:32.757842 ignition[923]: Stage: fetch Sep 12 10:11:32.758012 ignition[923]: no configs at "/usr/lib/ignition/base.d" Sep 12 10:11:32.758025 ignition[923]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 12 10:11:32.758124 ignition[923]: parsed url from cmdline: "" Sep 12 10:11:32.758128 ignition[923]: no config URL provided Sep 12 10:11:32.758134 ignition[923]: reading system config file "/usr/lib/ignition/user.ign" Sep 12 10:11:32.758143 ignition[923]: no config at "/usr/lib/ignition/user.ign" Sep 12 10:11:32.758167 ignition[923]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Sep 12 10:11:32.873863 ignition[923]: GET result: OK Sep 12 10:11:32.873911 ignition[923]: config has been read from IMDS userdata Sep 12 10:11:32.873923 ignition[923]: parsing config with SHA512: df9b21176119b7fb3ad25dd96dfccec964ca501b4d7eed2f8d0e877ae985580445306304afbcf36bcaab33273fa18b3e87ed30bd35e50df3c6219bc5f39b9623 Sep 12 10:11:32.878272 unknown[923]: fetched base config from "system" Sep 12 10:11:32.878487 ignition[923]: fetch: fetch complete Sep 12 10:11:32.878280 unknown[923]: fetched base config from "system" Sep 12 10:11:32.878492 ignition[923]: fetch: fetch passed Sep 12 10:11:32.878286 unknown[923]: fetched user config from "azure" Sep 12 10:11:32.878544 ignition[923]: Ignition finished successfully Sep 12 10:11:32.880416 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Sep 12 10:11:32.909205 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 12 10:11:32.929394 ignition[932]: Ignition 2.20.0 Sep 12 10:11:32.929407 ignition[932]: Stage: kargs Sep 12 10:11:32.929601 ignition[932]: no configs at "/usr/lib/ignition/base.d" Sep 12 10:11:32.934674 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 12 10:11:32.929614 ignition[932]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 12 10:11:32.930318 ignition[932]: kargs: kargs passed Sep 12 10:11:32.930364 ignition[932]: Ignition finished successfully Sep 12 10:11:32.961170 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 12 10:11:32.979933 ignition[942]: Ignition 2.20.0 Sep 12 10:11:32.979944 ignition[942]: Stage: disks Sep 12 10:11:32.980121 ignition[942]: no configs at "/usr/lib/ignition/base.d" Sep 12 10:11:32.980134 ignition[942]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 12 10:11:32.985197 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 12 10:11:32.980675 ignition[942]: disks: disks passed Sep 12 10:11:32.991703 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 12 10:11:32.980717 ignition[942]: Ignition finished successfully Sep 12 10:11:32.999465 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 12 10:11:33.007882 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 12 10:11:33.014167 systemd[1]: Reached target sysinit.target - System Initialization. Sep 12 10:11:33.015510 systemd[1]: Reached target basic.target - Basic System. Sep 12 10:11:33.043177 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 12 10:11:33.126829 systemd-fsck[953]: ROOT: clean, 14/7326000 files, 477710/7359488 blocks Sep 12 10:11:33.132753 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 12 10:11:33.147157 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 12 10:11:33.249059 kernel: EXT4-fs (sda9): mounted filesystem 4caafea7-bbab-4a47-b77b-37af606fc08b r/w with ordered data mode. Quota mode: none. Sep 12 10:11:33.249938 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 12 10:11:33.251869 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 12 10:11:33.292116 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 12 10:11:33.308061 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/sda6 scanned by mount (964) Sep 12 10:11:33.314957 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 12 10:11:33.324577 kernel: BTRFS info (device sda6): first mount of filesystem 36a15e30-b48e-4687-be9c-f68c3ae1825b Sep 12 10:11:33.324595 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Sep 12 10:11:33.324606 kernel: BTRFS info (device sda6): using free space tree Sep 12 10:11:33.325598 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Sep 12 10:11:33.335061 kernel: BTRFS info (device sda6): auto enabling async discard Sep 12 10:11:33.341651 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Sep 12 10:11:33.341697 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 12 10:11:33.356931 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 12 10:11:33.361210 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 12 10:11:33.383196 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 12 10:11:33.444158 systemd-networkd[910]: eth0: Gained IPv6LL Sep 12 10:11:34.100443 coreos-metadata[980]: Sep 12 10:11:34.100 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Sep 12 10:11:34.108590 coreos-metadata[980]: Sep 12 10:11:34.108 INFO Fetch successful Sep 12 10:11:34.112195 coreos-metadata[980]: Sep 12 10:11:34.108 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Sep 12 10:11:34.127624 coreos-metadata[980]: Sep 12 10:11:34.127 INFO Fetch successful Sep 12 10:11:34.131255 coreos-metadata[980]: Sep 12 10:11:34.127 INFO wrote hostname ci-4230.2.2-n-d02a5ec55b to /sysroot/etc/hostname Sep 12 10:11:34.138910 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Sep 12 10:11:34.299030 initrd-setup-root[994]: cut: /sysroot/etc/passwd: No such file or directory Sep 12 10:11:34.356467 initrd-setup-root[1001]: cut: /sysroot/etc/group: No such file or directory Sep 12 10:11:34.391511 initrd-setup-root[1008]: cut: /sysroot/etc/shadow: No such file or directory Sep 12 10:11:34.408890 initrd-setup-root[1015]: cut: /sysroot/etc/gshadow: No such file or directory Sep 12 10:11:35.342528 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 12 10:11:35.367117 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 12 10:11:35.369257 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 12 10:11:35.385494 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 12 10:11:35.393993 kernel: BTRFS info (device sda6): last unmount of filesystem 36a15e30-b48e-4687-be9c-f68c3ae1825b Sep 12 10:11:35.415792 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 12 10:11:35.426111 ignition[1085]: INFO : Ignition 2.20.0 Sep 12 10:11:35.426111 ignition[1085]: INFO : Stage: mount Sep 12 10:11:35.431567 ignition[1085]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 12 10:11:35.431567 ignition[1085]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 12 10:11:35.431567 ignition[1085]: INFO : mount: mount passed Sep 12 10:11:35.431567 ignition[1085]: INFO : Ignition finished successfully Sep 12 10:11:35.441318 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 12 10:11:35.462195 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 12 10:11:35.471524 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 12 10:11:35.500410 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/sda6 scanned by mount (1098) Sep 12 10:11:35.500460 kernel: BTRFS info (device sda6): first mount of filesystem 36a15e30-b48e-4687-be9c-f68c3ae1825b Sep 12 10:11:35.504625 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Sep 12 10:11:35.507626 kernel: BTRFS info (device sda6): using free space tree Sep 12 10:11:35.515050 kernel: BTRFS info (device sda6): auto enabling async discard Sep 12 10:11:35.516306 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 12 10:11:35.549879 ignition[1115]: INFO : Ignition 2.20.0 Sep 12 10:11:35.549879 ignition[1115]: INFO : Stage: files Sep 12 10:11:35.555964 ignition[1115]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 12 10:11:35.555964 ignition[1115]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 12 10:11:35.555964 ignition[1115]: DEBUG : files: compiled without relabeling support, skipping Sep 12 10:11:35.569280 ignition[1115]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 12 10:11:35.569280 ignition[1115]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 12 10:11:35.666412 ignition[1115]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 12 10:11:35.671987 ignition[1115]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 12 10:11:35.677306 ignition[1115]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 12 10:11:35.672536 unknown[1115]: wrote ssh authorized keys file for user: core Sep 12 10:11:35.709917 ignition[1115]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 12 10:11:35.709917 ignition[1115]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 12 10:11:35.723700 ignition[1115]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 12 10:11:35.723700 ignition[1115]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 12 10:11:35.723700 ignition[1115]: INFO : files: files passed Sep 12 10:11:35.723700 ignition[1115]: INFO : Ignition finished successfully Sep 12 10:11:35.731880 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 12 10:11:35.756176 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 12 10:11:35.757654 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 12 10:11:35.766351 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 12 10:11:35.766448 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 12 10:11:35.790532 initrd-setup-root-after-ignition[1146]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 12 10:11:35.790532 initrd-setup-root-after-ignition[1146]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 12 10:11:35.798064 initrd-setup-root-after-ignition[1150]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 12 10:11:35.797412 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 12 10:11:35.807810 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 12 10:11:35.833166 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 12 10:11:35.855543 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 12 10:11:35.855654 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 12 10:11:35.864187 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 12 10:11:35.872482 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 12 10:11:35.880153 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 12 10:11:35.902232 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 12 10:11:35.916975 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 12 10:11:35.920776 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 12 10:11:35.938704 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 12 10:11:35.940296 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 12 10:11:35.948996 systemd[1]: Stopped target timers.target - Timer Units. Sep 12 10:11:35.957992 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 12 10:11:35.958228 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 12 10:11:35.971918 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 12 10:11:35.973981 systemd[1]: Stopped target basic.target - Basic System. Sep 12 10:11:35.985061 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 12 10:11:35.990955 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 12 10:11:35.992302 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 12 10:11:36.008229 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 12 10:11:36.022643 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 12 10:11:36.024189 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 12 10:11:36.033349 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 12 10:11:36.040993 systemd[1]: Stopped target swap.target - Swaps. Sep 12 10:11:36.048417 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 12 10:11:36.048576 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 12 10:11:36.063277 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 12 10:11:36.072556 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 12 10:11:36.080804 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 12 10:11:36.080940 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 12 10:11:36.089808 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 12 10:11:36.089942 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 12 10:11:36.102665 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 12 10:11:36.102796 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 12 10:11:36.110911 systemd[1]: ignition-files.service: Deactivated successfully. Sep 12 10:11:36.111028 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 12 10:11:36.118403 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Sep 12 10:11:36.118520 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Sep 12 10:11:36.143189 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 12 10:11:36.154946 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 12 10:11:36.162917 ignition[1170]: INFO : Ignition 2.20.0 Sep 12 10:11:36.162917 ignition[1170]: INFO : Stage: umount Sep 12 10:11:36.162917 ignition[1170]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 12 10:11:36.162917 ignition[1170]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 12 10:11:36.162917 ignition[1170]: INFO : umount: umount passed Sep 12 10:11:36.162917 ignition[1170]: INFO : Ignition finished successfully Sep 12 10:11:36.157807 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 12 10:11:36.157960 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 12 10:11:36.164477 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 12 10:11:36.164597 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 12 10:11:36.178795 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 12 10:11:36.178905 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 12 10:11:36.183317 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 12 10:11:36.183435 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 12 10:11:36.191020 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 12 10:11:36.191090 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 12 10:11:36.198017 systemd[1]: ignition-fetch.service: Deactivated successfully. Sep 12 10:11:36.198078 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Sep 12 10:11:36.204988 systemd[1]: Stopped target network.target - Network. Sep 12 10:11:36.214271 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 12 10:11:36.214332 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 12 10:11:36.216427 systemd[1]: Stopped target paths.target - Path Units. Sep 12 10:11:36.228079 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 12 10:11:36.228180 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 12 10:11:36.236249 systemd[1]: Stopped target slices.target - Slice Units. Sep 12 10:11:36.245866 systemd[1]: Stopped target sockets.target - Socket Units. Sep 12 10:11:36.256935 systemd[1]: iscsid.socket: Deactivated successfully. Sep 12 10:11:36.256987 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 12 10:11:36.264340 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 12 10:11:36.264387 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 12 10:11:36.266846 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 12 10:11:36.266899 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 12 10:11:36.278316 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 12 10:11:36.278364 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 12 10:11:36.292016 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 12 10:11:36.299270 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 12 10:11:36.311685 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 12 10:11:36.312513 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 12 10:11:36.312664 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 12 10:11:36.321406 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 12 10:11:36.321516 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 12 10:11:36.332428 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 12 10:11:36.332524 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 12 10:11:36.335378 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 12 10:11:36.335476 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 12 10:11:36.339135 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 12 10:11:36.339199 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 12 10:11:36.354794 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 12 10:11:36.354853 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 12 10:11:36.372236 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 12 10:11:36.373190 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 12 10:11:36.373251 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 12 10:11:36.381966 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 12 10:11:36.382154 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 12 10:11:36.394348 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 12 10:11:36.394419 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 12 10:11:36.401160 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 12 10:11:36.401212 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 12 10:11:36.410969 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 12 10:11:36.416387 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Sep 12 10:11:36.416448 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Sep 12 10:11:36.416493 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Sep 12 10:11:36.416606 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Sep 12 10:11:36.436797 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 12 10:11:36.436955 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 12 10:11:36.445793 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 12 10:11:36.445952 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 12 10:11:36.447772 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 12 10:11:36.447820 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 12 10:11:36.459004 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 12 10:11:36.459054 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 12 10:11:36.467951 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 12 10:11:36.468000 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 12 10:11:36.481103 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 12 10:11:36.481153 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 12 10:11:36.492589 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 12 10:11:36.492642 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 12 10:11:36.528577 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 12 10:11:36.536471 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 12 10:11:36.536530 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 12 10:11:36.549515 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Sep 12 10:11:36.549565 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 12 10:11:36.559193 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 12 10:11:36.559243 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 12 10:11:36.568319 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 12 10:11:36.568369 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 10:11:36.588020 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Sep 12 10:11:36.588098 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 12 10:11:36.588443 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 12 10:11:36.588532 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 12 10:11:36.592439 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 12 10:11:36.619158 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 12 10:11:36.645234 systemd[1]: Switching root. Sep 12 10:11:36.748393 systemd-journald[204]: Journal stopped Sep 12 10:11:43.647388 systemd-journald[204]: Received SIGTERM from PID 1 (systemd). Sep 12 10:11:43.647422 kernel: SELinux: policy capability network_peer_controls=1 Sep 12 10:11:43.647435 kernel: SELinux: policy capability open_perms=1 Sep 12 10:11:43.647445 kernel: SELinux: policy capability extended_socket_class=1 Sep 12 10:11:43.647453 kernel: SELinux: policy capability always_check_network=0 Sep 12 10:11:43.647463 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 12 10:11:43.647477 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 12 10:11:43.647490 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 12 10:11:43.647499 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 12 10:11:43.647509 kernel: audit: type=1403 audit(1757671897.649:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 12 10:11:43.647519 systemd[1]: Successfully loaded SELinux policy in 194.693ms. Sep 12 10:11:43.647531 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 15.499ms. Sep 12 10:11:43.647542 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 10:11:43.647552 systemd[1]: Detected virtualization microsoft. Sep 12 10:11:43.647566 systemd[1]: Detected architecture x86-64. Sep 12 10:11:43.647576 systemd[1]: Detected first boot. Sep 12 10:11:43.647587 systemd[1]: Hostname set to . Sep 12 10:11:43.647598 systemd[1]: Initializing machine ID from random generator. Sep 12 10:11:43.647608 zram_generator::config[1217]: No configuration found. Sep 12 10:11:43.647622 kernel: Guest personality initialized and is inactive Sep 12 10:11:43.647632 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Sep 12 10:11:43.647641 kernel: Initialized host personality Sep 12 10:11:43.647651 kernel: NET: Registered PF_VSOCK protocol family Sep 12 10:11:43.647661 systemd[1]: Populated /etc with preset unit settings. Sep 12 10:11:43.647672 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Sep 12 10:11:43.647683 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 12 10:11:43.647693 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 12 10:11:43.647710 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 12 10:11:43.647720 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 12 10:11:43.647732 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 12 10:11:43.647742 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 12 10:11:43.647753 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 12 10:11:43.647764 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 12 10:11:43.647775 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 12 10:11:43.647788 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 12 10:11:43.647799 systemd[1]: Created slice user.slice - User and Session Slice. Sep 12 10:11:43.647809 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 12 10:11:43.647820 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 12 10:11:43.647831 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 12 10:11:43.647841 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 12 10:11:43.647856 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 12 10:11:43.647868 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 12 10:11:43.647882 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Sep 12 10:11:43.647893 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 12 10:11:43.647903 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 12 10:11:43.647914 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 12 10:11:43.647925 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 12 10:11:43.647936 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 12 10:11:43.647950 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 12 10:11:43.647961 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 12 10:11:43.647975 systemd[1]: Reached target slices.target - Slice Units. Sep 12 10:11:43.647986 systemd[1]: Reached target swap.target - Swaps. Sep 12 10:11:43.647997 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 12 10:11:43.648008 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 12 10:11:43.648018 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Sep 12 10:11:43.648033 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 12 10:11:43.648054 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 12 10:11:43.648066 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 12 10:11:43.648077 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 12 10:11:43.648088 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 12 10:11:43.648099 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 12 10:11:43.648110 systemd[1]: Mounting media.mount - External Media Directory... Sep 12 10:11:43.648121 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 12 10:11:43.648136 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 12 10:11:43.648147 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 12 10:11:43.648158 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 12 10:11:43.648169 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 10:11:43.648180 systemd[1]: Reached target machines.target - Containers. Sep 12 10:11:43.648191 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 12 10:11:43.648206 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 12 10:11:43.648217 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 12 10:11:43.648232 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 12 10:11:43.648243 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 12 10:11:43.648254 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 12 10:11:43.648265 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 12 10:11:43.648277 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 12 10:11:43.648288 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 12 10:11:43.648299 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 12 10:11:43.648310 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 12 10:11:43.648325 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 12 10:11:43.648336 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 12 10:11:43.648347 systemd[1]: Stopped systemd-fsck-usr.service. Sep 12 10:11:43.648359 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 10:11:43.648370 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 12 10:11:43.648381 kernel: loop: module loaded Sep 12 10:11:43.648392 kernel: fuse: init (API version 7.39) Sep 12 10:11:43.648402 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 12 10:11:43.648436 systemd-journald[1324]: Collecting audit messages is disabled. Sep 12 10:11:43.648461 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 12 10:11:43.648474 systemd-journald[1324]: Journal started Sep 12 10:11:43.648503 systemd-journald[1324]: Runtime Journal (/run/log/journal/178269910bc44b28a9684eee04aadbb3) is 8M, max 2.2G, 2.1G free. Sep 12 10:11:42.833878 systemd[1]: Queued start job for default target multi-user.target. Sep 12 10:11:42.851363 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Sep 12 10:11:42.851823 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 12 10:11:43.660059 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 12 10:11:43.670663 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Sep 12 10:11:43.680694 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 12 10:11:43.690347 systemd[1]: verity-setup.service: Deactivated successfully. Sep 12 10:11:43.690387 systemd[1]: Stopped verity-setup.service. Sep 12 10:11:43.704051 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 10:11:43.704087 kernel: ACPI: bus type drm_connector registered Sep 12 10:11:43.707055 systemd[1]: Started systemd-journald.service - Journal Service. Sep 12 10:11:43.715595 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 12 10:11:43.719892 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 12 10:11:43.724300 systemd[1]: Mounted media.mount - External Media Directory. Sep 12 10:11:43.728183 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 12 10:11:43.732516 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 12 10:11:43.737219 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 12 10:11:43.741187 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 12 10:11:43.745926 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 12 10:11:43.750939 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 12 10:11:43.751146 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 12 10:11:43.756187 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 12 10:11:43.756375 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 12 10:11:43.761093 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 12 10:11:43.761282 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 12 10:11:43.765724 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 12 10:11:43.765910 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 12 10:11:43.770845 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 12 10:11:43.771080 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 12 10:11:43.775411 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 12 10:11:43.775594 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 12 10:11:43.780008 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 12 10:11:43.784610 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 12 10:11:43.789714 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 12 10:11:43.794798 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Sep 12 10:11:43.808833 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 12 10:11:43.828159 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 12 10:11:43.833657 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 12 10:11:43.838212 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 12 10:11:43.838253 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 12 10:11:43.843416 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Sep 12 10:11:43.849757 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 12 10:11:43.855296 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 12 10:11:43.859293 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 12 10:11:43.892165 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 12 10:11:43.897384 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 12 10:11:43.901937 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 12 10:11:43.902991 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 12 10:11:43.907230 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 12 10:11:43.908211 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 12 10:11:43.913184 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 12 10:11:43.918630 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 12 10:11:43.925138 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 12 10:11:43.929908 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 12 10:11:43.934767 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 12 10:11:43.944706 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 12 10:11:43.968223 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Sep 12 10:11:43.980002 udevadm[1367]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Sep 12 10:11:44.000906 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 12 10:11:44.005791 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 12 10:11:44.016119 systemd-journald[1324]: Time spent on flushing to /var/log/journal/178269910bc44b28a9684eee04aadbb3 is 42.073ms for 949 entries. Sep 12 10:11:44.016119 systemd-journald[1324]: System Journal (/var/log/journal/178269910bc44b28a9684eee04aadbb3) is 11.8M, max 2.6G, 2.6G free. Sep 12 10:11:44.168074 systemd-journald[1324]: Received client request to flush runtime journal. Sep 12 10:11:44.168142 systemd-journald[1324]: /var/log/journal/178269910bc44b28a9684eee04aadbb3/system.journal: Realtime clock jumped backwards relative to last journal entry, rotating. Sep 12 10:11:44.168175 systemd-journald[1324]: Rotating system journal. Sep 12 10:11:44.168210 kernel: loop0: detected capacity change from 0 to 138176 Sep 12 10:11:44.020175 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Sep 12 10:11:44.151031 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 12 10:11:44.169536 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 12 10:11:44.179800 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 12 10:11:44.180549 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Sep 12 10:11:44.220143 systemd-tmpfiles[1361]: ACLs are not supported, ignoring. Sep 12 10:11:44.220165 systemd-tmpfiles[1361]: ACLs are not supported, ignoring. Sep 12 10:11:44.236791 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 12 10:11:44.252193 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 12 10:11:44.772224 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 12 10:11:44.786228 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 12 10:11:44.800107 systemd-tmpfiles[1384]: ACLs are not supported, ignoring. Sep 12 10:11:44.800129 systemd-tmpfiles[1384]: ACLs are not supported, ignoring. Sep 12 10:11:44.804769 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 12 10:11:44.828056 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 12 10:11:44.968060 kernel: loop1: detected capacity change from 0 to 28272 Sep 12 10:11:45.698063 kernel: loop2: detected capacity change from 0 to 147912 Sep 12 10:11:45.820951 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 12 10:11:45.835756 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 12 10:11:45.860221 systemd-udevd[1389]: Using default interface naming scheme 'v255'. Sep 12 10:11:46.197070 kernel: loop3: detected capacity change from 0 to 138176 Sep 12 10:11:46.229061 kernel: loop4: detected capacity change from 0 to 28272 Sep 12 10:11:46.245054 kernel: loop5: detected capacity change from 0 to 147912 Sep 12 10:11:46.262150 (sd-merge)[1392]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Sep 12 10:11:46.262643 (sd-merge)[1392]: Merged extensions into '/usr'. Sep 12 10:11:46.266055 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 12 10:11:46.284184 systemd[1]: Starting ensure-sysext.service... Sep 12 10:11:46.288198 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 12 10:11:46.337093 systemd[1]: Reload requested from client PID 1394 ('systemctl') (unit ensure-sysext.service)... Sep 12 10:11:46.337110 systemd[1]: Reloading... Sep 12 10:11:46.347018 systemd-tmpfiles[1395]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 12 10:11:46.347347 systemd-tmpfiles[1395]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 12 10:11:46.348327 systemd-tmpfiles[1395]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 12 10:11:46.348618 systemd-tmpfiles[1395]: ACLs are not supported, ignoring. Sep 12 10:11:46.348691 systemd-tmpfiles[1395]: ACLs are not supported, ignoring. Sep 12 10:11:46.382064 zram_generator::config[1424]: No configuration found. Sep 12 10:11:46.394060 systemd-tmpfiles[1395]: Detected autofs mount point /boot during canonicalization of boot. Sep 12 10:11:46.394074 systemd-tmpfiles[1395]: Skipping /boot Sep 12 10:11:46.406885 systemd-tmpfiles[1395]: Detected autofs mount point /boot during canonicalization of boot. Sep 12 10:11:46.406901 systemd-tmpfiles[1395]: Skipping /boot Sep 12 10:11:46.524063 kernel: hv_vmbus: registering driver hv_balloon Sep 12 10:11:46.524147 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Sep 12 10:11:46.532400 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 10:11:46.533053 kernel: mousedev: PS/2 mouse device common for all mice Sep 12 10:11:46.552055 kernel: hv_vmbus: registering driver hyperv_fb Sep 12 10:11:46.552108 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Sep 12 10:11:46.558809 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Sep 12 10:11:46.562905 kernel: Console: switching to colour dummy device 80x25 Sep 12 10:11:46.566737 kernel: Console: switching to colour frame buffer device 128x48 Sep 12 10:11:46.636879 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Sep 12 10:11:46.637483 systemd[1]: Reloading finished in 299 ms. Sep 12 10:11:46.640518 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 35 scanned by (udev-worker) (1458) Sep 12 10:11:46.657751 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 12 10:11:46.666063 kernel: EDAC MC: Ver: 3.0.0 Sep 12 10:11:46.679920 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 12 10:11:46.713258 systemd[1]: Finished ensure-sysext.service. Sep 12 10:11:46.747785 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Sep 12 10:11:46.757780 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Sep 12 10:11:46.767175 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 12 10:11:46.784176 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 12 10:11:46.822171 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 12 10:11:46.826729 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 12 10:11:46.827712 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Sep 12 10:11:46.833085 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 12 10:11:46.838084 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 12 10:11:46.842813 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 12 10:11:46.848182 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 12 10:11:46.852206 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 12 10:11:46.853118 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 12 10:11:46.857790 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 10:11:46.858867 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 12 10:11:46.865104 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 12 10:11:46.870691 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 12 10:11:46.874999 systemd[1]: Reached target time-set.target - System Time Set. Sep 12 10:11:46.879984 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 12 10:11:46.885625 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 12 10:11:46.889865 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 10:11:46.891071 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 12 10:11:46.891273 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 12 10:11:46.896135 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 12 10:11:46.896326 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 12 10:11:46.900897 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 12 10:11:46.901185 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 12 10:11:46.907847 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 12 10:11:46.908055 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 12 10:11:46.914646 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 12 10:11:46.914713 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 12 10:11:46.917225 lvm[1601]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Sep 12 10:11:46.920266 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 12 10:11:46.945214 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 12 10:11:46.979242 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 12 10:11:46.981134 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Sep 12 10:11:46.982166 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 12 10:11:46.984914 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Sep 12 10:11:46.992363 lvm[1637]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Sep 12 10:11:47.034448 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Sep 12 10:11:47.038104 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 12 10:11:47.053238 augenrules[1647]: No rules Sep 12 10:11:47.054452 systemd[1]: audit-rules.service: Deactivated successfully. Sep 12 10:11:47.054706 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 12 10:11:47.152197 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 12 10:11:47.199383 systemd-networkd[1613]: lo: Link UP Sep 12 10:11:47.199393 systemd-networkd[1613]: lo: Gained carrier Sep 12 10:11:47.200859 systemd-networkd[1613]: Enumeration completed Sep 12 10:11:47.200969 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 12 10:11:47.201380 systemd-networkd[1613]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 12 10:11:47.201386 systemd-networkd[1613]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 12 10:11:47.202394 systemd-networkd[1613]: eth0: Link UP Sep 12 10:11:47.202401 systemd-networkd[1613]: eth0: Gained carrier Sep 12 10:11:47.202417 systemd-networkd[1613]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 12 10:11:47.216174 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Sep 12 10:11:47.218781 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 12 10:11:47.221776 systemd-resolved[1614]: Positive Trust Anchors: Sep 12 10:11:47.221786 systemd-resolved[1614]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 12 10:11:47.221817 systemd-resolved[1614]: 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 10:11:47.245081 systemd-networkd[1613]: eth0: DHCPv4 address 10.200.8.23/24, gateway 10.200.8.1 acquired from 168.63.129.16 Sep 12 10:11:47.257836 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Sep 12 10:11:47.279270 systemd-resolved[1614]: Using system hostname 'ci-4230.2.2-n-d02a5ec55b'. Sep 12 10:11:47.281123 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 12 10:11:47.282630 systemd[1]: Reached target network.target - Network. Sep 12 10:11:47.283257 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 12 10:11:47.874201 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 10:11:49.034794 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 12 10:11:49.040177 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 10:11:49.060141 systemd-networkd[1613]: eth0: Gained IPv6LL Sep 12 10:11:49.062466 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 12 10:11:49.067899 systemd[1]: Reached target network-online.target - Network is Online. Sep 12 10:11:52.686131 ldconfig[1354]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 12 10:11:52.706333 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 12 10:11:52.729203 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 12 10:11:52.749134 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 12 10:11:52.753872 systemd[1]: Reached target sysinit.target - System Initialization. Sep 12 10:11:52.758091 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 12 10:11:52.762938 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 10:11:52.768188 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 12 10:11:52.772517 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 12 10:11:52.777437 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 12 10:11:52.782568 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 12 10:11:52.782614 systemd[1]: Reached target paths.target - Path Units. Sep 12 10:11:52.786230 systemd[1]: Reached target timers.target - Timer Units. Sep 12 10:11:52.814268 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 12 10:11:52.820084 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 12 10:11:52.826951 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Sep 12 10:11:52.835208 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Sep 12 10:11:52.840398 systemd[1]: Reached target ssh-access.target - SSH Access Available. Sep 12 10:11:52.847003 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 12 10:11:52.851480 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Sep 12 10:11:52.856761 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 12 10:11:52.861123 systemd[1]: Reached target sockets.target - Socket Units. Sep 12 10:11:52.864908 systemd[1]: Reached target basic.target - Basic System. Sep 12 10:11:52.868540 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 12 10:11:52.868593 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 12 10:11:52.891138 systemd[1]: Starting chronyd.service - NTP client/server... Sep 12 10:11:52.895522 systemd[1]: Starting containerd.service - containerd container runtime... Sep 12 10:11:52.900688 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Sep 12 10:11:52.905885 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 12 10:11:52.910514 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 12 10:11:52.915400 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 12 10:11:52.919442 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 10:11:52.919491 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 10:11:52.920491 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Sep 12 10:11:52.924799 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 10:11:52.925997 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 12 10:11:52.930074 jq[1678]: false Sep 12 10:11:52.930831 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 12 10:11:52.935913 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 12 10:11:52.942565 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 12 10:11:52.949741 KVP[1680]: KVP starting; pid is:1680 Sep 12 10:11:52.950614 (chronyd)[1671]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Sep 12 10:11:52.951936 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 12 10:11:52.953547 KVP[1680]: KVP LIC Version: 3.1 Sep 12 10:11:52.954055 kernel: hv_utils: KVP IC version 4.0 Sep 12 10:11:52.958412 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 12 10:11:52.958993 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 12 10:11:52.959523 chronyd[1688]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) Sep 12 10:11:52.960198 systemd[1]: Starting update-engine.service - Update Engine... Sep 12 10:11:52.965187 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 12 10:11:52.972884 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 12 10:11:52.973312 jq[1692]: true Sep 12 10:11:52.973121 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 12 10:11:52.973444 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 12 10:11:52.973640 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 12 10:11:52.987053 jq[1696]: true Sep 12 10:11:52.994623 (ntainerd)[1700]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 12 10:11:52.997634 systemd[1]: motdgen.service: Deactivated successfully. Sep 12 10:11:52.997874 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 12 10:11:53.011217 extend-filesystems[1679]: Found loop3 Sep 12 10:11:53.014002 extend-filesystems[1679]: Found loop4 Sep 12 10:11:53.014002 extend-filesystems[1679]: Found loop5 Sep 12 10:11:53.014002 extend-filesystems[1679]: Found sda Sep 12 10:11:53.014002 extend-filesystems[1679]: Found sda1 Sep 12 10:11:53.014002 extend-filesystems[1679]: Found sda2 Sep 12 10:11:53.014002 extend-filesystems[1679]: Found sda3 Sep 12 10:11:53.014002 extend-filesystems[1679]: Found usr Sep 12 10:11:53.014002 extend-filesystems[1679]: Found sda4 Sep 12 10:11:53.014002 extend-filesystems[1679]: Found sda6 Sep 12 10:11:53.014002 extend-filesystems[1679]: Found sda7 Sep 12 10:11:53.014002 extend-filesystems[1679]: Found sda9 Sep 12 10:11:53.014002 extend-filesystems[1679]: Checking size of /dev/sda9 Sep 12 10:11:53.013108 chronyd[1688]: Timezone right/UTC failed leap second check, ignoring Sep 12 10:11:53.049337 update_engine[1690]: I20250912 10:11:53.035135 1690 main.cc:92] Flatcar Update Engine starting Sep 12 10:11:53.015193 systemd[1]: Started chronyd.service - NTP client/server. Sep 12 10:11:53.013276 chronyd[1688]: Loaded seccomp filter (level 2) Sep 12 10:11:53.053526 extend-filesystems[1679]: Old size kept for /dev/sda9 Sep 12 10:11:53.056760 extend-filesystems[1679]: Found sdb Sep 12 10:11:53.056760 extend-filesystems[1679]: Found sdb1 Sep 12 10:11:53.056760 extend-filesystems[1679]: Found sr0 Sep 12 10:11:53.054754 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 12 10:11:53.054996 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 12 10:11:53.070396 systemd-logind[1686]: New seat seat0. Sep 12 10:11:53.073619 systemd-logind[1686]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Sep 12 10:11:53.074015 systemd[1]: Started systemd-logind.service - User Login Management. Sep 12 10:11:53.096369 bash[1724]: Updated "/home/core/.ssh/authorized_keys" Sep 12 10:11:53.097157 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 12 10:11:53.105694 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Sep 12 10:11:53.107634 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 35 scanned by (udev-worker) (1738) Sep 12 10:11:53.149121 setup-nvidia[1682]: Downloading Flatcar Container Linux Developer Container for version: 4230.2.2+nightly-20250911-2100 Sep 12 10:11:53.175577 sshd_keygen[1729]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 12 10:11:53.196984 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 12 10:11:53.213193 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 12 10:11:53.217910 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Sep 12 10:11:53.222318 systemd[1]: issuegen.service: Deactivated successfully. Sep 12 10:11:53.222529 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 12 10:11:53.229615 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 12 10:11:53.235324 dbus-daemon[1674]: [system] SELinux support is enabled Sep 12 10:11:53.235476 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 12 10:11:53.237822 update_engine[1690]: I20250912 10:11:53.237766 1690 update_check_scheduler.cc:74] Next update check in 10m53s Sep 12 10:11:53.243012 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Sep 12 10:11:53.247829 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 10:11:53.247867 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 12 10:11:53.248334 dbus-daemon[1674]: [system] Successfully activated service 'org.freedesktop.systemd1' Sep 12 10:11:53.252805 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 10:11:53.252834 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 12 10:11:53.259793 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 12 10:11:53.265547 systemd[1]: Started update-engine.service - Update Engine. Sep 12 10:11:53.271556 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 12 10:11:53.276297 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Sep 12 10:11:53.280681 systemd[1]: Reached target getty.target - Login Prompts. Sep 12 10:11:53.285510 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 12 10:11:53.301701 coreos-metadata[1673]: Sep 12 10:11:53.301 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Sep 12 10:11:53.304444 coreos-metadata[1673]: Sep 12 10:11:53.304 INFO Fetch successful Sep 12 10:11:53.304590 coreos-metadata[1673]: Sep 12 10:11:53.304 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Sep 12 10:11:53.316144 coreos-metadata[1673]: Sep 12 10:11:53.316 INFO Fetch successful Sep 12 10:11:53.316447 coreos-metadata[1673]: Sep 12 10:11:53.316 INFO Fetching http://168.63.129.16/machine/42b37ad2-005d-4154-8e83-6446e3e2d9ba/be6400aa%2De865%2D4df0%2D9bd8%2D8da0432e5f69.%5Fci%2D4230.2.2%2Dn%2Dd02a5ec55b?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Sep 12 10:11:53.317983 coreos-metadata[1673]: Sep 12 10:11:53.317 INFO Fetch successful Sep 12 10:11:53.318151 coreos-metadata[1673]: Sep 12 10:11:53.318 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Sep 12 10:11:53.325174 coreos-metadata[1673]: Sep 12 10:11:53.325 INFO Fetch successful Sep 12 10:11:53.346652 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Sep 12 10:11:53.354618 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 12 10:11:53.379647 locksmithd[1824]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 12 10:11:54.088332 containerd[1700]: time="2025-09-12T10:11:54.088248100Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Sep 12 10:11:54.107580 containerd[1700]: time="2025-09-12T10:11:54.107541100Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Sep 12 10:11:54.109422 containerd[1700]: time="2025-09-12T10:11:54.109386400Z" 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.105-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Sep 12 10:11:54.109456 containerd[1700]: time="2025-09-12T10:11:54.109420000Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Sep 12 10:11:54.109456 containerd[1700]: time="2025-09-12T10:11:54.109437800Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Sep 12 10:11:54.109609 containerd[1700]: time="2025-09-12T10:11:54.109584600Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Sep 12 10:11:54.109645 containerd[1700]: time="2025-09-12T10:11:54.109625600Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Sep 12 10:11:54.109721 containerd[1700]: time="2025-09-12T10:11:54.109697800Z" 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 10:11:54.109721 containerd[1700]: time="2025-09-12T10:11:54.109717700Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Sep 12 10:11:54.109973 containerd[1700]: time="2025-09-12T10:11:54.109947100Z" 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 10:11:54.109973 containerd[1700]: time="2025-09-12T10:11:54.109970600Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Sep 12 10:11:54.110030 containerd[1700]: time="2025-09-12T10:11:54.109984600Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Sep 12 10:11:54.110030 containerd[1700]: time="2025-09-12T10:11:54.109994400Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Sep 12 10:11:54.110121 containerd[1700]: time="2025-09-12T10:11:54.110098700Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Sep 12 10:11:54.110346 containerd[1700]: time="2025-09-12T10:11:54.110324200Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Sep 12 10:11:54.110480 containerd[1700]: time="2025-09-12T10:11:54.110456500Z" 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 10:11:54.110480 containerd[1700]: time="2025-09-12T10:11:54.110476100Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Sep 12 10:11:54.110577 containerd[1700]: time="2025-09-12T10:11:54.110557300Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Sep 12 10:11:54.110633 containerd[1700]: time="2025-09-12T10:11:54.110614300Z" level=info msg="metadata content store policy set" policy=shared Sep 12 10:11:54.128928 containerd[1700]: time="2025-09-12T10:11:54.128898000Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Sep 12 10:11:54.128988 containerd[1700]: time="2025-09-12T10:11:54.128942400Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Sep 12 10:11:54.128988 containerd[1700]: time="2025-09-12T10:11:54.128960300Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Sep 12 10:11:54.128988 containerd[1700]: time="2025-09-12T10:11:54.128976100Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Sep 12 10:11:54.129069 containerd[1700]: time="2025-09-12T10:11:54.128990300Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Sep 12 10:11:54.129157 containerd[1700]: time="2025-09-12T10:11:54.129133500Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Sep 12 10:11:54.129513 containerd[1700]: time="2025-09-12T10:11:54.129478300Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Sep 12 10:11:54.129652 containerd[1700]: time="2025-09-12T10:11:54.129630500Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Sep 12 10:11:54.129687 containerd[1700]: time="2025-09-12T10:11:54.129661200Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Sep 12 10:11:54.129687 containerd[1700]: time="2025-09-12T10:11:54.129681900Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Sep 12 10:11:54.129744 containerd[1700]: time="2025-09-12T10:11:54.129702100Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Sep 12 10:11:54.129744 containerd[1700]: time="2025-09-12T10:11:54.129720800Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Sep 12 10:11:54.129744 containerd[1700]: time="2025-09-12T10:11:54.129737900Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Sep 12 10:11:54.129804 containerd[1700]: time="2025-09-12T10:11:54.129754900Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Sep 12 10:11:54.129833 containerd[1700]: time="2025-09-12T10:11:54.129812000Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Sep 12 10:11:54.129887 containerd[1700]: time="2025-09-12T10:11:54.129862300Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Sep 12 10:11:54.130021 containerd[1700]: time="2025-09-12T10:11:54.129920400Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Sep 12 10:11:54.130069 containerd[1700]: time="2025-09-12T10:11:54.130023200Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Sep 12 10:11:54.130095 containerd[1700]: time="2025-09-12T10:11:54.130077100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Sep 12 10:11:54.130118 containerd[1700]: time="2025-09-12T10:11:54.130097100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Sep 12 10:11:54.130118 containerd[1700]: time="2025-09-12T10:11:54.130109900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Sep 12 10:11:54.130186 containerd[1700]: time="2025-09-12T10:11:54.130122300Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Sep 12 10:11:54.130186 containerd[1700]: time="2025-09-12T10:11:54.130134100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Sep 12 10:11:54.130186 containerd[1700]: time="2025-09-12T10:11:54.130146700Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Sep 12 10:11:54.130186 containerd[1700]: time="2025-09-12T10:11:54.130157300Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Sep 12 10:11:54.130186 containerd[1700]: time="2025-09-12T10:11:54.130170100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Sep 12 10:11:54.130186 containerd[1700]: time="2025-09-12T10:11:54.130182900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Sep 12 10:11:54.130308 containerd[1700]: time="2025-09-12T10:11:54.130197600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Sep 12 10:11:54.130308 containerd[1700]: time="2025-09-12T10:11:54.130209200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Sep 12 10:11:54.130308 containerd[1700]: time="2025-09-12T10:11:54.130220000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Sep 12 10:11:54.130308 containerd[1700]: time="2025-09-12T10:11:54.130230600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Sep 12 10:11:54.130308 containerd[1700]: time="2025-09-12T10:11:54.130243500Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Sep 12 10:11:54.130456 containerd[1700]: time="2025-09-12T10:11:54.130304700Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Sep 12 10:11:54.130456 containerd[1700]: time="2025-09-12T10:11:54.130330400Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Sep 12 10:11:54.130456 containerd[1700]: time="2025-09-12T10:11:54.130341200Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Sep 12 10:11:54.130456 containerd[1700]: time="2025-09-12T10:11:54.130393100Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Sep 12 10:11:54.130456 containerd[1700]: time="2025-09-12T10:11:54.130410400Z" 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 10:11:54.130456 containerd[1700]: time="2025-09-12T10:11:54.130420900Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Sep 12 10:11:54.130456 containerd[1700]: time="2025-09-12T10:11:54.130432400Z" 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 10:11:54.130456 containerd[1700]: time="2025-09-12T10:11:54.130441900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Sep 12 10:11:54.130456 containerd[1700]: time="2025-09-12T10:11:54.130453400Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Sep 12 10:11:54.130644 containerd[1700]: time="2025-09-12T10:11:54.130463500Z" level=info msg="NRI interface is disabled by configuration." Sep 12 10:11:54.130644 containerd[1700]: time="2025-09-12T10:11:54.130472200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Sep 12 10:11:54.130767 containerd[1700]: time="2025-09-12T10:11:54.130716100Z" 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 10:11:54.130767 containerd[1700]: time="2025-09-12T10:11:54.130766300Z" level=info msg="Connect containerd service" Sep 12 10:11:54.130939 containerd[1700]: time="2025-09-12T10:11:54.130791300Z" level=info msg="using legacy CRI server" Sep 12 10:11:54.130939 containerd[1700]: time="2025-09-12T10:11:54.130798700Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 12 10:11:54.130939 containerd[1700]: time="2025-09-12T10:11:54.130896000Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Sep 12 10:11:54.131548 containerd[1700]: time="2025-09-12T10:11:54.131519100Z" 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 10:11:54.131698 containerd[1700]: time="2025-09-12T10:11:54.131662700Z" level=info msg="Start subscribing containerd event" Sep 12 10:11:54.131739 containerd[1700]: time="2025-09-12T10:11:54.131708200Z" level=info msg="Start recovering state" Sep 12 10:11:54.131786 containerd[1700]: time="2025-09-12T10:11:54.131767300Z" level=info msg="Start event monitor" Sep 12 10:11:54.131820 containerd[1700]: time="2025-09-12T10:11:54.131793000Z" level=info msg="Start snapshots syncer" Sep 12 10:11:54.131820 containerd[1700]: time="2025-09-12T10:11:54.131804000Z" level=info msg="Start cni network conf syncer for default" Sep 12 10:11:54.131820 containerd[1700]: time="2025-09-12T10:11:54.131812700Z" level=info msg="Start streaming server" Sep 12 10:11:54.131880 containerd[1700]: time="2025-09-12T10:11:54.131821100Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 12 10:11:54.131906 containerd[1700]: time="2025-09-12T10:11:54.131891800Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 12 10:11:54.131990 containerd[1700]: time="2025-09-12T10:11:54.131970500Z" level=info msg="containerd successfully booted in 0.045101s" Sep 12 10:11:54.132070 systemd[1]: Started containerd.service - containerd container runtime. Sep 12 10:11:54.319012 setup-nvidia[1845]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 12 10:11:54.319313 setup-nvidia[1845]: Dload Upload Total Spent Left Speed Sep 12 10:11:55.552721 waagent[1819]: 2025-09-12T10:11:55.552626Z INFO Daemon Daemon Azure Linux Agent Version: 2.9.1.1 Sep 12 10:11:55.556910 waagent[1819]: 2025-09-12T10:11:55.556857Z INFO Daemon Daemon OS: flatcar 4230.2.2 Sep 12 10:11:55.560296 waagent[1819]: 2025-09-12T10:11:55.560245Z INFO Daemon Daemon Python: 3.11.11 Sep 12 10:11:55.563481 waagent[1819]: 2025-09-12T10:11:55.563424Z INFO Daemon Daemon Run daemon Sep 12 10:11:55.566442 waagent[1819]: 2025-09-12T10:11:55.566391Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4230.2.2' Sep 12 10:11:55.572973 waagent[1819]: 2025-09-12T10:11:55.572921Z INFO Daemon Daemon Using waagent for provisioning Sep 12 10:11:55.576875 waagent[1819]: 2025-09-12T10:11:55.576823Z INFO Daemon Daemon Activate resource disk Sep 12 10:11:55.580347 waagent[1819]: 2025-09-12T10:11:55.580289Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Sep 12 10:11:55.587689 waagent[1819]: 2025-09-12T10:11:55.587635Z INFO Daemon Daemon Found device: sdb Sep 12 10:11:55.596792 waagent[1819]: 2025-09-12T10:11:55.596736Z INFO Daemon Daemon Examining partition table Sep 12 10:11:55.672863 waagent[1819]: 2025-09-12T10:11:55.672791Z INFO Daemon Daemon GPT not detected, determining filesystem Sep 12 10:11:55.738380 waagent[1819]: 2025-09-12T10:11:55.738291Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Sep 12 10:11:55.742806 waagent[1819]: 2025-09-12T10:11:55.742737Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Sep 12 10:11:56.068066 kernel: sdb: sdb1 Sep 12 10:11:56.072055 kernel: sdb: sdb1 Sep 12 10:11:56.109225 waagent[1819]: 2025-09-12T10:11:56.109137Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Sep 12 10:11:56.123342 waagent[1819]: 2025-09-12T10:11:56.123283Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Sep 12 10:11:56.127460 kernel: sdb: sdb1 Sep 12 10:12:01.083938 login[1823]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Sep 12 10:12:01.191782 login[1822]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Sep 12 10:12:01.203237 systemd-logind[1686]: New session 1 of user core. Sep 12 10:12:01.204306 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 12 10:12:01.217260 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 12 10:12:01.300227 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 12 10:12:01.316270 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 12 10:12:01.386008 (systemd)[1923]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 12 10:12:01.388579 systemd-logind[1686]: New session c1 of user core. Sep 12 10:12:01.715422 waagent[1819]: 2025-09-12T10:12:01.715277Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Sep 12 10:12:01.737797 waagent[1819]: 2025-09-12T10:12:01.737717Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Sep 12 10:12:01.738066 kernel: EXT4-fs (sdb1): mounted filesystem a69bf1c4-dbbb-4f55-bd1b-7bdcd28074bf r/w with ordered data mode. Quota mode: none. Sep 12 10:12:01.739922 waagent[1819]: 2025-09-12T10:12:01.739871Z INFO Daemon Daemon Clean protocol and wireserver endpoint Sep 12 10:12:01.740156 waagent[1819]: 2025-09-12T10:12:01.740113Z INFO Daemon Daemon Running default provisioning handler Sep 12 10:12:01.754537 waagent[1819]: 2025-09-12T10:12:01.753375Z 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 10:12:01.756563 waagent[1819]: 2025-09-12T10:12:01.756512Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Sep 12 10:12:01.756800 waagent[1819]: 2025-09-12T10:12:01.756757Z INFO Daemon Daemon cloud-init is enabled: False Sep 12 10:12:01.761351 waagent[1819]: 2025-09-12T10:12:01.761306Z INFO Daemon Daemon Copying ovf-env.xml Sep 12 10:12:01.954795 waagent[1819]: 2025-09-12T10:12:01.954697Z INFO Daemon Daemon Successfully mounted dvd Sep 12 10:12:01.990524 systemd[1923]: Queued start job for default target default.target. Sep 12 10:12:02.003261 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Sep 12 10:12:02.005297 waagent[1819]: 2025-09-12T10:12:02.003233Z INFO Daemon Daemon Detect protocol endpoint Sep 12 10:12:02.005297 waagent[1819]: 2025-09-12T10:12:02.004795Z INFO Daemon Daemon Clean protocol and wireserver endpoint Sep 12 10:12:02.006252 waagent[1819]: 2025-09-12T10:12:02.006209Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Sep 12 10:12:02.006832 waagent[1819]: 2025-09-12T10:12:02.006793Z INFO Daemon Daemon Test for route to 168.63.129.16 Sep 12 10:12:02.007939 systemd[1923]: Created slice app.slice - User Application Slice. Sep 12 10:12:02.007972 systemd[1923]: Reached target paths.target - Paths. Sep 12 10:12:02.008022 systemd[1923]: Reached target timers.target - Timers. Sep 12 10:12:02.008376 waagent[1819]: 2025-09-12T10:12:02.008331Z INFO Daemon Daemon Route to 168.63.129.16 exists Sep 12 10:12:02.009374 systemd[1923]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 12 10:12:02.009957 waagent[1819]: 2025-09-12T10:12:02.009909Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Sep 12 10:12:02.019940 systemd[1923]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 12 10:12:02.020000 systemd[1923]: Reached target sockets.target - Sockets. Sep 12 10:12:02.020060 systemd[1923]: Reached target basic.target - Basic System. Sep 12 10:12:02.020117 systemd[1923]: Reached target default.target - Main User Target. Sep 12 10:12:02.020149 systemd[1923]: Startup finished in 625ms. Sep 12 10:12:02.020231 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 12 10:12:02.021636 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 12 10:12:02.064532 waagent[1819]: 2025-09-12T10:12:02.064423Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Sep 12 10:12:02.067423 waagent[1819]: 2025-09-12T10:12:02.067383Z INFO Daemon Daemon Wire protocol version:2012-11-30 Sep 12 10:12:02.067942 waagent[1819]: 2025-09-12T10:12:02.067901Z INFO Daemon Daemon Server preferred version:2015-04-05 Sep 12 10:12:02.085766 login[1823]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Sep 12 10:12:02.090250 systemd-logind[1686]: New session 2 of user core. Sep 12 10:12:02.105176 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 12 10:12:02.184265 waagent[1819]: 2025-09-12T10:12:02.184165Z INFO Daemon Daemon Initializing goal state during protocol detection Sep 12 10:12:02.185888 waagent[1819]: 2025-09-12T10:12:02.185834Z INFO Daemon Daemon Forcing an update of the goal state. Sep 12 10:12:02.190546 waagent[1819]: 2025-09-12T10:12:02.190497Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Sep 12 10:12:02.224103 waagent[1819]: 2025-09-12T10:12:02.224031Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.175 Sep 12 10:12:02.226116 waagent[1819]: 2025-09-12T10:12:02.226061Z INFO Daemon Sep 12 10:12:02.227136 waagent[1819]: 2025-09-12T10:12:02.227088Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 36e333e7-bd10-4e29-a24a-d271315d963e eTag: 8288475755893748169 source: Fabric] Sep 12 10:12:02.228107 waagent[1819]: 2025-09-12T10:12:02.228062Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Sep 12 10:12:02.229093 waagent[1819]: 2025-09-12T10:12:02.229032Z INFO Daemon Sep 12 10:12:02.229243 waagent[1819]: 2025-09-12T10:12:02.229205Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Sep 12 10:12:02.233817 waagent[1819]: 2025-09-12T10:12:02.233778Z INFO Daemon Daemon Downloading artifacts profile blob Sep 12 10:12:02.368942 waagent[1819]: 2025-09-12T10:12:02.368805Z INFO Daemon Downloaded certificate {'thumbprint': 'F71992916925B1AC31DEBEF2DAE4C8FBB955D8A0', 'hasPrivateKey': True} Sep 12 10:12:02.376504 waagent[1819]: 2025-09-12T10:12:02.376452Z INFO Daemon Fetch goal state completed Sep 12 10:12:02.411470 waagent[1819]: 2025-09-12T10:12:02.411414Z INFO Daemon Daemon Starting provisioning Sep 12 10:12:02.415386 waagent[1819]: 2025-09-12T10:12:02.415317Z INFO Daemon Daemon Handle ovf-env.xml. Sep 12 10:12:02.416952 waagent[1819]: 2025-09-12T10:12:02.416897Z INFO Daemon Daemon Set hostname [ci-4230.2.2-n-d02a5ec55b] Sep 12 10:12:02.469681 waagent[1819]: 2025-09-12T10:12:02.469626Z INFO Daemon Daemon Publish hostname [ci-4230.2.2-n-d02a5ec55b] Sep 12 10:12:02.471411 waagent[1819]: 2025-09-12T10:12:02.471361Z INFO Daemon Daemon Examine /proc/net/route for primary interface Sep 12 10:12:02.472156 waagent[1819]: 2025-09-12T10:12:02.472111Z INFO Daemon Daemon Primary interface is [eth0] Sep 12 10:12:02.503167 systemd-networkd[1613]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 12 10:12:02.503177 systemd-networkd[1613]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 12 10:12:02.503202 systemd-networkd[1613]: eth0: DHCP lease lost Sep 12 10:12:02.503613 waagent[1819]: 2025-09-12T10:12:02.503558Z INFO Daemon Daemon Create user account if not exists Sep 12 10:12:02.505241 waagent[1819]: 2025-09-12T10:12:02.505188Z INFO Daemon Daemon User core already exists, skip useradd Sep 12 10:12:02.505904 waagent[1819]: 2025-09-12T10:12:02.505864Z INFO Daemon Daemon Configure sudoer Sep 12 10:12:02.507640 waagent[1819]: 2025-09-12T10:12:02.507592Z INFO Daemon Daemon Configure sshd Sep 12 10:12:02.508338 waagent[1819]: 2025-09-12T10:12:02.508288Z 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 10:12:02.508810 waagent[1819]: 2025-09-12T10:12:02.508770Z INFO Daemon Daemon Deploy ssh public key. Sep 12 10:12:02.571071 systemd-networkd[1613]: eth0: DHCPv4 address 10.200.8.23/24, gateway 10.200.8.1 acquired from 168.63.129.16 Sep 12 10:12:03.718532 waagent[1819]: 2025-09-12T10:12:03.718453Z INFO Daemon Daemon Provisioning complete Sep 12 10:12:03.727937 waagent[1819]: 2025-09-12T10:12:03.727890Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Sep 12 10:12:03.729550 waagent[1819]: 2025-09-12T10:12:03.729504Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Sep 12 10:12:03.731075 waagent[1819]: 2025-09-12T10:12:03.731014Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.9.1.1 is the most current agent Sep 12 10:12:03.872604 waagent[1982]: 2025-09-12T10:12:03.872503Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.9.1.1) Sep 12 10:12:03.872989 waagent[1982]: 2025-09-12T10:12:03.872659Z INFO ExtHandler ExtHandler OS: flatcar 4230.2.2 Sep 12 10:12:03.872989 waagent[1982]: 2025-09-12T10:12:03.872738Z INFO ExtHandler ExtHandler Python: 3.11.11 Sep 12 10:12:03.951613 waagent[1982]: 2025-09-12T10:12:03.951531Z INFO ExtHandler ExtHandler Distro: flatcar-4230.2.2; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.11; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; Sep 12 10:12:03.951821 waagent[1982]: 2025-09-12T10:12:03.951776Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 12 10:12:03.951907 waagent[1982]: 2025-09-12T10:12:03.951868Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 12 10:12:03.959668 waagent[1982]: 2025-09-12T10:12:03.959604Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Sep 12 10:12:03.964399 waagent[1982]: 2025-09-12T10:12:03.964349Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.175 Sep 12 10:12:03.964842 waagent[1982]: 2025-09-12T10:12:03.964796Z INFO ExtHandler Sep 12 10:12:03.964927 waagent[1982]: 2025-09-12T10:12:03.964889Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: ed867f2c-cc10-43f6-a48b-bd17a7849765 eTag: 8288475755893748169 source: Fabric] Sep 12 10:12:03.965287 waagent[1982]: 2025-09-12T10:12:03.965241Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Sep 12 10:12:03.965965 waagent[1982]: 2025-09-12T10:12:03.965917Z INFO ExtHandler Sep 12 10:12:03.966228 waagent[1982]: 2025-09-12T10:12:03.966008Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Sep 12 10:12:03.969702 waagent[1982]: 2025-09-12T10:12:03.969634Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Sep 12 10:12:04.032862 waagent[1982]: 2025-09-12T10:12:04.032791Z INFO ExtHandler Downloaded certificate {'thumbprint': 'F71992916925B1AC31DEBEF2DAE4C8FBB955D8A0', 'hasPrivateKey': True} Sep 12 10:12:04.033369 waagent[1982]: 2025-09-12T10:12:04.033315Z INFO ExtHandler Fetch goal state completed Sep 12 10:12:04.044573 waagent[1982]: 2025-09-12T10:12:04.044511Z INFO ExtHandler ExtHandler WALinuxAgent-2.9.1.1 running as process 1982 Sep 12 10:12:04.044735 waagent[1982]: 2025-09-12T10:12:04.044689Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Sep 12 10:12:04.046571 waagent[1982]: 2025-09-12T10:12:04.046524Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '4230.2.2', '', 'Flatcar Container Linux by Kinvolk'] Sep 12 10:12:04.046951 waagent[1982]: 2025-09-12T10:12:04.046905Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Sep 12 10:12:04.118651 waagent[1982]: 2025-09-12T10:12:04.118589Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Sep 12 10:12:04.118853 waagent[1982]: 2025-09-12T10:12:04.118807Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Sep 12 10:12:04.125583 waagent[1982]: 2025-09-12T10:12:04.125543Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Sep 12 10:12:04.133124 systemd[1]: Reload requested from client PID 1995 ('systemctl') (unit waagent.service)... Sep 12 10:12:04.133143 systemd[1]: Reloading... Sep 12 10:12:04.188061 zram_generator::config[2035]: No configuration found. Sep 12 10:12:04.323241 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 10:12:04.470135 systemd[1]: Reloading finished in 336 ms. Sep 12 10:12:04.483864 waagent[1982]: 2025-09-12T10:12:04.483773Z INFO ExtHandler ExtHandler Executing systemctl daemon-reload for setting up waagent-network-setup.service Sep 12 10:12:04.489673 systemd[1]: Reload requested from client PID 2092 ('systemctl') (unit waagent.service)... Sep 12 10:12:04.489690 systemd[1]: Reloading... Sep 12 10:12:04.556125 zram_generator::config[2132]: No configuration found. Sep 12 10:12:04.688944 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 10:12:04.844745 systemd[1]: Reloading finished in 354 ms. Sep 12 10:12:04.862212 waagent[1982]: 2025-09-12T10:12:04.862121Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Sep 12 10:12:04.862325 waagent[1982]: 2025-09-12T10:12:04.862288Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Sep 12 10:12:05.346890 waagent[1982]: 2025-09-12T10:12:05.346793Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Sep 12 10:12:05.347465 waagent[1982]: 2025-09-12T10:12:05.347406Z 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 10:12:05.348271 waagent[1982]: 2025-09-12T10:12:05.348220Z INFO ExtHandler ExtHandler Starting env monitor service. Sep 12 10:12:05.348641 waagent[1982]: 2025-09-12T10:12:05.348586Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 12 10:12:05.348831 waagent[1982]: 2025-09-12T10:12:05.348785Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Sep 12 10:12:05.349147 waagent[1982]: 2025-09-12T10:12:05.349101Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 12 10:12:05.349273 waagent[1982]: 2025-09-12T10:12:05.349221Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 12 10:12:05.349563 waagent[1982]: 2025-09-12T10:12:05.349510Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Sep 12 10:12:05.349856 waagent[1982]: 2025-09-12T10:12:05.349791Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Sep 12 10:12:05.349944 waagent[1982]: 2025-09-12T10:12:05.349901Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 12 10:12:05.350008 waagent[1982]: 2025-09-12T10:12:05.349962Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Sep 12 10:12:05.350397 waagent[1982]: 2025-09-12T10:12:05.350346Z INFO EnvHandler ExtHandler Configure routes Sep 12 10:12:05.350889 waagent[1982]: 2025-09-12T10:12:05.350823Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Sep 12 10:12:05.350958 waagent[1982]: 2025-09-12T10:12:05.350916Z INFO EnvHandler ExtHandler Gateway:None Sep 12 10:12:05.351088 waagent[1982]: 2025-09-12T10:12:05.351032Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Sep 12 10:12:05.351194 waagent[1982]: 2025-09-12T10:12:05.351144Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Sep 12 10:12:05.351194 waagent[1982]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Sep 12 10:12:05.351194 waagent[1982]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Sep 12 10:12:05.351194 waagent[1982]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Sep 12 10:12:05.351194 waagent[1982]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Sep 12 10:12:05.351194 waagent[1982]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Sep 12 10:12:05.351194 waagent[1982]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Sep 12 10:12:05.351523 waagent[1982]: 2025-09-12T10:12:05.351476Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Sep 12 10:12:05.351721 waagent[1982]: 2025-09-12T10:12:05.351679Z INFO EnvHandler ExtHandler Routes:None Sep 12 10:12:05.357909 waagent[1982]: 2025-09-12T10:12:05.357858Z INFO ExtHandler ExtHandler Sep 12 10:12:05.358444 waagent[1982]: 2025-09-12T10:12:05.358388Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: e2f8ff72-0041-41d6-b8da-5fc83308be7b correlation ec04d085-6575-48b5-9195-4acbf3b5c032 created: 2025-09-12T10:10:33.996338Z] Sep 12 10:12:05.359668 waagent[1982]: 2025-09-12T10:12:05.359611Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Sep 12 10:12:05.361571 waagent[1982]: 2025-09-12T10:12:05.361522Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Sep 12 10:12:05.399598 waagent[1982]: 2025-09-12T10:12:05.399521Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.9.1.1 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: DB38EF72-561F-412D-9BD7-FCABE5064EC8;DroppedPackets: 0;UpdateGSErrors: 0;AutoUpdate: 0] Sep 12 10:12:05.445018 waagent[1982]: 2025-09-12T10:12:05.444935Z INFO MonitorHandler ExtHandler Network interfaces: Sep 12 10:12:05.445018 waagent[1982]: Executing ['ip', '-a', '-o', 'link']: Sep 12 10:12:05.445018 waagent[1982]: 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 10:12:05.445018 waagent[1982]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:ed:8d:49:97:3a brd ff:ff:ff:ff:ff:ff Sep 12 10:12:05.445018 waagent[1982]: Executing ['ip', '-4', '-a', '-o', 'address']: Sep 12 10:12:05.445018 waagent[1982]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Sep 12 10:12:05.445018 waagent[1982]: 2: eth0 inet 10.200.8.23/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Sep 12 10:12:05.445018 waagent[1982]: Executing ['ip', '-6', '-a', '-o', 'address']: Sep 12 10:12:05.445018 waagent[1982]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Sep 12 10:12:05.445018 waagent[1982]: 2: eth0 inet6 fe80::7eed:8dff:fe49:973a/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Sep 12 10:12:05.514835 waagent[1982]: 2025-09-12T10:12:05.514763Z INFO EnvHandler ExtHandler Successfully added Azure fabric firewall rules. Current Firewall rules: Sep 12 10:12:05.514835 waagent[1982]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Sep 12 10:12:05.514835 waagent[1982]: pkts bytes target prot opt in out source destination Sep 12 10:12:05.514835 waagent[1982]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Sep 12 10:12:05.514835 waagent[1982]: pkts bytes target prot opt in out source destination Sep 12 10:12:05.514835 waagent[1982]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Sep 12 10:12:05.514835 waagent[1982]: pkts bytes target prot opt in out source destination Sep 12 10:12:05.514835 waagent[1982]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Sep 12 10:12:05.514835 waagent[1982]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Sep 12 10:12:05.514835 waagent[1982]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Sep 12 10:12:05.518397 waagent[1982]: 2025-09-12T10:12:05.518348Z INFO EnvHandler ExtHandler Current Firewall rules: Sep 12 10:12:05.518397 waagent[1982]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Sep 12 10:12:05.518397 waagent[1982]: pkts bytes target prot opt in out source destination Sep 12 10:12:05.518397 waagent[1982]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Sep 12 10:12:05.518397 waagent[1982]: pkts bytes target prot opt in out source destination Sep 12 10:12:05.518397 waagent[1982]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Sep 12 10:12:05.518397 waagent[1982]: pkts bytes target prot opt in out source destination Sep 12 10:12:05.518397 waagent[1982]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Sep 12 10:12:05.518397 waagent[1982]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Sep 12 10:12:05.518397 waagent[1982]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Sep 12 10:12:05.518663 waagent[1982]: 2025-09-12T10:12:05.518625Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Sep 12 10:12:05.518822 waagent[1982]: 2025-09-12T10:12:05.518786Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Sep 12 10:12:16.793409 chronyd[1688]: Selected source PHC0 Sep 12 10:12:19.900318 setup-nvidia[1845]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 537M 0 16384 0 0 27678 0 5:39:41 --:--:-- 5:39:41 27675\u000d 0 537M 0 4143k 0 0 2618k 0 0:03:30 0:00:01 0:03:29 2617k\u000d 4 537M 4 26.6M 0 0 10.2M 0 0:00:52 0:00:02 0:00:50 10.2M\u000d 8 537M 8 47.8M 0 0 13.2M 0 0:00:40 0:00:03 0:00:37 13.2M\u000d 13 537M 13 69.9M 0 0 15.1M 0 0:00:35 0:00:04 0:00:31 15.1M\u000d 17 537M 17 93.0M 0 0 16.5M 0 0:00:32 0:00:05 0:00:27 18.4M\u000d 21 537M 21 115M 0 0 17.3M 0 0:00:30 0:00:06 0:00:24 21.9M\u000d 25 537M 25 136M 0 0 18.0M 0 0:00:29 0:00:07 0:00:22 22.0M\u000d 29 537M 29 159M 0 0 18.5M 0 0:00:28 0:00:08 0:00:20 22.4M\u000d 33 537M 33 182M 0 0 19.0M 0 0:00:28 0:00:09 0:00:19 22.5M\u000d 38 537M 38 207M 0 0 19.5M 0 0:00:27 0:00:10 0:00:17 22.8M\u000d 41 537M 41 222M 0 0 19.0M 0 0:00:28 0:00:11 0:00:17 21.1M\u000d 43 537M 43 236M 0 0 18.7M 0 0:00:28 0:00:12 0:00:16 19.8M\u000d 48 537M 48 259M 0 0 19.0M 0 0:00:28 0:00:13 0:00:15 19.8M\u000d 52 537M 52 281M 0 0 19.2M 0 0:00:27 0:00:14 0:00:13 19.6M\u000d 56 537M 56 304M 0 0 19.4M 0 0:00:27 0:00:15 0:00:12 19.3M\u000d 60 537M 60 327M 0 0 19.6M 0 0:00:27 0:00:16 0:00:11 21.2M\u000d 64 537M 64 348M 0 0 19.7M 0 0:00:27 0:00:17 0:00:10 22.0M\u000d 68 537M 68 369M 0 0 19.7M 0 0:00:27 0:00:18 0:00:09 21.6M\u000d 72 537M 72 391M 0 0 19.8M 0 0:00:27 0:00:19 0:00:08 21.7M\u000d 76 537M 76 414M 0 0 20.0M 0 0:00:26 0:00:20 0:00:06 22.0M\u000d 81 537M 81 437M 0 0 20.2M 0 0:00:26 0:00:21 0:00:05 22.2M\u000d 86 537M 86 462M 0 0 20.4M 0 0:00:26 0:00:22 0:00:04 23.1M\u000d 90 537M 90 488M 0 0 20.6M 0 0:00:26 0:00:23 0:00:03 23.9M\u000d 95 537M 95 513M 0 0 20.8M 0 0:00:25 0:00:24 0:00:01 24.5M\u000d 99 537M 99 537M 0 0 21.0M 0 0:00:25 0:00:25 --:--:-- 24.8M\u000d100 537M 100 537M 0 0 21.0M 0 0:00:25 0:00:25 --:--:-- 25.1M Sep 12 10:12:20.937246 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 12 10:12:20.944296 systemd[1]: Started sshd@0-10.200.8.23:22-10.200.16.10:45678.service - OpenSSH per-connection server daemon (10.200.16.10:45678). Sep 12 10:12:21.830207 sshd[2233]: Accepted publickey for core from 10.200.16.10 port 45678 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:12:21.831656 sshd-session[2233]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:12:21.836798 systemd-logind[1686]: New session 3 of user core. Sep 12 10:12:21.844187 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 12 10:12:22.403291 systemd[1]: Started sshd@1-10.200.8.23:22-10.200.16.10:45690.service - OpenSSH per-connection server daemon (10.200.16.10:45690). Sep 12 10:12:23.049474 sshd[2238]: Accepted publickey for core from 10.200.16.10 port 45690 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:12:23.050092 sshd-session[2238]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:12:23.056222 systemd-logind[1686]: New session 4 of user core. Sep 12 10:12:23.069269 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 12 10:12:23.515680 sshd[2240]: Connection closed by 10.200.16.10 port 45690 Sep 12 10:12:23.516111 sshd-session[2238]: pam_unix(sshd:session): session closed for user core Sep 12 10:12:23.520138 systemd[1]: sshd@1-10.200.8.23:22-10.200.16.10:45690.service: Deactivated successfully. Sep 12 10:12:23.524387 systemd[1]: session-4.scope: Deactivated successfully. Sep 12 10:12:23.526909 systemd-logind[1686]: Session 4 logged out. Waiting for processes to exit. Sep 12 10:12:23.529651 systemd-logind[1686]: Removed session 4. Sep 12 10:12:23.639309 systemd[1]: Started sshd@2-10.200.8.23:22-10.200.16.10:45694.service - OpenSSH per-connection server daemon (10.200.16.10:45694). Sep 12 10:12:24.282911 sshd[2246]: Accepted publickey for core from 10.200.16.10 port 45694 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:12:24.283874 sshd-session[2246]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:12:24.292313 systemd-logind[1686]: New session 5 of user core. Sep 12 10:12:24.297250 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 12 10:12:24.741425 sshd[2248]: Connection closed by 10.200.16.10 port 45694 Sep 12 10:12:24.741985 sshd-session[2246]: pam_unix(sshd:session): session closed for user core Sep 12 10:12:24.749441 systemd[1]: sshd@2-10.200.8.23:22-10.200.16.10:45694.service: Deactivated successfully. Sep 12 10:12:24.751321 systemd[1]: session-5.scope: Deactivated successfully. Sep 12 10:12:24.755221 systemd-logind[1686]: Session 5 logged out. Waiting for processes to exit. Sep 12 10:12:24.757532 systemd-logind[1686]: Removed session 5. Sep 12 10:12:30.717334 setup-nvidia[1682]: Downloading NVIDIA 535.230.02 Driver Sep 12 10:12:30.721786 setup-nvidia[2254]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 12 10:12:30.722029 setup-nvidia[2254]: Dload Upload Total Spent Left Speed Sep 12 10:12:34.665786 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Sep 12 10:12:34.856837 systemd[1]: Started sshd@3-10.200.8.23:22-10.200.16.10:46848.service - OpenSSH per-connection server daemon (10.200.16.10:46848). Sep 12 10:12:35.494881 sshd[2256]: Accepted publickey for core from 10.200.16.10 port 46848 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:12:35.496378 sshd-session[2256]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:12:35.501097 systemd-logind[1686]: New session 6 of user core. Sep 12 10:12:35.514168 systemd[1]: Started session-6.scope - Session 6 of User core. Sep 12 10:12:35.947937 sshd[2258]: Connection closed by 10.200.16.10 port 46848 Sep 12 10:12:35.948325 sshd-session[2256]: pam_unix(sshd:session): session closed for user core Sep 12 10:12:35.951476 systemd[1]: sshd@3-10.200.8.23:22-10.200.16.10:46848.service: Deactivated successfully. Sep 12 10:12:35.953073 systemd[1]: session-6.scope: Deactivated successfully. Sep 12 10:12:35.953688 systemd-logind[1686]: Session 6 logged out. Waiting for processes to exit. Sep 12 10:12:35.954512 systemd-logind[1686]: Removed session 6. Sep 12 10:12:36.060487 systemd[1]: Started sshd@4-10.200.8.23:22-10.200.16.10:46858.service - OpenSSH per-connection server daemon (10.200.16.10:46858). Sep 12 10:12:36.701804 sshd[2264]: Accepted publickey for core from 10.200.16.10 port 46858 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:12:36.703073 sshd-session[2264]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:12:36.707197 systemd-logind[1686]: New session 7 of user core. Sep 12 10:12:36.723173 systemd[1]: Started session-7.scope - Session 7 of User core. Sep 12 10:12:37.153846 sshd[2266]: Connection closed by 10.200.16.10 port 46858 Sep 12 10:12:37.154247 sshd-session[2264]: pam_unix(sshd:session): session closed for user core Sep 12 10:12:37.157469 systemd[1]: sshd@4-10.200.8.23:22-10.200.16.10:46858.service: Deactivated successfully. Sep 12 10:12:37.159220 systemd[1]: session-7.scope: Deactivated successfully. Sep 12 10:12:37.159905 systemd-logind[1686]: Session 7 logged out. Waiting for processes to exit. Sep 12 10:12:37.160903 systemd-logind[1686]: Removed session 7. Sep 12 10:12:38.768928 update_engine[1690]: I20250912 10:12:38.768829 1690 update_attempter.cc:509] Updating boot flags... Sep 12 10:12:38.842076 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 35 scanned by (udev-worker) (2286) Sep 12 10:12:41.071268 setup-nvidia[2254]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 7 326M 7 26.0M 0 0 21.5M 0 0:00:15 0:00:01 0:00:14 21.5M\u000d 19 326M 19 64.0M 0 0 29.2M 0 0:00:11 0:00:02 0:00:09 29.2M\u000d 32 326M 32 105M 0 0 33.0M 0 0:00:09 0:00:03 0:00:06 33.0M\u000d 43 326M 43 142M 0 0 34.1M 0 0:00:09 0:00:04 0:00:05 34.1M\u000d 50 326M 50 164M 0 0 31.3M 0 0:00:10 0:00:05 0:00:05 32.4M\u000d 61 326M 61 202M 0 0 32.5M 0 0:00:10 0:00:06 0:00:04 35.1M\u000d 72 326M 72 236M 0 0 32.8M 0 0:00:09 0:00:07 0:00:02 34.4M\u000d 79 326M 79 258M 0 0 30.8M 0 0:00:10 0:00:08 0:00:02 29.5M\u000d 88 326M 88 288M 0 0 31.3M 0 0:00:10 0:00:09 0:00:01 29.0M\u000d 98 326M 98 322M 0 0 31.4M 0 0:00:10 0:00:10 --:--:-- 31.4M\u000d100 326M 100 326M 0 0 31.5M 0 0:00:10 0:00:10 --:--:-- 29.9M Sep 12 10:12:41.139882 setup-nvidia[1682]: Extract the NVIDIA Driver Installer 535.230.02 Sep 12 10:12:41.139882 setup-nvidia[1682]: /opt/nvidia/workdir/nvidia-workdir / Sep 12 10:12:41.203137 setup-nvidia[2336]: Creating directory NVIDIA-Linux-x86_64-535.230.02 Sep 12 10:12:41.524386 setup-nvidia[2336]: Verifying archive integrity... OK Sep 12 10:12:42.265611 setup-nvidia[2336]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.230.02 Sep 12 10:12:44.176994 setup-nvidia[2362]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Sep 12 10:12:44.217992 setup-nvidia[1682]: / Sep 12 10:12:44.218209 setup-nvidia[1682]: Spawn system-nspawn container to install the NVIDIA drivers Sep 12 10:12:44.544117 sudo[2369]: 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.2+nightly-20250911-2100.bin --overlay=/usr/lib/modules::/usr/lib/modules --bind=/opt/nvidia/workdir/nvidia-workdir:/nvidia --bind=/usr/lib/nvidia/bin:/app/bin/ /app/bin/install-nvidia NVIDIA-Linux-x86_64-535.230.02 Sep 12 10:12:44.545955 sudo[2369]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 12 10:12:44.555622 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Sep 12 10:12:44.572244 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Sep 12 10:12:44.582458 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Sep 12 10:12:44.586176 systemd[1]: Starting user@0.service - User Manager for UID 0... Sep 12 10:12:44.592026 (systemd)[2372]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Sep 12 10:12:44.594317 systemd-logind[1686]: New session c3 of user root. Sep 12 10:12:44.772959 systemd[2372]: Queued start job for default target default.target. Sep 12 10:12:44.784085 systemd[2372]: Created slice app.slice - User Application Slice. Sep 12 10:12:44.784114 systemd[2372]: Reached target paths.target - Paths. Sep 12 10:12:44.784162 systemd[2372]: Reached target timers.target - Timers. Sep 12 10:12:44.785297 systemd[2372]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 12 10:12:44.795337 systemd[2372]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 12 10:12:44.795399 systemd[2372]: Reached target sockets.target - Sockets. Sep 12 10:12:44.795445 systemd[2372]: Reached target basic.target - Basic System. Sep 12 10:12:44.795498 systemd[2372]: Reached target default.target - Main User Target. Sep 12 10:12:44.795532 systemd[2372]: Startup finished in 195ms. Sep 12 10:12:44.795672 systemd[1]: Started user@0.service - User Manager for UID 0. Sep 12 10:12:44.799636 systemd[1]: Started session-c2.scope - Session c2 of User root. Sep 12 10:12:44.839061 kernel: loop6: detected capacity change from 0 to 12644352 Sep 12 10:12:44.850064 kernel: loop6: p9 Sep 12 10:12:45.027097 setup-nvidia[2381]: ░ Spawning container flatcardevelopercontainer-4230.2.2nightly-20250911-2100.bin on /opt/nvidia/workdir/flatcar_developer_container-4230.2.2+nightly-20250911-2100.bin. Sep 12 10:12:45.077070 kernel: EXT4-fs (loop6p9): mounted filesystem e3e4fd57-6dbd-4666-81f1-84c1ac2e084b ro without journal. Quota mode: none. Sep 12 10:12:47.267549 systemd[1]: Started sshd@5-10.200.8.23:22-10.200.16.10:49256.service - OpenSSH per-connection server daemon (10.200.16.10:49256). Sep 12 10:12:47.905990 sshd[2399]: Accepted publickey for core from 10.200.16.10 port 49256 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:12:47.907386 sshd-session[2399]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:12:47.911479 systemd-logind[1686]: New session 8 of user core. Sep 12 10:12:47.921173 systemd[1]: Started session-8.scope - Session 8 of User core. Sep 12 10:12:48.356929 sshd[2445]: Connection closed by 10.200.16.10 port 49256 Sep 12 10:12:48.357318 sshd-session[2399]: pam_unix(sshd:session): session closed for user core Sep 12 10:12:48.360542 systemd[1]: sshd@5-10.200.8.23:22-10.200.16.10:49256.service: Deactivated successfully. Sep 12 10:12:48.362155 systemd[1]: session-8.scope: Deactivated successfully. Sep 12 10:12:48.362778 systemd-logind[1686]: Session 8 logged out. Waiting for processes to exit. Sep 12 10:12:48.363546 systemd-logind[1686]: Removed session 8. Sep 12 10:12:48.473503 systemd[1]: Started sshd@6-10.200.8.23:22-10.200.16.10:49270.service - OpenSSH per-connection server daemon (10.200.16.10:49270). Sep 12 10:12:49.116551 sshd[2452]: Accepted publickey for core from 10.200.16.10 port 49270 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:12:49.117860 sshd-session[2452]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:12:49.121907 systemd-logind[1686]: New session 9 of user core. Sep 12 10:12:49.136162 systemd[1]: Started session-9.scope - Session 9 of User core. Sep 12 10:12:49.569141 sshd[2454]: Connection closed by 10.200.16.10 port 49270 Sep 12 10:12:49.569541 sshd-session[2452]: pam_unix(sshd:session): session closed for user core Sep 12 10:12:49.572710 systemd[1]: sshd@6-10.200.8.23:22-10.200.16.10:49270.service: Deactivated successfully. Sep 12 10:12:49.574300 systemd[1]: session-9.scope: Deactivated successfully. Sep 12 10:12:49.574907 systemd-logind[1686]: Session 9 logged out. Waiting for processes to exit. Sep 12 10:12:49.575699 systemd-logind[1686]: Removed session 9. Sep 12 10:12:59.682990 systemd[1]: Started sshd@7-10.200.8.23:22-10.200.16.10:55978.service - OpenSSH per-connection server daemon (10.200.16.10:55978). Sep 12 10:13:00.323596 sshd[3421]: Accepted publickey for core from 10.200.16.10 port 55978 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:13:00.324954 sshd-session[3421]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:13:00.330372 systemd-logind[1686]: New session 10 of user core. Sep 12 10:13:00.340162 systemd[1]: Started session-10.scope - Session 10 of User core. Sep 12 10:13:00.775924 sshd[3423]: Connection closed by 10.200.16.10 port 55978 Sep 12 10:13:00.776355 sshd-session[3421]: pam_unix(sshd:session): session closed for user core Sep 12 10:13:00.779563 systemd[1]: sshd@7-10.200.8.23:22-10.200.16.10:55978.service: Deactivated successfully. Sep 12 10:13:00.781191 systemd[1]: session-10.scope: Deactivated successfully. Sep 12 10:13:00.781836 systemd-logind[1686]: Session 10 logged out. Waiting for processes to exit. Sep 12 10:13:00.782657 systemd-logind[1686]: Removed session 10. Sep 12 10:13:00.890084 systemd[1]: Started sshd@8-10.200.8.23:22-10.200.16.10:58546.service - OpenSSH per-connection server daemon (10.200.16.10:58546). Sep 12 10:13:01.528903 sshd[3429]: Accepted publickey for core from 10.200.16.10 port 58546 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:13:01.530311 sshd-session[3429]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:13:01.534463 systemd-logind[1686]: New session 11 of user core. Sep 12 10:13:01.548177 systemd[1]: Started session-11.scope - Session 11 of User core. Sep 12 10:13:01.982523 sshd[3431]: Connection closed by 10.200.16.10 port 58546 Sep 12 10:13:01.982933 sshd-session[3429]: pam_unix(sshd:session): session closed for user core Sep 12 10:13:01.986192 systemd[1]: sshd@8-10.200.8.23:22-10.200.16.10:58546.service: Deactivated successfully. Sep 12 10:13:01.987813 systemd[1]: session-11.scope: Deactivated successfully. Sep 12 10:13:01.988442 systemd-logind[1686]: Session 11 logged out. Waiting for processes to exit. Sep 12 10:13:01.989210 systemd-logind[1686]: Removed session 11. Sep 12 10:13:12.098498 systemd[1]: Started sshd@9-10.200.8.23:22-10.200.16.10:40278.service - OpenSSH per-connection server daemon (10.200.16.10:40278). Sep 12 10:13:12.737273 sshd[3481]: Accepted publickey for core from 10.200.16.10 port 40278 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:13:12.738599 sshd-session[3481]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:13:12.742697 systemd-logind[1686]: New session 12 of user core. Sep 12 10:13:12.750163 systemd[1]: Started session-12.scope - Session 12 of User core. Sep 12 10:13:13.189649 sshd[3483]: Connection closed by 10.200.16.10 port 40278 Sep 12 10:13:13.190137 sshd-session[3481]: pam_unix(sshd:session): session closed for user core Sep 12 10:13:13.193436 systemd[1]: sshd@9-10.200.8.23:22-10.200.16.10:40278.service: Deactivated successfully. Sep 12 10:13:13.195131 systemd[1]: session-12.scope: Deactivated successfully. Sep 12 10:13:13.195753 systemd-logind[1686]: Session 12 logged out. Waiting for processes to exit. Sep 12 10:13:13.196513 systemd-logind[1686]: Removed session 12. Sep 12 10:13:13.302576 systemd[1]: Started sshd@10-10.200.8.23:22-10.200.16.10:40286.service - OpenSSH per-connection server daemon (10.200.16.10:40286). Sep 12 10:13:13.943459 sshd[3536]: Accepted publickey for core from 10.200.16.10 port 40286 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:13:13.944735 sshd-session[3536]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:13:13.949064 systemd-logind[1686]: New session 13 of user core. Sep 12 10:13:13.958159 systemd[1]: Started session-13.scope - Session 13 of User core. Sep 12 10:13:14.396514 sshd[3563]: Connection closed by 10.200.16.10 port 40286 Sep 12 10:13:14.396909 sshd-session[3536]: pam_unix(sshd:session): session closed for user core Sep 12 10:13:14.400180 systemd[1]: sshd@10-10.200.8.23:22-10.200.16.10:40286.service: Deactivated successfully. Sep 12 10:13:14.401849 systemd[1]: session-13.scope: Deactivated successfully. Sep 12 10:13:14.402490 systemd-logind[1686]: Session 13 logged out. Waiting for processes to exit. Sep 12 10:13:14.403259 systemd-logind[1686]: Removed session 13. Sep 12 10:13:24.510648 systemd[1]: Started sshd@11-10.200.8.23:22-10.200.16.10:49516.service - OpenSSH per-connection server daemon (10.200.16.10:49516). Sep 12 10:13:25.159500 sshd[5568]: Accepted publickey for core from 10.200.16.10 port 49516 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:13:25.161023 sshd-session[5568]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:13:25.172018 systemd-logind[1686]: New session 14 of user core. Sep 12 10:13:25.179145 systemd[1]: Started session-14.scope - Session 14 of User core. Sep 12 10:13:25.615624 sshd[5850]: Connection closed by 10.200.16.10 port 49516 Sep 12 10:13:25.617166 sshd-session[5568]: pam_unix(sshd:session): session closed for user core Sep 12 10:13:25.623609 systemd[1]: sshd@11-10.200.8.23:22-10.200.16.10:49516.service: Deactivated successfully. Sep 12 10:13:25.625339 systemd[1]: session-14.scope: Deactivated successfully. Sep 12 10:13:25.626063 systemd-logind[1686]: Session 14 logged out. Waiting for processes to exit. Sep 12 10:13:25.628453 systemd-logind[1686]: Removed session 14. Sep 12 10:13:25.727327 systemd[1]: Started sshd@12-10.200.8.23:22-10.200.16.10:49522.service - OpenSSH per-connection server daemon (10.200.16.10:49522). Sep 12 10:13:26.366731 sshd[5998]: Accepted publickey for core from 10.200.16.10 port 49522 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:13:26.368541 sshd-session[5998]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:13:26.373722 systemd-logind[1686]: New session 15 of user core. Sep 12 10:13:26.385286 systemd[1]: Started session-15.scope - Session 15 of User core. Sep 12 10:13:26.818118 sshd[6175]: Connection closed by 10.200.16.10 port 49522 Sep 12 10:13:26.818719 sshd-session[5998]: pam_unix(sshd:session): session closed for user core Sep 12 10:13:26.822415 systemd-logind[1686]: Session 15 logged out. Waiting for processes to exit. Sep 12 10:13:26.824412 systemd[1]: sshd@12-10.200.8.23:22-10.200.16.10:49522.service: Deactivated successfully. Sep 12 10:13:26.827793 systemd[1]: session-15.scope: Deactivated successfully. Sep 12 10:13:26.829573 systemd-logind[1686]: Removed session 15. Sep 12 10:13:36.939115 systemd[1]: Started sshd@13-10.200.8.23:22-10.200.16.10:34146.service - OpenSSH per-connection server daemon (10.200.16.10:34146). Sep 12 10:13:37.581269 sshd[9707]: Accepted publickey for core from 10.200.16.10 port 34146 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:13:37.583146 sshd-session[9707]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:13:37.588127 systemd-logind[1686]: New session 16 of user core. Sep 12 10:13:37.594199 systemd[1]: Started session-16.scope - Session 16 of User core. Sep 12 10:13:38.039984 sshd[9910]: Connection closed by 10.200.16.10 port 34146 Sep 12 10:13:38.041719 sshd-session[9707]: pam_unix(sshd:session): session closed for user core Sep 12 10:13:38.045408 systemd[1]: sshd@13-10.200.8.23:22-10.200.16.10:34146.service: Deactivated successfully. Sep 12 10:13:38.047972 systemd[1]: session-16.scope: Deactivated successfully. Sep 12 10:13:38.048763 systemd-logind[1686]: Session 16 logged out. Waiting for processes to exit. Sep 12 10:13:38.049980 systemd-logind[1686]: Removed session 16. Sep 12 10:13:38.158940 systemd[1]: Started sshd@14-10.200.8.23:22-10.200.16.10:34156.service - OpenSSH per-connection server daemon (10.200.16.10:34156). Sep 12 10:13:38.805799 sshd[10197]: Accepted publickey for core from 10.200.16.10 port 34156 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:13:38.807701 sshd-session[10197]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:13:38.814165 systemd-logind[1686]: New session 17 of user core. Sep 12 10:13:38.821623 systemd[1]: Started session-17.scope - Session 17 of User core. Sep 12 10:13:39.260746 sshd[10332]: Connection closed by 10.200.16.10 port 34156 Sep 12 10:13:39.262847 sshd-session[10197]: pam_unix(sshd:session): session closed for user core Sep 12 10:13:39.268775 systemd[1]: sshd@14-10.200.8.23:22-10.200.16.10:34156.service: Deactivated successfully. Sep 12 10:13:39.279425 systemd[1]: session-17.scope: Deactivated successfully. Sep 12 10:13:39.280474 systemd-logind[1686]: Session 17 logged out. Waiting for processes to exit. Sep 12 10:13:39.281638 systemd-logind[1686]: Removed session 17. Sep 12 10:13:49.392310 systemd[1]: Started sshd@15-10.200.8.23:22-10.200.16.10:48448.service - OpenSSH per-connection server daemon (10.200.16.10:48448). Sep 12 10:13:50.031176 sshd[11807]: Accepted publickey for core from 10.200.16.10 port 48448 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:13:50.033016 sshd-session[11807]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:13:50.039506 systemd-logind[1686]: New session 18 of user core. Sep 12 10:13:50.048471 systemd[1]: Started session-18.scope - Session 18 of User core. Sep 12 10:13:50.486624 sshd[11847]: Connection closed by 10.200.16.10 port 48448 Sep 12 10:13:50.487258 sshd-session[11807]: pam_unix(sshd:session): session closed for user core Sep 12 10:13:50.492873 systemd-logind[1686]: Session 18 logged out. Waiting for processes to exit. Sep 12 10:13:50.494421 systemd[1]: sshd@15-10.200.8.23:22-10.200.16.10:48448.service: Deactivated successfully. Sep 12 10:13:50.496447 systemd[1]: session-18.scope: Deactivated successfully. Sep 12 10:13:50.497839 systemd-logind[1686]: Removed session 18. Sep 12 10:13:50.605949 systemd[1]: Started sshd@16-10.200.8.23:22-10.200.16.10:55742.service - OpenSSH per-connection server daemon (10.200.16.10:55742). Sep 12 10:13:51.257971 sshd[11875]: Accepted publickey for core from 10.200.16.10 port 55742 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:13:51.259422 sshd-session[11875]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:13:51.266809 systemd-logind[1686]: New session 19 of user core. Sep 12 10:13:51.282630 systemd[1]: Started session-19.scope - Session 19 of User core. Sep 12 10:13:51.711168 sshd[11886]: Connection closed by 10.200.16.10 port 55742 Sep 12 10:13:51.711775 sshd-session[11875]: pam_unix(sshd:session): session closed for user core Sep 12 10:13:51.717387 systemd-logind[1686]: Session 19 logged out. Waiting for processes to exit. Sep 12 10:13:51.718774 systemd[1]: sshd@16-10.200.8.23:22-10.200.16.10:55742.service: Deactivated successfully. Sep 12 10:13:51.720400 systemd[1]: session-19.scope: Deactivated successfully. Sep 12 10:13:51.723454 systemd-logind[1686]: Removed session 19. Sep 12 10:14:01.825729 systemd[1]: Started sshd@17-10.200.8.23:22-10.200.16.10:44928.service - OpenSSH per-connection server daemon (10.200.16.10:44928). Sep 12 10:14:02.473197 sshd[12189]: Accepted publickey for core from 10.200.16.10 port 44928 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:14:02.475145 sshd-session[12189]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:14:02.483386 systemd-logind[1686]: New session 20 of user core. Sep 12 10:14:02.492307 systemd[1]: Started session-20.scope - Session 20 of User core. Sep 12 10:14:02.927187 sshd[12215]: Connection closed by 10.200.16.10 port 44928 Sep 12 10:14:02.927589 sshd-session[12189]: pam_unix(sshd:session): session closed for user core Sep 12 10:14:02.933477 systemd[1]: sshd@17-10.200.8.23:22-10.200.16.10:44928.service: Deactivated successfully. Sep 12 10:14:02.935098 systemd[1]: session-20.scope: Deactivated successfully. Sep 12 10:14:02.935651 systemd-logind[1686]: Session 20 logged out. Waiting for processes to exit. Sep 12 10:14:02.937009 systemd-logind[1686]: Removed session 20. Sep 12 10:14:03.053007 systemd[1]: Started sshd@18-10.200.8.23:22-10.200.16.10:44944.service - OpenSSH per-connection server daemon (10.200.16.10:44944). Sep 12 10:14:03.698861 sshd[12233]: Accepted publickey for core from 10.200.16.10 port 44944 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:14:03.700747 sshd-session[12233]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:14:03.706731 systemd-logind[1686]: New session 21 of user core. Sep 12 10:14:03.717263 systemd[1]: Started session-21.scope - Session 21 of User core. Sep 12 10:14:04.150948 sshd[12265]: Connection closed by 10.200.16.10 port 44944 Sep 12 10:14:04.151788 sshd-session[12233]: pam_unix(sshd:session): session closed for user core Sep 12 10:14:04.155216 systemd-logind[1686]: Session 21 logged out. Waiting for processes to exit. Sep 12 10:14:04.156334 systemd[1]: sshd@18-10.200.8.23:22-10.200.16.10:44944.service: Deactivated successfully. Sep 12 10:14:04.158744 systemd[1]: session-21.scope: Deactivated successfully. Sep 12 10:14:04.161354 systemd-logind[1686]: Removed session 21. Sep 12 10:14:14.285357 systemd[1]: Started sshd@19-10.200.8.23:22-10.200.16.10:34616.service - OpenSSH per-connection server daemon (10.200.16.10:34616). Sep 12 10:14:14.929197 sshd[12559]: Accepted publickey for core from 10.200.16.10 port 34616 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:14:14.931088 sshd-session[12559]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:14:14.939743 systemd-logind[1686]: New session 22 of user core. Sep 12 10:14:14.948660 systemd[1]: Started session-22.scope - Session 22 of User core. Sep 12 10:14:15.383719 sshd[12591]: Connection closed by 10.200.16.10 port 34616 Sep 12 10:14:15.385804 sshd-session[12559]: pam_unix(sshd:session): session closed for user core Sep 12 10:14:15.392201 systemd[1]: sshd@19-10.200.8.23:22-10.200.16.10:34616.service: Deactivated successfully. Sep 12 10:14:15.393812 systemd[1]: session-22.scope: Deactivated successfully. Sep 12 10:14:15.394562 systemd-logind[1686]: Session 22 logged out. Waiting for processes to exit. Sep 12 10:14:15.397216 systemd-logind[1686]: Removed session 22. Sep 12 10:14:15.501330 systemd[1]: Started sshd@20-10.200.8.23:22-10.200.16.10:34620.service - OpenSSH per-connection server daemon (10.200.16.10:34620). Sep 12 10:14:16.146019 sshd[12627]: Accepted publickey for core from 10.200.16.10 port 34620 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:14:16.148115 sshd-session[12627]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:14:16.152894 systemd-logind[1686]: New session 23 of user core. Sep 12 10:14:16.171258 systemd[1]: Started session-23.scope - Session 23 of User core. Sep 12 10:14:16.597256 sshd[12672]: Connection closed by 10.200.16.10 port 34620 Sep 12 10:14:16.597589 sshd-session[12627]: pam_unix(sshd:session): session closed for user core Sep 12 10:14:16.600915 systemd[1]: sshd@20-10.200.8.23:22-10.200.16.10:34620.service: Deactivated successfully. Sep 12 10:14:16.602568 systemd[1]: session-23.scope: Deactivated successfully. Sep 12 10:14:16.603218 systemd-logind[1686]: Session 23 logged out. Waiting for processes to exit. Sep 12 10:14:16.603974 systemd-logind[1686]: Removed session 23. Sep 12 10:14:24.712345 setup-nvidia[2381]: \u000d Sep 12 10:14:24.712345 setup-nvidia[2381]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Sep 12 10:14:24.712345 setup-nvidia[2381]: frequently when this kernel module was built against the wrong or Sep 12 10:14:24.712345 setup-nvidia[2381]: improperly configured kernel sources, with a version of gcc that differs Sep 12 10:14:24.712345 setup-nvidia[2381]: from the one used to build the target kernel, or if another driver, such Sep 12 10:14:24.712345 setup-nvidia[2381]: as nouveau, is present and prevents the NVIDIA kernel module from Sep 12 10:14:24.712345 setup-nvidia[2381]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Sep 12 10:14:24.712345 setup-nvidia[2381]: installed in this system is supported by this NVIDIA Linux graphics Sep 12 10:14:24.712345 setup-nvidia[2381]: driver release. Sep 12 10:14:24.712345 setup-nvidia[2381]: \u000d Sep 12 10:14:24.712345 setup-nvidia[2381]: Please see the log entries 'Kernel module load error' and 'Kernel Sep 12 10:14:24.712345 setup-nvidia[2381]: messages' at the end of the file Sep 12 10:14:24.712345 setup-nvidia[2381]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more Sep 12 10:14:24.712345 setup-nvidia[2381]: information. Sep 12 10:14:24.712345 setup-nvidia[2381]: \u000d Sep 12 10:14:24.727226 setup-nvidia[2381]: dmesg: read kernel buffer failed: Operation not permitted Sep 12 10:14:24.748321 setup-nvidia[2381]: \u000d Sep 12 10:14:24.748321 setup-nvidia[2381]: ERROR: Installation has failed. Please see the file Sep 12 10:14:24.748321 setup-nvidia[2381]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for Sep 12 10:14:24.748321 setup-nvidia[2381]: details. You may find suggestions on fixing installation problems in Sep 12 10:14:24.748321 setup-nvidia[2381]: the README available on the Linux driver download page at Sep 12 10:14:24.748321 setup-nvidia[2381]: www.nvidia.com. Sep 12 10:14:24.748321 setup-nvidia[2381]: \u000d Sep 12 10:14:24.748661 setup-nvidia[2381]: Last 50 lines of nvidia-installer.log: Sep 12 10:14:24.751328 setup-nvidia[2381]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038989rm+0x32: 'naked' return found in RETHUNK build Sep 12 10:14:24.751328 setup-nvidia[2381]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039000rm+0x53: 'naked' return found in RETHUNK build Sep 12 10:14:24.751328 setup-nvidia[2381]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038997rm+0x90: 'naked' return found in RETHUNK build Sep 12 10:14:24.751328 setup-nvidia[2381]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038990rm+0x2b: 'naked' return found in RETHUNK build Sep 12 10:14:24.751328 setup-nvidia[2381]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039046rm+0x93: 'naked' return found in RETHUNK build Sep 12 10:14:24.751328 setup-nvidia[2381]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039042rm+0x36: 'naked' return found in RETHUNK build Sep 12 10:14:24.751328 setup-nvidia[2381]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039041rm+0x9d: 'naked' return found in RETHUNK build Sep 12 10:14:24.751328 setup-nvidia[2381]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039043rm+0x77: 'naked' return found in RETHUNK build Sep 12 10:14:24.751328 setup-nvidia[2381]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038987rm+0x7f: 'naked' return found in RETHUNK build Sep 12 10:14:24.751328 setup-nvidia[2381]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030558rm+0x3e: 'naked' return found in RETHUNK build Sep 12 10:14:24.751328 setup-nvidia[2381]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030557rm+0x27: 'naked' return found in RETHUNK build Sep 12 10:14:24.751328 setup-nvidia[2381]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030561rm+0x37: 'naked' return found in RETHUNK build Sep 12 10:14:24.751328 setup-nvidia[2381]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030560rm+0x24: 'naked' return found in RETHUNK build Sep 12 10:14:24.751328 setup-nvidia[2381]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030559rm+0xca: 'naked' return found in RETHUNK build Sep 12 10:14:24.751328 setup-nvidia[2381]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030562rm+0x193: 'naked' return found in RETHUNK build Sep 12 10:14:24.751328 setup-nvidia[2381]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030564rm+0x17f: 'naked' return found in RETHUNK build Sep 12 10:14:24.751328 setup-nvidia[2381]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv011860rm+0x5d: 'naked' return found in RETHUNK build Sep 12 10:14:24.751328 setup-nvidia[2381]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039354rm+0x12f: 'naked' return found in RETHUNK build Sep 12 10:14:24.751328 setup-nvidia[2381]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/Module.symvers Sep 12 10:14:24.751328 setup-nvidia[2381]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.mod.o Sep 12 10:14:24.751328 setup-nvidia[2381]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.mod.o Sep 12 10:14:24.751328 setup-nvidia[2381]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.mod.o Sep 12 10:14:24.751328 setup-nvidia[2381]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.mod.o Sep 12 10:14:24.751328 setup-nvidia[2381]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.mod.o Sep 12 10:14:24.751328 setup-nvidia[2381]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Sep 12 10:14:24.751328 setup-nvidia[2381]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Sep 12 10:14:24.751328 setup-nvidia[2381]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Sep 12 10:14:24.751328 setup-nvidia[2381]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Sep 12 10:14:24.751328 setup-nvidia[2381]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Sep 12 10:14:24.751328 setup-nvidia[2381]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Sep 12 10:14:24.751328 setup-nvidia[2381]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko due to unavailability of vmlinux Sep 12 10:14:24.751328 setup-nvidia[2381]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Sep 12 10:14:24.751328 setup-nvidia[2381]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko due to unavailability of vmlinux Sep 12 10:14:24.751328 setup-nvidia[2381]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Sep 12 10:14:24.751328 setup-nvidia[2381]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko due to unavailability of vmlinux Sep 12 10:14:24.751328 setup-nvidia[2381]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Sep 12 10:14:24.751328 setup-nvidia[2381]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko due to unavailability of vmlinux Sep 12 10:14:24.752320 setup-nvidia[2381]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Sep 12 10:14:24.752320 setup-nvidia[2381]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko due to unavailability of vmlinux Sep 12 10:14:24.752320 setup-nvidia[2381]: make[2]: Leaving directory '/usr/lib/modules/6.6.105-flatcar/build' Sep 12 10:14:24.752320 setup-nvidia[2381]: make[1]: Leaving directory '/usr/lib/modules/6.6.105-flatcar/source' Sep 12 10:14:24.752320 setup-nvidia[2381]: -> done. Sep 12 10:14:24.752320 setup-nvidia[2381]: -> Kernel module compilation complete. Sep 12 10:14:24.752320 setup-nvidia[2381]: -> Unable to determine if Secure Boot is enabled: No such file or directory Sep 12 10:14:24.752320 setup-nvidia[2381]: 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 10:14:24.752320 setup-nvidia[2381]: \u000d Sep 12 10:14:24.752320 setup-nvidia[2381]: 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 10:14:24.752320 setup-nvidia[2381]: -> Kernel module load error: Operation not permitted Sep 12 10:14:24.752320 setup-nvidia[2381]: -> Kernel messages: Sep 12 10:14:24.752320 setup-nvidia[2381]: 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 10:14:26.710604 systemd[1]: Started sshd@21-10.200.8.23:22-10.200.16.10:48240.service - OpenSSH per-connection server daemon (10.200.16.10:48240). Sep 12 10:14:27.336830 sshd[12907]: Accepted publickey for core from 10.200.16.10 port 48240 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:14:27.338267 sshd-session[12907]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:14:27.342447 systemd-logind[1686]: New session 24 of user core. Sep 12 10:14:27.355171 systemd[1]: Started session-24.scope - Session 24 of User core. Sep 12 10:14:27.781278 sshd[12909]: Connection closed by 10.200.16.10 port 48240 Sep 12 10:14:27.781676 sshd-session[12907]: pam_unix(sshd:session): session closed for user core Sep 12 10:14:27.784992 systemd[1]: sshd@21-10.200.8.23:22-10.200.16.10:48240.service: Deactivated successfully. Sep 12 10:14:27.786626 systemd[1]: session-24.scope: Deactivated successfully. Sep 12 10:14:27.787294 systemd-logind[1686]: Session 24 logged out. Waiting for processes to exit. Sep 12 10:14:27.788078 systemd-logind[1686]: Removed session 24. Sep 12 10:14:27.892417 systemd[1]: Started sshd@22-10.200.8.23:22-10.200.16.10:48246.service - OpenSSH per-connection server daemon (10.200.16.10:48246). Sep 12 10:14:28.517246 sshd[12915]: Accepted publickey for core from 10.200.16.10 port 48246 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:14:28.518618 sshd-session[12915]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:14:28.522739 systemd-logind[1686]: New session 25 of user core. Sep 12 10:14:28.534159 systemd[1]: Started session-25.scope - Session 25 of User core. Sep 12 10:14:28.963247 sshd[12917]: Connection closed by 10.200.16.10 port 48246 Sep 12 10:14:28.963668 sshd-session[12915]: pam_unix(sshd:session): session closed for user core Sep 12 10:14:28.966932 systemd[1]: sshd@22-10.200.8.23:22-10.200.16.10:48246.service: Deactivated successfully. Sep 12 10:14:28.968548 systemd[1]: session-25.scope: Deactivated successfully. Sep 12 10:14:28.969180 systemd-logind[1686]: Session 25 logged out. Waiting for processes to exit. Sep 12 10:14:28.969962 systemd-logind[1686]: Removed session 25. Sep 12 10:14:39.074749 systemd[1]: Started sshd@23-10.200.8.23:22-10.200.16.10:37568.service - OpenSSH per-connection server daemon (10.200.16.10:37568). Sep 12 10:14:39.704173 sshd[12923]: Accepted publickey for core from 10.200.16.10 port 37568 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:14:39.705531 sshd-session[12923]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:14:39.709649 systemd-logind[1686]: New session 26 of user core. Sep 12 10:14:39.726178 systemd[1]: Started session-26.scope - Session 26 of User core. Sep 12 10:14:40.145455 sshd[12925]: Connection closed by 10.200.16.10 port 37568 Sep 12 10:14:40.145851 sshd-session[12923]: pam_unix(sshd:session): session closed for user core Sep 12 10:14:40.149085 systemd[1]: sshd@23-10.200.8.23:22-10.200.16.10:37568.service: Deactivated successfully. Sep 12 10:14:40.150665 systemd[1]: session-26.scope: Deactivated successfully. Sep 12 10:14:40.151329 systemd-logind[1686]: Session 26 logged out. Waiting for processes to exit. Sep 12 10:14:40.152090 systemd-logind[1686]: Removed session 26. Sep 12 10:14:40.256670 systemd[1]: Started sshd@24-10.200.8.23:22-10.200.16.10:58324.service - OpenSSH per-connection server daemon (10.200.16.10:58324). Sep 12 10:14:40.882248 sshd[12931]: Accepted publickey for core from 10.200.16.10 port 58324 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:14:40.883574 sshd-session[12931]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:14:40.887711 systemd-logind[1686]: New session 27 of user core. Sep 12 10:14:40.902158 systemd[1]: Started session-27.scope - Session 27 of User core. Sep 12 10:14:41.326963 sshd[12933]: Connection closed by 10.200.16.10 port 58324 Sep 12 10:14:41.327393 sshd-session[12931]: pam_unix(sshd:session): session closed for user core Sep 12 10:14:41.330613 systemd[1]: sshd@24-10.200.8.23:22-10.200.16.10:58324.service: Deactivated successfully. Sep 12 10:14:41.332277 systemd[1]: session-27.scope: Deactivated successfully. Sep 12 10:14:41.332909 systemd-logind[1686]: Session 27 logged out. Waiting for processes to exit. Sep 12 10:14:41.333755 systemd-logind[1686]: Removed session 27. Sep 12 10:14:47.306070 kernel: EXT4-fs (loop6p9): unmounting filesystem e3e4fd57-6dbd-4666-81f1-84c1ac2e084b. Sep 12 10:14:47.306763 setup-nvidia[2381]: Container flatcardevelopercontainer-4230.2.2nightly-20250911-2100.bin exited successfully. Sep 12 10:14:47.308909 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4230.2.2nightly\x2d20250911\x2d2100.bin.mount: Deactivated successfully. Sep 12 10:14:47.370860 sudo[2369]: pam_unix(sudo:session): session closed for user root Sep 12 10:14:47.372621 systemd[1]: session-c2.scope: Deactivated successfully. Sep 12 10:14:47.372893 systemd[1]: session-c2.scope: Consumed 5min 17.857s CPU time, 882.9M memory peak. Sep 12 10:14:47.786861 setup-nvidia[1682]: /opt/nvidia / Sep 12 10:14:47.787826 setup-nvidia[1682]: / Sep 12 10:14:47.804116 setup-nvidia[12956]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 12 10:14:47.862058 kernel: IPMI message handler: version 39.2 Sep 12 10:14:47.865053 kernel: ipmi device interface Sep 12 10:14:47.866227 setup-nvidia[1682]: /opt/nvidia/current/usr/lib/modules/6.6.105-flatcar/video / Sep 12 10:14:47.919323 kernel: nvidia: loading out-of-tree module taints kernel. Sep 12 10:14:47.919489 kernel: nvidia: module license 'NVIDIA' taints kernel. Sep 12 10:14:47.919517 kernel: Disabling lock debugging due to kernel taint Sep 12 10:14:47.926363 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Sep 12 10:14:47.926412 kernel: nvidia: module license taints kernel. Sep 12 10:14:48.325068 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 245 Sep 12 10:14:48.723956 kernel: Sep 12 10:14:48.724122 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Sep 12 10:14:48.930064 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.230.02 Fri Dec 20 21:42:05 UTC 2024 Sep 12 10:14:48.952072 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 10:14:48.971070 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Sep 12 10:14:49.018531 setup-nvidia[1682]: / Sep 12 10:14:49.019059 kernel: nvidia-uvm: Loaded the UVM driver, major device number 243. Sep 12 10:14:50.741616 setup-nvidia[12986]: Fri Sep 12 10:14:50 2025 Sep 12 10:14:50.741616 setup-nvidia[12986]: +---------------------------------------------------------------------------------------+ Sep 12 10:14:50.741616 setup-nvidia[12986]: | NVIDIA-SMI 535.230.02 Driver Version: 535.230.02 CUDA Version: 12.2 | Sep 12 10:14:50.741616 setup-nvidia[12986]: |-----------------------------------------+----------------------+----------------------+ Sep 12 10:14:50.741616 setup-nvidia[12986]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Sep 12 10:14:50.741616 setup-nvidia[12986]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Sep 12 10:14:50.741616 setup-nvidia[12986]: | | | MIG M. | Sep 12 10:14:50.741616 setup-nvidia[12986]: |=========================================+======================+======================| Sep 12 10:14:50.745141 setup-nvidia[12986]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Sep 12 10:14:50.745141 setup-nvidia[12986]: | N/A 25C P0 37W / 250W | 0MiB / 16384MiB | 0% Default | Sep 12 10:14:50.745141 setup-nvidia[12986]: | | | N/A | Sep 12 10:14:50.745141 setup-nvidia[12986]: +-----------------------------------------+----------------------+----------------------+ Sep 12 10:14:50.745255 setup-nvidia[12986]: Sep 12 10:14:50.745255 setup-nvidia[12986]: +---------------------------------------------------------------------------------------+ Sep 12 10:14:50.745255 setup-nvidia[12986]: | Processes: | Sep 12 10:14:50.745255 setup-nvidia[12986]: | GPU GI CI PID Type Process name GPU Memory | Sep 12 10:14:50.745255 setup-nvidia[12986]: | ID ID Usage | Sep 12 10:14:50.745255 setup-nvidia[12986]: |=======================================================================================| Sep 12 10:14:50.745385 setup-nvidia[12986]: | No running processes found | Sep 12 10:14:50.745385 setup-nvidia[12986]: +---------------------------------------------------------------------------------------+ Sep 12 10:14:51.121395 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 12 10:14:51.124132 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 12 10:14:51.131132 systemd[1]: Startup finished in 1.246s (firmware) + 27.567s (loader) + 1.873s (kernel) + 11.815s (initrd) + 3min 13.675s (userspace) = 3min 56.178s. Sep 12 10:14:51.442950 systemd[1]: Started sshd@25-10.200.8.23:22-10.200.16.10:46200.service - OpenSSH per-connection server daemon (10.200.16.10:46200). Sep 12 10:14:52.069662 sshd[12993]: Accepted publickey for core from 10.200.16.10 port 46200 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:14:52.070989 sshd-session[12993]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:14:52.075241 systemd-logind[1686]: New session 28 of user core. Sep 12 10:14:52.087174 systemd[1]: Started session-28.scope - Session 28 of User core. Sep 12 10:14:52.514340 sshd[12995]: Connection closed by 10.200.16.10 port 46200 Sep 12 10:14:52.514750 sshd-session[12993]: pam_unix(sshd:session): session closed for user core Sep 12 10:14:52.518066 systemd[1]: sshd@25-10.200.8.23:22-10.200.16.10:46200.service: Deactivated successfully. Sep 12 10:14:52.519709 systemd[1]: session-28.scope: Deactivated successfully. Sep 12 10:14:52.520389 systemd-logind[1686]: Session 28 logged out. Waiting for processes to exit. Sep 12 10:14:52.521192 systemd-logind[1686]: Removed session 28. Sep 12 10:14:52.626709 systemd[1]: Started sshd@26-10.200.8.23:22-10.200.16.10:46204.service - OpenSSH per-connection server daemon (10.200.16.10:46204). Sep 12 10:14:53.252723 sshd[13001]: Accepted publickey for core from 10.200.16.10 port 46204 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:14:53.254142 sshd-session[13001]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:14:53.258249 systemd-logind[1686]: New session 29 of user core. Sep 12 10:14:53.271157 systemd[1]: Started session-29.scope - Session 29 of User core. Sep 12 10:14:53.691547 sshd[13003]: Connection closed by 10.200.16.10 port 46204 Sep 12 10:14:53.691975 sshd-session[13001]: pam_unix(sshd:session): session closed for user core Sep 12 10:14:53.695329 systemd[1]: sshd@26-10.200.8.23:22-10.200.16.10:46204.service: Deactivated successfully. Sep 12 10:14:53.696947 systemd[1]: session-29.scope: Deactivated successfully. Sep 12 10:14:53.697600 systemd-logind[1686]: Session 29 logged out. Waiting for processes to exit. Sep 12 10:14:53.698372 systemd-logind[1686]: Removed session 29. Sep 12 10:14:53.806394 systemd[1]: Started sshd@27-10.200.8.23:22-10.200.16.10:46220.service - OpenSSH per-connection server daemon (10.200.16.10:46220). Sep 12 10:14:54.436522 sshd[13009]: Accepted publickey for core from 10.200.16.10 port 46220 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:14:54.437972 sshd-session[13009]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:14:54.442215 systemd-logind[1686]: New session 30 of user core. Sep 12 10:14:54.454168 systemd[1]: Started session-30.scope - Session 30 of User core. Sep 12 10:14:54.881516 sshd[13011]: Connection closed by 10.200.16.10 port 46220 Sep 12 10:14:54.881864 sshd-session[13009]: pam_unix(sshd:session): session closed for user core Sep 12 10:14:54.884944 systemd[1]: sshd@27-10.200.8.23:22-10.200.16.10:46220.service: Deactivated successfully. Sep 12 10:14:54.886550 systemd[1]: session-30.scope: Deactivated successfully. Sep 12 10:14:54.887182 systemd-logind[1686]: Session 30 logged out. Waiting for processes to exit. Sep 12 10:14:54.887939 systemd-logind[1686]: Removed session 30. Sep 12 10:14:54.992666 systemd[1]: Started sshd@28-10.200.8.23:22-10.200.16.10:46226.service - OpenSSH per-connection server daemon (10.200.16.10:46226). Sep 12 10:14:55.621520 sshd[13017]: Accepted publickey for core from 10.200.16.10 port 46226 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:14:55.622855 sshd-session[13017]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:14:55.626900 systemd-logind[1686]: New session 31 of user core. Sep 12 10:14:55.638174 systemd[1]: Started session-31.scope - Session 31 of User core. Sep 12 10:14:56.067890 sshd[13019]: Connection closed by 10.200.16.10 port 46226 Sep 12 10:14:56.068271 sshd-session[13017]: pam_unix(sshd:session): session closed for user core Sep 12 10:14:56.071347 systemd[1]: sshd@28-10.200.8.23:22-10.200.16.10:46226.service: Deactivated successfully. Sep 12 10:14:56.073014 systemd[1]: session-31.scope: Deactivated successfully. Sep 12 10:14:56.073664 systemd-logind[1686]: Session 31 logged out. Waiting for processes to exit. Sep 12 10:14:56.074424 systemd-logind[1686]: Removed session 31. Sep 12 10:14:56.186786 systemd[1]: Started sshd@29-10.200.8.23:22-10.200.16.10:46236.service - OpenSSH per-connection server daemon (10.200.16.10:46236). Sep 12 10:14:56.812430 sshd[13025]: Accepted publickey for core from 10.200.16.10 port 46236 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:14:56.813737 sshd-session[13025]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:14:56.818010 systemd-logind[1686]: New session 32 of user core. Sep 12 10:14:56.829153 systemd[1]: Started session-32.scope - Session 32 of User core. Sep 12 10:14:57.390045 systemd[1]: Stopping user@0.service - User Manager for UID 0... Sep 12 10:14:57.390847 systemd[2372]: Activating special unit exit.target... Sep 12 10:14:57.390933 systemd[2372]: Stopped target default.target - Main User Target. Sep 12 10:14:57.390975 systemd[2372]: Stopped target basic.target - Basic System. Sep 12 10:14:57.391005 systemd[2372]: Stopped target paths.target - Paths. Sep 12 10:14:57.391032 systemd[2372]: Stopped target sockets.target - Sockets. Sep 12 10:14:57.391084 systemd[2372]: Stopped target timers.target - Timers. Sep 12 10:14:57.391456 systemd[2372]: Closed dbus.socket - D-Bus User Message Bus Socket. Sep 12 10:14:57.391678 systemd[2372]: Removed slice app.slice - User Application Slice. Sep 12 10:14:57.391712 systemd[2372]: Reached target shutdown.target - Shutdown. Sep 12 10:14:57.391872 systemd[2372]: Finished systemd-exit.service - Exit the Session. Sep 12 10:14:57.391913 systemd[2372]: Reached target exit.target - Exit the Session. Sep 12 10:14:57.403148 (sd-pam)[2373]: pam_unix(systemd-user:session): session closed for user root Sep 12 10:14:57.404553 systemd[1]: user@0.service: Deactivated successfully. Sep 12 10:14:57.404900 systemd[1]: Stopped user@0.service - User Manager for UID 0. Sep 12 10:14:57.421376 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Sep 12 10:14:57.430266 systemd[1]: run-user-0.mount: Deactivated successfully. Sep 12 10:14:57.431319 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Sep 12 10:14:57.431527 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Sep 12 10:14:57.433374 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Sep 12 10:14:57.433465 systemd[1]: user-0.slice: Consumed 5min 18.076s CPU time, 892.1M memory peak. Sep 12 10:14:57.433826 systemd-logind[1686]: Removed session c3. Sep 12 10:14:58.735933 sshd[13027]: Connection closed by 10.200.16.10 port 46236 Sep 12 10:14:58.736488 sshd-session[13025]: pam_unix(sshd:session): session closed for user core Sep 12 10:14:58.740186 systemd[1]: sshd@29-10.200.8.23:22-10.200.16.10:46236.service: Deactivated successfully. Sep 12 10:14:58.741832 systemd[1]: session-32.scope: Deactivated successfully. Sep 12 10:14:58.742029 systemd[1]: session-32.scope: Consumed 1.425s CPU time, 14.1M memory peak. Sep 12 10:14:58.742485 systemd-logind[1686]: Session 32 logged out. Waiting for processes to exit. Sep 12 10:14:58.743294 systemd-logind[1686]: Removed session 32.