Aug 19 08:13:40.206183 kernel: Linux version 6.12.41-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.0 p8) 14.3.0, GNU ld (Gentoo 2.44 p4) 2.44.0) #1 SMP PREEMPT_DYNAMIC Mon Aug 18 22:19:37 -00 2025 Aug 19 08:13:40.206211 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=cc23dd01793203541561c15ffc568736bb5dae0d652141296dd11bf777bdf42f Aug 19 08:13:40.206221 kernel: BIOS-provided physical RAM map: Aug 19 08:13:40.206227 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Aug 19 08:13:40.206234 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Aug 19 08:13:40.206240 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Aug 19 08:13:40.206248 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Aug 19 08:13:40.206257 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Aug 19 08:13:40.206264 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Aug 19 08:13:40.206270 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Aug 19 08:13:40.206277 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Aug 19 08:13:40.206283 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Aug 19 08:13:40.206290 kernel: printk: legacy bootconsole [earlyser0] enabled Aug 19 08:13:40.206297 kernel: NX (Execute Disable) protection: active Aug 19 08:13:40.206308 kernel: APIC: Static calls initialized Aug 19 08:13:40.206315 kernel: efi: EFI v2.7 by Microsoft Aug 19 08:13:40.206323 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Aug 19 08:13:40.206330 kernel: random: crng init done Aug 19 08:13:40.206338 kernel: secureboot: Secure boot disabled Aug 19 08:13:40.206345 kernel: SMBIOS 3.1.0 present. Aug 19 08:13:40.206352 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Aug 19 08:13:40.206360 kernel: DMI: Memory slots populated: 3/3 Aug 19 08:13:40.206367 kernel: Hypervisor detected: Microsoft Hyper-V Aug 19 08:13:40.206374 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Aug 19 08:13:40.206384 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Aug 19 08:13:40.206391 kernel: Hyper-V: Using hypercall for remote TLB flush Aug 19 08:13:40.206398 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Aug 19 08:13:40.206406 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Aug 19 08:13:40.206414 kernel: tsc: Marking TSC unstable due to running on Hyper-V Aug 19 08:13:40.206422 kernel: tsc: Detected 2593.993 MHz processor Aug 19 08:13:40.206429 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Aug 19 08:13:40.206437 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Aug 19 08:13:40.206444 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Aug 19 08:13:40.206454 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Aug 19 08:13:40.206462 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Aug 19 08:13:40.206469 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Aug 19 08:13:40.206477 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Aug 19 08:13:40.206484 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Aug 19 08:13:40.206491 kernel: Using GB pages for direct mapping Aug 19 08:13:40.206499 kernel: ACPI: Early table checksum verification disabled Aug 19 08:13:40.206510 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Aug 19 08:13:40.206520 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 19 08:13:40.206528 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 19 08:13:40.206536 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Aug 19 08:13:40.206544 kernel: ACPI: FACS 0x000000003FFFE000 000040 Aug 19 08:13:40.206552 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 19 08:13:40.206560 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 19 08:13:40.206570 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 19 08:13:40.206578 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 19 08:13:40.206586 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 19 08:13:40.206594 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 19 08:13:40.206602 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 19 08:13:40.206610 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Aug 19 08:13:40.206618 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Aug 19 08:13:40.206626 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Aug 19 08:13:40.206634 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Aug 19 08:13:40.206644 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Aug 19 08:13:40.206652 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Aug 19 08:13:40.206660 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Aug 19 08:13:40.206668 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Aug 19 08:13:40.206682 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Aug 19 08:13:40.206696 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Aug 19 08:13:40.206704 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Aug 19 08:13:40.206712 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Aug 19 08:13:40.206724 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Aug 19 08:13:40.206732 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Aug 19 08:13:40.206739 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Aug 19 08:13:40.206747 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Aug 19 08:13:40.206755 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Aug 19 08:13:40.206775 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Aug 19 08:13:40.206783 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] Aug 19 08:13:40.206792 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] Aug 19 08:13:40.206800 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] Aug 19 08:13:40.206811 kernel: Zone ranges: Aug 19 08:13:40.206819 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Aug 19 08:13:40.206827 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Aug 19 08:13:40.206835 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Aug 19 08:13:40.206843 kernel: Device empty Aug 19 08:13:40.206851 kernel: Movable zone start for each node Aug 19 08:13:40.206858 kernel: Early memory node ranges Aug 19 08:13:40.206866 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Aug 19 08:13:40.206874 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Aug 19 08:13:40.206885 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Aug 19 08:13:40.206892 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Aug 19 08:13:40.206900 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Aug 19 08:13:40.206911 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Aug 19 08:13:40.206919 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Aug 19 08:13:40.206927 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Aug 19 08:13:40.206935 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Aug 19 08:13:40.206942 kernel: ACPI: PM-Timer IO Port: 0x408 Aug 19 08:13:40.206950 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Aug 19 08:13:40.206961 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Aug 19 08:13:40.206969 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Aug 19 08:13:40.206977 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Aug 19 08:13:40.206987 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Aug 19 08:13:40.206996 kernel: CPU topo: Max. logical packages: 1 Aug 19 08:13:40.207004 kernel: CPU topo: Max. logical dies: 1 Aug 19 08:13:40.207012 kernel: CPU topo: Max. dies per package: 1 Aug 19 08:13:40.207019 kernel: CPU topo: Max. threads per core: 1 Aug 19 08:13:40.207027 kernel: CPU topo: Num. cores per package: 6 Aug 19 08:13:40.207038 kernel: CPU topo: Num. threads per package: 6 Aug 19 08:13:40.207046 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs Aug 19 08:13:40.207054 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Aug 19 08:13:40.207062 kernel: Booting paravirtualized kernel on Hyper-V Aug 19 08:13:40.207070 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Aug 19 08:13:40.207078 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Aug 19 08:13:40.207086 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 Aug 19 08:13:40.207094 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 Aug 19 08:13:40.207102 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Aug 19 08:13:40.207112 kernel: Hyper-V: PV spinlocks enabled Aug 19 08:13:40.207120 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Aug 19 08:13:40.207129 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=cc23dd01793203541561c15ffc568736bb5dae0d652141296dd11bf777bdf42f Aug 19 08:13:40.207138 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Aug 19 08:13:40.207146 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Aug 19 08:13:40.207154 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Aug 19 08:13:40.207162 kernel: Fallback order for Node 0: 0 Aug 19 08:13:40.207170 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 Aug 19 08:13:40.207180 kernel: Policy zone: Normal Aug 19 08:13:40.207196 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Aug 19 08:13:40.207204 kernel: software IO TLB: area num 8. Aug 19 08:13:40.207215 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Aug 19 08:13:40.207223 kernel: Kernel/User page tables isolation: enabled Aug 19 08:13:40.207232 kernel: ftrace: allocating 40101 entries in 157 pages Aug 19 08:13:40.207240 kernel: ftrace: allocated 157 pages with 5 groups Aug 19 08:13:40.207248 kernel: Dynamic Preempt: voluntary Aug 19 08:13:40.207257 kernel: rcu: Preemptible hierarchical RCU implementation. Aug 19 08:13:40.207266 kernel: rcu: RCU event tracing is enabled. Aug 19 08:13:40.207275 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Aug 19 08:13:40.207283 kernel: Trampoline variant of Tasks RCU enabled. Aug 19 08:13:40.207294 kernel: Rude variant of Tasks RCU enabled. Aug 19 08:13:40.207303 kernel: Tracing variant of Tasks RCU enabled. Aug 19 08:13:40.207311 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Aug 19 08:13:40.207320 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Aug 19 08:13:40.207328 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Aug 19 08:13:40.207339 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Aug 19 08:13:40.207348 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Aug 19 08:13:40.207356 kernel: Using NULL legacy PIC Aug 19 08:13:40.207364 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Aug 19 08:13:40.207376 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Aug 19 08:13:40.207387 kernel: Console: colour dummy device 80x25 Aug 19 08:13:40.207400 kernel: printk: legacy console [tty1] enabled Aug 19 08:13:40.207412 kernel: printk: legacy console [ttyS0] enabled Aug 19 08:13:40.207425 kernel: printk: legacy bootconsole [earlyser0] disabled Aug 19 08:13:40.207439 kernel: ACPI: Core revision 20240827 Aug 19 08:13:40.207447 kernel: Failed to register legacy timer interrupt Aug 19 08:13:40.207456 kernel: APIC: Switch to symmetric I/O mode setup Aug 19 08:13:40.207464 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Aug 19 08:13:40.207472 kernel: Hyper-V: enabling crash_kexec_post_notifiers Aug 19 08:13:40.207481 kernel: Hyper-V: Using IPI hypercalls Aug 19 08:13:40.207489 kernel: APIC: send_IPI() replaced with hv_send_ipi() Aug 19 08:13:40.207497 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Aug 19 08:13:40.207506 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Aug 19 08:13:40.207517 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Aug 19 08:13:40.207525 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Aug 19 08:13:40.207533 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Aug 19 08:13:40.207542 kernel: Hyper-V: Using enlightened APIC (xapic mode) Aug 19 08:13:40.207550 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Aug 19 08:13:40.207559 kernel: APIC: read() replaced with hv_apic_read() Aug 19 08:13:40.207567 kernel: APIC: write() replaced with hv_apic_write() Aug 19 08:13:40.207576 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Aug 19 08:13:40.207584 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Aug 19 08:13:40.207595 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593993) Aug 19 08:13:40.207603 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Aug 19 08:13:40.207612 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Aug 19 08:13:40.207620 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Aug 19 08:13:40.207635 kernel: Spectre V2 : Mitigation: Retpolines Aug 19 08:13:40.207648 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Aug 19 08:13:40.207657 kernel: Speculative Store Bypass: Vulnerable Aug 19 08:13:40.207666 kernel: MDS: Mitigation: Clear CPU buffers Aug 19 08:13:40.207674 kernel: TAA: Mitigation: Clear CPU buffers Aug 19 08:13:40.207682 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Aug 19 08:13:40.207696 kernel: ITS: Mitigation: Aligned branch/return thunks Aug 19 08:13:40.207705 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Aug 19 08:13:40.207713 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Aug 19 08:13:40.207721 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Aug 19 08:13:40.207729 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Aug 19 08:13:40.207738 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Aug 19 08:13:40.207746 kernel: Freeing SMP alternatives memory: 32K Aug 19 08:13:40.207754 kernel: pid_max: default: 32768 minimum: 301 Aug 19 08:13:40.207775 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Aug 19 08:13:40.207784 kernel: landlock: Up and running. Aug 19 08:13:40.207792 kernel: SELinux: Initializing. Aug 19 08:13:40.207804 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Aug 19 08:13:40.207812 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Aug 19 08:13:40.207821 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Aug 19 08:13:40.207829 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Aug 19 08:13:40.207838 kernel: signal: max sigframe size: 1776 Aug 19 08:13:40.207846 kernel: rcu: Hierarchical SRCU implementation. Aug 19 08:13:40.207855 kernel: rcu: Max phase no-delay instances is 400. Aug 19 08:13:40.207864 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Aug 19 08:13:40.207872 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Aug 19 08:13:40.207880 kernel: smp: Bringing up secondary CPUs ... Aug 19 08:13:40.207891 kernel: smpboot: x86: Booting SMP configuration: Aug 19 08:13:40.207900 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Aug 19 08:13:40.207908 kernel: smp: Brought up 1 node, 6 CPUs Aug 19 08:13:40.207916 kernel: smpboot: Total of 6 processors activated (31127.91 BogoMIPS) Aug 19 08:13:40.207925 kernel: Memory: 115339088K/117439364K available (14336K kernel code, 2430K rwdata, 9960K rodata, 54040K init, 2928K bss, 2090000K reserved, 0K cma-reserved) Aug 19 08:13:40.207934 kernel: devtmpfs: initialized Aug 19 08:13:40.207942 kernel: x86/mm: Memory block size: 1024MB Aug 19 08:13:40.207951 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Aug 19 08:13:40.207959 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Aug 19 08:13:40.207970 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Aug 19 08:13:40.207979 kernel: pinctrl core: initialized pinctrl subsystem Aug 19 08:13:40.207987 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Aug 19 08:13:40.207995 kernel: audit: initializing netlink subsys (disabled) Aug 19 08:13:40.208004 kernel: audit: type=2000 audit(1755591216.028:1): state=initialized audit_enabled=0 res=1 Aug 19 08:13:40.208012 kernel: thermal_sys: Registered thermal governor 'step_wise' Aug 19 08:13:40.208021 kernel: thermal_sys: Registered thermal governor 'user_space' Aug 19 08:13:40.208029 kernel: cpuidle: using governor menu Aug 19 08:13:40.208037 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Aug 19 08:13:40.208048 kernel: dca service started, version 1.12.1 Aug 19 08:13:40.208056 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Aug 19 08:13:40.208065 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Aug 19 08:13:40.208073 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Aug 19 08:13:40.208082 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Aug 19 08:13:40.208090 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Aug 19 08:13:40.208098 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Aug 19 08:13:40.208107 kernel: ACPI: Added _OSI(Module Device) Aug 19 08:13:40.208115 kernel: ACPI: Added _OSI(Processor Device) Aug 19 08:13:40.208126 kernel: ACPI: Added _OSI(Processor Aggregator Device) Aug 19 08:13:40.208134 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Aug 19 08:13:40.208143 kernel: ACPI: Interpreter enabled Aug 19 08:13:40.208151 kernel: ACPI: PM: (supports S0 S5) Aug 19 08:13:40.208159 kernel: ACPI: Using IOAPIC for interrupt routing Aug 19 08:13:40.208168 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Aug 19 08:13:40.208176 kernel: PCI: Ignoring E820 reservations for host bridge windows Aug 19 08:13:40.208184 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Aug 19 08:13:40.208193 kernel: iommu: Default domain type: Translated Aug 19 08:13:40.208204 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Aug 19 08:13:40.208212 kernel: efivars: Registered efivars operations Aug 19 08:13:40.208220 kernel: PCI: Using ACPI for IRQ routing Aug 19 08:13:40.208229 kernel: PCI: System does not support PCI Aug 19 08:13:40.208237 kernel: vgaarb: loaded Aug 19 08:13:40.208245 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Aug 19 08:13:40.208254 kernel: VFS: Disk quotas dquot_6.6.0 Aug 19 08:13:40.208262 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Aug 19 08:13:40.208270 kernel: pnp: PnP ACPI init Aug 19 08:13:40.208281 kernel: pnp: PnP ACPI: found 3 devices Aug 19 08:13:40.208290 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Aug 19 08:13:40.208298 kernel: NET: Registered PF_INET protocol family Aug 19 08:13:40.208307 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Aug 19 08:13:40.208315 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Aug 19 08:13:40.208324 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Aug 19 08:13:40.208332 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Aug 19 08:13:40.208341 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Aug 19 08:13:40.208352 kernel: TCP: Hash tables configured (established 524288 bind 65536) Aug 19 08:13:40.208360 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Aug 19 08:13:40.208368 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Aug 19 08:13:40.208377 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Aug 19 08:13:40.208385 kernel: NET: Registered PF_XDP protocol family Aug 19 08:13:40.208394 kernel: PCI: CLS 0 bytes, default 64 Aug 19 08:13:40.208402 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Aug 19 08:13:40.208410 kernel: software IO TLB: mapped [mem 0x000000003ae61000-0x000000003ee61000] (64MB) Aug 19 08:13:40.208419 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Aug 19 08:13:40.208430 kernel: Initialise system trusted keyrings Aug 19 08:13:40.208438 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Aug 19 08:13:40.208446 kernel: Key type asymmetric registered Aug 19 08:13:40.208454 kernel: Asymmetric key parser 'x509' registered Aug 19 08:13:40.208463 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Aug 19 08:13:40.208471 kernel: io scheduler mq-deadline registered Aug 19 08:13:40.208479 kernel: io scheduler kyber registered Aug 19 08:13:40.208488 kernel: io scheduler bfq registered Aug 19 08:13:40.208496 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Aug 19 08:13:40.208504 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Aug 19 08:13:40.208515 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Aug 19 08:13:40.208524 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Aug 19 08:13:40.208532 kernel: i8042: PNP: No PS/2 controller found. Aug 19 08:13:40.208685 kernel: rtc_cmos 00:02: registered as rtc0 Aug 19 08:13:40.208821 kernel: rtc_cmos 00:02: setting system clock to 2025-08-19T08:13:39 UTC (1755591219) Aug 19 08:13:40.208922 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Aug 19 08:13:40.208935 kernel: intel_pstate: CPU model not supported Aug 19 08:13:40.208949 kernel: efifb: probing for efifb Aug 19 08:13:40.208958 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Aug 19 08:13:40.208966 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Aug 19 08:13:40.208974 kernel: efifb: scrolling: redraw Aug 19 08:13:40.208983 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Aug 19 08:13:40.208991 kernel: Console: switching to colour frame buffer device 128x48 Aug 19 08:13:40.208999 kernel: fb0: EFI VGA frame buffer device Aug 19 08:13:40.209008 kernel: pstore: Using crash dump compression: deflate Aug 19 08:13:40.209016 kernel: pstore: Registered efi_pstore as persistent store backend Aug 19 08:13:40.209027 kernel: NET: Registered PF_INET6 protocol family Aug 19 08:13:40.209035 kernel: Segment Routing with IPv6 Aug 19 08:13:40.209044 kernel: In-situ OAM (IOAM) with IPv6 Aug 19 08:13:40.209052 kernel: NET: Registered PF_PACKET protocol family Aug 19 08:13:40.209060 kernel: Key type dns_resolver registered Aug 19 08:13:40.209069 kernel: IPI shorthand broadcast: enabled Aug 19 08:13:40.209077 kernel: sched_clock: Marking stable (3781004800, 112411700)->(4193832200, -300415700) Aug 19 08:13:40.209085 kernel: registered taskstats version 1 Aug 19 08:13:40.209094 kernel: Loading compiled-in X.509 certificates Aug 19 08:13:40.209105 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.41-flatcar: 93a065b103c00d4b81cc5822e4e7f9674e63afaf' Aug 19 08:13:40.209113 kernel: Demotion targets for Node 0: null Aug 19 08:13:40.209122 kernel: Key type .fscrypt registered Aug 19 08:13:40.209130 kernel: Key type fscrypt-provisioning registered Aug 19 08:13:40.209138 kernel: ima: No TPM chip found, activating TPM-bypass! Aug 19 08:13:40.209147 kernel: ima: Allocated hash algorithm: sha1 Aug 19 08:13:40.209155 kernel: ima: No architecture policies found Aug 19 08:13:40.209163 kernel: clk: Disabling unused clocks Aug 19 08:13:40.209172 kernel: Warning: unable to open an initial console. Aug 19 08:13:40.209183 kernel: Freeing unused kernel image (initmem) memory: 54040K Aug 19 08:13:40.209191 kernel: Write protecting the kernel read-only data: 24576k Aug 19 08:13:40.209199 kernel: Freeing unused kernel image (rodata/data gap) memory: 280K Aug 19 08:13:40.209208 kernel: Run /init as init process Aug 19 08:13:40.209216 kernel: with arguments: Aug 19 08:13:40.209225 kernel: /init Aug 19 08:13:40.209233 kernel: with environment: Aug 19 08:13:40.209241 kernel: HOME=/ Aug 19 08:13:40.209249 kernel: TERM=linux Aug 19 08:13:40.209260 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Aug 19 08:13:40.209269 systemd[1]: Successfully made /usr/ read-only. Aug 19 08:13:40.209281 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) Aug 19 08:13:40.209290 systemd[1]: Detected virtualization microsoft. Aug 19 08:13:40.209299 systemd[1]: Detected architecture x86-64. Aug 19 08:13:40.209308 systemd[1]: Running in initrd. Aug 19 08:13:40.209316 systemd[1]: No hostname configured, using default hostname. Aug 19 08:13:40.209328 systemd[1]: Hostname set to . Aug 19 08:13:40.209337 systemd[1]: Initializing machine ID from random generator. Aug 19 08:13:40.209346 systemd[1]: Queued start job for default target initrd.target. Aug 19 08:13:40.209355 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 19 08:13:40.209364 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 19 08:13:40.209373 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Aug 19 08:13:40.209383 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Aug 19 08:13:40.209392 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Aug 19 08:13:40.209404 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Aug 19 08:13:40.209414 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Aug 19 08:13:40.209423 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Aug 19 08:13:40.209432 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 19 08:13:40.209441 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Aug 19 08:13:40.209450 systemd[1]: Reached target paths.target - Path Units. Aug 19 08:13:40.209459 systemd[1]: Reached target slices.target - Slice Units. Aug 19 08:13:40.209471 systemd[1]: Reached target swap.target - Swaps. Aug 19 08:13:40.209480 systemd[1]: Reached target timers.target - Timer Units. Aug 19 08:13:40.209489 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Aug 19 08:13:40.209498 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Aug 19 08:13:40.209507 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Aug 19 08:13:40.209516 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Aug 19 08:13:40.209525 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Aug 19 08:13:40.209534 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Aug 19 08:13:40.209543 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Aug 19 08:13:40.209554 systemd[1]: Reached target sockets.target - Socket Units. Aug 19 08:13:40.209563 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Aug 19 08:13:40.209572 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Aug 19 08:13:40.209581 systemd[1]: Finished network-cleanup.service - Network Cleanup. Aug 19 08:13:40.209591 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). Aug 19 08:13:40.209600 systemd[1]: Starting systemd-fsck-usr.service... Aug 19 08:13:40.209609 systemd[1]: Starting systemd-journald.service - Journal Service... Aug 19 08:13:40.209618 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Aug 19 08:13:40.209629 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 19 08:13:40.209657 systemd-journald[232]: Collecting audit messages is disabled. Aug 19 08:13:40.209679 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Aug 19 08:13:40.209693 systemd-journald[232]: Journal started Aug 19 08:13:40.209713 systemd-journald[232]: Runtime Journal (/run/log/journal/307aa3071d3342819b099c41cbcf916e) is 8M, max 2.2G, 2.1G free. Aug 19 08:13:40.207671 systemd-modules-load[234]: Inserted module 'overlay' Aug 19 08:13:40.218780 systemd[1]: Started systemd-journald.service - Journal Service. Aug 19 08:13:40.218977 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Aug 19 08:13:40.219524 systemd[1]: Finished systemd-fsck-usr.service. Aug 19 08:13:40.221834 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Aug 19 08:13:40.222931 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Aug 19 08:13:40.265255 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 19 08:13:40.268105 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Aug 19 08:13:40.269016 systemd-tmpfiles[244]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Aug 19 08:13:40.270207 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Aug 19 08:13:40.271356 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Aug 19 08:13:40.272963 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Aug 19 08:13:40.291182 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 19 08:13:40.303618 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Aug 19 08:13:40.313619 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 19 08:13:40.358789 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Aug 19 08:13:40.360157 dracut-cmdline[264]: 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=cc23dd01793203541561c15ffc568736bb5dae0d652141296dd11bf777bdf42f Aug 19 08:13:40.388139 systemd-modules-load[234]: Inserted module 'br_netfilter' Aug 19 08:13:40.389746 kernel: Bridge firewalling registered Aug 19 08:13:40.389215 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Aug 19 08:13:40.397506 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Aug 19 08:13:40.415491 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Aug 19 08:13:40.425910 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Aug 19 08:13:40.473280 systemd-resolved[320]: Positive Trust Anchors: Aug 19 08:13:40.473296 systemd-resolved[320]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Aug 19 08:13:40.473326 systemd-resolved[320]: 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 Aug 19 08:13:40.476005 systemd-resolved[320]: Defaulting to hostname 'linux'. Aug 19 08:13:40.477117 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Aug 19 08:13:40.487022 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Aug 19 08:13:40.534781 kernel: SCSI subsystem initialized Aug 19 08:13:40.544779 kernel: Loading iSCSI transport class v2.0-870. Aug 19 08:13:40.557785 kernel: iscsi: registered transport (tcp) Aug 19 08:13:40.582135 kernel: iscsi: registered transport (qla4xxx) Aug 19 08:13:40.582163 kernel: QLogic iSCSI HBA Driver Aug 19 08:13:40.600787 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Aug 19 08:13:40.628316 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Aug 19 08:13:40.630912 systemd[1]: Reached target network-pre.target - Preparation for Network. Aug 19 08:13:40.685602 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Aug 19 08:13:40.688489 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Aug 19 08:13:40.758788 kernel: raid6: avx2x4 gen() 29752 MB/s Aug 19 08:13:40.777784 kernel: raid6: avx2x2 gen() 30561 MB/s Aug 19 08:13:40.798997 kernel: raid6: avx2x1 gen() 26203 MB/s Aug 19 08:13:40.799023 kernel: raid6: using algorithm avx2x2 gen() 30561 MB/s Aug 19 08:13:40.822055 kernel: raid6: .... xor() 18482 MB/s, rmw enabled Aug 19 08:13:40.822082 kernel: raid6: using avx2x2 recovery algorithm Aug 19 08:13:40.844787 kernel: xor: automatically using best checksumming function avx Aug 19 08:13:41.013791 kernel: Btrfs loaded, zoned=no, fsverity=no Aug 19 08:13:41.020834 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Aug 19 08:13:41.023069 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 19 08:13:41.060758 systemd-udevd[484]: Using default interface naming scheme 'v255'. Aug 19 08:13:41.067143 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 19 08:13:41.070872 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Aug 19 08:13:41.104197 dracut-pre-trigger[493]: rd.md=0: removing MD RAID activation Aug 19 08:13:41.132494 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Aug 19 08:13:41.135191 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Aug 19 08:13:41.223212 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Aug 19 08:13:41.238175 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Aug 19 08:13:41.253793 kernel: cryptd: max_cpu_qlen set to 1000 Aug 19 08:13:41.263784 kernel: AES CTR mode by8 optimization enabled Aug 19 08:13:41.274077 kernel: hv_vmbus: Vmbus version:4.0 Aug 19 08:13:41.275809 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Aug 19 08:13:41.284012 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Aug 19 08:13:41.289928 kernel: hv_vmbus: registering driver hyperv_keyboard Aug 19 08:13:41.302605 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Aug 19 08:13:41.304203 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 Aug 19 08:13:41.313125 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 19 08:13:41.363550 kernel: pps_core: LinuxPPS API ver. 1 registered Aug 19 08:13:41.363575 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Aug 19 08:13:41.363587 kernel: hv_vmbus: registering driver hv_pci Aug 19 08:13:41.363598 kernel: hid: raw HID events driver (C) Jiri Kosina Aug 19 08:13:41.363609 kernel: hv_vmbus: registering driver hv_netvsc Aug 19 08:13:41.363630 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Aug 19 08:13:41.363917 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Aug 19 08:13:41.364059 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Aug 19 08:13:41.364203 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Aug 19 08:13:41.364323 kernel: PTP clock support registered Aug 19 08:13:41.364335 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Aug 19 08:13:41.364456 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint Aug 19 08:13:41.364483 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] Aug 19 08:13:41.364957 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Aug 19 08:13:41.376351 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] Aug 19 08:13:41.376382 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] Aug 19 08:13:41.384232 kernel: pci 0001:00:00.0: Enabling HDA controller Aug 19 08:13:41.384278 kernel: hv_vmbus: registering driver hv_storvsc Aug 19 08:13:41.389474 kernel: hv_vmbus: registering driver hid_hyperv Aug 19 08:13:41.390698 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Aug 19 08:13:42.284192 kernel: hv_utils: Registering HyperV Utility Driver Aug 19 08:13:42.296727 kernel: hv_vmbus: registering driver hv_utils Aug 19 08:13:42.296749 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Aug 19 08:13:42.296766 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Aug 19 08:13:42.296986 kernel: scsi host1: storvsc_host_t Aug 19 08:13:42.297220 kernel: hv_utils: Shutdown IC version 3.2 Aug 19 08:13:42.297244 kernel: scsi host0: storvsc_host_t Aug 19 08:13:42.297404 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Aug 19 08:13:42.297580 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Aug 19 08:13:42.297826 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Aug 19 08:13:42.298253 kernel: hv_utils: Heartbeat IC version 3.0 Aug 19 08:13:42.298274 kernel: hv_utils: TimeSync IC version 4.0 Aug 19 08:13:42.298286 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Aug 19 08:13:42.298453 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned Aug 19 08:13:42.298606 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned Aug 19 08:13:42.298767 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned Aug 19 08:13:42.298903 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Aug 19 08:13:42.299039 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Aug 19 08:13:41.390815 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Aug 19 08:13:42.351256 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks Aug 19 08:13:42.351952 kernel: sd 0:0:0:1: [sdb] Write Protect is off Aug 19 08:13:42.352514 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Aug 19 08:13:42.352773 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Aug 19 08:13:42.352911 kernel: sd 0:0:0:0: [sda] Write Protect is off Aug 19 08:13:42.353040 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Aug 19 08:13:42.353179 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Aug 19 08:13:42.353312 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#385 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Aug 19 08:13:42.353444 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Aug 19 08:13:42.353575 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#180 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Aug 19 08:13:42.353711 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#578 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Aug 19 08:13:41.431808 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 19 08:13:42.275535 systemd-resolved[320]: Clock change detected. Flushing caches. Aug 19 08:13:42.368638 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#521 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Aug 19 08:13:42.368803 kernel: sdb: sdb1 Aug 19 08:13:42.372131 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk Aug 19 08:13:42.372390 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 19 08:13:42.385650 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Aug 19 08:13:42.385677 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Aug 19 08:13:42.388858 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Aug 19 08:13:42.400689 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Aug 19 08:13:42.402646 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Aug 19 08:13:42.966637 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Aug 19 08:13:43.001796 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Aug 19 08:13:43.045914 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Aug 19 08:13:43.177806 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Aug 19 08:13:43.182782 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Aug 19 08:13:43.188369 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Aug 19 08:13:43.229651 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#163 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Aug 19 08:13:43.239638 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Aug 19 08:13:43.248376 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#593 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Aug 19 08:13:43.271644 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Aug 19 08:13:44.278895 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#620 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Aug 19 08:13:44.289650 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Aug 19 08:13:44.289898 disk-uuid[725]: The operation has completed successfully. Aug 19 08:13:46.222325 systemd[1]: disk-uuid.service: Deactivated successfully. Aug 19 08:13:46.222438 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Aug 19 08:13:46.225152 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Aug 19 08:13:46.226431 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Aug 19 08:13:46.228223 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Aug 19 08:13:46.228883 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 19 08:13:46.229556 systemd[1]: Reached target remote-fs.target - Remote File Systems. Aug 19 08:13:46.279407 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Aug 19 08:13:46.284956 sh[890]: Success Aug 19 08:13:46.303021 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Aug 19 08:13:46.323856 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Aug 19 08:13:46.323902 kernel: device-mapper: uevent: version 1.0.3 Aug 19 08:13:46.330474 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Aug 19 08:13:46.342650 kernel: device-mapper: verity: sha256 using shash "sha256-avx2" Aug 19 08:13:46.553657 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Aug 19 08:13:46.563449 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Aug 19 08:13:46.590513 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Aug 19 08:13:46.603654 kernel: BTRFS: device fsid 99050df3-5e04-4f37-acde-dec46aab7896 devid 1 transid 39 /dev/mapper/usr (254:0) scanned by mount (915) Aug 19 08:13:46.613114 kernel: BTRFS info (device dm-0): first mount of filesystem 99050df3-5e04-4f37-acde-dec46aab7896 Aug 19 08:13:46.613142 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Aug 19 08:13:46.616631 kernel: BTRFS info (device dm-0): using free-space-tree Aug 19 08:13:46.953109 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Aug 19 08:13:46.955090 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Aug 19 08:13:46.961399 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Aug 19 08:13:46.962298 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Aug 19 08:13:46.993998 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Aug 19 08:13:47.022648 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (946) Aug 19 08:13:47.031559 kernel: BTRFS info (device sda6): first mount of filesystem 43dd0637-5e0b-4b8d-a544-a82ca0652f6f Aug 19 08:13:47.031594 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Aug 19 08:13:47.034844 kernel: BTRFS info (device sda6): using free-space-tree Aug 19 08:13:47.057649 kernel: BTRFS info (device sda6): last unmount of filesystem 43dd0637-5e0b-4b8d-a544-a82ca0652f6f Aug 19 08:13:47.059690 systemd[1]: Finished ignition-setup.service - Ignition (setup). Aug 19 08:13:47.064698 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Aug 19 08:13:47.113171 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Aug 19 08:13:47.116270 systemd[1]: Starting systemd-networkd.service - Network Configuration... Aug 19 08:13:47.164413 systemd-networkd[1084]: lo: Link UP Aug 19 08:13:47.164425 systemd-networkd[1084]: lo: Gained carrier Aug 19 08:13:47.165590 systemd-networkd[1084]: Enumeration completed Aug 19 08:13:47.165697 systemd[1]: Started systemd-networkd.service - Network Configuration. Aug 19 08:13:47.165962 systemd-networkd[1084]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 19 08:13:47.165967 systemd-networkd[1084]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Aug 19 08:13:47.166795 systemd-networkd[1084]: eth0: Link UP Aug 19 08:13:47.166956 systemd-networkd[1084]: eth0: Gained carrier Aug 19 08:13:47.166966 systemd-networkd[1084]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 19 08:13:47.167606 systemd[1]: Reached target network.target - Network. Aug 19 08:13:47.238671 systemd-networkd[1084]: eth0: DHCPv4 address 10.200.8.16/24, gateway 10.200.8.1 acquired from 168.63.129.16 Aug 19 08:13:48.543787 systemd-networkd[1084]: eth0: Gained IPv6LL Aug 19 08:13:49.571735 ignition[1032]: Ignition 2.21.0 Aug 19 08:13:49.571753 ignition[1032]: Stage: fetch-offline Aug 19 08:13:49.571848 ignition[1032]: no configs at "/usr/lib/ignition/base.d" Aug 19 08:13:49.571859 ignition[1032]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Aug 19 08:13:49.571950 ignition[1032]: parsed url from cmdline: "" Aug 19 08:13:49.571954 ignition[1032]: no config URL provided Aug 19 08:13:49.571964 ignition[1032]: reading system config file "/usr/lib/ignition/user.ign" Aug 19 08:13:49.571973 ignition[1032]: no config at "/usr/lib/ignition/user.ign" Aug 19 08:13:49.571979 ignition[1032]: failed to fetch config: resource requires networking Aug 19 08:13:49.572142 ignition[1032]: Ignition finished successfully Aug 19 08:13:49.604068 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Aug 19 08:13:49.613928 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Aug 19 08:13:49.651260 ignition[1098]: Ignition 2.21.0 Aug 19 08:13:49.652908 ignition[1098]: Stage: fetch Aug 19 08:13:49.653257 ignition[1098]: no configs at "/usr/lib/ignition/base.d" Aug 19 08:13:49.653274 ignition[1098]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Aug 19 08:13:49.653390 ignition[1098]: parsed url from cmdline: "" Aug 19 08:13:49.653394 ignition[1098]: no config URL provided Aug 19 08:13:49.653400 ignition[1098]: reading system config file "/usr/lib/ignition/user.ign" Aug 19 08:13:49.653410 ignition[1098]: no config at "/usr/lib/ignition/user.ign" Aug 19 08:13:49.653454 ignition[1098]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Aug 19 08:13:49.769815 ignition[1098]: GET result: OK Aug 19 08:13:49.769863 ignition[1098]: config has been read from IMDS userdata Aug 19 08:13:49.769878 ignition[1098]: parsing config with SHA512: 0313e6852e46b8609a7f9d111148fb57c55a76aca87baf0c5f39f03c3423576f21dfd72ef8690461ac831bcb5150b3b363728b240953687bc85c2f082b2d4453 Aug 19 08:13:49.773499 unknown[1098]: fetched base config from "system" Aug 19 08:13:49.773716 ignition[1098]: fetch: fetch complete Aug 19 08:13:49.773506 unknown[1098]: fetched base config from "system" Aug 19 08:13:49.773722 ignition[1098]: fetch: fetch passed Aug 19 08:13:49.773511 unknown[1098]: fetched user config from "azure" Aug 19 08:13:49.773767 ignition[1098]: Ignition finished successfully Aug 19 08:13:49.785263 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Aug 19 08:13:49.790513 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Aug 19 08:13:49.834928 ignition[1109]: Ignition 2.21.0 Aug 19 08:13:49.834941 ignition[1109]: Stage: kargs Aug 19 08:13:49.835113 ignition[1109]: no configs at "/usr/lib/ignition/base.d" Aug 19 08:13:49.840121 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Aug 19 08:13:49.835124 ignition[1109]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Aug 19 08:13:49.847977 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Aug 19 08:13:49.836097 ignition[1109]: kargs: kargs passed Aug 19 08:13:49.836140 ignition[1109]: Ignition finished successfully Aug 19 08:13:49.900428 ignition[1119]: Ignition 2.21.0 Aug 19 08:13:49.900442 ignition[1119]: Stage: disks Aug 19 08:13:49.900712 ignition[1119]: no configs at "/usr/lib/ignition/base.d" Aug 19 08:13:49.900724 ignition[1119]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Aug 19 08:13:49.909328 systemd[1]: Finished ignition-disks.service - Ignition (disks). Aug 19 08:13:49.903543 ignition[1119]: disks: disks passed Aug 19 08:13:49.911421 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Aug 19 08:13:49.903607 ignition[1119]: Ignition finished successfully Aug 19 08:13:49.925909 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Aug 19 08:13:49.933357 systemd[1]: Reached target local-fs.target - Local File Systems. Aug 19 08:13:49.941983 systemd[1]: Reached target sysinit.target - System Initialization. Aug 19 08:13:49.949188 systemd[1]: Reached target basic.target - Basic System. Aug 19 08:13:49.958700 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Aug 19 08:13:50.056487 systemd-fsck[1131]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks Aug 19 08:13:50.062300 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Aug 19 08:13:50.064904 systemd[1]: Mounting sysroot.mount - /sysroot... Aug 19 08:13:50.316664 kernel: EXT4-fs (sda9): mounted filesystem 41966107-04fa-426e-9830-6b4efa50e27b r/w with ordered data mode. Quota mode: none. Aug 19 08:13:50.317401 systemd[1]: Mounted sysroot.mount - /sysroot. Aug 19 08:13:50.319503 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Aug 19 08:13:50.347502 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Aug 19 08:13:50.349853 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Aug 19 08:13:50.375185 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Aug 19 08:13:50.389210 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1146) Aug 19 08:13:50.376674 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Aug 19 08:13:50.403576 kernel: BTRFS info (device sda6): first mount of filesystem 43dd0637-5e0b-4b8d-a544-a82ca0652f6f Aug 19 08:13:50.403596 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Aug 19 08:13:50.403609 kernel: BTRFS info (device sda6): using free-space-tree Aug 19 08:13:50.376705 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Aug 19 08:13:50.391785 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Aug 19 08:13:50.405391 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Aug 19 08:13:50.431414 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Aug 19 08:13:51.628749 coreos-metadata[1148]: Aug 19 08:13:51.628 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Aug 19 08:13:51.636956 coreos-metadata[1148]: Aug 19 08:13:51.636 INFO Fetch successful Aug 19 08:13:51.640925 coreos-metadata[1148]: Aug 19 08:13:51.640 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Aug 19 08:13:51.649238 coreos-metadata[1148]: Aug 19 08:13:51.649 INFO Fetch successful Aug 19 08:13:51.649238 coreos-metadata[1148]: Aug 19 08:13:51.649 INFO wrote hostname ci-4426.0.0-a-9bb0dea2fd to /sysroot/etc/hostname Aug 19 08:13:51.650565 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Aug 19 08:13:52.295638 initrd-setup-root[1176]: cut: /sysroot/etc/passwd: No such file or directory Aug 19 08:13:52.484769 initrd-setup-root[1183]: cut: /sysroot/etc/group: No such file or directory Aug 19 08:13:52.514444 initrd-setup-root[1190]: cut: /sysroot/etc/shadow: No such file or directory Aug 19 08:13:52.535413 initrd-setup-root[1197]: cut: /sysroot/etc/gshadow: No such file or directory Aug 19 08:13:54.138323 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Aug 19 08:13:54.148580 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Aug 19 08:13:54.177310 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Aug 19 08:13:54.190227 systemd[1]: sysroot-oem.mount: Deactivated successfully. Aug 19 08:13:54.199193 kernel: BTRFS info (device sda6): last unmount of filesystem 43dd0637-5e0b-4b8d-a544-a82ca0652f6f Aug 19 08:13:54.229157 ignition[1266]: INFO : Ignition 2.21.0 Aug 19 08:13:54.229157 ignition[1266]: INFO : Stage: mount Aug 19 08:13:54.239106 ignition[1266]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 19 08:13:54.239106 ignition[1266]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Aug 19 08:13:54.239106 ignition[1266]: INFO : mount: mount passed Aug 19 08:13:54.239106 ignition[1266]: INFO : Ignition finished successfully Aug 19 08:13:54.231665 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Aug 19 08:13:54.240326 systemd[1]: Finished ignition-mount.service - Ignition (mount). Aug 19 08:13:54.248216 systemd[1]: Starting ignition-files.service - Ignition (files)... Aug 19 08:13:54.286290 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Aug 19 08:13:54.309692 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1281) Aug 19 08:13:54.309736 kernel: BTRFS info (device sda6): first mount of filesystem 43dd0637-5e0b-4b8d-a544-a82ca0652f6f Aug 19 08:13:54.314066 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Aug 19 08:13:54.317415 kernel: BTRFS info (device sda6): using free-space-tree Aug 19 08:13:54.321165 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Aug 19 08:13:54.377958 ignition[1298]: INFO : Ignition 2.21.0 Aug 19 08:13:54.377958 ignition[1298]: INFO : Stage: files Aug 19 08:13:54.383975 ignition[1298]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 19 08:13:54.383975 ignition[1298]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Aug 19 08:13:54.383975 ignition[1298]: DEBUG : files: compiled without relabeling support, skipping Aug 19 08:13:54.398007 ignition[1298]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Aug 19 08:13:54.398007 ignition[1298]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Aug 19 08:13:54.409621 ignition[1298]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Aug 19 08:13:54.409621 ignition[1298]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Aug 19 08:13:54.409621 ignition[1298]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Aug 19 08:13:54.407709 unknown[1298]: wrote ssh authorized keys file for user: core Aug 19 08:13:54.436309 ignition[1298]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Aug 19 08:13:54.443641 ignition[1298]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Aug 19 08:13:54.443641 ignition[1298]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Aug 19 08:13:54.443641 ignition[1298]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Aug 19 08:13:54.443641 ignition[1298]: INFO : files: files passed Aug 19 08:13:54.443641 ignition[1298]: INFO : Ignition finished successfully Aug 19 08:13:54.445164 systemd[1]: Finished ignition-files.service - Ignition (files). Aug 19 08:13:54.455051 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Aug 19 08:13:54.490239 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Aug 19 08:13:54.500590 systemd[1]: ignition-quench.service: Deactivated successfully. Aug 19 08:13:54.500705 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Aug 19 08:13:54.588508 initrd-setup-root-after-ignition[1331]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Aug 19 08:13:54.588508 initrd-setup-root-after-ignition[1331]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Aug 19 08:13:54.601663 initrd-setup-root-after-ignition[1335]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Aug 19 08:13:54.596702 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Aug 19 08:13:54.602687 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Aug 19 08:13:54.623001 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Aug 19 08:13:54.664176 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Aug 19 08:13:54.664287 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Aug 19 08:13:54.665913 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Aug 19 08:13:54.674816 systemd[1]: Reached target initrd.target - Initrd Default Target. Aug 19 08:13:54.690908 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Aug 19 08:13:54.692287 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Aug 19 08:13:54.713300 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Aug 19 08:13:54.724936 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Aug 19 08:13:54.744748 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Aug 19 08:13:54.745700 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 19 08:13:54.759234 systemd[1]: Stopped target timers.target - Timer Units. Aug 19 08:13:54.767737 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Aug 19 08:13:54.767890 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Aug 19 08:13:54.779847 systemd[1]: Stopped target initrd.target - Initrd Default Target. Aug 19 08:13:54.789207 systemd[1]: Stopped target basic.target - Basic System. Aug 19 08:13:54.797111 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Aug 19 08:13:54.805255 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Aug 19 08:13:54.808505 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Aug 19 08:13:54.820144 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Aug 19 08:13:54.829404 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Aug 19 08:13:54.837684 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Aug 19 08:13:54.850502 systemd[1]: Stopped target sysinit.target - System Initialization. Aug 19 08:13:54.858676 systemd[1]: Stopped target local-fs.target - Local File Systems. Aug 19 08:13:54.867866 systemd[1]: Stopped target swap.target - Swaps. Aug 19 08:13:54.870102 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Aug 19 08:13:54.870244 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Aug 19 08:13:54.889571 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Aug 19 08:13:54.891090 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 19 08:13:54.899506 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Aug 19 08:13:54.899656 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 19 08:13:54.908699 systemd[1]: dracut-initqueue.service: Deactivated successfully. Aug 19 08:13:54.908842 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Aug 19 08:13:54.925303 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Aug 19 08:13:54.925440 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Aug 19 08:13:54.934398 systemd[1]: ignition-files.service: Deactivated successfully. Aug 19 08:13:54.934524 systemd[1]: Stopped ignition-files.service - Ignition (files). Aug 19 08:13:54.935954 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Aug 19 08:13:54.936071 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Aug 19 08:13:54.946429 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Aug 19 08:13:54.978983 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Aug 19 08:13:54.990603 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Aug 19 08:13:54.990805 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Aug 19 08:13:55.026675 ignition[1355]: INFO : Ignition 2.21.0 Aug 19 08:13:55.026675 ignition[1355]: INFO : Stage: umount Aug 19 08:13:55.026675 ignition[1355]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 19 08:13:55.026675 ignition[1355]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Aug 19 08:13:55.026675 ignition[1355]: INFO : umount: umount passed Aug 19 08:13:55.026675 ignition[1355]: INFO : Ignition finished successfully Aug 19 08:13:55.000932 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Aug 19 08:13:55.001070 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Aug 19 08:13:55.016243 systemd[1]: initrd-cleanup.service: Deactivated successfully. Aug 19 08:13:55.016344 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Aug 19 08:13:55.027906 systemd[1]: ignition-mount.service: Deactivated successfully. Aug 19 08:13:55.028018 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Aug 19 08:13:55.036121 systemd[1]: sysroot-boot.mount: Deactivated successfully. Aug 19 08:13:55.036594 systemd[1]: ignition-disks.service: Deactivated successfully. Aug 19 08:13:55.036664 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Aug 19 08:13:55.044732 systemd[1]: ignition-kargs.service: Deactivated successfully. Aug 19 08:13:55.044783 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Aug 19 08:13:55.053683 systemd[1]: ignition-fetch.service: Deactivated successfully. Aug 19 08:13:55.053734 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Aug 19 08:13:55.061016 systemd[1]: Stopped target network.target - Network. Aug 19 08:13:55.068724 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Aug 19 08:13:55.068951 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Aug 19 08:13:55.077712 systemd[1]: Stopped target paths.target - Path Units. Aug 19 08:13:55.085558 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Aug 19 08:13:55.088691 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 19 08:13:55.090759 systemd[1]: Stopped target slices.target - Slice Units. Aug 19 08:13:55.100783 systemd[1]: Stopped target sockets.target - Socket Units. Aug 19 08:13:55.107502 systemd[1]: iscsid.socket: Deactivated successfully. Aug 19 08:13:55.107548 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Aug 19 08:13:55.122146 systemd[1]: iscsiuio.socket: Deactivated successfully. Aug 19 08:13:55.122194 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Aug 19 08:13:55.130341 systemd[1]: ignition-setup.service: Deactivated successfully. Aug 19 08:13:55.130407 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Aug 19 08:13:55.138758 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Aug 19 08:13:55.138813 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Aug 19 08:13:55.146960 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Aug 19 08:13:55.154783 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Aug 19 08:13:55.156495 systemd[1]: sysroot-boot.service: Deactivated successfully. Aug 19 08:13:55.156599 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Aug 19 08:13:55.164923 systemd[1]: systemd-resolved.service: Deactivated successfully. Aug 19 08:13:55.165022 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Aug 19 08:13:55.181350 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Aug 19 08:13:55.182442 systemd[1]: initrd-setup-root.service: Deactivated successfully. Aug 19 08:13:55.182504 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Aug 19 08:13:55.186123 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Aug 19 08:13:55.186175 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Aug 19 08:13:55.204327 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Aug 19 08:13:55.204669 systemd[1]: systemd-networkd.service: Deactivated successfully. Aug 19 08:13:55.204783 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Aug 19 08:13:55.219975 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Aug 19 08:13:55.220505 systemd[1]: Stopped target network-pre.target - Preparation for Network. Aug 19 08:13:55.224072 systemd[1]: systemd-networkd.socket: Deactivated successfully. Aug 19 08:13:55.224322 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Aug 19 08:13:55.234557 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Aug 19 08:13:55.239939 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Aug 19 08:13:55.239993 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Aug 19 08:13:55.247543 systemd[1]: systemd-sysctl.service: Deactivated successfully. Aug 19 08:13:55.247590 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Aug 19 08:13:55.263408 systemd[1]: systemd-modules-load.service: Deactivated successfully. Aug 19 08:13:55.263455 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Aug 19 08:13:55.270864 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 19 08:13:55.273342 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Aug 19 08:13:55.298318 systemd[1]: network-cleanup.service: Deactivated successfully. Aug 19 08:13:55.298444 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Aug 19 08:13:55.319379 systemd[1]: systemd-udevd.service: Deactivated successfully. Aug 19 08:13:55.319531 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 19 08:13:55.324138 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Aug 19 08:13:55.324183 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Aug 19 08:13:55.332767 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Aug 19 08:13:55.332803 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Aug 19 08:13:55.341856 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Aug 19 08:13:55.341906 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Aug 19 08:13:55.358760 systemd[1]: dracut-cmdline.service: Deactivated successfully. Aug 19 08:13:55.358812 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Aug 19 08:13:55.372463 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Aug 19 08:13:55.372590 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 19 08:13:55.391881 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Aug 19 08:13:55.401911 systemd[1]: systemd-network-generator.service: Deactivated successfully. Aug 19 08:13:55.401970 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Aug 19 08:13:55.415667 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Aug 19 08:13:55.415720 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 19 08:13:55.430245 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Aug 19 08:13:55.430299 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Aug 19 08:13:55.443978 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Aug 19 08:13:55.444038 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Aug 19 08:13:55.445447 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Aug 19 08:13:55.445495 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Aug 19 08:13:55.463242 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Aug 19 08:13:55.463297 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev\x2dearly.service.mount: Deactivated successfully. Aug 19 08:13:55.463335 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Aug 19 08:13:55.463374 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Aug 19 08:13:55.463770 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Aug 19 08:13:55.463868 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Aug 19 08:13:55.469363 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Aug 19 08:13:55.486381 systemd[1]: Starting initrd-switch-root.service - Switch Root... Aug 19 08:13:55.530438 systemd[1]: Switching root. Aug 19 08:13:55.791901 systemd-journald[232]: Journal stopped Aug 19 08:14:02.472863 systemd-journald[232]: Received SIGTERM from PID 1 (systemd). Aug 19 08:14:02.472895 kernel: SELinux: policy capability network_peer_controls=1 Aug 19 08:14:02.472908 kernel: SELinux: policy capability open_perms=1 Aug 19 08:14:02.472917 kernel: SELinux: policy capability extended_socket_class=1 Aug 19 08:14:02.472926 kernel: SELinux: policy capability always_check_network=0 Aug 19 08:14:02.472935 kernel: SELinux: policy capability cgroup_seclabel=1 Aug 19 08:14:02.472945 kernel: SELinux: policy capability nnp_nosuid_transition=1 Aug 19 08:14:02.472957 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Aug 19 08:14:02.472967 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Aug 19 08:14:02.472976 kernel: SELinux: policy capability userspace_initial_context=0 Aug 19 08:14:02.472986 kernel: audit: type=1403 audit(1755591236.704:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Aug 19 08:14:02.472996 systemd[1]: Successfully loaded SELinux policy in 163.514ms. Aug 19 08:14:02.473007 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 7.652ms. Aug 19 08:14:02.473019 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) Aug 19 08:14:02.473032 systemd[1]: Detected virtualization microsoft. Aug 19 08:14:02.473043 systemd[1]: Detected architecture x86-64. Aug 19 08:14:02.473053 systemd[1]: Detected first boot. Aug 19 08:14:02.473064 systemd[1]: Hostname set to . Aug 19 08:14:02.473074 systemd[1]: Initializing machine ID from random generator. Aug 19 08:14:02.473087 zram_generator::config[1402]: No configuration found. Aug 19 08:14:02.473098 kernel: Guest personality initialized and is inactive Aug 19 08:14:02.473108 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Aug 19 08:14:02.473117 kernel: Initialized host personality Aug 19 08:14:02.473127 kernel: NET: Registered PF_VSOCK protocol family Aug 19 08:14:02.473137 systemd[1]: Populated /etc with preset unit settings. Aug 19 08:14:02.473148 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Aug 19 08:14:02.473161 systemd[1]: initrd-switch-root.service: Deactivated successfully. Aug 19 08:14:02.473172 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Aug 19 08:14:02.473182 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Aug 19 08:14:02.473192 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Aug 19 08:14:02.473203 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Aug 19 08:14:02.473214 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Aug 19 08:14:02.473224 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Aug 19 08:14:02.473237 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Aug 19 08:14:02.473248 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Aug 19 08:14:02.473259 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Aug 19 08:14:02.473273 systemd[1]: Created slice user.slice - User and Session Slice. Aug 19 08:14:02.473283 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 19 08:14:02.473294 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 19 08:14:02.473305 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Aug 19 08:14:02.473319 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Aug 19 08:14:02.473330 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Aug 19 08:14:02.473344 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Aug 19 08:14:02.473355 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Aug 19 08:14:02.473366 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 19 08:14:02.473377 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Aug 19 08:14:02.473388 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Aug 19 08:14:02.473398 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Aug 19 08:14:02.473409 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Aug 19 08:14:02.473422 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Aug 19 08:14:02.473433 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 19 08:14:02.473444 systemd[1]: Reached target remote-fs.target - Remote File Systems. Aug 19 08:14:02.473455 systemd[1]: Reached target slices.target - Slice Units. Aug 19 08:14:02.473466 systemd[1]: Reached target swap.target - Swaps. Aug 19 08:14:02.473476 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Aug 19 08:14:02.473487 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Aug 19 08:14:02.473501 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Aug 19 08:14:02.473515 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Aug 19 08:14:02.473527 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Aug 19 08:14:02.473537 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Aug 19 08:14:02.473549 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Aug 19 08:14:02.473560 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Aug 19 08:14:02.473573 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Aug 19 08:14:02.473584 systemd[1]: Mounting media.mount - External Media Directory... Aug 19 08:14:02.473596 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 19 08:14:02.473607 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Aug 19 08:14:02.473618 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Aug 19 08:14:02.473640 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Aug 19 08:14:02.473653 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). Aug 19 08:14:02.473664 systemd[1]: Reached target machines.target - Containers. Aug 19 08:14:02.473678 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Aug 19 08:14:02.473690 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 19 08:14:02.473701 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Aug 19 08:14:02.473712 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Aug 19 08:14:02.473723 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 19 08:14:02.473734 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Aug 19 08:14:02.473745 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 19 08:14:02.473756 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Aug 19 08:14:02.473773 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 19 08:14:02.473784 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Aug 19 08:14:02.473795 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Aug 19 08:14:02.473806 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Aug 19 08:14:02.473817 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Aug 19 08:14:02.473828 systemd[1]: Stopped systemd-fsck-usr.service. Aug 19 08:14:02.473839 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). Aug 19 08:14:02.473851 systemd[1]: Starting systemd-journald.service - Journal Service... Aug 19 08:14:02.473862 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Aug 19 08:14:02.473875 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Aug 19 08:14:02.473887 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Aug 19 08:14:02.473897 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Aug 19 08:14:02.473908 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Aug 19 08:14:02.473919 systemd[1]: verity-setup.service: Deactivated successfully. Aug 19 08:14:02.473930 systemd[1]: Stopped verity-setup.service. Aug 19 08:14:02.473941 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 19 08:14:02.473952 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Aug 19 08:14:02.473965 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Aug 19 08:14:02.473976 systemd[1]: Mounted media.mount - External Media Directory. Aug 19 08:14:02.473987 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Aug 19 08:14:02.473998 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Aug 19 08:14:02.474012 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Aug 19 08:14:02.474023 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Aug 19 08:14:02.474057 systemd-journald[1509]: Collecting audit messages is disabled. Aug 19 08:14:02.474086 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Aug 19 08:14:02.474098 systemd-journald[1509]: Journal started Aug 19 08:14:02.474124 systemd-journald[1509]: Runtime Journal (/run/log/journal/91eea981e46f4de3aa5b14d9ce39fe20) is 8M, max 2.2G, 2.1G free. Aug 19 08:14:01.484387 systemd[1]: Queued start job for default target multi-user.target. Aug 19 08:14:01.513238 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Aug 19 08:14:01.513675 systemd[1]: systemd-journald.service: Deactivated successfully. Aug 19 08:14:02.479696 kernel: loop: module loaded Aug 19 08:14:02.492854 systemd[1]: Started systemd-journald.service - Journal Service. Aug 19 08:14:02.497396 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 19 08:14:02.497602 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 19 08:14:02.502906 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 19 08:14:02.503094 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 19 08:14:02.508562 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 19 08:14:02.508775 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 19 08:14:02.513657 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Aug 19 08:14:02.518657 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Aug 19 08:14:02.524215 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Aug 19 08:14:02.536578 systemd[1]: Reached target network-pre.target - Preparation for Network. Aug 19 08:14:02.544830 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Aug 19 08:14:02.544867 systemd[1]: Reached target local-fs.target - Local File Systems. Aug 19 08:14:02.552540 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Aug 19 08:14:02.557528 kernel: fuse: init (API version 7.41) Aug 19 08:14:02.562581 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Aug 19 08:14:02.575815 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 19 08:14:02.603801 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Aug 19 08:14:02.625920 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Aug 19 08:14:02.630811 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Aug 19 08:14:02.631849 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Aug 19 08:14:02.636487 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Aug 19 08:14:02.637526 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Aug 19 08:14:02.642745 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Aug 19 08:14:02.648670 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Aug 19 08:14:02.655485 systemd[1]: modprobe@configfs.service: Deactivated successfully. Aug 19 08:14:02.655700 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Aug 19 08:14:02.660913 systemd[1]: modprobe@fuse.service: Deactivated successfully. Aug 19 08:14:02.661098 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Aug 19 08:14:02.666930 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Aug 19 08:14:02.672981 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Aug 19 08:14:02.681005 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Aug 19 08:14:02.695833 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Aug 19 08:14:02.701849 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Aug 19 08:14:02.707832 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Aug 19 08:14:02.849837 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Aug 19 08:14:02.855022 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Aug 19 08:14:02.861206 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Aug 19 08:14:02.866913 systemd-tmpfiles[1540]: ACLs are not supported, ignoring. Aug 19 08:14:02.866934 systemd-tmpfiles[1540]: ACLs are not supported, ignoring. Aug 19 08:14:02.876244 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Aug 19 08:14:02.878508 systemd-journald[1509]: Time spent on flushing to /var/log/journal/91eea981e46f4de3aa5b14d9ce39fe20 is 16.992ms for 956 entries. Aug 19 08:14:02.878508 systemd-journald[1509]: System Journal (/var/log/journal/91eea981e46f4de3aa5b14d9ce39fe20) is 8M, max 2.6G, 2.6G free. Aug 19 08:14:03.635436 systemd-journald[1509]: Received client request to flush runtime journal. Aug 19 08:14:03.635518 kernel: loop0: detected capacity change from 0 to 128016 Aug 19 08:14:03.635548 kernel: ACPI: bus type drm_connector registered Aug 19 08:14:02.886981 systemd[1]: Starting systemd-sysusers.service - Create System Users... Aug 19 08:14:02.905794 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Aug 19 08:14:03.147121 systemd[1]: modprobe@drm.service: Deactivated successfully. Aug 19 08:14:03.147331 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Aug 19 08:14:03.637070 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Aug 19 08:14:03.684942 systemd[1]: Finished systemd-sysusers.service - Create System Users. Aug 19 08:14:03.690611 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Aug 19 08:14:03.722589 systemd-tmpfiles[1561]: ACLs are not supported, ignoring. Aug 19 08:14:03.722611 systemd-tmpfiles[1561]: ACLs are not supported, ignoring. Aug 19 08:14:03.726059 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 19 08:14:04.526675 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Aug 19 08:14:04.527387 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Aug 19 08:14:05.206663 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Aug 19 08:14:05.253658 kernel: loop1: detected capacity change from 0 to 29256 Aug 19 08:14:05.943663 kernel: loop2: detected capacity change from 0 to 111000 Aug 19 08:14:06.403049 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Aug 19 08:14:06.409597 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 19 08:14:06.456044 systemd-udevd[1570]: Using default interface naming scheme 'v255'. Aug 19 08:14:06.821026 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 19 08:14:06.829041 systemd[1]: Starting systemd-networkd.service - Network Configuration... Aug 19 08:14:06.854194 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Aug 19 08:14:06.902666 kernel: loop3: detected capacity change from 0 to 128016 Aug 19 08:14:06.912671 kernel: hv_vmbus: registering driver hyperv_fb Aug 19 08:14:06.924473 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Aug 19 08:14:06.924537 kernel: loop4: detected capacity change from 0 to 29256 Aug 19 08:14:06.924559 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Aug 19 08:14:06.929661 kernel: Console: switching to colour dummy device 80x25 Aug 19 08:14:06.929711 kernel: loop5: detected capacity change from 0 to 111000 Aug 19 08:14:06.938008 kernel: Console: switching to colour frame buffer device 128x48 Aug 19 08:14:06.942432 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Aug 19 08:14:06.942719 (sd-merge)[1616]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Aug 19 08:14:06.945650 (sd-merge)[1616]: Merged extensions into '/usr'. Aug 19 08:14:06.956392 kernel: hv_vmbus: registering driver hv_balloon Aug 19 08:14:06.956467 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Aug 19 08:14:06.964075 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Aug 19 08:14:06.973877 systemd[1]: Starting ensure-sysext.service... Aug 19 08:14:06.980746 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Aug 19 08:14:06.985466 kernel: mousedev: PS/2 mouse device common for all mice Aug 19 08:14:07.012325 systemd[1]: Started systemd-userdbd.service - User Database Manager. Aug 19 08:14:07.032786 systemd[1]: Reload requested from client PID 1644 ('systemctl') (unit ensure-sysext.service)... Aug 19 08:14:07.032801 systemd[1]: Reloading... Aug 19 08:14:07.043653 systemd-tmpfiles[1645]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Aug 19 08:14:07.043696 systemd-tmpfiles[1645]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Aug 19 08:14:07.043969 systemd-tmpfiles[1645]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Aug 19 08:14:07.044261 systemd-tmpfiles[1645]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Aug 19 08:14:07.045157 systemd-tmpfiles[1645]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Aug 19 08:14:07.045448 systemd-tmpfiles[1645]: ACLs are not supported, ignoring. Aug 19 08:14:07.045516 systemd-tmpfiles[1645]: ACLs are not supported, ignoring. Aug 19 08:14:07.052677 kernel: EDAC MC: Ver: 3.0.0 Aug 19 08:14:07.061410 systemd-tmpfiles[1645]: Detected autofs mount point /boot during canonicalization of boot. Aug 19 08:14:07.061424 systemd-tmpfiles[1645]: Skipping /boot Aug 19 08:14:07.069970 systemd-tmpfiles[1645]: Detected autofs mount point /boot during canonicalization of boot. Aug 19 08:14:07.069984 systemd-tmpfiles[1645]: Skipping /boot Aug 19 08:14:07.099741 zram_generator::config[1679]: No configuration found. Aug 19 08:14:07.279151 systemd-networkd[1592]: lo: Link UP Aug 19 08:14:07.279163 systemd-networkd[1592]: lo: Gained carrier Aug 19 08:14:07.280528 systemd-networkd[1592]: Enumeration completed Aug 19 08:14:07.280920 systemd-networkd[1592]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 19 08:14:07.280930 systemd-networkd[1592]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Aug 19 08:14:07.281649 systemd-networkd[1592]: eth0: Link UP Aug 19 08:14:07.281658 systemd-networkd[1592]: eth0: Gained carrier Aug 19 08:14:07.281674 systemd-networkd[1592]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 19 08:14:07.316674 systemd-networkd[1592]: eth0: DHCPv4 address 10.200.8.16/24, gateway 10.200.8.1 acquired from 168.63.129.16 Aug 19 08:14:07.355482 systemd[1]: Reloading finished in 322 ms. Aug 19 08:14:07.387099 systemd[1]: Started systemd-networkd.service - Network Configuration. Aug 19 08:14:07.416032 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Aug 19 08:14:07.478419 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Aug 19 08:14:07.486480 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 19 08:14:07.487896 systemd[1]: Starting audit-rules.service - Load Audit Rules... Aug 19 08:14:07.510528 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Aug 19 08:14:07.515372 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 19 08:14:07.516459 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 19 08:14:07.521663 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Aug 19 08:14:07.526535 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 19 08:14:07.532214 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 19 08:14:07.536466 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 19 08:14:07.537497 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Aug 19 08:14:07.542481 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). Aug 19 08:14:07.543771 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Aug 19 08:14:07.549585 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Aug 19 08:14:07.555362 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Aug 19 08:14:07.561797 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Aug 19 08:14:07.566142 systemd[1]: Reached target time-set.target - System Time Set. Aug 19 08:14:07.571453 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Aug 19 08:14:07.577104 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 19 08:14:07.581831 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 19 08:14:07.586753 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 19 08:14:07.586942 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 19 08:14:07.588989 systemd[1]: modprobe@drm.service: Deactivated successfully. Aug 19 08:14:07.589177 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Aug 19 08:14:07.589743 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 19 08:14:07.589923 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 19 08:14:07.590504 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 19 08:14:07.590700 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 19 08:14:07.593454 systemd[1]: Finished ensure-sysext.service. Aug 19 08:14:07.597925 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Aug 19 08:14:07.597993 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Aug 19 08:14:07.602779 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Aug 19 08:14:07.666677 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Aug 19 08:14:07.680712 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Aug 19 08:14:07.711562 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Aug 19 08:14:07.762659 systemd-resolved[1822]: Positive Trust Anchors: Aug 19 08:14:07.762675 systemd-resolved[1822]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Aug 19 08:14:07.762707 systemd-resolved[1822]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Aug 19 08:14:07.791446 systemd-resolved[1822]: Using system hostname 'ci-4426.0.0-a-9bb0dea2fd'. Aug 19 08:14:07.793133 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Aug 19 08:14:07.794823 systemd[1]: Reached target network.target - Network. Aug 19 08:14:07.795485 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Aug 19 08:14:07.859331 augenrules[1851]: No rules Aug 19 08:14:07.860720 systemd[1]: audit-rules.service: Deactivated successfully. Aug 19 08:14:07.860971 systemd[1]: Finished audit-rules.service - Load Audit Rules. Aug 19 08:14:08.222718 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 19 08:14:08.970247 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Aug 19 08:14:08.976048 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). Aug 19 08:14:09.151934 systemd-networkd[1592]: eth0: Gained IPv6LL Aug 19 08:14:09.154524 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Aug 19 08:14:09.160425 systemd[1]: Reached target network-online.target - Network is Online. Aug 19 08:14:16.888844 ldconfig[1532]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Aug 19 08:14:16.901476 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Aug 19 08:14:16.909208 systemd[1]: Starting systemd-update-done.service - Update is Completed... Aug 19 08:14:16.941798 systemd[1]: Finished systemd-update-done.service - Update is Completed. Aug 19 08:14:16.946660 systemd[1]: Reached target sysinit.target - System Initialization. Aug 19 08:14:16.951128 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Aug 19 08:14:16.956087 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Aug 19 08:14:16.961198 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Aug 19 08:14:16.966155 systemd[1]: Started logrotate.timer - Daily rotation of log files. Aug 19 08:14:16.970591 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Aug 19 08:14:16.975864 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Aug 19 08:14:16.981057 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Aug 19 08:14:16.981103 systemd[1]: Reached target paths.target - Path Units. Aug 19 08:14:16.984882 systemd[1]: Reached target timers.target - Timer Units. Aug 19 08:14:16.989671 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Aug 19 08:14:16.995602 systemd[1]: Starting docker.socket - Docker Socket for the API... Aug 19 08:14:17.001923 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Aug 19 08:14:17.007782 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Aug 19 08:14:17.012881 systemd[1]: Reached target ssh-access.target - SSH Access Available. Aug 19 08:14:17.019002 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Aug 19 08:14:17.023428 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Aug 19 08:14:17.029339 systemd[1]: Listening on docker.socket - Docker Socket for the API. Aug 19 08:14:17.034874 systemd[1]: Reached target sockets.target - Socket Units. Aug 19 08:14:17.038914 systemd[1]: Reached target basic.target - Basic System. Aug 19 08:14:17.042803 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Aug 19 08:14:17.042838 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Aug 19 08:14:17.045436 systemd[1]: Starting chronyd.service - NTP client/server... Aug 19 08:14:17.049885 systemd[1]: Starting containerd.service - containerd container runtime... Aug 19 08:14:17.070482 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Aug 19 08:14:17.075505 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Aug 19 08:14:17.080405 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Aug 19 08:14:17.097871 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Aug 19 08:14:17.103191 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Aug 19 08:14:17.107470 jq[1875]: false Aug 19 08:14:17.107536 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). Aug 19 08:14:17.108601 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Aug 19 08:14:17.113121 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). Aug 19 08:14:17.114173 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Aug 19 08:14:17.118487 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Aug 19 08:14:17.119523 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Aug 19 08:14:17.124481 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Aug 19 08:14:17.129075 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Aug 19 08:14:17.135248 KVP[1878]: KVP starting; pid is:1878 Aug 19 08:14:17.135527 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Aug 19 08:14:17.135973 chronyd[1867]: chronyd version 4.7 starting (+CMDMON +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +NTS +SECHASH +IPV6 -DEBUG) Aug 19 08:14:17.139891 KVP[1878]: KVP LIC Version: 3.1 Aug 19 08:14:17.140070 chronyd[1867]: Timezone right/UTC failed leap second check, ignoring Aug 19 08:14:17.140211 chronyd[1867]: Loaded seccomp filter (level 2) Aug 19 08:14:17.140643 kernel: hv_utils: KVP IC version 4.0 Aug 19 08:14:17.144758 systemd[1]: Starting systemd-logind.service - User Login Management... Aug 19 08:14:17.149777 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Aug 19 08:14:17.150217 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Aug 19 08:14:17.150915 systemd[1]: Starting update-engine.service - Update Engine... Aug 19 08:14:17.155496 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Aug 19 08:14:17.161635 systemd[1]: Started chronyd.service - NTP client/server. Aug 19 08:14:17.163871 jq[1893]: true Aug 19 08:14:17.166588 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Aug 19 08:14:17.172245 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Aug 19 08:14:17.178993 extend-filesystems[1876]: Found /dev/sda6 Aug 19 08:14:17.189806 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Aug 19 08:14:17.190138 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Aug 19 08:14:17.190338 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Aug 19 08:14:17.196746 google_oslogin_nss_cache[1877]: oslogin_cache_refresh[1877]: Refreshing passwd entry cache Aug 19 08:14:17.196756 oslogin_cache_refresh[1877]: Refreshing passwd entry cache Aug 19 08:14:17.204169 jq[1897]: true Aug 19 08:14:17.206863 systemd[1]: motdgen.service: Deactivated successfully. Aug 19 08:14:17.210062 google_oslogin_nss_cache[1877]: oslogin_cache_refresh[1877]: Failure getting users, quitting Aug 19 08:14:17.210054 oslogin_cache_refresh[1877]: Failure getting users, quitting Aug 19 08:14:17.210156 google_oslogin_nss_cache[1877]: oslogin_cache_refresh[1877]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Aug 19 08:14:17.210156 google_oslogin_nss_cache[1877]: oslogin_cache_refresh[1877]: Refreshing group entry cache Aug 19 08:14:17.210072 oslogin_cache_refresh[1877]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Aug 19 08:14:17.210115 oslogin_cache_refresh[1877]: Refreshing group entry cache Aug 19 08:14:17.213194 (ntainerd)[1900]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Aug 19 08:14:17.213196 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Aug 19 08:14:17.218150 google_oslogin_nss_cache[1877]: oslogin_cache_refresh[1877]: Failure getting groups, quitting Aug 19 08:14:17.218150 google_oslogin_nss_cache[1877]: oslogin_cache_refresh[1877]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Aug 19 08:14:17.217850 oslogin_cache_refresh[1877]: Failure getting groups, quitting Aug 19 08:14:17.217863 oslogin_cache_refresh[1877]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Aug 19 08:14:17.219595 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Aug 19 08:14:17.219885 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Aug 19 08:14:17.226099 extend-filesystems[1876]: Found /dev/sda9 Aug 19 08:14:17.230667 extend-filesystems[1876]: Checking size of /dev/sda9 Aug 19 08:14:17.277760 extend-filesystems[1876]: Old size kept for /dev/sda9 Aug 19 08:14:17.278900 systemd[1]: extend-filesystems.service: Deactivated successfully. Aug 19 08:14:17.279207 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Aug 19 08:14:17.280634 bash[1930]: Updated "/home/core/.ssh/authorized_keys" Aug 19 08:14:17.286597 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Aug 19 08:14:17.292741 systemd-logind[1889]: New seat seat0. Aug 19 08:14:17.294152 systemd-logind[1889]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Aug 19 08:14:17.295059 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Aug 19 08:14:17.295167 systemd[1]: Started systemd-logind.service - User Login Management. Aug 19 08:14:17.310552 update_engine[1891]: I20250819 08:14:17.310472 1891 main.cc:92] Flatcar Update Engine starting Aug 19 08:14:17.409092 setup-nvidia[1880]: Downloading Flatcar Container Linux Developer Container for version: 4426.0.0 Aug 19 08:14:17.490296 dbus-daemon[1870]: [system] SELinux support is enabled Aug 19 08:14:17.490556 systemd[1]: Started dbus.service - D-Bus System Message Bus. Aug 19 08:14:17.494704 update_engine[1891]: I20250819 08:14:17.493597 1891 update_check_scheduler.cc:74] Next update check in 7m8s Aug 19 08:14:17.497081 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). Aug 19 08:14:17.497113 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Aug 19 08:14:17.497927 dbus-daemon[1870]: [system] Successfully activated service 'org.freedesktop.systemd1' Aug 19 08:14:17.502306 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). Aug 19 08:14:17.502326 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Aug 19 08:14:17.507464 systemd[1]: Started update-engine.service - Update Engine. Aug 19 08:14:17.513324 systemd[1]: Started locksmithd.service - Cluster reboot manager. Aug 19 08:14:17.543664 coreos-metadata[1869]: Aug 19 08:14:17.543 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Aug 19 08:14:17.546109 coreos-metadata[1869]: Aug 19 08:14:17.546 INFO Fetch successful Aug 19 08:14:17.546699 coreos-metadata[1869]: Aug 19 08:14:17.546 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Aug 19 08:14:17.550580 coreos-metadata[1869]: Aug 19 08:14:17.550 INFO Fetch successful Aug 19 08:14:17.550936 coreos-metadata[1869]: Aug 19 08:14:17.550 INFO Fetching http://168.63.129.16/machine/d5f493ab-f41b-4084-94ac-82b1ecf47df9/25b6ac92%2D11d0%2D4366%2D88bb%2Dc6efeb31c0b3.%5Fci%2D4426.0.0%2Da%2D9bb0dea2fd?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Aug 19 08:14:17.560594 sshd_keygen[1894]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Aug 19 08:14:17.582056 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Aug 19 08:14:17.584295 coreos-metadata[1869]: Aug 19 08:14:17.584 INFO Fetch successful Aug 19 08:14:17.584452 coreos-metadata[1869]: Aug 19 08:14:17.584 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Aug 19 08:14:17.588358 systemd[1]: Starting issuegen.service - Generate /run/issue... Aug 19 08:14:17.592029 coreos-metadata[1869]: Aug 19 08:14:17.592 INFO Fetch successful Aug 19 08:14:17.592985 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Aug 19 08:14:17.609217 systemd[1]: issuegen.service: Deactivated successfully. Aug 19 08:14:17.609440 systemd[1]: Finished issuegen.service - Generate /run/issue. Aug 19 08:14:17.617368 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Aug 19 08:14:17.623835 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Aug 19 08:14:17.629242 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Aug 19 08:14:17.635294 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Aug 19 08:14:17.644918 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Aug 19 08:14:17.651746 systemd[1]: Started getty@tty1.service - Getty on tty1. Aug 19 08:14:17.656721 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Aug 19 08:14:17.661475 systemd[1]: Reached target getty.target - Login Prompts. Aug 19 08:14:18.023701 locksmithd[2008]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Aug 19 08:14:18.070064 setup-nvidia[2046]: % Total % Received % Xferd Average Speed Time Time Time Current Aug 19 08:14:18.070359 setup-nvidia[2046]: Dload Upload Total Spent Left Speed Aug 19 08:14:18.822335 containerd[1900]: time="2025-08-19T08:14:18Z" 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 Aug 19 08:14:18.823005 containerd[1900]: time="2025-08-19T08:14:18.822972400Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Aug 19 08:14:18.833135 containerd[1900]: time="2025-08-19T08:14:18.833093900Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="8µs" Aug 19 08:14:18.833135 containerd[1900]: time="2025-08-19T08:14:18.833123200Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Aug 19 08:14:18.833135 containerd[1900]: time="2025-08-19T08:14:18.833140100Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Aug 19 08:14:18.833345 containerd[1900]: time="2025-08-19T08:14:18.833319100Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Aug 19 08:14:18.833379 containerd[1900]: time="2025-08-19T08:14:18.833357100Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Aug 19 08:14:18.833406 containerd[1900]: time="2025-08-19T08:14:18.833387700Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Aug 19 08:14:18.833476 containerd[1900]: time="2025-08-19T08:14:18.833452800Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Aug 19 08:14:18.833476 containerd[1900]: time="2025-08-19T08:14:18.833472500Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Aug 19 08:14:18.833721 containerd[1900]: time="2025-08-19T08:14:18.833696700Z" 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 Aug 19 08:14:18.833721 containerd[1900]: time="2025-08-19T08:14:18.833717900Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Aug 19 08:14:18.833772 containerd[1900]: time="2025-08-19T08:14:18.833729400Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Aug 19 08:14:18.833772 containerd[1900]: time="2025-08-19T08:14:18.833737800Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Aug 19 08:14:18.833858 containerd[1900]: time="2025-08-19T08:14:18.833835100Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Aug 19 08:14:18.834134 containerd[1900]: time="2025-08-19T08:14:18.834111900Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Aug 19 08:14:18.834163 containerd[1900]: time="2025-08-19T08:14:18.834148800Z" 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 Aug 19 08:14:18.834163 containerd[1900]: time="2025-08-19T08:14:18.834159800Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Aug 19 08:14:18.834209 containerd[1900]: time="2025-08-19T08:14:18.834187100Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Aug 19 08:14:18.834606 containerd[1900]: time="2025-08-19T08:14:18.834583200Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Aug 19 08:14:18.834686 containerd[1900]: time="2025-08-19T08:14:18.834665700Z" level=info msg="metadata content store policy set" policy=shared Aug 19 08:14:18.852420 containerd[1900]: time="2025-08-19T08:14:18.852390400Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Aug 19 08:14:18.852508 containerd[1900]: time="2025-08-19T08:14:18.852434800Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Aug 19 08:14:18.852508 containerd[1900]: time="2025-08-19T08:14:18.852450600Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Aug 19 08:14:18.852508 containerd[1900]: time="2025-08-19T08:14:18.852462700Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Aug 19 08:14:18.852508 containerd[1900]: time="2025-08-19T08:14:18.852474500Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Aug 19 08:14:18.852508 containerd[1900]: time="2025-08-19T08:14:18.852485700Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Aug 19 08:14:18.852508 containerd[1900]: time="2025-08-19T08:14:18.852497800Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Aug 19 08:14:18.852508 containerd[1900]: time="2025-08-19T08:14:18.852509800Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Aug 19 08:14:18.852699 containerd[1900]: time="2025-08-19T08:14:18.852521300Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Aug 19 08:14:18.852699 containerd[1900]: time="2025-08-19T08:14:18.852532400Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Aug 19 08:14:18.852699 containerd[1900]: time="2025-08-19T08:14:18.852559200Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Aug 19 08:14:18.852699 containerd[1900]: time="2025-08-19T08:14:18.852575500Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Aug 19 08:14:18.852784 containerd[1900]: time="2025-08-19T08:14:18.852703700Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Aug 19 08:14:18.852784 containerd[1900]: time="2025-08-19T08:14:18.852725900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Aug 19 08:14:18.852784 containerd[1900]: time="2025-08-19T08:14:18.852759500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Aug 19 08:14:18.852784 containerd[1900]: time="2025-08-19T08:14:18.852775800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Aug 19 08:14:18.852860 containerd[1900]: time="2025-08-19T08:14:18.852796100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Aug 19 08:14:18.852860 containerd[1900]: time="2025-08-19T08:14:18.852809100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Aug 19 08:14:18.852860 containerd[1900]: time="2025-08-19T08:14:18.852820100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Aug 19 08:14:18.852860 containerd[1900]: time="2025-08-19T08:14:18.852838600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Aug 19 08:14:18.852860 containerd[1900]: time="2025-08-19T08:14:18.852851600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Aug 19 08:14:18.852965 containerd[1900]: time="2025-08-19T08:14:18.852878500Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Aug 19 08:14:18.852965 containerd[1900]: time="2025-08-19T08:14:18.852891200Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Aug 19 08:14:18.853006 containerd[1900]: time="2025-08-19T08:14:18.852974300Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Aug 19 08:14:18.853006 containerd[1900]: time="2025-08-19T08:14:18.852991000Z" level=info msg="Start snapshots syncer" Aug 19 08:14:18.853054 containerd[1900]: time="2025-08-19T08:14:18.853013900Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Aug 19 08:14:18.854328 containerd[1900]: time="2025-08-19T08:14:18.854273600Z" 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\"}" Aug 19 08:14:18.854466 containerd[1900]: time="2025-08-19T08:14:18.854337800Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Aug 19 08:14:18.854466 containerd[1900]: time="2025-08-19T08:14:18.854406100Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Aug 19 08:14:18.854580 containerd[1900]: time="2025-08-19T08:14:18.854555300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Aug 19 08:14:18.854639 containerd[1900]: time="2025-08-19T08:14:18.854584200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Aug 19 08:14:18.854639 containerd[1900]: time="2025-08-19T08:14:18.854596200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Aug 19 08:14:18.854639 containerd[1900]: time="2025-08-19T08:14:18.854606200Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Aug 19 08:14:18.854639 containerd[1900]: time="2025-08-19T08:14:18.854617700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Aug 19 08:14:18.854732 containerd[1900]: time="2025-08-19T08:14:18.854645500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Aug 19 08:14:18.854732 containerd[1900]: time="2025-08-19T08:14:18.854658300Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Aug 19 08:14:18.854732 containerd[1900]: time="2025-08-19T08:14:18.854678900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Aug 19 08:14:18.854732 containerd[1900]: time="2025-08-19T08:14:18.854689800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Aug 19 08:14:18.854732 containerd[1900]: time="2025-08-19T08:14:18.854699900Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Aug 19 08:14:18.854835 containerd[1900]: time="2025-08-19T08:14:18.854732700Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Aug 19 08:14:18.854835 containerd[1900]: time="2025-08-19T08:14:18.854748400Z" 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 Aug 19 08:14:18.854835 containerd[1900]: time="2025-08-19T08:14:18.854759400Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Aug 19 08:14:18.854835 containerd[1900]: time="2025-08-19T08:14:18.854775600Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Aug 19 08:14:18.854835 containerd[1900]: time="2025-08-19T08:14:18.854787500Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Aug 19 08:14:18.854835 containerd[1900]: time="2025-08-19T08:14:18.854812300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Aug 19 08:14:18.854835 containerd[1900]: time="2025-08-19T08:14:18.854824500Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Aug 19 08:14:18.854976 containerd[1900]: time="2025-08-19T08:14:18.854840600Z" level=info msg="runtime interface created" Aug 19 08:14:18.854976 containerd[1900]: time="2025-08-19T08:14:18.854846000Z" level=info msg="created NRI interface" Aug 19 08:14:18.854976 containerd[1900]: time="2025-08-19T08:14:18.854853900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Aug 19 08:14:18.854976 containerd[1900]: time="2025-08-19T08:14:18.854865500Z" level=info msg="Connect containerd service" Aug 19 08:14:18.854976 containerd[1900]: time="2025-08-19T08:14:18.854889200Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Aug 19 08:14:18.855606 containerd[1900]: time="2025-08-19T08:14:18.855578500Z" 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" Aug 19 08:14:19.816905 containerd[1900]: time="2025-08-19T08:14:19.816856300Z" level=info msg="Start subscribing containerd event" Aug 19 08:14:19.817030 containerd[1900]: time="2025-08-19T08:14:19.816913700Z" level=info msg="Start recovering state" Aug 19 08:14:19.817059 containerd[1900]: time="2025-08-19T08:14:19.817041900Z" level=info msg="Start event monitor" Aug 19 08:14:19.817101 containerd[1900]: time="2025-08-19T08:14:19.817071700Z" level=info msg="Start cni network conf syncer for default" Aug 19 08:14:19.817101 containerd[1900]: time="2025-08-19T08:14:19.817083800Z" level=info msg="Start streaming server" Aug 19 08:14:19.817158 containerd[1900]: time="2025-08-19T08:14:19.817149400Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Aug 19 08:14:19.817186 containerd[1900]: time="2025-08-19T08:14:19.817158900Z" level=info msg="runtime interface starting up..." Aug 19 08:14:19.817186 containerd[1900]: time="2025-08-19T08:14:19.817164600Z" level=info msg="starting plugins..." Aug 19 08:14:19.817186 containerd[1900]: time="2025-08-19T08:14:19.817183000Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Aug 19 08:14:19.817252 containerd[1900]: time="2025-08-19T08:14:19.817199600Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Aug 19 08:14:19.817307 containerd[1900]: time="2025-08-19T08:14:19.817284200Z" level=info msg=serving... address=/run/containerd/containerd.sock Aug 19 08:14:19.817391 containerd[1900]: time="2025-08-19T08:14:19.817370500Z" level=info msg="containerd successfully booted in 0.995616s" Aug 19 08:14:19.817477 systemd[1]: Started containerd.service - containerd container runtime. Aug 19 08:14:20.547873 waagent[2032]: 2025-08-19T08:14:20.547775Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Aug 19 08:14:20.552251 waagent[2032]: 2025-08-19T08:14:20.552206Z INFO Daemon Daemon OS: flatcar 4426.0.0 Aug 19 08:14:20.555749 waagent[2032]: 2025-08-19T08:14:20.555706Z INFO Daemon Daemon Python: 3.11.13 Aug 19 08:14:20.559252 waagent[2032]: 2025-08-19T08:14:20.559206Z INFO Daemon Daemon Run daemon Aug 19 08:14:20.562364 waagent[2032]: 2025-08-19T08:14:20.562321Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4426.0.0' Aug 19 08:14:20.569020 waagent[2032]: 2025-08-19T08:14:20.568967Z INFO Daemon Daemon Using waagent for provisioning Aug 19 08:14:20.573019 waagent[2032]: 2025-08-19T08:14:20.572976Z INFO Daemon Daemon Activate resource disk Aug 19 08:14:20.576699 waagent[2032]: 2025-08-19T08:14:20.576648Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Aug 19 08:14:20.584357 waagent[2032]: 2025-08-19T08:14:20.584295Z INFO Daemon Daemon Found device: sdb Aug 19 08:14:20.593541 waagent[2032]: 2025-08-19T08:14:20.593480Z INFO Daemon Daemon Examining partition table Aug 19 08:14:20.622177 waagent[2032]: 2025-08-19T08:14:20.622102Z INFO Daemon Daemon GPT not detected, determining filesystem Aug 19 08:14:20.665050 waagent[2032]: 2025-08-19T08:14:20.664974Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Aug 19 08:14:20.669840 waagent[2032]: 2025-08-19T08:14:20.669780Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Aug 19 08:14:21.232679 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#553 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Aug 19 08:14:21.245646 kernel: sdb: sdb1 Aug 19 08:14:21.282757 waagent[2032]: 2025-08-19T08:14:21.282674Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Aug 19 08:14:21.304659 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#169 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Aug 19 08:14:21.316642 kernel: sdb: sdb1 Aug 19 08:14:21.316946 waagent[2032]: 2025-08-19T08:14:21.316876Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Aug 19 08:14:25.220517 login[2035]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Aug 19 08:14:25.222006 login[2036]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Aug 19 08:14:25.226655 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Aug 19 08:14:25.227591 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Aug 19 08:14:25.233733 systemd-logind[1889]: New session 1 of user core. Aug 19 08:14:25.236891 systemd-logind[1889]: New session 2 of user core. Aug 19 08:14:25.341579 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Aug 19 08:14:25.344290 systemd[1]: Starting user@500.service - User Manager for UID 500... Aug 19 08:14:25.427032 (systemd)[2135]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Aug 19 08:14:25.429512 systemd-logind[1889]: New session c1 of user core. Aug 19 08:14:26.770095 systemd[2135]: Queued start job for default target default.target. Aug 19 08:14:26.787709 systemd[2135]: Created slice app.slice - User Application Slice. Aug 19 08:14:26.787739 systemd[2135]: Reached target paths.target - Paths. Aug 19 08:14:26.787784 systemd[2135]: Reached target timers.target - Timers. Aug 19 08:14:26.788985 systemd[2135]: Starting dbus.socket - D-Bus User Message Bus Socket... Aug 19 08:14:26.799491 systemd[2135]: Listening on dbus.socket - D-Bus User Message Bus Socket. Aug 19 08:14:26.799549 systemd[2135]: Reached target sockets.target - Sockets. Aug 19 08:14:26.799597 systemd[2135]: Reached target basic.target - Basic System. Aug 19 08:14:26.799661 systemd[2135]: Reached target default.target - Main User Target. Aug 19 08:14:26.799693 systemd[2135]: Startup finished in 1.362s. Aug 19 08:14:26.799753 systemd[1]: Started user@500.service - User Manager for UID 500. Aug 19 08:14:26.801146 systemd[1]: Started session-1.scope - Session 1 of User core. Aug 19 08:14:26.801871 systemd[1]: Started session-2.scope - Session 2 of User core. Aug 19 08:14:26.935494 waagent[2032]: 2025-08-19T08:14:26.935411Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Aug 19 08:14:27.064657 kernel: EXT4-fs (sdb1): mounted filesystem da293892-bca2-4e38-a3f1-d9b3228379e7 r/w with ordered data mode. Quota mode: none. Aug 19 08:14:27.064778 waagent[2032]: 2025-08-19T08:14:27.064639Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Aug 19 08:14:27.066870 waagent[2032]: 2025-08-19T08:14:27.066820Z INFO Daemon Daemon Clean protocol and wireserver endpoint Aug 19 08:14:27.067143 waagent[2032]: 2025-08-19T08:14:27.067105Z INFO Daemon Daemon Running default provisioning handler Aug 19 08:14:27.077727 waagent[2032]: 2025-08-19T08:14:27.077669Z 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. Aug 19 08:14:27.078282 waagent[2032]: 2025-08-19T08:14:27.078240Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Aug 19 08:14:27.078757 waagent[2032]: 2025-08-19T08:14:27.078722Z INFO Daemon Daemon cloud-init is enabled: False Aug 19 08:14:27.079532 waagent[2032]: 2025-08-19T08:14:27.079500Z INFO Daemon Daemon Copying ovf-env.xml Aug 19 08:14:27.187323 waagent[2032]: 2025-08-19T08:14:27.187241Z INFO Daemon Daemon Successfully mounted dvd Aug 19 08:14:27.200575 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Aug 19 08:14:27.200739 waagent[2032]: 2025-08-19T08:14:27.200634Z INFO Daemon Daemon Detect protocol endpoint Aug 19 08:14:27.202269 waagent[2032]: 2025-08-19T08:14:27.202227Z INFO Daemon Daemon Clean protocol and wireserver endpoint Aug 19 08:14:27.203842 waagent[2032]: 2025-08-19T08:14:27.203751Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Aug 19 08:14:27.205260 waagent[2032]: 2025-08-19T08:14:27.205215Z INFO Daemon Daemon Test for route to 168.63.129.16 Aug 19 08:14:27.206986 waagent[2032]: 2025-08-19T08:14:27.206899Z INFO Daemon Daemon Route to 168.63.129.16 exists Aug 19 08:14:27.208229 waagent[2032]: 2025-08-19T08:14:27.208192Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Aug 19 08:14:27.220369 waagent[2032]: 2025-08-19T08:14:27.220322Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Aug 19 08:14:27.221820 waagent[2032]: 2025-08-19T08:14:27.221793Z INFO Daemon Daemon Wire protocol version:2012-11-30 Aug 19 08:14:27.222449 waagent[2032]: 2025-08-19T08:14:27.222419Z INFO Daemon Daemon Server preferred version:2015-04-05 Aug 19 08:14:27.365138 waagent[2032]: 2025-08-19T08:14:27.365063Z INFO Daemon Daemon Initializing goal state during protocol detection Aug 19 08:14:27.366679 waagent[2032]: 2025-08-19T08:14:27.366633Z INFO Daemon Daemon Forcing an update of the goal state. Aug 19 08:14:27.372769 waagent[2032]: 2025-08-19T08:14:27.372587Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Aug 19 08:14:27.399716 waagent[2032]: 2025-08-19T08:14:27.399677Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.175 Aug 19 08:14:27.402206 waagent[2032]: 2025-08-19T08:14:27.402164Z INFO Daemon Aug 19 08:14:27.402590 waagent[2032]: 2025-08-19T08:14:27.402555Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: dcf36b45-5b95-497c-8829-aecbab706206 eTag: 12086659239738758255 source: Fabric] Aug 19 08:14:27.403586 waagent[2032]: 2025-08-19T08:14:27.403545Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Aug 19 08:14:27.404302 waagent[2032]: 2025-08-19T08:14:27.404264Z INFO Daemon Aug 19 08:14:27.405520 waagent[2032]: 2025-08-19T08:14:27.405486Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Aug 19 08:14:27.410664 waagent[2032]: 2025-08-19T08:14:27.410613Z INFO Daemon Daemon Downloading artifacts profile blob Aug 19 08:14:27.537250 waagent[2032]: 2025-08-19T08:14:27.537191Z INFO Daemon Downloaded certificate {'thumbprint': '6DEF7512225B192C0244FC739BA5447855DE5D0E', 'hasPrivateKey': True} Aug 19 08:14:27.539258 waagent[2032]: 2025-08-19T08:14:27.539218Z INFO Daemon Fetch goal state completed Aug 19 08:14:27.571497 waagent[2032]: 2025-08-19T08:14:27.571438Z INFO Daemon Daemon Starting provisioning Aug 19 08:14:27.575269 waagent[2032]: 2025-08-19T08:14:27.574334Z INFO Daemon Daemon Handle ovf-env.xml. Aug 19 08:14:27.578191 waagent[2032]: 2025-08-19T08:14:27.578137Z INFO Daemon Daemon Set hostname [ci-4426.0.0-a-9bb0dea2fd] Aug 19 08:14:27.603672 waagent[2032]: 2025-08-19T08:14:27.603601Z INFO Daemon Daemon Publish hostname [ci-4426.0.0-a-9bb0dea2fd] Aug 19 08:14:27.607223 waagent[2032]: 2025-08-19T08:14:27.607169Z INFO Daemon Daemon Examine /proc/net/route for primary interface Aug 19 08:14:27.608663 waagent[2032]: 2025-08-19T08:14:27.608610Z INFO Daemon Daemon Primary interface is [eth0] Aug 19 08:14:27.627524 systemd-networkd[1592]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 19 08:14:27.627535 systemd-networkd[1592]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Aug 19 08:14:27.627561 systemd-networkd[1592]: eth0: DHCP lease lost Aug 19 08:14:27.628014 waagent[2032]: 2025-08-19T08:14:27.627967Z INFO Daemon Daemon Create user account if not exists Aug 19 08:14:27.629795 waagent[2032]: 2025-08-19T08:14:27.629601Z INFO Daemon Daemon User core already exists, skip useradd Aug 19 08:14:27.630331 waagent[2032]: 2025-08-19T08:14:27.630292Z INFO Daemon Daemon Configure sudoer Aug 19 08:14:27.637935 waagent[2032]: 2025-08-19T08:14:27.637891Z INFO Daemon Daemon Configure sshd Aug 19 08:14:27.643529 waagent[2032]: 2025-08-19T08:14:27.643486Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Aug 19 08:14:27.646379 waagent[2032]: 2025-08-19T08:14:27.646328Z INFO Daemon Daemon Deploy ssh public key. Aug 19 08:14:27.708902 systemd-networkd[1592]: eth0: DHCPv4 address 10.200.8.16/24, gateway 10.200.8.1 acquired from 168.63.129.16 Aug 19 08:14:28.756082 waagent[2032]: 2025-08-19T08:14:28.756027Z INFO Daemon Daemon Provisioning complete Aug 19 08:14:28.766410 waagent[2032]: 2025-08-19T08:14:28.766367Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Aug 19 08:14:28.768084 waagent[2032]: 2025-08-19T08:14:28.768030Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Aug 19 08:14:28.769571 waagent[2032]: 2025-08-19T08:14:28.769530Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Aug 19 08:14:28.958357 waagent[2197]: 2025-08-19T08:14:28.958266Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Aug 19 08:14:28.958710 waagent[2197]: 2025-08-19T08:14:28.958407Z INFO ExtHandler ExtHandler OS: flatcar 4426.0.0 Aug 19 08:14:28.958710 waagent[2197]: 2025-08-19T08:14:28.958476Z INFO ExtHandler ExtHandler Python: 3.11.13 Aug 19 08:14:28.958710 waagent[2197]: 2025-08-19T08:14:28.958542Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Aug 19 08:14:29.038748 waagent[2197]: 2025-08-19T08:14:29.038585Z INFO ExtHandler ExtHandler Distro: flatcar-4426.0.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.13; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Aug 19 08:14:29.038910 waagent[2197]: 2025-08-19T08:14:29.038868Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Aug 19 08:14:29.039005 waagent[2197]: 2025-08-19T08:14:29.038959Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Aug 19 08:14:29.045717 waagent[2197]: 2025-08-19T08:14:29.045659Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Aug 19 08:14:29.050494 waagent[2197]: 2025-08-19T08:14:29.050457Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.175 Aug 19 08:14:29.050951 waagent[2197]: 2025-08-19T08:14:29.050911Z INFO ExtHandler Aug 19 08:14:29.051075 waagent[2197]: 2025-08-19T08:14:29.051031Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 2baf5f62-f1d3-4d3e-a0fa-08feac228a8c eTag: 12086659239738758255 source: Fabric] Aug 19 08:14:29.051402 waagent[2197]: 2025-08-19T08:14:29.051362Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Aug 19 08:14:29.052024 waagent[2197]: 2025-08-19T08:14:29.051984Z INFO ExtHandler Aug 19 08:14:29.052099 waagent[2197]: 2025-08-19T08:14:29.052067Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Aug 19 08:14:29.057099 waagent[2197]: 2025-08-19T08:14:29.057057Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Aug 19 08:14:29.132481 waagent[2197]: 2025-08-19T08:14:29.132425Z INFO ExtHandler Downloaded certificate {'thumbprint': '6DEF7512225B192C0244FC739BA5447855DE5D0E', 'hasPrivateKey': True} Aug 19 08:14:29.132980 waagent[2197]: 2025-08-19T08:14:29.132938Z INFO ExtHandler Fetch goal state completed Aug 19 08:14:29.144257 waagent[2197]: 2025-08-19T08:14:29.144219Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.4.1 11 Feb 2025 (Library: OpenSSL 3.4.1 11 Feb 2025) Aug 19 08:14:29.149024 waagent[2197]: 2025-08-19T08:14:29.148982Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2197 Aug 19 08:14:29.149162 waagent[2197]: 2025-08-19T08:14:29.149126Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Aug 19 08:14:29.149488 waagent[2197]: 2025-08-19T08:14:29.149451Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Aug 19 08:14:29.151297 waagent[2197]: 2025-08-19T08:14:29.151249Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4426.0.0', '', 'Flatcar Container Linux by Kinvolk'] Aug 19 08:14:29.151776 waagent[2197]: 2025-08-19T08:14:29.151736Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4426.0.0', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Aug 19 08:14:29.151931 waagent[2197]: 2025-08-19T08:14:29.151895Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Aug 19 08:14:29.152517 waagent[2197]: 2025-08-19T08:14:29.152478Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Aug 19 08:14:29.279272 waagent[2197]: 2025-08-19T08:14:29.279220Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Aug 19 08:14:29.279473 waagent[2197]: 2025-08-19T08:14:29.279435Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Aug 19 08:14:29.288034 waagent[2197]: 2025-08-19T08:14:29.287993Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Aug 19 08:14:29.301018 systemd[1]: Reload requested from client PID 2212 ('systemctl') (unit waagent.service)... Aug 19 08:14:29.301034 systemd[1]: Reloading... Aug 19 08:14:29.368655 zram_generator::config[2255]: No configuration found. Aug 19 08:14:29.669386 systemd[1]: Reloading finished in 367 ms. Aug 19 08:14:29.692252 waagent[2197]: 2025-08-19T08:14:29.692182Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Aug 19 08:14:29.692360 waagent[2197]: 2025-08-19T08:14:29.692324Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Aug 19 08:14:30.591251 waagent[2197]: 2025-08-19T08:14:30.591167Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Aug 19 08:14:30.591593 waagent[2197]: 2025-08-19T08:14:30.591505Z 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] Aug 19 08:14:30.592302 waagent[2197]: 2025-08-19T08:14:30.592255Z INFO ExtHandler ExtHandler Starting env monitor service. Aug 19 08:14:30.592668 waagent[2197]: 2025-08-19T08:14:30.592600Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Aug 19 08:14:30.592861 waagent[2197]: 2025-08-19T08:14:30.592821Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Aug 19 08:14:30.593025 waagent[2197]: 2025-08-19T08:14:30.592986Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Aug 19 08:14:30.593421 waagent[2197]: 2025-08-19T08:14:30.593368Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Aug 19 08:14:30.593520 waagent[2197]: 2025-08-19T08:14:30.593479Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Aug 19 08:14:30.593752 waagent[2197]: 2025-08-19T08:14:30.593708Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Aug 19 08:14:30.593790 waagent[2197]: 2025-08-19T08:14:30.593754Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Aug 19 08:14:30.594038 waagent[2197]: 2025-08-19T08:14:30.594002Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Aug 19 08:14:30.594506 waagent[2197]: 2025-08-19T08:14:30.594443Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Aug 19 08:14:30.594651 waagent[2197]: 2025-08-19T08:14:30.594590Z INFO EnvHandler ExtHandler Configure routes Aug 19 08:14:30.594737 waagent[2197]: 2025-08-19T08:14:30.594687Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Aug 19 08:14:30.594972 waagent[2197]: 2025-08-19T08:14:30.594935Z INFO EnvHandler ExtHandler Gateway:None Aug 19 08:14:30.595053 waagent[2197]: 2025-08-19T08:14:30.595012Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Aug 19 08:14:30.595335 waagent[2197]: 2025-08-19T08:14:30.595290Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Aug 19 08:14:30.595335 waagent[2197]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Aug 19 08:14:30.595335 waagent[2197]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Aug 19 08:14:30.595335 waagent[2197]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Aug 19 08:14:30.595335 waagent[2197]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Aug 19 08:14:30.595335 waagent[2197]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Aug 19 08:14:30.595335 waagent[2197]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Aug 19 08:14:30.595489 waagent[2197]: 2025-08-19T08:14:30.595409Z INFO EnvHandler ExtHandler Routes:None Aug 19 08:14:30.603309 waagent[2197]: 2025-08-19T08:14:30.603262Z INFO ExtHandler ExtHandler Aug 19 08:14:30.603372 waagent[2197]: 2025-08-19T08:14:30.603346Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 703674a1-58e6-44cd-b1a7-dc7b97efaf90 correlation 2c085e3b-04b6-432e-9ad4-ff8a5d537007 created: 2025-08-19T08:12:34.439758Z] Aug 19 08:14:30.603739 waagent[2197]: 2025-08-19T08:14:30.603697Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Aug 19 08:14:30.604317 waagent[2197]: 2025-08-19T08:14:30.604279Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 1 ms] Aug 19 08:14:30.752488 waagent[2197]: 2025-08-19T08:14:30.752419Z 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 Aug 19 08:14:30.752488 waagent[2197]: Try `iptables -h' or 'iptables --help' for more information.) Aug 19 08:14:30.752964 waagent[2197]: 2025-08-19T08:14:30.752923Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: BACA1C3C-148A-4148-A046-35B6070ABBA3;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Aug 19 08:14:30.803968 waagent[2197]: 2025-08-19T08:14:30.803910Z INFO MonitorHandler ExtHandler Network interfaces: Aug 19 08:14:30.803968 waagent[2197]: Executing ['ip', '-a', '-o', 'link']: Aug 19 08:14:30.803968 waagent[2197]: 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 Aug 19 08:14:30.803968 waagent[2197]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:ed:8d:48:d9:b4 brd ff:ff:ff:ff:ff:ff Aug 19 08:14:30.803968 waagent[2197]: Executing ['ip', '-4', '-a', '-o', 'address']: Aug 19 08:14:30.803968 waagent[2197]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Aug 19 08:14:30.803968 waagent[2197]: 2: eth0 inet 10.200.8.16/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Aug 19 08:14:30.803968 waagent[2197]: Executing ['ip', '-6', '-a', '-o', 'address']: Aug 19 08:14:30.803968 waagent[2197]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Aug 19 08:14:30.803968 waagent[2197]: 2: eth0 inet6 fe80::7eed:8dff:fe48:d9b4/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Aug 19 08:14:30.996156 waagent[2197]: 2025-08-19T08:14:30.996084Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Aug 19 08:14:30.996156 waagent[2197]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Aug 19 08:14:30.996156 waagent[2197]: pkts bytes target prot opt in out source destination Aug 19 08:14:30.996156 waagent[2197]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Aug 19 08:14:30.996156 waagent[2197]: pkts bytes target prot opt in out source destination Aug 19 08:14:30.996156 waagent[2197]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Aug 19 08:14:30.996156 waagent[2197]: pkts bytes target prot opt in out source destination Aug 19 08:14:30.996156 waagent[2197]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Aug 19 08:14:30.996156 waagent[2197]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Aug 19 08:14:30.996156 waagent[2197]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Aug 19 08:14:30.999480 waagent[2197]: 2025-08-19T08:14:30.999441Z INFO EnvHandler ExtHandler Current Firewall rules: Aug 19 08:14:30.999480 waagent[2197]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Aug 19 08:14:30.999480 waagent[2197]: pkts bytes target prot opt in out source destination Aug 19 08:14:30.999480 waagent[2197]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Aug 19 08:14:30.999480 waagent[2197]: pkts bytes target prot opt in out source destination Aug 19 08:14:30.999480 waagent[2197]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Aug 19 08:14:30.999480 waagent[2197]: pkts bytes target prot opt in out source destination Aug 19 08:14:30.999480 waagent[2197]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Aug 19 08:14:30.999480 waagent[2197]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Aug 19 08:14:30.999480 waagent[2197]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Aug 19 08:14:30.999752 waagent[2197]: 2025-08-19T08:14:30.999711Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Aug 19 08:14:30.999894 waagent[2197]: 2025-08-19T08:14:30.999861Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Aug 19 08:14:38.954462 setup-nvidia[2046]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 476M 0 44619 0 0 53333 0 2:36:02 --:--:-- 2:36:02 53308\u000d 1 476M 1 6768k 0 0 3771k 0 0:02:09 0:00:01 0:02:08 3770k\u000d 6 476M 6 29.8M 0 0 10.7M 0 0:00:44 0:00:02 0:00:42 10.7M\u000d 11 476M 11 53.4M 0 0 14.1M 0 0:00:33 0:00:03 0:00:30 14.1M\u000d 16 476M 16 77.0M 0 0 16.1M 0 0:00:29 0:00:04 0:00:25 16.1M\u000d 21 476M 21 102M 0 0 17.7M 0 0:00:26 0:00:05 0:00:21 20.7M\u000d 26 476M 26 127M 0 0 18.8M 0 0:00:25 0:00:06 0:00:19 24.3M\u000d 32 476M 32 152M 0 0 19.6M 0 0:00:24 0:00:07 0:00:17 24.6M\u000d 37 476M 37 176M 0 0 20.0M 0 0:00:23 0:00:08 0:00:15 24.4M\u000d 42 476M 42 201M 0 0 20.4M 0 0:00:23 0:00:09 0:00:14 24.6M\u000d 47 476M 47 226M 0 0 20.9M 0 0:00:22 0:00:10 0:00:12 24.6M\u000d 52 476M 52 250M 0 0 21.1M 0 0:00:22 0:00:11 0:00:11 24.3M\u000d 57 476M 57 274M 0 0 21.4M 0 0:00:22 0:00:12 0:00:10 24.1M\u000d 62 476M 62 299M 0 0 21.6M 0 0:00:21 0:00:13 0:00:08 24.6M\u000d 67 476M 67 323M 0 0 21.8M 0 0:00:21 0:00:14 0:00:07 24.6M\u000d 73 476M 73 347M 0 0 22.0M 0 0:00:21 0:00:15 0:00:06 24.4M\u000d 78 476M 78 372M 0 0 22.1M 0 0:00:21 0:00:16 0:00:05 24.5M\u000d 83 476M 83 395M 0 0 22.2M 0 0:00:21 0:00:17 0:00:04 24.4M\u000d 88 476M 88 422M 0 0 22.5M 0 0:00:21 0:00:18 0:00:03 24.8M\u000d 94 476M 94 448M 0 0 22.7M 0 0:00:20 0:00:19 0:00:01 25.1M\u000d 99 476M 99 474M 0 0 22.7M 0 0:00:20 0:00:20 --:--:-- 24.8M\u000d100 476M 100 476M 0 0 22.8M 0 0:00:20 0:00:20 --:--:-- 25.2M Aug 19 08:14:40.920375 chronyd[1867]: Selected source PHC0 Aug 19 08:14:49.091363 setup-nvidia[1880]: Downloading NVIDIA 535.247.01 Driver Aug 19 08:14:49.095784 setup-nvidia[2355]: % Total % Received % Xferd Average Speed Time Time Time Current Aug 19 08:14:49.096014 setup-nvidia[2355]: Dload Upload Total Spent Left Speed Aug 19 08:14:51.566915 setup-nvidia[2355]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 10 326M 10 33.3M 0 0 45.3M 0 0:00:07 --:--:-- 0:00:07 45.3M\u000d 49 326M 49 161M 0 0 92.7M 0 0:00:03 0:00:01 0:00:02 92.7M\u000d100 326M 100 326M 0 0 131M 0 0:00:02 0:00:02 --:--:-- 131M Aug 19 08:14:51.613484 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Aug 19 08:14:51.616226 systemd[1]: Started sshd@0-10.200.8.16:22-10.200.16.10:40322.service - OpenSSH per-connection server daemon (10.200.16.10:40322). Aug 19 08:14:51.658488 setup-nvidia[1880]: Extract the NVIDIA Driver Installer 535.247.01 Aug 19 08:14:51.658696 setup-nvidia[1880]: /opt/nvidia/workdir/nvidia-workdir / Aug 19 08:14:51.683071 setup-nvidia[2360]: Creating directory NVIDIA-Linux-x86_64-535.247.01 Aug 19 08:14:52.004767 setup-nvidia[2360]: Verifying archive integrity... OK Aug 19 08:14:52.617979 sshd[2358]: Accepted publickey for core from 10.200.16.10 port 40322 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:14:52.619271 sshd-session[2358]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:14:52.624060 systemd-logind[1889]: New session 3 of user core. Aug 19 08:14:52.635760 systemd[1]: Started session-3.scope - Session 3 of User core. Aug 19 08:14:52.836376 setup-nvidia[2360]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.247.01 Aug 19 08:14:53.209173 systemd[1]: Started sshd@1-10.200.8.16:22-10.200.16.10:40324.service - OpenSSH per-connection server daemon (10.200.16.10:40324). Aug 19 08:14:53.874643 sshd[2395]: Accepted publickey for core from 10.200.16.10 port 40324 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:14:53.876181 sshd-session[2395]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:14:53.880364 systemd-logind[1889]: New session 4 of user core. Aug 19 08:14:53.891772 systemd[1]: Started session-4.scope - Session 4 of User core. Aug 19 08:14:54.344553 sshd[2398]: Connection closed by 10.200.16.10 port 40324 Aug 19 08:14:54.344950 sshd-session[2395]: pam_unix(sshd:session): session closed for user core Aug 19 08:14:54.348701 systemd[1]: sshd@1-10.200.8.16:22-10.200.16.10:40324.service: Deactivated successfully. Aug 19 08:14:54.350345 systemd[1]: session-4.scope: Deactivated successfully. Aug 19 08:14:54.351479 systemd-logind[1889]: Session 4 logged out. Waiting for processes to exit. Aug 19 08:14:54.352545 systemd-logind[1889]: Removed session 4. Aug 19 08:14:54.466508 systemd[1]: Started sshd@2-10.200.8.16:22-10.200.16.10:40326.service - OpenSSH per-connection server daemon (10.200.16.10:40326). Aug 19 08:14:54.611979 setup-nvidia[2389]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Aug 19 08:14:54.714926 setup-nvidia[1880]: / Aug 19 08:14:54.714926 setup-nvidia[1880]: Spawn system-nspawn container to install the NVIDIA drivers Aug 19 08:14:55.009662 sudo[2410]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4426.0.0.bin --overlay=/usr/lib/modules::/usr/lib/modules --bind=/opt/nvidia/workdir/nvidia-workdir:/nvidia --bind=/usr/lib/nvidia/bin:/app/bin/ /app/bin/install-nvidia NVIDIA-Linux-x86_64-535.247.01 Aug 19 08:14:55.011376 sudo[2410]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 19 08:14:55.017154 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Aug 19 08:14:55.022504 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Aug 19 08:14:55.043400 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Aug 19 08:14:55.050816 systemd[1]: Starting user@0.service - User Manager for UID 0... Aug 19 08:14:55.057966 (systemd)[2413]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Aug 19 08:14:55.060037 systemd-logind[1889]: New session c3 of user root. Aug 19 08:14:55.078655 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Aug 19 08:14:55.143136 sshd[2404]: Accepted publickey for core from 10.200.16.10 port 40326 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:14:55.144391 sshd-session[2404]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:14:55.148640 systemd-logind[1889]: New session 5 of user core. Aug 19 08:14:55.155754 systemd[1]: Started session-5.scope - Session 5 of User core. Aug 19 08:14:55.246641 systemd[2413]: Queued start job for default target default.target. Aug 19 08:14:55.270706 systemd[2413]: Created slice app.slice - User Application Slice. Aug 19 08:14:55.270735 systemd[2413]: Reached target paths.target - Paths. Aug 19 08:14:55.270781 systemd[2413]: Reached target timers.target - Timers. Aug 19 08:14:55.271964 systemd[2413]: Starting dbus.socket - D-Bus User Message Bus Socket... Aug 19 08:14:55.282484 systemd[2413]: Listening on dbus.socket - D-Bus User Message Bus Socket. Aug 19 08:14:55.282542 systemd[2413]: Reached target sockets.target - Sockets. Aug 19 08:14:55.282592 systemd[2413]: Reached target basic.target - Basic System. Aug 19 08:14:55.282713 systemd[2413]: Reached target default.target - Main User Target. Aug 19 08:14:55.282753 systemd[1]: Started user@0.service - User Manager for UID 0. Aug 19 08:14:55.282756 systemd[2413]: Startup finished in 216ms. Aug 19 08:14:55.285754 systemd[1]: Started session-c2.scope - Session c2 of User root. Aug 19 08:14:55.408652 kernel: loop6: detected capacity change from 0 to 12644352 Aug 19 08:14:55.416649 kernel: loop6: p9 Aug 19 08:14:55.613150 sshd[2419]: Connection closed by 10.200.16.10 port 40326 Aug 19 08:14:55.613525 sshd-session[2404]: pam_unix(sshd:session): session closed for user core Aug 19 08:14:55.615856 setup-nvidia[2423]: ░ Spawning container flatcardevelopercontainer-4426.0.0.bin on /opt/nvidia/workdir/flatcar_developer_container-4426.0.0.bin. Aug 19 08:14:55.621315 systemd[1]: sshd@2-10.200.8.16:22-10.200.16.10:40326.service: Deactivated successfully. Aug 19 08:14:55.623101 systemd[1]: session-5.scope: Deactivated successfully. Aug 19 08:14:55.627448 systemd-logind[1889]: Session 5 logged out. Waiting for processes to exit. Aug 19 08:14:55.628884 systemd-logind[1889]: Removed session 5. Aug 19 08:14:56.821847 kernel: EXT4-fs (loop6p9): write access unavailable, skipping orphan cleanup Aug 19 08:14:56.821966 kernel: EXT4-fs (loop6p9): mounted filesystem 1c457d39-febc-4c4d-8be4-692431dfc416 ro without journal. Quota mode: none. Aug 19 08:15:02.644136 update_engine[1891]: I20250819 08:15:02.644049 1891 update_attempter.cc:509] Updating boot flags... Aug 19 08:15:05.729021 systemd[1]: Started sshd@3-10.200.8.16:22-10.200.16.10:56156.service - OpenSSH per-connection server daemon (10.200.16.10:56156). Aug 19 08:15:06.368367 sshd[2661]: Accepted publickey for core from 10.200.16.10 port 56156 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:15:06.369766 sshd-session[2661]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:15:06.374068 systemd-logind[1889]: New session 6 of user core. Aug 19 08:15:06.393775 systemd[1]: Started session-6.scope - Session 6 of User core. Aug 19 08:15:06.823267 sshd[2706]: Connection closed by 10.200.16.10 port 56156 Aug 19 08:15:06.823603 sshd-session[2661]: pam_unix(sshd:session): session closed for user core Aug 19 08:15:06.827241 systemd[1]: sshd@3-10.200.8.16:22-10.200.16.10:56156.service: Deactivated successfully. Aug 19 08:15:06.828776 systemd[1]: session-6.scope: Deactivated successfully. Aug 19 08:15:06.829486 systemd-logind[1889]: Session 6 logged out. Waiting for processes to exit. Aug 19 08:15:06.830762 systemd-logind[1889]: Removed session 6. Aug 19 08:15:06.936159 systemd[1]: Started sshd@4-10.200.8.16:22-10.200.16.10:56162.service - OpenSSH per-connection server daemon (10.200.16.10:56162). Aug 19 08:15:07.598069 sshd[2719]: Accepted publickey for core from 10.200.16.10 port 56162 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:15:07.599335 sshd-session[2719]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:15:07.603559 systemd-logind[1889]: New session 7 of user core. Aug 19 08:15:07.613779 systemd[1]: Started session-7.scope - Session 7 of User core. Aug 19 08:15:08.053503 sshd[2798]: Connection closed by 10.200.16.10 port 56162 Aug 19 08:15:08.053803 sshd-session[2719]: pam_unix(sshd:session): session closed for user core Aug 19 08:15:08.057362 systemd[1]: sshd@4-10.200.8.16:22-10.200.16.10:56162.service: Deactivated successfully. Aug 19 08:15:08.058942 systemd[1]: session-7.scope: Deactivated successfully. Aug 19 08:15:08.059651 systemd-logind[1889]: Session 7 logged out. Waiting for processes to exit. Aug 19 08:15:08.060541 systemd-logind[1889]: Removed session 7. Aug 19 08:15:18.167122 systemd[1]: Started sshd@5-10.200.8.16:22-10.200.16.10:54628.service - OpenSSH per-connection server daemon (10.200.16.10:54628). Aug 19 08:15:18.816094 sshd[3337]: Accepted publickey for core from 10.200.16.10 port 54628 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:15:18.817387 sshd-session[3337]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:15:18.821596 systemd-logind[1889]: New session 8 of user core. Aug 19 08:15:18.831771 systemd[1]: Started session-8.scope - Session 8 of User core. Aug 19 08:15:19.278802 sshd[3340]: Connection closed by 10.200.16.10 port 54628 Aug 19 08:15:19.279158 sshd-session[3337]: pam_unix(sshd:session): session closed for user core Aug 19 08:15:19.282195 systemd[1]: sshd@5-10.200.8.16:22-10.200.16.10:54628.service: Deactivated successfully. Aug 19 08:15:19.283851 systemd[1]: session-8.scope: Deactivated successfully. Aug 19 08:15:19.285980 systemd-logind[1889]: Session 8 logged out. Waiting for processes to exit. Aug 19 08:15:19.286951 systemd-logind[1889]: Removed session 8. Aug 19 08:15:19.397178 systemd[1]: Started sshd@6-10.200.8.16:22-10.200.16.10:54632.service - OpenSSH per-connection server daemon (10.200.16.10:54632). Aug 19 08:15:20.038751 sshd[3346]: Accepted publickey for core from 10.200.16.10 port 54632 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:15:20.040043 sshd-session[3346]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:15:20.044201 systemd-logind[1889]: New session 9 of user core. Aug 19 08:15:20.050749 systemd[1]: Started session-9.scope - Session 9 of User core. Aug 19 08:15:20.490922 sshd[3393]: Connection closed by 10.200.16.10 port 54632 Aug 19 08:15:20.491292 sshd-session[3346]: pam_unix(sshd:session): session closed for user core Aug 19 08:15:20.494874 systemd[1]: sshd@6-10.200.8.16:22-10.200.16.10:54632.service: Deactivated successfully. Aug 19 08:15:20.496618 systemd[1]: session-9.scope: Deactivated successfully. Aug 19 08:15:20.497313 systemd-logind[1889]: Session 9 logged out. Waiting for processes to exit. Aug 19 08:15:20.498210 systemd-logind[1889]: Removed session 9. Aug 19 08:15:30.610026 systemd[1]: Started sshd@7-10.200.8.16:22-10.200.16.10:39350.service - OpenSSH per-connection server daemon (10.200.16.10:39350). Aug 19 08:15:31.251816 sshd[3496]: Accepted publickey for core from 10.200.16.10 port 39350 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:15:31.253093 sshd-session[3496]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:15:31.257219 systemd-logind[1889]: New session 10 of user core. Aug 19 08:15:31.267835 systemd[1]: Started session-10.scope - Session 10 of User core. Aug 19 08:15:31.711838 sshd[3499]: Connection closed by 10.200.16.10 port 39350 Aug 19 08:15:31.712240 sshd-session[3496]: pam_unix(sshd:session): session closed for user core Aug 19 08:15:31.716590 systemd[1]: sshd@7-10.200.8.16:22-10.200.16.10:39350.service: Deactivated successfully. Aug 19 08:15:31.718196 systemd[1]: session-10.scope: Deactivated successfully. Aug 19 08:15:31.718879 systemd-logind[1889]: Session 10 logged out. Waiting for processes to exit. Aug 19 08:15:31.719777 systemd-logind[1889]: Removed session 10. Aug 19 08:15:31.853959 systemd[1]: Started sshd@8-10.200.8.16:22-10.200.16.10:39356.service - OpenSSH per-connection server daemon (10.200.16.10:39356). Aug 19 08:15:32.516061 sshd[3506]: Accepted publickey for core from 10.200.16.10 port 39356 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:15:32.517329 sshd-session[3506]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:15:32.521946 systemd-logind[1889]: New session 11 of user core. Aug 19 08:15:32.532759 systemd[1]: Started session-11.scope - Session 11 of User core. Aug 19 08:15:32.967998 sshd[3509]: Connection closed by 10.200.16.10 port 39356 Aug 19 08:15:32.968426 sshd-session[3506]: pam_unix(sshd:session): session closed for user core Aug 19 08:15:32.972061 systemd[1]: sshd@8-10.200.8.16:22-10.200.16.10:39356.service: Deactivated successfully. Aug 19 08:15:32.973692 systemd[1]: session-11.scope: Deactivated successfully. Aug 19 08:15:32.974712 systemd-logind[1889]: Session 11 logged out. Waiting for processes to exit. Aug 19 08:15:32.975650 systemd-logind[1889]: Removed session 11. Aug 19 08:15:43.084393 systemd[1]: Started sshd@9-10.200.8.16:22-10.200.16.10:48966.service - OpenSSH per-connection server daemon (10.200.16.10:48966). Aug 19 08:15:43.728387 sshd[7050]: Accepted publickey for core from 10.200.16.10 port 48966 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:15:43.728949 sshd-session[7050]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:15:43.737232 systemd-logind[1889]: New session 12 of user core. Aug 19 08:15:43.746663 systemd[1]: Started session-12.scope - Session 12 of User core. Aug 19 08:15:44.182264 sshd[7264]: Connection closed by 10.200.16.10 port 48966 Aug 19 08:15:44.184621 sshd-session[7050]: pam_unix(sshd:session): session closed for user core Aug 19 08:15:44.188295 systemd[1]: sshd@9-10.200.8.16:22-10.200.16.10:48966.service: Deactivated successfully. Aug 19 08:15:44.191052 systemd[1]: session-12.scope: Deactivated successfully. Aug 19 08:15:44.192368 systemd-logind[1889]: Session 12 logged out. Waiting for processes to exit. Aug 19 08:15:44.194588 systemd-logind[1889]: Removed session 12. Aug 19 08:15:44.307894 systemd[1]: Started sshd@10-10.200.8.16:22-10.200.16.10:48974.service - OpenSSH per-connection server daemon (10.200.16.10:48974). Aug 19 08:15:44.965896 sshd[7462]: Accepted publickey for core from 10.200.16.10 port 48974 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:15:44.967660 sshd-session[7462]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:15:44.975687 systemd-logind[1889]: New session 13 of user core. Aug 19 08:15:44.983795 systemd[1]: Started session-13.scope - Session 13 of User core. Aug 19 08:15:45.430740 sshd[7733]: Connection closed by 10.200.16.10 port 48974 Aug 19 08:15:45.431561 sshd-session[7462]: pam_unix(sshd:session): session closed for user core Aug 19 08:15:45.436328 systemd-logind[1889]: Session 13 logged out. Waiting for processes to exit. Aug 19 08:15:45.440019 systemd[1]: sshd@10-10.200.8.16:22-10.200.16.10:48974.service: Deactivated successfully. Aug 19 08:15:45.442400 systemd[1]: session-13.scope: Deactivated successfully. Aug 19 08:15:45.444808 systemd-logind[1889]: Removed session 13. Aug 19 08:15:55.574853 systemd[1]: Started sshd@11-10.200.8.16:22-10.200.16.10:60462.service - OpenSSH per-connection server daemon (10.200.16.10:60462). Aug 19 08:15:56.229315 sshd[11418]: Accepted publickey for core from 10.200.16.10 port 60462 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:15:56.230710 sshd-session[11418]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:15:56.234841 systemd-logind[1889]: New session 14 of user core. Aug 19 08:15:56.252769 systemd[1]: Started session-14.scope - Session 14 of User core. Aug 19 08:15:56.685831 sshd[11662]: Connection closed by 10.200.16.10 port 60462 Aug 19 08:15:56.686198 sshd-session[11418]: pam_unix(sshd:session): session closed for user core Aug 19 08:15:56.690023 systemd[1]: sshd@11-10.200.8.16:22-10.200.16.10:60462.service: Deactivated successfully. Aug 19 08:15:56.691642 systemd[1]: session-14.scope: Deactivated successfully. Aug 19 08:15:56.692340 systemd-logind[1889]: Session 14 logged out. Waiting for processes to exit. Aug 19 08:15:56.693230 systemd-logind[1889]: Removed session 14. Aug 19 08:15:56.802265 systemd[1]: Started sshd@12-10.200.8.16:22-10.200.16.10:60464.service - OpenSSH per-connection server daemon (10.200.16.10:60464). Aug 19 08:15:57.459722 sshd[11698]: Accepted publickey for core from 10.200.16.10 port 60464 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:15:57.461047 sshd-session[11698]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:15:57.466147 systemd-logind[1889]: New session 15 of user core. Aug 19 08:15:57.481815 systemd[1]: Started session-15.scope - Session 15 of User core. Aug 19 08:15:57.910211 sshd[11701]: Connection closed by 10.200.16.10 port 60464 Aug 19 08:15:57.912482 sshd-session[11698]: pam_unix(sshd:session): session closed for user core Aug 19 08:15:57.916189 systemd[1]: sshd@12-10.200.8.16:22-10.200.16.10:60464.service: Deactivated successfully. Aug 19 08:15:57.918420 systemd[1]: session-15.scope: Deactivated successfully. Aug 19 08:15:57.919780 systemd-logind[1889]: Session 15 logged out. Waiting for processes to exit. Aug 19 08:15:57.927007 systemd-logind[1889]: Removed session 15. Aug 19 08:16:08.027719 systemd[1]: Started sshd@13-10.200.8.16:22-10.200.16.10:42372.service - OpenSSH per-connection server daemon (10.200.16.10:42372). Aug 19 08:16:08.689245 sshd[12151]: Accepted publickey for core from 10.200.16.10 port 42372 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:16:08.690658 sshd-session[12151]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:16:08.698204 systemd-logind[1889]: New session 16 of user core. Aug 19 08:16:08.706979 systemd[1]: Started session-16.scope - Session 16 of User core. Aug 19 08:16:09.145449 sshd[12162]: Connection closed by 10.200.16.10 port 42372 Aug 19 08:16:09.145830 sshd-session[12151]: pam_unix(sshd:session): session closed for user core Aug 19 08:16:09.149270 systemd-logind[1889]: Session 16 logged out. Waiting for processes to exit. Aug 19 08:16:09.150057 systemd[1]: sshd@13-10.200.8.16:22-10.200.16.10:42372.service: Deactivated successfully. Aug 19 08:16:09.152190 systemd[1]: session-16.scope: Deactivated successfully. Aug 19 08:16:09.156404 systemd-logind[1889]: Removed session 16. Aug 19 08:16:09.264018 systemd[1]: Started sshd@14-10.200.8.16:22-10.200.16.10:42384.service - OpenSSH per-connection server daemon (10.200.16.10:42384). Aug 19 08:16:09.931389 sshd[12184]: Accepted publickey for core from 10.200.16.10 port 42384 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:16:09.935618 sshd-session[12184]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:16:09.944230 systemd-logind[1889]: New session 17 of user core. Aug 19 08:16:09.950766 systemd[1]: Started session-17.scope - Session 17 of User core. Aug 19 08:16:10.389159 sshd[12199]: Connection closed by 10.200.16.10 port 42384 Aug 19 08:16:10.389549 sshd-session[12184]: pam_unix(sshd:session): session closed for user core Aug 19 08:16:10.395451 systemd-logind[1889]: Session 17 logged out. Waiting for processes to exit. Aug 19 08:16:10.397171 systemd[1]: sshd@14-10.200.8.16:22-10.200.16.10:42384.service: Deactivated successfully. Aug 19 08:16:10.399161 systemd[1]: session-17.scope: Deactivated successfully. Aug 19 08:16:10.401246 systemd-logind[1889]: Removed session 17. Aug 19 08:16:20.509249 systemd[1]: Started sshd@15-10.200.8.16:22-10.200.16.10:57170.service - OpenSSH per-connection server daemon (10.200.16.10:57170). Aug 19 08:16:21.163062 sshd[12560]: Accepted publickey for core from 10.200.16.10 port 57170 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:16:21.164986 sshd-session[12560]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:16:21.174201 systemd-logind[1889]: New session 18 of user core. Aug 19 08:16:21.180708 systemd[1]: Started session-18.scope - Session 18 of User core. Aug 19 08:16:21.615650 sshd[12580]: Connection closed by 10.200.16.10 port 57170 Aug 19 08:16:21.617904 sshd-session[12560]: pam_unix(sshd:session): session closed for user core Aug 19 08:16:21.622224 systemd[1]: sshd@15-10.200.8.16:22-10.200.16.10:57170.service: Deactivated successfully. Aug 19 08:16:21.624742 systemd[1]: session-18.scope: Deactivated successfully. Aug 19 08:16:21.626448 systemd-logind[1889]: Session 18 logged out. Waiting for processes to exit. Aug 19 08:16:21.628018 systemd-logind[1889]: Removed session 18. Aug 19 08:16:21.729099 systemd[1]: Started sshd@16-10.200.8.16:22-10.200.16.10:57172.service - OpenSSH per-connection server daemon (10.200.16.10:57172). Aug 19 08:16:22.385576 sshd[12598]: Accepted publickey for core from 10.200.16.10 port 57172 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:16:22.386129 sshd-session[12598]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:16:22.394094 systemd-logind[1889]: New session 19 of user core. Aug 19 08:16:22.400805 systemd[1]: Started session-19.scope - Session 19 of User core. Aug 19 08:16:22.843230 sshd[12619]: Connection closed by 10.200.16.10 port 57172 Aug 19 08:16:22.845421 sshd-session[12598]: pam_unix(sshd:session): session closed for user core Aug 19 08:16:22.849006 systemd-logind[1889]: Session 19 logged out. Waiting for processes to exit. Aug 19 08:16:22.850642 systemd[1]: sshd@16-10.200.8.16:22-10.200.16.10:57172.service: Deactivated successfully. Aug 19 08:16:22.852279 systemd[1]: session-19.scope: Deactivated successfully. Aug 19 08:16:22.856120 systemd-logind[1889]: Removed session 19. Aug 19 08:16:32.962940 systemd[1]: Started sshd@17-10.200.8.16:22-10.200.16.10:43086.service - OpenSSH per-connection server daemon (10.200.16.10:43086). Aug 19 08:16:33.598721 sshd[12868]: Accepted publickey for core from 10.200.16.10 port 43086 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:16:33.600026 sshd-session[12868]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:16:33.604253 systemd-logind[1889]: New session 20 of user core. Aug 19 08:16:33.617755 systemd[1]: Started session-20.scope - Session 20 of User core. Aug 19 08:16:34.051043 sshd[12871]: Connection closed by 10.200.16.10 port 43086 Aug 19 08:16:34.051314 sshd-session[12868]: pam_unix(sshd:session): session closed for user core Aug 19 08:16:34.054846 systemd[1]: sshd@17-10.200.8.16:22-10.200.16.10:43086.service: Deactivated successfully. Aug 19 08:16:34.056842 systemd[1]: session-20.scope: Deactivated successfully. Aug 19 08:16:34.057548 systemd-logind[1889]: Session 20 logged out. Waiting for processes to exit. Aug 19 08:16:34.058451 systemd-logind[1889]: Removed session 20. Aug 19 08:16:34.196917 systemd[1]: Started sshd@18-10.200.8.16:22-10.200.16.10:43100.service - OpenSSH per-connection server daemon (10.200.16.10:43100). Aug 19 08:16:34.833939 sshd[12877]: Accepted publickey for core from 10.200.16.10 port 43100 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:16:34.835712 sshd-session[12877]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:16:34.839966 systemd-logind[1889]: New session 21 of user core. Aug 19 08:16:34.856836 systemd[1]: Started session-21.scope - Session 21 of User core. Aug 19 08:16:35.287093 sshd[12880]: Connection closed by 10.200.16.10 port 43100 Aug 19 08:16:35.287381 sshd-session[12877]: pam_unix(sshd:session): session closed for user core Aug 19 08:16:35.290840 systemd[1]: sshd@18-10.200.8.16:22-10.200.16.10:43100.service: Deactivated successfully. Aug 19 08:16:35.292457 systemd[1]: session-21.scope: Deactivated successfully. Aug 19 08:16:35.293150 systemd-logind[1889]: Session 21 logged out. Waiting for processes to exit. Aug 19 08:16:35.294055 systemd-logind[1889]: Removed session 21. Aug 19 08:16:37.873703 setup-nvidia[2423]: \u000d Aug 19 08:16:37.873703 setup-nvidia[2423]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Aug 19 08:16:37.873703 setup-nvidia[2423]: frequently when this kernel module was built against the wrong or Aug 19 08:16:37.873703 setup-nvidia[2423]: improperly configured kernel sources, with a version of gcc that differs Aug 19 08:16:37.873703 setup-nvidia[2423]: from the one used to build the target kernel, or if another driver, such Aug 19 08:16:37.873703 setup-nvidia[2423]: as nouveau, is present and prevents the NVIDIA kernel module from Aug 19 08:16:37.873703 setup-nvidia[2423]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Aug 19 08:16:37.873703 setup-nvidia[2423]: installed in this system is supported by this NVIDIA Linux graphics Aug 19 08:16:37.873703 setup-nvidia[2423]: driver release. Aug 19 08:16:37.873703 setup-nvidia[2423]: \u000d Aug 19 08:16:37.873703 setup-nvidia[2423]: Please see the log entries 'Kernel module load error' and 'Kernel Aug 19 08:16:37.873703 setup-nvidia[2423]: messages' at the end of the file Aug 19 08:16:37.873703 setup-nvidia[2423]: '/nvidia/NVIDIA-Linux-x86_64-535.247.01/nvidia-installer.log' for more Aug 19 08:16:37.873703 setup-nvidia[2423]: information. Aug 19 08:16:37.873703 setup-nvidia[2423]: \u000d Aug 19 08:16:37.888747 setup-nvidia[2423]: dmesg: read kernel buffer failed: Operation not permitted Aug 19 08:16:37.907276 setup-nvidia[2423]: \u000d Aug 19 08:16:37.907276 setup-nvidia[2423]: ERROR: Installation has failed. Please see the file Aug 19 08:16:37.907276 setup-nvidia[2423]: '/nvidia/NVIDIA-Linux-x86_64-535.247.01/nvidia-installer.log' for Aug 19 08:16:37.907276 setup-nvidia[2423]: details. You may find suggestions on fixing installation problems in Aug 19 08:16:37.907276 setup-nvidia[2423]: the README available on the Linux driver download page at Aug 19 08:16:37.907276 setup-nvidia[2423]: www.nvidia.com. Aug 19 08:16:37.907276 setup-nvidia[2423]: \u000d Aug 19 08:16:37.907858 setup-nvidia[2423]: Last 50 lines of nvidia-installer.log: Aug 19 08:16:37.908719 setup-nvidia[2423]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039045rm+0x53: 'naked' return found in MITIGATION_RETHUNK build Aug 19 08:16:37.908719 setup-nvidia[2423]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039042rm+0x90: 'naked' return found in MITIGATION_RETHUNK build Aug 19 08:16:37.908719 setup-nvidia[2423]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039035rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build Aug 19 08:16:37.908719 setup-nvidia[2423]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039091rm+0x93: 'naked' return found in MITIGATION_RETHUNK build Aug 19 08:16:37.908719 setup-nvidia[2423]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039087rm+0x36: 'naked' return found in MITIGATION_RETHUNK build Aug 19 08:16:37.908719 setup-nvidia[2423]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039086rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build Aug 19 08:16:37.908719 setup-nvidia[2423]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039088rm+0x77: 'naked' return found in MITIGATION_RETHUNK build Aug 19 08:16:37.908719 setup-nvidia[2423]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039032rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build Aug 19 08:16:37.908719 setup-nvidia[2423]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv030603rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build Aug 19 08:16:37.908719 setup-nvidia[2423]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv030602rm+0x27: 'naked' return found in MITIGATION_RETHUNK build Aug 19 08:16:37.908719 setup-nvidia[2423]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv030606rm+0x37: 'naked' return found in MITIGATION_RETHUNK build Aug 19 08:16:37.908719 setup-nvidia[2423]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv030605rm+0x24: 'naked' return found in MITIGATION_RETHUNK build Aug 19 08:16:37.908719 setup-nvidia[2423]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv030604rm+0xca: 'naked' return found in MITIGATION_RETHUNK build Aug 19 08:16:37.908719 setup-nvidia[2423]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv030607rm+0x193: 'naked' return found in MITIGATION_RETHUNK build Aug 19 08:16:37.908719 setup-nvidia[2423]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv030609rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build Aug 19 08:16:37.908719 setup-nvidia[2423]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv011864rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build Aug 19 08:16:37.908719 setup-nvidia[2423]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039399rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build Aug 19 08:16:37.908719 setup-nvidia[2423]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/Module.symvers Aug 19 08:16:37.908719 setup-nvidia[2423]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.mod.o Aug 19 08:16:37.908719 setup-nvidia[2423]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/.module-common.o Aug 19 08:16:37.908719 setup-nvidia[2423]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-uvm.mod.o Aug 19 08:16:37.908719 setup-nvidia[2423]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-modeset.mod.o Aug 19 08:16:37.908719 setup-nvidia[2423]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-drm.mod.o Aug 19 08:16:37.908719 setup-nvidia[2423]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-peermem.mod.o Aug 19 08:16:37.908719 setup-nvidia[2423]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-drm.ko Aug 19 08:16:37.908719 setup-nvidia[2423]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-peermem.ko Aug 19 08:16:37.908719 setup-nvidia[2423]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-modeset.ko Aug 19 08:16:37.908719 setup-nvidia[2423]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-peermem.ko Aug 19 08:16:37.908719 setup-nvidia[2423]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-peermem.ko due to unavailability of vmlinux Aug 19 08:16:37.908719 setup-nvidia[2423]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.ko Aug 19 08:16:37.908719 setup-nvidia[2423]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-uvm.ko Aug 19 08:16:37.908719 setup-nvidia[2423]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-drm.ko Aug 19 08:16:37.908719 setup-nvidia[2423]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-drm.ko due to unavailability of vmlinux Aug 19 08:16:37.908719 setup-nvidia[2423]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-modeset.ko Aug 19 08:16:37.908719 setup-nvidia[2423]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-modeset.ko due to unavailability of vmlinux Aug 19 08:16:37.908719 setup-nvidia[2423]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-uvm.ko Aug 19 08:16:37.908719 setup-nvidia[2423]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-uvm.ko due to unavailability of vmlinux Aug 19 08:16:37.908719 setup-nvidia[2423]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.ko Aug 19 08:16:37.908719 setup-nvidia[2423]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.ko due to unavailability of vmlinux Aug 19 08:16:37.908719 setup-nvidia[2423]: make[2]: Leaving directory '/usr/lib/modules/6.12.41-flatcar/build' Aug 19 08:16:37.908719 setup-nvidia[2423]: make[1]: Leaving directory '/usr/lib/modules/6.12.41-flatcar/source' Aug 19 08:16:37.908719 setup-nvidia[2423]: -> done. Aug 19 08:16:37.908719 setup-nvidia[2423]: -> Kernel module compilation complete. Aug 19 08:16:37.908719 setup-nvidia[2423]: -> Unable to determine if Secure Boot is enabled: No such file or directory Aug 19 08:16:37.908719 setup-nvidia[2423]: 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. Aug 19 08:16:37.909772 setup-nvidia[2423]: \u000d Aug 19 08:16:37.909772 setup-nvidia[2423]: Please see the log entries 'Kernel module load error' and 'Kernel messages' at the end of the file '/nvidia/NVIDIA-Linux-x86_64-535.247.01/nvidia-installer.log' for more information. Aug 19 08:16:37.909772 setup-nvidia[2423]: -> Kernel module load error: Operation not permitted Aug 19 08:16:37.909772 setup-nvidia[2423]: -> Kernel messages: Aug 19 08:16:37.909772 setup-nvidia[2423]: ERROR: Installation has failed. Please see the file '/nvidia/NVIDIA-Linux-x86_64-535.247.01/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. Aug 19 08:16:45.412816 systemd[1]: Started sshd@19-10.200.8.16:22-10.200.16.10:60254.service - OpenSSH per-connection server daemon (10.200.16.10:60254). Aug 19 08:16:46.048646 sshd[13066]: Accepted publickey for core from 10.200.16.10 port 60254 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:16:46.050025 sshd-session[13066]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:16:46.054100 systemd-logind[1889]: New session 22 of user core. Aug 19 08:16:46.066754 systemd[1]: Started session-22.scope - Session 22 of User core. Aug 19 08:16:46.500285 sshd[13069]: Connection closed by 10.200.16.10 port 60254 Aug 19 08:16:46.500663 sshd-session[13066]: pam_unix(sshd:session): session closed for user core Aug 19 08:16:46.506834 systemd[1]: sshd@19-10.200.8.16:22-10.200.16.10:60254.service: Deactivated successfully. Aug 19 08:16:46.508614 systemd[1]: session-22.scope: Deactivated successfully. Aug 19 08:16:46.509412 systemd-logind[1889]: Session 22 logged out. Waiting for processes to exit. Aug 19 08:16:46.510482 systemd-logind[1889]: Removed session 22. Aug 19 08:16:46.618307 systemd[1]: Started sshd@20-10.200.8.16:22-10.200.16.10:60266.service - OpenSSH per-connection server daemon (10.200.16.10:60266). Aug 19 08:16:47.280827 sshd[13075]: Accepted publickey for core from 10.200.16.10 port 60266 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:16:47.282104 sshd-session[13075]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:16:47.286226 systemd-logind[1889]: New session 23 of user core. Aug 19 08:16:47.292754 systemd[1]: Started session-23.scope - Session 23 of User core. Aug 19 08:16:47.733768 sshd[13078]: Connection closed by 10.200.16.10 port 60266 Aug 19 08:16:47.734154 sshd-session[13075]: pam_unix(sshd:session): session closed for user core Aug 19 08:16:47.737731 systemd[1]: sshd@20-10.200.8.16:22-10.200.16.10:60266.service: Deactivated successfully. Aug 19 08:16:47.739268 systemd[1]: session-23.scope: Deactivated successfully. Aug 19 08:16:47.739964 systemd-logind[1889]: Session 23 logged out. Waiting for processes to exit. Aug 19 08:16:47.740857 systemd-logind[1889]: Removed session 23. Aug 19 08:16:57.850991 systemd[1]: Started sshd@21-10.200.8.16:22-10.200.16.10:37362.service - OpenSSH per-connection server daemon (10.200.16.10:37362). Aug 19 08:16:58.489542 sshd[13085]: Accepted publickey for core from 10.200.16.10 port 37362 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:16:58.490860 sshd-session[13085]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:16:58.494979 systemd-logind[1889]: New session 24 of user core. Aug 19 08:16:58.510775 systemd[1]: Started session-24.scope - Session 24 of User core. Aug 19 08:16:58.538647 kernel: EXT4-fs (loop6p9): unmounting filesystem 1c457d39-febc-4c4d-8be4-692431dfc416. Aug 19 08:16:58.539334 setup-nvidia[2423]: Container flatcardevelopercontainer-4426.0.0.bin exited successfully. Aug 19 08:16:58.541420 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4426.0.0.bin.mount: Deactivated successfully. Aug 19 08:16:58.594469 sudo[2410]: pam_unix(sudo:session): session closed for user root Aug 19 08:16:58.596387 systemd[1]: session-c2.scope: Deactivated successfully. Aug 19 08:16:58.596673 systemd[1]: session-c2.scope: Consumed 5min 13.851s CPU time, 888.4M memory peak. Aug 19 08:16:58.941767 sshd[13088]: Connection closed by 10.200.16.10 port 37362 Aug 19 08:16:58.942164 sshd-session[13085]: pam_unix(sshd:session): session closed for user core Aug 19 08:16:58.945824 systemd[1]: sshd@21-10.200.8.16:22-10.200.16.10:37362.service: Deactivated successfully. Aug 19 08:16:58.947484 systemd[1]: session-24.scope: Deactivated successfully. Aug 19 08:16:58.948219 systemd-logind[1889]: Session 24 logged out. Waiting for processes to exit. Aug 19 08:16:58.949098 systemd-logind[1889]: Removed session 24. Aug 19 08:16:59.010320 setup-nvidia[1880]: /opt/nvidia / Aug 19 08:16:59.011296 setup-nvidia[1880]: / Aug 19 08:16:59.020645 kernel: IPMI message handler: version 39.2 Aug 19 08:16:59.023651 kernel: ipmi device interface Aug 19 08:16:59.035994 setup-nvidia[1880]: /opt/nvidia/current/usr/lib/modules/6.12.41-flatcar/video / Aug 19 08:16:59.064774 systemd[1]: Started sshd@22-10.200.8.16:22-10.200.16.10:37370.service - OpenSSH per-connection server daemon (10.200.16.10:37370). Aug 19 08:16:59.095901 kernel: nvidia: loading out-of-tree module taints kernel. Aug 19 08:16:59.095987 kernel: nvidia: module license 'NVIDIA' taints kernel. Aug 19 08:16:59.096010 kernel: Disabling lock debugging due to kernel taint Aug 19 08:16:59.103284 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Aug 19 08:16:59.103345 kernel: nvidia: module license taints kernel. Aug 19 08:16:59.499653 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 Aug 19 08:16:59.728161 sshd[13119]: Accepted publickey for core from 10.200.16.10 port 37370 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:16:59.733972 sshd-session[13119]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:16:59.748540 systemd-logind[1889]: New session 25 of user core. Aug 19 08:16:59.897962 kernel: Aug 19 08:16:59.898092 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Aug 19 08:16:59.907829 systemd[1]: Started session-25.scope - Session 25 of User core. Aug 19 08:17:00.108649 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.247.01 Wed Mar 26 11:50:32 UTC 2025 Aug 19 08:17:00.130651 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.247.01 Wed Mar 26 06:12:31 UTC 2025 Aug 19 08:17:00.152668 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Aug 19 08:17:00.196588 setup-nvidia[1880]: / Aug 19 08:17:00.197037 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. Aug 19 08:17:00.210817 setup-nvidia[13133]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Aug 19 08:17:00.266265 sshd[13124]: Connection closed by 10.200.16.10 port 37370 Aug 19 08:17:00.266615 sshd-session[13119]: pam_unix(sshd:session): session closed for user core Aug 19 08:17:00.269926 systemd[1]: sshd@22-10.200.8.16:22-10.200.16.10:37370.service: Deactivated successfully. Aug 19 08:17:00.271544 systemd[1]: session-25.scope: Deactivated successfully. Aug 19 08:17:00.272239 systemd-logind[1889]: Session 25 logged out. Waiting for processes to exit. Aug 19 08:17:00.273166 systemd-logind[1889]: Removed session 25. Aug 19 08:17:02.002128 setup-nvidia[13153]: Tue Aug 19 08:17:02 2025 Aug 19 08:17:02.002128 setup-nvidia[13153]: +---------------------------------------------------------------------------------------+ Aug 19 08:17:02.002128 setup-nvidia[13153]: | NVIDIA-SMI 535.247.01 Driver Version: 535.247.01 CUDA Version: 12.2 | Aug 19 08:17:02.002128 setup-nvidia[13153]: |-----------------------------------------+----------------------+----------------------+ Aug 19 08:17:02.002128 setup-nvidia[13153]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Aug 19 08:17:02.002128 setup-nvidia[13153]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Aug 19 08:17:02.002128 setup-nvidia[13153]: | | | MIG M. | Aug 19 08:17:02.002128 setup-nvidia[13153]: |=========================================+======================+======================| Aug 19 08:17:02.010383 setup-nvidia[13153]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | 0 | Aug 19 08:17:02.010383 setup-nvidia[13153]: | N/A 28C P0 36W / 250W | 0MiB / 16384MiB | 0% Default | Aug 19 08:17:02.010383 setup-nvidia[13153]: | | | N/A | Aug 19 08:17:02.010383 setup-nvidia[13153]: +-----------------------------------------+----------------------+----------------------+ Aug 19 08:17:02.010383 setup-nvidia[13153]: Aug 19 08:17:02.010383 setup-nvidia[13153]: +---------------------------------------------------------------------------------------+ Aug 19 08:17:02.010383 setup-nvidia[13153]: | Processes: | Aug 19 08:17:02.010383 setup-nvidia[13153]: | GPU GI CI PID Type Process name GPU Memory | Aug 19 08:17:02.010383 setup-nvidia[13153]: | ID ID Usage | Aug 19 08:17:02.010383 setup-nvidia[13153]: |=======================================================================================| Aug 19 08:17:02.010383 setup-nvidia[13153]: | No running processes found | Aug 19 08:17:02.010383 setup-nvidia[13153]: +---------------------------------------------------------------------------------------+ Aug 19 08:17:02.382883 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Aug 19 08:17:02.387488 systemd[1]: Reached target multi-user.target - Multi-User System. Aug 19 08:17:02.389032 systemd[1]: Startup finished in 1.421s (firmware) + 41.720s (loader) + 3.905s (kernel) + 15.997s (initrd) + 3min 5.847s (userspace) = 4min 8.892s. Aug 19 08:17:08.823454 systemd[1]: Stopping user@0.service - User Manager for UID 0... Aug 19 08:17:08.824243 systemd[2413]: Activating special unit exit.target... Aug 19 08:17:08.824324 systemd[2413]: Stopped target default.target - Main User Target. Aug 19 08:17:08.824363 systemd[2413]: Stopped target basic.target - Basic System. Aug 19 08:17:08.824390 systemd[2413]: Stopped target paths.target - Paths. Aug 19 08:17:08.824415 systemd[2413]: Stopped target sockets.target - Sockets. Aug 19 08:17:08.824440 systemd[2413]: Stopped target timers.target - Timers. Aug 19 08:17:08.824792 systemd[2413]: Closed dbus.socket - D-Bus User Message Bus Socket. Aug 19 08:17:08.825000 systemd[2413]: Removed slice app.slice - User Application Slice. Aug 19 08:17:08.825031 systemd[2413]: Reached target shutdown.target - Shutdown. Aug 19 08:17:08.825172 systemd[2413]: Finished systemd-exit.service - Exit the Session. Aug 19 08:17:08.825207 systemd[2413]: Reached target exit.target - Exit the Session. Aug 19 08:17:08.834288 (sd-pam)[2414]: pam_unix(systemd-user:session): session closed for user root Aug 19 08:17:08.835512 systemd[1]: user@0.service: Deactivated successfully. Aug 19 08:17:08.835973 systemd[1]: Stopped user@0.service - User Manager for UID 0. Aug 19 08:17:08.838369 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Aug 19 08:17:08.864891 systemd[1]: run-user-0.mount: Deactivated successfully. Aug 19 08:17:08.865832 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Aug 19 08:17:08.866020 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Aug 19 08:17:08.867467 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Aug 19 08:17:08.867559 systemd[1]: user-0.slice: Consumed 5min 14.154s CPU time, 894.6M memory peak. Aug 19 08:17:08.867999 systemd-logind[1889]: Removed session c3. Aug 19 08:17:10.380000 systemd[1]: Started sshd@23-10.200.8.16:22-10.200.16.10:52416.service - OpenSSH per-connection server daemon (10.200.16.10:52416). Aug 19 08:17:11.031287 sshd[13164]: Accepted publickey for core from 10.200.16.10 port 52416 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:17:11.874423 sshd-session[13164]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:17:11.878983 systemd-logind[1889]: New session 26 of user core. Aug 19 08:17:11.893802 systemd[1]: Started session-26.scope - Session 26 of User core. Aug 19 08:17:12.249710 sshd[13167]: Connection closed by 10.200.16.10 port 52416 Aug 19 08:17:12.250021 sshd-session[13164]: pam_unix(sshd:session): session closed for user core Aug 19 08:17:12.253708 systemd[1]: sshd@23-10.200.8.16:22-10.200.16.10:52416.service: Deactivated successfully. Aug 19 08:17:12.255303 systemd[1]: session-26.scope: Deactivated successfully. Aug 19 08:17:12.255969 systemd-logind[1889]: Session 26 logged out. Waiting for processes to exit. Aug 19 08:17:12.256845 systemd-logind[1889]: Removed session 26. Aug 19 08:17:12.367075 systemd[1]: Started sshd@24-10.200.8.16:22-10.200.16.10:52426.service - OpenSSH per-connection server daemon (10.200.16.10:52426). Aug 19 08:17:13.018086 sshd[13173]: Accepted publickey for core from 10.200.16.10 port 52426 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:17:13.019459 sshd-session[13173]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:17:13.023541 systemd-logind[1889]: New session 27 of user core. Aug 19 08:17:13.044747 systemd[1]: Started session-27.scope - Session 27 of User core. Aug 19 08:17:13.466668 sshd[13176]: Connection closed by 10.200.16.10 port 52426 Aug 19 08:17:13.467076 sshd-session[13173]: pam_unix(sshd:session): session closed for user core Aug 19 08:17:13.470659 systemd[1]: sshd@24-10.200.8.16:22-10.200.16.10:52426.service: Deactivated successfully. Aug 19 08:17:13.472224 systemd[1]: session-27.scope: Deactivated successfully. Aug 19 08:17:13.472900 systemd-logind[1889]: Session 27 logged out. Waiting for processes to exit. Aug 19 08:17:13.473772 systemd-logind[1889]: Removed session 27. Aug 19 08:17:13.582782 systemd[1]: Started sshd@25-10.200.8.16:22-10.200.16.10:52440.service - OpenSSH per-connection server daemon (10.200.16.10:52440). Aug 19 08:17:14.219279 sshd[13182]: Accepted publickey for core from 10.200.16.10 port 52440 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:17:14.220568 sshd-session[13182]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:17:14.224774 systemd-logind[1889]: New session 28 of user core. Aug 19 08:17:14.244762 systemd[1]: Started session-28.scope - Session 28 of User core. Aug 19 08:17:14.672679 sshd[13185]: Connection closed by 10.200.16.10 port 52440 Aug 19 08:17:14.673050 sshd-session[13182]: pam_unix(sshd:session): session closed for user core Aug 19 08:17:14.676585 systemd[1]: sshd@25-10.200.8.16:22-10.200.16.10:52440.service: Deactivated successfully. Aug 19 08:17:14.678222 systemd[1]: session-28.scope: Deactivated successfully. Aug 19 08:17:14.678962 systemd-logind[1889]: Session 28 logged out. Waiting for processes to exit. Aug 19 08:17:14.679846 systemd-logind[1889]: Removed session 28. Aug 19 08:17:14.789754 systemd[1]: Started sshd@26-10.200.8.16:22-10.200.16.10:52450.service - OpenSSH per-connection server daemon (10.200.16.10:52450). Aug 19 08:17:15.448077 sshd[13191]: Accepted publickey for core from 10.200.16.10 port 52450 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:17:15.449551 sshd-session[13191]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:17:15.454012 systemd-logind[1889]: New session 29 of user core. Aug 19 08:17:15.469752 systemd[1]: Started session-29.scope - Session 29 of User core. Aug 19 08:17:15.902646 sshd[13196]: Connection closed by 10.200.16.10 port 52450 Aug 19 08:17:15.903044 sshd-session[13191]: pam_unix(sshd:session): session closed for user core Aug 19 08:17:15.912132 systemd[1]: sshd@26-10.200.8.16:22-10.200.16.10:52450.service: Deactivated successfully. Aug 19 08:17:15.913782 systemd[1]: session-29.scope: Deactivated successfully. Aug 19 08:17:15.914444 systemd-logind[1889]: Session 29 logged out. Waiting for processes to exit. Aug 19 08:17:15.915384 systemd-logind[1889]: Removed session 29. Aug 19 08:17:16.019877 systemd[1]: Started sshd@27-10.200.8.16:22-10.200.16.10:52464.service - OpenSSH per-connection server daemon (10.200.16.10:52464). Aug 19 08:17:16.677349 sshd[13203]: Accepted publickey for core from 10.200.16.10 port 52464 ssh2: RSA SHA256:M35smYUC1iQmgd7lSSqhsz+InajE9l+ceZNVpSSD7bY Aug 19 08:17:16.678661 sshd-session[13203]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 19 08:17:16.683241 systemd-logind[1889]: New session 30 of user core. Aug 19 08:17:16.697748 systemd[1]: Started session-30.scope - Session 30 of User core. Aug 19 08:17:18.615532 sshd[13206]: Connection closed by 10.200.16.10 port 52464 Aug 19 08:17:18.615979 sshd-session[13203]: pam_unix(sshd:session): session closed for user core Aug 19 08:17:18.619470 systemd[1]: sshd@27-10.200.8.16:22-10.200.16.10:52464.service: Deactivated successfully. Aug 19 08:17:18.621059 systemd[1]: session-30.scope: Deactivated successfully. Aug 19 08:17:18.621255 systemd[1]: session-30.scope: Consumed 1.424s CPU time, 14M memory peak. Aug 19 08:17:18.621721 systemd-logind[1889]: Session 30 logged out. Waiting for processes to exit. Aug 19 08:17:18.622672 systemd-logind[1889]: Removed session 30.