Jul 10 00:21:59.195063 kernel: Linux version 6.12.36-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.44 p1) 2.44.0) #1 SMP PREEMPT_DYNAMIC Wed Jul 9 22:15:30 -00 2025 Jul 10 00:21:59.195093 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=844005237fb9709f65a093d5533c4229fb6c54e8e257736d9c3d041b6d3080ea Jul 10 00:21:59.195103 kernel: BIOS-provided physical RAM map: Jul 10 00:21:59.195110 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Jul 10 00:21:59.195116 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Jul 10 00:21:59.195123 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Jul 10 00:21:59.195131 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Jul 10 00:21:59.195141 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Jul 10 00:21:59.195148 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Jul 10 00:21:59.195154 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Jul 10 00:21:59.195161 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Jul 10 00:21:59.195167 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Jul 10 00:21:59.195174 kernel: printk: legacy bootconsole [earlyser0] enabled Jul 10 00:21:59.195181 kernel: NX (Execute Disable) protection: active Jul 10 00:21:59.195192 kernel: APIC: Static calls initialized Jul 10 00:21:59.195200 kernel: efi: EFI v2.7 by Microsoft Jul 10 00:21:59.195208 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Jul 10 00:21:59.195215 kernel: random: crng init done Jul 10 00:21:59.195223 kernel: secureboot: Secure boot disabled Jul 10 00:21:59.195230 kernel: SMBIOS 3.1.0 present. Jul 10 00:21:59.195238 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Jul 10 00:21:59.195245 kernel: DMI: Memory slots populated: 3/3 Jul 10 00:21:59.195253 kernel: Hypervisor detected: Microsoft Hyper-V Jul 10 00:21:59.195260 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Jul 10 00:21:59.195271 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Jul 10 00:21:59.195278 kernel: Hyper-V: Using hypercall for remote TLB flush Jul 10 00:21:59.195286 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Jul 10 00:21:59.195293 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Jul 10 00:21:59.195301 kernel: tsc: Marking TSC unstable due to running on Hyper-V Jul 10 00:21:59.195309 kernel: tsc: Detected 2593.992 MHz processor Jul 10 00:21:59.195317 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Jul 10 00:21:59.195329 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Jul 10 00:21:59.195337 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Jul 10 00:21:59.195348 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Jul 10 00:21:59.195355 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Jul 10 00:21:59.195363 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Jul 10 00:21:59.195370 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Jul 10 00:21:59.195377 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Jul 10 00:21:59.195385 kernel: Using GB pages for direct mapping Jul 10 00:21:59.195393 kernel: ACPI: Early table checksum verification disabled Jul 10 00:21:59.195405 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Jul 10 00:21:59.195415 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 10 00:21:59.195424 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 10 00:21:59.195432 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Jul 10 00:21:59.195439 kernel: ACPI: FACS 0x000000003FFFE000 000040 Jul 10 00:21:59.195447 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 10 00:21:59.195455 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 10 00:21:59.195466 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 10 00:21:59.195474 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 10 00:21:59.195482 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 10 00:21:59.195490 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 10 00:21:59.195498 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 10 00:21:59.195506 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Jul 10 00:21:59.195514 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Jul 10 00:21:59.195522 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Jul 10 00:21:59.195533 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Jul 10 00:21:59.195541 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Jul 10 00:21:59.195549 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Jul 10 00:21:59.195557 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Jul 10 00:21:59.195565 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Jul 10 00:21:59.195573 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Jul 10 00:21:59.195581 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Jul 10 00:21:59.195589 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Jul 10 00:21:59.195597 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Jul 10 00:21:59.195607 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Jul 10 00:21:59.195627 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Jul 10 00:21:59.195635 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Jul 10 00:21:59.195643 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Jul 10 00:21:59.195651 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Jul 10 00:21:59.195659 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Jul 10 00:21:59.195674 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] Jul 10 00:21:59.195683 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] Jul 10 00:21:59.195691 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] Jul 10 00:21:59.195712 kernel: Zone ranges: Jul 10 00:21:59.195721 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Jul 10 00:21:59.195734 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Jul 10 00:21:59.195743 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Jul 10 00:21:59.195751 kernel: Device empty Jul 10 00:21:59.195759 kernel: Movable zone start for each node Jul 10 00:21:59.195772 kernel: Early memory node ranges Jul 10 00:21:59.195788 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Jul 10 00:21:59.195797 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Jul 10 00:21:59.195809 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Jul 10 00:21:59.195817 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Jul 10 00:21:59.195825 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Jul 10 00:21:59.195834 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Jul 10 00:21:59.195842 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Jul 10 00:21:59.195850 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Jul 10 00:21:59.195858 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Jul 10 00:21:59.195866 kernel: ACPI: PM-Timer IO Port: 0x408 Jul 10 00:21:59.195874 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Jul 10 00:21:59.195884 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Jul 10 00:21:59.195893 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Jul 10 00:21:59.195901 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Jul 10 00:21:59.195909 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Jul 10 00:21:59.195917 kernel: CPU topo: Max. logical packages: 1 Jul 10 00:21:59.195925 kernel: CPU topo: Max. logical dies: 1 Jul 10 00:21:59.195932 kernel: CPU topo: Max. dies per package: 1 Jul 10 00:21:59.195940 kernel: CPU topo: Max. threads per core: 1 Jul 10 00:21:59.195948 kernel: CPU topo: Num. cores per package: 6 Jul 10 00:21:59.195959 kernel: CPU topo: Num. threads per package: 6 Jul 10 00:21:59.195967 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs Jul 10 00:21:59.195975 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Jul 10 00:21:59.195983 kernel: Booting paravirtualized kernel on Hyper-V Jul 10 00:21:59.195994 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Jul 10 00:21:59.196003 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Jul 10 00:21:59.196011 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 Jul 10 00:21:59.196019 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 Jul 10 00:21:59.196027 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Jul 10 00:21:59.196038 kernel: Hyper-V: PV spinlocks enabled Jul 10 00:21:59.196046 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Jul 10 00:21:59.196055 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=844005237fb9709f65a093d5533c4229fb6c54e8e257736d9c3d041b6d3080ea Jul 10 00:21:59.196064 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Jul 10 00:21:59.196072 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Jul 10 00:21:59.196080 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Jul 10 00:21:59.196088 kernel: Fallback order for Node 0: 0 Jul 10 00:21:59.196096 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 Jul 10 00:21:59.196107 kernel: Policy zone: Normal Jul 10 00:21:59.196124 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jul 10 00:21:59.196133 kernel: software IO TLB: area num 8. Jul 10 00:21:59.196144 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Jul 10 00:21:59.196152 kernel: Kernel/User page tables isolation: enabled Jul 10 00:21:59.196161 kernel: ftrace: allocating 40095 entries in 157 pages Jul 10 00:21:59.196169 kernel: ftrace: allocated 157 pages with 5 groups Jul 10 00:21:59.196178 kernel: Dynamic Preempt: voluntary Jul 10 00:21:59.196186 kernel: rcu: Preemptible hierarchical RCU implementation. Jul 10 00:21:59.196196 kernel: rcu: RCU event tracing is enabled. Jul 10 00:21:59.196204 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Jul 10 00:21:59.196213 kernel: Trampoline variant of Tasks RCU enabled. Jul 10 00:21:59.196224 kernel: Rude variant of Tasks RCU enabled. Jul 10 00:21:59.196233 kernel: Tracing variant of Tasks RCU enabled. Jul 10 00:21:59.196242 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Jul 10 00:21:59.196250 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Jul 10 00:21:59.196259 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jul 10 00:21:59.196270 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jul 10 00:21:59.196279 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jul 10 00:21:59.196288 kernel: Using NULL legacy PIC Jul 10 00:21:59.196296 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Jul 10 00:21:59.196305 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Jul 10 00:21:59.196313 kernel: Console: colour dummy device 80x25 Jul 10 00:21:59.196322 kernel: printk: legacy console [tty1] enabled Jul 10 00:21:59.196330 kernel: printk: legacy console [ttyS0] enabled Jul 10 00:21:59.196339 kernel: printk: legacy bootconsole [earlyser0] disabled Jul 10 00:21:59.196350 kernel: ACPI: Core revision 20240827 Jul 10 00:21:59.196359 kernel: Failed to register legacy timer interrupt Jul 10 00:21:59.196367 kernel: APIC: Switch to symmetric I/O mode setup Jul 10 00:21:59.196375 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Jul 10 00:21:59.196384 kernel: Hyper-V: enabling crash_kexec_post_notifiers Jul 10 00:21:59.196392 kernel: Hyper-V: Using IPI hypercalls Jul 10 00:21:59.196401 kernel: APIC: send_IPI() replaced with hv_send_ipi() Jul 10 00:21:59.196409 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Jul 10 00:21:59.196418 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Jul 10 00:21:59.196429 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Jul 10 00:21:59.196437 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Jul 10 00:21:59.196446 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Jul 10 00:21:59.196454 kernel: Hyper-V: Using enlightened APIC (xapic mode) Jul 10 00:21:59.196467 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Jul 10 00:21:59.196480 kernel: APIC: read() replaced with hv_apic_read() Jul 10 00:21:59.196491 kernel: APIC: write() replaced with hv_apic_write() Jul 10 00:21:59.196505 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Jul 10 00:21:59.196518 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Jul 10 00:21:59.196533 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593992) Jul 10 00:21:59.196542 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Jul 10 00:21:59.196550 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Jul 10 00:21:59.196559 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Jul 10 00:21:59.196567 kernel: Spectre V2 : Mitigation: Retpolines Jul 10 00:21:59.196575 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Jul 10 00:21:59.196583 kernel: Speculative Store Bypass: Vulnerable Jul 10 00:21:59.196592 kernel: MDS: Mitigation: Clear CPU buffers Jul 10 00:21:59.196600 kernel: TAA: Mitigation: Clear CPU buffers Jul 10 00:21:59.196616 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Jul 10 00:21:59.196629 kernel: ITS: Mitigation: Aligned branch/return thunks Jul 10 00:21:59.196637 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Jul 10 00:21:59.196646 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Jul 10 00:21:59.196654 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Jul 10 00:21:59.196662 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Jul 10 00:21:59.196670 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Jul 10 00:21:59.196679 kernel: Freeing SMP alternatives memory: 32K Jul 10 00:21:59.196687 kernel: pid_max: default: 32768 minimum: 301 Jul 10 00:21:59.196695 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Jul 10 00:21:59.196703 kernel: landlock: Up and running. Jul 10 00:21:59.196712 kernel: SELinux: Initializing. Jul 10 00:21:59.196723 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Jul 10 00:21:59.196731 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Jul 10 00:21:59.196740 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Jul 10 00:21:59.196753 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Jul 10 00:21:59.196770 kernel: signal: max sigframe size: 1776 Jul 10 00:21:59.196780 kernel: rcu: Hierarchical SRCU implementation. Jul 10 00:21:59.196788 kernel: rcu: Max phase no-delay instances is 400. Jul 10 00:21:59.196797 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Jul 10 00:21:59.196805 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Jul 10 00:21:59.196814 kernel: smp: Bringing up secondary CPUs ... Jul 10 00:21:59.196826 kernel: smpboot: x86: Booting SMP configuration: Jul 10 00:21:59.196834 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Jul 10 00:21:59.196843 kernel: smp: Brought up 1 node, 6 CPUs Jul 10 00:21:59.196851 kernel: smpboot: Total of 6 processors activated (31127.90 BogoMIPS) Jul 10 00:21:59.196860 kernel: Memory: 115339088K/117439364K available (14336K kernel code, 2430K rwdata, 9956K rodata, 54420K init, 2548K bss, 2090000K reserved, 0K cma-reserved) Jul 10 00:21:59.196869 kernel: devtmpfs: initialized Jul 10 00:21:59.196877 kernel: x86/mm: Memory block size: 1024MB Jul 10 00:21:59.196886 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Jul 10 00:21:59.196895 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Jul 10 00:21:59.196906 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Jul 10 00:21:59.196914 kernel: pinctrl core: initialized pinctrl subsystem Jul 10 00:21:59.196923 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jul 10 00:21:59.196931 kernel: audit: initializing netlink subsys (disabled) Jul 10 00:21:59.196940 kernel: audit: type=2000 audit(1752106915.029:1): state=initialized audit_enabled=0 res=1 Jul 10 00:21:59.196948 kernel: thermal_sys: Registered thermal governor 'step_wise' Jul 10 00:21:59.196957 kernel: thermal_sys: Registered thermal governor 'user_space' Jul 10 00:21:59.196965 kernel: cpuidle: using governor menu Jul 10 00:21:59.196974 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Jul 10 00:21:59.196985 kernel: dca service started, version 1.12.1 Jul 10 00:21:59.196993 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Jul 10 00:21:59.197002 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Jul 10 00:21:59.197010 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Jul 10 00:21:59.197019 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Jul 10 00:21:59.197027 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Jul 10 00:21:59.197036 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Jul 10 00:21:59.197044 kernel: ACPI: Added _OSI(Module Device) Jul 10 00:21:59.197053 kernel: ACPI: Added _OSI(Processor Device) Jul 10 00:21:59.197064 kernel: ACPI: Added _OSI(Processor Aggregator Device) Jul 10 00:21:59.197072 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Jul 10 00:21:59.197081 kernel: ACPI: Interpreter enabled Jul 10 00:21:59.197089 kernel: ACPI: PM: (supports S0 S5) Jul 10 00:21:59.197098 kernel: ACPI: Using IOAPIC for interrupt routing Jul 10 00:21:59.197106 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Jul 10 00:21:59.197115 kernel: PCI: Ignoring E820 reservations for host bridge windows Jul 10 00:21:59.197123 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Jul 10 00:21:59.197132 kernel: iommu: Default domain type: Translated Jul 10 00:21:59.197143 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Jul 10 00:21:59.197151 kernel: efivars: Registered efivars operations Jul 10 00:21:59.197160 kernel: PCI: Using ACPI for IRQ routing Jul 10 00:21:59.197168 kernel: PCI: System does not support PCI Jul 10 00:21:59.197177 kernel: vgaarb: loaded Jul 10 00:21:59.197185 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Jul 10 00:21:59.197194 kernel: VFS: Disk quotas dquot_6.6.0 Jul 10 00:21:59.197202 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jul 10 00:21:59.197210 kernel: pnp: PnP ACPI init Jul 10 00:21:59.197222 kernel: pnp: PnP ACPI: found 3 devices Jul 10 00:21:59.197230 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Jul 10 00:21:59.197239 kernel: NET: Registered PF_INET protocol family Jul 10 00:21:59.197247 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Jul 10 00:21:59.197256 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Jul 10 00:21:59.197264 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jul 10 00:21:59.197273 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Jul 10 00:21:59.197281 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jul 10 00:21:59.197292 kernel: TCP: Hash tables configured (established 524288 bind 65536) Jul 10 00:21:59.197301 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jul 10 00:21:59.197309 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jul 10 00:21:59.197318 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jul 10 00:21:59.197326 kernel: NET: Registered PF_XDP protocol family Jul 10 00:21:59.197335 kernel: PCI: CLS 0 bytes, default 64 Jul 10 00:21:59.197343 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Jul 10 00:21:59.197352 kernel: software IO TLB: mapped [mem 0x000000003b5bf000-0x000000003f5bf000] (64MB) Jul 10 00:21:59.197360 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Jul 10 00:21:59.197412 kernel: Initialise system trusted keyrings Jul 10 00:21:59.197423 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Jul 10 00:21:59.197432 kernel: Key type asymmetric registered Jul 10 00:21:59.197440 kernel: Asymmetric key parser 'x509' registered Jul 10 00:21:59.197449 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Jul 10 00:21:59.197457 kernel: io scheduler mq-deadline registered Jul 10 00:21:59.197466 kernel: io scheduler kyber registered Jul 10 00:21:59.197474 kernel: io scheduler bfq registered Jul 10 00:21:59.197483 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Jul 10 00:21:59.197494 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Jul 10 00:21:59.197503 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Jul 10 00:21:59.197511 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Jul 10 00:21:59.197520 kernel: i8042: PNP: No PS/2 controller found. Jul 10 00:21:59.197714 kernel: rtc_cmos 00:02: registered as rtc0 Jul 10 00:21:59.197821 kernel: rtc_cmos 00:02: setting system clock to 2025-07-10T00:21:58 UTC (1752106918) Jul 10 00:21:59.197961 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Jul 10 00:21:59.197978 kernel: intel_pstate: CPU model not supported Jul 10 00:21:59.197995 kernel: efifb: probing for efifb Jul 10 00:21:59.198004 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Jul 10 00:21:59.198013 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Jul 10 00:21:59.198021 kernel: efifb: scrolling: redraw Jul 10 00:21:59.198030 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Jul 10 00:21:59.198039 kernel: Console: switching to colour frame buffer device 128x48 Jul 10 00:21:59.198047 kernel: fb0: EFI VGA frame buffer device Jul 10 00:21:59.198056 kernel: pstore: Using crash dump compression: deflate Jul 10 00:21:59.198064 kernel: pstore: Registered efi_pstore as persistent store backend Jul 10 00:21:59.198076 kernel: NET: Registered PF_INET6 protocol family Jul 10 00:21:59.198085 kernel: Segment Routing with IPv6 Jul 10 00:21:59.198093 kernel: In-situ OAM (IOAM) with IPv6 Jul 10 00:21:59.198102 kernel: NET: Registered PF_PACKET protocol family Jul 10 00:21:59.198110 kernel: Key type dns_resolver registered Jul 10 00:21:59.198119 kernel: IPI shorthand broadcast: enabled Jul 10 00:21:59.198128 kernel: sched_clock: Marking stable (3886006000, 109090700)->(4323520900, -328424200) Jul 10 00:21:59.198136 kernel: registered taskstats version 1 Jul 10 00:21:59.198145 kernel: Loading compiled-in X.509 certificates Jul 10 00:21:59.198156 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.36-flatcar: f515550de55d4e43b2ea11ae212aa0cb3a4e55cf' Jul 10 00:21:59.198165 kernel: Demotion targets for Node 0: null Jul 10 00:21:59.198173 kernel: Key type .fscrypt registered Jul 10 00:21:59.198181 kernel: Key type fscrypt-provisioning registered Jul 10 00:21:59.198190 kernel: ima: No TPM chip found, activating TPM-bypass! Jul 10 00:21:59.198198 kernel: ima: Allocated hash algorithm: sha1 Jul 10 00:21:59.198207 kernel: ima: No architecture policies found Jul 10 00:21:59.198215 kernel: clk: Disabling unused clocks Jul 10 00:21:59.198224 kernel: Warning: unable to open an initial console. Jul 10 00:21:59.198235 kernel: Freeing unused kernel image (initmem) memory: 54420K Jul 10 00:21:59.198244 kernel: Write protecting the kernel read-only data: 24576k Jul 10 00:21:59.198253 kernel: Freeing unused kernel image (rodata/data gap) memory: 284K Jul 10 00:21:59.198261 kernel: Run /init as init process Jul 10 00:21:59.198270 kernel: with arguments: Jul 10 00:21:59.198278 kernel: /init Jul 10 00:21:59.198286 kernel: with environment: Jul 10 00:21:59.198294 kernel: HOME=/ Jul 10 00:21:59.198303 kernel: TERM=linux Jul 10 00:21:59.198313 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Jul 10 00:21:59.198323 systemd[1]: Successfully made /usr/ read-only. Jul 10 00:21:59.198335 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) Jul 10 00:21:59.198344 systemd[1]: Detected virtualization microsoft. Jul 10 00:21:59.198353 systemd[1]: Detected architecture x86-64. Jul 10 00:21:59.198362 systemd[1]: Running in initrd. Jul 10 00:21:59.198371 systemd[1]: No hostname configured, using default hostname. Jul 10 00:21:59.198383 systemd[1]: Hostname set to . Jul 10 00:21:59.198392 systemd[1]: Initializing machine ID from random generator. Jul 10 00:21:59.198401 systemd[1]: Queued start job for default target initrd.target. Jul 10 00:21:59.198410 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 10 00:21:59.198419 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 10 00:21:59.198429 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Jul 10 00:21:59.198438 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jul 10 00:21:59.198447 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Jul 10 00:21:59.198459 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Jul 10 00:21:59.198470 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Jul 10 00:21:59.198479 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Jul 10 00:21:59.198488 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 10 00:21:59.198497 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jul 10 00:21:59.198506 systemd[1]: Reached target paths.target - Path Units. Jul 10 00:21:59.198515 systemd[1]: Reached target slices.target - Slice Units. Jul 10 00:21:59.198527 systemd[1]: Reached target swap.target - Swaps. Jul 10 00:21:59.198536 systemd[1]: Reached target timers.target - Timer Units. Jul 10 00:21:59.198545 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Jul 10 00:21:59.198779 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jul 10 00:21:59.198790 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Jul 10 00:21:59.198799 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Jul 10 00:21:59.198808 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jul 10 00:21:59.198817 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jul 10 00:21:59.198827 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jul 10 00:21:59.198841 systemd[1]: Reached target sockets.target - Socket Units. Jul 10 00:21:59.198851 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Jul 10 00:21:59.198860 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jul 10 00:21:59.198869 systemd[1]: Finished network-cleanup.service - Network Cleanup. Jul 10 00:21:59.198878 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). Jul 10 00:21:59.198887 systemd[1]: Starting systemd-fsck-usr.service... Jul 10 00:21:59.198896 systemd[1]: Starting systemd-journald.service - Journal Service... Jul 10 00:21:59.198906 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jul 10 00:21:59.198917 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 10 00:21:59.198951 systemd-journald[232]: Collecting audit messages is disabled. Jul 10 00:21:59.198973 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Jul 10 00:21:59.198989 systemd-journald[232]: Journal started Jul 10 00:21:59.199010 systemd-journald[232]: Runtime Journal (/run/log/journal/86c2601b97924223b1294e2942740efd) is 8M, max 2.2G, 2.1G free. Jul 10 00:21:59.196796 systemd-modules-load[235]: Inserted module 'overlay' Jul 10 00:21:59.208630 systemd[1]: Started systemd-journald.service - Journal Service. Jul 10 00:21:59.208759 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jul 10 00:21:59.209262 systemd[1]: Finished systemd-fsck-usr.service. Jul 10 00:21:59.211472 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jul 10 00:21:59.212510 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jul 10 00:21:59.254670 systemd-tmpfiles[243]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Jul 10 00:21:59.255991 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jul 10 00:21:59.259765 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jul 10 00:21:59.260105 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 10 00:21:59.300762 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 10 00:21:59.303187 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jul 10 00:21:59.324366 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 10 00:21:59.329529 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Jul 10 00:21:59.339981 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jul 10 00:21:59.342310 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Jul 10 00:21:59.360389 systemd-modules-load[235]: Inserted module 'br_netfilter' Jul 10 00:21:59.363748 kernel: Bridge firewalling registered Jul 10 00:21:59.373728 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jul 10 00:21:59.382232 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jul 10 00:21:59.390678 dracut-cmdline[268]: 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=844005237fb9709f65a093d5533c4229fb6c54e8e257736d9c3d041b6d3080ea Jul 10 00:21:59.395936 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jul 10 00:21:59.418957 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jul 10 00:21:59.458143 systemd-resolved[290]: Positive Trust Anchors: Jul 10 00:21:59.458159 systemd-resolved[290]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 10 00:21:59.458188 systemd-resolved[290]: 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 Jul 10 00:21:59.460857 systemd-resolved[290]: Defaulting to hostname 'linux'. Jul 10 00:21:59.461905 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jul 10 00:21:59.471830 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jul 10 00:21:59.568631 kernel: SCSI subsystem initialized Jul 10 00:21:59.578630 kernel: Loading iSCSI transport class v2.0-870. Jul 10 00:21:59.593632 kernel: iscsi: registered transport (tcp) Jul 10 00:21:59.614837 kernel: iscsi: registered transport (qla4xxx) Jul 10 00:21:59.614864 kernel: QLogic iSCSI HBA Driver Jul 10 00:21:59.633798 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jul 10 00:21:59.656110 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jul 10 00:21:59.668382 systemd[1]: Reached target network-pre.target - Preparation for Network. Jul 10 00:21:59.725494 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Jul 10 00:21:59.735367 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Jul 10 00:21:59.796632 kernel: raid6: avx2x4 gen() 26437 MB/s Jul 10 00:21:59.815629 kernel: raid6: avx2x2 gen() 26576 MB/s Jul 10 00:21:59.836620 kernel: raid6: avx2x1 gen() 23392 MB/s Jul 10 00:21:59.836647 kernel: raid6: using algorithm avx2x2 gen() 26576 MB/s Jul 10 00:21:59.859797 kernel: raid6: .... xor() 18344 MB/s, rmw enabled Jul 10 00:21:59.859824 kernel: raid6: using avx2x2 recovery algorithm Jul 10 00:21:59.882632 kernel: xor: automatically using best checksumming function avx Jul 10 00:22:00.048646 kernel: Btrfs loaded, zoned=no, fsverity=no Jul 10 00:22:00.056150 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Jul 10 00:22:00.066199 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 10 00:22:00.112688 systemd-udevd[484]: Using default interface naming scheme 'v255'. Jul 10 00:22:00.119319 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 10 00:22:00.121599 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Jul 10 00:22:00.160892 dracut-pre-trigger[491]: rd.md=0: removing MD RAID activation Jul 10 00:22:00.189351 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Jul 10 00:22:00.198424 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jul 10 00:22:00.273980 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jul 10 00:22:00.278735 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Jul 10 00:22:00.300629 kernel: cryptd: max_cpu_qlen set to 1000 Jul 10 00:22:00.309630 kernel: AES CTR mode by8 optimization enabled Jul 10 00:22:00.317898 kernel: hv_vmbus: Vmbus version:4.0 Jul 10 00:22:00.338274 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 10 00:22:00.349437 kernel: pps_core: LinuxPPS API ver. 1 registered Jul 10 00:22:00.349465 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Jul 10 00:22:00.338414 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jul 10 00:22:00.351744 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Jul 10 00:22:00.357175 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 10 00:22:00.363945 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jul 10 00:22:00.382633 kernel: hv_vmbus: registering driver hyperv_keyboard Jul 10 00:22:00.382670 kernel: PTP clock support registered Jul 10 00:22:00.395308 kernel: hv_vmbus: registering driver hv_pci Jul 10 00:22:00.395355 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 Jul 10 00:22:00.395370 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Jul 10 00:22:00.403535 kernel: hid: raw HID events driver (C) Jiri Kosina Jul 10 00:22:00.403565 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Jul 10 00:22:00.411492 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Jul 10 00:22:00.413316 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Jul 10 00:22:00.416731 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Jul 10 00:22:00.425725 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint Jul 10 00:22:00.425776 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] Jul 10 00:22:00.430309 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] Jul 10 00:22:00.434917 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 10 00:22:00.450922 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] Jul 10 00:22:00.450968 kernel: pci 0001:00:00.0: Enabling HDA controller Jul 10 00:22:00.461807 kernel: hv_utils: Registering HyperV Utility Driver Jul 10 00:22:00.461844 kernel: hv_vmbus: registering driver hv_netvsc Jul 10 00:22:00.461861 kernel: hv_vmbus: registering driver hv_utils Jul 10 00:22:00.462629 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Jul 10 00:22:00.462836 kernel: hv_vmbus: registering driver hid_hyperv Jul 10 00:22:00.467719 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned Jul 10 00:22:00.468365 kernel: hv_utils: Heartbeat IC version 3.0 Jul 10 00:22:00.468381 kernel: hv_utils: Shutdown IC version 3.2 Jul 10 00:22:00.468393 kernel: hv_utils: TimeSync IC version 4.0 Jul 10 00:22:00.484046 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Jul 10 00:22:00.484088 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned Jul 10 00:22:00.484342 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Jul 10 00:22:00.488707 kernel: hv_vmbus: registering driver hv_storvsc Jul 10 00:22:00.488738 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned Jul 10 00:22:00.565025 systemd-resolved[290]: Clock change detected. Flushing caches. Jul 10 00:22:00.583507 kernel: scsi host0: storvsc_host_t Jul 10 00:22:00.586734 kernel: scsi host1: storvsc_host_t Jul 10 00:22:00.586781 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Jul 10 00:22:00.596738 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Jul 10 00:22:00.601763 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Jul 10 00:22:00.616478 kernel: sd 0:0:0:0: [sdb] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Jul 10 00:22:00.616676 kernel: sd 0:0:0:1: [sda] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Jul 10 00:22:00.618891 kernel: sd 0:0:0:1: [sda] 4096-byte physical blocks Jul 10 00:22:00.619038 kernel: sd 0:0:0:0: [sdb] 4096-byte physical blocks Jul 10 00:22:00.622774 kernel: sd 0:0:0:1: [sda] Write Protect is off Jul 10 00:22:00.629354 kernel: sd 0:0:0:0: [sdb] Write Protect is off Jul 10 00:22:00.629524 kernel: sd 0:0:0:1: [sda] Mode Sense: 0f 00 10 00 Jul 10 00:22:00.629668 kernel: sd 0:0:0:0: [sdb] Mode Sense: 0f 00 10 00 Jul 10 00:22:00.632729 kernel: sd 0:0:0:1: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Jul 10 00:22:00.636729 kernel: sd 0:0:0:0: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Jul 10 00:22:00.636874 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#196 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 10 00:22:00.641727 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#261 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 10 00:22:00.653581 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#203 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 10 00:22:00.653750 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#268 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 10 00:22:00.684642 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Jul 10 00:22:00.684681 kernel: sd 0:0:0:0: [sdb] Attached SCSI disk Jul 10 00:22:00.685732 kernel: sda: sda1 Jul 10 00:22:00.689733 kernel: sd 0:0:0:1: [sda] Attached SCSI disk Jul 10 00:22:00.697106 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Jul 10 00:22:00.697317 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Jul 10 00:22:00.698742 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Jul 10 00:22:01.243251 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Jul 10 00:22:01.289164 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Jul 10 00:22:01.319308 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Jul 10 00:22:01.436672 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Jul 10 00:22:01.438371 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Jul 10 00:22:01.449375 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Jul 10 00:22:01.497727 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#254 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 10 00:22:01.509733 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Jul 10 00:22:01.518729 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#206 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 10 00:22:01.534742 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Jul 10 00:22:02.541302 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#222 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 10 00:22:02.551739 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Jul 10 00:22:02.552089 disk-uuid[720]: The operation has completed successfully. Jul 10 00:22:03.126520 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Jul 10 00:22:03.131465 systemd[1]: disk-uuid.service: Deactivated successfully. Jul 10 00:22:03.131565 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Jul 10 00:22:03.140800 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Jul 10 00:22:03.148936 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 10 00:22:03.153964 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jul 10 00:22:03.164271 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Jul 10 00:22:03.170404 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Jul 10 00:22:03.203755 sh[885]: Success Jul 10 00:22:03.210843 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Jul 10 00:22:03.241890 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Jul 10 00:22:03.241946 kernel: device-mapper: uevent: version 1.0.3 Jul 10 00:22:03.246550 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Jul 10 00:22:03.259733 kernel: device-mapper: verity: sha256 using shash "sha256-avx2" Jul 10 00:22:03.446553 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Jul 10 00:22:03.449967 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Jul 10 00:22:03.484528 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Jul 10 00:22:03.504649 kernel: BTRFS info: 'norecovery' is for compatibility only, recommended to use 'rescue=nologreplay' Jul 10 00:22:03.504677 kernel: BTRFS: device fsid c4cb30b0-bb74-4f98-aab6-7a1c6f47edee devid 1 transid 36 /dev/mapper/usr (254:0) scanned by mount (911) Jul 10 00:22:03.510025 kernel: BTRFS info (device dm-0): first mount of filesystem c4cb30b0-bb74-4f98-aab6-7a1c6f47edee Jul 10 00:22:03.514294 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Jul 10 00:22:03.517501 kernel: BTRFS info (device dm-0): using free-space-tree Jul 10 00:22:04.010772 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Jul 10 00:22:04.018358 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Jul 10 00:22:04.027910 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Jul 10 00:22:04.028808 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Jul 10 00:22:04.040782 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Jul 10 00:22:04.089855 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sdb6 (8:22) scanned by mount (938) Jul 10 00:22:04.089908 kernel: BTRFS info (device sdb6): first mount of filesystem 66535909-6865-4f30-ad42-a3000fffd5f6 Jul 10 00:22:04.095092 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Jul 10 00:22:04.098237 kernel: BTRFS info (device sdb6): using free-space-tree Jul 10 00:22:04.120729 kernel: BTRFS info (device sdb6): last unmount of filesystem 66535909-6865-4f30-ad42-a3000fffd5f6 Jul 10 00:22:04.121096 systemd[1]: Finished ignition-setup.service - Ignition (setup). Jul 10 00:22:04.130719 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Jul 10 00:22:04.167220 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jul 10 00:22:04.170273 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jul 10 00:22:04.231170 systemd-networkd[1080]: lo: Link UP Jul 10 00:22:04.231183 systemd-networkd[1080]: lo: Gained carrier Jul 10 00:22:04.232850 systemd-networkd[1080]: Enumeration completed Jul 10 00:22:04.232958 systemd[1]: Started systemd-networkd.service - Network Configuration. Jul 10 00:22:04.233239 systemd-networkd[1080]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 10 00:22:04.233244 systemd-networkd[1080]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 10 00:22:04.234841 systemd-networkd[1080]: eth0: Link UP Jul 10 00:22:04.235014 systemd-networkd[1080]: eth0: Gained carrier Jul 10 00:22:04.235025 systemd-networkd[1080]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 10 00:22:04.235561 systemd[1]: Reached target network.target - Network. Jul 10 00:22:04.293761 systemd-networkd[1080]: eth0: DHCPv4 address 10.200.8.11/24, gateway 10.200.8.1 acquired from 168.63.129.16 Jul 10 00:22:05.793883 systemd-networkd[1080]: eth0: Gained IPv6LL Jul 10 00:22:06.463316 ignition[1045]: Ignition 2.21.0 Jul 10 00:22:06.463334 ignition[1045]: Stage: fetch-offline Jul 10 00:22:06.463467 ignition[1045]: no configs at "/usr/lib/ignition/base.d" Jul 10 00:22:06.469897 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Jul 10 00:22:06.463481 ignition[1045]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 10 00:22:06.478601 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Jul 10 00:22:06.463602 ignition[1045]: parsed url from cmdline: "" Jul 10 00:22:06.463606 ignition[1045]: no config URL provided Jul 10 00:22:06.463618 ignition[1045]: reading system config file "/usr/lib/ignition/user.ign" Jul 10 00:22:06.463630 ignition[1045]: no config at "/usr/lib/ignition/user.ign" Jul 10 00:22:06.463636 ignition[1045]: failed to fetch config: resource requires networking Jul 10 00:22:06.466249 ignition[1045]: Ignition finished successfully Jul 10 00:22:06.528523 ignition[1092]: Ignition 2.21.0 Jul 10 00:22:06.528537 ignition[1092]: Stage: fetch Jul 10 00:22:06.528839 ignition[1092]: no configs at "/usr/lib/ignition/base.d" Jul 10 00:22:06.528851 ignition[1092]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 10 00:22:06.528954 ignition[1092]: parsed url from cmdline: "" Jul 10 00:22:06.528958 ignition[1092]: no config URL provided Jul 10 00:22:06.528963 ignition[1092]: reading system config file "/usr/lib/ignition/user.ign" Jul 10 00:22:06.528973 ignition[1092]: no config at "/usr/lib/ignition/user.ign" Jul 10 00:22:06.529008 ignition[1092]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Jul 10 00:22:06.618389 ignition[1092]: GET result: OK Jul 10 00:22:06.618465 ignition[1092]: config has been read from IMDS userdata Jul 10 00:22:06.618488 ignition[1092]: parsing config with SHA512: a4841d8ce63eef62eb6c5771726470ec0ebee252107cdd969b663c93215f14ce743ed3ed30801498d2865fbaf536c4dace14daa20d21914c1b19525d95c2b21b Jul 10 00:22:06.625512 unknown[1092]: fetched base config from "system" Jul 10 00:22:06.625523 unknown[1092]: fetched base config from "system" Jul 10 00:22:06.625678 ignition[1092]: fetch: fetch complete Jul 10 00:22:06.625528 unknown[1092]: fetched user config from "azure" Jul 10 00:22:06.625683 ignition[1092]: fetch: fetch passed Jul 10 00:22:06.634358 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Jul 10 00:22:06.625746 ignition[1092]: Ignition finished successfully Jul 10 00:22:06.648875 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Jul 10 00:22:06.694616 ignition[1102]: Ignition 2.21.0 Jul 10 00:22:06.694632 ignition[1102]: Stage: kargs Jul 10 00:22:06.694903 ignition[1102]: no configs at "/usr/lib/ignition/base.d" Jul 10 00:22:06.699888 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Jul 10 00:22:06.694915 ignition[1102]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 10 00:22:06.707496 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Jul 10 00:22:06.695433 ignition[1102]: kargs: kargs passed Jul 10 00:22:06.695480 ignition[1102]: Ignition finished successfully Jul 10 00:22:06.754524 ignition[1111]: Ignition 2.21.0 Jul 10 00:22:06.754539 ignition[1111]: Stage: disks Jul 10 00:22:06.754828 ignition[1111]: no configs at "/usr/lib/ignition/base.d" Jul 10 00:22:06.754841 ignition[1111]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 10 00:22:06.761337 systemd[1]: Finished ignition-disks.service - Ignition (disks). Jul 10 00:22:06.756263 ignition[1111]: disks: disks passed Jul 10 00:22:06.764947 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Jul 10 00:22:06.756346 ignition[1111]: Ignition finished successfully Jul 10 00:22:06.773103 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Jul 10 00:22:06.781463 systemd[1]: Reached target local-fs.target - Local File Systems. Jul 10 00:22:06.796524 systemd[1]: Reached target sysinit.target - System Initialization. Jul 10 00:22:06.803819 systemd[1]: Reached target basic.target - Basic System. Jul 10 00:22:06.815669 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Jul 10 00:22:06.896074 systemd-fsck[1123]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks Jul 10 00:22:06.901322 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Jul 10 00:22:06.911692 systemd[1]: Mounting sysroot.mount - /sysroot... Jul 10 00:22:07.141737 kernel: EXT4-fs (sdb9): mounted filesystem a310c019-7915-47f5-9fce-db4a09ac26c2 r/w with ordered data mode. Quota mode: none. Jul 10 00:22:07.142398 systemd[1]: Mounted sysroot.mount - /sysroot. Jul 10 00:22:07.144668 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Jul 10 00:22:07.163642 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jul 10 00:22:07.165920 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Jul 10 00:22:07.189287 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Jul 10 00:22:07.200731 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sdb6 (8:22) scanned by mount (1137) Jul 10 00:22:07.203450 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Jul 10 00:22:07.213934 kernel: BTRFS info (device sdb6): first mount of filesystem 66535909-6865-4f30-ad42-a3000fffd5f6 Jul 10 00:22:07.213954 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Jul 10 00:22:07.203492 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Jul 10 00:22:07.232468 kernel: BTRFS info (device sdb6): using free-space-tree Jul 10 00:22:07.219322 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Jul 10 00:22:07.235029 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jul 10 00:22:07.242791 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Jul 10 00:22:08.043730 coreos-metadata[1139]: Jul 10 00:22:08.043 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Jul 10 00:22:08.050043 coreos-metadata[1139]: Jul 10 00:22:08.046 INFO Fetch successful Jul 10 00:22:08.050043 coreos-metadata[1139]: Jul 10 00:22:08.046 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Jul 10 00:22:08.061800 coreos-metadata[1139]: Jul 10 00:22:08.055 INFO Fetch successful Jul 10 00:22:08.067412 coreos-metadata[1139]: Jul 10 00:22:08.067 INFO wrote hostname ci-4344.1.1-n-526e1d82a0 to /sysroot/etc/hostname Jul 10 00:22:08.074557 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Jul 10 00:22:08.272133 initrd-setup-root[1167]: cut: /sysroot/etc/passwd: No such file or directory Jul 10 00:22:08.389242 initrd-setup-root[1174]: cut: /sysroot/etc/group: No such file or directory Jul 10 00:22:08.423925 initrd-setup-root[1181]: cut: /sysroot/etc/shadow: No such file or directory Jul 10 00:22:08.431638 initrd-setup-root[1188]: cut: /sysroot/etc/gshadow: No such file or directory Jul 10 00:22:09.629265 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Jul 10 00:22:09.635487 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Jul 10 00:22:09.640987 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Jul 10 00:22:09.666648 systemd[1]: sysroot-oem.mount: Deactivated successfully. Jul 10 00:22:09.675568 kernel: BTRFS info (device sdb6): last unmount of filesystem 66535909-6865-4f30-ad42-a3000fffd5f6 Jul 10 00:22:09.690874 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Jul 10 00:22:09.705505 ignition[1257]: INFO : Ignition 2.21.0 Jul 10 00:22:09.705505 ignition[1257]: INFO : Stage: mount Jul 10 00:22:09.711320 ignition[1257]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 10 00:22:09.711320 ignition[1257]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 10 00:22:09.711320 ignition[1257]: INFO : mount: mount passed Jul 10 00:22:09.711320 ignition[1257]: INFO : Ignition finished successfully Jul 10 00:22:09.712574 systemd[1]: Finished ignition-mount.service - Ignition (mount). Jul 10 00:22:09.721836 systemd[1]: Starting ignition-files.service - Ignition (files)... Jul 10 00:22:09.752803 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jul 10 00:22:09.777865 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sdb6 (8:22) scanned by mount (1271) Jul 10 00:22:09.777903 kernel: BTRFS info (device sdb6): first mount of filesystem 66535909-6865-4f30-ad42-a3000fffd5f6 Jul 10 00:22:09.782231 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Jul 10 00:22:09.803006 kernel: BTRFS info (device sdb6): using free-space-tree Jul 10 00:22:09.806983 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jul 10 00:22:09.845892 ignition[1288]: INFO : Ignition 2.21.0 Jul 10 00:22:09.845892 ignition[1288]: INFO : Stage: files Jul 10 00:22:09.851485 ignition[1288]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 10 00:22:09.851485 ignition[1288]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 10 00:22:09.851485 ignition[1288]: DEBUG : files: compiled without relabeling support, skipping Jul 10 00:22:09.864899 ignition[1288]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Jul 10 00:22:09.864899 ignition[1288]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Jul 10 00:22:09.889970 ignition[1288]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Jul 10 00:22:09.895581 ignition[1288]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Jul 10 00:22:09.900799 unknown[1288]: wrote ssh authorized keys file for user: core Jul 10 00:22:09.904680 ignition[1288]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Jul 10 00:22:09.943991 ignition[1288]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Jul 10 00:22:09.951447 ignition[1288]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Jul 10 00:22:09.951447 ignition[1288]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Jul 10 00:22:09.951447 ignition[1288]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Jul 10 00:22:09.951447 ignition[1288]: INFO : files: files passed Jul 10 00:22:09.951447 ignition[1288]: INFO : Ignition finished successfully Jul 10 00:22:09.960905 systemd[1]: Finished ignition-files.service - Ignition (files). Jul 10 00:22:09.968983 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Jul 10 00:22:09.998227 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Jul 10 00:22:10.001040 systemd[1]: ignition-quench.service: Deactivated successfully. Jul 10 00:22:10.001151 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Jul 10 00:22:10.029828 initrd-setup-root-after-ignition[1321]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jul 10 00:22:10.035975 initrd-setup-root-after-ignition[1321]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Jul 10 00:22:10.042144 initrd-setup-root-after-ignition[1325]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jul 10 00:22:10.049439 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Jul 10 00:22:10.051160 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Jul 10 00:22:10.065454 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Jul 10 00:22:10.113891 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Jul 10 00:22:10.114015 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Jul 10 00:22:10.123228 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Jul 10 00:22:10.132140 systemd[1]: Reached target initrd.target - Initrd Default Target. Jul 10 00:22:10.135096 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Jul 10 00:22:10.135921 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Jul 10 00:22:10.165621 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jul 10 00:22:10.176620 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Jul 10 00:22:10.195853 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Jul 10 00:22:10.197315 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 10 00:22:10.206008 systemd[1]: Stopped target timers.target - Timer Units. Jul 10 00:22:10.215075 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Jul 10 00:22:10.215190 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jul 10 00:22:10.228904 systemd[1]: Stopped target initrd.target - Initrd Default Target. Jul 10 00:22:10.231128 systemd[1]: Stopped target basic.target - Basic System. Jul 10 00:22:10.242750 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Jul 10 00:22:10.254624 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Jul 10 00:22:10.263220 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Jul 10 00:22:10.271922 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Jul 10 00:22:10.280330 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Jul 10 00:22:10.281748 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Jul 10 00:22:10.282447 systemd[1]: Stopped target sysinit.target - System Initialization. Jul 10 00:22:10.283138 systemd[1]: Stopped target local-fs.target - Local File Systems. Jul 10 00:22:10.283775 systemd[1]: Stopped target swap.target - Swaps. Jul 10 00:22:10.284450 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Jul 10 00:22:10.284579 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Jul 10 00:22:10.392502 ignition[1345]: INFO : Ignition 2.21.0 Jul 10 00:22:10.392502 ignition[1345]: INFO : Stage: umount Jul 10 00:22:10.392502 ignition[1345]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 10 00:22:10.392502 ignition[1345]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 10 00:22:10.285899 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Jul 10 00:22:10.414726 ignition[1345]: INFO : umount: umount passed Jul 10 00:22:10.414726 ignition[1345]: INFO : Ignition finished successfully Jul 10 00:22:10.286590 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 10 00:22:10.287333 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Jul 10 00:22:10.287459 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 10 00:22:10.287990 systemd[1]: dracut-initqueue.service: Deactivated successfully. Jul 10 00:22:10.288102 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Jul 10 00:22:10.289428 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Jul 10 00:22:10.289545 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Jul 10 00:22:10.290152 systemd[1]: ignition-files.service: Deactivated successfully. Jul 10 00:22:10.290270 systemd[1]: Stopped ignition-files.service - Ignition (files). Jul 10 00:22:10.290856 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Jul 10 00:22:10.290966 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Jul 10 00:22:10.292361 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Jul 10 00:22:10.292883 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Jul 10 00:22:10.293001 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Jul 10 00:22:10.294400 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Jul 10 00:22:10.295032 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Jul 10 00:22:10.295154 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Jul 10 00:22:10.295818 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Jul 10 00:22:10.295927 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Jul 10 00:22:10.300263 systemd[1]: initrd-cleanup.service: Deactivated successfully. Jul 10 00:22:10.374929 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Jul 10 00:22:10.403600 systemd[1]: sysroot-boot.mount: Deactivated successfully. Jul 10 00:22:10.404271 systemd[1]: ignition-mount.service: Deactivated successfully. Jul 10 00:22:10.404373 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Jul 10 00:22:10.410000 systemd[1]: ignition-disks.service: Deactivated successfully. Jul 10 00:22:10.410310 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Jul 10 00:22:10.416180 systemd[1]: ignition-kargs.service: Deactivated successfully. Jul 10 00:22:10.416233 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Jul 10 00:22:10.425301 systemd[1]: ignition-fetch.service: Deactivated successfully. Jul 10 00:22:10.425351 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Jul 10 00:22:10.432532 systemd[1]: Stopped target network.target - Network. Jul 10 00:22:10.440373 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Jul 10 00:22:10.440430 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Jul 10 00:22:10.448464 systemd[1]: Stopped target paths.target - Path Units. Jul 10 00:22:10.457054 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Jul 10 00:22:10.463789 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 10 00:22:10.465964 systemd[1]: Stopped target slices.target - Slice Units. Jul 10 00:22:10.474918 systemd[1]: Stopped target sockets.target - Socket Units. Jul 10 00:22:10.482504 systemd[1]: iscsid.socket: Deactivated successfully. Jul 10 00:22:10.482548 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Jul 10 00:22:10.492572 systemd[1]: iscsiuio.socket: Deactivated successfully. Jul 10 00:22:10.492617 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jul 10 00:22:10.500064 systemd[1]: ignition-setup.service: Deactivated successfully. Jul 10 00:22:10.500125 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Jul 10 00:22:10.508330 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Jul 10 00:22:10.508375 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Jul 10 00:22:10.517811 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Jul 10 00:22:10.525078 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Jul 10 00:22:10.532578 systemd[1]: sysroot-boot.service: Deactivated successfully. Jul 10 00:22:10.532683 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Jul 10 00:22:10.541003 systemd[1]: initrd-setup-root.service: Deactivated successfully. Jul 10 00:22:10.541074 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Jul 10 00:22:10.552594 systemd[1]: systemd-resolved.service: Deactivated successfully. Jul 10 00:22:10.552788 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Jul 10 00:22:10.565125 systemd[1]: systemd-networkd.service: Deactivated successfully. Jul 10 00:22:10.565244 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Jul 10 00:22:10.576281 systemd[1]: Stopped target network-pre.target - Preparation for Network. Jul 10 00:22:10.577837 systemd[1]: systemd-networkd.socket: Deactivated successfully. Jul 10 00:22:10.577878 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Jul 10 00:22:10.589952 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Jul 10 00:22:10.597047 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Jul 10 00:22:10.597101 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jul 10 00:22:10.604151 systemd[1]: systemd-sysctl.service: Deactivated successfully. Jul 10 00:22:10.604200 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Jul 10 00:22:10.618703 systemd[1]: systemd-modules-load.service: Deactivated successfully. Jul 10 00:22:10.618765 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Jul 10 00:22:10.620677 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Jul 10 00:22:10.620744 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 10 00:22:10.638103 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 10 00:22:10.647208 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Jul 10 00:22:10.647271 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Jul 10 00:22:10.647313 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Jul 10 00:22:10.647358 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Jul 10 00:22:10.677397 systemd[1]: systemd-udevd.service: Deactivated successfully. Jul 10 00:22:10.677556 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 10 00:22:10.679835 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Jul 10 00:22:10.679880 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Jul 10 00:22:10.690192 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Jul 10 00:22:10.690226 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Jul 10 00:22:10.697562 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Jul 10 00:22:10.697608 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Jul 10 00:22:10.711493 systemd[1]: dracut-cmdline.service: Deactivated successfully. Jul 10 00:22:10.711540 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Jul 10 00:22:10.722753 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jul 10 00:22:10.722809 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jul 10 00:22:10.739746 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Jul 10 00:22:10.747140 systemd[1]: systemd-network-generator.service: Deactivated successfully. Jul 10 00:22:10.747200 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Jul 10 00:22:10.763530 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Jul 10 00:22:10.763583 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 10 00:22:10.772399 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 10 00:22:10.772449 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jul 10 00:22:10.782942 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Jul 10 00:22:10.783000 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Jul 10 00:22:10.783043 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jul 10 00:22:10.783433 systemd[1]: network-cleanup.service: Deactivated successfully. Jul 10 00:22:10.783531 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Jul 10 00:22:10.786118 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Jul 10 00:22:10.786205 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Jul 10 00:22:11.000608 systemd-journald[232]: Received SIGTERM from PID 1 (systemd). Jul 10 00:22:10.794094 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Jul 10 00:22:10.802952 systemd[1]: Starting initrd-switch-root.service - Switch Root... Jul 10 00:22:10.853060 systemd[1]: Switching root. Jul 10 00:22:11.010851 systemd-journald[232]: Journal stopped Jul 10 00:22:15.620294 kernel: SELinux: policy capability network_peer_controls=1 Jul 10 00:22:15.620325 kernel: SELinux: policy capability open_perms=1 Jul 10 00:22:15.620338 kernel: SELinux: policy capability extended_socket_class=1 Jul 10 00:22:15.620347 kernel: SELinux: policy capability always_check_network=0 Jul 10 00:22:15.620356 kernel: SELinux: policy capability cgroup_seclabel=1 Jul 10 00:22:15.620365 kernel: SELinux: policy capability nnp_nosuid_transition=1 Jul 10 00:22:15.620376 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Jul 10 00:22:15.620388 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Jul 10 00:22:15.620397 kernel: SELinux: policy capability userspace_initial_context=0 Jul 10 00:22:15.620407 kernel: audit: type=1403 audit(1752106931.771:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Jul 10 00:22:15.620418 systemd[1]: Successfully loaded SELinux policy in 175.431ms. Jul 10 00:22:15.620430 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 15.907ms. Jul 10 00:22:15.620441 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) Jul 10 00:22:15.620452 systemd[1]: Detected virtualization microsoft. Jul 10 00:22:15.620466 systemd[1]: Detected architecture x86-64. Jul 10 00:22:15.620477 systemd[1]: Detected first boot. Jul 10 00:22:15.620488 systemd[1]: Hostname set to . Jul 10 00:22:15.620502 systemd[1]: Initializing machine ID from random generator. Jul 10 00:22:15.620513 zram_generator::config[1390]: No configuration found. Jul 10 00:22:15.620527 kernel: Guest personality initialized and is inactive Jul 10 00:22:15.620538 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Jul 10 00:22:15.620548 kernel: Initialized host personality Jul 10 00:22:15.620557 kernel: NET: Registered PF_VSOCK protocol family Jul 10 00:22:15.620568 systemd[1]: Populated /etc with preset unit settings. Jul 10 00:22:15.620579 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Jul 10 00:22:15.620590 systemd[1]: initrd-switch-root.service: Deactivated successfully. Jul 10 00:22:15.620603 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Jul 10 00:22:15.620614 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Jul 10 00:22:15.620625 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Jul 10 00:22:15.620636 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Jul 10 00:22:15.620646 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Jul 10 00:22:15.620657 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Jul 10 00:22:15.620668 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Jul 10 00:22:15.620679 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Jul 10 00:22:15.620693 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Jul 10 00:22:15.620704 systemd[1]: Created slice user.slice - User and Session Slice. Jul 10 00:22:15.620727 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 10 00:22:15.620739 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 10 00:22:15.620754 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Jul 10 00:22:15.620765 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Jul 10 00:22:15.620781 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Jul 10 00:22:15.620793 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jul 10 00:22:15.620806 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Jul 10 00:22:15.620818 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 10 00:22:15.620829 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jul 10 00:22:15.620840 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Jul 10 00:22:15.620850 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Jul 10 00:22:15.620861 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Jul 10 00:22:15.620873 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Jul 10 00:22:15.620887 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 10 00:22:15.620898 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jul 10 00:22:15.620909 systemd[1]: Reached target slices.target - Slice Units. Jul 10 00:22:15.620919 systemd[1]: Reached target swap.target - Swaps. Jul 10 00:22:15.620930 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Jul 10 00:22:15.620941 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Jul 10 00:22:15.620956 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Jul 10 00:22:15.620971 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jul 10 00:22:15.620982 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jul 10 00:22:15.620994 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jul 10 00:22:15.621009 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Jul 10 00:22:15.621020 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Jul 10 00:22:15.621032 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Jul 10 00:22:15.621046 systemd[1]: Mounting media.mount - External Media Directory... Jul 10 00:22:15.621057 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 10 00:22:15.621069 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Jul 10 00:22:15.621080 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Jul 10 00:22:15.621091 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Jul 10 00:22:15.621103 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). Jul 10 00:22:15.621114 systemd[1]: Reached target machines.target - Containers. Jul 10 00:22:15.621126 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Jul 10 00:22:15.621137 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 10 00:22:15.621151 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jul 10 00:22:15.621162 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Jul 10 00:22:15.621173 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 10 00:22:15.621184 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jul 10 00:22:15.621195 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 10 00:22:15.621206 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Jul 10 00:22:15.621217 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 10 00:22:15.621229 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Jul 10 00:22:15.621243 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Jul 10 00:22:15.621258 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Jul 10 00:22:15.621269 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Jul 10 00:22:15.621280 systemd[1]: Stopped systemd-fsck-usr.service. Jul 10 00:22:15.621292 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). Jul 10 00:22:15.621303 systemd[1]: Starting systemd-journald.service - Journal Service... Jul 10 00:22:15.621315 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jul 10 00:22:15.621326 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jul 10 00:22:15.621340 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Jul 10 00:22:15.621351 kernel: loop: module loaded Jul 10 00:22:15.621362 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Jul 10 00:22:15.621373 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jul 10 00:22:15.621384 systemd[1]: verity-setup.service: Deactivated successfully. Jul 10 00:22:15.621395 systemd[1]: Stopped verity-setup.service. Jul 10 00:22:15.621407 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 10 00:22:15.621418 kernel: fuse: init (API version 7.41) Jul 10 00:22:15.621428 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Jul 10 00:22:15.621464 systemd-journald[1490]: Collecting audit messages is disabled. Jul 10 00:22:15.621489 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Jul 10 00:22:15.621501 systemd-journald[1490]: Journal started Jul 10 00:22:15.621527 systemd-journald[1490]: Runtime Journal (/run/log/journal/6c301bbdca264fb19d518362dad8b82c) is 8M, max 2.2G, 2.1G free. Jul 10 00:22:14.848184 systemd[1]: Queued start job for default target multi-user.target. Jul 10 00:22:14.872223 systemd[1]: Unnecessary job was removed for dev-sdb6.device - /dev/sdb6. Jul 10 00:22:14.872770 systemd[1]: systemd-journald.service: Deactivated successfully. Jul 10 00:22:15.631254 systemd[1]: Started systemd-journald.service - Journal Service. Jul 10 00:22:15.634535 systemd[1]: Mounted media.mount - External Media Directory. Jul 10 00:22:15.638493 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Jul 10 00:22:15.642819 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Jul 10 00:22:15.647630 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Jul 10 00:22:15.651972 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Jul 10 00:22:15.657130 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jul 10 00:22:15.662585 systemd[1]: modprobe@configfs.service: Deactivated successfully. Jul 10 00:22:15.662840 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Jul 10 00:22:15.668039 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 10 00:22:15.668241 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 10 00:22:15.673147 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 10 00:22:15.673333 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 10 00:22:15.678377 systemd[1]: modprobe@fuse.service: Deactivated successfully. Jul 10 00:22:15.678581 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Jul 10 00:22:15.683490 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 10 00:22:15.683687 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 10 00:22:15.688437 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jul 10 00:22:15.693961 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jul 10 00:22:15.699729 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Jul 10 00:22:15.705354 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Jul 10 00:22:15.720202 systemd[1]: Reached target network-pre.target - Preparation for Network. Jul 10 00:22:15.726556 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Jul 10 00:22:15.729735 kernel: ACPI: bus type drm_connector registered Jul 10 00:22:15.734141 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Jul 10 00:22:15.738652 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Jul 10 00:22:15.738694 systemd[1]: Reached target local-fs.target - Local File Systems. Jul 10 00:22:15.744104 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Jul 10 00:22:15.750275 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Jul 10 00:22:15.765855 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 10 00:22:15.767283 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Jul 10 00:22:15.781964 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Jul 10 00:22:15.786459 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 10 00:22:15.787403 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Jul 10 00:22:15.791695 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jul 10 00:22:15.792888 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jul 10 00:22:15.797855 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Jul 10 00:22:15.803601 systemd[1]: Starting systemd-sysusers.service - Create System Users... Jul 10 00:22:15.809531 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 10 00:22:15.809754 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jul 10 00:22:15.814244 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jul 10 00:22:15.819144 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Jul 10 00:22:15.824708 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Jul 10 00:22:15.829736 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Jul 10 00:22:15.837551 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Jul 10 00:22:15.838376 systemd-journald[1490]: Time spent on flushing to /var/log/journal/6c301bbdca264fb19d518362dad8b82c is 13.925ms for 950 entries. Jul 10 00:22:15.838376 systemd-journald[1490]: System Journal (/var/log/journal/6c301bbdca264fb19d518362dad8b82c) is 8M, max 2.6G, 2.6G free. Jul 10 00:22:15.931221 systemd-journald[1490]: Received client request to flush runtime journal. Jul 10 00:22:15.931392 kernel: loop0: detected capacity change from 0 to 113872 Jul 10 00:22:15.847272 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Jul 10 00:22:15.932347 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Jul 10 00:22:15.937845 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jul 10 00:22:15.946468 systemd[1]: Finished systemd-sysusers.service - Create System Users. Jul 10 00:22:15.952828 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jul 10 00:22:15.983169 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Jul 10 00:22:15.983896 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Jul 10 00:22:16.024815 systemd-tmpfiles[1545]: ACLs are not supported, ignoring. Jul 10 00:22:16.024836 systemd-tmpfiles[1545]: ACLs are not supported, ignoring. Jul 10 00:22:16.030191 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 10 00:22:16.642744 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jul 10 00:22:16.734748 kernel: loop1: detected capacity change from 0 to 28496 Jul 10 00:22:17.119848 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Jul 10 00:22:17.126601 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 10 00:22:17.177197 systemd-udevd[1553]: Using default interface naming scheme 'v255'. Jul 10 00:22:17.359059 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 10 00:22:17.367099 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jul 10 00:22:17.409124 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Jul 10 00:22:17.438874 kernel: hv_vmbus: registering driver hyperv_fb Jul 10 00:22:17.446220 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Jul 10 00:22:17.446273 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Jul 10 00:22:17.449689 kernel: Console: switching to colour dummy device 80x25 Jul 10 00:22:17.451739 kernel: Console: switching to colour frame buffer device 128x48 Jul 10 00:22:17.466748 kernel: hv_vmbus: registering driver hv_balloon Jul 10 00:22:17.473939 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Jul 10 00:22:17.477238 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Jul 10 00:22:17.496778 kernel: mousedev: PS/2 mouse device common for all mice Jul 10 00:22:17.547310 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 10 00:22:17.550112 kernel: loop2: detected capacity change from 0 to 146240 Jul 10 00:22:17.554385 systemd[1]: Started systemd-userdbd.service - User Database Manager. Jul 10 00:22:17.569735 kernel: EDAC MC: Ver: 3.0.0 Jul 10 00:22:17.571012 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 10 00:22:17.571389 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jul 10 00:22:17.579729 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 10 00:22:17.786255 systemd-networkd[1575]: lo: Link UP Jul 10 00:22:17.786268 systemd-networkd[1575]: lo: Gained carrier Jul 10 00:22:17.788155 systemd-networkd[1575]: Enumeration completed Jul 10 00:22:17.788293 systemd[1]: Started systemd-networkd.service - Network Configuration. Jul 10 00:22:17.792938 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Jul 10 00:22:17.795835 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Jul 10 00:22:17.798594 systemd-networkd[1575]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 10 00:22:17.798599 systemd-networkd[1575]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 10 00:22:17.799401 systemd-networkd[1575]: eth0: Link UP Jul 10 00:22:17.799462 systemd-networkd[1575]: eth0: Gained carrier Jul 10 00:22:17.799521 systemd-networkd[1575]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 10 00:22:17.813515 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Jul 10 00:22:17.820459 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Jul 10 00:22:17.848788 systemd-networkd[1575]: eth0: DHCPv4 address 10.200.8.11/24, gateway 10.200.8.1 acquired from 168.63.129.16 Jul 10 00:22:17.851914 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Jul 10 00:22:17.914291 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Jul 10 00:22:17.939725 kernel: loop3: detected capacity change from 0 to 113872 Jul 10 00:22:17.951732 kernel: loop4: detected capacity change from 0 to 28496 Jul 10 00:22:17.960731 kernel: loop5: detected capacity change from 0 to 146240 Jul 10 00:22:17.971007 (sd-merge)[1709]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Jul 10 00:22:17.971490 (sd-merge)[1709]: Merged extensions into '/usr'. Jul 10 00:22:17.975053 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Jul 10 00:22:17.980322 systemd[1]: Starting ensure-sysext.service... Jul 10 00:22:17.982759 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jul 10 00:22:18.010532 systemd[1]: Reload requested from client PID 1711 ('systemctl') (unit ensure-sysext.service)... Jul 10 00:22:18.010551 systemd[1]: Reloading... Jul 10 00:22:18.022619 systemd-tmpfiles[1712]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Jul 10 00:22:18.022659 systemd-tmpfiles[1712]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Jul 10 00:22:18.022966 systemd-tmpfiles[1712]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Jul 10 00:22:18.023254 systemd-tmpfiles[1712]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Jul 10 00:22:18.024254 systemd-tmpfiles[1712]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Jul 10 00:22:18.024542 systemd-tmpfiles[1712]: ACLs are not supported, ignoring. Jul 10 00:22:18.024615 systemd-tmpfiles[1712]: ACLs are not supported, ignoring. Jul 10 00:22:18.028879 systemd-tmpfiles[1712]: Detected autofs mount point /boot during canonicalization of boot. Jul 10 00:22:18.028893 systemd-tmpfiles[1712]: Skipping /boot Jul 10 00:22:18.044268 systemd-tmpfiles[1712]: Detected autofs mount point /boot during canonicalization of boot. Jul 10 00:22:18.044288 systemd-tmpfiles[1712]: Skipping /boot Jul 10 00:22:18.051761 zram_generator::config[1747]: No configuration found. Jul 10 00:22:18.170069 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. Jul 10 00:22:18.332011 systemd[1]: Reloading finished in 321 ms. Jul 10 00:22:18.374531 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 10 00:22:18.397048 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 10 00:22:18.415120 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 10 00:22:18.416544 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jul 10 00:22:18.437644 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Jul 10 00:22:18.442454 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 10 00:22:18.443636 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 10 00:22:18.448797 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 10 00:22:18.454580 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 10 00:22:18.458687 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 10 00:22:18.458834 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). Jul 10 00:22:18.460115 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Jul 10 00:22:18.466766 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jul 10 00:22:18.471992 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Jul 10 00:22:18.476789 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 10 00:22:18.478931 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 10 00:22:18.479149 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 10 00:22:18.484357 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 10 00:22:18.484552 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 10 00:22:18.489897 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 10 00:22:18.490096 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 10 00:22:18.503885 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Jul 10 00:22:18.510692 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Jul 10 00:22:18.522165 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 10 00:22:18.522458 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 10 00:22:18.523846 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 10 00:22:18.534245 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jul 10 00:22:18.539578 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 10 00:22:18.544567 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 10 00:22:18.549691 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 10 00:22:18.549851 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). Jul 10 00:22:18.550038 systemd[1]: Reached target time-set.target - System Time Set. Jul 10 00:22:18.554017 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 10 00:22:18.555704 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 10 00:22:18.555925 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 10 00:22:18.560753 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 10 00:22:18.560941 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jul 10 00:22:18.565610 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 10 00:22:18.565818 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 10 00:22:18.571364 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 10 00:22:18.571534 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 10 00:22:18.579070 systemd[1]: Finished ensure-sysext.service. Jul 10 00:22:18.585703 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 10 00:22:18.585774 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jul 10 00:22:18.598498 systemd-resolved[1819]: Positive Trust Anchors: Jul 10 00:22:18.598515 systemd-resolved[1819]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 10 00:22:18.598547 systemd-resolved[1819]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Jul 10 00:22:18.602768 systemd-resolved[1819]: Using system hostname 'ci-4344.1.1-n-526e1d82a0'. Jul 10 00:22:18.604895 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jul 10 00:22:18.609579 systemd[1]: Reached target network.target - Network. Jul 10 00:22:18.613226 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jul 10 00:22:18.616402 augenrules[1849]: No rules Jul 10 00:22:18.618391 systemd[1]: audit-rules.service: Deactivated successfully. Jul 10 00:22:18.618646 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jul 10 00:22:19.105878 systemd-networkd[1575]: eth0: Gained IPv6LL Jul 10 00:22:19.108669 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Jul 10 00:22:19.114315 systemd[1]: Reached target network-online.target - Network is Online. Jul 10 00:22:19.678936 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Jul 10 00:22:19.684413 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). Jul 10 00:22:23.989437 ldconfig[1525]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jul 10 00:22:24.004952 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Jul 10 00:22:24.011814 systemd[1]: Starting systemd-update-done.service - Update is Completed... Jul 10 00:22:24.037872 systemd[1]: Finished systemd-update-done.service - Update is Completed. Jul 10 00:22:24.042602 systemd[1]: Reached target sysinit.target - System Initialization. Jul 10 00:22:24.047150 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Jul 10 00:22:24.052318 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Jul 10 00:22:24.057567 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Jul 10 00:22:24.062450 systemd[1]: Started logrotate.timer - Daily rotation of log files. Jul 10 00:22:24.066858 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Jul 10 00:22:24.071829 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Jul 10 00:22:24.077007 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Jul 10 00:22:24.077059 systemd[1]: Reached target paths.target - Path Units. Jul 10 00:22:24.080746 systemd[1]: Reached target timers.target - Timer Units. Jul 10 00:22:24.097310 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Jul 10 00:22:24.103397 systemd[1]: Starting docker.socket - Docker Socket for the API... Jul 10 00:22:24.110093 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Jul 10 00:22:24.115254 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Jul 10 00:22:24.120198 systemd[1]: Reached target ssh-access.target - SSH Access Available. Jul 10 00:22:24.131512 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Jul 10 00:22:24.135962 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Jul 10 00:22:24.141542 systemd[1]: Listening on docker.socket - Docker Socket for the API. Jul 10 00:22:24.146961 systemd[1]: Reached target sockets.target - Socket Units. Jul 10 00:22:24.151087 systemd[1]: Reached target basic.target - Basic System. Jul 10 00:22:24.154854 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Jul 10 00:22:24.154885 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Jul 10 00:22:24.157505 systemd[1]: Starting chronyd.service - NTP client/server... Jul 10 00:22:24.161653 systemd[1]: Starting containerd.service - containerd container runtime... Jul 10 00:22:24.166989 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Jul 10 00:22:24.172079 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Jul 10 00:22:24.176660 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Jul 10 00:22:24.182468 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Jul 10 00:22:24.187779 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Jul 10 00:22:24.194802 jq[1870]: false Jul 10 00:22:24.194879 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). Jul 10 00:22:24.195946 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Jul 10 00:22:24.200047 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). Jul 10 00:22:24.201118 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Jul 10 00:22:24.205704 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Jul 10 00:22:24.206765 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Jul 10 00:22:24.208459 KVP[1873]: KVP starting; pid is:1873 Jul 10 00:22:24.210340 KVP[1873]: KVP LIC Version: 3.1 Jul 10 00:22:24.212803 kernel: hv_utils: KVP IC version 4.0 Jul 10 00:22:24.214273 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Jul 10 00:22:24.214488 (chronyd)[1862]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Jul 10 00:22:24.219078 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Jul 10 00:22:24.222748 extend-filesystems[1871]: Found /dev/sdb6 Jul 10 00:22:24.227838 extend-filesystems[1871]: Found /dev/sdb9 Jul 10 00:22:24.227838 extend-filesystems[1871]: Checking size of /dev/sdb9 Jul 10 00:22:24.226692 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Jul 10 00:22:24.231165 systemd[1]: Starting systemd-logind.service - User Login Management... Jul 10 00:22:24.242513 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Jul 10 00:22:24.243015 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Jul 10 00:22:24.245460 systemd[1]: Starting update-engine.service - Update Engine... Jul 10 00:22:24.246193 chronyd[1890]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) Jul 10 00:22:24.250619 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Jul 10 00:22:24.257382 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Jul 10 00:22:24.262896 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Jul 10 00:22:24.263118 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Jul 10 00:22:24.263448 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Jul 10 00:22:24.263653 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Jul 10 00:22:24.266908 jq[1891]: true Jul 10 00:22:24.272935 systemd[1]: motdgen.service: Deactivated successfully. Jul 10 00:22:24.273169 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Jul 10 00:22:24.278329 (ntainerd)[1900]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Jul 10 00:22:24.278567 jq[1899]: true Jul 10 00:22:24.284762 extend-filesystems[1871]: Old size kept for /dev/sdb9 Jul 10 00:22:24.289159 systemd[1]: extend-filesystems.service: Deactivated successfully. Jul 10 00:22:24.289402 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Jul 10 00:22:24.301048 chronyd[1890]: Timezone right/UTC failed leap second check, ignoring Jul 10 00:22:24.301297 oslogin_cache_refresh[1872]: Refreshing passwd entry cache Jul 10 00:22:24.301514 google_oslogin_nss_cache[1872]: oslogin_cache_refresh[1872]: Refreshing passwd entry cache Jul 10 00:22:24.301610 chronyd[1890]: Loaded seccomp filter (level 2) Jul 10 00:22:24.302543 systemd[1]: Started chronyd.service - NTP client/server. Jul 10 00:22:24.310678 google_oslogin_nss_cache[1872]: oslogin_cache_refresh[1872]: Failure getting users, quitting Jul 10 00:22:24.310673 oslogin_cache_refresh[1872]: Failure getting users, quitting Jul 10 00:22:24.310987 google_oslogin_nss_cache[1872]: oslogin_cache_refresh[1872]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Jul 10 00:22:24.310987 google_oslogin_nss_cache[1872]: oslogin_cache_refresh[1872]: Refreshing group entry cache Jul 10 00:22:24.310693 oslogin_cache_refresh[1872]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Jul 10 00:22:24.310757 oslogin_cache_refresh[1872]: Refreshing group entry cache Jul 10 00:22:24.315071 google_oslogin_nss_cache[1872]: oslogin_cache_refresh[1872]: Failure getting groups, quitting Jul 10 00:22:24.315071 google_oslogin_nss_cache[1872]: oslogin_cache_refresh[1872]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Jul 10 00:22:24.315062 oslogin_cache_refresh[1872]: Failure getting groups, quitting Jul 10 00:22:24.315073 oslogin_cache_refresh[1872]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Jul 10 00:22:24.317546 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Jul 10 00:22:24.318168 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Jul 10 00:22:24.330959 systemd-logind[1887]: New seat seat0. Jul 10 00:22:24.338325 systemd-logind[1887]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Jul 10 00:22:24.338632 systemd[1]: Started systemd-logind.service - User Login Management. Jul 10 00:22:24.348276 update_engine[1888]: I20250710 00:22:24.348182 1888 main.cc:92] Flatcar Update Engine starting Jul 10 00:22:24.463318 dbus-daemon[1865]: [system] SELinux support is enabled Jul 10 00:22:24.463545 systemd[1]: Started dbus.service - D-Bus System Message Bus. Jul 10 00:22:24.466512 update_engine[1888]: I20250710 00:22:24.466449 1888 update_check_scheduler.cc:74] Next update check in 5m2s Jul 10 00:22:24.470073 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). Jul 10 00:22:24.470108 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Jul 10 00:22:24.470876 dbus-daemon[1865]: [system] Successfully activated service 'org.freedesktop.systemd1' Jul 10 00:22:24.475622 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). Jul 10 00:22:24.475649 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Jul 10 00:22:24.481078 systemd[1]: Started update-engine.service - Update Engine. Jul 10 00:22:24.487424 systemd[1]: Started locksmithd.service - Cluster reboot manager. Jul 10 00:22:24.511599 bash[1931]: Updated "/home/core/.ssh/authorized_keys" Jul 10 00:22:24.513273 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Jul 10 00:22:24.519317 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Jul 10 00:22:24.520177 setup-nvidia[1878]: Downloading Flatcar Container Linux Developer Container for version: 4344.1.1+nightly-20250709-2100 Jul 10 00:22:24.601686 coreos-metadata[1864]: Jul 10 00:22:24.601 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Jul 10 00:22:24.604084 coreos-metadata[1864]: Jul 10 00:22:24.604 INFO Fetch successful Jul 10 00:22:24.604272 coreos-metadata[1864]: Jul 10 00:22:24.604 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Jul 10 00:22:24.608530 coreos-metadata[1864]: Jul 10 00:22:24.608 INFO Fetch successful Jul 10 00:22:24.608833 coreos-metadata[1864]: Jul 10 00:22:24.608 INFO Fetching http://168.63.129.16/machine/c48b2c15-b265-4dad-85a4-bcd7a016b81c/a363ae15%2Dc105%2D4d15%2D8ce2%2De0eec817f143.%5Fci%2D4344.1.1%2Dn%2D526e1d82a0?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Jul 10 00:22:24.610360 coreos-metadata[1864]: Jul 10 00:22:24.610 INFO Fetch successful Jul 10 00:22:24.610521 coreos-metadata[1864]: Jul 10 00:22:24.610 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Jul 10 00:22:24.617450 coreos-metadata[1864]: Jul 10 00:22:24.617 INFO Fetch successful Jul 10 00:22:24.653925 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Jul 10 00:22:24.658899 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Jul 10 00:22:24.714809 sshd_keygen[1935]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Jul 10 00:22:24.738791 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Jul 10 00:22:24.745251 systemd[1]: Starting issuegen.service - Generate /run/issue... Jul 10 00:22:24.749986 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Jul 10 00:22:24.771854 systemd[1]: issuegen.service: Deactivated successfully. Jul 10 00:22:24.772065 systemd[1]: Finished issuegen.service - Generate /run/issue. Jul 10 00:22:24.780537 locksmithd[2003]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Jul 10 00:22:24.780585 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Jul 10 00:22:24.785431 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Jul 10 00:22:24.805901 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Jul 10 00:22:24.816356 systemd[1]: Started getty@tty1.service - Getty on tty1. Jul 10 00:22:24.821643 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Jul 10 00:22:24.826733 systemd[1]: Reached target getty.target - Login Prompts. Jul 10 00:22:25.536333 containerd[1900]: time="2025-07-10T00:22:25Z" 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 Jul 10 00:22:25.537133 containerd[1900]: time="2025-07-10T00:22:25.537100100Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 Jul 10 00:22:25.545732 containerd[1900]: time="2025-07-10T00:22:25.545684000Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="9.6µs" Jul 10 00:22:25.545732 containerd[1900]: time="2025-07-10T00:22:25.545727300Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Jul 10 00:22:25.545822 containerd[1900]: time="2025-07-10T00:22:25.545749400Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Jul 10 00:22:25.545920 containerd[1900]: time="2025-07-10T00:22:25.545897900Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Jul 10 00:22:25.545949 containerd[1900]: time="2025-07-10T00:22:25.545920900Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Jul 10 00:22:25.545977 containerd[1900]: time="2025-07-10T00:22:25.545963300Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jul 10 00:22:25.546053 containerd[1900]: time="2025-07-10T00:22:25.546030700Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jul 10 00:22:25.546053 containerd[1900]: time="2025-07-10T00:22:25.546049100Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Jul 10 00:22:25.546322 containerd[1900]: time="2025-07-10T00:22:25.546298800Z" 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 Jul 10 00:22:25.546322 containerd[1900]: time="2025-07-10T00:22:25.546318900Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jul 10 00:22:25.546376 containerd[1900]: time="2025-07-10T00:22:25.546331300Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jul 10 00:22:25.546376 containerd[1900]: time="2025-07-10T00:22:25.546339400Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Jul 10 00:22:25.546457 containerd[1900]: time="2025-07-10T00:22:25.546437300Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Jul 10 00:22:25.546756 containerd[1900]: time="2025-07-10T00:22:25.546732800Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Jul 10 00:22:25.546786 containerd[1900]: time="2025-07-10T00:22:25.546772100Z" 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 Jul 10 00:22:25.546810 containerd[1900]: time="2025-07-10T00:22:25.546784800Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Jul 10 00:22:25.546843 containerd[1900]: time="2025-07-10T00:22:25.546829200Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Jul 10 00:22:25.548481 containerd[1900]: time="2025-07-10T00:22:25.548448400Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Jul 10 00:22:25.548563 containerd[1900]: time="2025-07-10T00:22:25.548541900Z" level=info msg="metadata content store policy set" policy=shared Jul 10 00:22:25.563485 containerd[1900]: time="2025-07-10T00:22:25.563450100Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Jul 10 00:22:25.563547 containerd[1900]: time="2025-07-10T00:22:25.563499500Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Jul 10 00:22:25.563547 containerd[1900]: time="2025-07-10T00:22:25.563517000Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Jul 10 00:22:25.563547 containerd[1900]: time="2025-07-10T00:22:25.563530400Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Jul 10 00:22:25.563547 containerd[1900]: time="2025-07-10T00:22:25.563542800Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Jul 10 00:22:25.563650 containerd[1900]: time="2025-07-10T00:22:25.563552600Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Jul 10 00:22:25.563650 containerd[1900]: time="2025-07-10T00:22:25.563565100Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Jul 10 00:22:25.563650 containerd[1900]: time="2025-07-10T00:22:25.563576200Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Jul 10 00:22:25.563650 containerd[1900]: time="2025-07-10T00:22:25.563586900Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Jul 10 00:22:25.563650 containerd[1900]: time="2025-07-10T00:22:25.563596600Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Jul 10 00:22:25.563650 containerd[1900]: time="2025-07-10T00:22:25.563605800Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Jul 10 00:22:25.563650 containerd[1900]: time="2025-07-10T00:22:25.563618600Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Jul 10 00:22:25.563825 containerd[1900]: time="2025-07-10T00:22:25.563771600Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Jul 10 00:22:25.563825 containerd[1900]: time="2025-07-10T00:22:25.563795800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Jul 10 00:22:25.563825 containerd[1900]: time="2025-07-10T00:22:25.563809500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Jul 10 00:22:25.563892 containerd[1900]: time="2025-07-10T00:22:25.563832600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Jul 10 00:22:25.563892 containerd[1900]: time="2025-07-10T00:22:25.563848000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Jul 10 00:22:25.563892 containerd[1900]: time="2025-07-10T00:22:25.563857900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Jul 10 00:22:25.563892 containerd[1900]: time="2025-07-10T00:22:25.563869800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Jul 10 00:22:25.563892 containerd[1900]: time="2025-07-10T00:22:25.563878900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Jul 10 00:22:25.563892 containerd[1900]: time="2025-07-10T00:22:25.563890300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Jul 10 00:22:25.564033 containerd[1900]: time="2025-07-10T00:22:25.563929400Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Jul 10 00:22:25.564033 containerd[1900]: time="2025-07-10T00:22:25.563945100Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Jul 10 00:22:25.564033 containerd[1900]: time="2025-07-10T00:22:25.564022800Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Jul 10 00:22:25.564100 containerd[1900]: time="2025-07-10T00:22:25.564041100Z" level=info msg="Start snapshots syncer" Jul 10 00:22:25.564100 containerd[1900]: time="2025-07-10T00:22:25.564068800Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Jul 10 00:22:25.564352 containerd[1900]: time="2025-07-10T00:22:25.564304400Z" 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\"}" Jul 10 00:22:25.564482 containerd[1900]: time="2025-07-10T00:22:25.564372900Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Jul 10 00:22:25.564482 containerd[1900]: time="2025-07-10T00:22:25.564472700Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Jul 10 00:22:25.564629 containerd[1900]: time="2025-07-10T00:22:25.564601900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Jul 10 00:22:25.564670 containerd[1900]: time="2025-07-10T00:22:25.564648400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Jul 10 00:22:25.564670 containerd[1900]: time="2025-07-10T00:22:25.564664800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Jul 10 00:22:25.564734 containerd[1900]: time="2025-07-10T00:22:25.564674800Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Jul 10 00:22:25.564734 containerd[1900]: time="2025-07-10T00:22:25.564687700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Jul 10 00:22:25.564734 containerd[1900]: time="2025-07-10T00:22:25.564698400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Jul 10 00:22:25.564734 containerd[1900]: time="2025-07-10T00:22:25.564708000Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Jul 10 00:22:25.564834 containerd[1900]: time="2025-07-10T00:22:25.564764600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Jul 10 00:22:25.564834 containerd[1900]: time="2025-07-10T00:22:25.564795200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Jul 10 00:22:25.564834 containerd[1900]: time="2025-07-10T00:22:25.564813900Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Jul 10 00:22:25.564908 containerd[1900]: time="2025-07-10T00:22:25.564844800Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Jul 10 00:22:25.564908 containerd[1900]: time="2025-07-10T00:22:25.564858000Z" 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 Jul 10 00:22:25.564908 containerd[1900]: time="2025-07-10T00:22:25.564867200Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jul 10 00:22:25.564908 containerd[1900]: time="2025-07-10T00:22:25.564877300Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jul 10 00:22:25.564908 containerd[1900]: time="2025-07-10T00:22:25.564884600Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Jul 10 00:22:25.564908 containerd[1900]: time="2025-07-10T00:22:25.564892800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Jul 10 00:22:25.564908 containerd[1900]: time="2025-07-10T00:22:25.564908000Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Jul 10 00:22:25.565059 containerd[1900]: time="2025-07-10T00:22:25.564925700Z" level=info msg="runtime interface created" Jul 10 00:22:25.565059 containerd[1900]: time="2025-07-10T00:22:25.564931600Z" level=info msg="created NRI interface" Jul 10 00:22:25.565059 containerd[1900]: time="2025-07-10T00:22:25.564939600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Jul 10 00:22:25.565059 containerd[1900]: time="2025-07-10T00:22:25.564969300Z" level=info msg="Connect containerd service" Jul 10 00:22:25.565059 containerd[1900]: time="2025-07-10T00:22:25.564999900Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Jul 10 00:22:25.565857 containerd[1900]: time="2025-07-10T00:22:25.565829600Z" 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" Jul 10 00:22:25.642528 setup-nvidia[2054]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 10 00:22:25.643232 setup-nvidia[2054]: Dload Upload Total Spent Left Speed Jul 10 00:22:26.554963 waagent[2038]: 2025-07-10T00:22:26.554860Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Jul 10 00:22:26.559374 waagent[2038]: 2025-07-10T00:22:26.559330Z INFO Daemon Daemon OS: flatcar 4344.1.1 Jul 10 00:22:26.562860 waagent[2038]: 2025-07-10T00:22:26.562816Z INFO Daemon Daemon Python: 3.11.12 Jul 10 00:22:26.566173 waagent[2038]: 2025-07-10T00:22:26.566130Z INFO Daemon Daemon Run daemon Jul 10 00:22:26.569004 waagent[2038]: 2025-07-10T00:22:26.568959Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4344.1.1' Jul 10 00:22:26.575553 waagent[2038]: 2025-07-10T00:22:26.575497Z INFO Daemon Daemon Using waagent for provisioning Jul 10 00:22:26.579484 waagent[2038]: 2025-07-10T00:22:26.579434Z INFO Daemon Daemon Activate resource disk Jul 10 00:22:26.582857 waagent[2038]: 2025-07-10T00:22:26.582808Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Jul 10 00:22:26.590204 waagent[2038]: 2025-07-10T00:22:26.590163Z INFO Daemon Daemon Found device: sda Jul 10 00:22:26.599753 waagent[2038]: 2025-07-10T00:22:26.599686Z INFO Daemon Daemon Examining partition table Jul 10 00:22:26.714804 waagent[2038]: 2025-07-10T00:22:26.714681Z INFO Daemon Daemon GPT not detected, determining filesystem Jul 10 00:22:26.815932 waagent[2038]: 2025-07-10T00:22:26.815768Z INFO Daemon Daemon sfdisk --part-type -f /dev/sda 1 -n succeeded Jul 10 00:22:26.821284 waagent[2038]: 2025-07-10T00:22:26.821230Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Jul 10 00:22:26.852649 containerd[1900]: time="2025-07-10T00:22:26.852594600Z" level=info msg="Start subscribing containerd event" Jul 10 00:22:26.853016 containerd[1900]: time="2025-07-10T00:22:26.852667400Z" level=info msg="Start recovering state" Jul 10 00:22:26.853016 containerd[1900]: time="2025-07-10T00:22:26.852832700Z" level=info msg="Start event monitor" Jul 10 00:22:26.853016 containerd[1900]: time="2025-07-10T00:22:26.852860900Z" level=info msg="Start cni network conf syncer for default" Jul 10 00:22:26.853016 containerd[1900]: time="2025-07-10T00:22:26.852876900Z" level=info msg="Start streaming server" Jul 10 00:22:26.853016 containerd[1900]: time="2025-07-10T00:22:26.852887800Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jul 10 00:22:26.853016 containerd[1900]: time="2025-07-10T00:22:26.852951600Z" level=info msg=serving... address=/run/containerd/containerd.sock Jul 10 00:22:26.853016 containerd[1900]: time="2025-07-10T00:22:26.852894200Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Jul 10 00:22:26.853016 containerd[1900]: time="2025-07-10T00:22:26.853005700Z" level=info msg="runtime interface starting up..." Jul 10 00:22:26.853016 containerd[1900]: time="2025-07-10T00:22:26.853020300Z" level=info msg="starting plugins..." Jul 10 00:22:26.853212 containerd[1900]: time="2025-07-10T00:22:26.853048000Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Jul 10 00:22:26.853327 containerd[1900]: time="2025-07-10T00:22:26.853304300Z" level=info msg="containerd successfully booted in 1.317472s" Jul 10 00:22:26.853408 systemd[1]: Started containerd.service - containerd container runtime. Jul 10 00:22:27.233823 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#203 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 10 00:22:27.244737 kernel: sda: sda1 Jul 10 00:22:27.261278 waagent[2038]: 2025-07-10T00:22:27.261181Z INFO Daemon Daemon sfdisk --part-type /dev/sda 1 83 succeeded Jul 10 00:22:27.274155 waagent[2038]: 2025-07-10T00:22:27.274102Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sda1] Jul 10 00:22:32.465033 login[2042]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Jul 10 00:22:32.559783 login[2041]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Jul 10 00:22:32.566972 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Jul 10 00:22:32.568200 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Jul 10 00:22:32.574587 systemd-logind[1887]: New session 2 of user core. Jul 10 00:22:32.684081 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Jul 10 00:22:32.687040 systemd[1]: Starting user@500.service - User Manager for UID 500... Jul 10 00:22:32.768874 (systemd)[2115]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Jul 10 00:22:32.771464 systemd-logind[1887]: New session c1 of user core. Jul 10 00:22:33.085000 waagent[2038]: 2025-07-10T00:22:33.084841Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sda1 /mnt/resource] Jul 10 00:22:33.215753 kernel: EXT4-fs (sda1): mounted filesystem 7f0d708a-fe35-4d49-940c-dd11d353c764 r/w with ordered data mode. Quota mode: none. Jul 10 00:22:33.216308 waagent[2038]: 2025-07-10T00:22:33.216209Z INFO Daemon Daemon Resource disk /dev/sda is mounted at /mnt/resource with ext4 Jul 10 00:22:33.222703 waagent[2038]: 2025-07-10T00:22:33.222648Z INFO Daemon Daemon Clean protocol and wireserver endpoint Jul 10 00:22:33.227488 waagent[2038]: 2025-07-10T00:22:33.227437Z INFO Daemon Daemon Running default provisioning handler Jul 10 00:22:33.239168 waagent[2038]: 2025-07-10T00:22:33.239112Z 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. Jul 10 00:22:33.249850 waagent[2038]: 2025-07-10T00:22:33.249798Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Jul 10 00:22:33.251436 waagent[2038]: 2025-07-10T00:22:33.251389Z INFO Daemon Daemon cloud-init is enabled: False Jul 10 00:22:33.252939 waagent[2038]: 2025-07-10T00:22:33.252902Z INFO Daemon Daemon Copying ovf-env.xml Jul 10 00:22:33.296552 systemd[2115]: Queued start job for default target default.target. Jul 10 00:22:33.313098 systemd[2115]: Created slice app.slice - User Application Slice. Jul 10 00:22:33.313131 systemd[2115]: Reached target paths.target - Paths. Jul 10 00:22:33.313190 systemd[2115]: Reached target timers.target - Timers. Jul 10 00:22:33.314009 waagent[2038]: 2025-07-10T00:22:33.313918Z INFO Daemon Daemon Successfully mounted dvd Jul 10 00:22:33.317904 systemd[2115]: Starting dbus.socket - D-Bus User Message Bus Socket... Jul 10 00:22:33.329080 systemd[2115]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jul 10 00:22:33.329201 systemd[2115]: Reached target sockets.target - Sockets. Jul 10 00:22:33.329252 systemd[2115]: Reached target basic.target - Basic System. Jul 10 00:22:33.329297 systemd[2115]: Reached target default.target - Main User Target. Jul 10 00:22:33.329333 systemd[2115]: Startup finished in 550ms. Jul 10 00:22:33.329446 systemd[1]: Started user@500.service - User Manager for UID 500. Jul 10 00:22:33.330939 systemd[1]: Started session-2.scope - Session 2 of User core. Jul 10 00:22:33.343383 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Jul 10 00:22:33.343581 waagent[2038]: 2025-07-10T00:22:33.343515Z INFO Daemon Daemon Detect protocol endpoint Jul 10 00:22:33.345240 waagent[2038]: 2025-07-10T00:22:33.345195Z INFO Daemon Daemon Clean protocol and wireserver endpoint Jul 10 00:22:33.346671 waagent[2038]: 2025-07-10T00:22:33.346633Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Jul 10 00:22:33.347257 waagent[2038]: 2025-07-10T00:22:33.347226Z INFO Daemon Daemon Test for route to 168.63.129.16 Jul 10 00:22:33.348870 waagent[2038]: 2025-07-10T00:22:33.348828Z INFO Daemon Daemon Route to 168.63.129.16 exists Jul 10 00:22:33.349452 waagent[2038]: 2025-07-10T00:22:33.349412Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Jul 10 00:22:33.360946 waagent[2038]: 2025-07-10T00:22:33.360893Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Jul 10 00:22:33.362576 waagent[2038]: 2025-07-10T00:22:33.362541Z INFO Daemon Daemon Wire protocol version:2012-11-30 Jul 10 00:22:33.363884 waagent[2038]: 2025-07-10T00:22:33.363844Z INFO Daemon Daemon Server preferred version:2015-04-05 Jul 10 00:22:33.466955 login[2042]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Jul 10 00:22:33.472163 systemd-logind[1887]: New session 1 of user core. Jul 10 00:22:33.478203 waagent[2038]: 2025-07-10T00:22:33.478116Z INFO Daemon Daemon Initializing goal state during protocol detection Jul 10 00:22:33.480022 waagent[2038]: 2025-07-10T00:22:33.479966Z INFO Daemon Daemon Forcing an update of the goal state. Jul 10 00:22:33.485389 waagent[2038]: 2025-07-10T00:22:33.485345Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Jul 10 00:22:33.494898 systemd[1]: Started session-1.scope - Session 1 of User core. Jul 10 00:22:33.533707 waagent[2038]: 2025-07-10T00:22:33.533648Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.175 Jul 10 00:22:33.535806 waagent[2038]: 2025-07-10T00:22:33.535750Z INFO Daemon Jul 10 00:22:33.536061 waagent[2038]: 2025-07-10T00:22:33.536024Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: b97d2fe9-094d-4984-93b7-d670f747835d eTag: 15693979672472566057 source: Fabric] Jul 10 00:22:33.537904 waagent[2038]: 2025-07-10T00:22:33.537857Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Jul 10 00:22:33.538625 waagent[2038]: 2025-07-10T00:22:33.538587Z INFO Daemon Jul 10 00:22:33.539922 waagent[2038]: 2025-07-10T00:22:33.539879Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Jul 10 00:22:33.544457 waagent[2038]: 2025-07-10T00:22:33.544426Z INFO Daemon Daemon Downloading artifacts profile blob Jul 10 00:22:33.668863 waagent[2038]: 2025-07-10T00:22:33.668729Z INFO Daemon Downloaded certificate {'thumbprint': '2E6FCE220DEEDFD50F7671421E406D4CD2313868', 'hasPrivateKey': True} Jul 10 00:22:33.670865 waagent[2038]: 2025-07-10T00:22:33.670817Z INFO Daemon Fetch goal state completed Jul 10 00:22:33.712352 waagent[2038]: 2025-07-10T00:22:33.712307Z INFO Daemon Daemon Starting provisioning Jul 10 00:22:33.713919 waagent[2038]: 2025-07-10T00:22:33.713877Z INFO Daemon Daemon Handle ovf-env.xml. Jul 10 00:22:33.715326 waagent[2038]: 2025-07-10T00:22:33.715288Z INFO Daemon Daemon Set hostname [ci-4344.1.1-n-526e1d82a0] Jul 10 00:22:33.736322 waagent[2038]: 2025-07-10T00:22:33.736272Z INFO Daemon Daemon Publish hostname [ci-4344.1.1-n-526e1d82a0] Jul 10 00:22:33.738116 waagent[2038]: 2025-07-10T00:22:33.738055Z INFO Daemon Daemon Examine /proc/net/route for primary interface Jul 10 00:22:33.739769 waagent[2038]: 2025-07-10T00:22:33.739692Z INFO Daemon Daemon Primary interface is [eth0] Jul 10 00:22:33.759177 systemd-networkd[1575]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 10 00:22:33.759187 systemd-networkd[1575]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 10 00:22:33.759216 systemd-networkd[1575]: eth0: DHCP lease lost Jul 10 00:22:33.759761 waagent[2038]: 2025-07-10T00:22:33.759698Z INFO Daemon Daemon Create user account if not exists Jul 10 00:22:33.761530 waagent[2038]: 2025-07-10T00:22:33.761343Z INFO Daemon Daemon User core already exists, skip useradd Jul 10 00:22:33.762743 waagent[2038]: 2025-07-10T00:22:33.762691Z INFO Daemon Daemon Configure sudoer Jul 10 00:22:33.771019 waagent[2038]: 2025-07-10T00:22:33.770974Z INFO Daemon Daemon Configure sshd Jul 10 00:22:33.776136 waagent[2038]: 2025-07-10T00:22:33.776093Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Jul 10 00:22:33.777700 waagent[2038]: 2025-07-10T00:22:33.777657Z INFO Daemon Daemon Deploy ssh public key. Jul 10 00:22:33.841767 systemd-networkd[1575]: eth0: DHCPv4 address 10.200.8.11/24, gateway 10.200.8.1 acquired from 168.63.129.16 Jul 10 00:22:48.083296 chronyd[1890]: Selected source PHC0 Jul 10 00:22:49.719138 setup-nvidia[2054]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 544M 0 1488k 0 0 956k 0 0:09:43 0:00:01 0:09:42 956k\u000d 4 544M 4 23.9M 0 0 9654k 0 0:00:57 0:00:02 0:00:55 9651k\u000d 8 544M 8 47.5M 0 0 13.5M 0 0:00:40 0:00:03 0:00:37 13.5M\u000d 12 544M 12 67.6M 0 0 15.1M 0 0:00:35 0:00:04 0:00:31 15.1M\u000d 16 544M 16 91.9M 0 0 16.6M 0 0:00:32 0:00:05 0:00:27 18.1M\u000d 20 544M 20 113M 0 0 17.5M 0 0:00:31 0:00:06 0:00:25 22.7M\u000d 24 544M 24 134M 0 0 17.9M 0 0:00:30 0:00:07 0:00:23 22.2M\u000d 29 544M 29 159M 0 0 18.6M 0 0:00:29 0:00:08 0:00:21 22.3M\u000d 33 544M 33 182M 0 0 19.1M 0 0:00:28 0:00:09 0:00:19 22.8M\u000d 37 544M 37 206M 0 0 19.7M 0 0:00:27 0:00:10 0:00:17 23.1M\u000d 42 544M 42 230M 0 0 20.0M 0 0:00:27 0:00:11 0:00:16 23.4M\u000d 46 544M 46 254M 0 0 20.4M 0 0:00:26 0:00:12 0:00:14 24.2M\u000d 51 544M 51 279M 0 0 20.8M 0 0:00:26 0:00:13 0:00:13 24.4M\u000d 56 544M 56 305M 0 0 20.9M 0 0:00:25 0:00:14 0:00:11 24.3M\u000d 60 544M 60 330M 0 0 21.2M 0 0:00:25 0:00:15 0:00:10 24.4M\u000d 65 544M 65 356M 0 0 21.5M 0 0:00:25 0:00:16 0:00:09 24.7M\u000d 70 544M 70 381M 0 0 21.7M 0 0:00:25 0:00:17 0:00:08 25.0M\u000d 74 544M 74 405M 0 0 21.8M 0 0:00:24 0:00:18 0:00:06 24.7M\u000d 79 544M 79 431M 0 0 22.0M 0 0:00:24 0:00:19 0:00:05 25.2M\u000d 83 544M 83 456M 0 0 22.2M 0 0:00:24 0:00:20 0:00:04 25.1M\u000d 88 544M 88 481M 0 0 22.3M 0 0:00:24 0:00:21 0:00:03 25.1M\u000d 92 544M 92 506M 0 0 22.4M 0 0:00:24 0:00:22 0:00:02 25.0M\u000d 97 544M 97 530M 0 0 22.5M 0 0:00:24 0:00:23 0:00:01 25.1M\u000d100 544M 100 544M 0 0 22.6M 0 0:00:24 0:00:24 --:--:-- 25.0M Jul 10 00:23:01.775421 setup-nvidia[1878]: Downloading NVIDIA 535.230.02 Driver Jul 10 00:23:01.780265 setup-nvidia[2183]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 10 00:23:01.780504 setup-nvidia[2183]: Dload Upload Total Spent Left Speed Jul 10 00:23:03.325008 setup-nvidia[2183]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 326M 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 79 326M 79 258M 0 0 196M 0 0:00:01 0:00:01 --:--:-- 196M\u000d100 326M 100 326M 0 0 211M 0 0:00:01 0:00:01 --:--:-- 211M Jul 10 00:23:03.409013 setup-nvidia[1878]: Extract the NVIDIA Driver Installer 535.230.02 Jul 10 00:23:03.409279 setup-nvidia[1878]: /opt/nvidia/workdir/nvidia-workdir / Jul 10 00:23:03.429894 setup-nvidia[2186]: Creating directory NVIDIA-Linux-x86_64-535.230.02 Jul 10 00:23:03.732647 setup-nvidia[2186]: Verifying archive integrity... OK Jul 10 00:23:03.851065 waagent[2038]: 2025-07-10T00:23:03.850987Z INFO Daemon Daemon Provisioning complete Jul 10 00:23:03.861623 waagent[2038]: 2025-07-10T00:23:03.861569Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Jul 10 00:23:03.863150 waagent[2038]: 2025-07-10T00:23:03.863102Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Jul 10 00:23:03.864546 waagent[2038]: 2025-07-10T00:23:03.864507Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Jul 10 00:23:04.031691 waagent[2209]: 2025-07-10T00:23:04.031502Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Jul 10 00:23:04.032108 waagent[2209]: 2025-07-10T00:23:04.031700Z INFO ExtHandler ExtHandler OS: flatcar 4344.1.1 Jul 10 00:23:04.032108 waagent[2209]: 2025-07-10T00:23:04.031799Z INFO ExtHandler ExtHandler Python: 3.11.12 Jul 10 00:23:04.032108 waagent[2209]: 2025-07-10T00:23:04.031875Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Jul 10 00:23:04.070911 waagent[2209]: 2025-07-10T00:23:04.070816Z INFO ExtHandler ExtHandler Distro: flatcar-4344.1.1; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.12; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Jul 10 00:23:04.071167 waagent[2209]: 2025-07-10T00:23:04.071128Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jul 10 00:23:04.071255 waagent[2209]: 2025-07-10T00:23:04.071222Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Jul 10 00:23:04.078959 waagent[2209]: 2025-07-10T00:23:04.078896Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Jul 10 00:23:04.088140 waagent[2209]: 2025-07-10T00:23:04.088093Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.175 Jul 10 00:23:04.088647 waagent[2209]: 2025-07-10T00:23:04.088607Z INFO ExtHandler Jul 10 00:23:04.088762 waagent[2209]: 2025-07-10T00:23:04.088699Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: b956fb44-12ad-48b2-a25f-57ca168c77a9 eTag: 15693979672472566057 source: Fabric] Jul 10 00:23:04.089084 waagent[2209]: 2025-07-10T00:23:04.089046Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Jul 10 00:23:04.089778 waagent[2209]: 2025-07-10T00:23:04.089693Z INFO ExtHandler Jul 10 00:23:04.089861 waagent[2209]: 2025-07-10T00:23:04.089829Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Jul 10 00:23:04.093324 waagent[2209]: 2025-07-10T00:23:04.093287Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Jul 10 00:23:04.167741 waagent[2209]: 2025-07-10T00:23:04.166517Z INFO ExtHandler Downloaded certificate {'thumbprint': '2E6FCE220DEEDFD50F7671421E406D4CD2313868', 'hasPrivateKey': True} Jul 10 00:23:04.167741 waagent[2209]: 2025-07-10T00:23:04.167183Z INFO ExtHandler Fetch goal state completed Jul 10 00:23:04.179431 waagent[2209]: 2025-07-10T00:23:04.179380Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.3.3 11 Feb 2025 (Library: OpenSSL 3.3.3 11 Feb 2025) Jul 10 00:23:04.184693 waagent[2209]: 2025-07-10T00:23:04.184652Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2209 Jul 10 00:23:04.184859 waagent[2209]: 2025-07-10T00:23:04.184822Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Jul 10 00:23:04.185197 waagent[2209]: 2025-07-10T00:23:04.185160Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Jul 10 00:23:04.186917 waagent[2209]: 2025-07-10T00:23:04.186871Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4344.1.1', '', 'Flatcar Container Linux by Kinvolk'] Jul 10 00:23:04.187337 waagent[2209]: 2025-07-10T00:23:04.187298Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4344.1.1', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Jul 10 00:23:04.187505 waagent[2209]: 2025-07-10T00:23:04.187471Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Jul 10 00:23:04.188132 waagent[2209]: 2025-07-10T00:23:04.188094Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Jul 10 00:23:04.239745 waagent[2209]: 2025-07-10T00:23:04.239684Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Jul 10 00:23:04.239990 waagent[2209]: 2025-07-10T00:23:04.239953Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Jul 10 00:23:04.247125 waagent[2209]: 2025-07-10T00:23:04.247082Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Jul 10 00:23:04.254585 systemd[1]: Reload requested from client PID 2224 ('systemctl') (unit waagent.service)... Jul 10 00:23:04.254607 systemd[1]: Reloading... Jul 10 00:23:04.317803 zram_generator::config[2263]: No configuration found. Jul 10 00:23:04.436566 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. Jul 10 00:23:04.601393 systemd[1]: Reloading finished in 346 ms. Jul 10 00:23:04.622441 waagent[2209]: 2025-07-10T00:23:04.622355Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Jul 10 00:23:04.622575 waagent[2209]: 2025-07-10T00:23:04.622538Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Jul 10 00:23:04.651830 setup-nvidia[2186]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.230.02 Jul 10 00:23:04.968392 waagent[2209]: 2025-07-10T00:23:04.968286Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Jul 10 00:23:04.968777 waagent[2209]: 2025-07-10T00:23:04.968736Z 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] Jul 10 00:23:04.969707 waagent[2209]: 2025-07-10T00:23:04.969656Z INFO ExtHandler ExtHandler Starting env monitor service. Jul 10 00:23:04.970080 waagent[2209]: 2025-07-10T00:23:04.970026Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jul 10 00:23:04.970336 waagent[2209]: 2025-07-10T00:23:04.970290Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Jul 10 00:23:04.970633 waagent[2209]: 2025-07-10T00:23:04.970587Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Jul 10 00:23:04.970765 waagent[2209]: 2025-07-10T00:23:04.970703Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jul 10 00:23:04.971112 waagent[2209]: 2025-07-10T00:23:04.971071Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Jul 10 00:23:04.971293 waagent[2209]: 2025-07-10T00:23:04.971255Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Jul 10 00:23:04.971612 waagent[2209]: 2025-07-10T00:23:04.971559Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Jul 10 00:23:04.971701 waagent[2209]: 2025-07-10T00:23:04.971644Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Jul 10 00:23:04.972286 waagent[2209]: 2025-07-10T00:23:04.972232Z INFO EnvHandler ExtHandler Configure routes Jul 10 00:23:04.972816 waagent[2209]: 2025-07-10T00:23:04.972772Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Jul 10 00:23:04.972816 waagent[2209]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Jul 10 00:23:04.972816 waagent[2209]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Jul 10 00:23:04.972816 waagent[2209]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Jul 10 00:23:04.972816 waagent[2209]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Jul 10 00:23:04.972816 waagent[2209]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jul 10 00:23:04.972816 waagent[2209]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jul 10 00:23:04.973069 waagent[2209]: 2025-07-10T00:23:04.972836Z INFO EnvHandler ExtHandler Gateway:None Jul 10 00:23:04.973069 waagent[2209]: 2025-07-10T00:23:04.972976Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Jul 10 00:23:04.973156 waagent[2209]: 2025-07-10T00:23:04.973109Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Jul 10 00:23:04.973383 waagent[2209]: 2025-07-10T00:23:04.973341Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Jul 10 00:23:04.973513 waagent[2209]: 2025-07-10T00:23:04.973475Z INFO EnvHandler ExtHandler Routes:None Jul 10 00:23:04.981000 waagent[2209]: 2025-07-10T00:23:04.980950Z INFO ExtHandler ExtHandler Jul 10 00:23:04.981345 waagent[2209]: 2025-07-10T00:23:04.981307Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 38a48728-5117-4ea3-88cf-f0e87a8be099 correlation 718ad4b0-4bf0-481b-a8f1-a29cf8605e2d created: 2025-07-10T00:21:06.468521Z] Jul 10 00:23:04.983443 waagent[2209]: 2025-07-10T00:23:04.983403Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Jul 10 00:23:04.984104 waagent[2209]: 2025-07-10T00:23:04.984067Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Jul 10 00:23:05.006674 waagent[2209]: 2025-07-10T00:23:05.006612Z 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 Jul 10 00:23:05.006674 waagent[2209]: Try `iptables -h' or 'iptables --help' for more information.) Jul 10 00:23:05.007122 waagent[2209]: 2025-07-10T00:23:05.007083Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: F1BD1EA8-2456-45E9-A0C9-3FA55824A772;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Jul 10 00:23:05.032371 waagent[2209]: 2025-07-10T00:23:05.032270Z INFO MonitorHandler ExtHandler Network interfaces: Jul 10 00:23:05.032371 waagent[2209]: Executing ['ip', '-a', '-o', 'link']: Jul 10 00:23:05.032371 waagent[2209]: 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 Jul 10 00:23:05.032371 waagent[2209]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:ed:8d:2d:ef:5b brd ff:ff:ff:ff:ff:ff Jul 10 00:23:05.032371 waagent[2209]: Executing ['ip', '-4', '-a', '-o', 'address']: Jul 10 00:23:05.032371 waagent[2209]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Jul 10 00:23:05.032371 waagent[2209]: 2: eth0 inet 10.200.8.11/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Jul 10 00:23:05.032371 waagent[2209]: Executing ['ip', '-6', '-a', '-o', 'address']: Jul 10 00:23:05.032371 waagent[2209]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Jul 10 00:23:05.032371 waagent[2209]: 2: eth0 inet6 fe80::7eed:8dff:fe2d:ef5b/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Jul 10 00:23:05.093340 waagent[2209]: 2025-07-10T00:23:05.093257Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Jul 10 00:23:05.093340 waagent[2209]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Jul 10 00:23:05.093340 waagent[2209]: pkts bytes target prot opt in out source destination Jul 10 00:23:05.093340 waagent[2209]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Jul 10 00:23:05.093340 waagent[2209]: pkts bytes target prot opt in out source destination Jul 10 00:23:05.093340 waagent[2209]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Jul 10 00:23:05.093340 waagent[2209]: pkts bytes target prot opt in out source destination Jul 10 00:23:05.093340 waagent[2209]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Jul 10 00:23:05.093340 waagent[2209]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Jul 10 00:23:05.093340 waagent[2209]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Jul 10 00:23:05.097778 waagent[2209]: 2025-07-10T00:23:05.097701Z INFO EnvHandler ExtHandler Current Firewall rules: Jul 10 00:23:05.097778 waagent[2209]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Jul 10 00:23:05.097778 waagent[2209]: pkts bytes target prot opt in out source destination Jul 10 00:23:05.097778 waagent[2209]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Jul 10 00:23:05.097778 waagent[2209]: pkts bytes target prot opt in out source destination Jul 10 00:23:05.097778 waagent[2209]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Jul 10 00:23:05.097778 waagent[2209]: pkts bytes target prot opt in out source destination Jul 10 00:23:05.097778 waagent[2209]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Jul 10 00:23:05.097778 waagent[2209]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Jul 10 00:23:05.097778 waagent[2209]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Jul 10 00:23:05.098061 waagent[2209]: 2025-07-10T00:23:05.098027Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Jul 10 00:23:05.098212 waagent[2209]: 2025-07-10T00:23:05.098180Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Jul 10 00:23:05.622753 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Jul 10 00:23:06.640563 setup-nvidia[2322]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Jul 10 00:23:06.711697 setup-nvidia[1878]: / Jul 10 00:23:06.711991 setup-nvidia[1878]: Spawn system-nspawn container to install the NVIDIA drivers Jul 10 00:23:07.110704 sudo[2366]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4344.1.1+nightly-20250709-2100.bin --overlay=/usr/lib/modules::/usr/lib/modules --bind=/opt/nvidia/workdir/nvidia-workdir:/nvidia --bind=/usr/lib/nvidia/bin:/app/bin/ /app/bin/install-nvidia NVIDIA-Linux-x86_64-535.230.02 Jul 10 00:23:07.112466 sudo[2366]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 10 00:23:07.118527 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Jul 10 00:23:07.121364 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Jul 10 00:23:07.160537 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Jul 10 00:23:07.164720 systemd[1]: Starting user@0.service - User Manager for UID 0... Jul 10 00:23:07.186820 (systemd)[2369]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Jul 10 00:23:07.189201 systemd-logind[1887]: New session c3 of user root. Jul 10 00:23:07.379075 systemd[2369]: Queued start job for default target default.target. Jul 10 00:23:07.402899 systemd[2369]: Created slice app.slice - User Application Slice. Jul 10 00:23:07.402929 systemd[2369]: Reached target paths.target - Paths. Jul 10 00:23:07.402979 systemd[2369]: Reached target timers.target - Timers. Jul 10 00:23:07.404269 systemd[2369]: Starting dbus.socket - D-Bus User Message Bus Socket... Jul 10 00:23:07.415705 systemd[2369]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jul 10 00:23:07.415856 systemd[2369]: Reached target sockets.target - Sockets. Jul 10 00:23:07.415910 systemd[2369]: Reached target basic.target - Basic System. Jul 10 00:23:07.415956 systemd[2369]: Reached target default.target - Main User Target. Jul 10 00:23:07.416000 systemd[2369]: Startup finished in 220ms. Jul 10 00:23:07.416164 systemd[1]: Started user@0.service - User Manager for UID 0. Jul 10 00:23:07.419245 systemd[1]: Started session-c2.scope - Session c2 of User root. Jul 10 00:23:07.611746 kernel: loop6: detected capacity change from 0 to 12644352 Jul 10 00:23:07.620739 kernel: loop6: p9 Jul 10 00:23:07.727827 setup-nvidia[2378]: ░ Spawning container flatcardevelopercontainer-4344.1.1nightly-20250709-2100.bin on /opt/nvidia/workdir/flatcar_developer_container-4344.1.1+nightly-20250709-2100.bin. Jul 10 00:23:09.427378 kernel: EXT4-fs (loop6p9): write access unavailable, skipping orphan cleanup Jul 10 00:23:09.427539 kernel: EXT4-fs (loop6p9): mounted filesystem 0b76e2d8-7a79-42f7-b981-d0b16ae017a3 ro without journal. Quota mode: none. Jul 10 00:23:09.778188 update_engine[1888]: I20250710 00:23:09.778025 1888 update_attempter.cc:509] Updating boot flags... Jul 10 00:23:23.692073 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Jul 10 00:23:23.695006 systemd[1]: Started sshd@0-10.200.8.11:22-10.200.16.10:34918.service - OpenSSH per-connection server daemon (10.200.16.10:34918). Jul 10 00:23:24.906488 sshd[3206]: Accepted publickey for core from 10.200.16.10 port 34918 ssh2: RSA SHA256:3h+tkKhDgHLcc6lb3dyCgy4hKUs2nEozCTgQH7eTVjg Jul 10 00:23:24.907939 sshd-session[3206]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 10 00:23:24.912805 systemd-logind[1887]: New session 3 of user core. Jul 10 00:23:24.928856 systemd[1]: Started session-3.scope - Session 3 of User core. Jul 10 00:23:25.468604 systemd[1]: Started sshd@1-10.200.8.11:22-10.200.16.10:34928.service - OpenSSH per-connection server daemon (10.200.16.10:34928). Jul 10 00:23:26.100054 sshd[3261]: Accepted publickey for core from 10.200.16.10 port 34928 ssh2: RSA SHA256:3h+tkKhDgHLcc6lb3dyCgy4hKUs2nEozCTgQH7eTVjg Jul 10 00:23:26.101516 sshd-session[3261]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 10 00:23:26.106451 systemd-logind[1887]: New session 4 of user core. Jul 10 00:23:26.114903 systemd[1]: Started session-4.scope - Session 4 of User core. Jul 10 00:23:26.545982 sshd[3310]: Connection closed by 10.200.16.10 port 34928 Jul 10 00:23:26.546327 sshd-session[3261]: pam_unix(sshd:session): session closed for user core Jul 10 00:23:26.550339 systemd[1]: sshd@1-10.200.8.11:22-10.200.16.10:34928.service: Deactivated successfully. Jul 10 00:23:26.552058 systemd[1]: session-4.scope: Deactivated successfully. Jul 10 00:23:26.552789 systemd-logind[1887]: Session 4 logged out. Waiting for processes to exit. Jul 10 00:23:26.554422 systemd-logind[1887]: Removed session 4. Jul 10 00:23:26.657227 systemd[1]: Started sshd@2-10.200.8.11:22-10.200.16.10:34944.service - OpenSSH per-connection server daemon (10.200.16.10:34944). Jul 10 00:23:27.290058 sshd[3316]: Accepted publickey for core from 10.200.16.10 port 34944 ssh2: RSA SHA256:3h+tkKhDgHLcc6lb3dyCgy4hKUs2nEozCTgQH7eTVjg Jul 10 00:23:27.291589 sshd-session[3316]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 10 00:23:27.296508 systemd-logind[1887]: New session 5 of user core. Jul 10 00:23:27.315847 systemd[1]: Started session-5.scope - Session 5 of User core. Jul 10 00:23:27.749593 sshd[3318]: Connection closed by 10.200.16.10 port 34944 Jul 10 00:23:27.750017 sshd-session[3316]: pam_unix(sshd:session): session closed for user core Jul 10 00:23:27.754280 systemd[1]: sshd@2-10.200.8.11:22-10.200.16.10:34944.service: Deactivated successfully. Jul 10 00:23:27.756278 systemd[1]: session-5.scope: Deactivated successfully. Jul 10 00:23:27.757000 systemd-logind[1887]: Session 5 logged out. Waiting for processes to exit. Jul 10 00:23:27.758121 systemd-logind[1887]: Removed session 5. Jul 10 00:23:37.870226 systemd[1]: Started sshd@3-10.200.8.11:22-10.200.16.10:60340.service - OpenSSH per-connection server daemon (10.200.16.10:60340). Jul 10 00:23:38.527139 sshd[5948]: Accepted publickey for core from 10.200.16.10 port 60340 ssh2: RSA SHA256:3h+tkKhDgHLcc6lb3dyCgy4hKUs2nEozCTgQH7eTVjg Jul 10 00:23:38.528558 sshd-session[5948]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 10 00:23:38.534377 systemd-logind[1887]: New session 6 of user core. Jul 10 00:23:38.544867 systemd[1]: Started session-6.scope - Session 6 of User core. Jul 10 00:23:38.972831 sshd[6130]: Connection closed by 10.200.16.10 port 60340 Jul 10 00:23:38.973740 sshd-session[5948]: pam_unix(sshd:session): session closed for user core Jul 10 00:23:38.979817 systemd[1]: sshd@3-10.200.8.11:22-10.200.16.10:60340.service: Deactivated successfully. Jul 10 00:23:38.980191 systemd-logind[1887]: Session 6 logged out. Waiting for processes to exit. Jul 10 00:23:38.982473 systemd[1]: session-6.scope: Deactivated successfully. Jul 10 00:23:38.985096 systemd-logind[1887]: Removed session 6. Jul 10 00:23:39.089387 systemd[1]: Started sshd@4-10.200.8.11:22-10.200.16.10:60350.service - OpenSSH per-connection server daemon (10.200.16.10:60350). Jul 10 00:23:39.740933 sshd[6297]: Accepted publickey for core from 10.200.16.10 port 60350 ssh2: RSA SHA256:3h+tkKhDgHLcc6lb3dyCgy4hKUs2nEozCTgQH7eTVjg Jul 10 00:23:39.743092 sshd-session[6297]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 10 00:23:39.748868 systemd-logind[1887]: New session 7 of user core. Jul 10 00:23:39.758876 systemd[1]: Started session-7.scope - Session 7 of User core. Jul 10 00:23:40.195858 sshd[6492]: Connection closed by 10.200.16.10 port 60350 Jul 10 00:23:40.197831 sshd-session[6297]: pam_unix(sshd:session): session closed for user core Jul 10 00:23:40.202334 systemd[1]: sshd@4-10.200.8.11:22-10.200.16.10:60350.service: Deactivated successfully. Jul 10 00:23:40.204430 systemd-logind[1887]: Session 7 logged out. Waiting for processes to exit. Jul 10 00:23:40.205358 systemd[1]: session-7.scope: Deactivated successfully. Jul 10 00:23:40.211074 systemd-logind[1887]: Removed session 7. Jul 10 00:23:50.315457 systemd[1]: Started sshd@5-10.200.8.11:22-10.200.16.10:43234.service - OpenSSH per-connection server daemon (10.200.16.10:43234). Jul 10 00:23:50.974666 sshd[9188]: Accepted publickey for core from 10.200.16.10 port 43234 ssh2: RSA SHA256:3h+tkKhDgHLcc6lb3dyCgy4hKUs2nEozCTgQH7eTVjg Jul 10 00:23:50.976054 sshd-session[9188]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 10 00:23:50.985143 systemd-logind[1887]: New session 8 of user core. Jul 10 00:23:50.991347 systemd[1]: Started session-8.scope - Session 8 of User core. Jul 10 00:23:51.424803 sshd[9371]: Connection closed by 10.200.16.10 port 43234 Jul 10 00:23:51.425406 sshd-session[9188]: pam_unix(sshd:session): session closed for user core Jul 10 00:23:51.429276 systemd-logind[1887]: Session 8 logged out. Waiting for processes to exit. Jul 10 00:23:51.431485 systemd[1]: sshd@5-10.200.8.11:22-10.200.16.10:43234.service: Deactivated successfully. Jul 10 00:23:51.434224 systemd[1]: session-8.scope: Deactivated successfully. Jul 10 00:23:51.436473 systemd-logind[1887]: Removed session 8. Jul 10 00:23:51.537962 systemd[1]: Started sshd@6-10.200.8.11:22-10.200.16.10:43248.service - OpenSSH per-connection server daemon (10.200.16.10:43248). Jul 10 00:23:52.194175 sshd[9521]: Accepted publickey for core from 10.200.16.10 port 43248 ssh2: RSA SHA256:3h+tkKhDgHLcc6lb3dyCgy4hKUs2nEozCTgQH7eTVjg Jul 10 00:23:52.196365 sshd-session[9521]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 10 00:23:52.202488 systemd-logind[1887]: New session 9 of user core. Jul 10 00:23:52.211915 systemd[1]: Started session-9.scope - Session 9 of User core. Jul 10 00:23:52.642746 sshd[9767]: Connection closed by 10.200.16.10 port 43248 Jul 10 00:23:52.643444 sshd-session[9521]: pam_unix(sshd:session): session closed for user core Jul 10 00:23:52.648553 systemd[1]: sshd@6-10.200.8.11:22-10.200.16.10:43248.service: Deactivated successfully. Jul 10 00:23:52.650541 systemd[1]: session-9.scope: Deactivated successfully. Jul 10 00:23:52.650904 systemd-logind[1887]: Session 9 logged out. Waiting for processes to exit. Jul 10 00:23:52.659016 systemd-logind[1887]: Removed session 9. Jul 10 00:24:02.771975 systemd[1]: Started sshd@7-10.200.8.11:22-10.200.16.10:47760.service - OpenSSH per-connection server daemon (10.200.16.10:47760). Jul 10 00:24:03.423970 sshd[11494]: Accepted publickey for core from 10.200.16.10 port 47760 ssh2: RSA SHA256:3h+tkKhDgHLcc6lb3dyCgy4hKUs2nEozCTgQH7eTVjg Jul 10 00:24:03.424551 sshd-session[11494]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 10 00:24:03.431738 systemd-logind[1887]: New session 10 of user core. Jul 10 00:24:03.439906 systemd[1]: Started session-10.scope - Session 10 of User core. Jul 10 00:24:03.872774 sshd[11509]: Connection closed by 10.200.16.10 port 47760 Jul 10 00:24:03.875935 sshd-session[11494]: pam_unix(sshd:session): session closed for user core Jul 10 00:24:03.881629 systemd[1]: sshd@7-10.200.8.11:22-10.200.16.10:47760.service: Deactivated successfully. Jul 10 00:24:03.884012 systemd[1]: session-10.scope: Deactivated successfully. Jul 10 00:24:03.887667 systemd-logind[1887]: Session 10 logged out. Waiting for processes to exit. Jul 10 00:24:03.889315 systemd-logind[1887]: Removed session 10. Jul 10 00:24:03.990380 systemd[1]: Started sshd@8-10.200.8.11:22-10.200.16.10:47776.service - OpenSSH per-connection server daemon (10.200.16.10:47776). Jul 10 00:24:04.652641 sshd[11532]: Accepted publickey for core from 10.200.16.10 port 47776 ssh2: RSA SHA256:3h+tkKhDgHLcc6lb3dyCgy4hKUs2nEozCTgQH7eTVjg Jul 10 00:24:04.654278 sshd-session[11532]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 10 00:24:04.661476 systemd-logind[1887]: New session 11 of user core. Jul 10 00:24:04.669232 systemd[1]: Started session-11.scope - Session 11 of User core. Jul 10 00:24:05.113659 sshd[11558]: Connection closed by 10.200.16.10 port 47776 Jul 10 00:24:05.115577 sshd-session[11532]: pam_unix(sshd:session): session closed for user core Jul 10 00:24:05.120615 systemd-logind[1887]: Session 11 logged out. Waiting for processes to exit. Jul 10 00:24:05.121518 systemd[1]: sshd@8-10.200.8.11:22-10.200.16.10:47776.service: Deactivated successfully. Jul 10 00:24:05.125465 systemd[1]: session-11.scope: Deactivated successfully. Jul 10 00:24:05.129045 systemd-logind[1887]: Removed session 11. Jul 10 00:24:15.230943 systemd[1]: Started sshd@9-10.200.8.11:22-10.200.16.10:34074.service - OpenSSH per-connection server daemon (10.200.16.10:34074). Jul 10 00:24:15.890657 sshd[11870]: Accepted publickey for core from 10.200.16.10 port 34074 ssh2: RSA SHA256:3h+tkKhDgHLcc6lb3dyCgy4hKUs2nEozCTgQH7eTVjg Jul 10 00:24:15.890410 sshd-session[11870]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 10 00:24:15.898061 systemd-logind[1887]: New session 12 of user core. Jul 10 00:24:15.906469 systemd[1]: Started session-12.scope - Session 12 of User core. Jul 10 00:24:16.341264 sshd[11885]: Connection closed by 10.200.16.10 port 34074 Jul 10 00:24:16.341701 sshd-session[11870]: pam_unix(sshd:session): session closed for user core Jul 10 00:24:16.345466 systemd-logind[1887]: Session 12 logged out. Waiting for processes to exit. Jul 10 00:24:16.347254 systemd[1]: sshd@9-10.200.8.11:22-10.200.16.10:34074.service: Deactivated successfully. Jul 10 00:24:16.348936 systemd[1]: session-12.scope: Deactivated successfully. Jul 10 00:24:16.352131 systemd-logind[1887]: Removed session 12. Jul 10 00:24:16.454617 systemd[1]: Started sshd@10-10.200.8.11:22-10.200.16.10:34082.service - OpenSSH per-connection server daemon (10.200.16.10:34082). Jul 10 00:24:17.124568 sshd[11921]: Accepted publickey for core from 10.200.16.10 port 34082 ssh2: RSA SHA256:3h+tkKhDgHLcc6lb3dyCgy4hKUs2nEozCTgQH7eTVjg Jul 10 00:24:17.124459 sshd-session[11921]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 10 00:24:17.130433 systemd-logind[1887]: New session 13 of user core. Jul 10 00:24:17.138646 systemd[1]: Started session-13.scope - Session 13 of User core. Jul 10 00:24:17.576981 sshd[11940]: Connection closed by 10.200.16.10 port 34082 Jul 10 00:24:17.578910 sshd-session[11921]: pam_unix(sshd:session): session closed for user core Jul 10 00:24:17.583618 systemd[1]: sshd@10-10.200.8.11:22-10.200.16.10:34082.service: Deactivated successfully. Jul 10 00:24:17.587017 systemd[1]: session-13.scope: Deactivated successfully. Jul 10 00:24:17.588845 systemd-logind[1887]: Session 13 logged out. Waiting for processes to exit. Jul 10 00:24:17.590319 systemd-logind[1887]: Removed session 13. Jul 10 00:24:27.693984 systemd[1]: Started sshd@11-10.200.8.11:22-10.200.16.10:35224.service - OpenSSH per-connection server daemon (10.200.16.10:35224). Jul 10 00:24:28.360003 sshd[12235]: Accepted publickey for core from 10.200.16.10 port 35224 ssh2: RSA SHA256:3h+tkKhDgHLcc6lb3dyCgy4hKUs2nEozCTgQH7eTVjg Jul 10 00:24:28.361770 sshd-session[12235]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 10 00:24:28.369972 systemd-logind[1887]: New session 14 of user core. Jul 10 00:24:28.377509 systemd[1]: Started session-14.scope - Session 14 of User core. Jul 10 00:24:28.817807 sshd[12250]: Connection closed by 10.200.16.10 port 35224 Jul 10 00:24:28.820931 sshd-session[12235]: pam_unix(sshd:session): session closed for user core Jul 10 00:24:28.824555 systemd-logind[1887]: Session 14 logged out. Waiting for processes to exit. Jul 10 00:24:28.826292 systemd[1]: sshd@11-10.200.8.11:22-10.200.16.10:35224.service: Deactivated successfully. Jul 10 00:24:28.828107 systemd[1]: session-14.scope: Deactivated successfully. Jul 10 00:24:28.830955 systemd-logind[1887]: Removed session 14. Jul 10 00:24:28.940002 systemd[1]: Started sshd@12-10.200.8.11:22-10.200.16.10:35228.service - OpenSSH per-connection server daemon (10.200.16.10:35228). Jul 10 00:24:29.601467 sshd[12279]: Accepted publickey for core from 10.200.16.10 port 35228 ssh2: RSA SHA256:3h+tkKhDgHLcc6lb3dyCgy4hKUs2nEozCTgQH7eTVjg Jul 10 00:24:29.603501 sshd-session[12279]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 10 00:24:29.611341 systemd-logind[1887]: New session 15 of user core. Jul 10 00:24:29.619842 systemd[1]: Started session-15.scope - Session 15 of User core. Jul 10 00:24:30.055848 sshd[12312]: Connection closed by 10.200.16.10 port 35228 Jul 10 00:24:30.056216 sshd-session[12279]: pam_unix(sshd:session): session closed for user core Jul 10 00:24:30.061930 systemd[1]: sshd@12-10.200.8.11:22-10.200.16.10:35228.service: Deactivated successfully. Jul 10 00:24:30.065571 systemd[1]: session-15.scope: Deactivated successfully. Jul 10 00:24:30.066812 systemd-logind[1887]: Session 15 logged out. Waiting for processes to exit. Jul 10 00:24:30.069652 systemd-logind[1887]: Removed session 15. Jul 10 00:24:40.172378 systemd[1]: Started sshd@13-10.200.8.11:22-10.200.16.10:39292.service - OpenSSH per-connection server daemon (10.200.16.10:39292). Jul 10 00:24:40.828465 sshd[12466]: Accepted publickey for core from 10.200.16.10 port 39292 ssh2: RSA SHA256:3h+tkKhDgHLcc6lb3dyCgy4hKUs2nEozCTgQH7eTVjg Jul 10 00:24:40.829090 sshd-session[12466]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 10 00:24:40.837928 systemd-logind[1887]: New session 16 of user core. Jul 10 00:24:40.846891 systemd[1]: Started session-16.scope - Session 16 of User core. Jul 10 00:24:41.273552 sshd[12487]: Connection closed by 10.200.16.10 port 39292 Jul 10 00:24:41.273950 sshd-session[12466]: pam_unix(sshd:session): session closed for user core Jul 10 00:24:41.277777 systemd[1]: sshd@13-10.200.8.11:22-10.200.16.10:39292.service: Deactivated successfully. Jul 10 00:24:41.279902 systemd[1]: session-16.scope: Deactivated successfully. Jul 10 00:24:41.280645 systemd-logind[1887]: Session 16 logged out. Waiting for processes to exit. Jul 10 00:24:41.281611 systemd-logind[1887]: Removed session 16. Jul 10 00:24:41.391464 systemd[1]: Started sshd@14-10.200.8.11:22-10.200.16.10:39294.service - OpenSSH per-connection server daemon (10.200.16.10:39294). Jul 10 00:24:41.687287 setup-nvidia[2378]: \u000d Jul 10 00:24:41.687287 setup-nvidia[2378]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Jul 10 00:24:41.687287 setup-nvidia[2378]: frequently when this kernel module was built against the wrong or Jul 10 00:24:41.687287 setup-nvidia[2378]: improperly configured kernel sources, with a version of gcc that differs Jul 10 00:24:41.687287 setup-nvidia[2378]: from the one used to build the target kernel, or if another driver, such Jul 10 00:24:41.687287 setup-nvidia[2378]: as nouveau, is present and prevents the NVIDIA kernel module from Jul 10 00:24:41.687287 setup-nvidia[2378]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Jul 10 00:24:41.687287 setup-nvidia[2378]: installed in this system is supported by this NVIDIA Linux graphics Jul 10 00:24:41.687287 setup-nvidia[2378]: driver release. Jul 10 00:24:41.687287 setup-nvidia[2378]: \u000d Jul 10 00:24:41.687287 setup-nvidia[2378]: Please see the log entries 'Kernel module load error' and 'Kernel Jul 10 00:24:41.687287 setup-nvidia[2378]: messages' at the end of the file Jul 10 00:24:41.687287 setup-nvidia[2378]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more Jul 10 00:24:41.687287 setup-nvidia[2378]: information. Jul 10 00:24:41.687287 setup-nvidia[2378]: \u000d Jul 10 00:24:41.702183 setup-nvidia[2378]: dmesg: read kernel buffer failed: Operation not permitted Jul 10 00:24:41.725802 setup-nvidia[2378]: \u000d Jul 10 00:24:41.725802 setup-nvidia[2378]: ERROR: Installation has failed. Please see the file Jul 10 00:24:41.725802 setup-nvidia[2378]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for Jul 10 00:24:41.725802 setup-nvidia[2378]: details. You may find suggestions on fixing installation problems in Jul 10 00:24:41.725802 setup-nvidia[2378]: the README available on the Linux driver download page at Jul 10 00:24:41.725802 setup-nvidia[2378]: www.nvidia.com. Jul 10 00:24:41.725802 setup-nvidia[2378]: \u000d Jul 10 00:24:41.725802 setup-nvidia[2378]: Last 50 lines of nvidia-installer.log: Jul 10 00:24:41.725802 setup-nvidia[2378]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039000rm+0x53: 'naked' return found in MITIGATION_RETHUNK build Jul 10 00:24:41.725802 setup-nvidia[2378]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038997rm+0x90: 'naked' return found in MITIGATION_RETHUNK build Jul 10 00:24:41.725802 setup-nvidia[2378]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038990rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build Jul 10 00:24:41.725802 setup-nvidia[2378]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039046rm+0x93: 'naked' return found in MITIGATION_RETHUNK build Jul 10 00:24:41.725802 setup-nvidia[2378]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039042rm+0x36: 'naked' return found in MITIGATION_RETHUNK build Jul 10 00:24:41.725802 setup-nvidia[2378]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039041rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build Jul 10 00:24:41.725802 setup-nvidia[2378]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039043rm+0x77: 'naked' return found in MITIGATION_RETHUNK build Jul 10 00:24:41.725802 setup-nvidia[2378]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038987rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build Jul 10 00:24:41.725802 setup-nvidia[2378]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030558rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build Jul 10 00:24:41.725802 setup-nvidia[2378]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030557rm+0x27: 'naked' return found in MITIGATION_RETHUNK build Jul 10 00:24:41.725802 setup-nvidia[2378]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030561rm+0x37: 'naked' return found in MITIGATION_RETHUNK build Jul 10 00:24:41.725802 setup-nvidia[2378]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030560rm+0x24: 'naked' return found in MITIGATION_RETHUNK build Jul 10 00:24:41.725802 setup-nvidia[2378]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030559rm+0xca: 'naked' return found in MITIGATION_RETHUNK build Jul 10 00:24:41.725802 setup-nvidia[2378]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030562rm+0x193: 'naked' return found in MITIGATION_RETHUNK build Jul 10 00:24:41.725802 setup-nvidia[2378]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030564rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build Jul 10 00:24:41.725802 setup-nvidia[2378]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv011860rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build Jul 10 00:24:41.725802 setup-nvidia[2378]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039354rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build Jul 10 00:24:41.725802 setup-nvidia[2378]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/Module.symvers Jul 10 00:24:41.725802 setup-nvidia[2378]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.mod.o Jul 10 00:24:41.725802 setup-nvidia[2378]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/.module-common.o Jul 10 00:24:41.725802 setup-nvidia[2378]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.mod.o Jul 10 00:24:41.725802 setup-nvidia[2378]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.mod.o Jul 10 00:24:41.725802 setup-nvidia[2378]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.mod.o Jul 10 00:24:41.725802 setup-nvidia[2378]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.mod.o Jul 10 00:24:41.725802 setup-nvidia[2378]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Jul 10 00:24:41.725802 setup-nvidia[2378]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Jul 10 00:24:41.725802 setup-nvidia[2378]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Jul 10 00:24:41.725802 setup-nvidia[2378]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Jul 10 00:24:41.725802 setup-nvidia[2378]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko due to unavailability of vmlinux Jul 10 00:24:41.725802 setup-nvidia[2378]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Jul 10 00:24:41.725802 setup-nvidia[2378]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Jul 10 00:24:41.725802 setup-nvidia[2378]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko due to unavailability of vmlinux Jul 10 00:24:41.725802 setup-nvidia[2378]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Jul 10 00:24:41.725802 setup-nvidia[2378]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Jul 10 00:24:41.725802 setup-nvidia[2378]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko due to unavailability of vmlinux Jul 10 00:24:41.725802 setup-nvidia[2378]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Jul 10 00:24:41.725802 setup-nvidia[2378]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko due to unavailability of vmlinux Jul 10 00:24:41.725802 setup-nvidia[2378]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Jul 10 00:24:41.725802 setup-nvidia[2378]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko due to unavailability of vmlinux Jul 10 00:24:41.725802 setup-nvidia[2378]: make[2]: Leaving directory '/usr/lib/modules/6.12.36-flatcar/build' Jul 10 00:24:41.725802 setup-nvidia[2378]: make[1]: Leaving directory '/usr/lib/modules/6.12.36-flatcar/source' Jul 10 00:24:41.725802 setup-nvidia[2378]: -> done. Jul 10 00:24:41.725802 setup-nvidia[2378]: -> Kernel module compilation complete. Jul 10 00:24:41.725802 setup-nvidia[2378]: -> Unable to determine if Secure Boot is enabled: No such file or directory Jul 10 00:24:41.727225 setup-nvidia[2378]: 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. Jul 10 00:24:41.727225 setup-nvidia[2378]: \u000d Jul 10 00:24:41.727225 setup-nvidia[2378]: 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. Jul 10 00:24:41.727225 setup-nvidia[2378]: -> Kernel module load error: Operation not permitted Jul 10 00:24:41.727225 setup-nvidia[2378]: -> Kernel messages: Jul 10 00:24:41.727225 setup-nvidia[2378]: 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. Jul 10 00:24:42.021021 sshd[12525]: Accepted publickey for core from 10.200.16.10 port 39294 ssh2: RSA SHA256:3h+tkKhDgHLcc6lb3dyCgy4hKUs2nEozCTgQH7eTVjg Jul 10 00:24:42.022538 sshd-session[12525]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 10 00:24:42.027396 systemd-logind[1887]: New session 17 of user core. Jul 10 00:24:42.035870 systemd[1]: Started session-17.scope - Session 17 of User core. Jul 10 00:24:42.466322 sshd[12624]: Connection closed by 10.200.16.10 port 39294 Jul 10 00:24:42.466746 sshd-session[12525]: pam_unix(sshd:session): session closed for user core Jul 10 00:24:42.470483 systemd[1]: sshd@14-10.200.8.11:22-10.200.16.10:39294.service: Deactivated successfully. Jul 10 00:24:42.472213 systemd[1]: session-17.scope: Deactivated successfully. Jul 10 00:24:42.472908 systemd-logind[1887]: Session 17 logged out. Waiting for processes to exit. Jul 10 00:24:42.473863 systemd-logind[1887]: Removed session 17. Jul 10 00:24:52.578475 systemd[1]: Started sshd@15-10.200.8.11:22-10.200.16.10:49812.service - OpenSSH per-connection server daemon (10.200.16.10:49812). Jul 10 00:24:53.209574 sshd[12631]: Accepted publickey for core from 10.200.16.10 port 49812 ssh2: RSA SHA256:3h+tkKhDgHLcc6lb3dyCgy4hKUs2nEozCTgQH7eTVjg Jul 10 00:24:53.211199 sshd-session[12631]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 10 00:24:53.216681 systemd-logind[1887]: New session 18 of user core. Jul 10 00:24:53.227868 systemd[1]: Started session-18.scope - Session 18 of User core. Jul 10 00:24:53.663471 sshd[12633]: Connection closed by 10.200.16.10 port 49812 Jul 10 00:24:53.663900 sshd-session[12631]: pam_unix(sshd:session): session closed for user core Jul 10 00:24:53.667879 systemd[1]: sshd@15-10.200.8.11:22-10.200.16.10:49812.service: Deactivated successfully. Jul 10 00:24:53.669623 systemd[1]: session-18.scope: Deactivated successfully. Jul 10 00:24:53.670371 systemd-logind[1887]: Session 18 logged out. Waiting for processes to exit. Jul 10 00:24:53.672547 systemd-logind[1887]: Removed session 18. Jul 10 00:24:53.775376 systemd[1]: Started sshd@16-10.200.8.11:22-10.200.16.10:49826.service - OpenSSH per-connection server daemon (10.200.16.10:49826). Jul 10 00:24:54.407042 sshd[12639]: Accepted publickey for core from 10.200.16.10 port 49826 ssh2: RSA SHA256:3h+tkKhDgHLcc6lb3dyCgy4hKUs2nEozCTgQH7eTVjg Jul 10 00:24:54.408528 sshd-session[12639]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 10 00:24:54.413836 systemd-logind[1887]: New session 19 of user core. Jul 10 00:24:54.433839 systemd[1]: Started session-19.scope - Session 19 of User core. Jul 10 00:24:54.853413 sshd[12641]: Connection closed by 10.200.16.10 port 49826 Jul 10 00:24:54.853874 sshd-session[12639]: pam_unix(sshd:session): session closed for user core Jul 10 00:24:54.857742 systemd[1]: sshd@16-10.200.8.11:22-10.200.16.10:49826.service: Deactivated successfully. Jul 10 00:24:54.859582 systemd[1]: session-19.scope: Deactivated successfully. Jul 10 00:24:54.860358 systemd-logind[1887]: Session 19 logged out. Waiting for processes to exit. Jul 10 00:24:54.861413 systemd-logind[1887]: Removed session 19. Jul 10 00:24:55.740749 kernel: EXT4-fs (loop6p9): unmounting filesystem 0b76e2d8-7a79-42f7-b981-d0b16ae017a3. Jul 10 00:24:55.740981 setup-nvidia[2378]: Container flatcardevelopercontainer-4344.1.1nightly-20250709-2100.bin exited successfully. Jul 10 00:24:55.743649 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4344.1.1nightly\x2d20250709\x2d2100.bin.mount: Deactivated successfully. Jul 10 00:24:55.810649 sudo[2366]: pam_unix(sudo:session): session closed for user root Jul 10 00:24:55.812796 systemd[1]: session-c2.scope: Deactivated successfully. Jul 10 00:24:55.813091 systemd[1]: session-c2.scope: Consumed 5min 33.847s CPU time, 893.4M memory peak. Jul 10 00:24:56.300058 setup-nvidia[1878]: /opt/nvidia / Jul 10 00:24:56.301062 setup-nvidia[1878]: / Jul 10 00:24:56.310731 kernel: IPMI message handler: version 39.2 Jul 10 00:24:56.314730 kernel: ipmi device interface Jul 10 00:24:56.327408 setup-nvidia[1878]: /opt/nvidia/current/usr/lib/modules/6.12.36-flatcar/video / Jul 10 00:24:56.396672 kernel: nvidia: loading out-of-tree module taints kernel. Jul 10 00:24:56.396875 kernel: nvidia: module license 'NVIDIA' taints kernel. Jul 10 00:24:56.396900 kernel: Disabling lock debugging due to kernel taint Jul 10 00:24:56.403838 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Jul 10 00:24:56.403877 kernel: nvidia: module license taints kernel. Jul 10 00:24:56.804750 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 Jul 10 00:24:57.254942 kernel: Jul 10 00:24:57.255122 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Jul 10 00:24:57.462749 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.230.02 Fri Dec 20 21:42:05 UTC 2024 Jul 10 00:24:57.484734 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.230.02 Fri Dec 20 21:52:34 UTC 2024 Jul 10 00:24:57.509757 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Jul 10 00:24:57.554742 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. Jul 10 00:24:57.554809 setup-nvidia[1878]: / Jul 10 00:24:57.571318 setup-nvidia[12680]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jul 10 00:24:59.346579 setup-nvidia[12697]: Thu Jul 10 00:24:59 2025 Jul 10 00:24:59.346579 setup-nvidia[12697]: +---------------------------------------------------------------------------------------+ Jul 10 00:24:59.346579 setup-nvidia[12697]: | NVIDIA-SMI 535.230.02 Driver Version: 535.230.02 CUDA Version: 12.2 | Jul 10 00:24:59.346579 setup-nvidia[12697]: |-----------------------------------------+----------------------+----------------------+ Jul 10 00:24:59.346579 setup-nvidia[12697]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Jul 10 00:24:59.346579 setup-nvidia[12697]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Jul 10 00:24:59.346579 setup-nvidia[12697]: | | | MIG M. | Jul 10 00:24:59.346579 setup-nvidia[12697]: |=========================================+======================+======================| Jul 10 00:24:59.350034 setup-nvidia[12697]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Jul 10 00:24:59.350034 setup-nvidia[12697]: | N/A 27C P0 37W / 250W | 0MiB / 16384MiB | 0% Default | Jul 10 00:24:59.350034 setup-nvidia[12697]: | | | N/A | Jul 10 00:24:59.350034 setup-nvidia[12697]: +-----------------------------------------+----------------------+----------------------+ Jul 10 00:24:59.350144 setup-nvidia[12697]: Jul 10 00:24:59.350144 setup-nvidia[12697]: +---------------------------------------------------------------------------------------+ Jul 10 00:24:59.350144 setup-nvidia[12697]: | Processes: | Jul 10 00:24:59.350144 setup-nvidia[12697]: | GPU GI CI PID Type Process name GPU Memory | Jul 10 00:24:59.350144 setup-nvidia[12697]: | ID ID Usage | Jul 10 00:24:59.350144 setup-nvidia[12697]: |=======================================================================================| Jul 10 00:24:59.350285 setup-nvidia[12697]: | No running processes found | Jul 10 00:24:59.350285 setup-nvidia[12697]: +---------------------------------------------------------------------------------------+ Jul 10 00:24:59.721882 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Jul 10 00:24:59.723905 systemd[1]: Reached target multi-user.target - Multi-User System. Jul 10 00:24:59.724179 systemd[1]: Startup finished in 1.199s (firmware) + 30.432s (loader) + 4.012s (kernel) + 12.808s (initrd) + 2min 48.125s (userspace) = 3min 36.578s. Jul 10 00:25:04.970540 systemd[1]: Started sshd@17-10.200.8.11:22-10.200.16.10:59318.service - OpenSSH per-connection server daemon (10.200.16.10:59318). Jul 10 00:25:05.602023 sshd[12704]: Accepted publickey for core from 10.200.16.10 port 59318 ssh2: RSA SHA256:3h+tkKhDgHLcc6lb3dyCgy4hKUs2nEozCTgQH7eTVjg Jul 10 00:25:05.603596 sshd-session[12704]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 10 00:25:05.609796 systemd-logind[1887]: New session 20 of user core. Jul 10 00:25:05.620842 systemd[1]: Started session-20.scope - Session 20 of User core. Jul 10 00:25:05.833810 systemd[1]: Stopping user@0.service - User Manager for UID 0... Jul 10 00:25:05.834671 systemd[2369]: Activating special unit exit.target... Jul 10 00:25:05.834790 systemd[2369]: Stopped target default.target - Main User Target. Jul 10 00:25:05.834835 systemd[2369]: Stopped target basic.target - Basic System. Jul 10 00:25:05.834867 systemd[2369]: Stopped target paths.target - Paths. Jul 10 00:25:05.834895 systemd[2369]: Stopped target sockets.target - Sockets. Jul 10 00:25:05.834924 systemd[2369]: Stopped target timers.target - Timers. Jul 10 00:25:05.835309 systemd[2369]: Closed dbus.socket - D-Bus User Message Bus Socket. Jul 10 00:25:05.835538 systemd[2369]: Removed slice app.slice - User Application Slice. Jul 10 00:25:05.835573 systemd[2369]: Reached target shutdown.target - Shutdown. Jul 10 00:25:05.835740 systemd[2369]: Finished systemd-exit.service - Exit the Session. Jul 10 00:25:05.835781 systemd[2369]: Reached target exit.target - Exit the Session. Jul 10 00:25:05.857336 (sd-pam)[2370]: pam_unix(systemd-user:session): session closed for user root Jul 10 00:25:05.858081 systemd[1]: user@0.service: Deactivated successfully. Jul 10 00:25:05.858429 systemd[1]: Stopped user@0.service - User Manager for UID 0. Jul 10 00:25:05.861660 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Jul 10 00:25:05.886265 systemd[1]: run-user-0.mount: Deactivated successfully. Jul 10 00:25:05.887226 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Jul 10 00:25:05.887415 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Jul 10 00:25:05.888991 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Jul 10 00:25:05.889084 systemd[1]: user-0.slice: Consumed 5min 34.118s CPU time, 902.4M memory peak. Jul 10 00:25:05.889479 systemd-logind[1887]: Removed session c3. Jul 10 00:25:06.048192 sshd[12706]: Connection closed by 10.200.16.10 port 59318 Jul 10 00:25:06.048621 sshd-session[12704]: pam_unix(sshd:session): session closed for user core Jul 10 00:25:06.052207 systemd[1]: sshd@17-10.200.8.11:22-10.200.16.10:59318.service: Deactivated successfully. Jul 10 00:25:06.054131 systemd[1]: session-20.scope: Deactivated successfully. Jul 10 00:25:06.056056 systemd-logind[1887]: Session 20 logged out. Waiting for processes to exit. Jul 10 00:25:06.057173 systemd-logind[1887]: Removed session 20. Jul 10 00:25:06.167417 systemd[1]: Started sshd@18-10.200.8.11:22-10.200.16.10:59332.service - OpenSSH per-connection server daemon (10.200.16.10:59332). Jul 10 00:25:06.795002 sshd[12716]: Accepted publickey for core from 10.200.16.10 port 59332 ssh2: RSA SHA256:3h+tkKhDgHLcc6lb3dyCgy4hKUs2nEozCTgQH7eTVjg Jul 10 00:25:06.796457 sshd-session[12716]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 10 00:25:06.801273 systemd-logind[1887]: New session 21 of user core. Jul 10 00:25:06.815847 systemd[1]: Started session-21.scope - Session 21 of User core. Jul 10 00:25:07.238134 sshd[12718]: Connection closed by 10.200.16.10 port 59332 Jul 10 00:25:07.238499 sshd-session[12716]: pam_unix(sshd:session): session closed for user core Jul 10 00:25:07.242553 systemd[1]: sshd@18-10.200.8.11:22-10.200.16.10:59332.service: Deactivated successfully. Jul 10 00:25:07.244417 systemd[1]: session-21.scope: Deactivated successfully. Jul 10 00:25:07.245143 systemd-logind[1887]: Session 21 logged out. Waiting for processes to exit. Jul 10 00:25:07.246128 systemd-logind[1887]: Removed session 21. Jul 10 00:25:07.354216 systemd[1]: Started sshd@19-10.200.8.11:22-10.200.16.10:59340.service - OpenSSH per-connection server daemon (10.200.16.10:59340). Jul 10 00:25:08.006734 sshd[12724]: Accepted publickey for core from 10.200.16.10 port 59340 ssh2: RSA SHA256:3h+tkKhDgHLcc6lb3dyCgy4hKUs2nEozCTgQH7eTVjg Jul 10 00:25:08.009415 sshd-session[12724]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 10 00:25:08.014809 systemd-logind[1887]: New session 22 of user core. Jul 10 00:25:08.025838 systemd[1]: Started session-22.scope - Session 22 of User core. Jul 10 00:25:08.454781 sshd[12726]: Connection closed by 10.200.16.10 port 59340 Jul 10 00:25:08.455154 sshd-session[12724]: pam_unix(sshd:session): session closed for user core Jul 10 00:25:08.458847 systemd[1]: sshd@19-10.200.8.11:22-10.200.16.10:59340.service: Deactivated successfully. Jul 10 00:25:08.460529 systemd[1]: session-22.scope: Deactivated successfully. Jul 10 00:25:08.461241 systemd-logind[1887]: Session 22 logged out. Waiting for processes to exit. Jul 10 00:25:08.462196 systemd-logind[1887]: Removed session 22. Jul 10 00:25:08.570150 systemd[1]: Started sshd@20-10.200.8.11:22-10.200.16.10:59350.service - OpenSSH per-connection server daemon (10.200.16.10:59350). Jul 10 00:25:09.221485 sshd[12732]: Accepted publickey for core from 10.200.16.10 port 59350 ssh2: RSA SHA256:3h+tkKhDgHLcc6lb3dyCgy4hKUs2nEozCTgQH7eTVjg Jul 10 00:25:09.223052 sshd-session[12732]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 10 00:25:09.227775 systemd-logind[1887]: New session 23 of user core. Jul 10 00:25:09.238838 systemd[1]: Started session-23.scope - Session 23 of User core. Jul 10 00:25:09.670848 sshd[12734]: Connection closed by 10.200.16.10 port 59350 Jul 10 00:25:09.671310 sshd-session[12732]: pam_unix(sshd:session): session closed for user core Jul 10 00:25:09.675361 systemd[1]: sshd@20-10.200.8.11:22-10.200.16.10:59350.service: Deactivated successfully. Jul 10 00:25:09.677297 systemd[1]: session-23.scope: Deactivated successfully. Jul 10 00:25:09.678260 systemd-logind[1887]: Session 23 logged out. Waiting for processes to exit. Jul 10 00:25:09.679428 systemd-logind[1887]: Removed session 23. Jul 10 00:25:09.786109 systemd[1]: Started sshd@21-10.200.8.11:22-10.200.16.10:34588.service - OpenSSH per-connection server daemon (10.200.16.10:34588). Jul 10 00:25:10.440504 sshd[12740]: Accepted publickey for core from 10.200.16.10 port 34588 ssh2: RSA SHA256:3h+tkKhDgHLcc6lb3dyCgy4hKUs2nEozCTgQH7eTVjg Jul 10 00:25:10.442046 sshd-session[12740]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 10 00:25:10.446661 systemd-logind[1887]: New session 24 of user core. Jul 10 00:25:10.454860 systemd[1]: Started session-24.scope - Session 24 of User core. Jul 10 00:25:12.387095 sshd[12742]: Connection closed by 10.200.16.10 port 34588 Jul 10 00:25:12.387564 sshd-session[12740]: pam_unix(sshd:session): session closed for user core Jul 10 00:25:12.391636 systemd[1]: sshd@21-10.200.8.11:22-10.200.16.10:34588.service: Deactivated successfully. Jul 10 00:25:12.393443 systemd[1]: session-24.scope: Deactivated successfully. Jul 10 00:25:12.393655 systemd[1]: session-24.scope: Consumed 1.441s CPU time, 14M memory peak. Jul 10 00:25:12.394177 systemd-logind[1887]: Session 24 logged out. Waiting for processes to exit. Jul 10 00:25:12.395151 systemd-logind[1887]: Removed session 24.