Aug 13 00:23:34.206322 kernel: Linux version 6.12.40-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.44 p1) 2.44.0) #1 SMP PREEMPT_DYNAMIC Tue Aug 12 21:42:48 -00 2025 Aug 13 00:23:34.206350 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=215bdedb8de38f6b96ec4f9db80853e25015f60454b867e319fdcb9244320a21 Aug 13 00:23:34.206360 kernel: BIOS-provided physical RAM map: Aug 13 00:23:34.206367 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Aug 13 00:23:34.206374 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Aug 13 00:23:34.206380 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Aug 13 00:23:34.206388 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Aug 13 00:23:34.206398 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Aug 13 00:23:34.206405 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Aug 13 00:23:34.206412 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Aug 13 00:23:34.206419 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Aug 13 00:23:34.206425 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Aug 13 00:23:34.206432 kernel: printk: legacy bootconsole [earlyser0] enabled Aug 13 00:23:34.206439 kernel: NX (Execute Disable) protection: active Aug 13 00:23:34.206450 kernel: APIC: Static calls initialized Aug 13 00:23:34.206458 kernel: efi: EFI v2.7 by Microsoft Aug 13 00:23:34.206465 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Aug 13 00:23:34.206473 kernel: random: crng init done Aug 13 00:23:34.206481 kernel: secureboot: Secure boot disabled Aug 13 00:23:34.206488 kernel: SMBIOS 3.1.0 present. Aug 13 00:23:34.206496 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Aug 13 00:23:34.206504 kernel: DMI: Memory slots populated: 3/3 Aug 13 00:23:34.206511 kernel: Hypervisor detected: Microsoft Hyper-V Aug 13 00:23:34.206519 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Aug 13 00:23:34.206529 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Aug 13 00:23:34.206536 kernel: Hyper-V: Using hypercall for remote TLB flush Aug 13 00:23:34.206544 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Aug 13 00:23:34.206552 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Aug 13 00:23:34.206560 kernel: tsc: Marking TSC unstable due to running on Hyper-V Aug 13 00:23:34.206568 kernel: tsc: Detected 2593.993 MHz processor Aug 13 00:23:34.206576 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Aug 13 00:23:34.206584 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Aug 13 00:23:34.206592 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Aug 13 00:23:34.206602 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Aug 13 00:23:34.206610 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Aug 13 00:23:34.206618 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Aug 13 00:23:34.206625 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Aug 13 00:23:34.206633 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Aug 13 00:23:34.206641 kernel: Using GB pages for direct mapping Aug 13 00:23:34.206649 kernel: ACPI: Early table checksum verification disabled Aug 13 00:23:34.206660 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Aug 13 00:23:34.206671 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 13 00:23:34.206679 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 13 00:23:34.206687 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Aug 13 00:23:34.206695 kernel: ACPI: FACS 0x000000003FFFE000 000040 Aug 13 00:23:34.206703 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 13 00:23:34.206712 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 13 00:23:34.206722 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 13 00:23:34.206736 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 13 00:23:34.206750 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 13 00:23:34.206759 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 13 00:23:34.206772 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 13 00:23:34.206781 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Aug 13 00:23:34.206789 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Aug 13 00:23:34.206814 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Aug 13 00:23:34.206832 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Aug 13 00:23:34.206845 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Aug 13 00:23:34.206853 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Aug 13 00:23:34.206861 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Aug 13 00:23:34.206869 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Aug 13 00:23:34.206878 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Aug 13 00:23:34.206886 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Aug 13 00:23:34.206894 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Aug 13 00:23:34.206902 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Aug 13 00:23:34.206913 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Aug 13 00:23:34.206922 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Aug 13 00:23:34.206930 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Aug 13 00:23:34.206938 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Aug 13 00:23:34.206946 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Aug 13 00:23:34.206954 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Aug 13 00:23:34.206963 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] Aug 13 00:23:34.206971 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] Aug 13 00:23:34.206979 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] Aug 13 00:23:34.206990 kernel: Zone ranges: Aug 13 00:23:34.206998 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Aug 13 00:23:34.207006 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Aug 13 00:23:34.207014 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Aug 13 00:23:34.207023 kernel: Device empty Aug 13 00:23:34.207031 kernel: Movable zone start for each node Aug 13 00:23:34.207039 kernel: Early memory node ranges Aug 13 00:23:34.207047 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Aug 13 00:23:34.207058 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Aug 13 00:23:34.207069 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Aug 13 00:23:34.207077 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Aug 13 00:23:34.207086 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Aug 13 00:23:34.207094 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Aug 13 00:23:34.207102 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Aug 13 00:23:34.207110 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Aug 13 00:23:34.207118 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Aug 13 00:23:34.207126 kernel: ACPI: PM-Timer IO Port: 0x408 Aug 13 00:23:34.207135 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Aug 13 00:23:34.207145 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Aug 13 00:23:34.207153 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Aug 13 00:23:34.207162 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Aug 13 00:23:34.207170 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Aug 13 00:23:34.207178 kernel: CPU topo: Max. logical packages: 1 Aug 13 00:23:34.207186 kernel: CPU topo: Max. logical dies: 1 Aug 13 00:23:34.207194 kernel: CPU topo: Max. dies per package: 1 Aug 13 00:23:34.207202 kernel: CPU topo: Max. threads per core: 1 Aug 13 00:23:34.207210 kernel: CPU topo: Num. cores per package: 6 Aug 13 00:23:34.207221 kernel: CPU topo: Num. threads per package: 6 Aug 13 00:23:34.207229 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs Aug 13 00:23:34.207237 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Aug 13 00:23:34.207245 kernel: Booting paravirtualized kernel on Hyper-V Aug 13 00:23:34.207254 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Aug 13 00:23:34.207262 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Aug 13 00:23:34.207270 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 Aug 13 00:23:34.207278 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 Aug 13 00:23:34.207286 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Aug 13 00:23:34.207297 kernel: Hyper-V: PV spinlocks enabled Aug 13 00:23:34.207305 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Aug 13 00:23:34.207314 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=215bdedb8de38f6b96ec4f9db80853e25015f60454b867e319fdcb9244320a21 Aug 13 00:23:34.207323 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Aug 13 00:23:34.207331 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Aug 13 00:23:34.207340 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Aug 13 00:23:34.207348 kernel: Fallback order for Node 0: 0 Aug 13 00:23:34.207356 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 Aug 13 00:23:34.207367 kernel: Policy zone: Normal Aug 13 00:23:34.207383 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Aug 13 00:23:34.207391 kernel: software IO TLB: area num 8. Aug 13 00:23:34.207402 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Aug 13 00:23:34.207411 kernel: Kernel/User page tables isolation: enabled Aug 13 00:23:34.207420 kernel: ftrace: allocating 40098 entries in 157 pages Aug 13 00:23:34.207429 kernel: ftrace: allocated 157 pages with 5 groups Aug 13 00:23:34.207437 kernel: Dynamic Preempt: voluntary Aug 13 00:23:34.207446 kernel: rcu: Preemptible hierarchical RCU implementation. Aug 13 00:23:34.207455 kernel: rcu: RCU event tracing is enabled. Aug 13 00:23:34.207464 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Aug 13 00:23:34.207473 kernel: Trampoline variant of Tasks RCU enabled. Aug 13 00:23:34.207484 kernel: Rude variant of Tasks RCU enabled. Aug 13 00:23:34.207493 kernel: Tracing variant of Tasks RCU enabled. Aug 13 00:23:34.207501 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Aug 13 00:23:34.207513 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Aug 13 00:23:34.207526 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Aug 13 00:23:34.207542 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Aug 13 00:23:34.207556 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Aug 13 00:23:34.207569 kernel: Using NULL legacy PIC Aug 13 00:23:34.207577 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Aug 13 00:23:34.207586 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Aug 13 00:23:34.207595 kernel: Console: colour dummy device 80x25 Aug 13 00:23:34.207604 kernel: printk: legacy console [tty1] enabled Aug 13 00:23:34.207612 kernel: printk: legacy console [ttyS0] enabled Aug 13 00:23:34.207621 kernel: printk: legacy bootconsole [earlyser0] disabled Aug 13 00:23:34.207632 kernel: ACPI: Core revision 20240827 Aug 13 00:23:34.207640 kernel: Failed to register legacy timer interrupt Aug 13 00:23:34.207649 kernel: APIC: Switch to symmetric I/O mode setup Aug 13 00:23:34.207658 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Aug 13 00:23:34.207666 kernel: Hyper-V: enabling crash_kexec_post_notifiers Aug 13 00:23:34.207675 kernel: Hyper-V: Using IPI hypercalls Aug 13 00:23:34.207684 kernel: APIC: send_IPI() replaced with hv_send_ipi() Aug 13 00:23:34.207692 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Aug 13 00:23:34.207701 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Aug 13 00:23:34.207713 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Aug 13 00:23:34.207728 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Aug 13 00:23:34.207741 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Aug 13 00:23:34.207750 kernel: Hyper-V: Using enlightened APIC (xapic mode) Aug 13 00:23:34.207759 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Aug 13 00:23:34.207767 kernel: APIC: read() replaced with hv_apic_read() Aug 13 00:23:34.207776 kernel: APIC: write() replaced with hv_apic_write() Aug 13 00:23:34.207785 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Aug 13 00:23:34.207793 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Aug 13 00:23:34.207856 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593993) Aug 13 00:23:34.207865 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Aug 13 00:23:34.207874 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Aug 13 00:23:34.207882 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Aug 13 00:23:34.207891 kernel: Spectre V2 : Mitigation: Retpolines Aug 13 00:23:34.207899 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Aug 13 00:23:34.207908 kernel: Speculative Store Bypass: Vulnerable Aug 13 00:23:34.207916 kernel: MDS: Mitigation: Clear CPU buffers Aug 13 00:23:34.207925 kernel: TAA: Mitigation: Clear CPU buffers Aug 13 00:23:34.207933 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Aug 13 00:23:34.207944 kernel: ITS: Mitigation: Aligned branch/return thunks Aug 13 00:23:34.207953 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Aug 13 00:23:34.207961 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Aug 13 00:23:34.207970 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Aug 13 00:23:34.207978 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Aug 13 00:23:34.207987 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Aug 13 00:23:34.207995 kernel: Freeing SMP alternatives memory: 32K Aug 13 00:23:34.208004 kernel: pid_max: default: 32768 minimum: 301 Aug 13 00:23:34.208012 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Aug 13 00:23:34.208021 kernel: landlock: Up and running. Aug 13 00:23:34.208029 kernel: SELinux: Initializing. Aug 13 00:23:34.208040 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Aug 13 00:23:34.208049 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Aug 13 00:23:34.208057 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Aug 13 00:23:34.208066 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Aug 13 00:23:34.208075 kernel: signal: max sigframe size: 1776 Aug 13 00:23:34.208083 kernel: rcu: Hierarchical SRCU implementation. Aug 13 00:23:34.208092 kernel: rcu: Max phase no-delay instances is 400. Aug 13 00:23:34.208101 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Aug 13 00:23:34.208109 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Aug 13 00:23:34.208118 kernel: smp: Bringing up secondary CPUs ... Aug 13 00:23:34.208129 kernel: smpboot: x86: Booting SMP configuration: Aug 13 00:23:34.208138 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Aug 13 00:23:34.208146 kernel: smp: Brought up 1 node, 6 CPUs Aug 13 00:23:34.208155 kernel: smpboot: Total of 6 processors activated (31127.91 BogoMIPS) Aug 13 00:23:34.208164 kernel: Memory: 115339088K/117439364K available (14336K kernel code, 2430K rwdata, 9960K rodata, 54444K init, 2524K bss, 2090000K reserved, 0K cma-reserved) Aug 13 00:23:34.208173 kernel: devtmpfs: initialized Aug 13 00:23:34.208181 kernel: x86/mm: Memory block size: 1024MB Aug 13 00:23:34.208190 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Aug 13 00:23:34.208199 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Aug 13 00:23:34.208210 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Aug 13 00:23:34.208218 kernel: pinctrl core: initialized pinctrl subsystem Aug 13 00:23:34.208227 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Aug 13 00:23:34.208235 kernel: audit: initializing netlink subsys (disabled) Aug 13 00:23:34.208244 kernel: audit: type=2000 audit(1755044610.028:1): state=initialized audit_enabled=0 res=1 Aug 13 00:23:34.208253 kernel: thermal_sys: Registered thermal governor 'step_wise' Aug 13 00:23:34.208261 kernel: thermal_sys: Registered thermal governor 'user_space' Aug 13 00:23:34.208270 kernel: cpuidle: using governor menu Aug 13 00:23:34.208278 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Aug 13 00:23:34.208289 kernel: dca service started, version 1.12.1 Aug 13 00:23:34.208298 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Aug 13 00:23:34.208306 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Aug 13 00:23:34.208315 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Aug 13 00:23:34.208324 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Aug 13 00:23:34.208332 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Aug 13 00:23:34.208341 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Aug 13 00:23:34.208350 kernel: ACPI: Added _OSI(Module Device) Aug 13 00:23:34.208358 kernel: ACPI: Added _OSI(Processor Device) Aug 13 00:23:34.208369 kernel: ACPI: Added _OSI(Processor Aggregator Device) Aug 13 00:23:34.208378 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Aug 13 00:23:34.208386 kernel: ACPI: Interpreter enabled Aug 13 00:23:34.208395 kernel: ACPI: PM: (supports S0 S5) Aug 13 00:23:34.208403 kernel: ACPI: Using IOAPIC for interrupt routing Aug 13 00:23:34.208412 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Aug 13 00:23:34.208421 kernel: PCI: Ignoring E820 reservations for host bridge windows Aug 13 00:23:34.208430 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Aug 13 00:23:34.208438 kernel: iommu: Default domain type: Translated Aug 13 00:23:34.208449 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Aug 13 00:23:34.208458 kernel: efivars: Registered efivars operations Aug 13 00:23:34.208466 kernel: PCI: Using ACPI for IRQ routing Aug 13 00:23:34.208475 kernel: PCI: System does not support PCI Aug 13 00:23:34.208483 kernel: vgaarb: loaded Aug 13 00:23:34.208492 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Aug 13 00:23:34.208501 kernel: VFS: Disk quotas dquot_6.6.0 Aug 13 00:23:34.208509 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Aug 13 00:23:34.208518 kernel: pnp: PnP ACPI init Aug 13 00:23:34.208529 kernel: pnp: PnP ACPI: found 3 devices Aug 13 00:23:34.208538 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Aug 13 00:23:34.208546 kernel: NET: Registered PF_INET protocol family Aug 13 00:23:34.208555 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Aug 13 00:23:34.208564 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Aug 13 00:23:34.208573 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Aug 13 00:23:34.208581 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Aug 13 00:23:34.208590 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Aug 13 00:23:34.208601 kernel: TCP: Hash tables configured (established 524288 bind 65536) Aug 13 00:23:34.208610 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Aug 13 00:23:34.208618 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Aug 13 00:23:34.208627 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Aug 13 00:23:34.208635 kernel: NET: Registered PF_XDP protocol family Aug 13 00:23:34.208644 kernel: PCI: CLS 0 bytes, default 64 Aug 13 00:23:34.208653 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Aug 13 00:23:34.208661 kernel: software IO TLB: mapped [mem 0x000000003b5bf000-0x000000003f5bf000] (64MB) Aug 13 00:23:34.208670 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Aug 13 00:23:34.208681 kernel: Initialise system trusted keyrings Aug 13 00:23:34.208689 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Aug 13 00:23:34.208698 kernel: Key type asymmetric registered Aug 13 00:23:34.208706 kernel: Asymmetric key parser 'x509' registered Aug 13 00:23:34.208715 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Aug 13 00:23:34.208723 kernel: io scheduler mq-deadline registered Aug 13 00:23:34.208732 kernel: io scheduler kyber registered Aug 13 00:23:34.208740 kernel: io scheduler bfq registered Aug 13 00:23:34.208749 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Aug 13 00:23:34.208760 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Aug 13 00:23:34.208769 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Aug 13 00:23:34.208777 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Aug 13 00:23:34.208786 kernel: i8042: PNP: No PS/2 controller found. Aug 13 00:23:34.208950 kernel: rtc_cmos 00:02: registered as rtc0 Aug 13 00:23:34.209053 kernel: rtc_cmos 00:02: setting system clock to 2025-08-13T00:23:33 UTC (1755044613) Aug 13 00:23:34.209146 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Aug 13 00:23:34.209159 kernel: intel_pstate: CPU model not supported Aug 13 00:23:34.209172 kernel: efifb: probing for efifb Aug 13 00:23:34.209181 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Aug 13 00:23:34.209190 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Aug 13 00:23:34.209198 kernel: efifb: scrolling: redraw Aug 13 00:23:34.209207 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Aug 13 00:23:34.209216 kernel: Console: switching to colour frame buffer device 128x48 Aug 13 00:23:34.209224 kernel: fb0: EFI VGA frame buffer device Aug 13 00:23:34.209233 kernel: pstore: Using crash dump compression: deflate Aug 13 00:23:34.209242 kernel: pstore: Registered efi_pstore as persistent store backend Aug 13 00:23:34.209253 kernel: NET: Registered PF_INET6 protocol family Aug 13 00:23:34.209261 kernel: Segment Routing with IPv6 Aug 13 00:23:34.209273 kernel: In-situ OAM (IOAM) with IPv6 Aug 13 00:23:34.209282 kernel: NET: Registered PF_PACKET protocol family Aug 13 00:23:34.209290 kernel: Key type dns_resolver registered Aug 13 00:23:34.209299 kernel: IPI shorthand broadcast: enabled Aug 13 00:23:34.209308 kernel: sched_clock: Marking stable (3747005100, 112969600)->(4186627800, -326653100) Aug 13 00:23:34.209316 kernel: registered taskstats version 1 Aug 13 00:23:34.209325 kernel: Loading compiled-in X.509 certificates Aug 13 00:23:34.209336 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.40-flatcar: dee0b464d3f7f8d09744a2392f69dde258bc95c0' Aug 13 00:23:34.209345 kernel: Demotion targets for Node 0: null Aug 13 00:23:34.209353 kernel: Key type .fscrypt registered Aug 13 00:23:34.209362 kernel: Key type fscrypt-provisioning registered Aug 13 00:23:34.209370 kernel: ima: No TPM chip found, activating TPM-bypass! Aug 13 00:23:34.209379 kernel: ima: Allocated hash algorithm: sha1 Aug 13 00:23:34.209387 kernel: ima: No architecture policies found Aug 13 00:23:34.209396 kernel: clk: Disabling unused clocks Aug 13 00:23:34.209405 kernel: Warning: unable to open an initial console. Aug 13 00:23:34.209416 kernel: Freeing unused kernel image (initmem) memory: 54444K Aug 13 00:23:34.209424 kernel: Write protecting the kernel read-only data: 24576k Aug 13 00:23:34.209433 kernel: Freeing unused kernel image (rodata/data gap) memory: 280K Aug 13 00:23:34.209442 kernel: Run /init as init process Aug 13 00:23:34.209450 kernel: with arguments: Aug 13 00:23:34.209459 kernel: /init Aug 13 00:23:34.209467 kernel: with environment: Aug 13 00:23:34.209476 kernel: HOME=/ Aug 13 00:23:34.209484 kernel: TERM=linux Aug 13 00:23:34.209495 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Aug 13 00:23:34.209505 systemd[1]: Successfully made /usr/ read-only. Aug 13 00:23:34.209516 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 13 00:23:34.209526 systemd[1]: Detected virtualization microsoft. Aug 13 00:23:34.209535 systemd[1]: Detected architecture x86-64. Aug 13 00:23:34.209544 systemd[1]: Running in initrd. Aug 13 00:23:34.209552 systemd[1]: No hostname configured, using default hostname. Aug 13 00:23:34.209564 systemd[1]: Hostname set to . Aug 13 00:23:34.209573 systemd[1]: Initializing machine ID from random generator. Aug 13 00:23:34.209605 systemd[1]: Queued start job for default target initrd.target. Aug 13 00:23:34.209617 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 13 00:23:34.209627 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 13 00:23:34.209636 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Aug 13 00:23:34.209646 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Aug 13 00:23:34.209655 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Aug 13 00:23:34.209670 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Aug 13 00:23:34.209680 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Aug 13 00:23:34.209689 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Aug 13 00:23:34.209698 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 13 00:23:34.209708 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Aug 13 00:23:34.209717 systemd[1]: Reached target paths.target - Path Units. Aug 13 00:23:34.209726 systemd[1]: Reached target slices.target - Slice Units. Aug 13 00:23:34.209737 systemd[1]: Reached target swap.target - Swaps. Aug 13 00:23:34.209747 systemd[1]: Reached target timers.target - Timer Units. Aug 13 00:23:34.209756 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Aug 13 00:23:34.209765 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Aug 13 00:23:34.209774 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Aug 13 00:23:34.209783 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Aug 13 00:23:34.209792 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Aug 13 00:23:34.209815 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Aug 13 00:23:34.209824 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Aug 13 00:23:34.209837 systemd[1]: Reached target sockets.target - Socket Units. Aug 13 00:23:34.209846 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Aug 13 00:23:34.209855 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Aug 13 00:23:34.209864 systemd[1]: Finished network-cleanup.service - Network Cleanup. Aug 13 00:23:34.209873 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 13 00:23:34.209883 systemd[1]: Starting systemd-fsck-usr.service... Aug 13 00:23:34.209892 systemd[1]: Starting systemd-journald.service - Journal Service... Aug 13 00:23:34.209901 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Aug 13 00:23:34.209912 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 13 00:23:34.209944 systemd-journald[233]: Collecting audit messages is disabled. Aug 13 00:23:34.209966 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Aug 13 00:23:34.209980 systemd-journald[233]: Journal started Aug 13 00:23:34.210001 systemd-journald[233]: Runtime Journal (/run/log/journal/310ae95952df4b2eb9d5ef3bd078fba8) is 8M, max 2.2G, 2.1G free. Aug 13 00:23:34.207754 systemd-modules-load[235]: Inserted module 'overlay' Aug 13 00:23:34.219817 systemd[1]: Started systemd-journald.service - Journal Service. Aug 13 00:23:34.220086 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Aug 13 00:23:34.220597 systemd[1]: Finished systemd-fsck-usr.service. Aug 13 00:23:34.222793 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Aug 13 00:23:34.223880 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Aug 13 00:23:34.261668 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 13 00:23:34.264200 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Aug 13 00:23:34.265047 systemd-tmpfiles[243]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Aug 13 00:23:34.266505 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Aug 13 00:23:34.267479 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Aug 13 00:23:34.269096 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Aug 13 00:23:34.286152 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 13 00:23:34.301672 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 13 00:23:34.309722 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Aug 13 00:23:34.340344 dracut-cmdline[265]: 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=215bdedb8de38f6b96ec4f9db80853e25015f60454b867e319fdcb9244320a21 Aug 13 00:23:34.371967 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 13 00:23:34.374414 systemd-modules-load[235]: Inserted module 'br_netfilter' Aug 13 00:23:34.378171 kernel: Bridge firewalling registered Aug 13 00:23:34.377043 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Aug 13 00:23:34.380540 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Aug 13 00:23:34.402089 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Aug 13 00:23:34.404268 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Aug 13 00:23:34.446033 systemd-resolved[311]: Positive Trust Anchors: Aug 13 00:23:34.446050 systemd-resolved[311]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Aug 13 00:23:34.446082 systemd-resolved[311]: 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 13 00:23:34.448706 systemd-resolved[311]: Defaulting to hostname 'linux'. Aug 13 00:23:34.449743 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Aug 13 00:23:34.459089 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Aug 13 00:23:34.536826 kernel: SCSI subsystem initialized Aug 13 00:23:34.547825 kernel: Loading iSCSI transport class v2.0-870. Aug 13 00:23:34.559822 kernel: iscsi: registered transport (tcp) Aug 13 00:23:34.582438 kernel: iscsi: registered transport (qla4xxx) Aug 13 00:23:34.582466 kernel: QLogic iSCSI HBA Driver Aug 13 00:23:34.600795 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Aug 13 00:23:34.628479 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Aug 13 00:23:34.631040 systemd[1]: Reached target network-pre.target - Preparation for Network. Aug 13 00:23:34.694433 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Aug 13 00:23:34.697191 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Aug 13 00:23:34.768826 kernel: raid6: avx2x4 gen() 30771 MB/s Aug 13 00:23:34.787818 kernel: raid6: avx2x2 gen() 30960 MB/s Aug 13 00:23:34.808930 kernel: raid6: avx2x1 gen() 27837 MB/s Aug 13 00:23:34.808958 kernel: raid6: using algorithm avx2x2 gen() 30960 MB/s Aug 13 00:23:34.831961 kernel: raid6: .... xor() 18563 MB/s, rmw enabled Aug 13 00:23:34.831990 kernel: raid6: using avx2x2 recovery algorithm Aug 13 00:23:34.856829 kernel: xor: automatically using best checksumming function avx Aug 13 00:23:35.022834 kernel: Btrfs loaded, zoned=no, fsverity=no Aug 13 00:23:35.030325 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Aug 13 00:23:35.034667 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 13 00:23:35.068396 systemd-udevd[485]: Using default interface naming scheme 'v255'. Aug 13 00:23:35.074509 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 13 00:23:35.076767 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Aug 13 00:23:35.122082 dracut-pre-trigger[493]: rd.md=0: removing MD RAID activation Aug 13 00:23:35.146055 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Aug 13 00:23:35.148078 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Aug 13 00:23:35.226421 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Aug 13 00:23:35.229362 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Aug 13 00:23:35.258827 kernel: cryptd: max_cpu_qlen set to 1000 Aug 13 00:23:35.277828 kernel: hv_vmbus: Vmbus version:4.0 Aug 13 00:23:35.284199 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Aug 13 00:23:35.284333 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Aug 13 00:23:35.296396 kernel: AES CTR mode by8 optimization enabled Aug 13 00:23:35.295400 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Aug 13 00:23:35.330664 kernel: hv_vmbus: registering driver hyperv_keyboard Aug 13 00:23:35.330689 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 13 00:23:35.330702 kernel: hid: raw HID events driver (C) Jiri Kosina Aug 13 00:23:35.330714 kernel: hv_vmbus: registering driver hv_netvsc Aug 13 00:23:35.330725 kernel: hv_vmbus: registering driver hv_storvsc Aug 13 00:23:35.330736 kernel: scsi host0: storvsc_host_t Aug 13 00:23:35.317499 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 13 00:23:35.344484 kernel: scsi host1: storvsc_host_t Aug 13 00:23:35.344682 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Aug 13 00:23:35.344711 kernel: hv_vmbus: registering driver hv_pci Aug 13 00:23:35.323848 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Aug 13 00:23:35.451870 kernel: pps_core: LinuxPPS API ver. 1 registered Aug 13 00:23:35.451904 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Aug 13 00:23:35.452082 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Aug 13 00:23:35.452097 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Aug 13 00:23:35.452242 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Aug 13 00:23:35.452362 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Aug 13 00:23:35.452495 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Aug 13 00:23:35.452630 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Aug 13 00:23:35.452747 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Aug 13 00:23:35.452877 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint Aug 13 00:23:35.452905 kernel: sd 0:0:0:1: [sda] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Aug 13 00:23:35.453033 kernel: sd 0:0:0:1: [sda] 4096-byte physical blocks Aug 13 00:23:35.453158 kernel: sd 0:0:0:0: [sdb] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Aug 13 00:23:35.453292 kernel: sd 0:0:0:0: [sdb] 4096-byte physical blocks Aug 13 00:23:35.453421 kernel: sd 0:0:0:1: [sda] Write Protect is off Aug 13 00:23:35.453546 kernel: sd 0:0:0:1: [sda] Mode Sense: 0f 00 10 00 Aug 13 00:23:35.453674 kernel: sd 0:0:0:0: [sdb] Write Protect is off Aug 13 00:23:35.453814 kernel: sd 0:0:0:0: [sdb] Mode Sense: 0f 00 10 00 Aug 13 00:23:35.453949 kernel: sd 0:0:0:1: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Aug 13 00:23:35.454077 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#182 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Aug 13 00:23:35.454199 kernel: sd 0:0:0:0: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Aug 13 00:23:35.454329 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#184 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Aug 13 00:23:35.454444 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#133 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Aug 13 00:23:35.454555 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#134 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Aug 13 00:23:35.454666 kernel: PTP clock support registered Aug 13 00:23:35.454679 kernel: hv_vmbus: registering driver hid_hyperv Aug 13 00:23:35.454690 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] Aug 13 00:23:35.454710 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] Aug 13 00:23:35.460855 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] Aug 13 00:23:35.462750 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 13 00:23:35.478095 kernel: pci 0001:00:00.0: Enabling HDA controller Aug 13 00:23:35.478134 kernel: sda: sda1 Aug 13 00:23:35.478147 kernel: sd 0:0:0:1: [sda] Attached SCSI disk Aug 13 00:23:35.485772 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Aug 13 00:23:35.485808 kernel: sd 0:0:0:0: [sdb] Attached SCSI disk Aug 13 00:23:35.485968 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Aug 13 00:23:35.486105 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Aug 13 00:23:35.491041 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned Aug 13 00:23:35.491230 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Aug 13 00:23:35.497472 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned Aug 13 00:23:35.501846 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Aug 13 00:23:35.502868 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned Aug 13 00:23:35.661394 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Aug 13 00:23:35.661443 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Aug 13 00:23:35.669674 kernel: hv_utils: Registering HyperV Utility Driver Aug 13 00:23:35.669706 kernel: hv_vmbus: registering driver hv_utils Aug 13 00:23:35.670825 kernel: hv_utils: Heartbeat IC version 3.0 Aug 13 00:23:35.675237 kernel: hv_utils: Shutdown IC version 3.2 Aug 13 00:23:35.677718 kernel: hv_utils: TimeSync IC version 4.0 Aug 13 00:23:35.910332 systemd-resolved[311]: Clock change detected. Flushing caches. Aug 13 00:23:36.440980 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Aug 13 00:23:36.471368 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Aug 13 00:23:36.493377 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Aug 13 00:23:36.513431 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Aug 13 00:23:36.523126 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Aug 13 00:23:36.535269 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Aug 13 00:23:36.574169 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#146 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Aug 13 00:23:36.594171 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Aug 13 00:23:36.603170 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#335 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Aug 13 00:23:36.624165 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Aug 13 00:23:37.631534 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#244 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Aug 13 00:23:37.648922 disk-uuid[716]: The operation has completed successfully. Aug 13 00:23:37.652448 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Aug 13 00:23:39.476936 systemd[1]: disk-uuid.service: Deactivated successfully. Aug 13 00:23:39.477047 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Aug 13 00:23:39.488755 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Aug 13 00:23:39.513272 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Aug 13 00:23:39.516724 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Aug 13 00:23:39.524992 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 13 00:23:39.534340 systemd[1]: Reached target remote-fs.target - Remote File Systems. Aug 13 00:23:39.543340 sh[880]: Success Aug 13 00:23:39.550720 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Aug 13 00:23:39.590323 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Aug 13 00:23:39.604824 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Aug 13 00:23:39.604851 kernel: device-mapper: uevent: version 1.0.3 Aug 13 00:23:39.604865 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Aug 13 00:23:39.621177 kernel: device-mapper: verity: sha256 using shash "sha256-avx2" Aug 13 00:23:39.818900 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Aug 13 00:23:39.822310 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Aug 13 00:23:39.836783 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Aug 13 00:23:39.858175 kernel: BTRFS info: 'norecovery' is for compatibility only, recommended to use 'rescue=nologreplay' Aug 13 00:23:39.858218 kernel: BTRFS: device fsid 0c0338fb-9434-41c1-99a2-737cbe2351c4 devid 1 transid 44 /dev/mapper/usr (254:0) scanned by mount (906) Aug 13 00:23:39.868074 kernel: BTRFS info (device dm-0): first mount of filesystem 0c0338fb-9434-41c1-99a2-737cbe2351c4 Aug 13 00:23:39.868104 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Aug 13 00:23:39.871187 kernel: BTRFS info (device dm-0): using free-space-tree Aug 13 00:23:40.335624 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Aug 13 00:23:40.337512 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Aug 13 00:23:40.343672 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Aug 13 00:23:40.350923 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Aug 13 00:23:40.362803 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Aug 13 00:23:40.416142 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sdb6 (8:22) scanned by mount (938) Aug 13 00:23:40.416192 kernel: BTRFS info (device sdb6): first mount of filesystem 900bf3f4-cc50-4925-b275-d85854bb916f Aug 13 00:23:40.420261 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Aug 13 00:23:40.423392 kernel: BTRFS info (device sdb6): using free-space-tree Aug 13 00:23:40.445184 kernel: BTRFS info (device sdb6): last unmount of filesystem 900bf3f4-cc50-4925-b275-d85854bb916f Aug 13 00:23:40.445459 systemd[1]: Finished ignition-setup.service - Ignition (setup). Aug 13 00:23:40.447850 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Aug 13 00:23:40.495616 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Aug 13 00:23:40.498636 systemd[1]: Starting systemd-networkd.service - Network Configuration... Aug 13 00:23:40.554742 systemd-networkd[1075]: lo: Link UP Aug 13 00:23:40.554753 systemd-networkd[1075]: lo: Gained carrier Aug 13 00:23:40.555780 systemd-networkd[1075]: Enumeration completed Aug 13 00:23:40.555884 systemd[1]: Started systemd-networkd.service - Network Configuration. Aug 13 00:23:40.556154 systemd-networkd[1075]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 13 00:23:40.556159 systemd-networkd[1075]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Aug 13 00:23:40.556932 systemd-networkd[1075]: eth0: Link UP Aug 13 00:23:40.557092 systemd-networkd[1075]: eth0: Gained carrier Aug 13 00:23:40.557102 systemd-networkd[1075]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 13 00:23:40.562561 systemd[1]: Reached target network.target - Network. Aug 13 00:23:40.614194 systemd-networkd[1075]: eth0: DHCPv4 address 10.200.8.11/24, gateway 10.200.8.1 acquired from 168.63.129.16 Aug 13 00:23:41.922740 ignition[1030]: Ignition 2.21.0 Aug 13 00:23:41.922759 ignition[1030]: Stage: fetch-offline Aug 13 00:23:41.922887 ignition[1030]: no configs at "/usr/lib/ignition/base.d" Aug 13 00:23:41.922903 ignition[1030]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Aug 13 00:23:41.923018 ignition[1030]: parsed url from cmdline: "" Aug 13 00:23:41.923023 ignition[1030]: no config URL provided Aug 13 00:23:41.923029 ignition[1030]: reading system config file "/usr/lib/ignition/user.ign" Aug 13 00:23:41.923038 ignition[1030]: no config at "/usr/lib/ignition/user.ign" Aug 13 00:23:41.923044 ignition[1030]: failed to fetch config: resource requires networking Aug 13 00:23:41.925429 ignition[1030]: Ignition finished successfully Aug 13 00:23:41.954114 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Aug 13 00:23:41.956650 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Aug 13 00:23:42.006196 ignition[1087]: Ignition 2.21.0 Aug 13 00:23:42.006211 ignition[1087]: Stage: fetch Aug 13 00:23:42.006467 ignition[1087]: no configs at "/usr/lib/ignition/base.d" Aug 13 00:23:42.006479 ignition[1087]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Aug 13 00:23:42.006582 ignition[1087]: parsed url from cmdline: "" Aug 13 00:23:42.006587 ignition[1087]: no config URL provided Aug 13 00:23:42.006593 ignition[1087]: reading system config file "/usr/lib/ignition/user.ign" Aug 13 00:23:42.006606 ignition[1087]: no config at "/usr/lib/ignition/user.ign" Aug 13 00:23:42.006638 ignition[1087]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Aug 13 00:23:42.108337 systemd-networkd[1075]: eth0: Gained IPv6LL Aug 13 00:23:42.116607 ignition[1087]: GET result: OK Aug 13 00:23:42.116683 ignition[1087]: config has been read from IMDS userdata Aug 13 00:23:42.116697 ignition[1087]: parsing config with SHA512: 842d17c5f4f7e6161a68416e7d4affc515482f280bc588026575d49175bee02d8c182e9f1edba0a5b02927a7ca9aa7dde36ecc21b1f1193e6906de5942e09a52 Aug 13 00:23:42.122017 unknown[1087]: fetched base config from "system" Aug 13 00:23:42.122213 ignition[1087]: fetch: fetch complete Aug 13 00:23:42.122024 unknown[1087]: fetched base config from "system" Aug 13 00:23:42.122221 ignition[1087]: fetch: fetch passed Aug 13 00:23:42.122030 unknown[1087]: fetched user config from "azure" Aug 13 00:23:42.122266 ignition[1087]: Ignition finished successfully Aug 13 00:23:42.127860 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Aug 13 00:23:42.135726 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Aug 13 00:23:42.185623 ignition[1097]: Ignition 2.21.0 Aug 13 00:23:42.185636 ignition[1097]: Stage: kargs Aug 13 00:23:42.186134 ignition[1097]: no configs at "/usr/lib/ignition/base.d" Aug 13 00:23:42.186162 ignition[1097]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Aug 13 00:23:42.188734 ignition[1097]: kargs: kargs passed Aug 13 00:23:42.198129 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Aug 13 00:23:42.188793 ignition[1097]: Ignition finished successfully Aug 13 00:23:42.204552 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Aug 13 00:23:42.241374 ignition[1107]: Ignition 2.21.0 Aug 13 00:23:42.241388 ignition[1107]: Stage: disks Aug 13 00:23:42.243936 systemd[1]: Finished ignition-disks.service - Ignition (disks). Aug 13 00:23:42.241583 ignition[1107]: no configs at "/usr/lib/ignition/base.d" Aug 13 00:23:42.245386 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Aug 13 00:23:42.241596 ignition[1107]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Aug 13 00:23:42.255074 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Aug 13 00:23:42.242528 ignition[1107]: disks: disks passed Aug 13 00:23:42.263724 systemd[1]: Reached target local-fs.target - Local File Systems. Aug 13 00:23:42.242579 ignition[1107]: Ignition finished successfully Aug 13 00:23:42.270954 systemd[1]: Reached target sysinit.target - System Initialization. Aug 13 00:23:42.279358 systemd[1]: Reached target basic.target - Basic System. Aug 13 00:23:42.281629 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Aug 13 00:23:42.355631 systemd-fsck[1119]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks Aug 13 00:23:42.360796 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Aug 13 00:23:42.371731 systemd[1]: Mounting sysroot.mount - /sysroot... Aug 13 00:23:42.642165 kernel: EXT4-fs (sdb9): mounted filesystem 069caac6-7833-4acd-8940-01a7ff7d1281 r/w with ordered data mode. Quota mode: none. Aug 13 00:23:42.642809 systemd[1]: Mounted sysroot.mount - /sysroot. Aug 13 00:23:42.645747 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Aug 13 00:23:42.664693 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Aug 13 00:23:42.667073 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Aug 13 00:23:42.688699 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Aug 13 00:23:42.704518 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sdb6 (8:22) scanned by mount (1133) Aug 13 00:23:42.689411 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Aug 13 00:23:42.728066 kernel: BTRFS info (device sdb6): first mount of filesystem 900bf3f4-cc50-4925-b275-d85854bb916f Aug 13 00:23:42.728090 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Aug 13 00:23:42.728104 kernel: BTRFS info (device sdb6): using free-space-tree Aug 13 00:23:42.689445 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Aug 13 00:23:42.707283 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Aug 13 00:23:42.731123 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Aug 13 00:23:42.748582 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Aug 13 00:23:43.625803 coreos-metadata[1135]: Aug 13 00:23:43.625 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Aug 13 00:23:43.631793 coreos-metadata[1135]: Aug 13 00:23:43.628 INFO Fetch successful Aug 13 00:23:43.631793 coreos-metadata[1135]: Aug 13 00:23:43.628 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Aug 13 00:23:43.644414 coreos-metadata[1135]: Aug 13 00:23:43.635 INFO Fetch successful Aug 13 00:23:43.648462 coreos-metadata[1135]: Aug 13 00:23:43.647 INFO wrote hostname ci-4372.1.0-a-985d590859 to /sysroot/etc/hostname Aug 13 00:23:43.649394 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Aug 13 00:23:43.703489 initrd-setup-root[1164]: cut: /sysroot/etc/passwd: No such file or directory Aug 13 00:23:43.736959 initrd-setup-root[1171]: cut: /sysroot/etc/group: No such file or directory Aug 13 00:23:43.774212 initrd-setup-root[1178]: cut: /sysroot/etc/shadow: No such file or directory Aug 13 00:23:43.789350 initrd-setup-root[1185]: cut: /sysroot/etc/gshadow: No such file or directory Aug 13 00:23:44.903310 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Aug 13 00:23:44.909041 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Aug 13 00:23:44.914245 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Aug 13 00:23:44.943159 systemd[1]: sysroot-oem.mount: Deactivated successfully. Aug 13 00:23:44.962869 kernel: BTRFS info (device sdb6): last unmount of filesystem 900bf3f4-cc50-4925-b275-d85854bb916f Aug 13 00:23:44.972238 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Aug 13 00:23:44.987403 ignition[1254]: INFO : Ignition 2.21.0 Aug 13 00:23:44.987403 ignition[1254]: INFO : Stage: mount Aug 13 00:23:44.997257 ignition[1254]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 13 00:23:44.997257 ignition[1254]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Aug 13 00:23:44.997257 ignition[1254]: INFO : mount: mount passed Aug 13 00:23:44.997257 ignition[1254]: INFO : Ignition finished successfully Aug 13 00:23:44.991821 systemd[1]: Finished ignition-mount.service - Ignition (mount). Aug 13 00:23:44.993784 systemd[1]: Starting ignition-files.service - Ignition (files)... Aug 13 00:23:45.025175 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Aug 13 00:23:45.043162 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sdb6 (8:22) scanned by mount (1267) Aug 13 00:23:45.051755 kernel: BTRFS info (device sdb6): first mount of filesystem 900bf3f4-cc50-4925-b275-d85854bb916f Aug 13 00:23:45.051774 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Aug 13 00:23:45.054956 kernel: BTRFS info (device sdb6): using free-space-tree Aug 13 00:23:45.058592 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Aug 13 00:23:45.106494 ignition[1284]: INFO : Ignition 2.21.0 Aug 13 00:23:45.106494 ignition[1284]: INFO : Stage: files Aug 13 00:23:45.112300 ignition[1284]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 13 00:23:45.112300 ignition[1284]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Aug 13 00:23:45.112300 ignition[1284]: DEBUG : files: compiled without relabeling support, skipping Aug 13 00:23:45.126870 ignition[1284]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Aug 13 00:23:45.126870 ignition[1284]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Aug 13 00:23:45.144516 ignition[1284]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Aug 13 00:23:45.150302 ignition[1284]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Aug 13 00:23:45.150302 ignition[1284]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Aug 13 00:23:45.145224 unknown[1284]: wrote ssh authorized keys file for user: core Aug 13 00:23:45.179458 ignition[1284]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Aug 13 00:23:45.186509 ignition[1284]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Aug 13 00:23:45.186509 ignition[1284]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Aug 13 00:23:45.186509 ignition[1284]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Aug 13 00:23:45.186509 ignition[1284]: INFO : files: files passed Aug 13 00:23:45.186509 ignition[1284]: INFO : Ignition finished successfully Aug 13 00:23:45.194960 systemd[1]: Finished ignition-files.service - Ignition (files). Aug 13 00:23:45.202420 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Aug 13 00:23:45.230865 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Aug 13 00:23:45.233864 systemd[1]: ignition-quench.service: Deactivated successfully. Aug 13 00:23:45.233959 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Aug 13 00:23:45.261133 initrd-setup-root-after-ignition[1315]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Aug 13 00:23:45.261133 initrd-setup-root-after-ignition[1315]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Aug 13 00:23:45.273308 initrd-setup-root-after-ignition[1319]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Aug 13 00:23:45.274506 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Aug 13 00:23:45.284592 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Aug 13 00:23:45.294277 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Aug 13 00:23:45.345810 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Aug 13 00:23:45.345927 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Aug 13 00:23:45.354667 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Aug 13 00:23:45.363322 systemd[1]: Reached target initrd.target - Initrd Default Target. Aug 13 00:23:45.371076 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Aug 13 00:23:45.371854 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Aug 13 00:23:45.394630 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Aug 13 00:23:45.397220 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Aug 13 00:23:45.419489 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Aug 13 00:23:45.421047 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 13 00:23:45.429054 systemd[1]: Stopped target timers.target - Timer Units. Aug 13 00:23:45.442220 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Aug 13 00:23:45.442356 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Aug 13 00:23:45.453909 systemd[1]: Stopped target initrd.target - Initrd Default Target. Aug 13 00:23:45.462317 systemd[1]: Stopped target basic.target - Basic System. Aug 13 00:23:45.464424 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Aug 13 00:23:45.474224 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Aug 13 00:23:45.482425 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Aug 13 00:23:45.491241 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Aug 13 00:23:45.499120 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Aug 13 00:23:45.500531 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Aug 13 00:23:45.516237 systemd[1]: Stopped target sysinit.target - System Initialization. Aug 13 00:23:45.525201 systemd[1]: Stopped target local-fs.target - Local File Systems. Aug 13 00:23:45.536264 systemd[1]: Stopped target swap.target - Swaps. Aug 13 00:23:45.543123 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Aug 13 00:23:45.543300 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Aug 13 00:23:45.557449 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Aug 13 00:23:45.565747 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 13 00:23:45.574611 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Aug 13 00:23:45.574766 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 13 00:23:45.584624 systemd[1]: dracut-initqueue.service: Deactivated successfully. Aug 13 00:23:45.584762 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Aug 13 00:23:45.596854 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Aug 13 00:23:45.596987 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Aug 13 00:23:45.605137 systemd[1]: ignition-files.service: Deactivated successfully. Aug 13 00:23:45.605279 systemd[1]: Stopped ignition-files.service - Ignition (files). Aug 13 00:23:45.613861 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Aug 13 00:23:45.613983 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Aug 13 00:23:45.624543 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Aug 13 00:23:45.653433 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Aug 13 00:23:45.654214 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Aug 13 00:23:45.654342 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Aug 13 00:23:45.662497 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Aug 13 00:23:45.683757 ignition[1339]: INFO : Ignition 2.21.0 Aug 13 00:23:45.683757 ignition[1339]: INFO : Stage: umount Aug 13 00:23:45.683757 ignition[1339]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 13 00:23:45.683757 ignition[1339]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Aug 13 00:23:45.662620 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Aug 13 00:23:45.711690 ignition[1339]: INFO : umount: umount passed Aug 13 00:23:45.711690 ignition[1339]: INFO : Ignition finished successfully Aug 13 00:23:45.683582 systemd[1]: sysroot-boot.mount: Deactivated successfully. Aug 13 00:23:45.686187 systemd[1]: initrd-cleanup.service: Deactivated successfully. Aug 13 00:23:45.686283 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Aug 13 00:23:45.688779 systemd[1]: ignition-mount.service: Deactivated successfully. Aug 13 00:23:45.689140 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Aug 13 00:23:45.695628 systemd[1]: ignition-disks.service: Deactivated successfully. Aug 13 00:23:45.695720 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Aug 13 00:23:45.703245 systemd[1]: ignition-kargs.service: Deactivated successfully. Aug 13 00:23:45.703299 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Aug 13 00:23:45.710988 systemd[1]: ignition-fetch.service: Deactivated successfully. Aug 13 00:23:45.711035 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Aug 13 00:23:45.725504 systemd[1]: Stopped target network.target - Network. Aug 13 00:23:45.732178 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Aug 13 00:23:45.732233 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Aug 13 00:23:45.740074 systemd[1]: Stopped target paths.target - Path Units. Aug 13 00:23:45.746883 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Aug 13 00:23:45.752203 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 13 00:23:45.760775 systemd[1]: Stopped target slices.target - Slice Units. Aug 13 00:23:45.767900 systemd[1]: Stopped target sockets.target - Socket Units. Aug 13 00:23:45.774967 systemd[1]: iscsid.socket: Deactivated successfully. Aug 13 00:23:45.775011 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Aug 13 00:23:45.782362 systemd[1]: iscsiuio.socket: Deactivated successfully. Aug 13 00:23:45.782401 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Aug 13 00:23:45.790456 systemd[1]: ignition-setup.service: Deactivated successfully. Aug 13 00:23:45.790523 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Aug 13 00:23:45.797816 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Aug 13 00:23:45.797861 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Aug 13 00:23:45.805719 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Aug 13 00:23:45.813046 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Aug 13 00:23:45.820308 systemd[1]: systemd-resolved.service: Deactivated successfully. Aug 13 00:23:45.820413 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Aug 13 00:23:45.832581 systemd[1]: sysroot-boot.service: Deactivated successfully. Aug 13 00:23:45.832683 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Aug 13 00:23:45.839277 systemd[1]: systemd-networkd.service: Deactivated successfully. Aug 13 00:23:45.840107 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Aug 13 00:23:45.854561 systemd[1]: Stopped target network-pre.target - Preparation for Network. Aug 13 00:23:45.858112 systemd[1]: systemd-networkd.socket: Deactivated successfully. Aug 13 00:23:45.858186 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Aug 13 00:23:45.865123 systemd[1]: initrd-setup-root.service: Deactivated successfully. Aug 13 00:23:45.865217 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Aug 13 00:23:45.876877 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Aug 13 00:23:45.883015 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Aug 13 00:23:45.883068 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Aug 13 00:23:45.891200 systemd[1]: systemd-sysctl.service: Deactivated successfully. Aug 13 00:23:45.891250 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Aug 13 00:23:45.902397 systemd[1]: systemd-modules-load.service: Deactivated successfully. Aug 13 00:23:45.902446 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Aug 13 00:23:45.911168 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Aug 13 00:23:45.911221 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Aug 13 00:23:45.920504 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 13 00:23:45.931984 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Aug 13 00:23:45.932042 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Aug 13 00:23:45.932083 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Aug 13 00:23:45.932124 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Aug 13 00:23:45.946920 systemd[1]: systemd-udevd.service: Deactivated successfully. Aug 13 00:23:45.958305 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 13 00:23:45.967327 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Aug 13 00:23:45.967373 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Aug 13 00:23:45.975091 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Aug 13 00:23:45.975126 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Aug 13 00:23:45.982781 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Aug 13 00:23:45.982833 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Aug 13 00:23:45.994741 systemd[1]: dracut-cmdline.service: Deactivated successfully. Aug 13 00:23:45.994792 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Aug 13 00:23:46.006291 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Aug 13 00:23:46.006345 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 13 00:23:46.020626 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Aug 13 00:23:46.027625 systemd[1]: systemd-network-generator.service: Deactivated successfully. Aug 13 00:23:46.027678 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Aug 13 00:23:46.040214 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Aug 13 00:23:46.040263 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 13 00:23:46.053426 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Aug 13 00:23:46.053477 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Aug 13 00:23:46.068299 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Aug 13 00:23:46.068354 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Aug 13 00:23:46.077903 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Aug 13 00:23:46.077949 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Aug 13 00:23:46.092894 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Aug 13 00:23:46.092947 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev\x2dearly.service.mount: Deactivated successfully. Aug 13 00:23:46.092983 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Aug 13 00:23:46.093021 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Aug 13 00:23:46.093447 systemd[1]: network-cleanup.service: Deactivated successfully. Aug 13 00:23:46.093544 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Aug 13 00:23:46.098478 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Aug 13 00:23:46.252195 systemd-journald[233]: Received SIGTERM from PID 1 (systemd). Aug 13 00:23:46.098565 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Aug 13 00:23:46.105865 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Aug 13 00:23:46.115939 systemd[1]: Starting initrd-switch-root.service - Switch Root... Aug 13 00:23:46.148601 systemd[1]: Switching root. Aug 13 00:23:46.264211 systemd-journald[233]: Journal stopped Aug 13 00:23:50.794311 kernel: SELinux: policy capability network_peer_controls=1 Aug 13 00:23:50.794408 kernel: SELinux: policy capability open_perms=1 Aug 13 00:23:50.794451 kernel: SELinux: policy capability extended_socket_class=1 Aug 13 00:23:50.794490 kernel: SELinux: policy capability always_check_network=0 Aug 13 00:23:50.794530 kernel: SELinux: policy capability cgroup_seclabel=1 Aug 13 00:23:50.794546 kernel: SELinux: policy capability nnp_nosuid_transition=1 Aug 13 00:23:50.794556 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Aug 13 00:23:50.794571 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Aug 13 00:23:50.794580 kernel: SELinux: policy capability userspace_initial_context=0 Aug 13 00:23:50.794589 kernel: audit: type=1403 audit(1755044627.171:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Aug 13 00:23:50.794602 systemd[1]: Successfully loaded SELinux policy in 221.175ms. Aug 13 00:23:50.794614 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 15.323ms. Aug 13 00:23:50.794627 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 13 00:23:50.794638 systemd[1]: Detected virtualization microsoft. Aug 13 00:23:50.794702 systemd[1]: Detected architecture x86-64. Aug 13 00:23:50.794744 systemd[1]: Detected first boot. Aug 13 00:23:50.794787 systemd[1]: Hostname set to . Aug 13 00:23:50.794848 systemd[1]: Initializing machine ID from random generator. Aug 13 00:23:50.794896 zram_generator::config[1388]: No configuration found. Aug 13 00:23:50.794943 kernel: Guest personality initialized and is inactive Aug 13 00:23:50.795003 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Aug 13 00:23:50.795043 kernel: Initialized host personality Aug 13 00:23:50.795083 kernel: NET: Registered PF_VSOCK protocol family Aug 13 00:23:50.795126 systemd[1]: Populated /etc with preset unit settings. Aug 13 00:23:50.795200 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Aug 13 00:23:50.795244 systemd[1]: initrd-switch-root.service: Deactivated successfully. Aug 13 00:23:50.795264 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Aug 13 00:23:50.795275 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Aug 13 00:23:50.795285 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Aug 13 00:23:50.795297 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Aug 13 00:23:50.795307 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Aug 13 00:23:50.795318 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Aug 13 00:23:50.795328 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Aug 13 00:23:50.795339 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Aug 13 00:23:50.795381 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Aug 13 00:23:50.795443 systemd[1]: Created slice user.slice - User and Session Slice. Aug 13 00:23:50.795486 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 13 00:23:50.795529 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 13 00:23:50.795572 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Aug 13 00:23:50.795637 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Aug 13 00:23:50.795687 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Aug 13 00:23:50.795704 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Aug 13 00:23:50.795718 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Aug 13 00:23:50.795732 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 13 00:23:50.795743 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Aug 13 00:23:50.795754 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Aug 13 00:23:50.795765 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Aug 13 00:23:50.795776 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Aug 13 00:23:50.795787 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Aug 13 00:23:50.795800 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 13 00:23:50.795811 systemd[1]: Reached target remote-fs.target - Remote File Systems. Aug 13 00:23:50.795822 systemd[1]: Reached target slices.target - Slice Units. Aug 13 00:23:50.795833 systemd[1]: Reached target swap.target - Swaps. Aug 13 00:23:50.795844 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Aug 13 00:23:50.795854 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Aug 13 00:23:50.795868 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Aug 13 00:23:50.795880 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Aug 13 00:23:50.795890 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Aug 13 00:23:50.795901 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Aug 13 00:23:50.795912 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Aug 13 00:23:50.795926 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Aug 13 00:23:50.795938 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Aug 13 00:23:50.795951 systemd[1]: Mounting media.mount - External Media Directory... Aug 13 00:23:50.795962 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 13 00:23:50.795973 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Aug 13 00:23:50.795984 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Aug 13 00:23:50.795995 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Aug 13 00:23:50.796006 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 13 00:23:50.796017 systemd[1]: Reached target machines.target - Containers. Aug 13 00:23:50.796028 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Aug 13 00:23:50.796039 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 13 00:23:50.796081 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Aug 13 00:23:50.796143 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Aug 13 00:23:50.796277 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 13 00:23:50.796339 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Aug 13 00:23:50.796384 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 13 00:23:50.796428 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Aug 13 00:23:50.796492 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 13 00:23:50.796536 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Aug 13 00:23:50.796606 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Aug 13 00:23:50.796649 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Aug 13 00:23:50.796668 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Aug 13 00:23:50.796679 systemd[1]: Stopped systemd-fsck-usr.service. Aug 13 00:23:50.796714 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 13 00:23:50.796760 systemd[1]: Starting systemd-journald.service - Journal Service... Aug 13 00:23:50.796803 kernel: loop: module loaded Aug 13 00:23:50.796865 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Aug 13 00:23:50.796914 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Aug 13 00:23:50.796976 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Aug 13 00:23:50.797019 kernel: fuse: init (API version 7.41) Aug 13 00:23:50.797062 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Aug 13 00:23:50.797140 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Aug 13 00:23:50.797193 systemd[1]: verity-setup.service: Deactivated successfully. Aug 13 00:23:50.797255 systemd[1]: Stopped verity-setup.service. Aug 13 00:23:50.797303 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 13 00:23:50.797372 systemd-journald[1488]: Collecting audit messages is disabled. Aug 13 00:23:50.797475 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Aug 13 00:23:50.797520 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Aug 13 00:23:50.797563 systemd-journald[1488]: Journal started Aug 13 00:23:50.797639 systemd-journald[1488]: Runtime Journal (/run/log/journal/be07dad66ad746bdb8d6498884bbd6e1) is 8M, max 2.2G, 2.1G free. Aug 13 00:23:50.019009 systemd[1]: Queued start job for default target multi-user.target. Aug 13 00:23:50.044475 systemd[1]: Unnecessary job was removed for dev-sdb6.device - /dev/sdb6. Aug 13 00:23:50.044957 systemd[1]: systemd-journald.service: Deactivated successfully. Aug 13 00:23:50.809602 systemd[1]: Started systemd-journald.service - Journal Service. Aug 13 00:23:50.813959 systemd[1]: Mounted media.mount - External Media Directory. Aug 13 00:23:50.818583 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Aug 13 00:23:50.827193 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Aug 13 00:23:50.836073 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Aug 13 00:23:50.840019 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Aug 13 00:23:50.844704 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Aug 13 00:23:50.849860 systemd[1]: modprobe@configfs.service: Deactivated successfully. Aug 13 00:23:50.850341 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Aug 13 00:23:50.855024 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 13 00:23:50.855253 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 13 00:23:50.861167 kernel: ACPI: bus type drm_connector registered Aug 13 00:23:50.862296 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 13 00:23:50.862493 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 13 00:23:50.867448 systemd[1]: modprobe@drm.service: Deactivated successfully. Aug 13 00:23:50.867641 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Aug 13 00:23:50.872440 systemd[1]: modprobe@fuse.service: Deactivated successfully. Aug 13 00:23:50.872637 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Aug 13 00:23:50.876830 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 13 00:23:50.877013 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 13 00:23:50.881334 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Aug 13 00:23:50.885820 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Aug 13 00:23:50.890602 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Aug 13 00:23:50.906693 systemd[1]: Reached target network-pre.target - Preparation for Network. Aug 13 00:23:50.915474 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Aug 13 00:23:50.937407 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Aug 13 00:23:50.941502 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Aug 13 00:23:50.941545 systemd[1]: Reached target local-fs.target - Local File Systems. Aug 13 00:23:50.946212 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Aug 13 00:23:50.951739 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Aug 13 00:23:50.955480 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 13 00:23:50.956589 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Aug 13 00:23:50.961531 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Aug 13 00:23:50.966375 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Aug 13 00:23:50.967371 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Aug 13 00:23:50.971470 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Aug 13 00:23:50.972548 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Aug 13 00:23:50.977224 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Aug 13 00:23:50.982319 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Aug 13 00:23:50.989293 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Aug 13 00:23:50.995495 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Aug 13 00:23:51.001349 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Aug 13 00:23:51.006141 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Aug 13 00:23:51.011334 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Aug 13 00:23:51.018994 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Aug 13 00:23:51.020798 systemd-journald[1488]: Time spent on flushing to /var/log/journal/be07dad66ad746bdb8d6498884bbd6e1 is 14.166ms for 953 entries. Aug 13 00:23:51.020798 systemd-journald[1488]: System Journal (/var/log/journal/be07dad66ad746bdb8d6498884bbd6e1) is 8M, max 2.6G, 2.6G free. Aug 13 00:23:51.070963 systemd-journald[1488]: Received client request to flush runtime journal. Aug 13 00:23:51.028874 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Aug 13 00:23:51.072399 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Aug 13 00:23:51.094209 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Aug 13 00:23:51.122005 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Aug 13 00:23:51.122732 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Aug 13 00:23:51.146175 kernel: loop0: detected capacity change from 0 to 146240 Aug 13 00:23:51.152405 systemd-tmpfiles[1531]: ACLs are not supported, ignoring. Aug 13 00:23:51.152427 systemd-tmpfiles[1531]: ACLs are not supported, ignoring. Aug 13 00:23:51.158196 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Aug 13 00:23:51.164642 systemd[1]: Starting systemd-sysusers.service - Create System Users... Aug 13 00:23:51.417745 systemd[1]: Finished systemd-sysusers.service - Create System Users. Aug 13 00:23:51.423884 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Aug 13 00:23:51.454026 systemd-tmpfiles[1549]: ACLs are not supported, ignoring. Aug 13 00:23:51.454049 systemd-tmpfiles[1549]: ACLs are not supported, ignoring. Aug 13 00:23:51.458608 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 13 00:23:51.732175 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Aug 13 00:23:51.863184 kernel: loop1: detected capacity change from 0 to 113872 Aug 13 00:23:52.163183 kernel: loop2: detected capacity change from 0 to 28496 Aug 13 00:23:52.269248 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Aug 13 00:23:52.276048 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 13 00:23:52.316957 systemd-udevd[1556]: Using default interface naming scheme 'v255'. Aug 13 00:23:52.470467 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 13 00:23:52.478956 systemd[1]: Starting systemd-networkd.service - Network Configuration... Aug 13 00:23:52.521176 kernel: loop3: detected capacity change from 0 to 146240 Aug 13 00:23:52.544206 kernel: loop4: detected capacity change from 0 to 113872 Aug 13 00:23:52.545466 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Aug 13 00:23:52.554644 kernel: hv_vmbus: registering driver hyperv_fb Aug 13 00:23:52.554704 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Aug 13 00:23:52.554726 kernel: loop5: detected capacity change from 0 to 28496 Aug 13 00:23:52.554742 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Aug 13 00:23:52.565402 kernel: Console: switching to colour dummy device 80x25 Aug 13 00:23:52.564525 (sd-merge)[1596]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Aug 13 00:23:52.564982 (sd-merge)[1596]: Merged extensions into '/usr'. Aug 13 00:23:52.569819 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Aug 13 00:23:52.571869 kernel: Console: switching to colour frame buffer device 128x48 Aug 13 00:23:52.580399 systemd[1]: Starting ensure-sysext.service... Aug 13 00:23:52.590188 kernel: hv_vmbus: registering driver hv_balloon Aug 13 00:23:52.594305 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Aug 13 00:23:52.601317 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Aug 13 00:23:52.606721 kernel: mousedev: PS/2 mouse device common for all mice Aug 13 00:23:52.616223 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Aug 13 00:23:52.625496 kernel: EDAC MC: Ver: 3.0.0 Aug 13 00:23:52.648385 systemd-tmpfiles[1626]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Aug 13 00:23:52.648738 systemd-tmpfiles[1626]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Aug 13 00:23:52.649118 systemd-tmpfiles[1626]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Aug 13 00:23:52.649505 systemd-tmpfiles[1626]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Aug 13 00:23:52.650469 systemd-tmpfiles[1626]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Aug 13 00:23:52.650760 systemd-tmpfiles[1626]: ACLs are not supported, ignoring. Aug 13 00:23:52.650829 systemd-tmpfiles[1626]: ACLs are not supported, ignoring. Aug 13 00:23:52.653079 systemd[1]: Reload requested from client PID 1625 ('systemctl') (unit ensure-sysext.service)... Aug 13 00:23:52.653097 systemd[1]: Reloading... Aug 13 00:23:52.680524 systemd-tmpfiles[1626]: Detected autofs mount point /boot during canonicalization of boot. Aug 13 00:23:52.680540 systemd-tmpfiles[1626]: Skipping /boot Aug 13 00:23:52.693229 systemd-tmpfiles[1626]: Detected autofs mount point /boot during canonicalization of boot. Aug 13 00:23:52.693331 systemd-tmpfiles[1626]: Skipping /boot Aug 13 00:23:52.744211 zram_generator::config[1725]: No configuration found. Aug 13 00:23:52.802257 systemd-networkd[1577]: lo: Link UP Aug 13 00:23:52.802269 systemd-networkd[1577]: lo: Gained carrier Aug 13 00:23:52.803580 systemd-networkd[1577]: Enumeration completed Aug 13 00:23:52.803953 systemd-networkd[1577]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 13 00:23:52.803964 systemd-networkd[1577]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Aug 13 00:23:52.804511 systemd-networkd[1577]: eth0: Link UP Aug 13 00:23:52.804520 systemd-networkd[1577]: eth0: Gained carrier Aug 13 00:23:52.804535 systemd-networkd[1577]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 13 00:23:52.844205 systemd-networkd[1577]: eth0: DHCPv4 address 10.200.8.11/24, gateway 10.200.8.1 acquired from 168.63.129.16 Aug 13 00:23:52.861579 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Aug 13 00:23:53.024255 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Aug 13 00:23:53.029655 systemd[1]: Reloading finished in 376 ms. Aug 13 00:23:53.075656 systemd[1]: Started systemd-userdbd.service - User Database Manager. Aug 13 00:23:53.080449 systemd[1]: Started systemd-networkd.service - Network Configuration. Aug 13 00:23:53.110774 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Aug 13 00:23:53.166089 systemd[1]: Finished ensure-sysext.service. Aug 13 00:23:53.171244 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 13 00:23:53.172474 systemd[1]: Starting audit-rules.service - Load Audit Rules... Aug 13 00:23:53.195230 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Aug 13 00:23:53.200114 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 13 00:23:53.203721 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 13 00:23:53.209230 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Aug 13 00:23:53.214536 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 13 00:23:53.219984 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 13 00:23:53.223864 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 13 00:23:53.224873 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Aug 13 00:23:53.229405 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 13 00:23:53.230462 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Aug 13 00:23:53.236073 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Aug 13 00:23:53.241810 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Aug 13 00:23:53.247999 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Aug 13 00:23:53.252470 systemd[1]: Reached target time-set.target - System Time Set. Aug 13 00:23:53.259324 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Aug 13 00:23:53.280482 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 13 00:23:53.281926 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 13 00:23:53.283015 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 13 00:23:53.283233 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 13 00:23:53.283600 systemd[1]: modprobe@drm.service: Deactivated successfully. Aug 13 00:23:53.283787 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Aug 13 00:23:53.284289 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 13 00:23:53.284464 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 13 00:23:53.284985 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 13 00:23:53.285174 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 13 00:23:53.288341 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Aug 13 00:23:53.288393 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Aug 13 00:23:53.299988 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Aug 13 00:23:53.308823 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Aug 13 00:23:53.323996 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Aug 13 00:23:53.357960 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Aug 13 00:23:53.411118 systemd-resolved[1810]: Positive Trust Anchors: Aug 13 00:23:53.411135 systemd-resolved[1810]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Aug 13 00:23:53.411178 systemd-resolved[1810]: 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 13 00:23:53.412803 augenrules[1838]: No rules Aug 13 00:23:53.414137 systemd[1]: audit-rules.service: Deactivated successfully. Aug 13 00:23:53.414403 systemd[1]: Finished audit-rules.service - Load Audit Rules. Aug 13 00:23:53.443326 systemd-resolved[1810]: Using system hostname 'ci-4372.1.0-a-985d590859'. Aug 13 00:23:53.445325 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Aug 13 00:23:53.446875 systemd[1]: Reached target network.target - Network. Aug 13 00:23:53.448213 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Aug 13 00:23:53.799120 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 13 00:23:53.879642 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Aug 13 00:23:53.884807 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 13 00:23:53.948288 systemd-networkd[1577]: eth0: Gained IPv6LL Aug 13 00:23:53.950600 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Aug 13 00:23:53.957029 systemd[1]: Reached target network-online.target - Network is Online. Aug 13 00:23:57.357959 ldconfig[1524]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Aug 13 00:23:57.372997 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Aug 13 00:23:57.379598 systemd[1]: Starting systemd-update-done.service - Update is Completed... Aug 13 00:23:57.412976 systemd[1]: Finished systemd-update-done.service - Update is Completed. Aug 13 00:23:57.417890 systemd[1]: Reached target sysinit.target - System Initialization. Aug 13 00:23:57.422247 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Aug 13 00:23:57.427208 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 13 00:23:57.432232 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Aug 13 00:23:57.436913 systemd[1]: Started logrotate.timer - Daily rotation of log files. Aug 13 00:23:57.441079 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Aug 13 00:23:57.445895 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Aug 13 00:23:57.450850 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Aug 13 00:23:57.450906 systemd[1]: Reached target paths.target - Path Units. Aug 13 00:23:57.454480 systemd[1]: Reached target timers.target - Timer Units. Aug 13 00:23:57.459323 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Aug 13 00:23:57.464807 systemd[1]: Starting docker.socket - Docker Socket for the API... Aug 13 00:23:57.471110 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Aug 13 00:23:57.476179 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Aug 13 00:23:57.484566 systemd[1]: Reached target ssh-access.target - SSH Access Available. Aug 13 00:23:57.490653 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Aug 13 00:23:57.495009 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Aug 13 00:23:57.500548 systemd[1]: Listening on docker.socket - Docker Socket for the API. Aug 13 00:23:57.505653 systemd[1]: Reached target sockets.target - Socket Units. Aug 13 00:23:57.509469 systemd[1]: Reached target basic.target - Basic System. Aug 13 00:23:57.513165 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Aug 13 00:23:57.513197 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Aug 13 00:23:57.515673 systemd[1]: Starting chronyd.service - NTP client/server... Aug 13 00:23:57.520408 systemd[1]: Starting containerd.service - containerd container runtime... Aug 13 00:23:57.540113 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Aug 13 00:23:57.545123 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Aug 13 00:23:57.549837 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Aug 13 00:23:57.570374 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Aug 13 00:23:57.575171 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Aug 13 00:23:57.579219 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 13 00:23:57.580864 (chronyd)[1854]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Aug 13 00:23:57.586442 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Aug 13 00:23:57.590749 jq[1862]: false Aug 13 00:23:57.590727 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 13 00:23:57.591797 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Aug 13 00:23:57.596792 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Aug 13 00:23:57.598210 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Aug 13 00:23:57.602823 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Aug 13 00:23:57.607370 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Aug 13 00:23:57.614603 extend-filesystems[1863]: Found /dev/sdb6 Aug 13 00:23:57.615581 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Aug 13 00:23:57.618830 systemd[1]: Starting systemd-logind.service - User Login Management... Aug 13 00:23:57.623559 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Aug 13 00:23:57.624032 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Aug 13 00:23:57.624664 systemd[1]: Starting update-engine.service - Update Engine... Aug 13 00:23:57.629582 google_oslogin_nss_cache[1864]: oslogin_cache_refresh[1864]: Refreshing passwd entry cache Aug 13 00:23:57.629826 oslogin_cache_refresh[1864]: Refreshing passwd entry cache Aug 13 00:23:57.630998 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Aug 13 00:23:57.637436 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Aug 13 00:23:57.637890 google_oslogin_nss_cache[1864]: oslogin_cache_refresh[1864]: Failure getting users, quitting Aug 13 00:23:57.637890 google_oslogin_nss_cache[1864]: oslogin_cache_refresh[1864]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Aug 13 00:23:57.637881 oslogin_cache_refresh[1864]: Failure getting users, quitting Aug 13 00:23:57.638000 google_oslogin_nss_cache[1864]: oslogin_cache_refresh[1864]: Refreshing group entry cache Aug 13 00:23:57.637898 oslogin_cache_refresh[1864]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Aug 13 00:23:57.637938 oslogin_cache_refresh[1864]: Refreshing group entry cache Aug 13 00:23:57.638752 jq[1880]: true Aug 13 00:23:57.642683 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Aug 13 00:23:57.642914 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Aug 13 00:23:57.643244 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Aug 13 00:23:57.643462 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Aug 13 00:23:57.645793 KVP[1865]: KVP starting; pid is:1865 Aug 13 00:23:57.649516 chronyd[1887]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) Aug 13 00:23:57.649750 KVP[1865]: KVP LIC Version: 3.1 Aug 13 00:23:57.650170 kernel: hv_utils: KVP IC version 4.0 Aug 13 00:23:57.651226 google_oslogin_nss_cache[1864]: oslogin_cache_refresh[1864]: Failure getting groups, quitting Aug 13 00:23:57.651226 google_oslogin_nss_cache[1864]: oslogin_cache_refresh[1864]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Aug 13 00:23:57.650771 systemd[1]: motdgen.service: Deactivated successfully. Aug 13 00:23:57.650454 oslogin_cache_refresh[1864]: Failure getting groups, quitting Aug 13 00:23:57.650980 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Aug 13 00:23:57.650467 oslogin_cache_refresh[1864]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Aug 13 00:23:57.652049 extend-filesystems[1863]: Found /dev/sdb9 Aug 13 00:23:57.657656 extend-filesystems[1863]: Checking size of /dev/sdb9 Aug 13 00:23:57.658457 (ntainerd)[1891]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Aug 13 00:23:57.661763 extend-filesystems[1863]: Old size kept for /dev/sdb9 Aug 13 00:23:57.661868 systemd[1]: extend-filesystems.service: Deactivated successfully. Aug 13 00:23:57.662110 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Aug 13 00:23:57.669837 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Aug 13 00:23:57.670092 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Aug 13 00:23:57.672071 jq[1890]: true Aug 13 00:23:57.692841 update_engine[1877]: I20250813 00:23:57.692761 1877 main.cc:92] Flatcar Update Engine starting Aug 13 00:23:57.695202 chronyd[1887]: Timezone right/UTC failed leap second check, ignoring Aug 13 00:23:57.695391 chronyd[1887]: Loaded seccomp filter (level 2) Aug 13 00:23:57.696899 systemd[1]: Started chronyd.service - NTP client/server. Aug 13 00:23:57.722136 systemd-logind[1875]: New seat seat0. Aug 13 00:23:57.724585 systemd-logind[1875]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Aug 13 00:23:57.726245 systemd[1]: Started systemd-logind.service - User Login Management. Aug 13 00:23:57.766225 bash[1924]: Updated "/home/core/.ssh/authorized_keys" Aug 13 00:23:57.775574 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Aug 13 00:23:57.781709 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Aug 13 00:23:57.790164 sshd_keygen[1888]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Aug 13 00:23:57.809568 setup-nvidia[1870]: Downloading Flatcar Container Linux Developer Container for version: 4372.1.0 Aug 13 00:23:57.812783 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Aug 13 00:23:57.818702 systemd[1]: Starting issuegen.service - Generate /run/issue... Aug 13 00:23:57.830407 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Aug 13 00:23:57.836764 systemd[1]: issuegen.service: Deactivated successfully. Aug 13 00:23:57.836987 systemd[1]: Finished issuegen.service - Generate /run/issue. Aug 13 00:23:57.842206 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Aug 13 00:23:57.856349 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Aug 13 00:23:57.860892 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Aug 13 00:23:57.867254 systemd[1]: Started getty@tty1.service - Getty on tty1. Aug 13 00:23:57.871795 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Aug 13 00:23:57.876041 systemd[1]: Reached target getty.target - Login Prompts. Aug 13 00:23:57.915754 dbus-daemon[1857]: [system] SELinux support is enabled Aug 13 00:23:57.915966 systemd[1]: Started dbus.service - D-Bus System Message Bus. Aug 13 00:23:57.919288 update_engine[1877]: I20250813 00:23:57.918882 1877 update_check_scheduler.cc:74] Next update check in 10m46s Aug 13 00:23:57.923652 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 13 00:23:57.923688 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Aug 13 00:23:57.924598 dbus-daemon[1857]: [system] Successfully activated service 'org.freedesktop.systemd1' Aug 13 00:23:57.928682 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 13 00:23:57.928709 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Aug 13 00:23:57.933663 systemd[1]: Started update-engine.service - Update Engine. Aug 13 00:23:57.939346 systemd[1]: Started locksmithd.service - Cluster reboot manager. Aug 13 00:23:57.949697 coreos-metadata[1856]: Aug 13 00:23:57.949 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Aug 13 00:23:57.952039 coreos-metadata[1856]: Aug 13 00:23:57.952 INFO Fetch successful Aug 13 00:23:57.952197 coreos-metadata[1856]: Aug 13 00:23:57.952 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Aug 13 00:23:57.955860 coreos-metadata[1856]: Aug 13 00:23:57.955 INFO Fetch successful Aug 13 00:23:57.956155 coreos-metadata[1856]: Aug 13 00:23:57.956 INFO Fetching http://168.63.129.16/machine/04df4982-0271-45d8-8a25-e236c3f5864b/5de01334%2Da57c%2D4fd3%2Dadcc%2D812cd636e168.%5Fci%2D4372.1.0%2Da%2D985d590859?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Aug 13 00:23:57.957723 coreos-metadata[1856]: Aug 13 00:23:57.957 INFO Fetch successful Aug 13 00:23:57.957771 coreos-metadata[1856]: Aug 13 00:23:57.957 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Aug 13 00:23:57.966410 coreos-metadata[1856]: Aug 13 00:23:57.966 INFO Fetch successful Aug 13 00:23:57.983981 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Aug 13 00:23:57.988614 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Aug 13 00:23:58.139231 locksmithd[2025]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Aug 13 00:23:58.420927 setup-nvidia[2040]: % Total % Received % Xferd Average Speed Time Time Time Current Aug 13 00:23:58.421270 setup-nvidia[2040]: Dload Upload Total Spent Left Speed Aug 13 00:23:58.652119 containerd[1891]: time="2025-08-13T00:23:58Z" 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 13 00:23:58.652750 containerd[1891]: time="2025-08-13T00:23:58.652715400Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 Aug 13 00:23:58.661444 containerd[1891]: time="2025-08-13T00:23:58.661402900Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="8.7µs" Aug 13 00:23:58.661444 containerd[1891]: time="2025-08-13T00:23:58.661435600Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Aug 13 00:23:58.661553 containerd[1891]: time="2025-08-13T00:23:58.661454300Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Aug 13 00:23:58.661620 containerd[1891]: time="2025-08-13T00:23:58.661598600Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Aug 13 00:23:58.661649 containerd[1891]: time="2025-08-13T00:23:58.661620400Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Aug 13 00:23:58.661649 containerd[1891]: time="2025-08-13T00:23:58.661645400Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Aug 13 00:23:58.661726 containerd[1891]: time="2025-08-13T00:23:58.661704600Z" 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 13 00:23:58.661759 containerd[1891]: time="2025-08-13T00:23:58.661723500Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Aug 13 00:23:58.661964 containerd[1891]: time="2025-08-13T00:23:58.661941700Z" 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 13 00:23:58.661992 containerd[1891]: time="2025-08-13T00:23:58.661961900Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Aug 13 00:23:58.661992 containerd[1891]: time="2025-08-13T00:23:58.661975200Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Aug 13 00:23:58.661992 containerd[1891]: time="2025-08-13T00:23:58.661983500Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Aug 13 00:23:58.662078 containerd[1891]: time="2025-08-13T00:23:58.662058900Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Aug 13 00:23:58.662321 containerd[1891]: time="2025-08-13T00:23:58.662301800Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Aug 13 00:23:58.662351 containerd[1891]: time="2025-08-13T00:23:58.662337200Z" 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 13 00:23:58.662373 containerd[1891]: time="2025-08-13T00:23:58.662349100Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Aug 13 00:23:58.662406 containerd[1891]: time="2025-08-13T00:23:58.662378200Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Aug 13 00:23:58.663813 containerd[1891]: time="2025-08-13T00:23:58.663692000Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Aug 13 00:23:58.663885 containerd[1891]: time="2025-08-13T00:23:58.663869300Z" level=info msg="metadata content store policy set" policy=shared Aug 13 00:23:58.678750 containerd[1891]: time="2025-08-13T00:23:58.678692300Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Aug 13 00:23:58.678750 containerd[1891]: time="2025-08-13T00:23:58.678735400Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Aug 13 00:23:58.678810 containerd[1891]: time="2025-08-13T00:23:58.678753900Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Aug 13 00:23:58.678810 containerd[1891]: time="2025-08-13T00:23:58.678766700Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Aug 13 00:23:58.678810 containerd[1891]: time="2025-08-13T00:23:58.678778900Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Aug 13 00:23:58.678810 containerd[1891]: time="2025-08-13T00:23:58.678789300Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Aug 13 00:23:58.678810 containerd[1891]: time="2025-08-13T00:23:58.678801600Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Aug 13 00:23:58.678916 containerd[1891]: time="2025-08-13T00:23:58.678812700Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Aug 13 00:23:58.678916 containerd[1891]: time="2025-08-13T00:23:58.678830000Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Aug 13 00:23:58.678916 containerd[1891]: time="2025-08-13T00:23:58.678841000Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Aug 13 00:23:58.678916 containerd[1891]: time="2025-08-13T00:23:58.678849900Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Aug 13 00:23:58.678916 containerd[1891]: time="2025-08-13T00:23:58.678861500Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Aug 13 00:23:58.679017 containerd[1891]: time="2025-08-13T00:23:58.678970200Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Aug 13 00:23:58.679017 containerd[1891]: time="2025-08-13T00:23:58.678991800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Aug 13 00:23:58.679017 containerd[1891]: time="2025-08-13T00:23:58.679005200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Aug 13 00:23:58.679017 containerd[1891]: time="2025-08-13T00:23:58.679015700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Aug 13 00:23:58.679095 containerd[1891]: time="2025-08-13T00:23:58.679032400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Aug 13 00:23:58.679095 containerd[1891]: time="2025-08-13T00:23:58.679042900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Aug 13 00:23:58.679095 containerd[1891]: time="2025-08-13T00:23:58.679053300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Aug 13 00:23:58.679095 containerd[1891]: time="2025-08-13T00:23:58.679078300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Aug 13 00:23:58.679200 containerd[1891]: time="2025-08-13T00:23:58.679094400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Aug 13 00:23:58.679200 containerd[1891]: time="2025-08-13T00:23:58.679104400Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Aug 13 00:23:58.679200 containerd[1891]: time="2025-08-13T00:23:58.679115100Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Aug 13 00:23:58.679200 containerd[1891]: time="2025-08-13T00:23:58.679187800Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Aug 13 00:23:58.679286 containerd[1891]: time="2025-08-13T00:23:58.679202700Z" level=info msg="Start snapshots syncer" Aug 13 00:23:58.679286 containerd[1891]: time="2025-08-13T00:23:58.679230300Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Aug 13 00:23:58.679499 containerd[1891]: time="2025-08-13T00:23:58.679448200Z" 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 13 00:23:58.679618 containerd[1891]: time="2025-08-13T00:23:58.679517700Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Aug 13 00:23:58.679618 containerd[1891]: time="2025-08-13T00:23:58.679595500Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Aug 13 00:23:58.679720 containerd[1891]: time="2025-08-13T00:23:58.679695900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Aug 13 00:23:58.679753 containerd[1891]: time="2025-08-13T00:23:58.679726400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Aug 13 00:23:58.679776 containerd[1891]: time="2025-08-13T00:23:58.679751600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Aug 13 00:23:58.679798 containerd[1891]: time="2025-08-13T00:23:58.679783100Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Aug 13 00:23:58.679821 containerd[1891]: time="2025-08-13T00:23:58.679797600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Aug 13 00:23:58.679821 containerd[1891]: time="2025-08-13T00:23:58.679808000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Aug 13 00:23:58.679821 containerd[1891]: time="2025-08-13T00:23:58.679817800Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Aug 13 00:23:58.679889 containerd[1891]: time="2025-08-13T00:23:58.679839200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Aug 13 00:23:58.679889 containerd[1891]: time="2025-08-13T00:23:58.679851500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Aug 13 00:23:58.679889 containerd[1891]: time="2025-08-13T00:23:58.679862500Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Aug 13 00:23:58.679946 containerd[1891]: time="2025-08-13T00:23:58.679909800Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Aug 13 00:23:58.679946 containerd[1891]: time="2025-08-13T00:23:58.679927000Z" 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 13 00:23:58.679946 containerd[1891]: time="2025-08-13T00:23:58.679935800Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Aug 13 00:23:58.680010 containerd[1891]: time="2025-08-13T00:23:58.679945500Z" 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 13 00:23:58.680010 containerd[1891]: time="2025-08-13T00:23:58.679953400Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Aug 13 00:23:58.680010 containerd[1891]: time="2025-08-13T00:23:58.679962200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Aug 13 00:23:58.680010 containerd[1891]: time="2025-08-13T00:23:58.679971500Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Aug 13 00:23:58.680010 containerd[1891]: time="2025-08-13T00:23:58.680001600Z" level=info msg="runtime interface created" Aug 13 00:23:58.680010 containerd[1891]: time="2025-08-13T00:23:58.680011000Z" level=info msg="created NRI interface" Aug 13 00:23:58.680135 containerd[1891]: time="2025-08-13T00:23:58.680028500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Aug 13 00:23:58.680135 containerd[1891]: time="2025-08-13T00:23:58.680044100Z" level=info msg="Connect containerd service" Aug 13 00:23:58.680135 containerd[1891]: time="2025-08-13T00:23:58.680070000Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Aug 13 00:23:58.680785 containerd[1891]: time="2025-08-13T00:23:58.680758900Z" 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 13 00:23:59.589024 containerd[1891]: time="2025-08-13T00:23:59.588973100Z" level=info msg="Start subscribing containerd event" Aug 13 00:23:59.589156 containerd[1891]: time="2025-08-13T00:23:59.589038500Z" level=info msg="Start recovering state" Aug 13 00:23:59.589203 containerd[1891]: time="2025-08-13T00:23:59.589178200Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Aug 13 00:23:59.589249 containerd[1891]: time="2025-08-13T00:23:59.589197500Z" level=info msg="Start event monitor" Aug 13 00:23:59.589249 containerd[1891]: time="2025-08-13T00:23:59.589216500Z" level=info msg="Start cni network conf syncer for default" Aug 13 00:23:59.589249 containerd[1891]: time="2025-08-13T00:23:59.589224800Z" level=info msg="Start streaming server" Aug 13 00:23:59.589249 containerd[1891]: time="2025-08-13T00:23:59.589241700Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Aug 13 00:23:59.589335 containerd[1891]: time="2025-08-13T00:23:59.589246000Z" level=info msg=serving... address=/run/containerd/containerd.sock Aug 13 00:23:59.589335 containerd[1891]: time="2025-08-13T00:23:59.589249400Z" level=info msg="runtime interface starting up..." Aug 13 00:23:59.589335 containerd[1891]: time="2025-08-13T00:23:59.589274900Z" level=info msg="starting plugins..." Aug 13 00:23:59.589335 containerd[1891]: time="2025-08-13T00:23:59.589293500Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Aug 13 00:23:59.589472 containerd[1891]: time="2025-08-13T00:23:59.589452900Z" level=info msg="containerd successfully booted in 0.937802s" Aug 13 00:23:59.589603 systemd[1]: Started containerd.service - containerd container runtime. Aug 13 00:23:59.742610 waagent[2019]: 2025-08-13T00:23:59.742524Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Aug 13 00:23:59.746769 waagent[2019]: 2025-08-13T00:23:59.746720Z INFO Daemon Daemon OS: flatcar 4372.1.0 Aug 13 00:23:59.750062 waagent[2019]: 2025-08-13T00:23:59.750014Z INFO Daemon Daemon Python: 3.11.12 Aug 13 00:23:59.753280 waagent[2019]: 2025-08-13T00:23:59.753232Z INFO Daemon Daemon Run daemon Aug 13 00:23:59.756186 waagent[2019]: 2025-08-13T00:23:59.756124Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4372.1.0' Aug 13 00:23:59.762527 waagent[2019]: 2025-08-13T00:23:59.762476Z INFO Daemon Daemon Using waagent for provisioning Aug 13 00:23:59.766289 waagent[2019]: 2025-08-13T00:23:59.766240Z INFO Daemon Daemon Activate resource disk Aug 13 00:23:59.769721 waagent[2019]: 2025-08-13T00:23:59.769673Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Aug 13 00:23:59.776909 waagent[2019]: 2025-08-13T00:23:59.776862Z INFO Daemon Daemon Found device: sda Aug 13 00:23:59.786059 waagent[2019]: 2025-08-13T00:23:59.786005Z INFO Daemon Daemon Examining partition table Aug 13 00:23:59.812655 waagent[2019]: 2025-08-13T00:23:59.812606Z INFO Daemon Daemon GPT not detected, determining filesystem Aug 13 00:23:59.889333 waagent[2019]: 2025-08-13T00:23:59.889213Z INFO Daemon Daemon sfdisk --part-type -f /dev/sda 1 -n succeeded Aug 13 00:23:59.893850 waagent[2019]: 2025-08-13T00:23:59.893807Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Aug 13 00:24:00.225898 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#228 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Aug 13 00:24:00.237178 kernel: sda: sda1 Aug 13 00:24:00.247978 waagent[2019]: 2025-08-13T00:24:00.247905Z INFO Daemon Daemon sfdisk --part-type /dev/sda 1 83 succeeded Aug 13 00:24:00.266168 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#171 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Aug 13 00:24:00.278990 waagent[2019]: 2025-08-13T00:24:00.278928Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sda1] Aug 13 00:24:00.283223 kernel: sda: sda1 Aug 13 00:24:05.781756 login[2021]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Aug 13 00:24:05.783497 login[2022]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Aug 13 00:24:05.789878 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Aug 13 00:24:05.791011 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Aug 13 00:24:05.797468 systemd-logind[1875]: New session 2 of user core. Aug 13 00:24:05.904162 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Aug 13 00:24:05.906926 systemd[1]: Starting user@500.service - User Manager for UID 500... Aug 13 00:24:05.988247 (systemd)[2122]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Aug 13 00:24:05.990913 systemd-logind[1875]: New session c1 of user core. Aug 13 00:24:06.111278 waagent[2019]: 2025-08-13T00:24:06.111135Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sda1 /mnt/resource] Aug 13 00:24:06.242185 kernel: EXT4-fs (sda1): mounted filesystem 6a7f38a8-bd1a-4575-8ca8-86f7f8fdbafb r/w with ordered data mode. Quota mode: none. Aug 13 00:24:06.242424 waagent[2019]: 2025-08-13T00:24:06.242353Z INFO Daemon Daemon Resource disk /dev/sda is mounted at /mnt/resource with ext4 Aug 13 00:24:06.248162 waagent[2019]: 2025-08-13T00:24:06.248103Z INFO Daemon Daemon Clean protocol and wireserver endpoint Aug 13 00:24:06.252503 waagent[2019]: 2025-08-13T00:24:06.252464Z INFO Daemon Daemon Running default provisioning handler Aug 13 00:24:06.260082 systemd[2122]: Queued start job for default target default.target. Aug 13 00:24:06.263077 waagent[2019]: 2025-08-13T00:24:06.263036Z 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 13 00:24:06.272912 waagent[2019]: 2025-08-13T00:24:06.272864Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Aug 13 00:24:06.274496 waagent[2019]: 2025-08-13T00:24:06.274453Z INFO Daemon Daemon cloud-init is enabled: False Aug 13 00:24:06.275932 waagent[2019]: 2025-08-13T00:24:06.275887Z INFO Daemon Daemon Copying ovf-env.xml Aug 13 00:24:06.296115 systemd[2122]: Created slice app.slice - User Application Slice. Aug 13 00:24:06.296177 systemd[2122]: Reached target paths.target - Paths. Aug 13 00:24:06.296229 systemd[2122]: Reached target timers.target - Timers. Aug 13 00:24:06.297615 systemd[2122]: Starting dbus.socket - D-Bus User Message Bus Socket... Aug 13 00:24:06.308583 systemd[2122]: Listening on dbus.socket - D-Bus User Message Bus Socket. Aug 13 00:24:06.308703 systemd[2122]: Reached target sockets.target - Sockets. Aug 13 00:24:06.308756 systemd[2122]: Reached target basic.target - Basic System. Aug 13 00:24:06.308801 systemd[2122]: Reached target default.target - Main User Target. Aug 13 00:24:06.308834 systemd[2122]: Startup finished in 311ms. Aug 13 00:24:06.308888 systemd[1]: Started user@500.service - User Manager for UID 500. Aug 13 00:24:06.310391 systemd[1]: Started session-2.scope - Session 2 of User core. Aug 13 00:24:06.326369 waagent[2019]: 2025-08-13T00:24:06.326291Z INFO Daemon Daemon Successfully mounted dvd Aug 13 00:24:06.351443 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Aug 13 00:24:06.351567 waagent[2019]: 2025-08-13T00:24:06.351523Z INFO Daemon Daemon Detect protocol endpoint Aug 13 00:24:06.353233 waagent[2019]: 2025-08-13T00:24:06.353187Z INFO Daemon Daemon Clean protocol and wireserver endpoint Aug 13 00:24:06.354659 waagent[2019]: 2025-08-13T00:24:06.354620Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Aug 13 00:24:06.356029 waagent[2019]: 2025-08-13T00:24:06.355991Z INFO Daemon Daemon Test for route to 168.63.129.16 Aug 13 00:24:06.358025 waagent[2019]: 2025-08-13T00:24:06.357868Z INFO Daemon Daemon Route to 168.63.129.16 exists Aug 13 00:24:06.360289 waagent[2019]: 2025-08-13T00:24:06.360250Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Aug 13 00:24:06.376746 waagent[2019]: 2025-08-13T00:24:06.376662Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Aug 13 00:24:06.378374 waagent[2019]: 2025-08-13T00:24:06.378339Z INFO Daemon Daemon Wire protocol version:2012-11-30 Aug 13 00:24:06.378927 waagent[2019]: 2025-08-13T00:24:06.378893Z INFO Daemon Daemon Server preferred version:2015-04-05 Aug 13 00:24:06.488321 waagent[2019]: 2025-08-13T00:24:06.488234Z INFO Daemon Daemon Initializing goal state during protocol detection Aug 13 00:24:06.490135 waagent[2019]: 2025-08-13T00:24:06.490072Z INFO Daemon Daemon Forcing an update of the goal state. Aug 13 00:24:06.493802 waagent[2019]: 2025-08-13T00:24:06.493752Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Aug 13 00:24:06.529881 waagent[2019]: 2025-08-13T00:24:06.529834Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.175 Aug 13 00:24:06.531812 waagent[2019]: 2025-08-13T00:24:06.531768Z INFO Daemon Aug 13 00:24:06.532079 waagent[2019]: 2025-08-13T00:24:06.532045Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: ff6e18b9-324b-41c3-90f1-c1dac42c78c3 eTag: 675189148303025255 source: Fabric] Aug 13 00:24:06.533696 waagent[2019]: 2025-08-13T00:24:06.533658Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Aug 13 00:24:06.534436 waagent[2019]: 2025-08-13T00:24:06.534398Z INFO Daemon Aug 13 00:24:06.534750 waagent[2019]: 2025-08-13T00:24:06.534717Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Aug 13 00:24:06.557303 waagent[2019]: 2025-08-13T00:24:06.557270Z INFO Daemon Daemon Downloading artifacts profile blob Aug 13 00:24:06.687249 waagent[2019]: 2025-08-13T00:24:06.687106Z INFO Daemon Downloaded certificate {'thumbprint': '5C974D2F217CA829B4F2AC143CC05596DD436031', 'hasPrivateKey': True} Aug 13 00:24:06.689205 waagent[2019]: 2025-08-13T00:24:06.689136Z INFO Daemon Fetch goal state completed Aug 13 00:24:06.720509 waagent[2019]: 2025-08-13T00:24:06.720462Z INFO Daemon Daemon Starting provisioning Aug 13 00:24:06.722139 waagent[2019]: 2025-08-13T00:24:06.722091Z INFO Daemon Daemon Handle ovf-env.xml. Aug 13 00:24:06.722880 waagent[2019]: 2025-08-13T00:24:06.722841Z INFO Daemon Daemon Set hostname [ci-4372.1.0-a-985d590859] Aug 13 00:24:06.732797 waagent[2019]: 2025-08-13T00:24:06.732753Z INFO Daemon Daemon Publish hostname [ci-4372.1.0-a-985d590859] Aug 13 00:24:06.734901 waagent[2019]: 2025-08-13T00:24:06.734846Z INFO Daemon Daemon Examine /proc/net/route for primary interface Aug 13 00:24:06.736405 waagent[2019]: 2025-08-13T00:24:06.736352Z INFO Daemon Daemon Primary interface is [eth0] Aug 13 00:24:06.753446 systemd-networkd[1577]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 13 00:24:06.753458 systemd-networkd[1577]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Aug 13 00:24:06.753484 systemd-networkd[1577]: eth0: DHCP lease lost Aug 13 00:24:06.753945 waagent[2019]: 2025-08-13T00:24:06.753856Z INFO Daemon Daemon Create user account if not exists Aug 13 00:24:06.758110 waagent[2019]: 2025-08-13T00:24:06.758060Z INFO Daemon Daemon User core already exists, skip useradd Aug 13 00:24:06.759580 waagent[2019]: 2025-08-13T00:24:06.759540Z INFO Daemon Daemon Configure sudoer Aug 13 00:24:06.764678 waagent[2019]: 2025-08-13T00:24:06.764635Z INFO Daemon Daemon Configure sshd Aug 13 00:24:06.769626 waagent[2019]: 2025-08-13T00:24:06.769577Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Aug 13 00:24:06.771157 waagent[2019]: 2025-08-13T00:24:06.771098Z INFO Daemon Daemon Deploy ssh public key. Aug 13 00:24:06.782126 login[2021]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Aug 13 00:24:06.787761 systemd-logind[1875]: New session 1 of user core. Aug 13 00:24:06.796290 systemd[1]: Started session-1.scope - Session 1 of User core. Aug 13 00:24:06.809195 systemd-networkd[1577]: eth0: DHCPv4 address 10.200.8.11/24, gateway 10.200.8.1 acquired from 168.63.129.16 Aug 13 00:24:07.890345 waagent[2019]: 2025-08-13T00:24:07.890265Z INFO Daemon Daemon Provisioning complete Aug 13 00:24:07.900519 waagent[2019]: 2025-08-13T00:24:07.900473Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Aug 13 00:24:07.902160 waagent[2019]: 2025-08-13T00:24:07.902091Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Aug 13 00:24:07.902850 waagent[2019]: 2025-08-13T00:24:07.902807Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Aug 13 00:24:08.066873 waagent[2179]: 2025-08-13T00:24:08.066784Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Aug 13 00:24:08.067230 waagent[2179]: 2025-08-13T00:24:08.066930Z INFO ExtHandler ExtHandler OS: flatcar 4372.1.0 Aug 13 00:24:08.067230 waagent[2179]: 2025-08-13T00:24:08.066995Z INFO ExtHandler ExtHandler Python: 3.11.12 Aug 13 00:24:08.067230 waagent[2179]: 2025-08-13T00:24:08.067059Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Aug 13 00:24:08.117468 waagent[2179]: 2025-08-13T00:24:08.117397Z INFO ExtHandler ExtHandler Distro: flatcar-4372.1.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.12; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Aug 13 00:24:08.117656 waagent[2179]: 2025-08-13T00:24:08.117618Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Aug 13 00:24:08.117735 waagent[2179]: 2025-08-13T00:24:08.117702Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Aug 13 00:24:08.124477 waagent[2179]: 2025-08-13T00:24:08.124423Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Aug 13 00:24:08.129529 waagent[2179]: 2025-08-13T00:24:08.129490Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.175 Aug 13 00:24:08.129969 waagent[2179]: 2025-08-13T00:24:08.129930Z INFO ExtHandler Aug 13 00:24:08.130181 waagent[2179]: 2025-08-13T00:24:08.130125Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 2ed6d154-b95e-421c-afd5-a66f5992e3f1 eTag: 675189148303025255 source: Fabric] Aug 13 00:24:08.130512 waagent[2179]: 2025-08-13T00:24:08.130474Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Aug 13 00:24:08.131407 waagent[2179]: 2025-08-13T00:24:08.131363Z INFO ExtHandler Aug 13 00:24:08.131478 waagent[2179]: 2025-08-13T00:24:08.131448Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Aug 13 00:24:08.135307 waagent[2179]: 2025-08-13T00:24:08.135270Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Aug 13 00:24:08.194764 waagent[2179]: 2025-08-13T00:24:08.194661Z INFO ExtHandler Downloaded certificate {'thumbprint': '5C974D2F217CA829B4F2AC143CC05596DD436031', 'hasPrivateKey': True} Aug 13 00:24:08.195231 waagent[2179]: 2025-08-13T00:24:08.195189Z INFO ExtHandler Fetch goal state completed Aug 13 00:24:08.206194 waagent[2179]: 2025-08-13T00:24:08.206129Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.3.3 11 Feb 2025 (Library: OpenSSL 3.3.3 11 Feb 2025) Aug 13 00:24:08.211251 waagent[2179]: 2025-08-13T00:24:08.211211Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2179 Aug 13 00:24:08.211445 waagent[2179]: 2025-08-13T00:24:08.211402Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Aug 13 00:24:08.211774 waagent[2179]: 2025-08-13T00:24:08.211735Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Aug 13 00:24:08.213348 waagent[2179]: 2025-08-13T00:24:08.213306Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4372.1.0', '', 'Flatcar Container Linux by Kinvolk'] Aug 13 00:24:08.213771 waagent[2179]: 2025-08-13T00:24:08.213731Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4372.1.0', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Aug 13 00:24:08.213959 waagent[2179]: 2025-08-13T00:24:08.213913Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Aug 13 00:24:08.214569 waagent[2179]: 2025-08-13T00:24:08.214529Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Aug 13 00:24:08.254132 waagent[2179]: 2025-08-13T00:24:08.254091Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Aug 13 00:24:08.254327 waagent[2179]: 2025-08-13T00:24:08.254289Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Aug 13 00:24:08.260629 waagent[2179]: 2025-08-13T00:24:08.260600Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Aug 13 00:24:08.267734 systemd[1]: Reload requested from client PID 2194 ('systemctl') (unit waagent.service)... Aug 13 00:24:08.267751 systemd[1]: Reloading... Aug 13 00:24:08.329183 zram_generator::config[2233]: No configuration found. Aug 13 00:24:08.439898 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Aug 13 00:24:08.601725 systemd[1]: Reloading finished in 333 ms. Aug 13 00:24:08.623556 waagent[2179]: 2025-08-13T00:24:08.623493Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Aug 13 00:24:08.623669 waagent[2179]: 2025-08-13T00:24:08.623635Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Aug 13 00:24:08.862938 waagent[2179]: 2025-08-13T00:24:08.862039Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Aug 13 00:24:08.862938 waagent[2179]: 2025-08-13T00:24:08.862417Z 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 13 00:24:08.863221 waagent[2179]: 2025-08-13T00:24:08.863173Z INFO ExtHandler ExtHandler Starting env monitor service. Aug 13 00:24:08.863427 waagent[2179]: 2025-08-13T00:24:08.863380Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Aug 13 00:24:08.864733 waagent[2179]: 2025-08-13T00:24:08.864691Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Aug 13 00:24:08.865129 waagent[2179]: 2025-08-13T00:24:08.865084Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Aug 13 00:24:08.865411 waagent[2179]: 2025-08-13T00:24:08.865356Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Aug 13 00:24:08.865693 waagent[2179]: 2025-08-13T00:24:08.865647Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Aug 13 00:24:08.865750 waagent[2179]: 2025-08-13T00:24:08.865709Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Aug 13 00:24:08.865810 waagent[2179]: 2025-08-13T00:24:08.865764Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Aug 13 00:24:08.866242 waagent[2179]: 2025-08-13T00:24:08.866202Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Aug 13 00:24:08.866604 waagent[2179]: 2025-08-13T00:24:08.866553Z INFO EnvHandler ExtHandler Configure routes Aug 13 00:24:08.866943 waagent[2179]: 2025-08-13T00:24:08.866878Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Aug 13 00:24:08.867013 waagent[2179]: 2025-08-13T00:24:08.866957Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Aug 13 00:24:08.867122 waagent[2179]: 2025-08-13T00:24:08.867075Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Aug 13 00:24:08.867122 waagent[2179]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Aug 13 00:24:08.867122 waagent[2179]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Aug 13 00:24:08.867122 waagent[2179]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Aug 13 00:24:08.867122 waagent[2179]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Aug 13 00:24:08.867122 waagent[2179]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Aug 13 00:24:08.867122 waagent[2179]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Aug 13 00:24:08.867311 waagent[2179]: 2025-08-13T00:24:08.867274Z INFO EnvHandler ExtHandler Gateway:None Aug 13 00:24:08.867906 waagent[2179]: 2025-08-13T00:24:08.867868Z INFO EnvHandler ExtHandler Routes:None Aug 13 00:24:08.867961 waagent[2179]: 2025-08-13T00:24:08.867922Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Aug 13 00:24:08.873270 waagent[2179]: 2025-08-13T00:24:08.873222Z INFO ExtHandler ExtHandler Aug 13 00:24:08.873562 waagent[2179]: 2025-08-13T00:24:08.873523Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 93120bb5-6231-4856-943b-9ecd5fe3e3c3 correlation c9495069-6ed1-4e28-a14d-0b3db4abe6cf created: 2025-08-13T00:22:42.344795Z] Aug 13 00:24:08.874770 waagent[2179]: 2025-08-13T00:24:08.874722Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Aug 13 00:24:08.877670 waagent[2179]: 2025-08-13T00:24:08.877616Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 4 ms] Aug 13 00:24:08.924404 waagent[2179]: 2025-08-13T00:24:08.924350Z 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 13 00:24:08.924404 waagent[2179]: Try `iptables -h' or 'iptables --help' for more information.) Aug 13 00:24:08.924790 waagent[2179]: 2025-08-13T00:24:08.924749Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 320AC8DC-6113-49B1-BE51-9992931F7A91;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Aug 13 00:24:08.952724 waagent[2179]: 2025-08-13T00:24:08.952673Z INFO MonitorHandler ExtHandler Network interfaces: Aug 13 00:24:08.952724 waagent[2179]: Executing ['ip', '-a', '-o', 'link']: Aug 13 00:24:08.952724 waagent[2179]: 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 13 00:24:08.952724 waagent[2179]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:ed:8d:42:18:a4 brd ff:ff:ff:ff:ff:ff Aug 13 00:24:08.952724 waagent[2179]: Executing ['ip', '-4', '-a', '-o', 'address']: Aug 13 00:24:08.952724 waagent[2179]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Aug 13 00:24:08.952724 waagent[2179]: 2: eth0 inet 10.200.8.11/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Aug 13 00:24:08.952724 waagent[2179]: Executing ['ip', '-6', '-a', '-o', 'address']: Aug 13 00:24:08.952724 waagent[2179]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Aug 13 00:24:08.952724 waagent[2179]: 2: eth0 inet6 fe80::7eed:8dff:fe42:18a4/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Aug 13 00:24:08.995232 waagent[2179]: 2025-08-13T00:24:08.995178Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Aug 13 00:24:08.995232 waagent[2179]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Aug 13 00:24:08.995232 waagent[2179]: pkts bytes target prot opt in out source destination Aug 13 00:24:08.995232 waagent[2179]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Aug 13 00:24:08.995232 waagent[2179]: pkts bytes target prot opt in out source destination Aug 13 00:24:08.995232 waagent[2179]: Chain OUTPUT (policy ACCEPT 4 packets, 416 bytes) Aug 13 00:24:08.995232 waagent[2179]: pkts bytes target prot opt in out source destination Aug 13 00:24:08.995232 waagent[2179]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Aug 13 00:24:08.995232 waagent[2179]: 11 1099 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Aug 13 00:24:08.995232 waagent[2179]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Aug 13 00:24:08.998653 waagent[2179]: 2025-08-13T00:24:08.998602Z INFO EnvHandler ExtHandler Current Firewall rules: Aug 13 00:24:08.998653 waagent[2179]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Aug 13 00:24:08.998653 waagent[2179]: pkts bytes target prot opt in out source destination Aug 13 00:24:08.998653 waagent[2179]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Aug 13 00:24:08.998653 waagent[2179]: pkts bytes target prot opt in out source destination Aug 13 00:24:08.998653 waagent[2179]: Chain OUTPUT (policy ACCEPT 7 packets, 765 bytes) Aug 13 00:24:08.998653 waagent[2179]: pkts bytes target prot opt in out source destination Aug 13 00:24:08.998653 waagent[2179]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Aug 13 00:24:08.998653 waagent[2179]: 11 1099 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Aug 13 00:24:08.998653 waagent[2179]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Aug 13 00:24:08.998941 waagent[2179]: 2025-08-13T00:24:08.998907Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Aug 13 00:24:08.999089 waagent[2179]: 2025-08-13T00:24:08.999058Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Aug 13 00:24:21.475936 chronyd[1887]: Selected source PHC0 Aug 13 00:24:23.178260 setup-nvidia[2040]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 496M 0 32768 0 0 36897 0 3:54:58 --:--:-- 3:54:58 36900\u000d 0 496M 0 2975k 0 0 1612k 0 0:05:15 0:00:01 0:05:14 1612k\u000d 5 496M 5 28.6M 0 0 10.1M 0 0:00:49 0:00:02 0:00:47 10.1M\u000d 10 496M 10 52.4M 0 0 13.2M 0 0:00:37 0:00:03 0:00:34 13.2M\u000d 14 496M 14 73.6M 0 0 14.9M 0 0:00:33 0:00:04 0:00:29 14.9M\u000d 19 496M 19 96.3M 0 0 16.2M 0 0:00:30 0:00:05 0:00:25 19.1M\u000d 23 496M 23 118M 0 0 17.0M 0 0:00:29 0:00:06 0:00:23 22.7M\u000d 28 496M 28 140M 0 0 17.7M 0 0:00:27 0:00:07 0:00:20 22.0M\u000d 32 496M 32 163M 0 0 18.3M 0 0:00:26 0:00:08 0:00:18 22.4M\u000d 36 496M 36 182M 0 0 18.5M 0 0:00:26 0:00:09 0:00:17 22.2M\u000d 41 496M 41 207M 0 0 18.9M 0 0:00:26 0:00:10 0:00:16 22.0M\u000d 46 496M 46 230M 0 0 19.2M 0 0:00:25 0:00:11 0:00:14 22.2M\u000d 50 496M 50 250M 0 0 19.3M 0 0:00:25 0:00:12 0:00:13 21.9M\u000d 54 496M 54 272M 0 0 19.6M 0 0:00:25 0:00:13 0:00:12 21.7M\u000d 59 496M 59 294M 0 0 19.8M 0 0:00:25 0:00:14 0:00:11 22.1M\u000d 63 496M 63 315M 0 0 19.9M 0 0:00:24 0:00:15 0:00:09 22.1M\u000d 68 496M 68 339M 0 0 20.0M 0 0:00:24 0:00:16 0:00:08 21.8M\u000d 72 496M 72 361M 0 0 20.1M 0 0:00:24 0:00:17 0:00:07 22.0M\u000d 75 496M 75 372M 0 0 19.3M 0 0:00:25 0:00:19 0:00:06 18.6M\u000d 78 496M 78 388M 0 0 19.4M 0 0:00:25 0:00:19 0:00:06 18.4M\u000d 82 496M 82 408M 0 0 19.5M 0 0:00:25 0:00:20 0:00:05 18.2M\u000d 86 496M 86 431M 0 0 19.6M 0 0:00:25 0:00:21 0:00:04 18.4M\u000d 91 496M 91 452M 0 0 19.7M 0 0:00:25 0:00:22 0:00:03 18.3M\u000d 96 496M 96 476M 0 0 19.9M 0 0:00:24 0:00:23 0:00:01 22.4M\u000d100 496M 100 496M 0 0 20.0M 0 0:00:24 0:00:24 --:--:-- 22.4M Aug 13 00:24:29.403110 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Aug 13 00:24:29.410099 systemd[1]: Started sshd@0-10.200.8.11:22-10.200.16.10:54694.service - OpenSSH per-connection server daemon (10.200.16.10:54694). Aug 13 00:24:30.334371 sshd[2336]: Accepted publickey for core from 10.200.16.10 port 54694 ssh2: RSA SHA256:SBsZYx70TPYFFSXAJ6H1pKUrUrWd7UrZsv34XJzQ5MI Aug 13 00:24:30.335798 sshd-session[2336]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:24:30.341339 systemd-logind[1875]: New session 3 of user core. Aug 13 00:24:30.357373 systemd[1]: Started session-3.scope - Session 3 of User core. Aug 13 00:24:30.889388 systemd[1]: Started sshd@1-10.200.8.11:22-10.200.16.10:44544.service - OpenSSH per-connection server daemon (10.200.16.10:44544). Aug 13 00:24:31.540381 sshd[2341]: Accepted publickey for core from 10.200.16.10 port 44544 ssh2: RSA SHA256:SBsZYx70TPYFFSXAJ6H1pKUrUrWd7UrZsv34XJzQ5MI Aug 13 00:24:31.541831 sshd-session[2341]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:24:31.549081 systemd-logind[1875]: New session 4 of user core. Aug 13 00:24:31.554321 systemd[1]: Started session-4.scope - Session 4 of User core. Aug 13 00:24:31.992989 sshd[2343]: Connection closed by 10.200.16.10 port 44544 Aug 13 00:24:31.994327 sshd-session[2341]: pam_unix(sshd:session): session closed for user core Aug 13 00:24:31.998510 systemd[1]: sshd@1-10.200.8.11:22-10.200.16.10:44544.service: Deactivated successfully. Aug 13 00:24:32.000272 systemd[1]: session-4.scope: Deactivated successfully. Aug 13 00:24:32.001494 systemd-logind[1875]: Session 4 logged out. Waiting for processes to exit. Aug 13 00:24:32.005489 systemd-logind[1875]: Removed session 4. Aug 13 00:24:32.107499 systemd[1]: Started sshd@2-10.200.8.11:22-10.200.16.10:44552.service - OpenSSH per-connection server daemon (10.200.16.10:44552). Aug 13 00:24:32.757718 sshd[2349]: Accepted publickey for core from 10.200.16.10 port 44552 ssh2: RSA SHA256:SBsZYx70TPYFFSXAJ6H1pKUrUrWd7UrZsv34XJzQ5MI Aug 13 00:24:32.758316 sshd-session[2349]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:24:32.764357 systemd-logind[1875]: New session 5 of user core. Aug 13 00:24:32.770280 systemd[1]: Started session-5.scope - Session 5 of User core. Aug 13 00:24:33.210195 sshd[2351]: Connection closed by 10.200.16.10 port 44552 Aug 13 00:24:33.208945 sshd-session[2349]: pam_unix(sshd:session): session closed for user core Aug 13 00:24:33.218488 systemd-logind[1875]: Session 5 logged out. Waiting for processes to exit. Aug 13 00:24:33.218659 systemd[1]: sshd@2-10.200.8.11:22-10.200.16.10:44552.service: Deactivated successfully. Aug 13 00:24:33.221320 systemd[1]: session-5.scope: Deactivated successfully. Aug 13 00:24:33.222561 systemd-logind[1875]: Removed session 5. Aug 13 00:24:33.401618 setup-nvidia[1870]: Downloading NVIDIA 535.230.02 Driver Aug 13 00:24:33.405795 setup-nvidia[2358]: % Total % Received % Xferd Average Speed Time Time Time Current Aug 13 00:24:33.406008 setup-nvidia[2358]: Dload Upload Total Spent Left Speed Aug 13 00:24:34.663782 setup-nvidia[2358]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 64 326M 64 209M 0 0 244M 0 0:00:01 --:--:-- 0:00:01 244M\u000d100 326M 100 326M 0 0 259M 0 0:00:01 0:00:01 --:--:-- 259M Aug 13 00:24:34.699105 setup-nvidia[1870]: Extract the NVIDIA Driver Installer 535.230.02 Aug 13 00:24:34.699400 setup-nvidia[1870]: /opt/nvidia/workdir/nvidia-workdir / Aug 13 00:24:34.716512 setup-nvidia[2361]: Creating directory NVIDIA-Linux-x86_64-535.230.02 Aug 13 00:24:34.954458 setup-nvidia[2361]: Verifying archive integrity... OK Aug 13 00:24:35.675442 setup-nvidia[2361]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.230.02 Aug 13 00:24:37.453510 setup-nvidia[2387]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Aug 13 00:24:37.550123 setup-nvidia[1870]: / Aug 13 00:24:37.550123 setup-nvidia[1870]: Spawn system-nspawn container to install the NVIDIA drivers Aug 13 00:24:37.935427 sudo[2394]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4372.1.0.bin --overlay=/usr/lib/modules::/usr/lib/modules --bind=/opt/nvidia/workdir/nvidia-workdir:/nvidia --bind=/usr/lib/nvidia/bin:/app/bin/ /app/bin/install-nvidia NVIDIA-Linux-x86_64-535.230.02 Aug 13 00:24:37.937184 sudo[2394]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 13 00:24:37.943194 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Aug 13 00:24:37.948565 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Aug 13 00:24:37.985012 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Aug 13 00:24:37.992723 systemd[1]: Starting user@0.service - User Manager for UID 0... Aug 13 00:24:38.008094 (systemd)[2397]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Aug 13 00:24:38.010959 systemd-logind[1875]: New session c3 of user root. Aug 13 00:24:38.197515 systemd[2397]: Queued start job for default target default.target. Aug 13 00:24:38.219319 systemd[2397]: Created slice app.slice - User Application Slice. Aug 13 00:24:38.219349 systemd[2397]: Reached target paths.target - Paths. Aug 13 00:24:38.219398 systemd[2397]: Reached target timers.target - Timers. Aug 13 00:24:38.220538 systemd[2397]: Starting dbus.socket - D-Bus User Message Bus Socket... Aug 13 00:24:38.230910 systemd[2397]: Listening on dbus.socket - D-Bus User Message Bus Socket. Aug 13 00:24:38.230967 systemd[2397]: Reached target sockets.target - Sockets. Aug 13 00:24:38.231010 systemd[2397]: Reached target basic.target - Basic System. Aug 13 00:24:38.231053 systemd[2397]: Reached target default.target - Main User Target. Aug 13 00:24:38.231089 systemd[2397]: Startup finished in 214ms. Aug 13 00:24:38.231312 systemd[1]: Started user@0.service - User Manager for UID 0. Aug 13 00:24:38.235622 systemd[1]: Started session-c2.scope - Session c2 of User root. Aug 13 00:24:38.317179 kernel: loop6: detected capacity change from 0 to 12644352 Aug 13 00:24:38.328169 kernel: loop6: p9 Aug 13 00:24:38.494043 setup-nvidia[2406]: ░ Spawning container flatcardevelopercontainer-4372.1.0.bin on /opt/nvidia/workdir/flatcar_developer_container-4372.1.0.bin. Aug 13 00:24:40.056438 kernel: EXT4-fs (loop6p9): write access unavailable, skipping orphan cleanup Aug 13 00:24:40.056561 kernel: EXT4-fs (loop6p9): mounted filesystem 5379994e-ad1d-4390-9695-fc97a8ee658f ro without journal. Quota mode: none. Aug 13 00:24:40.720568 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Aug 13 00:24:43.066227 update_engine[1877]: I20250813 00:24:43.066121 1877 update_attempter.cc:509] Updating boot flags... Aug 13 00:24:43.330682 systemd[1]: Started sshd@3-10.200.8.11:22-10.200.16.10:59292.service - OpenSSH per-connection server daemon (10.200.16.10:59292). Aug 13 00:24:43.969163 sshd[2593]: Accepted publickey for core from 10.200.16.10 port 59292 ssh2: RSA SHA256:SBsZYx70TPYFFSXAJ6H1pKUrUrWd7UrZsv34XJzQ5MI Aug 13 00:24:43.970494 sshd-session[2593]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:24:43.974817 systemd-logind[1875]: New session 6 of user core. Aug 13 00:24:43.990271 systemd[1]: Started session-6.scope - Session 6 of User core. Aug 13 00:24:44.412469 sshd[2639]: Connection closed by 10.200.16.10 port 59292 Aug 13 00:24:44.412825 sshd-session[2593]: pam_unix(sshd:session): session closed for user core Aug 13 00:24:44.416276 systemd[1]: sshd@3-10.200.8.11:22-10.200.16.10:59292.service: Deactivated successfully. Aug 13 00:24:44.417833 systemd[1]: session-6.scope: Deactivated successfully. Aug 13 00:24:44.418552 systemd-logind[1875]: Session 6 logged out. Waiting for processes to exit. Aug 13 00:24:44.419516 systemd-logind[1875]: Removed session 6. Aug 13 00:24:44.523566 systemd[1]: Started sshd@4-10.200.8.11:22-10.200.16.10:59308.service - OpenSSH per-connection server daemon (10.200.16.10:59308). Aug 13 00:24:45.152185 sshd[2646]: Accepted publickey for core from 10.200.16.10 port 59308 ssh2: RSA SHA256:SBsZYx70TPYFFSXAJ6H1pKUrUrWd7UrZsv34XJzQ5MI Aug 13 00:24:45.153497 sshd-session[2646]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:24:45.158643 systemd-logind[1875]: New session 7 of user core. Aug 13 00:24:45.171270 systemd[1]: Started session-7.scope - Session 7 of User core. Aug 13 00:24:45.595333 sshd[2661]: Connection closed by 10.200.16.10 port 59308 Aug 13 00:24:45.595668 sshd-session[2646]: pam_unix(sshd:session): session closed for user core Aug 13 00:24:45.599080 systemd[1]: sshd@4-10.200.8.11:22-10.200.16.10:59308.service: Deactivated successfully. Aug 13 00:24:45.600674 systemd[1]: session-7.scope: Deactivated successfully. Aug 13 00:24:45.601375 systemd-logind[1875]: Session 7 logged out. Waiting for processes to exit. Aug 13 00:24:45.603657 systemd-logind[1875]: Removed session 7. Aug 13 00:24:55.716594 systemd[1]: Started sshd@5-10.200.8.11:22-10.200.16.10:43800.service - OpenSSH per-connection server daemon (10.200.16.10:43800). Aug 13 00:24:56.357435 sshd[3480]: Accepted publickey for core from 10.200.16.10 port 43800 ssh2: RSA SHA256:SBsZYx70TPYFFSXAJ6H1pKUrUrWd7UrZsv34XJzQ5MI Aug 13 00:24:56.358728 sshd-session[3480]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:24:56.363078 systemd-logind[1875]: New session 8 of user core. Aug 13 00:24:56.371270 systemd[1]: Started session-8.scope - Session 8 of User core. Aug 13 00:24:56.808098 sshd[3876]: Connection closed by 10.200.16.10 port 43800 Aug 13 00:24:56.807349 sshd-session[3480]: pam_unix(sshd:session): session closed for user core Aug 13 00:24:56.811767 systemd[1]: sshd@5-10.200.8.11:22-10.200.16.10:43800.service: Deactivated successfully. Aug 13 00:24:56.813757 systemd[1]: session-8.scope: Deactivated successfully. Aug 13 00:24:56.814533 systemd-logind[1875]: Session 8 logged out. Waiting for processes to exit. Aug 13 00:24:56.815955 systemd-logind[1875]: Removed session 8. Aug 13 00:24:56.924533 systemd[1]: Started sshd@6-10.200.8.11:22-10.200.16.10:43804.service - OpenSSH per-connection server daemon (10.200.16.10:43804). Aug 13 00:24:57.553988 sshd[4262]: Accepted publickey for core from 10.200.16.10 port 43804 ssh2: RSA SHA256:SBsZYx70TPYFFSXAJ6H1pKUrUrWd7UrZsv34XJzQ5MI Aug 13 00:24:57.555329 sshd-session[4262]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:24:57.559773 systemd-logind[1875]: New session 9 of user core. Aug 13 00:24:57.567293 systemd[1]: Started session-9.scope - Session 9 of User core. Aug 13 00:24:58.002348 sshd[4707]: Connection closed by 10.200.16.10 port 43804 Aug 13 00:24:58.002687 sshd-session[4262]: pam_unix(sshd:session): session closed for user core Aug 13 00:24:58.005995 systemd[1]: sshd@6-10.200.8.11:22-10.200.16.10:43804.service: Deactivated successfully. Aug 13 00:24:58.007590 systemd[1]: session-9.scope: Deactivated successfully. Aug 13 00:24:58.008290 systemd-logind[1875]: Session 9 logged out. Waiting for processes to exit. Aug 13 00:24:58.009326 systemd-logind[1875]: Removed session 9. Aug 13 00:25:08.118365 systemd[1]: Started sshd@7-10.200.8.11:22-10.200.16.10:34828.service - OpenSSH per-connection server daemon (10.200.16.10:34828). Aug 13 00:25:08.759045 sshd[6440]: Accepted publickey for core from 10.200.16.10 port 34828 ssh2: RSA SHA256:SBsZYx70TPYFFSXAJ6H1pKUrUrWd7UrZsv34XJzQ5MI Aug 13 00:25:08.760382 sshd-session[6440]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:25:08.765540 systemd-logind[1875]: New session 10 of user core. Aug 13 00:25:08.774328 systemd[1]: Started session-10.scope - Session 10 of User core. Aug 13 00:25:09.208769 sshd[6536]: Connection closed by 10.200.16.10 port 34828 Aug 13 00:25:09.209048 sshd-session[6440]: pam_unix(sshd:session): session closed for user core Aug 13 00:25:09.213082 systemd[1]: sshd@7-10.200.8.11:22-10.200.16.10:34828.service: Deactivated successfully. Aug 13 00:25:09.215453 systemd[1]: session-10.scope: Deactivated successfully. Aug 13 00:25:09.218329 systemd-logind[1875]: Session 10 logged out. Waiting for processes to exit. Aug 13 00:25:09.219446 systemd-logind[1875]: Removed session 10. Aug 13 00:25:09.320839 systemd[1]: Started sshd@8-10.200.8.11:22-10.200.16.10:34834.service - OpenSSH per-connection server daemon (10.200.16.10:34834). Aug 13 00:25:09.952812 sshd[6637]: Accepted publickey for core from 10.200.16.10 port 34834 ssh2: RSA SHA256:SBsZYx70TPYFFSXAJ6H1pKUrUrWd7UrZsv34XJzQ5MI Aug 13 00:25:09.954254 sshd-session[6637]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:25:09.960043 systemd-logind[1875]: New session 11 of user core. Aug 13 00:25:09.967285 systemd[1]: Started session-11.scope - Session 11 of User core. Aug 13 00:25:10.403498 sshd[6754]: Connection closed by 10.200.16.10 port 34834 Aug 13 00:25:10.405109 sshd-session[6637]: pam_unix(sshd:session): session closed for user core Aug 13 00:25:10.408859 systemd[1]: sshd@8-10.200.8.11:22-10.200.16.10:34834.service: Deactivated successfully. Aug 13 00:25:10.410609 systemd[1]: session-11.scope: Deactivated successfully. Aug 13 00:25:10.412005 systemd-logind[1875]: Session 11 logged out. Waiting for processes to exit. Aug 13 00:25:10.413270 systemd-logind[1875]: Removed session 11. Aug 13 00:25:20.518376 systemd[1]: Started sshd@9-10.200.8.11:22-10.200.16.10:45614.service - OpenSSH per-connection server daemon (10.200.16.10:45614). Aug 13 00:25:21.166679 sshd[9658]: Accepted publickey for core from 10.200.16.10 port 45614 ssh2: RSA SHA256:SBsZYx70TPYFFSXAJ6H1pKUrUrWd7UrZsv34XJzQ5MI Aug 13 00:25:21.168091 sshd-session[9658]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:25:21.175028 systemd-logind[1875]: New session 12 of user core. Aug 13 00:25:21.178267 systemd[1]: Started session-12.scope - Session 12 of User core. Aug 13 00:25:21.618538 sshd[9918]: Connection closed by 10.200.16.10 port 45614 Aug 13 00:25:21.619088 sshd-session[9658]: pam_unix(sshd:session): session closed for user core Aug 13 00:25:21.624047 systemd-logind[1875]: Session 12 logged out. Waiting for processes to exit. Aug 13 00:25:21.626590 systemd[1]: sshd@9-10.200.8.11:22-10.200.16.10:45614.service: Deactivated successfully. Aug 13 00:25:21.629031 systemd[1]: session-12.scope: Deactivated successfully. Aug 13 00:25:21.633207 systemd-logind[1875]: Removed session 12. Aug 13 00:25:21.738394 systemd[1]: Started sshd@10-10.200.8.11:22-10.200.16.10:45630.service - OpenSSH per-connection server daemon (10.200.16.10:45630). Aug 13 00:25:22.384032 sshd[10133]: Accepted publickey for core from 10.200.16.10 port 45630 ssh2: RSA SHA256:SBsZYx70TPYFFSXAJ6H1pKUrUrWd7UrZsv34XJzQ5MI Aug 13 00:25:22.387090 sshd-session[10133]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:25:22.392401 systemd-logind[1875]: New session 13 of user core. Aug 13 00:25:22.401606 systemd[1]: Started session-13.scope - Session 13 of User core. Aug 13 00:25:22.830470 sshd[10252]: Connection closed by 10.200.16.10 port 45630 Aug 13 00:25:22.832528 sshd-session[10133]: pam_unix(sshd:session): session closed for user core Aug 13 00:25:22.835618 systemd[1]: sshd@10-10.200.8.11:22-10.200.16.10:45630.service: Deactivated successfully. Aug 13 00:25:22.837705 systemd[1]: session-13.scope: Deactivated successfully. Aug 13 00:25:22.839305 systemd-logind[1875]: Session 13 logged out. Waiting for processes to exit. Aug 13 00:25:22.842037 systemd-logind[1875]: Removed session 13. Aug 13 00:25:32.952755 systemd[1]: Started sshd@11-10.200.8.11:22-10.200.16.10:35820.service - OpenSSH per-connection server daemon (10.200.16.10:35820). Aug 13 00:25:33.599245 sshd[11699]: Accepted publickey for core from 10.200.16.10 port 35820 ssh2: RSA SHA256:SBsZYx70TPYFFSXAJ6H1pKUrUrWd7UrZsv34XJzQ5MI Aug 13 00:25:33.600540 sshd-session[11699]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:25:33.607646 systemd-logind[1875]: New session 14 of user core. Aug 13 00:25:33.612100 systemd[1]: Started session-14.scope - Session 14 of User core. Aug 13 00:25:34.048447 sshd[11744]: Connection closed by 10.200.16.10 port 35820 Aug 13 00:25:34.048815 sshd-session[11699]: pam_unix(sshd:session): session closed for user core Aug 13 00:25:34.052688 systemd-logind[1875]: Session 14 logged out. Waiting for processes to exit. Aug 13 00:25:34.054172 systemd[1]: sshd@11-10.200.8.11:22-10.200.16.10:35820.service: Deactivated successfully. Aug 13 00:25:34.055661 systemd[1]: session-14.scope: Deactivated successfully. Aug 13 00:25:34.058115 systemd-logind[1875]: Removed session 14. Aug 13 00:25:34.167987 systemd[1]: Started sshd@12-10.200.8.11:22-10.200.16.10:35832.service - OpenSSH per-connection server daemon (10.200.16.10:35832). Aug 13 00:25:34.820069 sshd[11761]: Accepted publickey for core from 10.200.16.10 port 35832 ssh2: RSA SHA256:SBsZYx70TPYFFSXAJ6H1pKUrUrWd7UrZsv34XJzQ5MI Aug 13 00:25:34.823060 sshd-session[11761]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:25:34.832473 systemd-logind[1875]: New session 15 of user core. Aug 13 00:25:34.842341 systemd[1]: Started session-15.scope - Session 15 of User core. Aug 13 00:25:35.268050 sshd[11786]: Connection closed by 10.200.16.10 port 35832 Aug 13 00:25:35.268655 sshd-session[11761]: pam_unix(sshd:session): session closed for user core Aug 13 00:25:35.272119 systemd-logind[1875]: Session 15 logged out. Waiting for processes to exit. Aug 13 00:25:35.273695 systemd[1]: sshd@12-10.200.8.11:22-10.200.16.10:35832.service: Deactivated successfully. Aug 13 00:25:35.275512 systemd[1]: session-15.scope: Deactivated successfully. Aug 13 00:25:35.279410 systemd-logind[1875]: Removed session 15. Aug 13 00:25:45.381484 systemd[1]: Started sshd@13-10.200.8.11:22-10.200.16.10:40372.service - OpenSSH per-connection server daemon (10.200.16.10:40372). Aug 13 00:25:46.025640 sshd[12111]: Accepted publickey for core from 10.200.16.10 port 40372 ssh2: RSA SHA256:SBsZYx70TPYFFSXAJ6H1pKUrUrWd7UrZsv34XJzQ5MI Aug 13 00:25:46.028052 sshd-session[12111]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:25:46.036060 systemd-logind[1875]: New session 16 of user core. Aug 13 00:25:46.049255 systemd[1]: Started session-16.scope - Session 16 of User core. Aug 13 00:25:46.476727 sshd[12143]: Connection closed by 10.200.16.10 port 40372 Aug 13 00:25:46.477475 sshd-session[12111]: pam_unix(sshd:session): session closed for user core Aug 13 00:25:46.483416 systemd-logind[1875]: Session 16 logged out. Waiting for processes to exit. Aug 13 00:25:46.483960 systemd[1]: sshd@13-10.200.8.11:22-10.200.16.10:40372.service: Deactivated successfully. Aug 13 00:25:46.485645 systemd[1]: session-16.scope: Deactivated successfully. Aug 13 00:25:46.487838 systemd-logind[1875]: Removed session 16. Aug 13 00:25:46.589966 systemd[1]: Started sshd@14-10.200.8.11:22-10.200.16.10:40380.service - OpenSSH per-connection server daemon (10.200.16.10:40380). Aug 13 00:25:47.249209 sshd[12173]: Accepted publickey for core from 10.200.16.10 port 40380 ssh2: RSA SHA256:SBsZYx70TPYFFSXAJ6H1pKUrUrWd7UrZsv34XJzQ5MI Aug 13 00:25:47.250056 sshd-session[12173]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:25:47.256221 systemd-logind[1875]: New session 17 of user core. Aug 13 00:25:47.267324 systemd[1]: Started session-17.scope - Session 17 of User core. Aug 13 00:25:47.700125 sshd[12187]: Connection closed by 10.200.16.10 port 40380 Aug 13 00:25:47.703334 sshd-session[12173]: pam_unix(sshd:session): session closed for user core Aug 13 00:25:47.707056 systemd[1]: sshd@14-10.200.8.11:22-10.200.16.10:40380.service: Deactivated successfully. Aug 13 00:25:47.709329 systemd[1]: session-17.scope: Deactivated successfully. Aug 13 00:25:47.712382 systemd-logind[1875]: Session 17 logged out. Waiting for processes to exit. Aug 13 00:25:47.713746 systemd-logind[1875]: Removed session 17. Aug 13 00:25:57.818413 systemd[1]: Started sshd@15-10.200.8.11:22-10.200.16.10:36612.service - OpenSSH per-connection server daemon (10.200.16.10:36612). Aug 13 00:25:58.461901 sshd[12543]: Accepted publickey for core from 10.200.16.10 port 36612 ssh2: RSA SHA256:SBsZYx70TPYFFSXAJ6H1pKUrUrWd7UrZsv34XJzQ5MI Aug 13 00:25:58.463273 sshd-session[12543]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:25:58.467807 systemd-logind[1875]: New session 18 of user core. Aug 13 00:25:58.476300 systemd[1]: Started session-18.scope - Session 18 of User core. Aug 13 00:25:58.904650 sshd[12564]: Connection closed by 10.200.16.10 port 36612 Aug 13 00:25:58.905023 sshd-session[12543]: pam_unix(sshd:session): session closed for user core Aug 13 00:25:58.908441 systemd[1]: sshd@15-10.200.8.11:22-10.200.16.10:36612.service: Deactivated successfully. Aug 13 00:25:58.909972 systemd[1]: session-18.scope: Deactivated successfully. Aug 13 00:25:58.910657 systemd-logind[1875]: Session 18 logged out. Waiting for processes to exit. Aug 13 00:25:58.911663 systemd-logind[1875]: Removed session 18. Aug 13 00:25:59.028445 systemd[1]: Started sshd@16-10.200.8.11:22-10.200.16.10:36620.service - OpenSSH per-connection server daemon (10.200.16.10:36620). Aug 13 00:25:59.656814 sshd[12570]: Accepted publickey for core from 10.200.16.10 port 36620 ssh2: RSA SHA256:SBsZYx70TPYFFSXAJ6H1pKUrUrWd7UrZsv34XJzQ5MI Aug 13 00:25:59.658488 sshd-session[12570]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:25:59.664925 systemd-logind[1875]: New session 19 of user core. Aug 13 00:25:59.677277 systemd[1]: Started session-19.scope - Session 19 of User core. Aug 13 00:26:00.105749 sshd[12572]: Connection closed by 10.200.16.10 port 36620 Aug 13 00:26:00.106093 sshd-session[12570]: pam_unix(sshd:session): session closed for user core Aug 13 00:26:00.109543 systemd[1]: sshd@16-10.200.8.11:22-10.200.16.10:36620.service: Deactivated successfully. Aug 13 00:26:00.111072 systemd[1]: session-19.scope: Deactivated successfully. Aug 13 00:26:00.111748 systemd-logind[1875]: Session 19 logged out. Waiting for processes to exit. Aug 13 00:26:00.112699 systemd-logind[1875]: Removed session 19. Aug 13 00:26:07.278743 setup-nvidia[2406]: \u000d Aug 13 00:26:07.278743 setup-nvidia[2406]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Aug 13 00:26:07.278743 setup-nvidia[2406]: frequently when this kernel module was built against the wrong or Aug 13 00:26:07.278743 setup-nvidia[2406]: improperly configured kernel sources, with a version of gcc that differs Aug 13 00:26:07.278743 setup-nvidia[2406]: from the one used to build the target kernel, or if another driver, such Aug 13 00:26:07.278743 setup-nvidia[2406]: as nouveau, is present and prevents the NVIDIA kernel module from Aug 13 00:26:07.278743 setup-nvidia[2406]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Aug 13 00:26:07.278743 setup-nvidia[2406]: installed in this system is supported by this NVIDIA Linux graphics Aug 13 00:26:07.278743 setup-nvidia[2406]: driver release. Aug 13 00:26:07.278743 setup-nvidia[2406]: \u000d Aug 13 00:26:07.278743 setup-nvidia[2406]: Please see the log entries 'Kernel module load error' and 'Kernel Aug 13 00:26:07.278743 setup-nvidia[2406]: messages' at the end of the file Aug 13 00:26:07.278743 setup-nvidia[2406]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more Aug 13 00:26:07.278743 setup-nvidia[2406]: information. Aug 13 00:26:07.278743 setup-nvidia[2406]: \u000d Aug 13 00:26:07.293784 setup-nvidia[2406]: dmesg: read kernel buffer failed: Operation not permitted Aug 13 00:26:07.312487 setup-nvidia[2406]: \u000d Aug 13 00:26:07.312487 setup-nvidia[2406]: ERROR: Installation has failed. Please see the file Aug 13 00:26:07.312487 setup-nvidia[2406]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for Aug 13 00:26:07.312487 setup-nvidia[2406]: details. You may find suggestions on fixing installation problems in Aug 13 00:26:07.312487 setup-nvidia[2406]: the README available on the Linux driver download page at Aug 13 00:26:07.312487 setup-nvidia[2406]: www.nvidia.com. Aug 13 00:26:07.312487 setup-nvidia[2406]: \u000d Aug 13 00:26:07.313056 setup-nvidia[2406]: Last 50 lines of nvidia-installer.log: Aug 13 00:26:07.314028 setup-nvidia[2406]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039000rm+0x53: 'naked' return found in MITIGATION_RETHUNK build Aug 13 00:26:07.314028 setup-nvidia[2406]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038997rm+0x90: 'naked' return found in MITIGATION_RETHUNK build Aug 13 00:26:07.314028 setup-nvidia[2406]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038990rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build Aug 13 00:26:07.314028 setup-nvidia[2406]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039046rm+0x93: 'naked' return found in MITIGATION_RETHUNK build Aug 13 00:26:07.314028 setup-nvidia[2406]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039042rm+0x36: 'naked' return found in MITIGATION_RETHUNK build Aug 13 00:26:07.314028 setup-nvidia[2406]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039041rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build Aug 13 00:26:07.314028 setup-nvidia[2406]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039043rm+0x77: 'naked' return found in MITIGATION_RETHUNK build Aug 13 00:26:07.314028 setup-nvidia[2406]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038987rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build Aug 13 00:26:07.314028 setup-nvidia[2406]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030558rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build Aug 13 00:26:07.314028 setup-nvidia[2406]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030557rm+0x27: 'naked' return found in MITIGATION_RETHUNK build Aug 13 00:26:07.314028 setup-nvidia[2406]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030561rm+0x37: 'naked' return found in MITIGATION_RETHUNK build Aug 13 00:26:07.314028 setup-nvidia[2406]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030560rm+0x24: 'naked' return found in MITIGATION_RETHUNK build Aug 13 00:26:07.314028 setup-nvidia[2406]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030559rm+0xca: 'naked' return found in MITIGATION_RETHUNK build Aug 13 00:26:07.314028 setup-nvidia[2406]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030562rm+0x193: 'naked' return found in MITIGATION_RETHUNK build Aug 13 00:26:07.314028 setup-nvidia[2406]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030564rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build Aug 13 00:26:07.314028 setup-nvidia[2406]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv011860rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build Aug 13 00:26:07.314028 setup-nvidia[2406]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039354rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build Aug 13 00:26:07.314028 setup-nvidia[2406]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/Module.symvers Aug 13 00:26:07.314028 setup-nvidia[2406]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.mod.o Aug 13 00:26:07.314028 setup-nvidia[2406]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/.module-common.o Aug 13 00:26:07.314028 setup-nvidia[2406]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.mod.o Aug 13 00:26:07.314028 setup-nvidia[2406]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.mod.o Aug 13 00:26:07.314028 setup-nvidia[2406]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.mod.o Aug 13 00:26:07.314028 setup-nvidia[2406]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.mod.o Aug 13 00:26:07.314028 setup-nvidia[2406]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Aug 13 00:26:07.314028 setup-nvidia[2406]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Aug 13 00:26:07.314028 setup-nvidia[2406]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Aug 13 00:26:07.314028 setup-nvidia[2406]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Aug 13 00:26:07.314028 setup-nvidia[2406]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Aug 13 00:26:07.314028 setup-nvidia[2406]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Aug 13 00:26:07.314028 setup-nvidia[2406]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko due to unavailability of vmlinux Aug 13 00:26:07.314028 setup-nvidia[2406]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Aug 13 00:26:07.314028 setup-nvidia[2406]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko due to unavailability of vmlinux Aug 13 00:26:07.314028 setup-nvidia[2406]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Aug 13 00:26:07.314028 setup-nvidia[2406]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko due to unavailability of vmlinux Aug 13 00:26:07.314028 setup-nvidia[2406]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Aug 13 00:26:07.314028 setup-nvidia[2406]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko due to unavailability of vmlinux Aug 13 00:26:07.314028 setup-nvidia[2406]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Aug 13 00:26:07.314028 setup-nvidia[2406]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko due to unavailability of vmlinux Aug 13 00:26:07.314028 setup-nvidia[2406]: make[2]: Leaving directory '/usr/lib/modules/6.12.40-flatcar/build' Aug 13 00:26:07.314028 setup-nvidia[2406]: make[1]: Leaving directory '/usr/lib/modules/6.12.40-flatcar/source' Aug 13 00:26:07.314028 setup-nvidia[2406]: -> done. Aug 13 00:26:07.314028 setup-nvidia[2406]: -> Kernel module compilation complete. Aug 13 00:26:07.314028 setup-nvidia[2406]: -> Unable to determine if Secure Boot is enabled: No such file or directory Aug 13 00:26:07.314028 setup-nvidia[2406]: 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 13 00:26:07.315124 setup-nvidia[2406]: \u000d Aug 13 00:26:07.315124 setup-nvidia[2406]: Please see the log entries 'Kernel module load error' and 'Kernel messages' at the end of the file '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more information. Aug 13 00:26:07.315124 setup-nvidia[2406]: -> Kernel module load error: Operation not permitted Aug 13 00:26:07.315124 setup-nvidia[2406]: -> Kernel messages: Aug 13 00:26:07.315124 setup-nvidia[2406]: ERROR: Installation has failed. Please see the file '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for details. You may find suggestions on fixing installation problems in the README available on the Linux driver download page at www.nvidia.com. Aug 13 00:26:10.225032 systemd[1]: Started sshd@17-10.200.8.11:22-10.200.16.10:52004.service - OpenSSH per-connection server daemon (10.200.16.10:52004). Aug 13 00:26:10.855351 sshd[12758]: Accepted publickey for core from 10.200.16.10 port 52004 ssh2: RSA SHA256:SBsZYx70TPYFFSXAJ6H1pKUrUrWd7UrZsv34XJzQ5MI Aug 13 00:26:10.856712 sshd-session[12758]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:26:10.860950 systemd-logind[1875]: New session 20 of user core. Aug 13 00:26:10.872276 systemd[1]: Started session-20.scope - Session 20 of User core. Aug 13 00:26:11.299484 sshd[12760]: Connection closed by 10.200.16.10 port 52004 Aug 13 00:26:11.299869 sshd-session[12758]: pam_unix(sshd:session): session closed for user core Aug 13 00:26:11.303317 systemd[1]: sshd@17-10.200.8.11:22-10.200.16.10:52004.service: Deactivated successfully. Aug 13 00:26:11.304986 systemd[1]: session-20.scope: Deactivated successfully. Aug 13 00:26:11.305765 systemd-logind[1875]: Session 20 logged out. Waiting for processes to exit. Aug 13 00:26:11.306744 systemd-logind[1875]: Removed session 20. Aug 13 00:26:11.410709 systemd[1]: Started sshd@18-10.200.8.11:22-10.200.16.10:52006.service - OpenSSH per-connection server daemon (10.200.16.10:52006). Aug 13 00:26:12.044624 sshd[12766]: Accepted publickey for core from 10.200.16.10 port 52006 ssh2: RSA SHA256:SBsZYx70TPYFFSXAJ6H1pKUrUrWd7UrZsv34XJzQ5MI Aug 13 00:26:12.045974 sshd-session[12766]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:26:12.050361 systemd-logind[1875]: New session 21 of user core. Aug 13 00:26:12.067296 systemd[1]: Started session-21.scope - Session 21 of User core. Aug 13 00:26:12.493135 sshd[12768]: Connection closed by 10.200.16.10 port 52006 Aug 13 00:26:12.493502 sshd-session[12766]: pam_unix(sshd:session): session closed for user core Aug 13 00:26:12.497306 systemd[1]: sshd@18-10.200.8.11:22-10.200.16.10:52006.service: Deactivated successfully. Aug 13 00:26:12.498851 systemd[1]: session-21.scope: Deactivated successfully. Aug 13 00:26:12.499574 systemd-logind[1875]: Session 21 logged out. Waiting for processes to exit. Aug 13 00:26:12.500509 systemd-logind[1875]: Removed session 21. Aug 13 00:26:21.415172 kernel: EXT4-fs (loop6p9): unmounting filesystem 5379994e-ad1d-4390-9695-fc97a8ee658f. Aug 13 00:26:21.415660 setup-nvidia[2406]: Container flatcardevelopercontainer-4372.1.0.bin exited successfully. Aug 13 00:26:21.417921 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4372.1.0.bin.mount: Deactivated successfully. Aug 13 00:26:21.469069 sudo[2394]: pam_unix(sudo:session): session closed for user root Aug 13 00:26:21.471184 systemd[1]: session-c2.scope: Deactivated successfully. Aug 13 00:26:21.471467 systemd[1]: session-c2.scope: Consumed 5min 9.779s CPU time, 893.1M memory peak. Aug 13 00:26:21.887773 setup-nvidia[1870]: /opt/nvidia / Aug 13 00:26:21.888793 setup-nvidia[1870]: / Aug 13 00:26:21.898175 kernel: IPMI message handler: version 39.2 Aug 13 00:26:21.901181 kernel: ipmi device interface Aug 13 00:26:21.913831 setup-nvidia[1870]: /opt/nvidia/current/usr/lib/modules/6.12.40-flatcar/video / Aug 13 00:26:21.966010 kernel: nvidia: loading out-of-tree module taints kernel. Aug 13 00:26:21.966138 kernel: nvidia: module license 'NVIDIA' taints kernel. Aug 13 00:26:21.966185 kernel: Disabling lock debugging due to kernel taint Aug 13 00:26:21.973347 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Aug 13 00:26:21.973391 kernel: nvidia: module license taints kernel. Aug 13 00:26:22.362180 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 Aug 13 00:26:22.764038 kernel: Aug 13 00:26:22.764211 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Aug 13 00:26:22.878787 systemd[1]: Started sshd@19-10.200.8.11:22-10.200.16.10:48748.service - OpenSSH per-connection server daemon (10.200.16.10:48748). Aug 13 00:26:22.975167 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.230.02 Fri Dec 20 21:42:05 UTC 2024 Aug 13 00:26:22.995169 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.230.02 Fri Dec 20 21:52:34 UTC 2024 Aug 13 00:26:23.014184 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Aug 13 00:26:23.057172 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. Aug 13 00:26:23.057345 setup-nvidia[1870]: / Aug 13 00:26:23.072483 setup-nvidia[12811]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Aug 13 00:26:23.507690 sshd[12802]: Accepted publickey for core from 10.200.16.10 port 48748 ssh2: RSA SHA256:SBsZYx70TPYFFSXAJ6H1pKUrUrWd7UrZsv34XJzQ5MI Aug 13 00:26:23.509026 sshd-session[12802]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:26:23.513417 systemd-logind[1875]: New session 22 of user core. Aug 13 00:26:23.525280 systemd[1]: Started session-22.scope - Session 22 of User core. Aug 13 00:26:23.956752 sshd[12832]: Connection closed by 10.200.16.10 port 48748 Aug 13 00:26:23.957222 sshd-session[12802]: pam_unix(sshd:session): session closed for user core Aug 13 00:26:23.961185 systemd[1]: sshd@19-10.200.8.11:22-10.200.16.10:48748.service: Deactivated successfully. Aug 13 00:26:23.962834 systemd[1]: session-22.scope: Deactivated successfully. Aug 13 00:26:23.963561 systemd-logind[1875]: Session 22 logged out. Waiting for processes to exit. Aug 13 00:26:23.964737 systemd-logind[1875]: Removed session 22. Aug 13 00:26:24.068638 systemd[1]: Started sshd@20-10.200.8.11:22-10.200.16.10:48764.service - OpenSSH per-connection server daemon (10.200.16.10:48764). Aug 13 00:26:24.718423 sshd[12838]: Accepted publickey for core from 10.200.16.10 port 48764 ssh2: RSA SHA256:SBsZYx70TPYFFSXAJ6H1pKUrUrWd7UrZsv34XJzQ5MI Aug 13 00:26:24.719818 sshd-session[12838]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:26:24.724221 systemd-logind[1875]: New session 23 of user core. Aug 13 00:26:24.735281 systemd[1]: Started session-23.scope - Session 23 of User core. Aug 13 00:26:24.818556 setup-nvidia[12828]: Wed Aug 13 00:26:24 2025 Aug 13 00:26:24.818556 setup-nvidia[12828]: +---------------------------------------------------------------------------------------+ Aug 13 00:26:24.818556 setup-nvidia[12828]: | NVIDIA-SMI 535.230.02 Driver Version: 535.230.02 CUDA Version: 12.2 | Aug 13 00:26:24.818556 setup-nvidia[12828]: |-----------------------------------------+----------------------+----------------------+ Aug 13 00:26:24.818556 setup-nvidia[12828]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Aug 13 00:26:24.818556 setup-nvidia[12828]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Aug 13 00:26:24.818556 setup-nvidia[12828]: | | | MIG M. | Aug 13 00:26:24.818556 setup-nvidia[12828]: |=========================================+======================+======================| Aug 13 00:26:24.822087 setup-nvidia[12828]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Aug 13 00:26:24.822087 setup-nvidia[12828]: | N/A 28C P0 35W / 250W | 0MiB / 16384MiB | 0% Default | Aug 13 00:26:24.822087 setup-nvidia[12828]: | | | N/A | Aug 13 00:26:24.822087 setup-nvidia[12828]: +-----------------------------------------+----------------------+----------------------+ Aug 13 00:26:24.822225 setup-nvidia[12828]: Aug 13 00:26:24.822225 setup-nvidia[12828]: +---------------------------------------------------------------------------------------+ Aug 13 00:26:24.822225 setup-nvidia[12828]: | Processes: | Aug 13 00:26:24.822225 setup-nvidia[12828]: | GPU GI CI PID Type Process name GPU Memory | Aug 13 00:26:24.822225 setup-nvidia[12828]: | ID ID Usage | Aug 13 00:26:24.822225 setup-nvidia[12828]: |=======================================================================================| Aug 13 00:26:24.822397 setup-nvidia[12828]: | No running processes found | Aug 13 00:26:24.822397 setup-nvidia[12828]: +---------------------------------------------------------------------------------------+ Aug 13 00:26:25.162208 sshd[12840]: Connection closed by 10.200.16.10 port 48764 Aug 13 00:26:25.162564 sshd-session[12838]: pam_unix(sshd:session): session closed for user core Aug 13 00:26:25.166139 systemd[1]: sshd@20-10.200.8.11:22-10.200.16.10:48764.service: Deactivated successfully. Aug 13 00:26:25.167705 systemd[1]: session-23.scope: Deactivated successfully. Aug 13 00:26:25.168392 systemd-logind[1875]: Session 23 logged out. Waiting for processes to exit. Aug 13 00:26:25.169351 systemd-logind[1875]: Removed session 23. Aug 13 00:26:25.203857 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Aug 13 00:26:25.206327 systemd[1]: Reached target multi-user.target - Multi-User System. Aug 13 00:26:25.207196 systemd[1]: Startup finished in 1.186s (firmware) + 27.652s (loader) + 3.876s (kernel) + 13.012s (initrd) + 2min 38.254s (userspace) = 3min 23.983s. Aug 13 00:26:31.477321 systemd[1]: Stopping user@0.service - User Manager for UID 0... Aug 13 00:26:31.478088 systemd[2397]: Activating special unit exit.target... Aug 13 00:26:31.478191 systemd[2397]: Stopped target default.target - Main User Target. Aug 13 00:26:31.478232 systemd[2397]: Stopped target basic.target - Basic System. Aug 13 00:26:31.478260 systemd[2397]: Stopped target paths.target - Paths. Aug 13 00:26:31.478286 systemd[2397]: Stopped target sockets.target - Sockets. Aug 13 00:26:31.478311 systemd[2397]: Stopped target timers.target - Timers. Aug 13 00:26:31.478654 systemd[2397]: Closed dbus.socket - D-Bus User Message Bus Socket. Aug 13 00:26:31.478858 systemd[2397]: Removed slice app.slice - User Application Slice. Aug 13 00:26:31.478891 systemd[2397]: Reached target shutdown.target - Shutdown. Aug 13 00:26:31.479030 systemd[2397]: Finished systemd-exit.service - Exit the Session. Aug 13 00:26:31.479066 systemd[2397]: Reached target exit.target - Exit the Session. Aug 13 00:26:31.495121 (sd-pam)[2398]: pam_unix(systemd-user:session): session closed for user root Aug 13 00:26:31.496541 systemd[1]: user@0.service: Deactivated successfully. Aug 13 00:26:31.496897 systemd[1]: Stopped user@0.service - User Manager for UID 0. Aug 13 00:26:31.499177 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Aug 13 00:26:31.529055 systemd[1]: run-user-0.mount: Deactivated successfully. Aug 13 00:26:31.529973 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Aug 13 00:26:31.530188 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Aug 13 00:26:31.531783 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Aug 13 00:26:31.531874 systemd[1]: user-0.slice: Consumed 5min 10.086s CPU time, 902.3M memory peak. Aug 13 00:26:31.532315 systemd-logind[1875]: Removed session c3. Aug 13 00:26:35.285544 systemd[1]: Started sshd@21-10.200.8.11:22-10.200.16.10:50162.service - OpenSSH per-connection server daemon (10.200.16.10:50162). Aug 13 00:26:35.914520 sshd[12852]: Accepted publickey for core from 10.200.16.10 port 50162 ssh2: RSA SHA256:SBsZYx70TPYFFSXAJ6H1pKUrUrWd7UrZsv34XJzQ5MI Aug 13 00:26:35.915850 sshd-session[12852]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:26:35.920271 systemd-logind[1875]: New session 24 of user core. Aug 13 00:26:35.933264 systemd[1]: Started session-24.scope - Session 24 of User core. Aug 13 00:26:36.360872 sshd[12854]: Connection closed by 10.200.16.10 port 50162 Aug 13 00:26:36.361264 sshd-session[12852]: pam_unix(sshd:session): session closed for user core Aug 13 00:26:36.364878 systemd[1]: sshd@21-10.200.8.11:22-10.200.16.10:50162.service: Deactivated successfully. Aug 13 00:26:36.366426 systemd[1]: session-24.scope: Deactivated successfully. Aug 13 00:26:36.367077 systemd-logind[1875]: Session 24 logged out. Waiting for processes to exit. Aug 13 00:26:36.368012 systemd-logind[1875]: Removed session 24. Aug 13 00:26:36.476553 systemd[1]: Started sshd@22-10.200.8.11:22-10.200.16.10:50166.service - OpenSSH per-connection server daemon (10.200.16.10:50166). Aug 13 00:26:37.126596 sshd[12860]: Accepted publickey for core from 10.200.16.10 port 50166 ssh2: RSA SHA256:SBsZYx70TPYFFSXAJ6H1pKUrUrWd7UrZsv34XJzQ5MI Aug 13 00:26:37.127942 sshd-session[12860]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:26:37.132354 systemd-logind[1875]: New session 25 of user core. Aug 13 00:26:37.149284 systemd[1]: Started session-25.scope - Session 25 of User core. Aug 13 00:26:37.566737 sshd[12862]: Connection closed by 10.200.16.10 port 50166 Aug 13 00:26:37.567113 sshd-session[12860]: pam_unix(sshd:session): session closed for user core Aug 13 00:26:37.570665 systemd[1]: sshd@22-10.200.8.11:22-10.200.16.10:50166.service: Deactivated successfully. Aug 13 00:26:37.572293 systemd[1]: session-25.scope: Deactivated successfully. Aug 13 00:26:37.572963 systemd-logind[1875]: Session 25 logged out. Waiting for processes to exit. Aug 13 00:26:37.573921 systemd-logind[1875]: Removed session 25. Aug 13 00:26:37.681408 systemd[1]: Started sshd@23-10.200.8.11:22-10.200.16.10:50168.service - OpenSSH per-connection server daemon (10.200.16.10:50168). Aug 13 00:26:38.313528 sshd[12868]: Accepted publickey for core from 10.200.16.10 port 50168 ssh2: RSA SHA256:SBsZYx70TPYFFSXAJ6H1pKUrUrWd7UrZsv34XJzQ5MI Aug 13 00:26:38.314912 sshd-session[12868]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:26:38.319201 systemd-logind[1875]: New session 26 of user core. Aug 13 00:26:38.328298 systemd[1]: Started session-26.scope - Session 26 of User core. Aug 13 00:26:38.762073 sshd[12870]: Connection closed by 10.200.16.10 port 50168 Aug 13 00:26:38.762446 sshd-session[12868]: pam_unix(sshd:session): session closed for user core Aug 13 00:26:38.765854 systemd[1]: sshd@23-10.200.8.11:22-10.200.16.10:50168.service: Deactivated successfully. Aug 13 00:26:38.767469 systemd[1]: session-26.scope: Deactivated successfully. Aug 13 00:26:38.768164 systemd-logind[1875]: Session 26 logged out. Waiting for processes to exit. Aug 13 00:26:38.769106 systemd-logind[1875]: Removed session 26. Aug 13 00:26:38.879589 systemd[1]: Started sshd@24-10.200.8.11:22-10.200.16.10:50180.service - OpenSSH per-connection server daemon (10.200.16.10:50180). Aug 13 00:26:39.529217 sshd[12876]: Accepted publickey for core from 10.200.16.10 port 50180 ssh2: RSA SHA256:SBsZYx70TPYFFSXAJ6H1pKUrUrWd7UrZsv34XJzQ5MI Aug 13 00:26:39.530601 sshd-session[12876]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:26:39.534900 systemd-logind[1875]: New session 27 of user core. Aug 13 00:26:39.545266 systemd[1]: Started session-27.scope - Session 27 of User core. Aug 13 00:26:39.980999 sshd[12878]: Connection closed by 10.200.16.10 port 50180 Aug 13 00:26:39.981359 sshd-session[12876]: pam_unix(sshd:session): session closed for user core Aug 13 00:26:39.984758 systemd[1]: sshd@24-10.200.8.11:22-10.200.16.10:50180.service: Deactivated successfully. Aug 13 00:26:39.986279 systemd[1]: session-27.scope: Deactivated successfully. Aug 13 00:26:39.986960 systemd-logind[1875]: Session 27 logged out. Waiting for processes to exit. Aug 13 00:26:39.987901 systemd-logind[1875]: Removed session 27. Aug 13 00:26:40.103479 systemd[1]: Started sshd@25-10.200.8.11:22-10.200.16.10:50190.service - OpenSSH per-connection server daemon (10.200.16.10:50190). Aug 13 00:26:40.730720 sshd[12884]: Accepted publickey for core from 10.200.16.10 port 50190 ssh2: RSA SHA256:SBsZYx70TPYFFSXAJ6H1pKUrUrWd7UrZsv34XJzQ5MI Aug 13 00:26:40.732181 sshd-session[12884]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:26:40.736508 systemd-logind[1875]: New session 28 of user core. Aug 13 00:26:40.748274 systemd[1]: Started session-28.scope - Session 28 of User core. Aug 13 00:26:42.660864 sshd[12886]: Connection closed by 10.200.16.10 port 50190 Aug 13 00:26:42.661254 sshd-session[12884]: pam_unix(sshd:session): session closed for user core Aug 13 00:26:42.664718 systemd[1]: sshd@25-10.200.8.11:22-10.200.16.10:50190.service: Deactivated successfully. Aug 13 00:26:42.666341 systemd[1]: session-28.scope: Deactivated successfully. Aug 13 00:26:42.666535 systemd[1]: session-28.scope: Consumed 1.425s CPU time, 14.1M memory peak. Aug 13 00:26:42.667035 systemd-logind[1875]: Session 28 logged out. Waiting for processes to exit. Aug 13 00:26:42.667989 systemd-logind[1875]: Removed session 28.