Sep 11 00:25:14.182839 kernel: Linux version 6.12.46-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.44 p1) 2.44.0) #1 SMP PREEMPT_DYNAMIC Wed Sep 10 22:25:29 -00 2025 Sep 11 00:25:14.182866 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=24178014e7d1a618b6c727661dc98ca9324f7f5aeefcaa5f4996d4d839e6e63a Sep 11 00:25:14.182876 kernel: BIOS-provided physical RAM map: Sep 11 00:25:14.182883 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Sep 11 00:25:14.182889 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Sep 11 00:25:14.182896 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Sep 11 00:25:14.182904 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Sep 11 00:25:14.182913 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Sep 11 00:25:14.182920 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Sep 11 00:25:14.182927 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Sep 11 00:25:14.182934 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Sep 11 00:25:14.182940 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Sep 11 00:25:14.182947 kernel: printk: legacy bootconsole [earlyser0] enabled Sep 11 00:25:14.182954 kernel: NX (Execute Disable) protection: active Sep 11 00:25:14.182965 kernel: APIC: Static calls initialized Sep 11 00:25:14.182973 kernel: efi: EFI v2.7 by Microsoft Sep 11 00:25:14.182981 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3ee5fa98 RNG=0x3ffd1018 Sep 11 00:25:14.182988 kernel: random: crng init done Sep 11 00:25:14.182996 kernel: secureboot: Secure boot disabled Sep 11 00:25:14.183003 kernel: SMBIOS 3.1.0 present. Sep 11 00:25:14.183011 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Sep 11 00:25:14.183019 kernel: DMI: Memory slots populated: 3/3 Sep 11 00:25:14.183026 kernel: Hypervisor detected: Microsoft Hyper-V Sep 11 00:25:14.183034 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Sep 11 00:25:14.183043 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Sep 11 00:25:14.183051 kernel: Hyper-V: Using hypercall for remote TLB flush Sep 11 00:25:14.183058 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Sep 11 00:25:14.183066 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Sep 11 00:25:14.183086 kernel: tsc: Marking TSC unstable due to running on Hyper-V Sep 11 00:25:14.183094 kernel: tsc: Detected 2593.993 MHz processor Sep 11 00:25:14.183101 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Sep 11 00:25:14.183110 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Sep 11 00:25:14.183117 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Sep 11 00:25:14.183128 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Sep 11 00:25:14.183136 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Sep 11 00:25:14.183143 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Sep 11 00:25:14.183151 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Sep 11 00:25:14.183158 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Sep 11 00:25:14.183166 kernel: Using GB pages for direct mapping Sep 11 00:25:14.183174 kernel: ACPI: Early table checksum verification disabled Sep 11 00:25:14.183185 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Sep 11 00:25:14.183196 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 11 00:25:14.183204 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 11 00:25:14.183212 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Sep 11 00:25:14.183220 kernel: ACPI: FACS 0x000000003FFFE000 000040 Sep 11 00:25:14.183228 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 11 00:25:14.183236 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 11 00:25:14.183247 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 11 00:25:14.183255 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 11 00:25:14.183263 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 11 00:25:14.183271 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 11 00:25:14.183279 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 11 00:25:14.183288 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Sep 11 00:25:14.183295 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Sep 11 00:25:14.183303 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Sep 11 00:25:14.183314 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Sep 11 00:25:14.183322 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Sep 11 00:25:14.183330 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Sep 11 00:25:14.183338 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Sep 11 00:25:14.183346 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Sep 11 00:25:14.183354 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Sep 11 00:25:14.183362 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Sep 11 00:25:14.183370 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Sep 11 00:25:14.183378 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Sep 11 00:25:14.183394 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Sep 11 00:25:14.183408 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Sep 11 00:25:14.183424 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Sep 11 00:25:14.183441 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Sep 11 00:25:14.183450 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Sep 11 00:25:14.183458 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Sep 11 00:25:14.183466 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] Sep 11 00:25:14.183475 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] Sep 11 00:25:14.183483 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] Sep 11 00:25:14.183494 kernel: Zone ranges: Sep 11 00:25:14.183502 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Sep 11 00:25:14.183510 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Sep 11 00:25:14.183518 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Sep 11 00:25:14.183526 kernel: Device empty Sep 11 00:25:14.183534 kernel: Movable zone start for each node Sep 11 00:25:14.183542 kernel: Early memory node ranges Sep 11 00:25:14.183550 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Sep 11 00:25:14.183558 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Sep 11 00:25:14.183569 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Sep 11 00:25:14.183577 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Sep 11 00:25:14.183585 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Sep 11 00:25:14.183593 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Sep 11 00:25:14.183601 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Sep 11 00:25:14.183609 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Sep 11 00:25:14.183617 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Sep 11 00:25:14.183625 kernel: ACPI: PM-Timer IO Port: 0x408 Sep 11 00:25:14.183633 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Sep 11 00:25:14.183643 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Sep 11 00:25:14.183651 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Sep 11 00:25:14.183660 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Sep 11 00:25:14.183668 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Sep 11 00:25:14.183676 kernel: CPU topo: Max. logical packages: 1 Sep 11 00:25:14.183687 kernel: CPU topo: Max. logical dies: 1 Sep 11 00:25:14.183695 kernel: CPU topo: Max. dies per package: 1 Sep 11 00:25:14.183703 kernel: CPU topo: Max. threads per core: 1 Sep 11 00:25:14.183711 kernel: CPU topo: Num. cores per package: 6 Sep 11 00:25:14.183722 kernel: CPU topo: Num. threads per package: 6 Sep 11 00:25:14.183730 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs Sep 11 00:25:14.183738 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Sep 11 00:25:14.183746 kernel: Booting paravirtualized kernel on Hyper-V Sep 11 00:25:14.183754 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Sep 11 00:25:14.183762 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Sep 11 00:25:14.183770 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 Sep 11 00:25:14.183778 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 Sep 11 00:25:14.183786 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Sep 11 00:25:14.183797 kernel: Hyper-V: PV spinlocks enabled Sep 11 00:25:14.183805 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Sep 11 00:25:14.183814 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=24178014e7d1a618b6c727661dc98ca9324f7f5aeefcaa5f4996d4d839e6e63a Sep 11 00:25:14.183822 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Sep 11 00:25:14.183831 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Sep 11 00:25:14.183839 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Sep 11 00:25:14.183847 kernel: Fallback order for Node 0: 0 Sep 11 00:25:14.183855 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 Sep 11 00:25:14.183865 kernel: Policy zone: Normal Sep 11 00:25:14.183881 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 11 00:25:14.183890 kernel: software IO TLB: area num 8. Sep 11 00:25:14.183900 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Sep 11 00:25:14.183909 kernel: Kernel/User page tables isolation: enabled Sep 11 00:25:14.183917 kernel: ftrace: allocating 40103 entries in 157 pages Sep 11 00:25:14.183926 kernel: ftrace: allocated 157 pages with 5 groups Sep 11 00:25:14.183934 kernel: Dynamic Preempt: voluntary Sep 11 00:25:14.183943 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 11 00:25:14.183952 kernel: rcu: RCU event tracing is enabled. Sep 11 00:25:14.183961 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Sep 11 00:25:14.183969 kernel: Trampoline variant of Tasks RCU enabled. Sep 11 00:25:14.183980 kernel: Rude variant of Tasks RCU enabled. Sep 11 00:25:14.183989 kernel: Tracing variant of Tasks RCU enabled. Sep 11 00:25:14.183997 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 11 00:25:14.184006 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Sep 11 00:25:14.184015 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Sep 11 00:25:14.184025 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Sep 11 00:25:14.184034 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Sep 11 00:25:14.184043 kernel: Using NULL legacy PIC Sep 11 00:25:14.184051 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Sep 11 00:25:14.184060 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 11 00:25:14.184076 kernel: Console: colour dummy device 80x25 Sep 11 00:25:14.184085 kernel: printk: legacy console [tty1] enabled Sep 11 00:25:14.184094 kernel: printk: legacy console [ttyS0] enabled Sep 11 00:25:14.184103 kernel: printk: legacy bootconsole [earlyser0] disabled Sep 11 00:25:14.184114 kernel: ACPI: Core revision 20240827 Sep 11 00:25:14.184125 kernel: Failed to register legacy timer interrupt Sep 11 00:25:14.184136 kernel: APIC: Switch to symmetric I/O mode setup Sep 11 00:25:14.184149 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Sep 11 00:25:14.184162 kernel: Hyper-V: enabling crash_kexec_post_notifiers Sep 11 00:25:14.184173 kernel: Hyper-V: Using IPI hypercalls Sep 11 00:25:14.184186 kernel: APIC: send_IPI() replaced with hv_send_ipi() Sep 11 00:25:14.184195 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Sep 11 00:25:14.184203 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Sep 11 00:25:14.184214 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Sep 11 00:25:14.184223 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Sep 11 00:25:14.184231 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Sep 11 00:25:14.184240 kernel: Hyper-V: Using enlightened APIC (xapic mode) Sep 11 00:25:14.184248 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Sep 11 00:25:14.184257 kernel: APIC: read() replaced with hv_apic_read() Sep 11 00:25:14.184265 kernel: APIC: write() replaced with hv_apic_write() Sep 11 00:25:14.184274 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Sep 11 00:25:14.184282 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Sep 11 00:25:14.184293 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593993) Sep 11 00:25:14.184302 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Sep 11 00:25:14.184311 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Sep 11 00:25:14.184319 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Sep 11 00:25:14.184327 kernel: Spectre V2 : Mitigation: Retpolines Sep 11 00:25:14.184336 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Sep 11 00:25:14.184344 kernel: Speculative Store Bypass: Vulnerable Sep 11 00:25:14.184353 kernel: MDS: Mitigation: Clear CPU buffers Sep 11 00:25:14.184367 kernel: TAA: Mitigation: Clear CPU buffers Sep 11 00:25:14.184381 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Sep 11 00:25:14.184393 kernel: active return thunk: its_return_thunk Sep 11 00:25:14.184401 kernel: ITS: Mitigation: Aligned branch/return thunks Sep 11 00:25:14.184409 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Sep 11 00:25:14.184418 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Sep 11 00:25:14.184426 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Sep 11 00:25:14.184440 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Sep 11 00:25:14.184450 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Sep 11 00:25:14.184458 kernel: Freeing SMP alternatives memory: 32K Sep 11 00:25:14.184467 kernel: pid_max: default: 32768 minimum: 301 Sep 11 00:25:14.184475 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Sep 11 00:25:14.184483 kernel: landlock: Up and running. Sep 11 00:25:14.184494 kernel: SELinux: Initializing. Sep 11 00:25:14.184503 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Sep 11 00:25:14.184511 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Sep 11 00:25:14.184520 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Sep 11 00:25:14.184528 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Sep 11 00:25:14.184537 kernel: signal: max sigframe size: 1776 Sep 11 00:25:14.184545 kernel: rcu: Hierarchical SRCU implementation. Sep 11 00:25:14.184554 kernel: rcu: Max phase no-delay instances is 400. Sep 11 00:25:14.184563 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Sep 11 00:25:14.184571 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Sep 11 00:25:14.184582 kernel: smp: Bringing up secondary CPUs ... Sep 11 00:25:14.184591 kernel: smpboot: x86: Booting SMP configuration: Sep 11 00:25:14.184599 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Sep 11 00:25:14.184608 kernel: smp: Brought up 1 node, 6 CPUs Sep 11 00:25:14.184616 kernel: smpboot: Total of 6 processors activated (31127.91 BogoMIPS) Sep 11 00:25:14.184625 kernel: Memory: 115341148K/117439364K available (14336K kernel code, 2429K rwdata, 9960K rodata, 53832K init, 1088K bss, 2087948K reserved, 0K cma-reserved) Sep 11 00:25:14.184634 kernel: devtmpfs: initialized Sep 11 00:25:14.184642 kernel: x86/mm: Memory block size: 1024MB Sep 11 00:25:14.184651 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Sep 11 00:25:14.184662 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 11 00:25:14.184671 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Sep 11 00:25:14.184679 kernel: pinctrl core: initialized pinctrl subsystem Sep 11 00:25:14.184688 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 11 00:25:14.184696 kernel: audit: initializing netlink subsys (disabled) Sep 11 00:25:14.184705 kernel: audit: type=2000 audit(1757550310.028:1): state=initialized audit_enabled=0 res=1 Sep 11 00:25:14.184713 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 11 00:25:14.184722 kernel: thermal_sys: Registered thermal governor 'user_space' Sep 11 00:25:14.184730 kernel: cpuidle: using governor menu Sep 11 00:25:14.184741 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 11 00:25:14.184750 kernel: dca service started, version 1.12.1 Sep 11 00:25:14.184758 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Sep 11 00:25:14.184767 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Sep 11 00:25:14.184776 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 11 00:25:14.184784 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Sep 11 00:25:14.184793 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 11 00:25:14.184801 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Sep 11 00:25:14.184810 kernel: ACPI: Added _OSI(Module Device) Sep 11 00:25:14.184820 kernel: ACPI: Added _OSI(Processor Device) Sep 11 00:25:14.184829 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 11 00:25:14.184837 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 11 00:25:14.184846 kernel: ACPI: Interpreter enabled Sep 11 00:25:14.184854 kernel: ACPI: PM: (supports S0 S5) Sep 11 00:25:14.184863 kernel: ACPI: Using IOAPIC for interrupt routing Sep 11 00:25:14.184872 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Sep 11 00:25:14.184880 kernel: PCI: Ignoring E820 reservations for host bridge windows Sep 11 00:25:14.184889 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Sep 11 00:25:14.184900 kernel: iommu: Default domain type: Translated Sep 11 00:25:14.184908 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Sep 11 00:25:14.184917 kernel: efivars: Registered efivars operations Sep 11 00:25:14.184925 kernel: PCI: Using ACPI for IRQ routing Sep 11 00:25:14.184934 kernel: PCI: System does not support PCI Sep 11 00:25:14.184942 kernel: vgaarb: loaded Sep 11 00:25:14.184951 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Sep 11 00:25:14.184959 kernel: VFS: Disk quotas dquot_6.6.0 Sep 11 00:25:14.184968 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 11 00:25:14.184979 kernel: pnp: PnP ACPI init Sep 11 00:25:14.184987 kernel: pnp: PnP ACPI: found 3 devices Sep 11 00:25:14.184996 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Sep 11 00:25:14.185005 kernel: NET: Registered PF_INET protocol family Sep 11 00:25:14.185013 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 11 00:25:14.185022 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Sep 11 00:25:14.185031 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 11 00:25:14.185039 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 11 00:25:14.185048 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Sep 11 00:25:14.185059 kernel: TCP: Hash tables configured (established 524288 bind 65536) Sep 11 00:25:14.185067 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Sep 11 00:25:14.185084 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Sep 11 00:25:14.185093 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 11 00:25:14.185101 kernel: NET: Registered PF_XDP protocol family Sep 11 00:25:14.185110 kernel: PCI: CLS 0 bytes, default 64 Sep 11 00:25:14.185118 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Sep 11 00:25:14.185127 kernel: software IO TLB: mapped [mem 0x000000003ae5f000-0x000000003ee5f000] (64MB) Sep 11 00:25:14.185136 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Sep 11 00:25:14.185147 kernel: Initialise system trusted keyrings Sep 11 00:25:14.185156 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Sep 11 00:25:14.185164 kernel: Key type asymmetric registered Sep 11 00:25:14.185173 kernel: Asymmetric key parser 'x509' registered Sep 11 00:25:14.185181 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Sep 11 00:25:14.185190 kernel: io scheduler mq-deadline registered Sep 11 00:25:14.185198 kernel: io scheduler kyber registered Sep 11 00:25:14.185207 kernel: io scheduler bfq registered Sep 11 00:25:14.185215 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Sep 11 00:25:14.185226 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 11 00:25:14.185235 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Sep 11 00:25:14.185244 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Sep 11 00:25:14.185252 kernel: i8042: PNP: No PS/2 controller found. Sep 11 00:25:14.185400 kernel: rtc_cmos 00:02: registered as rtc0 Sep 11 00:25:14.185516 kernel: rtc_cmos 00:02: setting system clock to 2025-09-11T00:25:13 UTC (1757550313) Sep 11 00:25:14.185613 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Sep 11 00:25:14.185631 kernel: intel_pstate: CPU model not supported Sep 11 00:25:14.185640 kernel: efifb: probing for efifb Sep 11 00:25:14.185649 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Sep 11 00:25:14.185657 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Sep 11 00:25:14.185666 kernel: efifb: scrolling: redraw Sep 11 00:25:14.185674 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Sep 11 00:25:14.185683 kernel: Console: switching to colour frame buffer device 128x48 Sep 11 00:25:14.185692 kernel: fb0: EFI VGA frame buffer device Sep 11 00:25:14.185700 kernel: pstore: Using crash dump compression: deflate Sep 11 00:25:14.185712 kernel: pstore: Registered efi_pstore as persistent store backend Sep 11 00:25:14.185720 kernel: NET: Registered PF_INET6 protocol family Sep 11 00:25:14.185729 kernel: Segment Routing with IPv6 Sep 11 00:25:14.185737 kernel: In-situ OAM (IOAM) with IPv6 Sep 11 00:25:14.185746 kernel: NET: Registered PF_PACKET protocol family Sep 11 00:25:14.185754 kernel: Key type dns_resolver registered Sep 11 00:25:14.185763 kernel: IPI shorthand broadcast: enabled Sep 11 00:25:14.185775 kernel: sched_clock: Marking stable (3669005100, 111823800)->(4066942500, -286113600) Sep 11 00:25:14.185784 kernel: registered taskstats version 1 Sep 11 00:25:14.185795 kernel: Loading compiled-in X.509 certificates Sep 11 00:25:14.185804 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.46-flatcar: 8138ce5002a1b572fd22b23ac238f29bab3f249f' Sep 11 00:25:14.185812 kernel: Demotion targets for Node 0: null Sep 11 00:25:14.185821 kernel: Key type .fscrypt registered Sep 11 00:25:14.185829 kernel: Key type fscrypt-provisioning registered Sep 11 00:25:14.185838 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 11 00:25:14.185846 kernel: ima: Allocated hash algorithm: sha1 Sep 11 00:25:14.185855 kernel: ima: No architecture policies found Sep 11 00:25:14.185863 kernel: clk: Disabling unused clocks Sep 11 00:25:14.185874 kernel: Warning: unable to open an initial console. Sep 11 00:25:14.185883 kernel: Freeing unused kernel image (initmem) memory: 53832K Sep 11 00:25:14.185892 kernel: Write protecting the kernel read-only data: 24576k Sep 11 00:25:14.185900 kernel: Freeing unused kernel image (rodata/data gap) memory: 280K Sep 11 00:25:14.185909 kernel: Run /init as init process Sep 11 00:25:14.185917 kernel: with arguments: Sep 11 00:25:14.185926 kernel: /init Sep 11 00:25:14.185934 kernel: with environment: Sep 11 00:25:14.185942 kernel: HOME=/ Sep 11 00:25:14.185953 kernel: TERM=linux Sep 11 00:25:14.185962 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 11 00:25:14.185972 systemd[1]: Successfully made /usr/ read-only. Sep 11 00:25:14.185983 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Sep 11 00:25:14.185993 systemd[1]: Detected virtualization microsoft. Sep 11 00:25:14.186002 systemd[1]: Detected architecture x86-64. Sep 11 00:25:14.186010 systemd[1]: Running in initrd. Sep 11 00:25:14.186019 systemd[1]: No hostname configured, using default hostname. Sep 11 00:25:14.186031 systemd[1]: Hostname set to . Sep 11 00:25:14.186040 systemd[1]: Initializing machine ID from random generator. Sep 11 00:25:14.186049 systemd[1]: Queued start job for default target initrd.target. Sep 11 00:25:14.186058 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 11 00:25:14.186067 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 11 00:25:14.186089 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 11 00:25:14.186098 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 11 00:25:14.186108 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 11 00:25:14.186121 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 11 00:25:14.186131 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Sep 11 00:25:14.186140 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 11 00:25:14.186150 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 11 00:25:14.186159 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 11 00:25:14.186168 systemd[1]: Reached target paths.target - Path Units. Sep 11 00:25:14.186180 systemd[1]: Reached target slices.target - Slice Units. Sep 11 00:25:14.186189 systemd[1]: Reached target swap.target - Swaps. Sep 11 00:25:14.186198 systemd[1]: Reached target timers.target - Timer Units. Sep 11 00:25:14.186207 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 11 00:25:14.186216 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 11 00:25:14.186226 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 11 00:25:14.186235 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Sep 11 00:25:14.186244 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 11 00:25:14.186253 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 11 00:25:14.186265 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 11 00:25:14.186274 systemd[1]: Reached target sockets.target - Socket Units. Sep 11 00:25:14.186283 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 11 00:25:14.186292 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 11 00:25:14.186301 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 11 00:25:14.186311 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). Sep 11 00:25:14.186320 systemd[1]: Starting systemd-fsck-usr.service... Sep 11 00:25:14.186329 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 11 00:25:14.186341 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 11 00:25:14.186350 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 11 00:25:14.186379 systemd-journald[232]: Collecting audit messages is disabled. Sep 11 00:25:14.186401 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 11 00:25:14.186415 systemd-journald[232]: Journal started Sep 11 00:25:14.186437 systemd-journald[232]: Runtime Journal (/run/log/journal/17c6eb5f239c4ea9872c982650337b8f) is 8M, max 2.2G, 2.1G free. Sep 11 00:25:14.184390 systemd-modules-load[234]: Inserted module 'overlay' Sep 11 00:25:14.195103 systemd[1]: Started systemd-journald.service - Journal Service. Sep 11 00:25:14.195455 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 11 00:25:14.196302 systemd[1]: Finished systemd-fsck-usr.service. Sep 11 00:25:14.198471 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 11 00:25:14.199501 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 11 00:25:14.242584 systemd-tmpfiles[243]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Sep 11 00:25:14.250661 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 11 00:25:14.259564 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 11 00:25:14.269789 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 11 00:25:14.280458 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 11 00:25:14.285763 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 11 00:25:14.312090 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Sep 11 00:25:14.316365 systemd-modules-load[234]: Inserted module 'br_netfilter' Sep 11 00:25:14.317813 kernel: Bridge firewalling registered Sep 11 00:25:14.317325 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 11 00:25:14.319361 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 11 00:25:14.321149 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 11 00:25:14.345466 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 11 00:25:14.347699 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 11 00:25:14.366564 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 11 00:25:14.369314 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 11 00:25:14.376143 dracut-cmdline[272]: 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=24178014e7d1a618b6c727661dc98ca9324f7f5aeefcaa5f4996d4d839e6e63a Sep 11 00:25:14.431620 systemd-resolved[284]: Positive Trust Anchors: Sep 11 00:25:14.431637 systemd-resolved[284]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 11 00:25:14.431668 systemd-resolved[284]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Sep 11 00:25:14.434221 systemd-resolved[284]: Defaulting to hostname 'linux'. Sep 11 00:25:14.435221 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 11 00:25:14.444516 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 11 00:25:14.532096 kernel: SCSI subsystem initialized Sep 11 00:25:14.542089 kernel: Loading iSCSI transport class v2.0-870. Sep 11 00:25:14.555093 kernel: iscsi: registered transport (tcp) Sep 11 00:25:14.577541 kernel: iscsi: registered transport (qla4xxx) Sep 11 00:25:14.577567 kernel: QLogic iSCSI HBA Driver Sep 11 00:25:14.595653 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 11 00:25:14.621263 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 11 00:25:14.632879 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 11 00:25:14.687379 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 11 00:25:14.690054 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 11 00:25:14.766469 kernel: raid6: avx2x4 gen() 30839 MB/s Sep 11 00:25:14.782089 kernel: raid6: avx2x2 gen() 30821 MB/s Sep 11 00:25:14.803213 kernel: raid6: avx2x1 gen() 26934 MB/s Sep 11 00:25:14.803249 kernel: raid6: using algorithm avx2x4 gen() 30839 MB/s Sep 11 00:25:14.826223 kernel: raid6: .... xor() 10037 MB/s, rmw enabled Sep 11 00:25:14.826250 kernel: raid6: using avx2x2 recovery algorithm Sep 11 00:25:14.849098 kernel: xor: automatically using best checksumming function avx Sep 11 00:25:15.021099 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 11 00:25:15.027840 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 11 00:25:15.037643 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 11 00:25:15.068646 systemd-udevd[487]: Using default interface naming scheme 'v255'. Sep 11 00:25:15.074737 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 11 00:25:15.076946 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 11 00:25:15.113996 dracut-pre-trigger[495]: rd.md=0: removing MD RAID activation Sep 11 00:25:15.140436 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 11 00:25:15.144712 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 11 00:25:15.223950 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 11 00:25:15.240537 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 11 00:25:15.256108 kernel: cryptd: max_cpu_qlen set to 1000 Sep 11 00:25:15.267115 kernel: AES CTR mode by8 optimization enabled Sep 11 00:25:15.278089 kernel: hv_vmbus: Vmbus version:4.0 Sep 11 00:25:15.291659 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 11 00:25:15.323133 kernel: hv_vmbus: registering driver hyperv_keyboard Sep 11 00:25:15.323156 kernel: hv_vmbus: registering driver hv_pci Sep 11 00:25:15.323169 kernel: pps_core: LinuxPPS API ver. 1 registered Sep 11 00:25:15.323187 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Sep 11 00:25:15.323356 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Sep 11 00:25:15.323370 kernel: input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/VMBUS:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input0 Sep 11 00:25:15.323382 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Sep 11 00:25:15.293466 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 11 00:25:15.347795 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Sep 11 00:25:15.347971 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Sep 11 00:25:15.348138 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Sep 11 00:25:15.348267 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint Sep 11 00:25:15.348295 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] Sep 11 00:25:15.347802 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 11 00:25:15.365850 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] Sep 11 00:25:15.365882 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] Sep 11 00:25:15.365901 kernel: pci 0001:00:00.0: Enabling HDA controller Sep 11 00:25:15.366241 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 11 00:25:15.375890 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 11 00:25:15.386123 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Sep 11 00:25:15.386287 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned Sep 11 00:25:15.386438 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned Sep 11 00:25:15.394046 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 11 00:25:15.399334 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned Sep 11 00:25:15.394163 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 11 00:25:15.409859 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 11 00:25:15.414255 kernel: hv_vmbus: registering driver hv_netvsc Sep 11 00:25:15.424111 kernel: hid: raw HID events driver (C) Jiri Kosina Sep 11 00:25:15.424149 kernel: hv_vmbus: registering driver hv_storvsc Sep 11 00:25:15.427088 kernel: PTP clock support registered Sep 11 00:25:15.429089 kernel: hv_vmbus: registering driver hid_hyperv Sep 11 00:25:15.429117 kernel: scsi host1: storvsc_host_t Sep 11 00:25:15.429159 kernel: scsi host0: storvsc_host_t Sep 11 00:25:15.432088 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Sep 11 00:25:15.432137 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Sep 11 00:25:15.444462 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Sep 11 00:25:15.444694 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Sep 11 00:25:15.449094 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Sep 11 00:25:15.470036 kernel: hv_utils: Registering HyperV Utility Driver Sep 11 00:25:15.470080 kernel: hv_vmbus: registering driver hv_utils Sep 11 00:25:15.473182 kernel: hv_utils: Heartbeat IC version 3.0 Sep 11 00:25:15.473226 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Sep 11 00:25:15.473416 kernel: hv_utils: Shutdown IC version 3.2 Sep 11 00:25:15.473431 kernel: hv_utils: TimeSync IC version 4.0 Sep 11 00:25:15.473442 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Sep 11 00:25:15.473590 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks Sep 11 00:25:15.479063 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Sep 11 00:25:15.479664 kernel: sd 0:0:0:1: [sdb] Write Protect is off Sep 11 00:25:16.184728 systemd-resolved[284]: Clock change detected. Flushing caches. Sep 11 00:25:16.224296 kernel: sd 0:0:0:0: [sda] Write Protect is off Sep 11 00:25:16.224477 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Sep 11 00:25:16.224619 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Sep 11 00:25:16.224776 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Sep 11 00:25:16.224910 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Sep 11 00:25:16.225048 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#195 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 11 00:25:16.225181 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#202 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 11 00:25:16.223889 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 11 00:25:16.240915 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#371 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 11 00:25:16.241076 kernel: sdb: sdb1 Sep 11 00:25:16.242691 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk Sep 11 00:25:16.245689 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#378 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 11 00:25:16.275792 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 11 00:25:16.275822 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Sep 11 00:25:16.283923 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Sep 11 00:25:16.284119 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Sep 11 00:25:16.286690 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Sep 11 00:25:16.978308 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Sep 11 00:25:16.991424 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Sep 11 00:25:17.004245 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Sep 11 00:25:17.060166 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Sep 11 00:25:17.061649 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Sep 11 00:25:17.073554 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 11 00:25:17.116701 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#547 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 11 00:25:17.139700 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 11 00:25:18.029319 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 11 00:25:18.037971 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 11 00:25:18.047569 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 11 00:25:18.049005 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 11 00:25:18.066960 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 11 00:25:18.093764 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 11 00:25:18.152958 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#561 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 11 00:25:18.170687 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 11 00:25:18.171668 disk-uuid[726]: The operation has completed successfully. Sep 11 00:25:18.241793 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 11 00:25:18.241897 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 11 00:25:18.295683 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 11 00:25:18.326505 sh[898]: Success Sep 11 00:25:18.364847 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 11 00:25:18.364884 kernel: device-mapper: uevent: version 1.0.3 Sep 11 00:25:18.369606 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Sep 11 00:25:18.380695 kernel: device-mapper: verity: sha256 using shash "sha256-avx2" Sep 11 00:25:18.726428 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 11 00:25:18.729745 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 11 00:25:18.749164 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 11 00:25:18.774690 kernel: BTRFS: device fsid f1eb5eb7-34cc-49c0-9f2b-e603bd772d66 devid 1 transid 39 /dev/mapper/usr (254:0) scanned by mount (916) Sep 11 00:25:18.774723 kernel: BTRFS info (device dm-0): first mount of filesystem f1eb5eb7-34cc-49c0-9f2b-e603bd772d66 Sep 11 00:25:18.783211 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Sep 11 00:25:19.192006 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 11 00:25:19.192091 kernel: BTRFS info (device dm-0): enabling free space tree Sep 11 00:25:19.264572 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 11 00:25:19.266525 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Sep 11 00:25:19.272625 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 11 00:25:19.273423 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 11 00:25:19.311168 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 11 00:25:19.345698 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (946) Sep 11 00:25:19.354285 kernel: BTRFS info (device sda6): first mount of filesystem a5de7b5e-e14d-4c62-883d-af7ea22fae7e Sep 11 00:25:19.354320 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Sep 11 00:25:19.404847 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 11 00:25:19.415008 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 11 00:25:19.430074 kernel: BTRFS info (device sda6): turning on async discard Sep 11 00:25:19.430101 kernel: BTRFS info (device sda6): enabling free space tree Sep 11 00:25:19.438688 kernel: BTRFS info (device sda6): last unmount of filesystem a5de7b5e-e14d-4c62-883d-af7ea22fae7e Sep 11 00:25:19.439281 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 11 00:25:19.442121 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 11 00:25:19.477313 systemd-networkd[1079]: lo: Link UP Sep 11 00:25:19.477324 systemd-networkd[1079]: lo: Gained carrier Sep 11 00:25:19.478356 systemd-networkd[1079]: Enumeration completed Sep 11 00:25:19.478450 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 11 00:25:19.478724 systemd-networkd[1079]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 11 00:25:19.478729 systemd-networkd[1079]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 11 00:25:19.479512 systemd-networkd[1079]: eth0: Link UP Sep 11 00:25:19.479683 systemd-networkd[1079]: eth0: Gained carrier Sep 11 00:25:19.479694 systemd-networkd[1079]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 11 00:25:19.484239 systemd[1]: Reached target network.target - Network. Sep 11 00:25:19.529716 systemd-networkd[1079]: eth0: DHCPv4 address 10.200.8.35/24, gateway 10.200.8.1 acquired from 168.63.129.16 Sep 11 00:25:21.202778 systemd-networkd[1079]: eth0: Gained IPv6LL Sep 11 00:25:21.337377 ignition[1086]: Ignition 2.21.0 Sep 11 00:25:21.337406 ignition[1086]: Stage: fetch-offline Sep 11 00:25:21.337521 ignition[1086]: no configs at "/usr/lib/ignition/base.d" Sep 11 00:25:21.337532 ignition[1086]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 11 00:25:21.337657 ignition[1086]: parsed url from cmdline: "" Sep 11 00:25:21.337661 ignition[1086]: no config URL provided Sep 11 00:25:21.337667 ignition[1086]: reading system config file "/usr/lib/ignition/user.ign" Sep 11 00:25:21.337691 ignition[1086]: no config at "/usr/lib/ignition/user.ign" Sep 11 00:25:21.337700 ignition[1086]: failed to fetch config: resource requires networking Sep 11 00:25:21.337859 ignition[1086]: Ignition finished successfully Sep 11 00:25:21.367757 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 11 00:25:21.370223 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Sep 11 00:25:21.413114 ignition[1098]: Ignition 2.21.0 Sep 11 00:25:21.413127 ignition[1098]: Stage: fetch Sep 11 00:25:21.413614 ignition[1098]: no configs at "/usr/lib/ignition/base.d" Sep 11 00:25:21.413627 ignition[1098]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 11 00:25:21.413763 ignition[1098]: parsed url from cmdline: "" Sep 11 00:25:21.413768 ignition[1098]: no config URL provided Sep 11 00:25:21.413774 ignition[1098]: reading system config file "/usr/lib/ignition/user.ign" Sep 11 00:25:21.413788 ignition[1098]: no config at "/usr/lib/ignition/user.ign" Sep 11 00:25:21.413824 ignition[1098]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Sep 11 00:25:21.534747 ignition[1098]: GET result: OK Sep 11 00:25:21.534810 ignition[1098]: config has been read from IMDS userdata Sep 11 00:25:21.534823 ignition[1098]: parsing config with SHA512: a15ff897478d6089a303d18faed3d2a2ca9cb83031fb8c0f8699107a233bc2d19d968355eae34034ec5536273a5a18c8ff32e0cf46b5f0017249e3791f932d4f Sep 11 00:25:21.538510 unknown[1098]: fetched base config from "system" Sep 11 00:25:21.538667 ignition[1098]: fetch: fetch complete Sep 11 00:25:21.538517 unknown[1098]: fetched base config from "system" Sep 11 00:25:21.538689 ignition[1098]: fetch: fetch passed Sep 11 00:25:21.538523 unknown[1098]: fetched user config from "azure" Sep 11 00:25:21.538733 ignition[1098]: Ignition finished successfully Sep 11 00:25:21.545857 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Sep 11 00:25:21.548584 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 11 00:25:21.596984 ignition[1108]: Ignition 2.21.0 Sep 11 00:25:21.596997 ignition[1108]: Stage: kargs Sep 11 00:25:21.597237 ignition[1108]: no configs at "/usr/lib/ignition/base.d" Sep 11 00:25:21.597248 ignition[1108]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 11 00:25:21.598418 ignition[1108]: kargs: kargs passed Sep 11 00:25:21.605339 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 11 00:25:21.598494 ignition[1108]: Ignition finished successfully Sep 11 00:25:21.613727 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 11 00:25:21.656611 ignition[1118]: Ignition 2.21.0 Sep 11 00:25:21.656624 ignition[1118]: Stage: disks Sep 11 00:25:21.656927 ignition[1118]: no configs at "/usr/lib/ignition/base.d" Sep 11 00:25:21.656941 ignition[1118]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 11 00:25:21.662249 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 11 00:25:21.657928 ignition[1118]: disks: disks passed Sep 11 00:25:21.668538 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 11 00:25:21.658072 ignition[1118]: Ignition finished successfully Sep 11 00:25:21.676946 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 11 00:25:21.685398 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 11 00:25:21.691767 systemd[1]: Reached target sysinit.target - System Initialization. Sep 11 00:25:21.693184 systemd[1]: Reached target basic.target - Basic System. Sep 11 00:25:21.705078 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 11 00:25:21.805318 systemd-fsck[1130]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks Sep 11 00:25:21.810429 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 11 00:25:21.820516 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 11 00:25:23.837687 kernel: EXT4-fs (sda9): mounted filesystem 6a9ce0af-81d0-4628-9791-e47488ed2744 r/w with ordered data mode. Quota mode: none. Sep 11 00:25:23.838247 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 11 00:25:23.840235 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 11 00:25:23.881263 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 11 00:25:23.907162 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 11 00:25:23.909617 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Sep 11 00:25:23.935690 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1145) Sep 11 00:25:23.935717 kernel: BTRFS info (device sda6): first mount of filesystem a5de7b5e-e14d-4c62-883d-af7ea22fae7e Sep 11 00:25:23.935730 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Sep 11 00:25:23.914970 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Sep 11 00:25:23.915001 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 11 00:25:23.938272 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 11 00:25:23.950739 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 11 00:25:23.973273 kernel: BTRFS info (device sda6): turning on async discard Sep 11 00:25:23.973295 kernel: BTRFS info (device sda6): enabling free space tree Sep 11 00:25:23.974746 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 11 00:25:25.028902 coreos-metadata[1147]: Sep 11 00:25:25.028 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Sep 11 00:25:25.034979 coreos-metadata[1147]: Sep 11 00:25:25.031 INFO Fetch successful Sep 11 00:25:25.034979 coreos-metadata[1147]: Sep 11 00:25:25.031 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Sep 11 00:25:25.046654 coreos-metadata[1147]: Sep 11 00:25:25.039 INFO Fetch successful Sep 11 00:25:25.062345 coreos-metadata[1147]: Sep 11 00:25:25.062 INFO wrote hostname ci-4372.1.0-n-10814f282e to /sysroot/etc/hostname Sep 11 00:25:25.069463 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Sep 11 00:25:25.256231 initrd-setup-root[1175]: cut: /sysroot/etc/passwd: No such file or directory Sep 11 00:25:25.355476 initrd-setup-root[1182]: cut: /sysroot/etc/group: No such file or directory Sep 11 00:25:25.371565 initrd-setup-root[1189]: cut: /sysroot/etc/shadow: No such file or directory Sep 11 00:25:25.378779 initrd-setup-root[1196]: cut: /sysroot/etc/gshadow: No such file or directory Sep 11 00:25:26.746450 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 11 00:25:26.749200 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 11 00:25:26.757072 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 11 00:25:26.778975 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 11 00:25:26.786339 kernel: BTRFS info (device sda6): last unmount of filesystem a5de7b5e-e14d-4c62-883d-af7ea22fae7e Sep 11 00:25:26.805858 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 11 00:25:26.822828 ignition[1264]: INFO : Ignition 2.21.0 Sep 11 00:25:26.822828 ignition[1264]: INFO : Stage: mount Sep 11 00:25:26.828398 ignition[1264]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 11 00:25:26.828398 ignition[1264]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 11 00:25:26.828398 ignition[1264]: INFO : mount: mount passed Sep 11 00:25:26.828398 ignition[1264]: INFO : Ignition finished successfully Sep 11 00:25:26.829283 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 11 00:25:26.837641 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 11 00:25:26.873182 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 11 00:25:26.895683 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1279) Sep 11 00:25:26.904536 kernel: BTRFS info (device sda6): first mount of filesystem a5de7b5e-e14d-4c62-883d-af7ea22fae7e Sep 11 00:25:26.904560 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Sep 11 00:25:26.913968 kernel: BTRFS info (device sda6): turning on async discard Sep 11 00:25:26.913992 kernel: BTRFS info (device sda6): enabling free space tree Sep 11 00:25:26.916117 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 11 00:25:26.960869 ignition[1296]: INFO : Ignition 2.21.0 Sep 11 00:25:26.960869 ignition[1296]: INFO : Stage: files Sep 11 00:25:26.966466 ignition[1296]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 11 00:25:26.966466 ignition[1296]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 11 00:25:26.966466 ignition[1296]: DEBUG : files: compiled without relabeling support, skipping Sep 11 00:25:26.993439 ignition[1296]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 11 00:25:26.993439 ignition[1296]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 11 00:25:27.077285 ignition[1296]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 11 00:25:27.082398 ignition[1296]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 11 00:25:27.082398 ignition[1296]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 11 00:25:27.078051 unknown[1296]: wrote ssh authorized keys file for user: core Sep 11 00:25:27.141958 ignition[1296]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 11 00:25:27.148709 ignition[1296]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 11 00:25:27.148709 ignition[1296]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 11 00:25:27.148709 ignition[1296]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 11 00:25:27.148709 ignition[1296]: INFO : files: files passed Sep 11 00:25:27.148709 ignition[1296]: INFO : Ignition finished successfully Sep 11 00:25:27.156442 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 11 00:25:27.167218 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 11 00:25:27.177019 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 11 00:25:27.202425 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 11 00:25:27.202529 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 11 00:25:27.242812 initrd-setup-root-after-ignition[1329]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 11 00:25:27.242812 initrd-setup-root-after-ignition[1329]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 11 00:25:27.260176 initrd-setup-root-after-ignition[1333]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 11 00:25:27.244655 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 11 00:25:27.250567 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 11 00:25:27.270531 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 11 00:25:27.314332 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 11 00:25:27.314436 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 11 00:25:27.323104 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 11 00:25:27.331866 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 11 00:25:27.333319 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 11 00:25:27.343511 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 11 00:25:27.386622 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 11 00:25:27.389311 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 11 00:25:27.419282 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 11 00:25:27.420873 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 11 00:25:27.429774 systemd[1]: Stopped target timers.target - Timer Units. Sep 11 00:25:27.438808 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 11 00:25:27.438932 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 11 00:25:27.452684 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 11 00:25:27.461095 systemd[1]: Stopped target basic.target - Basic System. Sep 11 00:25:27.468381 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 11 00:25:27.476026 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 11 00:25:27.477473 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 11 00:25:27.486069 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Sep 11 00:25:27.501802 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 11 00:25:27.510189 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 11 00:25:27.518631 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 11 00:25:27.527471 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 11 00:25:27.535185 systemd[1]: Stopped target swap.target - Swaps. Sep 11 00:25:27.542028 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 11 00:25:27.542155 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 11 00:25:27.552611 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 11 00:25:27.560802 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 11 00:25:27.574099 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 11 00:25:27.574246 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 11 00:25:27.576378 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 11 00:25:27.576979 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 11 00:25:27.595892 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 11 00:25:27.596021 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 11 00:25:27.604733 systemd[1]: ignition-files.service: Deactivated successfully. Sep 11 00:25:27.604854 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 11 00:25:27.612873 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Sep 11 00:25:27.612987 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Sep 11 00:25:27.623356 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 11 00:25:27.650939 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 11 00:25:27.652413 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 11 00:25:27.652539 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 11 00:25:27.659378 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 11 00:25:27.659796 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 11 00:25:27.688134 ignition[1353]: INFO : Ignition 2.21.0 Sep 11 00:25:27.688134 ignition[1353]: INFO : Stage: umount Sep 11 00:25:27.688134 ignition[1353]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 11 00:25:27.688134 ignition[1353]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 11 00:25:27.688134 ignition[1353]: INFO : umount: umount passed Sep 11 00:25:27.688134 ignition[1353]: INFO : Ignition finished successfully Sep 11 00:25:27.678393 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 11 00:25:27.678494 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 11 00:25:27.689404 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 11 00:25:27.689536 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 11 00:25:27.694986 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 11 00:25:27.695044 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 11 00:25:27.701316 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 11 00:25:27.701368 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 11 00:25:27.708986 systemd[1]: ignition-fetch.service: Deactivated successfully. Sep 11 00:25:27.709039 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Sep 11 00:25:27.711072 systemd[1]: Stopped target network.target - Network. Sep 11 00:25:27.721737 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 11 00:25:27.721789 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 11 00:25:27.728491 systemd[1]: Stopped target paths.target - Path Units. Sep 11 00:25:27.736499 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 11 00:25:27.741718 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 11 00:25:27.744484 systemd[1]: Stopped target slices.target - Slice Units. Sep 11 00:25:27.753232 systemd[1]: Stopped target sockets.target - Socket Units. Sep 11 00:25:27.760338 systemd[1]: iscsid.socket: Deactivated successfully. Sep 11 00:25:27.760383 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 11 00:25:27.767435 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 11 00:25:27.767473 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 11 00:25:27.774668 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 11 00:25:27.774733 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 11 00:25:27.781926 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 11 00:25:27.781971 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 11 00:25:27.791563 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 11 00:25:27.798661 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 11 00:25:27.810366 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 11 00:25:27.810951 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 11 00:25:27.811049 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 11 00:25:27.821524 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Sep 11 00:25:27.821822 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 11 00:25:27.821919 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 11 00:25:27.826159 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 11 00:25:27.826258 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 11 00:25:27.840282 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Sep 11 00:25:27.841602 systemd[1]: Stopped target network-pre.target - Preparation for Network. Sep 11 00:25:27.846437 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 11 00:25:27.846501 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 11 00:25:27.853869 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 11 00:25:27.853930 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 11 00:25:27.862877 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 11 00:25:27.868742 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 11 00:25:27.868793 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 11 00:25:27.875613 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 11 00:25:27.875660 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 11 00:25:27.889756 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 11 00:25:27.889802 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 11 00:25:27.891193 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 11 00:25:27.891235 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 11 00:25:27.909196 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 11 00:25:27.911606 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Sep 11 00:25:27.911666 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Sep 11 00:25:27.952133 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 11 00:25:27.952282 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 11 00:25:27.961178 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 11 00:25:27.961272 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 11 00:25:27.970232 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 11 00:25:27.970324 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 11 00:25:27.977743 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 11 00:25:27.977798 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 11 00:25:27.985471 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 11 00:25:27.985534 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 11 00:25:27.995881 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 11 00:25:27.995931 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 11 00:25:28.009247 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 11 00:25:28.009300 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 11 00:25:28.023716 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 11 00:25:28.028697 systemd[1]: systemd-network-generator.service: Deactivated successfully. Sep 11 00:25:28.028753 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Sep 11 00:25:28.041515 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 11 00:25:28.041566 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 11 00:25:28.050048 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Sep 11 00:25:28.050094 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 11 00:25:28.060003 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 11 00:25:28.060052 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 11 00:25:28.067901 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 11 00:25:28.067947 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 11 00:25:28.079030 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Sep 11 00:25:28.079085 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev\x2dearly.service.mount: Deactivated successfully. Sep 11 00:25:28.079122 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Sep 11 00:25:28.079162 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 11 00:25:28.079554 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 11 00:25:28.079646 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 11 00:25:28.083879 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 11 00:25:28.093228 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 11 00:25:28.123967 systemd[1]: Switching root. Sep 11 00:25:28.291343 systemd-journald[232]: Journal stopped Sep 11 00:25:36.496513 systemd-journald[232]: Received SIGTERM from PID 1 (systemd). Sep 11 00:25:36.496544 kernel: SELinux: policy capability network_peer_controls=1 Sep 11 00:25:36.496557 kernel: SELinux: policy capability open_perms=1 Sep 11 00:25:36.496567 kernel: SELinux: policy capability extended_socket_class=1 Sep 11 00:25:36.496576 kernel: SELinux: policy capability always_check_network=0 Sep 11 00:25:36.496589 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 11 00:25:36.496599 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 11 00:25:36.496612 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 11 00:25:36.496621 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 11 00:25:36.496631 kernel: SELinux: policy capability userspace_initial_context=0 Sep 11 00:25:36.496641 kernel: audit: type=1403 audit(1757550329.293:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 11 00:25:36.496651 systemd[1]: Successfully loaded SELinux policy in 269.890ms. Sep 11 00:25:36.496663 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 15.410ms. Sep 11 00:25:36.496686 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Sep 11 00:25:36.496720 systemd[1]: Detected virtualization microsoft. Sep 11 00:25:36.496732 systemd[1]: Detected architecture x86-64. Sep 11 00:25:36.496743 systemd[1]: Detected first boot. Sep 11 00:25:36.496754 systemd[1]: Hostname set to . Sep 11 00:25:36.496764 systemd[1]: Initializing machine ID from random generator. Sep 11 00:25:36.496777 zram_generator::config[1398]: No configuration found. Sep 11 00:25:36.496789 kernel: Guest personality initialized and is inactive Sep 11 00:25:36.496799 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Sep 11 00:25:36.496809 kernel: Initialized host personality Sep 11 00:25:36.496818 kernel: NET: Registered PF_VSOCK protocol family Sep 11 00:25:36.496829 systemd[1]: Populated /etc with preset unit settings. Sep 11 00:25:36.496841 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Sep 11 00:25:36.496857 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 11 00:25:36.496871 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 11 00:25:36.496894 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 11 00:25:36.496912 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 11 00:25:36.496925 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 11 00:25:36.496936 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 11 00:25:36.496947 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 11 00:25:36.496962 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 11 00:25:36.496973 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 11 00:25:36.496984 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 11 00:25:36.496995 systemd[1]: Created slice user.slice - User and Session Slice. Sep 11 00:25:36.497006 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 11 00:25:36.497017 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 11 00:25:36.497028 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 11 00:25:36.497042 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 11 00:25:36.497054 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 11 00:25:36.497068 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 11 00:25:36.497079 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Sep 11 00:25:36.497090 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 11 00:25:36.497101 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 11 00:25:36.497112 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 11 00:25:36.497127 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 11 00:25:36.497138 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 11 00:25:36.497152 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 11 00:25:36.497163 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 11 00:25:36.497174 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 11 00:25:36.497186 systemd[1]: Reached target slices.target - Slice Units. Sep 11 00:25:36.497196 systemd[1]: Reached target swap.target - Swaps. Sep 11 00:25:36.497208 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 11 00:25:36.497218 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 11 00:25:36.497245 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Sep 11 00:25:36.497260 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 11 00:25:36.497272 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 11 00:25:36.497283 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 11 00:25:36.497295 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 11 00:25:36.497306 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 11 00:25:36.497320 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 11 00:25:36.497332 systemd[1]: Mounting media.mount - External Media Directory... Sep 11 00:25:36.497343 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 11 00:25:36.497354 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 11 00:25:36.497366 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 11 00:25:36.497377 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 11 00:25:36.497392 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Sep 11 00:25:36.497404 systemd[1]: Reached target machines.target - Containers. Sep 11 00:25:36.497418 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 11 00:25:36.497430 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 11 00:25:36.497441 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 11 00:25:36.497453 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 11 00:25:36.497464 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 11 00:25:36.497475 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 11 00:25:36.497486 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 11 00:25:36.497498 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 11 00:25:36.497511 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 11 00:25:36.497523 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 11 00:25:36.497534 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 11 00:25:36.497545 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 11 00:25:36.497556 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 11 00:25:36.497568 systemd[1]: Stopped systemd-fsck-usr.service. Sep 11 00:25:36.497579 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 11 00:25:36.497591 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 11 00:25:36.497602 kernel: loop: module loaded Sep 11 00:25:36.497615 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 11 00:25:36.497626 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 11 00:25:36.497641 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 11 00:25:36.497689 systemd-journald[1498]: Collecting audit messages is disabled. Sep 11 00:25:36.497723 kernel: fuse: init (API version 7.41) Sep 11 00:25:36.497736 systemd-journald[1498]: Journal started Sep 11 00:25:36.497758 systemd-journald[1498]: Runtime Journal (/run/log/journal/4d9d74beb18e484388d8b7a4f2e3da3b) is 8M, max 2.2G, 2.1G free. Sep 11 00:25:35.687612 systemd[1]: Queued start job for default target multi-user.target. Sep 11 00:25:35.714968 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Sep 11 00:25:35.715439 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 11 00:25:36.510993 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Sep 11 00:25:36.521036 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 11 00:25:36.530923 systemd[1]: verity-setup.service: Deactivated successfully. Sep 11 00:25:36.530963 systemd[1]: Stopped verity-setup.service. Sep 11 00:25:36.542690 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 11 00:25:36.550854 systemd[1]: Started systemd-journald.service - Journal Service. Sep 11 00:25:36.555069 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 11 00:25:36.559302 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 11 00:25:36.563800 systemd[1]: Mounted media.mount - External Media Directory. Sep 11 00:25:36.567751 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 11 00:25:36.572145 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 11 00:25:36.576520 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 11 00:25:36.580648 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 11 00:25:36.585605 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 11 00:25:36.590841 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 11 00:25:36.591065 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 11 00:25:36.596070 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 11 00:25:36.596243 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 11 00:25:36.601055 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 11 00:25:36.601234 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 11 00:25:36.606441 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 11 00:25:36.606617 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 11 00:25:36.611195 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 11 00:25:36.611374 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 11 00:25:36.615974 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 11 00:25:36.620739 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 11 00:25:36.626056 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 11 00:25:36.639032 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 11 00:25:36.644732 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 11 00:25:36.664686 kernel: ACPI: bus type drm_connector registered Sep 11 00:25:36.671884 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 11 00:25:36.676294 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 11 00:25:36.676335 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 11 00:25:36.681398 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Sep 11 00:25:36.687476 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 11 00:25:36.691520 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 11 00:25:36.692524 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 11 00:25:36.698296 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 11 00:25:36.702945 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 11 00:25:36.703891 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 11 00:25:36.708666 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 11 00:25:36.709702 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 11 00:25:36.714575 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 11 00:25:36.720184 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 11 00:25:36.726465 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 11 00:25:36.726651 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 11 00:25:36.731244 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Sep 11 00:25:36.737308 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 11 00:25:36.742332 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 11 00:25:36.747175 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 11 00:25:36.786397 systemd-journald[1498]: Time spent on flushing to /var/log/journal/4d9d74beb18e484388d8b7a4f2e3da3b is 16.728ms for 956 entries. Sep 11 00:25:36.786397 systemd-journald[1498]: System Journal (/var/log/journal/4d9d74beb18e484388d8b7a4f2e3da3b) is 8M, max 2.6G, 2.6G free. Sep 11 00:25:36.835568 systemd-journald[1498]: Received client request to flush runtime journal. Sep 11 00:25:36.810980 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 11 00:25:36.815852 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 11 00:25:36.824365 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Sep 11 00:25:36.843837 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 11 00:25:36.881693 kernel: loop0: detected capacity change from 0 to 28504 Sep 11 00:25:36.903400 systemd-tmpfiles[1539]: ACLs are not supported, ignoring. Sep 11 00:25:36.903420 systemd-tmpfiles[1539]: ACLs are not supported, ignoring. Sep 11 00:25:36.908564 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 11 00:25:36.914655 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 11 00:25:36.915355 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Sep 11 00:25:36.922041 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 11 00:25:36.936809 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 11 00:25:37.539056 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 11 00:25:37.544835 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 11 00:25:37.577873 systemd-tmpfiles[1558]: ACLs are not supported, ignoring. Sep 11 00:25:37.577893 systemd-tmpfiles[1558]: ACLs are not supported, ignoring. Sep 11 00:25:37.582179 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 11 00:25:37.915696 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 11 00:25:38.082708 kernel: loop1: detected capacity change from 0 to 113872 Sep 11 00:25:38.588696 kernel: loop2: detected capacity change from 0 to 146240 Sep 11 00:25:38.897611 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 11 00:25:38.904124 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 11 00:25:38.956246 systemd-udevd[1566]: Using default interface naming scheme 'v255'. Sep 11 00:25:39.332707 kernel: loop3: detected capacity change from 0 to 28504 Sep 11 00:25:39.347699 kernel: loop4: detected capacity change from 0 to 113872 Sep 11 00:25:39.362697 kernel: loop5: detected capacity change from 0 to 146240 Sep 11 00:25:39.379726 (sd-merge)[1568]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Sep 11 00:25:39.380200 (sd-merge)[1568]: Merged extensions into '/usr'. Sep 11 00:25:39.383454 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 11 00:25:39.389890 systemd[1]: Starting ensure-sysext.service... Sep 11 00:25:39.393550 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 11 00:25:39.463842 systemd[1]: Reload requested from client PID 1570 ('systemctl') (unit ensure-sysext.service)... Sep 11 00:25:39.463863 systemd[1]: Reloading... Sep 11 00:25:39.509762 zram_generator::config[1601]: No configuration found. Sep 11 00:25:39.643356 systemd-tmpfiles[1571]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Sep 11 00:25:39.643408 systemd-tmpfiles[1571]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Sep 11 00:25:39.643771 systemd-tmpfiles[1571]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 11 00:25:39.644054 systemd-tmpfiles[1571]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 11 00:25:39.644951 systemd-tmpfiles[1571]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 11 00:25:39.645271 systemd-tmpfiles[1571]: ACLs are not supported, ignoring. Sep 11 00:25:39.645344 systemd-tmpfiles[1571]: ACLs are not supported, ignoring. Sep 11 00:25:39.679645 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Sep 11 00:25:39.717750 systemd-tmpfiles[1571]: Detected autofs mount point /boot during canonicalization of boot. Sep 11 00:25:39.717763 systemd-tmpfiles[1571]: Skipping /boot Sep 11 00:25:39.729706 systemd-tmpfiles[1571]: Detected autofs mount point /boot during canonicalization of boot. Sep 11 00:25:39.729720 systemd-tmpfiles[1571]: Skipping /boot Sep 11 00:25:39.807504 systemd[1]: Reloading finished in 343 ms. Sep 11 00:25:39.827104 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 11 00:25:39.847892 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 11 00:25:39.862348 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Sep 11 00:25:39.865498 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 11 00:25:39.868053 kernel: mousedev: PS/2 mouse device common for all mice Sep 11 00:25:39.881702 kernel: hv_vmbus: registering driver hyperv_fb Sep 11 00:25:39.881763 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Sep 11 00:25:39.890475 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 11 00:25:39.901024 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Sep 11 00:25:39.901073 kernel: Console: switching to colour dummy device 80x25 Sep 11 00:25:39.901093 kernel: Console: switching to colour frame buffer device 128x48 Sep 11 00:25:39.902462 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 11 00:25:39.908821 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 11 00:25:39.914529 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 11 00:25:39.925061 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 11 00:25:39.927749 kernel: hv_vmbus: registering driver hv_balloon Sep 11 00:25:39.927817 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Sep 11 00:25:39.955933 systemd[1]: Finished ensure-sysext.service. Sep 11 00:25:39.969359 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 11 00:25:39.976698 kernel: EDAC MC: Ver: 3.0.0 Sep 11 00:25:39.979209 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 11 00:25:39.979430 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 11 00:25:39.980585 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 11 00:25:39.985902 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 11 00:25:39.998961 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 11 00:25:40.004347 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 11 00:25:40.008362 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 11 00:25:40.008409 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 11 00:25:40.008476 systemd[1]: Reached target time-set.target - System Time Set. Sep 11 00:25:40.013312 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 11 00:25:40.017486 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 11 00:25:40.018434 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 11 00:25:40.018614 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 11 00:25:40.023450 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 11 00:25:40.023629 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 11 00:25:40.029909 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 11 00:25:40.030104 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 11 00:25:40.035508 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 11 00:25:40.035701 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 11 00:25:40.043370 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 11 00:25:40.043430 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 11 00:25:40.048138 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 11 00:25:40.136233 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 11 00:25:40.172228 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Sep 11 00:25:40.186570 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 11 00:25:40.233664 augenrules[1840]: No rules Sep 11 00:25:40.235126 systemd[1]: audit-rules.service: Deactivated successfully. Sep 11 00:25:40.235380 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 11 00:25:40.318491 systemd-resolved[1729]: Positive Trust Anchors: Sep 11 00:25:40.318506 systemd-resolved[1729]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 11 00:25:40.318537 systemd-resolved[1729]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Sep 11 00:25:40.325481 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 11 00:25:40.342539 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 11 00:25:40.368715 systemd-resolved[1729]: Using system hostname 'ci-4372.1.0-n-10814f282e'. Sep 11 00:25:40.370219 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 11 00:25:40.371722 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 11 00:25:40.427107 systemd-networkd[1726]: lo: Link UP Sep 11 00:25:40.427117 systemd-networkd[1726]: lo: Gained carrier Sep 11 00:25:40.428525 systemd-networkd[1726]: Enumeration completed Sep 11 00:25:40.428626 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 11 00:25:40.429063 systemd-networkd[1726]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 11 00:25:40.429075 systemd-networkd[1726]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 11 00:25:40.429635 systemd-networkd[1726]: eth0: Link UP Sep 11 00:25:40.429643 systemd-networkd[1726]: eth0: Gained carrier Sep 11 00:25:40.429658 systemd-networkd[1726]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 11 00:25:40.430166 systemd[1]: Reached target network.target - Network. Sep 11 00:25:40.436572 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Sep 11 00:25:40.438950 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 11 00:25:40.487728 systemd-networkd[1726]: eth0: DHCPv4 address 10.200.8.35/24, gateway 10.200.8.1 acquired from 168.63.129.16 Sep 11 00:25:40.516900 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Sep 11 00:25:41.485124 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 11 00:25:42.403968 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 11 00:25:42.409010 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Sep 11 00:25:42.450776 systemd-networkd[1726]: eth0: Gained IPv6LL Sep 11 00:25:42.452816 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 11 00:25:42.457987 systemd[1]: Reached target network-online.target - Network is Online. Sep 11 00:25:47.583312 ldconfig[1532]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 11 00:25:47.631902 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 11 00:25:47.638511 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 11 00:25:47.677001 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 11 00:25:47.681419 systemd[1]: Reached target sysinit.target - System Initialization. Sep 11 00:25:47.685742 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 11 00:25:47.690732 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Sep 11 00:25:47.695731 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Sep 11 00:25:47.700450 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 11 00:25:47.704726 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 11 00:25:47.709739 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 11 00:25:47.714774 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 11 00:25:47.714808 systemd[1]: Reached target paths.target - Path Units. Sep 11 00:25:47.718297 systemd[1]: Reached target timers.target - Timer Units. Sep 11 00:25:47.722788 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 11 00:25:47.728253 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 11 00:25:47.734603 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Sep 11 00:25:47.739881 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Sep 11 00:25:47.744743 systemd[1]: Reached target ssh-access.target - SSH Access Available. Sep 11 00:25:47.750739 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 11 00:25:47.755106 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Sep 11 00:25:47.760374 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 11 00:25:47.765422 systemd[1]: Reached target sockets.target - Socket Units. Sep 11 00:25:47.769320 systemd[1]: Reached target basic.target - Basic System. Sep 11 00:25:47.773034 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 11 00:25:47.773065 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 11 00:25:47.788496 systemd[1]: Starting chronyd.service - NTP client/server... Sep 11 00:25:47.792669 systemd[1]: Starting containerd.service - containerd container runtime... Sep 11 00:25:47.812458 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Sep 11 00:25:47.817435 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 11 00:25:47.821880 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 11 00:25:47.827249 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 11 00:25:47.832099 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 11 00:25:47.836058 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Sep 11 00:25:47.837132 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Sep 11 00:25:47.841101 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). Sep 11 00:25:47.842049 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Sep 11 00:25:47.846084 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Sep 11 00:25:47.847040 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 11 00:25:47.848646 jq[1871]: false Sep 11 00:25:47.851836 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 11 00:25:47.856378 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 11 00:25:47.862618 (chronyd)[1863]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Sep 11 00:25:47.862978 KVP[1874]: KVP starting; pid is:1874 Sep 11 00:25:47.863246 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 11 00:25:47.865708 KVP[1874]: KVP LIC Version: 3.1 Sep 11 00:25:47.867691 kernel: hv_utils: KVP IC version 4.0 Sep 11 00:25:47.869026 chronyd[1885]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) Sep 11 00:25:47.871784 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 11 00:25:47.876540 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 11 00:25:47.876958 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 11 00:25:47.877505 systemd[1]: Starting update-engine.service - Update Engine... Sep 11 00:25:47.882471 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 11 00:25:47.889129 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 11 00:25:47.890595 jq[1889]: true Sep 11 00:25:47.894529 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 11 00:25:47.894769 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 11 00:25:47.895057 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 11 00:25:47.895248 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 11 00:25:47.896450 extend-filesystems[1872]: Found /dev/sda6 Sep 11 00:25:47.906087 systemd[1]: motdgen.service: Deactivated successfully. Sep 11 00:25:47.906295 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 11 00:25:47.906721 (ntainerd)[1898]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 11 00:25:47.907750 jq[1897]: true Sep 11 00:25:47.914101 google_oslogin_nss_cache[1873]: oslogin_cache_refresh[1873]: Refreshing passwd entry cache Sep 11 00:25:47.912366 oslogin_cache_refresh[1873]: Refreshing passwd entry cache Sep 11 00:25:47.929466 google_oslogin_nss_cache[1873]: oslogin_cache_refresh[1873]: Failure getting users, quitting Sep 11 00:25:47.929466 google_oslogin_nss_cache[1873]: oslogin_cache_refresh[1873]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Sep 11 00:25:47.929443 oslogin_cache_refresh[1873]: Failure getting users, quitting Sep 11 00:25:47.929644 google_oslogin_nss_cache[1873]: oslogin_cache_refresh[1873]: Refreshing group entry cache Sep 11 00:25:47.929459 oslogin_cache_refresh[1873]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Sep 11 00:25:47.929500 oslogin_cache_refresh[1873]: Refreshing group entry cache Sep 11 00:25:47.934531 google_oslogin_nss_cache[1873]: oslogin_cache_refresh[1873]: Failure getting groups, quitting Sep 11 00:25:47.934531 google_oslogin_nss_cache[1873]: oslogin_cache_refresh[1873]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Sep 11 00:25:47.934519 oslogin_cache_refresh[1873]: Failure getting groups, quitting Sep 11 00:25:47.934530 oslogin_cache_refresh[1873]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Sep 11 00:25:47.935493 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Sep 11 00:25:47.935741 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Sep 11 00:25:47.941246 chronyd[1885]: Timezone right/UTC failed leap second check, ignoring Sep 11 00:25:47.941444 chronyd[1885]: Loaded seccomp filter (level 2) Sep 11 00:25:47.942696 update_engine[1888]: I20250911 00:25:47.942618 1888 main.cc:92] Flatcar Update Engine starting Sep 11 00:25:47.942697 systemd[1]: Started chronyd.service - NTP client/server. Sep 11 00:25:48.016950 systemd-logind[1887]: New seat seat0. Sep 11 00:25:48.018956 systemd-logind[1887]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Sep 11 00:25:48.019121 systemd[1]: Started systemd-logind.service - User Login Management. Sep 11 00:25:48.023926 extend-filesystems[1872]: Found /dev/sda9 Sep 11 00:25:48.026733 extend-filesystems[1872]: Checking size of /dev/sda9 Sep 11 00:25:48.058935 bash[1924]: Updated "/home/core/.ssh/authorized_keys" Sep 11 00:25:48.059932 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 11 00:25:48.066146 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Sep 11 00:25:48.093086 extend-filesystems[1872]: Old size kept for /dev/sda9 Sep 11 00:25:48.096750 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 11 00:25:48.096995 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 11 00:25:48.140427 sshd_keygen[1927]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 11 00:25:48.159443 setup-nvidia[1876]: Downloading Flatcar Container Linux Developer Container for version: 4372.1.0+nightly-20250910-2100 Sep 11 00:25:48.163439 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 11 00:25:48.169088 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 11 00:25:48.181885 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Sep 11 00:25:48.188004 systemd[1]: issuegen.service: Deactivated successfully. Sep 11 00:25:48.188226 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 11 00:25:48.194499 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 11 00:25:48.204173 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Sep 11 00:25:48.225886 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 11 00:25:48.231896 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 11 00:25:48.237019 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Sep 11 00:25:48.241489 systemd[1]: Reached target getty.target - Login Prompts. Sep 11 00:25:48.278303 dbus-daemon[1866]: [system] SELinux support is enabled Sep 11 00:25:48.278466 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 11 00:25:48.281353 update_engine[1888]: I20250911 00:25:48.281299 1888 update_check_scheduler.cc:74] Next update check in 5m18s Sep 11 00:25:48.284786 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Sep 11 00:25:48.284815 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 11 00:25:48.285604 dbus-daemon[1866]: [system] Successfully activated service 'org.freedesktop.systemd1' Sep 11 00:25:48.289779 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Sep 11 00:25:48.289804 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 11 00:25:48.294749 systemd[1]: Started update-engine.service - Update Engine. Sep 11 00:25:48.300116 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 11 00:25:48.400646 coreos-metadata[1865]: Sep 11 00:25:48.400 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Sep 11 00:25:48.403002 coreos-metadata[1865]: Sep 11 00:25:48.402 INFO Fetch successful Sep 11 00:25:48.403160 coreos-metadata[1865]: Sep 11 00:25:48.403 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Sep 11 00:25:48.407224 coreos-metadata[1865]: Sep 11 00:25:48.407 INFO Fetch successful Sep 11 00:25:48.407496 coreos-metadata[1865]: Sep 11 00:25:48.407 INFO Fetching http://168.63.129.16/machine/b2b0856e-1765-48c2-974c-08d7ff8d6189/345cb0d4%2Dfc47%2D4a17%2Dac0f%2Db40c9556f572.%5Fci%2D4372.1.0%2Dn%2D10814f282e?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Sep 11 00:25:48.409052 coreos-metadata[1865]: Sep 11 00:25:48.409 INFO Fetch successful Sep 11 00:25:48.409193 coreos-metadata[1865]: Sep 11 00:25:48.409 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Sep 11 00:25:48.415931 coreos-metadata[1865]: Sep 11 00:25:48.415 INFO Fetch successful Sep 11 00:25:48.453401 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Sep 11 00:25:48.458251 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 11 00:25:48.681611 locksmithd[2026]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 11 00:25:49.408120 containerd[1898]: time="2025-09-11T00:25:49Z" 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 Sep 11 00:25:49.408789 containerd[1898]: time="2025-09-11T00:25:49.408755700Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 Sep 11 00:25:49.416495 containerd[1898]: time="2025-09-11T00:25:49.416460300Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="6.8µs" Sep 11 00:25:49.416495 containerd[1898]: time="2025-09-11T00:25:49.416487600Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Sep 11 00:25:49.416563 containerd[1898]: time="2025-09-11T00:25:49.416504100Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Sep 11 00:25:49.416679 containerd[1898]: time="2025-09-11T00:25:49.416649100Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Sep 11 00:25:49.416724 containerd[1898]: time="2025-09-11T00:25:49.416682800Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Sep 11 00:25:49.416724 containerd[1898]: time="2025-09-11T00:25:49.416709300Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 11 00:25:49.416796 containerd[1898]: time="2025-09-11T00:25:49.416774200Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 11 00:25:49.416796 containerd[1898]: time="2025-09-11T00:25:49.416793100Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 11 00:25:49.417040 containerd[1898]: time="2025-09-11T00:25:49.417017400Z" 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 Sep 11 00:25:49.417075 containerd[1898]: time="2025-09-11T00:25:49.417038300Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 11 00:25:49.417075 containerd[1898]: time="2025-09-11T00:25:49.417049300Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 11 00:25:49.417075 containerd[1898]: time="2025-09-11T00:25:49.417057300Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Sep 11 00:25:49.417158 containerd[1898]: time="2025-09-11T00:25:49.417139500Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Sep 11 00:25:49.417380 containerd[1898]: time="2025-09-11T00:25:49.417359800Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 11 00:25:49.417409 containerd[1898]: time="2025-09-11T00:25:49.417395500Z" 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 Sep 11 00:25:49.417409 containerd[1898]: time="2025-09-11T00:25:49.417406200Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Sep 11 00:25:49.417455 containerd[1898]: time="2025-09-11T00:25:49.417432100Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Sep 11 00:25:49.418188 containerd[1898]: time="2025-09-11T00:25:49.418150000Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Sep 11 00:25:49.418258 containerd[1898]: time="2025-09-11T00:25:49.418238200Z" level=info msg="metadata content store policy set" policy=shared Sep 11 00:25:49.437917 containerd[1898]: time="2025-09-11T00:25:49.437882500Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Sep 11 00:25:49.437958 containerd[1898]: time="2025-09-11T00:25:49.437922800Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Sep 11 00:25:49.437958 containerd[1898]: time="2025-09-11T00:25:49.437937500Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Sep 11 00:25:49.437958 containerd[1898]: time="2025-09-11T00:25:49.437948500Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Sep 11 00:25:49.438018 containerd[1898]: time="2025-09-11T00:25:49.437959900Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Sep 11 00:25:49.438018 containerd[1898]: time="2025-09-11T00:25:49.437969700Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Sep 11 00:25:49.438018 containerd[1898]: time="2025-09-11T00:25:49.437979900Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Sep 11 00:25:49.438018 containerd[1898]: time="2025-09-11T00:25:49.437990100Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Sep 11 00:25:49.438018 containerd[1898]: time="2025-09-11T00:25:49.438000500Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Sep 11 00:25:49.438018 containerd[1898]: time="2025-09-11T00:25:49.438010000Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Sep 11 00:25:49.438018 containerd[1898]: time="2025-09-11T00:25:49.438018100Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Sep 11 00:25:49.438177 containerd[1898]: time="2025-09-11T00:25:49.438028700Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Sep 11 00:25:49.438177 containerd[1898]: time="2025-09-11T00:25:49.438156600Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Sep 11 00:25:49.438225 containerd[1898]: time="2025-09-11T00:25:49.438181100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Sep 11 00:25:49.438225 containerd[1898]: time="2025-09-11T00:25:49.438194600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Sep 11 00:25:49.438225 containerd[1898]: time="2025-09-11T00:25:49.438205200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Sep 11 00:25:49.438286 containerd[1898]: time="2025-09-11T00:25:49.438227300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Sep 11 00:25:49.438286 containerd[1898]: time="2025-09-11T00:25:49.438241800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Sep 11 00:25:49.438286 containerd[1898]: time="2025-09-11T00:25:49.438252000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Sep 11 00:25:49.438286 containerd[1898]: time="2025-09-11T00:25:49.438260600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Sep 11 00:25:49.438286 containerd[1898]: time="2025-09-11T00:25:49.438271000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Sep 11 00:25:49.438286 containerd[1898]: time="2025-09-11T00:25:49.438282200Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Sep 11 00:25:49.438413 containerd[1898]: time="2025-09-11T00:25:49.438291500Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Sep 11 00:25:49.438413 containerd[1898]: time="2025-09-11T00:25:49.438344200Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Sep 11 00:25:49.438413 containerd[1898]: time="2025-09-11T00:25:49.438358200Z" level=info msg="Start snapshots syncer" Sep 11 00:25:49.438413 containerd[1898]: time="2025-09-11T00:25:49.438381300Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Sep 11 00:25:49.438610 containerd[1898]: time="2025-09-11T00:25:49.438559200Z" 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\"}" Sep 11 00:25:49.438735 containerd[1898]: time="2025-09-11T00:25:49.438625400Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Sep 11 00:25:49.438735 containerd[1898]: time="2025-09-11T00:25:49.438716000Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Sep 11 00:25:49.438843 containerd[1898]: time="2025-09-11T00:25:49.438818100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Sep 11 00:25:49.438876 containerd[1898]: time="2025-09-11T00:25:49.438852200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Sep 11 00:25:49.438876 containerd[1898]: time="2025-09-11T00:25:49.438864600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Sep 11 00:25:49.438876 containerd[1898]: time="2025-09-11T00:25:49.438874200Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Sep 11 00:25:49.438945 containerd[1898]: time="2025-09-11T00:25:49.438885100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Sep 11 00:25:49.438945 containerd[1898]: time="2025-09-11T00:25:49.438894500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Sep 11 00:25:49.438945 containerd[1898]: time="2025-09-11T00:25:49.438904100Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Sep 11 00:25:49.438945 containerd[1898]: time="2025-09-11T00:25:49.438923600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Sep 11 00:25:49.438945 containerd[1898]: time="2025-09-11T00:25:49.438935200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Sep 11 00:25:49.438945 containerd[1898]: time="2025-09-11T00:25:49.438944700Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Sep 11 00:25:49.439080 containerd[1898]: time="2025-09-11T00:25:49.438977300Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 11 00:25:49.439080 containerd[1898]: time="2025-09-11T00:25:49.438991500Z" 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 Sep 11 00:25:49.439080 containerd[1898]: time="2025-09-11T00:25:49.438999800Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 11 00:25:49.439080 containerd[1898]: time="2025-09-11T00:25:49.439009200Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 11 00:25:49.439080 containerd[1898]: time="2025-09-11T00:25:49.439016900Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Sep 11 00:25:49.439080 containerd[1898]: time="2025-09-11T00:25:49.439025200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Sep 11 00:25:49.439080 containerd[1898]: time="2025-09-11T00:25:49.439034400Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Sep 11 00:25:49.439080 containerd[1898]: time="2025-09-11T00:25:49.439044600Z" level=info msg="runtime interface created" Sep 11 00:25:49.439080 containerd[1898]: time="2025-09-11T00:25:49.439049300Z" level=info msg="created NRI interface" Sep 11 00:25:49.439080 containerd[1898]: time="2025-09-11T00:25:49.439057000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Sep 11 00:25:49.439080 containerd[1898]: time="2025-09-11T00:25:49.439068500Z" level=info msg="Connect containerd service" Sep 11 00:25:49.439303 containerd[1898]: time="2025-09-11T00:25:49.439091100Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 11 00:25:49.439821 containerd[1898]: time="2025-09-11T00:25:49.439790900Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Sep 11 00:25:49.465618 setup-nvidia[2047]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 00:25:49.465852 setup-nvidia[2047]: Dload Upload Total Spent Left Speed Sep 11 00:25:50.447731 containerd[1898]: time="2025-09-11T00:25:50.447667700Z" level=info msg="Start subscribing containerd event" Sep 11 00:25:50.448086 containerd[1898]: time="2025-09-11T00:25:50.447739300Z" level=info msg="Start recovering state" Sep 11 00:25:50.448086 containerd[1898]: time="2025-09-11T00:25:50.447843700Z" level=info msg="Start event monitor" Sep 11 00:25:50.448086 containerd[1898]: time="2025-09-11T00:25:50.447861800Z" level=info msg="Start cni network conf syncer for default" Sep 11 00:25:50.448086 containerd[1898]: time="2025-09-11T00:25:50.447869300Z" level=info msg="Start streaming server" Sep 11 00:25:50.448086 containerd[1898]: time="2025-09-11T00:25:50.447879800Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Sep 11 00:25:50.448086 containerd[1898]: time="2025-09-11T00:25:50.447887600Z" level=info msg="runtime interface starting up..." Sep 11 00:25:50.448086 containerd[1898]: time="2025-09-11T00:25:50.447893000Z" level=info msg="starting plugins..." Sep 11 00:25:50.448086 containerd[1898]: time="2025-09-11T00:25:50.447911900Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Sep 11 00:25:50.448086 containerd[1898]: time="2025-09-11T00:25:50.447946500Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 11 00:25:50.448086 containerd[1898]: time="2025-09-11T00:25:50.448038500Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 11 00:25:50.448316 containerd[1898]: time="2025-09-11T00:25:50.448115100Z" level=info msg="containerd successfully booted in 1.040476s" Sep 11 00:25:50.448217 systemd[1]: Started containerd.service - containerd container runtime. Sep 11 00:25:50.985192 waagent[2020]: 2025-09-11T00:25:50.985114Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Sep 11 00:25:50.989379 waagent[2020]: 2025-09-11T00:25:50.989329Z INFO Daemon Daemon OS: flatcar 4372.1.0 Sep 11 00:25:50.992707 waagent[2020]: 2025-09-11T00:25:50.992653Z INFO Daemon Daemon Python: 3.11.12 Sep 11 00:25:50.995882 waagent[2020]: 2025-09-11T00:25:50.995818Z INFO Daemon Daemon Run daemon Sep 11 00:25:50.998798 waagent[2020]: 2025-09-11T00:25:50.998751Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4372.1.0' Sep 11 00:25:51.005228 waagent[2020]: 2025-09-11T00:25:51.005153Z INFO Daemon Daemon Using waagent for provisioning Sep 11 00:25:51.009105 waagent[2020]: 2025-09-11T00:25:51.009055Z INFO Daemon Daemon Activate resource disk Sep 11 00:25:51.012531 waagent[2020]: 2025-09-11T00:25:51.012484Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Sep 11 00:25:51.019958 waagent[2020]: 2025-09-11T00:25:51.019915Z INFO Daemon Daemon Found device: sdb Sep 11 00:25:51.028757 waagent[2020]: 2025-09-11T00:25:51.028709Z INFO Daemon Daemon Examining partition table Sep 11 00:25:51.090595 waagent[2020]: 2025-09-11T00:25:51.090537Z INFO Daemon Daemon GPT not detected, determining filesystem Sep 11 00:25:51.169539 waagent[2020]: 2025-09-11T00:25:51.169465Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Sep 11 00:25:51.174027 waagent[2020]: 2025-09-11T00:25:51.173969Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Sep 11 00:25:51.578049 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#527 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 11 00:25:51.589713 kernel: sdb: sdb1 Sep 11 00:25:51.619010 waagent[2020]: 2025-09-11T00:25:51.618936Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Sep 11 00:25:51.636695 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#567 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 11 00:25:51.651634 waagent[2020]: 2025-09-11T00:25:51.651584Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Sep 11 00:25:51.651727 kernel: sdb: sdb1 Sep 11 00:25:55.955301 login[2022]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Sep 11 00:25:56.005454 login[2023]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Sep 11 00:25:56.011925 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 11 00:25:56.013019 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 11 00:25:56.019179 systemd-logind[1887]: New session 2 of user core. Sep 11 00:25:56.054010 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 11 00:25:56.057566 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 11 00:25:56.292203 (systemd)[2129]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 11 00:25:56.294771 systemd-logind[1887]: New session c1 of user core. Sep 11 00:25:56.955699 login[2022]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Sep 11 00:25:56.960602 systemd-logind[1887]: New session 1 of user core. Sep 11 00:25:57.224119 waagent[2020]: 2025-09-11T00:25:57.223990Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Sep 11 00:25:57.335705 kernel: EXT4-fs (sdb1): mounted filesystem 458c521e-6531-4400-98de-e91fcb22cc31 r/w with ordered data mode. Quota mode: none. Sep 11 00:25:57.336127 waagent[2020]: 2025-09-11T00:25:57.336053Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Sep 11 00:25:57.338247 waagent[2020]: 2025-09-11T00:25:57.338204Z INFO Daemon Daemon Clean protocol and wireserver endpoint Sep 11 00:25:57.338499 waagent[2020]: 2025-09-11T00:25:57.338463Z INFO Daemon Daemon Running default provisioning handler Sep 11 00:25:57.345781 waagent[2020]: 2025-09-11T00:25:57.345743Z INFO Daemon Daemon Unable to get cloud-init enabled status from systemctl: Command '['systemctl', 'is-enabled', 'cloud-init-local.service']' returned non-zero exit status 4. Sep 11 00:25:57.346563 waagent[2020]: 2025-09-11T00:25:57.346521Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Sep 11 00:25:57.347668 waagent[2020]: 2025-09-11T00:25:57.347631Z INFO Daemon Daemon cloud-init is enabled: False Sep 11 00:25:57.348408 waagent[2020]: 2025-09-11T00:25:57.348375Z INFO Daemon Daemon Copying ovf-env.xml Sep 11 00:25:57.385665 systemd[2129]: Queued start job for default target default.target. Sep 11 00:25:57.402445 systemd[2129]: Created slice app.slice - User Application Slice. Sep 11 00:25:57.402490 systemd[2129]: Reached target paths.target - Paths. Sep 11 00:25:57.402535 systemd[2129]: Reached target timers.target - Timers. Sep 11 00:25:57.403762 systemd[2129]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 11 00:25:57.413902 systemd[2129]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 11 00:25:57.414021 systemd[2129]: Reached target sockets.target - Sockets. Sep 11 00:25:57.414076 systemd[2129]: Reached target basic.target - Basic System. Sep 11 00:25:57.414121 systemd[2129]: Reached target default.target - Main User Target. Sep 11 00:25:57.414153 systemd[2129]: Startup finished in 1.113s. Sep 11 00:25:57.414236 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 11 00:25:57.415988 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 11 00:25:57.416750 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 11 00:25:57.491260 waagent[2020]: 2025-09-11T00:25:57.491149Z INFO Daemon Daemon Successfully mounted dvd Sep 11 00:25:57.523338 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Sep 11 00:25:57.523475 waagent[2020]: 2025-09-11T00:25:57.523366Z INFO Daemon Daemon Detect protocol endpoint Sep 11 00:25:57.524975 waagent[2020]: 2025-09-11T00:25:57.524925Z INFO Daemon Daemon Clean protocol and wireserver endpoint Sep 11 00:25:57.525732 waagent[2020]: 2025-09-11T00:25:57.525694Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Sep 11 00:25:57.527138 waagent[2020]: 2025-09-11T00:25:57.527100Z INFO Daemon Daemon Test for route to 168.63.129.16 Sep 11 00:25:57.528770 waagent[2020]: 2025-09-11T00:25:57.528728Z INFO Daemon Daemon Route to 168.63.129.16 exists Sep 11 00:25:57.529372 waagent[2020]: 2025-09-11T00:25:57.529338Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Sep 11 00:25:57.559536 waagent[2020]: 2025-09-11T00:25:57.559493Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Sep 11 00:25:57.561482 waagent[2020]: 2025-09-11T00:25:57.561454Z INFO Daemon Daemon Wire protocol version:2012-11-30 Sep 11 00:25:57.562795 waagent[2020]: 2025-09-11T00:25:57.562763Z INFO Daemon Daemon Server preferred version:2015-04-05 Sep 11 00:25:57.686133 waagent[2020]: 2025-09-11T00:25:57.686071Z INFO Daemon Daemon Initializing goal state during protocol detection Sep 11 00:25:57.687660 waagent[2020]: 2025-09-11T00:25:57.687617Z INFO Daemon Daemon Forcing an update of the goal state. Sep 11 00:25:57.691329 waagent[2020]: 2025-09-11T00:25:57.691283Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Sep 11 00:25:57.725275 waagent[2020]: 2025-09-11T00:25:57.725213Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.175 Sep 11 00:25:57.727274 waagent[2020]: 2025-09-11T00:25:57.727232Z INFO Daemon Sep 11 00:25:57.727572 waagent[2020]: 2025-09-11T00:25:57.727540Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: def7387f-f8e2-41f3-b123-c7bb2bba9fb7 eTag: 4586469700672638254 source: Fabric] Sep 11 00:25:57.729289 waagent[2020]: 2025-09-11T00:25:57.729248Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Sep 11 00:25:57.730600 waagent[2020]: 2025-09-11T00:25:57.730563Z INFO Daemon Sep 11 00:25:57.731803 waagent[2020]: 2025-09-11T00:25:57.731766Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Sep 11 00:25:57.735890 waagent[2020]: 2025-09-11T00:25:57.735858Z INFO Daemon Daemon Downloading artifacts profile blob Sep 11 00:25:57.874611 waagent[2020]: 2025-09-11T00:25:57.874500Z INFO Daemon Downloaded certificate {'thumbprint': '76624C6551543C714A1E7EE03184ABC0B57FD831', 'hasPrivateKey': True} Sep 11 00:25:57.875825 waagent[2020]: 2025-09-11T00:25:57.875782Z INFO Daemon Fetch goal state completed Sep 11 00:25:57.912620 waagent[2020]: 2025-09-11T00:25:57.912573Z INFO Daemon Daemon Starting provisioning Sep 11 00:25:57.914300 waagent[2020]: 2025-09-11T00:25:57.914255Z INFO Daemon Daemon Handle ovf-env.xml. Sep 11 00:25:57.915898 waagent[2020]: 2025-09-11T00:25:57.915858Z INFO Daemon Daemon Set hostname [ci-4372.1.0-n-10814f282e] Sep 11 00:25:57.935185 waagent[2020]: 2025-09-11T00:25:57.935145Z INFO Daemon Daemon Publish hostname [ci-4372.1.0-n-10814f282e] Sep 11 00:25:57.936884 waagent[2020]: 2025-09-11T00:25:57.936825Z INFO Daemon Daemon Examine /proc/net/route for primary interface Sep 11 00:25:57.937570 waagent[2020]: 2025-09-11T00:25:57.937531Z INFO Daemon Daemon Primary interface is [eth0] Sep 11 00:25:57.946889 systemd-networkd[1726]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 11 00:25:57.946900 systemd-networkd[1726]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 11 00:25:57.946924 systemd-networkd[1726]: eth0: DHCP lease lost Sep 11 00:25:57.947387 waagent[2020]: 2025-09-11T00:25:57.947341Z INFO Daemon Daemon Create user account if not exists Sep 11 00:25:57.948942 waagent[2020]: 2025-09-11T00:25:57.948903Z INFO Daemon Daemon User core already exists, skip useradd Sep 11 00:25:57.949615 waagent[2020]: 2025-09-11T00:25:57.949584Z INFO Daemon Daemon Configure sudoer Sep 11 00:25:57.960175 waagent[2020]: 2025-09-11T00:25:57.960128Z INFO Daemon Daemon Configure sshd Sep 11 00:25:57.965685 waagent[2020]: 2025-09-11T00:25:57.965641Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Sep 11 00:25:57.967223 waagent[2020]: 2025-09-11T00:25:57.967185Z INFO Daemon Daemon Deploy ssh public key. Sep 11 00:25:58.044715 systemd-networkd[1726]: eth0: DHCPv4 address 10.200.8.35/24, gateway 10.200.8.1 acquired from 168.63.129.16 Sep 11 00:25:59.160564 waagent[2020]: 2025-09-11T00:25:59.160499Z INFO Daemon Daemon Provisioning complete Sep 11 00:25:59.171215 waagent[2020]: 2025-09-11T00:25:59.171176Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Sep 11 00:25:59.172814 waagent[2020]: 2025-09-11T00:25:59.172774Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Sep 11 00:25:59.173553 waagent[2020]: 2025-09-11T00:25:59.173515Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Sep 11 00:25:59.324984 waagent[2187]: 2025-09-11T00:25:59.324901Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Sep 11 00:25:59.325298 waagent[2187]: 2025-09-11T00:25:59.325039Z INFO ExtHandler ExtHandler OS: flatcar 4372.1.0 Sep 11 00:25:59.325298 waagent[2187]: 2025-09-11T00:25:59.325112Z INFO ExtHandler ExtHandler Python: 3.11.12 Sep 11 00:25:59.325298 waagent[2187]: 2025-09-11T00:25:59.325180Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Sep 11 00:25:59.421005 waagent[2187]: 2025-09-11T00:25:59.420951Z INFO ExtHandler ExtHandler Distro: flatcar-4372.1.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.12; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Sep 11 00:25:59.421200 waagent[2187]: 2025-09-11T00:25:59.421158Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 11 00:25:59.421272 waagent[2187]: 2025-09-11T00:25:59.421240Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 11 00:25:59.427641 waagent[2187]: 2025-09-11T00:25:59.427589Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Sep 11 00:25:59.432182 waagent[2187]: 2025-09-11T00:25:59.432141Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.175 Sep 11 00:25:59.432730 waagent[2187]: 2025-09-11T00:25:59.432666Z INFO ExtHandler Sep 11 00:25:59.432809 waagent[2187]: 2025-09-11T00:25:59.432776Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 2bfc6ffb-9adc-4e7c-9a89-eea2050e1ea0 eTag: 4586469700672638254 source: Fabric] Sep 11 00:25:59.433124 waagent[2187]: 2025-09-11T00:25:59.433086Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Sep 11 00:25:59.433758 waagent[2187]: 2025-09-11T00:25:59.433720Z INFO ExtHandler Sep 11 00:25:59.433830 waagent[2187]: 2025-09-11T00:25:59.433800Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Sep 11 00:25:59.437162 waagent[2187]: 2025-09-11T00:25:59.437129Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Sep 11 00:25:59.509943 waagent[2187]: 2025-09-11T00:25:59.509890Z INFO ExtHandler Downloaded certificate {'thumbprint': '76624C6551543C714A1E7EE03184ABC0B57FD831', 'hasPrivateKey': True} Sep 11 00:25:59.510432 waagent[2187]: 2025-09-11T00:25:59.510384Z INFO ExtHandler Fetch goal state completed Sep 11 00:25:59.521053 waagent[2187]: 2025-09-11T00:25:59.521006Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.3.3 11 Feb 2025 (Library: OpenSSL 3.3.3 11 Feb 2025) Sep 11 00:25:59.525988 waagent[2187]: 2025-09-11T00:25:59.525948Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2187 Sep 11 00:25:59.526128 waagent[2187]: 2025-09-11T00:25:59.526093Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Sep 11 00:25:59.526459 waagent[2187]: 2025-09-11T00:25:59.526422Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Sep 11 00:25:59.528045 waagent[2187]: 2025-09-11T00:25:59.528006Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4372.1.0', '', 'Flatcar Container Linux by Kinvolk'] Sep 11 00:25:59.528472 waagent[2187]: 2025-09-11T00:25:59.528434Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4372.1.0', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Sep 11 00:25:59.528619 waagent[2187]: 2025-09-11T00:25:59.528584Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Sep 11 00:25:59.529339 waagent[2187]: 2025-09-11T00:25:59.529295Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Sep 11 00:25:59.645897 waagent[2187]: 2025-09-11T00:25:59.645861Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Sep 11 00:25:59.646063 waagent[2187]: 2025-09-11T00:25:59.646028Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Sep 11 00:25:59.652342 waagent[2187]: 2025-09-11T00:25:59.652315Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Sep 11 00:25:59.659446 systemd[1]: Reload requested from client PID 2202 ('systemctl') (unit waagent.service)... Sep 11 00:25:59.659467 systemd[1]: Reloading... Sep 11 00:25:59.720708 zram_generator::config[2241]: No configuration found. Sep 11 00:25:59.830627 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Sep 11 00:25:59.992488 systemd[1]: Reloading finished in 332 ms. Sep 11 00:26:00.017179 waagent[2187]: 2025-09-11T00:26:00.017113Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Sep 11 00:26:00.017286 waagent[2187]: 2025-09-11T00:26:00.017251Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Sep 11 00:26:00.529419 waagent[2187]: 2025-09-11T00:26:00.529340Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Sep 11 00:26:00.529793 waagent[2187]: 2025-09-11T00:26:00.529668Z 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] Sep 11 00:26:00.530496 waagent[2187]: 2025-09-11T00:26:00.530448Z INFO ExtHandler ExtHandler Starting env monitor service. Sep 11 00:26:00.530991 waagent[2187]: 2025-09-11T00:26:00.530947Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Sep 11 00:26:00.531076 waagent[2187]: 2025-09-11T00:26:00.531032Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 11 00:26:00.531467 waagent[2187]: 2025-09-11T00:26:00.531414Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Sep 11 00:26:00.531586 waagent[2187]: 2025-09-11T00:26:00.531551Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 11 00:26:00.531726 waagent[2187]: 2025-09-11T00:26:00.531685Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 11 00:26:00.532029 waagent[2187]: 2025-09-11T00:26:00.531989Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Sep 11 00:26:00.532233 waagent[2187]: 2025-09-11T00:26:00.532191Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Sep 11 00:26:00.532323 waagent[2187]: 2025-09-11T00:26:00.532288Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 11 00:26:00.532765 waagent[2187]: 2025-09-11T00:26:00.532712Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Sep 11 00:26:00.532848 waagent[2187]: 2025-09-11T00:26:00.532808Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Sep 11 00:26:00.533204 waagent[2187]: 2025-09-11T00:26:00.533162Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Sep 11 00:26:00.533204 waagent[2187]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Sep 11 00:26:00.533204 waagent[2187]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Sep 11 00:26:00.533204 waagent[2187]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Sep 11 00:26:00.533204 waagent[2187]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Sep 11 00:26:00.533204 waagent[2187]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Sep 11 00:26:00.533204 waagent[2187]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Sep 11 00:26:00.533363 waagent[2187]: 2025-09-11T00:26:00.533243Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Sep 11 00:26:00.534147 waagent[2187]: 2025-09-11T00:26:00.534100Z INFO EnvHandler ExtHandler Configure routes Sep 11 00:26:00.534744 waagent[2187]: 2025-09-11T00:26:00.534703Z INFO EnvHandler ExtHandler Gateway:None Sep 11 00:26:00.535135 waagent[2187]: 2025-09-11T00:26:00.535091Z INFO EnvHandler ExtHandler Routes:None Sep 11 00:26:00.538903 waagent[2187]: 2025-09-11T00:26:00.538861Z INFO ExtHandler ExtHandler Sep 11 00:26:00.538978 waagent[2187]: 2025-09-11T00:26:00.538945Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 74d71938-9a08-44e0-a9c1-269ce3cd3bb5 correlation 852448a2-4e96-4272-8fc8-bb0374ef9f27 created: 2025-09-11T00:24:14.845955Z] Sep 11 00:26:00.539377 waagent[2187]: 2025-09-11T00:26:00.539338Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Sep 11 00:26:00.540157 waagent[2187]: 2025-09-11T00:26:00.540119Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 1 ms] Sep 11 00:26:00.582468 waagent[2187]: 2025-09-11T00:26:00.582418Z 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 Sep 11 00:26:00.582468 waagent[2187]: Try `iptables -h' or 'iptables --help' for more information.) Sep 11 00:26:00.582843 waagent[2187]: 2025-09-11T00:26:00.582806Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: C0BE8922-05ED-4097-A831-81CB53492AD6;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Sep 11 00:26:00.645131 waagent[2187]: 2025-09-11T00:26:00.645078Z INFO MonitorHandler ExtHandler Network interfaces: Sep 11 00:26:00.645131 waagent[2187]: Executing ['ip', '-a', '-o', 'link']: Sep 11 00:26:00.645131 waagent[2187]: 1: lo: mtu 65536 qdisc noqueue state UNKNOWN mode DEFAULT group default qlen 1000\ link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00 Sep 11 00:26:00.645131 waagent[2187]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:ed:8d:49:52:f6 brd ff:ff:ff:ff:ff:ff Sep 11 00:26:00.645131 waagent[2187]: Executing ['ip', '-4', '-a', '-o', 'address']: Sep 11 00:26:00.645131 waagent[2187]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Sep 11 00:26:00.645131 waagent[2187]: 2: eth0 inet 10.200.8.35/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Sep 11 00:26:00.645131 waagent[2187]: Executing ['ip', '-6', '-a', '-o', 'address']: Sep 11 00:26:00.645131 waagent[2187]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Sep 11 00:26:00.645131 waagent[2187]: 2: eth0 inet6 fe80::7eed:8dff:fe49:52f6/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Sep 11 00:26:00.715768 waagent[2187]: 2025-09-11T00:26:00.715721Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Sep 11 00:26:00.715768 waagent[2187]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Sep 11 00:26:00.715768 waagent[2187]: pkts bytes target prot opt in out source destination Sep 11 00:26:00.715768 waagent[2187]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Sep 11 00:26:00.715768 waagent[2187]: pkts bytes target prot opt in out source destination Sep 11 00:26:00.715768 waagent[2187]: Chain OUTPUT (policy ACCEPT 4 packets, 401 bytes) Sep 11 00:26:00.715768 waagent[2187]: pkts bytes target prot opt in out source destination Sep 11 00:26:00.715768 waagent[2187]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Sep 11 00:26:00.715768 waagent[2187]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Sep 11 00:26:00.715768 waagent[2187]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Sep 11 00:26:00.719202 waagent[2187]: 2025-09-11T00:26:00.719155Z INFO EnvHandler ExtHandler Current Firewall rules: Sep 11 00:26:00.719202 waagent[2187]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Sep 11 00:26:00.719202 waagent[2187]: pkts bytes target prot opt in out source destination Sep 11 00:26:00.719202 waagent[2187]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Sep 11 00:26:00.719202 waagent[2187]: pkts bytes target prot opt in out source destination Sep 11 00:26:00.719202 waagent[2187]: Chain OUTPUT (policy ACCEPT 6 packets, 505 bytes) Sep 11 00:26:00.719202 waagent[2187]: pkts bytes target prot opt in out source destination Sep 11 00:26:00.719202 waagent[2187]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Sep 11 00:26:00.719202 waagent[2187]: 1 60 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Sep 11 00:26:00.719202 waagent[2187]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Sep 11 00:26:00.719438 waagent[2187]: 2025-09-11T00:26:00.719407Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Sep 11 00:26:00.719581 waagent[2187]: 2025-09-11T00:26:00.719548Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Sep 11 00:26:01.753177 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 11 00:26:01.755880 systemd[1]: Started sshd@0-10.200.8.35:22-10.200.16.10:45412.service - OpenSSH per-connection server daemon (10.200.16.10:45412). Sep 11 00:26:02.823196 sshd[2334]: Accepted publickey for core from 10.200.16.10 port 45412 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:26:02.824426 sshd-session[2334]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:26:02.828612 systemd-logind[1887]: New session 3 of user core. Sep 11 00:26:02.838861 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 11 00:26:03.381000 systemd[1]: Started sshd@1-10.200.8.35:22-10.200.16.10:45422.service - OpenSSH per-connection server daemon (10.200.16.10:45422). Sep 11 00:26:04.032588 sshd[2339]: Accepted publickey for core from 10.200.16.10 port 45422 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:26:04.034042 sshd-session[2339]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:26:04.038361 systemd-logind[1887]: New session 4 of user core. Sep 11 00:26:04.046798 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 11 00:26:04.483948 sshd[2341]: Connection closed by 10.200.16.10 port 45422 Sep 11 00:26:04.484284 sshd-session[2339]: pam_unix(sshd:session): session closed for user core Sep 11 00:26:04.487752 systemd[1]: sshd@1-10.200.8.35:22-10.200.16.10:45422.service: Deactivated successfully. Sep 11 00:26:04.489535 systemd[1]: session-4.scope: Deactivated successfully. Sep 11 00:26:04.490354 systemd-logind[1887]: Session 4 logged out. Waiting for processes to exit. Sep 11 00:26:04.491417 systemd-logind[1887]: Removed session 4. Sep 11 00:26:04.618453 systemd[1]: Started sshd@2-10.200.8.35:22-10.200.16.10:45434.service - OpenSSH per-connection server daemon (10.200.16.10:45434). Sep 11 00:26:05.293412 sshd[2347]: Accepted publickey for core from 10.200.16.10 port 45434 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:26:05.294663 sshd-session[2347]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:26:05.299051 systemd-logind[1887]: New session 5 of user core. Sep 11 00:26:05.314804 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 11 00:26:05.744526 sshd[2349]: Connection closed by 10.200.16.10 port 45434 Sep 11 00:26:05.744850 sshd-session[2347]: pam_unix(sshd:session): session closed for user core Sep 11 00:26:05.747916 systemd[1]: sshd@2-10.200.8.35:22-10.200.16.10:45434.service: Deactivated successfully. Sep 11 00:26:05.749460 systemd[1]: session-5.scope: Deactivated successfully. Sep 11 00:26:05.750139 systemd-logind[1887]: Session 5 logged out. Waiting for processes to exit. Sep 11 00:26:05.751077 systemd-logind[1887]: Removed session 5. Sep 11 00:26:11.725456 chronyd[1885]: Selected source PHC0 Sep 11 00:26:15.866010 systemd[1]: Started sshd@3-10.200.8.35:22-10.200.16.10:43952.service - OpenSSH per-connection server daemon (10.200.16.10:43952). Sep 11 00:26:16.517350 sshd[2355]: Accepted publickey for core from 10.200.16.10 port 43952 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:26:16.518613 sshd-session[2355]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:26:16.522850 systemd-logind[1887]: New session 6 of user core. Sep 11 00:26:16.535792 systemd[1]: Started session-6.scope - Session 6 of User core. Sep 11 00:26:16.967450 sshd[2357]: Connection closed by 10.200.16.10 port 43952 Sep 11 00:26:16.967801 sshd-session[2355]: pam_unix(sshd:session): session closed for user core Sep 11 00:26:16.970956 systemd[1]: sshd@3-10.200.8.35:22-10.200.16.10:43952.service: Deactivated successfully. Sep 11 00:26:16.972492 systemd[1]: session-6.scope: Deactivated successfully. Sep 11 00:26:16.973165 systemd-logind[1887]: Session 6 logged out. Waiting for processes to exit. Sep 11 00:26:16.974115 systemd-logind[1887]: Removed session 6. Sep 11 00:26:17.083879 systemd[1]: Started sshd@4-10.200.8.35:22-10.200.16.10:43958.service - OpenSSH per-connection server daemon (10.200.16.10:43958). Sep 11 00:26:17.755858 sshd[2363]: Accepted publickey for core from 10.200.16.10 port 43958 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:26:17.757086 sshd-session[2363]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:26:17.761266 systemd-logind[1887]: New session 7 of user core. Sep 11 00:26:17.772792 systemd[1]: Started session-7.scope - Session 7 of User core. Sep 11 00:26:18.206940 sshd[2365]: Connection closed by 10.200.16.10 port 43958 Sep 11 00:26:18.207268 sshd-session[2363]: pam_unix(sshd:session): session closed for user core Sep 11 00:26:18.210474 systemd[1]: sshd@4-10.200.8.35:22-10.200.16.10:43958.service: Deactivated successfully. Sep 11 00:26:18.212012 systemd[1]: session-7.scope: Deactivated successfully. Sep 11 00:26:18.212692 systemd-logind[1887]: Session 7 logged out. Waiting for processes to exit. Sep 11 00:26:18.213609 systemd-logind[1887]: Removed session 7. Sep 11 00:26:20.872103 setup-nvidia[2047]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 544M 0 384k 0 0 294k 0 0:31:32 0:00:01 0:31:31 294k\u000d 2 544M 2 15.5M 0 0 6925k 0 0:01:20 0:00:02 0:01:18 6925k\u000d 7 544M 7 40.9M 0 0 12.3M 0 0:00:44 0:00:03 0:00:41 12.3M\u000d 11 544M 11 64.4M 0 0 14.9M 0 0:00:36 0:00:04 0:00:32 14.9M\u000d 15 544M 15 86.7M 0 0 16.3M 0 0:00:33 0:00:05 0:00:28 17.3M\u000d 20 544M 20 111M 0 0 17.6M 0 0:00:30 0:00:06 0:00:24 22.1M\u000d 25 544M 25 137M 0 0 18.6M 0 0:00:29 0:00:07 0:00:22 23.9M\u000d 29 544M 29 160M 0 0 19.1M 0 0:00:28 0:00:08 0:00:20 23.4M\u000d 30 544M 30 164M 0 0 17.6M 0 0:00:30 0:00:09 0:00:21 19.9M\u000d 30 544M 30 164M 0 0 15.7M 0 0:00:34 0:00:10 0:00:24 15.1M\u000d 30 544M 30 165M 0 0 14.4M 0 0:00:37 0:00:11 0:00:26 10.4M\u000d 30 544M 30 167M 0 0 13.5M 0 0:00:40 0:00:12 0:00:28 6192k\u000d 34 544M 34 187M 0 0 13.9M 0 0:00:38 0:00:13 0:00:25 5467k\u000d 38 544M 38 211M 0 0 14.6M 0 0:00:37 0:00:14 0:00:23 9475k\u000d 42 544M 42 233M 0 0 15.2M 0 0:00:35 0:00:15 0:00:20 14.1M\u000d 47 544M 47 256M 0 0 15.7M 0 0:00:34 0:00:16 0:00:18 18.7M\u000d 51 544M 51 281M 0 0 16.2M 0 0:00:33 0:00:17 0:00:16 23.0M\u000d 56 544M 56 305M 0 0 16.6M 0 0:00:32 0:00:18 0:00:14 23.9M\u000d 60 544M 60 328M 0 0 17.0M 0 0:00:31 0:00:19 0:00:12 23.9M\u000d 64 544M 64 353M 0 0 17.3M 0 0:00:31 0:00:20 0:00:11 23.8M\u000d 69 544M 69 376M 0 0 17.6M 0 0:00:30 0:00:21 0:00:09 23.7M\u000d 73 544M 73 399M 0 0 17.8M 0 0:00:30 0:00:22 0:00:08 23.5M\u000d 76 544M 76 414M 0 0 17.7M 0 0:00:30 0:00:23 0:00:07 21.9M\u000d 77 544M 77 423M 0 0 16.4M 0 0:00:33 0:00:25 0:00:08 14.6M\u000d 78 544M 78 429M 0 0 16.2M 0 0:00:33 0:00:26 0:00:07 12.5M\u000d 82 544M 82 449M 0 0 16.4M 0 0:00:33 0:00:27 0:00:06 12.0M\u000d 86 544M 86 472M 0 0 16.6M 0 0:00:32 0:00:28 0:00:04 11.9M\u000d 91 544M 91 495M 0 0 16.8M 0 0:00:32 0:00:29 0:00:03 13.2M\u000d 95 544M 95 519M 0 0 17.1M 0 0:00:31 0:00:30 0:00:01 20.9M\u000d 99 544M 99 543M 0 0 17.3M 0 0:00:31 0:00:31 --:--:-- 22.7M\u000d100 544M 100 544M 0 0 17.3M 0 0:00:31 0:00:31 --:--:-- 23.5M Sep 11 00:26:28.070701 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Sep 11 00:26:28.330225 systemd[1]: Started sshd@5-10.200.8.35:22-10.200.16.10:60572.service - OpenSSH per-connection server daemon (10.200.16.10:60572). Sep 11 00:26:28.992056 sshd[2381]: Accepted publickey for core from 10.200.16.10 port 60572 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:26:28.993479 sshd-session[2381]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:26:29.001849 systemd-logind[1887]: New session 8 of user core. Sep 11 00:26:29.008827 systemd[1]: Started session-8.scope - Session 8 of User core. Sep 11 00:26:29.455698 sshd[2383]: Connection closed by 10.200.16.10 port 60572 Sep 11 00:26:29.456296 sshd-session[2381]: pam_unix(sshd:session): session closed for user core Sep 11 00:26:29.461989 systemd[1]: sshd@5-10.200.8.35:22-10.200.16.10:60572.service: Deactivated successfully. Sep 11 00:26:29.466868 systemd[1]: session-8.scope: Deactivated successfully. Sep 11 00:26:29.470018 systemd-logind[1887]: Session 8 logged out. Waiting for processes to exit. Sep 11 00:26:29.471560 systemd-logind[1887]: Removed session 8. Sep 11 00:26:29.573452 systemd[1]: Started sshd@6-10.200.8.35:22-10.200.16.10:60586.service - OpenSSH per-connection server daemon (10.200.16.10:60586). Sep 11 00:26:30.239606 sshd[2389]: Accepted publickey for core from 10.200.16.10 port 60586 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:26:30.241121 sshd-session[2389]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:26:30.247153 systemd-logind[1887]: New session 9 of user core. Sep 11 00:26:30.254830 systemd[1]: Started session-9.scope - Session 9 of User core. Sep 11 00:26:30.698235 sshd[2391]: Connection closed by 10.200.16.10 port 60586 Sep 11 00:26:30.697883 sshd-session[2389]: pam_unix(sshd:session): session closed for user core Sep 11 00:26:30.702580 systemd[1]: sshd@6-10.200.8.35:22-10.200.16.10:60586.service: Deactivated successfully. Sep 11 00:26:30.704612 systemd[1]: session-9.scope: Deactivated successfully. Sep 11 00:26:30.704870 systemd-logind[1887]: Session 9 logged out. Waiting for processes to exit. Sep 11 00:26:30.707910 systemd-logind[1887]: Removed session 9. Sep 11 00:26:33.616793 update_engine[1888]: I20250911 00:26:33.616712 1888 update_attempter.cc:509] Updating boot flags... Sep 11 00:26:33.972095 setup-nvidia[1876]: Downloading NVIDIA 535.230.02 Driver Sep 11 00:26:33.976145 setup-nvidia[2501]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 00:26:33.976353 setup-nvidia[2501]: Dload Upload Total Spent Left Speed Sep 11 00:26:35.312847 setup-nvidia[2501]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 55 326M 55 180M 0 0 230M 0 0:00:01 --:--:-- 0:00:01 230M\u000d100 326M 100 326M 0 0 243M 0 0:00:01 0:00:01 --:--:-- 244M Sep 11 00:26:35.402334 setup-nvidia[1876]: Extract the NVIDIA Driver Installer 535.230.02 Sep 11 00:26:35.402334 setup-nvidia[1876]: /opt/nvidia/workdir/nvidia-workdir / Sep 11 00:26:35.439361 setup-nvidia[2568]: Creating directory NVIDIA-Linux-x86_64-535.230.02 Sep 11 00:26:35.758546 setup-nvidia[2568]: Verifying archive integrity... OK Sep 11 00:26:36.526768 setup-nvidia[2568]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.230.02 Sep 11 00:26:38.341507 setup-nvidia[2594]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Sep 11 00:26:38.453359 setup-nvidia[1876]: / Sep 11 00:26:38.453637 setup-nvidia[1876]: Spawn system-nspawn container to install the NVIDIA drivers Sep 11 00:26:39.052220 sudo[2601]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4372.1.0+nightly-20250910-2100.bin --overlay=/usr/lib/modules::/usr/lib/modules --bind=/opt/nvidia/workdir/nvidia-workdir:/nvidia --bind=/usr/lib/nvidia/bin:/app/bin/ /app/bin/install-nvidia NVIDIA-Linux-x86_64-535.230.02 Sep 11 00:26:39.053931 sudo[2601]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 11 00:26:39.061810 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Sep 11 00:26:39.064352 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Sep 11 00:26:39.090048 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Sep 11 00:26:39.095952 systemd[1]: Starting user@0.service - User Manager for UID 0... Sep 11 00:26:39.119603 (systemd)[2604]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Sep 11 00:26:39.121859 systemd-logind[1887]: New session c3 of user root. Sep 11 00:26:39.302257 systemd[2604]: Queued start job for default target default.target. Sep 11 00:26:39.320831 systemd[2604]: Created slice app.slice - User Application Slice. Sep 11 00:26:39.320862 systemd[2604]: Reached target paths.target - Paths. Sep 11 00:26:39.320909 systemd[2604]: Reached target timers.target - Timers. Sep 11 00:26:39.322075 systemd[2604]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 11 00:26:39.332641 systemd[2604]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 11 00:26:39.332718 systemd[2604]: Reached target sockets.target - Sockets. Sep 11 00:26:39.332763 systemd[2604]: Reached target basic.target - Basic System. Sep 11 00:26:39.332809 systemd[2604]: Reached target default.target - Main User Target. Sep 11 00:26:39.332845 systemd[2604]: Startup finished in 205ms. Sep 11 00:26:39.333007 systemd[1]: Started user@0.service - User Manager for UID 0. Sep 11 00:26:39.336990 systemd[1]: Started session-c2.scope - Session c2 of User root. Sep 11 00:26:39.398695 kernel: loop6: detected capacity change from 0 to 12644352 Sep 11 00:26:39.433693 kernel: loop6: p9 Sep 11 00:26:39.692261 setup-nvidia[2613]: ░ Spawning container flatcardevelopercontainer-4372.1.0nightly-20250910-2100.bin on /opt/nvidia/workdir/flatcar_developer_container-4372.1.0+nightly-20250910-2100.bin. Sep 11 00:26:40.813976 systemd[1]: Started sshd@7-10.200.8.35:22-10.200.16.10:54042.service - OpenSSH per-connection server daemon (10.200.16.10:54042). Sep 11 00:26:41.455939 sshd[2618]: Accepted publickey for core from 10.200.16.10 port 54042 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:26:41.457244 sshd-session[2618]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:26:41.461598 systemd-logind[1887]: New session 10 of user core. Sep 11 00:26:41.472805 systemd[1]: Started session-10.scope - Session 10 of User core. Sep 11 00:26:41.906785 sshd[2620]: Connection closed by 10.200.16.10 port 54042 Sep 11 00:26:41.907066 sshd-session[2618]: pam_unix(sshd:session): session closed for user core Sep 11 00:26:41.910280 systemd[1]: sshd@7-10.200.8.35:22-10.200.16.10:54042.service: Deactivated successfully. Sep 11 00:26:41.911871 systemd[1]: session-10.scope: Deactivated successfully. Sep 11 00:26:41.912546 systemd-logind[1887]: Session 10 logged out. Waiting for processes to exit. Sep 11 00:26:41.913480 systemd-logind[1887]: Removed session 10. Sep 11 00:26:42.022714 systemd[1]: Started sshd@8-10.200.8.35:22-10.200.16.10:54044.service - OpenSSH per-connection server daemon (10.200.16.10:54044). Sep 11 00:26:42.686839 sshd[2627]: Accepted publickey for core from 10.200.16.10 port 54044 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:26:42.688113 sshd-session[2627]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:26:42.692365 systemd-logind[1887]: New session 11 of user core. Sep 11 00:26:42.703795 systemd[1]: Started session-11.scope - Session 11 of User core. Sep 11 00:26:43.140120 sshd[2629]: Connection closed by 10.200.16.10 port 54044 Sep 11 00:26:43.140394 sshd-session[2627]: pam_unix(sshd:session): session closed for user core Sep 11 00:26:43.143742 systemd[1]: sshd@8-10.200.8.35:22-10.200.16.10:54044.service: Deactivated successfully. Sep 11 00:26:43.145259 systemd[1]: session-11.scope: Deactivated successfully. Sep 11 00:26:43.145952 systemd-logind[1887]: Session 11 logged out. Waiting for processes to exit. Sep 11 00:26:43.146885 systemd-logind[1887]: Removed session 11. Sep 11 00:26:43.621826 kernel: EXT4-fs (loop6p9): write access unavailable, skipping orphan cleanup Sep 11 00:26:43.621943 kernel: EXT4-fs (loop6p9): mounted filesystem f1e27df3-5e40-42fc-9547-7151e2f73697 ro without journal. Quota mode: none. Sep 11 00:26:53.265913 systemd[1]: Started sshd@9-10.200.8.35:22-10.200.16.10:33790.service - OpenSSH per-connection server daemon (10.200.16.10:33790). Sep 11 00:26:53.924853 sshd[2738]: Accepted publickey for core from 10.200.16.10 port 33790 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:26:53.926085 sshd-session[2738]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:26:53.930442 systemd-logind[1887]: New session 12 of user core. Sep 11 00:26:53.936803 systemd[1]: Started session-12.scope - Session 12 of User core. Sep 11 00:26:54.375000 sshd[2799]: Connection closed by 10.200.16.10 port 33790 Sep 11 00:26:54.375273 sshd-session[2738]: pam_unix(sshd:session): session closed for user core Sep 11 00:26:54.378501 systemd[1]: sshd@9-10.200.8.35:22-10.200.16.10:33790.service: Deactivated successfully. Sep 11 00:26:54.380041 systemd[1]: session-12.scope: Deactivated successfully. Sep 11 00:26:54.380733 systemd-logind[1887]: Session 12 logged out. Waiting for processes to exit. Sep 11 00:26:54.381792 systemd-logind[1887]: Removed session 12. Sep 11 00:26:54.490972 systemd[1]: Started sshd@10-10.200.8.35:22-10.200.16.10:33794.service - OpenSSH per-connection server daemon (10.200.16.10:33794). Sep 11 00:26:55.132211 sshd[2970]: Accepted publickey for core from 10.200.16.10 port 33794 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:26:55.133526 sshd-session[2970]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:26:55.143507 systemd-logind[1887]: New session 13 of user core. Sep 11 00:26:55.166791 systemd[1]: Started session-13.scope - Session 13 of User core. Sep 11 00:26:55.582947 sshd[3033]: Connection closed by 10.200.16.10 port 33794 Sep 11 00:26:55.583275 sshd-session[2970]: pam_unix(sshd:session): session closed for user core Sep 11 00:26:55.586442 systemd[1]: sshd@10-10.200.8.35:22-10.200.16.10:33794.service: Deactivated successfully. Sep 11 00:26:55.588011 systemd[1]: session-13.scope: Deactivated successfully. Sep 11 00:26:55.588689 systemd-logind[1887]: Session 13 logged out. Waiting for processes to exit. Sep 11 00:26:55.589718 systemd-logind[1887]: Removed session 13. Sep 11 00:27:05.698709 systemd[1]: Started sshd@11-10.200.8.35:22-10.200.16.10:36930.service - OpenSSH per-connection server daemon (10.200.16.10:36930). Sep 11 00:27:06.342034 sshd[3370]: Accepted publickey for core from 10.200.16.10 port 36930 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:27:06.343395 sshd-session[3370]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:27:06.347704 systemd-logind[1887]: New session 14 of user core. Sep 11 00:27:06.355801 systemd[1]: Started session-14.scope - Session 14 of User core. Sep 11 00:27:06.796262 sshd[3372]: Connection closed by 10.200.16.10 port 36930 Sep 11 00:27:06.796589 sshd-session[3370]: pam_unix(sshd:session): session closed for user core Sep 11 00:27:06.799885 systemd[1]: sshd@11-10.200.8.35:22-10.200.16.10:36930.service: Deactivated successfully. Sep 11 00:27:06.801623 systemd[1]: session-14.scope: Deactivated successfully. Sep 11 00:27:06.802420 systemd-logind[1887]: Session 14 logged out. Waiting for processes to exit. Sep 11 00:27:06.803361 systemd-logind[1887]: Removed session 14. Sep 11 00:27:06.905102 systemd[1]: Started sshd@12-10.200.8.35:22-10.200.16.10:36936.service - OpenSSH per-connection server daemon (10.200.16.10:36936). Sep 11 00:27:07.546580 sshd[3378]: Accepted publickey for core from 10.200.16.10 port 36936 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:27:07.547887 sshd-session[3378]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:27:07.552295 systemd-logind[1887]: New session 15 of user core. Sep 11 00:27:07.568801 systemd[1]: Started session-15.scope - Session 15 of User core. Sep 11 00:27:07.996255 sshd[3380]: Connection closed by 10.200.16.10 port 36936 Sep 11 00:27:07.996573 sshd-session[3378]: pam_unix(sshd:session): session closed for user core Sep 11 00:27:07.999849 systemd[1]: sshd@12-10.200.8.35:22-10.200.16.10:36936.service: Deactivated successfully. Sep 11 00:27:08.001340 systemd[1]: session-15.scope: Deactivated successfully. Sep 11 00:27:08.002025 systemd-logind[1887]: Session 15 logged out. Waiting for processes to exit. Sep 11 00:27:08.002996 systemd-logind[1887]: Removed session 15. Sep 11 00:27:18.114058 systemd[1]: Started sshd@13-10.200.8.35:22-10.200.16.10:55108.service - OpenSSH per-connection server daemon (10.200.16.10:55108). Sep 11 00:27:18.766004 sshd[3430]: Accepted publickey for core from 10.200.16.10 port 55108 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:27:18.767238 sshd-session[3430]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:27:18.771612 systemd-logind[1887]: New session 16 of user core. Sep 11 00:27:18.781793 systemd[1]: Started session-16.scope - Session 16 of User core. Sep 11 00:27:19.222920 sshd[3432]: Connection closed by 10.200.16.10 port 55108 Sep 11 00:27:19.223250 sshd-session[3430]: pam_unix(sshd:session): session closed for user core Sep 11 00:27:19.226526 systemd[1]: sshd@13-10.200.8.35:22-10.200.16.10:55108.service: Deactivated successfully. Sep 11 00:27:19.228060 systemd[1]: session-16.scope: Deactivated successfully. Sep 11 00:27:19.228786 systemd-logind[1887]: Session 16 logged out. Waiting for processes to exit. Sep 11 00:27:19.229752 systemd-logind[1887]: Removed session 16. Sep 11 00:27:19.340755 systemd[1]: Started sshd@14-10.200.8.35:22-10.200.16.10:55122.service - OpenSSH per-connection server daemon (10.200.16.10:55122). Sep 11 00:27:19.990356 sshd[3438]: Accepted publickey for core from 10.200.16.10 port 55122 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:27:19.991612 sshd-session[3438]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:27:19.995951 systemd-logind[1887]: New session 17 of user core. Sep 11 00:27:20.005812 systemd[1]: Started session-17.scope - Session 17 of User core. Sep 11 00:27:20.441317 sshd[3443]: Connection closed by 10.200.16.10 port 55122 Sep 11 00:27:20.441639 sshd-session[3438]: pam_unix(sshd:session): session closed for user core Sep 11 00:27:20.444927 systemd[1]: sshd@14-10.200.8.35:22-10.200.16.10:55122.service: Deactivated successfully. Sep 11 00:27:20.446543 systemd[1]: session-17.scope: Deactivated successfully. Sep 11 00:27:20.447252 systemd-logind[1887]: Session 17 logged out. Waiting for processes to exit. Sep 11 00:27:20.448310 systemd-logind[1887]: Removed session 17. Sep 11 00:27:30.557401 systemd[1]: Started sshd@15-10.200.8.35:22-10.200.16.10:40616.service - OpenSSH per-connection server daemon (10.200.16.10:40616). Sep 11 00:27:31.234873 sshd[6536]: Accepted publickey for core from 10.200.16.10 port 40616 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:27:31.235714 sshd-session[6536]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:27:31.242178 systemd-logind[1887]: New session 18 of user core. Sep 11 00:27:31.248936 systemd[1]: Started session-18.scope - Session 18 of User core. Sep 11 00:27:31.692191 sshd[6713]: Connection closed by 10.200.16.10 port 40616 Sep 11 00:27:31.692948 sshd-session[6536]: pam_unix(sshd:session): session closed for user core Sep 11 00:27:31.698287 systemd-logind[1887]: Session 18 logged out. Waiting for processes to exit. Sep 11 00:27:31.698852 systemd[1]: sshd@15-10.200.8.35:22-10.200.16.10:40616.service: Deactivated successfully. Sep 11 00:27:31.700604 systemd[1]: session-18.scope: Deactivated successfully. Sep 11 00:27:31.703836 systemd-logind[1887]: Removed session 18. Sep 11 00:27:31.810923 systemd[1]: Started sshd@16-10.200.8.35:22-10.200.16.10:40622.service - OpenSSH per-connection server daemon (10.200.16.10:40622). Sep 11 00:27:32.465466 sshd[6865]: Accepted publickey for core from 10.200.16.10 port 40622 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:27:32.467358 sshd-session[6865]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:27:32.472743 systemd-logind[1887]: New session 19 of user core. Sep 11 00:27:32.479806 systemd[1]: Started session-19.scope - Session 19 of User core. Sep 11 00:27:32.926283 sshd[7169]: Connection closed by 10.200.16.10 port 40622 Sep 11 00:27:32.926799 sshd-session[6865]: pam_unix(sshd:session): session closed for user core Sep 11 00:27:32.930339 systemd-logind[1887]: Session 19 logged out. Waiting for processes to exit. Sep 11 00:27:32.932091 systemd[1]: sshd@16-10.200.8.35:22-10.200.16.10:40622.service: Deactivated successfully. Sep 11 00:27:32.934578 systemd[1]: session-19.scope: Deactivated successfully. Sep 11 00:27:32.936952 systemd-logind[1887]: Removed session 19. Sep 11 00:27:43.038924 systemd[1]: Started sshd@17-10.200.8.35:22-10.200.16.10:39344.service - OpenSSH per-connection server daemon (10.200.16.10:39344). Sep 11 00:27:43.681957 sshd[10725]: Accepted publickey for core from 10.200.16.10 port 39344 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:27:43.683428 sshd-session[10725]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:27:43.691141 systemd-logind[1887]: New session 20 of user core. Sep 11 00:27:43.697872 systemd[1]: Started session-20.scope - Session 20 of User core. Sep 11 00:27:44.139460 sshd[10984]: Connection closed by 10.200.16.10 port 39344 Sep 11 00:27:44.139764 sshd-session[10725]: pam_unix(sshd:session): session closed for user core Sep 11 00:27:44.142932 systemd[1]: sshd@17-10.200.8.35:22-10.200.16.10:39344.service: Deactivated successfully. Sep 11 00:27:44.145577 systemd[1]: session-20.scope: Deactivated successfully. Sep 11 00:27:44.146308 systemd-logind[1887]: Session 20 logged out. Waiting for processes to exit. Sep 11 00:27:44.148413 systemd-logind[1887]: Removed session 20. Sep 11 00:27:44.251889 systemd[1]: Started sshd@18-10.200.8.35:22-10.200.16.10:39352.service - OpenSSH per-connection server daemon (10.200.16.10:39352). Sep 11 00:27:44.901993 sshd[11231]: Accepted publickey for core from 10.200.16.10 port 39352 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:27:44.903254 sshd-session[11231]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:27:44.907571 systemd-logind[1887]: New session 21 of user core. Sep 11 00:27:44.929800 systemd[1]: Started session-21.scope - Session 21 of User core. Sep 11 00:27:45.359015 sshd[11446]: Connection closed by 10.200.16.10 port 39352 Sep 11 00:27:45.360772 sshd-session[11231]: pam_unix(sshd:session): session closed for user core Sep 11 00:27:45.364025 systemd-logind[1887]: Session 21 logged out. Waiting for processes to exit. Sep 11 00:27:45.364921 systemd[1]: sshd@18-10.200.8.35:22-10.200.16.10:39352.service: Deactivated successfully. Sep 11 00:27:45.366969 systemd[1]: session-21.scope: Deactivated successfully. Sep 11 00:27:45.369784 systemd-logind[1887]: Removed session 21. Sep 11 00:27:55.475278 systemd[1]: Started sshd@19-10.200.8.35:22-10.200.16.10:53934.service - OpenSSH per-connection server daemon (10.200.16.10:53934). Sep 11 00:27:56.146574 sshd[11891]: Accepted publickey for core from 10.200.16.10 port 53934 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:27:56.148059 sshd-session[11891]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:27:56.154369 systemd-logind[1887]: New session 22 of user core. Sep 11 00:27:56.159878 systemd[1]: Started session-22.scope - Session 22 of User core. Sep 11 00:27:56.607713 sshd[11907]: Connection closed by 10.200.16.10 port 53934 Sep 11 00:27:56.607292 sshd-session[11891]: pam_unix(sshd:session): session closed for user core Sep 11 00:27:56.613432 systemd[1]: sshd@19-10.200.8.35:22-10.200.16.10:53934.service: Deactivated successfully. Sep 11 00:27:56.613920 systemd-logind[1887]: Session 22 logged out. Waiting for processes to exit. Sep 11 00:27:56.617433 systemd[1]: session-22.scope: Deactivated successfully. Sep 11 00:27:56.621177 systemd-logind[1887]: Removed session 22. Sep 11 00:27:56.723959 systemd[1]: Started sshd@20-10.200.8.35:22-10.200.16.10:53938.service - OpenSSH per-connection server daemon (10.200.16.10:53938). Sep 11 00:27:57.394572 sshd[11929]: Accepted publickey for core from 10.200.16.10 port 53938 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:27:57.396381 sshd-session[11929]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:27:57.405572 systemd-logind[1887]: New session 23 of user core. Sep 11 00:27:57.413861 systemd[1]: Started session-23.scope - Session 23 of User core. Sep 11 00:27:57.861448 sshd[11944]: Connection closed by 10.200.16.10 port 53938 Sep 11 00:27:57.862240 sshd-session[11929]: pam_unix(sshd:session): session closed for user core Sep 11 00:27:57.866955 systemd-logind[1887]: Session 23 logged out. Waiting for processes to exit. Sep 11 00:27:57.868426 systemd[1]: sshd@20-10.200.8.35:22-10.200.16.10:53938.service: Deactivated successfully. Sep 11 00:27:57.870363 systemd[1]: session-23.scope: Deactivated successfully. Sep 11 00:27:57.872383 systemd-logind[1887]: Removed session 23. Sep 11 00:28:07.980052 systemd[1]: Started sshd@21-10.200.8.35:22-10.200.16.10:44778.service - OpenSSH per-connection server daemon (10.200.16.10:44778). Sep 11 00:28:08.654702 sshd[12303]: Accepted publickey for core from 10.200.16.10 port 44778 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:28:08.656136 sshd-session[12303]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:28:08.662604 systemd-logind[1887]: New session 24 of user core. Sep 11 00:28:08.670873 systemd[1]: Started session-24.scope - Session 24 of User core. Sep 11 00:28:09.115257 sshd[12323]: Connection closed by 10.200.16.10 port 44778 Sep 11 00:28:09.115627 sshd-session[12303]: pam_unix(sshd:session): session closed for user core Sep 11 00:28:09.122722 systemd-logind[1887]: Session 24 logged out. Waiting for processes to exit. Sep 11 00:28:09.123995 systemd[1]: sshd@21-10.200.8.35:22-10.200.16.10:44778.service: Deactivated successfully. Sep 11 00:28:09.126322 systemd[1]: session-24.scope: Deactivated successfully. Sep 11 00:28:09.130147 systemd-logind[1887]: Removed session 24. Sep 11 00:28:09.230539 systemd[1]: Started sshd@22-10.200.8.35:22-10.200.16.10:44782.service - OpenSSH per-connection server daemon (10.200.16.10:44782). Sep 11 00:28:09.907447 sshd[12341]: Accepted publickey for core from 10.200.16.10 port 44782 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:28:09.908901 sshd-session[12341]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:28:09.917335 systemd-logind[1887]: New session 25 of user core. Sep 11 00:28:09.923280 systemd[1]: Started session-25.scope - Session 25 of User core. Sep 11 00:28:10.364786 sshd[12367]: Connection closed by 10.200.16.10 port 44782 Sep 11 00:28:10.365173 sshd-session[12341]: pam_unix(sshd:session): session closed for user core Sep 11 00:28:10.369086 systemd-logind[1887]: Session 25 logged out. Waiting for processes to exit. Sep 11 00:28:10.371037 systemd[1]: sshd@22-10.200.8.35:22-10.200.16.10:44782.service: Deactivated successfully. Sep 11 00:28:10.374358 systemd[1]: session-25.scope: Deactivated successfully. Sep 11 00:28:10.376871 systemd-logind[1887]: Removed session 25. Sep 11 00:28:20.483040 systemd[1]: Started sshd@23-10.200.8.35:22-10.200.16.10:59472.service - OpenSSH per-connection server daemon (10.200.16.10:59472). Sep 11 00:28:21.124523 sshd[12634]: Accepted publickey for core from 10.200.16.10 port 59472 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:28:21.125854 sshd-session[12634]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:28:21.130874 systemd-logind[1887]: New session 26 of user core. Sep 11 00:28:21.140822 systemd[1]: Started session-26.scope - Session 26 of User core. Sep 11 00:28:21.575729 sshd[12636]: Connection closed by 10.200.16.10 port 59472 Sep 11 00:28:21.576080 sshd-session[12634]: pam_unix(sshd:session): session closed for user core Sep 11 00:28:21.579516 systemd[1]: sshd@23-10.200.8.35:22-10.200.16.10:59472.service: Deactivated successfully. Sep 11 00:28:21.581062 systemd[1]: session-26.scope: Deactivated successfully. Sep 11 00:28:21.581751 systemd-logind[1887]: Session 26 logged out. Waiting for processes to exit. Sep 11 00:28:21.582783 systemd-logind[1887]: Removed session 26. Sep 11 00:28:21.693164 systemd[1]: Started sshd@24-10.200.8.35:22-10.200.16.10:59478.service - OpenSSH per-connection server daemon (10.200.16.10:59478). Sep 11 00:28:22.339874 sshd[12642]: Accepted publickey for core from 10.200.16.10 port 59478 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:28:22.341163 sshd-session[12642]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:28:22.345436 systemd-logind[1887]: New session 27 of user core. Sep 11 00:28:22.356788 systemd[1]: Started session-27.scope - Session 27 of User core. Sep 11 00:28:22.795289 sshd[12644]: Connection closed by 10.200.16.10 port 59478 Sep 11 00:28:22.795616 sshd-session[12642]: pam_unix(sshd:session): session closed for user core Sep 11 00:28:22.798948 systemd[1]: sshd@24-10.200.8.35:22-10.200.16.10:59478.service: Deactivated successfully. Sep 11 00:28:22.800481 systemd[1]: session-27.scope: Deactivated successfully. Sep 11 00:28:22.801160 systemd-logind[1887]: Session 27 logged out. Waiting for processes to exit. Sep 11 00:28:22.802100 systemd-logind[1887]: Removed session 27. Sep 11 00:28:26.218059 setup-nvidia[2613]: \u000d Sep 11 00:28:26.218059 setup-nvidia[2613]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Sep 11 00:28:26.218059 setup-nvidia[2613]: frequently when this kernel module was built against the wrong or Sep 11 00:28:26.218059 setup-nvidia[2613]: improperly configured kernel sources, with a version of gcc that differs Sep 11 00:28:26.218059 setup-nvidia[2613]: from the one used to build the target kernel, or if another driver, such Sep 11 00:28:26.218059 setup-nvidia[2613]: as nouveau, is present and prevents the NVIDIA kernel module from Sep 11 00:28:26.218059 setup-nvidia[2613]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Sep 11 00:28:26.218059 setup-nvidia[2613]: installed in this system is supported by this NVIDIA Linux graphics Sep 11 00:28:26.218059 setup-nvidia[2613]: driver release. Sep 11 00:28:26.218059 setup-nvidia[2613]: \u000d Sep 11 00:28:26.218059 setup-nvidia[2613]: Please see the log entries 'Kernel module load error' and 'Kernel Sep 11 00:28:26.218059 setup-nvidia[2613]: messages' at the end of the file Sep 11 00:28:26.218059 setup-nvidia[2613]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more Sep 11 00:28:26.218059 setup-nvidia[2613]: information. Sep 11 00:28:26.218059 setup-nvidia[2613]: \u000d Sep 11 00:28:26.232501 setup-nvidia[2613]: dmesg: read kernel buffer failed: Operation not permitted Sep 11 00:28:26.253338 setup-nvidia[2613]: \u000d Sep 11 00:28:26.253338 setup-nvidia[2613]: ERROR: Installation has failed. Please see the file Sep 11 00:28:26.253338 setup-nvidia[2613]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for Sep 11 00:28:26.253338 setup-nvidia[2613]: details. You may find suggestions on fixing installation problems in Sep 11 00:28:26.253338 setup-nvidia[2613]: the README available on the Linux driver download page at Sep 11 00:28:26.253338 setup-nvidia[2613]: www.nvidia.com. Sep 11 00:28:26.253338 setup-nvidia[2613]: \u000d Sep 11 00:28:26.253870 setup-nvidia[2613]: Last 50 lines of nvidia-installer.log: Sep 11 00:28:26.256128 setup-nvidia[2613]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039000rm+0x53: 'naked' return found in MITIGATION_RETHUNK build Sep 11 00:28:26.256128 setup-nvidia[2613]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038997rm+0x90: 'naked' return found in MITIGATION_RETHUNK build Sep 11 00:28:26.256128 setup-nvidia[2613]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038990rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build Sep 11 00:28:26.256128 setup-nvidia[2613]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039046rm+0x93: 'naked' return found in MITIGATION_RETHUNK build Sep 11 00:28:26.256128 setup-nvidia[2613]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039042rm+0x36: 'naked' return found in MITIGATION_RETHUNK build Sep 11 00:28:26.256128 setup-nvidia[2613]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039041rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build Sep 11 00:28:26.256128 setup-nvidia[2613]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039043rm+0x77: 'naked' return found in MITIGATION_RETHUNK build Sep 11 00:28:26.256128 setup-nvidia[2613]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038987rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build Sep 11 00:28:26.256128 setup-nvidia[2613]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030558rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build Sep 11 00:28:26.256128 setup-nvidia[2613]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030557rm+0x27: 'naked' return found in MITIGATION_RETHUNK build Sep 11 00:28:26.256128 setup-nvidia[2613]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030561rm+0x37: 'naked' return found in MITIGATION_RETHUNK build Sep 11 00:28:26.256128 setup-nvidia[2613]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030560rm+0x24: 'naked' return found in MITIGATION_RETHUNK build Sep 11 00:28:26.256128 setup-nvidia[2613]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030559rm+0xca: 'naked' return found in MITIGATION_RETHUNK build Sep 11 00:28:26.256128 setup-nvidia[2613]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030562rm+0x193: 'naked' return found in MITIGATION_RETHUNK build Sep 11 00:28:26.256128 setup-nvidia[2613]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030564rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build Sep 11 00:28:26.256128 setup-nvidia[2613]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv011860rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build Sep 11 00:28:26.256128 setup-nvidia[2613]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039354rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build Sep 11 00:28:26.256128 setup-nvidia[2613]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/Module.symvers Sep 11 00:28:26.256128 setup-nvidia[2613]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.mod.o Sep 11 00:28:26.256128 setup-nvidia[2613]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/.module-common.o Sep 11 00:28:26.256128 setup-nvidia[2613]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.mod.o Sep 11 00:28:26.256128 setup-nvidia[2613]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.mod.o Sep 11 00:28:26.256128 setup-nvidia[2613]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.mod.o Sep 11 00:28:26.256128 setup-nvidia[2613]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.mod.o Sep 11 00:28:26.256128 setup-nvidia[2613]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Sep 11 00:28:26.256128 setup-nvidia[2613]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Sep 11 00:28:26.256128 setup-nvidia[2613]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Sep 11 00:28:26.256128 setup-nvidia[2613]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Sep 11 00:28:26.256128 setup-nvidia[2613]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Sep 11 00:28:26.256128 setup-nvidia[2613]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko due to unavailability of vmlinux Sep 11 00:28:26.256128 setup-nvidia[2613]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Sep 11 00:28:26.256128 setup-nvidia[2613]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Sep 11 00:28:26.256128 setup-nvidia[2613]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko due to unavailability of vmlinux Sep 11 00:28:26.256128 setup-nvidia[2613]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Sep 11 00:28:26.256128 setup-nvidia[2613]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko due to unavailability of vmlinux Sep 11 00:28:26.256128 setup-nvidia[2613]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Sep 11 00:28:26.257031 setup-nvidia[2613]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko due to unavailability of vmlinux Sep 11 00:28:26.257031 setup-nvidia[2613]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Sep 11 00:28:26.257031 setup-nvidia[2613]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko due to unavailability of vmlinux Sep 11 00:28:26.257031 setup-nvidia[2613]: make[2]: Leaving directory '/usr/lib/modules/6.12.46-flatcar/build' Sep 11 00:28:26.257031 setup-nvidia[2613]: make[1]: Leaving directory '/usr/lib/modules/6.12.46-flatcar/source' Sep 11 00:28:26.257031 setup-nvidia[2613]: -> done. Sep 11 00:28:26.257031 setup-nvidia[2613]: -> Kernel module compilation complete. Sep 11 00:28:26.257031 setup-nvidia[2613]: -> Unable to determine if Secure Boot is enabled: No such file or directory Sep 11 00:28:26.257031 setup-nvidia[2613]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most frequently when this kernel module was built against the wrong or improperly configured kernel sources, with a version of gcc that differs from the one used to build the target kernel, or if another driver, such as nouveau, is present and prevents the NVIDIA kernel module from obtaining ownership of the NVIDIA device(s), or no NVIDIA device installed in this system is supported by this NVIDIA Linux graphics driver release. Sep 11 00:28:26.257031 setup-nvidia[2613]: \u000d Sep 11 00:28:26.257031 setup-nvidia[2613]: Please see the log entries 'Kernel module load error' and 'Kernel messages' at the end of the file '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more information. Sep 11 00:28:26.257031 setup-nvidia[2613]: -> Kernel module load error: Operation not permitted Sep 11 00:28:26.257031 setup-nvidia[2613]: -> Kernel messages: Sep 11 00:28:26.257031 setup-nvidia[2613]: ERROR: Installation has failed. Please see the file '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for details. You may find suggestions on fixing installation problems in the README available on the Linux driver download page at www.nvidia.com. Sep 11 00:28:32.913023 systemd[1]: Started sshd@25-10.200.8.35:22-10.200.16.10:54372.service - OpenSSH per-connection server daemon (10.200.16.10:54372). Sep 11 00:28:33.588183 sshd[12830]: Accepted publickey for core from 10.200.16.10 port 54372 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:28:33.589445 sshd-session[12830]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:28:33.593663 systemd-logind[1887]: New session 28 of user core. Sep 11 00:28:33.602806 systemd[1]: Started session-28.scope - Session 28 of User core. Sep 11 00:28:34.042289 sshd[12832]: Connection closed by 10.200.16.10 port 54372 Sep 11 00:28:34.042600 sshd-session[12830]: pam_unix(sshd:session): session closed for user core Sep 11 00:28:34.045743 systemd[1]: sshd@25-10.200.8.35:22-10.200.16.10:54372.service: Deactivated successfully. Sep 11 00:28:34.047267 systemd[1]: session-28.scope: Deactivated successfully. Sep 11 00:28:34.047949 systemd-logind[1887]: Session 28 logged out. Waiting for processes to exit. Sep 11 00:28:34.048878 systemd-logind[1887]: Removed session 28. Sep 11 00:28:34.158714 systemd[1]: Started sshd@26-10.200.8.35:22-10.200.16.10:54374.service - OpenSSH per-connection server daemon (10.200.16.10:54374). Sep 11 00:28:34.831886 sshd[12838]: Accepted publickey for core from 10.200.16.10 port 54374 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:28:34.833135 sshd-session[12838]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:28:34.837392 systemd-logind[1887]: New session 29 of user core. Sep 11 00:28:34.844813 systemd[1]: Started session-29.scope - Session 29 of User core. Sep 11 00:28:35.284901 sshd[12840]: Connection closed by 10.200.16.10 port 54374 Sep 11 00:28:35.285219 sshd-session[12838]: pam_unix(sshd:session): session closed for user core Sep 11 00:28:35.288398 systemd[1]: sshd@26-10.200.8.35:22-10.200.16.10:54374.service: Deactivated successfully. Sep 11 00:28:35.290037 systemd[1]: session-29.scope: Deactivated successfully. Sep 11 00:28:35.290700 systemd-logind[1887]: Session 29 logged out. Waiting for processes to exit. Sep 11 00:28:35.291594 systemd-logind[1887]: Removed session 29. Sep 11 00:28:45.401766 systemd[1]: Started sshd@27-10.200.8.35:22-10.200.16.10:56518.service - OpenSSH per-connection server daemon (10.200.16.10:56518). Sep 11 00:28:46.045139 sshd[12846]: Accepted publickey for core from 10.200.16.10 port 56518 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:28:46.046375 sshd-session[12846]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:28:46.050457 systemd-logind[1887]: New session 30 of user core. Sep 11 00:28:46.070802 systemd[1]: Started session-30.scope - Session 30 of User core. Sep 11 00:28:46.496741 sshd[12848]: Connection closed by 10.200.16.10 port 56518 Sep 11 00:28:46.497073 sshd-session[12846]: pam_unix(sshd:session): session closed for user core Sep 11 00:28:46.500445 systemd[1]: sshd@27-10.200.8.35:22-10.200.16.10:56518.service: Deactivated successfully. Sep 11 00:28:46.502216 systemd[1]: session-30.scope: Deactivated successfully. Sep 11 00:28:46.503016 systemd-logind[1887]: Session 30 logged out. Waiting for processes to exit. Sep 11 00:28:46.504062 systemd-logind[1887]: Removed session 30. Sep 11 00:28:46.613313 systemd[1]: Started sshd@28-10.200.8.35:22-10.200.16.10:56524.service - OpenSSH per-connection server daemon (10.200.16.10:56524). Sep 11 00:28:47.277165 sshd[12854]: Accepted publickey for core from 10.200.16.10 port 56524 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:28:47.278470 sshd-session[12854]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:28:47.283434 systemd-logind[1887]: New session 31 of user core. Sep 11 00:28:47.296305 systemd[1]: Started session-31.scope - Session 31 of User core. Sep 11 00:28:47.728556 sshd[12856]: Connection closed by 10.200.16.10 port 56524 Sep 11 00:28:47.728920 sshd-session[12854]: pam_unix(sshd:session): session closed for user core Sep 11 00:28:47.732350 systemd[1]: sshd@28-10.200.8.35:22-10.200.16.10:56524.service: Deactivated successfully. Sep 11 00:28:47.733869 systemd[1]: session-31.scope: Deactivated successfully. Sep 11 00:28:47.734538 systemd-logind[1887]: Session 31 logged out. Waiting for processes to exit. Sep 11 00:28:47.735488 systemd-logind[1887]: Removed session 31. Sep 11 00:28:52.763704 kernel: EXT4-fs (loop6p9): unmounting filesystem f1e27df3-5e40-42fc-9547-7151e2f73697. Sep 11 00:28:52.764201 setup-nvidia[2613]: Container flatcardevelopercontainer-4372.1.0nightly-20250910-2100.bin exited successfully. Sep 11 00:28:52.766533 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4372.1.0nightly\x2d20250910\x2d2100.bin.mount: Deactivated successfully. Sep 11 00:28:52.824478 sudo[2601]: pam_unix(sudo:session): session closed for user root Sep 11 00:28:52.826957 systemd[1]: session-c2.scope: Deactivated successfully. Sep 11 00:28:52.827222 systemd[1]: session-c2.scope: Consumed 5min 8.400s CPU time, 893.4M memory peak. Sep 11 00:28:53.240254 setup-nvidia[1876]: /opt/nvidia / Sep 11 00:28:53.241262 setup-nvidia[1876]: / Sep 11 00:28:53.250692 kernel: IPMI message handler: version 39.2 Sep 11 00:28:53.253702 kernel: ipmi device interface Sep 11 00:28:53.266016 setup-nvidia[1876]: /opt/nvidia/current/usr/lib/modules/6.12.46-flatcar/video / Sep 11 00:28:53.318494 kernel: nvidia: loading out-of-tree module taints kernel. Sep 11 00:28:53.318628 kernel: nvidia: module license 'NVIDIA' taints kernel. Sep 11 00:28:53.318651 kernel: Disabling lock debugging due to kernel taint Sep 11 00:28:53.325764 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Sep 11 00:28:53.325805 kernel: nvidia: module license taints kernel. Sep 11 00:28:53.742695 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 Sep 11 00:28:54.131260 kernel: Sep 11 00:28:54.131403 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Sep 11 00:28:54.339693 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.230.02 Fri Dec 20 21:42:05 UTC 2024 Sep 11 00:28:54.360691 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.230.02 Fri Dec 20 21:52:34 UTC 2024 Sep 11 00:28:54.379699 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Sep 11 00:28:54.424691 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. Sep 11 00:28:54.424956 setup-nvidia[1876]: / Sep 11 00:28:54.439036 setup-nvidia[12896]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 11 00:28:56.190647 setup-nvidia[12913]: Thu Sep 11 00:28:56 2025 Sep 11 00:28:56.190647 setup-nvidia[12913]: +---------------------------------------------------------------------------------------+ Sep 11 00:28:56.190647 setup-nvidia[12913]: | NVIDIA-SMI 535.230.02 Driver Version: 535.230.02 CUDA Version: 12.2 | Sep 11 00:28:56.190647 setup-nvidia[12913]: |-----------------------------------------+----------------------+----------------------+ Sep 11 00:28:56.190647 setup-nvidia[12913]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Sep 11 00:28:56.190647 setup-nvidia[12913]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Sep 11 00:28:56.190647 setup-nvidia[12913]: | | | MIG M. | Sep 11 00:28:56.190647 setup-nvidia[12913]: |=========================================+======================+======================| Sep 11 00:28:56.194380 setup-nvidia[12913]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Sep 11 00:28:56.194380 setup-nvidia[12913]: | N/A 24C P0 36W / 250W | 0MiB / 16384MiB | 0% Default | Sep 11 00:28:56.194380 setup-nvidia[12913]: | | | N/A | Sep 11 00:28:56.194380 setup-nvidia[12913]: +-----------------------------------------+----------------------+----------------------+ Sep 11 00:28:56.194492 setup-nvidia[12913]: Sep 11 00:28:56.194492 setup-nvidia[12913]: +---------------------------------------------------------------------------------------+ Sep 11 00:28:56.194492 setup-nvidia[12913]: | Processes: | Sep 11 00:28:56.194492 setup-nvidia[12913]: | GPU GI CI PID Type Process name GPU Memory | Sep 11 00:28:56.194492 setup-nvidia[12913]: | ID ID Usage | Sep 11 00:28:56.194492 setup-nvidia[12913]: |=======================================================================================| Sep 11 00:28:56.194622 setup-nvidia[12913]: | No running processes found | Sep 11 00:28:56.194622 setup-nvidia[12913]: +---------------------------------------------------------------------------------------+ Sep 11 00:28:56.565462 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 11 00:28:56.570257 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 11 00:28:56.574751 systemd[1]: Startup finished in 1.302s (firmware) + 33.043s (loader) + 3.799s (kernel) + 14.610s (initrd) + 3min 27.549s (userspace) = 4min 20.306s. Sep 11 00:28:57.842055 systemd[1]: Started sshd@29-10.200.8.35:22-10.200.16.10:35890.service - OpenSSH per-connection server daemon (10.200.16.10:35890). Sep 11 00:28:58.497016 sshd[12920]: Accepted publickey for core from 10.200.16.10 port 35890 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:28:58.498287 sshd-session[12920]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:28:58.502561 systemd-logind[1887]: New session 32 of user core. Sep 11 00:28:58.516807 systemd[1]: Started session-32.scope - Session 32 of User core. Sep 11 00:28:58.952384 sshd[12922]: Connection closed by 10.200.16.10 port 35890 Sep 11 00:28:58.952760 sshd-session[12920]: pam_unix(sshd:session): session closed for user core Sep 11 00:28:58.956117 systemd[1]: sshd@29-10.200.8.35:22-10.200.16.10:35890.service: Deactivated successfully. Sep 11 00:28:58.957660 systemd[1]: session-32.scope: Deactivated successfully. Sep 11 00:28:58.958334 systemd-logind[1887]: Session 32 logged out. Waiting for processes to exit. Sep 11 00:28:58.959259 systemd-logind[1887]: Removed session 32. Sep 11 00:28:59.082707 systemd[1]: Started sshd@30-10.200.8.35:22-10.200.16.10:35902.service - OpenSSH per-connection server daemon (10.200.16.10:35902). Sep 11 00:28:59.745257 sshd[12928]: Accepted publickey for core from 10.200.16.10 port 35902 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:28:59.746571 sshd-session[12928]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:28:59.750869 systemd-logind[1887]: New session 33 of user core. Sep 11 00:28:59.767804 systemd[1]: Started session-33.scope - Session 33 of User core. Sep 11 00:29:00.192151 sshd[12930]: Connection closed by 10.200.16.10 port 35902 Sep 11 00:29:00.192456 sshd-session[12928]: pam_unix(sshd:session): session closed for user core Sep 11 00:29:00.195812 systemd[1]: sshd@30-10.200.8.35:22-10.200.16.10:35902.service: Deactivated successfully. Sep 11 00:29:00.197343 systemd[1]: session-33.scope: Deactivated successfully. Sep 11 00:29:00.198300 systemd-logind[1887]: Session 33 logged out. Waiting for processes to exit. Sep 11 00:29:00.199230 systemd-logind[1887]: Removed session 33. Sep 11 00:29:00.308818 systemd[1]: Started sshd@31-10.200.8.35:22-10.200.16.10:33148.service - OpenSSH per-connection server daemon (10.200.16.10:33148). Sep 11 00:29:00.971689 sshd[12936]: Accepted publickey for core from 10.200.16.10 port 33148 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:29:00.972937 sshd-session[12936]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:29:00.977132 systemd-logind[1887]: New session 34 of user core. Sep 11 00:29:00.986794 systemd[1]: Started session-34.scope - Session 34 of User core. Sep 11 00:29:01.431997 sshd[12938]: Connection closed by 10.200.16.10 port 33148 Sep 11 00:29:01.432360 sshd-session[12936]: pam_unix(sshd:session): session closed for user core Sep 11 00:29:01.435721 systemd[1]: sshd@31-10.200.8.35:22-10.200.16.10:33148.service: Deactivated successfully. Sep 11 00:29:01.437229 systemd[1]: session-34.scope: Deactivated successfully. Sep 11 00:29:01.437963 systemd-logind[1887]: Session 34 logged out. Waiting for processes to exit. Sep 11 00:29:01.438904 systemd-logind[1887]: Removed session 34. Sep 11 00:29:01.553794 systemd[1]: Started sshd@32-10.200.8.35:22-10.200.16.10:33158.service - OpenSSH per-connection server daemon (10.200.16.10:33158). Sep 11 00:29:02.193891 sshd[12944]: Accepted publickey for core from 10.200.16.10 port 33158 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:29:02.195263 sshd-session[12944]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:29:02.199445 systemd-logind[1887]: New session 35 of user core. Sep 11 00:29:02.209794 systemd[1]: Started session-35.scope - Session 35 of User core. Sep 11 00:29:02.649654 sshd[12946]: Connection closed by 10.200.16.10 port 33158 Sep 11 00:29:02.649944 sshd-session[12944]: pam_unix(sshd:session): session closed for user core Sep 11 00:29:02.653170 systemd[1]: sshd@32-10.200.8.35:22-10.200.16.10:33158.service: Deactivated successfully. Sep 11 00:29:02.654704 systemd[1]: session-35.scope: Deactivated successfully. Sep 11 00:29:02.655367 systemd-logind[1887]: Session 35 logged out. Waiting for processes to exit. Sep 11 00:29:02.656284 systemd-logind[1887]: Removed session 35. Sep 11 00:29:02.765754 systemd[1]: Started sshd@33-10.200.8.35:22-10.200.16.10:33164.service - OpenSSH per-connection server daemon (10.200.16.10:33164). Sep 11 00:29:02.918905 systemd[1]: Stopping user@0.service - User Manager for UID 0... Sep 11 00:29:02.919706 systemd[2604]: Activating special unit exit.target... Sep 11 00:29:02.919779 systemd[2604]: Stopped target default.target - Main User Target. Sep 11 00:29:02.919817 systemd[2604]: Stopped target basic.target - Basic System. Sep 11 00:29:02.919845 systemd[2604]: Stopped target paths.target - Paths. Sep 11 00:29:02.919870 systemd[2604]: Stopped target sockets.target - Sockets. Sep 11 00:29:02.919895 systemd[2604]: Stopped target timers.target - Timers. Sep 11 00:29:02.920224 systemd[2604]: Closed dbus.socket - D-Bus User Message Bus Socket. Sep 11 00:29:02.920424 systemd[2604]: Removed slice app.slice - User Application Slice. Sep 11 00:29:02.920457 systemd[2604]: Reached target shutdown.target - Shutdown. Sep 11 00:29:02.920590 systemd[2604]: Finished systemd-exit.service - Exit the Session. Sep 11 00:29:02.920621 systemd[2604]: Reached target exit.target - Exit the Session. Sep 11 00:29:02.939721 (sd-pam)[2605]: pam_unix(systemd-user:session): session closed for user root Sep 11 00:29:02.940934 systemd[1]: user@0.service: Deactivated successfully. Sep 11 00:29:02.941277 systemd[1]: Stopped user@0.service - User Manager for UID 0. Sep 11 00:29:02.943429 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Sep 11 00:29:02.973815 systemd[1]: run-user-0.mount: Deactivated successfully. Sep 11 00:29:02.974661 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Sep 11 00:29:02.974885 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Sep 11 00:29:02.976400 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Sep 11 00:29:02.976490 systemd[1]: user-0.slice: Consumed 5min 8.626s CPU time, 902.6M memory peak. Sep 11 00:29:02.976907 systemd-logind[1887]: Removed session c3. Sep 11 00:29:03.406641 sshd[12952]: Accepted publickey for core from 10.200.16.10 port 33164 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:29:03.407912 sshd-session[12952]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:29:03.412346 systemd-logind[1887]: New session 36 of user core. Sep 11 00:29:03.423813 systemd[1]: Started session-36.scope - Session 36 of User core. Sep 11 00:29:05.350897 sshd[12958]: Connection closed by 10.200.16.10 port 33164 Sep 11 00:29:06.072549 sshd-session[12952]: pam_unix(sshd:session): session closed for user core Sep 11 00:29:06.077385 systemd[1]: sshd@33-10.200.8.35:22-10.200.16.10:33164.service: Deactivated successfully. Sep 11 00:29:06.078938 systemd[1]: session-36.scope: Deactivated successfully. Sep 11 00:29:06.079134 systemd[1]: session-36.scope: Consumed 1.431s CPU time, 14M memory peak. Sep 11 00:29:06.079955 systemd-logind[1887]: Session 36 logged out. Waiting for processes to exit. Sep 11 00:29:06.080967 systemd-logind[1887]: Removed session 36.