Oct 13 05:48:51.232557 kernel: Linux version 6.12.51-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.0 p8) 14.3.0, GNU ld (Gentoo 2.44 p4) 2.44.0) #1 SMP PREEMPT_DYNAMIC Sun Oct 12 22:37:12 -00 2025 Oct 13 05:48:51.232585 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=a48d469b0deb49c328e6faf6cf366b11952d47f2d24963c866a0ea8221fb0039 Oct 13 05:48:51.232596 kernel: BIOS-provided physical RAM map: Oct 13 05:48:51.232602 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Oct 13 05:48:51.232609 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Oct 13 05:48:51.232616 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Oct 13 05:48:51.232624 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Oct 13 05:48:51.232633 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Oct 13 05:48:51.232640 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Oct 13 05:48:51.232647 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Oct 13 05:48:51.232654 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Oct 13 05:48:51.232660 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Oct 13 05:48:51.232667 kernel: printk: legacy bootconsole [earlyser0] enabled Oct 13 05:48:51.232674 kernel: NX (Execute Disable) protection: active Oct 13 05:48:51.232685 kernel: APIC: Static calls initialized Oct 13 05:48:51.232693 kernel: efi: EFI v2.7 by Microsoft Oct 13 05:48:51.232701 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Oct 13 05:48:51.232708 kernel: random: crng init done Oct 13 05:48:51.232716 kernel: secureboot: Secure boot disabled Oct 13 05:48:51.232723 kernel: SMBIOS 3.1.0 present. Oct 13 05:48:51.232731 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Oct 13 05:48:51.232739 kernel: DMI: Memory slots populated: 3/3 Oct 13 05:48:51.232746 kernel: Hypervisor detected: Microsoft Hyper-V Oct 13 05:48:51.232753 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Oct 13 05:48:51.232763 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Oct 13 05:48:51.232771 kernel: Hyper-V: Using hypercall for remote TLB flush Oct 13 05:48:51.232778 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Oct 13 05:48:51.232786 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Oct 13 05:48:51.232794 kernel: tsc: Marking TSC unstable due to running on Hyper-V Oct 13 05:48:51.232802 kernel: tsc: Detected 2593.990 MHz processor Oct 13 05:48:51.232810 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Oct 13 05:48:51.232818 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Oct 13 05:48:51.232826 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Oct 13 05:48:51.232836 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Oct 13 05:48:51.232844 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Oct 13 05:48:51.232851 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Oct 13 05:48:51.232859 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Oct 13 05:48:51.232866 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Oct 13 05:48:51.232874 kernel: Using GB pages for direct mapping Oct 13 05:48:51.232882 kernel: ACPI: Early table checksum verification disabled Oct 13 05:48:51.232893 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Oct 13 05:48:51.232904 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 05:48:51.232912 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 05:48:51.232920 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Oct 13 05:48:51.232928 kernel: ACPI: FACS 0x000000003FFFE000 000040 Oct 13 05:48:51.232936 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 05:48:51.232945 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 05:48:51.232955 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 05:48:51.232963 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 05:48:51.232971 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 05:48:51.232979 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 05:48:51.232988 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 05:48:51.232996 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Oct 13 05:48:51.233004 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Oct 13 05:48:51.233012 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Oct 13 05:48:51.233020 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Oct 13 05:48:51.233030 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Oct 13 05:48:51.233038 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Oct 13 05:48:51.233046 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Oct 13 05:48:51.233054 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Oct 13 05:48:51.233062 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Oct 13 05:48:51.233071 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Oct 13 05:48:51.233079 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Oct 13 05:48:51.233087 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Oct 13 05:48:51.233097 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Oct 13 05:48:51.233105 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Oct 13 05:48:51.233113 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Oct 13 05:48:51.233122 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Oct 13 05:48:51.233130 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Oct 13 05:48:51.233138 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Oct 13 05:48:51.233146 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] Oct 13 05:48:51.233154 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] Oct 13 05:48:51.233162 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] Oct 13 05:48:51.233173 kernel: Zone ranges: Oct 13 05:48:51.233181 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Oct 13 05:48:51.233189 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Oct 13 05:48:51.233197 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Oct 13 05:48:51.233205 kernel: Device empty Oct 13 05:48:51.233213 kernel: Movable zone start for each node Oct 13 05:48:51.233221 kernel: Early memory node ranges Oct 13 05:48:51.233229 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Oct 13 05:48:51.233238 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Oct 13 05:48:51.233248 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Oct 13 05:48:51.233257 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Oct 13 05:48:51.233265 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Oct 13 05:48:51.233273 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Oct 13 05:48:51.233281 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Oct 13 05:48:51.233289 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Oct 13 05:48:51.233309 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Oct 13 05:48:51.233317 kernel: ACPI: PM-Timer IO Port: 0x408 Oct 13 05:48:51.233325 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Oct 13 05:48:51.233337 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Oct 13 05:48:51.233345 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Oct 13 05:48:51.233353 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Oct 13 05:48:51.233361 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Oct 13 05:48:51.233369 kernel: CPU topo: Max. logical packages: 1 Oct 13 05:48:51.233377 kernel: CPU topo: Max. logical dies: 1 Oct 13 05:48:51.233385 kernel: CPU topo: Max. dies per package: 1 Oct 13 05:48:51.233393 kernel: CPU topo: Max. threads per core: 1 Oct 13 05:48:51.233401 kernel: CPU topo: Num. cores per package: 6 Oct 13 05:48:51.233412 kernel: CPU topo: Num. threads per package: 6 Oct 13 05:48:51.233420 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs Oct 13 05:48:51.233431 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Oct 13 05:48:51.233439 kernel: Booting paravirtualized kernel on Hyper-V Oct 13 05:48:51.233448 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Oct 13 05:48:51.233456 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Oct 13 05:48:51.233464 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 Oct 13 05:48:51.233472 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 Oct 13 05:48:51.233480 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Oct 13 05:48:51.233491 kernel: Hyper-V: PV spinlocks enabled Oct 13 05:48:51.233499 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Oct 13 05:48:51.233509 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=a48d469b0deb49c328e6faf6cf366b11952d47f2d24963c866a0ea8221fb0039 Oct 13 05:48:51.233517 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Oct 13 05:48:51.233525 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Oct 13 05:48:51.233534 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Oct 13 05:48:51.233542 kernel: Fallback order for Node 0: 0 Oct 13 05:48:51.233550 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 Oct 13 05:48:51.233561 kernel: Policy zone: Normal Oct 13 05:48:51.233576 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 13 05:48:51.233585 kernel: software IO TLB: area num 8. Oct 13 05:48:51.233596 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Oct 13 05:48:51.233605 kernel: Kernel/User page tables isolation: enabled Oct 13 05:48:51.233613 kernel: ftrace: allocating 40139 entries in 157 pages Oct 13 05:48:51.233622 kernel: ftrace: allocated 157 pages with 5 groups Oct 13 05:48:51.233630 kernel: Dynamic Preempt: voluntary Oct 13 05:48:51.233639 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 13 05:48:51.233651 kernel: rcu: RCU event tracing is enabled. Oct 13 05:48:51.233660 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Oct 13 05:48:51.233669 kernel: Trampoline variant of Tasks RCU enabled. Oct 13 05:48:51.233680 kernel: Rude variant of Tasks RCU enabled. Oct 13 05:48:51.233689 kernel: Tracing variant of Tasks RCU enabled. Oct 13 05:48:51.233697 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 13 05:48:51.233706 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Oct 13 05:48:51.233715 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Oct 13 05:48:51.233726 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Oct 13 05:48:51.233734 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Oct 13 05:48:51.233743 kernel: Using NULL legacy PIC Oct 13 05:48:51.233752 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Oct 13 05:48:51.233760 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Oct 13 05:48:51.233769 kernel: Console: colour dummy device 80x25 Oct 13 05:48:51.233777 kernel: printk: legacy console [tty1] enabled Oct 13 05:48:51.233786 kernel: printk: legacy console [ttyS0] enabled Oct 13 05:48:51.233794 kernel: printk: legacy bootconsole [earlyser0] disabled Oct 13 05:48:51.233805 kernel: ACPI: Core revision 20240827 Oct 13 05:48:51.233814 kernel: Failed to register legacy timer interrupt Oct 13 05:48:51.233822 kernel: APIC: Switch to symmetric I/O mode setup Oct 13 05:48:51.233831 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Oct 13 05:48:51.233840 kernel: Hyper-V: enabling crash_kexec_post_notifiers Oct 13 05:48:51.233848 kernel: Hyper-V: Using IPI hypercalls Oct 13 05:48:51.233857 kernel: APIC: send_IPI() replaced with hv_send_ipi() Oct 13 05:48:51.233865 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Oct 13 05:48:51.233874 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Oct 13 05:48:51.233885 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Oct 13 05:48:51.233893 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Oct 13 05:48:51.233902 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Oct 13 05:48:51.233911 kernel: Hyper-V: Using enlightened APIC (xapic mode) Oct 13 05:48:51.233919 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Oct 13 05:48:51.233928 kernel: APIC: read() replaced with hv_apic_read() Oct 13 05:48:51.233936 kernel: APIC: write() replaced with hv_apic_write() Oct 13 05:48:51.233945 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Oct 13 05:48:51.233953 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Oct 13 05:48:51.233964 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593990) Oct 13 05:48:51.233973 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Oct 13 05:48:51.233982 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Oct 13 05:48:51.233990 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Oct 13 05:48:51.233999 kernel: Spectre V2 : Mitigation: Retpolines Oct 13 05:48:51.234007 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Oct 13 05:48:51.234015 kernel: Speculative Store Bypass: Vulnerable Oct 13 05:48:51.234024 kernel: MDS: Mitigation: Clear CPU buffers Oct 13 05:48:51.234032 kernel: TAA: Mitigation: Clear CPU buffers Oct 13 05:48:51.234041 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Oct 13 05:48:51.234052 kernel: active return thunk: its_return_thunk Oct 13 05:48:51.234060 kernel: ITS: Mitigation: Aligned branch/return thunks Oct 13 05:48:51.234069 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Oct 13 05:48:51.234077 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Oct 13 05:48:51.234085 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Oct 13 05:48:51.234094 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Oct 13 05:48:51.234102 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Oct 13 05:48:51.234111 kernel: Freeing SMP alternatives memory: 32K Oct 13 05:48:51.234119 kernel: pid_max: default: 32768 minimum: 301 Oct 13 05:48:51.234128 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Oct 13 05:48:51.234136 kernel: landlock: Up and running. Oct 13 05:48:51.234147 kernel: SELinux: Initializing. Oct 13 05:48:51.234155 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Oct 13 05:48:51.234164 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Oct 13 05:48:51.234172 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Oct 13 05:48:51.234181 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Oct 13 05:48:51.234190 kernel: signal: max sigframe size: 1776 Oct 13 05:48:51.234198 kernel: rcu: Hierarchical SRCU implementation. Oct 13 05:48:51.234207 kernel: rcu: Max phase no-delay instances is 400. Oct 13 05:48:51.234216 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Oct 13 05:48:51.234224 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Oct 13 05:48:51.234235 kernel: smp: Bringing up secondary CPUs ... Oct 13 05:48:51.234244 kernel: smpboot: x86: Booting SMP configuration: Oct 13 05:48:51.234252 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Oct 13 05:48:51.234261 kernel: smp: Brought up 1 node, 6 CPUs Oct 13 05:48:51.234269 kernel: smpboot: Total of 6 processors activated (31127.88 BogoMIPS) Oct 13 05:48:51.234278 kernel: Memory: 115339088K/117439364K available (14336K kernel code, 2443K rwdata, 10000K rodata, 54096K init, 2852K bss, 2090000K reserved, 0K cma-reserved) Oct 13 05:48:51.234287 kernel: devtmpfs: initialized Oct 13 05:48:51.234304 kernel: x86/mm: Memory block size: 1024MB Oct 13 05:48:51.234313 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Oct 13 05:48:51.234324 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 13 05:48:51.234333 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Oct 13 05:48:51.234342 kernel: pinctrl core: initialized pinctrl subsystem Oct 13 05:48:51.234350 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Oct 13 05:48:51.234359 kernel: audit: initializing netlink subsys (disabled) Oct 13 05:48:51.234367 kernel: audit: type=2000 audit(1760334527.029:1): state=initialized audit_enabled=0 res=1 Oct 13 05:48:51.234376 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 13 05:48:51.234384 kernel: thermal_sys: Registered thermal governor 'user_space' Oct 13 05:48:51.234393 kernel: cpuidle: using governor menu Oct 13 05:48:51.234404 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 13 05:48:51.234412 kernel: dca service started, version 1.12.1 Oct 13 05:48:51.234421 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Oct 13 05:48:51.234429 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Oct 13 05:48:51.234438 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Oct 13 05:48:51.234446 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Oct 13 05:48:51.234455 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Oct 13 05:48:51.234463 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Oct 13 05:48:51.234472 kernel: ACPI: Added _OSI(Module Device) Oct 13 05:48:51.234483 kernel: ACPI: Added _OSI(Processor Device) Oct 13 05:48:51.234491 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 13 05:48:51.234500 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 13 05:48:51.234508 kernel: ACPI: Interpreter enabled Oct 13 05:48:51.234517 kernel: ACPI: PM: (supports S0 S5) Oct 13 05:48:51.234525 kernel: ACPI: Using IOAPIC for interrupt routing Oct 13 05:48:51.234534 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Oct 13 05:48:51.234543 kernel: PCI: Ignoring E820 reservations for host bridge windows Oct 13 05:48:51.234551 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Oct 13 05:48:51.234562 kernel: iommu: Default domain type: Translated Oct 13 05:48:51.234571 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Oct 13 05:48:51.234579 kernel: efivars: Registered efivars operations Oct 13 05:48:51.234587 kernel: PCI: Using ACPI for IRQ routing Oct 13 05:48:51.234596 kernel: PCI: System does not support PCI Oct 13 05:48:51.234605 kernel: vgaarb: loaded Oct 13 05:48:51.234613 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Oct 13 05:48:51.234622 kernel: VFS: Disk quotas dquot_6.6.0 Oct 13 05:48:51.234630 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 13 05:48:51.234642 kernel: pnp: PnP ACPI init Oct 13 05:48:51.234650 kernel: pnp: PnP ACPI: found 3 devices Oct 13 05:48:51.234659 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Oct 13 05:48:51.234667 kernel: NET: Registered PF_INET protocol family Oct 13 05:48:51.234676 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 13 05:48:51.234685 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Oct 13 05:48:51.234693 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Oct 13 05:48:51.234702 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 13 05:48:51.234711 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Oct 13 05:48:51.234722 kernel: TCP: Hash tables configured (established 524288 bind 65536) Oct 13 05:48:51.234730 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Oct 13 05:48:51.234739 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Oct 13 05:48:51.234748 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Oct 13 05:48:51.234756 kernel: NET: Registered PF_XDP protocol family Oct 13 05:48:51.234765 kernel: PCI: CLS 0 bytes, default 64 Oct 13 05:48:51.234773 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Oct 13 05:48:51.234782 kernel: software IO TLB: mapped [mem 0x000000003b5bf000-0x000000003f5bf000] (64MB) Oct 13 05:48:51.234790 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Oct 13 05:48:51.234801 kernel: Initialise system trusted keyrings Oct 13 05:48:51.234810 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Oct 13 05:48:51.234818 kernel: Key type asymmetric registered Oct 13 05:48:51.234827 kernel: Asymmetric key parser 'x509' registered Oct 13 05:48:51.234835 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Oct 13 05:48:51.234844 kernel: io scheduler mq-deadline registered Oct 13 05:48:51.234852 kernel: io scheduler kyber registered Oct 13 05:48:51.234861 kernel: io scheduler bfq registered Oct 13 05:48:51.234869 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Oct 13 05:48:51.234880 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 13 05:48:51.234889 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Oct 13 05:48:51.234897 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Oct 13 05:48:51.234906 kernel: i8042: PNP: No PS/2 controller found. Oct 13 05:48:51.235065 kernel: rtc_cmos 00:02: registered as rtc0 Oct 13 05:48:51.235169 kernel: rtc_cmos 00:02: setting system clock to 2025-10-13T05:48:50 UTC (1760334530) Oct 13 05:48:51.235263 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Oct 13 05:48:51.235280 kernel: intel_pstate: CPU model not supported Oct 13 05:48:51.235289 kernel: efifb: probing for efifb Oct 13 05:48:51.235310 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Oct 13 05:48:51.235319 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Oct 13 05:48:51.235328 kernel: efifb: scrolling: redraw Oct 13 05:48:51.235336 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Oct 13 05:48:51.235345 kernel: Console: switching to colour frame buffer device 128x48 Oct 13 05:48:51.235354 kernel: fb0: EFI VGA frame buffer device Oct 13 05:48:51.235362 kernel: pstore: Using crash dump compression: deflate Oct 13 05:48:51.235374 kernel: pstore: Registered efi_pstore as persistent store backend Oct 13 05:48:51.235383 kernel: NET: Registered PF_INET6 protocol family Oct 13 05:48:51.235391 kernel: Segment Routing with IPv6 Oct 13 05:48:51.235400 kernel: In-situ OAM (IOAM) with IPv6 Oct 13 05:48:51.235408 kernel: NET: Registered PF_PACKET protocol family Oct 13 05:48:51.235417 kernel: Key type dns_resolver registered Oct 13 05:48:51.235426 kernel: IPI shorthand broadcast: enabled Oct 13 05:48:51.235434 kernel: sched_clock: Marking stable (3750005900, 116120600)->(4222070400, -355943900) Oct 13 05:48:51.235443 kernel: registered taskstats version 1 Oct 13 05:48:51.235452 kernel: Loading compiled-in X.509 certificates Oct 13 05:48:51.235463 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.51-flatcar: d8dbf4abead15098249886d373d42a3af4f50ccd' Oct 13 05:48:51.235471 kernel: Demotion targets for Node 0: null Oct 13 05:48:51.235480 kernel: Key type .fscrypt registered Oct 13 05:48:51.235488 kernel: Key type fscrypt-provisioning registered Oct 13 05:48:51.235497 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 13 05:48:51.235505 kernel: ima: Allocated hash algorithm: sha1 Oct 13 05:48:51.235514 kernel: ima: No architecture policies found Oct 13 05:48:51.235522 kernel: clk: Disabling unused clocks Oct 13 05:48:51.235533 kernel: Warning: unable to open an initial console. Oct 13 05:48:51.235542 kernel: Freeing unused kernel image (initmem) memory: 54096K Oct 13 05:48:51.235551 kernel: Write protecting the kernel read-only data: 24576k Oct 13 05:48:51.235560 kernel: Freeing unused kernel image (rodata/data gap) memory: 240K Oct 13 05:48:51.235568 kernel: Run /init as init process Oct 13 05:48:51.235577 kernel: with arguments: Oct 13 05:48:51.235585 kernel: /init Oct 13 05:48:51.235593 kernel: with environment: Oct 13 05:48:51.235602 kernel: HOME=/ Oct 13 05:48:51.235612 kernel: TERM=linux Oct 13 05:48:51.235621 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 13 05:48:51.235631 systemd[1]: Successfully made /usr/ read-only. Oct 13 05:48:51.235642 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) Oct 13 05:48:51.235652 systemd[1]: Detected virtualization microsoft. Oct 13 05:48:51.235661 systemd[1]: Detected architecture x86-64. Oct 13 05:48:51.235670 systemd[1]: Running in initrd. Oct 13 05:48:51.235679 systemd[1]: No hostname configured, using default hostname. Oct 13 05:48:51.235690 systemd[1]: Hostname set to . Oct 13 05:48:51.235699 systemd[1]: Initializing machine ID from random generator. Oct 13 05:48:51.235708 systemd[1]: Queued start job for default target initrd.target. Oct 13 05:48:51.235718 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 13 05:48:51.235727 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 13 05:48:51.235737 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Oct 13 05:48:51.235746 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 13 05:48:51.235755 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Oct 13 05:48:51.235768 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Oct 13 05:48:51.235778 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Oct 13 05:48:51.235787 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Oct 13 05:48:51.235796 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 13 05:48:51.235806 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 13 05:48:51.235815 systemd[1]: Reached target paths.target - Path Units. Oct 13 05:48:51.235826 systemd[1]: Reached target slices.target - Slice Units. Oct 13 05:48:51.235836 systemd[1]: Reached target swap.target - Swaps. Oct 13 05:48:51.235845 systemd[1]: Reached target timers.target - Timer Units. Oct 13 05:48:51.235854 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Oct 13 05:48:51.235863 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 13 05:48:51.235872 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Oct 13 05:48:51.235881 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Oct 13 05:48:51.235891 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 13 05:48:51.235900 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 13 05:48:51.235911 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 13 05:48:51.235921 systemd[1]: Reached target sockets.target - Socket Units. Oct 13 05:48:51.235930 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Oct 13 05:48:51.235939 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 13 05:48:51.235948 systemd[1]: Finished network-cleanup.service - Network Cleanup. Oct 13 05:48:51.235957 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). Oct 13 05:48:51.235967 systemd[1]: Starting systemd-fsck-usr.service... Oct 13 05:48:51.235976 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 13 05:48:51.235987 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 13 05:48:51.236016 systemd-journald[233]: Collecting audit messages is disabled. Oct 13 05:48:51.236038 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 13 05:48:51.236049 systemd-journald[233]: Journal started Oct 13 05:48:51.236073 systemd-journald[233]: Runtime Journal (/run/log/journal/d31234dfdd0d4a548d3a8c1f0c10ba0e) is 8M, max 2.2G, 2.1G free. Oct 13 05:48:51.239332 systemd-modules-load[236]: Inserted module 'overlay' Oct 13 05:48:51.245025 systemd[1]: Started systemd-journald.service - Journal Service. Oct 13 05:48:51.245399 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Oct 13 05:48:51.245908 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 13 05:48:51.246670 systemd[1]: Finished systemd-fsck-usr.service. Oct 13 05:48:51.249033 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 13 05:48:51.250227 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 13 05:48:51.300865 systemd-tmpfiles[244]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Oct 13 05:48:51.302191 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 13 05:48:51.305186 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 13 05:48:51.307111 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 13 05:48:51.331074 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 13 05:48:51.341612 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 13 05:48:51.365549 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 13 05:48:51.370803 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Oct 13 05:48:51.382466 systemd-modules-load[236]: Inserted module 'br_netfilter' Oct 13 05:48:51.384099 kernel: Bridge firewalling registered Oct 13 05:48:51.383765 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 13 05:48:51.386960 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 13 05:48:51.388847 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 13 05:48:51.399610 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Oct 13 05:48:51.419024 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 13 05:48:51.425010 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 13 05:48:51.437176 dracut-cmdline[269]: 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=a48d469b0deb49c328e6faf6cf366b11952d47f2d24963c866a0ea8221fb0039 Oct 13 05:48:51.488252 systemd-resolved[285]: Positive Trust Anchors: Oct 13 05:48:51.488268 systemd-resolved[285]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 13 05:48:51.488313 systemd-resolved[285]: 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 Oct 13 05:48:51.490835 systemd-resolved[285]: Defaulting to hostname 'linux'. Oct 13 05:48:51.491905 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 13 05:48:51.496903 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 13 05:48:51.598323 kernel: SCSI subsystem initialized Oct 13 05:48:51.608318 kernel: Loading iSCSI transport class v2.0-870. Oct 13 05:48:51.621321 kernel: iscsi: registered transport (tcp) Oct 13 05:48:51.645383 kernel: iscsi: registered transport (qla4xxx) Oct 13 05:48:51.645440 kernel: QLogic iSCSI HBA Driver Oct 13 05:48:51.665675 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 13 05:48:51.695454 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 13 05:48:51.708626 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 13 05:48:51.755236 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Oct 13 05:48:51.758204 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Oct 13 05:48:51.826327 kernel: raid6: avx2x4 gen() 30461 MB/s Oct 13 05:48:51.846313 kernel: raid6: avx2x2 gen() 30013 MB/s Oct 13 05:48:51.867719 kernel: raid6: avx2x1 gen() 28000 MB/s Oct 13 05:48:51.867760 kernel: raid6: using algorithm avx2x4 gen() 30461 MB/s Oct 13 05:48:51.889800 kernel: raid6: .... xor() 10289 MB/s, rmw enabled Oct 13 05:48:51.889826 kernel: raid6: using avx2x2 recovery algorithm Oct 13 05:48:51.914320 kernel: xor: automatically using best checksumming function avx Oct 13 05:48:52.077328 kernel: Btrfs loaded, zoned=no, fsverity=no Oct 13 05:48:52.083938 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Oct 13 05:48:52.087127 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 13 05:48:52.122499 systemd-udevd[484]: Using default interface naming scheme 'v255'. Oct 13 05:48:52.128557 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 13 05:48:52.130996 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Oct 13 05:48:52.177134 dracut-pre-trigger[493]: rd.md=0: removing MD RAID activation Oct 13 05:48:52.202700 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Oct 13 05:48:52.213352 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 13 05:48:52.295324 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 13 05:48:52.300338 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Oct 13 05:48:52.327329 kernel: cryptd: max_cpu_qlen set to 1000 Oct 13 05:48:52.338313 kernel: AES CTR mode by8 optimization enabled Oct 13 05:48:52.345266 kernel: hv_vmbus: Vmbus version:4.0 Oct 13 05:48:52.365319 kernel: pps_core: LinuxPPS API ver. 1 registered Oct 13 05:48:52.365367 kernel: hv_vmbus: registering driver hyperv_keyboard Oct 13 05:48:52.365380 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Oct 13 05:48:52.367618 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 13 05:48:52.387790 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 13 05:48:52.367895 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 13 05:48:52.388721 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Oct 13 05:48:52.400065 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 Oct 13 05:48:52.414378 kernel: PTP clock support registered Oct 13 05:48:52.414672 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 13 05:48:52.423006 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Oct 13 05:48:52.434313 kernel: hv_vmbus: registering driver hv_pci Oct 13 05:48:52.434348 kernel: hv_vmbus: registering driver hid_hyperv Oct 13 05:48:52.434368 kernel: hv_vmbus: registering driver hv_netvsc Oct 13 05:48:53.047358 kernel: hv_utils: Registering HyperV Utility Driver Oct 13 05:48:53.047411 kernel: hv_vmbus: registering driver hv_utils Oct 13 05:48:53.047426 kernel: hv_utils: Heartbeat IC version 3.0 Oct 13 05:48:53.047438 kernel: hv_utils: Shutdown IC version 3.2 Oct 13 05:48:53.047449 kernel: hv_utils: TimeSync IC version 4.0 Oct 13 05:48:53.047461 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Oct 13 05:48:53.047643 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Oct 13 05:48:53.037743 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 13 05:48:53.197073 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Oct 13 05:48:53.197307 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Oct 13 05:48:53.197465 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Oct 13 05:48:53.197623 kernel: hv_vmbus: registering driver hv_storvsc Oct 13 05:48:53.197637 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Oct 13 05:48:53.197769 kernel: scsi host0: storvsc_host_t Oct 13 05:48:53.197901 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Oct 13 05:48:53.198022 kernel: scsi host1: storvsc_host_t Oct 13 05:48:53.198147 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint Oct 13 05:48:53.198292 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] Oct 13 05:48:53.198448 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] Oct 13 05:48:53.198578 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] Oct 13 05:48:53.198703 kernel: pci 0001:00:00.0: Enabling HDA controller Oct 13 05:48:53.198833 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Oct 13 05:48:53.198976 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Oct 13 05:48:53.199095 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Oct 13 05:48:53.199233 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned Oct 13 05:48:53.199376 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Oct 13 05:48:53.199518 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned Oct 13 05:48:53.199649 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Oct 13 05:48:53.199779 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Oct 13 05:48:53.199906 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks Oct 13 05:48:53.200031 kernel: sd 0:0:0:1: [sdb] Write Protect is off Oct 13 05:48:53.200155 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned Oct 13 05:48:53.200279 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Oct 13 05:48:53.200419 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Oct 13 05:48:53.200557 kernel: sd 0:0:0:0: [sda] Write Protect is off Oct 13 05:48:53.200686 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Oct 13 05:48:53.200811 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Oct 13 05:48:53.200937 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#517 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 13 05:48:53.201061 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Oct 13 05:48:53.201188 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#524 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 13 05:48:53.201307 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#69 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 13 05:48:53.037848 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 13 05:48:53.038738 systemd-resolved[285]: Clock change detected. Flushing caches. Oct 13 05:48:53.224370 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#198 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 13 05:48:53.224560 kernel: sdb: sdb1 Oct 13 05:48:53.205403 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 13 05:48:53.231390 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk Oct 13 05:48:53.249420 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Oct 13 05:48:53.249476 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Oct 13 05:48:53.255266 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 13 05:48:53.271850 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Oct 13 05:48:53.272071 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Oct 13 05:48:53.273382 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Oct 13 05:48:53.800951 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Oct 13 05:48:53.862619 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Oct 13 05:48:53.902117 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Oct 13 05:48:53.903800 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Oct 13 05:48:53.928024 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Oct 13 05:48:53.930533 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Oct 13 05:48:53.973357 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#473 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 13 05:48:53.990351 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Oct 13 05:48:54.001484 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#240 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 13 05:48:54.021349 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Oct 13 05:48:55.028651 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#374 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 13 05:48:55.047083 disk-uuid[723]: The operation has completed successfully. Oct 13 05:48:55.053210 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Oct 13 05:48:57.013392 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 13 05:48:57.013505 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Oct 13 05:48:57.016797 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Oct 13 05:48:57.045512 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Oct 13 05:48:57.053153 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Oct 13 05:48:57.079996 sh[887]: Success Oct 13 05:48:57.053497 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 13 05:48:57.055006 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 13 05:48:57.072010 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Oct 13 05:48:57.110431 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Oct 13 05:48:57.110470 kernel: device-mapper: uevent: version 1.0.3 Oct 13 05:48:57.115386 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Oct 13 05:48:57.128350 kernel: device-mapper: verity: sha256 using shash "sha256-avx2" Oct 13 05:48:57.128487 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Oct 13 05:48:57.460855 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Oct 13 05:48:57.471041 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Oct 13 05:48:57.494491 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Oct 13 05:48:57.514345 kernel: BTRFS: device fsid c8746500-26f5-4ec1-9da8-aef51ec7db92 devid 1 transid 41 /dev/mapper/usr (254:0) scanned by mount (914) Oct 13 05:48:57.523808 kernel: BTRFS info (device dm-0): first mount of filesystem c8746500-26f5-4ec1-9da8-aef51ec7db92 Oct 13 05:48:57.523841 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Oct 13 05:48:57.859151 kernel: BTRFS info (device dm-0): disabling log replay at mount time Oct 13 05:48:57.859231 kernel: BTRFS info (device dm-0): enabling free space tree Oct 13 05:48:57.893161 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Oct 13 05:48:57.895187 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Oct 13 05:48:57.909356 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Oct 13 05:48:57.917675 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Oct 13 05:48:57.931840 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Oct 13 05:48:57.975353 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (946) Oct 13 05:48:57.984552 kernel: BTRFS info (device sda6): first mount of filesystem 1cd10441-4b32-40b7-b370-b928e4bc90dd Oct 13 05:48:57.984588 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Oct 13 05:48:58.031876 kernel: BTRFS info (device sda6): turning on async discard Oct 13 05:48:58.031942 kernel: BTRFS info (device sda6): enabling free space tree Oct 13 05:48:58.039948 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 13 05:48:58.052736 kernel: BTRFS info (device sda6): last unmount of filesystem 1cd10441-4b32-40b7-b370-b928e4bc90dd Oct 13 05:48:58.045890 systemd[1]: Finished ignition-setup.service - Ignition (setup). Oct 13 05:48:58.062346 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Oct 13 05:48:58.079599 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 13 05:48:58.134251 systemd-networkd[1083]: lo: Link UP Oct 13 05:48:58.134263 systemd-networkd[1083]: lo: Gained carrier Oct 13 05:48:58.135279 systemd-networkd[1083]: Enumeration completed Oct 13 05:48:58.135400 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 13 05:48:58.135659 systemd-networkd[1083]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 13 05:48:58.135664 systemd-networkd[1083]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 13 05:48:58.136616 systemd-networkd[1083]: eth0: Link UP Oct 13 05:48:58.136779 systemd-networkd[1083]: eth0: Gained carrier Oct 13 05:48:58.136789 systemd-networkd[1083]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 13 05:48:58.141735 systemd[1]: Reached target network.target - Network. Oct 13 05:48:58.205387 systemd-networkd[1083]: eth0: DHCPv4 address 10.200.4.17/24, gateway 10.200.4.1 acquired from 168.63.129.16 Oct 13 05:48:59.290282 ignition[1082]: Ignition 2.22.0 Oct 13 05:48:59.290302 ignition[1082]: Stage: fetch-offline Oct 13 05:48:59.290474 ignition[1082]: no configs at "/usr/lib/ignition/base.d" Oct 13 05:48:59.290485 ignition[1082]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 13 05:48:59.290579 ignition[1082]: parsed url from cmdline: "" Oct 13 05:48:59.290583 ignition[1082]: no config URL provided Oct 13 05:48:59.290589 ignition[1082]: reading system config file "/usr/lib/ignition/user.ign" Oct 13 05:48:59.290597 ignition[1082]: no config at "/usr/lib/ignition/user.ign" Oct 13 05:48:59.290602 ignition[1082]: failed to fetch config: resource requires networking Oct 13 05:48:59.290793 ignition[1082]: Ignition finished successfully Oct 13 05:48:59.323555 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Oct 13 05:48:59.334528 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Oct 13 05:48:59.378405 ignition[1097]: Ignition 2.22.0 Oct 13 05:48:59.378419 ignition[1097]: Stage: fetch Oct 13 05:48:59.378653 ignition[1097]: no configs at "/usr/lib/ignition/base.d" Oct 13 05:48:59.378664 ignition[1097]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 13 05:48:59.378756 ignition[1097]: parsed url from cmdline: "" Oct 13 05:48:59.378760 ignition[1097]: no config URL provided Oct 13 05:48:59.378766 ignition[1097]: reading system config file "/usr/lib/ignition/user.ign" Oct 13 05:48:59.378775 ignition[1097]: no config at "/usr/lib/ignition/user.ign" Oct 13 05:48:59.378793 ignition[1097]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Oct 13 05:48:59.402462 systemd-networkd[1083]: eth0: Gained IPv6LL Oct 13 05:48:59.464119 ignition[1097]: GET result: OK Oct 13 05:48:59.464195 ignition[1097]: config has been read from IMDS userdata Oct 13 05:48:59.464224 ignition[1097]: parsing config with SHA512: 8cfd9f8d303f1d5e6d8c807acd6d772979e373bf6e86da07dac90a86250999f9b232e67b64595a93f255991ceed5cd80a8f5de49944424a0d90100debf845079 Oct 13 05:48:59.469179 unknown[1097]: fetched base config from "system" Oct 13 05:48:59.469363 ignition[1097]: fetch: fetch complete Oct 13 05:48:59.469187 unknown[1097]: fetched base config from "system" Oct 13 05:48:59.469369 ignition[1097]: fetch: fetch passed Oct 13 05:48:59.469192 unknown[1097]: fetched user config from "azure" Oct 13 05:48:59.469413 ignition[1097]: Ignition finished successfully Oct 13 05:48:59.482053 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Oct 13 05:48:59.485806 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Oct 13 05:48:59.533021 ignition[1107]: Ignition 2.22.0 Oct 13 05:48:59.533036 ignition[1107]: Stage: kargs Oct 13 05:48:59.533315 ignition[1107]: no configs at "/usr/lib/ignition/base.d" Oct 13 05:48:59.533342 ignition[1107]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 13 05:48:59.542484 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Oct 13 05:48:59.533853 ignition[1107]: kargs: kargs passed Oct 13 05:48:59.533902 ignition[1107]: Ignition finished successfully Oct 13 05:48:59.554041 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Oct 13 05:48:59.598523 ignition[1117]: Ignition 2.22.0 Oct 13 05:48:59.598538 ignition[1117]: Stage: disks Oct 13 05:48:59.598780 ignition[1117]: no configs at "/usr/lib/ignition/base.d" Oct 13 05:48:59.598793 ignition[1117]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 13 05:48:59.605050 systemd[1]: Finished ignition-disks.service - Ignition (disks). Oct 13 05:48:59.599288 ignition[1117]: disks: disks passed Oct 13 05:48:59.608747 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Oct 13 05:48:59.599398 ignition[1117]: Ignition finished successfully Oct 13 05:48:59.617111 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Oct 13 05:48:59.626450 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 13 05:48:59.637804 systemd[1]: Reached target sysinit.target - System Initialization. Oct 13 05:48:59.644398 systemd[1]: Reached target basic.target - Basic System. Oct 13 05:48:59.660694 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Oct 13 05:48:59.763684 systemd-fsck[1129]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks Oct 13 05:48:59.771123 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Oct 13 05:48:59.775235 systemd[1]: Mounting sysroot.mount - /sysroot... Oct 13 05:49:01.767342 kernel: EXT4-fs (sda9): mounted filesystem 8b520359-9763-45f3-b7f7-db1e9fbc640d r/w with ordered data mode. Quota mode: none. Oct 13 05:49:01.767611 systemd[1]: Mounted sysroot.mount - /sysroot. Oct 13 05:49:01.774590 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Oct 13 05:49:01.805127 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 13 05:49:01.830944 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Oct 13 05:49:01.838931 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Oct 13 05:49:01.840562 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Oct 13 05:49:01.840596 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Oct 13 05:49:01.864782 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Oct 13 05:49:01.885901 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1143) Oct 13 05:49:01.885929 kernel: BTRFS info (device sda6): first mount of filesystem 1cd10441-4b32-40b7-b370-b928e4bc90dd Oct 13 05:49:01.885949 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Oct 13 05:49:01.877120 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Oct 13 05:49:01.904489 kernel: BTRFS info (device sda6): turning on async discard Oct 13 05:49:01.904516 kernel: BTRFS info (device sda6): enabling free space tree Oct 13 05:49:01.905836 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 13 05:49:02.604733 coreos-metadata[1145]: Oct 13 05:49:02.604 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Oct 13 05:49:02.611168 coreos-metadata[1145]: Oct 13 05:49:02.607 INFO Fetch successful Oct 13 05:49:02.611168 coreos-metadata[1145]: Oct 13 05:49:02.607 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Oct 13 05:49:02.623576 coreos-metadata[1145]: Oct 13 05:49:02.615 INFO Fetch successful Oct 13 05:49:02.623576 coreos-metadata[1145]: Oct 13 05:49:02.615 INFO wrote hostname ci-4459.1.0-a-55d8ca4053 to /sysroot/etc/hostname Oct 13 05:49:02.634770 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Oct 13 05:49:02.995806 initrd-setup-root[1173]: cut: /sysroot/etc/passwd: No such file or directory Oct 13 05:49:03.046434 initrd-setup-root[1180]: cut: /sysroot/etc/group: No such file or directory Oct 13 05:49:03.102198 initrd-setup-root[1187]: cut: /sysroot/etc/shadow: No such file or directory Oct 13 05:49:03.121251 initrd-setup-root[1194]: cut: /sysroot/etc/gshadow: No such file or directory Oct 13 05:49:04.589813 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Oct 13 05:49:04.593074 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Oct 13 05:49:04.629939 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Oct 13 05:49:04.638198 systemd[1]: sysroot-oem.mount: Deactivated successfully. Oct 13 05:49:04.647309 kernel: BTRFS info (device sda6): last unmount of filesystem 1cd10441-4b32-40b7-b370-b928e4bc90dd Oct 13 05:49:04.672636 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Oct 13 05:49:04.685634 ignition[1262]: INFO : Ignition 2.22.0 Oct 13 05:49:04.685634 ignition[1262]: INFO : Stage: mount Oct 13 05:49:04.691664 ignition[1262]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 13 05:49:04.691664 ignition[1262]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 13 05:49:04.691664 ignition[1262]: INFO : mount: mount passed Oct 13 05:49:04.691664 ignition[1262]: INFO : Ignition finished successfully Oct 13 05:49:04.693221 systemd[1]: Finished ignition-mount.service - Ignition (mount). Oct 13 05:49:04.707917 systemd[1]: Starting ignition-files.service - Ignition (files)... Oct 13 05:49:04.738166 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 13 05:49:04.765364 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1277) Oct 13 05:49:04.771350 kernel: BTRFS info (device sda6): first mount of filesystem 1cd10441-4b32-40b7-b370-b928e4bc90dd Oct 13 05:49:04.771379 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Oct 13 05:49:04.786338 kernel: BTRFS info (device sda6): turning on async discard Oct 13 05:49:04.786413 kernel: BTRFS info (device sda6): enabling free space tree Oct 13 05:49:04.788720 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 13 05:49:04.833051 ignition[1294]: INFO : Ignition 2.22.0 Oct 13 05:49:04.833051 ignition[1294]: INFO : Stage: files Oct 13 05:49:04.839195 ignition[1294]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 13 05:49:04.839195 ignition[1294]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 13 05:49:04.839195 ignition[1294]: DEBUG : files: compiled without relabeling support, skipping Oct 13 05:49:04.853203 ignition[1294]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Oct 13 05:49:04.853203 ignition[1294]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Oct 13 05:49:04.940577 ignition[1294]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Oct 13 05:49:04.946359 ignition[1294]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Oct 13 05:49:04.946359 ignition[1294]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Oct 13 05:49:04.941533 unknown[1294]: wrote ssh authorized keys file for user: core Oct 13 05:49:05.000136 ignition[1294]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Oct 13 05:49:05.007442 ignition[1294]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Oct 13 05:49:05.007442 ignition[1294]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Oct 13 05:49:05.007442 ignition[1294]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Oct 13 05:49:05.007442 ignition[1294]: INFO : files: files passed Oct 13 05:49:05.007442 ignition[1294]: INFO : Ignition finished successfully Oct 13 05:49:05.030944 systemd[1]: Finished ignition-files.service - Ignition (files). Oct 13 05:49:05.034379 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Oct 13 05:49:05.070018 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Oct 13 05:49:05.080946 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 13 05:49:05.081058 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Oct 13 05:49:05.109235 initrd-setup-root-after-ignition[1327]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 13 05:49:05.109235 initrd-setup-root-after-ignition[1327]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Oct 13 05:49:05.122038 initrd-setup-root-after-ignition[1331]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 13 05:49:05.129357 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 13 05:49:05.130437 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Oct 13 05:49:05.146113 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Oct 13 05:49:05.190916 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 13 05:49:05.191035 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Oct 13 05:49:05.200310 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Oct 13 05:49:05.209487 systemd[1]: Reached target initrd.target - Initrd Default Target. Oct 13 05:49:05.217866 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Oct 13 05:49:05.218786 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Oct 13 05:49:05.259379 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 13 05:49:05.270685 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Oct 13 05:49:05.304339 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Oct 13 05:49:05.314154 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 13 05:49:05.315915 systemd[1]: Stopped target timers.target - Timer Units. Oct 13 05:49:05.325474 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 13 05:49:05.325619 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 13 05:49:05.340200 systemd[1]: Stopped target initrd.target - Initrd Default Target. Oct 13 05:49:05.349071 systemd[1]: Stopped target basic.target - Basic System. Oct 13 05:49:05.351358 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Oct 13 05:49:05.361915 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Oct 13 05:49:05.372763 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Oct 13 05:49:05.381450 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Oct 13 05:49:05.394191 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Oct 13 05:49:05.402934 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Oct 13 05:49:05.411918 systemd[1]: Stopped target sysinit.target - System Initialization. Oct 13 05:49:05.421132 systemd[1]: Stopped target local-fs.target - Local File Systems. Oct 13 05:49:05.423167 systemd[1]: Stopped target swap.target - Swaps. Oct 13 05:49:05.423866 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 13 05:49:05.424011 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Oct 13 05:49:05.425380 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Oct 13 05:49:05.426129 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 13 05:49:05.426806 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Oct 13 05:49:05.426951 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 13 05:49:05.528211 ignition[1351]: INFO : Ignition 2.22.0 Oct 13 05:49:05.528211 ignition[1351]: INFO : Stage: umount Oct 13 05:49:05.528211 ignition[1351]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 13 05:49:05.528211 ignition[1351]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 13 05:49:05.528211 ignition[1351]: INFO : umount: umount passed Oct 13 05:49:05.528211 ignition[1351]: INFO : Ignition finished successfully Oct 13 05:49:05.427571 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 13 05:49:05.427678 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Oct 13 05:49:05.429120 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Oct 13 05:49:05.429238 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 13 05:49:05.429874 systemd[1]: ignition-files.service: Deactivated successfully. Oct 13 05:49:05.429985 systemd[1]: Stopped ignition-files.service - Ignition (files). Oct 13 05:49:05.430634 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Oct 13 05:49:05.430739 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Oct 13 05:49:05.432250 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Oct 13 05:49:05.433465 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Oct 13 05:49:05.433555 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 13 05:49:05.433664 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Oct 13 05:49:05.434423 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 13 05:49:05.434529 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Oct 13 05:49:05.494674 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 13 05:49:05.503528 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Oct 13 05:49:05.525157 systemd[1]: ignition-mount.service: Deactivated successfully. Oct 13 05:49:05.525371 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Oct 13 05:49:05.529917 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 13 05:49:05.529986 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Oct 13 05:49:05.537060 systemd[1]: ignition-kargs.service: Deactivated successfully. Oct 13 05:49:05.537109 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Oct 13 05:49:05.543191 systemd[1]: ignition-fetch.service: Deactivated successfully. Oct 13 05:49:05.543244 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Oct 13 05:49:05.554695 systemd[1]: Stopped target network.target - Network. Oct 13 05:49:05.563228 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Oct 13 05:49:05.563298 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Oct 13 05:49:05.571311 systemd[1]: Stopped target paths.target - Path Units. Oct 13 05:49:05.580252 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 13 05:49:05.583392 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 13 05:49:05.585705 systemd[1]: Stopped target slices.target - Slice Units. Oct 13 05:49:05.595114 systemd[1]: Stopped target sockets.target - Socket Units. Oct 13 05:49:05.603615 systemd[1]: iscsid.socket: Deactivated successfully. Oct 13 05:49:05.603671 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Oct 13 05:49:05.612621 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 13 05:49:05.612664 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 13 05:49:05.620640 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 13 05:49:05.620714 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Oct 13 05:49:05.628424 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Oct 13 05:49:05.628483 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Oct 13 05:49:05.640307 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Oct 13 05:49:05.649439 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Oct 13 05:49:05.659524 systemd[1]: sysroot-boot.mount: Deactivated successfully. Oct 13 05:49:05.660147 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 13 05:49:05.660252 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Oct 13 05:49:05.663781 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 13 05:49:05.663894 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Oct 13 05:49:05.680567 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Oct 13 05:49:05.680876 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 13 05:49:05.680996 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Oct 13 05:49:05.694111 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Oct 13 05:49:05.697085 systemd[1]: Stopped target network-pre.target - Preparation for Network. Oct 13 05:49:05.705200 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 13 05:49:05.705251 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Oct 13 05:49:05.707352 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 13 05:49:05.707421 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Oct 13 05:49:05.719341 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Oct 13 05:49:05.726968 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Oct 13 05:49:05.727037 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 13 05:49:05.734575 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 13 05:49:05.734641 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Oct 13 05:49:05.750167 systemd[1]: systemd-modules-load.service: Deactivated successfully. Oct 13 05:49:05.750230 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Oct 13 05:49:05.758299 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 13 05:49:05.758373 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 13 05:49:05.770674 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 13 05:49:05.781170 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Oct 13 05:49:05.781247 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Oct 13 05:49:05.796620 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 13 05:49:05.812838 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 13 05:49:05.815450 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 13 05:49:05.815497 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Oct 13 05:49:05.827209 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 13 05:49:05.827256 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Oct 13 05:49:05.835486 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 13 05:49:05.835540 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Oct 13 05:49:05.850539 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 13 05:49:05.850592 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Oct 13 05:49:05.863033 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 13 05:49:05.863086 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 13 05:49:05.877619 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Oct 13 05:49:05.885029 systemd[1]: systemd-network-generator.service: Deactivated successfully. Oct 13 05:49:05.885091 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Oct 13 05:49:05.897594 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 13 05:49:05.897654 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 13 05:49:05.907421 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Oct 13 05:49:05.907472 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 13 05:49:05.916978 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 13 05:49:05.917025 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Oct 13 05:49:05.926300 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 13 05:49:05.926369 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 13 05:49:05.941430 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Oct 13 05:49:05.941724 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev\x2dearly.service.mount: Deactivated successfully. Oct 13 05:49:05.941833 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Oct 13 05:49:06.172299 systemd-journald[233]: Received SIGTERM from PID 1 (systemd). Oct 13 05:49:05.941882 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Oct 13 05:49:05.942987 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 13 05:49:05.943571 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Oct 13 05:49:05.945817 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 13 05:49:05.945909 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Oct 13 05:49:05.954337 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Oct 13 05:49:05.964032 systemd[1]: Starting initrd-switch-root.service - Switch Root... Oct 13 05:49:06.014979 systemd[1]: Switching root. Oct 13 05:49:06.182178 systemd-journald[233]: Journal stopped Oct 13 05:49:15.116941 kernel: SELinux: policy capability network_peer_controls=1 Oct 13 05:49:15.116974 kernel: SELinux: policy capability open_perms=1 Oct 13 05:49:15.116986 kernel: SELinux: policy capability extended_socket_class=1 Oct 13 05:49:15.116999 kernel: SELinux: policy capability always_check_network=0 Oct 13 05:49:15.117008 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 13 05:49:15.117017 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 13 05:49:15.117028 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 13 05:49:15.117040 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Oct 13 05:49:15.117049 kernel: SELinux: policy capability userspace_initial_context=0 Oct 13 05:49:15.117059 kernel: audit: type=1403 audit(1760334547.334:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 13 05:49:15.117071 systemd[1]: Successfully loaded SELinux policy in 274.791ms. Oct 13 05:49:15.117083 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 9.984ms. Oct 13 05:49:15.117094 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) Oct 13 05:49:15.117105 systemd[1]: Detected virtualization microsoft. Oct 13 05:49:15.117118 systemd[1]: Detected architecture x86-64. Oct 13 05:49:15.117129 systemd[1]: Detected first boot. Oct 13 05:49:15.117140 systemd[1]: Hostname set to . Oct 13 05:49:15.117150 systemd[1]: Initializing machine ID from random generator. Oct 13 05:49:15.117160 zram_generator::config[1397]: No configuration found. Oct 13 05:49:15.117174 kernel: Guest personality initialized and is inactive Oct 13 05:49:15.117183 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Oct 13 05:49:15.117193 kernel: Initialized host personality Oct 13 05:49:15.117203 kernel: NET: Registered PF_VSOCK protocol family Oct 13 05:49:15.117213 systemd[1]: Populated /etc with preset unit settings. Oct 13 05:49:15.117227 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Oct 13 05:49:15.117238 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 13 05:49:15.117251 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Oct 13 05:49:15.117261 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 13 05:49:15.117272 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Oct 13 05:49:15.117283 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Oct 13 05:49:15.117293 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Oct 13 05:49:15.117304 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Oct 13 05:49:15.117315 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Oct 13 05:49:15.117336 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Oct 13 05:49:15.117352 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Oct 13 05:49:15.117362 systemd[1]: Created slice user.slice - User and Session Slice. Oct 13 05:49:15.117373 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 13 05:49:15.117384 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 13 05:49:15.117394 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Oct 13 05:49:15.117405 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Oct 13 05:49:15.117420 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Oct 13 05:49:15.117431 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 13 05:49:15.117445 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Oct 13 05:49:15.117456 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 13 05:49:15.117467 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 13 05:49:15.117481 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Oct 13 05:49:15.117492 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Oct 13 05:49:15.117503 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Oct 13 05:49:15.117514 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Oct 13 05:49:15.117528 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 13 05:49:15.117539 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 13 05:49:15.117550 systemd[1]: Reached target slices.target - Slice Units. Oct 13 05:49:15.117561 systemd[1]: Reached target swap.target - Swaps. Oct 13 05:49:15.117572 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Oct 13 05:49:15.117582 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Oct 13 05:49:15.117597 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Oct 13 05:49:15.117608 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 13 05:49:15.117619 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 13 05:49:15.117630 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 13 05:49:15.117651 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Oct 13 05:49:15.117670 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Oct 13 05:49:15.117682 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Oct 13 05:49:15.117697 systemd[1]: Mounting media.mount - External Media Directory... Oct 13 05:49:15.117709 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 13 05:49:15.117720 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Oct 13 05:49:15.117731 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Oct 13 05:49:15.117746 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Oct 13 05:49:15.117758 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). Oct 13 05:49:15.117769 systemd[1]: Reached target machines.target - Containers. Oct 13 05:49:15.117780 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Oct 13 05:49:15.117791 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 13 05:49:15.117806 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 13 05:49:15.117817 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Oct 13 05:49:15.117828 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 13 05:49:15.117839 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 13 05:49:15.117850 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 13 05:49:15.117861 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Oct 13 05:49:15.117872 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 13 05:49:15.117884 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Oct 13 05:49:15.117898 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 13 05:49:15.117909 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Oct 13 05:49:15.117920 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 13 05:49:15.117931 systemd[1]: Stopped systemd-fsck-usr.service. Oct 13 05:49:15.117943 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). Oct 13 05:49:15.117954 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 13 05:49:15.117965 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 13 05:49:15.117976 kernel: fuse: init (API version 7.41) Oct 13 05:49:15.117992 kernel: loop: module loaded Oct 13 05:49:15.118010 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 13 05:49:15.118024 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Oct 13 05:49:15.118035 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Oct 13 05:49:15.118046 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 13 05:49:15.118083 systemd-journald[1505]: Collecting audit messages is disabled. Oct 13 05:49:15.118111 systemd[1]: verity-setup.service: Deactivated successfully. Oct 13 05:49:15.118123 systemd[1]: Stopped verity-setup.service. Oct 13 05:49:15.118143 systemd-journald[1505]: Journal started Oct 13 05:49:15.118167 systemd-journald[1505]: Runtime Journal (/run/log/journal/7dc3a3f5c909414987a565c05b739dbf) is 8M, max 2.2G, 2.1G free. Oct 13 05:49:14.100573 systemd[1]: Queued start job for default target multi-user.target. Oct 13 05:49:14.117002 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Oct 13 05:49:14.117572 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 13 05:49:15.134348 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 13 05:49:15.142465 systemd[1]: Started systemd-journald.service - Journal Service. Oct 13 05:49:15.146779 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Oct 13 05:49:15.151210 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Oct 13 05:49:15.155896 systemd[1]: Mounted media.mount - External Media Directory. Oct 13 05:49:15.162918 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Oct 13 05:49:15.167613 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Oct 13 05:49:15.172337 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Oct 13 05:49:15.176696 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Oct 13 05:49:15.182043 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 13 05:49:15.188605 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 13 05:49:15.188960 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Oct 13 05:49:15.194443 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 13 05:49:15.194740 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 13 05:49:15.199956 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 13 05:49:15.200144 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 13 05:49:15.205668 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 13 05:49:15.205860 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Oct 13 05:49:15.210888 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 13 05:49:15.211068 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 13 05:49:15.216175 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 13 05:49:15.223823 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 13 05:49:15.229388 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Oct 13 05:49:15.242660 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 13 05:49:15.248966 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Oct 13 05:49:15.264955 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Oct 13 05:49:15.270996 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Oct 13 05:49:15.271056 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 13 05:49:15.276561 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Oct 13 05:49:15.283074 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Oct 13 05:49:15.299750 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 13 05:49:15.301067 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Oct 13 05:49:15.317680 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Oct 13 05:49:15.322739 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 13 05:49:15.323823 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Oct 13 05:49:15.328431 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 13 05:49:15.329397 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 13 05:49:15.334814 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Oct 13 05:49:15.340755 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 13 05:49:15.347841 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Oct 13 05:49:15.353605 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 13 05:49:15.362398 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Oct 13 05:49:15.367815 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Oct 13 05:49:15.378354 kernel: ACPI: bus type drm_connector registered Oct 13 05:49:15.379526 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 13 05:49:15.379739 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 13 05:49:15.406515 systemd-journald[1505]: Time spent on flushing to /var/log/journal/7dc3a3f5c909414987a565c05b739dbf is 16.752ms for 958 entries. Oct 13 05:49:15.406515 systemd-journald[1505]: System Journal (/var/log/journal/7dc3a3f5c909414987a565c05b739dbf) is 8M, max 2.6G, 2.6G free. Oct 13 05:49:15.471412 systemd-journald[1505]: Received client request to flush runtime journal. Oct 13 05:49:15.417253 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Oct 13 05:49:15.422515 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Oct 13 05:49:15.428890 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Oct 13 05:49:15.473782 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Oct 13 05:49:15.523391 kernel: loop0: detected capacity change from 0 to 128016 Oct 13 05:49:15.527151 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 13 05:49:15.527931 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Oct 13 05:49:15.549229 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 13 05:49:15.552039 systemd-tmpfiles[1539]: ACLs are not supported, ignoring. Oct 13 05:49:15.552060 systemd-tmpfiles[1539]: ACLs are not supported, ignoring. Oct 13 05:49:15.556136 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 13 05:49:15.562865 systemd[1]: Starting systemd-sysusers.service - Create System Users... Oct 13 05:49:16.174083 systemd[1]: Finished systemd-sysusers.service - Create System Users. Oct 13 05:49:16.184082 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 13 05:49:16.220143 systemd-tmpfiles[1559]: ACLs are not supported, ignoring. Oct 13 05:49:16.220166 systemd-tmpfiles[1559]: ACLs are not supported, ignoring. Oct 13 05:49:16.225785 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 13 05:49:16.440356 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Oct 13 05:49:16.493362 kernel: loop1: detected capacity change from 0 to 27936 Oct 13 05:49:16.504178 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Oct 13 05:49:16.511135 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 13 05:49:16.554381 systemd-udevd[1565]: Using default interface naming scheme 'v255'. Oct 13 05:49:17.198164 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 13 05:49:17.210070 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 13 05:49:17.245176 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Oct 13 05:49:17.344043 kernel: hv_vmbus: registering driver hyperv_fb Oct 13 05:49:17.344139 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Oct 13 05:49:17.349068 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Oct 13 05:49:17.352497 kernel: Console: switching to colour dummy device 80x25 Oct 13 05:49:17.354344 kernel: Console: switching to colour frame buffer device 128x48 Oct 13 05:49:17.369348 kernel: mousedev: PS/2 mouse device common for all mice Oct 13 05:49:17.380350 kernel: hv_vmbus: registering driver hv_balloon Oct 13 05:49:17.380430 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Oct 13 05:49:17.385261 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Oct 13 05:49:17.402367 kernel: loop2: detected capacity change from 0 to 110984 Oct 13 05:49:17.441795 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 13 05:49:17.455573 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 13 05:49:17.455782 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 13 05:49:17.462746 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 13 05:49:17.468092 systemd[1]: Started systemd-userdbd.service - User Database Manager. Oct 13 05:49:17.483357 kernel: EDAC MC: Ver: 3.0.0 Oct 13 05:49:17.693942 systemd-networkd[1586]: lo: Link UP Oct 13 05:49:17.693954 systemd-networkd[1586]: lo: Gained carrier Oct 13 05:49:17.695461 systemd-networkd[1586]: Enumeration completed Oct 13 05:49:17.695588 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 13 05:49:17.695974 systemd-networkd[1586]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 13 05:49:17.696006 systemd-networkd[1586]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 13 05:49:17.697289 systemd-networkd[1586]: eth0: Link UP Oct 13 05:49:17.697298 systemd-networkd[1586]: eth0: Gained carrier Oct 13 05:49:17.697312 systemd-networkd[1586]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 13 05:49:17.698562 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Oct 13 05:49:17.699719 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Oct 13 05:49:17.750458 systemd-networkd[1586]: eth0: DHCPv4 address 10.200.4.17/24, gateway 10.200.4.1 acquired from 168.63.129.16 Oct 13 05:49:17.773698 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Oct 13 05:49:17.782937 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Oct 13 05:49:17.785621 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Oct 13 05:49:17.879208 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Oct 13 05:49:17.899342 kernel: loop3: detected capacity change from 0 to 128016 Oct 13 05:49:17.915342 kernel: loop4: detected capacity change from 0 to 27936 Oct 13 05:49:17.931348 kernel: loop5: detected capacity change from 0 to 110984 Oct 13 05:49:17.944492 (sd-merge)[1721]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Oct 13 05:49:17.944975 (sd-merge)[1721]: Merged extensions into '/usr'. Oct 13 05:49:17.948510 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Oct 13 05:49:17.954117 systemd[1]: Starting ensure-sysext.service... Oct 13 05:49:17.956546 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 13 05:49:17.992716 systemd[1]: Reload requested from client PID 1723 ('systemctl') (unit ensure-sysext.service)... Oct 13 05:49:17.992735 systemd[1]: Reloading... Oct 13 05:49:18.040416 zram_generator::config[1755]: No configuration found. Oct 13 05:49:18.042094 systemd-tmpfiles[1724]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Oct 13 05:49:18.042136 systemd-tmpfiles[1724]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Oct 13 05:49:18.042542 systemd-tmpfiles[1724]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Oct 13 05:49:18.042900 systemd-tmpfiles[1724]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Oct 13 05:49:18.044137 systemd-tmpfiles[1724]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Oct 13 05:49:18.044720 systemd-tmpfiles[1724]: ACLs are not supported, ignoring. Oct 13 05:49:18.044791 systemd-tmpfiles[1724]: ACLs are not supported, ignoring. Oct 13 05:49:18.095400 systemd-tmpfiles[1724]: Detected autofs mount point /boot during canonicalization of boot. Oct 13 05:49:18.095417 systemd-tmpfiles[1724]: Skipping /boot Oct 13 05:49:18.103854 systemd-tmpfiles[1724]: Detected autofs mount point /boot during canonicalization of boot. Oct 13 05:49:18.103869 systemd-tmpfiles[1724]: Skipping /boot Oct 13 05:49:18.309864 systemd[1]: Reloading finished in 316 ms. Oct 13 05:49:18.365114 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 13 05:49:18.377981 systemd[1]: Starting audit-rules.service - Load Audit Rules... Oct 13 05:49:18.403814 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Oct 13 05:49:18.406600 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Oct 13 05:49:18.418710 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 13 05:49:18.421379 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Oct 13 05:49:18.424229 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 13 05:49:18.424443 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 13 05:49:18.425492 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 13 05:49:18.426784 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 13 05:49:18.428296 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 13 05:49:18.428869 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 13 05:49:18.428977 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). Oct 13 05:49:18.429072 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 13 05:49:18.433175 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 13 05:49:18.433390 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 13 05:49:18.434634 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 13 05:49:18.434831 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 13 05:49:18.435414 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 13 05:49:18.446056 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Oct 13 05:49:18.458205 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 13 05:49:18.458567 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 13 05:49:18.462007 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 13 05:49:18.464956 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 13 05:49:18.465164 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 13 05:49:18.466407 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 13 05:49:18.468992 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 13 05:49:18.470730 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 13 05:49:18.471170 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 13 05:49:18.471281 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). Oct 13 05:49:18.471391 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 13 05:49:18.472685 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 13 05:49:18.472869 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 13 05:49:18.477487 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 13 05:49:18.477667 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 13 05:49:18.478445 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 13 05:49:18.478614 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 13 05:49:18.481955 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 13 05:49:18.482235 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 13 05:49:18.483457 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 13 05:49:18.485133 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 13 05:49:18.485816 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 13 05:49:18.485862 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). Oct 13 05:49:18.485910 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 13 05:49:18.485956 systemd[1]: Reached target time-set.target - System Time Set. Oct 13 05:49:18.486501 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 13 05:49:18.486952 systemd[1]: Finished ensure-sysext.service. Oct 13 05:49:18.490014 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 13 05:49:18.490199 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 13 05:49:18.490578 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 13 05:49:18.492257 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 13 05:49:18.492453 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 13 05:49:18.626875 systemd-resolved[1819]: Positive Trust Anchors: Oct 13 05:49:18.626893 systemd-resolved[1819]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 13 05:49:18.626925 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 Oct 13 05:49:18.694836 systemd-resolved[1819]: Using system hostname 'ci-4459.1.0-a-55d8ca4053'. Oct 13 05:49:18.696721 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 13 05:49:18.698509 systemd[1]: Reached target network.target - Network. Oct 13 05:49:18.699156 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 13 05:49:18.783866 augenrules[1859]: No rules Oct 13 05:49:18.785096 systemd[1]: audit-rules.service: Deactivated successfully. Oct 13 05:49:18.785363 systemd[1]: Finished audit-rules.service - Load Audit Rules. Oct 13 05:49:18.795473 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Oct 13 05:49:18.913536 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 13 05:49:19.242466 systemd-networkd[1586]: eth0: Gained IPv6LL Oct 13 05:49:19.244792 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Oct 13 05:49:19.250636 systemd[1]: Reached target network-online.target - Network is Online. Oct 13 05:49:21.558801 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Oct 13 05:49:21.568379 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). Oct 13 05:49:27.260556 ldconfig[1532]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 13 05:49:27.269981 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Oct 13 05:49:27.276674 systemd[1]: Starting systemd-update-done.service - Update is Completed... Oct 13 05:49:27.320060 systemd[1]: Finished systemd-update-done.service - Update is Completed. Oct 13 05:49:27.325029 systemd[1]: Reached target sysinit.target - System Initialization. Oct 13 05:49:27.329552 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Oct 13 05:49:27.334729 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 13 05:49:27.340083 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Oct 13 05:49:27.345418 systemd[1]: Started logrotate.timer - Daily rotation of log files. Oct 13 05:49:27.351293 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Oct 13 05:49:27.356772 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Oct 13 05:49:27.362208 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Oct 13 05:49:27.362294 systemd[1]: Reached target paths.target - Path Units. Oct 13 05:49:27.366183 systemd[1]: Reached target timers.target - Timer Units. Oct 13 05:49:27.419435 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Oct 13 05:49:27.425706 systemd[1]: Starting docker.socket - Docker Socket for the API... Oct 13 05:49:27.432154 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Oct 13 05:49:27.437661 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Oct 13 05:49:27.442927 systemd[1]: Reached target ssh-access.target - SSH Access Available. Oct 13 05:49:27.449579 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Oct 13 05:49:27.454249 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Oct 13 05:49:27.460012 systemd[1]: Listening on docker.socket - Docker Socket for the API. Oct 13 05:49:27.465870 systemd[1]: Reached target sockets.target - Socket Units. Oct 13 05:49:27.469980 systemd[1]: Reached target basic.target - Basic System. Oct 13 05:49:27.473969 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Oct 13 05:49:27.474001 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Oct 13 05:49:27.488872 systemd[1]: Starting chronyd.service - NTP client/server... Oct 13 05:49:27.517291 systemd[1]: Starting containerd.service - containerd container runtime... Oct 13 05:49:27.522941 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Oct 13 05:49:27.528572 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Oct 13 05:49:27.533966 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Oct 13 05:49:27.539754 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Oct 13 05:49:27.547930 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Oct 13 05:49:27.552217 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). Oct 13 05:49:27.553606 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Oct 13 05:49:27.560611 systemd[1]: hv_fcopy_uio_daemon.service - Hyper-V FCOPY UIO daemon was skipped because of an unmet condition check (ConditionPathExists=/sys/bus/vmbus/devices/eb765408-105f-49b6-b4aa-c123b64d17d4/uio). Oct 13 05:49:27.561770 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Oct 13 05:49:27.565574 jq[1884]: false Oct 13 05:49:27.566103 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Oct 13 05:49:27.567161 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Oct 13 05:49:27.572316 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Oct 13 05:49:27.577311 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Oct 13 05:49:27.583252 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Oct 13 05:49:27.590050 systemd[1]: Starting systemd-logind.service - User Login Management... Oct 13 05:49:27.595279 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Oct 13 05:49:27.595743 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 13 05:49:27.596311 systemd[1]: Starting update-engine.service - Update Engine... Oct 13 05:49:27.601107 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Oct 13 05:49:27.608439 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Oct 13 05:49:27.609870 extend-filesystems[1885]: Found /dev/sda6 Oct 13 05:49:27.614019 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Oct 13 05:49:27.614496 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Oct 13 05:49:27.614795 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 13 05:49:27.615006 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Oct 13 05:49:27.615884 jq[1900]: true Oct 13 05:49:27.626506 jq[1907]: true Oct 13 05:49:27.626767 extend-filesystems[1885]: Found /dev/sda9 Oct 13 05:49:27.629640 systemd[1]: motdgen.service: Deactivated successfully. Oct 13 05:49:27.629153 KVP[1890]: KVP starting; pid is:1890 Oct 13 05:49:27.629942 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Oct 13 05:49:27.631435 KVP[1890]: KVP LIC Version: 3.1 Oct 13 05:49:27.630527 (ntainerd)[1916]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Oct 13 05:49:27.633318 chronyd[1876]: chronyd version 4.7 starting (+CMDMON +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +NTS +SECHASH +IPV6 -DEBUG) Oct 13 05:49:27.634625 kernel: hv_utils: KVP IC version 4.0 Oct 13 05:49:27.637535 extend-filesystems[1885]: Checking size of /dev/sda9 Oct 13 05:49:27.692290 chronyd[1876]: Timezone right/UTC failed leap second check, ignoring Oct 13 05:49:27.692510 chronyd[1876]: Loaded seccomp filter (level 2) Oct 13 05:49:27.692664 systemd[1]: Started chronyd.service - NTP client/server. Oct 13 05:49:27.701312 google_oslogin_nss_cache[1886]: oslogin_cache_refresh[1886]: Refreshing passwd entry cache Oct 13 05:49:27.701313 oslogin_cache_refresh[1886]: Refreshing passwd entry cache Oct 13 05:49:27.702533 extend-filesystems[1885]: Old size kept for /dev/sda9 Oct 13 05:49:27.708008 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 13 05:49:27.708258 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Oct 13 05:49:27.709273 oslogin_cache_refresh[1886]: Failure getting users, quitting Oct 13 05:49:27.710199 google_oslogin_nss_cache[1886]: oslogin_cache_refresh[1886]: Failure getting users, quitting Oct 13 05:49:27.710199 google_oslogin_nss_cache[1886]: oslogin_cache_refresh[1886]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Oct 13 05:49:27.709293 oslogin_cache_refresh[1886]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Oct 13 05:49:27.712112 google_oslogin_nss_cache[1886]: oslogin_cache_refresh[1886]: Refreshing group entry cache Oct 13 05:49:27.712107 oslogin_cache_refresh[1886]: Refreshing group entry cache Oct 13 05:49:27.717341 google_oslogin_nss_cache[1886]: oslogin_cache_refresh[1886]: Failure getting groups, quitting Oct 13 05:49:27.717341 google_oslogin_nss_cache[1886]: oslogin_cache_refresh[1886]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Oct 13 05:49:27.717336 oslogin_cache_refresh[1886]: Failure getting groups, quitting Oct 13 05:49:27.717426 oslogin_cache_refresh[1886]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Oct 13 05:49:27.719032 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Oct 13 05:49:27.719290 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Oct 13 05:49:27.730253 update_engine[1899]: I20251013 05:49:27.730172 1899 main.cc:92] Flatcar Update Engine starting Oct 13 05:49:27.744674 systemd-logind[1897]: New seat seat0. Oct 13 05:49:27.745836 systemd-logind[1897]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Oct 13 05:49:27.746046 systemd[1]: Started systemd-logind.service - User Login Management. Oct 13 05:49:27.824776 sshd_keygen[1936]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Oct 13 05:49:27.846588 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Oct 13 05:49:27.853226 systemd[1]: Starting issuegen.service - Generate /run/issue... Oct 13 05:49:27.857947 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Oct 13 05:49:27.871411 bash[1935]: Updated "/home/core/.ssh/authorized_keys" Oct 13 05:49:27.875520 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Oct 13 05:49:27.881815 systemd[1]: issuegen.service: Deactivated successfully. Oct 13 05:49:27.882044 systemd[1]: Finished issuegen.service - Generate /run/issue. Oct 13 05:49:27.888682 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Oct 13 05:49:27.889815 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Oct 13 05:49:27.897554 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Oct 13 05:49:27.913147 setup-nvidia[1892]: Downloading Flatcar Container Linux Developer Container for version: 4459.1.0 Oct 13 05:49:27.914557 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Oct 13 05:49:27.922858 systemd[1]: Started getty@tty1.service - Getty on tty1. Oct 13 05:49:27.927871 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Oct 13 05:49:27.932654 systemd[1]: Reached target getty.target - Login Prompts. Oct 13 05:49:27.986039 dbus-daemon[1879]: [system] SELinux support is enabled Oct 13 05:49:27.986270 systemd[1]: Started dbus.service - D-Bus System Message Bus. Oct 13 05:49:27.990276 update_engine[1899]: I20251013 05:49:27.990224 1899 update_check_scheduler.cc:74] Next update check in 7m33s Oct 13 05:49:27.993925 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). Oct 13 05:49:27.993955 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Oct 13 05:49:27.995304 dbus-daemon[1879]: [system] Successfully activated service 'org.freedesktop.systemd1' Oct 13 05:49:27.999346 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). Oct 13 05:49:27.999371 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Oct 13 05:49:28.004592 systemd[1]: Started update-engine.service - Update Engine. Oct 13 05:49:28.010403 systemd[1]: Started locksmithd.service - Cluster reboot manager. Oct 13 05:49:28.088901 coreos-metadata[1878]: Oct 13 05:49:28.088 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Oct 13 05:49:28.091532 coreos-metadata[1878]: Oct 13 05:49:28.091 INFO Fetch successful Oct 13 05:49:28.091690 coreos-metadata[1878]: Oct 13 05:49:28.091 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Oct 13 05:49:28.096353 coreos-metadata[1878]: Oct 13 05:49:28.096 INFO Fetch successful Oct 13 05:49:28.096648 coreos-metadata[1878]: Oct 13 05:49:28.096 INFO Fetching http://168.63.129.16/machine/16b9b3b9-45e1-4e76-8a7a-a091031682ea/d906faef%2Dedb4%2D4090%2D984a%2Dc32a66fb354a.%5Fci%2D4459.1.0%2Da%2D55d8ca4053?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Oct 13 05:49:28.098271 coreos-metadata[1878]: Oct 13 05:49:28.098 INFO Fetch successful Oct 13 05:49:28.098423 coreos-metadata[1878]: Oct 13 05:49:28.098 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Oct 13 05:49:28.105309 coreos-metadata[1878]: Oct 13 05:49:28.105 INFO Fetch successful Oct 13 05:49:28.130147 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Oct 13 05:49:28.135196 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Oct 13 05:49:28.360992 locksmithd[2045]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 13 05:49:28.697979 setup-nvidia[2060]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 13 05:49:28.698357 setup-nvidia[2060]: Dload Upload Total Spent Left Speed Oct 13 05:49:29.162468 containerd[1916]: time="2025-10-13T05:49:29Z" 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 Oct 13 05:49:29.163399 containerd[1916]: time="2025-10-13T05:49:29.163364400Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Oct 13 05:49:29.173849 containerd[1916]: time="2025-10-13T05:49:29.173806200Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="8.6µs" Oct 13 05:49:29.173849 containerd[1916]: time="2025-10-13T05:49:29.173841700Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Oct 13 05:49:29.173960 containerd[1916]: time="2025-10-13T05:49:29.173861100Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Oct 13 05:49:29.174153 containerd[1916]: time="2025-10-13T05:49:29.174014100Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Oct 13 05:49:29.174153 containerd[1916]: time="2025-10-13T05:49:29.174036100Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Oct 13 05:49:29.174153 containerd[1916]: time="2025-10-13T05:49:29.174061100Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Oct 13 05:49:29.174153 containerd[1916]: time="2025-10-13T05:49:29.174123100Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Oct 13 05:49:29.174153 containerd[1916]: time="2025-10-13T05:49:29.174137500Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Oct 13 05:49:29.174421 containerd[1916]: time="2025-10-13T05:49:29.174396400Z" 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 Oct 13 05:49:29.174421 containerd[1916]: time="2025-10-13T05:49:29.174417500Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Oct 13 05:49:29.174507 containerd[1916]: time="2025-10-13T05:49:29.174440600Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Oct 13 05:49:29.174507 containerd[1916]: time="2025-10-13T05:49:29.174453300Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Oct 13 05:49:29.174607 containerd[1916]: time="2025-10-13T05:49:29.174583400Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Oct 13 05:49:29.174878 containerd[1916]: time="2025-10-13T05:49:29.174856700Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Oct 13 05:49:29.174878 containerd[1916]: time="2025-10-13T05:49:29.174895800Z" 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 Oct 13 05:49:29.174878 containerd[1916]: time="2025-10-13T05:49:29.174906800Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Oct 13 05:49:29.174878 containerd[1916]: time="2025-10-13T05:49:29.174936700Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Oct 13 05:49:29.175188 containerd[1916]: time="2025-10-13T05:49:29.175161700Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Oct 13 05:49:29.175249 containerd[1916]: time="2025-10-13T05:49:29.175230700Z" level=info msg="metadata content store policy set" policy=shared Oct 13 05:49:29.189421 containerd[1916]: time="2025-10-13T05:49:29.189377800Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Oct 13 05:49:29.189421 containerd[1916]: time="2025-10-13T05:49:29.189424900Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Oct 13 05:49:29.189533 containerd[1916]: time="2025-10-13T05:49:29.189439400Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Oct 13 05:49:29.189533 containerd[1916]: time="2025-10-13T05:49:29.189452600Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Oct 13 05:49:29.189533 containerd[1916]: time="2025-10-13T05:49:29.189464300Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Oct 13 05:49:29.189533 containerd[1916]: time="2025-10-13T05:49:29.189476900Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Oct 13 05:49:29.189533 containerd[1916]: time="2025-10-13T05:49:29.189487500Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Oct 13 05:49:29.189533 containerd[1916]: time="2025-10-13T05:49:29.189498900Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Oct 13 05:49:29.189533 containerd[1916]: time="2025-10-13T05:49:29.189509400Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Oct 13 05:49:29.189533 containerd[1916]: time="2025-10-13T05:49:29.189520200Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Oct 13 05:49:29.189533 containerd[1916]: time="2025-10-13T05:49:29.189529700Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Oct 13 05:49:29.189727 containerd[1916]: time="2025-10-13T05:49:29.189543100Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Oct 13 05:49:29.189727 containerd[1916]: time="2025-10-13T05:49:29.189654400Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Oct 13 05:49:29.189727 containerd[1916]: time="2025-10-13T05:49:29.189676600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Oct 13 05:49:29.189727 containerd[1916]: time="2025-10-13T05:49:29.189692300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Oct 13 05:49:29.189727 containerd[1916]: time="2025-10-13T05:49:29.189702700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Oct 13 05:49:29.189825 containerd[1916]: time="2025-10-13T05:49:29.189728200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Oct 13 05:49:29.189825 containerd[1916]: time="2025-10-13T05:49:29.189745200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Oct 13 05:49:29.189825 containerd[1916]: time="2025-10-13T05:49:29.189756100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Oct 13 05:49:29.189825 containerd[1916]: time="2025-10-13T05:49:29.189768000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Oct 13 05:49:29.189825 containerd[1916]: time="2025-10-13T05:49:29.189778400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Oct 13 05:49:29.189825 containerd[1916]: time="2025-10-13T05:49:29.189788700Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Oct 13 05:49:29.189825 containerd[1916]: time="2025-10-13T05:49:29.189798200Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Oct 13 05:49:29.189972 containerd[1916]: time="2025-10-13T05:49:29.189857700Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Oct 13 05:49:29.189972 containerd[1916]: time="2025-10-13T05:49:29.189872800Z" level=info msg="Start snapshots syncer" Oct 13 05:49:29.189972 containerd[1916]: time="2025-10-13T05:49:29.189912200Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Oct 13 05:49:29.190187 containerd[1916]: time="2025-10-13T05:49:29.190145800Z" 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\"}" Oct 13 05:49:29.190304 containerd[1916]: time="2025-10-13T05:49:29.190206700Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Oct 13 05:49:29.190304 containerd[1916]: time="2025-10-13T05:49:29.190273400Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Oct 13 05:49:29.190418 containerd[1916]: time="2025-10-13T05:49:29.190393900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Oct 13 05:49:29.190458 containerd[1916]: time="2025-10-13T05:49:29.190422300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Oct 13 05:49:29.190458 containerd[1916]: time="2025-10-13T05:49:29.190433800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Oct 13 05:49:29.190458 containerd[1916]: time="2025-10-13T05:49:29.190446300Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Oct 13 05:49:29.190520 containerd[1916]: time="2025-10-13T05:49:29.190458300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Oct 13 05:49:29.190520 containerd[1916]: time="2025-10-13T05:49:29.190468900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Oct 13 05:49:29.190520 containerd[1916]: time="2025-10-13T05:49:29.190484800Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Oct 13 05:49:29.190520 containerd[1916]: time="2025-10-13T05:49:29.190507800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Oct 13 05:49:29.190653 containerd[1916]: time="2025-10-13T05:49:29.190520100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Oct 13 05:49:29.190653 containerd[1916]: time="2025-10-13T05:49:29.190542600Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Oct 13 05:49:29.190653 containerd[1916]: time="2025-10-13T05:49:29.190584000Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Oct 13 05:49:29.190653 containerd[1916]: time="2025-10-13T05:49:29.190602000Z" 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 Oct 13 05:49:29.190653 containerd[1916]: time="2025-10-13T05:49:29.190611300Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Oct 13 05:49:29.190653 containerd[1916]: time="2025-10-13T05:49:29.190620200Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Oct 13 05:49:29.190653 containerd[1916]: time="2025-10-13T05:49:29.190627900Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Oct 13 05:49:29.190803 containerd[1916]: time="2025-10-13T05:49:29.190660500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Oct 13 05:49:29.190803 containerd[1916]: time="2025-10-13T05:49:29.190674600Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Oct 13 05:49:29.190803 containerd[1916]: time="2025-10-13T05:49:29.190691200Z" level=info msg="runtime interface created" Oct 13 05:49:29.190803 containerd[1916]: time="2025-10-13T05:49:29.190696600Z" level=info msg="created NRI interface" Oct 13 05:49:29.190803 containerd[1916]: time="2025-10-13T05:49:29.190703800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Oct 13 05:49:29.190803 containerd[1916]: time="2025-10-13T05:49:29.190714800Z" level=info msg="Connect containerd service" Oct 13 05:49:29.190803 containerd[1916]: time="2025-10-13T05:49:29.190754200Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Oct 13 05:49:29.191524 containerd[1916]: time="2025-10-13T05:49:29.191496300Z" 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" Oct 13 05:49:29.810550 containerd[1916]: time="2025-10-13T05:49:29.810504800Z" level=info msg="Start subscribing containerd event" Oct 13 05:49:29.810709 containerd[1916]: time="2025-10-13T05:49:29.810561900Z" level=info msg="Start recovering state" Oct 13 05:49:29.810774 containerd[1916]: time="2025-10-13T05:49:29.810751400Z" level=info msg="Start event monitor" Oct 13 05:49:29.810804 containerd[1916]: time="2025-10-13T05:49:29.810756500Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Oct 13 05:49:29.810857 containerd[1916]: time="2025-10-13T05:49:29.810838400Z" level=info msg=serving... address=/run/containerd/containerd.sock Oct 13 05:49:29.810896 containerd[1916]: time="2025-10-13T05:49:29.810781700Z" level=info msg="Start cni network conf syncer for default" Oct 13 05:49:29.810924 containerd[1916]: time="2025-10-13T05:49:29.810901300Z" level=info msg="Start streaming server" Oct 13 05:49:29.810924 containerd[1916]: time="2025-10-13T05:49:29.810918800Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Oct 13 05:49:29.810971 containerd[1916]: time="2025-10-13T05:49:29.810926500Z" level=info msg="runtime interface starting up..." Oct 13 05:49:29.810971 containerd[1916]: time="2025-10-13T05:49:29.810932000Z" level=info msg="starting plugins..." Oct 13 05:49:29.810971 containerd[1916]: time="2025-10-13T05:49:29.810948900Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Oct 13 05:49:29.811112 containerd[1916]: time="2025-10-13T05:49:29.811093400Z" level=info msg="containerd successfully booted in 0.649634s" Oct 13 05:49:29.811214 systemd[1]: Started containerd.service - containerd container runtime. Oct 13 05:49:30.990083 waagent[2033]: 2025-10-13T05:49:30.989989Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Oct 13 05:49:30.994519 waagent[2033]: 2025-10-13T05:49:30.994474Z INFO Daemon Daemon OS: flatcar 4459.1.0 Oct 13 05:49:30.998017 waagent[2033]: 2025-10-13T05:49:30.997973Z INFO Daemon Daemon Python: 3.11.13 Oct 13 05:49:31.001434 waagent[2033]: 2025-10-13T05:49:31.001390Z INFO Daemon Daemon Run daemon Oct 13 05:49:31.004569 waagent[2033]: 2025-10-13T05:49:31.004527Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4459.1.0' Oct 13 05:49:31.011409 waagent[2033]: 2025-10-13T05:49:31.011360Z INFO Daemon Daemon Using waagent for provisioning Oct 13 05:49:31.016550 waagent[2033]: 2025-10-13T05:49:31.016504Z INFO Daemon Daemon Activate resource disk Oct 13 05:49:31.020080 waagent[2033]: 2025-10-13T05:49:31.020029Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Oct 13 05:49:31.028926 waagent[2033]: 2025-10-13T05:49:31.028861Z INFO Daemon Daemon Found device: sdb Oct 13 05:49:31.038635 waagent[2033]: 2025-10-13T05:49:31.038582Z INFO Daemon Daemon Examining partition table Oct 13 05:49:31.108686 waagent[2033]: 2025-10-13T05:49:31.108596Z INFO Daemon Daemon GPT not detected, determining filesystem Oct 13 05:49:31.186516 waagent[2033]: 2025-10-13T05:49:31.186442Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Oct 13 05:49:31.191337 waagent[2033]: 2025-10-13T05:49:31.191277Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Oct 13 05:49:31.697176 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#545 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 13 05:49:31.715091 kernel: sdb: sdb1 Oct 13 05:49:31.739593 waagent[2033]: 2025-10-13T05:49:31.739514Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Oct 13 05:49:31.758357 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#203 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 13 05:49:31.769340 kernel: sdb: sdb1 Oct 13 05:49:31.769732 waagent[2033]: 2025-10-13T05:49:31.769666Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Oct 13 05:49:35.000639 login[2041]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Oct 13 05:49:35.002378 login[2042]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Oct 13 05:49:35.008841 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Oct 13 05:49:35.009894 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Oct 13 05:49:35.016359 systemd-logind[1897]: New session 1 of user core. Oct 13 05:49:35.075049 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Oct 13 05:49:35.077946 systemd[1]: Starting user@500.service - User Manager for UID 500... Oct 13 05:49:35.140535 (systemd)[2148]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 13 05:49:35.143008 systemd-logind[1897]: New session c1 of user core. Oct 13 05:49:35.600320 systemd[2148]: Queued start job for default target default.target. Oct 13 05:49:35.618548 systemd[2148]: Created slice app.slice - User Application Slice. Oct 13 05:49:35.618582 systemd[2148]: Reached target paths.target - Paths. Oct 13 05:49:35.618633 systemd[2148]: Reached target timers.target - Timers. Oct 13 05:49:35.619993 systemd[2148]: Starting dbus.socket - D-Bus User Message Bus Socket... Oct 13 05:49:35.638781 systemd[2148]: Listening on dbus.socket - D-Bus User Message Bus Socket. Oct 13 05:49:35.638925 systemd[2148]: Reached target sockets.target - Sockets. Oct 13 05:49:35.638984 systemd[2148]: Reached target basic.target - Basic System. Oct 13 05:49:35.639029 systemd[2148]: Reached target default.target - Main User Target. Oct 13 05:49:35.639063 systemd[2148]: Startup finished in 489ms. Oct 13 05:49:35.639140 systemd[1]: Started user@500.service - User Manager for UID 500. Oct 13 05:49:35.640670 systemd[1]: Started session-1.scope - Session 1 of User core. Oct 13 05:49:36.001119 login[2041]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Oct 13 05:49:36.005768 systemd-logind[1897]: New session 2 of user core. Oct 13 05:49:36.016568 systemd[1]: Started session-2.scope - Session 2 of User core. Oct 13 05:49:37.134260 waagent[2033]: 2025-10-13T05:49:37.134178Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Oct 13 05:49:37.274434 kernel: EXT4-fs (sdb1): mounted filesystem 8fb23865-0ec2-4d78-93b1-6c28bdfd5109 r/w with ordered data mode. Quota mode: none. Oct 13 05:49:37.274734 waagent[2033]: 2025-10-13T05:49:37.274657Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Oct 13 05:49:37.276512 waagent[2033]: 2025-10-13T05:49:37.276461Z INFO Daemon Daemon Clean protocol and wireserver endpoint Oct 13 05:49:37.276723 waagent[2033]: 2025-10-13T05:49:37.276671Z INFO Daemon Daemon Running default provisioning handler Oct 13 05:49:37.283762 waagent[2033]: 2025-10-13T05:49:37.283697Z 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. Oct 13 05:49:37.285955 waagent[2033]: 2025-10-13T05:49:37.285905Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Oct 13 05:49:37.287504 waagent[2033]: 2025-10-13T05:49:37.287466Z INFO Daemon Daemon cloud-init is enabled: False Oct 13 05:49:37.288285 waagent[2033]: 2025-10-13T05:49:37.288253Z INFO Daemon Daemon Copying ovf-env.xml Oct 13 05:49:37.416488 waagent[2033]: 2025-10-13T05:49:37.416355Z INFO Daemon Daemon Successfully mounted dvd Oct 13 05:49:37.448912 waagent[2033]: 2025-10-13T05:49:37.448838Z INFO Daemon Daemon Detect protocol endpoint Oct 13 05:49:37.448916 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Oct 13 05:49:37.450786 waagent[2033]: 2025-10-13T05:49:37.450728Z INFO Daemon Daemon Clean protocol and wireserver endpoint Oct 13 05:49:37.452265 waagent[2033]: 2025-10-13T05:49:37.452227Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Oct 13 05:49:37.453733 waagent[2033]: 2025-10-13T05:49:37.453695Z INFO Daemon Daemon Test for route to 168.63.129.16 Oct 13 05:49:37.455481 waagent[2033]: 2025-10-13T05:49:37.455426Z INFO Daemon Daemon Route to 168.63.129.16 exists Oct 13 05:49:37.456061 waagent[2033]: 2025-10-13T05:49:37.456024Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Oct 13 05:49:37.485282 waagent[2033]: 2025-10-13T05:49:37.485222Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Oct 13 05:49:37.487520 waagent[2033]: 2025-10-13T05:49:37.487477Z INFO Daemon Daemon Wire protocol version:2012-11-30 Oct 13 05:49:37.488115 waagent[2033]: 2025-10-13T05:49:37.488079Z INFO Daemon Daemon Server preferred version:2015-04-05 Oct 13 05:49:37.633021 waagent[2033]: 2025-10-13T05:49:37.632925Z INFO Daemon Daemon Initializing goal state during protocol detection Oct 13 05:49:37.634835 waagent[2033]: 2025-10-13T05:49:37.634768Z INFO Daemon Daemon Forcing an update of the goal state. Oct 13 05:49:37.639761 waagent[2033]: 2025-10-13T05:49:37.639705Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Oct 13 05:49:37.673766 waagent[2033]: 2025-10-13T05:49:37.673627Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.177 Oct 13 05:49:37.675986 waagent[2033]: 2025-10-13T05:49:37.675928Z INFO Daemon Oct 13 05:49:37.676257 waagent[2033]: 2025-10-13T05:49:37.676219Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 8d4b758a-f4f4-4597-86af-c551164651b0 eTag: 18170425488510512329 source: Fabric] Oct 13 05:49:37.678050 waagent[2033]: 2025-10-13T05:49:37.678003Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Oct 13 05:49:37.679623 waagent[2033]: 2025-10-13T05:49:37.679576Z INFO Daemon Oct 13 05:49:37.680122 waagent[2033]: 2025-10-13T05:49:37.680086Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Oct 13 05:49:37.685489 waagent[2033]: 2025-10-13T05:49:37.685437Z INFO Daemon Daemon Downloading artifacts profile blob Oct 13 05:49:37.768906 waagent[2033]: 2025-10-13T05:49:37.768815Z INFO Daemon Downloaded certificate {'thumbprint': '3FF630A06556ADFE2EB99F846AFBCC73566A1DD0', 'hasPrivateKey': True} Oct 13 05:49:37.770999 waagent[2033]: 2025-10-13T05:49:37.770949Z INFO Daemon Fetch goal state completed Oct 13 05:49:37.778562 waagent[2033]: 2025-10-13T05:49:37.778506Z INFO Daemon Daemon Starting provisioning Oct 13 05:49:37.779095 waagent[2033]: 2025-10-13T05:49:37.779048Z INFO Daemon Daemon Handle ovf-env.xml. Oct 13 05:49:37.780655 waagent[2033]: 2025-10-13T05:49:37.780594Z INFO Daemon Daemon Set hostname [ci-4459.1.0-a-55d8ca4053] Oct 13 05:49:37.836714 waagent[2033]: 2025-10-13T05:49:37.836639Z INFO Daemon Daemon Publish hostname [ci-4459.1.0-a-55d8ca4053] Oct 13 05:49:37.839131 waagent[2033]: 2025-10-13T05:49:37.839072Z INFO Daemon Daemon Examine /proc/net/route for primary interface Oct 13 05:49:37.840671 waagent[2033]: 2025-10-13T05:49:37.840624Z INFO Daemon Daemon Primary interface is [eth0] Oct 13 05:49:37.852757 systemd-networkd[1586]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 13 05:49:37.852769 systemd-networkd[1586]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 13 05:49:37.852807 systemd-networkd[1586]: eth0: DHCP lease lost Oct 13 05:49:37.853439 waagent[2033]: 2025-10-13T05:49:37.853375Z INFO Daemon Daemon Create user account if not exists Oct 13 05:49:37.855203 waagent[2033]: 2025-10-13T05:49:37.855165Z INFO Daemon Daemon User core already exists, skip useradd Oct 13 05:49:37.856645 waagent[2033]: 2025-10-13T05:49:37.856612Z INFO Daemon Daemon Configure sudoer Oct 13 05:49:37.873322 waagent[2033]: 2025-10-13T05:49:37.873237Z INFO Daemon Daemon Configure sshd Oct 13 05:49:37.878116 waagent[2033]: 2025-10-13T05:49:37.878066Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Oct 13 05:49:37.879786 waagent[2033]: 2025-10-13T05:49:37.879738Z INFO Daemon Daemon Deploy ssh public key. Oct 13 05:49:37.940369 systemd-networkd[1586]: eth0: DHCPv4 address 10.200.4.17/24, gateway 10.200.4.1 acquired from 168.63.129.16 Oct 13 05:49:39.016397 waagent[2033]: 2025-10-13T05:49:39.016296Z INFO Daemon Daemon Provisioning complete Oct 13 05:49:39.026935 waagent[2033]: 2025-10-13T05:49:39.026885Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Oct 13 05:49:39.028605 waagent[2033]: 2025-10-13T05:49:39.028555Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Oct 13 05:49:39.029242 waagent[2033]: 2025-10-13T05:49:39.029202Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Oct 13 05:49:39.207971 waagent[2207]: 2025-10-13T05:49:39.207878Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Oct 13 05:49:39.208299 waagent[2207]: 2025-10-13T05:49:39.208034Z INFO ExtHandler ExtHandler OS: flatcar 4459.1.0 Oct 13 05:49:39.208299 waagent[2207]: 2025-10-13T05:49:39.208107Z INFO ExtHandler ExtHandler Python: 3.11.13 Oct 13 05:49:39.208299 waagent[2207]: 2025-10-13T05:49:39.208179Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Oct 13 05:49:39.362967 waagent[2207]: 2025-10-13T05:49:39.362828Z INFO ExtHandler ExtHandler Distro: flatcar-4459.1.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.13; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Oct 13 05:49:39.363136 waagent[2207]: 2025-10-13T05:49:39.363095Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Oct 13 05:49:39.363217 waagent[2207]: 2025-10-13T05:49:39.363181Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Oct 13 05:49:39.372090 waagent[2207]: 2025-10-13T05:49:39.372017Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Oct 13 05:49:39.379001 waagent[2207]: 2025-10-13T05:49:39.378945Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.177 Oct 13 05:49:39.379549 waagent[2207]: 2025-10-13T05:49:39.379509Z INFO ExtHandler Oct 13 05:49:39.379632 waagent[2207]: 2025-10-13T05:49:39.379600Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 373f9561-b5c1-41fc-9826-2103fbbcf059 eTag: 18170425488510512329 source: Fabric] Oct 13 05:49:39.379949 waagent[2207]: 2025-10-13T05:49:39.379911Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Oct 13 05:49:39.380639 waagent[2207]: 2025-10-13T05:49:39.380599Z INFO ExtHandler Oct 13 05:49:39.380715 waagent[2207]: 2025-10-13T05:49:39.380683Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Oct 13 05:49:39.385904 waagent[2207]: 2025-10-13T05:49:39.385854Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Oct 13 05:49:39.448187 waagent[2207]: 2025-10-13T05:49:39.448107Z INFO ExtHandler Downloaded certificate {'thumbprint': '3FF630A06556ADFE2EB99F846AFBCC73566A1DD0', 'hasPrivateKey': True} Oct 13 05:49:39.448756 waagent[2207]: 2025-10-13T05:49:39.448714Z INFO ExtHandler Fetch goal state completed Oct 13 05:49:39.461382 waagent[2207]: 2025-10-13T05:49:39.461307Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.4.2 1 Jul 2025 (Library: OpenSSL 3.4.2 1 Jul 2025) Oct 13 05:49:39.466654 waagent[2207]: 2025-10-13T05:49:39.466597Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2207 Oct 13 05:49:39.466792 waagent[2207]: 2025-10-13T05:49:39.466758Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Oct 13 05:49:39.467150 waagent[2207]: 2025-10-13T05:49:39.467113Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Oct 13 05:49:39.468876 waagent[2207]: 2025-10-13T05:49:39.468835Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4459.1.0', '', 'Flatcar Container Linux by Kinvolk'] Oct 13 05:49:39.469311 waagent[2207]: 2025-10-13T05:49:39.469271Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4459.1.0', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Oct 13 05:49:39.469486 waagent[2207]: 2025-10-13T05:49:39.469449Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Oct 13 05:49:39.470100 waagent[2207]: 2025-10-13T05:49:39.470060Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Oct 13 05:49:39.586774 waagent[2207]: 2025-10-13T05:49:39.586718Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Oct 13 05:49:39.586975 waagent[2207]: 2025-10-13T05:49:39.586937Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Oct 13 05:49:39.595447 waagent[2207]: 2025-10-13T05:49:39.595403Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Oct 13 05:49:39.602723 systemd[1]: Reload requested from client PID 2222 ('systemctl') (unit waagent.service)... Oct 13 05:49:39.602741 systemd[1]: Reloading... Oct 13 05:49:39.682359 zram_generator::config[2259]: No configuration found. Oct 13 05:49:39.979744 systemd[1]: Reloading finished in 376 ms. Oct 13 05:49:40.003172 waagent[2207]: 2025-10-13T05:49:40.003098Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Oct 13 05:49:40.003285 waagent[2207]: 2025-10-13T05:49:40.003253Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Oct 13 05:49:40.889772 waagent[2207]: 2025-10-13T05:49:40.889684Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Oct 13 05:49:40.890186 waagent[2207]: 2025-10-13T05:49:40.890030Z 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] Oct 13 05:49:40.890880 waagent[2207]: 2025-10-13T05:49:40.890828Z INFO ExtHandler ExtHandler Starting env monitor service. Oct 13 05:49:40.891279 waagent[2207]: 2025-10-13T05:49:40.891222Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Oct 13 05:49:40.891508 waagent[2207]: 2025-10-13T05:49:40.891468Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Oct 13 05:49:40.891553 waagent[2207]: 2025-10-13T05:49:40.891511Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Oct 13 05:49:40.892059 waagent[2207]: 2025-10-13T05:49:40.892013Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Oct 13 05:49:40.892250 waagent[2207]: 2025-10-13T05:49:40.892206Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Oct 13 05:49:40.892374 waagent[2207]: 2025-10-13T05:49:40.892307Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Oct 13 05:49:40.892443 waagent[2207]: 2025-10-13T05:49:40.892402Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Oct 13 05:49:40.892814 waagent[2207]: 2025-10-13T05:49:40.892778Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Oct 13 05:49:40.893143 waagent[2207]: 2025-10-13T05:49:40.893082Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Oct 13 05:49:40.893341 waagent[2207]: 2025-10-13T05:49:40.893287Z INFO EnvHandler ExtHandler Configure routes Oct 13 05:49:40.893434 waagent[2207]: 2025-10-13T05:49:40.893383Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Oct 13 05:49:40.893691 waagent[2207]: 2025-10-13T05:49:40.893652Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Oct 13 05:49:40.893816 waagent[2207]: 2025-10-13T05:49:40.893772Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Oct 13 05:49:40.893816 waagent[2207]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Oct 13 05:49:40.893816 waagent[2207]: eth0 00000000 0104C80A 0003 0 0 1024 00000000 0 0 0 Oct 13 05:49:40.893816 waagent[2207]: eth0 0004C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Oct 13 05:49:40.893816 waagent[2207]: eth0 0104C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Oct 13 05:49:40.893816 waagent[2207]: eth0 10813FA8 0104C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Oct 13 05:49:40.893816 waagent[2207]: eth0 FEA9FEA9 0104C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Oct 13 05:49:40.894158 waagent[2207]: 2025-10-13T05:49:40.894121Z INFO EnvHandler ExtHandler Gateway:None Oct 13 05:49:40.895845 waagent[2207]: 2025-10-13T05:49:40.895794Z INFO EnvHandler ExtHandler Routes:None Oct 13 05:49:40.900761 waagent[2207]: 2025-10-13T05:49:40.900715Z INFO ExtHandler ExtHandler Oct 13 05:49:40.901018 waagent[2207]: 2025-10-13T05:49:40.900981Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 041c0916-6995-4cca-8a46-f7d84f0f977c correlation 3b0e852a-87fe-44bf-8489-e08fd63ded6c created: 2025-10-13T05:47:46.423256Z] Oct 13 05:49:40.902146 waagent[2207]: 2025-10-13T05:49:40.902101Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Oct 13 05:49:40.904115 waagent[2207]: 2025-10-13T05:49:40.904074Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Oct 13 05:49:40.947505 waagent[2207]: 2025-10-13T05:49:40.947430Z 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 Oct 13 05:49:40.947505 waagent[2207]: Try `iptables -h' or 'iptables --help' for more information.) Oct 13 05:49:40.947954 waagent[2207]: 2025-10-13T05:49:40.947915Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: FB7EF9AD-DD59-45FB-B064-723096F88CC1;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Oct 13 05:49:40.981980 waagent[2207]: 2025-10-13T05:49:40.981908Z INFO MonitorHandler ExtHandler Network interfaces: Oct 13 05:49:40.981980 waagent[2207]: Executing ['ip', '-a', '-o', 'link']: Oct 13 05:49:40.981980 waagent[2207]: 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 Oct 13 05:49:40.981980 waagent[2207]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:1e:52:78:cf:6e brd ff:ff:ff:ff:ff:ff Oct 13 05:49:40.981980 waagent[2207]: Executing ['ip', '-4', '-a', '-o', 'address']: Oct 13 05:49:40.981980 waagent[2207]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Oct 13 05:49:40.981980 waagent[2207]: 2: eth0 inet 10.200.4.17/24 metric 1024 brd 10.200.4.255 scope global eth0\ valid_lft forever preferred_lft forever Oct 13 05:49:40.981980 waagent[2207]: Executing ['ip', '-6', '-a', '-o', 'address']: Oct 13 05:49:40.981980 waagent[2207]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Oct 13 05:49:40.981980 waagent[2207]: 2: eth0 inet6 fe80::7e1e:52ff:fe78:cf6e/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Oct 13 05:49:41.116132 waagent[2207]: 2025-10-13T05:49:41.116062Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Oct 13 05:49:41.116132 waagent[2207]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Oct 13 05:49:41.116132 waagent[2207]: pkts bytes target prot opt in out source destination Oct 13 05:49:41.116132 waagent[2207]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Oct 13 05:49:41.116132 waagent[2207]: pkts bytes target prot opt in out source destination Oct 13 05:49:41.116132 waagent[2207]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Oct 13 05:49:41.116132 waagent[2207]: pkts bytes target prot opt in out source destination Oct 13 05:49:41.116132 waagent[2207]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Oct 13 05:49:41.116132 waagent[2207]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Oct 13 05:49:41.116132 waagent[2207]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Oct 13 05:49:41.119705 waagent[2207]: 2025-10-13T05:49:41.119659Z INFO EnvHandler ExtHandler Current Firewall rules: Oct 13 05:49:41.119705 waagent[2207]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Oct 13 05:49:41.119705 waagent[2207]: pkts bytes target prot opt in out source destination Oct 13 05:49:41.119705 waagent[2207]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Oct 13 05:49:41.119705 waagent[2207]: pkts bytes target prot opt in out source destination Oct 13 05:49:41.119705 waagent[2207]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Oct 13 05:49:41.119705 waagent[2207]: pkts bytes target prot opt in out source destination Oct 13 05:49:41.119705 waagent[2207]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Oct 13 05:49:41.119705 waagent[2207]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Oct 13 05:49:41.119705 waagent[2207]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Oct 13 05:49:41.119961 waagent[2207]: 2025-10-13T05:49:41.119926Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Oct 13 05:49:41.120108 waagent[2207]: 2025-10-13T05:49:41.120079Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Oct 13 05:49:51.426222 setup-nvidia[2060]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 473M 0 303k 0 0 285k 0 0:28:18 0:00:01 0:28:17 285k\u000d 2 473M 2 13.7M 0 0 7012k 0 0:01:09 0:00:02 0:01:07 7015k\u000d 7 473M 7 35.2M 0 0 11.7M 0 0:00:40 0:00:02 0:00:38 11.7M\u000d 12 473M 12 59.5M 0 0 14.7M 0 0:00:32 0:00:04 0:00:28 14.7M\u000d 17 473M 17 84.9M 0 0 16.5M 0 0:00:28 0:00:05 0:00:23 16.5M\u000d 22 473M 22 106M 0 0 17.7M 0 0:00:26 0:00:06 0:00:20 21.4M\u000d 27 473M 27 131M 0 0 18.6M 0 0:00:25 0:00:07 0:00:18 23.2M\u000d 32 473M 32 155M 0 0 19.4M 0 0:00:24 0:00:07 0:00:17 24.0M\u000d 37 473M 37 175M 0 0 19.4M 0 0:00:24 0:00:09 0:00:15 23.2M\u000d 41 473M 41 197M 0 0 19.5M 0 0:00:24 0:00:10 0:00:14 22.7M\u000d 46 473M 46 222M 0 0 19.9M 0 0:00:23 0:00:11 0:00:12 22.6M\u000d 51 473M 51 243M 0 0 20.2M 0 0:00:23 0:00:12 0:00:11 22.6M\u000d 53 473M 53 254M 0 0 19.5M 0 0:00:24 0:00:12 0:00:12 19.7M\u000d 57 473M 57 271M 0 0 19.3M 0 0:00:24 0:00:14 0:00:10 19.1M\u000d 62 473M 62 296M 0 0 19.6M 0 0:00:24 0:00:15 0:00:09 19.7M\u000d 67 473M 67 318M 0 0 19.9M 0 0:00:23 0:00:15 0:00:08 19.7M\u000d 72 473M 72 341M 0 0 20.0M 0 0:00:23 0:00:17 0:00:06 19.4M\u000d 77 473M 77 365M 0 0 20.2M 0 0:00:23 0:00:18 0:00:05 21.8M\u000d 81 473M 81 387M 0 0 20.4M 0 0:00:23 0:00:18 0:00:05 23.3M\u000d 86 473M 86 411M 0 0 20.5M 0 0:00:23 0:00:20 0:00:03 23.3M\u000d 92 473M 92 436M 0 0 20.6M 0 0:00:22 0:00:21 0:00:01 23.1M\u000d 96 473M 96 457M 0 0 20.8M 0 0:00:22 0:00:21 0:00:01 23.4M\u000d100 473M 100 473M 0 0 20.8M 0 0:00:22 0:00:22 --:--:-- 23.1M Oct 13 05:49:51.473164 chronyd[1876]: Selected source PHC0 Oct 13 05:50:02.363835 setup-nvidia[1892]: Downloading NVIDIA 535.261.03 Driver Oct 13 05:50:02.368221 setup-nvidia[2365]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 13 05:50:02.368474 setup-nvidia[2365]: Dload Upload Total Spent Left Speed Oct 13 05:50:03.266908 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Oct 13 05:50:03.269785 systemd[1]: Started sshd@0-10.200.4.17:22-10.200.16.10:33088.service - OpenSSH per-connection server daemon (10.200.16.10:33088). Oct 13 05:50:04.240644 sshd[2367]: Accepted publickey for core from 10.200.16.10 port 33088 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:50:04.241894 sshd-session[2367]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:50:04.246347 systemd-logind[1897]: New session 3 of user core. Oct 13 05:50:04.262482 systemd[1]: Started session-3.scope - Session 3 of User core. Oct 13 05:50:04.769037 systemd[1]: Started sshd@1-10.200.4.17:22-10.200.16.10:33092.service - OpenSSH per-connection server daemon (10.200.16.10:33092). Oct 13 05:50:05.381912 sshd[2373]: Accepted publickey for core from 10.200.16.10 port 33092 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:50:05.383158 sshd-session[2373]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:50:05.388080 systemd-logind[1897]: New session 4 of user core. Oct 13 05:50:05.398745 systemd[1]: Started session-4.scope - Session 4 of User core. Oct 13 05:50:05.490403 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Oct 13 05:50:05.811496 sshd[2376]: Connection closed by 10.200.16.10 port 33092 Oct 13 05:50:05.811867 sshd-session[2373]: pam_unix(sshd:session): session closed for user core Oct 13 05:50:05.815608 systemd[1]: sshd@1-10.200.4.17:22-10.200.16.10:33092.service: Deactivated successfully. Oct 13 05:50:05.817319 systemd[1]: session-4.scope: Deactivated successfully. Oct 13 05:50:05.818048 systemd-logind[1897]: Session 4 logged out. Waiting for processes to exit. Oct 13 05:50:05.818947 systemd-logind[1897]: Removed session 4. Oct 13 05:50:05.925729 systemd[1]: Started sshd@2-10.200.4.17:22-10.200.16.10:33108.service - OpenSSH per-connection server daemon (10.200.16.10:33108). Oct 13 05:50:06.555912 sshd[2382]: Accepted publickey for core from 10.200.16.10 port 33108 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:50:06.557163 sshd-session[2382]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:50:06.561451 systemd-logind[1897]: New session 5 of user core. Oct 13 05:50:06.580511 systemd[1]: Started session-5.scope - Session 5 of User core. Oct 13 05:50:06.983442 sshd[2385]: Connection closed by 10.200.16.10 port 33108 Oct 13 05:50:06.983813 sshd-session[2382]: pam_unix(sshd:session): session closed for user core Oct 13 05:50:06.987449 systemd[1]: sshd@2-10.200.4.17:22-10.200.16.10:33108.service: Deactivated successfully. Oct 13 05:50:06.989317 systemd[1]: session-5.scope: Deactivated successfully. Oct 13 05:50:06.990150 systemd-logind[1897]: Session 5 logged out. Waiting for processes to exit. Oct 13 05:50:06.991068 systemd-logind[1897]: Removed session 5. Oct 13 05:50:07.653681 setup-nvidia[2365]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 3 325M 3 12.0M 0 0 36.5M 0 0:00:08 --:--:-- 0:00:08 36.4M\u000d 23 325M 23 77.4M 0 0 58.9M 0 0:00:05 0:00:01 0:00:04 58.9M\u000d 42 325M 42 138M 0 0 59.6M 0 0:00:05 0:00:02 0:00:03 59.6M\u000d 61 325M 61 201M 0 0 60.9M 0 0:00:05 0:00:03 0:00:02 60.9M\u000d 80 325M 80 264M 0 0 61.1M 0 0:00:05 0:00:04 0:00:01 61.1M\u000d100 325M 100 325M 0 0 61.6M 0 0:00:05 0:00:05 --:--:-- 63.3M Oct 13 05:50:07.772781 setup-nvidia[1892]: Extract the NVIDIA Driver Installer 535.261.03 Oct 13 05:50:07.772781 setup-nvidia[1892]: /opt/nvidia/workdir/nvidia-workdir / Oct 13 05:50:07.852890 setup-nvidia[2392]: Creating directory NVIDIA-Linux-x86_64-535.261.03 Oct 13 05:50:08.135809 setup-nvidia[2392]: Verifying archive integrity... OK Oct 13 05:50:09.195408 setup-nvidia[2392]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.261.03 Oct 13 05:50:11.036851 setup-nvidia[2418]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Oct 13 05:50:11.106610 setup-nvidia[1892]: / Oct 13 05:50:11.106897 setup-nvidia[1892]: Spawn system-nspawn container to install the NVIDIA drivers Oct 13 05:50:11.345461 sudo[2425]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4459.1.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.261.03 Oct 13 05:50:11.347170 sudo[2425]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 13 05:50:11.354241 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Oct 13 05:50:11.357089 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Oct 13 05:50:11.382927 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Oct 13 05:50:11.387967 systemd[1]: Starting user@0.service - User Manager for UID 0... Oct 13 05:50:11.395154 (systemd)[2428]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Oct 13 05:50:11.397435 systemd-logind[1897]: New session c3 of user root. Oct 13 05:50:11.577149 systemd[2428]: Queued start job for default target default.target. Oct 13 05:50:11.595658 systemd[2428]: Created slice app.slice - User Application Slice. Oct 13 05:50:11.595690 systemd[2428]: Reached target paths.target - Paths. Oct 13 05:50:11.595739 systemd[2428]: Reached target timers.target - Timers. Oct 13 05:50:11.596977 systemd[2428]: Starting dbus.socket - D-Bus User Message Bus Socket... Oct 13 05:50:11.607847 systemd[2428]: Listening on dbus.socket - D-Bus User Message Bus Socket. Oct 13 05:50:11.607967 systemd[2428]: Reached target sockets.target - Sockets. Oct 13 05:50:11.608023 systemd[2428]: Reached target basic.target - Basic System. Oct 13 05:50:11.608067 systemd[2428]: Reached target default.target - Main User Target. Oct 13 05:50:11.608101 systemd[2428]: Startup finished in 202ms. Oct 13 05:50:11.608192 systemd[1]: Started user@0.service - User Manager for UID 0. Oct 13 05:50:11.612517 systemd[1]: Started session-c2.scope - Session c2 of User root. Oct 13 05:50:11.742356 kernel: loop6: detected capacity change from 0 to 12644352 Oct 13 05:50:11.749352 kernel: loop6: p9 Oct 13 05:50:11.776401 setup-nvidia[2437]: ░ Spawning container flatcardevelopercontainer-4459.1.0.bin on /opt/nvidia/workdir/flatcar_developer_container-4459.1.0.bin. Oct 13 05:50:12.187894 kernel: EXT4-fs (loop6p9): write access unavailable, skipping orphan cleanup Oct 13 05:50:12.188028 kernel: EXT4-fs (loop6p9): mounted filesystem fe653036-7ca0-48cd-a35d-424b2604ac97 ro without journal. Quota mode: none. Oct 13 05:50:13.435705 update_engine[1899]: I20251013 05:50:13.435598 1899 update_attempter.cc:509] Updating boot flags... Oct 13 05:50:17.107003 systemd[1]: Started sshd@3-10.200.4.17:22-10.200.16.10:60746.service - OpenSSH per-connection server daemon (10.200.16.10:60746). Oct 13 05:50:17.715840 sshd[2633]: Accepted publickey for core from 10.200.16.10 port 60746 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:50:17.717028 sshd-session[2633]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:50:17.724614 systemd-logind[1897]: New session 6 of user core. Oct 13 05:50:17.739528 systemd[1]: Started session-6.scope - Session 6 of User core. Oct 13 05:50:18.147456 sshd[2670]: Connection closed by 10.200.16.10 port 60746 Oct 13 05:50:18.147757 sshd-session[2633]: pam_unix(sshd:session): session closed for user core Oct 13 05:50:18.151306 systemd[1]: sshd@3-10.200.4.17:22-10.200.16.10:60746.service: Deactivated successfully. Oct 13 05:50:18.152911 systemd[1]: session-6.scope: Deactivated successfully. Oct 13 05:50:18.153606 systemd-logind[1897]: Session 6 logged out. Waiting for processes to exit. Oct 13 05:50:18.154686 systemd-logind[1897]: Removed session 6. Oct 13 05:50:18.254691 systemd[1]: Started sshd@4-10.200.4.17:22-10.200.16.10:60750.service - OpenSSH per-connection server daemon (10.200.16.10:60750). Oct 13 05:50:18.876905 sshd[2763]: Accepted publickey for core from 10.200.16.10 port 60750 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:50:18.878100 sshd-session[2763]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:50:18.882600 systemd-logind[1897]: New session 7 of user core. Oct 13 05:50:18.900499 systemd[1]: Started session-7.scope - Session 7 of User core. Oct 13 05:50:19.311862 sshd[2956]: Connection closed by 10.200.16.10 port 60750 Oct 13 05:50:19.312510 sshd-session[2763]: pam_unix(sshd:session): session closed for user core Oct 13 05:50:19.316362 systemd[1]: sshd@4-10.200.4.17:22-10.200.16.10:60750.service: Deactivated successfully. Oct 13 05:50:19.318918 systemd[1]: session-7.scope: Deactivated successfully. Oct 13 05:50:19.319672 systemd-logind[1897]: Session 7 logged out. Waiting for processes to exit. Oct 13 05:50:19.320735 systemd-logind[1897]: Removed session 7. Oct 13 05:50:29.432804 systemd[1]: Started sshd@5-10.200.4.17:22-10.200.16.10:44822.service - OpenSSH per-connection server daemon (10.200.16.10:44822). Oct 13 05:50:30.052667 sshd[3501]: Accepted publickey for core from 10.200.16.10 port 44822 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:50:30.053902 sshd-session[3501]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:50:30.058118 systemd-logind[1897]: New session 8 of user core. Oct 13 05:50:30.070494 systemd[1]: Started session-8.scope - Session 8 of User core. Oct 13 05:50:30.499695 sshd[3535]: Connection closed by 10.200.16.10 port 44822 Oct 13 05:50:30.500071 sshd-session[3501]: pam_unix(sshd:session): session closed for user core Oct 13 05:50:30.503609 systemd[1]: sshd@5-10.200.4.17:22-10.200.16.10:44822.service: Deactivated successfully. Oct 13 05:50:30.505201 systemd[1]: session-8.scope: Deactivated successfully. Oct 13 05:50:30.505916 systemd-logind[1897]: Session 8 logged out. Waiting for processes to exit. Oct 13 05:50:30.506913 systemd-logind[1897]: Removed session 8. Oct 13 05:50:30.622579 systemd[1]: Started sshd@6-10.200.4.17:22-10.200.16.10:50984.service - OpenSSH per-connection server daemon (10.200.16.10:50984). Oct 13 05:50:31.242227 sshd[3541]: Accepted publickey for core from 10.200.16.10 port 50984 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:50:31.245358 sshd-session[3541]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:50:31.249637 systemd-logind[1897]: New session 9 of user core. Oct 13 05:50:31.265460 systemd[1]: Started session-9.scope - Session 9 of User core. Oct 13 05:50:31.671598 sshd[3544]: Connection closed by 10.200.16.10 port 50984 Oct 13 05:50:31.671897 sshd-session[3541]: pam_unix(sshd:session): session closed for user core Oct 13 05:50:31.674841 systemd[1]: sshd@6-10.200.4.17:22-10.200.16.10:50984.service: Deactivated successfully. Oct 13 05:50:31.676567 systemd[1]: session-9.scope: Deactivated successfully. Oct 13 05:50:31.678438 systemd-logind[1897]: Session 9 logged out. Waiting for processes to exit. Oct 13 05:50:31.679635 systemd-logind[1897]: Removed session 9. Oct 13 05:50:41.782567 systemd[1]: Started sshd@7-10.200.4.17:22-10.200.16.10:36036.service - OpenSSH per-connection server daemon (10.200.16.10:36036). Oct 13 05:50:42.427951 sshd[7653]: Accepted publickey for core from 10.200.16.10 port 36036 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:50:42.429798 sshd-session[7653]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:50:42.439787 systemd-logind[1897]: New session 10 of user core. Oct 13 05:50:42.450431 systemd[1]: Started session-10.scope - Session 10 of User core. Oct 13 05:50:42.864790 sshd[7835]: Connection closed by 10.200.16.10 port 36036 Oct 13 05:50:42.865151 sshd-session[7653]: pam_unix(sshd:session): session closed for user core Oct 13 05:50:42.868545 systemd[1]: sshd@7-10.200.4.17:22-10.200.16.10:36036.service: Deactivated successfully. Oct 13 05:50:42.870167 systemd[1]: session-10.scope: Deactivated successfully. Oct 13 05:50:42.871386 systemd-logind[1897]: Session 10 logged out. Waiting for processes to exit. Oct 13 05:50:42.872389 systemd-logind[1897]: Removed session 10. Oct 13 05:50:42.978175 systemd[1]: Started sshd@8-10.200.4.17:22-10.200.16.10:36044.service - OpenSSH per-connection server daemon (10.200.16.10:36044). Oct 13 05:50:43.608952 sshd[8096]: Accepted publickey for core from 10.200.16.10 port 36044 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:50:43.610721 sshd-session[8096]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:50:43.616111 systemd-logind[1897]: New session 11 of user core. Oct 13 05:50:43.626598 systemd[1]: Started session-11.scope - Session 11 of User core. Oct 13 05:50:44.049305 sshd[8288]: Connection closed by 10.200.16.10 port 36044 Oct 13 05:50:44.048013 sshd-session[8096]: pam_unix(sshd:session): session closed for user core Oct 13 05:50:44.052072 systemd-logind[1897]: Session 11 logged out. Waiting for processes to exit. Oct 13 05:50:44.052952 systemd[1]: sshd@8-10.200.4.17:22-10.200.16.10:36044.service: Deactivated successfully. Oct 13 05:50:44.055014 systemd[1]: session-11.scope: Deactivated successfully. Oct 13 05:50:44.059082 systemd-logind[1897]: Removed session 11. Oct 13 05:50:54.154556 systemd[1]: Started sshd@9-10.200.4.17:22-10.200.16.10:58378.service - OpenSSH per-connection server daemon (10.200.16.10:58378). Oct 13 05:50:54.778970 sshd[10872]: Accepted publickey for core from 10.200.16.10 port 58378 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:50:54.780431 sshd-session[10872]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:50:54.785736 systemd-logind[1897]: New session 12 of user core. Oct 13 05:50:54.792497 systemd[1]: Started session-12.scope - Session 12 of User core. Oct 13 05:50:55.211498 sshd[11049]: Connection closed by 10.200.16.10 port 58378 Oct 13 05:50:55.213518 sshd-session[10872]: pam_unix(sshd:session): session closed for user core Oct 13 05:50:55.216907 systemd-logind[1897]: Session 12 logged out. Waiting for processes to exit. Oct 13 05:50:55.217988 systemd[1]: sshd@9-10.200.4.17:22-10.200.16.10:58378.service: Deactivated successfully. Oct 13 05:50:55.220223 systemd[1]: session-12.scope: Deactivated successfully. Oct 13 05:50:55.222305 systemd-logind[1897]: Removed session 12. Oct 13 05:50:55.322391 systemd[1]: Started sshd@10-10.200.4.17:22-10.200.16.10:58386.service - OpenSSH per-connection server daemon (10.200.16.10:58386). Oct 13 05:50:55.948991 sshd[11225]: Accepted publickey for core from 10.200.16.10 port 58386 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:50:55.950430 sshd-session[11225]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:50:55.955737 systemd-logind[1897]: New session 13 of user core. Oct 13 05:50:55.964493 systemd[1]: Started session-13.scope - Session 13 of User core. Oct 13 05:50:56.378851 sshd[11438]: Connection closed by 10.200.16.10 port 58386 Oct 13 05:50:56.381406 sshd-session[11225]: pam_unix(sshd:session): session closed for user core Oct 13 05:50:56.385651 systemd-logind[1897]: Session 13 logged out. Waiting for processes to exit. Oct 13 05:50:56.387714 systemd[1]: sshd@10-10.200.4.17:22-10.200.16.10:58386.service: Deactivated successfully. Oct 13 05:50:56.390000 systemd[1]: session-13.scope: Deactivated successfully. Oct 13 05:50:56.393260 systemd-logind[1897]: Removed session 13. Oct 13 05:51:06.490132 systemd[1]: Started sshd@11-10.200.4.17:22-10.200.16.10:52922.service - OpenSSH per-connection server daemon (10.200.16.10:52922). Oct 13 05:51:07.112359 sshd[14067]: Accepted publickey for core from 10.200.16.10 port 52922 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:51:07.114150 sshd-session[14067]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:51:07.125855 systemd-logind[1897]: New session 14 of user core. Oct 13 05:51:07.133198 systemd[1]: Started session-14.scope - Session 14 of User core. Oct 13 05:51:07.541645 sshd[14076]: Connection closed by 10.200.16.10 port 52922 Oct 13 05:51:07.545262 sshd-session[14067]: pam_unix(sshd:session): session closed for user core Oct 13 05:51:07.548994 systemd[1]: sshd@11-10.200.4.17:22-10.200.16.10:52922.service: Deactivated successfully. Oct 13 05:51:07.550875 systemd[1]: session-14.scope: Deactivated successfully. Oct 13 05:51:07.554580 systemd-logind[1897]: Session 14 logged out. Waiting for processes to exit. Oct 13 05:51:07.555909 systemd-logind[1897]: Removed session 14. Oct 13 05:51:07.656416 systemd[1]: Started sshd@12-10.200.4.17:22-10.200.16.10:52930.service - OpenSSH per-connection server daemon (10.200.16.10:52930). Oct 13 05:51:08.291336 sshd[14112]: Accepted publickey for core from 10.200.16.10 port 52930 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:51:08.292698 sshd-session[14112]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:51:08.300777 systemd-logind[1897]: New session 15 of user core. Oct 13 05:51:08.310562 systemd[1]: Started session-15.scope - Session 15 of User core. Oct 13 05:51:08.727493 sshd[14133]: Connection closed by 10.200.16.10 port 52930 Oct 13 05:51:08.727892 sshd-session[14112]: pam_unix(sshd:session): session closed for user core Oct 13 05:51:08.733847 systemd[1]: sshd@12-10.200.4.17:22-10.200.16.10:52930.service: Deactivated successfully. Oct 13 05:51:08.734117 systemd-logind[1897]: Session 15 logged out. Waiting for processes to exit. Oct 13 05:51:08.736988 systemd[1]: session-15.scope: Deactivated successfully. Oct 13 05:51:08.740840 systemd-logind[1897]: Removed session 15. Oct 13 05:51:18.841630 systemd[1]: Started sshd@13-10.200.4.17:22-10.200.16.10:55618.service - OpenSSH per-connection server daemon (10.200.16.10:55618). Oct 13 05:51:19.472250 sshd[14535]: Accepted publickey for core from 10.200.16.10 port 55618 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:51:19.474126 sshd-session[14535]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:51:19.483051 systemd-logind[1897]: New session 16 of user core. Oct 13 05:51:19.491437 systemd[1]: Started session-16.scope - Session 16 of User core. Oct 13 05:51:19.903275 sshd[14556]: Connection closed by 10.200.16.10 port 55618 Oct 13 05:51:19.905103 sshd-session[14535]: pam_unix(sshd:session): session closed for user core Oct 13 05:51:19.908715 systemd[1]: sshd@13-10.200.4.17:22-10.200.16.10:55618.service: Deactivated successfully. Oct 13 05:51:19.908903 systemd-logind[1897]: Session 16 logged out. Waiting for processes to exit. Oct 13 05:51:19.911501 systemd[1]: session-16.scope: Deactivated successfully. Oct 13 05:51:19.916746 systemd-logind[1897]: Removed session 16. Oct 13 05:51:20.014616 systemd[1]: Started sshd@14-10.200.4.17:22-10.200.16.10:55628.service - OpenSSH per-connection server daemon (10.200.16.10:55628). Oct 13 05:51:20.629609 sshd[14579]: Accepted publickey for core from 10.200.16.10 port 55628 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:51:20.631365 sshd-session[14579]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:51:20.638211 systemd-logind[1897]: New session 17 of user core. Oct 13 05:51:20.648428 systemd[1]: Started session-17.scope - Session 17 of User core. Oct 13 05:51:21.060563 sshd[14596]: Connection closed by 10.200.16.10 port 55628 Oct 13 05:51:21.061546 sshd-session[14579]: pam_unix(sshd:session): session closed for user core Oct 13 05:51:21.070203 systemd[1]: sshd@14-10.200.4.17:22-10.200.16.10:55628.service: Deactivated successfully. Oct 13 05:51:21.070426 systemd-logind[1897]: Session 17 logged out. Waiting for processes to exit. Oct 13 05:51:21.075816 systemd[1]: session-17.scope: Deactivated successfully. Oct 13 05:51:21.078176 systemd-logind[1897]: Removed session 17. Oct 13 05:51:31.174575 systemd[1]: Started sshd@15-10.200.4.17:22-10.200.16.10:36156.service - OpenSSH per-connection server daemon (10.200.16.10:36156). Oct 13 05:51:31.795351 sshd[14943]: Accepted publickey for core from 10.200.16.10 port 36156 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:51:31.796824 sshd-session[14943]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:51:31.804711 systemd-logind[1897]: New session 18 of user core. Oct 13 05:51:31.810511 systemd[1]: Started session-18.scope - Session 18 of User core. Oct 13 05:51:32.223414 sshd[14959]: Connection closed by 10.200.16.10 port 36156 Oct 13 05:51:32.223816 sshd-session[14943]: pam_unix(sshd:session): session closed for user core Oct 13 05:51:32.226973 systemd[1]: sshd@15-10.200.4.17:22-10.200.16.10:36156.service: Deactivated successfully. Oct 13 05:51:32.228686 systemd[1]: session-18.scope: Deactivated successfully. Oct 13 05:51:32.231491 systemd-logind[1897]: Session 18 logged out. Waiting for processes to exit. Oct 13 05:51:32.234891 systemd-logind[1897]: Removed session 18. Oct 13 05:51:32.332015 systemd[1]: Started sshd@16-10.200.4.17:22-10.200.16.10:36168.service - OpenSSH per-connection server daemon (10.200.16.10:36168). Oct 13 05:51:32.967513 sshd[14976]: Accepted publickey for core from 10.200.16.10 port 36168 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:51:32.968918 sshd-session[14976]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:51:32.976502 systemd-logind[1897]: New session 19 of user core. Oct 13 05:51:32.982542 systemd[1]: Started session-19.scope - Session 19 of User core. Oct 13 05:51:33.400134 sshd[14995]: Connection closed by 10.200.16.10 port 36168 Oct 13 05:51:33.400272 sshd-session[14976]: pam_unix(sshd:session): session closed for user core Oct 13 05:51:33.404449 systemd-logind[1897]: Session 19 logged out. Waiting for processes to exit. Oct 13 05:51:33.405700 systemd[1]: sshd@16-10.200.4.17:22-10.200.16.10:36168.service: Deactivated successfully. Oct 13 05:51:33.408891 systemd[1]: session-19.scope: Deactivated successfully. Oct 13 05:51:33.411392 systemd-logind[1897]: Removed session 19. Oct 13 05:51:43.523663 systemd[1]: Started sshd@17-10.200.4.17:22-10.200.16.10:50288.service - OpenSSH per-connection server daemon (10.200.16.10:50288). Oct 13 05:51:44.126653 sshd[15216]: Accepted publickey for core from 10.200.16.10 port 50288 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:51:44.128008 sshd-session[15216]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:51:44.132670 systemd-logind[1897]: New session 20 of user core. Oct 13 05:51:44.142579 systemd[1]: Started session-20.scope - Session 20 of User core. Oct 13 05:51:44.552120 sshd[15219]: Connection closed by 10.200.16.10 port 50288 Oct 13 05:51:44.552506 sshd-session[15216]: pam_unix(sshd:session): session closed for user core Oct 13 05:51:44.556090 systemd[1]: sshd@17-10.200.4.17:22-10.200.16.10:50288.service: Deactivated successfully. Oct 13 05:51:44.557694 systemd[1]: session-20.scope: Deactivated successfully. Oct 13 05:51:44.558409 systemd-logind[1897]: Session 20 logged out. Waiting for processes to exit. Oct 13 05:51:44.559302 systemd-logind[1897]: Removed session 20. Oct 13 05:51:44.666025 systemd[1]: Started sshd@18-10.200.4.17:22-10.200.16.10:50300.service - OpenSSH per-connection server daemon (10.200.16.10:50300). Oct 13 05:51:45.275730 sshd[15225]: Accepted publickey for core from 10.200.16.10 port 50300 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:51:45.277081 sshd-session[15225]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:51:45.281426 systemd-logind[1897]: New session 21 of user core. Oct 13 05:51:45.299548 systemd[1]: Started session-21.scope - Session 21 of User core. Oct 13 05:51:45.704804 sshd[15231]: Connection closed by 10.200.16.10 port 50300 Oct 13 05:51:45.705094 sshd-session[15225]: pam_unix(sshd:session): session closed for user core Oct 13 05:51:45.708729 systemd[1]: sshd@18-10.200.4.17:22-10.200.16.10:50300.service: Deactivated successfully. Oct 13 05:51:45.710346 systemd[1]: session-21.scope: Deactivated successfully. Oct 13 05:51:45.711070 systemd-logind[1897]: Session 21 logged out. Waiting for processes to exit. Oct 13 05:51:45.712140 systemd-logind[1897]: Removed session 21. Oct 13 05:51:47.985621 setup-nvidia[2437]: \u000d Oct 13 05:51:47.985621 setup-nvidia[2437]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Oct 13 05:51:47.985621 setup-nvidia[2437]: frequently when this kernel module was built against the wrong or Oct 13 05:51:47.985621 setup-nvidia[2437]: improperly configured kernel sources, with a version of gcc that differs Oct 13 05:51:47.985621 setup-nvidia[2437]: from the one used to build the target kernel, or if another driver, such Oct 13 05:51:47.985621 setup-nvidia[2437]: as nouveau, is present and prevents the NVIDIA kernel module from Oct 13 05:51:47.985621 setup-nvidia[2437]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Oct 13 05:51:47.985621 setup-nvidia[2437]: installed in this system is supported by this NVIDIA Linux graphics Oct 13 05:51:47.985621 setup-nvidia[2437]: driver release. Oct 13 05:51:47.985621 setup-nvidia[2437]: \u000d Oct 13 05:51:47.985621 setup-nvidia[2437]: Please see the log entries 'Kernel module load error' and 'Kernel Oct 13 05:51:47.985621 setup-nvidia[2437]: messages' at the end of the file Oct 13 05:51:47.985621 setup-nvidia[2437]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for more Oct 13 05:51:47.985621 setup-nvidia[2437]: information. Oct 13 05:51:47.985621 setup-nvidia[2437]: \u000d Oct 13 05:51:48.001436 setup-nvidia[2437]: dmesg: read kernel buffer failed: Operation not permitted Oct 13 05:51:48.020591 setup-nvidia[2437]: \u000d Oct 13 05:51:48.020591 setup-nvidia[2437]: ERROR: Installation has failed. Please see the file Oct 13 05:51:48.020591 setup-nvidia[2437]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for Oct 13 05:51:48.020591 setup-nvidia[2437]: details. You may find suggestions on fixing installation problems in Oct 13 05:51:48.020591 setup-nvidia[2437]: the README available on the Linux driver download page at Oct 13 05:51:48.020591 setup-nvidia[2437]: www.nvidia.com. Oct 13 05:51:48.020591 setup-nvidia[2437]: \u000d Oct 13 05:51:48.021024 setup-nvidia[2437]: Last 50 lines of nvidia-installer.log: Oct 13 05:51:48.021862 setup-nvidia[2437]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039059rm+0x53: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:51:48.021862 setup-nvidia[2437]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039056rm+0x90: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:51:48.021862 setup-nvidia[2437]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039049rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:51:48.021862 setup-nvidia[2437]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039105rm+0x93: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:51:48.021862 setup-nvidia[2437]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039101rm+0x36: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:51:48.021862 setup-nvidia[2437]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039100rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:51:48.021862 setup-nvidia[2437]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039102rm+0x77: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:51:48.021862 setup-nvidia[2437]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039046rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:51:48.021862 setup-nvidia[2437]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030615rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:51:48.021862 setup-nvidia[2437]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030614rm+0x27: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:51:48.021862 setup-nvidia[2437]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030618rm+0x37: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:51:48.021862 setup-nvidia[2437]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030617rm+0x24: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:51:48.021862 setup-nvidia[2437]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030616rm+0xca: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:51:48.021862 setup-nvidia[2437]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030619rm+0x193: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:51:48.021862 setup-nvidia[2437]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030621rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:51:48.021862 setup-nvidia[2437]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv011864rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:51:48.021862 setup-nvidia[2437]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039413rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:51:48.021862 setup-nvidia[2437]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/Module.symvers Oct 13 05:51:48.021862 setup-nvidia[2437]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.mod.o Oct 13 05:51:48.021862 setup-nvidia[2437]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/.module-common.o Oct 13 05:51:48.021862 setup-nvidia[2437]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.mod.o Oct 13 05:51:48.021862 setup-nvidia[2437]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.mod.o Oct 13 05:51:48.021862 setup-nvidia[2437]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.mod.o Oct 13 05:51:48.021862 setup-nvidia[2437]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.mod.o Oct 13 05:51:48.021862 setup-nvidia[2437]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Oct 13 05:51:48.021862 setup-nvidia[2437]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Oct 13 05:51:48.021862 setup-nvidia[2437]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Oct 13 05:51:48.021862 setup-nvidia[2437]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Oct 13 05:51:48.021862 setup-nvidia[2437]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Oct 13 05:51:48.021862 setup-nvidia[2437]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko due to unavailability of vmlinux Oct 13 05:51:48.021862 setup-nvidia[2437]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Oct 13 05:51:48.021862 setup-nvidia[2437]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Oct 13 05:51:48.021862 setup-nvidia[2437]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko due to unavailability of vmlinux Oct 13 05:51:48.021862 setup-nvidia[2437]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Oct 13 05:51:48.021862 setup-nvidia[2437]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko due to unavailability of vmlinux Oct 13 05:51:48.021862 setup-nvidia[2437]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Oct 13 05:51:48.021862 setup-nvidia[2437]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko due to unavailability of vmlinux Oct 13 05:51:48.021862 setup-nvidia[2437]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Oct 13 05:51:48.021862 setup-nvidia[2437]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko due to unavailability of vmlinux Oct 13 05:51:48.021862 setup-nvidia[2437]: make[2]: Leaving directory '/usr/lib/modules/6.12.51-flatcar/build' Oct 13 05:51:48.021862 setup-nvidia[2437]: make[1]: Leaving directory '/usr/lib/modules/6.12.51-flatcar/source' Oct 13 05:51:48.021862 setup-nvidia[2437]: -> done. Oct 13 05:51:48.021862 setup-nvidia[2437]: -> Kernel module compilation complete. Oct 13 05:51:48.021862 setup-nvidia[2437]: -> Unable to determine if Secure Boot is enabled: No such file or directory Oct 13 05:51:48.021862 setup-nvidia[2437]: 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. Oct 13 05:51:48.021862 setup-nvidia[2437]: \u000d Oct 13 05:51:48.022978 setup-nvidia[2437]: Please see the log entries 'Kernel module load error' and 'Kernel messages' at the end of the file '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for more information. Oct 13 05:51:48.022978 setup-nvidia[2437]: -> Kernel module load error: Operation not permitted Oct 13 05:51:48.022978 setup-nvidia[2437]: -> Kernel messages: Oct 13 05:51:48.022978 setup-nvidia[2437]: ERROR: Installation has failed. Please see the file '/nvidia/NVIDIA-Linux-x86_64-535.261.03/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. Oct 13 05:51:55.812359 systemd[1]: Started sshd@19-10.200.4.17:22-10.200.16.10:43330.service - OpenSSH per-connection server daemon (10.200.16.10:43330). Oct 13 05:51:56.420117 sshd[15414]: Accepted publickey for core from 10.200.16.10 port 43330 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:51:56.421435 sshd-session[15414]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:51:56.425717 systemd-logind[1897]: New session 22 of user core. Oct 13 05:51:56.437511 systemd[1]: Started session-22.scope - Session 22 of User core. Oct 13 05:51:56.857342 sshd[15417]: Connection closed by 10.200.16.10 port 43330 Oct 13 05:51:56.857772 sshd-session[15414]: pam_unix(sshd:session): session closed for user core Oct 13 05:51:56.861424 systemd[1]: sshd@19-10.200.4.17:22-10.200.16.10:43330.service: Deactivated successfully. Oct 13 05:51:56.863269 systemd[1]: session-22.scope: Deactivated successfully. Oct 13 05:51:56.864122 systemd-logind[1897]: Session 22 logged out. Waiting for processes to exit. Oct 13 05:51:56.865184 systemd-logind[1897]: Removed session 22. Oct 13 05:51:56.964915 systemd[1]: Started sshd@20-10.200.4.17:22-10.200.16.10:43340.service - OpenSSH per-connection server daemon (10.200.16.10:43340). Oct 13 05:51:57.571965 sshd[15423]: Accepted publickey for core from 10.200.16.10 port 43340 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:51:57.573262 sshd-session[15423]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:51:57.577652 systemd-logind[1897]: New session 23 of user core. Oct 13 05:51:57.597428 systemd[1]: Started session-23.scope - Session 23 of User core. Oct 13 05:51:58.010881 sshd[15426]: Connection closed by 10.200.16.10 port 43340 Oct 13 05:51:58.011262 sshd-session[15423]: pam_unix(sshd:session): session closed for user core Oct 13 05:51:58.014818 systemd[1]: sshd@20-10.200.4.17:22-10.200.16.10:43340.service: Deactivated successfully. Oct 13 05:51:58.016438 systemd[1]: session-23.scope: Deactivated successfully. Oct 13 05:51:58.017090 systemd-logind[1897]: Session 23 logged out. Waiting for processes to exit. Oct 13 05:51:58.017952 systemd-logind[1897]: Removed session 23. Oct 13 05:52:08.118899 systemd[1]: Started sshd@21-10.200.4.17:22-10.200.16.10:33436.service - OpenSSH per-connection server daemon (10.200.16.10:33436). Oct 13 05:52:08.725791 sshd[15432]: Accepted publickey for core from 10.200.16.10 port 33436 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:52:08.727102 sshd-session[15432]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:52:08.731361 systemd-logind[1897]: New session 24 of user core. Oct 13 05:52:08.744475 systemd[1]: Started session-24.scope - Session 24 of User core. Oct 13 05:52:09.151976 sshd[15435]: Connection closed by 10.200.16.10 port 33436 Oct 13 05:52:09.152298 sshd-session[15432]: pam_unix(sshd:session): session closed for user core Oct 13 05:52:09.156046 systemd[1]: sshd@21-10.200.4.17:22-10.200.16.10:33436.service: Deactivated successfully. Oct 13 05:52:09.157630 systemd[1]: session-24.scope: Deactivated successfully. Oct 13 05:52:09.158277 systemd-logind[1897]: Session 24 logged out. Waiting for processes to exit. Oct 13 05:52:09.159429 systemd-logind[1897]: Removed session 24. Oct 13 05:52:09.262952 systemd[1]: Started sshd@22-10.200.4.17:22-10.200.16.10:33442.service - OpenSSH per-connection server daemon (10.200.16.10:33442). Oct 13 05:52:09.865733 sshd[15441]: Accepted publickey for core from 10.200.16.10 port 33442 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:52:09.866969 sshd-session[15441]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:52:09.871112 systemd-logind[1897]: New session 25 of user core. Oct 13 05:52:09.886659 systemd[1]: Started session-25.scope - Session 25 of User core. Oct 13 05:52:10.290962 sshd[15444]: Connection closed by 10.200.16.10 port 33442 Oct 13 05:52:10.291357 sshd-session[15441]: pam_unix(sshd:session): session closed for user core Oct 13 05:52:10.294866 systemd[1]: sshd@22-10.200.4.17:22-10.200.16.10:33442.service: Deactivated successfully. Oct 13 05:52:10.296453 systemd[1]: session-25.scope: Deactivated successfully. Oct 13 05:52:10.297140 systemd-logind[1897]: Session 25 logged out. Waiting for processes to exit. Oct 13 05:52:10.298083 systemd-logind[1897]: Removed session 25. Oct 13 05:52:19.047362 kernel: EXT4-fs (loop6p9): unmounting filesystem fe653036-7ca0-48cd-a35d-424b2604ac97. Oct 13 05:52:19.048178 setup-nvidia[2437]: Container flatcardevelopercontainer-4459.1.0.bin exited successfully. Oct 13 05:52:19.050556 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4459.1.0.bin.mount: Deactivated successfully. Oct 13 05:52:19.113366 sudo[2425]: pam_unix(sudo:session): session closed for user root Oct 13 05:52:19.115420 systemd[1]: session-c2.scope: Deactivated successfully. Oct 13 05:52:19.115706 systemd[1]: session-c2.scope: Consumed 5min 31.125s CPU time, 889.3M memory peak. Oct 13 05:52:19.532935 setup-nvidia[1892]: /opt/nvidia / Oct 13 05:52:19.533939 setup-nvidia[1892]: / Oct 13 05:52:19.544349 kernel: IPMI message handler: version 39.2 Oct 13 05:52:19.547347 kernel: ipmi device interface Oct 13 05:52:19.561034 setup-nvidia[1892]: /opt/nvidia/current/usr/lib/modules/6.12.51-flatcar/video / Oct 13 05:52:19.617709 kernel: nvidia: loading out-of-tree module taints kernel. Oct 13 05:52:19.617848 kernel: nvidia: module license 'NVIDIA' taints kernel. Oct 13 05:52:19.617872 kernel: Disabling lock debugging due to kernel taint Oct 13 05:52:19.625382 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Oct 13 05:52:19.625453 kernel: nvidia: module license taints kernel. Oct 13 05:52:20.024357 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 Oct 13 05:52:20.437642 kernel: Oct 13 05:52:20.437782 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Oct 13 05:52:20.452428 systemd[1]: Started sshd@23-10.200.4.17:22-10.200.16.10:38194.service - OpenSSH per-connection server daemon (10.200.16.10:38194). Oct 13 05:52:20.495346 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.261.03 Sat Jun 14 16:05:50 UTC 2025 Oct 13 05:52:20.515361 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.261.03 Sat Jun 14 15:39:16 UTC 2025 Oct 13 05:52:20.537372 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Oct 13 05:52:20.589966 setup-nvidia[1892]: / Oct 13 05:52:20.590443 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. Oct 13 05:52:20.606967 setup-nvidia[15488]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 13 05:52:21.076321 sshd[15478]: Accepted publickey for core from 10.200.16.10 port 38194 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:52:21.077631 sshd-session[15478]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:52:21.081992 systemd-logind[1897]: New session 26 of user core. Oct 13 05:52:21.093515 systemd[1]: Started session-26.scope - Session 26 of User core. Oct 13 05:52:21.513173 sshd[15509]: Connection closed by 10.200.16.10 port 38194 Oct 13 05:52:21.513634 sshd-session[15478]: pam_unix(sshd:session): session closed for user core Oct 13 05:52:21.517418 systemd[1]: sshd@23-10.200.4.17:22-10.200.16.10:38194.service: Deactivated successfully. Oct 13 05:52:21.519007 systemd[1]: session-26.scope: Deactivated successfully. Oct 13 05:52:21.519715 systemd-logind[1897]: Session 26 logged out. Waiting for processes to exit. Oct 13 05:52:21.520741 systemd-logind[1897]: Removed session 26. Oct 13 05:52:21.624987 systemd[1]: Started sshd@24-10.200.4.17:22-10.200.16.10:38206.service - OpenSSH per-connection server daemon (10.200.16.10:38206). Oct 13 05:52:22.260730 sshd[15515]: Accepted publickey for core from 10.200.16.10 port 38206 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:52:22.262003 sshd-session[15515]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:52:22.266606 systemd-logind[1897]: New session 27 of user core. Oct 13 05:52:22.278476 systemd[1]: Started session-27.scope - Session 27 of User core. Oct 13 05:52:22.309976 setup-nvidia[15505]: Mon Oct 13 05:52:22 2025 Oct 13 05:52:22.309976 setup-nvidia[15505]: +---------------------------------------------------------------------------------------+ Oct 13 05:52:22.309976 setup-nvidia[15505]: | NVIDIA-SMI 535.261.03 Driver Version: 535.261.03 CUDA Version: 12.2 | Oct 13 05:52:22.309976 setup-nvidia[15505]: |-----------------------------------------+----------------------+----------------------+ Oct 13 05:52:22.309976 setup-nvidia[15505]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Oct 13 05:52:22.309976 setup-nvidia[15505]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Oct 13 05:52:22.309976 setup-nvidia[15505]: | | | MIG M. | Oct 13 05:52:22.309976 setup-nvidia[15505]: |=========================================+======================+======================| Oct 13 05:52:22.318022 setup-nvidia[15505]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | 0 | Oct 13 05:52:22.318022 setup-nvidia[15505]: | N/A 30C P0 35W / 250W | 0MiB / 16384MiB | 0% Default | Oct 13 05:52:22.318022 setup-nvidia[15505]: | | | N/A | Oct 13 05:52:22.318022 setup-nvidia[15505]: +-----------------------------------------+----------------------+----------------------+ Oct 13 05:52:22.318022 setup-nvidia[15505]: Oct 13 05:52:22.318022 setup-nvidia[15505]: +---------------------------------------------------------------------------------------+ Oct 13 05:52:22.318022 setup-nvidia[15505]: | Processes: | Oct 13 05:52:22.318022 setup-nvidia[15505]: | GPU GI CI PID Type Process name GPU Memory | Oct 13 05:52:22.318022 setup-nvidia[15505]: | ID ID Usage | Oct 13 05:52:22.318022 setup-nvidia[15505]: |=======================================================================================| Oct 13 05:52:22.318361 setup-nvidia[15505]: | No running processes found | Oct 13 05:52:22.318361 setup-nvidia[15505]: +---------------------------------------------------------------------------------------+ Oct 13 05:52:22.692918 sshd[15518]: Connection closed by 10.200.16.10 port 38206 Oct 13 05:52:22.693253 sshd-session[15515]: pam_unix(sshd:session): session closed for user core Oct 13 05:52:22.697038 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Oct 13 05:52:22.699219 systemd[1]: sshd@24-10.200.4.17:22-10.200.16.10:38206.service: Deactivated successfully. Oct 13 05:52:22.700854 systemd[1]: session-27.scope: Deactivated successfully. Oct 13 05:52:22.702073 systemd-logind[1897]: Session 27 logged out. Waiting for processes to exit. Oct 13 05:52:22.702737 systemd[1]: Reached target multi-user.target - Multi-User System. Oct 13 05:52:22.706453 systemd[1]: Startup finished in 1.337s (firmware) + 37.052s (loader) + 3.889s (kernel) + 15.738s (initrd) + 3min 15.645s (userspace) = 4min 13.661s. Oct 13 05:52:22.707260 systemd-logind[1897]: Removed session 27. Oct 13 05:52:29.208248 systemd[1]: Stopping user@0.service - User Manager for UID 0... Oct 13 05:52:29.209044 systemd[2428]: Activating special unit exit.target... Oct 13 05:52:29.209129 systemd[2428]: Stopped target default.target - Main User Target. Oct 13 05:52:29.209169 systemd[2428]: Stopped target basic.target - Basic System. Oct 13 05:52:29.209198 systemd[2428]: Stopped target paths.target - Paths. Oct 13 05:52:29.209224 systemd[2428]: Stopped target sockets.target - Sockets. Oct 13 05:52:29.209250 systemd[2428]: Stopped target timers.target - Timers. Oct 13 05:52:29.209611 systemd[2428]: Closed dbus.socket - D-Bus User Message Bus Socket. Oct 13 05:52:29.209822 systemd[2428]: Removed slice app.slice - User Application Slice. Oct 13 05:52:29.209855 systemd[2428]: Reached target shutdown.target - Shutdown. Oct 13 05:52:29.209995 systemd[2428]: Finished systemd-exit.service - Exit the Session. Oct 13 05:52:29.210026 systemd[2428]: Reached target exit.target - Exit the Session. Oct 13 05:52:29.230648 (sd-pam)[2429]: pam_unix(systemd-user:session): session closed for user root Oct 13 05:52:29.231956 systemd[1]: user@0.service: Deactivated successfully. Oct 13 05:52:29.232321 systemd[1]: Stopped user@0.service - User Manager for UID 0. Oct 13 05:52:29.234631 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Oct 13 05:52:29.256358 systemd[1]: run-user-0.mount: Deactivated successfully. Oct 13 05:52:29.257346 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Oct 13 05:52:29.257556 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Oct 13 05:52:29.259240 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Oct 13 05:52:29.259345 systemd[1]: user-0.slice: Consumed 5min 31.402s CPU time, 895.3M memory peak. Oct 13 05:52:29.259748 systemd-logind[1897]: Removed session c3. Oct 13 05:52:32.804937 systemd[1]: Started sshd@25-10.200.4.17:22-10.200.16.10:54576.service - OpenSSH per-connection server daemon (10.200.16.10:54576). Oct 13 05:52:33.413837 sshd[15530]: Accepted publickey for core from 10.200.16.10 port 54576 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:52:33.415097 sshd-session[15530]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:52:33.419407 systemd-logind[1897]: New session 28 of user core. Oct 13 05:52:33.433562 systemd[1]: Started session-28.scope - Session 28 of User core. Oct 13 05:52:33.853527 sshd[15533]: Connection closed by 10.200.16.10 port 54576 Oct 13 05:52:33.853937 sshd-session[15530]: pam_unix(sshd:session): session closed for user core Oct 13 05:52:33.856976 systemd[1]: sshd@25-10.200.4.17:22-10.200.16.10:54576.service: Deactivated successfully. Oct 13 05:52:33.858629 systemd[1]: session-28.scope: Deactivated successfully. Oct 13 05:52:33.862758 systemd-logind[1897]: Session 28 logged out. Waiting for processes to exit. Oct 13 05:52:33.863730 systemd-logind[1897]: Removed session 28. Oct 13 05:52:33.961653 systemd[1]: Started sshd@26-10.200.4.17:22-10.200.16.10:54592.service - OpenSSH per-connection server daemon (10.200.16.10:54592). Oct 13 05:52:34.590814 sshd[15539]: Accepted publickey for core from 10.200.16.10 port 54592 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:52:34.592140 sshd-session[15539]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:52:34.596353 systemd-logind[1897]: New session 29 of user core. Oct 13 05:52:34.613247 systemd[1]: Started session-29.scope - Session 29 of User core. Oct 13 05:52:35.033775 sshd[15544]: Connection closed by 10.200.16.10 port 54592 Oct 13 05:52:35.034178 sshd-session[15539]: pam_unix(sshd:session): session closed for user core Oct 13 05:52:35.037750 systemd[1]: sshd@26-10.200.4.17:22-10.200.16.10:54592.service: Deactivated successfully. Oct 13 05:52:35.039340 systemd[1]: session-29.scope: Deactivated successfully. Oct 13 05:52:35.040028 systemd-logind[1897]: Session 29 logged out. Waiting for processes to exit. Oct 13 05:52:35.040944 systemd-logind[1897]: Removed session 29. Oct 13 05:52:35.144811 systemd[1]: Started sshd@27-10.200.4.17:22-10.200.16.10:54608.service - OpenSSH per-connection server daemon (10.200.16.10:54608). Oct 13 05:52:35.779344 sshd[15551]: Accepted publickey for core from 10.200.16.10 port 54608 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:52:35.780629 sshd-session[15551]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:52:35.785277 systemd-logind[1897]: New session 30 of user core. Oct 13 05:52:35.794448 systemd[1]: Started session-30.scope - Session 30 of User core. Oct 13 05:52:36.211405 sshd[15554]: Connection closed by 10.200.16.10 port 54608 Oct 13 05:52:36.211808 sshd-session[15551]: pam_unix(sshd:session): session closed for user core Oct 13 05:52:36.215398 systemd[1]: sshd@27-10.200.4.17:22-10.200.16.10:54608.service: Deactivated successfully. Oct 13 05:52:36.216975 systemd[1]: session-30.scope: Deactivated successfully. Oct 13 05:52:36.217733 systemd-logind[1897]: Session 30 logged out. Waiting for processes to exit. Oct 13 05:52:36.218640 systemd-logind[1897]: Removed session 30. Oct 13 05:52:36.322700 systemd[1]: Started sshd@28-10.200.4.17:22-10.200.16.10:54624.service - OpenSSH per-connection server daemon (10.200.16.10:54624). Oct 13 05:52:36.951053 sshd[15560]: Accepted publickey for core from 10.200.16.10 port 54624 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:52:36.952429 sshd-session[15560]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:52:36.957035 systemd-logind[1897]: New session 31 of user core. Oct 13 05:52:36.973532 systemd[1]: Started session-31.scope - Session 31 of User core. Oct 13 05:52:37.301382 sudo[15564]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Oct 13 05:52:37.301739 sudo[15564]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 13 05:52:37.345006 sudo[15564]: pam_unix(sudo:session): session closed for user root Oct 13 05:52:37.451679 sshd[15563]: Connection closed by 10.200.16.10 port 54624 Oct 13 05:52:37.452137 sshd-session[15560]: pam_unix(sshd:session): session closed for user core Oct 13 05:52:37.456036 systemd[1]: sshd@28-10.200.4.17:22-10.200.16.10:54624.service: Deactivated successfully. Oct 13 05:52:37.457645 systemd[1]: session-31.scope: Deactivated successfully. Oct 13 05:52:37.458392 systemd-logind[1897]: Session 31 logged out. Waiting for processes to exit. Oct 13 05:52:37.459422 systemd-logind[1897]: Removed session 31. Oct 13 05:52:37.564989 systemd[1]: Started sshd@29-10.200.4.17:22-10.200.16.10:54640.service - OpenSSH per-connection server daemon (10.200.16.10:54640). Oct 13 05:52:38.176677 sshd[15570]: Accepted publickey for core from 10.200.16.10 port 54640 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:52:38.177999 sshd-session[15570]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:52:38.182220 systemd-logind[1897]: New session 32 of user core. Oct 13 05:52:38.196460 systemd[1]: Started session-32.scope - Session 32 of User core. Oct 13 05:52:38.512186 sudo[15575]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Oct 13 05:52:38.512587 sudo[15575]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 13 05:52:38.519269 sudo[15575]: pam_unix(sudo:session): session closed for user root Oct 13 05:52:38.524984 sudo[15574]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Oct 13 05:52:38.525270 sudo[15574]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 13 05:52:38.535136 systemd[1]: Starting audit-rules.service - Load Audit Rules... Oct 13 05:52:38.572860 augenrules[15597]: No rules Oct 13 05:52:38.574124 systemd[1]: audit-rules.service: Deactivated successfully. Oct 13 05:52:38.574378 systemd[1]: Finished audit-rules.service - Load Audit Rules. Oct 13 05:52:38.575237 sudo[15574]: pam_unix(sudo:session): session closed for user root Oct 13 05:52:38.689247 sshd[15573]: Connection closed by 10.200.16.10 port 54640 Oct 13 05:52:38.689658 sshd-session[15570]: pam_unix(sshd:session): session closed for user core Oct 13 05:52:38.693468 systemd[1]: sshd@29-10.200.4.17:22-10.200.16.10:54640.service: Deactivated successfully. Oct 13 05:52:38.695043 systemd[1]: session-32.scope: Deactivated successfully. Oct 13 05:52:38.695723 systemd-logind[1897]: Session 32 logged out. Waiting for processes to exit. Oct 13 05:52:38.696598 systemd-logind[1897]: Removed session 32. Oct 13 05:52:38.797761 systemd[1]: Started sshd@30-10.200.4.17:22-10.200.16.10:54652.service - OpenSSH per-connection server daemon (10.200.16.10:54652). Oct 13 05:52:39.419314 sshd[15606]: Accepted publickey for core from 10.200.16.10 port 54652 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:52:39.420592 sshd-session[15606]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:52:39.425073 systemd-logind[1897]: New session 33 of user core. Oct 13 05:52:39.441476 systemd[1]: Started session-33.scope - Session 33 of User core. Oct 13 05:52:39.754645 sudo[15610]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/mkdir -p . Oct 13 05:52:39.754943 sudo[15610]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 13 05:52:39.758274 sudo[15610]: pam_unix(sudo:session): session closed for user root Oct 13 05:52:39.871828 sshd[15609]: Connection closed by 10.200.16.10 port 54652 Oct 13 05:52:39.872233 sshd-session[15606]: pam_unix(sshd:session): session closed for user core Oct 13 05:52:39.875814 systemd[1]: sshd@30-10.200.4.17:22-10.200.16.10:54652.service: Deactivated successfully. Oct 13 05:52:39.877699 systemd[1]: session-33.scope: Deactivated successfully. Oct 13 05:52:39.878553 systemd-logind[1897]: Session 33 logged out. Waiting for processes to exit. Oct 13 05:52:39.879578 systemd-logind[1897]: Removed session 33. Oct 13 05:52:39.989277 systemd[1]: Started sshd@31-10.200.4.17:22-10.200.16.10:54656.service - OpenSSH per-connection server daemon (10.200.16.10:54656). Oct 13 05:52:40.591487 sshd[15616]: Accepted publickey for core from 10.200.16.10 port 54656 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:52:40.592705 sshd-session[15616]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:52:40.596973 systemd-logind[1897]: New session 34 of user core. Oct 13 05:52:40.614496 systemd[1]: Started session-34.scope - Session 34 of User core. Oct 13 05:52:40.923067 sudo[15620]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/install -m 0755 /dev/stdin kolet Oct 13 05:52:40.923380 sudo[15620]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 13 05:52:44.385655 sudo[15620]: pam_unix(sudo:session): session closed for user root Oct 13 05:52:44.490571 sshd[15619]: Connection closed by 10.200.16.10 port 54656 Oct 13 05:52:44.490897 sshd-session[15616]: pam_unix(sshd:session): session closed for user core Oct 13 05:52:44.494688 systemd[1]: sshd@31-10.200.4.17:22-10.200.16.10:54656.service: Deactivated successfully. Oct 13 05:52:44.496614 systemd[1]: session-34.scope: Deactivated successfully. Oct 13 05:52:44.497343 systemd-logind[1897]: Session 34 logged out. Waiting for processes to exit. Oct 13 05:52:44.498224 systemd-logind[1897]: Removed session 34. Oct 13 05:52:44.597636 systemd[1]: Started sshd@32-10.200.4.17:22-10.200.16.10:57620.service - OpenSSH per-connection server daemon (10.200.16.10:57620). Oct 13 05:52:45.221342 sshd[15626]: Accepted publickey for core from 10.200.16.10 port 57620 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:52:45.222595 sshd-session[15626]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:52:45.226825 systemd-logind[1897]: New session 35 of user core. Oct 13 05:52:45.235472 systemd[1]: Started session-35.scope - Session 35 of User core. Oct 13 05:52:47.021517 systemd[1]: Starting docker.service - Docker Application Container Engine... Oct 13 05:52:47.037697 (dockerd)[15650]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPTS, DOCKER_OPT_BIP, DOCKER_OPT_IPMASQ, DOCKER_OPT_MTU Oct 13 05:52:48.945415 dockerd[15650]: time="2025-10-13T05:52:48.945352142Z" level=info msg="Starting up" Oct 13 05:52:48.946051 dockerd[15650]: time="2025-10-13T05:52:48.946011073Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Oct 13 05:52:48.956618 dockerd[15650]: time="2025-10-13T05:52:48.956582272Z" level=info msg="Creating a containerd client" address=/var/run/docker/libcontainerd/docker-containerd.sock timeout=1m0s Oct 13 05:52:49.591246 dockerd[15650]: time="2025-10-13T05:52:49.591191856Z" level=info msg="Loading containers: start." Oct 13 05:52:49.606350 kernel: Initializing XFRM netlink socket Oct 13 05:52:49.958685 systemd-networkd[1586]: docker0: Link UP Oct 13 05:52:49.972143 dockerd[15650]: time="2025-10-13T05:52:49.972096972Z" level=info msg="Loading containers: done." Oct 13 05:52:49.985659 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck1645749374-merged.mount: Deactivated successfully. Oct 13 05:52:49.990452 dockerd[15650]: time="2025-10-13T05:52:49.990412064Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2 Oct 13 05:52:49.990536 dockerd[15650]: time="2025-10-13T05:52:49.990490056Z" level=info msg="Docker daemon" commit=6430e49a55babd9b8f4d08e70ecb2b68900770fe containerd-snapshotter=false storage-driver=overlay2 version=28.0.4 Oct 13 05:52:49.990599 dockerd[15650]: time="2025-10-13T05:52:49.990578747Z" level=info msg="Initializing buildkit" Oct 13 05:52:50.033480 dockerd[15650]: time="2025-10-13T05:52:50.033429882Z" level=info msg="Completed buildkit initialization" Oct 13 05:52:50.041093 dockerd[15650]: time="2025-10-13T05:52:50.041057787Z" level=info msg="Daemon has completed initialization" Oct 13 05:52:50.041181 dockerd[15650]: time="2025-10-13T05:52:50.041137079Z" level=info msg="API listen on /run/docker.sock" Oct 13 05:52:50.041367 systemd[1]: Started docker.service - Docker Application Container Engine. Oct 13 05:52:51.416979 systemd[1]: var-lib-docker-overlay2-1f36d0901a76996357821d0192cda1ddee30f6bdd83a5401d87e833153db8885\x2dinit-merged.mount: Deactivated successfully. Oct 13 05:52:51.490597 containerd[1916]: time="2025-10-13T05:52:51.490548175Z" level=info msg="connecting to shim ffc43a3d80287def4b292897ed1b2b846242dd18c96fc88ef2fd246560ce4226" address="unix:///run/containerd/s/f8a38d60c03f3f5bab6583645dbac322e45e2978e08ab9891675075a679ce6ef" namespace=moby protocol=ttrpc version=3 Oct 13 05:52:51.524521 systemd[1]: Started docker-ffc43a3d80287def4b292897ed1b2b846242dd18c96fc88ef2fd246560ce4226.scope - libcontainer container ffc43a3d80287def4b292897ed1b2b846242dd18c96fc88ef2fd246560ce4226. Oct 13 05:52:51.575245 kernel: docker0: port 1(vethc48753a) entered blocking state Oct 13 05:52:51.575356 kernel: docker0: port 1(vethc48753a) entered disabled state Oct 13 05:52:51.575394 kernel: vethc48753a: entered allmulticast mode Oct 13 05:52:51.580612 kernel: vethc48753a: entered promiscuous mode Oct 13 05:52:51.581139 systemd-networkd[1586]: vethc48753a: Link UP Oct 13 05:52:51.601356 kernel: eth0: renamed from veth35f53ed Oct 13 05:52:51.601462 kernel: docker0: port 1(vethc48753a) entered blocking state Oct 13 05:52:51.608492 kernel: docker0: port 1(vethc48753a) entered forwarding state Oct 13 05:52:51.608914 systemd-networkd[1586]: vethc48753a: Gained carrier Oct 13 05:52:51.609205 systemd-networkd[1586]: docker0: Gained carrier Oct 13 05:52:53.450506 systemd-networkd[1586]: docker0: Gained IPv6LL Oct 13 05:52:53.642498 systemd-networkd[1586]: vethc48753a: Gained IPv6LL Oct 13 05:52:54.888304 systemd[1]: docker-ffc43a3d80287def4b292897ed1b2b846242dd18c96fc88ef2fd246560ce4226.scope: Deactivated successfully. Oct 13 05:52:54.900817 dockerd[15650]: time="2025-10-13T05:52:54.900761996Z" level=info msg="ignoring event" container=ffc43a3d80287def4b292897ed1b2b846242dd18c96fc88ef2fd246560ce4226 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Oct 13 05:52:54.901303 containerd[1916]: time="2025-10-13T05:52:54.901244453Z" level=info msg="shim disconnected" id=ffc43a3d80287def4b292897ed1b2b846242dd18c96fc88ef2fd246560ce4226 namespace=moby Oct 13 05:52:54.901303 containerd[1916]: time="2025-10-13T05:52:54.901280150Z" level=warning msg="cleaning up after shim disconnected" id=ffc43a3d80287def4b292897ed1b2b846242dd18c96fc88ef2fd246560ce4226 namespace=moby Oct 13 05:52:54.901586 containerd[1916]: time="2025-10-13T05:52:54.901288249Z" level=info msg="cleaning up dead shim" namespace=moby Oct 13 05:52:54.929586 systemd-networkd[1586]: vethc48753a: Lost carrier Oct 13 05:52:54.930352 kernel: docker0: port 1(vethc48753a) entered disabled state Oct 13 05:52:54.930394 kernel: veth35f53ed: renamed from eth0 Oct 13 05:52:54.946024 systemd-networkd[1586]: vethc48753a: Link DOWN Oct 13 05:52:54.953459 kernel: docker0: port 1(vethc48753a) entered disabled state Oct 13 05:52:54.953523 kernel: vethc48753a (unregistering): left allmulticast mode Oct 13 05:52:54.956866 kernel: vethc48753a (unregistering): left promiscuous mode Oct 13 05:52:54.960389 kernel: docker0: port 1(vethc48753a) entered disabled state Oct 13 05:52:54.982202 systemd[1]: run-docker-netns-1b6250cc1522.mount: Deactivated successfully. Oct 13 05:52:54.986576 systemd[1]: var-lib-docker-overlay2-1f36d0901a76996357821d0192cda1ddee30f6bdd83a5401d87e833153db8885-merged.mount: Deactivated successfully. Oct 13 05:52:55.109494 sshd[15629]: Connection closed by 10.200.16.10 port 57620 Oct 13 05:52:55.109887 sshd-session[15626]: pam_unix(sshd:session): session closed for user core Oct 13 05:52:55.113523 systemd[1]: sshd@32-10.200.4.17:22-10.200.16.10:57620.service: Deactivated successfully. Oct 13 05:52:55.115093 systemd[1]: session-35.scope: Deactivated successfully. Oct 13 05:52:55.115810 systemd-logind[1897]: Session 35 logged out. Waiting for processes to exit. Oct 13 05:52:55.116710 systemd-logind[1897]: Removed session 35. Oct 13 05:52:55.220744 systemd[1]: Started sshd@33-10.200.4.17:22-10.200.16.10:48660.service - OpenSSH per-connection server daemon (10.200.16.10:48660). Oct 13 05:52:55.825969 sshd[15940]: Accepted publickey for core from 10.200.16.10 port 48660 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:52:55.827195 sshd-session[15940]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:52:55.831493 systemd-logind[1897]: New session 36 of user core. Oct 13 05:52:55.847474 systemd[1]: Started session-36.scope - Session 36 of User core. Oct 13 05:52:55.946613 systemd-networkd[1586]: docker0: Lost carrier Oct 13 05:52:56.211278 systemd[1]: var-lib-docker-overlay2-bf2e1fd3a9174fd8e119d374e918068874371ffbdbee3afe03b364da6434b9a4\x2dinit-merged.mount: Deactivated successfully. Oct 13 05:52:56.308513 containerd[1916]: time="2025-10-13T05:52:56.308457297Z" level=info msg="connecting to shim 4412f49f3a0369f440c7949acd33bf767d2c98d348fc7d4d0af7f85ba3f58b26" address="unix:///run/containerd/s/89062681dd241ead8dd4989959ccda007c86690a8e6bd7908fc114c1f7816df9" namespace=moby protocol=ttrpc version=3 Oct 13 05:52:56.345548 systemd[1]: Started docker-4412f49f3a0369f440c7949acd33bf767d2c98d348fc7d4d0af7f85ba3f58b26.scope - libcontainer container 4412f49f3a0369f440c7949acd33bf767d2c98d348fc7d4d0af7f85ba3f58b26. Oct 13 05:52:56.389490 kernel: docker0: port 1(veth464f2f1) entered blocking state Oct 13 05:52:56.389606 kernel: docker0: port 1(veth464f2f1) entered disabled state Oct 13 05:52:56.392481 kernel: veth464f2f1: entered allmulticast mode Oct 13 05:52:56.395545 kernel: veth464f2f1: entered promiscuous mode Oct 13 05:52:56.396520 systemd-networkd[1586]: veth464f2f1: Link UP Oct 13 05:52:56.412362 kernel: eth0: renamed from veth182fab4 Oct 13 05:52:56.412444 kernel: docker0: port 1(veth464f2f1) entered blocking state Oct 13 05:52:56.419224 kernel: docker0: port 1(veth464f2f1) entered forwarding state Oct 13 05:52:56.419635 systemd-networkd[1586]: veth464f2f1: Gained carrier Oct 13 05:52:56.419898 systemd-networkd[1586]: docker0: Gained carrier Oct 13 05:52:56.447555 systemd[1]: docker-4412f49f3a0369f440c7949acd33bf767d2c98d348fc7d4d0af7f85ba3f58b26.scope: Deactivated successfully. Oct 13 05:52:56.467512 dockerd[15650]: time="2025-10-13T05:52:56.467009995Z" level=info msg="ignoring event" container=4412f49f3a0369f440c7949acd33bf767d2c98d348fc7d4d0af7f85ba3f58b26 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Oct 13 05:52:56.467894 containerd[1916]: time="2025-10-13T05:52:56.467590024Z" level=info msg="shim disconnected" id=4412f49f3a0369f440c7949acd33bf767d2c98d348fc7d4d0af7f85ba3f58b26 namespace=moby Oct 13 05:52:56.467894 containerd[1916]: time="2025-10-13T05:52:56.467637618Z" level=warning msg="cleaning up after shim disconnected" id=4412f49f3a0369f440c7949acd33bf767d2c98d348fc7d4d0af7f85ba3f58b26 namespace=moby Oct 13 05:52:56.467894 containerd[1916]: time="2025-10-13T05:52:56.467647917Z" level=info msg="cleaning up dead shim" namespace=moby Oct 13 05:52:56.499364 kernel: docker0: port 1(veth464f2f1) entered disabled state Oct 13 05:52:56.499458 kernel: veth182fab4: renamed from eth0 Oct 13 05:52:56.499407 systemd-networkd[1586]: veth464f2f1: Lost carrier Oct 13 05:52:56.523475 systemd-networkd[1586]: veth464f2f1: Link DOWN Oct 13 05:52:56.531291 kernel: docker0: port 1(veth464f2f1) entered disabled state Oct 13 05:52:56.531375 kernel: veth464f2f1 (unregistering): left allmulticast mode Oct 13 05:52:56.534983 kernel: veth464f2f1 (unregistering): left promiscuous mode Oct 13 05:52:56.538610 kernel: docker0: port 1(veth464f2f1) entered disabled state Oct 13 05:52:56.674203 sshd[15943]: Connection closed by 10.200.16.10 port 48660 Oct 13 05:52:56.674615 sshd-session[15940]: pam_unix(sshd:session): session closed for user core Oct 13 05:52:56.678376 systemd[1]: sshd@33-10.200.4.17:22-10.200.16.10:48660.service: Deactivated successfully. Oct 13 05:52:56.680194 systemd[1]: session-36.scope: Deactivated successfully. Oct 13 05:52:56.680877 systemd-logind[1897]: Session 36 logged out. Waiting for processes to exit. Oct 13 05:52:56.681859 systemd-logind[1897]: Removed session 36. Oct 13 05:52:56.785022 systemd[1]: Started sshd@34-10.200.4.17:22-10.200.16.10:48672.service - OpenSSH per-connection server daemon (10.200.16.10:48672). Oct 13 05:52:56.954690 systemd-networkd[1586]: docker0: Lost carrier Oct 13 05:52:57.208661 systemd[1]: var-lib-docker-overlay2-bf2e1fd3a9174fd8e119d374e918068874371ffbdbee3afe03b364da6434b9a4-merged.mount: Deactivated successfully. Oct 13 05:52:57.407384 sshd[16045]: Accepted publickey for core from 10.200.16.10 port 48672 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:52:57.408642 sshd-session[16045]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:52:57.413049 systemd-logind[1897]: New session 37 of user core. Oct 13 05:52:57.421469 systemd[1]: Started session-37.scope - Session 37 of User core. Oct 13 05:52:57.830863 ntpdate[16058]: ntpdate 4.2.8p18@1.4062-o Sun Oct 12 22:02:54 UTC 2025 (1) Oct 13 05:53:04.549151 ntpdate[16058]: adjust time server 139.143.5.30 offset -0.022813 sec Oct 13 05:53:04.651826 sshd[16048]: Connection closed by 10.200.16.10 port 48672 Oct 13 05:53:04.652231 sshd-session[16045]: pam_unix(sshd:session): session closed for user core Oct 13 05:53:04.655783 systemd[1]: sshd@34-10.200.4.17:22-10.200.16.10:48672.service: Deactivated successfully. Oct 13 05:53:04.657381 systemd[1]: session-37.scope: Deactivated successfully. Oct 13 05:53:04.658228 systemd-logind[1897]: Session 37 logged out. Waiting for processes to exit. Oct 13 05:53:04.659125 systemd-logind[1897]: Removed session 37. Oct 13 05:53:04.763987 systemd[1]: Started sshd@35-10.200.4.17:22-10.200.16.10:33536.service - OpenSSH per-connection server daemon (10.200.16.10:33536). Oct 13 05:53:05.389769 sshd[16063]: Accepted publickey for core from 10.200.16.10 port 33536 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:53:05.391012 sshd-session[16063]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:53:05.395369 systemd-logind[1897]: New session 38 of user core. Oct 13 05:53:05.411585 systemd[1]: Started session-38.scope - Session 38 of User core. Oct 13 05:53:05.880449 sshd[16066]: Connection closed by 10.200.16.10 port 33536 Oct 13 05:53:05.880825 sshd-session[16063]: pam_unix(sshd:session): session closed for user core Oct 13 05:53:05.884399 systemd[1]: sshd@35-10.200.4.17:22-10.200.16.10:33536.service: Deactivated successfully. Oct 13 05:53:05.886036 systemd[1]: session-38.scope: Deactivated successfully. Oct 13 05:53:05.886731 systemd-logind[1897]: Session 38 logged out. Waiting for processes to exit. Oct 13 05:53:05.887628 systemd-logind[1897]: Removed session 38.