May 27 17:44:13.200094 kernel: Linux version 6.12.30-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.44 p1) 2.44.0) #1 SMP PREEMPT_DYNAMIC Tue May 27 15:32:02 -00 2025 May 27 17:44:13.200122 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=daa3e2d55cc4a7ff0ec15aa9bb0c07df9999cb4e3041f3adad1b1101efdea101 May 27 17:44:13.200132 kernel: BIOS-provided physical RAM map: May 27 17:44:13.200139 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable May 27 17:44:13.200146 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved May 27 17:44:13.200152 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable May 27 17:44:13.200160 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved May 27 17:44:13.200170 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data May 27 17:44:13.200177 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS May 27 17:44:13.200184 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable May 27 17:44:13.200191 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable May 27 17:44:13.200197 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable May 27 17:44:13.200204 kernel: printk: legacy bootconsole [earlyser0] enabled May 27 17:44:13.200211 kernel: NX (Execute Disable) protection: active May 27 17:44:13.200222 kernel: APIC: Static calls initialized May 27 17:44:13.200230 kernel: efi: EFI v2.7 by Microsoft May 27 17:44:13.200238 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 May 27 17:44:13.200245 kernel: random: crng init done May 27 17:44:13.200253 kernel: secureboot: Secure boot disabled May 27 17:44:13.200260 kernel: SMBIOS 3.1.0 present. May 27 17:44:13.200268 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 May 27 17:44:13.200276 kernel: DMI: Memory slots populated: 3/3 May 27 17:44:13.200283 kernel: Hypervisor detected: Microsoft Hyper-V May 27 17:44:13.200291 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 May 27 17:44:13.200301 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 May 27 17:44:13.200308 kernel: Hyper-V: Using hypercall for remote TLB flush May 27 17:44:13.200316 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns May 27 17:44:13.200324 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns May 27 17:44:13.200332 kernel: tsc: Marking TSC unstable due to running on Hyper-V May 27 17:44:13.200340 kernel: tsc: Detected 2593.992 MHz processor May 27 17:44:13.200348 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved May 27 17:44:13.200356 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable May 27 17:44:13.200364 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 May 27 17:44:13.200374 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs May 27 17:44:13.200382 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT May 27 17:44:13.200389 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved May 27 17:44:13.200397 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved May 27 17:44:13.200405 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 May 27 17:44:13.200412 kernel: Using GB pages for direct mapping May 27 17:44:13.200420 kernel: ACPI: Early table checksum verification disabled May 27 17:44:13.200432 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) May 27 17:44:13.200442 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) May 27 17:44:13.200451 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) May 27 17:44:13.200459 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) May 27 17:44:13.200467 kernel: ACPI: FACS 0x000000003FFFE000 000040 May 27 17:44:13.200475 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) May 27 17:44:13.200483 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) May 27 17:44:13.200494 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) May 27 17:44:13.200502 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) May 27 17:44:13.200510 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) May 27 17:44:13.200518 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) May 27 17:44:13.200526 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) May 27 17:44:13.200534 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] May 27 17:44:13.200543 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] May 27 17:44:13.200551 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] May 27 17:44:13.200561 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] May 27 17:44:13.200569 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] May 27 17:44:13.200577 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] May 27 17:44:13.200592 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] May 27 17:44:13.200602 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] May 27 17:44:13.200614 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] May 27 17:44:13.200624 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] May 27 17:44:13.200637 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug May 27 17:44:13.200645 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug May 27 17:44:13.200656 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug May 27 17:44:13.200670 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug May 27 17:44:13.200686 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug May 27 17:44:13.200699 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug May 27 17:44:13.200708 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug May 27 17:44:13.200716 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug May 27 17:44:13.200725 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] May 27 17:44:13.200733 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] May 27 17:44:13.200741 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] May 27 17:44:13.200766 kernel: Zone ranges: May 27 17:44:13.200775 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] May 27 17:44:13.200783 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] May 27 17:44:13.200791 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] May 27 17:44:13.200799 kernel: Device empty May 27 17:44:13.200807 kernel: Movable zone start for each node May 27 17:44:13.200816 kernel: Early memory node ranges May 27 17:44:13.200824 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] May 27 17:44:13.200832 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] May 27 17:44:13.200843 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] May 27 17:44:13.200851 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] May 27 17:44:13.200859 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] May 27 17:44:13.200868 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] May 27 17:44:13.200876 kernel: On node 0, zone DMA: 1 pages in unavailable ranges May 27 17:44:13.200884 kernel: On node 0, zone DMA: 96 pages in unavailable ranges May 27 17:44:13.200892 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges May 27 17:44:13.200900 kernel: ACPI: PM-Timer IO Port: 0x408 May 27 17:44:13.200908 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) May 27 17:44:13.200919 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 May 27 17:44:13.200930 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) May 27 17:44:13.200939 kernel: ACPI: Using ACPI (MADT) for SMP configuration information May 27 17:44:13.200947 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 May 27 17:44:13.200955 kernel: CPU topo: Max. logical packages: 1 May 27 17:44:13.200963 kernel: CPU topo: Max. logical dies: 1 May 27 17:44:13.200971 kernel: CPU topo: Max. dies per package: 1 May 27 17:44:13.200979 kernel: CPU topo: Max. threads per core: 1 May 27 17:44:13.200987 kernel: CPU topo: Num. cores per package: 6 May 27 17:44:13.200998 kernel: CPU topo: Num. threads per package: 6 May 27 17:44:13.201006 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs May 27 17:44:13.201015 kernel: [mem 0x40000000-0xffffffff] available for PCI devices May 27 17:44:13.201023 kernel: Booting paravirtualized kernel on Hyper-V May 27 17:44:13.201034 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns May 27 17:44:13.201042 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 May 27 17:44:13.201050 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 May 27 17:44:13.201059 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 May 27 17:44:13.201067 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - May 27 17:44:13.201077 kernel: Hyper-V: PV spinlocks enabled May 27 17:44:13.201086 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) May 27 17:44:13.201095 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=daa3e2d55cc4a7ff0ec15aa9bb0c07df9999cb4e3041f3adad1b1101efdea101 May 27 17:44:13.201104 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. May 27 17:44:13.201112 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) May 27 17:44:13.201120 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) May 27 17:44:13.201128 kernel: Fallback order for Node 0: 0 May 27 17:44:13.201137 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 May 27 17:44:13.201147 kernel: Policy zone: Normal May 27 17:44:13.201163 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off May 27 17:44:13.201172 kernel: software IO TLB: area num 8. May 27 17:44:13.201183 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 May 27 17:44:13.201192 kernel: Kernel/User page tables isolation: enabled May 27 17:44:13.201201 kernel: ftrace: allocating 40081 entries in 157 pages May 27 17:44:13.201209 kernel: ftrace: allocated 157 pages with 5 groups May 27 17:44:13.201218 kernel: Dynamic Preempt: voluntary May 27 17:44:13.201226 kernel: rcu: Preemptible hierarchical RCU implementation. May 27 17:44:13.201236 kernel: rcu: RCU event tracing is enabled. May 27 17:44:13.201244 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. May 27 17:44:13.201253 kernel: Trampoline variant of Tasks RCU enabled. May 27 17:44:13.201265 kernel: Rude variant of Tasks RCU enabled. May 27 17:44:13.201273 kernel: Tracing variant of Tasks RCU enabled. May 27 17:44:13.201282 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. May 27 17:44:13.201291 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 May 27 17:44:13.201300 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. May 27 17:44:13.201311 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. May 27 17:44:13.201320 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. May 27 17:44:13.201328 kernel: Using NULL legacy PIC May 27 17:44:13.201337 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 May 27 17:44:13.201346 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. May 27 17:44:13.201354 kernel: Console: colour dummy device 80x25 May 27 17:44:13.201363 kernel: printk: legacy console [tty1] enabled May 27 17:44:13.201377 kernel: printk: legacy console [ttyS0] enabled May 27 17:44:13.201390 kernel: printk: legacy bootconsole [earlyser0] disabled May 27 17:44:13.201406 kernel: ACPI: Core revision 20240827 May 27 17:44:13.201420 kernel: Failed to register legacy timer interrupt May 27 17:44:13.201431 kernel: APIC: Switch to symmetric I/O mode setup May 27 17:44:13.201439 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 May 27 17:44:13.201448 kernel: Hyper-V: enabling crash_kexec_post_notifiers May 27 17:44:13.201457 kernel: Hyper-V: Using IPI hypercalls May 27 17:44:13.201465 kernel: APIC: send_IPI() replaced with hv_send_ipi() May 27 17:44:13.201474 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() May 27 17:44:13.201483 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() May 27 17:44:13.201494 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() May 27 17:44:13.201503 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() May 27 17:44:13.201512 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() May 27 17:44:13.201520 kernel: Hyper-V: Using enlightened APIC (xapic mode) May 27 17:44:13.201529 kernel: APIC: eoi() replaced with hv_apic_eoi_write() May 27 17:44:13.201538 kernel: APIC: read() replaced with hv_apic_read() May 27 17:44:13.201546 kernel: APIC: write() replaced with hv_apic_write() May 27 17:44:13.201555 kernel: APIC: icr_write() replaced with hv_apic_icr_write() May 27 17:44:13.201564 kernel: APIC: icr_read() replaced with hv_apic_icr_read() May 27 17:44:13.201575 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593992) May 27 17:44:13.201584 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 May 27 17:44:13.201599 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 May 27 17:44:13.201613 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization May 27 17:44:13.201622 kernel: Spectre V2 : Mitigation: Retpolines May 27 17:44:13.201631 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT May 27 17:44:13.201639 kernel: Speculative Store Bypass: Vulnerable May 27 17:44:13.201648 kernel: MDS: Mitigation: Clear CPU buffers May 27 17:44:13.201656 kernel: TAA: Mitigation: Clear CPU buffers May 27 17:44:13.201665 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode May 27 17:44:13.201680 kernel: ITS: Mitigation: Aligned branch/return thunks May 27 17:44:13.201688 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' May 27 17:44:13.201697 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' May 27 17:44:13.201705 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' May 27 17:44:13.201714 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 May 27 17:44:13.201723 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. May 27 17:44:13.201731 kernel: Freeing SMP alternatives memory: 32K May 27 17:44:13.201740 kernel: pid_max: default: 32768 minimum: 301 May 27 17:44:13.201801 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima May 27 17:44:13.201811 kernel: landlock: Up and running. May 27 17:44:13.201819 kernel: SELinux: Initializing. May 27 17:44:13.201831 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) May 27 17:44:13.201840 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) May 27 17:44:13.201849 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) May 27 17:44:13.201857 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. May 27 17:44:13.201866 kernel: signal: max sigframe size: 1776 May 27 17:44:13.201875 kernel: rcu: Hierarchical SRCU implementation. May 27 17:44:13.201884 kernel: rcu: Max phase no-delay instances is 400. May 27 17:44:13.201893 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level May 27 17:44:13.201901 kernel: NMI watchdog: Perf NMI watchdog permanently disabled May 27 17:44:13.201910 kernel: smp: Bringing up secondary CPUs ... May 27 17:44:13.201921 kernel: smpboot: x86: Booting SMP configuration: May 27 17:44:13.201930 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 May 27 17:44:13.201938 kernel: smp: Brought up 1 node, 6 CPUs May 27 17:44:13.201947 kernel: smpboot: Total of 6 processors activated (31127.90 BogoMIPS) May 27 17:44:13.201956 kernel: Memory: 115339084K/117439364K available (14336K kernel code, 2430K rwdata, 9952K rodata, 54416K init, 2552K bss, 2090004K reserved, 0K cma-reserved) May 27 17:44:13.201965 kernel: devtmpfs: initialized May 27 17:44:13.201974 kernel: x86/mm: Memory block size: 1024MB May 27 17:44:13.201982 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) May 27 17:44:13.201991 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns May 27 17:44:13.202002 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) May 27 17:44:13.202011 kernel: pinctrl core: initialized pinctrl subsystem May 27 17:44:13.202019 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family May 27 17:44:13.202028 kernel: audit: initializing netlink subsys (disabled) May 27 17:44:13.202037 kernel: audit: type=2000 audit(1748367849.031:1): state=initialized audit_enabled=0 res=1 May 27 17:44:13.202045 kernel: thermal_sys: Registered thermal governor 'step_wise' May 27 17:44:13.202054 kernel: thermal_sys: Registered thermal governor 'user_space' May 27 17:44:13.202063 kernel: cpuidle: using governor menu May 27 17:44:13.202071 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 May 27 17:44:13.202082 kernel: dca service started, version 1.12.1 May 27 17:44:13.202091 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] May 27 17:44:13.202100 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. May 27 17:44:13.202108 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages May 27 17:44:13.202117 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page May 27 17:44:13.202126 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages May 27 17:44:13.202134 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page May 27 17:44:13.202143 kernel: ACPI: Added _OSI(Module Device) May 27 17:44:13.202151 kernel: ACPI: Added _OSI(Processor Device) May 27 17:44:13.202162 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) May 27 17:44:13.202171 kernel: ACPI: Added _OSI(Processor Aggregator Device) May 27 17:44:13.202180 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded May 27 17:44:13.202188 kernel: ACPI: Interpreter enabled May 27 17:44:13.202197 kernel: ACPI: PM: (supports S0 S5) May 27 17:44:13.202205 kernel: ACPI: Using IOAPIC for interrupt routing May 27 17:44:13.202214 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug May 27 17:44:13.202223 kernel: PCI: Ignoring E820 reservations for host bridge windows May 27 17:44:13.202232 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F May 27 17:44:13.202243 kernel: iommu: Default domain type: Translated May 27 17:44:13.202251 kernel: iommu: DMA domain TLB invalidation policy: lazy mode May 27 17:44:13.202260 kernel: efivars: Registered efivars operations May 27 17:44:13.202268 kernel: PCI: Using ACPI for IRQ routing May 27 17:44:13.202277 kernel: PCI: System does not support PCI May 27 17:44:13.202286 kernel: vgaarb: loaded May 27 17:44:13.202294 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page May 27 17:44:13.202303 kernel: VFS: Disk quotas dquot_6.6.0 May 27 17:44:13.202312 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) May 27 17:44:13.202323 kernel: pnp: PnP ACPI init May 27 17:44:13.202331 kernel: pnp: PnP ACPI: found 3 devices May 27 17:44:13.202340 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns May 27 17:44:13.202349 kernel: NET: Registered PF_INET protocol family May 27 17:44:13.202358 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) May 27 17:44:13.202366 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) May 27 17:44:13.202375 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) May 27 17:44:13.202384 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) May 27 17:44:13.202395 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) May 27 17:44:13.202404 kernel: TCP: Hash tables configured (established 524288 bind 65536) May 27 17:44:13.202412 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) May 27 17:44:13.202421 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) May 27 17:44:13.202430 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family May 27 17:44:13.202438 kernel: NET: Registered PF_XDP protocol family May 27 17:44:13.202447 kernel: PCI: CLS 0 bytes, default 64 May 27 17:44:13.202456 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) May 27 17:44:13.202465 kernel: software IO TLB: mapped [mem 0x000000003ae61000-0x000000003ee61000] (64MB) May 27 17:44:13.202476 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer May 27 17:44:13.202484 kernel: Initialise system trusted keyrings May 27 17:44:13.202493 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 May 27 17:44:13.202501 kernel: Key type asymmetric registered May 27 17:44:13.202510 kernel: Asymmetric key parser 'x509' registered May 27 17:44:13.202518 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) May 27 17:44:13.202527 kernel: io scheduler mq-deadline registered May 27 17:44:13.202536 kernel: io scheduler kyber registered May 27 17:44:13.202544 kernel: io scheduler bfq registered May 27 17:44:13.202553 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 May 27 17:44:13.202564 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled May 27 17:44:13.202573 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A May 27 17:44:13.202581 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A May 27 17:44:13.202590 kernel: i8042: PNP: No PS/2 controller found. May 27 17:44:13.202757 kernel: rtc_cmos 00:02: registered as rtc0 May 27 17:44:13.202874 kernel: rtc_cmos 00:02: setting system clock to 2025-05-27T17:44:12 UTC (1748367852) May 27 17:44:13.202972 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram May 27 17:44:13.202989 kernel: intel_pstate: CPU model not supported May 27 17:44:13.202999 kernel: efifb: probing for efifb May 27 17:44:13.203007 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k May 27 17:44:13.203016 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 May 27 17:44:13.203025 kernel: efifb: scrolling: redraw May 27 17:44:13.203033 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 May 27 17:44:13.203042 kernel: Console: switching to colour frame buffer device 128x48 May 27 17:44:13.203051 kernel: fb0: EFI VGA frame buffer device May 27 17:44:13.203059 kernel: pstore: Using crash dump compression: deflate May 27 17:44:13.203071 kernel: pstore: Registered efi_pstore as persistent store backend May 27 17:44:13.203079 kernel: NET: Registered PF_INET6 protocol family May 27 17:44:13.203088 kernel: Segment Routing with IPv6 May 27 17:44:13.203097 kernel: In-situ OAM (IOAM) with IPv6 May 27 17:44:13.203105 kernel: NET: Registered PF_PACKET protocol family May 27 17:44:13.203114 kernel: Key type dns_resolver registered May 27 17:44:13.203122 kernel: IPI shorthand broadcast: enabled May 27 17:44:13.203131 kernel: sched_clock: Marking stable (3696005100, 106349900)->(4131500300, -329145300) May 27 17:44:13.203140 kernel: registered taskstats version 1 May 27 17:44:13.203151 kernel: Loading compiled-in X.509 certificates May 27 17:44:13.203160 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.30-flatcar: 9507e5c390e18536b38d58c90da64baf0ac9837c' May 27 17:44:13.203168 kernel: Demotion targets for Node 0: null May 27 17:44:13.203177 kernel: Key type .fscrypt registered May 27 17:44:13.203185 kernel: Key type fscrypt-provisioning registered May 27 17:44:13.203194 kernel: ima: No TPM chip found, activating TPM-bypass! May 27 17:44:13.203202 kernel: ima: Allocated hash algorithm: sha1 May 27 17:44:13.203211 kernel: ima: No architecture policies found May 27 17:44:13.203220 kernel: clk: Disabling unused clocks May 27 17:44:13.203231 kernel: Warning: unable to open an initial console. May 27 17:44:13.203239 kernel: Freeing unused kernel image (initmem) memory: 54416K May 27 17:44:13.203248 kernel: Write protecting the kernel read-only data: 24576k May 27 17:44:13.203257 kernel: Freeing unused kernel image (rodata/data gap) memory: 288K May 27 17:44:13.203266 kernel: Run /init as init process May 27 17:44:13.203274 kernel: with arguments: May 27 17:44:13.203283 kernel: /init May 27 17:44:13.203291 kernel: with environment: May 27 17:44:13.203300 kernel: HOME=/ May 27 17:44:13.203310 kernel: TERM=linux May 27 17:44:13.203319 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a May 27 17:44:13.203329 systemd[1]: Successfully made /usr/ read-only. May 27 17:44:13.203340 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) May 27 17:44:13.203350 systemd[1]: Detected virtualization microsoft. May 27 17:44:13.203359 systemd[1]: Detected architecture x86-64. May 27 17:44:13.203368 systemd[1]: Running in initrd. May 27 17:44:13.203379 systemd[1]: No hostname configured, using default hostname. May 27 17:44:13.203389 systemd[1]: Hostname set to . May 27 17:44:13.203398 systemd[1]: Initializing machine ID from random generator. May 27 17:44:13.203407 systemd[1]: Queued start job for default target initrd.target. May 27 17:44:13.203416 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 27 17:44:13.203425 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 27 17:44:13.203435 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... May 27 17:44:13.203444 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 27 17:44:13.203456 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... May 27 17:44:13.203466 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... May 27 17:44:13.203476 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... May 27 17:44:13.203485 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... May 27 17:44:13.203495 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 27 17:44:13.203504 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 27 17:44:13.203513 systemd[1]: Reached target paths.target - Path Units. May 27 17:44:13.203525 systemd[1]: Reached target slices.target - Slice Units. May 27 17:44:13.203534 systemd[1]: Reached target swap.target - Swaps. May 27 17:44:13.203543 systemd[1]: Reached target timers.target - Timer Units. May 27 17:44:13.203552 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. May 27 17:44:13.203561 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 27 17:44:13.203571 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). May 27 17:44:13.203580 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. May 27 17:44:13.203589 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 27 17:44:13.203598 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 27 17:44:13.203610 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 27 17:44:13.203619 systemd[1]: Reached target sockets.target - Socket Units. May 27 17:44:13.203628 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... May 27 17:44:13.203637 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 27 17:44:13.203646 systemd[1]: Finished network-cleanup.service - Network Cleanup. May 27 17:44:13.203656 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). May 27 17:44:13.203665 systemd[1]: Starting systemd-fsck-usr.service... May 27 17:44:13.203674 systemd[1]: Starting systemd-journald.service - Journal Service... May 27 17:44:13.203686 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 27 17:44:13.203695 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 27 17:44:13.203723 systemd-journald[231]: Collecting audit messages is disabled. May 27 17:44:13.203759 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. May 27 17:44:13.203775 systemd-journald[231]: Journal started May 27 17:44:13.203798 systemd-journald[231]: Runtime Journal (/run/log/journal/4d595e57872149b897764d6ee1e5cca1) is 8M, max 2.2G, 2.1G free. May 27 17:44:13.201637 systemd-modules-load[233]: Inserted module 'overlay' May 27 17:44:13.212656 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 27 17:44:13.213067 systemd[1]: Started systemd-journald.service - Journal Service. May 27 17:44:13.213208 systemd[1]: Finished systemd-fsck-usr.service. May 27 17:44:13.215443 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 27 17:44:13.216706 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 27 17:44:13.257455 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 27 17:44:13.261521 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 27 17:44:13.285015 systemd-tmpfiles[245]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. May 27 17:44:13.292374 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. May 27 17:44:13.285994 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 27 17:44:13.292654 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 27 17:44:13.294372 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 27 17:44:13.328483 systemd-modules-load[233]: Inserted module 'br_netfilter' May 27 17:44:13.331967 kernel: Bridge firewalling registered May 27 17:44:13.334193 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 27 17:44:13.336263 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 27 17:44:13.347635 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... May 27 17:44:13.368315 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 27 17:44:13.370078 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 27 17:44:13.389141 dracut-cmdline[267]: Using kernel command line parameters: rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1 rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=daa3e2d55cc4a7ff0ec15aa9bb0c07df9999cb4e3041f3adad1b1101efdea101 May 27 17:44:13.391390 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 27 17:44:13.423153 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 27 17:44:13.467105 systemd-resolved[298]: Positive Trust Anchors: May 27 17:44:13.467122 systemd-resolved[298]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 27 17:44:13.467152 systemd-resolved[298]: 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 May 27 17:44:13.469937 systemd-resolved[298]: Defaulting to hostname 'linux'. May 27 17:44:13.471004 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 27 17:44:13.480025 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 27 17:44:13.560768 kernel: SCSI subsystem initialized May 27 17:44:13.571861 kernel: Loading iSCSI transport class v2.0-870. May 27 17:44:13.583767 kernel: iscsi: registered transport (tcp) May 27 17:44:13.608325 kernel: iscsi: registered transport (qla4xxx) May 27 17:44:13.608361 kernel: QLogic iSCSI HBA Driver May 27 17:44:13.627026 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 27 17:44:13.652038 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 27 17:44:13.654518 systemd[1]: Reached target network-pre.target - Preparation for Network. May 27 17:44:13.707217 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. May 27 17:44:13.709907 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... May 27 17:44:13.780764 kernel: raid6: avx2x4 gen() 30643 MB/s May 27 17:44:13.799767 kernel: raid6: avx2x2 gen() 30890 MB/s May 27 17:44:13.820761 kernel: raid6: avx2x1 gen() 27994 MB/s May 27 17:44:13.820792 kernel: raid6: using algorithm avx2x2 gen() 30890 MB/s May 27 17:44:13.843804 kernel: raid6: .... xor() 18489 MB/s, rmw enabled May 27 17:44:13.843830 kernel: raid6: using avx2x2 recovery algorithm May 27 17:44:13.866769 kernel: xor: automatically using best checksumming function avx May 27 17:44:14.034772 kernel: Btrfs loaded, zoned=no, fsverity=no May 27 17:44:14.041021 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. May 27 17:44:14.043929 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 27 17:44:14.083906 systemd-udevd[486]: Using default interface naming scheme 'v255'. May 27 17:44:14.090054 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 27 17:44:14.092406 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... May 27 17:44:14.129825 dracut-pre-trigger[493]: rd.md=0: removing MD RAID activation May 27 17:44:14.159999 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. May 27 17:44:14.163190 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 27 17:44:14.240358 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 27 17:44:14.257497 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... May 27 17:44:14.271769 kernel: cryptd: max_cpu_qlen set to 1000 May 27 17:44:14.277770 kernel: hv_vmbus: Vmbus version:4.0 May 27 17:44:14.283776 kernel: AES CTR mode by8 optimization enabled May 27 17:44:14.306221 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 27 17:44:14.323923 kernel: pps_core: LinuxPPS API ver. 1 registered May 27 17:44:14.323947 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti May 27 17:44:14.323960 kernel: hv_vmbus: registering driver hyperv_keyboard May 27 17:44:14.323987 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 May 27 17:44:14.306396 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 27 17:44:14.332372 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... May 27 17:44:14.346542 kernel: hid: raw HID events driver (C) Jiri Kosina May 27 17:44:14.346564 kernel: PTP clock support registered May 27 17:44:14.340998 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 27 17:44:14.355419 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. May 27 17:44:15.217142 kernel: hv_vmbus: registering driver hv_pci May 27 17:44:15.217170 kernel: hv_vmbus: registering driver hv_storvsc May 27 17:44:15.217183 kernel: hv_vmbus: registering driver hv_netvsc May 27 17:44:15.217203 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 May 27 17:44:15.217378 kernel: scsi host0: storvsc_host_t May 27 17:44:15.217526 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 May 27 17:44:15.217666 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 May 27 17:44:15.217826 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] May 27 17:44:15.217970 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 May 27 17:44:15.218119 kernel: scsi host1: storvsc_host_t May 27 17:44:15.218249 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] May 27 17:44:15.218369 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] May 27 17:44:15.218485 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint May 27 17:44:15.218643 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] May 27 17:44:15.218851 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] May 27 17:44:15.218998 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] May 27 17:44:15.219127 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 May 27 17:44:15.219274 kernel: pci 0001:00:00.0: Enabling HDA controller May 27 17:44:15.219404 kernel: hv_utils: Registering HyperV Utility Driver May 27 17:44:15.219423 kernel: hv_vmbus: registering driver hid_hyperv May 27 17:44:15.219438 kernel: hv_vmbus: registering driver hv_utils May 27 17:44:15.219450 kernel: hv_utils: Heartbeat IC version 3.0 May 27 17:44:15.219461 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 May 27 17:44:15.219594 kernel: hv_utils: Shutdown IC version 3.2 May 27 17:44:15.219609 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned May 27 17:44:15.219746 kernel: hv_utils: TimeSync IC version 4.0 May 27 17:44:15.219760 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned May 27 17:44:15.219890 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 May 27 17:44:15.219903 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned May 27 17:44:15.220030 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on May 27 17:44:15.177794 systemd-resolved[298]: Clock change detected. Flushing caches. May 27 17:44:15.233883 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) May 27 17:44:15.234092 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) May 27 17:44:15.234236 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks May 27 17:44:15.234371 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks May 27 17:44:15.234502 kernel: sd 0:0:0:1: [sdb] Write Protect is off May 27 17:44:15.242723 kernel: sd 0:0:0:0: [sda] Write Protect is off May 27 17:44:15.242888 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00 May 27 17:44:15.243029 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 May 27 17:44:15.243162 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA May 27 17:44:15.245606 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA May 27 17:44:15.247597 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#380 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 May 27 17:44:15.270595 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#389 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 May 27 17:44:15.270748 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#8 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 May 27 17:44:15.276719 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 27 17:44:15.290536 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#394 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 May 27 17:44:15.297692 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 May 27 17:44:15.297720 kernel: sd 0:0:0:0: [sda] Attached SCSI disk May 27 17:44:15.305137 kernel: sdb: sdb1 May 27 17:44:15.305166 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk May 27 17:44:15.311172 kernel: sr 0:0:0:2: [sr0] scsi-1 drive May 27 17:44:15.311378 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 May 27 17:44:15.313731 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 May 27 17:44:15.816746 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. May 27 17:44:15.848900 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. May 27 17:44:15.885212 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. May 27 17:44:15.886776 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. May 27 17:44:15.900301 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... May 27 17:44:15.933600 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#257 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 May 27 17:44:15.935049 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. May 27 17:44:15.956595 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 May 27 17:44:15.966596 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#494 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 May 27 17:44:15.983605 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 May 27 17:44:16.900450 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. May 27 17:44:16.905286 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. May 27 17:44:16.913887 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 27 17:44:16.918612 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 27 17:44:16.928444 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... May 27 17:44:16.962033 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. May 27 17:44:16.989950 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#445 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 May 27 17:44:17.015528 disk-uuid[724]: The operation has completed successfully. May 27 17:44:17.019160 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 May 27 17:44:17.066921 systemd[1]: disk-uuid.service: Deactivated successfully. May 27 17:44:17.067041 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. May 27 17:44:17.126451 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... May 27 17:44:17.158262 sh[896]: Success May 27 17:44:17.194853 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. May 27 17:44:17.194889 kernel: device-mapper: uevent: version 1.0.3 May 27 17:44:17.199801 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev May 27 17:44:17.211597 kernel: device-mapper: verity: sha256 using shash "sha256-avx2" May 27 17:44:17.409978 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. May 27 17:44:17.417968 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... May 27 17:44:17.431008 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. May 27 17:44:17.451055 kernel: BTRFS info: 'norecovery' is for compatibility only, recommended to use 'rescue=nologreplay' May 27 17:44:17.451087 kernel: BTRFS: device fsid 7caef027-0915-4c01-a3d5-28eff70f7ebd devid 1 transid 39 /dev/mapper/usr (254:0) scanned by mount (914) May 27 17:44:17.456528 kernel: BTRFS info (device dm-0): first mount of filesystem 7caef027-0915-4c01-a3d5-28eff70f7ebd May 27 17:44:17.460636 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm May 27 17:44:17.463879 kernel: BTRFS info (device dm-0): using free-space-tree May 27 17:44:18.100711 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. May 27 17:44:18.107984 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. May 27 17:44:18.116786 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. May 27 17:44:18.117590 systemd[1]: Starting ignition-setup.service - Ignition (setup)... May 27 17:44:18.152831 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... May 27 17:44:18.181602 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/sda6 (8:6) scanned by mount (947) May 27 17:44:18.190990 kernel: BTRFS info (device sda6): first mount of filesystem be856aed-e34b-4b7b-be8a-0716b27db212 May 27 17:44:18.191020 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm May 27 17:44:18.194230 kernel: BTRFS info (device sda6): using free-space-tree May 27 17:44:18.235604 kernel: BTRFS info (device sda6): last unmount of filesystem be856aed-e34b-4b7b-be8a-0716b27db212 May 27 17:44:18.236086 systemd[1]: Finished ignition-setup.service - Ignition (setup). May 27 17:44:18.238545 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... May 27 17:44:18.262347 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 27 17:44:18.265306 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 27 17:44:18.324172 systemd-networkd[1083]: lo: Link UP May 27 17:44:18.324183 systemd-networkd[1083]: lo: Gained carrier May 27 17:44:18.325211 systemd-networkd[1083]: Enumeration completed May 27 17:44:18.325311 systemd[1]: Started systemd-networkd.service - Network Configuration. May 27 17:44:18.325591 systemd-networkd[1083]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 27 17:44:18.325595 systemd-networkd[1083]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 27 17:44:18.326372 systemd-networkd[1083]: eth0: Link UP May 27 17:44:18.326533 systemd-networkd[1083]: eth0: Gained carrier May 27 17:44:18.326543 systemd-networkd[1083]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 27 17:44:18.327250 systemd[1]: Reached target network.target - Network. May 27 17:44:18.393614 systemd-networkd[1083]: eth0: DHCPv4 address 10.200.8.12/24, gateway 10.200.8.1 acquired from 168.63.129.16 May 27 17:44:20.019629 ignition[1076]: Ignition 2.21.0 May 27 17:44:20.020167 ignition[1076]: Stage: fetch-offline May 27 17:44:20.020308 ignition[1076]: no configs at "/usr/lib/ignition/base.d" May 27 17:44:20.020319 ignition[1076]: no config dir at "/usr/lib/ignition/base.platform.d/azure" May 27 17:44:20.020435 ignition[1076]: parsed url from cmdline: "" May 27 17:44:20.020441 ignition[1076]: no config URL provided May 27 17:44:20.020450 ignition[1076]: reading system config file "/usr/lib/ignition/user.ign" May 27 17:44:20.020460 ignition[1076]: no config at "/usr/lib/ignition/user.ign" May 27 17:44:20.020466 ignition[1076]: failed to fetch config: resource requires networking May 27 17:44:20.020696 ignition[1076]: Ignition finished successfully May 27 17:44:20.049453 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). May 27 17:44:20.061246 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... May 27 17:44:20.105218 ignition[1097]: Ignition 2.21.0 May 27 17:44:20.105243 ignition[1097]: Stage: fetch May 27 17:44:20.105472 ignition[1097]: no configs at "/usr/lib/ignition/base.d" May 27 17:44:20.105485 ignition[1097]: no config dir at "/usr/lib/ignition/base.platform.d/azure" May 27 17:44:20.105615 ignition[1097]: parsed url from cmdline: "" May 27 17:44:20.105620 ignition[1097]: no config URL provided May 27 17:44:20.105626 ignition[1097]: reading system config file "/usr/lib/ignition/user.ign" May 27 17:44:20.105636 ignition[1097]: no config at "/usr/lib/ignition/user.ign" May 27 17:44:20.105668 ignition[1097]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 May 27 17:44:20.164703 systemd-networkd[1083]: eth0: Gained IPv6LL May 27 17:44:20.184053 ignition[1097]: GET result: OK May 27 17:44:20.184098 ignition[1097]: config has been read from IMDS userdata May 27 17:44:20.184109 ignition[1097]: parsing config with SHA512: 9c65872c6a5cd3f0753009dd9cc3780db24afb22c1500e20cf608ef5ccaeaab40916e500d898f6af8bdf11a03f9aae15e2617953dde0812d83b5fb4d34cae634 May 27 17:44:20.187688 unknown[1097]: fetched base config from "system" May 27 17:44:20.187896 ignition[1097]: fetch: fetch complete May 27 17:44:20.187696 unknown[1097]: fetched base config from "system" May 27 17:44:20.187902 ignition[1097]: fetch: fetch passed May 27 17:44:20.187701 unknown[1097]: fetched user config from "azure" May 27 17:44:20.187946 ignition[1097]: Ignition finished successfully May 27 17:44:20.195735 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). May 27 17:44:20.205262 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... May 27 17:44:20.263045 ignition[1107]: Ignition 2.21.0 May 27 17:44:20.263058 ignition[1107]: Stage: kargs May 27 17:44:20.263304 ignition[1107]: no configs at "/usr/lib/ignition/base.d" May 27 17:44:20.263316 ignition[1107]: no config dir at "/usr/lib/ignition/base.platform.d/azure" May 27 17:44:20.275437 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). May 27 17:44:20.264085 ignition[1107]: kargs: kargs passed May 27 17:44:20.277985 systemd[1]: Starting ignition-disks.service - Ignition (disks)... May 27 17:44:20.264132 ignition[1107]: Ignition finished successfully May 27 17:44:20.316973 ignition[1117]: Ignition 2.21.0 May 27 17:44:20.316987 ignition[1117]: Stage: disks May 27 17:44:20.317239 ignition[1117]: no configs at "/usr/lib/ignition/base.d" May 27 17:44:20.317250 ignition[1117]: no config dir at "/usr/lib/ignition/base.platform.d/azure" May 27 17:44:20.322817 systemd[1]: Finished ignition-disks.service - Ignition (disks). May 27 17:44:20.318385 ignition[1117]: disks: disks passed May 27 17:44:20.328988 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. May 27 17:44:20.318528 ignition[1117]: Ignition finished successfully May 27 17:44:20.330916 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. May 27 17:44:20.342125 systemd[1]: Reached target local-fs.target - Local File Systems. May 27 17:44:20.349305 systemd[1]: Reached target sysinit.target - System Initialization. May 27 17:44:20.357215 systemd[1]: Reached target basic.target - Basic System. May 27 17:44:20.370786 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... May 27 17:44:20.508127 systemd-fsck[1129]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks May 27 17:44:20.513460 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. May 27 17:44:20.524593 systemd[1]: Mounting sysroot.mount - /sysroot... May 27 17:44:20.750601 kernel: EXT4-fs (sda9): mounted filesystem bf93e767-f532-4480-b210-a196f7ac181e r/w with ordered data mode. Quota mode: none. May 27 17:44:20.751362 systemd[1]: Mounted sysroot.mount - /sysroot. May 27 17:44:20.753345 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. May 27 17:44:20.765916 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 27 17:44:20.795448 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... May 27 17:44:20.808296 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... May 27 17:44:20.821495 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/sda6 (8:6) scanned by mount (1143) May 27 17:44:20.821520 kernel: BTRFS info (device sda6): first mount of filesystem be856aed-e34b-4b7b-be8a-0716b27db212 May 27 17:44:20.821533 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm May 27 17:44:20.821545 kernel: BTRFS info (device sda6): using free-space-tree May 27 17:44:20.821476 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). May 27 17:44:20.821508 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. May 27 17:44:20.830688 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. May 27 17:44:20.856130 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... May 27 17:44:20.867558 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 27 17:44:21.619441 coreos-metadata[1145]: May 27 17:44:21.619 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 May 27 17:44:21.625404 coreos-metadata[1145]: May 27 17:44:21.622 INFO Fetch successful May 27 17:44:21.625404 coreos-metadata[1145]: May 27 17:44:21.622 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 May 27 17:44:21.637940 coreos-metadata[1145]: May 27 17:44:21.630 INFO Fetch successful May 27 17:44:21.644212 coreos-metadata[1145]: May 27 17:44:21.644 INFO wrote hostname ci-4344.0.0-a-3ca9c4afc0 to /sysroot/etc/hostname May 27 17:44:21.651555 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. May 27 17:44:21.821138 initrd-setup-root[1173]: cut: /sysroot/etc/passwd: No such file or directory May 27 17:44:21.870428 initrd-setup-root[1180]: cut: /sysroot/etc/group: No such file or directory May 27 17:44:21.898779 initrd-setup-root[1187]: cut: /sysroot/etc/shadow: No such file or directory May 27 17:44:21.909762 initrd-setup-root[1194]: cut: /sysroot/etc/gshadow: No such file or directory May 27 17:44:23.013429 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. May 27 17:44:23.023453 systemd[1]: Starting ignition-mount.service - Ignition (mount)... May 27 17:44:23.025725 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... May 27 17:44:23.058755 systemd[1]: sysroot-oem.mount: Deactivated successfully. May 27 17:44:23.067111 kernel: BTRFS info (device sda6): last unmount of filesystem be856aed-e34b-4b7b-be8a-0716b27db212 May 27 17:44:23.078735 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. May 27 17:44:23.095094 ignition[1263]: INFO : Ignition 2.21.0 May 27 17:44:23.095094 ignition[1263]: INFO : Stage: mount May 27 17:44:23.099548 ignition[1263]: INFO : no configs at "/usr/lib/ignition/base.d" May 27 17:44:23.099548 ignition[1263]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" May 27 17:44:23.099548 ignition[1263]: INFO : mount: mount passed May 27 17:44:23.099548 ignition[1263]: INFO : Ignition finished successfully May 27 17:44:23.099387 systemd[1]: Finished ignition-mount.service - Ignition (mount). May 27 17:44:23.105441 systemd[1]: Starting ignition-files.service - Ignition (files)... May 27 17:44:23.138032 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 27 17:44:23.160815 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/sda6 (8:6) scanned by mount (1276) May 27 17:44:23.160849 kernel: BTRFS info (device sda6): first mount of filesystem be856aed-e34b-4b7b-be8a-0716b27db212 May 27 17:44:23.164974 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm May 27 17:44:23.168126 kernel: BTRFS info (device sda6): using free-space-tree May 27 17:44:23.172137 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 27 17:44:23.214611 ignition[1293]: INFO : Ignition 2.21.0 May 27 17:44:23.214611 ignition[1293]: INFO : Stage: files May 27 17:44:23.220258 ignition[1293]: INFO : no configs at "/usr/lib/ignition/base.d" May 27 17:44:23.220258 ignition[1293]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" May 27 17:44:23.220258 ignition[1293]: DEBUG : files: compiled without relabeling support, skipping May 27 17:44:23.254918 ignition[1293]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" May 27 17:44:23.254918 ignition[1293]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" May 27 17:44:23.283052 ignition[1293]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" May 27 17:44:23.288512 ignition[1293]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" May 27 17:44:23.294093 unknown[1293]: wrote ssh authorized keys file for user: core May 27 17:44:23.297909 ignition[1293]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" May 27 17:44:23.310747 ignition[1293]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" May 27 17:44:23.318135 ignition[1293]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" May 27 17:44:23.318135 ignition[1293]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" May 27 17:44:23.318135 ignition[1293]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" May 27 17:44:23.318135 ignition[1293]: INFO : files: files passed May 27 17:44:23.318135 ignition[1293]: INFO : Ignition finished successfully May 27 17:44:23.334088 systemd[1]: Finished ignition-files.service - Ignition (files). May 27 17:44:23.335267 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... May 27 17:44:23.364118 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... May 27 17:44:23.374785 systemd[1]: ignition-quench.service: Deactivated successfully. May 27 17:44:23.374885 systemd[1]: Finished ignition-quench.service - Ignition (record completion). May 27 17:44:23.397707 initrd-setup-root-after-ignition[1326]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 27 17:44:23.397707 initrd-setup-root-after-ignition[1326]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory May 27 17:44:23.409808 initrd-setup-root-after-ignition[1330]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 27 17:44:23.416538 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. May 27 17:44:23.418174 systemd[1]: Reached target ignition-complete.target - Ignition Complete. May 27 17:44:23.428290 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... May 27 17:44:23.467325 systemd[1]: initrd-parse-etc.service: Deactivated successfully. May 27 17:44:23.467430 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. May 27 17:44:23.481800 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. May 27 17:44:23.490913 systemd[1]: Reached target initrd.target - Initrd Default Target. May 27 17:44:23.499195 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. May 27 17:44:23.499966 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... May 27 17:44:23.520769 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 27 17:44:23.523912 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... May 27 17:44:23.570601 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. May 27 17:44:23.571692 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. May 27 17:44:23.583974 systemd[1]: Stopped target timers.target - Timer Units. May 27 17:44:23.591737 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. May 27 17:44:23.591860 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 27 17:44:23.603099 systemd[1]: Stopped target initrd.target - Initrd Default Target. May 27 17:44:23.611423 systemd[1]: Stopped target basic.target - Basic System. May 27 17:44:23.618662 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. May 27 17:44:23.626241 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. May 27 17:44:23.628215 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. May 27 17:44:23.639454 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. May 27 17:44:23.651624 systemd[1]: Stopped target remote-fs.target - Remote File Systems. May 27 17:44:23.653916 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. May 27 17:44:23.672927 systemd[1]: Stopped target sysinit.target - System Initialization. May 27 17:44:23.674514 systemd[1]: Stopped target local-fs.target - Local File Systems. May 27 17:44:23.681535 systemd[1]: Stopped target swap.target - Swaps. May 27 17:44:23.691298 systemd[1]: dracut-pre-mount.service: Deactivated successfully. May 27 17:44:23.691417 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. May 27 17:44:23.701742 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. May 27 17:44:23.709862 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 27 17:44:23.718405 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. May 27 17:44:23.719093 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 27 17:44:23.727452 systemd[1]: dracut-initqueue.service: Deactivated successfully. May 27 17:44:23.727571 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. May 27 17:44:23.740378 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. May 27 17:44:23.740505 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. May 27 17:44:23.748674 systemd[1]: ignition-files.service: Deactivated successfully. May 27 17:44:23.748792 systemd[1]: Stopped ignition-files.service - Ignition (files). May 27 17:44:23.756368 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. May 27 17:44:23.756486 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. May 27 17:44:23.766151 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... May 27 17:44:23.789866 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... May 27 17:44:23.796303 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. May 27 17:44:23.815777 ignition[1351]: INFO : Ignition 2.21.0 May 27 17:44:23.815777 ignition[1351]: INFO : Stage: umount May 27 17:44:23.815777 ignition[1351]: INFO : no configs at "/usr/lib/ignition/base.d" May 27 17:44:23.815777 ignition[1351]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" May 27 17:44:23.815777 ignition[1351]: INFO : umount: umount passed May 27 17:44:23.815777 ignition[1351]: INFO : Ignition finished successfully May 27 17:44:23.796435 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. May 27 17:44:23.806971 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. May 27 17:44:23.807266 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. May 27 17:44:23.818789 systemd[1]: sysroot-boot.mount: Deactivated successfully. May 27 17:44:23.819638 systemd[1]: ignition-mount.service: Deactivated successfully. May 27 17:44:23.819737 systemd[1]: Stopped ignition-mount.service - Ignition (mount). May 27 17:44:23.824253 systemd[1]: ignition-disks.service: Deactivated successfully. May 27 17:44:23.824366 systemd[1]: Stopped ignition-disks.service - Ignition (disks). May 27 17:44:23.831591 systemd[1]: ignition-kargs.service: Deactivated successfully. May 27 17:44:23.831641 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). May 27 17:44:23.839359 systemd[1]: ignition-fetch.service: Deactivated successfully. May 27 17:44:23.839403 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). May 27 17:44:23.849911 systemd[1]: Stopped target network.target - Network. May 27 17:44:23.851259 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. May 27 17:44:23.851360 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). May 27 17:44:23.859240 systemd[1]: Stopped target paths.target - Path Units. May 27 17:44:23.868604 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. May 27 17:44:23.873625 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 27 17:44:23.876273 systemd[1]: Stopped target slices.target - Slice Units. May 27 17:44:23.885604 systemd[1]: Stopped target sockets.target - Socket Units. May 27 17:44:23.892531 systemd[1]: iscsid.socket: Deactivated successfully. May 27 17:44:23.892590 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. May 27 17:44:23.902780 systemd[1]: iscsiuio.socket: Deactivated successfully. May 27 17:44:23.902828 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 27 17:44:23.910356 systemd[1]: ignition-setup.service: Deactivated successfully. May 27 17:44:23.910408 systemd[1]: Stopped ignition-setup.service - Ignition (setup). May 27 17:44:23.917830 systemd[1]: ignition-setup-pre.service: Deactivated successfully. May 27 17:44:23.917875 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. May 27 17:44:23.923254 systemd[1]: Stopping systemd-networkd.service - Network Configuration... May 27 17:44:23.930500 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... May 27 17:44:23.941789 systemd[1]: initrd-cleanup.service: Deactivated successfully. May 27 17:44:23.941879 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. May 27 17:44:23.950427 systemd[1]: sysroot-boot.service: Deactivated successfully. May 27 17:44:23.950515 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. May 27 17:44:23.957785 systemd[1]: systemd-networkd.service: Deactivated successfully. May 27 17:44:23.957885 systemd[1]: Stopped systemd-networkd.service - Network Configuration. May 27 17:44:23.965855 systemd[1]: systemd-resolved.service: Deactivated successfully. May 27 17:44:23.965949 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. May 27 17:44:23.980920 systemd[1]: Stopped target network-pre.target - Preparation for Network. May 27 17:44:23.990936 systemd[1]: systemd-networkd.socket: Deactivated successfully. May 27 17:44:23.990980 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. May 27 17:44:24.002045 systemd[1]: initrd-setup-root.service: Deactivated successfully. May 27 17:44:24.002096 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. May 27 17:44:24.010282 systemd[1]: Stopping network-cleanup.service - Network Cleanup... May 27 17:44:24.016263 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. May 27 17:44:24.016319 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 27 17:44:24.025246 systemd[1]: systemd-sysctl.service: Deactivated successfully. May 27 17:44:24.025293 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. May 27 17:44:24.034911 systemd[1]: systemd-modules-load.service: Deactivated successfully. May 27 17:44:24.034961 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. May 27 17:44:24.041543 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. May 27 17:44:24.041606 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. May 27 17:44:24.052316 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... May 27 17:44:24.055624 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. May 27 17:44:24.055686 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. May 27 17:44:24.055727 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. May 27 17:44:24.055768 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. May 27 17:44:24.090240 systemd[1]: systemd-udevd.service: Deactivated successfully. May 27 17:44:24.090443 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. May 27 17:44:24.093157 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. May 27 17:44:24.093206 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. May 27 17:44:24.101656 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. May 27 17:44:24.101691 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. May 27 17:44:24.108634 systemd[1]: dracut-pre-udev.service: Deactivated successfully. May 27 17:44:24.108689 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. May 27 17:44:24.123376 systemd[1]: dracut-cmdline.service: Deactivated successfully. May 27 17:44:24.123549 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. May 27 17:44:24.135167 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 27 17:44:24.135221 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 27 17:44:24.148655 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... May 27 17:44:24.155757 systemd[1]: systemd-network-generator.service: Deactivated successfully. May 27 17:44:24.155811 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. May 27 17:44:24.165548 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. May 27 17:44:24.165611 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 27 17:44:24.174044 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. May 27 17:44:24.340689 systemd-journald[231]: Received SIGTERM from PID 1 (systemd). May 27 17:44:24.174089 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 27 17:44:24.183076 systemd[1]: kmod-static-nodes.service: Deactivated successfully. May 27 17:44:24.183121 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. May 27 17:44:24.192384 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 27 17:44:24.192429 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 27 17:44:24.202926 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. May 27 17:44:24.202980 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev\x2dearly.service.mount: Deactivated successfully. May 27 17:44:24.203018 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. May 27 17:44:24.203058 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. May 27 17:44:24.203378 systemd[1]: network-cleanup.service: Deactivated successfully. May 27 17:44:24.203469 systemd[1]: Stopped network-cleanup.service - Network Cleanup. May 27 17:44:24.206712 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. May 27 17:44:24.206802 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. May 27 17:44:24.218014 systemd[1]: Reached target initrd-switch-root.target - Switch Root. May 27 17:44:24.226239 systemd[1]: Starting initrd-switch-root.service - Switch Root... May 27 17:44:24.259346 systemd[1]: Switching root. May 27 17:44:24.346933 systemd-journald[231]: Journal stopped May 27 17:44:29.474068 kernel: SELinux: policy capability network_peer_controls=1 May 27 17:44:29.474098 kernel: SELinux: policy capability open_perms=1 May 27 17:44:29.474114 kernel: SELinux: policy capability extended_socket_class=1 May 27 17:44:29.474123 kernel: SELinux: policy capability always_check_network=0 May 27 17:44:29.474132 kernel: SELinux: policy capability cgroup_seclabel=1 May 27 17:44:29.474141 kernel: SELinux: policy capability nnp_nosuid_transition=1 May 27 17:44:29.474151 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 May 27 17:44:29.474163 kernel: SELinux: policy capability ioctl_skip_cloexec=0 May 27 17:44:29.474172 kernel: SELinux: policy capability userspace_initial_context=0 May 27 17:44:29.474181 kernel: audit: type=1403 audit(1748367865.139:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 May 27 17:44:29.474193 systemd[1]: Successfully loaded SELinux policy in 234.406ms. May 27 17:44:29.474205 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 15.382ms. May 27 17:44:29.474216 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) May 27 17:44:29.474227 systemd[1]: Detected virtualization microsoft. May 27 17:44:29.474240 systemd[1]: Detected architecture x86-64. May 27 17:44:29.474250 systemd[1]: Detected first boot. May 27 17:44:29.474261 systemd[1]: Hostname set to . May 27 17:44:29.474272 systemd[1]: Initializing machine ID from random generator. May 27 17:44:29.474283 zram_generator::config[1396]: No configuration found. May 27 17:44:29.474296 kernel: Guest personality initialized and is inactive May 27 17:44:29.474306 kernel: VMCI host device registered (name=vmci, major=10, minor=124) May 27 17:44:29.474316 kernel: Initialized host personality May 27 17:44:29.474325 kernel: NET: Registered PF_VSOCK protocol family May 27 17:44:29.474339 systemd[1]: Populated /etc with preset unit settings. May 27 17:44:29.474350 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. May 27 17:44:29.474361 systemd[1]: initrd-switch-root.service: Deactivated successfully. May 27 17:44:29.474374 systemd[1]: Stopped initrd-switch-root.service - Switch Root. May 27 17:44:29.474384 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. May 27 17:44:29.474395 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. May 27 17:44:29.474406 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. May 27 17:44:29.474417 systemd[1]: Created slice system-getty.slice - Slice /system/getty. May 27 17:44:29.474428 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. May 27 17:44:29.474439 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. May 27 17:44:29.474449 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. May 27 17:44:29.474463 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. May 27 17:44:29.474473 systemd[1]: Created slice user.slice - User and Session Slice. May 27 17:44:29.474484 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 27 17:44:29.474494 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 27 17:44:29.474505 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. May 27 17:44:29.474516 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. May 27 17:44:29.474530 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. May 27 17:44:29.474542 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 27 17:44:29.474555 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... May 27 17:44:29.474566 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 27 17:44:29.474589 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 27 17:44:29.474601 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. May 27 17:44:29.474612 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. May 27 17:44:29.474623 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. May 27 17:44:29.474691 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. May 27 17:44:29.474716 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 27 17:44:29.476358 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 27 17:44:29.476376 systemd[1]: Reached target slices.target - Slice Units. May 27 17:44:29.476388 systemd[1]: Reached target swap.target - Swaps. May 27 17:44:29.476399 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. May 27 17:44:29.476410 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. May 27 17:44:29.476428 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. May 27 17:44:29.476439 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 27 17:44:29.476451 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 27 17:44:29.476462 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 27 17:44:29.476473 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. May 27 17:44:29.476484 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... May 27 17:44:29.476495 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... May 27 17:44:29.476509 systemd[1]: Mounting media.mount - External Media Directory... May 27 17:44:29.476520 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 27 17:44:29.476532 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... May 27 17:44:29.476543 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... May 27 17:44:29.476554 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... May 27 17:44:29.476566 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). May 27 17:44:29.476592 systemd[1]: Reached target machines.target - Containers. May 27 17:44:29.476606 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... May 27 17:44:29.476618 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 27 17:44:29.476633 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 27 17:44:29.476644 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... May 27 17:44:29.476655 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 27 17:44:29.476667 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 27 17:44:29.476678 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 27 17:44:29.476689 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... May 27 17:44:29.476704 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 27 17:44:29.476715 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). May 27 17:44:29.476730 systemd[1]: systemd-fsck-root.service: Deactivated successfully. May 27 17:44:29.476741 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. May 27 17:44:29.476752 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. May 27 17:44:29.476764 systemd[1]: Stopped systemd-fsck-usr.service. May 27 17:44:29.476775 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). May 27 17:44:29.476787 systemd[1]: Starting systemd-journald.service - Journal Service... May 27 17:44:29.476798 kernel: loop: module loaded May 27 17:44:29.476808 kernel: fuse: init (API version 7.41) May 27 17:44:29.476821 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 27 17:44:29.476860 systemd-journald[1503]: Collecting audit messages is disabled. May 27 17:44:29.476886 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 27 17:44:29.476899 systemd-journald[1503]: Journal started May 27 17:44:29.476925 systemd-journald[1503]: Runtime Journal (/run/log/journal/ce456404c9064aa9814ee288b30b70ca) is 8M, max 2.2G, 2.1G free. May 27 17:44:28.698888 systemd[1]: Queued start job for default target multi-user.target. May 27 17:44:28.723940 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. May 27 17:44:28.724418 systemd[1]: systemd-journald.service: Deactivated successfully. May 27 17:44:29.489623 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... May 27 17:44:29.499605 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... May 27 17:44:29.499644 kernel: ACPI: bus type drm_connector registered May 27 17:44:29.514276 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 27 17:44:29.523306 systemd[1]: verity-setup.service: Deactivated successfully. May 27 17:44:29.523337 systemd[1]: Stopped verity-setup.service. May 27 17:44:29.542613 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 27 17:44:29.549885 systemd[1]: Started systemd-journald.service - Journal Service. May 27 17:44:29.553962 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. May 27 17:44:29.558251 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. May 27 17:44:29.562692 systemd[1]: Mounted media.mount - External Media Directory. May 27 17:44:29.566666 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. May 27 17:44:29.571091 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. May 27 17:44:29.575601 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. May 27 17:44:29.579735 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. May 27 17:44:29.584785 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 27 17:44:29.590042 systemd[1]: modprobe@configfs.service: Deactivated successfully. May 27 17:44:29.590239 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. May 27 17:44:29.595352 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 27 17:44:29.595543 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 27 17:44:29.600281 systemd[1]: modprobe@drm.service: Deactivated successfully. May 27 17:44:29.600467 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 27 17:44:29.604877 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 27 17:44:29.605061 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 27 17:44:29.610093 systemd[1]: modprobe@fuse.service: Deactivated successfully. May 27 17:44:29.610276 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. May 27 17:44:29.614842 systemd[1]: modprobe@loop.service: Deactivated successfully. May 27 17:44:29.615023 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 27 17:44:29.619640 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 27 17:44:29.624327 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 27 17:44:29.629399 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. May 27 17:44:29.634643 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. May 27 17:44:29.639890 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 27 17:44:29.655272 systemd[1]: Reached target network-pre.target - Preparation for Network. May 27 17:44:29.661106 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... May 27 17:44:29.674782 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... May 27 17:44:29.679195 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). May 27 17:44:29.679232 systemd[1]: Reached target local-fs.target - Local File Systems. May 27 17:44:29.684267 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. May 27 17:44:29.690083 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... May 27 17:44:29.694043 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 27 17:44:29.695287 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... May 27 17:44:29.700402 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... May 27 17:44:29.704917 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 27 17:44:29.705844 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... May 27 17:44:29.709960 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 27 17:44:29.710922 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 27 17:44:29.715699 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... May 27 17:44:29.721016 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 27 17:44:29.723164 systemd-journald[1503]: Time spent on flushing to /var/log/journal/ce456404c9064aa9814ee288b30b70ca is 14.809ms for 950 entries. May 27 17:44:29.723164 systemd-journald[1503]: System Journal (/var/log/journal/ce456404c9064aa9814ee288b30b70ca) is 8M, max 2.6G, 2.6G free. May 27 17:44:29.787611 systemd-journald[1503]: Received client request to flush runtime journal. May 27 17:44:29.730947 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. May 27 17:44:29.732524 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. May 27 17:44:29.744433 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. May 27 17:44:29.749495 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. May 27 17:44:29.755189 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... May 27 17:44:29.788611 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. May 27 17:44:29.792710 kernel: loop0: detected capacity change from 0 to 146240 May 27 17:44:29.801798 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 27 17:44:29.842612 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. May 27 17:44:29.843474 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. May 27 17:44:29.883372 systemd-tmpfiles[1540]: ACLs are not supported, ignoring. May 27 17:44:29.883392 systemd-tmpfiles[1540]: ACLs are not supported, ignoring. May 27 17:44:29.889006 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 27 17:44:29.896005 systemd[1]: Starting systemd-sysusers.service - Create System Users... May 27 17:44:29.994030 systemd[1]: Finished systemd-sysusers.service - Create System Users. May 27 17:44:29.999862 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 27 17:44:30.039634 systemd-tmpfiles[1556]: ACLs are not supported, ignoring. May 27 17:44:30.039655 systemd-tmpfiles[1556]: ACLs are not supported, ignoring. May 27 17:44:30.044306 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 27 17:44:30.518597 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher May 27 17:44:30.572609 kernel: loop1: detected capacity change from 0 to 28496 May 27 17:44:31.023232 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. May 27 17:44:31.029748 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 27 17:44:31.089599 systemd-udevd[1563]: Using default interface naming scheme 'v255'. May 27 17:44:31.137610 kernel: loop2: detected capacity change from 0 to 113872 May 27 17:44:31.309501 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 27 17:44:31.317445 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 27 17:44:31.368538 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. May 27 17:44:31.416732 kernel: hv_vmbus: registering driver hyperv_fb May 27 17:44:31.430600 kernel: hyperv_fb: Synthvid Version major 3, minor 5 May 27 17:44:31.438121 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 May 27 17:44:31.438170 kernel: Console: switching to colour dummy device 80x25 May 27 17:44:31.439601 kernel: Console: switching to colour frame buffer device 128x48 May 27 17:44:31.460357 systemd[1]: Starting systemd-userdbd.service - User Database Manager... May 27 17:44:31.470603 kernel: mousedev: PS/2 mouse device common for all mice May 27 17:44:31.473062 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 27 17:44:31.474605 kernel: hv_vmbus: registering driver hv_balloon May 27 17:44:31.474657 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 May 27 17:44:31.484217 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 27 17:44:31.484444 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 27 17:44:31.495516 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 27 17:44:31.534604 kernel: EDAC MC: Ver: 3.0.0 May 27 17:44:31.534825 systemd[1]: Started systemd-userdbd.service - User Database Manager. May 27 17:44:31.677607 systemd-networkd[1569]: lo: Link UP May 27 17:44:31.677618 systemd-networkd[1569]: lo: Gained carrier May 27 17:44:31.678937 systemd-networkd[1569]: Enumeration completed May 27 17:44:31.679037 systemd[1]: Started systemd-networkd.service - Network Configuration. May 27 17:44:31.679309 systemd-networkd[1569]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 27 17:44:31.679319 systemd-networkd[1569]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 27 17:44:31.679867 systemd-networkd[1569]: eth0: Link UP May 27 17:44:31.679876 systemd-networkd[1569]: eth0: Gained carrier May 27 17:44:31.679890 systemd-networkd[1569]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 27 17:44:31.681601 kernel: loop3: detected capacity change from 0 to 146240 May 27 17:44:31.683728 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... May 27 17:44:31.684807 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... May 27 17:44:31.712600 kernel: loop4: detected capacity change from 0 to 28496 May 27 17:44:31.722604 kernel: loop5: detected capacity change from 0 to 113872 May 27 17:44:31.734570 (sd-merge)[1649]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. May 27 17:44:31.735415 (sd-merge)[1649]: Merged extensions into '/usr'. May 27 17:44:31.739827 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. May 27 17:44:31.743709 systemd[1]: Starting ensure-sysext.service... May 27 17:44:31.747293 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 27 17:44:31.747758 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. May 27 17:44:31.764764 systemd-networkd[1569]: eth0: DHCPv4 address 10.200.8.12/24, gateway 10.200.8.1 acquired from 168.63.129.16 May 27 17:44:31.784837 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. May 27 17:44:31.791083 systemd[1]: Reload requested from client PID 1696 ('systemctl') (unit ensure-sysext.service)... May 27 17:44:31.791098 systemd[1]: Reloading... May 27 17:44:31.801540 systemd-tmpfiles[1700]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. May 27 17:44:31.801594 systemd-tmpfiles[1700]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. May 27 17:44:31.801899 systemd-tmpfiles[1700]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. May 27 17:44:31.802183 systemd-tmpfiles[1700]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. May 27 17:44:31.803073 systemd-tmpfiles[1700]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. May 27 17:44:31.803360 systemd-tmpfiles[1700]: ACLs are not supported, ignoring. May 27 17:44:31.803428 systemd-tmpfiles[1700]: ACLs are not supported, ignoring. May 27 17:44:31.837632 zram_generator::config[1751]: No configuration found. May 27 17:44:31.838687 systemd-tmpfiles[1700]: Detected autofs mount point /boot during canonicalization of boot. May 27 17:44:31.838701 systemd-tmpfiles[1700]: Skipping /boot May 27 17:44:31.850489 systemd-tmpfiles[1700]: Detected autofs mount point /boot during canonicalization of boot. May 27 17:44:31.850503 systemd-tmpfiles[1700]: Skipping /boot May 27 17:44:31.947284 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. May 27 17:44:32.102388 systemd[1]: Reloading finished in 310 ms. May 27 17:44:32.133128 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 27 17:44:32.159960 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 27 17:44:32.180541 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 27 17:44:32.181847 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 27 17:44:32.199426 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... May 27 17:44:32.204362 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 27 17:44:32.205454 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 27 17:44:32.211046 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 27 17:44:32.216892 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 27 17:44:32.221010 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 27 17:44:32.222043 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... May 27 17:44:32.226688 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). May 27 17:44:32.228364 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... May 27 17:44:32.234861 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 27 17:44:32.239992 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... May 27 17:44:32.244995 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 27 17:44:32.247409 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 27 17:44:32.247618 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 27 17:44:32.252336 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 27 17:44:32.252526 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 27 17:44:32.257748 systemd[1]: modprobe@loop.service: Deactivated successfully. May 27 17:44:32.257933 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 27 17:44:32.268758 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 27 17:44:32.268998 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 27 17:44:32.277663 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 27 17:44:32.282664 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 27 17:44:32.288353 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 27 17:44:32.292377 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 27 17:44:32.292517 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). May 27 17:44:32.292978 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 27 17:44:32.296292 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. May 27 17:44:32.298179 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 27 17:44:32.298360 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 27 17:44:32.298856 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 27 17:44:32.299028 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 27 17:44:32.299547 systemd[1]: modprobe@loop.service: Deactivated successfully. May 27 17:44:32.299728 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 27 17:44:32.306380 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 27 17:44:32.307252 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 27 17:44:32.308539 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 27 17:44:32.310084 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 27 17:44:32.311990 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 27 17:44:32.314023 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 27 17:44:32.314676 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 27 17:44:32.314794 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). May 27 17:44:32.314960 systemd[1]: Reached target time-set.target - System Time Set. May 27 17:44:32.316039 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 27 17:44:32.317636 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 27 17:44:32.318041 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 27 17:44:32.321109 systemd[1]: modprobe@drm.service: Deactivated successfully. May 27 17:44:32.321274 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 27 17:44:32.323098 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 27 17:44:32.323276 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 27 17:44:32.324377 systemd[1]: modprobe@loop.service: Deactivated successfully. May 27 17:44:32.324549 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 27 17:44:32.327572 systemd[1]: Finished ensure-sysext.service. May 27 17:44:32.333510 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 27 17:44:32.333568 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 27 17:44:32.378971 augenrules[1863]: No rules May 27 17:44:32.380315 systemd[1]: audit-rules.service: Deactivated successfully. May 27 17:44:32.380569 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 27 17:44:32.385820 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. May 27 17:44:32.401297 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. May 27 17:44:32.444381 systemd-resolved[1822]: Positive Trust Anchors: May 27 17:44:32.444397 systemd-resolved[1822]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 27 17:44:32.444429 systemd-resolved[1822]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test May 27 17:44:32.448562 systemd-resolved[1822]: Using system hostname 'ci-4344.0.0-a-3ca9c4afc0'. May 27 17:44:32.450615 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 27 17:44:32.455074 systemd[1]: Reached target network.target - Network. May 27 17:44:32.458609 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 27 17:44:32.835343 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. May 27 17:44:32.840918 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). May 27 17:44:33.732732 systemd-networkd[1569]: eth0: Gained IPv6LL May 27 17:44:33.735090 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. May 27 17:44:33.740628 systemd[1]: Reached target network-online.target - Network is Online. May 27 17:44:37.148169 ldconfig[1533]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. May 27 17:44:37.160182 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. May 27 17:44:37.166939 systemd[1]: Starting systemd-update-done.service - Update is Completed... May 27 17:44:37.203975 systemd[1]: Finished systemd-update-done.service - Update is Completed. May 27 17:44:37.208837 systemd[1]: Reached target sysinit.target - System Initialization. May 27 17:44:37.213439 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. May 27 17:44:37.218691 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. May 27 17:44:37.224032 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. May 27 17:44:37.228931 systemd[1]: Started logrotate.timer - Daily rotation of log files. May 27 17:44:37.233493 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. May 27 17:44:37.238797 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. May 27 17:44:37.244040 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). May 27 17:44:37.244088 systemd[1]: Reached target paths.target - Path Units. May 27 17:44:37.247588 systemd[1]: Reached target timers.target - Timer Units. May 27 17:44:37.252192 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. May 27 17:44:37.257863 systemd[1]: Starting docker.socket - Docker Socket for the API... May 27 17:44:37.264383 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). May 27 17:44:37.269422 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). May 27 17:44:37.274403 systemd[1]: Reached target ssh-access.target - SSH Access Available. May 27 17:44:37.285248 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. May 27 17:44:37.289645 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. May 27 17:44:37.294882 systemd[1]: Listening on docker.socket - Docker Socket for the API. May 27 17:44:37.300035 systemd[1]: Reached target sockets.target - Socket Units. May 27 17:44:37.303838 systemd[1]: Reached target basic.target - Basic System. May 27 17:44:37.307668 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. May 27 17:44:37.307707 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. May 27 17:44:37.310025 systemd[1]: Starting chronyd.service - NTP client/server... May 27 17:44:37.314249 systemd[1]: Starting containerd.service - containerd container runtime... May 27 17:44:37.330481 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... May 27 17:44:37.335320 systemd[1]: Starting dbus.service - D-Bus System Message Bus... May 27 17:44:37.339756 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... May 27 17:44:37.345170 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... May 27 17:44:37.350138 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... May 27 17:44:37.354098 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). May 27 17:44:37.354201 jq[1886]: false May 27 17:44:37.355129 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... May 27 17:44:37.359440 systemd[1]: hv_fcopy_uio_daemon.service - Hyper-V FCOPY UIO daemon was skipped because of an unmet condition check (ConditionPathExists=/sys/bus/vmbus/devices/eb765408-105f-49b6-b4aa-c123b64d17d4/uio). May 27 17:44:37.360468 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. May 27 17:44:37.364645 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). May 27 17:44:37.365704 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... May 27 17:44:37.367240 KVP[1890]: KVP starting; pid is:1890 May 27 17:44:37.369618 KVP[1890]: KVP LIC Version: 3.1 May 27 17:44:37.371601 kernel: hv_utils: KVP IC version 4.0 May 27 17:44:37.372388 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... May 27 17:44:37.376963 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... May 27 17:44:37.383071 (chronyd)[1878]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS May 27 17:44:37.383228 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... May 27 17:44:37.394041 systemd[1]: Starting systemd-logind.service - User Login Management... May 27 17:44:37.399397 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). May 27 17:44:37.400256 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. May 27 17:44:37.400444 chronyd[1905]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) May 27 17:44:37.400873 systemd[1]: Starting update-engine.service - Update Engine... May 27 17:44:37.403053 extend-filesystems[1887]: Found loop3 May 27 17:44:37.403053 extend-filesystems[1887]: Found loop4 May 27 17:44:37.403053 extend-filesystems[1887]: Found loop5 May 27 17:44:37.403053 extend-filesystems[1887]: Found sda May 27 17:44:37.403053 extend-filesystems[1887]: Found sda1 May 27 17:44:37.403053 extend-filesystems[1887]: Found sda2 May 27 17:44:37.403053 extend-filesystems[1887]: Found sda3 May 27 17:44:37.403053 extend-filesystems[1887]: Found usr May 27 17:44:37.403053 extend-filesystems[1887]: Found sda4 May 27 17:44:37.403053 extend-filesystems[1887]: Found sda6 May 27 17:44:37.403053 extend-filesystems[1887]: Found sda7 May 27 17:44:37.403053 extend-filesystems[1887]: Found sda9 May 27 17:44:37.403053 extend-filesystems[1887]: Checking size of /dev/sda9 May 27 17:44:37.471807 extend-filesystems[1887]: Old size kept for /dev/sda9 May 27 17:44:37.471807 extend-filesystems[1887]: Found sdb May 27 17:44:37.471807 extend-filesystems[1887]: Found sdb1 May 27 17:44:37.471807 extend-filesystems[1887]: Found sr0 May 27 17:44:37.403331 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... May 27 17:44:37.412978 oslogin_cache_refresh[1888]: Refreshing passwd entry cache May 27 17:44:37.486111 google_oslogin_nss_cache[1888]: oslogin_cache_refresh[1888]: Refreshing passwd entry cache May 27 17:44:37.486111 google_oslogin_nss_cache[1888]: oslogin_cache_refresh[1888]: Failure getting users, quitting May 27 17:44:37.486111 google_oslogin_nss_cache[1888]: oslogin_cache_refresh[1888]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. May 27 17:44:37.486111 google_oslogin_nss_cache[1888]: oslogin_cache_refresh[1888]: Refreshing group entry cache May 27 17:44:37.486111 google_oslogin_nss_cache[1888]: oslogin_cache_refresh[1888]: Failure getting groups, quitting May 27 17:44:37.486111 google_oslogin_nss_cache[1888]: oslogin_cache_refresh[1888]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. May 27 17:44:37.418188 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. May 27 17:44:37.431085 oslogin_cache_refresh[1888]: Failure getting users, quitting May 27 17:44:37.486501 jq[1909]: true May 27 17:44:37.424978 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. May 27 17:44:37.431101 oslogin_cache_refresh[1888]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. May 27 17:44:37.425225 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. May 27 17:44:37.431140 oslogin_cache_refresh[1888]: Refreshing group entry cache May 27 17:44:37.425507 systemd[1]: motdgen.service: Deactivated successfully. May 27 17:44:37.435824 oslogin_cache_refresh[1888]: Failure getting groups, quitting May 27 17:44:37.425797 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. May 27 17:44:37.435835 oslogin_cache_refresh[1888]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. May 27 17:44:37.487412 jq[1915]: true May 27 17:44:37.431281 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. May 27 17:44:37.439307 chronyd[1905]: Timezone right/UTC failed leap second check, ignoring May 27 17:44:37.431477 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. May 27 17:44:37.439499 chronyd[1905]: Loaded seccomp filter (level 2) May 27 17:44:37.438827 systemd[1]: google-oslogin-cache.service: Deactivated successfully. May 27 17:44:37.439025 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. May 27 17:44:37.450048 (ntainerd)[1916]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR May 27 17:44:37.451979 systemd[1]: Started chronyd.service - NTP client/server. May 27 17:44:37.455614 systemd[1]: extend-filesystems.service: Deactivated successfully. May 27 17:44:37.455890 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. May 27 17:44:37.520294 update_engine[1906]: I20250527 17:44:37.519990 1906 main.cc:92] Flatcar Update Engine starting May 27 17:44:37.543684 systemd-logind[1899]: New seat seat0. May 27 17:44:37.550144 systemd-logind[1899]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) May 27 17:44:37.550527 systemd[1]: Started systemd-logind.service - User Login Management. May 27 17:44:37.601272 bash[1943]: Updated "/home/core/.ssh/authorized_keys" May 27 17:44:37.602858 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. May 27 17:44:37.608489 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. May 27 17:44:37.628927 dbus-daemon[1881]: [system] SELinux support is enabled May 27 17:44:37.629093 systemd[1]: Started dbus.service - D-Bus System Message Bus. May 27 17:44:37.631665 update_engine[1906]: I20250527 17:44:37.631608 1906 update_check_scheduler.cc:74] Next update check in 10m51s May 27 17:44:37.635420 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). May 27 17:44:37.635454 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. May 27 17:44:37.636304 dbus-daemon[1881]: [system] Successfully activated service 'org.freedesktop.systemd1' May 27 17:44:37.641519 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). May 27 17:44:37.641548 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. May 27 17:44:37.646504 systemd[1]: Started update-engine.service - Update Engine. May 27 17:44:37.653084 systemd[1]: Started locksmithd.service - Cluster reboot manager. May 27 17:44:37.680969 sshd_keygen[1911]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 May 27 17:44:37.690569 setup-nvidia[1892]: Downloading Flatcar Container Linux Developer Container for version: 4344.0.0 May 27 17:44:37.703030 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. May 27 17:44:37.708948 systemd[1]: Starting issuegen.service - Generate /run/issue... May 27 17:44:37.724987 coreos-metadata[1880]: May 27 17:44:37.724 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 May 27 17:44:37.726955 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... May 27 17:44:37.727400 coreos-metadata[1880]: May 27 17:44:37.727 INFO Fetch successful May 27 17:44:37.727545 coreos-metadata[1880]: May 27 17:44:37.727 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 May 27 17:44:37.731942 coreos-metadata[1880]: May 27 17:44:37.731 INFO Fetch successful May 27 17:44:37.731942 coreos-metadata[1880]: May 27 17:44:37.731 INFO Fetching http://168.63.129.16/machine/2103fc10-45fb-417e-9378-e91f1c1ad457/8f5401d7%2D27dd%2D4c1b%2Da7cd%2Db1fcd9248f6b.%5Fci%2D4344.0.0%2Da%2D3ca9c4afc0?comp=config&type=sharedConfig&incarnation=1: Attempt #1 May 27 17:44:37.733512 coreos-metadata[1880]: May 27 17:44:37.733 INFO Fetch successful May 27 17:44:37.733675 coreos-metadata[1880]: May 27 17:44:37.733 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 May 27 17:44:37.733746 systemd[1]: issuegen.service: Deactivated successfully. May 27 17:44:37.733963 systemd[1]: Finished issuegen.service - Generate /run/issue. May 27 17:44:37.739646 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... May 27 17:44:37.741206 coreos-metadata[1880]: May 27 17:44:37.741 INFO Fetch successful May 27 17:44:37.752562 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. May 27 17:44:37.758085 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. May 27 17:44:37.765548 systemd[1]: Started getty@tty1.service - Getty on tty1. May 27 17:44:37.768234 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. May 27 17:44:37.773707 systemd[1]: Reached target getty.target - Login Prompts. May 27 17:44:37.778271 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. May 27 17:44:37.783450 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. May 27 17:44:37.910271 locksmithd[2009]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" May 27 17:44:38.326442 setup-nvidia[2053]: % Total % Received % Xferd Average Speed Time Time Time Current May 27 17:44:38.326774 setup-nvidia[2053]: Dload Upload Total Spent Left Speed May 27 17:44:38.667845 containerd[1916]: time="2025-05-27T17:44:38Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 May 27 17:44:38.670146 containerd[1916]: time="2025-05-27T17:44:38.670113100Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 May 27 17:44:38.678703 containerd[1916]: time="2025-05-27T17:44:38.678670000Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="7.8µs" May 27 17:44:38.678703 containerd[1916]: time="2025-05-27T17:44:38.678696800Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 May 27 17:44:38.678791 containerd[1916]: time="2025-05-27T17:44:38.678713500Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 May 27 17:44:38.678869 containerd[1916]: time="2025-05-27T17:44:38.678845400Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 May 27 17:44:38.678898 containerd[1916]: time="2025-05-27T17:44:38.678866600Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 May 27 17:44:38.678898 containerd[1916]: time="2025-05-27T17:44:38.678890400Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 May 27 17:44:38.678968 containerd[1916]: time="2025-05-27T17:44:38.678946600Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 May 27 17:44:38.678968 containerd[1916]: time="2025-05-27T17:44:38.678965300Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 May 27 17:44:38.679430 containerd[1916]: time="2025-05-27T17:44:38.679323500Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 May 27 17:44:38.679430 containerd[1916]: time="2025-05-27T17:44:38.679368000Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 27 17:44:38.679430 containerd[1916]: time="2025-05-27T17:44:38.679387800Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 27 17:44:38.679430 containerd[1916]: time="2025-05-27T17:44:38.679397100Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 May 27 17:44:38.679658 containerd[1916]: time="2025-05-27T17:44:38.679626000Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 May 27 17:44:38.679927 containerd[1916]: time="2025-05-27T17:44:38.679893800Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 May 27 17:44:38.679965 containerd[1916]: time="2025-05-27T17:44:38.679934700Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 May 27 17:44:38.679965 containerd[1916]: time="2025-05-27T17:44:38.679948100Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 May 27 17:44:38.680020 containerd[1916]: time="2025-05-27T17:44:38.679977200Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 May 27 17:44:38.680236 containerd[1916]: time="2025-05-27T17:44:38.680212700Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 May 27 17:44:38.680300 containerd[1916]: time="2025-05-27T17:44:38.680282100Z" level=info msg="metadata content store policy set" policy=shared May 27 17:44:38.695479 containerd[1916]: time="2025-05-27T17:44:38.695448700Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 May 27 17:44:38.695536 containerd[1916]: time="2025-05-27T17:44:38.695491600Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 May 27 17:44:38.695536 containerd[1916]: time="2025-05-27T17:44:38.695508600Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 May 27 17:44:38.695617 containerd[1916]: time="2025-05-27T17:44:38.695534000Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 May 27 17:44:38.695617 containerd[1916]: time="2025-05-27T17:44:38.695557500Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 May 27 17:44:38.695617 containerd[1916]: time="2025-05-27T17:44:38.695569600Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 May 27 17:44:38.695617 containerd[1916]: time="2025-05-27T17:44:38.695596200Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 May 27 17:44:38.695701 containerd[1916]: time="2025-05-27T17:44:38.695623300Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 May 27 17:44:38.695701 containerd[1916]: time="2025-05-27T17:44:38.695645100Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 May 27 17:44:38.695701 containerd[1916]: time="2025-05-27T17:44:38.695656200Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 May 27 17:44:38.695701 containerd[1916]: time="2025-05-27T17:44:38.695672900Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 May 27 17:44:38.695701 containerd[1916]: time="2025-05-27T17:44:38.695685900Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 May 27 17:44:38.695840 containerd[1916]: time="2025-05-27T17:44:38.695814900Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 May 27 17:44:38.695874 containerd[1916]: time="2025-05-27T17:44:38.695842300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 May 27 17:44:38.695874 containerd[1916]: time="2025-05-27T17:44:38.695867400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 May 27 17:44:38.695926 containerd[1916]: time="2025-05-27T17:44:38.695880100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 May 27 17:44:38.695926 containerd[1916]: time="2025-05-27T17:44:38.695891400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 May 27 17:44:38.695926 containerd[1916]: time="2025-05-27T17:44:38.695901200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 May 27 17:44:38.695926 containerd[1916]: time="2025-05-27T17:44:38.695911400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 May 27 17:44:38.695926 containerd[1916]: time="2025-05-27T17:44:38.695921300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 May 27 17:44:38.696035 containerd[1916]: time="2025-05-27T17:44:38.695933400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 May 27 17:44:38.696035 containerd[1916]: time="2025-05-27T17:44:38.695943400Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 May 27 17:44:38.696035 containerd[1916]: time="2025-05-27T17:44:38.695953500Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 May 27 17:44:38.696035 containerd[1916]: time="2025-05-27T17:44:38.696005100Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" May 27 17:44:38.696035 containerd[1916]: time="2025-05-27T17:44:38.696018900Z" level=info msg="Start snapshots syncer" May 27 17:44:38.696186 containerd[1916]: time="2025-05-27T17:44:38.696044600Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 May 27 17:44:38.696300 containerd[1916]: time="2025-05-27T17:44:38.696248900Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" May 27 17:44:38.696419 containerd[1916]: time="2025-05-27T17:44:38.696316500Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 May 27 17:44:38.696419 containerd[1916]: time="2025-05-27T17:44:38.696388500Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 May 27 17:44:38.696512 containerd[1916]: time="2025-05-27T17:44:38.696488800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 May 27 17:44:38.696547 containerd[1916]: time="2025-05-27T17:44:38.696517300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 May 27 17:44:38.696547 containerd[1916]: time="2025-05-27T17:44:38.696528900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 May 27 17:44:38.696547 containerd[1916]: time="2025-05-27T17:44:38.696539800Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 May 27 17:44:38.696640 containerd[1916]: time="2025-05-27T17:44:38.696551300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 May 27 17:44:38.696640 containerd[1916]: time="2025-05-27T17:44:38.696561300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 May 27 17:44:38.696640 containerd[1916]: time="2025-05-27T17:44:38.696571100Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 May 27 17:44:38.696640 containerd[1916]: time="2025-05-27T17:44:38.696608700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 May 27 17:44:38.696640 containerd[1916]: time="2025-05-27T17:44:38.696623500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 May 27 17:44:38.696640 containerd[1916]: time="2025-05-27T17:44:38.696634000Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 May 27 17:44:38.696759 containerd[1916]: time="2025-05-27T17:44:38.696668800Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 May 27 17:44:38.696759 containerd[1916]: time="2025-05-27T17:44:38.696683500Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 May 27 17:44:38.696759 containerd[1916]: time="2025-05-27T17:44:38.696692400Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 May 27 17:44:38.696759 containerd[1916]: time="2025-05-27T17:44:38.696701500Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 May 27 17:44:38.696759 containerd[1916]: time="2025-05-27T17:44:38.696714700Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 May 27 17:44:38.696759 containerd[1916]: time="2025-05-27T17:44:38.696733500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 May 27 17:44:38.696880 containerd[1916]: time="2025-05-27T17:44:38.696764200Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 May 27 17:44:38.696880 containerd[1916]: time="2025-05-27T17:44:38.696782400Z" level=info msg="runtime interface created" May 27 17:44:38.696880 containerd[1916]: time="2025-05-27T17:44:38.696788100Z" level=info msg="created NRI interface" May 27 17:44:38.696880 containerd[1916]: time="2025-05-27T17:44:38.696795700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 May 27 17:44:38.696880 containerd[1916]: time="2025-05-27T17:44:38.696805800Z" level=info msg="Connect containerd service" May 27 17:44:38.696880 containerd[1916]: time="2025-05-27T17:44:38.696841900Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" May 27 17:44:38.697544 containerd[1916]: time="2025-05-27T17:44:38.697516500Z" 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" May 27 17:44:39.685386 containerd[1916]: time="2025-05-27T17:44:39.685332000Z" level=info msg="Start subscribing containerd event" May 27 17:44:39.685769 containerd[1916]: time="2025-05-27T17:44:39.685388500Z" level=info msg="Start recovering state" May 27 17:44:39.685769 containerd[1916]: time="2025-05-27T17:44:39.685486000Z" level=info msg="Start event monitor" May 27 17:44:39.685769 containerd[1916]: time="2025-05-27T17:44:39.685502700Z" level=info msg="Start cni network conf syncer for default" May 27 17:44:39.685769 containerd[1916]: time="2025-05-27T17:44:39.685511800Z" level=info msg="Start streaming server" May 27 17:44:39.685769 containerd[1916]: time="2025-05-27T17:44:39.685521300Z" level=info msg="Registered namespace \"k8s.io\" with NRI" May 27 17:44:39.685769 containerd[1916]: time="2025-05-27T17:44:39.685529200Z" level=info msg="runtime interface starting up..." May 27 17:44:39.685769 containerd[1916]: time="2025-05-27T17:44:39.685535800Z" level=info msg="starting plugins..." May 27 17:44:39.685769 containerd[1916]: time="2025-05-27T17:44:39.685540800Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc May 27 17:44:39.685769 containerd[1916]: time="2025-05-27T17:44:39.685550200Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" May 27 17:44:39.685769 containerd[1916]: time="2025-05-27T17:44:39.685617800Z" level=info msg=serving... address=/run/containerd/containerd.sock May 27 17:44:39.685769 containerd[1916]: time="2025-05-27T17:44:39.685770000Z" level=info msg="containerd successfully booted in 1.018458s" May 27 17:44:39.685868 systemd[1]: Started containerd.service - containerd container runtime. May 27 17:44:39.970097 waagent[2037]: 2025-05-27T17:44:39.969962Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 May 27 17:44:39.974475 waagent[2037]: 2025-05-27T17:44:39.974432Z INFO Daemon Daemon OS: flatcar 4344.0.0 May 27 17:44:39.978072 waagent[2037]: 2025-05-27T17:44:39.978031Z INFO Daemon Daemon Python: 3.11.12 May 27 17:44:39.981596 waagent[2037]: 2025-05-27T17:44:39.981536Z INFO Daemon Daemon Run daemon May 27 17:44:39.984813 waagent[2037]: 2025-05-27T17:44:39.984764Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4344.0.0' May 27 17:44:39.991592 waagent[2037]: 2025-05-27T17:44:39.991539Z INFO Daemon Daemon Using waagent for provisioning May 27 17:44:39.995770 waagent[2037]: 2025-05-27T17:44:39.995729Z INFO Daemon Daemon Activate resource disk May 27 17:44:39.999386 waagent[2037]: 2025-05-27T17:44:39.999341Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb May 27 17:44:40.006755 waagent[2037]: 2025-05-27T17:44:40.006712Z INFO Daemon Daemon Found device: sdb May 27 17:44:40.016024 waagent[2037]: 2025-05-27T17:44:40.015972Z INFO Daemon Daemon Examining partition table May 27 17:44:40.041024 waagent[2037]: 2025-05-27T17:44:40.040977Z INFO Daemon Daemon GPT not detected, determining filesystem May 27 17:44:40.072322 waagent[2037]: 2025-05-27T17:44:40.072271Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded May 27 17:44:40.077043 waagent[2037]: 2025-05-27T17:44:40.076985Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 May 27 17:44:40.543298 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#242 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 May 27 17:44:40.551595 kernel: sdb: sdb1 May 27 17:44:40.564092 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#562 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 May 27 17:44:40.581600 kernel: sdb: sdb1 May 27 17:44:40.581926 waagent[2037]: 2025-05-27T17:44:40.581854Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded May 27 17:44:40.600600 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#363 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 May 27 17:44:40.617598 kernel: sdb: sdb1 May 27 17:44:40.617658 waagent[2037]: 2025-05-27T17:44:40.617616Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] May 27 17:44:46.168884 login[2041]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying May 27 17:44:46.328836 login[2042]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) May 27 17:44:46.335238 systemd[1]: Created slice user-500.slice - User Slice of UID 500. May 27 17:44:46.336329 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... May 27 17:44:46.342598 systemd-logind[1899]: New session 2 of user core. May 27 17:44:46.388425 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. May 27 17:44:46.391147 systemd[1]: Starting user@500.service - User Manager for UID 500... May 27 17:44:46.425492 (systemd)[2149]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) May 27 17:44:46.429221 systemd-logind[1899]: New session c1 of user core. May 27 17:44:46.455969 waagent[2037]: 2025-05-27T17:44:46.455885Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] May 27 17:44:46.562432 waagent[2037]: 2025-05-27T17:44:46.562352Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 May 27 17:44:46.562600 kernel: EXT4-fs (sdb1): mounted filesystem 8e6039c3-02f0-49d5-9658-738a4080aa77 r/w with ordered data mode. Quota mode: none. May 27 17:44:46.564625 waagent[2037]: 2025-05-27T17:44:46.564565Z INFO Daemon Daemon Clean protocol and wireserver endpoint May 27 17:44:46.564871 waagent[2037]: 2025-05-27T17:44:46.564834Z INFO Daemon Daemon Running default provisioning handler May 27 17:44:46.575261 waagent[2037]: 2025-05-27T17:44:46.575214Z 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. May 27 17:44:46.577818 waagent[2037]: 2025-05-27T17:44:46.577655Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' May 27 17:44:46.580359 waagent[2037]: 2025-05-27T17:44:46.580316Z INFO Daemon Daemon cloud-init is enabled: False May 27 17:44:46.581757 waagent[2037]: 2025-05-27T17:44:46.581723Z INFO Daemon Daemon Copying ovf-env.xml May 27 17:44:46.654192 waagent[2037]: 2025-05-27T17:44:46.654140Z INFO Daemon Daemon Successfully mounted dvd May 27 17:44:46.697337 systemd[2149]: Queued start job for default target default.target. May 27 17:44:46.711367 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. May 27 17:44:46.711771 waagent[2037]: 2025-05-27T17:44:46.711716Z INFO Daemon Daemon Detect protocol endpoint May 27 17:44:46.713347 waagent[2037]: 2025-05-27T17:44:46.713302Z INFO Daemon Daemon Clean protocol and wireserver endpoint May 27 17:44:46.714797 waagent[2037]: 2025-05-27T17:44:46.714756Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler May 27 17:44:46.715487 waagent[2037]: 2025-05-27T17:44:46.715454Z INFO Daemon Daemon Test for route to 168.63.129.16 May 27 17:44:46.717037 waagent[2037]: 2025-05-27T17:44:46.717002Z INFO Daemon Daemon Route to 168.63.129.16 exists May 27 17:44:46.718278 waagent[2037]: 2025-05-27T17:44:46.718239Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 May 27 17:44:46.729384 waagent[2037]: 2025-05-27T17:44:46.729344Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 May 27 17:44:46.730715 waagent[2037]: 2025-05-27T17:44:46.730688Z INFO Daemon Daemon Wire protocol version:2012-11-30 May 27 17:44:46.733614 waagent[2037]: 2025-05-27T17:44:46.733557Z INFO Daemon Daemon Server preferred version:2015-04-05 May 27 17:44:46.756358 systemd[2149]: Created slice app.slice - User Application Slice. May 27 17:44:46.756391 systemd[2149]: Reached target paths.target - Paths. May 27 17:44:46.756440 systemd[2149]: Reached target timers.target - Timers. May 27 17:44:46.757737 systemd[2149]: Starting dbus.socket - D-Bus User Message Bus Socket... May 27 17:44:46.768255 systemd[2149]: Listening on dbus.socket - D-Bus User Message Bus Socket. May 27 17:44:46.768374 systemd[2149]: Reached target sockets.target - Sockets. May 27 17:44:46.768423 systemd[2149]: Reached target basic.target - Basic System. May 27 17:44:46.768467 systemd[2149]: Reached target default.target - Main User Target. May 27 17:44:46.768505 systemd[2149]: Startup finished in 331ms. May 27 17:44:46.768622 systemd[1]: Started user@500.service - User Manager for UID 500. May 27 17:44:46.770305 systemd[1]: Started session-2.scope - Session 2 of User core. May 27 17:44:46.783081 waagent[2037]: 2025-05-27T17:44:46.783015Z INFO Daemon Daemon Initializing goal state during protocol detection May 27 17:44:46.784671 waagent[2037]: 2025-05-27T17:44:46.784627Z INFO Daemon Daemon Forcing an update of the goal state. May 27 17:44:46.788370 waagent[2037]: 2025-05-27T17:44:46.788326Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] May 27 17:44:46.822727 waagent[2037]: 2025-05-27T17:44:46.822685Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.164 May 27 17:44:46.824601 waagent[2037]: 2025-05-27T17:44:46.824544Z INFO Daemon May 27 17:44:46.824881 waagent[2037]: 2025-05-27T17:44:46.824847Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 85ce275b-7da1-423e-9034-d1f583eb4fa9 eTag: 14536534953643836613 source: Fabric] May 27 17:44:46.825844 waagent[2037]: 2025-05-27T17:44:46.825807Z INFO Daemon The vmSettings originated via Fabric; will ignore them. May 27 17:44:46.826530 waagent[2037]: 2025-05-27T17:44:46.826494Z INFO Daemon May 27 17:44:46.826994 waagent[2037]: 2025-05-27T17:44:46.826963Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] May 27 17:44:46.832268 waagent[2037]: 2025-05-27T17:44:46.832224Z INFO Daemon Daemon Downloading artifacts profile blob May 27 17:44:46.956712 waagent[2037]: 2025-05-27T17:44:46.956607Z INFO Daemon Downloaded certificate {'thumbprint': '9B5F836E8093970830FEB50E3DDF9084EE3B3DCE', 'hasPrivateKey': True} May 27 17:44:46.958532 waagent[2037]: 2025-05-27T17:44:46.958487Z INFO Daemon Fetch goal state completed May 27 17:44:46.997051 waagent[2037]: 2025-05-27T17:44:46.996985Z INFO Daemon Daemon Starting provisioning May 27 17:44:46.998663 waagent[2037]: 2025-05-27T17:44:46.998621Z INFO Daemon Daemon Handle ovf-env.xml. May 27 17:44:47.000083 waagent[2037]: 2025-05-27T17:44:47.000045Z INFO Daemon Daemon Set hostname [ci-4344.0.0-a-3ca9c4afc0] May 27 17:44:47.021912 waagent[2037]: 2025-05-27T17:44:47.021864Z INFO Daemon Daemon Publish hostname [ci-4344.0.0-a-3ca9c4afc0] May 27 17:44:47.023772 waagent[2037]: 2025-05-27T17:44:47.023727Z INFO Daemon Daemon Examine /proc/net/route for primary interface May 27 17:44:47.024459 waagent[2037]: 2025-05-27T17:44:47.024422Z INFO Daemon Daemon Primary interface is [eth0] May 27 17:44:47.042474 systemd-networkd[1569]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 27 17:44:47.042485 systemd-networkd[1569]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 27 17:44:47.042514 systemd-networkd[1569]: eth0: DHCP lease lost May 27 17:44:47.042927 waagent[2037]: 2025-05-27T17:44:47.042879Z INFO Daemon Daemon Create user account if not exists May 27 17:44:47.044474 waagent[2037]: 2025-05-27T17:44:47.044435Z INFO Daemon Daemon User core already exists, skip useradd May 27 17:44:47.045946 waagent[2037]: 2025-05-27T17:44:47.045910Z INFO Daemon Daemon Configure sudoer May 27 17:44:47.050828 waagent[2037]: 2025-05-27T17:44:47.050789Z INFO Daemon Daemon Configure sshd May 27 17:44:47.055120 waagent[2037]: 2025-05-27T17:44:47.054887Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. May 27 17:44:47.057236 waagent[2037]: 2025-05-27T17:44:47.056867Z INFO Daemon Daemon Deploy ssh public key. May 27 17:44:47.114621 systemd-networkd[1569]: eth0: DHCPv4 address 10.200.8.12/24, gateway 10.200.8.1 acquired from 168.63.129.16 May 27 17:44:47.169253 login[2041]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) May 27 17:44:47.174420 systemd-logind[1899]: New session 1 of user core. May 27 17:44:47.183700 systemd[1]: Started session-1.scope - Session 1 of User core. May 27 17:44:59.976752 setup-nvidia[2053]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 485M 0 32768 0 0 56316 0 2:30:38 --:--:-- 2:30:38 56302\u000d 0 485M 0 3200k 0 0 2079k 0 0:03:59 0:00:01 0:03:58 2077k\u000d 5 485M 5 27.5M 0 0 10.9M 0 0:00:44 0:00:02 0:00:42 10.9M\u000d 10 485M 10 52.5M 0 0 14.9M 0 0:00:32 0:00:03 0:00:29 14.9M\u000d 15 485M 15 77.1M 0 0 17.1M 0 0:00:28 0:00:04 0:00:24 17.1M\u000d 20 485M 20 100M 0 0 18.3M 0 0:00:26 0:00:05 0:00:21 20.5M\u000d 25 485M 25 124M 0 0 19.1M 0 0:00:25 0:00:06 0:00:19 24.5M\u000d 29 485M 29 144M 0 0 19.1M 0 0:00:25 0:00:07 0:00:18 23.2M\u000d 34 485M 34 167M 0 0 19.6M 0 0:00:24 0:00:08 0:00:16 22.9M\u000d 39 485M 39 190M 0 0 20.0M 0 0:00:24 0:00:09 0:00:15 22.6M\u000d 43 485M 43 212M 0 0 20.3M 0 0:00:23 0:00:10 0:00:13 22.4M\u000d 48 485M 48 236M 0 0 20.6M 0 0:00:23 0:00:11 0:00:12 22.5M\u000d 53 485M 53 261M 0 0 21.0M 0 0:00:23 0:00:12 0:00:11 23.8M\u000d 59 485M 59 288M 0 0 21.4M 0 0:00:22 0:00:13 0:00:09 24.4M\u000d 64 485M 64 312M 0 0 21.6M 0 0:00:22 0:00:14 0:00:08 24.7M\u000d 69 485M 69 338M 0 0 21.6M 0 0:00:22 0:00:15 0:00:07 24.5M\u000d 74 485M 74 360M 0 0 21.7M 0 0:00:22 0:00:16 0:00:06 24.3M\u000d 79 485M 79 383M 0 0 21.8M 0 0:00:22 0:00:17 0:00:05 23.9M\u000d 83 485M 83 407M 0 0 21.9M 0 0:00:22 0:00:18 0:00:04 23.5M\u000d 88 485M 88 431M 0 0 22.0M 0 0:00:21 0:00:19 0:00:02 23.4M\u000d 93 485M 93 455M 0 0 22.1M 0 0:00:21 0:00:20 0:00:01 23.7M\u000d 98 485M 98 478M 0 0 22.2M 0 0:00:21 0:00:21 --:--:-- 23.8M\u000d100 485M 100 485M 0 0 22.4M 0 0:00:21 0:00:21 --:--:-- 24.8M May 27 17:45:01.219968 chronyd[1905]: Selected source PHC0 May 27 17:45:10.974191 setup-nvidia[1892]: Downloading NVIDIA 535.230.02 Driver May 27 17:45:10.978444 setup-nvidia[2216]: % Total % Received % Xferd Average Speed Time Time Time Current May 27 17:45:10.978691 setup-nvidia[2216]: Dload Upload Total Spent Left Speed May 27 17:45:13.861709 setup-nvidia[2216]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 6 326M 6 20.6M 0 0 25.5M 0 0:00:12 --:--:-- 0:00:12 25.5M\u000d 50 326M 50 164M 0 0 91.0M 0 0:00:03 0:00:01 0:00:02 91.0M\u000d 98 326M 98 320M 0 0 113M 0 0:00:02 0:00:02 --:--:-- 113M\u000d100 326M 100 326M 0 0 113M 0 0:00:02 0:00:02 --:--:-- 113M May 27 17:45:13.951388 setup-nvidia[1892]: Extract the NVIDIA Driver Installer 535.230.02 May 27 17:45:13.951651 setup-nvidia[1892]: /opt/nvidia/workdir/nvidia-workdir / May 27 17:45:13.968864 setup-nvidia[2219]: Creating directory NVIDIA-Linux-x86_64-535.230.02 May 27 17:45:14.255064 setup-nvidia[2219]: Verifying archive integrity... OK May 27 17:45:14.978671 setup-nvidia[2219]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.230.02 May 27 17:45:16.750463 setup-nvidia[2245]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ May 27 17:45:16.848658 setup-nvidia[1892]: / May 27 17:45:16.848658 setup-nvidia[1892]: Spawn system-nspawn container to install the NVIDIA drivers May 27 17:45:17.129157 waagent[2037]: 2025-05-27T17:45:17.129041Z INFO Daemon Daemon Provisioning complete May 27 17:45:17.134139 sudo[2252]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4344.0.0.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 May 27 17:45:17.135904 sudo[2252]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 27 17:45:17.139983 waagent[2037]: 2025-05-27T17:45:17.139939Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping May 27 17:45:17.141503 systemd[1]: Created slice user-0.slice - User Slice of UID 0. May 27 17:45:17.141821 waagent[2037]: 2025-05-27T17:45:17.141542Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. May 27 17:45:17.142506 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... May 27 17:45:17.142982 waagent[2037]: 2025-05-27T17:45:17.142943Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent May 27 17:45:17.171820 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. May 27 17:45:17.174548 systemd[1]: Starting user@0.service - User Manager for UID 0... May 27 17:45:17.189539 (systemd)[2256]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) May 27 17:45:17.192243 systemd-logind[1899]: New session c3 of user root. May 27 17:45:17.299350 waagent[2254]: 2025-05-27T17:45:17.299266Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) May 27 17:45:17.299675 waagent[2254]: 2025-05-27T17:45:17.299406Z INFO ExtHandler ExtHandler OS: flatcar 4344.0.0 May 27 17:45:17.299675 waagent[2254]: 2025-05-27T17:45:17.299475Z INFO ExtHandler ExtHandler Python: 3.11.12 May 27 17:45:17.299675 waagent[2254]: 2025-05-27T17:45:17.299545Z INFO ExtHandler ExtHandler CPU Arch: x86_64 May 27 17:45:17.378772 waagent[2254]: 2025-05-27T17:45:17.378715Z INFO ExtHandler ExtHandler Distro: flatcar-4344.0.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.12; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; May 27 17:45:17.378958 waagent[2254]: 2025-05-27T17:45:17.378920Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file May 27 17:45:17.379036 waagent[2254]: 2025-05-27T17:45:17.379004Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 May 27 17:45:17.384408 systemd[2256]: Queued start job for default target default.target. May 27 17:45:17.385635 waagent[2254]: 2025-05-27T17:45:17.385570Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] May 27 17:45:17.395290 waagent[2254]: 2025-05-27T17:45:17.395249Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.164 May 27 17:45:17.395754 waagent[2254]: 2025-05-27T17:45:17.395716Z INFO ExtHandler May 27 17:45:17.395835 waagent[2254]: 2025-05-27T17:45:17.395804Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 77f8db03-7315-4089-8f4d-5153ae087a56 eTag: 14536534953643836613 source: Fabric] May 27 17:45:17.396149 waagent[2254]: 2025-05-27T17:45:17.396112Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. May 27 17:45:17.396845 waagent[2254]: 2025-05-27T17:45:17.396799Z INFO ExtHandler May 27 17:45:17.396901 waagent[2254]: 2025-05-27T17:45:17.396878Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] May 27 17:45:17.400275 waagent[2254]: 2025-05-27T17:45:17.400231Z INFO ExtHandler ExtHandler Downloading artifacts profile blob May 27 17:45:17.400771 systemd[2256]: Created slice app.slice - User Application Slice. May 27 17:45:17.400803 systemd[2256]: Reached target paths.target - Paths. May 27 17:45:17.400853 systemd[2256]: Reached target timers.target - Timers. May 27 17:45:17.401999 systemd[2256]: Starting dbus.socket - D-Bus User Message Bus Socket... May 27 17:45:17.412335 systemd[2256]: Listening on dbus.socket - D-Bus User Message Bus Socket. May 27 17:45:17.412393 systemd[2256]: Reached target sockets.target - Sockets. May 27 17:45:17.412445 systemd[2256]: Reached target basic.target - Basic System. May 27 17:45:17.412491 systemd[2256]: Reached target default.target - Main User Target. May 27 17:45:17.412523 systemd[2256]: Startup finished in 213ms. May 27 17:45:17.412621 systemd[1]: Started user@0.service - User Manager for UID 0. May 27 17:45:17.414063 systemd[1]: Started session-c2.scope - Session c2 of User root. May 27 17:45:17.462257 waagent[2254]: 2025-05-27T17:45:17.462201Z INFO ExtHandler Downloaded certificate {'thumbprint': '9B5F836E8093970830FEB50E3DDF9084EE3B3DCE', 'hasPrivateKey': True} May 27 17:45:17.462748 waagent[2254]: 2025-05-27T17:45:17.462709Z INFO ExtHandler Fetch goal state completed May 27 17:45:17.474948 waagent[2254]: 2025-05-27T17:45:17.474895Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.3.3 11 Feb 2025 (Library: OpenSSL 3.3.3 11 Feb 2025) May 27 17:45:17.481334 waagent[2254]: 2025-05-27T17:45:17.481284Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2254 May 27 17:45:17.481853 waagent[2254]: 2025-05-27T17:45:17.481804Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** May 27 17:45:17.482385 waagent[2254]: 2025-05-27T17:45:17.482340Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** May 27 17:45:17.484094 waagent[2254]: 2025-05-27T17:45:17.484052Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4344.0.0', '', 'Flatcar Container Linux by Kinvolk'] May 27 17:45:17.484514 waagent[2254]: 2025-05-27T17:45:17.484475Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4344.0.0', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported May 27 17:45:17.484693 waagent[2254]: 2025-05-27T17:45:17.484656Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False May 27 17:45:17.485287 waagent[2254]: 2025-05-27T17:45:17.485250Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules May 27 17:45:17.553711 waagent[2254]: 2025-05-27T17:45:17.553670Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service May 27 17:45:17.553882 waagent[2254]: 2025-05-27T17:45:17.553846Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup May 27 17:45:17.559603 kernel: loop6: detected capacity change from 0 to 12644352 May 27 17:45:17.560047 waagent[2254]: 2025-05-27T17:45:17.560017Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now May 27 17:45:17.565601 kernel: loop6: p9 May 27 17:45:17.566772 systemd[1]: Reload requested from client PID 2280 ('systemctl') (unit waagent.service)... May 27 17:45:17.566787 systemd[1]: Reloading... May 27 17:45:17.630614 zram_generator::config[2323]: No configuration found. May 27 17:45:17.741992 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. May 27 17:45:17.779689 setup-nvidia[2269]: ░ Spawning container flatcardevelopercontainer-4344.0.0.bin on /opt/nvidia/workdir/flatcar_developer_container-4344.0.0.bin. May 27 17:45:17.908333 systemd[1]: Reloading finished in 341 ms. May 27 17:45:17.932155 waagent[2254]: 2025-05-27T17:45:17.932081Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service May 27 17:45:17.932260 waagent[2254]: 2025-05-27T17:45:17.932226Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully May 27 17:45:18.640073 waagent[2254]: 2025-05-27T17:45:18.639985Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. May 27 17:45:18.640415 waagent[2254]: 2025-05-27T17:45:18.640312Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: 1. configuration enabled [True], 2. cgroups v1 enabled [False] OR cgroups v2 is in use and v2 resource limiting configuration enabled [False], 3. python supported: [True] May 27 17:45:18.641117 waagent[2254]: 2025-05-27T17:45:18.641071Z INFO ExtHandler ExtHandler Starting env monitor service. May 27 17:45:18.641464 waagent[2254]: 2025-05-27T17:45:18.641417Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file May 27 17:45:18.641650 waagent[2254]: 2025-05-27T17:45:18.641611Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. May 27 17:45:18.641866 waagent[2254]: 2025-05-27T17:45:18.641830Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 May 27 17:45:18.642228 waagent[2254]: 2025-05-27T17:45:18.642183Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file May 27 17:45:18.642495 waagent[2254]: 2025-05-27T17:45:18.642454Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. May 27 17:45:18.642600 waagent[2254]: 2025-05-27T17:45:18.642535Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread May 27 17:45:18.642708 waagent[2254]: 2025-05-27T17:45:18.642668Z INFO ExtHandler ExtHandler Start Extension Telemetry service. May 27 17:45:18.642905 waagent[2254]: 2025-05-27T17:45:18.642868Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 May 27 17:45:18.643285 waagent[2254]: 2025-05-27T17:45:18.643232Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True May 27 17:45:18.643416 waagent[2254]: 2025-05-27T17:45:18.643369Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. May 27 17:45:18.643570 waagent[2254]: 2025-05-27T17:45:18.643525Z INFO EnvHandler ExtHandler Configure routes May 27 17:45:18.643880 waagent[2254]: 2025-05-27T17:45:18.643838Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: May 27 17:45:18.643880 waagent[2254]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT May 27 17:45:18.643880 waagent[2254]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 May 27 17:45:18.643880 waagent[2254]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 May 27 17:45:18.643880 waagent[2254]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 May 27 17:45:18.643880 waagent[2254]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 May 27 17:45:18.643880 waagent[2254]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 May 27 17:45:18.644035 waagent[2254]: 2025-05-27T17:45:18.643931Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread May 27 17:45:18.644452 waagent[2254]: 2025-05-27T17:45:18.644414Z INFO EnvHandler ExtHandler Gateway:None May 27 17:45:18.645028 waagent[2254]: 2025-05-27T17:45:18.644982Z INFO EnvHandler ExtHandler Routes:None May 27 17:45:18.650247 waagent[2254]: 2025-05-27T17:45:18.650200Z INFO ExtHandler ExtHandler May 27 17:45:18.650560 waagent[2254]: 2025-05-27T17:45:18.650521Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 702b9863-be0d-4319-b63f-4a55162ce0ee correlation 4858829e-c834-44e4-af06-b345d383256d created: 2025-05-27T17:43:18.779381Z] May 27 17:45:18.651604 waagent[2254]: 2025-05-27T17:45:18.651549Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. May 27 17:45:18.653625 waagent[2254]: 2025-05-27T17:45:18.653570Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] May 27 17:45:18.707218 waagent[2254]: 2025-05-27T17:45:18.707157Z WARNING ExtHandler ExtHandler Failed to get firewall packets: 'iptables -w -t security -L OUTPUT --zero OUTPUT -nxv' failed: 2 (iptables v1.8.11 (nf_tables): Illegal option `--numeric' with this command May 27 17:45:18.707218 waagent[2254]: Try `iptables -h' or 'iptables --help' for more information.) May 27 17:45:18.707632 waagent[2254]: 2025-05-27T17:45:18.707593Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: DE994637-F8D6-4A69-A470-D687853E8EB1;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] May 27 17:45:18.740374 waagent[2254]: 2025-05-27T17:45:18.740313Z INFO MonitorHandler ExtHandler Network interfaces: May 27 17:45:18.740374 waagent[2254]: Executing ['ip', '-a', '-o', 'link']: May 27 17:45:18.740374 waagent[2254]: 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 May 27 17:45:18.740374 waagent[2254]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:ed:8d:2d:ba:14 brd ff:ff:ff:ff:ff:ff May 27 17:45:18.740374 waagent[2254]: Executing ['ip', '-4', '-a', '-o', 'address']: May 27 17:45:18.740374 waagent[2254]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever May 27 17:45:18.740374 waagent[2254]: 2: eth0 inet 10.200.8.12/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever May 27 17:45:18.740374 waagent[2254]: Executing ['ip', '-6', '-a', '-o', 'address']: May 27 17:45:18.740374 waagent[2254]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever May 27 17:45:18.740374 waagent[2254]: 2: eth0 inet6 fe80::7eed:8dff:fe2d:ba14/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever May 27 17:45:18.858602 waagent[2254]: 2025-05-27T17:45:18.858527Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: May 27 17:45:18.858602 waagent[2254]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) May 27 17:45:18.858602 waagent[2254]: pkts bytes target prot opt in out source destination May 27 17:45:18.858602 waagent[2254]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) May 27 17:45:18.858602 waagent[2254]: pkts bytes target prot opt in out source destination May 27 17:45:18.858602 waagent[2254]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) May 27 17:45:18.858602 waagent[2254]: pkts bytes target prot opt in out source destination May 27 17:45:18.858602 waagent[2254]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 May 27 17:45:18.858602 waagent[2254]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 May 27 17:45:18.858602 waagent[2254]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW May 27 17:45:18.861980 waagent[2254]: 2025-05-27T17:45:18.861940Z INFO EnvHandler ExtHandler Current Firewall rules: May 27 17:45:18.861980 waagent[2254]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) May 27 17:45:18.861980 waagent[2254]: pkts bytes target prot opt in out source destination May 27 17:45:18.861980 waagent[2254]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) May 27 17:45:18.861980 waagent[2254]: pkts bytes target prot opt in out source destination May 27 17:45:18.861980 waagent[2254]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) May 27 17:45:18.861980 waagent[2254]: pkts bytes target prot opt in out source destination May 27 17:45:18.861980 waagent[2254]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 May 27 17:45:18.861980 waagent[2254]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 May 27 17:45:18.861980 waagent[2254]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW May 27 17:45:18.862226 waagent[2254]: 2025-05-27T17:45:18.862194Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 May 27 17:45:18.862374 waagent[2254]: 2025-05-27T17:45:18.862345Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 May 27 17:45:19.488898 kernel: EXT4-fs (loop6p9): write access unavailable, skipping orphan cleanup May 27 17:45:19.489019 kernel: EXT4-fs (loop6p9): mounted filesystem dd441403-e7f4-4782-b932-a2370dfaea27 ro without journal. Quota mode: none. May 27 17:45:19.602082 kernel: hv_balloon: Max. dynamic memory size: 114688 MB May 27 17:45:22.640755 update_engine[1906]: I20250527 17:45:22.640684 1906 update_attempter.cc:509] Updating boot flags... May 27 17:45:44.953156 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. May 27 17:45:44.957787 systemd[1]: Started sshd@0-10.200.8.12:22-10.200.16.10:37796.service - OpenSSH per-connection server daemon (10.200.16.10:37796). May 27 17:45:45.927103 sshd[3293]: Accepted publickey for core from 10.200.16.10 port 37796 ssh2: RSA SHA256:43KLdvDzM7BELJcS29r3hKkL8hXR8u1eeLIPzvzInuc May 27 17:45:45.928668 sshd-session[3293]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:45:45.933807 systemd-logind[1899]: New session 3 of user core. May 27 17:45:45.942715 systemd[1]: Started session-3.scope - Session 3 of User core. May 27 17:45:46.474925 systemd[1]: Started sshd@1-10.200.8.12:22-10.200.16.10:37800.service - OpenSSH per-connection server daemon (10.200.16.10:37800). May 27 17:45:47.103074 sshd[3348]: Accepted publickey for core from 10.200.16.10 port 37800 ssh2: RSA SHA256:43KLdvDzM7BELJcS29r3hKkL8hXR8u1eeLIPzvzInuc May 27 17:45:47.104331 sshd-session[3348]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:45:47.109343 systemd-logind[1899]: New session 4 of user core. May 27 17:45:47.118717 systemd[1]: Started session-4.scope - Session 4 of User core. May 27 17:45:47.544988 sshd[3397]: Connection closed by 10.200.16.10 port 37800 May 27 17:45:47.545333 sshd-session[3348]: pam_unix(sshd:session): session closed for user core May 27 17:45:47.548731 systemd[1]: sshd@1-10.200.8.12:22-10.200.16.10:37800.service: Deactivated successfully. May 27 17:45:47.550562 systemd[1]: session-4.scope: Deactivated successfully. May 27 17:45:47.551249 systemd-logind[1899]: Session 4 logged out. Waiting for processes to exit. May 27 17:45:47.552393 systemd-logind[1899]: Removed session 4. May 27 17:45:47.658380 systemd[1]: Started sshd@2-10.200.8.12:22-10.200.16.10:37802.service - OpenSSH per-connection server daemon (10.200.16.10:37802). May 27 17:45:48.308755 sshd[3404]: Accepted publickey for core from 10.200.16.10 port 37802 ssh2: RSA SHA256:43KLdvDzM7BELJcS29r3hKkL8hXR8u1eeLIPzvzInuc May 27 17:45:48.310088 sshd-session[3404]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:45:48.315406 systemd-logind[1899]: New session 5 of user core. May 27 17:45:48.321735 systemd[1]: Started session-5.scope - Session 5 of User core. May 27 17:45:48.752705 sshd[3550]: Connection closed by 10.200.16.10 port 37802 May 27 17:45:48.753054 sshd-session[3404]: pam_unix(sshd:session): session closed for user core May 27 17:45:48.756421 systemd[1]: sshd@2-10.200.8.12:22-10.200.16.10:37802.service: Deactivated successfully. May 27 17:45:48.757973 systemd[1]: session-5.scope: Deactivated successfully. May 27 17:45:48.758755 systemd-logind[1899]: Session 5 logged out. Waiting for processes to exit. May 27 17:45:48.760009 systemd-logind[1899]: Removed session 5. May 27 17:45:58.867124 systemd[1]: Started sshd@3-10.200.8.12:22-10.200.16.10:38494.service - OpenSSH per-connection server daemon (10.200.16.10:38494). May 27 17:45:59.529652 sshd[6726]: Accepted publickey for core from 10.200.16.10 port 38494 ssh2: RSA SHA256:43KLdvDzM7BELJcS29r3hKkL8hXR8u1eeLIPzvzInuc May 27 17:45:59.530531 sshd-session[6726]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:45:59.537807 systemd-logind[1899]: New session 6 of user core. May 27 17:45:59.545841 systemd[1]: Started session-6.scope - Session 6 of User core. May 27 17:45:59.993570 sshd[7019]: Connection closed by 10.200.16.10 port 38494 May 27 17:45:59.994247 sshd-session[6726]: pam_unix(sshd:session): session closed for user core May 27 17:45:59.997672 systemd-logind[1899]: Session 6 logged out. Waiting for processes to exit. May 27 17:45:59.997777 systemd[1]: sshd@3-10.200.8.12:22-10.200.16.10:38494.service: Deactivated successfully. May 27 17:46:00.000190 systemd[1]: session-6.scope: Deactivated successfully. May 27 17:46:00.003215 systemd-logind[1899]: Removed session 6. May 27 17:46:00.106820 systemd[1]: Started sshd@4-10.200.8.12:22-10.200.16.10:38496.service - OpenSSH per-connection server daemon (10.200.16.10:38496). May 27 17:46:00.748519 sshd[7154]: Accepted publickey for core from 10.200.16.10 port 38496 ssh2: RSA SHA256:43KLdvDzM7BELJcS29r3hKkL8hXR8u1eeLIPzvzInuc May 27 17:46:00.749129 sshd-session[7154]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:46:00.756231 systemd-logind[1899]: New session 7 of user core. May 27 17:46:00.760722 systemd[1]: Started session-7.scope - Session 7 of User core. May 27 17:46:01.195523 sshd[7390]: Connection closed by 10.200.16.10 port 38496 May 27 17:46:01.196786 sshd-session[7154]: pam_unix(sshd:session): session closed for user core May 27 17:46:01.200878 systemd-logind[1899]: Session 7 logged out. Waiting for processes to exit. May 27 17:46:01.207035 systemd[1]: sshd@4-10.200.8.12:22-10.200.16.10:38496.service: Deactivated successfully. May 27 17:46:01.209158 systemd[1]: session-7.scope: Deactivated successfully. May 27 17:46:01.212917 systemd-logind[1899]: Removed session 7. May 27 17:46:11.313956 systemd[1]: Started sshd@5-10.200.8.12:22-10.200.16.10:41696.service - OpenSSH per-connection server daemon (10.200.16.10:41696). May 27 17:46:11.958599 sshd[11031]: Accepted publickey for core from 10.200.16.10 port 41696 ssh2: RSA SHA256:43KLdvDzM7BELJcS29r3hKkL8hXR8u1eeLIPzvzInuc May 27 17:46:11.959915 sshd-session[11031]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:46:11.964295 systemd-logind[1899]: New session 8 of user core. May 27 17:46:11.972709 systemd[1]: Started session-8.scope - Session 8 of User core. May 27 17:46:12.403506 sshd[11302]: Connection closed by 10.200.16.10 port 41696 May 27 17:46:12.403921 sshd-session[11031]: pam_unix(sshd:session): session closed for user core May 27 17:46:12.407747 systemd[1]: sshd@5-10.200.8.12:22-10.200.16.10:41696.service: Deactivated successfully. May 27 17:46:12.409333 systemd[1]: session-8.scope: Deactivated successfully. May 27 17:46:12.410037 systemd-logind[1899]: Session 8 logged out. Waiting for processes to exit. May 27 17:46:12.411016 systemd-logind[1899]: Removed session 8. May 27 17:46:12.520914 systemd[1]: Started sshd@6-10.200.8.12:22-10.200.16.10:41698.service - OpenSSH per-connection server daemon (10.200.16.10:41698). May 27 17:46:13.153256 sshd[11313]: Accepted publickey for core from 10.200.16.10 port 41698 ssh2: RSA SHA256:43KLdvDzM7BELJcS29r3hKkL8hXR8u1eeLIPzvzInuc May 27 17:46:13.155460 sshd-session[11313]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:46:13.161681 systemd-logind[1899]: New session 9 of user core. May 27 17:46:13.173685 systemd[1]: Started session-9.scope - Session 9 of User core. May 27 17:46:13.601202 sshd[11340]: Connection closed by 10.200.16.10 port 41698 May 27 17:46:13.602982 sshd-session[11313]: pam_unix(sshd:session): session closed for user core May 27 17:46:13.610053 systemd-logind[1899]: Session 9 logged out. Waiting for processes to exit. May 27 17:46:13.610201 systemd[1]: sshd@6-10.200.8.12:22-10.200.16.10:41698.service: Deactivated successfully. May 27 17:46:13.613782 systemd[1]: session-9.scope: Deactivated successfully. May 27 17:46:13.616232 systemd-logind[1899]: Removed session 9. May 27 17:46:23.720826 systemd[1]: Started sshd@7-10.200.8.12:22-10.200.16.10:46624.service - OpenSSH per-connection server daemon (10.200.16.10:46624). May 27 17:46:24.376568 sshd[11790]: Accepted publickey for core from 10.200.16.10 port 46624 ssh2: RSA SHA256:43KLdvDzM7BELJcS29r3hKkL8hXR8u1eeLIPzvzInuc May 27 17:46:24.377440 sshd-session[11790]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:46:24.383540 systemd-logind[1899]: New session 10 of user core. May 27 17:46:24.390193 systemd[1]: Started session-10.scope - Session 10 of User core. May 27 17:46:24.820790 sshd[11804]: Connection closed by 10.200.16.10 port 46624 May 27 17:46:24.821556 sshd-session[11790]: pam_unix(sshd:session): session closed for user core May 27 17:46:24.828446 systemd-logind[1899]: Session 10 logged out. Waiting for processes to exit. May 27 17:46:24.829999 systemd[1]: sshd@7-10.200.8.12:22-10.200.16.10:46624.service: Deactivated successfully. May 27 17:46:24.831518 systemd[1]: session-10.scope: Deactivated successfully. May 27 17:46:24.833808 systemd-logind[1899]: Removed session 10. May 27 17:46:24.934869 systemd[1]: Started sshd@8-10.200.8.12:22-10.200.16.10:46630.service - OpenSSH per-connection server daemon (10.200.16.10:46630). May 27 17:46:25.588009 sshd[11822]: Accepted publickey for core from 10.200.16.10 port 46630 ssh2: RSA SHA256:43KLdvDzM7BELJcS29r3hKkL8hXR8u1eeLIPzvzInuc May 27 17:46:25.588854 sshd-session[11822]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:46:25.595809 systemd-logind[1899]: New session 11 of user core. May 27 17:46:25.601747 systemd[1]: Started session-11.scope - Session 11 of User core. May 27 17:46:26.033893 sshd[11837]: Connection closed by 10.200.16.10 port 46630 May 27 17:46:26.036134 sshd-session[11822]: pam_unix(sshd:session): session closed for user core May 27 17:46:26.039763 systemd[1]: sshd@8-10.200.8.12:22-10.200.16.10:46630.service: Deactivated successfully. May 27 17:46:26.040233 systemd-logind[1899]: Session 11 logged out. Waiting for processes to exit. May 27 17:46:26.043013 systemd[1]: session-11.scope: Deactivated successfully. May 27 17:46:26.046898 systemd-logind[1899]: Removed session 11. May 27 17:46:36.146660 systemd[1]: Started sshd@9-10.200.8.12:22-10.200.16.10:40312.service - OpenSSH per-connection server daemon (10.200.16.10:40312). May 27 17:46:36.786973 sshd[12202]: Accepted publickey for core from 10.200.16.10 port 40312 ssh2: RSA SHA256:43KLdvDzM7BELJcS29r3hKkL8hXR8u1eeLIPzvzInuc May 27 17:46:36.788223 sshd-session[12202]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:46:36.796387 systemd-logind[1899]: New session 12 of user core. May 27 17:46:36.802249 systemd[1]: Started session-12.scope - Session 12 of User core. May 27 17:46:37.248357 sshd[12216]: Connection closed by 10.200.16.10 port 40312 May 27 17:46:37.248951 sshd-session[12202]: pam_unix(sshd:session): session closed for user core May 27 17:46:37.252930 systemd-logind[1899]: Session 12 logged out. Waiting for processes to exit. May 27 17:46:37.253767 systemd[1]: sshd@9-10.200.8.12:22-10.200.16.10:40312.service: Deactivated successfully. May 27 17:46:37.257349 systemd[1]: session-12.scope: Deactivated successfully. May 27 17:46:37.261079 systemd-logind[1899]: Removed session 12. May 27 17:46:37.364508 systemd[1]: Started sshd@10-10.200.8.12:22-10.200.16.10:40314.service - OpenSSH per-connection server daemon (10.200.16.10:40314). May 27 17:46:38.007520 sshd[12234]: Accepted publickey for core from 10.200.16.10 port 40314 ssh2: RSA SHA256:43KLdvDzM7BELJcS29r3hKkL8hXR8u1eeLIPzvzInuc May 27 17:46:38.011528 sshd-session[12234]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:46:38.020257 systemd-logind[1899]: New session 13 of user core. May 27 17:46:38.027793 systemd[1]: Started session-13.scope - Session 13 of User core. May 27 17:46:38.469694 sshd[12254]: Connection closed by 10.200.16.10 port 40314 May 27 17:46:38.469838 sshd-session[12234]: pam_unix(sshd:session): session closed for user core May 27 17:46:38.473345 systemd[1]: sshd@10-10.200.8.12:22-10.200.16.10:40314.service: Deactivated successfully. May 27 17:46:38.476367 systemd[1]: session-13.scope: Deactivated successfully. May 27 17:46:38.479209 systemd-logind[1899]: Session 13 logged out. Waiting for processes to exit. May 27 17:46:38.480911 systemd-logind[1899]: Removed session 13. May 27 17:46:48.595261 systemd[1]: Started sshd@11-10.200.8.12:22-10.200.16.10:50902.service - OpenSSH per-connection server daemon (10.200.16.10:50902). May 27 17:46:49.224880 sshd[12503]: Accepted publickey for core from 10.200.16.10 port 50902 ssh2: RSA SHA256:43KLdvDzM7BELJcS29r3hKkL8hXR8u1eeLIPzvzInuc May 27 17:46:49.226528 sshd-session[12503]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:46:49.231185 systemd-logind[1899]: New session 14 of user core. May 27 17:46:49.241714 systemd[1]: Started session-14.scope - Session 14 of User core. May 27 17:46:49.668887 sshd[12505]: Connection closed by 10.200.16.10 port 50902 May 27 17:46:49.669241 sshd-session[12503]: pam_unix(sshd:session): session closed for user core May 27 17:46:49.672764 systemd[1]: sshd@11-10.200.8.12:22-10.200.16.10:50902.service: Deactivated successfully. May 27 17:46:49.674329 systemd[1]: session-14.scope: Deactivated successfully. May 27 17:46:49.675031 systemd-logind[1899]: Session 14 logged out. Waiting for processes to exit. May 27 17:46:49.675997 systemd-logind[1899]: Removed session 14. May 27 17:46:49.783023 systemd[1]: Started sshd@12-10.200.8.12:22-10.200.16.10:50904.service - OpenSSH per-connection server daemon (10.200.16.10:50904). May 27 17:46:50.412882 sshd[12511]: Accepted publickey for core from 10.200.16.10 port 50904 ssh2: RSA SHA256:43KLdvDzM7BELJcS29r3hKkL8hXR8u1eeLIPzvzInuc May 27 17:46:50.414252 sshd-session[12511]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:46:50.418709 systemd-logind[1899]: New session 15 of user core. May 27 17:46:50.430801 systemd[1]: Started session-15.scope - Session 15 of User core. May 27 17:46:50.874145 sshd[12513]: Connection closed by 10.200.16.10 port 50904 May 27 17:46:50.874524 sshd-session[12511]: pam_unix(sshd:session): session closed for user core May 27 17:46:50.878098 systemd[1]: sshd@12-10.200.8.12:22-10.200.16.10:50904.service: Deactivated successfully. May 27 17:46:50.879665 systemd[1]: session-15.scope: Deactivated successfully. May 27 17:46:50.880362 systemd-logind[1899]: Session 15 logged out. Waiting for processes to exit. May 27 17:46:50.881328 systemd-logind[1899]: Removed session 15. May 27 17:46:53.425432 setup-nvidia[2269]: \u000d May 27 17:46:53.425432 setup-nvidia[2269]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most May 27 17:46:53.425432 setup-nvidia[2269]: frequently when this kernel module was built against the wrong or May 27 17:46:53.425432 setup-nvidia[2269]: improperly configured kernel sources, with a version of gcc that differs May 27 17:46:53.425432 setup-nvidia[2269]: from the one used to build the target kernel, or if another driver, such May 27 17:46:53.425432 setup-nvidia[2269]: as nouveau, is present and prevents the NVIDIA kernel module from May 27 17:46:53.425432 setup-nvidia[2269]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device May 27 17:46:53.425432 setup-nvidia[2269]: installed in this system is supported by this NVIDIA Linux graphics May 27 17:46:53.425432 setup-nvidia[2269]: driver release. May 27 17:46:53.425432 setup-nvidia[2269]: \u000d May 27 17:46:53.425432 setup-nvidia[2269]: Please see the log entries 'Kernel module load error' and 'Kernel May 27 17:46:53.425432 setup-nvidia[2269]: messages' at the end of the file May 27 17:46:53.425432 setup-nvidia[2269]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more May 27 17:46:53.425432 setup-nvidia[2269]: information. May 27 17:46:53.425432 setup-nvidia[2269]: \u000d May 27 17:46:53.440848 setup-nvidia[2269]: dmesg: read kernel buffer failed: Operation not permitted May 27 17:46:53.459710 setup-nvidia[2269]: \u000d May 27 17:46:53.459710 setup-nvidia[2269]: ERROR: Installation has failed. Please see the file May 27 17:46:53.459710 setup-nvidia[2269]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for May 27 17:46:53.459710 setup-nvidia[2269]: details. You may find suggestions on fixing installation problems in May 27 17:46:53.459710 setup-nvidia[2269]: the README available on the Linux driver download page at May 27 17:46:53.459710 setup-nvidia[2269]: www.nvidia.com. May 27 17:46:53.459710 setup-nvidia[2269]: \u000d May 27 17:46:53.460238 setup-nvidia[2269]: Last 50 lines of nvidia-installer.log: May 27 17:46:53.461024 setup-nvidia[2269]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039000rm+0x53: 'naked' return found in MITIGATION_RETHUNK build May 27 17:46:53.461024 setup-nvidia[2269]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038997rm+0x90: 'naked' return found in MITIGATION_RETHUNK build May 27 17:46:53.461024 setup-nvidia[2269]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038990rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build May 27 17:46:53.461024 setup-nvidia[2269]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039046rm+0x93: 'naked' return found in MITIGATION_RETHUNK build May 27 17:46:53.461024 setup-nvidia[2269]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039042rm+0x36: 'naked' return found in MITIGATION_RETHUNK build May 27 17:46:53.461024 setup-nvidia[2269]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039041rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build May 27 17:46:53.461024 setup-nvidia[2269]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039043rm+0x77: 'naked' return found in MITIGATION_RETHUNK build May 27 17:46:53.461024 setup-nvidia[2269]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038987rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build May 27 17:46:53.461024 setup-nvidia[2269]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030558rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build May 27 17:46:53.461024 setup-nvidia[2269]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030557rm+0x27: 'naked' return found in MITIGATION_RETHUNK build May 27 17:46:53.461024 setup-nvidia[2269]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030561rm+0x37: 'naked' return found in MITIGATION_RETHUNK build May 27 17:46:53.461024 setup-nvidia[2269]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030560rm+0x24: 'naked' return found in MITIGATION_RETHUNK build May 27 17:46:53.461024 setup-nvidia[2269]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030559rm+0xca: 'naked' return found in MITIGATION_RETHUNK build May 27 17:46:53.461024 setup-nvidia[2269]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030562rm+0x193: 'naked' return found in MITIGATION_RETHUNK build May 27 17:46:53.461024 setup-nvidia[2269]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030564rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build May 27 17:46:53.461024 setup-nvidia[2269]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv011860rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build May 27 17:46:53.461024 setup-nvidia[2269]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039354rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build May 27 17:46:53.461513 setup-nvidia[2269]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/Module.symvers May 27 17:46:53.461513 setup-nvidia[2269]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.mod.o May 27 17:46:53.461513 setup-nvidia[2269]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/.module-common.o May 27 17:46:53.461513 setup-nvidia[2269]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.mod.o May 27 17:46:53.461513 setup-nvidia[2269]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.mod.o May 27 17:46:53.461513 setup-nvidia[2269]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.mod.o May 27 17:46:53.461513 setup-nvidia[2269]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.mod.o May 27 17:46:53.461513 setup-nvidia[2269]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko May 27 17:46:53.461513 setup-nvidia[2269]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko May 27 17:46:53.461513 setup-nvidia[2269]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko May 27 17:46:53.461513 setup-nvidia[2269]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko May 27 17:46:53.461513 setup-nvidia[2269]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko May 27 17:46:53.461513 setup-nvidia[2269]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko due to unavailability of vmlinux May 27 17:46:53.461513 setup-nvidia[2269]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko May 27 17:46:53.461513 setup-nvidia[2269]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko May 27 17:46:53.461513 setup-nvidia[2269]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko due to unavailability of vmlinux May 27 17:46:53.461513 setup-nvidia[2269]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko May 27 17:46:53.461513 setup-nvidia[2269]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko due to unavailability of vmlinux May 27 17:46:53.461513 setup-nvidia[2269]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko May 27 17:46:53.461513 setup-nvidia[2269]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko due to unavailability of vmlinux May 27 17:46:53.461513 setup-nvidia[2269]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko May 27 17:46:53.461513 setup-nvidia[2269]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko due to unavailability of vmlinux May 27 17:46:53.461513 setup-nvidia[2269]: make[2]: Leaving directory '/usr/lib/modules/6.12.30-flatcar/build' May 27 17:46:53.461513 setup-nvidia[2269]: make[1]: Leaving directory '/usr/lib/modules/6.12.30-flatcar/source' May 27 17:46:53.461513 setup-nvidia[2269]: -> done. May 27 17:46:53.461513 setup-nvidia[2269]: -> Kernel module compilation complete. May 27 17:46:53.461513 setup-nvidia[2269]: -> Unable to determine if Secure Boot is enabled: No such file or directory May 27 17:46:53.461513 setup-nvidia[2269]: 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. May 27 17:46:53.461513 setup-nvidia[2269]: \u000d May 27 17:46:53.461513 setup-nvidia[2269]: 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. May 27 17:46:53.461513 setup-nvidia[2269]: -> Kernel module load error: Operation not permitted May 27 17:46:53.461513 setup-nvidia[2269]: -> Kernel messages: May 27 17:46:53.461513 setup-nvidia[2269]: 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. May 27 17:47:00.992051 systemd[1]: Started sshd@13-10.200.8.12:22-10.200.16.10:45520.service - OpenSSH per-connection server daemon (10.200.16.10:45520). May 27 17:47:01.633069 sshd[12699]: Accepted publickey for core from 10.200.16.10 port 45520 ssh2: RSA SHA256:43KLdvDzM7BELJcS29r3hKkL8hXR8u1eeLIPzvzInuc May 27 17:47:01.634437 sshd-session[12699]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:47:01.638870 systemd-logind[1899]: New session 16 of user core. May 27 17:47:01.651723 systemd[1]: Started session-16.scope - Session 16 of User core. May 27 17:47:02.479964 sshd[12702]: Connection closed by 10.200.16.10 port 45520 May 27 17:47:02.210020 systemd[1]: Started sshd@14-10.200.8.12:22-10.200.16.10:45530.service - OpenSSH per-connection server daemon (10.200.16.10:45530). May 27 17:47:02.479998 sshd-session[12699]: pam_unix(sshd:session): session closed for user core May 27 17:47:02.486780 systemd[1]: sshd@13-10.200.8.12:22-10.200.16.10:45520.service: Deactivated successfully. May 27 17:47:02.488417 systemd[1]: session-16.scope: Deactivated successfully. May 27 17:47:02.489140 systemd-logind[1899]: Session 16 logged out. Waiting for processes to exit. May 27 17:47:02.490187 systemd-logind[1899]: Removed session 16. May 27 17:47:02.838689 sshd[12705]: Accepted publickey for core from 10.200.16.10 port 45530 ssh2: RSA SHA256:43KLdvDzM7BELJcS29r3hKkL8hXR8u1eeLIPzvzInuc May 27 17:47:02.840111 sshd-session[12705]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:47:02.844762 systemd-logind[1899]: New session 17 of user core. May 27 17:47:02.854717 systemd[1]: Started session-17.scope - Session 17 of User core. May 27 17:47:03.281576 sshd[12711]: Connection closed by 10.200.16.10 port 45530 May 27 17:47:03.281943 sshd-session[12705]: pam_unix(sshd:session): session closed for user core May 27 17:47:03.285457 systemd[1]: sshd@14-10.200.8.12:22-10.200.16.10:45530.service: Deactivated successfully. May 27 17:47:03.287031 systemd[1]: session-17.scope: Deactivated successfully. May 27 17:47:03.287778 systemd-logind[1899]: Session 17 logged out. Waiting for processes to exit. May 27 17:47:03.288732 systemd-logind[1899]: Removed session 17. May 27 17:47:05.362617 kernel: EXT4-fs (loop6p9): unmounting filesystem dd441403-e7f4-4782-b932-a2370dfaea27. May 27 17:47:05.362964 setup-nvidia[2269]: Container flatcardevelopercontainer-4344.0.0.bin exited successfully. May 27 17:47:05.365226 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4344.0.0.bin.mount: Deactivated successfully. May 27 17:47:05.427491 sudo[2252]: pam_unix(sudo:session): session closed for user root May 27 17:47:05.429466 systemd[1]: session-c2.scope: Deactivated successfully. May 27 17:47:05.429735 systemd[1]: session-c2.scope: Consumed 5min 9.999s CPU time, 892.8M memory peak. May 27 17:47:05.844293 setup-nvidia[1892]: /opt/nvidia / May 27 17:47:05.845265 setup-nvidia[1892]: / May 27 17:47:05.854601 kernel: IPMI message handler: version 39.2 May 27 17:47:05.857605 kernel: ipmi device interface May 27 17:47:05.870327 setup-nvidia[1892]: /opt/nvidia/current/usr/lib/modules/6.12.30-flatcar/video / May 27 17:47:05.923448 kernel: nvidia: loading out-of-tree module taints kernel. May 27 17:47:05.923574 kernel: nvidia: module license 'NVIDIA' taints kernel. May 27 17:47:05.923617 kernel: Disabling lock debugging due to kernel taint May 27 17:47:05.930693 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel May 27 17:47:05.930733 kernel: nvidia: module license taints kernel. May 27 17:47:06.322610 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 May 27 17:47:06.718599 kernel: May 27 17:47:06.718735 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) May 27 17:47:06.925605 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.230.02 Fri Dec 20 21:42:05 UTC 2024 May 27 17:47:06.947607 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.230.02 Fri Dec 20 21:52:34 UTC 2024 May 27 17:47:06.966618 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. May 27 17:47:07.022081 setup-nvidia[1892]: / May 27 17:47:07.022598 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. May 27 17:47:07.037243 setup-nvidia[12750]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. May 27 17:47:08.783518 setup-nvidia[12767]: Tue May 27 17:47:08 2025 May 27 17:47:08.783518 setup-nvidia[12767]: +---------------------------------------------------------------------------------------+ May 27 17:47:08.783518 setup-nvidia[12767]: | NVIDIA-SMI 535.230.02 Driver Version: 535.230.02 CUDA Version: 12.2 | May 27 17:47:08.783518 setup-nvidia[12767]: |-----------------------------------------+----------------------+----------------------+ May 27 17:47:08.783518 setup-nvidia[12767]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | May 27 17:47:08.783518 setup-nvidia[12767]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | May 27 17:47:08.783518 setup-nvidia[12767]: | | | MIG M. | May 27 17:47:08.783518 setup-nvidia[12767]: |=========================================+======================+======================| May 27 17:47:08.786976 setup-nvidia[12767]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | May 27 17:47:08.786976 setup-nvidia[12767]: | N/A 24C P0 36W / 250W | 0MiB / 16384MiB | 0% Default | May 27 17:47:08.786976 setup-nvidia[12767]: | | | N/A | May 27 17:47:08.786976 setup-nvidia[12767]: +-----------------------------------------+----------------------+----------------------+ May 27 17:47:08.786976 setup-nvidia[12767]: May 27 17:47:08.786976 setup-nvidia[12767]: +---------------------------------------------------------------------------------------+ May 27 17:47:08.786976 setup-nvidia[12767]: | Processes: | May 27 17:47:08.786976 setup-nvidia[12767]: | GPU GI CI PID Type Process name GPU Memory | May 27 17:47:08.786976 setup-nvidia[12767]: | ID ID Usage | May 27 17:47:08.786976 setup-nvidia[12767]: |=======================================================================================| May 27 17:47:08.787350 setup-nvidia[12767]: | No running processes found | May 27 17:47:08.787350 setup-nvidia[12767]: +---------------------------------------------------------------------------------------+ May 27 17:47:09.167728 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. May 27 17:47:09.169758 systemd[1]: Reached target multi-user.target - Multi-User System. May 27 17:47:09.175751 systemd[1]: Startup finished in 925ms (firmware) + 31.256s (loader) + 3.824s (kernel) + 11.445s (initrd) + 2min 44.268s (userspace) = 3min 31.719s. May 27 17:47:13.392990 systemd[1]: Started sshd@15-10.200.8.12:22-10.200.16.10:59752.service - OpenSSH per-connection server daemon (10.200.16.10:59752). May 27 17:47:14.023938 sshd[12774]: Accepted publickey for core from 10.200.16.10 port 59752 ssh2: RSA SHA256:43KLdvDzM7BELJcS29r3hKkL8hXR8u1eeLIPzvzInuc May 27 17:47:14.025243 sshd-session[12774]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:47:14.029660 systemd-logind[1899]: New session 18 of user core. May 27 17:47:14.047703 systemd[1]: Started session-18.scope - Session 18 of User core. May 27 17:47:14.485127 sshd[12776]: Connection closed by 10.200.16.10 port 59752 May 27 17:47:14.485483 sshd-session[12774]: pam_unix(sshd:session): session closed for user core May 27 17:47:14.488663 systemd[1]: sshd@15-10.200.8.12:22-10.200.16.10:59752.service: Deactivated successfully. May 27 17:47:14.490186 systemd[1]: session-18.scope: Deactivated successfully. May 27 17:47:14.490905 systemd-logind[1899]: Session 18 logged out. Waiting for processes to exit. May 27 17:47:14.491834 systemd-logind[1899]: Removed session 18. May 27 17:47:14.599730 systemd[1]: Started sshd@16-10.200.8.12:22-10.200.16.10:59766.service - OpenSSH per-connection server daemon (10.200.16.10:59766). May 27 17:47:15.226765 sshd[12782]: Accepted publickey for core from 10.200.16.10 port 59766 ssh2: RSA SHA256:43KLdvDzM7BELJcS29r3hKkL8hXR8u1eeLIPzvzInuc May 27 17:47:15.228099 sshd-session[12782]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:47:15.232447 systemd-logind[1899]: New session 19 of user core. May 27 17:47:15.242703 systemd[1]: Started session-19.scope - Session 19 of User core. May 27 17:47:15.483951 systemd[1]: Stopping user@0.service - User Manager for UID 0... May 27 17:47:15.484788 systemd[2256]: Activating special unit exit.target... May 27 17:47:15.484873 systemd[2256]: Stopped target default.target - Main User Target. May 27 17:47:15.484914 systemd[2256]: Stopped target basic.target - Basic System. May 27 17:47:15.484942 systemd[2256]: Stopped target paths.target - Paths. May 27 17:47:15.484970 systemd[2256]: Stopped target sockets.target - Sockets. May 27 17:47:15.484997 systemd[2256]: Stopped target timers.target - Timers. May 27 17:47:15.485345 systemd[2256]: Closed dbus.socket - D-Bus User Message Bus Socket. May 27 17:47:15.485557 systemd[2256]: Removed slice app.slice - User Application Slice. May 27 17:47:15.485606 systemd[2256]: Reached target shutdown.target - Shutdown. May 27 17:47:15.485756 systemd[2256]: Finished systemd-exit.service - Exit the Session. May 27 17:47:15.485793 systemd[2256]: Reached target exit.target - Exit the Session. May 27 17:47:15.498560 (sd-pam)[2257]: pam_unix(systemd-user:session): session closed for user root May 27 17:47:15.499893 systemd[1]: user@0.service: Deactivated successfully. May 27 17:47:15.500237 systemd[1]: Stopped user@0.service - User Manager for UID 0. May 27 17:47:15.502499 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... May 27 17:47:15.524058 systemd[1]: run-user-0.mount: Deactivated successfully. May 27 17:47:15.524956 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. May 27 17:47:15.525143 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. May 27 17:47:15.526652 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. May 27 17:47:15.526745 systemd[1]: user-0.slice: Consumed 5min 10.284s CPU time, 901.4M memory peak. May 27 17:47:15.527137 systemd-logind[1899]: Removed session c3. May 27 17:47:15.666376 sshd[12784]: Connection closed by 10.200.16.10 port 59766 May 27 17:47:15.666766 sshd-session[12782]: pam_unix(sshd:session): session closed for user core May 27 17:47:15.669559 systemd[1]: sshd@16-10.200.8.12:22-10.200.16.10:59766.service: Deactivated successfully. May 27 17:47:15.671901 systemd[1]: session-19.scope: Deactivated successfully. May 27 17:47:15.673202 systemd-logind[1899]: Session 19 logged out. Waiting for processes to exit. May 27 17:47:15.674311 systemd-logind[1899]: Removed session 19. May 27 17:47:15.779980 systemd[1]: Started sshd@17-10.200.8.12:22-10.200.16.10:59768.service - OpenSSH per-connection server daemon (10.200.16.10:59768). May 27 17:47:16.408992 sshd[12794]: Accepted publickey for core from 10.200.16.10 port 59768 ssh2: RSA SHA256:43KLdvDzM7BELJcS29r3hKkL8hXR8u1eeLIPzvzInuc May 27 17:47:16.410301 sshd-session[12794]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:47:16.414873 systemd-logind[1899]: New session 20 of user core. May 27 17:47:16.422712 systemd[1]: Started session-20.scope - Session 20 of User core. May 27 17:47:16.882684 sshd[12796]: Connection closed by 10.200.16.10 port 59768 May 27 17:47:16.883046 sshd-session[12794]: pam_unix(sshd:session): session closed for user core May 27 17:47:16.886482 systemd[1]: sshd@17-10.200.8.12:22-10.200.16.10:59768.service: Deactivated successfully. May 27 17:47:16.888074 systemd[1]: session-20.scope: Deactivated successfully. May 27 17:47:16.888752 systemd-logind[1899]: Session 20 logged out. Waiting for processes to exit. May 27 17:47:16.889730 systemd-logind[1899]: Removed session 20. May 27 17:47:16.997028 systemd[1]: Started sshd@18-10.200.8.12:22-10.200.16.10:59776.service - OpenSSH per-connection server daemon (10.200.16.10:59776). May 27 17:47:17.637050 sshd[12802]: Accepted publickey for core from 10.200.16.10 port 59776 ssh2: RSA SHA256:43KLdvDzM7BELJcS29r3hKkL8hXR8u1eeLIPzvzInuc May 27 17:47:17.638394 sshd-session[12802]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:47:17.642763 systemd-logind[1899]: New session 21 of user core. May 27 17:47:17.660714 systemd[1]: Started session-21.scope - Session 21 of User core. May 27 17:47:17.981974 sudo[12805]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 May 27 17:47:17.982281 sudo[12805]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 27 17:47:18.884904 sudo[12805]: pam_unix(sudo:session): session closed for user root May 27 17:47:18.987199 sshd[12804]: Connection closed by 10.200.16.10 port 59776 May 27 17:47:18.987574 sshd-session[12802]: pam_unix(sshd:session): session closed for user core May 27 17:47:18.991287 systemd[1]: sshd@18-10.200.8.12:22-10.200.16.10:59776.service: Deactivated successfully. May 27 17:47:18.993013 systemd[1]: session-21.scope: Deactivated successfully. May 27 17:47:18.994524 systemd-logind[1899]: Session 21 logged out. Waiting for processes to exit. May 27 17:47:18.995757 systemd-logind[1899]: Removed session 21. May 27 17:47:19.110007 systemd[1]: Started sshd@19-10.200.8.12:22-10.200.16.10:58858.service - OpenSSH per-connection server daemon (10.200.16.10:58858). May 27 17:47:19.756496 sshd[12811]: Accepted publickey for core from 10.200.16.10 port 58858 ssh2: RSA SHA256:43KLdvDzM7BELJcS29r3hKkL8hXR8u1eeLIPzvzInuc May 27 17:47:19.757876 sshd-session[12811]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:47:19.762057 systemd-logind[1899]: New session 22 of user core. May 27 17:47:19.776708 systemd[1]: Started session-22.scope - Session 22 of User core. May 27 17:47:20.104019 sudo[12815]: 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 May 27 17:47:20.104317 sudo[12815]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 27 17:47:20.111576 sudo[12815]: pam_unix(sudo:session): session closed for user root May 27 17:47:20.116970 sudo[12814]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules May 27 17:47:20.117247 sudo[12814]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 27 17:47:20.126467 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 27 17:47:20.170230 augenrules[12837]: No rules May 27 17:47:20.171466 systemd[1]: audit-rules.service: Deactivated successfully. May 27 17:47:20.171759 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 27 17:47:20.172625 sudo[12814]: pam_unix(sudo:session): session closed for user root May 27 17:47:20.274891 sshd[12813]: Connection closed by 10.200.16.10 port 58858 May 27 17:47:20.275238 sshd-session[12811]: pam_unix(sshd:session): session closed for user core May 27 17:47:20.278684 systemd[1]: sshd@19-10.200.8.12:22-10.200.16.10:58858.service: Deactivated successfully. May 27 17:47:20.280303 systemd[1]: session-22.scope: Deactivated successfully. May 27 17:47:20.281036 systemd-logind[1899]: Session 22 logged out. Waiting for processes to exit. May 27 17:47:20.282030 systemd-logind[1899]: Removed session 22. May 27 17:47:20.389783 systemd[1]: Started sshd@20-10.200.8.12:22-10.200.16.10:58866.service - OpenSSH per-connection server daemon (10.200.16.10:58866). May 27 17:47:21.017057 sshd[12846]: Accepted publickey for core from 10.200.16.10 port 58866 ssh2: RSA SHA256:43KLdvDzM7BELJcS29r3hKkL8hXR8u1eeLIPzvzInuc May 27 17:47:21.018346 sshd-session[12846]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:47:21.022653 systemd-logind[1899]: New session 23 of user core. May 27 17:47:21.039697 systemd[1]: Started session-23.scope - Session 23 of User core. May 27 17:47:21.361737 sudo[12849]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/mkdir -p . May 27 17:47:21.362030 sudo[12849]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 27 17:47:21.365319 sudo[12849]: pam_unix(sudo:session): session closed for user root May 27 17:47:21.468110 sshd[12848]: Connection closed by 10.200.16.10 port 58866 May 27 17:47:21.468401 sshd-session[12846]: pam_unix(sshd:session): session closed for user core May 27 17:47:21.471546 systemd[1]: sshd@20-10.200.8.12:22-10.200.16.10:58866.service: Deactivated successfully. May 27 17:47:21.473100 systemd[1]: session-23.scope: Deactivated successfully. May 27 17:47:21.473834 systemd-logind[1899]: Session 23 logged out. Waiting for processes to exit. May 27 17:47:21.474788 systemd-logind[1899]: Removed session 23. May 27 17:47:21.583717 systemd[1]: Started sshd@21-10.200.8.12:22-10.200.16.10:58880.service - OpenSSH per-connection server daemon (10.200.16.10:58880). May 27 17:47:22.210886 sshd[12855]: Accepted publickey for core from 10.200.16.10 port 58880 ssh2: RSA SHA256:43KLdvDzM7BELJcS29r3hKkL8hXR8u1eeLIPzvzInuc May 27 17:47:22.212161 sshd-session[12855]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:47:22.216355 systemd-logind[1899]: New session 24 of user core. May 27 17:47:22.238705 systemd[1]: Started session-24.scope - Session 24 of User core. May 27 17:47:22.555211 sudo[12858]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/install -m 0755 /dev/stdin kolet May 27 17:47:22.555502 sudo[12858]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 27 17:47:31.610799 sudo[12858]: pam_unix(sudo:session): session closed for user root May 27 17:47:31.729004 sshd[12857]: Connection closed by 10.200.16.10 port 58880 May 27 17:47:31.729282 sshd-session[12855]: pam_unix(sshd:session): session closed for user core May 27 17:47:31.732754 systemd[1]: sshd@21-10.200.8.12:22-10.200.16.10:58880.service: Deactivated successfully. May 27 17:47:31.734395 systemd[1]: session-24.scope: Deactivated successfully. May 27 17:47:31.735095 systemd-logind[1899]: Session 24 logged out. Waiting for processes to exit. May 27 17:47:31.736140 systemd-logind[1899]: Removed session 24. May 27 17:47:31.843759 systemd[1]: Started sshd@22-10.200.8.12:22-10.200.16.10:40174.service - OpenSSH per-connection server daemon (10.200.16.10:40174). May 27 17:47:32.479095 sshd[12864]: Accepted publickey for core from 10.200.16.10 port 40174 ssh2: RSA SHA256:43KLdvDzM7BELJcS29r3hKkL8hXR8u1eeLIPzvzInuc May 27 17:47:32.480385 sshd-session[12864]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:47:32.484610 systemd-logind[1899]: New session 25 of user core. May 27 17:47:32.501711 systemd[1]: Started session-25.scope - Session 25 of User core. May 27 17:47:33.558992 systemd[1]: Starting docker.service - Docker Application Container Engine... May 27 17:47:33.578914 (dockerd)[12887]: 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 May 27 17:47:34.777419 dockerd[12887]: time="2025-05-27T17:47:34.777361860Z" level=info msg="Starting up" May 27 17:47:34.778082 dockerd[12887]: time="2025-05-27T17:47:34.778055639Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" May 27 17:47:35.514565 dockerd[12887]: time="2025-05-27T17:47:35.514511516Z" level=info msg="Loading containers: start." May 27 17:47:35.528599 kernel: Initializing XFRM netlink socket May 27 17:47:35.769636 systemd-networkd[1569]: docker0: Link UP May 27 17:47:35.785512 dockerd[12887]: time="2025-05-27T17:47:35.785467945Z" level=info msg="Loading containers: done." May 27 17:47:35.813159 dockerd[12887]: time="2025-05-27T17:47:35.813113081Z" 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 May 27 17:47:35.813273 dockerd[12887]: time="2025-05-27T17:47:35.813187979Z" level=info msg="Docker daemon" commit=bbd0a17ccc67e48d4a69393287b7fcc4f0578683 containerd-snapshotter=false storage-driver=overlay2 version=28.0.1 May 27 17:47:35.813314 dockerd[12887]: time="2025-05-27T17:47:35.813287276Z" level=info msg="Initializing buildkit" May 27 17:47:35.817054 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck3647970425-merged.mount: Deactivated successfully. May 27 17:47:35.862282 dockerd[12887]: time="2025-05-27T17:47:35.862249445Z" level=info msg="Completed buildkit initialization" May 27 17:47:35.868109 dockerd[12887]: time="2025-05-27T17:47:35.868080163Z" level=info msg="Daemon has completed initialization" May 27 17:47:35.868166 dockerd[12887]: time="2025-05-27T17:47:35.868125761Z" level=info msg="API listen on /run/docker.sock" May 27 17:47:35.868235 systemd[1]: Started docker.service - Docker Application Container Engine. May 27 17:47:36.966048 systemd[1]: var-lib-docker-overlay2-df5aeb592f61e1b4f8b5d0f4d901756ad6dde5413c59aa7f36eda11d86b618e3\x2dinit-merged.mount: Deactivated successfully. May 27 17:47:37.064177 containerd[1916]: time="2025-05-27T17:47:37.064126272Z" level=info msg="connecting to shim 353af0146ab436c311e24c01bacfd4fd7ddedd3235e746e2985a22e5575e814d" address="unix:///run/containerd/s/6cb1c134d8870c9664ff97d2cf4c3688fcc18a96ad7167f20973b728c5273d81" namespace=moby protocol=ttrpc version=3 May 27 17:47:37.098707 systemd[1]: Started docker-353af0146ab436c311e24c01bacfd4fd7ddedd3235e746e2985a22e5575e814d.scope - libcontainer container 353af0146ab436c311e24c01bacfd4fd7ddedd3235e746e2985a22e5575e814d. May 27 17:47:37.149771 kernel: docker0: port 1(veth4f0924e) entered blocking state May 27 17:47:37.149861 kernel: docker0: port 1(veth4f0924e) entered disabled state May 27 17:47:37.152320 kernel: veth4f0924e: entered allmulticast mode May 27 17:47:37.154746 kernel: veth4f0924e: entered promiscuous mode May 27 17:47:37.155253 systemd-networkd[1569]: veth4f0924e: Link UP May 27 17:47:37.177600 kernel: eth0: renamed from veth9d8438f May 27 17:47:37.183756 kernel: docker0: port 1(veth4f0924e) entered blocking state May 27 17:47:37.183804 kernel: docker0: port 1(veth4f0924e) entered forwarding state May 27 17:47:37.183853 systemd-networkd[1569]: veth4f0924e: Gained carrier May 27 17:47:37.184119 systemd-networkd[1569]: docker0: Gained carrier May 27 17:47:37.218857 systemd[1]: docker-353af0146ab436c311e24c01bacfd4fd7ddedd3235e746e2985a22e5575e814d.scope: Deactivated successfully. May 27 17:47:37.240152 containerd[1916]: time="2025-05-27T17:47:37.240115271Z" level=info msg="shim disconnected" id=353af0146ab436c311e24c01bacfd4fd7ddedd3235e746e2985a22e5575e814d namespace=moby May 27 17:47:37.240277 dockerd[12887]: time="2025-05-27T17:47:37.240225367Z" level=info msg="ignoring event" container=353af0146ab436c311e24c01bacfd4fd7ddedd3235e746e2985a22e5575e814d module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" May 27 17:47:37.240557 containerd[1916]: time="2025-05-27T17:47:37.240306465Z" level=warning msg="cleaning up after shim disconnected" id=353af0146ab436c311e24c01bacfd4fd7ddedd3235e746e2985a22e5575e814d namespace=moby May 27 17:47:37.240557 containerd[1916]: time="2025-05-27T17:47:37.240321564Z" level=info msg="cleaning up dead shim" namespace=moby May 27 17:47:37.267391 systemd-networkd[1569]: veth4f0924e: Lost carrier May 27 17:47:37.267595 kernel: docker0: port 1(veth4f0924e) entered disabled state May 27 17:47:37.267633 kernel: veth9d8438f: renamed from eth0 May 27 17:47:37.286824 systemd-networkd[1569]: veth4f0924e: Link DOWN May 27 17:47:37.290682 kernel: docker0: port 1(veth4f0924e) entered disabled state May 27 17:47:37.290736 kernel: veth4f0924e (unregistering): left allmulticast mode May 27 17:47:37.296815 kernel: veth4f0924e (unregistering): left promiscuous mode May 27 17:47:37.300381 kernel: docker0: port 1(veth4f0924e) entered disabled state May 27 17:47:37.459518 sshd[12866]: Connection closed by 10.200.16.10 port 40174 May 27 17:47:37.459894 sshd-session[12864]: pam_unix(sshd:session): session closed for user core May 27 17:47:37.463317 systemd[1]: sshd@22-10.200.8.12:22-10.200.16.10:40174.service: Deactivated successfully. May 27 17:47:37.464914 systemd[1]: session-25.scope: Deactivated successfully. May 27 17:47:37.465558 systemd-logind[1899]: Session 25 logged out. Waiting for processes to exit. May 27 17:47:37.466541 systemd-logind[1899]: Removed session 25. May 27 17:47:37.573770 systemd[1]: Started sshd@23-10.200.8.12:22-10.200.16.10:40188.service - OpenSSH per-connection server daemon (10.200.16.10:40188). May 27 17:47:37.961914 systemd[1]: var-lib-docker-overlay2-df5aeb592f61e1b4f8b5d0f4d901756ad6dde5413c59aa7f36eda11d86b618e3-merged.mount: Deactivated successfully. May 27 17:47:38.180837 systemd-networkd[1569]: docker0: Lost carrier May 27 17:47:38.199970 sshd[13167]: Accepted publickey for core from 10.200.16.10 port 40188 ssh2: RSA SHA256:43KLdvDzM7BELJcS29r3hKkL8hXR8u1eeLIPzvzInuc May 27 17:47:38.201287 sshd-session[13167]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:47:38.205534 systemd-logind[1899]: New session 26 of user core. May 27 17:47:38.226713 systemd[1]: Started session-26.scope - Session 26 of User core. May 27 17:47:38.657638 ntpdate[13180]: ntpdate 4.2.8p17@1.4004-o Tue May 27 14:54:37 UTC 2025 (1) May 27 17:47:39.076721 systemd-networkd[1569]: docker0: Gained IPv6LL May 27 17:47:45.388727 ntpdate[13180]: adjust time server 193.1.8.106 offset -0.000384 sec May 27 17:47:45.492387 sshd[13169]: Connection closed by 10.200.16.10 port 40188 May 27 17:47:45.492802 sshd-session[13167]: pam_unix(sshd:session): session closed for user core May 27 17:47:45.496350 systemd[1]: sshd@23-10.200.8.12:22-10.200.16.10:40188.service: Deactivated successfully. May 27 17:47:45.498000 systemd[1]: session-26.scope: Deactivated successfully. May 27 17:47:45.498669 systemd-logind[1899]: Session 26 logged out. Waiting for processes to exit. May 27 17:47:45.499622 systemd-logind[1899]: Removed session 26. May 27 17:47:45.603956 systemd[1]: Started sshd@24-10.200.8.12:22-10.200.16.10:54240.service - OpenSSH per-connection server daemon (10.200.16.10:54240). May 27 17:47:46.244077 sshd[13185]: Accepted publickey for core from 10.200.16.10 port 54240 ssh2: RSA SHA256:43KLdvDzM7BELJcS29r3hKkL8hXR8u1eeLIPzvzInuc May 27 17:47:46.245355 sshd-session[13185]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:47:46.249563 systemd-logind[1899]: New session 27 of user core. May 27 17:47:46.260705 systemd[1]: Started session-27.scope - Session 27 of User core. May 27 17:47:46.758313 sshd[13187]: Connection closed by 10.200.16.10 port 54240 May 27 17:47:46.758672 sshd-session[13185]: pam_unix(sshd:session): session closed for user core May 27 17:47:46.761964 systemd[1]: sshd@24-10.200.8.12:22-10.200.16.10:54240.service: Deactivated successfully. May 27 17:47:46.763557 systemd[1]: session-27.scope: Deactivated successfully. May 27 17:47:46.764228 systemd-logind[1899]: Session 27 logged out. Waiting for processes to exit. May 27 17:47:46.765161 systemd-logind[1899]: Removed session 27. May 27 17:47:46.873382 systemd[1]: Started sshd@25-10.200.8.12:22-10.200.16.10:54252.service - OpenSSH per-connection server daemon (10.200.16.10:54252). May 27 17:47:47.499039 sshd[13204]: Accepted publickey for core from 10.200.16.10 port 54252 ssh2: RSA SHA256:43KLdvDzM7BELJcS29r3hKkL8hXR8u1eeLIPzvzInuc May 27 17:47:47.500294 sshd-session[13204]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 17:47:47.504902 systemd-logind[1899]: New session 28 of user core. May 27 17:47:47.517703 systemd[1]: Started session-28.scope - Session 28 of User core. May 27 17:47:47.896654 systemd[1]: var-lib-docker-overlay2-07bf783b3455d7883b2675d0b4bf504747c5e2a7830e5f32089d28366d4abfcb\x2dinit-merged.mount: Deactivated successfully. May 27 17:47:48.028473 containerd[1916]: time="2025-05-27T17:47:48.028245226Z" level=info msg="connecting to shim c33f4225f5d9fce85dacd684463329d5b30bda5395f3d5ba85c57ea0a6f9c0e5" address="unix:///run/containerd/s/b21285be1e640521174eddcb0ea3f722a1961e74ddcd8818879e3cb49d68a18e" namespace=moby protocol=ttrpc version=3 May 27 17:47:48.074731 systemd[1]: Started docker-c33f4225f5d9fce85dacd684463329d5b30bda5395f3d5ba85c57ea0a6f9c0e5.scope - libcontainer container c33f4225f5d9fce85dacd684463329d5b30bda5395f3d5ba85c57ea0a6f9c0e5. May 27 17:47:48.117344 kernel: docker0: port 1(vethd16181c) entered blocking state May 27 17:47:48.117432 kernel: docker0: port 1(vethd16181c) entered disabled state May 27 17:47:48.120193 kernel: vethd16181c: entered allmulticast mode May 27 17:47:48.123752 kernel: vethd16181c: entered promiscuous mode May 27 17:47:48.124341 systemd-networkd[1569]: vethd16181c: Link UP May 27 17:47:48.141600 kernel: eth0: renamed from veth3b87a94 May 27 17:47:48.148425 kernel: docker0: port 1(vethd16181c) entered blocking state May 27 17:47:48.148475 kernel: docker0: port 1(vethd16181c) entered forwarding state May 27 17:47:48.148838 systemd-networkd[1569]: vethd16181c: Gained carrier May 27 17:47:48.149138 systemd-networkd[1569]: docker0: Gained carrier May 27 17:47:49.892748 systemd-networkd[1569]: vethd16181c: Gained IPv6LL May 27 17:47:51.436754 systemd[1]: docker-c33f4225f5d9fce85dacd684463329d5b30bda5395f3d5ba85c57ea0a6f9c0e5.scope: Deactivated successfully. May 27 17:47:51.448723 containerd[1916]: time="2025-05-27T17:47:51.448682145Z" level=info msg="shim disconnected" id=c33f4225f5d9fce85dacd684463329d5b30bda5395f3d5ba85c57ea0a6f9c0e5 namespace=moby May 27 17:47:51.448723 containerd[1916]: time="2025-05-27T17:47:51.448716644Z" level=warning msg="cleaning up after shim disconnected" id=c33f4225f5d9fce85dacd684463329d5b30bda5395f3d5ba85c57ea0a6f9c0e5 namespace=moby May 27 17:47:51.448723 containerd[1916]: time="2025-05-27T17:47:51.448726244Z" level=info msg="cleaning up dead shim" namespace=moby May 27 17:47:51.449111 dockerd[12887]: time="2025-05-27T17:47:51.448739944Z" level=info msg="ignoring event" container=c33f4225f5d9fce85dacd684463329d5b30bda5395f3d5ba85c57ea0a6f9c0e5 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" May 27 17:47:51.481596 kernel: docker0: port 1(vethd16181c) entered disabled state May 27 17:47:51.481669 kernel: veth3b87a94: renamed from eth0 May 27 17:47:51.481707 systemd-networkd[1569]: vethd16181c: Lost carrier May 27 17:47:51.501684 systemd-networkd[1569]: vethd16181c: Link DOWN May 27 17:47:51.508494 kernel: docker0: port 1(vethd16181c) entered disabled state May 27 17:47:51.508543 kernel: vethd16181c (unregistering): left allmulticast mode May 27 17:47:51.511966 kernel: vethd16181c (unregistering): left promiscuous mode May 27 17:47:51.515299 kernel: docker0: port 1(vethd16181c) entered disabled state May 27 17:47:51.534633 systemd[1]: run-docker-netns-18b17f83b5e0.mount: Deactivated successfully. May 27 17:47:51.537565 systemd[1]: var-lib-docker-overlay2-07bf783b3455d7883b2675d0b4bf504747c5e2a7830e5f32089d28366d4abfcb-merged.mount: Deactivated successfully. May 27 17:47:51.678851 sshd[13208]: Connection closed by 10.200.16.10 port 54252 May 27 17:47:51.679204 sshd-session[13204]: pam_unix(sshd:session): session closed for user core May 27 17:47:51.682848 systemd[1]: sshd@25-10.200.8.12:22-10.200.16.10:54252.service: Deactivated successfully. May 27 17:47:51.684414 systemd[1]: session-28.scope: Deactivated successfully. May 27 17:47:51.685286 systemd-logind[1899]: Session 28 logged out. Waiting for processes to exit. May 27 17:47:51.686464 systemd-logind[1899]: Removed session 28. May 27 17:47:52.516861 systemd-networkd[1569]: docker0: Lost carrier