May 15 12:21:39.190856 kernel: Linux version 6.12.20-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 Thu May 15 10:42:41 -00 2025 May 15 12:21:39.190884 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=48287e633374b880fa618bd42bee102ae77c50831859c6cedd6ca9e1aec3dd5c May 15 12:21:39.190894 kernel: BIOS-provided physical RAM map: May 15 12:21:39.190901 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable May 15 12:21:39.190907 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved May 15 12:21:39.190914 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable May 15 12:21:39.190921 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved May 15 12:21:39.190931 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data May 15 12:21:39.190938 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS May 15 12:21:39.190944 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable May 15 12:21:39.190951 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable May 15 12:21:39.190958 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable May 15 12:21:39.190965 kernel: printk: legacy bootconsole [earlyser0] enabled May 15 12:21:39.190972 kernel: NX (Execute Disable) protection: active May 15 12:21:39.190982 kernel: APIC: Static calls initialized May 15 12:21:39.190990 kernel: efi: EFI v2.7 by Microsoft May 15 12:21:39.190997 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 May 15 12:21:39.191005 kernel: random: crng init done May 15 12:21:39.191012 kernel: secureboot: Secure boot disabled May 15 12:21:39.191020 kernel: SMBIOS 3.1.0 present. May 15 12:21:39.191028 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 May 15 12:21:39.191035 kernel: DMI: Memory slots populated: 3/3 May 15 12:21:39.191042 kernel: Hypervisor detected: Microsoft Hyper-V May 15 12:21:39.191050 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 May 15 12:21:39.191060 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 May 15 12:21:39.191067 kernel: Hyper-V: Using hypercall for remote TLB flush May 15 12:21:39.191074 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns May 15 12:21:39.191082 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns May 15 12:21:39.191090 kernel: tsc: Marking TSC unstable due to running on Hyper-V May 15 12:21:39.191098 kernel: tsc: Detected 2593.992 MHz processor May 15 12:21:39.191106 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved May 15 12:21:39.191114 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable May 15 12:21:39.191122 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 May 15 12:21:39.191132 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs May 15 12:21:39.191139 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT May 15 12:21:39.191147 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved May 15 12:21:39.191155 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved May 15 12:21:39.191162 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 May 15 12:21:39.191170 kernel: Using GB pages for direct mapping May 15 12:21:39.191177 kernel: ACPI: Early table checksum verification disabled May 15 12:21:39.191189 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) May 15 12:21:39.191199 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) May 15 12:21:39.191207 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) May 15 12:21:39.191215 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) May 15 12:21:39.191223 kernel: ACPI: FACS 0x000000003FFFE000 000040 May 15 12:21:39.191231 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) May 15 12:21:39.191240 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) May 15 12:21:39.191250 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) May 15 12:21:39.191258 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) May 15 12:21:39.191266 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) May 15 12:21:39.191274 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) May 15 12:21:39.191282 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) May 15 12:21:39.191290 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] May 15 12:21:39.191298 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] May 15 12:21:39.191306 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] May 15 12:21:39.191316 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] May 15 12:21:39.191324 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] May 15 12:21:39.191332 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] May 15 12:21:39.191340 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] May 15 12:21:39.191348 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] May 15 12:21:39.191356 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] May 15 12:21:39.191364 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] May 15 12:21:39.191372 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug May 15 12:21:39.191380 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug May 15 12:21:39.191390 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug May 15 12:21:39.191398 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug May 15 12:21:39.191406 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug May 15 12:21:39.191414 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug May 15 12:21:39.191422 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug May 15 12:21:39.191430 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug May 15 12:21:39.191438 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] May 15 12:21:39.191447 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] May 15 12:21:39.191455 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] May 15 12:21:39.191465 kernel: Zone ranges: May 15 12:21:39.191473 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] May 15 12:21:39.191481 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] May 15 12:21:39.191489 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] May 15 12:21:39.191498 kernel: Device empty May 15 12:21:39.191506 kernel: Movable zone start for each node May 15 12:21:39.191514 kernel: Early memory node ranges May 15 12:21:39.191522 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] May 15 12:21:39.191530 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] May 15 12:21:39.191540 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] May 15 12:21:39.191548 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] May 15 12:21:39.191556 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] May 15 12:21:39.191564 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] May 15 12:21:39.191572 kernel: On node 0, zone DMA: 1 pages in unavailable ranges May 15 12:21:39.191580 kernel: On node 0, zone DMA: 96 pages in unavailable ranges May 15 12:21:39.191591 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges May 15 12:21:39.191600 kernel: ACPI: PM-Timer IO Port: 0x408 May 15 12:21:39.191608 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) May 15 12:21:39.191619 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 May 15 12:21:39.191627 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) May 15 12:21:39.191635 kernel: ACPI: Using ACPI (MADT) for SMP configuration information May 15 12:21:39.191643 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 May 15 12:21:39.191651 kernel: CPU topo: Max. logical packages: 1 May 15 12:21:39.191659 kernel: CPU topo: Max. logical dies: 1 May 15 12:21:39.191667 kernel: CPU topo: Max. dies per package: 1 May 15 12:21:39.191675 kernel: CPU topo: Max. threads per core: 1 May 15 12:21:39.191683 kernel: CPU topo: Num. cores per package: 6 May 15 12:21:39.191693 kernel: CPU topo: Num. threads per package: 6 May 15 12:21:39.191701 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs May 15 12:21:39.191731 kernel: [mem 0x40000000-0xffffffff] available for PCI devices May 15 12:21:39.191740 kernel: Booting paravirtualized kernel on Hyper-V May 15 12:21:39.191748 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns May 15 12:21:39.191756 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 May 15 12:21:39.191764 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 May 15 12:21:39.191772 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 May 15 12:21:39.191780 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - May 15 12:21:39.191791 kernel: Hyper-V: PV spinlocks enabled May 15 12:21:39.191800 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) May 15 12:21:39.191809 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=48287e633374b880fa618bd42bee102ae77c50831859c6cedd6ca9e1aec3dd5c May 15 12:21:39.191817 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. May 15 12:21:39.191826 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) May 15 12:21:39.191834 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) May 15 12:21:39.191842 kernel: Fallback order for Node 0: 0 May 15 12:21:39.191850 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 May 15 12:21:39.191860 kernel: Policy zone: Normal May 15 12:21:39.191876 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off May 15 12:21:39.191885 kernel: software IO TLB: area num 8. May 15 12:21:39.191895 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 May 15 12:21:39.191904 kernel: Kernel/User page tables isolation: enabled May 15 12:21:39.191912 kernel: ftrace: allocating 40065 entries in 157 pages May 15 12:21:39.191921 kernel: ftrace: allocated 157 pages with 5 groups May 15 12:21:39.191929 kernel: Dynamic Preempt: voluntary May 15 12:21:39.191938 kernel: rcu: Preemptible hierarchical RCU implementation. May 15 12:21:39.191947 kernel: rcu: RCU event tracing is enabled. May 15 12:21:39.191955 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. May 15 12:21:39.191964 kernel: Trampoline variant of Tasks RCU enabled. May 15 12:21:39.191975 kernel: Rude variant of Tasks RCU enabled. May 15 12:21:39.191984 kernel: Tracing variant of Tasks RCU enabled. May 15 12:21:39.191992 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. May 15 12:21:39.192001 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 May 15 12:21:39.192009 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. May 15 12:21:39.192021 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. May 15 12:21:39.192029 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. May 15 12:21:39.192038 kernel: Using NULL legacy PIC May 15 12:21:39.192046 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 May 15 12:21:39.192055 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. May 15 12:21:39.192063 kernel: Console: colour dummy device 80x25 May 15 12:21:39.192072 kernel: printk: legacy console [tty1] enabled May 15 12:21:39.192080 kernel: printk: legacy console [ttyS0] enabled May 15 12:21:39.192089 kernel: printk: legacy bootconsole [earlyser0] disabled May 15 12:21:39.192099 kernel: ACPI: Core revision 20240827 May 15 12:21:39.192108 kernel: Failed to register legacy timer interrupt May 15 12:21:39.192116 kernel: APIC: Switch to symmetric I/O mode setup May 15 12:21:39.192125 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 May 15 12:21:39.192133 kernel: Hyper-V: enabling crash_kexec_post_notifiers May 15 12:21:39.192141 kernel: Hyper-V: Using IPI hypercalls May 15 12:21:39.192150 kernel: APIC: send_IPI() replaced with hv_send_ipi() May 15 12:21:39.192158 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() May 15 12:21:39.192167 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() May 15 12:21:39.192178 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() May 15 12:21:39.192186 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() May 15 12:21:39.192195 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() May 15 12:21:39.192203 kernel: Hyper-V: Using enlightened APIC (xapic mode) May 15 12:21:39.192212 kernel: APIC: eoi() replaced with hv_apic_eoi_write() May 15 12:21:39.192220 kernel: APIC: read() replaced with hv_apic_read() May 15 12:21:39.192229 kernel: APIC: write() replaced with hv_apic_write() May 15 12:21:39.192237 kernel: APIC: icr_write() replaced with hv_apic_icr_write() May 15 12:21:39.192246 kernel: APIC: icr_read() replaced with hv_apic_icr_read() May 15 12:21:39.192257 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593992) May 15 12:21:39.192265 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 May 15 12:21:39.192274 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 May 15 12:21:39.192282 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization May 15 12:21:39.192291 kernel: Spectre V2 : Mitigation: Retpolines May 15 12:21:39.192299 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch May 15 12:21:39.192307 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT May 15 12:21:39.192316 kernel: Speculative Store Bypass: Vulnerable May 15 12:21:39.192324 kernel: MDS: Mitigation: Clear CPU buffers May 15 12:21:39.192332 kernel: TAA: Mitigation: Clear CPU buffers May 15 12:21:39.192343 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode May 15 12:21:39.192351 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' May 15 12:21:39.192360 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' May 15 12:21:39.192368 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' May 15 12:21:39.192376 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 May 15 12:21:39.192385 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. May 15 12:21:39.192393 kernel: Freeing SMP alternatives memory: 32K May 15 12:21:39.192402 kernel: pid_max: default: 32768 minimum: 301 May 15 12:21:39.192410 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima May 15 12:21:39.192418 kernel: landlock: Up and running. May 15 12:21:39.192427 kernel: SELinux: Initializing. May 15 12:21:39.192437 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) May 15 12:21:39.192446 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) May 15 12:21:39.192454 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) May 15 12:21:39.192463 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. May 15 12:21:39.192471 kernel: signal: max sigframe size: 1776 May 15 12:21:39.192480 kernel: rcu: Hierarchical SRCU implementation. May 15 12:21:39.192488 kernel: rcu: Max phase no-delay instances is 400. May 15 12:21:39.192497 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level May 15 12:21:39.192506 kernel: NMI watchdog: Perf NMI watchdog permanently disabled May 15 12:21:39.192514 kernel: smp: Bringing up secondary CPUs ... May 15 12:21:39.192525 kernel: smpboot: x86: Booting SMP configuration: May 15 12:21:39.192533 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 May 15 12:21:39.192542 kernel: smp: Brought up 1 node, 6 CPUs May 15 12:21:39.192550 kernel: smpboot: Total of 6 processors activated (31127.90 BogoMIPS) May 15 12:21:39.192559 kernel: Memory: 115339096K/117439364K available (14336K kernel code, 2438K rwdata, 9944K rodata, 54416K init, 2544K bss, 2089992K reserved, 0K cma-reserved) May 15 12:21:39.192568 kernel: devtmpfs: initialized May 15 12:21:39.192576 kernel: x86/mm: Memory block size: 1024MB May 15 12:21:39.192585 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) May 15 12:21:39.192593 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns May 15 12:21:39.192604 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) May 15 12:21:39.192613 kernel: pinctrl core: initialized pinctrl subsystem May 15 12:21:39.192621 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family May 15 12:21:39.192630 kernel: audit: initializing netlink subsys (disabled) May 15 12:21:39.192638 kernel: audit: type=2000 audit(1747311696.029:1): state=initialized audit_enabled=0 res=1 May 15 12:21:39.192647 kernel: thermal_sys: Registered thermal governor 'step_wise' May 15 12:21:39.192655 kernel: thermal_sys: Registered thermal governor 'user_space' May 15 12:21:39.192664 kernel: cpuidle: using governor menu May 15 12:21:39.192672 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 May 15 12:21:39.192683 kernel: dca service started, version 1.12.1 May 15 12:21:39.192692 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] May 15 12:21:39.192700 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. May 15 12:21:39.192719 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages May 15 12:21:39.192728 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page May 15 12:21:39.192737 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages May 15 12:21:39.192753 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page May 15 12:21:39.192762 kernel: ACPI: Added _OSI(Module Device) May 15 12:21:39.192774 kernel: ACPI: Added _OSI(Processor Device) May 15 12:21:39.192783 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) May 15 12:21:39.192792 kernel: ACPI: Added _OSI(Processor Aggregator Device) May 15 12:21:39.192800 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded May 15 12:21:39.192808 kernel: ACPI: Interpreter enabled May 15 12:21:39.192817 kernel: ACPI: PM: (supports S0 S5) May 15 12:21:39.192826 kernel: ACPI: Using IOAPIC for interrupt routing May 15 12:21:39.192834 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug May 15 12:21:39.192843 kernel: PCI: Ignoring E820 reservations for host bridge windows May 15 12:21:39.192851 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F May 15 12:21:39.192862 kernel: iommu: Default domain type: Translated May 15 12:21:39.192871 kernel: iommu: DMA domain TLB invalidation policy: lazy mode May 15 12:21:39.192879 kernel: efivars: Registered efivars operations May 15 12:21:39.192887 kernel: PCI: Using ACPI for IRQ routing May 15 12:21:39.192896 kernel: PCI: System does not support PCI May 15 12:21:39.192905 kernel: vgaarb: loaded May 15 12:21:39.192913 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page May 15 12:21:39.192922 kernel: VFS: Disk quotas dquot_6.6.0 May 15 12:21:39.192930 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) May 15 12:21:39.192941 kernel: pnp: PnP ACPI init May 15 12:21:39.192949 kernel: pnp: PnP ACPI: found 3 devices May 15 12:21:39.192958 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns May 15 12:21:39.192967 kernel: NET: Registered PF_INET protocol family May 15 12:21:39.192975 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) May 15 12:21:39.192984 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) May 15 12:21:39.192993 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) May 15 12:21:39.193001 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) May 15 12:21:39.193012 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) May 15 12:21:39.193021 kernel: TCP: Hash tables configured (established 524288 bind 65536) May 15 12:21:39.193029 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) May 15 12:21:39.193038 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) May 15 12:21:39.193046 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family May 15 12:21:39.193055 kernel: NET: Registered PF_XDP protocol family May 15 12:21:39.193063 kernel: PCI: CLS 0 bytes, default 64 May 15 12:21:39.193072 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) May 15 12:21:39.193080 kernel: software IO TLB: mapped [mem 0x000000003b5bf000-0x000000003f5bf000] (64MB) May 15 12:21:39.193091 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer May 15 12:21:39.193100 kernel: Initialise system trusted keyrings May 15 12:21:39.193108 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 May 15 12:21:39.193117 kernel: Key type asymmetric registered May 15 12:21:39.193125 kernel: Asymmetric key parser 'x509' registered May 15 12:21:39.193134 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) May 15 12:21:39.193142 kernel: io scheduler mq-deadline registered May 15 12:21:39.193151 kernel: io scheduler kyber registered May 15 12:21:39.193159 kernel: io scheduler bfq registered May 15 12:21:39.193170 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 May 15 12:21:39.193178 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled May 15 12:21:39.193187 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A May 15 12:21:39.193196 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A May 15 12:21:39.193204 kernel: i8042: PNP: No PS/2 controller found. May 15 12:21:39.193351 kernel: rtc_cmos 00:02: registered as rtc0 May 15 12:21:39.193448 kernel: rtc_cmos 00:02: setting system clock to 2025-05-15T12:21:38 UTC (1747311698) May 15 12:21:39.193537 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram May 15 12:21:39.193554 kernel: intel_pstate: CPU model not supported May 15 12:21:39.193562 kernel: efifb: probing for efifb May 15 12:21:39.193571 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k May 15 12:21:39.193580 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 May 15 12:21:39.193588 kernel: efifb: scrolling: redraw May 15 12:21:39.193597 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 May 15 12:21:39.193606 kernel: Console: switching to colour frame buffer device 128x48 May 15 12:21:39.193614 kernel: fb0: EFI VGA frame buffer device May 15 12:21:39.193623 kernel: pstore: Using crash dump compression: deflate May 15 12:21:39.193634 kernel: pstore: Registered efi_pstore as persistent store backend May 15 12:21:39.193643 kernel: NET: Registered PF_INET6 protocol family May 15 12:21:39.193651 kernel: Segment Routing with IPv6 May 15 12:21:39.193660 kernel: In-situ OAM (IOAM) with IPv6 May 15 12:21:39.193668 kernel: NET: Registered PF_PACKET protocol family May 15 12:21:39.193677 kernel: Key type dns_resolver registered May 15 12:21:39.193685 kernel: IPI shorthand broadcast: enabled May 15 12:21:39.193694 kernel: sched_clock: Marking stable (3667004400, 107645100)->(4114803500, -340154000) May 15 12:21:39.193703 kernel: registered taskstats version 1 May 15 12:21:39.193728 kernel: Loading compiled-in X.509 certificates May 15 12:21:39.193737 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.20-flatcar: 05e05785144663be6df1db78301487421c4773b6' May 15 12:21:39.193746 kernel: Demotion targets for Node 0: null May 15 12:21:39.193754 kernel: Key type .fscrypt registered May 15 12:21:39.193763 kernel: Key type fscrypt-provisioning registered May 15 12:21:39.193771 kernel: ima: No TPM chip found, activating TPM-bypass! May 15 12:21:39.193780 kernel: ima: Allocated hash algorithm: sha1 May 15 12:21:39.193789 kernel: ima: No architecture policies found May 15 12:21:39.193797 kernel: clk: Disabling unused clocks May 15 12:21:39.193808 kernel: Warning: unable to open an initial console. May 15 12:21:39.193817 kernel: Freeing unused kernel image (initmem) memory: 54416K May 15 12:21:39.193825 kernel: Write protecting the kernel read-only data: 24576k May 15 12:21:39.193834 kernel: Freeing unused kernel image (rodata/data gap) memory: 296K May 15 12:21:39.193843 kernel: Run /init as init process May 15 12:21:39.193851 kernel: with arguments: May 15 12:21:39.193859 kernel: /init May 15 12:21:39.193868 kernel: with environment: May 15 12:21:39.193876 kernel: HOME=/ May 15 12:21:39.193887 kernel: TERM=linux May 15 12:21:39.193895 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a May 15 12:21:39.193905 systemd[1]: Successfully made /usr/ read-only. May 15 12:21:39.193916 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 15 12:21:39.193926 systemd[1]: Detected virtualization microsoft. May 15 12:21:39.193935 systemd[1]: Detected architecture x86-64. May 15 12:21:39.193943 systemd[1]: Running in initrd. May 15 12:21:39.193954 systemd[1]: No hostname configured, using default hostname. May 15 12:21:39.193964 systemd[1]: Hostname set to . May 15 12:21:39.193972 systemd[1]: Initializing machine ID from random generator. May 15 12:21:39.193981 systemd[1]: Queued start job for default target initrd.target. May 15 12:21:39.193990 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 15 12:21:39.193999 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 15 12:21:39.194009 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... May 15 12:21:39.194018 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 15 12:21:39.194029 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... May 15 12:21:39.194039 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... May 15 12:21:39.194049 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... May 15 12:21:39.194058 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... May 15 12:21:39.194067 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 15 12:21:39.194076 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 15 12:21:39.194085 systemd[1]: Reached target paths.target - Path Units. May 15 12:21:39.194096 systemd[1]: Reached target slices.target - Slice Units. May 15 12:21:39.194105 systemd[1]: Reached target swap.target - Swaps. May 15 12:21:39.194114 systemd[1]: Reached target timers.target - Timer Units. May 15 12:21:39.194123 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. May 15 12:21:39.194132 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 15 12:21:39.194141 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). May 15 12:21:39.194150 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. May 15 12:21:39.194159 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 15 12:21:39.194168 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 15 12:21:39.194179 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 15 12:21:39.194188 systemd[1]: Reached target sockets.target - Socket Units. May 15 12:21:39.194197 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... May 15 12:21:39.194206 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 15 12:21:39.194215 systemd[1]: Finished network-cleanup.service - Network Cleanup. May 15 12:21:39.194224 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 15 12:21:39.194233 systemd[1]: Starting systemd-fsck-usr.service... May 15 12:21:39.194242 systemd[1]: Starting systemd-journald.service - Journal Service... May 15 12:21:39.194253 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 15 12:21:39.194281 systemd-journald[232]: Collecting audit messages is disabled. May 15 12:21:39.194303 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 15 12:21:39.194313 systemd-journald[232]: Journal started May 15 12:21:39.194336 systemd-journald[232]: Runtime Journal (/run/log/journal/44142eb97c1348a58058a2f4d9f0cdd8) is 8M, max 2.2G, 2.1G free. May 15 12:21:39.197251 systemd-modules-load[235]: Inserted module 'overlay' May 15 12:21:39.201722 systemd[1]: Started systemd-journald.service - Journal Service. May 15 12:21:39.201918 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. May 15 12:21:39.202360 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 15 12:21:39.203048 systemd[1]: Finished systemd-fsck-usr.service. May 15 12:21:39.232441 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 15 12:21:39.233190 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 15 12:21:39.258551 systemd-tmpfiles[245]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. May 15 12:21:39.259896 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 15 12:21:39.263886 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 15 12:21:39.263406 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 15 12:21:39.288929 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 15 12:21:39.296183 kernel: Bridge firewalling registered May 15 12:21:39.291120 systemd-modules-load[235]: Inserted module 'br_netfilter' May 15 12:21:39.310811 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 15 12:21:39.311189 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 15 12:21:39.320748 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 15 12:21:39.328381 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 15 12:21:39.337313 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 15 12:21:39.362830 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 15 12:21:39.371223 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 15 12:21:39.372246 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 15 12:21:39.382254 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... May 15 12:21:39.402155 dracut-cmdline[273]: 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=48287e633374b880fa618bd42bee102ae77c50831859c6cedd6ca9e1aec3dd5c May 15 12:21:39.415579 systemd-resolved[265]: Positive Trust Anchors: May 15 12:21:39.415587 systemd-resolved[265]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 15 12:21:39.415617 systemd-resolved[265]: 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 15 12:21:39.418142 systemd-resolved[265]: Defaulting to hostname 'linux'. May 15 12:21:39.419163 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 15 12:21:39.430156 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 15 12:21:39.522729 kernel: SCSI subsystem initialized May 15 12:21:39.533725 kernel: Loading iSCSI transport class v2.0-870. May 15 12:21:39.545725 kernel: iscsi: registered transport (tcp) May 15 12:21:39.568941 kernel: iscsi: registered transport (qla4xxx) May 15 12:21:39.568969 kernel: QLogic iSCSI HBA Driver May 15 12:21:39.586990 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 15 12:21:39.615133 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 15 12:21:39.626693 systemd[1]: Reached target network-pre.target - Preparation for Network. May 15 12:21:39.673397 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. May 15 12:21:39.674788 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... May 15 12:21:39.743722 kernel: raid6: avx2x4 gen() 31214 MB/s May 15 12:21:39.762720 kernel: raid6: avx2x2 gen() 31166 MB/s May 15 12:21:39.783712 kernel: raid6: avx2x1 gen() 27858 MB/s May 15 12:21:39.783736 kernel: raid6: using algorithm avx2x4 gen() 31214 MB/s May 15 12:21:39.806721 kernel: raid6: .... xor() 9960 MB/s, rmw enabled May 15 12:21:39.806749 kernel: raid6: using avx2x2 recovery algorithm May 15 12:21:39.828731 kernel: xor: automatically using best checksumming function avx May 15 12:21:39.993742 kernel: Btrfs loaded, zoned=no, fsverity=no May 15 12:21:40.000749 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. May 15 12:21:40.002248 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 15 12:21:40.038048 systemd-udevd[483]: Using default interface naming scheme 'v255'. May 15 12:21:40.046842 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 15 12:21:40.047662 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... May 15 12:21:40.099665 dracut-pre-trigger[487]: rd.md=0: removing MD RAID activation May 15 12:21:40.127361 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. May 15 12:21:40.128726 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 15 12:21:40.200676 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 15 12:21:40.215869 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... May 15 12:21:40.235828 kernel: cryptd: max_cpu_qlen set to 1000 May 15 12:21:40.242108 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 15 12:21:40.264876 kernel: AES CTR mode by8 optimization enabled May 15 12:21:40.264906 kernel: hv_vmbus: Vmbus version:4.0 May 15 12:21:40.264919 kernel: hv_vmbus: registering driver hyperv_keyboard May 15 12:21:40.242276 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 15 12:21:40.277978 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 15 12:21:40.258983 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... May 15 12:21:40.290347 kernel: pps_core: LinuxPPS API ver. 1 registered May 15 12:21:40.290373 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti May 15 12:21:40.290594 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 15 12:21:40.318389 kernel: hv_vmbus: registering driver hv_pci May 15 12:21:40.318411 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 May 15 12:21:41.410876 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 May 15 12:21:41.411095 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] May 15 12:21:41.412445 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] May 15 12:21:41.412603 kernel: PTP clock support registered May 15 12:21:41.412618 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] May 15 12:21:41.412738 kernel: hid: raw HID events driver (C) Jiri Kosina May 15 12:21:41.412752 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint May 15 12:21:41.412894 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] May 15 12:21:41.413025 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] May 15 12:21:41.413158 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] May 15 12:21:41.413288 kernel: pci 0001:00:00.0: Enabling HDA controller May 15 12:21:41.413415 kernel: hv_vmbus: registering driver hv_netvsc May 15 12:21:41.413429 kernel: hv_utils: Registering HyperV Utility Driver May 15 12:21:41.413440 kernel: hv_vmbus: registering driver hv_utils May 15 12:21:41.413463 kernel: hv_vmbus: registering driver hid_hyperv May 15 12:21:41.413476 kernel: hv_utils: Heartbeat IC version 3.0 May 15 12:21:41.413488 kernel: hv_vmbus: registering driver hv_storvsc May 15 12:21:41.413499 kernel: hv_utils: Shutdown IC version 3.2 May 15 12:21:41.413516 kernel: hv_utils: TimeSync IC version 4.0 May 15 12:21:41.413530 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 May 15 12:21:41.413547 kernel: scsi host0: storvsc_host_t May 15 12:21:41.413677 kernel: scsi host1: storvsc_host_t May 15 12:21:41.413798 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on May 15 12:21:41.413908 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 May 15 12:21:41.414029 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 May 15 12:21:41.486183 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 May 15 12:21:41.486359 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned May 15 12:21:41.486548 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 May 15 12:21:41.494086 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned May 15 12:21:41.494253 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned May 15 12:21:41.494397 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) May 15 12:21:41.494566 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks May 15 12:21:41.494724 kernel: sd 0:0:0:0: [sda] Write Protect is off May 15 12:21:41.494850 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) May 15 12:21:41.494972 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks May 15 12:21:41.495093 kernel: sd 0:0:0:1: [sdb] Write Protect is off May 15 12:21:41.495212 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00 May 15 12:21:41.495331 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA May 15 12:21:41.495463 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#268 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 May 15 12:21:41.495595 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#275 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 May 15 12:21:41.495707 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 May 15 12:21:41.495832 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA May 15 12:21:41.495954 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#388 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 May 15 12:21:41.496064 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#395 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 May 15 12:21:41.496171 kernel: sdb: sdb1 May 15 12:21:41.496189 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk May 15 12:21:41.496311 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 May 15 12:21:41.496325 kernel: sd 0:0:0:0: [sda] Attached SCSI disk May 15 12:21:41.496444 kernel: sr 0:0:0:2: [sr0] scsi-1 drive May 15 12:21:41.496582 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 May 15 12:21:41.496596 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 May 15 12:21:40.291008 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. May 15 12:21:40.295211 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 15 12:21:40.295302 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 15 12:21:40.298056 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 15 12:21:41.374600 systemd-resolved[265]: Clock change detected. Flushing caches. May 15 12:21:41.451079 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 15 12:21:41.940735 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. May 15 12:21:41.985093 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. May 15 12:21:41.990058 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. May 15 12:21:42.011635 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. May 15 12:21:42.037254 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. May 15 12:21:42.047512 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... May 15 12:21:42.082475 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#35 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 May 15 12:21:42.094473 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 May 15 12:21:42.102100 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#52 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 May 15 12:21:42.122493 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 May 15 12:21:43.129736 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#384 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 May 15 12:21:43.141471 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 May 15 12:21:43.141659 disk-uuid[726]: The operation has completed successfully. May 15 12:21:43.391754 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. May 15 12:21:43.396433 systemd[1]: disk-uuid.service: Deactivated successfully. May 15 12:21:43.396541 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. May 15 12:21:43.406050 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. May 15 12:21:43.408772 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 15 12:21:43.418152 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 15 12:21:43.428231 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... May 15 12:21:43.436440 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... May 15 12:21:43.469209 sh[891]: Success May 15 12:21:43.475865 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. May 15 12:21:43.556381 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. May 15 12:21:43.556416 kernel: device-mapper: uevent: version 1.0.3 May 15 12:21:43.561081 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev May 15 12:21:43.573470 kernel: device-mapper: verity: sha256 using shash "sha256-avx2" May 15 12:21:43.931412 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. May 15 12:21:43.933426 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... May 15 12:21:43.968115 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. May 15 12:21:43.994952 kernel: BTRFS info: 'norecovery' is for compatibility only, recommended to use 'rescue=nologreplay' May 15 12:21:43.994988 kernel: BTRFS: device fsid 2d504097-db49-4d66-a0d5-eeb665b21004 devid 1 transid 41 /dev/mapper/usr (254:0) scanned by mount (916) May 15 12:21:44.001217 kernel: BTRFS info (device dm-0): first mount of filesystem 2d504097-db49-4d66-a0d5-eeb665b21004 May 15 12:21:44.005280 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm May 15 12:21:44.008419 kernel: BTRFS info (device dm-0): using free-space-tree May 15 12:21:44.403484 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. May 15 12:21:44.407587 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. May 15 12:21:44.410586 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. May 15 12:21:44.411375 systemd[1]: Starting ignition-setup.service - Ignition (setup)... May 15 12:21:44.436667 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... May 15 12:21:44.464473 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/sda6 (8:6) scanned by mount (947) May 15 12:21:44.474778 kernel: BTRFS info (device sda6): first mount of filesystem afd0c70c-d15e-448c-8325-f96e3c3ed3a5 May 15 12:21:44.474813 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm May 15 12:21:44.478047 kernel: BTRFS info (device sda6): using free-space-tree May 15 12:21:44.499477 kernel: BTRFS info (device sda6): last unmount of filesystem afd0c70c-d15e-448c-8325-f96e3c3ed3a5 May 15 12:21:44.500126 systemd[1]: Finished ignition-setup.service - Ignition (setup). May 15 12:21:44.507893 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... May 15 12:21:44.544687 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 15 12:21:44.546206 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 15 12:21:44.594391 systemd-networkd[1086]: lo: Link UP May 15 12:21:44.594401 systemd-networkd[1086]: lo: Gained carrier May 15 12:21:44.595391 systemd-networkd[1086]: Enumeration completed May 15 12:21:44.595475 systemd[1]: Started systemd-networkd.service - Network Configuration. May 15 12:21:44.595753 systemd-networkd[1086]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 15 12:21:44.595758 systemd-networkd[1086]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 15 12:21:44.596538 systemd-networkd[1086]: eth0: Link UP May 15 12:21:44.596698 systemd-networkd[1086]: eth0: Gained carrier May 15 12:21:44.596708 systemd-networkd[1086]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 15 12:21:44.602409 systemd[1]: Reached target network.target - Network. May 15 12:21:44.656488 systemd-networkd[1086]: eth0: DHCPv4 address 10.200.8.39/24, gateway 10.200.8.1 acquired from 168.63.129.16 May 15 12:21:45.974078 ignition[1036]: Ignition 2.21.0 May 15 12:21:45.974093 ignition[1036]: Stage: fetch-offline May 15 12:21:45.974195 ignition[1036]: no configs at "/usr/lib/ignition/base.d" May 15 12:21:45.974204 ignition[1036]: no config dir at "/usr/lib/ignition/base.platform.d/azure" May 15 12:21:45.974300 ignition[1036]: parsed url from cmdline: "" May 15 12:21:45.974304 ignition[1036]: no config URL provided May 15 12:21:45.974309 ignition[1036]: reading system config file "/usr/lib/ignition/user.ign" May 15 12:21:45.974317 ignition[1036]: no config at "/usr/lib/ignition/user.ign" May 15 12:21:45.974322 ignition[1036]: failed to fetch config: resource requires networking May 15 12:21:45.974492 ignition[1036]: Ignition finished successfully May 15 12:21:46.004852 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). May 15 12:21:46.011159 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... May 15 12:21:46.063912 ignition[1098]: Ignition 2.21.0 May 15 12:21:46.063927 ignition[1098]: Stage: fetch May 15 12:21:46.064178 ignition[1098]: no configs at "/usr/lib/ignition/base.d" May 15 12:21:46.064190 ignition[1098]: no config dir at "/usr/lib/ignition/base.platform.d/azure" May 15 12:21:46.064295 ignition[1098]: parsed url from cmdline: "" May 15 12:21:46.064299 ignition[1098]: no config URL provided May 15 12:21:46.064305 ignition[1098]: reading system config file "/usr/lib/ignition/user.ign" May 15 12:21:46.064313 ignition[1098]: no config at "/usr/lib/ignition/user.ign" May 15 12:21:46.064344 ignition[1098]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 May 15 12:21:46.157692 ignition[1098]: GET result: OK May 15 12:21:46.157789 ignition[1098]: config has been read from IMDS userdata May 15 12:21:46.158140 ignition[1098]: parsing config with SHA512: b97935ba209d16f9aaeb3eabe48dc8f7b00ae517968eeb60d81c692335ff600ec858a4139a3e8a9317f3beda2132dd7ce3ac1ae4d9e6f5828fe34fbc5b2f6117 May 15 12:21:46.163735 unknown[1098]: fetched base config from "system" May 15 12:21:46.163745 unknown[1098]: fetched base config from "system" May 15 12:21:46.163905 ignition[1098]: fetch: fetch complete May 15 12:21:46.163751 unknown[1098]: fetched user config from "azure" May 15 12:21:46.163910 ignition[1098]: fetch: fetch passed May 15 12:21:46.163952 ignition[1098]: Ignition finished successfully May 15 12:21:46.183492 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). May 15 12:21:46.191382 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... May 15 12:21:46.238014 ignition[1108]: Ignition 2.21.0 May 15 12:21:46.238028 ignition[1108]: Stage: kargs May 15 12:21:46.238292 ignition[1108]: no configs at "/usr/lib/ignition/base.d" May 15 12:21:46.238304 ignition[1108]: no config dir at "/usr/lib/ignition/base.platform.d/azure" May 15 12:21:46.239502 ignition[1108]: kargs: kargs passed May 15 12:21:46.246926 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). May 15 12:21:46.239566 ignition[1108]: Ignition finished successfully May 15 12:21:46.251313 systemd[1]: Starting ignition-disks.service - Ignition (disks)... May 15 12:21:46.290764 ignition[1118]: Ignition 2.21.0 May 15 12:21:46.290779 ignition[1118]: Stage: disks May 15 12:21:46.291011 ignition[1118]: no configs at "/usr/lib/ignition/base.d" May 15 12:21:46.291022 ignition[1118]: no config dir at "/usr/lib/ignition/base.platform.d/azure" May 15 12:21:46.296640 systemd[1]: Finished ignition-disks.service - Ignition (disks). May 15 12:21:46.291574 ignition[1118]: disks: disks passed May 15 12:21:46.303087 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. May 15 12:21:46.291618 ignition[1118]: Ignition finished successfully May 15 12:21:46.311770 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. May 15 12:21:46.320538 systemd[1]: Reached target local-fs.target - Local File Systems. May 15 12:21:46.320656 systemd[1]: Reached target sysinit.target - System Initialization. May 15 12:21:46.326898 systemd[1]: Reached target basic.target - Basic System. May 15 12:21:46.339276 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... May 15 12:21:46.430517 systemd-fsck[1130]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks May 15 12:21:46.436109 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. May 15 12:21:46.437241 systemd[1]: Mounting sysroot.mount - /sysroot... May 15 12:21:46.562557 systemd-networkd[1086]: eth0: Gained IPv6LL May 15 12:21:46.661479 kernel: EXT4-fs (sda9): mounted filesystem f7dea4bd-2644-4592-b85b-330f322c4d2b r/w with ordered data mode. Quota mode: none. May 15 12:21:46.662558 systemd[1]: Mounted sysroot.mount - /sysroot. May 15 12:21:46.663145 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. May 15 12:21:46.681292 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 15 12:21:46.682146 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... May 15 12:21:46.706968 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... May 15 12:21:46.720209 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/sda6 (8:6) scanned by mount (1144) May 15 12:21:46.707099 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). May 15 12:21:46.744059 kernel: BTRFS info (device sda6): first mount of filesystem afd0c70c-d15e-448c-8325-f96e3c3ed3a5 May 15 12:21:46.744082 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm May 15 12:21:46.744095 kernel: BTRFS info (device sda6): using free-space-tree May 15 12:21:46.707127 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. May 15 12:21:46.736653 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. May 15 12:21:46.748730 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... May 15 12:21:46.760818 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 15 12:21:47.566057 coreos-metadata[1146]: May 15 12:21:47.566 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 May 15 12:21:47.572070 coreos-metadata[1146]: May 15 12:21:47.572 INFO Fetch successful May 15 12:21:47.572070 coreos-metadata[1146]: May 15 12:21:47.572 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 May 15 12:21:47.584151 coreos-metadata[1146]: May 15 12:21:47.584 INFO Fetch successful May 15 12:21:47.587983 coreos-metadata[1146]: May 15 12:21:47.587 INFO wrote hostname ci-4334.0.0-a-14b320e2a7 to /sysroot/etc/hostname May 15 12:21:47.594934 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. May 15 12:21:47.801061 initrd-setup-root[1174]: cut: /sysroot/etc/passwd: No such file or directory May 15 12:21:47.850536 initrd-setup-root[1181]: cut: /sysroot/etc/group: No such file or directory May 15 12:21:47.857737 initrd-setup-root[1188]: cut: /sysroot/etc/shadow: No such file or directory May 15 12:21:47.865471 initrd-setup-root[1195]: cut: /sysroot/etc/gshadow: No such file or directory May 15 12:21:48.782279 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. May 15 12:21:48.784191 systemd[1]: Starting ignition-mount.service - Ignition (mount)... May 15 12:21:48.806680 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... May 15 12:21:48.817305 systemd[1]: sysroot-oem.mount: Deactivated successfully. May 15 12:21:48.826014 kernel: BTRFS info (device sda6): last unmount of filesystem afd0c70c-d15e-448c-8325-f96e3c3ed3a5 May 15 12:21:48.841754 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. May 15 12:21:48.857297 ignition[1268]: INFO : Ignition 2.21.0 May 15 12:21:48.857297 ignition[1268]: INFO : Stage: mount May 15 12:21:48.863604 ignition[1268]: INFO : no configs at "/usr/lib/ignition/base.d" May 15 12:21:48.863604 ignition[1268]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" May 15 12:21:48.863604 ignition[1268]: INFO : mount: mount passed May 15 12:21:48.863604 ignition[1268]: INFO : Ignition finished successfully May 15 12:21:48.864604 systemd[1]: Finished ignition-mount.service - Ignition (mount). May 15 12:21:48.868553 systemd[1]: Starting ignition-files.service - Ignition (files)... May 15 12:21:48.902661 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 15 12:21:48.922477 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/sda6 (8:6) scanned by mount (1282) May 15 12:21:48.932776 kernel: BTRFS info (device sda6): first mount of filesystem afd0c70c-d15e-448c-8325-f96e3c3ed3a5 May 15 12:21:48.932806 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm May 15 12:21:48.935901 kernel: BTRFS info (device sda6): using free-space-tree May 15 12:21:48.939565 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 15 12:21:48.984689 ignition[1299]: INFO : Ignition 2.21.0 May 15 12:21:48.984689 ignition[1299]: INFO : Stage: files May 15 12:21:48.990504 ignition[1299]: INFO : no configs at "/usr/lib/ignition/base.d" May 15 12:21:48.990504 ignition[1299]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" May 15 12:21:48.990504 ignition[1299]: DEBUG : files: compiled without relabeling support, skipping May 15 12:21:49.021756 ignition[1299]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" May 15 12:21:49.021756 ignition[1299]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" May 15 12:21:49.115601 ignition[1299]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" May 15 12:21:49.121158 ignition[1299]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" May 15 12:21:49.121158 ignition[1299]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" May 15 12:21:49.116294 unknown[1299]: wrote ssh authorized keys file for user: core May 15 12:21:49.178663 ignition[1299]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" May 15 12:21:49.186409 ignition[1299]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" May 15 12:21:49.186409 ignition[1299]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" May 15 12:21:49.186409 ignition[1299]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" May 15 12:21:49.186409 ignition[1299]: INFO : files: files passed May 15 12:21:49.186409 ignition[1299]: INFO : Ignition finished successfully May 15 12:21:49.180847 systemd[1]: Finished ignition-files.service - Ignition (files). May 15 12:21:49.191437 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... May 15 12:21:49.221670 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... May 15 12:21:49.224265 systemd[1]: ignition-quench.service: Deactivated successfully. May 15 12:21:49.224354 systemd[1]: Finished ignition-quench.service - Ignition (record completion). May 15 12:21:49.280401 initrd-setup-root-after-ignition[1333]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 15 12:21:49.280401 initrd-setup-root-after-ignition[1333]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory May 15 12:21:49.292920 initrd-setup-root-after-ignition[1337]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 15 12:21:49.287434 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. May 15 12:21:49.298685 systemd[1]: Reached target ignition-complete.target - Ignition Complete. May 15 12:21:49.305914 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... May 15 12:21:49.349392 systemd[1]: initrd-parse-etc.service: Deactivated successfully. May 15 12:21:49.349510 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. May 15 12:21:49.353384 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. May 15 12:21:49.362602 systemd[1]: Reached target initrd.target - Initrd Default Target. May 15 12:21:49.370394 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. May 15 12:21:49.371129 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... May 15 12:21:49.427545 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 15 12:21:49.428934 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... May 15 12:21:49.463244 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. May 15 12:21:49.463445 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. May 15 12:21:49.472190 systemd[1]: Stopped target timers.target - Timer Units. May 15 12:21:49.481231 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. May 15 12:21:49.481340 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 15 12:21:49.496472 systemd[1]: Stopped target initrd.target - Initrd Default Target. May 15 12:21:49.500907 systemd[1]: Stopped target basic.target - Basic System. May 15 12:21:49.508533 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. May 15 12:21:49.515224 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. May 15 12:21:49.523351 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. May 15 12:21:49.532119 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. May 15 12:21:49.540540 systemd[1]: Stopped target remote-fs.target - Remote File Systems. May 15 12:21:49.549065 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. May 15 12:21:49.556789 systemd[1]: Stopped target sysinit.target - System Initialization. May 15 12:21:49.566140 systemd[1]: Stopped target local-fs.target - Local File Systems. May 15 12:21:49.581177 systemd[1]: Stopped target swap.target - Swaps. May 15 12:21:49.581341 systemd[1]: dracut-pre-mount.service: Deactivated successfully. May 15 12:21:49.587216 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. May 15 12:21:49.599163 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. May 15 12:21:49.599360 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 15 12:21:49.607793 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. May 15 12:21:49.607920 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 15 12:21:49.616759 systemd[1]: dracut-initqueue.service: Deactivated successfully. May 15 12:21:49.616878 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. May 15 12:21:49.634354 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. May 15 12:21:49.634491 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. May 15 12:21:49.638021 systemd[1]: ignition-files.service: Deactivated successfully. May 15 12:21:49.638143 systemd[1]: Stopped ignition-files.service - Ignition (files). May 15 12:21:49.646726 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. May 15 12:21:49.646838 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. May 15 12:21:49.655681 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... May 15 12:21:49.664343 systemd[1]: kmod-static-nodes.service: Deactivated successfully. May 15 12:21:49.664479 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. May 15 12:21:49.696120 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... May 15 12:21:49.700756 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. May 15 12:21:49.700886 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. May 15 12:21:49.721859 ignition[1357]: INFO : Ignition 2.21.0 May 15 12:21:49.721859 ignition[1357]: INFO : Stage: umount May 15 12:21:49.721859 ignition[1357]: INFO : no configs at "/usr/lib/ignition/base.d" May 15 12:21:49.721859 ignition[1357]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" May 15 12:21:49.721859 ignition[1357]: INFO : umount: umount passed May 15 12:21:49.721859 ignition[1357]: INFO : Ignition finished successfully May 15 12:21:49.713112 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. May 15 12:21:49.713238 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. May 15 12:21:49.731777 systemd[1]: sysroot-boot.mount: Deactivated successfully. May 15 12:21:49.732750 systemd[1]: ignition-mount.service: Deactivated successfully. May 15 12:21:49.732843 systemd[1]: Stopped ignition-mount.service - Ignition (mount). May 15 12:21:49.739506 systemd[1]: initrd-cleanup.service: Deactivated successfully. May 15 12:21:49.739603 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. May 15 12:21:49.745953 systemd[1]: ignition-disks.service: Deactivated successfully. May 15 12:21:49.746018 systemd[1]: Stopped ignition-disks.service - Ignition (disks). May 15 12:21:49.755985 systemd[1]: ignition-kargs.service: Deactivated successfully. May 15 12:21:49.756070 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). May 15 12:21:49.767283 systemd[1]: ignition-fetch.service: Deactivated successfully. May 15 12:21:49.767328 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). May 15 12:21:49.775867 systemd[1]: Stopped target network.target - Network. May 15 12:21:49.783316 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. May 15 12:21:49.783368 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). May 15 12:21:49.791491 systemd[1]: Stopped target paths.target - Path Units. May 15 12:21:49.795121 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. May 15 12:21:49.799603 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 15 12:21:49.802445 systemd[1]: Stopped target slices.target - Slice Units. May 15 12:21:49.814070 systemd[1]: Stopped target sockets.target - Socket Units. May 15 12:21:49.822352 systemd[1]: iscsid.socket: Deactivated successfully. May 15 12:21:49.822419 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. May 15 12:21:49.829446 systemd[1]: iscsiuio.socket: Deactivated successfully. May 15 12:21:49.829521 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 15 12:21:49.836733 systemd[1]: ignition-setup.service: Deactivated successfully. May 15 12:21:49.836804 systemd[1]: Stopped ignition-setup.service - Ignition (setup). May 15 12:21:49.844279 systemd[1]: ignition-setup-pre.service: Deactivated successfully. May 15 12:21:49.844332 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. May 15 12:21:49.851777 systemd[1]: Stopping systemd-networkd.service - Network Configuration... May 15 12:21:49.859295 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... May 15 12:21:49.870923 systemd[1]: systemd-resolved.service: Deactivated successfully. May 15 12:21:49.871026 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. May 15 12:21:49.884023 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. May 15 12:21:49.884371 systemd[1]: sysroot-boot.service: Deactivated successfully. May 15 12:21:49.884578 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. May 15 12:21:49.886543 systemd[1]: systemd-networkd.service: Deactivated successfully. May 15 12:21:49.886640 systemd[1]: Stopped systemd-networkd.service - Network Configuration. May 15 12:21:49.902366 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. May 15 12:21:49.903727 systemd[1]: Stopped target network-pre.target - Preparation for Network. May 15 12:21:49.907777 systemd[1]: systemd-networkd.socket: Deactivated successfully. May 15 12:21:49.907818 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. May 15 12:21:49.909300 systemd[1]: initrd-setup-root.service: Deactivated successfully. May 15 12:21:49.909356 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. May 15 12:21:49.934919 systemd[1]: Stopping network-cleanup.service - Network Cleanup... May 15 12:21:49.938209 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. May 15 12:21:49.938258 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 15 12:21:49.950187 systemd[1]: systemd-sysctl.service: Deactivated successfully. May 15 12:21:49.950233 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. May 15 12:21:49.961610 systemd[1]: systemd-modules-load.service: Deactivated successfully. May 15 12:21:49.961657 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. May 15 12:21:49.969925 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. May 15 12:21:49.969976 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. May 15 12:21:49.979300 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... May 15 12:21:49.989085 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. May 15 12:21:49.989152 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. May 15 12:21:50.008938 systemd[1]: systemd-udevd.service: Deactivated successfully. May 15 12:21:50.017583 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. May 15 12:21:50.020224 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. May 15 12:21:50.020267 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. May 15 12:21:50.029663 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. May 15 12:21:50.029731 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. May 15 12:21:50.042116 systemd[1]: dracut-pre-udev.service: Deactivated successfully. May 15 12:21:50.042169 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. May 15 12:21:50.054405 systemd[1]: dracut-cmdline.service: Deactivated successfully. May 15 12:21:50.054574 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. May 15 12:21:50.070338 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 15 12:21:50.070398 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 15 12:21:50.088495 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... May 15 12:21:50.096421 systemd[1]: systemd-network-generator.service: Deactivated successfully. May 15 12:21:50.096509 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. May 15 12:21:50.108783 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. May 15 12:21:50.108834 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 15 12:21:50.121526 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 15 12:21:50.121573 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 15 12:21:50.137572 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. May 15 12:21:50.137626 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. May 15 12:21:50.137670 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. May 15 12:21:50.137994 systemd[1]: network-cleanup.service: Deactivated successfully. May 15 12:21:50.139580 systemd[1]: Stopped network-cleanup.service - Network Cleanup. May 15 12:21:50.146151 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. May 15 12:21:50.146246 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. May 15 12:21:50.150407 systemd[1]: Reached target initrd-switch-root.target - Switch Root. May 15 12:21:50.163062 systemd[1]: Starting initrd-switch-root.service - Switch Root... May 15 12:21:50.195262 systemd[1]: Switching root. May 15 12:21:50.305289 systemd-journald[232]: Journal stopped May 15 12:21:55.180591 systemd-journald[232]: Received SIGTERM from PID 1 (systemd). May 15 12:21:55.180626 kernel: SELinux: policy capability network_peer_controls=1 May 15 12:21:55.180639 kernel: SELinux: policy capability open_perms=1 May 15 12:21:55.180648 kernel: SELinux: policy capability extended_socket_class=1 May 15 12:21:55.180657 kernel: SELinux: policy capability always_check_network=0 May 15 12:21:55.180666 kernel: SELinux: policy capability cgroup_seclabel=1 May 15 12:21:55.180676 kernel: SELinux: policy capability nnp_nosuid_transition=1 May 15 12:21:55.180688 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 May 15 12:21:55.180697 kernel: SELinux: policy capability ioctl_skip_cloexec=0 May 15 12:21:55.180706 kernel: SELinux: policy capability userspace_initial_context=0 May 15 12:21:55.180716 kernel: audit: type=1403 audit(1747311710.989:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 May 15 12:21:55.180726 systemd[1]: Successfully loaded SELinux policy in 182.883ms. May 15 12:21:55.180737 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 14.426ms. May 15 12:21:55.180749 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 15 12:21:55.180763 systemd[1]: Detected virtualization microsoft. May 15 12:21:55.180773 systemd[1]: Detected architecture x86-64. May 15 12:21:55.180783 systemd[1]: Detected first boot. May 15 12:21:55.180794 systemd[1]: Hostname set to . May 15 12:21:55.180805 systemd[1]: Initializing machine ID from random generator. May 15 12:21:55.180817 zram_generator::config[1401]: No configuration found. May 15 12:21:55.180829 kernel: Guest personality initialized and is inactive May 15 12:21:55.180838 kernel: VMCI host device registered (name=vmci, major=10, minor=124) May 15 12:21:55.180851 kernel: Initialized host personality May 15 12:21:55.180860 kernel: NET: Registered PF_VSOCK protocol family May 15 12:21:55.180871 systemd[1]: Populated /etc with preset unit settings. May 15 12:21:55.180882 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. May 15 12:21:55.180895 systemd[1]: initrd-switch-root.service: Deactivated successfully. May 15 12:21:55.180905 systemd[1]: Stopped initrd-switch-root.service - Switch Root. May 15 12:21:55.180915 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. May 15 12:21:55.180926 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. May 15 12:21:55.180937 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. May 15 12:21:55.180947 systemd[1]: Created slice system-getty.slice - Slice /system/getty. May 15 12:21:55.180957 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. May 15 12:21:55.180971 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. May 15 12:21:55.180982 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. May 15 12:21:55.180992 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. May 15 12:21:55.181003 systemd[1]: Created slice user.slice - User and Session Slice. May 15 12:21:55.181013 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 15 12:21:55.181024 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 15 12:21:55.181034 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. May 15 12:21:55.181048 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. May 15 12:21:55.181060 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. May 15 12:21:55.181074 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 15 12:21:55.181088 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... May 15 12:21:55.181099 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 15 12:21:55.181110 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 15 12:21:55.181121 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. May 15 12:21:55.181132 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. May 15 12:21:55.181143 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. May 15 12:21:55.181157 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. May 15 12:21:55.181168 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 15 12:21:55.181179 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 15 12:21:55.181190 systemd[1]: Reached target slices.target - Slice Units. May 15 12:21:55.181201 systemd[1]: Reached target swap.target - Swaps. May 15 12:21:55.181211 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. May 15 12:21:55.181222 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. May 15 12:21:55.181236 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. May 15 12:21:55.181247 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 15 12:21:55.181258 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 15 12:21:55.181269 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 15 12:21:55.181280 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. May 15 12:21:55.181291 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... May 15 12:21:55.181305 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... May 15 12:21:55.181316 systemd[1]: Mounting media.mount - External Media Directory... May 15 12:21:55.181330 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 15 12:21:55.181341 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... May 15 12:21:55.181352 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... May 15 12:21:55.181363 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... May 15 12:21:55.181375 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 15 12:21:55.181386 systemd[1]: Reached target machines.target - Containers. May 15 12:21:55.181400 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... May 15 12:21:55.181411 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 15 12:21:55.181423 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 15 12:21:55.181434 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... May 15 12:21:55.181445 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 15 12:21:55.181466 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 15 12:21:55.181478 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 15 12:21:55.181489 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... May 15 12:21:55.181503 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 15 12:21:55.181515 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). May 15 12:21:55.181526 systemd[1]: systemd-fsck-root.service: Deactivated successfully. May 15 12:21:55.181537 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. May 15 12:21:55.181548 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. May 15 12:21:55.181559 systemd[1]: Stopped systemd-fsck-usr.service. May 15 12:21:55.181571 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 15 12:21:55.181585 systemd[1]: Starting systemd-journald.service - Journal Service... May 15 12:21:55.181596 kernel: loop: module loaded May 15 12:21:55.181609 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 15 12:21:55.181642 systemd-journald[1508]: Collecting audit messages is disabled. May 15 12:21:55.181669 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 15 12:21:55.181682 systemd-journald[1508]: Journal started May 15 12:21:55.181707 systemd-journald[1508]: Runtime Journal (/run/log/journal/fb5808d17c954e55aff8770f88d928d7) is 8M, max 2.2G, 2.1G free. May 15 12:21:54.411895 systemd[1]: Queued start job for default target multi-user.target. May 15 12:21:54.437033 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. May 15 12:21:54.437674 systemd[1]: systemd-journald.service: Deactivated successfully. May 15 12:21:55.192484 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... May 15 12:21:55.203474 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... May 15 12:21:55.214469 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 15 12:21:55.224490 systemd[1]: verity-setup.service: Deactivated successfully. May 15 12:21:55.224536 systemd[1]: Stopped verity-setup.service. May 15 12:21:55.236474 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 15 12:21:55.243473 systemd[1]: Started systemd-journald.service - Journal Service. May 15 12:21:55.247539 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. May 15 12:21:55.251887 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. May 15 12:21:55.256313 systemd[1]: Mounted media.mount - External Media Directory. May 15 12:21:55.260386 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. May 15 12:21:55.264844 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. May 15 12:21:55.269464 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. May 15 12:21:55.273645 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. May 15 12:21:55.278471 kernel: fuse: init (API version 7.41) May 15 12:21:55.280590 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 15 12:21:55.285819 systemd[1]: modprobe@configfs.service: Deactivated successfully. May 15 12:21:55.286017 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. May 15 12:21:55.290968 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 15 12:21:55.291154 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 15 12:21:55.296014 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 15 12:21:55.296238 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 15 12:21:55.301516 systemd[1]: modprobe@fuse.service: Deactivated successfully. May 15 12:21:55.301736 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. May 15 12:21:55.306756 systemd[1]: modprobe@loop.service: Deactivated successfully. May 15 12:21:55.306956 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 15 12:21:55.311839 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 15 12:21:55.316477 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 15 12:21:55.321825 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. May 15 12:21:55.335394 systemd[1]: Reached target network-pre.target - Preparation for Network. May 15 12:21:55.341413 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... May 15 12:21:55.358722 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... May 15 12:21:55.363634 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). May 15 12:21:55.363674 systemd[1]: Reached target local-fs.target - Local File Systems. May 15 12:21:55.368758 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. May 15 12:21:55.374669 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... May 15 12:21:55.398469 kernel: ACPI: bus type drm_connector registered May 15 12:21:55.447471 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 15 12:21:55.491501 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... May 15 12:21:55.497092 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... May 15 12:21:55.501978 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 15 12:21:55.516242 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... May 15 12:21:55.520496 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 15 12:21:55.521375 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 15 12:21:55.526340 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... May 15 12:21:55.531849 systemd[1]: Starting systemd-sysusers.service - Create System Users... May 15 12:21:55.537741 systemd[1]: modprobe@drm.service: Deactivated successfully. May 15 12:21:55.538018 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 15 12:21:55.542711 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. May 15 12:21:55.548953 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 15 12:21:55.553869 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. May 15 12:21:55.558743 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. May 15 12:21:55.564072 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. May 15 12:21:55.572060 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. May 15 12:21:55.577671 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... May 15 12:21:55.579665 systemd-journald[1508]: Time spent on flushing to /var/log/journal/fb5808d17c954e55aff8770f88d928d7 is 14.204ms for 955 entries. May 15 12:21:55.579665 systemd-journald[1508]: System Journal (/var/log/journal/fb5808d17c954e55aff8770f88d928d7) is 8M, max 2.6G, 2.6G free. May 15 12:21:55.626736 systemd-journald[1508]: Received client request to flush runtime journal. May 15 12:21:55.626813 kernel: loop0: detected capacity change from 0 to 113872 May 15 12:21:55.628277 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. May 15 12:21:55.663521 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. May 15 12:21:55.664191 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. May 15 12:21:55.717838 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 15 12:21:55.763877 systemd[1]: Finished systemd-sysusers.service - Create System Users. May 15 12:21:55.769593 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 15 12:21:55.870967 systemd-tmpfiles[1559]: ACLs are not supported, ignoring. May 15 12:21:55.870989 systemd-tmpfiles[1559]: ACLs are not supported, ignoring. May 15 12:21:55.876336 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 15 12:21:56.288484 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher May 15 12:21:56.406481 kernel: loop1: detected capacity change from 0 to 146240 May 15 12:21:56.771334 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. May 15 12:21:56.777778 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 15 12:21:56.826316 systemd-udevd[1565]: Using default interface naming scheme 'v255'. May 15 12:21:57.031978 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 15 12:21:57.040014 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 15 12:21:57.077114 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. May 15 12:21:57.133475 kernel: loop2: detected capacity change from 0 to 28536 May 15 12:21:57.157239 systemd[1]: Starting systemd-userdbd.service - User Database Manager... May 15 12:21:57.200473 kernel: hv_vmbus: registering driver hyperv_fb May 15 12:21:57.208532 kernel: hyperv_fb: Synthvid Version major 3, minor 5 May 15 12:21:57.208580 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 May 15 12:21:57.212043 kernel: Console: switching to colour dummy device 80x25 May 15 12:21:57.216472 kernel: mousedev: PS/2 mouse device common for all mice May 15 12:21:57.216519 kernel: Console: switching to colour frame buffer device 128x48 May 15 12:21:57.230220 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 15 12:21:57.237563 kernel: hv_vmbus: registering driver hv_balloon May 15 12:21:57.240163 kernel: EDAC MC: Ver: 3.0.0 May 15 12:21:57.240214 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 May 15 12:21:57.244894 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 15 12:21:57.245099 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 15 12:21:57.250038 systemd[1]: Started systemd-userdbd.service - User Database Manager. May 15 12:21:57.258140 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 15 12:21:57.269934 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 15 12:21:57.270105 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 15 12:21:57.275982 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 15 12:21:57.423219 systemd-networkd[1587]: lo: Link UP May 15 12:21:57.423228 systemd-networkd[1587]: lo: Gained carrier May 15 12:21:57.424545 systemd-networkd[1587]: Enumeration completed May 15 12:21:57.424668 systemd[1]: Started systemd-networkd.service - Network Configuration. May 15 12:21:57.424910 systemd-networkd[1587]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 15 12:21:57.424920 systemd-networkd[1587]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 15 12:21:57.425472 systemd-networkd[1587]: eth0: Link UP May 15 12:21:57.425481 systemd-networkd[1587]: eth0: Gained carrier May 15 12:21:57.425495 systemd-networkd[1587]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 15 12:21:57.426115 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... May 15 12:21:57.427190 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... May 15 12:21:57.477528 systemd-networkd[1587]: eth0: DHCPv4 address 10.200.8.39/24, gateway 10.200.8.1 acquired from 168.63.129.16 May 15 12:21:57.491723 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. May 15 12:21:57.521253 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. May 15 12:21:57.522557 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... May 15 12:21:57.615182 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. May 15 12:21:57.657470 kernel: loop3: detected capacity change from 0 to 113872 May 15 12:21:57.667470 kernel: loop4: detected capacity change from 0 to 146240 May 15 12:21:57.681470 kernel: loop5: detected capacity change from 0 to 28536 May 15 12:21:57.686703 (sd-merge)[1721]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. May 15 12:21:57.687158 (sd-merge)[1721]: Merged extensions into '/usr'. May 15 12:21:57.690502 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. May 15 12:21:57.691979 systemd[1]: Starting ensure-sysext.service... May 15 12:21:57.692899 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 15 12:21:57.723640 systemd[1]: Reload requested from client PID 1723 ('systemctl') (unit ensure-sysext.service)... May 15 12:21:57.723657 systemd[1]: Reloading... May 15 12:21:57.765562 zram_generator::config[1756]: No configuration found. May 15 12:21:57.781578 systemd-tmpfiles[1724]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. May 15 12:21:57.781610 systemd-tmpfiles[1724]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. May 15 12:21:57.781872 systemd-tmpfiles[1724]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. May 15 12:21:57.782135 systemd-tmpfiles[1724]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. May 15 12:21:57.783010 systemd-tmpfiles[1724]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. May 15 12:21:57.783295 systemd-tmpfiles[1724]: ACLs are not supported, ignoring. May 15 12:21:57.783360 systemd-tmpfiles[1724]: ACLs are not supported, ignoring. May 15 12:21:57.866649 systemd-tmpfiles[1724]: Detected autofs mount point /boot during canonicalization of boot. May 15 12:21:57.866663 systemd-tmpfiles[1724]: Skipping /boot May 15 12:21:57.878158 systemd-tmpfiles[1724]: Detected autofs mount point /boot during canonicalization of boot. May 15 12:21:57.878198 systemd-tmpfiles[1724]: Skipping /boot May 15 12:21:57.883210 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 15 12:21:58.039697 systemd[1]: Reloading finished in 315 ms. May 15 12:21:58.104241 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 15 12:21:58.111568 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 15 12:21:58.146312 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... May 15 12:21:58.147689 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... May 15 12:21:58.149743 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 15 12:21:58.151023 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... May 15 12:21:58.153815 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 15 12:21:58.154035 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 15 12:21:58.174474 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 15 12:21:58.175635 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 15 12:21:58.177714 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 15 12:21:58.178243 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 15 12:21:58.178479 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 15 12:21:58.178589 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 15 12:21:58.182238 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 15 12:21:58.182413 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 15 12:21:58.183449 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 15 12:21:58.183635 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 15 12:21:58.184119 systemd[1]: modprobe@loop.service: Deactivated successfully. May 15 12:21:58.184276 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 15 12:21:58.189904 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 15 12:21:58.190212 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 15 12:21:58.191410 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 15 12:21:58.192528 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 15 12:21:58.194687 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 15 12:21:58.195133 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 15 12:21:58.195269 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 15 12:21:58.195366 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 15 12:21:58.196506 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. May 15 12:21:58.198034 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 15 12:21:58.198198 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 15 12:21:58.199026 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 15 12:21:58.199183 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 15 12:21:58.201030 systemd[1]: modprobe@loop.service: Deactivated successfully. May 15 12:21:58.201201 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 15 12:21:58.206888 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 15 12:21:58.207143 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 15 12:21:58.208324 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 15 12:21:58.209526 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 15 12:21:58.210978 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 15 12:21:58.212409 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 15 12:21:58.212950 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 15 12:21:58.213093 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 15 12:21:58.213250 systemd[1]: Reached target time-set.target - System Time Set. May 15 12:21:58.213625 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 15 12:21:58.215221 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 15 12:21:58.215379 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 15 12:21:58.217741 systemd[1]: modprobe@drm.service: Deactivated successfully. May 15 12:21:58.217902 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 15 12:21:58.218936 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 15 12:21:58.219093 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 15 12:21:58.219573 systemd[1]: modprobe@loop.service: Deactivated successfully. May 15 12:21:58.219733 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 15 12:21:58.222226 systemd[1]: Finished ensure-sysext.service. May 15 12:21:58.225574 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 15 12:21:58.225629 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 15 12:21:58.238644 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. May 15 12:21:58.372538 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 15 12:21:58.392283 systemd-resolved[1819]: Positive Trust Anchors: May 15 12:21:58.392304 systemd-resolved[1819]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 15 12:21:58.392335 systemd-resolved[1819]: 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 15 12:21:58.435934 systemd-resolved[1819]: Using system hostname 'ci-4334.0.0-a-14b320e2a7'. May 15 12:21:58.437893 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 15 12:21:58.442339 systemd[1]: Reached target network.target - Network. May 15 12:21:58.445852 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 15 12:21:58.536150 augenrules[1868]: No rules May 15 12:21:58.537361 systemd[1]: audit-rules.service: Deactivated successfully. May 15 12:21:58.537619 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 15 12:21:58.786610 systemd-networkd[1587]: eth0: Gained IPv6LL May 15 12:21:58.788926 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. May 15 12:21:58.794209 systemd[1]: Reached target network-online.target - Network is Online. May 15 12:21:59.186809 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. May 15 12:21:59.191988 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 15 12:22:03.870069 ldconfig[1535]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. May 15 12:22:03.883232 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. May 15 12:22:03.890438 systemd[1]: Starting systemd-update-done.service - Update is Completed... May 15 12:22:03.927813 systemd[1]: Finished systemd-update-done.service - Update is Completed. May 15 12:22:03.932479 systemd[1]: Reached target sysinit.target - System Initialization. May 15 12:22:03.936922 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. May 15 12:22:03.941866 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 15 12:22:03.947467 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. May 15 12:22:03.953046 systemd[1]: Started logrotate.timer - Daily rotation of log files. May 15 12:22:03.957584 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. May 15 12:22:03.962594 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. May 15 12:22:03.967696 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). May 15 12:22:03.967746 systemd[1]: Reached target paths.target - Path Units. May 15 12:22:03.971355 systemd[1]: Reached target timers.target - Timer Units. May 15 12:22:03.988215 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. May 15 12:22:03.994556 systemd[1]: Starting docker.socket - Docker Socket for the API... May 15 12:22:04.001436 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). May 15 12:22:04.006647 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). May 15 12:22:04.011543 systemd[1]: Reached target ssh-access.target - SSH Access Available. May 15 12:22:04.017986 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. May 15 12:22:04.022282 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. May 15 12:22:04.027444 systemd[1]: Listening on docker.socket - Docker Socket for the API. May 15 12:22:04.032979 systemd[1]: Reached target sockets.target - Socket Units. May 15 12:22:04.036834 systemd[1]: Reached target basic.target - Basic System. May 15 12:22:04.040532 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. May 15 12:22:04.040569 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. May 15 12:22:04.043293 systemd[1]: Starting chronyd.service - NTP client/server... May 15 12:22:04.047601 systemd[1]: Starting containerd.service - containerd container runtime... May 15 12:22:04.069731 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... May 15 12:22:04.075649 systemd[1]: Starting dbus.service - D-Bus System Message Bus... May 15 12:22:04.080251 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... May 15 12:22:04.085805 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... May 15 12:22:04.090717 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... May 15 12:22:04.095003 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 15 12:22:04.096056 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... May 15 12:22:04.101123 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... May 15 12:22:04.106168 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... May 15 12:22:04.110791 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... May 15 12:22:04.116619 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... May 15 12:22:04.123318 systemd[1]: Starting systemd-logind.service - User Login Management... May 15 12:22:04.127504 jq[1889]: false May 15 12:22:04.129004 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). May 15 12:22:04.129180 (chronyd)[1881]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS May 15 12:22:04.129628 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. May 15 12:22:04.130804 systemd[1]: Starting update-engine.service - Update Engine... May 15 12:22:04.144711 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... May 15 12:22:04.152413 jq[1904]: true May 15 12:22:04.152695 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. May 15 12:22:04.155919 google_oslogin_nss_cache[1891]: oslogin_cache_refresh[1891]: Refreshing passwd entry cache May 15 12:22:04.155925 oslogin_cache_refresh[1891]: Refreshing passwd entry cache May 15 12:22:04.157880 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. May 15 12:22:04.158082 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. May 15 12:22:04.158683 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. May 15 12:22:04.158901 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. May 15 12:22:04.167748 google_oslogin_nss_cache[1891]: oslogin_cache_refresh[1891]: Failure getting users, quitting May 15 12:22:04.167804 oslogin_cache_refresh[1891]: Failure getting users, quitting May 15 12:22:04.168278 google_oslogin_nss_cache[1891]: oslogin_cache_refresh[1891]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. May 15 12:22:04.168278 google_oslogin_nss_cache[1891]: oslogin_cache_refresh[1891]: Refreshing group entry cache May 15 12:22:04.167825 oslogin_cache_refresh[1891]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. May 15 12:22:04.167865 oslogin_cache_refresh[1891]: Refreshing group entry cache May 15 12:22:04.172359 google_oslogin_nss_cache[1891]: oslogin_cache_refresh[1891]: Failure getting groups, quitting May 15 12:22:04.172359 google_oslogin_nss_cache[1891]: oslogin_cache_refresh[1891]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. May 15 12:22:04.172343 oslogin_cache_refresh[1891]: Failure getting groups, quitting May 15 12:22:04.172354 oslogin_cache_refresh[1891]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. May 15 12:22:04.173902 systemd[1]: google-oslogin-cache.service: Deactivated successfully. May 15 12:22:04.175657 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. May 15 12:22:04.180083 jq[1906]: true May 15 12:22:04.183370 chronyd[1917]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) May 15 12:22:04.186531 chronyd[1917]: Timezone right/UTC failed leap second check, ignoring May 15 12:22:04.186719 chronyd[1917]: Loaded seccomp filter (level 2) May 15 12:22:04.187900 systemd[1]: Started chronyd.service - NTP client/server. May 15 12:22:04.189394 (ntainerd)[1920]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR May 15 12:22:04.191192 extend-filesystems[1890]: Found loop3 May 15 12:22:04.197191 extend-filesystems[1890]: Found loop4 May 15 12:22:04.197191 extend-filesystems[1890]: Found loop5 May 15 12:22:04.197191 extend-filesystems[1890]: Found sda May 15 12:22:04.197191 extend-filesystems[1890]: Found sda1 May 15 12:22:04.197191 extend-filesystems[1890]: Found sda2 May 15 12:22:04.197191 extend-filesystems[1890]: Found sda3 May 15 12:22:04.197191 extend-filesystems[1890]: Found usr May 15 12:22:04.197191 extend-filesystems[1890]: Found sda4 May 15 12:22:04.197191 extend-filesystems[1890]: Found sda6 May 15 12:22:04.197191 extend-filesystems[1890]: Found sda7 May 15 12:22:04.197191 extend-filesystems[1890]: Found sda9 May 15 12:22:04.197191 extend-filesystems[1890]: Checking size of /dev/sda9 May 15 12:22:04.192310 systemd[1]: motdgen.service: Deactivated successfully. May 15 12:22:04.192554 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. May 15 12:22:04.246463 extend-filesystems[1890]: Old size kept for /dev/sda9 May 15 12:22:04.246463 extend-filesystems[1890]: Found sdb May 15 12:22:04.246463 extend-filesystems[1890]: Found sdb1 May 15 12:22:04.246463 extend-filesystems[1890]: Found sr0 May 15 12:22:04.261639 update_engine[1901]: I20250515 12:22:04.248845 1901 main.cc:92] Flatcar Update Engine starting May 15 12:22:04.246903 systemd[1]: extend-filesystems.service: Deactivated successfully. May 15 12:22:04.247166 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. May 15 12:22:04.268466 systemd-logind[1899]: New seat seat0. May 15 12:22:04.270581 systemd-logind[1899]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) May 15 12:22:04.270767 systemd[1]: Started systemd-logind.service - User Login Management. May 15 12:22:04.291427 bash[1939]: Updated "/home/core/.ssh/authorized_keys" May 15 12:22:04.292259 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. May 15 12:22:04.298403 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. May 15 12:22:04.311077 sshd_keygen[1903]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 May 15 12:22:04.314438 dbus-daemon[1884]: [system] SELinux support is enabled May 15 12:22:04.316493 systemd[1]: Started dbus.service - D-Bus System Message Bus. May 15 12:22:04.317946 update_engine[1901]: I20250515 12:22:04.317881 1901 update_check_scheduler.cc:74] Next update check in 4m42s May 15 12:22:04.333515 dbus-daemon[1884]: [system] Successfully activated service 'org.freedesktop.systemd1' May 15 12:22:04.337430 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. May 15 12:22:04.347803 systemd[1]: Started update-engine.service - Update Engine. May 15 12:22:04.353860 systemd[1]: Starting issuegen.service - Generate /run/issue... May 15 12:22:04.357448 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 15 12:22:04.357617 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. May 15 12:22:04.362628 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 15 12:22:04.362749 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. May 15 12:22:04.381410 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... May 15 12:22:04.386283 systemd[1]: Started locksmithd.service - Cluster reboot manager. May 15 12:22:04.394077 systemd[1]: issuegen.service: Deactivated successfully. May 15 12:22:04.394279 systemd[1]: Finished issuegen.service - Generate /run/issue. May 15 12:22:04.398181 setup-nvidia[1894]: Downloading Flatcar Container Linux Developer Container for version: 4334.0.0+afterburn-5.8.0 May 15 12:22:04.401176 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... May 15 12:22:04.410864 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. May 15 12:22:04.435795 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. May 15 12:22:04.441743 systemd[1]: Started getty@tty1.service - Getty on tty1. May 15 12:22:04.446597 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. May 15 12:22:04.451128 systemd[1]: Reached target getty.target - Login Prompts. May 15 12:22:04.452990 coreos-metadata[1883]: May 15 12:22:04.452 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 May 15 12:22:04.455407 coreos-metadata[1883]: May 15 12:22:04.455 INFO Fetch successful May 15 12:22:04.455559 coreos-metadata[1883]: May 15 12:22:04.455 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 May 15 12:22:04.459353 coreos-metadata[1883]: May 15 12:22:04.459 INFO Fetch successful May 15 12:22:04.459649 coreos-metadata[1883]: May 15 12:22:04.459 INFO Fetching http://168.63.129.16/machine/525d1697-f60d-468c-b588-619ba055904e/87ef992f%2D789a%2D4b8a%2D8c47%2D86699d11d869.%5Fci%2D4334.0.0%2Da%2D14b320e2a7?comp=config&type=sharedConfig&incarnation=1: Attempt #1 May 15 12:22:04.461164 coreos-metadata[1883]: May 15 12:22:04.461 INFO Fetch successful May 15 12:22:04.461300 coreos-metadata[1883]: May 15 12:22:04.461 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 May 15 12:22:04.468504 coreos-metadata[1883]: May 15 12:22:04.468 INFO Fetch successful May 15 12:22:04.493342 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. May 15 12:22:04.498218 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. May 15 12:22:04.694769 locksmithd[2026]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" May 15 12:22:05.695601 containerd[1920]: time="2025-05-15T12:22:05Z" 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 15 12:22:05.696151 containerd[1920]: time="2025-05-15T12:22:05.696115600Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 May 15 12:22:05.705690 containerd[1920]: time="2025-05-15T12:22:05.705651500Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="7.8µs" May 15 12:22:05.705690 containerd[1920]: time="2025-05-15T12:22:05.705680100Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 May 15 12:22:05.705784 containerd[1920]: time="2025-05-15T12:22:05.705698000Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 May 15 12:22:05.705854 containerd[1920]: time="2025-05-15T12:22:05.705830700Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 May 15 12:22:05.705854 containerd[1920]: time="2025-05-15T12:22:05.705852500Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 May 15 12:22:05.705912 containerd[1920]: time="2025-05-15T12:22:05.705875100Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 May 15 12:22:05.705946 containerd[1920]: time="2025-05-15T12:22:05.705931500Z" 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 15 12:22:05.705946 containerd[1920]: time="2025-05-15T12:22:05.705944000Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 May 15 12:22:05.706179 containerd[1920]: time="2025-05-15T12:22:05.706125400Z" 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 15 12:22:05.706179 containerd[1920]: time="2025-05-15T12:22:05.706175300Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 15 12:22:05.706230 containerd[1920]: time="2025-05-15T12:22:05.706188900Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 15 12:22:05.706230 containerd[1920]: time="2025-05-15T12:22:05.706196800Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 May 15 12:22:05.706287 containerd[1920]: time="2025-05-15T12:22:05.706276400Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 May 15 12:22:05.706516 containerd[1920]: time="2025-05-15T12:22:05.706492700Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 May 15 12:22:05.706549 containerd[1920]: time="2025-05-15T12:22:05.706527300Z" 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 15 12:22:05.706549 containerd[1920]: time="2025-05-15T12:22:05.706537400Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 May 15 12:22:05.706723 containerd[1920]: time="2025-05-15T12:22:05.706682300Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 May 15 12:22:05.707395 containerd[1920]: time="2025-05-15T12:22:05.707353500Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 May 15 12:22:05.707495 containerd[1920]: time="2025-05-15T12:22:05.707468100Z" level=info msg="metadata content store policy set" policy=shared May 15 12:22:05.725919 containerd[1920]: time="2025-05-15T12:22:05.725846300Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 May 15 12:22:05.725982 containerd[1920]: time="2025-05-15T12:22:05.725930200Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 May 15 12:22:05.725982 containerd[1920]: time="2025-05-15T12:22:05.725948500Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 May 15 12:22:05.725982 containerd[1920]: time="2025-05-15T12:22:05.725960700Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 May 15 12:22:05.725982 containerd[1920]: time="2025-05-15T12:22:05.725971900Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 May 15 12:22:05.726072 containerd[1920]: time="2025-05-15T12:22:05.725994800Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 May 15 12:22:05.726072 containerd[1920]: time="2025-05-15T12:22:05.726008800Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 May 15 12:22:05.726072 containerd[1920]: time="2025-05-15T12:22:05.726021000Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 May 15 12:22:05.726072 containerd[1920]: time="2025-05-15T12:22:05.726032900Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 May 15 12:22:05.726072 containerd[1920]: time="2025-05-15T12:22:05.726042200Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 May 15 12:22:05.726072 containerd[1920]: time="2025-05-15T12:22:05.726051500Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 May 15 12:22:05.726072 containerd[1920]: time="2025-05-15T12:22:05.726063100Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 May 15 12:22:05.726218 containerd[1920]: time="2025-05-15T12:22:05.726167900Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 May 15 12:22:05.726218 containerd[1920]: time="2025-05-15T12:22:05.726187500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 May 15 12:22:05.726218 containerd[1920]: time="2025-05-15T12:22:05.726202800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 May 15 12:22:05.726218 containerd[1920]: time="2025-05-15T12:22:05.726213100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 May 15 12:22:05.726300 containerd[1920]: time="2025-05-15T12:22:05.726222800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 May 15 12:22:05.726300 containerd[1920]: time="2025-05-15T12:22:05.726232200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 May 15 12:22:05.726300 containerd[1920]: time="2025-05-15T12:22:05.726242100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 May 15 12:22:05.726300 containerd[1920]: time="2025-05-15T12:22:05.726251500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 May 15 12:22:05.726300 containerd[1920]: time="2025-05-15T12:22:05.726261300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 May 15 12:22:05.726300 containerd[1920]: time="2025-05-15T12:22:05.726284400Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 May 15 12:22:05.726300 containerd[1920]: time="2025-05-15T12:22:05.726297300Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 May 15 12:22:05.726448 containerd[1920]: time="2025-05-15T12:22:05.726353300Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" May 15 12:22:05.726448 containerd[1920]: time="2025-05-15T12:22:05.726366400Z" level=info msg="Start snapshots syncer" May 15 12:22:05.726448 containerd[1920]: time="2025-05-15T12:22:05.726390600Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 May 15 12:22:05.726641 containerd[1920]: time="2025-05-15T12:22:05.726600400Z" 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 15 12:22:05.726759 containerd[1920]: time="2025-05-15T12:22:05.726657900Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 May 15 12:22:05.726759 containerd[1920]: time="2025-05-15T12:22:05.726730800Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 May 15 12:22:05.726855 containerd[1920]: time="2025-05-15T12:22:05.726831100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 May 15 12:22:05.726889 containerd[1920]: time="2025-05-15T12:22:05.726857500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 May 15 12:22:05.726889 containerd[1920]: time="2025-05-15T12:22:05.726868200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 May 15 12:22:05.726889 containerd[1920]: time="2025-05-15T12:22:05.726880000Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 May 15 12:22:05.726955 containerd[1920]: time="2025-05-15T12:22:05.726891300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 May 15 12:22:05.726955 containerd[1920]: time="2025-05-15T12:22:05.726901300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 May 15 12:22:05.726955 containerd[1920]: time="2025-05-15T12:22:05.726910200Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 May 15 12:22:05.726955 containerd[1920]: time="2025-05-15T12:22:05.726930500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 May 15 12:22:05.726955 containerd[1920]: time="2025-05-15T12:22:05.726946000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 May 15 12:22:05.727058 containerd[1920]: time="2025-05-15T12:22:05.726956100Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 May 15 12:22:05.727058 containerd[1920]: time="2025-05-15T12:22:05.726982300Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 May 15 12:22:05.727058 containerd[1920]: time="2025-05-15T12:22:05.726995800Z" 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 15 12:22:05.727058 containerd[1920]: time="2025-05-15T12:22:05.727003800Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 May 15 12:22:05.727058 containerd[1920]: time="2025-05-15T12:22:05.727012200Z" 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 15 12:22:05.727058 containerd[1920]: time="2025-05-15T12:22:05.727019400Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 May 15 12:22:05.727058 containerd[1920]: time="2025-05-15T12:22:05.727027300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 May 15 12:22:05.727058 containerd[1920]: time="2025-05-15T12:22:05.727036700Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 May 15 12:22:05.727058 containerd[1920]: time="2025-05-15T12:22:05.727053600Z" level=info msg="runtime interface created" May 15 12:22:05.727058 containerd[1920]: time="2025-05-15T12:22:05.727059400Z" level=info msg="created NRI interface" May 15 12:22:05.727259 containerd[1920]: time="2025-05-15T12:22:05.727067000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 May 15 12:22:05.727259 containerd[1920]: time="2025-05-15T12:22:05.727076900Z" level=info msg="Connect containerd service" May 15 12:22:05.727259 containerd[1920]: time="2025-05-15T12:22:05.727098400Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" May 15 12:22:05.727767 containerd[1920]: time="2025-05-15T12:22:05.727738000Z" 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 15 12:22:06.976939 containerd[1920]: time="2025-05-15T12:22:06.976892300Z" level=info msg="Start subscribing containerd event" May 15 12:22:06.977285 containerd[1920]: time="2025-05-15T12:22:06.976949300Z" level=info msg="Start recovering state" May 15 12:22:06.977285 containerd[1920]: time="2025-05-15T12:22:06.977042800Z" level=info msg="Start event monitor" May 15 12:22:06.977285 containerd[1920]: time="2025-05-15T12:22:06.977065400Z" level=info msg="Start cni network conf syncer for default" May 15 12:22:06.977285 containerd[1920]: time="2025-05-15T12:22:06.977074000Z" level=info msg="Start streaming server" May 15 12:22:06.977285 containerd[1920]: time="2025-05-15T12:22:06.977082500Z" level=info msg="Registered namespace \"k8s.io\" with NRI" May 15 12:22:06.977285 containerd[1920]: time="2025-05-15T12:22:06.977089600Z" level=info msg="runtime interface starting up..." May 15 12:22:06.977285 containerd[1920]: time="2025-05-15T12:22:06.977095000Z" level=info msg="starting plugins..." May 15 12:22:06.977285 containerd[1920]: time="2025-05-15T12:22:06.977099200Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc May 15 12:22:06.977285 containerd[1920]: time="2025-05-15T12:22:06.977108200Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" May 15 12:22:06.977285 containerd[1920]: time="2025-05-15T12:22:06.977164000Z" level=info msg=serving... address=/run/containerd/containerd.sock May 15 12:22:06.977538 containerd[1920]: time="2025-05-15T12:22:06.977313800Z" level=info msg="containerd successfully booted in 1.282121s" May 15 12:22:06.977406 systemd[1]: Started containerd.service - containerd container runtime. May 15 12:22:07.034459 waagent[2038]: 2025-05-15T12:22:07.034370Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 May 15 12:22:07.038958 waagent[2038]: 2025-05-15T12:22:07.038912Z INFO Daemon Daemon OS: flatcar 4334.0.0 May 15 12:22:07.042661 waagent[2038]: 2025-05-15T12:22:07.042619Z INFO Daemon Daemon Python: 3.11.12 May 15 12:22:07.046359 waagent[2038]: 2025-05-15T12:22:07.046308Z INFO Daemon Daemon Run daemon May 15 12:22:07.049739 waagent[2038]: 2025-05-15T12:22:07.049694Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4334.0.0' May 15 12:22:07.056578 waagent[2038]: 2025-05-15T12:22:07.056520Z INFO Daemon Daemon Using waagent for provisioning May 15 12:22:07.061378 waagent[2038]: 2025-05-15T12:22:07.061335Z INFO Daemon Daemon Activate resource disk May 15 12:22:07.065391 waagent[2038]: 2025-05-15T12:22:07.065341Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb May 15 12:22:07.076948 waagent[2038]: 2025-05-15T12:22:07.076900Z INFO Daemon Daemon Found device: sdb May 15 12:22:07.085990 waagent[2038]: 2025-05-15T12:22:07.085938Z INFO Daemon Daemon Examining partition table May 15 12:22:07.113213 waagent[2038]: 2025-05-15T12:22:07.113148Z INFO Daemon Daemon GPT not detected, determining filesystem May 15 12:22:07.170641 waagent[2038]: 2025-05-15T12:22:07.170573Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded May 15 12:22:07.175167 waagent[2038]: 2025-05-15T12:22:07.175118Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 May 15 12:22:07.489002 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#542 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 May 15 12:22:07.500471 kernel: sdb: sdb1 May 15 12:22:07.507387 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#63 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 May 15 12:22:07.511711 waagent[2038]: 2025-05-15T12:22:07.511637Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded May 15 12:22:07.525479 kernel: sdb: sdb1 May 15 12:22:07.525526 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#131 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 May 15 12:22:07.545839 kernel: sdb: sdb1 May 15 12:22:07.545899 waagent[2038]: 2025-05-15T12:22:07.545542Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] May 15 12:22:12.425292 login[2041]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying May 15 12:22:12.425596 login[2040]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) May 15 12:22:12.432042 systemd[1]: Created slice user-500.slice - User Slice of UID 500. May 15 12:22:12.433139 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... May 15 12:22:12.439277 systemd-logind[1899]: New session 1 of user core. May 15 12:22:12.470076 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. May 15 12:22:12.472563 systemd[1]: Starting user@500.service - User Manager for UID 500... May 15 12:22:12.523146 (systemd)[2144]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) May 15 12:22:12.525315 systemd-logind[1899]: New session c1 of user core. May 15 12:22:13.270302 waagent[2038]: 2025-05-15T12:22:13.270216Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] May 15 12:22:13.377489 kernel: EXT4-fs (sdb1): mounted filesystem 98e6d72c-42cb-43a9-8332-87dbbdb5f207 r/w with ordered data mode. Quota mode: none. May 15 12:22:13.377847 waagent[2038]: 2025-05-15T12:22:13.377779Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 May 15 12:22:13.378655 waagent[2038]: 2025-05-15T12:22:13.378608Z INFO Daemon Daemon Clean protocol and wireserver endpoint May 15 12:22:13.378910 waagent[2038]: 2025-05-15T12:22:13.378869Z INFO Daemon Daemon Running default provisioning handler May 15 12:22:13.390656 waagent[2038]: 2025-05-15T12:22:13.390601Z 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 15 12:22:13.400122 waagent[2038]: 2025-05-15T12:22:13.400075Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' May 15 12:22:13.400338 waagent[2038]: 2025-05-15T12:22:13.400296Z INFO Daemon Daemon cloud-init is enabled: False May 15 12:22:13.401257 systemd[2144]: Queued start job for default target default.target. May 15 12:22:13.401765 waagent[2038]: 2025-05-15T12:22:13.401725Z INFO Daemon Daemon Copying ovf-env.xml May 15 12:22:13.427083 login[2041]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) May 15 12:22:13.431417 systemd-logind[1899]: New session 2 of user core. May 15 12:22:13.433561 systemd[2144]: Created slice app.slice - User Application Slice. May 15 12:22:13.433605 systemd[2144]: Reached target paths.target - Paths. May 15 12:22:13.433650 systemd[2144]: Reached target timers.target - Timers. May 15 12:22:13.434846 systemd[2144]: Starting dbus.socket - D-Bus User Message Bus Socket... May 15 12:22:13.444862 systemd[2144]: Listening on dbus.socket - D-Bus User Message Bus Socket. May 15 12:22:13.444950 systemd[2144]: Reached target sockets.target - Sockets. May 15 12:22:13.445002 systemd[2144]: Reached target basic.target - Basic System. May 15 12:22:13.445045 systemd[2144]: Reached target default.target - Main User Target. May 15 12:22:13.445075 systemd[2144]: Startup finished in 914ms. May 15 12:22:13.445186 systemd[1]: Started user@500.service - User Manager for UID 500. May 15 12:22:13.446679 systemd[1]: Started session-1.scope - Session 1 of User core. May 15 12:22:13.447503 systemd[1]: Started session-2.scope - Session 2 of User core. May 15 12:22:13.579166 waagent[2038]: 2025-05-15T12:22:13.579043Z INFO Daemon Daemon Successfully mounted dvd May 15 12:22:13.593778 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. May 15 12:22:13.593894 waagent[2038]: 2025-05-15T12:22:13.593779Z INFO Daemon Daemon Detect protocol endpoint May 15 12:22:13.597684 waagent[2038]: 2025-05-15T12:22:13.597641Z INFO Daemon Daemon Clean protocol and wireserver endpoint May 15 12:22:13.602087 waagent[2038]: 2025-05-15T12:22:13.602048Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler May 15 12:22:13.607610 waagent[2038]: 2025-05-15T12:22:13.607559Z INFO Daemon Daemon Test for route to 168.63.129.16 May 15 12:22:13.611823 waagent[2038]: 2025-05-15T12:22:13.611780Z INFO Daemon Daemon Route to 168.63.129.16 exists May 15 12:22:13.615840 waagent[2038]: 2025-05-15T12:22:13.615797Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 May 15 12:22:13.640715 waagent[2038]: 2025-05-15T12:22:13.640666Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 May 15 12:22:13.641042 waagent[2038]: 2025-05-15T12:22:13.641014Z INFO Daemon Daemon Wire protocol version:2012-11-30 May 15 12:22:13.642310 waagent[2038]: 2025-05-15T12:22:13.642276Z INFO Daemon Daemon Server preferred version:2015-04-05 May 15 12:22:13.845412 waagent[2038]: 2025-05-15T12:22:13.845301Z INFO Daemon Daemon Initializing goal state during protocol detection May 15 12:22:13.845623 waagent[2038]: 2025-05-15T12:22:13.845579Z INFO Daemon Daemon Forcing an update of the goal state. May 15 12:22:13.849401 waagent[2038]: 2025-05-15T12:22:13.849352Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] May 15 12:22:13.878991 waagent[2038]: 2025-05-15T12:22:13.878948Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.164 May 15 12:22:13.879613 waagent[2038]: 2025-05-15T12:22:13.879573Z INFO Daemon May 15 12:22:13.879923 waagent[2038]: 2025-05-15T12:22:13.879889Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 6d588e12-506f-42a6-8cad-e93a89616594 eTag: 12946161427161068202 source: Fabric] May 15 12:22:13.881607 waagent[2038]: 2025-05-15T12:22:13.881568Z INFO Daemon The vmSettings originated via Fabric; will ignore them. May 15 12:22:13.882308 waagent[2038]: 2025-05-15T12:22:13.882270Z INFO Daemon May 15 12:22:13.883514 waagent[2038]: 2025-05-15T12:22:13.883479Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] May 15 12:22:13.887528 waagent[2038]: 2025-05-15T12:22:13.887494Z INFO Daemon Daemon Downloading artifacts profile blob May 15 12:22:13.975539 waagent[2038]: 2025-05-15T12:22:13.975480Z INFO Daemon Downloaded certificate {'thumbprint': 'C2D21C89DAA60C9E47D055E1554C650F8E6F8E7D', 'hasPrivateKey': True} May 15 12:22:13.976063 waagent[2038]: 2025-05-15T12:22:13.976014Z INFO Daemon Fetch goal state completed May 15 12:22:14.024245 waagent[2038]: 2025-05-15T12:22:14.024197Z INFO Daemon Daemon Starting provisioning May 15 12:22:14.024440 waagent[2038]: 2025-05-15T12:22:14.024399Z INFO Daemon Daemon Handle ovf-env.xml. May 15 12:22:14.025163 waagent[2038]: 2025-05-15T12:22:14.025128Z INFO Daemon Daemon Set hostname [ci-4334.0.0-a-14b320e2a7] May 15 12:22:14.101455 waagent[2038]: 2025-05-15T12:22:14.101380Z INFO Daemon Daemon Publish hostname [ci-4334.0.0-a-14b320e2a7] May 15 12:22:14.101974 waagent[2038]: 2025-05-15T12:22:14.101929Z INFO Daemon Daemon Examine /proc/net/route for primary interface May 15 12:22:14.103404 waagent[2038]: 2025-05-15T12:22:14.103360Z INFO Daemon Daemon Primary interface is [eth0] May 15 12:22:14.123995 systemd-networkd[1587]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 15 12:22:14.124006 systemd-networkd[1587]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 15 12:22:14.124040 systemd-networkd[1587]: eth0: DHCP lease lost May 15 12:22:14.124442 waagent[2038]: 2025-05-15T12:22:14.124396Z INFO Daemon Daemon Create user account if not exists May 15 12:22:14.124707 waagent[2038]: 2025-05-15T12:22:14.124669Z INFO Daemon Daemon User core already exists, skip useradd May 15 12:22:14.126069 waagent[2038]: 2025-05-15T12:22:14.126027Z INFO Daemon Daemon Configure sudoer May 15 12:22:14.131863 waagent[2038]: 2025-05-15T12:22:14.131818Z INFO Daemon Daemon Configure sshd May 15 12:22:14.137001 waagent[2038]: 2025-05-15T12:22:14.136959Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. May 15 12:22:14.138036 waagent[2038]: 2025-05-15T12:22:14.137996Z INFO Daemon Daemon Deploy ssh public key. May 15 12:22:14.221499 systemd-networkd[1587]: eth0: DHCPv4 address 10.200.8.39/24, gateway 10.200.8.1 acquired from 168.63.129.16 May 15 12:22:27.968104 chronyd[1917]: Selected source PHC0 May 15 12:22:44.229578 waagent[2038]: 2025-05-15T12:22:44.229508Z INFO Daemon Daemon Provisioning complete May 15 12:22:44.237959 waagent[2038]: 2025-05-15T12:22:44.237913Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping May 15 12:22:44.238190 waagent[2038]: 2025-05-15T12:22:44.238144Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. May 15 12:22:44.239614 waagent[2038]: 2025-05-15T12:22:44.239579Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent May 15 12:22:44.392414 waagent[2211]: 2025-05-15T12:22:44.392330Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) May 15 12:22:44.392803 waagent[2211]: 2025-05-15T12:22:44.392489Z INFO ExtHandler ExtHandler OS: flatcar 4334.0.0 May 15 12:22:44.392803 waagent[2211]: 2025-05-15T12:22:44.392565Z INFO ExtHandler ExtHandler Python: 3.11.12 May 15 12:22:44.392803 waagent[2211]: 2025-05-15T12:22:44.392634Z INFO ExtHandler ExtHandler CPU Arch: x86_64 May 15 12:22:44.453932 waagent[2211]: 2025-05-15T12:22:44.453874Z INFO ExtHandler ExtHandler Distro: flatcar-4334.0.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.12; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; May 15 12:22:44.454116 waagent[2211]: 2025-05-15T12:22:44.454076Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file May 15 12:22:44.454194 waagent[2211]: 2025-05-15T12:22:44.454159Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 May 15 12:22:44.460655 waagent[2211]: 2025-05-15T12:22:44.460598Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] May 15 12:22:44.470919 waagent[2211]: 2025-05-15T12:22:44.470874Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.164 May 15 12:22:44.471382 waagent[2211]: 2025-05-15T12:22:44.471303Z INFO ExtHandler May 15 12:22:44.471480 waagent[2211]: 2025-05-15T12:22:44.471429Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 66687416-fe05-45ee-9041-b8907f742b75 eTag: 12946161427161068202 source: Fabric] May 15 12:22:44.471802 waagent[2211]: 2025-05-15T12:22:44.471762Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. May 15 12:22:44.472386 waagent[2211]: 2025-05-15T12:22:44.472345Z INFO ExtHandler May 15 12:22:44.472456 waagent[2211]: 2025-05-15T12:22:44.472423Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] May 15 12:22:44.476246 waagent[2211]: 2025-05-15T12:22:44.476207Z INFO ExtHandler ExtHandler Downloading artifacts profile blob May 15 12:22:44.548365 waagent[2211]: 2025-05-15T12:22:44.548261Z INFO ExtHandler Downloaded certificate {'thumbprint': 'C2D21C89DAA60C9E47D055E1554C650F8E6F8E7D', 'hasPrivateKey': True} May 15 12:22:44.548807 waagent[2211]: 2025-05-15T12:22:44.548764Z INFO ExtHandler Fetch goal state completed May 15 12:22:44.559546 waagent[2211]: 2025-05-15T12:22:44.559502Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.3.3 11 Feb 2025 (Library: OpenSSL 3.3.3 11 Feb 2025) May 15 12:22:44.564345 waagent[2211]: 2025-05-15T12:22:44.564300Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2211 May 15 12:22:44.564503 waagent[2211]: 2025-05-15T12:22:44.564443Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** May 15 12:22:44.564829 waagent[2211]: 2025-05-15T12:22:44.564788Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** May 15 12:22:44.566374 waagent[2211]: 2025-05-15T12:22:44.566332Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4334.0.0', '', 'Flatcar Container Linux by Kinvolk'] May 15 12:22:44.566977 waagent[2211]: 2025-05-15T12:22:44.566930Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4334.0.0', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported May 15 12:22:44.567126 waagent[2211]: 2025-05-15T12:22:44.567088Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False May 15 12:22:44.567816 waagent[2211]: 2025-05-15T12:22:44.567775Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules May 15 12:22:44.632595 waagent[2211]: 2025-05-15T12:22:44.632555Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service May 15 12:22:44.632769 waagent[2211]: 2025-05-15T12:22:44.632730Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup May 15 12:22:44.638891 waagent[2211]: 2025-05-15T12:22:44.638839Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now May 15 12:22:44.645275 systemd[1]: Reload requested from client PID 2226 ('systemctl') (unit waagent.service)... May 15 12:22:44.645297 systemd[1]: Reloading... May 15 12:22:44.703487 zram_generator::config[2267]: No configuration found. May 15 12:22:44.813857 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 15 12:22:44.977522 systemd[1]: Reloading finished in 331 ms. May 15 12:22:44.998568 waagent[2211]: 2025-05-15T12:22:44.998501Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service May 15 12:22:44.998681 waagent[2211]: 2025-05-15T12:22:44.998646Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully May 15 12:22:45.359984 kernel: hv_balloon: Max. dynamic memory size: 114688 MB May 15 12:22:45.883644 waagent[2211]: 2025-05-15T12:22:45.883564Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. May 15 12:22:45.883992 waagent[2211]: 2025-05-15T12:22:45.883901Z 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 15 12:22:45.884772 waagent[2211]: 2025-05-15T12:22:45.884670Z INFO ExtHandler ExtHandler Starting env monitor service. May 15 12:22:45.885089 waagent[2211]: 2025-05-15T12:22:45.885037Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file May 15 12:22:45.885266 waagent[2211]: 2025-05-15T12:22:45.885226Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. May 15 12:22:45.885799 waagent[2211]: 2025-05-15T12:22:45.885744Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread May 15 12:22:45.885895 waagent[2211]: 2025-05-15T12:22:45.885849Z INFO ExtHandler ExtHandler Start Extension Telemetry service. May 15 12:22:45.885957 waagent[2211]: 2025-05-15T12:22:45.885914Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file May 15 12:22:45.886008 waagent[2211]: 2025-05-15T12:22:45.885968Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 May 15 12:22:45.886647 waagent[2211]: 2025-05-15T12:22:45.886598Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. May 15 12:22:45.886948 waagent[2211]: 2025-05-15T12:22:45.886909Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 May 15 12:22:45.887196 waagent[2211]: 2025-05-15T12:22:45.887138Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True May 15 12:22:45.887487 waagent[2211]: 2025-05-15T12:22:45.887421Z INFO EnvHandler ExtHandler Configure routes May 15 12:22:45.887631 waagent[2211]: 2025-05-15T12:22:45.887578Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. May 15 12:22:45.887864 waagent[2211]: 2025-05-15T12:22:45.887820Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread May 15 12:22:45.887911 waagent[2211]: 2025-05-15T12:22:45.887874Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: May 15 12:22:45.887911 waagent[2211]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT May 15 12:22:45.887911 waagent[2211]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 May 15 12:22:45.887911 waagent[2211]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 May 15 12:22:45.887911 waagent[2211]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 May 15 12:22:45.887911 waagent[2211]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 May 15 12:22:45.887911 waagent[2211]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 May 15 12:22:45.888337 waagent[2211]: 2025-05-15T12:22:45.888298Z INFO EnvHandler ExtHandler Gateway:None May 15 12:22:45.888995 waagent[2211]: 2025-05-15T12:22:45.888959Z INFO EnvHandler ExtHandler Routes:None May 15 12:22:45.894399 waagent[2211]: 2025-05-15T12:22:45.894350Z INFO ExtHandler ExtHandler May 15 12:22:45.894754 waagent[2211]: 2025-05-15T12:22:45.894711Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: b1807299-5742-4086-aa72-c58ad325bb40 correlation 79b5270e-e3f5-4699-be22-2e248af38337 created: 2025-05-15T12:20:45.961739Z] May 15 12:22:45.895829 waagent[2211]: 2025-05-15T12:22:45.895785Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. May 15 12:22:45.898157 waagent[2211]: 2025-05-15T12:22:45.898109Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] May 15 12:22:45.927743 waagent[2211]: 2025-05-15T12:22:45.927685Z 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 15 12:22:45.927743 waagent[2211]: Try `iptables -h' or 'iptables --help' for more information.) May 15 12:22:45.928096 waagent[2211]: 2025-05-15T12:22:45.928057Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: D521C1BD-8918-4B5C-A291-453AC67B20A1;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] May 15 12:22:46.016414 waagent[2211]: 2025-05-15T12:22:46.016353Z INFO MonitorHandler ExtHandler Network interfaces: May 15 12:22:46.016414 waagent[2211]: Executing ['ip', '-a', '-o', 'link']: May 15 12:22:46.016414 waagent[2211]: 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 15 12:22:46.016414 waagent[2211]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 60:45:bd:e1:8d:73 brd ff:ff:ff:ff:ff:ff May 15 12:22:46.016414 waagent[2211]: Executing ['ip', '-4', '-a', '-o', 'address']: May 15 12:22:46.016414 waagent[2211]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever May 15 12:22:46.016414 waagent[2211]: 2: eth0 inet 10.200.8.39/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever May 15 12:22:46.016414 waagent[2211]: Executing ['ip', '-6', '-a', '-o', 'address']: May 15 12:22:46.016414 waagent[2211]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever May 15 12:22:46.016414 waagent[2211]: 2: eth0 inet6 fe80::6245:bdff:fee1:8d73/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever May 15 12:22:46.056830 waagent[2211]: 2025-05-15T12:22:46.056774Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: May 15 12:22:46.056830 waagent[2211]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) May 15 12:22:46.056830 waagent[2211]: pkts bytes target prot opt in out source destination May 15 12:22:46.056830 waagent[2211]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) May 15 12:22:46.056830 waagent[2211]: pkts bytes target prot opt in out source destination May 15 12:22:46.056830 waagent[2211]: Chain OUTPUT (policy ACCEPT 4 packets, 416 bytes) May 15 12:22:46.056830 waagent[2211]: pkts bytes target prot opt in out source destination May 15 12:22:46.056830 waagent[2211]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 May 15 12:22:46.056830 waagent[2211]: 10 1047 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 May 15 12:22:46.056830 waagent[2211]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW May 15 12:22:46.065525 waagent[2211]: 2025-05-15T12:22:46.065133Z INFO EnvHandler ExtHandler Current Firewall rules: May 15 12:22:46.065525 waagent[2211]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) May 15 12:22:46.065525 waagent[2211]: pkts bytes target prot opt in out source destination May 15 12:22:46.065525 waagent[2211]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) May 15 12:22:46.065525 waagent[2211]: pkts bytes target prot opt in out source destination May 15 12:22:46.065525 waagent[2211]: Chain OUTPUT (policy ACCEPT 7 packets, 765 bytes) May 15 12:22:46.065525 waagent[2211]: pkts bytes target prot opt in out source destination May 15 12:22:46.065525 waagent[2211]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 May 15 12:22:46.065525 waagent[2211]: 11 1099 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 May 15 12:22:46.065525 waagent[2211]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW May 15 12:22:46.065525 waagent[2211]: 2025-05-15T12:22:46.065388Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 May 15 12:22:46.065805 waagent[2211]: 2025-05-15T12:22:46.065548Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 May 15 12:22:49.927355 update_engine[1901]: I20250515 12:22:49.927269 1901 update_attempter.cc:509] Updating boot flags... May 15 12:23:03.199373 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. May 15 12:23:03.205055 systemd[1]: Started sshd@0-10.200.8.39:22-10.200.16.10:45680.service - OpenSSH per-connection server daemon (10.200.16.10:45680). May 15 12:23:04.074930 sshd[2422]: Accepted publickey for core from 10.200.16.10 port 45680 ssh2: RSA SHA256:alfUx42qKxt4UmMKlpWteu3ZEwnfJhYGidKCvoydx6g May 15 12:23:04.076296 sshd-session[2422]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 12:23:04.080535 systemd-logind[1899]: New session 3 of user core. May 15 12:23:04.097580 systemd[1]: Started session-3.scope - Session 3 of User core. May 15 12:23:04.636652 systemd[1]: Started sshd@1-10.200.8.39:22-10.200.16.10:45684.service - OpenSSH per-connection server daemon (10.200.16.10:45684). May 15 12:23:04.712881 setup-nvidia[2429]: % Total % Received % Xferd Average Speed Time Time Time Current May 15 12:23:04.713135 setup-nvidia[2429]: Dload Upload Total Spent Left Speed May 15 12:23:05.278710 sshd[2427]: Accepted publickey for core from 10.200.16.10 port 45684 ssh2: RSA SHA256:alfUx42qKxt4UmMKlpWteu3ZEwnfJhYGidKCvoydx6g May 15 12:23:05.279964 sshd-session[2427]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 12:23:05.284017 systemd-logind[1899]: New session 4 of user core. May 15 12:23:05.293575 systemd[1]: Started session-4.scope - Session 4 of User core. May 15 12:23:05.731688 sshd[2430]: Connection closed by 10.200.16.10 port 45684 May 15 12:23:05.732006 sshd-session[2427]: pam_unix(sshd:session): session closed for user core May 15 12:23:05.735116 systemd[1]: sshd@1-10.200.8.39:22-10.200.16.10:45684.service: Deactivated successfully. May 15 12:23:05.736702 systemd[1]: session-4.scope: Deactivated successfully. May 15 12:23:05.737388 systemd-logind[1899]: Session 4 logged out. Waiting for processes to exit. May 15 12:23:05.738361 systemd-logind[1899]: Removed session 4. May 15 12:23:05.847649 systemd[1]: Started sshd@2-10.200.8.39:22-10.200.16.10:45696.service - OpenSSH per-connection server daemon (10.200.16.10:45696). May 15 12:23:06.486666 sshd[2436]: Accepted publickey for core from 10.200.16.10 port 45696 ssh2: RSA SHA256:alfUx42qKxt4UmMKlpWteu3ZEwnfJhYGidKCvoydx6g May 15 12:23:06.487987 sshd-session[2436]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 12:23:06.492403 systemd-logind[1899]: New session 5 of user core. May 15 12:23:06.506582 systemd[1]: Started session-5.scope - Session 5 of User core. May 15 12:23:06.939968 sshd[2438]: Connection closed by 10.200.16.10 port 45696 May 15 12:23:06.940314 sshd-session[2436]: pam_unix(sshd:session): session closed for user core May 15 12:23:06.943595 systemd[1]: sshd@2-10.200.8.39:22-10.200.16.10:45696.service: Deactivated successfully. May 15 12:23:06.945214 systemd[1]: session-5.scope: Deactivated successfully. May 15 12:23:06.945978 systemd-logind[1899]: Session 5 logged out. Waiting for processes to exit. May 15 12:23:06.947011 systemd-logind[1899]: Removed session 5. May 15 12:23:17.057632 systemd[1]: Started sshd@3-10.200.8.39:22-10.200.16.10:35740.service - OpenSSH per-connection server daemon (10.200.16.10:35740). May 15 12:23:17.698987 sshd[2445]: Accepted publickey for core from 10.200.16.10 port 35740 ssh2: RSA SHA256:alfUx42qKxt4UmMKlpWteu3ZEwnfJhYGidKCvoydx6g May 15 12:23:17.704269 sshd-session[2445]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 12:23:17.713809 systemd-logind[1899]: New session 6 of user core. May 15 12:23:17.732590 systemd[1]: Started session-6.scope - Session 6 of User core. May 15 12:23:18.151544 sshd[2447]: Connection closed by 10.200.16.10 port 35740 May 15 12:23:18.151900 sshd-session[2445]: pam_unix(sshd:session): session closed for user core May 15 12:23:18.155389 systemd[1]: sshd@3-10.200.8.39:22-10.200.16.10:35740.service: Deactivated successfully. May 15 12:23:18.157381 systemd[1]: session-6.scope: Deactivated successfully. May 15 12:23:18.158304 systemd-logind[1899]: Session 6 logged out. Waiting for processes to exit. May 15 12:23:18.159264 systemd-logind[1899]: Removed session 6. May 15 12:23:18.263957 systemd[1]: Started sshd@4-10.200.8.39:22-10.200.16.10:35752.service - OpenSSH per-connection server daemon (10.200.16.10:35752). May 15 12:23:18.904441 sshd[2453]: Accepted publickey for core from 10.200.16.10 port 35752 ssh2: RSA SHA256:alfUx42qKxt4UmMKlpWteu3ZEwnfJhYGidKCvoydx6g May 15 12:23:18.905731 sshd-session[2453]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 12:23:18.912795 systemd-logind[1899]: New session 7 of user core. May 15 12:23:18.927585 systemd[1]: Started session-7.scope - Session 7 of User core. May 15 12:23:19.356201 sshd[2455]: Connection closed by 10.200.16.10 port 35752 May 15 12:23:19.356507 sshd-session[2453]: pam_unix(sshd:session): session closed for user core May 15 12:23:19.359854 systemd[1]: sshd@4-10.200.8.39:22-10.200.16.10:35752.service: Deactivated successfully. May 15 12:23:19.361383 systemd[1]: session-7.scope: Deactivated successfully. May 15 12:23:19.362059 systemd-logind[1899]: Session 7 logged out. Waiting for processes to exit. May 15 12:23:19.362980 systemd-logind[1899]: Removed session 7. May 15 12:23:29.289558 setup-nvidia[2429]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 543M 0 1743k 0 0 1250k 0 0:07:25 0:00:01 0:07:24 1250k\u000d 4 543M 4 22.5M 0 0 9725k 0 0:00:57 0:00:02 0:00:55 9725k\u000d 8 543M 8 47.0M 0 0 13.9M 0 0:00:38 0:00:03 0:00:35 13.9M\u000d 13 543M 13 71.1M 0 0 16.3M 0 0:00:33 0:00:04 0:00:29 16.3M\u000d 17 543M 17 94.0M 0 0 17.5M 0 0:00:30 0:00:05 0:00:25 19.1M\u000d 21 543M 21 118M 0 0 18.3M 0 0:00:29 0:00:06 0:00:23 23.0M\u000d 26 543M 26 142M 0 0 19.1M 0 0:00:28 0:00:07 0:00:21 23.6M\u000d 30 543M 30 166M 0 0 19.6M 0 0:00:27 0:00:08 0:00:19 23.4M\u000d 34 543M 34 189M 0 0 20.0M 0 0:00:27 0:00:09 0:00:18 23.2M\u000d 39 543M 39 213M 0 0 20.4M 0 0:00:26 0:00:10 0:00:16 23.4M\u000d 43 543M 43 238M 0 0 20.8M 0 0:00:26 0:00:11 0:00:15 24.0M\u000d 47 543M 47 260M 0 0 20.9M 0 0:00:25 0:00:12 0:00:13 23.6M\u000d 51 543M 51 281M 0 0 21.0M 0 0:00:25 0:00:13 0:00:12 23.3M\u000d 55 543M 55 303M 0 0 21.0M 0 0:00:25 0:00:14 0:00:11 23.0M\u000d 60 543M 60 326M 0 0 21.2M 0 0:00:25 0:00:15 0:00:10 22.8M\u000d 64 543M 64 349M 0 0 21.3M 0 0:00:25 0:00:16 0:00:09 22.4M\u000d 68 543M 68 371M 0 0 21.4M 0 0:00:25 0:00:17 0:00:08 22.6M\u000d 72 543M 72 395M 0 0 21.5M 0 0:00:25 0:00:18 0:00:07 23.0M\u000d 77 543M 77 419M 0 0 21.6M 0 0:00:25 0:00:19 0:00:06 23.3M\u000d 81 543M 81 444M 0 0 21.8M 0 0:00:24 0:00:20 0:00:04 23.7M\u000d 86 543M 86 470M 0 0 21.8M 0 0:00:24 0:00:21 0:00:03 23.6M\u000d 90 543M 90 493M 0 0 21.9M 0 0:00:24 0:00:22 0:00:02 23.8M\u000d 95 543M 95 517M 0 0 22.0M 0 0:00:24 0:00:23 0:00:01 23.8M\u000d 99 543M 99 538M 0 0 22.0M 0 0:00:24 0:00:24 --:--:-- 23.4M\u000d100 543M 100 543M 0 0 22.1M 0 0:00:24 0:00:24 --:--:-- 23.4M May 15 12:23:29.476702 systemd[1]: Started sshd@5-10.200.8.39:22-10.200.16.10:34118.service - OpenSSH per-connection server daemon (10.200.16.10:34118). May 15 12:23:30.133080 sshd[2471]: Accepted publickey for core from 10.200.16.10 port 34118 ssh2: RSA SHA256:alfUx42qKxt4UmMKlpWteu3ZEwnfJhYGidKCvoydx6g May 15 12:23:30.134447 sshd-session[2471]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 12:23:30.145835 systemd-logind[1899]: New session 8 of user core. May 15 12:23:30.153578 systemd[1]: Started session-8.scope - Session 8 of User core. May 15 12:23:30.598795 sshd[2473]: Connection closed by 10.200.16.10 port 34118 May 15 12:23:30.594998 sshd-session[2471]: pam_unix(sshd:session): session closed for user core May 15 12:23:30.604632 systemd[1]: sshd@5-10.200.8.39:22-10.200.16.10:34118.service: Deactivated successfully. May 15 12:23:30.607126 systemd[1]: session-8.scope: Deactivated successfully. May 15 12:23:30.610332 systemd-logind[1899]: Session 8 logged out. Waiting for processes to exit. May 15 12:23:30.611585 systemd-logind[1899]: Removed session 8. May 15 12:23:30.710255 systemd[1]: Started sshd@6-10.200.8.39:22-10.200.16.10:34132.service - OpenSSH per-connection server daemon (10.200.16.10:34132). May 15 12:23:31.368606 sshd[2479]: Accepted publickey for core from 10.200.16.10 port 34132 ssh2: RSA SHA256:alfUx42qKxt4UmMKlpWteu3ZEwnfJhYGidKCvoydx6g May 15 12:23:31.370203 sshd-session[2479]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 12:23:31.375935 systemd-logind[1899]: New session 9 of user core. May 15 12:23:31.381482 systemd[1]: Started session-9.scope - Session 9 of User core. May 15 12:23:31.824470 sshd[2481]: Connection closed by 10.200.16.10 port 34132 May 15 12:23:31.827629 sshd-session[2479]: pam_unix(sshd:session): session closed for user core May 15 12:23:31.833700 systemd[1]: sshd@6-10.200.8.39:22-10.200.16.10:34132.service: Deactivated successfully. May 15 12:23:31.837759 systemd[1]: session-9.scope: Deactivated successfully. May 15 12:23:31.840612 systemd-logind[1899]: Session 9 logged out. Waiting for processes to exit. May 15 12:23:31.842217 systemd-logind[1899]: Removed session 9. May 15 12:23:40.601984 setup-nvidia[1894]: Downloading NVIDIA 535.230.02 Driver May 15 12:23:40.606251 setup-nvidia[2487]: % Total % Received % Xferd Average Speed Time Time Time Current May 15 12:23:40.606498 setup-nvidia[2487]: Dload Upload Total Spent Left Speed May 15 12:23:41.938876 systemd[1]: Started sshd@7-10.200.8.39:22-10.200.16.10:39006.service - OpenSSH per-connection server daemon (10.200.16.10:39006). May 15 12:23:42.593861 sshd[2489]: Accepted publickey for core from 10.200.16.10 port 39006 ssh2: RSA SHA256:alfUx42qKxt4UmMKlpWteu3ZEwnfJhYGidKCvoydx6g May 15 12:23:42.595127 sshd-session[2489]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 12:23:42.599544 systemd-logind[1899]: New session 10 of user core. May 15 12:23:42.607607 systemd[1]: Started session-10.scope - Session 10 of User core. May 15 12:23:42.855375 setup-nvidia[2487]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 1 326M 1 6144k 0 0 13.2M 0 0:00:24 --:--:-- 0:00:24 13.2M\u000d 53 326M 53 174M 0 0 120M 0 0:00:02 0:00:01 0:00:01 120M\u000d100 326M 100 326M 0 0 144M 0 0:00:02 0:00:02 --:--:-- 144M May 15 12:23:42.902818 setup-nvidia[1894]: Extract the NVIDIA Driver Installer 535.230.02 May 15 12:23:42.902993 setup-nvidia[1894]: /opt/nvidia/workdir/nvidia-workdir / May 15 12:23:42.920474 setup-nvidia[2494]: Creating directory NVIDIA-Linux-x86_64-535.230.02 May 15 12:23:43.103626 sshd[2491]: Connection closed by 10.200.16.10 port 39006 May 15 12:23:43.103981 sshd-session[2489]: pam_unix(sshd:session): session closed for user core May 15 12:23:43.107335 systemd[1]: sshd@7-10.200.8.39:22-10.200.16.10:39006.service: Deactivated successfully. May 15 12:23:43.108856 systemd[1]: session-10.scope: Deactivated successfully. May 15 12:23:43.109511 systemd-logind[1899]: Session 10 logged out. Waiting for processes to exit. May 15 12:23:43.110428 systemd-logind[1899]: Removed session 10. May 15 12:23:43.187020 setup-nvidia[2494]: Verifying archive integrity... OK May 15 12:23:43.220662 systemd[1]: Started sshd@8-10.200.8.39:22-10.200.16.10:39012.service - OpenSSH per-connection server daemon (10.200.16.10:39012). May 15 12:23:43.861097 sshd[2522]: Accepted publickey for core from 10.200.16.10 port 39012 ssh2: RSA SHA256:alfUx42qKxt4UmMKlpWteu3ZEwnfJhYGidKCvoydx6g May 15 12:23:43.862418 sshd-session[2522]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 12:23:43.866803 systemd-logind[1899]: New session 11 of user core. May 15 12:23:43.879620 systemd[1]: Started session-11.scope - Session 11 of User core. May 15 12:23:43.914215 setup-nvidia[2494]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.230.02 May 15 12:23:44.312869 sshd[2531]: Connection closed by 10.200.16.10 port 39012 May 15 12:23:44.314504 sshd-session[2522]: pam_unix(sshd:session): session closed for user core May 15 12:23:44.317783 systemd-logind[1899]: Session 11 logged out. Waiting for processes to exit. May 15 12:23:44.318071 systemd[1]: sshd@8-10.200.8.39:22-10.200.16.10:39012.service: Deactivated successfully. May 15 12:23:44.319598 systemd[1]: session-11.scope: Deactivated successfully. May 15 12:23:44.321148 systemd-logind[1899]: Removed session 11. May 15 12:23:45.700789 setup-nvidia[2527]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ May 15 12:23:45.778796 setup-nvidia[1894]: / May 15 12:23:45.778796 setup-nvidia[1894]: Spawn system-nspawn container to install the NVIDIA drivers May 15 12:23:45.983278 sudo[2539]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4334.0.0+afterburn-5.8.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 15 12:23:45.985053 sudo[2539]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 15 12:23:45.990772 systemd[1]: Created slice user-0.slice - User Slice of UID 0. May 15 12:23:45.995078 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... May 15 12:23:46.036524 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. May 15 12:23:46.039325 systemd[1]: Starting user@0.service - User Manager for UID 0... May 15 12:23:46.045472 (systemd)[2542]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) May 15 12:23:46.047778 systemd-logind[1899]: New session c3 of user root. May 15 12:23:46.232919 systemd[2542]: Queued start job for default target default.target. May 15 12:23:46.252614 systemd[2542]: Created slice app.slice - User Application Slice. May 15 12:23:46.252644 systemd[2542]: Reached target paths.target - Paths. May 15 12:23:46.252693 systemd[2542]: Reached target timers.target - Timers. May 15 12:23:46.253948 systemd[2542]: Starting dbus.socket - D-Bus User Message Bus Socket... May 15 12:23:46.264257 systemd[2542]: Listening on dbus.socket - D-Bus User Message Bus Socket. May 15 12:23:46.264315 systemd[2542]: Reached target sockets.target - Sockets. May 15 12:23:46.264361 systemd[2542]: Reached target basic.target - Basic System. May 15 12:23:46.264401 systemd[2542]: Reached target default.target - Main User Target. May 15 12:23:46.264431 systemd[2542]: Startup finished in 209ms. May 15 12:23:46.264550 systemd[1]: Started user@0.service - User Manager for UID 0. May 15 12:23:46.266057 systemd[1]: Started session-c2.scope - Session c2 of User root. May 15 12:23:46.334490 kernel: loop6: detected capacity change from 0 to 12644352 May 15 12:23:46.341470 kernel: loop6: p9 May 15 12:23:46.408987 setup-nvidia[2551]: ░ Spawning container flatcardevelopercontainer-4334.0.0afterburn-5.8.0.bin on /opt/nvidia/workdir/flatcar_developer_container-4334.0.0+afterburn-5.8.0.bin. May 15 12:23:47.180487 kernel: EXT4-fs (loop6p9): write access unavailable, skipping orphan cleanup May 15 12:23:47.180610 kernel: EXT4-fs (loop6p9): mounted filesystem 112d916d-9081-4917-9584-1b5d8bdc1899 ro without journal. Quota mode: none. May 15 12:23:54.429804 systemd[1]: Started sshd@9-10.200.8.39:22-10.200.16.10:47846.service - OpenSSH per-connection server daemon (10.200.16.10:47846). May 15 12:23:55.082537 sshd[3271]: Accepted publickey for core from 10.200.16.10 port 47846 ssh2: RSA SHA256:alfUx42qKxt4UmMKlpWteu3ZEwnfJhYGidKCvoydx6g May 15 12:23:55.083940 sshd-session[3271]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 12:23:55.088177 systemd-logind[1899]: New session 12 of user core. May 15 12:23:55.101579 systemd[1]: Started session-12.scope - Session 12 of User core. May 15 12:23:55.538348 sshd[3273]: Connection closed by 10.200.16.10 port 47846 May 15 12:23:55.538708 sshd-session[3271]: pam_unix(sshd:session): session closed for user core May 15 12:23:55.542151 systemd[1]: sshd@9-10.200.8.39:22-10.200.16.10:47846.service: Deactivated successfully. May 15 12:23:55.544261 systemd[1]: session-12.scope: Deactivated successfully. May 15 12:23:55.545766 systemd-logind[1899]: Session 12 logged out. Waiting for processes to exit. May 15 12:23:55.547725 systemd-logind[1899]: Removed session 12. May 15 12:23:55.662639 systemd[1]: Started sshd@10-10.200.8.39:22-10.200.16.10:47850.service - OpenSSH per-connection server daemon (10.200.16.10:47850). May 15 12:23:56.304107 sshd[3279]: Accepted publickey for core from 10.200.16.10 port 47850 ssh2: RSA SHA256:alfUx42qKxt4UmMKlpWteu3ZEwnfJhYGidKCvoydx6g May 15 12:23:56.305426 sshd-session[3279]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 12:23:56.309926 systemd-logind[1899]: New session 13 of user core. May 15 12:23:56.325618 systemd[1]: Started session-13.scope - Session 13 of User core. May 15 12:23:56.755388 sshd[3325]: Connection closed by 10.200.16.10 port 47850 May 15 12:23:56.755764 sshd-session[3279]: pam_unix(sshd:session): session closed for user core May 15 12:23:56.759020 systemd[1]: sshd@10-10.200.8.39:22-10.200.16.10:47850.service: Deactivated successfully. May 15 12:23:56.760552 systemd[1]: session-13.scope: Deactivated successfully. May 15 12:23:56.761206 systemd-logind[1899]: Session 13 logged out. Waiting for processes to exit. May 15 12:23:56.762120 systemd-logind[1899]: Removed session 13. May 15 12:24:06.872672 systemd[1]: Started sshd@11-10.200.8.39:22-10.200.16.10:42274.service - OpenSSH per-connection server daemon (10.200.16.10:42274). May 15 12:24:07.722910 sshd[5764]: Accepted publickey for core from 10.200.16.10 port 42274 ssh2: RSA SHA256:alfUx42qKxt4UmMKlpWteu3ZEwnfJhYGidKCvoydx6g May 15 12:24:07.724204 sshd-session[5764]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 12:24:07.728948 systemd-logind[1899]: New session 14 of user core. May 15 12:24:07.739646 systemd[1]: Started session-14.scope - Session 14 of User core. May 15 12:24:08.178435 sshd[6027]: Connection closed by 10.200.16.10 port 42274 May 15 12:24:08.179073 sshd-session[5764]: pam_unix(sshd:session): session closed for user core May 15 12:24:08.184127 systemd[1]: sshd@11-10.200.8.39:22-10.200.16.10:42274.service: Deactivated successfully. May 15 12:24:08.186682 systemd[1]: session-14.scope: Deactivated successfully. May 15 12:24:08.190676 systemd-logind[1899]: Session 14 logged out. Waiting for processes to exit. May 15 12:24:08.192274 systemd-logind[1899]: Removed session 14. May 15 12:24:08.292852 systemd[1]: Started sshd@12-10.200.8.39:22-10.200.16.10:42280.service - OpenSSH per-connection server daemon (10.200.16.10:42280). May 15 12:24:08.930622 sshd[6138]: Accepted publickey for core from 10.200.16.10 port 42280 ssh2: RSA SHA256:alfUx42qKxt4UmMKlpWteu3ZEwnfJhYGidKCvoydx6g May 15 12:24:08.932021 sshd-session[6138]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 12:24:08.936416 systemd-logind[1899]: New session 15 of user core. May 15 12:24:08.953592 systemd[1]: Started session-15.scope - Session 15 of User core. May 15 12:24:09.388421 sshd[6170]: Connection closed by 10.200.16.10 port 42280 May 15 12:24:09.388313 sshd-session[6138]: pam_unix(sshd:session): session closed for user core May 15 12:24:09.393008 systemd-logind[1899]: Session 15 logged out. Waiting for processes to exit. May 15 12:24:09.394739 systemd[1]: sshd@12-10.200.8.39:22-10.200.16.10:42280.service: Deactivated successfully. May 15 12:24:09.397313 systemd[1]: session-15.scope: Deactivated successfully. May 15 12:24:09.400598 systemd-logind[1899]: Removed session 15. May 15 12:24:19.505835 systemd[1]: Started sshd@13-10.200.8.39:22-10.200.16.10:36572.service - OpenSSH per-connection server daemon (10.200.16.10:36572). May 15 12:24:20.158776 sshd-session[7727]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 12:24:20.163103 systemd-logind[1899]: New session 16 of user core. May 15 12:24:24.666573 sshd[7727]: Accepted publickey for core from 10.200.16.10 port 36572 ssh2: RSA SHA256:alfUx42qKxt4UmMKlpWteu3ZEwnfJhYGidKCvoydx6g May 15 12:24:20.611998 sshd-session[7727]: pam_unix(sshd:session): session closed for user core May 15 12:24:20.184584 systemd[1]: Started session-16.scope - Session 16 of User core. May 15 12:24:24.667052 sshd[7729]: Connection closed by 10.200.16.10 port 36572 May 15 12:24:24.667134 sshd[7734]: Accepted publickey for core from 10.200.16.10 port 36586 ssh2: RSA SHA256:alfUx42qKxt4UmMKlpWteu3ZEwnfJhYGidKCvoydx6g May 15 12:24:21.371955 sshd-session[7734]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 12:24:20.614777 systemd[1]: sshd@13-10.200.8.39:22-10.200.16.10:36572.service: Deactivated successfully. May 15 12:24:24.667443 sshd[7736]: Connection closed by 10.200.16.10 port 36586 May 15 12:24:21.822799 sshd-session[7734]: pam_unix(sshd:session): session closed for user core May 15 12:24:20.616415 systemd[1]: session-16.scope: Deactivated successfully. May 15 12:24:20.617072 systemd-logind[1899]: Session 16 logged out. Waiting for processes to exit. May 15 12:24:20.618004 systemd-logind[1899]: Removed session 16. May 15 12:24:20.727556 systemd[1]: Started sshd@14-10.200.8.39:22-10.200.16.10:36586.service - OpenSSH per-connection server daemon (10.200.16.10:36586). May 15 12:24:21.376228 systemd-logind[1899]: New session 17 of user core. May 15 12:24:21.384580 systemd[1]: Started session-17.scope - Session 17 of User core. May 15 12:24:21.825394 systemd[1]: sshd@14-10.200.8.39:22-10.200.16.10:36586.service: Deactivated successfully. May 15 12:24:21.827026 systemd[1]: session-17.scope: Deactivated successfully. May 15 12:24:21.827733 systemd-logind[1899]: Session 17 logged out. Waiting for processes to exit. May 15 12:24:21.828676 systemd-logind[1899]: Removed session 17. May 15 12:24:31.940918 systemd[1]: Started sshd@15-10.200.8.39:22-10.200.16.10:41078.service - OpenSSH per-connection server daemon (10.200.16.10:41078). May 15 12:24:32.584743 sshd[8033]: Accepted publickey for core from 10.200.16.10 port 41078 ssh2: RSA SHA256:alfUx42qKxt4UmMKlpWteu3ZEwnfJhYGidKCvoydx6g May 15 12:24:32.586085 sshd-session[8033]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 12:24:32.590381 systemd-logind[1899]: New session 18 of user core. May 15 12:24:32.598581 systemd[1]: Started session-18.scope - Session 18 of User core. May 15 12:24:33.037010 sshd[8241]: Connection closed by 10.200.16.10 port 41078 May 15 12:24:33.037390 sshd-session[8033]: pam_unix(sshd:session): session closed for user core May 15 12:24:33.040960 systemd[1]: sshd@15-10.200.8.39:22-10.200.16.10:41078.service: Deactivated successfully. May 15 12:24:33.042553 systemd[1]: session-18.scope: Deactivated successfully. May 15 12:24:33.043269 systemd-logind[1899]: Session 18 logged out. Waiting for processes to exit. May 15 12:24:33.044195 systemd-logind[1899]: Removed session 18. May 15 12:24:33.149769 systemd[1]: Started sshd@16-10.200.8.39:22-10.200.16.10:41080.service - OpenSSH per-connection server daemon (10.200.16.10:41080). May 15 12:24:33.789695 sshd[8247]: Accepted publickey for core from 10.200.16.10 port 41080 ssh2: RSA SHA256:alfUx42qKxt4UmMKlpWteu3ZEwnfJhYGidKCvoydx6g May 15 12:24:33.791000 sshd-session[8247]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 12:24:33.795418 systemd-logind[1899]: New session 19 of user core. May 15 12:24:33.801583 systemd[1]: Started session-19.scope - Session 19 of User core. May 15 12:24:34.248698 sshd[8549]: Connection closed by 10.200.16.10 port 41080 May 15 12:24:34.249032 sshd-session[8247]: pam_unix(sshd:session): session closed for user core May 15 12:24:34.252327 systemd[1]: sshd@16-10.200.8.39:22-10.200.16.10:41080.service: Deactivated successfully. May 15 12:24:34.253849 systemd[1]: session-19.scope: Deactivated successfully. May 15 12:24:34.254551 systemd-logind[1899]: Session 19 logged out. Waiting for processes to exit. May 15 12:24:34.255602 systemd-logind[1899]: Removed session 19. May 15 12:24:44.373710 systemd[1]: Started sshd@17-10.200.8.39:22-10.200.16.10:52830.service - OpenSSH per-connection server daemon (10.200.16.10:52830). May 15 12:24:45.042958 sshd[11421]: Accepted publickey for core from 10.200.16.10 port 52830 ssh2: RSA SHA256:alfUx42qKxt4UmMKlpWteu3ZEwnfJhYGidKCvoydx6g May 15 12:24:45.043577 sshd-session[11421]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 12:24:45.053163 systemd-logind[1899]: New session 20 of user core. May 15 12:24:45.066027 systemd[1]: Started session-20.scope - Session 20 of User core. May 15 12:24:45.496631 sshd[11446]: Connection closed by 10.200.16.10 port 52830 May 15 12:24:45.496981 sshd-session[11421]: pam_unix(sshd:session): session closed for user core May 15 12:24:45.500764 systemd[1]: sshd@17-10.200.8.39:22-10.200.16.10:52830.service: Deactivated successfully. May 15 12:24:45.503473 systemd[1]: session-20.scope: Deactivated successfully. May 15 12:24:45.504365 systemd-logind[1899]: Session 20 logged out. Waiting for processes to exit. May 15 12:24:45.506974 systemd-logind[1899]: Removed session 20. May 15 12:24:45.608197 systemd[1]: Started sshd@18-10.200.8.39:22-10.200.16.10:52844.service - OpenSSH per-connection server daemon (10.200.16.10:52844). May 15 12:24:46.273810 sshd[11482]: Accepted publickey for core from 10.200.16.10 port 52844 ssh2: RSA SHA256:alfUx42qKxt4UmMKlpWteu3ZEwnfJhYGidKCvoydx6g May 15 12:24:46.274951 sshd-session[11482]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 12:24:46.281524 systemd-logind[1899]: New session 21 of user core. May 15 12:24:46.290633 systemd[1]: Started session-21.scope - Session 21 of User core. May 15 12:24:46.731053 sshd[11514]: Connection closed by 10.200.16.10 port 52844 May 15 12:24:46.733630 sshd-session[11482]: pam_unix(sshd:session): session closed for user core May 15 12:24:46.737093 systemd[1]: sshd@18-10.200.8.39:22-10.200.16.10:52844.service: Deactivated successfully. May 15 12:24:46.738918 systemd[1]: session-21.scope: Deactivated successfully. May 15 12:24:46.740241 systemd-logind[1899]: Session 21 logged out. Waiting for processes to exit. May 15 12:24:46.741811 systemd-logind[1899]: Removed session 21. May 15 12:24:56.848818 systemd[1]: Started sshd@19-10.200.8.39:22-10.200.16.10:39708.service - OpenSSH per-connection server daemon (10.200.16.10:39708). May 15 12:24:57.516083 sshd[11911]: Accepted publickey for core from 10.200.16.10 port 39708 ssh2: RSA SHA256:alfUx42qKxt4UmMKlpWteu3ZEwnfJhYGidKCvoydx6g May 15 12:24:57.516931 sshd-session[11911]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 12:24:57.523045 systemd-logind[1899]: New session 22 of user core. May 15 12:24:57.530546 systemd[1]: Started session-22.scope - Session 22 of User core. May 15 12:24:57.971404 sshd[11925]: Connection closed by 10.200.16.10 port 39708 May 15 12:24:57.973540 sshd-session[11911]: pam_unix(sshd:session): session closed for user core May 15 12:24:57.977595 systemd[1]: sshd@19-10.200.8.39:22-10.200.16.10:39708.service: Deactivated successfully. May 15 12:24:57.980629 systemd[1]: session-22.scope: Deactivated successfully. May 15 12:24:57.981937 systemd-logind[1899]: Session 22 logged out. Waiting for processes to exit. May 15 12:24:57.983524 systemd-logind[1899]: Removed session 22. May 15 12:24:58.094691 systemd[1]: Started sshd@20-10.200.8.39:22-10.200.16.10:39716.service - OpenSSH per-connection server daemon (10.200.16.10:39716). May 15 12:24:58.767406 sshd[11954]: Accepted publickey for core from 10.200.16.10 port 39716 ssh2: RSA SHA256:alfUx42qKxt4UmMKlpWteu3ZEwnfJhYGidKCvoydx6g May 15 12:24:58.767966 sshd-session[11954]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 12:24:58.774284 systemd-logind[1899]: New session 23 of user core. May 15 12:24:58.783558 systemd[1]: Started session-23.scope - Session 23 of User core. May 15 12:24:59.228505 sshd[11982]: Connection closed by 10.200.16.10 port 39716 May 15 12:24:59.230638 sshd-session[11954]: pam_unix(sshd:session): session closed for user core May 15 12:24:59.233905 systemd-logind[1899]: Session 23 logged out. Waiting for processes to exit. May 15 12:24:59.235349 systemd[1]: sshd@20-10.200.8.39:22-10.200.16.10:39716.service: Deactivated successfully. May 15 12:24:59.236954 systemd[1]: session-23.scope: Deactivated successfully. May 15 12:24:59.239629 systemd-logind[1899]: Removed session 23. May 15 12:25:09.346158 systemd[1]: Started sshd@21-10.200.8.39:22-10.200.16.10:43196.service - OpenSSH per-connection server daemon (10.200.16.10:43196). May 15 12:25:10.002836 sshd[12312]: Accepted publickey for core from 10.200.16.10 port 43196 ssh2: RSA SHA256:alfUx42qKxt4UmMKlpWteu3ZEwnfJhYGidKCvoydx6g May 15 12:25:10.004327 sshd-session[12312]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 12:25:10.016400 systemd-logind[1899]: New session 24 of user core. May 15 12:25:10.024933 systemd[1]: Started session-24.scope - Session 24 of User core. May 15 12:25:10.458081 sshd[12342]: Connection closed by 10.200.16.10 port 43196 May 15 12:25:10.459981 sshd-session[12312]: pam_unix(sshd:session): session closed for user core May 15 12:25:10.463164 systemd-logind[1899]: Session 24 logged out. Waiting for processes to exit. May 15 12:25:10.463835 systemd[1]: sshd@21-10.200.8.39:22-10.200.16.10:43196.service: Deactivated successfully. May 15 12:25:10.466505 systemd[1]: session-24.scope: Deactivated successfully. May 15 12:25:10.471351 systemd-logind[1899]: Removed session 24. May 15 12:25:10.572964 systemd[1]: Started sshd@22-10.200.8.39:22-10.200.16.10:43206.service - OpenSSH per-connection server daemon (10.200.16.10:43206). May 15 12:25:11.228171 sshd[12355]: Accepted publickey for core from 10.200.16.10 port 43206 ssh2: RSA SHA256:alfUx42qKxt4UmMKlpWteu3ZEwnfJhYGidKCvoydx6g May 15 12:25:11.229636 sshd-session[12355]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 12:25:11.239662 systemd-logind[1899]: New session 25 of user core. May 15 12:25:11.244030 systemd[1]: Started session-25.scope - Session 25 of User core. May 15 12:25:11.682984 sshd[12375]: Connection closed by 10.200.16.10 port 43206 May 15 12:25:11.683348 sshd-session[12355]: pam_unix(sshd:session): session closed for user core May 15 12:25:11.687492 systemd-logind[1899]: Session 25 logged out. Waiting for processes to exit. May 15 12:25:11.688865 systemd[1]: sshd@22-10.200.8.39:22-10.200.16.10:43206.service: Deactivated successfully. May 15 12:25:11.691518 systemd[1]: session-25.scope: Deactivated successfully. May 15 12:25:11.694778 systemd-logind[1899]: Removed session 25. May 15 12:25:21.800911 systemd[1]: Started sshd@23-10.200.8.39:22-10.200.16.10:48262.service - OpenSSH per-connection server daemon (10.200.16.10:48262). May 15 12:25:22.439658 sshd[12583]: Accepted publickey for core from 10.200.16.10 port 48262 ssh2: RSA SHA256:alfUx42qKxt4UmMKlpWteu3ZEwnfJhYGidKCvoydx6g May 15 12:25:22.440943 sshd-session[12583]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 12:25:22.445072 systemd-logind[1899]: New session 26 of user core. May 15 12:25:22.454572 systemd[1]: Started session-26.scope - Session 26 of User core. May 15 12:25:22.897657 sshd[12585]: Connection closed by 10.200.16.10 port 48262 May 15 12:25:22.898033 sshd-session[12583]: pam_unix(sshd:session): session closed for user core May 15 12:25:22.903164 systemd[1]: sshd@23-10.200.8.39:22-10.200.16.10:48262.service: Deactivated successfully. May 15 12:25:22.904954 systemd[1]: session-26.scope: Deactivated successfully. May 15 12:25:22.905727 systemd-logind[1899]: Session 26 logged out. Waiting for processes to exit. May 15 12:25:22.908944 systemd-logind[1899]: Removed session 26. May 15 12:25:23.015181 systemd[1]: Started sshd@24-10.200.8.39:22-10.200.16.10:48266.service - OpenSSH per-connection server daemon (10.200.16.10:48266). May 15 12:25:23.654660 setup-nvidia[2551]: \u000d May 15 12:25:23.654660 setup-nvidia[2551]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most May 15 12:25:23.654660 setup-nvidia[2551]: frequently when this kernel module was built against the wrong or May 15 12:25:23.654660 setup-nvidia[2551]: improperly configured kernel sources, with a version of gcc that differs May 15 12:25:23.654660 setup-nvidia[2551]: from the one used to build the target kernel, or if another driver, such May 15 12:25:23.654660 setup-nvidia[2551]: as nouveau, is present and prevents the NVIDIA kernel module from May 15 12:25:23.654660 setup-nvidia[2551]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device May 15 12:25:23.654660 setup-nvidia[2551]: installed in this system is supported by this NVIDIA Linux graphics May 15 12:25:23.654660 setup-nvidia[2551]: driver release. May 15 12:25:23.654660 setup-nvidia[2551]: \u000d May 15 12:25:23.654660 setup-nvidia[2551]: Please see the log entries 'Kernel module load error' and 'Kernel May 15 12:25:23.654660 setup-nvidia[2551]: messages' at the end of the file May 15 12:25:23.654660 setup-nvidia[2551]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more May 15 12:25:23.654660 setup-nvidia[2551]: information. May 15 12:25:23.654660 setup-nvidia[2551]: \u000d May 15 12:25:23.655829 sshd[12641]: Accepted publickey for core from 10.200.16.10 port 48266 ssh2: RSA SHA256:alfUx42qKxt4UmMKlpWteu3ZEwnfJhYGidKCvoydx6g May 15 12:25:23.657108 sshd-session[12641]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 12:25:23.661345 systemd-logind[1899]: New session 27 of user core. May 15 12:25:23.669765 setup-nvidia[2551]: dmesg: read kernel buffer failed: Operation not permitted May 15 12:25:23.670583 systemd[1]: Started session-27.scope - Session 27 of User core. May 15 12:25:23.691891 setup-nvidia[2551]: \u000d May 15 12:25:23.691891 setup-nvidia[2551]: ERROR: Installation has failed. Please see the file May 15 12:25:23.691891 setup-nvidia[2551]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for May 15 12:25:23.691891 setup-nvidia[2551]: details. You may find suggestions on fixing installation problems in May 15 12:25:23.691891 setup-nvidia[2551]: the README available on the Linux driver download page at May 15 12:25:23.691891 setup-nvidia[2551]: www.nvidia.com. May 15 12:25:23.691891 setup-nvidia[2551]: \u000d May 15 12:25:23.692417 setup-nvidia[2551]: Last 50 lines of nvidia-installer.log: May 15 12:25:23.693221 setup-nvidia[2551]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039000rm+0x53: 'naked' return found in MITIGATION_RETHUNK build May 15 12:25:23.693221 setup-nvidia[2551]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038997rm+0x90: 'naked' return found in MITIGATION_RETHUNK build May 15 12:25:23.693221 setup-nvidia[2551]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038990rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build May 15 12:25:23.693221 setup-nvidia[2551]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039046rm+0x93: 'naked' return found in MITIGATION_RETHUNK build May 15 12:25:23.693221 setup-nvidia[2551]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039042rm+0x36: 'naked' return found in MITIGATION_RETHUNK build May 15 12:25:23.693221 setup-nvidia[2551]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039041rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build May 15 12:25:23.693221 setup-nvidia[2551]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039043rm+0x77: 'naked' return found in MITIGATION_RETHUNK build May 15 12:25:23.693221 setup-nvidia[2551]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038987rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build May 15 12:25:23.693221 setup-nvidia[2551]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030558rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build May 15 12:25:23.693221 setup-nvidia[2551]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030557rm+0x27: 'naked' return found in MITIGATION_RETHUNK build May 15 12:25:23.693221 setup-nvidia[2551]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030561rm+0x37: 'naked' return found in MITIGATION_RETHUNK build May 15 12:25:23.693221 setup-nvidia[2551]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030560rm+0x24: 'naked' return found in MITIGATION_RETHUNK build May 15 12:25:23.693221 setup-nvidia[2551]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030559rm+0xca: 'naked' return found in MITIGATION_RETHUNK build May 15 12:25:23.693221 setup-nvidia[2551]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030562rm+0x193: 'naked' return found in MITIGATION_RETHUNK build May 15 12:25:23.693221 setup-nvidia[2551]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030564rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build May 15 12:25:23.693221 setup-nvidia[2551]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv011860rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build May 15 12:25:23.693221 setup-nvidia[2551]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039354rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build May 15 12:25:23.693221 setup-nvidia[2551]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/Module.symvers May 15 12:25:23.693221 setup-nvidia[2551]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.mod.o May 15 12:25:23.693221 setup-nvidia[2551]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/.module-common.o May 15 12:25:23.693221 setup-nvidia[2551]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.mod.o May 15 12:25:23.693221 setup-nvidia[2551]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.mod.o May 15 12:25:23.693221 setup-nvidia[2551]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.mod.o May 15 12:25:23.693221 setup-nvidia[2551]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.mod.o May 15 12:25:23.693221 setup-nvidia[2551]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko May 15 12:25:23.693221 setup-nvidia[2551]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko May 15 12:25:23.693221 setup-nvidia[2551]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko May 15 12:25:23.693221 setup-nvidia[2551]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko May 15 12:25:23.693221 setup-nvidia[2551]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko May 15 12:25:23.693221 setup-nvidia[2551]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko May 15 12:25:23.693221 setup-nvidia[2551]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko due to unavailability of vmlinux May 15 12:25:23.693221 setup-nvidia[2551]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko May 15 12:25:23.693221 setup-nvidia[2551]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko due to unavailability of vmlinux May 15 12:25:23.693221 setup-nvidia[2551]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko May 15 12:25:23.693221 setup-nvidia[2551]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko due to unavailability of vmlinux May 15 12:25:23.693221 setup-nvidia[2551]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko May 15 12:25:23.693221 setup-nvidia[2551]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko due to unavailability of vmlinux May 15 12:25:23.693221 setup-nvidia[2551]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko May 15 12:25:23.693221 setup-nvidia[2551]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko due to unavailability of vmlinux May 15 12:25:23.693221 setup-nvidia[2551]: make[2]: Leaving directory '/usr/lib/modules/6.12.20-flatcar/build' May 15 12:25:23.693221 setup-nvidia[2551]: make[1]: Leaving directory '/usr/lib/modules/6.12.20-flatcar/source' May 15 12:25:23.693221 setup-nvidia[2551]: -> done. May 15 12:25:23.693221 setup-nvidia[2551]: -> Kernel module compilation complete. May 15 12:25:23.693221 setup-nvidia[2551]: -> Unable to determine if Secure Boot is enabled: No such file or directory May 15 12:25:23.694300 setup-nvidia[2551]: 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 15 12:25:23.694300 setup-nvidia[2551]: \u000d May 15 12:25:23.694300 setup-nvidia[2551]: 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 15 12:25:23.694300 setup-nvidia[2551]: -> Kernel module load error: Operation not permitted May 15 12:25:23.694300 setup-nvidia[2551]: -> Kernel messages: May 15 12:25:23.694300 setup-nvidia[2551]: 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 15 12:25:24.109429 sshd[12724]: Connection closed by 10.200.16.10 port 48266 May 15 12:25:24.109714 sshd-session[12641]: pam_unix(sshd:session): session closed for user core May 15 12:25:24.113410 systemd[1]: sshd@24-10.200.8.39:22-10.200.16.10:48266.service: Deactivated successfully. May 15 12:25:24.115056 systemd[1]: session-27.scope: Deactivated successfully. May 15 12:25:24.115821 systemd-logind[1899]: Session 27 logged out. Waiting for processes to exit. May 15 12:25:24.116826 systemd-logind[1899]: Removed session 27. May 15 12:25:34.228146 systemd[1]: Started sshd@25-10.200.8.39:22-10.200.16.10:57952.service - OpenSSH per-connection server daemon (10.200.16.10:57952). May 15 12:25:34.869034 sshd[12747]: Accepted publickey for core from 10.200.16.10 port 57952 ssh2: RSA SHA256:alfUx42qKxt4UmMKlpWteu3ZEwnfJhYGidKCvoydx6g May 15 12:25:34.870331 sshd-session[12747]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 12:25:34.874955 systemd-logind[1899]: New session 28 of user core. May 15 12:25:34.893581 systemd[1]: Started session-28.scope - Session 28 of User core. May 15 12:25:35.321214 sshd[12751]: Connection closed by 10.200.16.10 port 57952 May 15 12:25:35.321601 sshd-session[12747]: pam_unix(sshd:session): session closed for user core May 15 12:25:35.325820 systemd[1]: sshd@25-10.200.8.39:22-10.200.16.10:57952.service: Deactivated successfully. May 15 12:25:35.327348 systemd[1]: session-28.scope: Deactivated successfully. May 15 12:25:35.328050 systemd-logind[1899]: Session 28 logged out. Waiting for processes to exit. May 15 12:25:35.328973 systemd-logind[1899]: Removed session 28. May 15 12:25:35.439047 systemd[1]: Started sshd@26-10.200.8.39:22-10.200.16.10:57968.service - OpenSSH per-connection server daemon (10.200.16.10:57968). May 15 12:25:36.079811 sshd[12758]: Accepted publickey for core from 10.200.16.10 port 57968 ssh2: RSA SHA256:alfUx42qKxt4UmMKlpWteu3ZEwnfJhYGidKCvoydx6g May 15 12:25:36.081793 sshd-session[12758]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 12:25:36.086893 systemd-logind[1899]: New session 29 of user core. May 15 12:25:36.102599 systemd[1]: Started session-29.scope - Session 29 of User core. May 15 12:25:36.532578 sshd[12761]: Connection closed by 10.200.16.10 port 57968 May 15 12:25:36.532945 sshd-session[12758]: pam_unix(sshd:session): session closed for user core May 15 12:25:36.536389 systemd[1]: sshd@26-10.200.8.39:22-10.200.16.10:57968.service: Deactivated successfully. May 15 12:25:36.538115 systemd[1]: session-29.scope: Deactivated successfully. May 15 12:25:36.538828 systemd-logind[1899]: Session 29 logged out. Waiting for processes to exit. May 15 12:25:36.539821 systemd-logind[1899]: Removed session 29. May 15 12:25:38.523480 kernel: EXT4-fs (loop6p9): unmounting filesystem 112d916d-9081-4917-9584-1b5d8bdc1899. May 15 12:25:38.523866 setup-nvidia[2551]: Container flatcardevelopercontainer-4334.0.0afterburn-5.8.0.bin exited successfully. May 15 12:25:38.526045 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4334.0.0afterburn\x2d5.8.0.bin.mount: Deactivated successfully. May 15 12:25:38.582240 sudo[2539]: pam_unix(sudo:session): session closed for user root May 15 12:25:38.584094 systemd[1]: session-c2.scope: Deactivated successfully. May 15 12:25:38.584379 systemd[1]: session-c2.scope: Consumed 5min 10.156s CPU time, 893.2M memory peak. May 15 12:25:38.998123 setup-nvidia[1894]: /opt/nvidia / May 15 12:25:38.999088 setup-nvidia[1894]: / May 15 12:25:39.013943 setup-nvidia[12783]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. May 15 12:25:39.038470 kernel: IPMI message handler: version 39.2 May 15 12:25:39.041469 kernel: ipmi device interface May 15 12:25:39.048422 setup-nvidia[1894]: /opt/nvidia/current/usr/lib/modules/6.12.20-flatcar/video / May 15 12:25:39.106125 kernel: nvidia: loading out-of-tree module taints kernel. May 15 12:25:39.106255 kernel: nvidia: module license 'NVIDIA' taints kernel. May 15 12:25:39.106277 kernel: Disabling lock debugging due to kernel taint May 15 12:25:39.113313 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel May 15 12:25:39.113367 kernel: nvidia: module license taints kernel. May 15 12:25:39.494481 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 May 15 12:25:39.897440 kernel: May 15 12:25:39.897556 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) May 15 12:25:40.101967 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.230.02 Fri Dec 20 21:42:05 UTC 2024 May 15 12:25:40.130480 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.230.02 Fri Dec 20 21:52:34 UTC 2024 May 15 12:25:40.152490 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. May 15 12:25:40.199481 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. May 15 12:25:40.199561 setup-nvidia[1894]: / May 15 12:25:42.997207 setup-nvidia[12815]: Thu May 15 12:25:42 2025 May 15 12:25:42.997207 setup-nvidia[12815]: +---------------------------------------------------------------------------------------+ May 15 12:25:42.997207 setup-nvidia[12815]: | NVIDIA-SMI 535.230.02 Driver Version: 535.230.02 CUDA Version: 12.2 | May 15 12:25:42.997207 setup-nvidia[12815]: |-----------------------------------------+----------------------+----------------------+ May 15 12:25:42.997207 setup-nvidia[12815]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | May 15 12:25:42.997207 setup-nvidia[12815]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | May 15 12:25:42.997207 setup-nvidia[12815]: | | | MIG M. | May 15 12:25:42.997207 setup-nvidia[12815]: |=========================================+======================+======================| May 15 12:25:43.000679 setup-nvidia[12815]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | May 15 12:25:43.000679 setup-nvidia[12815]: | N/A 27C P0 37W / 250W | 0MiB / 16384MiB | 0% Default | May 15 12:25:43.000679 setup-nvidia[12815]: | | | N/A | May 15 12:25:43.000679 setup-nvidia[12815]: +-----------------------------------------+----------------------+----------------------+ May 15 12:25:43.000782 setup-nvidia[12815]: May 15 12:25:43.000782 setup-nvidia[12815]: +---------------------------------------------------------------------------------------+ May 15 12:25:43.000782 setup-nvidia[12815]: | Processes: | May 15 12:25:43.000782 setup-nvidia[12815]: | GPU GI CI PID Type Process name GPU Memory | May 15 12:25:43.000782 setup-nvidia[12815]: | ID ID Usage | May 15 12:25:43.000782 setup-nvidia[12815]: |=======================================================================================| May 15 12:25:43.000918 setup-nvidia[12815]: | No running processes found | May 15 12:25:43.000918 setup-nvidia[12815]: +---------------------------------------------------------------------------------------+ May 15 12:25:43.381390 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. May 15 12:25:43.382139 systemd[1]: Reached target multi-user.target - Multi-User System. May 15 12:25:43.382953 systemd[1]: Startup finished in 860ms (firmware) + 29.891s (loader) + 3.794s (kernel) + 11.122s (initrd) + 3min 52.574s (userspace) = 4min 38.243s. May 15 12:25:46.646489 systemd[1]: Started sshd@27-10.200.8.39:22-10.200.16.10:48838.service - OpenSSH per-connection server daemon (10.200.16.10:48838). May 15 12:25:47.311342 sshd[12822]: Accepted publickey for core from 10.200.16.10 port 48838 ssh2: RSA SHA256:alfUx42qKxt4UmMKlpWteu3ZEwnfJhYGidKCvoydx6g May 15 12:25:47.312692 sshd-session[12822]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 12:25:47.317670 systemd-logind[1899]: New session 30 of user core. May 15 12:25:47.330692 systemd[1]: Started session-30.scope - Session 30 of User core. May 15 12:25:47.761541 sshd[12824]: Connection closed by 10.200.16.10 port 48838 May 15 12:25:47.761875 sshd-session[12822]: pam_unix(sshd:session): session closed for user core May 15 12:25:47.764571 systemd[1]: sshd@27-10.200.8.39:22-10.200.16.10:48838.service: Deactivated successfully. May 15 12:25:47.766126 systemd[1]: session-30.scope: Deactivated successfully. May 15 12:25:47.767307 systemd-logind[1899]: Session 30 logged out. Waiting for processes to exit. May 15 12:25:47.768287 systemd-logind[1899]: Removed session 30. May 15 12:25:47.888252 systemd[1]: Started sshd@28-10.200.8.39:22-10.200.16.10:48848.service - OpenSSH per-connection server daemon (10.200.16.10:48848). May 15 12:25:48.548777 sshd[12830]: Accepted publickey for core from 10.200.16.10 port 48848 ssh2: RSA SHA256:alfUx42qKxt4UmMKlpWteu3ZEwnfJhYGidKCvoydx6g May 15 12:25:48.550128 sshd-session[12830]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 12:25:48.554578 systemd-logind[1899]: New session 31 of user core. May 15 12:25:48.571577 systemd[1]: Started session-31.scope - Session 31 of User core. May 15 12:25:48.626103 systemd[1]: Stopping user@0.service - User Manager for UID 0... May 15 12:25:48.626854 systemd[2542]: Activating special unit exit.target... May 15 12:25:48.626927 systemd[2542]: Stopped target default.target - Main User Target. May 15 12:25:48.626966 systemd[2542]: Stopped target basic.target - Basic System. May 15 12:25:48.626993 systemd[2542]: Stopped target paths.target - Paths. May 15 12:25:48.627018 systemd[2542]: Stopped target sockets.target - Sockets. May 15 12:25:48.627042 systemd[2542]: Stopped target timers.target - Timers. May 15 12:25:48.627375 systemd[2542]: Closed dbus.socket - D-Bus User Message Bus Socket. May 15 12:25:48.627598 systemd[2542]: Removed slice app.slice - User Application Slice. May 15 12:25:48.627630 systemd[2542]: Reached target shutdown.target - Shutdown. May 15 12:25:48.627765 systemd[2542]: Finished systemd-exit.service - Exit the Session. May 15 12:25:48.627802 systemd[2542]: Reached target exit.target - Exit the Session. May 15 12:25:48.640506 (sd-pam)[2543]: pam_unix(systemd-user:session): session closed for user root May 15 12:25:48.641754 systemd[1]: user@0.service: Deactivated successfully. May 15 12:25:48.642085 systemd[1]: Stopped user@0.service - User Manager for UID 0. May 15 12:25:48.644259 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... May 15 12:25:48.665531 systemd[1]: run-user-0.mount: Deactivated successfully. May 15 12:25:48.666415 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. May 15 12:25:48.666623 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. May 15 12:25:48.668278 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. May 15 12:25:48.668384 systemd[1]: user-0.slice: Consumed 5min 10.390s CPU time, 902.4M memory peak. May 15 12:25:48.668806 systemd-logind[1899]: Removed session c3. May 15 12:25:48.998251 sshd[12832]: Connection closed by 10.200.16.10 port 48848 May 15 12:25:48.998611 sshd-session[12830]: pam_unix(sshd:session): session closed for user core May 15 12:25:49.002137 systemd[1]: sshd@28-10.200.8.39:22-10.200.16.10:48848.service: Deactivated successfully. May 15 12:25:49.003691 systemd[1]: session-31.scope: Deactivated successfully. May 15 12:25:49.004340 systemd-logind[1899]: Session 31 logged out. Waiting for processes to exit. May 15 12:25:49.005276 systemd-logind[1899]: Removed session 31. May 15 12:25:49.119818 systemd[1]: Started sshd@29-10.200.8.39:22-10.200.16.10:51728.service - OpenSSH per-connection server daemon (10.200.16.10:51728). May 15 12:25:49.773915 sshd[12842]: Accepted publickey for core from 10.200.16.10 port 51728 ssh2: RSA SHA256:alfUx42qKxt4UmMKlpWteu3ZEwnfJhYGidKCvoydx6g May 15 12:25:49.775290 sshd-session[12842]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 12:25:49.781540 systemd-logind[1899]: New session 32 of user core. May 15 12:25:49.797580 systemd[1]: Started session-32.scope - Session 32 of User core. May 15 12:25:50.226572 sshd[12844]: Connection closed by 10.200.16.10 port 51728 May 15 12:25:50.226942 sshd-session[12842]: pam_unix(sshd:session): session closed for user core May 15 12:25:50.233426 systemd[1]: sshd@29-10.200.8.39:22-10.200.16.10:51728.service: Deactivated successfully. May 15 12:25:50.234991 systemd[1]: session-32.scope: Deactivated successfully. May 15 12:25:50.235702 systemd-logind[1899]: Session 32 logged out. Waiting for processes to exit. May 15 12:25:50.236672 systemd-logind[1899]: Removed session 32. May 15 12:25:50.343847 systemd[1]: Started sshd@30-10.200.8.39:22-10.200.16.10:51744.service - OpenSSH per-connection server daemon (10.200.16.10:51744). May 15 12:25:50.996522 sshd[12850]: Accepted publickey for core from 10.200.16.10 port 51744 ssh2: RSA SHA256:alfUx42qKxt4UmMKlpWteu3ZEwnfJhYGidKCvoydx6g May 15 12:25:50.997847 sshd-session[12850]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 12:25:51.002005 systemd-logind[1899]: New session 33 of user core. May 15 12:25:51.017581 systemd[1]: Started session-33.scope - Session 33 of User core. May 15 12:25:51.451290 sshd[12852]: Connection closed by 10.200.16.10 port 51744 May 15 12:25:51.451658 sshd-session[12850]: pam_unix(sshd:session): session closed for user core May 15 12:25:51.455039 systemd[1]: sshd@30-10.200.8.39:22-10.200.16.10:51744.service: Deactivated successfully. May 15 12:25:51.456627 systemd[1]: session-33.scope: Deactivated successfully. May 15 12:25:51.457276 systemd-logind[1899]: Session 33 logged out. Waiting for processes to exit. May 15 12:25:51.458211 systemd-logind[1899]: Removed session 33. May 15 12:25:51.569818 systemd[1]: Started sshd@31-10.200.8.39:22-10.200.16.10:51750.service - OpenSSH per-connection server daemon (10.200.16.10:51750). May 15 12:25:52.230837 sshd[12858]: Accepted publickey for core from 10.200.16.10 port 51750 ssh2: RSA SHA256:alfUx42qKxt4UmMKlpWteu3ZEwnfJhYGidKCvoydx6g May 15 12:25:52.232190 sshd-session[12858]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 12:25:52.236460 systemd-logind[1899]: New session 34 of user core. May 15 12:25:52.253573 systemd[1]: Started session-34.scope - Session 34 of User core. May 15 12:25:54.226912 sshd[12860]: Connection closed by 10.200.16.10 port 51750 May 15 12:25:54.227296 sshd-session[12858]: pam_unix(sshd:session): session closed for user core May 15 12:25:54.230702 systemd[1]: sshd@31-10.200.8.39:22-10.200.16.10:51750.service: Deactivated successfully. May 15 12:25:54.232243 systemd[1]: session-34.scope: Deactivated successfully. May 15 12:25:54.232432 systemd[1]: session-34.scope: Consumed 1.481s CPU time, 14.1M memory peak. May 15 12:25:54.232926 systemd-logind[1899]: Session 34 logged out. Waiting for processes to exit. May 15 12:25:54.233882 systemd-logind[1899]: Removed session 34.