Sep 12 10:11:25.221760 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:25.221803 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:25.221813 kernel: BIOS-provided physical RAM map: Sep 12 10:11:25.221820 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Sep 12 10:11:25.221827 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Sep 12 10:11:25.221833 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Sep 12 10:11:25.221841 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Sep 12 10:11:25.221851 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Sep 12 10:11:25.221858 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Sep 12 10:11:25.221865 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Sep 12 10:11:25.221872 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Sep 12 10:11:25.221878 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Sep 12 10:11:25.221885 kernel: printk: bootconsole [earlyser0] enabled Sep 12 10:11:25.221892 kernel: NX (Execute Disable) protection: active Sep 12 10:11:25.221903 kernel: APIC: Static calls initialized Sep 12 10:11:25.221910 kernel: efi: EFI v2.7 by Microsoft Sep 12 10:11:25.221918 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Sep 12 10:11:25.221926 kernel: random: crng init done Sep 12 10:11:25.221934 kernel: secureboot: Secure boot disabled Sep 12 10:11:25.221941 kernel: SMBIOS 3.1.0 present. Sep 12 10:11:25.221949 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Sep 12 10:11:25.221956 kernel: Hypervisor detected: Microsoft Hyper-V Sep 12 10:11:25.221964 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Sep 12 10:11:25.221971 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Sep 12 10:11:25.221981 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Sep 12 10:11:25.221988 kernel: Hyper-V: Using hypercall for remote TLB flush Sep 12 10:11:25.221995 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Sep 12 10:11:25.222003 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Sep 12 10:11:25.222011 kernel: tsc: Marking TSC unstable due to running on Hyper-V Sep 12 10:11:25.222019 kernel: tsc: Detected 2593.992 MHz processor Sep 12 10:11:25.222027 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Sep 12 10:11:25.222035 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Sep 12 10:11:25.222043 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Sep 12 10:11:25.222053 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Sep 12 10:11:25.222061 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Sep 12 10:11:25.222068 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Sep 12 10:11:25.222076 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Sep 12 10:11:25.222083 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Sep 12 10:11:25.222091 kernel: Using GB pages for direct mapping Sep 12 10:11:25.222098 kernel: ACPI: Early table checksum verification disabled Sep 12 10:11:25.222110 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Sep 12 10:11:25.222120 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 10:11:25.222128 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 10:11:25.222136 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Sep 12 10:11:25.222144 kernel: ACPI: FACS 0x000000003FFFE000 000040 Sep 12 10:11:25.222152 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 10:11:25.222161 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 10:11:25.222171 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 10:11:25.222179 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 10:11:25.222187 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 10:11:25.222195 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 10:11:25.222203 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 12 10:11:25.222211 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Sep 12 10:11:25.222220 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Sep 12 10:11:25.222228 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Sep 12 10:11:25.222238 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Sep 12 10:11:25.222246 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Sep 12 10:11:25.222254 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Sep 12 10:11:25.222262 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Sep 12 10:11:25.222270 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Sep 12 10:11:25.222278 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Sep 12 10:11:25.222286 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Sep 12 10:11:25.222294 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0 Sep 12 10:11:25.222302 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0 Sep 12 10:11:25.222312 kernel: SRAT: PXM 0 -> APIC 0x02 -> Node 0 Sep 12 10:11:25.222320 kernel: SRAT: PXM 0 -> APIC 0x03 -> Node 0 Sep 12 10:11:25.222328 kernel: SRAT: PXM 0 -> APIC 0x04 -> Node 0 Sep 12 10:11:25.222336 kernel: SRAT: PXM 0 -> APIC 0x05 -> Node 0 Sep 12 10:11:25.222344 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Sep 12 10:11:25.222352 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Sep 12 10:11:25.222360 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Sep 12 10:11:25.222368 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Sep 12 10:11:25.222376 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Sep 12 10:11:25.222386 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Sep 12 10:11:25.222394 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Sep 12 10:11:25.222402 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Sep 12 10:11:25.222411 kernel: NUMA: Node 0 [mem 0x00000000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00000000-0xfdfffffff] Sep 12 10:11:25.222419 kernel: NUMA: Node 0 [mem 0x00000000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00000000-0x5cbfffffff] Sep 12 10:11:25.222427 kernel: NODE_DATA(0) allocated [mem 0x5cbfff9000-0x5cbfffefff] Sep 12 10:11:25.222435 kernel: Zone ranges: Sep 12 10:11:25.222443 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Sep 12 10:11:25.222451 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Sep 12 10:11:25.222461 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Sep 12 10:11:25.222469 kernel: Movable zone start for each node Sep 12 10:11:25.222477 kernel: Early memory node ranges Sep 12 10:11:25.222485 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Sep 12 10:11:25.222493 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Sep 12 10:11:25.222501 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Sep 12 10:11:25.222509 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Sep 12 10:11:25.222517 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Sep 12 10:11:25.222525 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Sep 12 10:11:25.222536 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Sep 12 10:11:25.222544 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Sep 12 10:11:25.222552 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Sep 12 10:11:25.222560 kernel: ACPI: PM-Timer IO Port: 0x408 Sep 12 10:11:25.222568 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Sep 12 10:11:25.222575 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Sep 12 10:11:25.222583 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Sep 12 10:11:25.222591 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Sep 12 10:11:25.222599 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Sep 12 10:11:25.222610 kernel: smpboot: Allowing 6 CPUs, 0 hotplug CPUs Sep 12 10:11:25.222618 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Sep 12 10:11:25.222626 kernel: Booting paravirtualized kernel on Hyper-V Sep 12 10:11:25.222634 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Sep 12 10:11:25.222642 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Sep 12 10:11:25.222650 kernel: percpu: Embedded 58 pages/cpu s197160 r8192 d32216 u262144 Sep 12 10:11:25.222658 kernel: pcpu-alloc: s197160 r8192 d32216 u262144 alloc=1*2097152 Sep 12 10:11:25.222666 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Sep 12 10:11:25.222674 kernel: Hyper-V: PV spinlocks enabled Sep 12 10:11:25.222684 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Sep 12 10:11:25.222693 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:25.222702 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Sep 12 10:11:25.222710 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Sep 12 10:11:25.222718 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Sep 12 10:11:25.222726 kernel: Fallback order for Node 0: 0 Sep 12 10:11:25.222734 kernel: Built 1 zonelists, mobility grouping on. Total pages: 28899610 Sep 12 10:11:25.222742 kernel: Policy zone: Normal Sep 12 10:11:25.222753 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 12 10:11:25.222769 kernel: software IO TLB: area num 8. Sep 12 10:11:25.222785 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:25.222797 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Sep 12 10:11:25.222805 kernel: Kernel/User page tables isolation: enabled Sep 12 10:11:25.222814 kernel: ftrace: allocating 37946 entries in 149 pages Sep 12 10:11:25.222823 kernel: ftrace: allocated 149 pages with 4 groups Sep 12 10:11:25.222831 kernel: Dynamic Preempt: voluntary Sep 12 10:11:25.222839 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 12 10:11:25.222851 kernel: rcu: RCU event tracing is enabled. Sep 12 10:11:25.222860 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Sep 12 10:11:25.222869 kernel: Trampoline variant of Tasks RCU enabled. Sep 12 10:11:25.222880 kernel: Rude variant of Tasks RCU enabled. Sep 12 10:11:25.222888 kernel: Tracing variant of Tasks RCU enabled. Sep 12 10:11:25.222897 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 12 10:11:25.222905 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Sep 12 10:11:25.222916 kernel: Using NULL legacy PIC Sep 12 10:11:25.222925 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Sep 12 10:11:25.222933 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 12 10:11:25.222942 kernel: Console: colour dummy device 80x25 Sep 12 10:11:25.222950 kernel: printk: console [tty1] enabled Sep 12 10:11:25.222958 kernel: printk: console [ttyS0] enabled Sep 12 10:11:25.222967 kernel: printk: bootconsole [earlyser0] disabled Sep 12 10:11:25.222975 kernel: ACPI: Core revision 20230628 Sep 12 10:11:25.222984 kernel: Failed to register legacy timer interrupt Sep 12 10:11:25.222992 kernel: APIC: Switch to symmetric I/O mode setup Sep 12 10:11:25.223003 kernel: Hyper-V: enabling crash_kexec_post_notifiers Sep 12 10:11:25.223011 kernel: Hyper-V: Using IPI hypercalls Sep 12 10:11:25.223020 kernel: APIC: send_IPI() replaced with hv_send_ipi() Sep 12 10:11:25.223028 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Sep 12 10:11:25.223037 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Sep 12 10:11:25.223045 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Sep 12 10:11:25.223054 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Sep 12 10:11:25.223062 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Sep 12 10:11:25.223070 kernel: Hyper-V: Using enlightened APIC (xapic mode) Sep 12 10:11:25.223081 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Sep 12 10:11:25.223090 kernel: APIC: read() replaced with hv_apic_read() Sep 12 10:11:25.223098 kernel: APIC: write() replaced with hv_apic_write() Sep 12 10:11:25.223107 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Sep 12 10:11:25.223115 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Sep 12 10:11:25.223124 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593992) Sep 12 10:11:25.223132 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Sep 12 10:11:25.223141 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Sep 12 10:11:25.223149 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Sep 12 10:11:25.223160 kernel: Spectre V2 : Mitigation: Retpolines Sep 12 10:11:25.223168 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Sep 12 10:11:25.223176 kernel: Speculative Store Bypass: Vulnerable Sep 12 10:11:25.223185 kernel: MDS: Mitigation: Clear CPU buffers Sep 12 10:11:25.223193 kernel: TAA: Mitigation: Clear CPU buffers Sep 12 10:11:25.223201 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Sep 12 10:11:25.223210 kernel: active return thunk: its_return_thunk Sep 12 10:11:25.223218 kernel: ITS: Mitigation: Aligned branch/return thunks Sep 12 10:11:25.223226 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Sep 12 10:11:25.223234 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Sep 12 10:11:25.223242 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Sep 12 10:11:25.223253 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Sep 12 10:11:25.223262 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Sep 12 10:11:25.223270 kernel: Freeing SMP alternatives memory: 32K Sep 12 10:11:25.223279 kernel: pid_max: default: 32768 minimum: 301 Sep 12 10:11:25.223287 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Sep 12 10:11:25.223295 kernel: landlock: Up and running. Sep 12 10:11:25.223303 kernel: SELinux: Initializing. Sep 12 10:11:25.223312 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Sep 12 10:11:25.223320 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Sep 12 10:11:25.223329 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Sep 12 10:11:25.223337 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:25.223348 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:25.223357 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:25.223365 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Sep 12 10:11:25.223374 kernel: signal: max sigframe size: 1776 Sep 12 10:11:25.223382 kernel: rcu: Hierarchical SRCU implementation. Sep 12 10:11:25.223391 kernel: rcu: Max phase no-delay instances is 400. Sep 12 10:11:25.223400 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Sep 12 10:11:25.223408 kernel: smp: Bringing up secondary CPUs ... Sep 12 10:11:25.223416 kernel: smpboot: x86: Booting SMP configuration: Sep 12 10:11:25.223427 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Sep 12 10:11:25.223435 kernel: smp: Brought up 1 node, 6 CPUs Sep 12 10:11:25.223444 kernel: smpboot: Max logical packages: 1 Sep 12 10:11:25.223452 kernel: smpboot: Total of 6 processors activated (31127.90 BogoMIPS) Sep 12 10:11:25.223461 kernel: devtmpfs: initialized Sep 12 10:11:25.223469 kernel: x86/mm: Memory block size: 1024MB Sep 12 10:11:25.223477 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Sep 12 10:11:25.223486 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 12 10:11:25.223494 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Sep 12 10:11:25.223505 kernel: pinctrl core: initialized pinctrl subsystem Sep 12 10:11:25.223514 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 12 10:11:25.223522 kernel: audit: initializing netlink subsys (disabled) Sep 12 10:11:25.223531 kernel: audit: type=2000 audit(1757671883.029:1): state=initialized audit_enabled=0 res=1 Sep 12 10:11:25.223539 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 12 10:11:25.223547 kernel: thermal_sys: Registered thermal governor 'user_space' Sep 12 10:11:25.223556 kernel: cpuidle: using governor menu Sep 12 10:11:25.223564 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 12 10:11:25.223572 kernel: dca service started, version 1.12.1 Sep 12 10:11:25.223583 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Sep 12 10:11:25.223592 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Sep 12 10:11:25.223600 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 12 10:11:25.223609 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Sep 12 10:11:25.223617 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 12 10:11:25.223626 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Sep 12 10:11:25.223634 kernel: ACPI: Added _OSI(Module Device) Sep 12 10:11:25.223642 kernel: ACPI: Added _OSI(Processor Device) Sep 12 10:11:25.223651 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 12 10:11:25.223662 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 12 10:11:25.223670 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Sep 12 10:11:25.223678 kernel: ACPI: Interpreter enabled Sep 12 10:11:25.223687 kernel: ACPI: PM: (supports S0 S5) Sep 12 10:11:25.223695 kernel: ACPI: Using IOAPIC for interrupt routing Sep 12 10:11:25.223703 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Sep 12 10:11:25.223712 kernel: PCI: Ignoring E820 reservations for host bridge windows Sep 12 10:11:25.223720 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Sep 12 10:11:25.223729 kernel: iommu: Default domain type: Translated Sep 12 10:11:25.223740 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Sep 12 10:11:25.223748 kernel: efivars: Registered efivars operations Sep 12 10:11:25.223756 kernel: PCI: Using ACPI for IRQ routing Sep 12 10:11:25.223765 kernel: PCI: System does not support PCI Sep 12 10:11:25.223773 kernel: vgaarb: loaded Sep 12 10:11:25.223789 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Sep 12 10:11:25.223797 kernel: VFS: Disk quotas dquot_6.6.0 Sep 12 10:11:25.223806 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 12 10:11:25.223814 kernel: pnp: PnP ACPI init Sep 12 10:11:25.223825 kernel: pnp: PnP ACPI: found 3 devices Sep 12 10:11:25.223834 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Sep 12 10:11:25.223842 kernel: NET: Registered PF_INET protocol family Sep 12 10:11:25.223851 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 12 10:11:25.223859 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Sep 12 10:11:25.223868 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 12 10:11:25.223876 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 12 10:11:25.223885 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Sep 12 10:11:25.223894 kernel: TCP: Hash tables configured (established 524288 bind 65536) Sep 12 10:11:25.223904 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Sep 12 10:11:25.223913 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Sep 12 10:11:25.223921 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 12 10:11:25.223930 kernel: NET: Registered PF_XDP protocol family Sep 12 10:11:25.223938 kernel: PCI: CLS 0 bytes, default 64 Sep 12 10:11:25.223947 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Sep 12 10:11:25.223955 kernel: software IO TLB: mapped [mem 0x000000003b5bf000-0x000000003f5bf000] (64MB) Sep 12 10:11:25.223964 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Sep 12 10:11:25.223972 kernel: Initialise system trusted keyrings Sep 12 10:11:25.223983 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Sep 12 10:11:25.223991 kernel: Key type asymmetric registered Sep 12 10:11:25.223999 kernel: Asymmetric key parser 'x509' registered Sep 12 10:11:25.224008 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Sep 12 10:11:25.224016 kernel: io scheduler mq-deadline registered Sep 12 10:11:25.224025 kernel: io scheduler kyber registered Sep 12 10:11:25.224033 kernel: io scheduler bfq registered Sep 12 10:11:25.224042 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Sep 12 10:11:25.224050 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 12 10:11:25.224061 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Sep 12 10:11:25.224070 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Sep 12 10:11:25.224078 kernel: i8042: PNP: No PS/2 controller found. Sep 12 10:11:25.224248 kernel: rtc_cmos 00:02: registered as rtc0 Sep 12 10:11:25.224364 kernel: rtc_cmos 00:02: setting system clock to 2025-09-12T10:11:24 UTC (1757671884) Sep 12 10:11:25.224469 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Sep 12 10:11:25.224482 kernel: intel_pstate: CPU model not supported Sep 12 10:11:25.224496 kernel: efifb: probing for efifb Sep 12 10:11:25.224511 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Sep 12 10:11:25.224526 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Sep 12 10:11:25.224537 kernel: efifb: scrolling: redraw Sep 12 10:11:25.224548 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Sep 12 10:11:25.224557 kernel: Console: switching to colour frame buffer device 128x48 Sep 12 10:11:25.224565 kernel: fb0: EFI VGA frame buffer device Sep 12 10:11:25.224574 kernel: pstore: Using crash dump compression: deflate Sep 12 10:11:25.224582 kernel: pstore: Registered efi_pstore as persistent store backend Sep 12 10:11:25.224593 kernel: NET: Registered PF_INET6 protocol family Sep 12 10:11:25.224602 kernel: Segment Routing with IPv6 Sep 12 10:11:25.224610 kernel: In-situ OAM (IOAM) with IPv6 Sep 12 10:11:25.224619 kernel: NET: Registered PF_PACKET protocol family Sep 12 10:11:25.224627 kernel: Key type dns_resolver registered Sep 12 10:11:25.224636 kernel: IPI shorthand broadcast: enabled Sep 12 10:11:25.224644 kernel: sched_clock: Marking stable (1658006000, 99818700)->(2086438600, -328613900) Sep 12 10:11:25.224652 kernel: registered taskstats version 1 Sep 12 10:11:25.224661 kernel: Loading compiled-in X.509 certificates Sep 12 10:11:25.224669 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.105-flatcar: 0972efc09ee0bcd53f8cdb5573e11871ce7b16a9' Sep 12 10:11:25.224680 kernel: Key type .fscrypt registered Sep 12 10:11:25.224689 kernel: Key type fscrypt-provisioning registered Sep 12 10:11:25.224697 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 12 10:11:25.224706 kernel: ima: Allocated hash algorithm: sha1 Sep 12 10:11:25.224719 kernel: ima: No architecture policies found Sep 12 10:11:25.224729 kernel: clk: Disabling unused clocks Sep 12 10:11:25.224738 kernel: Freeing unused kernel image (initmem) memory: 43508K Sep 12 10:11:25.224746 kernel: Write protecting the kernel read-only data: 38912k Sep 12 10:11:25.224758 kernel: Freeing unused kernel image (rodata/data gap) memory: 1708K Sep 12 10:11:25.224767 kernel: Run /init as init process Sep 12 10:11:25.224775 kernel: with arguments: Sep 12 10:11:25.224795 kernel: /init Sep 12 10:11:25.224803 kernel: with environment: Sep 12 10:11:25.224812 kernel: HOME=/ Sep 12 10:11:25.224820 kernel: TERM=linux Sep 12 10:11:25.224828 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 12 10:11:25.224838 systemd[1]: Successfully made /usr/ read-only. Sep 12 10:11:25.224853 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:25.224862 systemd[1]: Detected virtualization microsoft. Sep 12 10:11:25.224871 systemd[1]: Detected architecture x86-64. Sep 12 10:11:25.224880 systemd[1]: Running in initrd. Sep 12 10:11:25.224888 systemd[1]: No hostname configured, using default hostname. Sep 12 10:11:25.224897 systemd[1]: Hostname set to . Sep 12 10:11:25.224906 systemd[1]: Initializing machine ID from random generator. Sep 12 10:11:25.224918 systemd[1]: Queued start job for default target initrd.target. Sep 12 10:11:25.224927 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 12 10:11:25.224936 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 12 10:11:25.224946 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 12 10:11:25.224955 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 12 10:11:25.224964 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 12 10:11:25.224974 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 12 10:11:25.224986 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:25.224996 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 12 10:11:25.225005 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 12 10:11:25.225014 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 12 10:11:25.225022 systemd[1]: Reached target paths.target - Path Units. Sep 12 10:11:25.225031 systemd[1]: Reached target slices.target - Slice Units. Sep 12 10:11:25.225040 systemd[1]: Reached target swap.target - Swaps. Sep 12 10:11:25.225049 systemd[1]: Reached target timers.target - Timer Units. Sep 12 10:11:25.225058 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 12 10:11:25.225070 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 12 10:11:25.225079 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 12 10:11:25.225088 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Sep 12 10:11:25.225097 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 12 10:11:25.225106 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 12 10:11:25.225115 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 12 10:11:25.225124 systemd[1]: Reached target sockets.target - Socket Units. Sep 12 10:11:25.225133 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 12 10:11:25.225144 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 12 10:11:25.225153 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 12 10:11:25.225162 systemd[1]: Starting systemd-fsck-usr.service... Sep 12 10:11:25.225171 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 12 10:11:25.225180 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 12 10:11:25.225189 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 12 10:11:25.225223 systemd-journald[205]: Collecting audit messages is disabled. Sep 12 10:11:25.225249 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 12 10:11:25.225259 systemd-journald[205]: Journal started Sep 12 10:11:25.225285 systemd-journald[205]: Runtime Journal (/run/log/journal/811d6ed0828945dfa20dbb1def7da8df) is 8M, max 2.2G, 2.1G free. Sep 12 10:11:25.230835 systemd[1]: Started systemd-journald.service - Journal Service. Sep 12 10:11:25.231205 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 12 10:11:25.231755 systemd[1]: Finished systemd-fsck-usr.service. Sep 12 10:11:25.234154 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 12 10:11:25.235095 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 12 10:11:25.245413 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 12 10:11:25.246582 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 12 10:11:25.291086 systemd-modules-load[207]: Inserted module 'overlay' Sep 12 10:11:25.300328 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 10:11:25.305229 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 12 10:11:25.335795 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:25.339326 systemd-modules-load[207]: Inserted module 'br_netfilter' Sep 12 10:11:25.340753 kernel: Bridge firewalling registered Sep 12 10:11:25.340238 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 12 10:11:25.347383 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 12 10:11:25.348827 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 12 10:11:25.350394 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 12 10:11:25.383686 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 12 10:11:25.386487 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 12 10:11:25.397637 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 12 10:11:25.400490 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 12 10:11:25.419431 dracut-cmdline[244]: dracut-dracut-053 Sep 12 10:11:25.422493 dracut-cmdline[244]: Using kernel command line parameters: rd.driver.pre=btrfs rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=87e444606a7368354f582e8f746f078f97e75cf74b35edd9ec39d0d73a54ead2 Sep 12 10:11:25.439325 systemd-resolved[233]: Positive Trust Anchors: Sep 12 10:11:25.439333 systemd-resolved[233]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 12 10:11:25.439363 systemd-resolved[233]: 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:25.441856 systemd-resolved[233]: Defaulting to hostname 'linux'. Sep 12 10:11:25.442971 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 12 10:11:25.446580 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 12 10:11:25.564801 kernel: SCSI subsystem initialized Sep 12 10:11:25.575798 kernel: Loading iSCSI transport class v2.0-870. Sep 12 10:11:25.587801 kernel: iscsi: registered transport (tcp) Sep 12 10:11:25.611203 kernel: iscsi: registered transport (qla4xxx) Sep 12 10:11:25.611239 kernel: QLogic iSCSI HBA Driver Sep 12 10:11:25.650417 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 12 10:11:25.670978 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 12 10:11:25.700727 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:25.700771 kernel: device-mapper: uevent: version 1.0.3 Sep 12 10:11:25.705180 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Sep 12 10:11:25.747795 kernel: raid6: avx2x4 gen() 31189 MB/s Sep 12 10:11:25.766790 kernel: raid6: avx2x2 gen() 31363 MB/s Sep 12 10:11:25.788864 kernel: raid6: avx2x1 gen() 29271 MB/s Sep 12 10:11:25.788895 kernel: raid6: using algorithm avx2x2 gen() 31363 MB/s Sep 12 10:11:25.811792 kernel: raid6: .... xor() 18269 MB/s, rmw enabled Sep 12 10:11:25.811820 kernel: raid6: using avx2x2 recovery algorithm Sep 12 10:11:25.834801 kernel: xor: automatically using best checksumming function avx Sep 12 10:11:26.026809 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 12 10:11:26.037430 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 12 10:11:26.050964 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 12 10:11:26.074594 systemd-udevd[428]: Using default interface naming scheme 'v255'. Sep 12 10:11:26.080393 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 12 10:11:26.097905 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 12 10:11:26.111471 dracut-pre-trigger[440]: rd.md=0: removing MD RAID activation Sep 12 10:11:26.156323 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 12 10:11:26.179955 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 12 10:11:26.242833 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 12 10:11:26.253960 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 12 10:11:26.269879 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 12 10:11:26.279099 kernel: cryptd: max_cpu_qlen set to 1000 Sep 12 10:11:26.272421 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 12 10:11:26.289667 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 12 10:11:26.299431 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 12 10:11:26.315301 kernel: AVX2 version of gcm_enc/dec engaged. Sep 12 10:11:26.315334 kernel: AES CTR mode by8 optimization enabled Sep 12 10:11:26.315347 kernel: hv_vmbus: Vmbus version:4.0 Sep 12 10:11:26.318963 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 12 10:11:26.330234 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 12 10:11:26.342398 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 12 10:11:26.349741 kernel: pps_core: LinuxPPS API ver. 1 registered Sep 12 10:11:26.349765 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Sep 12 10:11:26.342581 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 12 10:11:26.357796 kernel: hv_vmbus: registering driver hyperv_keyboard Sep 12 10:11:26.357829 kernel: hid: raw HID events driver (C) Jiri Kosina Sep 12 10:11:26.373794 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:26.373957 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 12 10:11:26.375398 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 12 10:11:26.375601 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 10:11:26.402319 kernel: hv_vmbus: registering driver hv_netvsc Sep 12 10:11:26.391763 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 12 10:11:26.408739 kernel: PTP clock support registered Sep 12 10:11:26.414358 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 12 10:11:26.422740 kernel: hv_vmbus: registering driver hv_storvsc Sep 12 10:11:26.416528 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 12 10:11:26.430815 kernel: scsi host0: storvsc_host_t Sep 12 10:11:26.430864 kernel: scsi host1: storvsc_host_t Sep 12 10:11:26.437841 kernel: scsi 1:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Sep 12 10:11:26.437898 kernel: hv_utils: Registering HyperV Utility Driver Sep 12 10:11:26.442799 kernel: hv_vmbus: registering driver hv_utils Sep 12 10:11:26.442829 kernel: hv_vmbus: registering driver hid_hyperv Sep 12 10:11:26.445795 kernel: hv_utils: Shutdown IC version 3.2 Sep 12 10:11:26.459741 kernel: scsi 1:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Sep 12 10:11:26.459800 kernel: hv_utils: Heartbeat IC version 3.0 Sep 12 10:11:26.459816 kernel: scsi 1:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Sep 12 10:11:26.459837 kernel: hv_utils: TimeSync IC version 4.0 Sep 12 10:11:26.460795 kernel: hv_vmbus: registering driver hv_pci Sep 12 10:11:27.267615 systemd-resolved[233]: Clock change detected. Flushing caches. Sep 12 10:11:27.317494 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Sep 12 10:11:27.317701 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Sep 12 10:11:27.319216 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Sep 12 10:11:27.320857 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Sep 12 10:11:27.321018 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Sep 12 10:11:27.321185 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Sep 12 10:11:27.321319 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Sep 12 10:11:27.321468 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 Sep 12 10:11:27.321500 kernel: pci 0001:00:00.0: reg 0x10: [mem 0x41000000-0x41ffffff] Sep 12 10:11:27.321519 kernel: pci 0001:00:00.0: reg 0x14: [mem 0x1000000000-0x13ffffffff 64bit pref] Sep 12 10:11:27.321538 kernel: pci 0001:00:00.0: reg 0x1c: [mem 0x1400000000-0x1401ffffff 64bit pref] Sep 12 10:11:27.317645 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 10:11:27.333379 kernel: pci 0001:00:00.0: Enabling HDA controller Sep 12 10:11:27.340909 kernel: sr 1:0:0:2: [sr0] scsi-1 drive Sep 12 10:11:27.341134 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Sep 12 10:11:27.342813 kernel: sr 1:0:0:2: Attached scsi CD-ROM sr0 Sep 12 10:11:27.351196 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Sep 12 10:11:27.351428 kernel: pci 0001:00:00.0: BAR 1: assigned [mem 0x1000000000-0x13ffffffff 64bit pref] Sep 12 10:11:27.356857 kernel: pci 0001:00:00.0: BAR 3: assigned [mem 0x1400000000-0x1401ffffff 64bit pref] Sep 12 10:11:27.357045 kernel: pci 0001:00:00.0: BAR 0: assigned [mem 0x41000000-0x41ffffff] Sep 12 10:11:27.368494 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 12 10:11:27.404491 kernel: sd 1:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Sep 12 10:11:27.404702 kernel: sd 1:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Sep 12 10:11:27.404877 kernel: sd 1:0:0:0: [sda] 4096-byte physical blocks Sep 12 10:11:27.405021 kernel: sd 1:0:0:1: [sdb] 4096-byte physical blocks Sep 12 10:11:27.405167 kernel: sd 1:0:0:1: [sdb] Write Protect is off Sep 12 10:11:27.405313 kernel: sd 1:0:0:0: [sda] Write Protect is off Sep 12 10:11:27.405457 kernel: sd 1:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Sep 12 10:11:27.405597 kernel: sd 1:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Sep 12 10:11:27.405734 kernel: sd 1:0:0:0: [sda] Mode Sense: 0f 00 10 00 Sep 12 10:11:27.405887 kernel: sdb: sdb1 Sep 12 10:11:27.405901 kernel: sd 1:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Sep 12 10:11:27.406037 kernel: sd 1:0:0:1: [sdb] Attached SCSI disk Sep 12 10:11:27.406175 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 12 10:11:27.406194 kernel: sd 1:0:0:0: [sda] Attached SCSI disk Sep 12 10:11:27.424319 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 12 10:11:27.875815 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 scanned by (udev-worker) (499) Sep 12 10:11:27.893426 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Sep 12 10:11:27.906610 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Sep 12 10:11:27.952667 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Sep 12 10:11:27.969817 kernel: BTRFS: device fsid 2566299d-dd4a-4826-ba43-7397a17991fb devid 1 transid 35 /dev/sda3 scanned by (udev-worker) (489) Sep 12 10:11:27.989294 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Sep 12 10:11:27.990706 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Sep 12 10:11:28.024937 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 12 10:11:28.047818 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 12 10:11:28.066824 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 12 10:11:29.076552 disk-uuid[640]: The operation has completed successfully. Sep 12 10:11:29.080358 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 12 10:11:31.177378 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 12 10:11:31.177494 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 12 10:11:31.199978 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 12 10:11:31.208906 sh[728]: Success Sep 12 10:11:31.232818 kernel: device-mapper: verity: sha256 using implementation "sha256-avx2" Sep 12 10:11:31.567642 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 12 10:11:31.588268 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 12 10:11:31.591702 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 12 10:11:31.619368 kernel: BTRFS info (device dm-0): first mount of filesystem 2566299d-dd4a-4826-ba43-7397a17991fb Sep 12 10:11:31.619430 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Sep 12 10:11:31.624018 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Sep 12 10:11:31.627604 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 12 10:11:31.630609 kernel: BTRFS info (device dm-0): using free space tree Sep 12 10:11:31.946679 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 12 10:11:31.948834 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.967914 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 12 10:11:31.970124 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 12 10:11:32.012052 kernel: BTRFS info (device sda6): first mount of filesystem 36a15e30-b48e-4687-be9c-f68c3ae1825b Sep 12 10:11:32.012091 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Sep 12 10:11:32.015053 kernel: BTRFS info (device sda6): using free space tree Sep 12 10:11:32.065814 kernel: BTRFS info (device sda6): auto enabling async discard Sep 12 10:11:32.071362 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 12 10:11:32.083187 kernel: BTRFS info (device sda6): last unmount of filesystem 36a15e30-b48e-4687-be9c-f68c3ae1825b Sep 12 10:11:32.094959 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 12 10:11:32.096631 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 12 10:11:32.104877 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 12 10:11:32.140039 systemd-networkd[907]: lo: Link UP Sep 12 10:11:32.140049 systemd-networkd[907]: lo: Gained carrier Sep 12 10:11:32.141213 systemd-networkd[907]: Enumeration completed Sep 12 10:11:32.141301 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 12 10:11:32.141579 systemd-networkd[907]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 12 10:11:32.141584 systemd-networkd[907]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 12 10:11:32.142375 systemd-networkd[907]: eth0: Link UP Sep 12 10:11:32.142546 systemd-networkd[907]: eth0: Gained carrier Sep 12 10:11:32.142555 systemd-networkd[907]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 12 10:11:32.147245 systemd[1]: Reached target network.target - Network. Sep 12 10:11:32.202836 systemd-networkd[907]: eth0: DHCPv4 address 10.200.8.18/24, gateway 10.200.8.1 acquired from 168.63.129.16 Sep 12 10:11:32.729882 ignition[910]: Ignition 2.20.0 Sep 12 10:11:32.729895 ignition[910]: Stage: fetch-offline Sep 12 10:11:32.729936 ignition[910]: no configs at "/usr/lib/ignition/base.d" Sep 12 10:11:32.729947 ignition[910]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 12 10:11:32.730058 ignition[910]: parsed url from cmdline: "" Sep 12 10:11:32.730063 ignition[910]: no config URL provided Sep 12 10:11:32.730069 ignition[910]: reading system config file "/usr/lib/ignition/user.ign" Sep 12 10:11:32.730122 ignition[910]: no config at "/usr/lib/ignition/user.ign" Sep 12 10:11:32.730129 ignition[910]: failed to fetch config: resource requires networking Sep 12 10:11:32.730314 ignition[910]: Ignition finished successfully Sep 12 10:11:32.758063 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 12 10:11:32.774979 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Sep 12 10:11:32.787049 ignition[921]: Ignition 2.20.0 Sep 12 10:11:32.787061 ignition[921]: Stage: fetch Sep 12 10:11:32.787234 ignition[921]: no configs at "/usr/lib/ignition/base.d" Sep 12 10:11:32.787247 ignition[921]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 12 10:11:32.787348 ignition[921]: parsed url from cmdline: "" Sep 12 10:11:32.787353 ignition[921]: no config URL provided Sep 12 10:11:32.787358 ignition[921]: reading system config file "/usr/lib/ignition/user.ign" Sep 12 10:11:32.787369 ignition[921]: no config at "/usr/lib/ignition/user.ign" Sep 12 10:11:32.787395 ignition[921]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Sep 12 10:11:32.901630 ignition[921]: GET result: OK Sep 12 10:11:32.901685 ignition[921]: config has been read from IMDS userdata Sep 12 10:11:32.901698 ignition[921]: parsing config with SHA512: df9b21176119b7fb3ad25dd96dfccec964ca501b4d7eed2f8d0e877ae985580445306304afbcf36bcaab33273fa18b3e87ed30bd35e50df3c6219bc5f39b9623 Sep 12 10:11:32.905895 unknown[921]: fetched base config from "system" Sep 12 10:11:32.906145 ignition[921]: fetch: fetch complete Sep 12 10:11:32.905903 unknown[921]: fetched base config from "system" Sep 12 10:11:32.906152 ignition[921]: fetch: fetch passed Sep 12 10:11:32.905909 unknown[921]: fetched user config from "azure" Sep 12 10:11:32.906203 ignition[921]: Ignition finished successfully Sep 12 10:11:32.925533 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Sep 12 10:11:32.938968 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 12 10:11:32.959884 ignition[931]: Ignition 2.20.0 Sep 12 10:11:32.959896 ignition[931]: Stage: kargs Sep 12 10:11:32.960090 ignition[931]: no configs at "/usr/lib/ignition/base.d" Sep 12 10:11:32.960104 ignition[931]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 12 10:11:32.960622 ignition[931]: kargs: kargs passed Sep 12 10:11:32.972321 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 12 10:11:32.960663 ignition[931]: Ignition finished successfully Sep 12 10:11:32.990982 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 12 10:11:33.001528 ignition[941]: Ignition 2.20.0 Sep 12 10:11:33.001540 ignition[941]: Stage: disks Sep 12 10:11:33.001691 ignition[941]: no configs at "/usr/lib/ignition/base.d" Sep 12 10:11:33.001702 ignition[941]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 12 10:11:33.007030 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 12 10:11:33.002247 ignition[941]: disks: disks passed Sep 12 10:11:33.013180 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 12 10:11:33.002289 ignition[941]: Ignition finished successfully Sep 12 10:11:33.021606 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 12 10:11:33.030016 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 12 10:11:33.036293 systemd[1]: Reached target sysinit.target - System Initialization. Sep 12 10:11:33.044579 systemd[1]: Reached target basic.target - Basic System. Sep 12 10:11:33.070938 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 12 10:11:33.138396 systemd-fsck[953]: ROOT: clean, 14/7326000 files, 477710/7359488 blocks Sep 12 10:11:33.144168 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 12 10:11:33.165899 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 12 10:11:33.263809 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.264436 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 12 10:11:33.266375 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 12 10:11:33.293888 systemd-networkd[907]: eth0: Gained IPv6LL Sep 12 10:11:33.299868 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 12 10:11:33.314812 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/sda6 scanned by mount (964) Sep 12 10:11:33.320276 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 12 10:11:33.333349 kernel: BTRFS info (device sda6): first mount of filesystem 36a15e30-b48e-4687-be9c-f68c3ae1825b Sep 12 10:11:33.333410 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Sep 12 10:11:33.333423 kernel: BTRFS info (device sda6): using free space tree Sep 12 10:11:33.329512 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Sep 12 10:11:33.334759 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.358337 kernel: BTRFS info (device sda6): auto enabling async discard Sep 12 10:11:33.334792 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 12 10:11:33.348493 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 12 10:11:33.362968 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 12 10:11:33.386904 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 12 10:11:34.122528 coreos-metadata[979]: Sep 12 10:11:34.122 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Sep 12 10:11:34.128487 coreos-metadata[979]: Sep 12 10:11:34.125 INFO Fetch successful Sep 12 10:11:34.128487 coreos-metadata[979]: Sep 12 10:11:34.125 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.141347 coreos-metadata[979]: Sep 12 10:11:34.132 INFO Fetch successful Sep 12 10:11:34.145181 coreos-metadata[979]: Sep 12 10:11:34.143 INFO wrote hostname ci-4230.2.2-n-6bb2c6a89f to /sysroot/etc/hostname Sep 12 10:11:34.145821 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Sep 12 10:11:34.309676 initrd-setup-root[994]: cut: /sysroot/etc/passwd: No such file or directory Sep 12 10:11:34.367489 initrd-setup-root[1001]: cut: /sysroot/etc/group: No such file or directory Sep 12 10:11:34.394882 initrd-setup-root[1008]: cut: /sysroot/etc/shadow: No such file or directory Sep 12 10:11:34.419016 initrd-setup-root[1015]: cut: /sysroot/etc/gshadow: No such file or directory Sep 12 10:11:35.355439 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 12 10:11:35.374873 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 12 10:11:35.376308 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 12 10:11:35.388807 kernel: BTRFS info (device sda6): last unmount of filesystem 36a15e30-b48e-4687-be9c-f68c3ae1825b Sep 12 10:11:35.389505 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 12 10:11:35.415201 ignition[1084]: INFO : Ignition 2.20.0 Sep 12 10:11:35.415201 ignition[1084]: INFO : Stage: mount Sep 12 10:11:35.419657 ignition[1084]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 12 10:11:35.419657 ignition[1084]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 12 10:11:35.419657 ignition[1084]: INFO : mount: mount passed Sep 12 10:11:35.419657 ignition[1084]: INFO : Ignition finished successfully Sep 12 10:11:35.418634 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 12 10:11:35.441972 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 12 10:11:35.447201 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 12 10:11:35.456790 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 12 10:11:35.481812 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/sda6 scanned by mount (1098) Sep 12 10:11:35.490362 kernel: BTRFS info (device sda6): first mount of filesystem 36a15e30-b48e-4687-be9c-f68c3ae1825b Sep 12 10:11:35.490392 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Sep 12 10:11:35.493339 kernel: BTRFS info (device sda6): using free space tree Sep 12 10:11:35.509813 kernel: BTRFS info (device sda6): auto enabling async discard Sep 12 10:11:35.511217 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 12 10:11:35.551784 ignition[1115]: INFO : Ignition 2.20.0 Sep 12 10:11:35.551784 ignition[1115]: INFO : Stage: files Sep 12 10:11:35.557411 ignition[1115]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 12 10:11:35.557411 ignition[1115]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 12 10:11:35.557411 ignition[1115]: DEBUG : files: compiled without relabeling support, skipping Sep 12 10:11:35.570514 ignition[1115]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 12 10:11:35.570514 ignition[1115]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 12 10:11:35.676250 ignition[1115]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 12 10:11:35.681563 ignition[1115]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 12 10:11:35.681563 ignition[1115]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 12 10:11:35.676893 unknown[1115]: wrote ssh authorized keys file for user: core Sep 12 10:11:35.720686 ignition[1115]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 12 10:11:35.727606 ignition[1115]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 12 10:11:35.727606 ignition[1115]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 12 10:11:35.727606 ignition[1115]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 12 10:11:35.727606 ignition[1115]: INFO : files: files passed Sep 12 10:11:35.727606 ignition[1115]: INFO : Ignition finished successfully Sep 12 10:11:35.735825 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 12 10:11:35.763928 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 12 10:11:35.765643 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 12 10:11:35.773997 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 12 10:11:35.774080 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 12 10:11:35.799902 initrd-setup-root-after-ignition[1147]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 12 10:11:35.806054 initrd-setup-root-after-ignition[1147]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 12 10:11:35.812313 initrd-setup-root-after-ignition[1151]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 12 10:11:35.812567 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 12 10:11:35.814926 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 12 10:11:35.838925 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 12 10:11:35.861047 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 12 10:11:35.861152 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 12 10:11:35.871491 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 12 10:11:35.879993 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 12 10:11:35.887680 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 12 10:11:35.888476 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 12 10:11:35.911379 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 12 10:11:35.913962 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 12 10:11:35.936649 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 12 10:11:35.938193 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 12 10:11:35.947103 systemd[1]: Stopped target timers.target - Timer Units. Sep 12 10:11:35.956158 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 12 10:11:35.956284 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 12 10:11:35.970331 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 12 10:11:35.973892 systemd[1]: Stopped target basic.target - Basic System. Sep 12 10:11:35.983527 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 12 10:11:35.990285 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 12 10:11:35.997775 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 12 10:11:36.010164 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 12 10:11:36.018172 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 12 10:11:36.026569 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 12 10:11:36.035177 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 12 10:11:36.042879 systemd[1]: Stopped target swap.target - Swaps. Sep 12 10:11:36.049637 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 12 10:11:36.049770 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 12 10:11:36.060120 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 12 10:11:36.068327 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 12 10:11:36.077757 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 12 10:11:36.077900 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 12 10:11:36.087063 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 12 10:11:36.087194 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 12 10:11:36.100098 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 12 10:11:36.100232 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 12 10:11:36.108572 systemd[1]: ignition-files.service: Deactivated successfully. Sep 12 10:11:36.108687 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 12 10:11:36.116237 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Sep 12 10:11:36.116361 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Sep 12 10:11:36.134942 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 12 10:11:36.145313 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 12 10:11:36.164346 ignition[1171]: INFO : Ignition 2.20.0 Sep 12 10:11:36.164346 ignition[1171]: INFO : Stage: umount Sep 12 10:11:36.164346 ignition[1171]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 12 10:11:36.164346 ignition[1171]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 12 10:11:36.164346 ignition[1171]: INFO : umount: umount passed Sep 12 10:11:36.164346 ignition[1171]: INFO : Ignition finished successfully Sep 12 10:11:36.153189 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 12 10:11:36.153336 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 12 10:11:36.163090 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 12 10:11:36.163212 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 12 10:11:36.177557 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 12 10:11:36.178062 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 12 10:11:36.188911 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 12 10:11:36.189017 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 12 10:11:36.192220 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 12 10:11:36.192293 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 12 10:11:36.199831 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 12 10:11:36.199911 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 12 10:11:36.213662 systemd[1]: ignition-fetch.service: Deactivated successfully. Sep 12 10:11:36.213714 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Sep 12 10:11:36.215931 systemd[1]: Stopped target network.target - Network. Sep 12 10:11:36.228761 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 12 10:11:36.228849 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 12 10:11:36.235806 systemd[1]: Stopped target paths.target - Path Units. Sep 12 10:11:36.243908 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 12 10:11:36.247861 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 12 10:11:36.250851 systemd[1]: Stopped target slices.target - Slice Units. Sep 12 10:11:36.259202 systemd[1]: Stopped target sockets.target - Socket Units. Sep 12 10:11:36.267274 systemd[1]: iscsid.socket: Deactivated successfully. Sep 12 10:11:36.267326 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 12 10:11:36.274446 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 12 10:11:36.274492 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 12 10:11:36.282747 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 12 10:11:36.282820 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 12 10:11:36.291960 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 12 10:11:36.292015 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 12 10:11:36.300039 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 12 10:11:36.307269 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 12 10:11:36.315936 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 12 10:11:36.316542 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 12 10:11:36.316639 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 12 10:11:36.319267 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 12 10:11:36.319353 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 12 10:11:36.334782 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 12 10:11:36.334915 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 12 10:11:36.345784 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 12 10:11:36.345936 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 12 10:11:36.360375 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Sep 12 10:11:36.360759 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Sep 12 10:11:36.361521 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 12 10:11:36.361570 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 12 10:11:36.404439 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 12 10:11:36.472708 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 12 10:11:36.472828 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 12 10:11:36.477844 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 12 10:11:36.477895 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 12 10:11:36.486454 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 12 10:11:36.486505 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 12 10:11:36.494774 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 12 10:11:36.494838 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 12 10:11:36.504049 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 12 10:11:36.506486 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Sep 12 10:11:36.506556 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Sep 12 10:11:36.533593 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 12 10:11:36.533742 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 12 10:11:36.548932 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 12 10:11:36.549118 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 12 10:11:36.551983 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 12 10:11:36.552036 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 12 10:11:36.566328 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 12 10:11:36.566366 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 12 10:11:36.575228 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 12 10:11:36.575277 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 12 10:11:36.586718 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 12 10:11:36.586768 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 12 10:11:36.598202 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 12 10:11:36.598255 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 12 10:11:36.635925 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 12 10:11:36.636689 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 12 10:11:36.636747 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 12 10:11:36.649959 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Sep 12 10:11:36.650008 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 12 10:11:36.658293 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 12 10:11:36.658342 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 12 10:11:36.660342 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 12 10:11:36.660388 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 10:11:36.683691 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Sep 12 10:11:36.683756 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 12 10:11:36.684191 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 12 10:11:36.684287 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 12 10:11:36.688486 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 12 10:11:36.715947 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 12 10:11:36.738291 systemd[1]: Switching root. Sep 12 10:11:36.823477 systemd-journald[205]: Journal stopped Sep 12 10:11:43.657659 systemd-journald[205]: Received SIGTERM from PID 1 (systemd). Sep 12 10:11:43.657695 kernel: SELinux: policy capability network_peer_controls=1 Sep 12 10:11:43.657784 kernel: SELinux: policy capability open_perms=1 Sep 12 10:11:43.657847 kernel: SELinux: policy capability extended_socket_class=1 Sep 12 10:11:43.657870 kernel: SELinux: policy capability always_check_network=0 Sep 12 10:11:43.657880 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 12 10:11:43.657890 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 12 10:11:43.657910 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 12 10:11:43.657920 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 12 10:11:43.657929 kernel: audit: type=1403 audit(1757671897.661:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 12 10:11:43.657941 systemd[1]: Successfully loaded SELinux policy in 189.590ms. Sep 12 10:11:43.657953 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 15.658ms. Sep 12 10:11:43.657965 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.657976 systemd[1]: Detected virtualization microsoft. Sep 12 10:11:43.657990 systemd[1]: Detected architecture x86-64. Sep 12 10:11:43.658001 systemd[1]: Detected first boot. Sep 12 10:11:43.658012 systemd[1]: Hostname set to . Sep 12 10:11:43.658023 systemd[1]: Initializing machine ID from random generator. Sep 12 10:11:43.658034 zram_generator::config[1218]: No configuration found. Sep 12 10:11:43.658048 kernel: Guest personality initialized and is inactive Sep 12 10:11:43.658058 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Sep 12 10:11:43.658068 kernel: Initialized host personality Sep 12 10:11:43.658077 kernel: NET: Registered PF_VSOCK protocol family Sep 12 10:11:43.658087 systemd[1]: Populated /etc with preset unit settings. Sep 12 10:11:43.658099 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Sep 12 10:11:43.658109 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 12 10:11:43.658120 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 12 10:11:43.658134 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 12 10:11:43.658148 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 12 10:11:43.658160 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 12 10:11:43.658170 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 12 10:11:43.658181 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 12 10:11:43.658192 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 12 10:11:43.658203 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 12 10:11:43.658217 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 12 10:11:43.658228 systemd[1]: Created slice user.slice - User and Session Slice. Sep 12 10:11:43.658239 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 12 10:11:43.658250 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 12 10:11:43.658261 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 12 10:11:43.658272 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 12 10:11:43.658287 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 12 10:11:43.658299 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 12 10:11:43.658313 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Sep 12 10:11:43.658324 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 12 10:11:43.658335 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 12 10:11:43.658347 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 12 10:11:43.658358 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 12 10:11:43.658369 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 12 10:11:43.658380 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 12 10:11:43.658395 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 12 10:11:43.658409 systemd[1]: Reached target slices.target - Slice Units. Sep 12 10:11:43.658420 systemd[1]: Reached target swap.target - Swaps. Sep 12 10:11:43.658431 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 12 10:11:43.658485 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 12 10:11:43.658498 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Sep 12 10:11:43.658514 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 12 10:11:43.658526 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 12 10:11:43.658537 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 12 10:11:43.658549 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 12 10:11:43.658560 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 12 10:11:43.658572 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 12 10:11:43.658583 systemd[1]: Mounting media.mount - External Media Directory... Sep 12 10:11:43.658595 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 12 10:11:43.658609 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 12 10:11:43.658620 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 12 10:11:43.658632 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 12 10:11:43.658643 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.658655 systemd[1]: Reached target machines.target - Containers. Sep 12 10:11:43.658666 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 12 10:11:43.658678 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 12 10:11:43.658693 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 12 10:11:43.658708 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 12 10:11:43.658719 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 12 10:11:43.658730 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 12 10:11:43.658742 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 12 10:11:43.658753 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 12 10:11:43.658764 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 12 10:11:43.658776 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.658788 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 12 10:11:43.658816 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 12 10:11:43.658829 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 12 10:11:43.658840 systemd[1]: Stopped systemd-fsck-usr.service. Sep 12 10:11:43.658852 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.658864 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 12 10:11:43.658875 kernel: loop: module loaded Sep 12 10:11:43.658886 kernel: fuse: init (API version 7.39) Sep 12 10:11:43.658935 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 12 10:11:43.658979 systemd-journald[1325]: Collecting audit messages is disabled. Sep 12 10:11:43.659005 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 12 10:11:43.659017 systemd-journald[1325]: Journal started Sep 12 10:11:43.659044 systemd-journald[1325]: Runtime Journal (/run/log/journal/ec2f75d5c3784aa38eaa78396f60c4af) is 8M, max 2.2G, 2.1G free. Sep 12 10:11:42.844922 systemd[1]: Queued start job for default target multi-user.target. Sep 12 10:11:42.858115 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Sep 12 10:11:42.858580 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 12 10:11:43.672297 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 12 10:11:43.684321 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Sep 12 10:11:43.690818 kernel: ACPI: bus type drm_connector registered Sep 12 10:11:43.690857 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 12 10:11:43.706672 systemd[1]: verity-setup.service: Deactivated successfully. Sep 12 10:11:43.706718 systemd[1]: Stopped verity-setup.service. Sep 12 10:11:43.718814 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.726207 systemd[1]: Started systemd-journald.service - Journal Service. Sep 12 10:11:43.730628 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 12 10:11:43.734946 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 12 10:11:43.739429 systemd[1]: Mounted media.mount - External Media Directory. Sep 12 10:11:43.743507 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 12 10:11:43.747993 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 12 10:11:43.752438 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 12 10:11:43.756473 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 12 10:11:43.761312 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 12 10:11:43.766499 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 12 10:11:43.766695 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 12 10:11:43.771702 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 12 10:11:43.771906 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 12 10:11:43.776663 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 12 10:11:43.776866 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 12 10:11:43.781514 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 12 10:11:43.781711 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 12 10:11:43.786761 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 12 10:11:43.786968 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 12 10:11:43.791412 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 12 10:11:43.791605 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 12 10:11:43.796663 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 12 10:11:43.801390 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 12 10:11:43.806526 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 12 10:11:43.811835 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Sep 12 10:11:43.826549 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 12 10:11:43.840901 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 12 10:11:43.846149 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 12 10:11:43.850528 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 12 10:11:43.850561 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 12 10:11:43.855615 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Sep 12 10:11:43.861499 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 12 10:11:43.867205 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 12 10:11:43.871256 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 12 10:11:43.904934 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 12 10:11:43.910138 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 12 10:11:43.915145 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.916186 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 12 10:11:43.920489 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 12 10:11:43.921521 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 12 10:11:43.926464 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 12 10:11:43.939346 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 12 10:11:43.947660 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 12 10:11:43.955028 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 12 10:11:43.960399 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 12 10:11:43.966388 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 12 10:11:43.973166 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 12 10:11:43.979719 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 12 10:11:43.981530 systemd-journald[1325]: Time spent on flushing to /var/log/journal/ec2f75d5c3784aa38eaa78396f60c4af is 16.385ms for 943 entries. Sep 12 10:11:43.981530 systemd-journald[1325]: System Journal (/var/log/journal/ec2f75d5c3784aa38eaa78396f60c4af) is 8M, max 2.6G, 2.6G free. Sep 12 10:11:44.036165 systemd-journald[1325]: Received client request to flush runtime journal. Sep 12 10:11:44.002974 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Sep 12 10:11:44.009187 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Sep 12 10:11:44.025158 udevadm[1371]: 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.037774 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 12 10:11:44.077689 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 12 10:11:44.078439 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Sep 12 10:11:44.085812 kernel: loop0: detected capacity change from 0 to 138176 Sep 12 10:11:44.141690 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 12 10:11:44.160576 systemd-tmpfiles[1362]: ACLs are not supported, ignoring. Sep 12 10:11:44.160597 systemd-tmpfiles[1362]: ACLs are not supported, ignoring. Sep 12 10:11:44.176931 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 12 10:11:44.192962 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 12 10:11:44.743995 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 12 10:11:44.763002 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 12 10:11:44.780700 systemd-tmpfiles[1381]: ACLs are not supported, ignoring. Sep 12 10:11:44.780723 systemd-tmpfiles[1381]: ACLs are not supported, ignoring. Sep 12 10:11:44.785345 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 12 10:11:44.839822 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 12 10:11:44.942825 kernel: loop1: detected capacity change from 0 to 147912 Sep 12 10:11:45.543822 kernel: loop2: detected capacity change from 0 to 28272 Sep 12 10:11:45.766992 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 12 10:11:45.781982 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 12 10:11:45.810048 systemd-udevd[1388]: Using default interface naming scheme 'v255'. Sep 12 10:11:45.999818 kernel: loop3: detected capacity change from 0 to 138176 Sep 12 10:11:46.021817 kernel: loop4: detected capacity change from 0 to 147912 Sep 12 10:11:46.040816 kernel: loop5: detected capacity change from 0 to 28272 Sep 12 10:11:46.051542 (sd-merge)[1390]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Sep 12 10:11:46.052040 (sd-merge)[1390]: Merged extensions into '/usr'. Sep 12 10:11:46.055331 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 12 10:11:46.070936 systemd[1]: Starting ensure-sysext.service... Sep 12 10:11:46.074860 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 12 10:11:46.133726 systemd[1]: Reload requested from client PID 1392 ('systemctl') (unit ensure-sysext.service)... Sep 12 10:11:46.133745 systemd[1]: Reloading... Sep 12 10:11:46.180838 systemd-tmpfiles[1393]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 12 10:11:46.181148 systemd-tmpfiles[1393]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 12 10:11:46.182078 systemd-tmpfiles[1393]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 12 10:11:46.182369 systemd-tmpfiles[1393]: ACLs are not supported, ignoring. Sep 12 10:11:46.182441 systemd-tmpfiles[1393]: ACLs are not supported, ignoring. Sep 12 10:11:46.184819 zram_generator::config[1425]: No configuration found. Sep 12 10:11:46.229728 systemd-tmpfiles[1393]: Detected autofs mount point /boot during canonicalization of boot. Sep 12 10:11:46.229745 systemd-tmpfiles[1393]: Skipping /boot Sep 12 10:11:46.246085 systemd-tmpfiles[1393]: Detected autofs mount point /boot during canonicalization of boot. Sep 12 10:11:46.246101 systemd-tmpfiles[1393]: Skipping /boot Sep 12 10:11:46.318357 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.416600 systemd[1]: Reloading finished in 282 ms. Sep 12 10:11:46.437716 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 12 10:11:46.458668 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 12 10:11:46.476752 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Sep 12 10:11:46.483945 systemd[1]: Condition check resulted in dev-ptp_hyperv.device - /dev/ptp_hyperv being skipped. Sep 12 10:11:46.484357 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 12 10:11:46.491229 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 12 10:11:46.537819 kernel: hv_vmbus: registering driver hv_balloon Sep 12 10:11:46.537894 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Sep 12 10:11:46.545812 kernel: mousedev: PS/2 mouse device common for all mice Sep 12 10:11:46.548054 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 12 10:11:46.562347 kernel: hv_vmbus: registering driver hyperv_fb Sep 12 10:11:46.562429 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Sep 12 10:11:46.562466 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Sep 12 10:11:46.562486 kernel: Console: switching to colour dummy device 80x25 Sep 12 10:11:46.562688 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 12 10:11:46.564390 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 12 10:11:46.566405 kernel: Console: switching to colour frame buffer device 128x48 Sep 12 10:11:46.573367 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 12 10:11:46.578553 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 12 10:11:46.584172 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 12 10:11:46.588171 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 12 10:11:46.588309 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.589636 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 12 10:11:46.595925 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 12 10:11:46.601931 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 12 10:11:46.606275 systemd[1]: Reached target time-set.target - System Time Set. Sep 12 10:11:46.611376 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 12 10:11:46.616921 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.620134 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 12 10:11:46.620352 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 12 10:11:46.625375 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 12 10:11:46.625558 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 12 10:11:46.630330 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 12 10:11:46.630515 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 12 10:11:46.637038 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 12 10:11:46.637298 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 12 10:11:46.644814 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 35 scanned by (udev-worker) (1492) Sep 12 10:11:46.648839 systemd[1]: Finished ensure-sysext.service. Sep 12 10:11:46.683358 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 12 10:11:46.689293 kernel: EDAC MC: Ver: 3.0.0 Sep 12 10:11:46.739556 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Sep 12 10:11:46.760166 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.764672 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.764736 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 12 10:11:46.766358 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 12 10:11:46.771289 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 12 10:11:46.776023 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Sep 12 10:11:46.783157 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Sep 12 10:11:46.833600 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 12 10:11:46.847145 augenrules[1642]: No rules Sep 12 10:11:46.848392 systemd[1]: audit-rules.service: Deactivated successfully. Sep 12 10:11:46.848642 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 12 10:11:46.854338 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.887197 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 12 10:11:46.915988 lvm[1632]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Sep 12 10:11:46.966581 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Sep 12 10:11:46.968350 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 12 10:11:46.985001 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Sep 12 10:11:46.993815 lvm[1654]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Sep 12 10:11:47.028356 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Sep 12 10:11:47.060843 systemd-networkd[1543]: lo: Link UP Sep 12 10:11:47.060853 systemd-networkd[1543]: lo: Gained carrier Sep 12 10:11:47.062365 systemd-resolved[1544]: Positive Trust Anchors: Sep 12 10:11:47.062380 systemd-resolved[1544]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 12 10:11:47.062411 systemd-resolved[1544]: 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.062517 systemd-networkd[1543]: Enumeration completed Sep 12 10:11:47.062625 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 12 10:11:47.065368 systemd-networkd[1543]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 12 10:11:47.065380 systemd-networkd[1543]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 12 10:11:47.066084 systemd-networkd[1543]: eth0: Link UP Sep 12 10:11:47.066094 systemd-networkd[1543]: eth0: Gained carrier Sep 12 10:11:47.066108 systemd-networkd[1543]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 12 10:11:47.079956 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Sep 12 10:11:47.085731 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 12 10:11:47.112858 systemd-networkd[1543]: eth0: DHCPv4 address 10.200.8.18/24, gateway 10.200.8.1 acquired from 168.63.129.16 Sep 12 10:11:47.127810 systemd-resolved[1544]: Using system hostname 'ci-4230.2.2-n-6bb2c6a89f'. Sep 12 10:11:47.130014 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 12 10:11:47.131777 systemd[1]: Reached target network.target - Network. Sep 12 10:11:47.132393 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 12 10:11:47.139973 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Sep 12 10:11:47.866397 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 10:11:48.205929 systemd-networkd[1543]: eth0: Gained IPv6LL Sep 12 10:11:48.208571 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 12 10:11:48.213753 systemd[1]: Reached target network-online.target - Network is Online. Sep 12 10:11:49.035276 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 12 10:11:49.040653 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:52.625580 ldconfig[1355]: /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.639446 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 12 10:11:52.655033 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 12 10:11:52.676378 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 12 10:11:52.680898 systemd[1]: Reached target sysinit.target - System Initialization. Sep 12 10:11:52.685371 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 12 10:11:52.690184 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.695235 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 12 10:11:52.699447 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 12 10:11:52.704451 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 12 10:11:52.709346 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.709404 systemd[1]: Reached target paths.target - Path Units. Sep 12 10:11:52.713061 systemd[1]: Reached target timers.target - Timer Units. Sep 12 10:11:52.740999 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 12 10:11:52.746641 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 12 10:11:52.753807 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Sep 12 10:11:52.758988 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Sep 12 10:11:52.763982 systemd[1]: Reached target ssh-access.target - SSH Access Available. Sep 12 10:11:52.769943 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 12 10:11:52.774388 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Sep 12 10:11:52.779613 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 12 10:11:52.784045 systemd[1]: Reached target sockets.target - Socket Units. Sep 12 10:11:52.787904 systemd[1]: Reached target basic.target - Basic System. Sep 12 10:11:52.791479 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 12 10:11:52.791522 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 12 10:11:52.813880 systemd[1]: Starting chronyd.service - NTP client/server... Sep 12 10:11:52.818258 systemd[1]: Starting containerd.service - containerd container runtime... Sep 12 10:11:52.823597 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Sep 12 10:11:52.828765 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 12 10:11:52.833630 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 12 10:11:52.838548 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 12 10:11:52.842547 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.842600 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.843662 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Sep 12 10:11:52.847849 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.848885 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 12 10:11:52.851957 jq[1677]: false Sep 12 10:11:52.853708 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 12 10:11:52.858684 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 12 10:11:52.864478 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 12 10:11:52.865023 KVP[1679]: KVP starting; pid is:1679 Sep 12 10:11:52.865648 (chronyd)[1670]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Sep 12 10:11:52.867826 KVP[1679]: KVP LIC Version: 3.1 Sep 12 10:11:52.869814 kernel: hv_utils: KVP IC version 4.0 Sep 12 10:11:52.872784 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 12 10:11:52.875453 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.877600 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 12 10:11:52.878070 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 12 10:11:52.878675 systemd[1]: Starting update-engine.service - Update Engine... Sep 12 10:11:52.883093 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 12 10:11:52.890025 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 12 10:11:52.890328 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 12 10:11:52.890660 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 12 10:11:52.890873 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 12 10:11:52.891340 jq[1691]: true Sep 12 10:11:52.904094 jq[1693]: true Sep 12 10:11:52.953920 chronyd[1688]: Timezone right/UTC failed leap second check, ignoring Sep 12 10:11:52.954077 chronyd[1688]: Loaded seccomp filter (level 2) Sep 12 10:11:52.955414 systemd[1]: Started chronyd.service - NTP client/server. Sep 12 10:11:52.972186 (ntainerd)[1721]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 12 10:11:52.972353 systemd[1]: motdgen.service: Deactivated successfully. Sep 12 10:11:52.972594 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 12 10:11:52.973540 systemd-logind[1685]: New seat seat0. Sep 12 10:11:52.974937 systemd-logind[1685]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Sep 12 10:11:52.977147 systemd[1]: Started systemd-logind.service - User Login Management. Sep 12 10:11:52.981392 extend-filesystems[1678]: Found loop3 Sep 12 10:11:52.981392 extend-filesystems[1678]: Found loop4 Sep 12 10:11:52.981392 extend-filesystems[1678]: Found loop5 Sep 12 10:11:52.981392 extend-filesystems[1678]: Found sda Sep 12 10:11:52.981392 extend-filesystems[1678]: Found sda1 Sep 12 10:11:52.981392 extend-filesystems[1678]: Found sda2 Sep 12 10:11:52.981392 extend-filesystems[1678]: Found sda3 Sep 12 10:11:52.981392 extend-filesystems[1678]: Found usr Sep 12 10:11:52.981392 extend-filesystems[1678]: Found sda4 Sep 12 10:11:52.981392 extend-filesystems[1678]: Found sda6 Sep 12 10:11:52.981392 extend-filesystems[1678]: Found sda7 Sep 12 10:11:52.981392 extend-filesystems[1678]: Found sda9 Sep 12 10:11:52.981392 extend-filesystems[1678]: Checking size of /dev/sda9 Sep 12 10:11:53.028221 extend-filesystems[1678]: Old size kept for /dev/sda9 Sep 12 10:11:53.028221 extend-filesystems[1678]: Found sdb Sep 12 10:11:53.028221 extend-filesystems[1678]: Found sdb1 Sep 12 10:11:53.028221 extend-filesystems[1678]: Found sr0 Sep 12 10:11:53.010196 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 12 10:11:53.037983 update_engine[1690]: I20250912 10:11:53.002857 1690 main.cc:92] Flatcar Update Engine starting Sep 12 10:11:53.038244 bash[1715]: Updated "/home/core/.ssh/authorized_keys" Sep 12 10:11:53.010437 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 12 10:11:53.037049 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 12 10:11:53.044081 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Sep 12 10:11:53.055827 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 35 scanned by (udev-worker) (1732) Sep 12 10:11:53.147452 sshd_keygen[1724]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 12 10:11:53.169443 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 12 10:11:53.173579 setup-nvidia[1681]: Downloading Flatcar Container Linux Developer Container for version: 4230.2.2+nightly-20250911-2100 Sep 12 10:11:53.187938 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 12 10:11:53.192583 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Sep 12 10:11:53.197016 systemd[1]: issuegen.service: Deactivated successfully. Sep 12 10:11:53.197236 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 12 10:11:53.201187 dbus-daemon[1673]: [system] SELinux support is enabled Sep 12 10:11:53.201627 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 12 10:11:53.203626 update_engine[1690]: I20250912 10:11:53.203573 1690 update_check_scheduler.cc:74] Next update check in 4m7s Sep 12 10:11:53.220371 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.220411 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 12 10:11:53.220942 dbus-daemon[1673]: [system] Successfully activated service 'org.freedesktop.systemd1' Sep 12 10:11:53.227026 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 12 10:11:53.231199 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.231231 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 12 10:11:53.236397 systemd[1]: Started update-engine.service - Update Engine. Sep 12 10:11:53.241040 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Sep 12 10:11:53.247637 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 12 10:11:53.251056 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 12 10:11:53.258231 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 12 10:11:53.263040 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Sep 12 10:11:53.267464 systemd[1]: Reached target getty.target - Login Prompts. Sep 12 10:11:53.313691 coreos-metadata[1672]: Sep 12 10:11:53.313 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Sep 12 10:11:53.315934 coreos-metadata[1672]: Sep 12 10:11:53.315 INFO Fetch successful Sep 12 10:11:53.316095 coreos-metadata[1672]: Sep 12 10:11:53.316 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Sep 12 10:11:53.320301 coreos-metadata[1672]: Sep 12 10:11:53.320 INFO Fetch successful Sep 12 10:11:53.320648 coreos-metadata[1672]: Sep 12 10:11:53.320 INFO Fetching http://168.63.129.16/machine/c38c911c-c63e-40c7-8d30-e3e24e54592a/81a78aaa%2D543a%2D48e3%2D8038%2D4dd2a2d624dc.%5Fci%2D4230.2.2%2Dn%2D6bb2c6a89f?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Sep 12 10:11:53.349023 coreos-metadata[1672]: Sep 12 10:11:53.348 INFO Fetch successful Sep 12 10:11:53.349582 coreos-metadata[1672]: Sep 12 10:11:53.349 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.356619 coreos-metadata[1672]: Sep 12 10:11:53.356 INFO Fetch successful Sep 12 10:11:53.357954 locksmithd[1821]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 12 10:11:53.379406 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Sep 12 10:11:53.384175 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 12 10:11:53.802332 setup-nvidia[1839]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 12 10:11:53.802677 setup-nvidia[1839]: Dload Upload Total Spent Left Speed Sep 12 10:11:54.099253 containerd[1721]: time="2025-09-12T10:11:54.099121300Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Sep 12 10:11:54.117949 containerd[1721]: time="2025-09-12T10:11:54.117914600Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Sep 12 10:11:54.119494 containerd[1721]: time="2025-09-12T10:11:54.119460600Z" 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.119494 containerd[1721]: time="2025-09-12T10:11:54.119490700Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Sep 12 10:11:54.119566 containerd[1721]: time="2025-09-12T10:11:54.119506800Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Sep 12 10:11:54.119669 containerd[1721]: time="2025-09-12T10:11:54.119647500Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Sep 12 10:11:54.119703 containerd[1721]: time="2025-09-12T10:11:54.119670000Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Sep 12 10:11:54.119753 containerd[1721]: time="2025-09-12T10:11:54.119732100Z" 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.119753 containerd[1721]: time="2025-09-12T10:11:54.119750200Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Sep 12 10:11:54.119962 containerd[1721]: time="2025-09-12T10:11:54.119938800Z" 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.119962 containerd[1721]: time="2025-09-12T10:11:54.119959600Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Sep 12 10:11:54.120018 containerd[1721]: time="2025-09-12T10:11:54.119973900Z" 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.120018 containerd[1721]: time="2025-09-12T10:11:54.119983200Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Sep 12 10:11:54.120087 containerd[1721]: time="2025-09-12T10:11:54.120067000Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Sep 12 10:11:54.120302 containerd[1721]: time="2025-09-12T10:11:54.120281400Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Sep 12 10:11:54.120430 containerd[1721]: time="2025-09-12T10:11:54.120408700Z" 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.120458 containerd[1721]: time="2025-09-12T10:11:54.120428200Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Sep 12 10:11:54.120530 containerd[1721]: time="2025-09-12T10:11:54.120509400Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Sep 12 10:11:54.120596 containerd[1721]: time="2025-09-12T10:11:54.120576500Z" level=info msg="metadata content store policy set" policy=shared Sep 12 10:11:54.144662 containerd[1721]: time="2025-09-12T10:11:54.144597700Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Sep 12 10:11:54.145002 containerd[1721]: time="2025-09-12T10:11:54.144730500Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Sep 12 10:11:54.145002 containerd[1721]: time="2025-09-12T10:11:54.144871000Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Sep 12 10:11:54.145002 containerd[1721]: time="2025-09-12T10:11:54.144905100Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Sep 12 10:11:54.145002 containerd[1721]: time="2025-09-12T10:11:54.144927800Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Sep 12 10:11:54.145296 containerd[1721]: time="2025-09-12T10:11:54.145265300Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Sep 12 10:11:54.145525 containerd[1721]: time="2025-09-12T10:11:54.145502500Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Sep 12 10:11:54.145652 containerd[1721]: time="2025-09-12T10:11:54.145631500Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Sep 12 10:11:54.145683 containerd[1721]: time="2025-09-12T10:11:54.145655200Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Sep 12 10:11:54.145683 containerd[1721]: time="2025-09-12T10:11:54.145670300Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Sep 12 10:11:54.145729 containerd[1721]: time="2025-09-12T10:11:54.145684100Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Sep 12 10:11:54.145729 containerd[1721]: time="2025-09-12T10:11:54.145697700Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Sep 12 10:11:54.145729 containerd[1721]: time="2025-09-12T10:11:54.145709700Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Sep 12 10:11:54.145729 containerd[1721]: time="2025-09-12T10:11:54.145721700Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Sep 12 10:11:54.145836 containerd[1721]: time="2025-09-12T10:11:54.145734400Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Sep 12 10:11:54.145836 containerd[1721]: time="2025-09-12T10:11:54.145747200Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Sep 12 10:11:54.145836 containerd[1721]: time="2025-09-12T10:11:54.145759500Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Sep 12 10:11:54.145836 containerd[1721]: time="2025-09-12T10:11:54.145770600Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Sep 12 10:11:54.145836 containerd[1721]: time="2025-09-12T10:11:54.145789000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Sep 12 10:11:54.145836 containerd[1721]: time="2025-09-12T10:11:54.145817900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Sep 12 10:11:54.145836 containerd[1721]: time="2025-09-12T10:11:54.145830500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Sep 12 10:11:54.146063 containerd[1721]: time="2025-09-12T10:11:54.145843800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Sep 12 10:11:54.146063 containerd[1721]: time="2025-09-12T10:11:54.145855800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Sep 12 10:11:54.146063 containerd[1721]: time="2025-09-12T10:11:54.145867900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Sep 12 10:11:54.146063 containerd[1721]: time="2025-09-12T10:11:54.145878900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Sep 12 10:11:54.146063 containerd[1721]: time="2025-09-12T10:11:54.145890800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Sep 12 10:11:54.146063 containerd[1721]: time="2025-09-12T10:11:54.145970800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Sep 12 10:11:54.146063 containerd[1721]: time="2025-09-12T10:11:54.145997600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Sep 12 10:11:54.146063 containerd[1721]: time="2025-09-12T10:11:54.146009300Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Sep 12 10:11:54.146063 containerd[1721]: time="2025-09-12T10:11:54.146019900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Sep 12 10:11:54.146063 containerd[1721]: time="2025-09-12T10:11:54.146030600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Sep 12 10:11:54.146063 containerd[1721]: time="2025-09-12T10:11:54.146043300Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Sep 12 10:11:54.146063 containerd[1721]: time="2025-09-12T10:11:54.146063400Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Sep 12 10:11:54.146306 containerd[1721]: time="2025-09-12T10:11:54.146077500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Sep 12 10:11:54.146306 containerd[1721]: time="2025-09-12T10:11:54.146087300Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Sep 12 10:11:54.146306 containerd[1721]: time="2025-09-12T10:11:54.146133200Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Sep 12 10:11:54.146306 containerd[1721]: time="2025-09-12T10:11:54.146151600Z" 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.146306 containerd[1721]: time="2025-09-12T10:11:54.146161700Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Sep 12 10:11:54.146306 containerd[1721]: time="2025-09-12T10:11:54.146172200Z" 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.146306 containerd[1721]: time="2025-09-12T10:11:54.146180700Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Sep 12 10:11:54.146306 containerd[1721]: time="2025-09-12T10:11:54.146192100Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Sep 12 10:11:54.146306 containerd[1721]: time="2025-09-12T10:11:54.146201400Z" level=info msg="NRI interface is disabled by configuration." Sep 12 10:11:54.146306 containerd[1721]: time="2025-09-12T10:11:54.146210600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Sep 12 10:11:54.146514 containerd[1721]: time="2025-09-12T10:11:54.146448600Z" 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.146514 containerd[1721]: time="2025-09-12T10:11:54.146497400Z" level=info msg="Connect containerd service" Sep 12 10:11:54.146677 containerd[1721]: time="2025-09-12T10:11:54.146535600Z" level=info msg="using legacy CRI server" Sep 12 10:11:54.146677 containerd[1721]: time="2025-09-12T10:11:54.146547200Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 12 10:11:54.146677 containerd[1721]: time="2025-09-12T10:11:54.146636900Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Sep 12 10:11:54.147274 containerd[1721]: time="2025-09-12T10:11:54.147246900Z" 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.147408 containerd[1721]: time="2025-09-12T10:11:54.147371000Z" level=info msg="Start subscribing containerd event" Sep 12 10:11:54.147436 containerd[1721]: time="2025-09-12T10:11:54.147423700Z" level=info msg="Start recovering state" Sep 12 10:11:54.147501 containerd[1721]: time="2025-09-12T10:11:54.147484400Z" level=info msg="Start event monitor" Sep 12 10:11:54.147528 containerd[1721]: time="2025-09-12T10:11:54.147512600Z" level=info msg="Start snapshots syncer" Sep 12 10:11:54.147528 containerd[1721]: time="2025-09-12T10:11:54.147523500Z" level=info msg="Start cni network conf syncer for default" Sep 12 10:11:54.147582 containerd[1721]: time="2025-09-12T10:11:54.147528700Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 12 10:11:54.147582 containerd[1721]: time="2025-09-12T10:11:54.147530900Z" level=info msg="Start streaming server" Sep 12 10:11:54.147624 containerd[1721]: time="2025-09-12T10:11:54.147597300Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 12 10:11:54.147684 containerd[1721]: time="2025-09-12T10:11:54.147666700Z" level=info msg="containerd successfully booted in 0.050014s" Sep 12 10:11:54.147760 systemd[1]: Started containerd.service - containerd container runtime. Sep 12 10:11:55.572581 waagent[1819]: 2025-09-12T10:11:55.572482Z INFO Daemon Daemon Azure Linux Agent Version: 2.9.1.1 Sep 12 10:11:55.576626 waagent[1819]: 2025-09-12T10:11:55.576561Z INFO Daemon Daemon OS: flatcar 4230.2.2 Sep 12 10:11:55.580033 waagent[1819]: 2025-09-12T10:11:55.579977Z INFO Daemon Daemon Python: 3.11.11 Sep 12 10:11:55.583282 waagent[1819]: 2025-09-12T10:11:55.583231Z INFO Daemon Daemon Run daemon Sep 12 10:11:55.586247 waagent[1819]: 2025-09-12T10:11:55.586199Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4230.2.2' Sep 12 10:11:55.592658 waagent[1819]: 2025-09-12T10:11:55.592602Z INFO Daemon Daemon Using waagent for provisioning Sep 12 10:11:55.596509 waagent[1819]: 2025-09-12T10:11:55.596456Z INFO Daemon Daemon Activate resource disk Sep 12 10:11:55.599906 waagent[1819]: 2025-09-12T10:11:55.599854Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Sep 12 10:11:55.611284 waagent[1819]: 2025-09-12T10:11:55.611226Z INFO Daemon Daemon Found device: sdb Sep 12 10:11:55.620237 waagent[1819]: 2025-09-12T10:11:55.620172Z INFO Daemon Daemon Examining partition table Sep 12 10:11:55.685505 waagent[1819]: 2025-09-12T10:11:55.685437Z INFO Daemon Daemon GPT not detected, determining filesystem Sep 12 10:11:55.749933 waagent[1819]: 2025-09-12T10:11:55.749855Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Sep 12 10:11:55.754673 waagent[1819]: 2025-09-12T10:11:55.754620Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Sep 12 10:11:56.237823 kernel: sdb: sdb1 Sep 12 10:11:56.241814 kernel: sdb: sdb1 Sep 12 10:11:56.278592 waagent[1819]: 2025-09-12T10:11:56.278499Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Sep 12 10:11:56.291661 waagent[1819]: 2025-09-12T10:11:56.291608Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Sep 12 10:12:01.364255 login[1823]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Sep 12 10:12:01.619606 login[1824]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Sep 12 10:12:01.630891 systemd-logind[1685]: New session 1 of user core. Sep 12 10:12:01.632007 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 12 10:12:01.643041 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 12 10:12:01.741912 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 12 10:12:01.756159 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 12 10:12:01.874347 (systemd)[1910]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 12 10:12:01.877188 systemd-logind[1685]: New session c1 of user core. Sep 12 10:12:02.060095 waagent[1819]: 2025-09-12T10:12:02.060003Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Sep 12 10:12:02.080822 kernel: EXT4-fs (sdb1): mounted filesystem 04a5fed6-ded3-4da3-bb15-e2ede8f07a2b r/w with ordered data mode. Quota mode: none. Sep 12 10:12:02.081154 waagent[1819]: 2025-09-12T10:12:02.081081Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Sep 12 10:12:02.087059 waagent[1819]: 2025-09-12T10:12:02.087008Z INFO Daemon Daemon Clean protocol and wireserver endpoint Sep 12 10:12:02.091423 waagent[1819]: 2025-09-12T10:12:02.091377Z INFO Daemon Daemon Running default provisioning handler Sep 12 10:12:02.101651 waagent[1819]: 2025-09-12T10:12:02.101596Z 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:02.112028 waagent[1819]: 2025-09-12T10:12:02.111974Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Sep 12 10:12:02.113697 waagent[1819]: 2025-09-12T10:12:02.113641Z INFO Daemon Daemon cloud-init is enabled: False Sep 12 10:12:02.115118 waagent[1819]: 2025-09-12T10:12:02.115075Z INFO Daemon Daemon Copying ovf-env.xml Sep 12 10:12:02.251092 waagent[1819]: 2025-09-12T10:12:02.250941Z INFO Daemon Daemon Successfully mounted dvd Sep 12 10:12:02.276347 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Sep 12 10:12:02.276524 waagent[1819]: 2025-09-12T10:12:02.276395Z INFO Daemon Daemon Detect protocol endpoint Sep 12 10:12:02.278101 waagent[1819]: 2025-09-12T10:12:02.278042Z INFO Daemon Daemon Clean protocol and wireserver endpoint Sep 12 10:12:02.279619 waagent[1819]: 2025-09-12T10:12:02.279548Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Sep 12 10:12:02.281055 waagent[1819]: 2025-09-12T10:12:02.281007Z INFO Daemon Daemon Test for route to 168.63.129.16 Sep 12 10:12:02.281932 waagent[1819]: 2025-09-12T10:12:02.281886Z INFO Daemon Daemon Route to 168.63.129.16 exists Sep 12 10:12:02.282524 waagent[1819]: 2025-09-12T10:12:02.282483Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Sep 12 10:12:02.321269 waagent[1819]: 2025-09-12T10:12:02.321208Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Sep 12 10:12:02.323059 waagent[1819]: 2025-09-12T10:12:02.323023Z INFO Daemon Daemon Wire protocol version:2012-11-30 Sep 12 10:12:02.323580 waagent[1819]: 2025-09-12T10:12:02.323540Z INFO Daemon Daemon Server preferred version:2015-04-05 Sep 12 10:12:02.346812 systemd[1910]: Queued start job for default target default.target. Sep 12 10:12:02.359775 systemd[1910]: Created slice app.slice - User Application Slice. Sep 12 10:12:02.359821 systemd[1910]: Reached target paths.target - Paths. Sep 12 10:12:02.359869 systemd[1910]: Reached target timers.target - Timers. Sep 12 10:12:02.361112 systemd[1910]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 12 10:12:02.364628 login[1823]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Sep 12 10:12:02.369042 systemd-logind[1685]: New session 2 of user core. Sep 12 10:12:02.372226 systemd[1910]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 12 10:12:02.372352 systemd[1910]: Reached target sockets.target - Sockets. Sep 12 10:12:02.372414 systemd[1910]: Reached target basic.target - Basic System. Sep 12 10:12:02.372462 systemd[1910]: Reached target default.target - Main User Target. Sep 12 10:12:02.372496 systemd[1910]: Startup finished in 487ms. Sep 12 10:12:02.372562 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 12 10:12:02.374086 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 12 10:12:02.374859 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 12 10:12:02.549159 waagent[1819]: 2025-09-12T10:12:02.549017Z INFO Daemon Daemon Initializing goal state during protocol detection Sep 12 10:12:02.550699 waagent[1819]: 2025-09-12T10:12:02.550648Z INFO Daemon Daemon Forcing an update of the goal state. Sep 12 10:12:02.555341 waagent[1819]: 2025-09-12T10:12:02.555289Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Sep 12 10:12:02.605330 waagent[1819]: 2025-09-12T10:12:02.605274Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.175 Sep 12 10:12:02.607329 waagent[1819]: 2025-09-12T10:12:02.607279Z INFO Daemon Sep 12 10:12:02.607675 waagent[1819]: 2025-09-12T10:12:02.607629Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: ce7cad28-79a8-4c92-be6f-11e76dc7175f eTag: 5275934622754417290 source: Fabric] Sep 12 10:12:02.609375 waagent[1819]: 2025-09-12T10:12:02.609324Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Sep 12 10:12:02.611138 waagent[1819]: 2025-09-12T10:12:02.611084Z INFO Daemon Sep 12 10:12:02.611296 waagent[1819]: 2025-09-12T10:12:02.611256Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Sep 12 10:12:02.616324 waagent[1819]: 2025-09-12T10:12:02.616276Z INFO Daemon Daemon Downloading artifacts profile blob Sep 12 10:12:02.733167 waagent[1819]: 2025-09-12T10:12:02.733097Z INFO Daemon Downloaded certificate {'thumbprint': 'F71992916925B1AC31DEBEF2DAE4C8FBB955D8A0', 'hasPrivateKey': True} Sep 12 10:12:02.735249 waagent[1819]: 2025-09-12T10:12:02.735199Z INFO Daemon Fetch goal state completed Sep 12 10:12:02.766379 waagent[1819]: 2025-09-12T10:12:02.766318Z INFO Daemon Daemon Starting provisioning Sep 12 10:12:02.768071 waagent[1819]: 2025-09-12T10:12:02.767978Z INFO Daemon Daemon Handle ovf-env.xml. Sep 12 10:12:02.768674 waagent[1819]: 2025-09-12T10:12:02.768600Z INFO Daemon Daemon Set hostname [ci-4230.2.2-n-6bb2c6a89f] Sep 12 10:12:02.795949 waagent[1819]: 2025-09-12T10:12:02.795887Z INFO Daemon Daemon Publish hostname [ci-4230.2.2-n-6bb2c6a89f] Sep 12 10:12:02.797680 waagent[1819]: 2025-09-12T10:12:02.797621Z INFO Daemon Daemon Examine /proc/net/route for primary interface Sep 12 10:12:02.799070 waagent[1819]: 2025-09-12T10:12:02.799024Z INFO Daemon Daemon Primary interface is [eth0] Sep 12 10:12:02.820758 systemd-networkd[1543]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 12 10:12:02.820769 systemd-networkd[1543]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 12 10:12:02.820860 systemd-networkd[1543]: eth0: DHCP lease lost Sep 12 10:12:02.821213 waagent[1819]: 2025-09-12T10:12:02.821147Z INFO Daemon Daemon Create user account if not exists Sep 12 10:12:02.822842 waagent[1819]: 2025-09-12T10:12:02.822778Z INFO Daemon Daemon User core already exists, skip useradd Sep 12 10:12:02.824239 waagent[1819]: 2025-09-12T10:12:02.824192Z INFO Daemon Daemon Configure sudoer Sep 12 10:12:02.825984 waagent[1819]: 2025-09-12T10:12:02.825936Z INFO Daemon Daemon Configure sshd Sep 12 10:12:02.826632 waagent[1819]: 2025-09-12T10:12:02.826586Z 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.827920 waagent[1819]: 2025-09-12T10:12:02.827871Z INFO Daemon Daemon Deploy ssh public key. Sep 12 10:12:02.913834 systemd-networkd[1543]: eth0: DHCPv4 address 10.200.8.18/24, gateway 10.200.8.1 acquired from 168.63.129.16 Sep 12 10:12:03.982300 waagent[1819]: 2025-09-12T10:12:03.982228Z INFO Daemon Daemon Provisioning complete Sep 12 10:12:03.993061 waagent[1819]: 2025-09-12T10:12:03.993010Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Sep 12 10:12:03.994744 waagent[1819]: 2025-09-12T10:12:03.994689Z 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.995455 waagent[1819]: 2025-09-12T10:12:03.995413Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.9.1.1 is the most current agent Sep 12 10:12:04.189307 waagent[1969]: 2025-09-12T10:12:04.189212Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.9.1.1) Sep 12 10:12:04.189659 waagent[1969]: 2025-09-12T10:12:04.189371Z INFO ExtHandler ExtHandler OS: flatcar 4230.2.2 Sep 12 10:12:04.189659 waagent[1969]: 2025-09-12T10:12:04.189452Z INFO ExtHandler ExtHandler Python: 3.11.11 Sep 12 10:12:04.237845 waagent[1969]: 2025-09-12T10:12:04.237686Z 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:04.238015 waagent[1969]: 2025-09-12T10:12:04.237969Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 12 10:12:04.238102 waagent[1969]: 2025-09-12T10:12:04.238064Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 12 10:12:04.246031 waagent[1969]: 2025-09-12T10:12:04.245969Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Sep 12 10:12:04.253467 waagent[1969]: 2025-09-12T10:12:04.253415Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.175 Sep 12 10:12:04.253939 waagent[1969]: 2025-09-12T10:12:04.253892Z INFO ExtHandler Sep 12 10:12:04.254030 waagent[1969]: 2025-09-12T10:12:04.253991Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 6a966884-9cfe-4be3-912e-32c5072525f3 eTag: 5275934622754417290 source: Fabric] Sep 12 10:12:04.254358 waagent[1969]: 2025-09-12T10:12:04.254314Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Sep 12 10:12:04.255023 waagent[1969]: 2025-09-12T10:12:04.254975Z INFO ExtHandler Sep 12 10:12:04.255107 waagent[1969]: 2025-09-12T10:12:04.255070Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Sep 12 10:12:04.258572 waagent[1969]: 2025-09-12T10:12:04.258528Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Sep 12 10:12:04.319340 waagent[1969]: 2025-09-12T10:12:04.319267Z INFO ExtHandler Downloaded certificate {'thumbprint': 'F71992916925B1AC31DEBEF2DAE4C8FBB955D8A0', 'hasPrivateKey': True} Sep 12 10:12:04.319866 waagent[1969]: 2025-09-12T10:12:04.319818Z INFO ExtHandler Fetch goal state completed Sep 12 10:12:04.330586 waagent[1969]: 2025-09-12T10:12:04.330534Z INFO ExtHandler ExtHandler WALinuxAgent-2.9.1.1 running as process 1969 Sep 12 10:12:04.330733 waagent[1969]: 2025-09-12T10:12:04.330690Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Sep 12 10:12:04.332560 waagent[1969]: 2025-09-12T10:12:04.332510Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '4230.2.2', '', 'Flatcar Container Linux by Kinvolk'] Sep 12 10:12:04.332972 waagent[1969]: 2025-09-12T10:12:04.332926Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Sep 12 10:12:04.386777 waagent[1969]: 2025-09-12T10:12:04.386719Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Sep 12 10:12:04.387596 waagent[1969]: 2025-09-12T10:12:04.386987Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Sep 12 10:12:04.393593 waagent[1969]: 2025-09-12T10:12:04.393550Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Sep 12 10:12:04.401303 systemd[1]: Reload requested from client PID 1982 ('systemctl') (unit waagent.service)... Sep 12 10:12:04.401319 systemd[1]: Reloading... Sep 12 10:12:04.473829 zram_generator::config[2025]: No configuration found. Sep 12 10:12:04.626714 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.775278 systemd[1]: Reloading finished in 373 ms. Sep 12 10:12:04.795681 waagent[1969]: 2025-09-12T10:12:04.795591Z INFO ExtHandler ExtHandler Executing systemctl daemon-reload for setting up waagent-network-setup.service Sep 12 10:12:04.801578 systemd[1]: Reload requested from client PID 2079 ('systemctl') (unit waagent.service)... Sep 12 10:12:04.801593 systemd[1]: Reloading... Sep 12 10:12:04.869818 zram_generator::config[2122]: No configuration found. Sep 12 10:12:05.007131 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:05.153563 systemd[1]: Reloading finished in 351 ms. Sep 12 10:12:05.168554 waagent[1969]: 2025-09-12T10:12:05.168468Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Sep 12 10:12:05.168676 waagent[1969]: 2025-09-12T10:12:05.168633Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Sep 12 10:12:05.522220 waagent[1969]: 2025-09-12T10:12:05.522132Z 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.522773 waagent[1969]: 2025-09-12T10:12:05.522714Z 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.523767 waagent[1969]: 2025-09-12T10:12:05.523705Z INFO ExtHandler ExtHandler Starting env monitor service. Sep 12 10:12:05.524049 waagent[1969]: 2025-09-12T10:12:05.523989Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 12 10:12:05.524456 waagent[1969]: 2025-09-12T10:12:05.524401Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Sep 12 10:12:05.524499 waagent[1969]: 2025-09-12T10:12:05.524461Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 12 10:12:05.525022 waagent[1969]: 2025-09-12T10:12:05.524969Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Sep 12 10:12:05.525146 waagent[1969]: 2025-09-12T10:12:05.525095Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 12 10:12:05.525540 waagent[1969]: 2025-09-12T10:12:05.525475Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Sep 12 10:12:05.525719 waagent[1969]: 2025-09-12T10:12:05.525676Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 12 10:12:05.525787 waagent[1969]: 2025-09-12T10:12:05.525739Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Sep 12 10:12:05.525787 waagent[1969]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Sep 12 10:12:05.525787 waagent[1969]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Sep 12 10:12:05.525787 waagent[1969]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Sep 12 10:12:05.525787 waagent[1969]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Sep 12 10:12:05.525787 waagent[1969]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Sep 12 10:12:05.525787 waagent[1969]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Sep 12 10:12:05.525958 waagent[1969]: 2025-09-12T10:12:05.525903Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Sep 12 10:12:05.526183 waagent[1969]: 2025-09-12T10:12:05.526127Z INFO EnvHandler ExtHandler Configure routes Sep 12 10:12:05.527186 waagent[1969]: 2025-09-12T10:12:05.527143Z INFO EnvHandler ExtHandler Gateway:None Sep 12 10:12:05.527367 waagent[1969]: 2025-09-12T10:12:05.527303Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Sep 12 10:12:05.527455 waagent[1969]: 2025-09-12T10:12:05.527406Z 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.528007 waagent[1969]: 2025-09-12T10:12:05.527868Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Sep 12 10:12:05.528007 waagent[1969]: 2025-09-12T10:12:05.527949Z INFO EnvHandler ExtHandler Routes:None Sep 12 10:12:05.533121 waagent[1969]: 2025-09-12T10:12:05.533067Z INFO ExtHandler ExtHandler Sep 12 10:12:05.533530 waagent[1969]: 2025-09-12T10:12:05.533485Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: ea9fe600-a1ad-461d-8a56-33c9f7c1dcd8 correlation 781cb041-b2b1-49d1-ad1d-e9fb0ebcb95c created: 2025-09-12T10:10:33.011978Z] Sep 12 10:12:05.534855 waagent[1969]: 2025-09-12T10:12:05.534790Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Sep 12 10:12:05.536753 waagent[1969]: 2025-09-12T10:12:05.536698Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Sep 12 10:12:05.569811 waagent[1969]: 2025-09-12T10:12:05.569746Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.9.1.1 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 394E10ED-0A68-4654-9AAD-AD4BA0FBD34D;DroppedPackets: 0;UpdateGSErrors: 0;AutoUpdate: 0] Sep 12 10:12:05.594940 waagent[1969]: 2025-09-12T10:12:05.594877Z INFO MonitorHandler ExtHandler Network interfaces: Sep 12 10:12:05.594940 waagent[1969]: Executing ['ip', '-a', '-o', 'link']: Sep 12 10:12:05.594940 waagent[1969]: 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.594940 waagent[1969]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:1e:52:87:a5:7b brd ff:ff:ff:ff:ff:ff Sep 12 10:12:05.594940 waagent[1969]: Executing ['ip', '-4', '-a', '-o', 'address']: Sep 12 10:12:05.594940 waagent[1969]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Sep 12 10:12:05.594940 waagent[1969]: 2: eth0 inet 10.200.8.18/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Sep 12 10:12:05.594940 waagent[1969]: Executing ['ip', '-6', '-a', '-o', 'address']: Sep 12 10:12:05.594940 waagent[1969]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Sep 12 10:12:05.594940 waagent[1969]: 2: eth0 inet6 fe80::7e1e:52ff:fe87:a57b/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Sep 12 10:12:05.675904 waagent[1969]: 2025-09-12T10:12:05.675831Z INFO EnvHandler ExtHandler Successfully added Azure fabric firewall rules. Current Firewall rules: Sep 12 10:12:05.675904 waagent[1969]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Sep 12 10:12:05.675904 waagent[1969]: pkts bytes target prot opt in out source destination Sep 12 10:12:05.675904 waagent[1969]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Sep 12 10:12:05.675904 waagent[1969]: pkts bytes target prot opt in out source destination Sep 12 10:12:05.675904 waagent[1969]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Sep 12 10:12:05.675904 waagent[1969]: pkts bytes target prot opt in out source destination Sep 12 10:12:05.675904 waagent[1969]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Sep 12 10:12:05.675904 waagent[1969]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Sep 12 10:12:05.675904 waagent[1969]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Sep 12 10:12:05.679449 waagent[1969]: 2025-09-12T10:12:05.679391Z INFO EnvHandler ExtHandler Current Firewall rules: Sep 12 10:12:05.679449 waagent[1969]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Sep 12 10:12:05.679449 waagent[1969]: pkts bytes target prot opt in out source destination Sep 12 10:12:05.679449 waagent[1969]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Sep 12 10:12:05.679449 waagent[1969]: pkts bytes target prot opt in out source destination Sep 12 10:12:05.679449 waagent[1969]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Sep 12 10:12:05.679449 waagent[1969]: pkts bytes target prot opt in out source destination Sep 12 10:12:05.679449 waagent[1969]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Sep 12 10:12:05.679449 waagent[1969]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Sep 12 10:12:05.679449 waagent[1969]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Sep 12 10:12:05.679708 waagent[1969]: 2025-09-12T10:12:05.679667Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Sep 12 10:12:05.679888 waagent[1969]: 2025-09-12T10:12:05.679850Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Sep 12 10:12:16.733966 chronyd[1688]: Selected source PHC0 Sep 12 10:12:17.170882 setup-nvidia[1839]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 537M 0 480k 0 0 441k 0 0:20:46 0:00:01 0:20:45 441k\u000d 3 537M 3 21.0M 0 0 9987k 0 0:00:55 0:00:02 0:00:53 9987k\u000d 8 537M 8 43.2M 0 0 13.9M 0 0:00:38 0:00:03 0:00:35 13.9M\u000d 12 537M 12 68.7M 0 0 16.4M 0 0:00:32 0:00:04 0:00:28 16.4M\u000d 17 537M 17 92.4M 0 0 18.0M 0 0:00:29 0:00:05 0:00:24 18.6M\u000d 21 537M 21 116M 0 0 19.2M 0 0:00:27 0:00:06 0:00:21 23.3M\u000d 26 537M 26 143M 0 0 20.0M 0 0:00:26 0:00:07 0:00:19 24.4M\u000d 30 537M 30 162M 0 0 20.1M 0 0:00:26 0:00:08 0:00:18 23.9M\u000d 34 537M 34 187M 0 0 20.4M 0 0:00:26 0:00:09 0:00:17 23.8M\u000d 39 537M 39 210M 0 0 20.8M 0 0:00:25 0:00:10 0:00:15 23.6M\u000d 44 537M 44 237M 0 0 21.2M 0 0:00:25 0:00:11 0:00:14 23.5M\u000d 47 537M 47 256M 0 0 21.1M 0 0:00:25 0:00:12 0:00:13 22.6M\u000d 52 537M 52 280M 0 0 21.4M 0 0:00:25 0:00:13 0:00:12 23.6M\u000d 57 537M 57 307M 0 0 21.6M 0 0:00:24 0:00:14 0:00:10 23.9M\u000d 61 537M 61 330M 0 0 21.8M 0 0:00:24 0:00:15 0:00:09 24.0M\u000d 66 537M 66 358M 0 0 22.1M 0 0:00:24 0:00:16 0:00:08 24.2M\u000d 70 537M 70 381M 0 0 22.2M 0 0:00:24 0:00:17 0:00:07 24.9M\u000d 75 537M 75 405M 0 0 22.3M 0 0:00:24 0:00:18 0:00:06 24.8M\u000d 80 537M 80 430M 0 0 22.4M 0 0:00:23 0:00:19 0:00:04 24.6M\u000d 84 537M 84 454M 0 0 22.5M 0 0:00:23 0:00:20 0:00:03 24.7M\u000d 89 537M 89 480M 0 0 22.6M 0 0:00:23 0:00:21 0:00:02 24.4M\u000d 93 537M 93 505M 0 0 22.8M 0 0:00:23 0:00:22 0:00:01 24.7M\u000d 98 537M 98 529M 0 0 22.9M 0 0:00:23 0:00:23 --:--:-- 24.9M\u000d100 537M 100 537M 0 0 23.0M 0 0:00:23 0:00:23 --:--:-- 25.5M Sep 12 10:12:20.303231 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 12 10:12:20.316055 systemd[1]: Started sshd@0-10.200.8.18:22-10.200.16.10:52344.service - OpenSSH per-connection server daemon (10.200.16.10:52344). Sep 12 10:12:21.149945 sshd[2220]: Accepted publickey for core from 10.200.16.10 port 52344 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:12:21.151367 sshd-session[2220]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:12:21.157404 systemd-logind[1685]: New session 3 of user core. Sep 12 10:12:21.161955 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 12 10:12:21.713448 systemd[1]: Started sshd@1-10.200.8.18:22-10.200.16.10:52354.service - OpenSSH per-connection server daemon (10.200.16.10:52354). Sep 12 10:12:22.353813 sshd[2225]: Accepted publickey for core from 10.200.16.10 port 52354 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:12:22.353456 sshd-session[2225]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:12:22.360463 systemd-logind[1685]: New session 4 of user core. Sep 12 10:12:22.365958 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 12 10:12:22.809475 sshd[2227]: Connection closed by 10.200.16.10 port 52354 Sep 12 10:12:22.807953 sshd-session[2225]: pam_unix(sshd:session): session closed for user core Sep 12 10:12:22.811410 systemd[1]: sshd@1-10.200.8.18:22-10.200.16.10:52354.service: Deactivated successfully. Sep 12 10:12:22.813106 systemd[1]: session-4.scope: Deactivated successfully. Sep 12 10:12:22.817117 systemd-logind[1685]: Session 4 logged out. Waiting for processes to exit. Sep 12 10:12:22.818162 systemd-logind[1685]: Removed session 4. Sep 12 10:12:22.930117 systemd[1]: Started sshd@2-10.200.8.18:22-10.200.16.10:52370.service - OpenSSH per-connection server daemon (10.200.16.10:52370). Sep 12 10:12:23.571444 sshd[2233]: Accepted publickey for core from 10.200.16.10 port 52370 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:12:23.572901 sshd-session[2233]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:12:23.582287 systemd-logind[1685]: New session 5 of user core. Sep 12 10:12:23.588010 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 12 10:12:24.023642 sshd[2235]: Connection closed by 10.200.16.10 port 52370 Sep 12 10:12:24.022595 sshd-session[2233]: pam_unix(sshd:session): session closed for user core Sep 12 10:12:24.029686 systemd[1]: sshd@2-10.200.8.18:22-10.200.16.10:52370.service: Deactivated successfully. Sep 12 10:12:24.032041 systemd[1]: session-5.scope: Deactivated successfully. Sep 12 10:12:24.033552 systemd-logind[1685]: Session 5 logged out. Waiting for processes to exit. Sep 12 10:12:24.035218 systemd-logind[1685]: Removed session 5. Sep 12 10:12:29.517035 setup-nvidia[1681]: Downloading NVIDIA 535.230.02 Driver Sep 12 10:12:29.521762 setup-nvidia[2241]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 12 10:12:29.522020 setup-nvidia[2241]: Dload Upload Total Spent Left Speed Sep 12 10:12:31.686636 setup-nvidia[2241]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 326M 0 16384 0 0 34594 0 2:44:43 --:--:-- 2:44:43 34565\u000d 55 326M 55 180M 0 0 132M 0 0:00:02 0:00:01 0:00:01 132M\u000d100 326M 100 326M 0 0 150M 0 0:00:02 0:00:02 --:--:-- 150M Sep 12 10:12:31.766385 setup-nvidia[1681]: Extract the NVIDIA Driver Installer 535.230.02 Sep 12 10:12:31.766580 setup-nvidia[1681]: /opt/nvidia/workdir/nvidia-workdir / Sep 12 10:12:31.798537 setup-nvidia[2244]: Creating directory NVIDIA-Linux-x86_64-535.230.02 Sep 12 10:12:32.090291 setup-nvidia[2244]: Verifying archive integrity... OK Sep 12 10:12:32.899820 setup-nvidia[2244]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.230.02 Sep 12 10:12:34.139549 systemd[1]: Started sshd@3-10.200.8.18:22-10.200.16.10:51920.service - OpenSSH per-connection server daemon (10.200.16.10:51920). Sep 12 10:12:34.651228 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Sep 12 10:12:34.710267 setup-nvidia[2270]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Sep 12 10:12:34.780358 sshd[2275]: Accepted publickey for core from 10.200.16.10 port 51920 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:12:34.781604 sshd-session[2275]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:12:34.785712 systemd-logind[1685]: New session 6 of user core. Sep 12 10:12:34.802912 systemd[1]: Started session-6.scope - Session 6 of User core. Sep 12 10:12:34.807034 setup-nvidia[1681]: / Sep 12 10:12:34.807227 setup-nvidia[1681]: Spawn system-nspawn container to install the NVIDIA drivers Sep 12 10:12:35.235116 sudo[2281]: 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:35.236583 sshd[2280]: Connection closed by 10.200.16.10 port 51920 Sep 12 10:12:35.236860 sudo[2281]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 12 10:12:35.236944 sshd-session[2275]: pam_unix(sshd:session): session closed for user core Sep 12 10:12:35.239983 systemd[1]: sshd@3-10.200.8.18:22-10.200.16.10:51920.service: Deactivated successfully. Sep 12 10:12:35.241614 systemd[1]: session-6.scope: Deactivated successfully. Sep 12 10:12:35.249060 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Sep 12 10:12:35.265023 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Sep 12 10:12:35.266904 systemd-logind[1685]: Session 6 logged out. Waiting for processes to exit. Sep 12 10:12:35.267621 systemd-logind[1685]: Removed session 6. Sep 12 10:12:35.280520 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Sep 12 10:12:35.284544 systemd[1]: Starting user@0.service - User Manager for UID 0... Sep 12 10:12:35.290954 (systemd)[2288]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Sep 12 10:12:35.293073 systemd-logind[1685]: New session c3 of user root. Sep 12 10:12:35.353915 systemd[1]: Started sshd@4-10.200.8.18:22-10.200.16.10:51926.service - OpenSSH per-connection server daemon (10.200.16.10:51926). Sep 12 10:12:35.475528 systemd[2288]: Queued start job for default target default.target. Sep 12 10:12:35.484844 systemd[2288]: Created slice app.slice - User Application Slice. Sep 12 10:12:35.484874 systemd[2288]: Reached target paths.target - Paths. Sep 12 10:12:35.484922 systemd[2288]: Reached target timers.target - Timers. Sep 12 10:12:35.486066 systemd[2288]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 12 10:12:35.496190 systemd[2288]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 12 10:12:35.496249 systemd[2288]: Reached target sockets.target - Sockets. Sep 12 10:12:35.496293 systemd[2288]: Reached target basic.target - Basic System. Sep 12 10:12:35.496338 systemd[2288]: Reached target default.target - Main User Target. Sep 12 10:12:35.496369 systemd[2288]: Startup finished in 197ms. Sep 12 10:12:35.496485 systemd[1]: Started user@0.service - User Manager for UID 0. Sep 12 10:12:35.499529 systemd[1]: Started session-c2.scope - Session c2 of User root. Sep 12 10:12:35.577816 kernel: loop6: detected capacity change from 0 to 12644352 Sep 12 10:12:35.597816 kernel: loop6: p9 Sep 12 10:12:35.707788 setup-nvidia[2300]: ░ 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:35.756820 kernel: EXT4-fs (loop6p9): mounted filesystem e3e4fd57-6dbd-4666-81f1-84c1ac2e084b ro without journal. Quota mode: none. Sep 12 10:12:35.990135 sshd[2295]: Accepted publickey for core from 10.200.16.10 port 51926 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:12:35.991401 sshd-session[2295]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:12:35.995485 systemd-logind[1685]: New session 7 of user core. Sep 12 10:12:36.003005 systemd[1]: Started session-7.scope - Session 7 of User core. Sep 12 10:12:36.445423 sshd[2306]: Connection closed by 10.200.16.10 port 51926 Sep 12 10:12:36.446477 sshd-session[2295]: pam_unix(sshd:session): session closed for user core Sep 12 10:12:36.449684 systemd[1]: sshd@4-10.200.8.18:22-10.200.16.10:51926.service: Deactivated successfully. Sep 12 10:12:36.451388 systemd[1]: session-7.scope: Deactivated successfully. Sep 12 10:12:36.452097 systemd-logind[1685]: Session 7 logged out. Waiting for processes to exit. Sep 12 10:12:36.452932 systemd-logind[1685]: Removed session 7. Sep 12 10:12:38.745908 update_engine[1690]: I20250912 10:12:38.745816 1690 update_attempter.cc:509] Updating boot flags... Sep 12 10:12:38.894826 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 35 scanned by (udev-worker) (2382) Sep 12 10:12:38.988828 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 35 scanned by (udev-worker) (2385) Sep 12 10:12:46.573073 systemd[1]: Started sshd@5-10.200.8.18:22-10.200.16.10:40650.service - OpenSSH per-connection server daemon (10.200.16.10:40650). Sep 12 10:12:47.206113 sshd[3413]: Accepted publickey for core from 10.200.16.10 port 40650 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:12:47.207436 sshd-session[3413]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:12:47.211594 systemd-logind[1685]: New session 8 of user core. Sep 12 10:12:47.220919 systemd[1]: Started session-8.scope - Session 8 of User core. Sep 12 10:12:47.659066 sshd[3445]: Connection closed by 10.200.16.10 port 40650 Sep 12 10:12:47.659437 sshd-session[3413]: pam_unix(sshd:session): session closed for user core Sep 12 10:12:47.662477 systemd[1]: sshd@5-10.200.8.18:22-10.200.16.10:40650.service: Deactivated successfully. Sep 12 10:12:47.664081 systemd[1]: session-8.scope: Deactivated successfully. Sep 12 10:12:47.664698 systemd-logind[1685]: Session 8 logged out. Waiting for processes to exit. Sep 12 10:12:47.665595 systemd-logind[1685]: Removed session 8. Sep 12 10:12:47.772132 systemd[1]: Started sshd@6-10.200.8.18:22-10.200.16.10:40654.service - OpenSSH per-connection server daemon (10.200.16.10:40654). Sep 12 10:12:48.410703 sshd[3451]: Accepted publickey for core from 10.200.16.10 port 40654 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:12:48.412037 sshd-session[3451]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:12:48.416275 systemd-logind[1685]: New session 9 of user core. Sep 12 10:12:48.431917 systemd[1]: Started session-9.scope - Session 9 of User core. Sep 12 10:12:48.860977 sshd[3453]: Connection closed by 10.200.16.10 port 40654 Sep 12 10:12:48.861364 sshd-session[3451]: pam_unix(sshd:session): session closed for user core Sep 12 10:12:48.864632 systemd[1]: sshd@6-10.200.8.18:22-10.200.16.10:40654.service: Deactivated successfully. Sep 12 10:12:48.866239 systemd[1]: session-9.scope: Deactivated successfully. Sep 12 10:12:48.866874 systemd-logind[1685]: Session 9 logged out. Waiting for processes to exit. Sep 12 10:12:48.867686 systemd-logind[1685]: Removed session 9. Sep 12 10:12:58.974474 systemd[1]: Started sshd@7-10.200.8.18:22-10.200.16.10:47376.service - OpenSSH per-connection server daemon (10.200.16.10:47376). Sep 12 10:12:59.612637 sshd[3503]: Accepted publickey for core from 10.200.16.10 port 47376 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:12:59.613996 sshd-session[3503]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:12:59.618163 systemd-logind[1685]: New session 10 of user core. Sep 12 10:12:59.627924 systemd[1]: Started session-10.scope - Session 10 of User core. Sep 12 10:13:00.075755 sshd[3505]: Connection closed by 10.200.16.10 port 47376 Sep 12 10:13:00.076186 sshd-session[3503]: pam_unix(sshd:session): session closed for user core Sep 12 10:13:00.079400 systemd[1]: sshd@7-10.200.8.18:22-10.200.16.10:47376.service: Deactivated successfully. Sep 12 10:13:00.081052 systemd[1]: session-10.scope: Deactivated successfully. Sep 12 10:13:00.081692 systemd-logind[1685]: Session 10 logged out. Waiting for processes to exit. Sep 12 10:13:00.082550 systemd-logind[1685]: Removed session 10. Sep 12 10:13:00.196398 systemd[1]: Started sshd@8-10.200.8.18:22-10.200.16.10:42634.service - OpenSSH per-connection server daemon (10.200.16.10:42634). Sep 12 10:13:00.841885 sshd[3511]: Accepted publickey for core from 10.200.16.10 port 42634 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:13:00.843189 sshd-session[3511]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:13:00.847338 systemd-logind[1685]: New session 11 of user core. Sep 12 10:13:00.859916 systemd[1]: Started session-11.scope - Session 11 of User core. Sep 12 10:13:01.293265 sshd[3513]: Connection closed by 10.200.16.10 port 42634 Sep 12 10:13:01.293647 sshd-session[3511]: pam_unix(sshd:session): session closed for user core Sep 12 10:13:01.296832 systemd[1]: sshd@8-10.200.8.18:22-10.200.16.10:42634.service: Deactivated successfully. Sep 12 10:13:01.298454 systemd[1]: session-11.scope: Deactivated successfully. Sep 12 10:13:01.299112 systemd-logind[1685]: Session 11 logged out. Waiting for processes to exit. Sep 12 10:13:01.299887 systemd-logind[1685]: Removed session 11. Sep 12 10:13:11.407522 systemd[1]: Started sshd@9-10.200.8.18:22-10.200.16.10:48622.service - OpenSSH per-connection server daemon (10.200.16.10:48622). Sep 12 10:13:12.047226 sshd[3591]: Accepted publickey for core from 10.200.16.10 port 48622 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:13:12.048554 sshd-session[3591]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:13:12.052714 systemd-logind[1685]: New session 12 of user core. Sep 12 10:13:12.070931 systemd[1]: Started session-12.scope - Session 12 of User core. Sep 12 10:13:12.500592 sshd[3618]: Connection closed by 10.200.16.10 port 48622 Sep 12 10:13:12.504628 sshd-session[3591]: pam_unix(sshd:session): session closed for user core Sep 12 10:13:12.508035 systemd[1]: sshd@9-10.200.8.18:22-10.200.16.10:48622.service: Deactivated successfully. Sep 12 10:13:12.509628 systemd[1]: session-12.scope: Deactivated successfully. Sep 12 10:13:12.510307 systemd-logind[1685]: Session 12 logged out. Waiting for processes to exit. Sep 12 10:13:12.511051 systemd-logind[1685]: Removed session 12. Sep 12 10:13:12.612969 systemd[1]: Started sshd@10-10.200.8.18:22-10.200.16.10:48628.service - OpenSSH per-connection server daemon (10.200.16.10:48628). Sep 12 10:13:13.250837 sshd[3624]: Accepted publickey for core from 10.200.16.10 port 48628 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:13:13.253326 sshd-session[3624]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:13:13.258185 systemd-logind[1685]: New session 13 of user core. Sep 12 10:13:13.282952 systemd[1]: Started session-13.scope - Session 13 of User core. Sep 12 10:13:13.704369 sshd[3626]: Connection closed by 10.200.16.10 port 48628 Sep 12 10:13:13.704677 sshd-session[3624]: pam_unix(sshd:session): session closed for user core Sep 12 10:13:13.707834 systemd[1]: sshd@10-10.200.8.18:22-10.200.16.10:48628.service: Deactivated successfully. Sep 12 10:13:13.709424 systemd[1]: session-13.scope: Deactivated successfully. Sep 12 10:13:13.710102 systemd-logind[1685]: Session 13 logged out. Waiting for processes to exit. Sep 12 10:13:13.710858 systemd-logind[1685]: Removed session 13. Sep 12 10:13:23.823471 systemd[1]: Started sshd@11-10.200.8.18:22-10.200.16.10:35760.service - OpenSSH per-connection server daemon (10.200.16.10:35760). Sep 12 10:13:24.465520 sshd[7826]: Accepted publickey for core from 10.200.16.10 port 35760 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:13:24.467001 sshd-session[7826]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:13:24.473641 systemd-logind[1685]: New session 14 of user core. Sep 12 10:13:24.486018 systemd[1]: Started session-14.scope - Session 14 of User core. Sep 12 10:13:24.935582 sshd[7940]: Connection closed by 10.200.16.10 port 35760 Sep 12 10:13:24.936236 sshd-session[7826]: pam_unix(sshd:session): session closed for user core Sep 12 10:13:24.940680 systemd-logind[1685]: Session 14 logged out. Waiting for processes to exit. Sep 12 10:13:24.942435 systemd[1]: sshd@11-10.200.8.18:22-10.200.16.10:35760.service: Deactivated successfully. Sep 12 10:13:24.944843 systemd[1]: session-14.scope: Deactivated successfully. Sep 12 10:13:24.946403 systemd-logind[1685]: Removed session 14. Sep 12 10:13:25.063094 systemd[1]: Started sshd@12-10.200.8.18:22-10.200.16.10:35776.service - OpenSSH per-connection server daemon (10.200.16.10:35776). Sep 12 10:13:25.709908 sshd[8045]: Accepted publickey for core from 10.200.16.10 port 35776 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:13:25.710534 sshd-session[8045]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:13:25.717019 systemd-logind[1685]: New session 15 of user core. Sep 12 10:13:25.728940 systemd[1]: Started session-15.scope - Session 15 of User core. Sep 12 10:13:26.163121 sshd[8272]: Connection closed by 10.200.16.10 port 35776 Sep 12 10:13:26.164629 sshd-session[8045]: pam_unix(sshd:session): session closed for user core Sep 12 10:13:26.167214 systemd[1]: sshd@12-10.200.8.18:22-10.200.16.10:35776.service: Deactivated successfully. Sep 12 10:13:26.168852 systemd[1]: session-15.scope: Deactivated successfully. Sep 12 10:13:26.170283 systemd-logind[1685]: Session 15 logged out. Waiting for processes to exit. Sep 12 10:13:26.171159 systemd-logind[1685]: Removed session 15. Sep 12 10:13:36.294096 systemd[1]: Started sshd@13-10.200.8.18:22-10.200.16.10:53170.service - OpenSSH per-connection server daemon (10.200.16.10:53170). Sep 12 10:13:36.938313 sshd[11577]: Accepted publickey for core from 10.200.16.10 port 53170 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:13:36.939751 sshd-session[11577]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:13:36.946474 systemd-logind[1685]: New session 16 of user core. Sep 12 10:13:36.952563 systemd[1]: Started session-16.scope - Session 16 of User core. Sep 12 10:13:37.396561 sshd[11593]: Connection closed by 10.200.16.10 port 53170 Sep 12 10:13:37.396982 sshd-session[11577]: pam_unix(sshd:session): session closed for user core Sep 12 10:13:37.399854 systemd[1]: sshd@13-10.200.8.18:22-10.200.16.10:53170.service: Deactivated successfully. Sep 12 10:13:37.402152 systemd[1]: session-16.scope: Deactivated successfully. Sep 12 10:13:37.403871 systemd-logind[1685]: Session 16 logged out. Waiting for processes to exit. Sep 12 10:13:37.404956 systemd-logind[1685]: Removed session 16. Sep 12 10:13:37.519077 systemd[1]: Started sshd@14-10.200.8.18:22-10.200.16.10:53182.service - OpenSSH per-connection server daemon (10.200.16.10:53182). Sep 12 10:13:38.164556 sshd[11621]: Accepted publickey for core from 10.200.16.10 port 53182 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:13:38.166338 sshd-session[11621]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:13:38.172945 systemd-logind[1685]: New session 17 of user core. Sep 12 10:13:38.188081 systemd[1]: Started session-17.scope - Session 17 of User core. Sep 12 10:13:38.618287 sshd[11653]: Connection closed by 10.200.16.10 port 53182 Sep 12 10:13:38.618894 sshd-session[11621]: pam_unix(sshd:session): session closed for user core Sep 12 10:13:38.621864 systemd-logind[1685]: Session 17 logged out. Waiting for processes to exit. Sep 12 10:13:38.623281 systemd[1]: sshd@14-10.200.8.18:22-10.200.16.10:53182.service: Deactivated successfully. Sep 12 10:13:38.625562 systemd[1]: session-17.scope: Deactivated successfully. Sep 12 10:13:38.627070 systemd-logind[1685]: Removed session 17. Sep 12 10:13:48.735602 systemd[1]: Started sshd@15-10.200.8.18:22-10.200.16.10:32872.service - OpenSSH per-connection server daemon (10.200.16.10:32872). Sep 12 10:13:49.380307 sshd[12043]: Accepted publickey for core from 10.200.16.10 port 32872 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:13:49.382204 sshd-session[12043]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:13:49.388787 systemd-logind[1685]: New session 18 of user core. Sep 12 10:13:49.399984 systemd[1]: Started session-18.scope - Session 18 of User core. Sep 12 10:13:49.835043 sshd[12063]: Connection closed by 10.200.16.10 port 32872 Sep 12 10:13:49.836661 sshd-session[12043]: pam_unix(sshd:session): session closed for user core Sep 12 10:13:49.840073 systemd[1]: sshd@15-10.200.8.18:22-10.200.16.10:32872.service: Deactivated successfully. Sep 12 10:13:49.843273 systemd[1]: session-18.scope: Deactivated successfully. Sep 12 10:13:49.844096 systemd-logind[1685]: Session 18 logged out. Waiting for processes to exit. Sep 12 10:13:49.845224 systemd-logind[1685]: Removed session 18. Sep 12 10:13:49.954098 systemd[1]: Started sshd@16-10.200.8.18:22-10.200.16.10:56852.service - OpenSSH per-connection server daemon (10.200.16.10:56852). Sep 12 10:13:50.597006 sshd[12081]: Accepted publickey for core from 10.200.16.10 port 56852 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:13:50.598426 sshd-session[12081]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:13:50.604231 systemd-logind[1685]: New session 19 of user core. Sep 12 10:13:50.612122 systemd[1]: Started session-19.scope - Session 19 of User core. Sep 12 10:13:51.051046 sshd[12107]: Connection closed by 10.200.16.10 port 56852 Sep 12 10:13:51.052614 sshd-session[12081]: pam_unix(sshd:session): session closed for user core Sep 12 10:13:51.056467 systemd[1]: sshd@16-10.200.8.18:22-10.200.16.10:56852.service: Deactivated successfully. Sep 12 10:13:51.059388 systemd[1]: session-19.scope: Deactivated successfully. Sep 12 10:13:51.061335 systemd-logind[1685]: Session 19 logged out. Waiting for processes to exit. Sep 12 10:13:51.062440 systemd-logind[1685]: Removed session 19. Sep 12 10:14:01.166062 systemd[1]: Started sshd@17-10.200.8.18:22-10.200.16.10:33360.service - OpenSSH per-connection server daemon (10.200.16.10:33360). Sep 12 10:14:01.808384 sshd[12444]: Accepted publickey for core from 10.200.16.10 port 33360 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:14:01.810206 sshd-session[12444]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:14:01.818198 systemd-logind[1685]: New session 20 of user core. Sep 12 10:14:01.828960 systemd[1]: Started session-20.scope - Session 20 of User core. Sep 12 10:14:02.261907 sshd[12463]: Connection closed by 10.200.16.10 port 33360 Sep 12 10:14:02.263006 sshd-session[12444]: pam_unix(sshd:session): session closed for user core Sep 12 10:14:02.266543 systemd[1]: sshd@17-10.200.8.18:22-10.200.16.10:33360.service: Deactivated successfully. Sep 12 10:14:02.268910 systemd[1]: session-20.scope: Deactivated successfully. Sep 12 10:14:02.272296 systemd-logind[1685]: Session 20 logged out. Waiting for processes to exit. Sep 12 10:14:02.273450 systemd-logind[1685]: Removed session 20. Sep 12 10:14:02.387137 systemd[1]: Started sshd@18-10.200.8.18:22-10.200.16.10:33376.service - OpenSSH per-connection server daemon (10.200.16.10:33376). Sep 12 10:14:03.026419 sshd[12476]: Accepted publickey for core from 10.200.16.10 port 33376 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:14:03.028216 sshd-session[12476]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:14:03.035714 systemd-logind[1685]: New session 21 of user core. Sep 12 10:14:03.044959 systemd[1]: Started session-21.scope - Session 21 of User core. Sep 12 10:14:03.483518 sshd[12495]: Connection closed by 10.200.16.10 port 33376 Sep 12 10:14:03.489289 sshd-session[12476]: pam_unix(sshd:session): session closed for user core Sep 12 10:14:03.494148 systemd[1]: sshd@18-10.200.8.18:22-10.200.16.10:33376.service: Deactivated successfully. Sep 12 10:14:03.496105 systemd-logind[1685]: Session 21 logged out. Waiting for processes to exit. Sep 12 10:14:03.496651 systemd[1]: session-21.scope: Deactivated successfully. Sep 12 10:14:03.497835 systemd-logind[1685]: Removed session 21. Sep 12 10:14:13.600733 systemd[1]: Started sshd@19-10.200.8.18:22-10.200.16.10:56140.service - OpenSSH per-connection server daemon (10.200.16.10:56140). Sep 12 10:14:14.242840 sshd[12720]: Accepted publickey for core from 10.200.16.10 port 56140 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:14:14.244079 sshd-session[12720]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:14:14.248281 systemd-logind[1685]: New session 22 of user core. Sep 12 10:14:14.263956 systemd[1]: Started session-22.scope - Session 22 of User core. Sep 12 10:14:14.695813 sshd[12756]: Connection closed by 10.200.16.10 port 56140 Sep 12 10:14:14.696235 sshd-session[12720]: pam_unix(sshd:session): session closed for user core Sep 12 10:14:14.699478 systemd[1]: sshd@19-10.200.8.18:22-10.200.16.10:56140.service: Deactivated successfully. Sep 12 10:14:14.701113 systemd[1]: session-22.scope: Deactivated successfully. Sep 12 10:14:14.701999 systemd-logind[1685]: Session 22 logged out. Waiting for processes to exit. Sep 12 10:14:14.702774 systemd-logind[1685]: Removed session 22. Sep 12 10:14:14.808199 systemd[1]: Started sshd@20-10.200.8.18:22-10.200.16.10:56152.service - OpenSSH per-connection server daemon (10.200.16.10:56152). Sep 12 10:14:15.445662 sshd[12774]: Accepted publickey for core from 10.200.16.10 port 56152 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:14:15.447010 sshd-session[12774]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:14:15.451065 systemd-logind[1685]: New session 23 of user core. Sep 12 10:14:15.462914 systemd[1]: Started session-23.scope - Session 23 of User core. Sep 12 10:14:15.899273 sshd[12776]: Connection closed by 10.200.16.10 port 56152 Sep 12 10:14:15.899777 sshd-session[12774]: pam_unix(sshd:session): session closed for user core Sep 12 10:14:15.903021 systemd[1]: sshd@20-10.200.8.18:22-10.200.16.10:56152.service: Deactivated successfully. Sep 12 10:14:15.904588 systemd[1]: session-23.scope: Deactivated successfully. Sep 12 10:14:15.905247 systemd-logind[1685]: Session 23 logged out. Waiting for processes to exit. Sep 12 10:14:15.906052 systemd-logind[1685]: Removed session 23. Sep 12 10:14:16.634991 setup-nvidia[2300]: \u000d Sep 12 10:14:16.634991 setup-nvidia[2300]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Sep 12 10:14:16.634991 setup-nvidia[2300]: frequently when this kernel module was built against the wrong or Sep 12 10:14:16.634991 setup-nvidia[2300]: improperly configured kernel sources, with a version of gcc that differs Sep 12 10:14:16.634991 setup-nvidia[2300]: from the one used to build the target kernel, or if another driver, such Sep 12 10:14:16.634991 setup-nvidia[2300]: as nouveau, is present and prevents the NVIDIA kernel module from Sep 12 10:14:16.634991 setup-nvidia[2300]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Sep 12 10:14:16.634991 setup-nvidia[2300]: installed in this system is supported by this NVIDIA Linux graphics Sep 12 10:14:16.634991 setup-nvidia[2300]: driver release. Sep 12 10:14:16.634991 setup-nvidia[2300]: \u000d Sep 12 10:14:16.634991 setup-nvidia[2300]: Please see the log entries 'Kernel module load error' and 'Kernel Sep 12 10:14:16.634991 setup-nvidia[2300]: messages' at the end of the file Sep 12 10:14:16.634991 setup-nvidia[2300]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more Sep 12 10:14:16.634991 setup-nvidia[2300]: information. Sep 12 10:14:16.634991 setup-nvidia[2300]: \u000d Sep 12 10:14:16.649694 setup-nvidia[2300]: dmesg: read kernel buffer failed: Operation not permitted Sep 12 10:14:16.671051 setup-nvidia[2300]: \u000d Sep 12 10:14:16.671051 setup-nvidia[2300]: ERROR: Installation has failed. Please see the file Sep 12 10:14:16.671051 setup-nvidia[2300]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for Sep 12 10:14:16.671051 setup-nvidia[2300]: details. You may find suggestions on fixing installation problems in Sep 12 10:14:16.671051 setup-nvidia[2300]: the README available on the Linux driver download page at Sep 12 10:14:16.671051 setup-nvidia[2300]: www.nvidia.com. Sep 12 10:14:16.671051 setup-nvidia[2300]: \u000d Sep 12 10:14:16.671467 setup-nvidia[2300]: Last 50 lines of nvidia-installer.log: Sep 12 10:14:16.672266 setup-nvidia[2300]: /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:16.672266 setup-nvidia[2300]: /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:16.672266 setup-nvidia[2300]: /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:16.672266 setup-nvidia[2300]: /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:16.672266 setup-nvidia[2300]: /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:16.672266 setup-nvidia[2300]: /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:16.672266 setup-nvidia[2300]: /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:16.672266 setup-nvidia[2300]: /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:16.672266 setup-nvidia[2300]: /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:16.672266 setup-nvidia[2300]: /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:16.672266 setup-nvidia[2300]: /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:16.672266 setup-nvidia[2300]: /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:16.672266 setup-nvidia[2300]: /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:16.672266 setup-nvidia[2300]: /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:16.672266 setup-nvidia[2300]: /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:16.672266 setup-nvidia[2300]: /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:16.672266 setup-nvidia[2300]: /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:16.672266 setup-nvidia[2300]: /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:16.672266 setup-nvidia[2300]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/Module.symvers Sep 12 10:14:16.672266 setup-nvidia[2300]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.mod.o Sep 12 10:14:16.672266 setup-nvidia[2300]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.mod.o Sep 12 10:14:16.672266 setup-nvidia[2300]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.mod.o Sep 12 10:14:16.672266 setup-nvidia[2300]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.mod.o Sep 12 10:14:16.672266 setup-nvidia[2300]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.mod.o Sep 12 10:14:16.672266 setup-nvidia[2300]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Sep 12 10:14:16.672266 setup-nvidia[2300]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Sep 12 10:14:16.672266 setup-nvidia[2300]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Sep 12 10:14:16.672266 setup-nvidia[2300]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Sep 12 10:14:16.672266 setup-nvidia[2300]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Sep 12 10:14:16.672266 setup-nvidia[2300]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Sep 12 10:14:16.672266 setup-nvidia[2300]: 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:16.672266 setup-nvidia[2300]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Sep 12 10:14:16.672266 setup-nvidia[2300]: 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:16.672266 setup-nvidia[2300]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Sep 12 10:14:16.672266 setup-nvidia[2300]: 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:16.672266 setup-nvidia[2300]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Sep 12 10:14:16.672266 setup-nvidia[2300]: 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:16.672266 setup-nvidia[2300]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Sep 12 10:14:16.672266 setup-nvidia[2300]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko due to unavailability of vmlinux Sep 12 10:14:16.672266 setup-nvidia[2300]: make[2]: Leaving directory '/usr/lib/modules/6.6.105-flatcar/build' Sep 12 10:14:16.672266 setup-nvidia[2300]: make[1]: Leaving directory '/usr/lib/modules/6.6.105-flatcar/source' Sep 12 10:14:16.672266 setup-nvidia[2300]: -> done. Sep 12 10:14:16.672266 setup-nvidia[2300]: -> Kernel module compilation complete. Sep 12 10:14:16.673362 setup-nvidia[2300]: -> Unable to determine if Secure Boot is enabled: No such file or directory Sep 12 10:14:16.673362 setup-nvidia[2300]: 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:16.673362 setup-nvidia[2300]: \u000d Sep 12 10:14:16.673362 setup-nvidia[2300]: 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:16.673362 setup-nvidia[2300]: -> Kernel module load error: Operation not permitted Sep 12 10:14:16.673362 setup-nvidia[2300]: -> Kernel messages: Sep 12 10:14:16.673362 setup-nvidia[2300]: 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.015304 systemd[1]: Started sshd@21-10.200.8.18:22-10.200.16.10:45104.service - OpenSSH per-connection server daemon (10.200.16.10:45104). Sep 12 10:14:26.640712 sshd[12945]: Accepted publickey for core from 10.200.16.10 port 45104 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:14:26.642069 sshd-session[12945]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:14:26.646180 systemd-logind[1685]: New session 24 of user core. Sep 12 10:14:26.658920 systemd[1]: Started session-24.scope - Session 24 of User core. Sep 12 10:14:27.085586 sshd[12947]: Connection closed by 10.200.16.10 port 45104 Sep 12 10:14:27.085994 sshd-session[12945]: pam_unix(sshd:session): session closed for user core Sep 12 10:14:27.089228 systemd[1]: sshd@21-10.200.8.18:22-10.200.16.10:45104.service: Deactivated successfully. Sep 12 10:14:27.090930 systemd[1]: session-24.scope: Deactivated successfully. Sep 12 10:14:27.091583 systemd-logind[1685]: Session 24 logged out. Waiting for processes to exit. Sep 12 10:14:27.092389 systemd-logind[1685]: Removed session 24. Sep 12 10:14:27.198462 systemd[1]: Started sshd@22-10.200.8.18:22-10.200.16.10:45108.service - OpenSSH per-connection server daemon (10.200.16.10:45108). Sep 12 10:14:27.822505 sshd[12953]: Accepted publickey for core from 10.200.16.10 port 45108 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:14:27.823849 sshd-session[12953]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:14:27.827902 systemd-logind[1685]: New session 25 of user core. Sep 12 10:14:27.834914 systemd[1]: Started session-25.scope - Session 25 of User core. Sep 12 10:14:28.276778 sshd[12955]: Connection closed by 10.200.16.10 port 45108 Sep 12 10:14:28.277193 sshd-session[12953]: pam_unix(sshd:session): session closed for user core Sep 12 10:14:28.280383 systemd[1]: sshd@22-10.200.8.18:22-10.200.16.10:45108.service: Deactivated successfully. Sep 12 10:14:28.282005 systemd[1]: session-25.scope: Deactivated successfully. Sep 12 10:14:28.282640 systemd-logind[1685]: Session 25 logged out. Waiting for processes to exit. Sep 12 10:14:28.283414 systemd-logind[1685]: Removed session 25. Sep 12 10:14:38.397145 systemd[1]: Started sshd@23-10.200.8.18:22-10.200.16.10:32832.service - OpenSSH per-connection server daemon (10.200.16.10:32832). Sep 12 10:14:39.024129 sshd[12962]: Accepted publickey for core from 10.200.16.10 port 32832 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:14:39.025532 sshd-session[12962]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:14:39.029567 systemd-logind[1685]: New session 26 of user core. Sep 12 10:14:39.043907 systemd[1]: Started session-26.scope - Session 26 of User core. Sep 12 10:14:39.468655 sshd[12964]: Connection closed by 10.200.16.10 port 32832 Sep 12 10:14:39.469112 sshd-session[12962]: pam_unix(sshd:session): session closed for user core Sep 12 10:14:39.472470 systemd[1]: sshd@23-10.200.8.18:22-10.200.16.10:32832.service: Deactivated successfully. Sep 12 10:14:39.474579 systemd[1]: session-26.scope: Deactivated successfully. Sep 12 10:14:39.475485 systemd-logind[1685]: Session 26 logged out. Waiting for processes to exit. Sep 12 10:14:39.476346 systemd-logind[1685]: Removed session 26. Sep 12 10:14:39.595609 systemd[1]: Started sshd@24-10.200.8.18:22-10.200.16.10:32836.service - OpenSSH per-connection server daemon (10.200.16.10:32836). Sep 12 10:14:39.877951 kernel: EXT4-fs (loop6p9): unmounting filesystem e3e4fd57-6dbd-4666-81f1-84c1ac2e084b. Sep 12 10:14:39.878723 setup-nvidia[2300]: Container flatcardevelopercontainer-4230.2.2nightly-20250911-2100.bin exited successfully. Sep 12 10:14:39.881108 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4230.2.2nightly\x2d20250911\x2d2100.bin.mount: Deactivated successfully. Sep 12 10:14:39.948651 sudo[2281]: pam_unix(sudo:session): session closed for user root Sep 12 10:14:39.950413 systemd[1]: session-c2.scope: Deactivated successfully. Sep 12 10:14:39.950719 systemd[1]: session-c2.scope: Consumed 5min 13.412s CPU time, 881.8M memory peak. Sep 12 10:14:40.224074 sshd[12970]: Accepted publickey for core from 10.200.16.10 port 32836 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:14:40.225335 sshd-session[12970]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:14:40.229828 systemd-logind[1685]: New session 27 of user core. Sep 12 10:14:40.248017 systemd[1]: Started session-27.scope - Session 27 of User core. Sep 12 10:14:40.364294 setup-nvidia[1681]: /opt/nvidia / Sep 12 10:14:40.365604 setup-nvidia[1681]: / Sep 12 10:14:40.381631 setup-nvidia[12990]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 12 10:14:40.409814 kernel: IPMI message handler: version 39.2 Sep 12 10:14:40.412838 kernel: ipmi device interface Sep 12 10:14:40.413936 setup-nvidia[1681]: /opt/nvidia/current/usr/lib/modules/6.6.105-flatcar/video / Sep 12 10:14:40.468866 kernel: nvidia: loading out-of-tree module taints kernel. Sep 12 10:14:40.469002 kernel: nvidia: module license 'NVIDIA' taints kernel. Sep 12 10:14:40.469026 kernel: Disabling lock debugging due to kernel taint Sep 12 10:14:40.475870 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Sep 12 10:14:40.475919 kernel: nvidia: module license taints kernel. Sep 12 10:14:40.667930 sshd[12981]: Connection closed by 10.200.16.10 port 32836 Sep 12 10:14:40.668265 sshd-session[12970]: pam_unix(sshd:session): session closed for user core Sep 12 10:14:40.671298 systemd[1]: sshd@24-10.200.8.18:22-10.200.16.10:32836.service: Deactivated successfully. Sep 12 10:14:40.672910 systemd[1]: session-27.scope: Deactivated successfully. Sep 12 10:14:40.673533 systemd-logind[1685]: Session 27 logged out. Waiting for processes to exit. Sep 12 10:14:40.674345 systemd-logind[1685]: Removed session 27. Sep 12 10:14:40.881828 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 245 Sep 12 10:14:41.285914 kernel: Sep 12 10:14:41.286298 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Sep 12 10:14:41.496817 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.230.02 Fri Dec 20 21:42:05 UTC 2024 Sep 12 10:14:41.518815 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:41.537835 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Sep 12 10:14:41.580666 setup-nvidia[1681]: / Sep 12 10:14:41.580997 kernel: nvidia-uvm: Loaded the UVM driver, major device number 243. Sep 12 10:14:43.342677 setup-nvidia[13024]: Fri Sep 12 10:14:43 2025 Sep 12 10:14:43.342677 setup-nvidia[13024]: +---------------------------------------------------------------------------------------+ Sep 12 10:14:43.342677 setup-nvidia[13024]: | NVIDIA-SMI 535.230.02 Driver Version: 535.230.02 CUDA Version: 12.2 | Sep 12 10:14:43.342677 setup-nvidia[13024]: |-----------------------------------------+----------------------+----------------------+ Sep 12 10:14:43.342677 setup-nvidia[13024]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Sep 12 10:14:43.342677 setup-nvidia[13024]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Sep 12 10:14:43.342677 setup-nvidia[13024]: | | | MIG M. | Sep 12 10:14:43.342677 setup-nvidia[13024]: |=========================================+======================+======================| Sep 12 10:14:43.346158 setup-nvidia[13024]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Sep 12 10:14:43.346158 setup-nvidia[13024]: | N/A 26C P0 35W / 250W | 0MiB / 16384MiB | 0% Default | Sep 12 10:14:43.346158 setup-nvidia[13024]: | | | N/A | Sep 12 10:14:43.346158 setup-nvidia[13024]: +-----------------------------------------+----------------------+----------------------+ Sep 12 10:14:43.346262 setup-nvidia[13024]: Sep 12 10:14:43.346262 setup-nvidia[13024]: +---------------------------------------------------------------------------------------+ Sep 12 10:14:43.346262 setup-nvidia[13024]: | Processes: | Sep 12 10:14:43.346262 setup-nvidia[13024]: | GPU GI CI PID Type Process name GPU Memory | Sep 12 10:14:43.346262 setup-nvidia[13024]: | ID ID Usage | Sep 12 10:14:43.346262 setup-nvidia[13024]: |=======================================================================================| Sep 12 10:14:43.346396 setup-nvidia[13024]: | No running processes found | Sep 12 10:14:43.346396 setup-nvidia[13024]: +---------------------------------------------------------------------------------------+ Sep 12 10:14:43.732738 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 12 10:14:43.737705 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 12 10:14:43.739303 systemd[1]: Startup finished in 1.264s (firmware) + 25.991s (loader) + 1.841s (kernel) + 11.889s (initrd) + 3min 6.266s (userspace) = 3min 47.252s. Sep 12 10:14:50.044211 systemd[1]: Stopping user@0.service - User Manager for UID 0... Sep 12 10:14:50.045041 systemd[2288]: Activating special unit exit.target... Sep 12 10:14:50.045128 systemd[2288]: Stopped target default.target - Main User Target. Sep 12 10:14:50.045168 systemd[2288]: Stopped target basic.target - Basic System. Sep 12 10:14:50.045197 systemd[2288]: Stopped target paths.target - Paths. Sep 12 10:14:50.045223 systemd[2288]: Stopped target sockets.target - Sockets. Sep 12 10:14:50.045250 systemd[2288]: Stopped target timers.target - Timers. Sep 12 10:14:50.045602 systemd[2288]: Closed dbus.socket - D-Bus User Message Bus Socket. Sep 12 10:14:50.045830 systemd[2288]: Removed slice app.slice - User Application Slice. Sep 12 10:14:50.045863 systemd[2288]: Reached target shutdown.target - Shutdown. Sep 12 10:14:50.046020 systemd[2288]: Finished systemd-exit.service - Exit the Session. Sep 12 10:14:50.046060 systemd[2288]: Reached target exit.target - Exit the Session. Sep 12 10:14:50.062917 (sd-pam)[2289]: pam_unix(systemd-user:session): session closed for user root Sep 12 10:14:50.064391 systemd[1]: user@0.service: Deactivated successfully. Sep 12 10:14:50.064752 systemd[1]: Stopped user@0.service - User Manager for UID 0. Sep 12 10:14:50.076128 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Sep 12 10:14:50.084771 systemd[1]: run-user-0.mount: Deactivated successfully. Sep 12 10:14:50.085638 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Sep 12 10:14:50.085849 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Sep 12 10:14:50.087629 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Sep 12 10:14:50.087720 systemd[1]: user-0.slice: Consumed 5min 13.687s CPU time, 891.2M memory peak. Sep 12 10:14:50.088009 systemd-logind[1685]: Removed session c3. Sep 12 10:14:50.783487 systemd[1]: Started sshd@25-10.200.8.18:22-10.200.16.10:46210.service - OpenSSH per-connection server daemon (10.200.16.10:46210). Sep 12 10:14:51.409728 sshd[13035]: Accepted publickey for core from 10.200.16.10 port 46210 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:14:51.411084 sshd-session[13035]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:14:51.415986 systemd-logind[1685]: New session 28 of user core. Sep 12 10:14:51.430983 systemd[1]: Started session-28.scope - Session 28 of User core. Sep 12 10:14:51.855051 sshd[13037]: Connection closed by 10.200.16.10 port 46210 Sep 12 10:14:51.855474 sshd-session[13035]: pam_unix(sshd:session): session closed for user core Sep 12 10:14:51.858705 systemd[1]: sshd@25-10.200.8.18:22-10.200.16.10:46210.service: Deactivated successfully. Sep 12 10:14:51.860361 systemd[1]: session-28.scope: Deactivated successfully. Sep 12 10:14:51.861026 systemd-logind[1685]: Session 28 logged out. Waiting for processes to exit. Sep 12 10:14:51.861865 systemd-logind[1685]: Removed session 28. Sep 12 10:14:51.966488 systemd[1]: Started sshd@26-10.200.8.18:22-10.200.16.10:46218.service - OpenSSH per-connection server daemon (10.200.16.10:46218). Sep 12 10:14:52.591630 sshd[13043]: Accepted publickey for core from 10.200.16.10 port 46218 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:14:52.593027 sshd-session[13043]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:14:52.597171 systemd-logind[1685]: New session 29 of user core. Sep 12 10:14:52.607928 systemd[1]: Started session-29.scope - Session 29 of User core. Sep 12 10:14:53.032974 sshd[13045]: Connection closed by 10.200.16.10 port 46218 Sep 12 10:14:53.033304 sshd-session[13043]: pam_unix(sshd:session): session closed for user core Sep 12 10:14:53.036552 systemd[1]: sshd@26-10.200.8.18:22-10.200.16.10:46218.service: Deactivated successfully. Sep 12 10:14:53.038218 systemd[1]: session-29.scope: Deactivated successfully. Sep 12 10:14:53.038870 systemd-logind[1685]: Session 29 logged out. Waiting for processes to exit. Sep 12 10:14:53.039635 systemd-logind[1685]: Removed session 29. Sep 12 10:14:53.144502 systemd[1]: Started sshd@27-10.200.8.18:22-10.200.16.10:46232.service - OpenSSH per-connection server daemon (10.200.16.10:46232). Sep 12 10:14:53.769755 sshd[13051]: Accepted publickey for core from 10.200.16.10 port 46232 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:14:53.771099 sshd-session[13051]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:14:53.775277 systemd-logind[1685]: New session 30 of user core. Sep 12 10:14:53.788018 systemd[1]: Started session-30.scope - Session 30 of User core. Sep 12 10:14:54.214561 sshd[13053]: Connection closed by 10.200.16.10 port 46232 Sep 12 10:14:54.214933 sshd-session[13051]: pam_unix(sshd:session): session closed for user core Sep 12 10:14:54.218076 systemd[1]: sshd@27-10.200.8.18:22-10.200.16.10:46232.service: Deactivated successfully. Sep 12 10:14:54.219668 systemd[1]: session-30.scope: Deactivated successfully. Sep 12 10:14:54.220348 systemd-logind[1685]: Session 30 logged out. Waiting for processes to exit. Sep 12 10:14:54.221121 systemd-logind[1685]: Removed session 30. Sep 12 10:14:54.330019 systemd[1]: Started sshd@28-10.200.8.18:22-10.200.16.10:46242.service - OpenSSH per-connection server daemon (10.200.16.10:46242). Sep 12 10:14:54.957255 sshd[13059]: Accepted publickey for core from 10.200.16.10 port 46242 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:14:54.958607 sshd-session[13059]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:14:54.963053 systemd-logind[1685]: New session 31 of user core. Sep 12 10:14:54.983910 systemd[1]: Started session-31.scope - Session 31 of User core. Sep 12 10:14:55.302408 sudo[13062]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Sep 12 10:14:55.302761 sudo[13062]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 12 10:14:55.329111 sudo[13062]: pam_unix(sudo:session): session closed for user root Sep 12 10:14:55.429589 sshd[13061]: Connection closed by 10.200.16.10 port 46242 Sep 12 10:14:55.429997 sshd-session[13059]: pam_unix(sshd:session): session closed for user core Sep 12 10:14:55.433258 systemd[1]: sshd@28-10.200.8.18:22-10.200.16.10:46242.service: Deactivated successfully. Sep 12 10:14:55.434939 systemd[1]: session-31.scope: Deactivated successfully. Sep 12 10:14:55.435578 systemd-logind[1685]: Session 31 logged out. Waiting for processes to exit. Sep 12 10:14:55.436399 systemd-logind[1685]: Removed session 31. Sep 12 10:14:55.540144 systemd[1]: Started sshd@29-10.200.8.18:22-10.200.16.10:46250.service - OpenSSH per-connection server daemon (10.200.16.10:46250). Sep 12 10:14:56.166875 sshd[13068]: Accepted publickey for core from 10.200.16.10 port 46250 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:14:56.168227 sshd-session[13068]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:14:56.172340 systemd-logind[1685]: New session 32 of user core. Sep 12 10:14:56.182929 systemd[1]: Started session-32.scope - Session 32 of User core. Sep 12 10:14:56.512656 sudo[13072]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Sep 12 10:14:56.512975 sudo[13072]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 12 10:14:56.516244 sudo[13072]: pam_unix(sudo:session): session closed for user root Sep 12 10:14:56.521680 sudo[13071]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Sep 12 10:14:56.522049 sudo[13071]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 12 10:14:56.538063 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 12 10:14:56.563443 augenrules[13094]: No rules Sep 12 10:14:56.564641 systemd[1]: audit-rules.service: Deactivated successfully. Sep 12 10:14:56.564917 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 12 10:14:56.565647 sudo[13071]: pam_unix(sudo:session): session closed for user root Sep 12 10:14:56.667468 sshd[13070]: Connection closed by 10.200.16.10 port 46250 Sep 12 10:14:56.667834 sshd-session[13068]: pam_unix(sshd:session): session closed for user core Sep 12 10:14:56.670843 systemd[1]: sshd@29-10.200.8.18:22-10.200.16.10:46250.service: Deactivated successfully. Sep 12 10:14:56.672396 systemd[1]: session-32.scope: Deactivated successfully. Sep 12 10:14:56.673072 systemd-logind[1685]: Session 32 logged out. Waiting for processes to exit. Sep 12 10:14:56.673815 systemd-logind[1685]: Removed session 32. Sep 12 10:14:56.779031 systemd[1]: Started sshd@30-10.200.8.18:22-10.200.16.10:46266.service - OpenSSH per-connection server daemon (10.200.16.10:46266). Sep 12 10:14:57.405671 sshd[13103]: Accepted publickey for core from 10.200.16.10 port 46266 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:14:57.406982 sshd-session[13103]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:14:57.411115 systemd-logind[1685]: New session 33 of user core. Sep 12 10:14:57.423954 systemd[1]: Started session-33.scope - Session 33 of User core. Sep 12 10:14:57.751208 sudo[13106]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/mkdir -p . Sep 12 10:14:57.751514 sudo[13106]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 12 10:14:57.754686 sudo[13106]: pam_unix(sudo:session): session closed for user root Sep 12 10:14:57.856273 sshd[13105]: Connection closed by 10.200.16.10 port 46266 Sep 12 10:14:57.856677 sshd-session[13103]: pam_unix(sshd:session): session closed for user core Sep 12 10:14:57.859891 systemd[1]: sshd@30-10.200.8.18:22-10.200.16.10:46266.service: Deactivated successfully. Sep 12 10:14:57.861478 systemd[1]: session-33.scope: Deactivated successfully. Sep 12 10:14:57.862141 systemd-logind[1685]: Session 33 logged out. Waiting for processes to exit. Sep 12 10:14:57.862911 systemd-logind[1685]: Removed session 33. Sep 12 10:14:57.971640 systemd[1]: Started sshd@31-10.200.8.18:22-10.200.16.10:46272.service - OpenSSH per-connection server daemon (10.200.16.10:46272). Sep 12 10:14:58.597303 sshd[13112]: Accepted publickey for core from 10.200.16.10 port 46272 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:14:58.600527 sshd-session[13112]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:14:58.604828 systemd-logind[1685]: New session 34 of user core. Sep 12 10:14:58.616929 systemd[1]: Started session-34.scope - Session 34 of User core. Sep 12 10:14:58.941659 sudo[13115]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/install -m 0755 /dev/stdin kolet Sep 12 10:14:58.941983 sudo[13115]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 12 10:15:02.297923 sudo[13115]: pam_unix(sudo:session): session closed for user root Sep 12 10:15:02.399553 sshd[13114]: Connection closed by 10.200.16.10 port 46272 Sep 12 10:15:02.399881 sshd-session[13112]: pam_unix(sshd:session): session closed for user core Sep 12 10:15:02.403224 systemd[1]: sshd@31-10.200.8.18:22-10.200.16.10:46272.service: Deactivated successfully. Sep 12 10:15:02.404908 systemd[1]: session-34.scope: Deactivated successfully. Sep 12 10:15:02.405553 systemd-logind[1685]: Session 34 logged out. Waiting for processes to exit. Sep 12 10:15:02.406387 systemd-logind[1685]: Removed session 34. Sep 12 10:15:02.510163 systemd[1]: Started sshd@32-10.200.8.18:22-10.200.16.10:45478.service - OpenSSH per-connection server daemon (10.200.16.10:45478). Sep 12 10:15:03.142923 sshd[13121]: Accepted publickey for core from 10.200.16.10 port 45478 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:15:03.144283 sshd-session[13121]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:15:03.148309 systemd-logind[1685]: New session 35 of user core. Sep 12 10:15:03.157925 systemd[1]: Started session-35.scope - Session 35 of User core. Sep 12 10:15:03.676963 sshd[13123]: Connection closed by 10.200.16.10 port 45478 Sep 12 10:15:03.677407 sshd-session[13121]: pam_unix(sshd:session): session closed for user core Sep 12 10:15:03.680707 systemd[1]: sshd@32-10.200.8.18:22-10.200.16.10:45478.service: Deactivated successfully. Sep 12 10:15:03.682361 systemd[1]: session-35.scope: Deactivated successfully. Sep 12 10:15:03.683099 systemd-logind[1685]: Session 35 logged out. Waiting for processes to exit. Sep 12 10:15:03.683915 systemd-logind[1685]: Removed session 35. Sep 12 10:15:03.788434 systemd[1]: Started sshd@33-10.200.8.18:22-10.200.16.10:45488.service - OpenSSH per-connection server daemon (10.200.16.10:45488). Sep 12 10:15:04.415053 sshd[13138]: Accepted publickey for core from 10.200.16.10 port 45488 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:15:04.416387 sshd-session[13138]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:15:04.420612 systemd-logind[1685]: New session 36 of user core. Sep 12 10:15:04.432931 systemd[1]: Started session-36.scope - Session 36 of User core. Sep 12 10:15:05.205038 systemd[1]: Starting docker.service - Docker Application Container Engine... Sep 12 10:15:05.205194 (dockerd)[13161]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPTS, DOCKER_OPT_BIP, DOCKER_OPT_IPMASQ, DOCKER_OPT_MTU Sep 12 10:15:07.361146 dockerd[13161]: time="2025-09-12T10:15:07.361037466Z" level=info msg="Starting up" Sep 12 10:15:07.996626 dockerd[13161]: time="2025-09-12T10:15:07.996583792Z" level=info msg="Loading containers: start." Sep 12 10:15:08.212820 kernel: Initializing XFRM netlink socket Sep 12 10:15:08.293534 systemd-networkd[1543]: docker0: Link UP Sep 12 10:15:08.348224 dockerd[13161]: time="2025-09-12T10:15:08.348184888Z" level=info msg="Loading containers: done." Sep 12 10:15:08.361618 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck1400942861-merged.mount: Deactivated successfully. Sep 12 10:15:08.380189 dockerd[13161]: time="2025-09-12T10:15:08.380148924Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2 Sep 12 10:15:08.380471 dockerd[13161]: time="2025-09-12T10:15:08.380264918Z" level=info msg="Docker daemon" commit=41ca978a0a5400cc24b274137efa9f25517fcc0b containerd-snapshotter=false storage-driver=overlay2 version=27.3.1 Sep 12 10:15:08.380471 dockerd[13161]: time="2025-09-12T10:15:08.380372912Z" level=info msg="Daemon has completed initialization" Sep 12 10:15:08.455019 dockerd[13161]: time="2025-09-12T10:15:08.454950896Z" level=info msg="API listen on /run/docker.sock" Sep 12 10:15:08.455140 systemd[1]: Started docker.service - Docker Application Container Engine. Sep 12 10:15:10.365595 systemd[1]: var-lib-docker-overlay2-77b704abc3b6f6181105b31fec92cc620798219716c3790b6eb000c506d39db2\x2dinit-merged.mount: Deactivated successfully. Sep 12 10:15:10.453804 kernel: docker0: port 1(veth7b6ac46) entered blocking state Sep 12 10:15:10.453919 kernel: docker0: port 1(veth7b6ac46) entered disabled state Sep 12 10:15:10.453943 kernel: veth7b6ac46: entered allmulticast mode Sep 12 10:15:10.458680 kernel: veth7b6ac46: entered promiscuous mode Sep 12 10:15:10.459294 systemd-networkd[1543]: veth7b6ac46: Link UP Sep 12 10:15:11.153241 containerd[1721]: time="2025-09-12T10:15:11.153147690Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 12 10:15:11.153241 containerd[1721]: time="2025-09-12T10:15:11.153210587Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 12 10:15:11.153241 containerd[1721]: time="2025-09-12T10:15:11.153223786Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 12 10:15:11.153716 containerd[1721]: time="2025-09-12T10:15:11.153302082Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 12 10:15:11.197938 systemd[1]: Started docker-34b0697403c05f8f69beebb6c5c4977589e8ca4879c279c1bacb7879c8236d1e.scope - libcontainer container 34b0697403c05f8f69beebb6c5c4977589e8ca4879c279c1bacb7879c8236d1e. Sep 12 10:15:11.254075 kernel: eth0: renamed from veth4b1aaef Sep 12 10:15:11.280376 kernel: docker0: port 1(veth7b6ac46) entered blocking state Sep 12 10:15:11.280430 kernel: docker0: port 1(veth7b6ac46) entered forwarding state Sep 12 10:15:11.280476 systemd-networkd[1543]: veth7b6ac46: Gained carrier Sep 12 10:15:11.280760 systemd-networkd[1543]: docker0: Gained carrier Sep 12 10:15:12.557948 systemd-networkd[1543]: docker0: Gained IPv6LL Sep 12 10:15:12.621880 systemd-networkd[1543]: veth7b6ac46: Gained IPv6LL Sep 12 10:15:14.568303 systemd[1]: docker-34b0697403c05f8f69beebb6c5c4977589e8ca4879c279c1bacb7879c8236d1e.scope: Deactivated successfully. Sep 12 10:15:14.582571 dockerd[13161]: time="2025-09-12T10:15:14.582516308Z" level=info msg="ignoring event" container=34b0697403c05f8f69beebb6c5c4977589e8ca4879c279c1bacb7879c8236d1e module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Sep 12 10:15:14.582907 containerd[1721]: time="2025-09-12T10:15:14.582546007Z" level=info msg="shim disconnected" id=34b0697403c05f8f69beebb6c5c4977589e8ca4879c279c1bacb7879c8236d1e namespace=moby Sep 12 10:15:14.582907 containerd[1721]: time="2025-09-12T10:15:14.582600605Z" level=warning msg="cleaning up after shim disconnected" id=34b0697403c05f8f69beebb6c5c4977589e8ca4879c279c1bacb7879c8236d1e namespace=moby Sep 12 10:15:14.582907 containerd[1721]: time="2025-09-12T10:15:14.582610304Z" level=info msg="cleaning up dead shim" namespace=moby Sep 12 10:15:14.612667 systemd-networkd[1543]: veth7b6ac46: Lost carrier Sep 12 10:15:14.613021 kernel: docker0: port 1(veth7b6ac46) entered disabled state Sep 12 10:15:14.613061 kernel: veth4b1aaef: renamed from eth0 Sep 12 10:15:14.647449 systemd-networkd[1543]: veth7b6ac46: Link DOWN Sep 12 10:15:14.656265 kernel: docker0: port 1(veth7b6ac46) entered disabled state Sep 12 10:15:14.656326 kernel: veth7b6ac46 (unregistering): left allmulticast mode Sep 12 10:15:14.656352 kernel: veth7b6ac46 (unregistering): left promiscuous mode Sep 12 10:15:14.660544 kernel: docker0: port 1(veth7b6ac46) entered disabled state Sep 12 10:15:14.693342 systemd[1]: run-docker-netns-64c8a4a281a2.mount: Deactivated successfully. Sep 12 10:15:14.699597 systemd[1]: var-lib-docker-overlay2-77b704abc3b6f6181105b31fec92cc620798219716c3790b6eb000c506d39db2-merged.mount: Deactivated successfully. Sep 12 10:15:14.855398 sshd[13140]: Connection closed by 10.200.16.10 port 45488 Sep 12 10:15:14.855737 sshd-session[13138]: pam_unix(sshd:session): session closed for user core Sep 12 10:15:14.859194 systemd[1]: sshd@33-10.200.8.18:22-10.200.16.10:45488.service: Deactivated successfully. Sep 12 10:15:14.860836 systemd[1]: session-36.scope: Deactivated successfully. Sep 12 10:15:14.861469 systemd-logind[1685]: Session 36 logged out. Waiting for processes to exit. Sep 12 10:15:14.862263 systemd-logind[1685]: Removed session 36. Sep 12 10:15:14.973465 systemd[1]: Started sshd@34-10.200.8.18:22-10.200.16.10:38252.service - OpenSSH per-connection server daemon (10.200.16.10:38252). Sep 12 10:15:15.599716 sshd[13434]: Accepted publickey for core from 10.200.16.10 port 38252 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:15:15.601023 sshd-session[13434]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:15:15.605135 systemd-logind[1685]: New session 37 of user core. Sep 12 10:15:15.617913 systemd[1]: Started session-37.scope - Session 37 of User core. Sep 12 10:15:15.629987 systemd-networkd[1543]: docker0: Lost carrier Sep 12 10:15:16.008268 systemd[1]: var-lib-docker-overlay2-3d2c901b97a19e4217de8d77920e3c281acfcd8c2a93a095f90760124ddd57bc\x2dinit-merged.mount: Deactivated successfully. Sep 12 10:15:16.097259 kernel: docker0: port 1(vethb0de0a4) entered blocking state Sep 12 10:15:16.097376 kernel: docker0: port 1(vethb0de0a4) entered disabled state Sep 12 10:15:16.100063 kernel: vethb0de0a4: entered allmulticast mode Sep 12 10:15:16.103703 kernel: vethb0de0a4: entered promiscuous mode Sep 12 10:15:16.103782 kernel: docker0: port 1(vethb0de0a4) entered blocking state Sep 12 10:15:16.109929 kernel: docker0: port 1(vethb0de0a4) entered forwarding state Sep 12 10:15:16.110681 systemd-networkd[1543]: vethb0de0a4: Link UP Sep 12 10:15:16.110841 kernel: docker0: port 1(vethb0de0a4) entered disabled state Sep 12 10:15:16.209026 containerd[1721]: time="2025-09-12T10:15:16.208936514Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 12 10:15:16.209026 containerd[1721]: time="2025-09-12T10:15:16.209000011Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 12 10:15:16.209026 containerd[1721]: time="2025-09-12T10:15:16.209012711Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 12 10:15:16.209468 containerd[1721]: time="2025-09-12T10:15:16.209092607Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 12 10:15:16.236923 systemd[1]: Started docker-96c5a7fc28ae173284e25ba532ca133f40911faa4f70a31007d6020310c06b3e.scope - libcontainer container 96c5a7fc28ae173284e25ba532ca133f40911faa4f70a31007d6020310c06b3e. Sep 12 10:15:16.286818 kernel: eth0: renamed from veth1aa2b07 Sep 12 10:15:16.303704 kernel: docker0: port 1(vethb0de0a4) entered blocking state Sep 12 10:15:16.303755 kernel: docker0: port 1(vethb0de0a4) entered forwarding state Sep 12 10:15:16.304085 systemd-networkd[1543]: vethb0de0a4: Gained carrier Sep 12 10:15:16.304357 systemd-networkd[1543]: docker0: Gained carrier Sep 12 10:15:16.343700 systemd[1]: docker-96c5a7fc28ae173284e25ba532ca133f40911faa4f70a31007d6020310c06b3e.scope: Deactivated successfully. Sep 12 10:15:16.366834 dockerd[13161]: time="2025-09-12T10:15:16.366669003Z" level=info msg="ignoring event" container=96c5a7fc28ae173284e25ba532ca133f40911faa4f70a31007d6020310c06b3e module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Sep 12 10:15:16.367177 containerd[1721]: time="2025-09-12T10:15:16.366721101Z" level=info msg="shim disconnected" id=96c5a7fc28ae173284e25ba532ca133f40911faa4f70a31007d6020310c06b3e namespace=moby Sep 12 10:15:16.367177 containerd[1721]: time="2025-09-12T10:15:16.366774899Z" level=warning msg="cleaning up after shim disconnected" id=96c5a7fc28ae173284e25ba532ca133f40911faa4f70a31007d6020310c06b3e namespace=moby Sep 12 10:15:16.367177 containerd[1721]: time="2025-09-12T10:15:16.366784098Z" level=info msg="cleaning up dead shim" namespace=moby Sep 12 10:15:16.400042 systemd-networkd[1543]: vethb0de0a4: Lost carrier Sep 12 10:15:16.400813 kernel: docker0: port 1(vethb0de0a4) entered disabled state Sep 12 10:15:16.400895 kernel: veth1aa2b07: renamed from eth0 Sep 12 10:15:16.430725 systemd-networkd[1543]: vethb0de0a4: Link DOWN Sep 12 10:15:16.437467 kernel: docker0: port 1(vethb0de0a4) entered disabled state Sep 12 10:15:16.437517 kernel: vethb0de0a4 (unregistering): left allmulticast mode Sep 12 10:15:16.440620 kernel: vethb0de0a4 (unregistering): left promiscuous mode Sep 12 10:15:16.443753 kernel: docker0: port 1(vethb0de0a4) entered disabled state Sep 12 10:15:16.597653 sshd[13436]: Connection closed by 10.200.16.10 port 38252 Sep 12 10:15:16.598004 sshd-session[13434]: pam_unix(sshd:session): session closed for user core Sep 12 10:15:16.601400 systemd[1]: sshd@34-10.200.8.18:22-10.200.16.10:38252.service: Deactivated successfully. Sep 12 10:15:16.603065 systemd[1]: session-37.scope: Deactivated successfully. Sep 12 10:15:16.603743 systemd-logind[1685]: Session 37 logged out. Waiting for processes to exit. Sep 12 10:15:16.604512 systemd-logind[1685]: Removed session 37. Sep 12 10:15:16.637984 systemd-networkd[1543]: docker0: Lost carrier Sep 12 10:15:16.711028 systemd[1]: Started sshd@35-10.200.8.18:22-10.200.16.10:38258.service - OpenSSH per-connection server daemon (10.200.16.10:38258). Sep 12 10:15:17.003534 systemd[1]: var-lib-docker-overlay2-3d2c901b97a19e4217de8d77920e3c281acfcd8c2a93a095f90760124ddd57bc-merged.mount: Deactivated successfully. Sep 12 10:15:17.336257 sshd[13544]: Accepted publickey for core from 10.200.16.10 port 38258 ssh2: RSA SHA256:a9s6po40hUuVp4tE/Fzgo9ULhWrxGPx12+51Bw2CKbE Sep 12 10:15:17.337568 sshd-session[13544]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 10:15:17.341638 systemd-logind[1685]: New session 38 of user core. Sep 12 10:15:17.349943 systemd[1]: Started session-38.scope - Session 38 of User core. Sep 12 10:15:17.754219 ntpdate[13556]: ntpdate 4.2.8p17@1.4004-o Fri Sep 12 08:14:40 UTC 2025 (1) Sep 12 10:15:24.491155 ntpdate[13556]: adjust time server 85.91.1.164 offset -0.018183 sec Sep 12 10:15:24.591853 sshd[13546]: Connection closed by 10.200.16.10 port 38258 Sep 12 10:15:24.592252 sshd-session[13544]: pam_unix(sshd:session): session closed for user core Sep 12 10:15:24.595444 systemd[1]: sshd@35-10.200.8.18:22-10.200.16.10:38258.service: Deactivated successfully. Sep 12 10:15:24.597085 systemd[1]: session-38.scope: Deactivated successfully. Sep 12 10:15:24.597732 systemd-logind[1685]: Session 38 logged out. Waiting for processes to exit. Sep 12 10:15:24.598502 systemd-logind[1685]: Removed session 38.