Oct 13 05:48:51.188285 kernel: Linux version 6.12.51-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.0 p8) 14.3.0, GNU ld (Gentoo 2.44 p4) 2.44.0) #1 SMP PREEMPT_DYNAMIC Sun Oct 12 22:37:12 -00 2025 Oct 13 05:48:51.188312 kernel: Command line: BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=a48d469b0deb49c328e6faf6cf366b11952d47f2d24963c866a0ea8221fb0039 Oct 13 05:48:51.188322 kernel: BIOS-provided physical RAM map: Oct 13 05:48:51.188328 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Oct 13 05:48:51.188335 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Oct 13 05:48:51.188342 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Oct 13 05:48:51.188350 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Oct 13 05:48:51.188359 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Oct 13 05:48:51.188366 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Oct 13 05:48:51.188373 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Oct 13 05:48:51.188380 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Oct 13 05:48:51.188386 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Oct 13 05:48:51.188393 kernel: printk: legacy bootconsole [earlyser0] enabled Oct 13 05:48:51.188400 kernel: NX (Execute Disable) protection: active Oct 13 05:48:51.188410 kernel: APIC: Static calls initialized Oct 13 05:48:51.188418 kernel: efi: EFI v2.7 by Microsoft Oct 13 05:48:51.188426 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Oct 13 05:48:51.188433 kernel: random: crng init done Oct 13 05:48:51.188441 kernel: secureboot: Secure boot disabled Oct 13 05:48:51.188449 kernel: SMBIOS 3.1.0 present. Oct 13 05:48:51.188456 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Oct 13 05:48:51.188464 kernel: DMI: Memory slots populated: 3/3 Oct 13 05:48:51.188471 kernel: Hypervisor detected: Microsoft Hyper-V Oct 13 05:48:51.188479 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Oct 13 05:48:51.188488 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Oct 13 05:48:51.188496 kernel: Hyper-V: Using hypercall for remote TLB flush Oct 13 05:48:51.188503 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Oct 13 05:48:51.188511 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Oct 13 05:48:51.188519 kernel: tsc: Marking TSC unstable due to running on Hyper-V Oct 13 05:48:51.188527 kernel: tsc: Detected 2593.993 MHz processor Oct 13 05:48:51.188546 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Oct 13 05:48:51.188554 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Oct 13 05:48:51.188562 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Oct 13 05:48:51.188573 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Oct 13 05:48:51.188580 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Oct 13 05:48:51.188588 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Oct 13 05:48:51.188595 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Oct 13 05:48:51.188603 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Oct 13 05:48:51.188610 kernel: Using GB pages for direct mapping Oct 13 05:48:51.188621 kernel: ACPI: Early table checksum verification disabled Oct 13 05:48:51.188632 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Oct 13 05:48:51.188643 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 05:48:51.188651 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 05:48:51.188659 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Oct 13 05:48:51.188667 kernel: ACPI: FACS 0x000000003FFFE000 000040 Oct 13 05:48:51.188675 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 05:48:51.188683 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 05:48:51.188694 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 05:48:51.188702 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 05:48:51.188710 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 05:48:51.188718 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 05:48:51.188726 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 05:48:51.188734 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Oct 13 05:48:51.188742 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Oct 13 05:48:51.188750 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Oct 13 05:48:51.188758 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Oct 13 05:48:51.188768 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Oct 13 05:48:51.188776 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Oct 13 05:48:51.188784 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Oct 13 05:48:51.188792 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Oct 13 05:48:51.188800 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Oct 13 05:48:51.188808 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Oct 13 05:48:51.188816 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Oct 13 05:48:51.188824 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Oct 13 05:48:51.188834 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Oct 13 05:48:51.188842 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Oct 13 05:48:51.188850 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Oct 13 05:48:51.188858 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Oct 13 05:48:51.188869 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Oct 13 05:48:51.188878 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Oct 13 05:48:51.188886 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] Oct 13 05:48:51.188894 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] Oct 13 05:48:51.188905 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] Oct 13 05:48:51.188915 kernel: Zone ranges: Oct 13 05:48:51.188923 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Oct 13 05:48:51.188936 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Oct 13 05:48:51.188946 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Oct 13 05:48:51.188959 kernel: Device empty Oct 13 05:48:51.188969 kernel: Movable zone start for each node Oct 13 05:48:51.188977 kernel: Early memory node ranges Oct 13 05:48:51.188985 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Oct 13 05:48:51.188993 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Oct 13 05:48:51.189003 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Oct 13 05:48:51.189011 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Oct 13 05:48:51.189019 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Oct 13 05:48:51.189027 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Oct 13 05:48:51.189035 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Oct 13 05:48:51.189043 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Oct 13 05:48:51.189051 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Oct 13 05:48:51.189059 kernel: ACPI: PM-Timer IO Port: 0x408 Oct 13 05:48:51.189067 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Oct 13 05:48:51.189078 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Oct 13 05:48:51.189086 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Oct 13 05:48:51.189094 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Oct 13 05:48:51.189102 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Oct 13 05:48:51.189110 kernel: CPU topo: Max. logical packages: 1 Oct 13 05:48:51.189118 kernel: CPU topo: Max. logical dies: 1 Oct 13 05:48:51.189126 kernel: CPU topo: Max. dies per package: 1 Oct 13 05:48:51.189134 kernel: CPU topo: Max. threads per core: 1 Oct 13 05:48:51.189149 kernel: CPU topo: Num. cores per package: 6 Oct 13 05:48:51.189170 kernel: CPU topo: Num. threads per package: 6 Oct 13 05:48:51.189179 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs Oct 13 05:48:51.189188 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Oct 13 05:48:51.189196 kernel: Booting paravirtualized kernel on Hyper-V Oct 13 05:48:51.189204 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Oct 13 05:48:51.189212 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Oct 13 05:48:51.189220 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 Oct 13 05:48:51.189228 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 Oct 13 05:48:51.189236 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Oct 13 05:48:51.189247 kernel: Hyper-V: PV spinlocks enabled Oct 13 05:48:51.189255 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Oct 13 05:48:51.189264 kernel: Kernel command line: rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=a48d469b0deb49c328e6faf6cf366b11952d47f2d24963c866a0ea8221fb0039 Oct 13 05:48:51.189273 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Oct 13 05:48:51.189281 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Oct 13 05:48:51.189289 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Oct 13 05:48:51.189297 kernel: Fallback order for Node 0: 0 Oct 13 05:48:51.189305 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 Oct 13 05:48:51.189316 kernel: Policy zone: Normal Oct 13 05:48:51.189331 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 13 05:48:51.189340 kernel: software IO TLB: area num 8. Oct 13 05:48:51.189351 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Oct 13 05:48:51.189359 kernel: Kernel/User page tables isolation: enabled Oct 13 05:48:51.189367 kernel: ftrace: allocating 40139 entries in 157 pages Oct 13 05:48:51.189376 kernel: ftrace: allocated 157 pages with 5 groups Oct 13 05:48:51.189384 kernel: Dynamic Preempt: voluntary Oct 13 05:48:51.189393 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 13 05:48:51.189402 kernel: rcu: RCU event tracing is enabled. Oct 13 05:48:51.189410 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Oct 13 05:48:51.189419 kernel: Trampoline variant of Tasks RCU enabled. Oct 13 05:48:51.189430 kernel: Rude variant of Tasks RCU enabled. Oct 13 05:48:51.189444 kernel: Tracing variant of Tasks RCU enabled. Oct 13 05:48:51.189453 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 13 05:48:51.189461 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Oct 13 05:48:51.189470 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Oct 13 05:48:51.189481 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Oct 13 05:48:51.189490 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Oct 13 05:48:51.189498 kernel: Using NULL legacy PIC Oct 13 05:48:51.189506 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Oct 13 05:48:51.189515 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Oct 13 05:48:51.189523 kernel: Console: colour dummy device 80x25 Oct 13 05:48:51.189541 kernel: printk: legacy console [tty1] enabled Oct 13 05:48:51.189551 kernel: printk: legacy console [ttyS0] enabled Oct 13 05:48:51.189559 kernel: printk: legacy bootconsole [earlyser0] disabled Oct 13 05:48:51.189571 kernel: ACPI: Core revision 20240827 Oct 13 05:48:51.189580 kernel: Failed to register legacy timer interrupt Oct 13 05:48:51.189588 kernel: APIC: Switch to symmetric I/O mode setup Oct 13 05:48:51.189596 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Oct 13 05:48:51.189605 kernel: Hyper-V: enabling crash_kexec_post_notifiers Oct 13 05:48:51.189613 kernel: Hyper-V: Using IPI hypercalls Oct 13 05:48:51.189622 kernel: APIC: send_IPI() replaced with hv_send_ipi() Oct 13 05:48:51.189631 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Oct 13 05:48:51.189640 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Oct 13 05:48:51.189651 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Oct 13 05:48:51.189659 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Oct 13 05:48:51.189668 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Oct 13 05:48:51.189676 kernel: Hyper-V: Using enlightened APIC (xapic mode) Oct 13 05:48:51.189684 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Oct 13 05:48:51.189693 kernel: APIC: read() replaced with hv_apic_read() Oct 13 05:48:51.189701 kernel: APIC: write() replaced with hv_apic_write() Oct 13 05:48:51.189710 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Oct 13 05:48:51.189718 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Oct 13 05:48:51.189729 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593993) Oct 13 05:48:51.189738 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Oct 13 05:48:51.189747 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Oct 13 05:48:51.189755 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Oct 13 05:48:51.189764 kernel: Spectre V2 : Mitigation: Retpolines Oct 13 05:48:51.189772 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Oct 13 05:48:51.189780 kernel: Speculative Store Bypass: Vulnerable Oct 13 05:48:51.189788 kernel: MDS: Mitigation: Clear CPU buffers Oct 13 05:48:51.189797 kernel: TAA: Mitigation: Clear CPU buffers Oct 13 05:48:51.189805 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Oct 13 05:48:51.189815 kernel: active return thunk: its_return_thunk Oct 13 05:48:51.189824 kernel: ITS: Mitigation: Aligned branch/return thunks Oct 13 05:48:51.189832 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Oct 13 05:48:51.189840 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Oct 13 05:48:51.189849 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Oct 13 05:48:51.189857 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Oct 13 05:48:51.189866 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Oct 13 05:48:51.189874 kernel: Freeing SMP alternatives memory: 32K Oct 13 05:48:51.189882 kernel: pid_max: default: 32768 minimum: 301 Oct 13 05:48:51.189890 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Oct 13 05:48:51.189899 kernel: landlock: Up and running. Oct 13 05:48:51.189909 kernel: SELinux: Initializing. Oct 13 05:48:51.189918 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Oct 13 05:48:51.189926 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Oct 13 05:48:51.189934 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Oct 13 05:48:51.189943 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Oct 13 05:48:51.189951 kernel: signal: max sigframe size: 1776 Oct 13 05:48:51.189960 kernel: rcu: Hierarchical SRCU implementation. Oct 13 05:48:51.189969 kernel: rcu: Max phase no-delay instances is 400. Oct 13 05:48:51.189977 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Oct 13 05:48:51.189986 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Oct 13 05:48:51.189997 kernel: smp: Bringing up secondary CPUs ... Oct 13 05:48:51.190005 kernel: smpboot: x86: Booting SMP configuration: Oct 13 05:48:51.190014 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Oct 13 05:48:51.190022 kernel: smp: Brought up 1 node, 6 CPUs Oct 13 05:48:51.190030 kernel: smpboot: Total of 6 processors activated (31127.91 BogoMIPS) Oct 13 05:48:51.190039 kernel: Memory: 115339088K/117439364K available (14336K kernel code, 2443K rwdata, 10000K rodata, 54096K init, 2852K bss, 2090000K reserved, 0K cma-reserved) Oct 13 05:48:51.190048 kernel: devtmpfs: initialized Oct 13 05:48:51.190056 kernel: x86/mm: Memory block size: 1024MB Oct 13 05:48:51.190065 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Oct 13 05:48:51.190076 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 13 05:48:51.190084 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Oct 13 05:48:51.190093 kernel: pinctrl core: initialized pinctrl subsystem Oct 13 05:48:51.190101 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Oct 13 05:48:51.190110 kernel: audit: initializing netlink subsys (disabled) Oct 13 05:48:51.190118 kernel: audit: type=2000 audit(1760334527.028:1): state=initialized audit_enabled=0 res=1 Oct 13 05:48:51.190127 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 13 05:48:51.190135 kernel: thermal_sys: Registered thermal governor 'user_space' Oct 13 05:48:51.190144 kernel: cpuidle: using governor menu Oct 13 05:48:51.190155 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 13 05:48:51.190163 kernel: dca service started, version 1.12.1 Oct 13 05:48:51.190171 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Oct 13 05:48:51.190180 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Oct 13 05:48:51.190188 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Oct 13 05:48:51.190197 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Oct 13 05:48:51.190205 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Oct 13 05:48:51.190220 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Oct 13 05:48:51.190230 kernel: ACPI: Added _OSI(Module Device) Oct 13 05:48:51.190241 kernel: ACPI: Added _OSI(Processor Device) Oct 13 05:48:51.190250 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 13 05:48:51.190258 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 13 05:48:51.190267 kernel: ACPI: Interpreter enabled Oct 13 05:48:51.190275 kernel: ACPI: PM: (supports S0 S5) Oct 13 05:48:51.190283 kernel: ACPI: Using IOAPIC for interrupt routing Oct 13 05:48:51.190292 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Oct 13 05:48:51.190300 kernel: PCI: Ignoring E820 reservations for host bridge windows Oct 13 05:48:51.190309 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Oct 13 05:48:51.190320 kernel: iommu: Default domain type: Translated Oct 13 05:48:51.190328 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Oct 13 05:48:51.190337 kernel: efivars: Registered efivars operations Oct 13 05:48:51.190345 kernel: PCI: Using ACPI for IRQ routing Oct 13 05:48:51.190354 kernel: PCI: System does not support PCI Oct 13 05:48:51.190362 kernel: vgaarb: loaded Oct 13 05:48:51.190371 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Oct 13 05:48:51.190379 kernel: VFS: Disk quotas dquot_6.6.0 Oct 13 05:48:51.190388 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 13 05:48:51.190398 kernel: pnp: PnP ACPI init Oct 13 05:48:51.190407 kernel: pnp: PnP ACPI: found 3 devices Oct 13 05:48:51.190415 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Oct 13 05:48:51.190424 kernel: NET: Registered PF_INET protocol family Oct 13 05:48:51.190432 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 13 05:48:51.190441 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Oct 13 05:48:51.190449 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Oct 13 05:48:51.190458 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 13 05:48:51.190466 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Oct 13 05:48:51.190477 kernel: TCP: Hash tables configured (established 524288 bind 65536) Oct 13 05:48:51.190485 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Oct 13 05:48:51.190494 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Oct 13 05:48:51.190502 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Oct 13 05:48:51.190511 kernel: NET: Registered PF_XDP protocol family Oct 13 05:48:51.190519 kernel: PCI: CLS 0 bytes, default 64 Oct 13 05:48:51.190528 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Oct 13 05:48:51.190585 kernel: software IO TLB: mapped [mem 0x000000003ae61000-0x000000003ee61000] (64MB) Oct 13 05:48:51.190594 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Oct 13 05:48:51.190606 kernel: Initialise system trusted keyrings Oct 13 05:48:51.190615 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Oct 13 05:48:51.190623 kernel: Key type asymmetric registered Oct 13 05:48:51.190631 kernel: Asymmetric key parser 'x509' registered Oct 13 05:48:51.190640 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Oct 13 05:48:51.190648 kernel: io scheduler mq-deadline registered Oct 13 05:48:51.190657 kernel: io scheduler kyber registered Oct 13 05:48:51.190665 kernel: io scheduler bfq registered Oct 13 05:48:51.190674 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Oct 13 05:48:51.190684 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 13 05:48:51.190693 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Oct 13 05:48:51.190701 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Oct 13 05:48:51.190710 kernel: i8042: PNP: No PS/2 controller found. Oct 13 05:48:51.190852 kernel: rtc_cmos 00:02: registered as rtc0 Oct 13 05:48:51.190956 kernel: rtc_cmos 00:02: setting system clock to 2025-10-13T05:48:50 UTC (1760334530) Oct 13 05:48:51.191051 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Oct 13 05:48:51.191068 kernel: intel_pstate: CPU model not supported Oct 13 05:48:51.191076 kernel: efifb: probing for efifb Oct 13 05:48:51.191085 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Oct 13 05:48:51.191094 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Oct 13 05:48:51.191102 kernel: efifb: scrolling: redraw Oct 13 05:48:51.191111 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Oct 13 05:48:51.191119 kernel: Console: switching to colour frame buffer device 128x48 Oct 13 05:48:51.191128 kernel: fb0: EFI VGA frame buffer device Oct 13 05:48:51.191136 kernel: pstore: Using crash dump compression: deflate Oct 13 05:48:51.191147 kernel: pstore: Registered efi_pstore as persistent store backend Oct 13 05:48:51.191155 kernel: NET: Registered PF_INET6 protocol family Oct 13 05:48:51.191164 kernel: Segment Routing with IPv6 Oct 13 05:48:51.191172 kernel: In-situ OAM (IOAM) with IPv6 Oct 13 05:48:51.191181 kernel: NET: Registered PF_PACKET protocol family Oct 13 05:48:51.191189 kernel: Key type dns_resolver registered Oct 13 05:48:51.191198 kernel: IPI shorthand broadcast: enabled Oct 13 05:48:51.191206 kernel: sched_clock: Marking stable (3637004800, 116614800)->(4096392200, -342772600) Oct 13 05:48:51.191215 kernel: registered taskstats version 1 Oct 13 05:48:51.191223 kernel: Loading compiled-in X.509 certificates Oct 13 05:48:51.191234 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.51-flatcar: d8dbf4abead15098249886d373d42a3af4f50ccd' Oct 13 05:48:51.191243 kernel: Demotion targets for Node 0: null Oct 13 05:48:51.191251 kernel: Key type .fscrypt registered Oct 13 05:48:51.191259 kernel: Key type fscrypt-provisioning registered Oct 13 05:48:51.191268 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 13 05:48:51.191277 kernel: ima: Allocated hash algorithm: sha1 Oct 13 05:48:51.191285 kernel: ima: No architecture policies found Oct 13 05:48:51.191293 kernel: clk: Disabling unused clocks Oct 13 05:48:51.191304 kernel: Warning: unable to open an initial console. Oct 13 05:48:51.191313 kernel: Freeing unused kernel image (initmem) memory: 54096K Oct 13 05:48:51.191322 kernel: Write protecting the kernel read-only data: 24576k Oct 13 05:48:51.191330 kernel: Freeing unused kernel image (rodata/data gap) memory: 240K Oct 13 05:48:51.191339 kernel: Run /init as init process Oct 13 05:48:51.191347 kernel: with arguments: Oct 13 05:48:51.191356 kernel: /init Oct 13 05:48:51.191364 kernel: with environment: Oct 13 05:48:51.191372 kernel: HOME=/ Oct 13 05:48:51.191383 kernel: TERM=linux Oct 13 05:48:51.191391 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 13 05:48:51.191401 systemd[1]: Successfully made /usr/ read-only. Oct 13 05:48:51.191412 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Oct 13 05:48:51.191422 systemd[1]: Detected virtualization microsoft. Oct 13 05:48:51.191430 systemd[1]: Detected architecture x86-64. Oct 13 05:48:51.191439 systemd[1]: Running in initrd. Oct 13 05:48:51.191448 systemd[1]: No hostname configured, using default hostname. Oct 13 05:48:51.191460 systemd[1]: Hostname set to . Oct 13 05:48:51.191468 systemd[1]: Initializing machine ID from random generator. Oct 13 05:48:51.191477 systemd[1]: Queued start job for default target initrd.target. Oct 13 05:48:51.191486 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 13 05:48:51.191495 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 13 05:48:51.191505 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Oct 13 05:48:51.191514 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 13 05:48:51.191523 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Oct 13 05:48:51.191547 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Oct 13 05:48:51.191558 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Oct 13 05:48:51.191567 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Oct 13 05:48:51.191576 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 13 05:48:51.191585 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 13 05:48:51.191594 systemd[1]: Reached target paths.target - Path Units. Oct 13 05:48:51.191606 systemd[1]: Reached target slices.target - Slice Units. Oct 13 05:48:51.191615 systemd[1]: Reached target swap.target - Swaps. Oct 13 05:48:51.191624 systemd[1]: Reached target timers.target - Timer Units. Oct 13 05:48:51.191633 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Oct 13 05:48:51.191642 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 13 05:48:51.191651 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Oct 13 05:48:51.191660 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Oct 13 05:48:51.191669 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 13 05:48:51.191678 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 13 05:48:51.191690 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 13 05:48:51.191699 systemd[1]: Reached target sockets.target - Socket Units. Oct 13 05:48:51.191708 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Oct 13 05:48:51.191717 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 13 05:48:51.191726 systemd[1]: Finished network-cleanup.service - Network Cleanup. Oct 13 05:48:51.191735 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). Oct 13 05:48:51.191744 systemd[1]: Starting systemd-fsck-usr.service... Oct 13 05:48:51.191753 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 13 05:48:51.191765 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 13 05:48:51.191774 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 13 05:48:51.191802 systemd-journald[232]: Collecting audit messages is disabled. Oct 13 05:48:51.191824 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Oct 13 05:48:51.191838 systemd-journald[232]: Journal started Oct 13 05:48:51.191861 systemd-journald[232]: Runtime Journal (/run/log/journal/2c1426c2172749b0b13fd400aca7783d) is 8M, max 2.2G, 2.1G free. Oct 13 05:48:51.189192 systemd-modules-load[234]: Inserted module 'overlay' Oct 13 05:48:51.200554 systemd[1]: Started systemd-journald.service - Journal Service. Oct 13 05:48:51.200724 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 13 05:48:51.201299 systemd[1]: Finished systemd-fsck-usr.service. Oct 13 05:48:51.203497 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 13 05:48:51.204711 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 13 05:48:51.249320 systemd-tmpfiles[244]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Oct 13 05:48:51.250836 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 13 05:48:51.257476 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 13 05:48:51.268138 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 13 05:48:51.278206 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 13 05:48:51.295353 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 13 05:48:51.317553 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Oct 13 05:48:51.320824 systemd-modules-load[234]: Inserted module 'br_netfilter' Oct 13 05:48:51.324274 kernel: Bridge firewalling registered Oct 13 05:48:51.331967 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 13 05:48:51.334266 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 13 05:48:51.353428 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 13 05:48:51.355140 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 13 05:48:51.357258 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 13 05:48:51.386644 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 13 05:48:51.388218 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Oct 13 05:48:51.406928 systemd-resolved[269]: Positive Trust Anchors: Oct 13 05:48:51.406967 systemd-resolved[269]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 13 05:48:51.406999 systemd-resolved[269]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Oct 13 05:48:51.409433 systemd-resolved[269]: Defaulting to hostname 'linux'. Oct 13 05:48:51.410406 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 13 05:48:51.420470 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 13 05:48:51.468475 dracut-cmdline[277]: Using kernel command line parameters: rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1 rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=a48d469b0deb49c328e6faf6cf366b11952d47f2d24963c866a0ea8221fb0039 Oct 13 05:48:51.551561 kernel: SCSI subsystem initialized Oct 13 05:48:51.561550 kernel: Loading iSCSI transport class v2.0-870. Oct 13 05:48:51.574556 kernel: iscsi: registered transport (tcp) Oct 13 05:48:51.596468 kernel: iscsi: registered transport (qla4xxx) Oct 13 05:48:51.596494 kernel: QLogic iSCSI HBA Driver Oct 13 05:48:51.614265 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 13 05:48:51.644873 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 13 05:48:51.658767 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 13 05:48:51.705950 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Oct 13 05:48:51.715795 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Oct 13 05:48:51.778560 kernel: raid6: avx2x4 gen() 31100 MB/s Oct 13 05:48:51.797556 kernel: raid6: avx2x2 gen() 30704 MB/s Oct 13 05:48:51.818726 kernel: raid6: avx2x1 gen() 27546 MB/s Oct 13 05:48:51.818757 kernel: raid6: using algorithm avx2x4 gen() 31100 MB/s Oct 13 05:48:51.841894 kernel: raid6: .... xor() 10467 MB/s, rmw enabled Oct 13 05:48:51.841920 kernel: raid6: using avx2x2 recovery algorithm Oct 13 05:48:51.864556 kernel: xor: automatically using best checksumming function avx Oct 13 05:48:52.033559 kernel: Btrfs loaded, zoned=no, fsverity=no Oct 13 05:48:52.039745 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Oct 13 05:48:52.042577 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 13 05:48:52.088922 systemd-udevd[486]: Using default interface naming scheme 'v255'. Oct 13 05:48:52.095421 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 13 05:48:52.097759 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Oct 13 05:48:52.144724 dracut-pre-trigger[494]: rd.md=0: removing MD RAID activation Oct 13 05:48:52.172155 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Oct 13 05:48:52.174887 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 13 05:48:52.251873 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 13 05:48:52.254508 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Oct 13 05:48:52.285563 kernel: cryptd: max_cpu_qlen set to 1000 Oct 13 05:48:52.295562 kernel: AES CTR mode by8 optimization enabled Oct 13 05:48:52.314552 kernel: hv_vmbus: Vmbus version:4.0 Oct 13 05:48:52.315657 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 13 05:48:52.327209 kernel: hv_vmbus: registering driver hyperv_keyboard Oct 13 05:48:52.315830 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 13 05:48:52.346912 kernel: pps_core: LinuxPPS API ver. 1 registered Oct 13 05:48:52.346933 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Oct 13 05:48:52.346945 kernel: input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/VMBUS:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input0 Oct 13 05:48:52.346958 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 13 05:48:52.334678 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Oct 13 05:48:52.359016 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 13 05:48:52.368825 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Oct 13 05:48:52.389575 kernel: hv_vmbus: registering driver hv_pci Oct 13 05:48:52.389599 kernel: hv_vmbus: registering driver hv_netvsc Oct 13 05:48:52.389611 kernel: PTP clock support registered Oct 13 05:48:52.389622 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Oct 13 05:48:52.389812 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Oct 13 05:48:52.389938 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Oct 13 05:48:52.401473 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Oct 13 05:48:52.401669 kernel: hv_vmbus: registering driver hv_storvsc Oct 13 05:48:52.401684 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Oct 13 05:48:52.406745 kernel: scsi host1: storvsc_host_t Oct 13 05:48:52.409149 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint Oct 13 05:48:52.411552 kernel: hv_vmbus: registering driver hid_hyperv Oct 13 05:48:52.411581 kernel: hv_utils: Registering HyperV Utility Driver Oct 13 05:48:52.411594 kernel: hv_vmbus: registering driver hv_utils Oct 13 05:48:52.412553 kernel: scsi host0: storvsc_host_t Oct 13 05:48:52.412587 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] Oct 13 05:48:52.413571 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] Oct 13 05:48:52.414626 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] Oct 13 05:48:52.414654 kernel: hv_utils: Heartbeat IC version 3.0 Oct 13 05:48:52.416591 kernel: pci 0001:00:00.0: Enabling HDA controller Oct 13 05:48:52.420960 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Oct 13 05:48:52.421006 kernel: hv_utils: Shutdown IC version 3.2 Oct 13 05:48:52.427546 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Oct 13 05:48:52.427591 kernel: hv_utils: TimeSync IC version 4.0 Oct 13 05:48:52.436175 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Oct 13 05:48:52.436219 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Oct 13 05:48:52.436233 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Oct 13 05:48:52.850708 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Oct 13 05:48:52.850619 systemd-resolved[269]: Clock change detected. Flushing caches. Oct 13 05:48:52.897038 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned Oct 13 05:48:52.897233 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned Oct 13 05:48:52.897371 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned Oct 13 05:48:52.910883 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Oct 13 05:48:52.911123 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Oct 13 05:48:52.911278 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Oct 13 05:48:52.911412 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks Oct 13 05:48:52.911541 kernel: sd 0:0:0:1: [sdb] Write Protect is off Oct 13 05:48:52.916069 kernel: sd 0:0:0:0: [sda] Write Protect is off Oct 13 05:48:52.916267 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Oct 13 05:48:52.916434 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Oct 13 05:48:52.918522 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Oct 13 05:48:52.918664 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Oct 13 05:48:52.926619 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#579 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 13 05:48:52.926787 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#581 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 13 05:48:52.936331 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#586 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 13 05:48:52.936531 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#453 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 13 05:48:52.956393 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 13 05:48:52.966629 kernel: sdb: sdb1 Oct 13 05:48:52.970871 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk Oct 13 05:48:52.979875 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Oct 13 05:48:52.982873 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Oct 13 05:48:52.983054 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Oct 13 05:48:52.987982 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Oct 13 05:48:52.989873 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Oct 13 05:48:53.615139 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Oct 13 05:48:53.631218 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Oct 13 05:48:53.666960 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Oct 13 05:48:53.698807 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Oct 13 05:48:53.700262 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Oct 13 05:48:53.720659 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Oct 13 05:48:53.762875 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#547 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 13 05:48:53.774871 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Oct 13 05:48:54.787875 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#486 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 13 05:48:54.802485 disk-uuid[724]: The operation has completed successfully. Oct 13 05:48:54.806203 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Oct 13 05:48:56.047223 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 13 05:48:56.047340 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Oct 13 05:48:56.056247 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Oct 13 05:48:56.066997 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Oct 13 05:48:56.075549 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 13 05:48:56.085482 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 13 05:48:56.090562 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Oct 13 05:48:56.096501 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Oct 13 05:48:56.125932 sh[890]: Success Oct 13 05:48:56.132299 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Oct 13 05:48:56.179683 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Oct 13 05:48:56.179718 kernel: device-mapper: uevent: version 1.0.3 Oct 13 05:48:56.184552 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Oct 13 05:48:56.196871 kernel: device-mapper: verity: sha256 using shash "sha256-avx2" Oct 13 05:48:56.539190 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Oct 13 05:48:56.542463 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Oct 13 05:48:56.558283 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Oct 13 05:48:56.574924 kernel: BTRFS: device fsid c8746500-26f5-4ec1-9da8-aef51ec7db92 devid 1 transid 41 /dev/mapper/usr (254:0) scanned by mount (915) Oct 13 05:48:56.583940 kernel: BTRFS info (device dm-0): first mount of filesystem c8746500-26f5-4ec1-9da8-aef51ec7db92 Oct 13 05:48:56.583965 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Oct 13 05:48:57.172877 kernel: BTRFS info (device dm-0): disabling log replay at mount time Oct 13 05:48:57.172957 kernel: BTRFS info (device dm-0): enabling free space tree Oct 13 05:48:57.248168 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Oct 13 05:48:57.252325 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Oct 13 05:48:57.260148 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Oct 13 05:48:57.260985 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Oct 13 05:48:57.300395 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Oct 13 05:48:57.336939 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (954) Oct 13 05:48:57.346254 kernel: BTRFS info (device sda6): first mount of filesystem 1cd10441-4b32-40b7-b370-b928e4bc90dd Oct 13 05:48:57.346287 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Oct 13 05:48:57.392755 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 13 05:48:57.414369 kernel: BTRFS info (device sda6): turning on async discard Oct 13 05:48:57.414391 kernel: BTRFS info (device sda6): enabling free space tree Oct 13 05:48:57.414404 kernel: BTRFS info (device sda6): last unmount of filesystem 1cd10441-4b32-40b7-b370-b928e4bc90dd Oct 13 05:48:57.398479 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 13 05:48:57.420985 systemd[1]: Finished ignition-setup.service - Ignition (setup). Oct 13 05:48:57.423393 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Oct 13 05:48:57.460464 systemd-networkd[1082]: lo: Link UP Oct 13 05:48:57.460474 systemd-networkd[1082]: lo: Gained carrier Oct 13 05:48:57.461480 systemd-networkd[1082]: Enumeration completed Oct 13 05:48:57.461566 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 13 05:48:57.461838 systemd-networkd[1082]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 13 05:48:57.461842 systemd-networkd[1082]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 13 05:48:57.462641 systemd-networkd[1082]: eth0: Link UP Oct 13 05:48:57.462803 systemd-networkd[1082]: eth0: Gained carrier Oct 13 05:48:57.462813 systemd-networkd[1082]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 13 05:48:57.467392 systemd[1]: Reached target network.target - Network. Oct 13 05:48:57.526891 systemd-networkd[1082]: eth0: DHCPv4 address 10.200.4.18/24, gateway 10.200.4.1 acquired from 168.63.129.16 Oct 13 05:48:59.054974 systemd-networkd[1082]: eth0: Gained IPv6LL Oct 13 05:48:59.264196 ignition[1085]: Ignition 2.22.0 Oct 13 05:48:59.264211 ignition[1085]: Stage: fetch-offline Oct 13 05:48:59.264381 ignition[1085]: no configs at "/usr/lib/ignition/base.d" Oct 13 05:48:59.264393 ignition[1085]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 13 05:48:59.264490 ignition[1085]: parsed url from cmdline: "" Oct 13 05:48:59.264495 ignition[1085]: no config URL provided Oct 13 05:48:59.264501 ignition[1085]: reading system config file "/usr/lib/ignition/user.ign" Oct 13 05:48:59.264512 ignition[1085]: no config at "/usr/lib/ignition/user.ign" Oct 13 05:48:59.264518 ignition[1085]: failed to fetch config: resource requires networking Oct 13 05:48:59.269708 ignition[1085]: Ignition finished successfully Oct 13 05:48:59.296129 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Oct 13 05:48:59.298564 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Oct 13 05:48:59.351969 ignition[1098]: Ignition 2.22.0 Oct 13 05:48:59.351984 ignition[1098]: Stage: fetch Oct 13 05:48:59.352207 ignition[1098]: no configs at "/usr/lib/ignition/base.d" Oct 13 05:48:59.352220 ignition[1098]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 13 05:48:59.352306 ignition[1098]: parsed url from cmdline: "" Oct 13 05:48:59.352311 ignition[1098]: no config URL provided Oct 13 05:48:59.352321 ignition[1098]: reading system config file "/usr/lib/ignition/user.ign" Oct 13 05:48:59.352331 ignition[1098]: no config at "/usr/lib/ignition/user.ign" Oct 13 05:48:59.352351 ignition[1098]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Oct 13 05:48:59.462223 ignition[1098]: GET result: OK Oct 13 05:48:59.462281 ignition[1098]: config has been read from IMDS userdata Oct 13 05:48:59.462291 ignition[1098]: parsing config with SHA512: 8cfd9f8d303f1d5e6d8c807acd6d772979e373bf6e86da07dac90a86250999f9b232e67b64595a93f255991ceed5cd80a8f5de49944424a0d90100debf845079 Oct 13 05:48:59.465891 unknown[1098]: fetched base config from "system" Oct 13 05:48:59.466106 ignition[1098]: fetch: fetch complete Oct 13 05:48:59.465900 unknown[1098]: fetched base config from "system" Oct 13 05:48:59.466111 ignition[1098]: fetch: fetch passed Oct 13 05:48:59.465905 unknown[1098]: fetched user config from "azure" Oct 13 05:48:59.466153 ignition[1098]: Ignition finished successfully Oct 13 05:48:59.487647 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Oct 13 05:48:59.490537 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Oct 13 05:48:59.535782 ignition[1108]: Ignition 2.22.0 Oct 13 05:48:59.535797 ignition[1108]: Stage: kargs Oct 13 05:48:59.536079 ignition[1108]: no configs at "/usr/lib/ignition/base.d" Oct 13 05:48:59.536092 ignition[1108]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 13 05:48:59.545179 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Oct 13 05:48:59.536695 ignition[1108]: kargs: kargs passed Oct 13 05:48:59.547889 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Oct 13 05:48:59.536741 ignition[1108]: Ignition finished successfully Oct 13 05:48:59.591960 ignition[1118]: Ignition 2.22.0 Oct 13 05:48:59.591974 ignition[1118]: Stage: disks Oct 13 05:48:59.592278 ignition[1118]: no configs at "/usr/lib/ignition/base.d" Oct 13 05:48:59.592290 ignition[1118]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 13 05:48:59.592959 ignition[1118]: disks: disks passed Oct 13 05:48:59.593005 ignition[1118]: Ignition finished successfully Oct 13 05:48:59.610921 systemd[1]: Finished ignition-disks.service - Ignition (disks). Oct 13 05:48:59.612299 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Oct 13 05:48:59.623679 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Oct 13 05:48:59.632743 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 13 05:48:59.641594 systemd[1]: Reached target sysinit.target - System Initialization. Oct 13 05:48:59.649969 systemd[1]: Reached target basic.target - Basic System. Oct 13 05:48:59.652463 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Oct 13 05:48:59.748462 systemd-fsck[1130]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks Oct 13 05:48:59.753722 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Oct 13 05:48:59.764329 systemd[1]: Mounting sysroot.mount - /sysroot... Oct 13 05:49:01.618888 kernel: EXT4-fs (sda9): mounted filesystem 8b520359-9763-45f3-b7f7-db1e9fbc640d r/w with ordered data mode. Quota mode: none. Oct 13 05:49:01.619977 systemd[1]: Mounted sysroot.mount - /sysroot. Oct 13 05:49:01.627014 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Oct 13 05:49:01.659383 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 13 05:49:01.685366 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Oct 13 05:49:01.687960 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Oct 13 05:49:01.692798 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Oct 13 05:49:01.720506 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1144) Oct 13 05:49:01.720531 kernel: BTRFS info (device sda6): first mount of filesystem 1cd10441-4b32-40b7-b370-b928e4bc90dd Oct 13 05:49:01.720544 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Oct 13 05:49:01.692831 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Oct 13 05:49:01.720250 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Oct 13 05:49:01.732111 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Oct 13 05:49:01.756069 kernel: BTRFS info (device sda6): turning on async discard Oct 13 05:49:01.756100 kernel: BTRFS info (device sda6): enabling free space tree Oct 13 05:49:01.757759 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 13 05:49:02.588937 coreos-metadata[1146]: Oct 13 05:49:02.588 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Oct 13 05:49:02.595016 coreos-metadata[1146]: Oct 13 05:49:02.594 INFO Fetch successful Oct 13 05:49:02.595016 coreos-metadata[1146]: Oct 13 05:49:02.594 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Oct 13 05:49:02.607121 coreos-metadata[1146]: Oct 13 05:49:02.598 INFO Fetch successful Oct 13 05:49:02.607121 coreos-metadata[1146]: Oct 13 05:49:02.599 INFO wrote hostname ci-4459.1.0-a-41e5bbf56a to /sysroot/etc/hostname Oct 13 05:49:02.618490 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Oct 13 05:49:02.977030 initrd-setup-root[1174]: cut: /sysroot/etc/passwd: No such file or directory Oct 13 05:49:03.030425 initrd-setup-root[1181]: cut: /sysroot/etc/group: No such file or directory Oct 13 05:49:03.086273 initrd-setup-root[1188]: cut: /sysroot/etc/shadow: No such file or directory Oct 13 05:49:03.101318 initrd-setup-root[1195]: cut: /sysroot/etc/gshadow: No such file or directory Oct 13 05:49:04.560844 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Oct 13 05:49:04.563683 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Oct 13 05:49:04.585322 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Oct 13 05:49:04.596941 systemd[1]: sysroot-oem.mount: Deactivated successfully. Oct 13 05:49:04.605772 kernel: BTRFS info (device sda6): last unmount of filesystem 1cd10441-4b32-40b7-b370-b928e4bc90dd Oct 13 05:49:04.632943 ignition[1262]: INFO : Ignition 2.22.0 Oct 13 05:49:04.632943 ignition[1262]: INFO : Stage: mount Oct 13 05:49:04.646245 ignition[1262]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 13 05:49:04.646245 ignition[1262]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 13 05:49:04.646245 ignition[1262]: INFO : mount: mount passed Oct 13 05:49:04.646245 ignition[1262]: INFO : Ignition finished successfully Oct 13 05:49:04.632940 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Oct 13 05:49:04.637443 systemd[1]: Finished ignition-mount.service - Ignition (mount). Oct 13 05:49:04.642199 systemd[1]: Starting ignition-files.service - Ignition (files)... Oct 13 05:49:04.678515 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 13 05:49:04.699868 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1276) Oct 13 05:49:04.704871 kernel: BTRFS info (device sda6): first mount of filesystem 1cd10441-4b32-40b7-b370-b928e4bc90dd Oct 13 05:49:04.704899 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Oct 13 05:49:04.718910 kernel: BTRFS info (device sda6): turning on async discard Oct 13 05:49:04.718936 kernel: BTRFS info (device sda6): enabling free space tree Oct 13 05:49:04.721118 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 13 05:49:04.759777 ignition[1293]: INFO : Ignition 2.22.0 Oct 13 05:49:04.759777 ignition[1293]: INFO : Stage: files Oct 13 05:49:04.765546 ignition[1293]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 13 05:49:04.765546 ignition[1293]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 13 05:49:04.765546 ignition[1293]: DEBUG : files: compiled without relabeling support, skipping Oct 13 05:49:04.779079 ignition[1293]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Oct 13 05:49:04.779079 ignition[1293]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Oct 13 05:49:04.925741 ignition[1293]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Oct 13 05:49:04.931407 ignition[1293]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Oct 13 05:49:04.936529 ignition[1293]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Oct 13 05:49:04.931955 unknown[1293]: wrote ssh authorized keys file for user: core Oct 13 05:49:04.984531 ignition[1293]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Oct 13 05:49:04.991762 ignition[1293]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Oct 13 05:49:04.991762 ignition[1293]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Oct 13 05:49:04.991762 ignition[1293]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Oct 13 05:49:04.991762 ignition[1293]: INFO : files: files passed Oct 13 05:49:04.991762 ignition[1293]: INFO : Ignition finished successfully Oct 13 05:49:05.000378 systemd[1]: Finished ignition-files.service - Ignition (files). Oct 13 05:49:05.008202 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Oct 13 05:49:05.036359 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Oct 13 05:49:05.047772 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 13 05:49:05.047887 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Oct 13 05:49:05.083065 initrd-setup-root-after-ignition[1327]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 13 05:49:05.083065 initrd-setup-root-after-ignition[1327]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Oct 13 05:49:05.095786 initrd-setup-root-after-ignition[1331]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 13 05:49:05.096717 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 13 05:49:05.098269 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Oct 13 05:49:05.113836 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Oct 13 05:49:05.163460 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 13 05:49:05.163566 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Oct 13 05:49:05.172370 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Oct 13 05:49:05.181238 systemd[1]: Reached target initrd.target - Initrd Default Target. Oct 13 05:49:05.189070 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Oct 13 05:49:05.189892 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Oct 13 05:49:05.213121 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 13 05:49:05.215830 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Oct 13 05:49:05.247772 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Oct 13 05:49:05.249580 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 13 05:49:05.258263 systemd[1]: Stopped target timers.target - Timer Units. Oct 13 05:49:05.269631 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 13 05:49:05.269749 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 13 05:49:05.281329 systemd[1]: Stopped target initrd.target - Initrd Default Target. Oct 13 05:49:05.289937 systemd[1]: Stopped target basic.target - Basic System. Oct 13 05:49:05.292125 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Oct 13 05:49:05.302257 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Oct 13 05:49:05.310834 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Oct 13 05:49:05.319800 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Oct 13 05:49:05.328430 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Oct 13 05:49:05.336956 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Oct 13 05:49:05.344938 systemd[1]: Stopped target sysinit.target - System Initialization. Oct 13 05:49:05.354743 systemd[1]: Stopped target local-fs.target - Local File Systems. Oct 13 05:49:05.369437 systemd[1]: Stopped target swap.target - Swaps. Oct 13 05:49:05.373248 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 13 05:49:05.373369 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Oct 13 05:49:05.384247 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Oct 13 05:49:05.392824 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 13 05:49:05.401846 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Oct 13 05:49:05.401959 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 13 05:49:05.411635 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 13 05:49:05.411748 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Oct 13 05:49:05.424990 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Oct 13 05:49:05.425114 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 13 05:49:05.433675 systemd[1]: ignition-files.service: Deactivated successfully. Oct 13 05:49:05.433788 systemd[1]: Stopped ignition-files.service - Ignition (files). Oct 13 05:49:05.441711 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Oct 13 05:49:05.441821 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Oct 13 05:49:05.451994 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Oct 13 05:49:05.459793 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 13 05:49:05.459932 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Oct 13 05:49:05.482093 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Oct 13 05:49:05.508056 ignition[1351]: INFO : Ignition 2.22.0 Oct 13 05:49:05.508056 ignition[1351]: INFO : Stage: umount Oct 13 05:49:05.508056 ignition[1351]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 13 05:49:05.508056 ignition[1351]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 13 05:49:05.508056 ignition[1351]: INFO : umount: umount passed Oct 13 05:49:05.508056 ignition[1351]: INFO : Ignition finished successfully Oct 13 05:49:05.489169 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 13 05:49:05.489307 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Oct 13 05:49:05.497973 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 13 05:49:05.498132 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Oct 13 05:49:05.511767 systemd[1]: ignition-mount.service: Deactivated successfully. Oct 13 05:49:05.511878 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Oct 13 05:49:05.516569 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 13 05:49:05.516672 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Oct 13 05:49:05.521477 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 13 05:49:05.521547 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Oct 13 05:49:05.529777 systemd[1]: ignition-kargs.service: Deactivated successfully. Oct 13 05:49:05.529827 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Oct 13 05:49:05.537726 systemd[1]: ignition-fetch.service: Deactivated successfully. Oct 13 05:49:05.537771 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Oct 13 05:49:05.539896 systemd[1]: Stopped target network.target - Network. Oct 13 05:49:05.551866 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Oct 13 05:49:05.552023 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Oct 13 05:49:05.563119 systemd[1]: Stopped target paths.target - Path Units. Oct 13 05:49:05.571042 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 13 05:49:05.576457 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 13 05:49:05.580650 systemd[1]: Stopped target slices.target - Slice Units. Oct 13 05:49:05.589134 systemd[1]: Stopped target sockets.target - Socket Units. Oct 13 05:49:05.595744 systemd[1]: iscsid.socket: Deactivated successfully. Oct 13 05:49:05.595794 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Oct 13 05:49:05.606302 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 13 05:49:05.606345 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 13 05:49:05.614884 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 13 05:49:05.614938 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Oct 13 05:49:05.622394 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Oct 13 05:49:05.622437 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Oct 13 05:49:05.623916 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Oct 13 05:49:05.624538 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Oct 13 05:49:05.626284 systemd[1]: sysroot-boot.mount: Deactivated successfully. Oct 13 05:49:05.626875 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 13 05:49:05.626977 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Oct 13 05:49:05.627831 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 13 05:49:05.627967 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Oct 13 05:49:05.646364 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 13 05:49:05.646481 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Oct 13 05:49:05.658978 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Oct 13 05:49:05.659236 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 13 05:49:05.659341 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Oct 13 05:49:05.670208 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Oct 13 05:49:05.670761 systemd[1]: Stopped target network-pre.target - Preparation for Network. Oct 13 05:49:05.674105 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 13 05:49:05.674169 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Oct 13 05:49:05.690139 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Oct 13 05:49:05.696959 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Oct 13 05:49:05.697014 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 13 05:49:05.698447 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 13 05:49:05.698489 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Oct 13 05:49:05.723083 systemd[1]: systemd-modules-load.service: Deactivated successfully. Oct 13 05:49:05.723133 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Oct 13 05:49:05.730670 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 13 05:49:05.730723 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 13 05:49:05.742441 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 13 05:49:05.751720 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Oct 13 05:49:05.751783 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Oct 13 05:49:05.772610 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 13 05:49:05.772773 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Oct 13 05:49:05.787120 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 13 05:49:05.787329 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 13 05:49:05.802592 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 13 05:49:05.802637 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Oct 13 05:49:05.810832 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 13 05:49:05.810889 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Oct 13 05:49:05.813756 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 13 05:49:05.813814 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Oct 13 05:49:05.832180 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 13 05:49:05.832237 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Oct 13 05:49:05.848617 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 13 05:49:05.848673 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 13 05:49:05.862141 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Oct 13 05:49:05.869864 systemd[1]: systemd-network-generator.service: Deactivated successfully. Oct 13 05:49:05.869925 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Oct 13 05:49:05.882492 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 13 05:49:05.882546 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 13 05:49:05.893406 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 13 05:49:05.893462 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 13 05:49:05.909952 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Oct 13 05:49:05.910015 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Oct 13 05:49:05.910066 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Oct 13 05:49:05.910414 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 13 05:49:05.910515 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Oct 13 05:49:05.914936 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Oct 13 05:49:05.924328 systemd[1]: Starting initrd-switch-root.service - Switch Root... Oct 13 05:49:05.965494 systemd[1]: Switching root. Oct 13 05:49:06.150940 systemd-journald[232]: Journal stopped Oct 13 05:49:15.114108 systemd-journald[232]: Received SIGTERM from PID 1 (systemd). Oct 13 05:49:15.114141 kernel: SELinux: policy capability network_peer_controls=1 Oct 13 05:49:15.114154 kernel: SELinux: policy capability open_perms=1 Oct 13 05:49:15.114163 kernel: SELinux: policy capability extended_socket_class=1 Oct 13 05:49:15.114172 kernel: SELinux: policy capability always_check_network=0 Oct 13 05:49:15.114181 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 13 05:49:15.114195 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 13 05:49:15.114216 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 13 05:49:15.114228 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Oct 13 05:49:15.114238 kernel: SELinux: policy capability userspace_initial_context=0 Oct 13 05:49:15.114247 kernel: audit: type=1403 audit(1760334547.323:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 13 05:49:15.114258 systemd[1]: Successfully loaded SELinux policy in 276.571ms. Oct 13 05:49:15.114270 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 7.533ms. Oct 13 05:49:15.114281 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Oct 13 05:49:15.114296 systemd[1]: Detected virtualization microsoft. Oct 13 05:49:15.114306 systemd[1]: Detected architecture x86-64. Oct 13 05:49:15.114316 systemd[1]: Detected first boot. Oct 13 05:49:15.114327 systemd[1]: Hostname set to . Oct 13 05:49:15.114338 systemd[1]: Initializing machine ID from random generator. Oct 13 05:49:15.114351 zram_generator::config[1397]: No configuration found. Oct 13 05:49:15.114362 kernel: Guest personality initialized and is inactive Oct 13 05:49:15.114371 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Oct 13 05:49:15.114381 kernel: Initialized host personality Oct 13 05:49:15.114390 kernel: NET: Registered PF_VSOCK protocol family Oct 13 05:49:15.114401 systemd[1]: Populated /etc with preset unit settings. Oct 13 05:49:15.114412 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Oct 13 05:49:15.114425 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 13 05:49:15.114439 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Oct 13 05:49:15.114450 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 13 05:49:15.114460 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Oct 13 05:49:15.114472 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Oct 13 05:49:15.114483 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Oct 13 05:49:15.114493 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Oct 13 05:49:15.114507 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Oct 13 05:49:15.114518 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Oct 13 05:49:15.114528 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Oct 13 05:49:15.114539 systemd[1]: Created slice user.slice - User and Session Slice. Oct 13 05:49:15.114549 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 13 05:49:15.114560 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 13 05:49:15.114571 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Oct 13 05:49:15.114586 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Oct 13 05:49:15.114597 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Oct 13 05:49:15.114611 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 13 05:49:15.114622 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Oct 13 05:49:15.114633 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 13 05:49:15.114644 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 13 05:49:15.114655 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Oct 13 05:49:15.114666 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Oct 13 05:49:15.114680 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Oct 13 05:49:15.114694 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Oct 13 05:49:15.114705 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 13 05:49:15.114716 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 13 05:49:15.114727 systemd[1]: Reached target slices.target - Slice Units. Oct 13 05:49:15.114738 systemd[1]: Reached target swap.target - Swaps. Oct 13 05:49:15.114749 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Oct 13 05:49:15.114760 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Oct 13 05:49:15.114773 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Oct 13 05:49:15.114785 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 13 05:49:15.114796 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 13 05:49:15.114807 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 13 05:49:15.114818 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Oct 13 05:49:15.114829 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Oct 13 05:49:15.114843 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Oct 13 05:49:15.114864 systemd[1]: Mounting media.mount - External Media Directory... Oct 13 05:49:15.114877 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 13 05:49:15.114888 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Oct 13 05:49:15.114899 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Oct 13 05:49:15.114910 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Oct 13 05:49:15.114921 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Oct 13 05:49:15.114936 systemd[1]: Reached target machines.target - Containers. Oct 13 05:49:15.114951 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Oct 13 05:49:15.114962 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 13 05:49:15.114974 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 13 05:49:15.114985 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Oct 13 05:49:15.114996 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 13 05:49:15.115007 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 13 05:49:15.115018 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 13 05:49:15.115029 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Oct 13 05:49:15.115043 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 13 05:49:15.115055 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Oct 13 05:49:15.115066 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 13 05:49:15.115077 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Oct 13 05:49:15.115088 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 13 05:49:15.115099 systemd[1]: Stopped systemd-fsck-usr.service. Oct 13 05:49:15.115111 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Oct 13 05:49:15.115122 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 13 05:49:15.115133 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 13 05:49:15.115146 kernel: fuse: init (API version 7.41) Oct 13 05:49:15.115157 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 13 05:49:15.115168 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Oct 13 05:49:15.115183 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Oct 13 05:49:15.115194 kernel: loop: module loaded Oct 13 05:49:15.115204 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 13 05:49:15.115215 systemd[1]: verity-setup.service: Deactivated successfully. Oct 13 05:49:15.115227 systemd[1]: Stopped verity-setup.service. Oct 13 05:49:15.115241 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 13 05:49:15.115252 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Oct 13 05:49:15.115263 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Oct 13 05:49:15.115300 systemd-journald[1504]: Collecting audit messages is disabled. Oct 13 05:49:15.115328 systemd[1]: Mounted media.mount - External Media Directory. Oct 13 05:49:15.115340 systemd-journald[1504]: Journal started Oct 13 05:49:15.115363 systemd-journald[1504]: Runtime Journal (/run/log/journal/5c87d80da37b4cc58aac15b7ca623044) is 8M, max 2.2G, 2.1G free. Oct 13 05:49:14.076932 systemd[1]: Queued start job for default target multi-user.target. Oct 13 05:49:14.101204 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Oct 13 05:49:14.101698 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 13 05:49:15.123450 systemd[1]: Started systemd-journald.service - Journal Service. Oct 13 05:49:15.128562 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Oct 13 05:49:15.133121 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Oct 13 05:49:15.137750 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Oct 13 05:49:15.141939 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Oct 13 05:49:15.147052 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 13 05:49:15.152341 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 13 05:49:15.152530 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Oct 13 05:49:15.157696 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 13 05:49:15.157901 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 13 05:49:15.162831 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 13 05:49:15.163036 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 13 05:49:15.168555 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 13 05:49:15.168749 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Oct 13 05:49:15.173371 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 13 05:49:15.173555 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 13 05:49:15.178279 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 13 05:49:15.183168 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 13 05:49:15.188557 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Oct 13 05:49:15.201234 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 13 05:49:15.207131 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Oct 13 05:49:15.224088 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Oct 13 05:49:15.228657 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Oct 13 05:49:15.228709 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 13 05:49:15.234220 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Oct 13 05:49:15.240661 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Oct 13 05:49:15.293231 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 13 05:49:15.294406 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Oct 13 05:49:15.312142 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Oct 13 05:49:15.317105 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 13 05:49:15.318099 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Oct 13 05:49:15.322723 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 13 05:49:15.323670 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 13 05:49:15.328575 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Oct 13 05:49:15.335008 systemd[1]: Starting systemd-sysusers.service - Create System Users... Oct 13 05:49:15.345616 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Oct 13 05:49:15.351114 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 13 05:49:15.356125 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Oct 13 05:49:15.361088 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Oct 13 05:49:15.370873 kernel: ACPI: bus type drm_connector registered Oct 13 05:49:15.371873 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 13 05:49:15.372082 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 13 05:49:15.381124 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Oct 13 05:49:15.386066 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Oct 13 05:49:15.388123 systemd-journald[1504]: Time spent on flushing to /var/log/journal/5c87d80da37b4cc58aac15b7ca623044 is 14.011ms for 952 entries. Oct 13 05:49:15.388123 systemd-journald[1504]: System Journal (/var/log/journal/5c87d80da37b4cc58aac15b7ca623044) is 8M, max 2.6G, 2.6G free. Oct 13 05:49:15.429803 systemd-journald[1504]: Received client request to flush runtime journal. Oct 13 05:49:15.397608 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Oct 13 05:49:15.431111 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Oct 13 05:49:15.501753 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 13 05:49:15.502520 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Oct 13 05:49:15.524877 kernel: loop0: detected capacity change from 0 to 110984 Oct 13 05:49:15.533782 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 13 05:49:16.117553 systemd[1]: Finished systemd-sysusers.service - Create System Users. Oct 13 05:49:16.125241 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 13 05:49:16.275171 systemd-tmpfiles[1554]: ACLs are not supported, ignoring. Oct 13 05:49:16.275192 systemd-tmpfiles[1554]: ACLs are not supported, ignoring. Oct 13 05:49:16.279349 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 13 05:49:16.507889 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Oct 13 05:49:16.515048 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Oct 13 05:49:16.521955 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 13 05:49:16.569134 systemd-udevd[1560]: Using default interface naming scheme 'v255'. Oct 13 05:49:16.579877 kernel: loop1: detected capacity change from 0 to 27936 Oct 13 05:49:17.195157 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 13 05:49:17.203502 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 13 05:49:17.224547 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Oct 13 05:49:17.323360 kernel: hv_vmbus: registering driver hyperv_fb Oct 13 05:49:17.323449 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Oct 13 05:49:17.326872 kernel: loop2: detected capacity change from 0 to 128016 Oct 13 05:49:17.326932 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Oct 13 05:49:17.337220 kernel: Console: switching to colour dummy device 80x25 Oct 13 05:49:17.338877 kernel: Console: switching to colour frame buffer device 128x48 Oct 13 05:49:17.363873 kernel: mousedev: PS/2 mouse device common for all mice Oct 13 05:49:17.363931 kernel: hv_vmbus: registering driver hv_balloon Oct 13 05:49:17.370342 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Oct 13 05:49:17.370869 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Oct 13 05:49:17.425486 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 13 05:49:17.440360 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 13 05:49:17.440553 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 13 05:49:17.446545 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 13 05:49:17.454813 systemd[1]: Started systemd-userdbd.service - User Database Manager. Oct 13 05:49:17.467904 kernel: EDAC MC: Ver: 3.0.0 Oct 13 05:49:17.699250 systemd-networkd[1585]: lo: Link UP Oct 13 05:49:17.699591 systemd-networkd[1585]: lo: Gained carrier Oct 13 05:49:17.701001 systemd-networkd[1585]: Enumeration completed Oct 13 05:49:17.701382 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 13 05:49:17.705954 systemd-networkd[1585]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 13 05:49:17.705966 systemd-networkd[1585]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 13 05:49:17.705973 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Oct 13 05:49:17.708519 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Oct 13 05:49:17.715812 systemd-networkd[1585]: eth0: Link UP Oct 13 05:49:17.715981 systemd-networkd[1585]: eth0: Gained carrier Oct 13 05:49:17.716060 systemd-networkd[1585]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 13 05:49:17.747940 systemd-networkd[1585]: eth0: DHCPv4 address 10.200.4.18/24, gateway 10.200.4.1 acquired from 168.63.129.16 Oct 13 05:49:17.751798 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Oct 13 05:49:17.754414 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Oct 13 05:49:17.772026 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Oct 13 05:49:17.845870 kernel: loop3: detected capacity change from 0 to 110984 Oct 13 05:49:17.858060 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Oct 13 05:49:17.864870 kernel: loop4: detected capacity change from 0 to 27936 Oct 13 05:49:17.878872 kernel: loop5: detected capacity change from 0 to 128016 Oct 13 05:49:17.891267 (sd-merge)[1714]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Oct 13 05:49:17.891732 (sd-merge)[1714]: Merged extensions into '/usr'. Oct 13 05:49:17.894955 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Oct 13 05:49:17.900380 systemd[1]: Starting ensure-sysext.service... Oct 13 05:49:17.902725 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 13 05:49:17.947716 systemd[1]: Reload requested from client PID 1717 ('systemctl') (unit ensure-sysext.service)... Oct 13 05:49:17.947733 systemd[1]: Reloading... Oct 13 05:49:17.986571 systemd-tmpfiles[1718]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Oct 13 05:49:17.986613 systemd-tmpfiles[1718]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Oct 13 05:49:17.986928 systemd-tmpfiles[1718]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Oct 13 05:49:17.987225 systemd-tmpfiles[1718]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Oct 13 05:49:17.988108 systemd-tmpfiles[1718]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Oct 13 05:49:17.988480 systemd-tmpfiles[1718]: ACLs are not supported, ignoring. Oct 13 05:49:17.988552 systemd-tmpfiles[1718]: ACLs are not supported, ignoring. Oct 13 05:49:17.996883 zram_generator::config[1749]: No configuration found. Oct 13 05:49:18.044833 systemd-tmpfiles[1718]: Detected autofs mount point /boot during canonicalization of boot. Oct 13 05:49:18.044860 systemd-tmpfiles[1718]: Skipping /boot Oct 13 05:49:18.053342 systemd-tmpfiles[1718]: Detected autofs mount point /boot during canonicalization of boot. Oct 13 05:49:18.053356 systemd-tmpfiles[1718]: Skipping /boot Oct 13 05:49:18.259719 systemd[1]: Reloading finished in 311 ms. Oct 13 05:49:18.313327 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 13 05:49:18.326357 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 13 05:49:18.327557 systemd[1]: Starting audit-rules.service - Load Audit Rules... Oct 13 05:49:18.364063 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Oct 13 05:49:18.365664 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 13 05:49:18.366785 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 13 05:49:18.367921 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 13 05:49:18.369442 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 13 05:49:18.370099 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 13 05:49:18.370206 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Oct 13 05:49:18.371489 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Oct 13 05:49:18.373903 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 13 05:49:18.375461 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Oct 13 05:49:18.375911 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 13 05:49:18.377599 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 13 05:49:18.377778 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 13 05:49:18.379363 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 13 05:49:18.379537 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 13 05:49:18.380729 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 13 05:49:18.380992 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 13 05:49:18.383965 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 13 05:49:18.384159 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 13 05:49:18.385241 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 13 05:49:18.386444 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 13 05:49:18.387999 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 13 05:49:18.388456 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 13 05:49:18.388622 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Oct 13 05:49:18.388776 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 13 05:49:18.392537 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 13 05:49:18.392712 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 13 05:49:18.393328 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 13 05:49:18.393493 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 13 05:49:18.394096 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 13 05:49:18.394259 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 13 05:49:18.397681 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 13 05:49:18.397991 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 13 05:49:18.399126 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 13 05:49:18.399268 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 13 05:49:18.399375 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Oct 13 05:49:18.399484 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 13 05:49:18.399595 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 13 05:49:18.399734 systemd[1]: Reached target time-set.target - System Time Set. Oct 13 05:49:18.400003 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 13 05:49:18.400981 systemd[1]: Finished ensure-sysext.service. Oct 13 05:49:18.403040 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Oct 13 05:49:18.405940 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 13 05:49:18.406152 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 13 05:49:18.592378 systemd-resolved[1816]: Positive Trust Anchors: Oct 13 05:49:18.592397 systemd-resolved[1816]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 13 05:49:18.592430 systemd-resolved[1816]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Oct 13 05:49:18.679507 systemd-resolved[1816]: Using system hostname 'ci-4459.1.0-a-41e5bbf56a'. Oct 13 05:49:18.681227 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 13 05:49:18.682985 systemd[1]: Reached target network.target - Network. Oct 13 05:49:18.683602 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 13 05:49:18.767323 augenrules[1853]: No rules Oct 13 05:49:18.768487 systemd[1]: audit-rules.service: Deactivated successfully. Oct 13 05:49:18.768735 systemd[1]: Finished audit-rules.service - Load Audit Rules. Oct 13 05:49:18.800380 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Oct 13 05:49:18.880153 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 13 05:49:19.598979 systemd-networkd[1585]: eth0: Gained IPv6LL Oct 13 05:49:19.601400 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Oct 13 05:49:19.606810 systemd[1]: Reached target network-online.target - Network is Online. Oct 13 05:49:21.512155 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Oct 13 05:49:21.517987 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Oct 13 05:49:27.244619 ldconfig[1531]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 13 05:49:27.254601 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Oct 13 05:49:27.262889 systemd[1]: Starting systemd-update-done.service - Update is Completed... Oct 13 05:49:27.312163 systemd[1]: Finished systemd-update-done.service - Update is Completed. Oct 13 05:49:27.318980 systemd[1]: Reached target sysinit.target - System Initialization. Oct 13 05:49:27.323798 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Oct 13 05:49:27.329245 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 13 05:49:27.334572 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Oct 13 05:49:27.339538 systemd[1]: Started logrotate.timer - Daily rotation of log files. Oct 13 05:49:27.344112 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Oct 13 05:49:27.349531 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Oct 13 05:49:27.354935 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Oct 13 05:49:27.354980 systemd[1]: Reached target paths.target - Path Units. Oct 13 05:49:27.358796 systemd[1]: Reached target timers.target - Timer Units. Oct 13 05:49:27.402105 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Oct 13 05:49:27.407931 systemd[1]: Starting docker.socket - Docker Socket for the API... Oct 13 05:49:27.413739 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Oct 13 05:49:27.419000 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Oct 13 05:49:27.424071 systemd[1]: Reached target ssh-access.target - SSH Access Available. Oct 13 05:49:27.430263 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Oct 13 05:49:27.434800 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Oct 13 05:49:27.440275 systemd[1]: Listening on docker.socket - Docker Socket for the API. Oct 13 05:49:27.445607 systemd[1]: Reached target sockets.target - Socket Units. Oct 13 05:49:27.449565 systemd[1]: Reached target basic.target - Basic System. Oct 13 05:49:27.453419 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Oct 13 05:49:27.453450 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Oct 13 05:49:27.468262 systemd[1]: Starting chronyd.service - NTP client/server... Oct 13 05:49:27.472579 systemd[1]: Starting containerd.service - containerd container runtime... Oct 13 05:49:27.496631 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Oct 13 05:49:27.501493 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Oct 13 05:49:27.506153 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Oct 13 05:49:27.511603 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Oct 13 05:49:27.516693 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Oct 13 05:49:27.520755 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Oct 13 05:49:27.522978 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Oct 13 05:49:27.527136 systemd[1]: hv_fcopy_uio_daemon.service - Hyper-V FCOPY UIO daemon was skipped because of an unmet condition check (ConditionPathExists=/sys/bus/vmbus/devices/eb765408-105f-49b6-b4aa-c123b64d17d4/uio). Oct 13 05:49:27.528094 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Oct 13 05:49:27.532370 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Oct 13 05:49:27.533371 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Oct 13 05:49:27.534446 jq[1878]: false Oct 13 05:49:27.539005 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Oct 13 05:49:27.543577 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Oct 13 05:49:27.549459 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Oct 13 05:49:27.556631 systemd[1]: Starting systemd-logind.service - User Login Management... Oct 13 05:49:27.561558 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Oct 13 05:49:27.562013 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 13 05:49:27.563979 systemd[1]: Starting update-engine.service - Update Engine... Oct 13 05:49:27.568727 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Oct 13 05:49:27.573561 extend-filesystems[1879]: Found /dev/sda6 Oct 13 05:49:27.575660 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Oct 13 05:49:27.582719 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Oct 13 05:49:27.583036 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Oct 13 05:49:27.583454 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 13 05:49:27.583714 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Oct 13 05:49:27.584681 jq[1894]: true Oct 13 05:49:27.592234 extend-filesystems[1879]: Found /dev/sda9 Oct 13 05:49:27.593531 systemd[1]: motdgen.service: Deactivated successfully. Oct 13 05:49:27.593778 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Oct 13 05:49:27.597300 extend-filesystems[1879]: Checking size of /dev/sda9 Oct 13 05:49:27.596201 oslogin_cache_refresh[1880]: Refreshing passwd entry cache Oct 13 05:49:27.603623 google_oslogin_nss_cache[1880]: oslogin_cache_refresh[1880]: Refreshing passwd entry cache Oct 13 05:49:27.603790 jq[1902]: true Oct 13 05:49:27.601456 (ntainerd)[1903]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Oct 13 05:49:27.604589 google_oslogin_nss_cache[1880]: oslogin_cache_refresh[1880]: Failure getting users, quitting Oct 13 05:49:27.604582 oslogin_cache_refresh[1880]: Failure getting users, quitting Oct 13 05:49:27.604781 google_oslogin_nss_cache[1880]: oslogin_cache_refresh[1880]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Oct 13 05:49:27.604781 google_oslogin_nss_cache[1880]: oslogin_cache_refresh[1880]: Refreshing group entry cache Oct 13 05:49:27.604598 oslogin_cache_refresh[1880]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Oct 13 05:49:27.604646 oslogin_cache_refresh[1880]: Refreshing group entry cache Oct 13 05:49:27.612988 KVP[1884]: KVP starting; pid is:1884 Oct 13 05:49:27.614094 chronyd[1870]: chronyd version 4.7 starting (+CMDMON +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +NTS +SECHASH +IPV6 -DEBUG) Oct 13 05:49:27.614151 oslogin_cache_refresh[1880]: Failure getting groups, quitting Oct 13 05:49:27.614390 google_oslogin_nss_cache[1880]: oslogin_cache_refresh[1880]: Failure getting groups, quitting Oct 13 05:49:27.614390 google_oslogin_nss_cache[1880]: oslogin_cache_refresh[1880]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Oct 13 05:49:27.614162 oslogin_cache_refresh[1880]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Oct 13 05:49:27.615664 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Oct 13 05:49:27.616011 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Oct 13 05:49:27.616107 KVP[1884]: KVP LIC Version: 3.1 Oct 13 05:49:27.617873 kernel: hv_utils: KVP IC version 4.0 Oct 13 05:49:27.626753 update_engine[1893]: I20251013 05:49:27.626681 1893 main.cc:92] Flatcar Update Engine starting Oct 13 05:49:27.652937 chronyd[1870]: Timezone right/UTC failed leap second check, ignoring Oct 13 05:49:27.653104 chronyd[1870]: Loaded seccomp filter (level 2) Oct 13 05:49:27.653224 systemd[1]: Started chronyd.service - NTP client/server. Oct 13 05:49:27.667500 extend-filesystems[1879]: Old size kept for /dev/sda9 Oct 13 05:49:27.668489 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 13 05:49:27.668729 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Oct 13 05:49:27.716700 bash[1929]: Updated "/home/core/.ssh/authorized_keys" Oct 13 05:49:27.717279 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Oct 13 05:49:27.723770 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Oct 13 05:49:27.736992 systemd-logind[1889]: New seat seat0. Oct 13 05:49:27.745478 systemd-logind[1889]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Oct 13 05:49:27.749641 systemd[1]: Started systemd-logind.service - User Login Management. Oct 13 05:49:27.877128 sshd_keygen[1930]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Oct 13 05:49:27.899093 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Oct 13 05:49:27.904634 setup-nvidia[1886]: Downloading Flatcar Container Linux Developer Container for version: 4459.1.0 Oct 13 05:49:27.904878 systemd[1]: Starting issuegen.service - Generate /run/issue... Oct 13 05:49:27.923055 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Oct 13 05:49:27.928956 systemd[1]: issuegen.service: Deactivated successfully. Oct 13 05:49:27.929172 systemd[1]: Finished issuegen.service - Generate /run/issue. Oct 13 05:49:27.935555 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Oct 13 05:49:27.946317 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Oct 13 05:49:27.960691 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Oct 13 05:49:27.967554 systemd[1]: Started getty@tty1.service - Getty on tty1. Oct 13 05:49:27.972128 dbus-daemon[1873]: [system] SELinux support is enabled Oct 13 05:49:27.972515 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Oct 13 05:49:27.974928 update_engine[1893]: I20251013 05:49:27.974874 1893 update_check_scheduler.cc:74] Next update check in 9m18s Oct 13 05:49:27.977268 systemd[1]: Reached target getty.target - Login Prompts. Oct 13 05:49:27.981375 systemd[1]: Started dbus.service - D-Bus System Message Bus. Oct 13 05:49:27.988469 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Oct 13 05:49:27.988501 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Oct 13 05:49:27.989642 dbus-daemon[1873]: [system] Successfully activated service 'org.freedesktop.systemd1' Oct 13 05:49:27.993885 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Oct 13 05:49:27.993912 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Oct 13 05:49:27.999234 systemd[1]: Started update-engine.service - Update Engine. Oct 13 05:49:28.004945 systemd[1]: Started locksmithd.service - Cluster reboot manager. Oct 13 05:49:28.074002 coreos-metadata[1872]: Oct 13 05:49:28.073 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Oct 13 05:49:28.076511 coreos-metadata[1872]: Oct 13 05:49:28.076 INFO Fetch successful Oct 13 05:49:28.076688 coreos-metadata[1872]: Oct 13 05:49:28.076 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Oct 13 05:49:28.080782 coreos-metadata[1872]: Oct 13 05:49:28.080 INFO Fetch successful Oct 13 05:49:28.081083 coreos-metadata[1872]: Oct 13 05:49:28.081 INFO Fetching http://168.63.129.16/machine/df0dbc79-0959-46eb-9067-5579f81cbcb7/4c883170%2D0d2d%2D4434%2Da001%2Da54290cff30e.%5Fci%2D4459.1.0%2Da%2D41e5bbf56a?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Oct 13 05:49:28.082791 coreos-metadata[1872]: Oct 13 05:49:28.082 INFO Fetch successful Oct 13 05:49:28.082791 coreos-metadata[1872]: Oct 13 05:49:28.082 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Oct 13 05:49:28.089606 coreos-metadata[1872]: Oct 13 05:49:28.089 INFO Fetch successful Oct 13 05:49:28.137283 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Oct 13 05:49:28.142270 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Oct 13 05:49:28.391176 locksmithd[2033]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 13 05:49:29.147226 containerd[1903]: time="2025-10-13T05:49:29Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Oct 13 05:49:29.147959 containerd[1903]: time="2025-10-13T05:49:29.147926300Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Oct 13 05:49:29.156775 containerd[1903]: time="2025-10-13T05:49:29.156715700Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="7.6µs" Oct 13 05:49:29.156775 containerd[1903]: time="2025-10-13T05:49:29.156766900Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Oct 13 05:49:29.156874 containerd[1903]: time="2025-10-13T05:49:29.156785800Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Oct 13 05:49:29.156962 containerd[1903]: time="2025-10-13T05:49:29.156939000Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Oct 13 05:49:29.156993 containerd[1903]: time="2025-10-13T05:49:29.156962100Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Oct 13 05:49:29.156993 containerd[1903]: time="2025-10-13T05:49:29.156985900Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Oct 13 05:49:29.157066 containerd[1903]: time="2025-10-13T05:49:29.157042100Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Oct 13 05:49:29.157066 containerd[1903]: time="2025-10-13T05:49:29.157061300Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Oct 13 05:49:29.157301 containerd[1903]: time="2025-10-13T05:49:29.157274100Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Oct 13 05:49:29.157301 containerd[1903]: time="2025-10-13T05:49:29.157295800Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Oct 13 05:49:29.157361 containerd[1903]: time="2025-10-13T05:49:29.157307700Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Oct 13 05:49:29.157361 containerd[1903]: time="2025-10-13T05:49:29.157316000Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Oct 13 05:49:29.157432 containerd[1903]: time="2025-10-13T05:49:29.157407900Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Oct 13 05:49:29.157655 containerd[1903]: time="2025-10-13T05:49:29.157632900Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Oct 13 05:49:29.157683 containerd[1903]: time="2025-10-13T05:49:29.157668200Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Oct 13 05:49:29.157683 containerd[1903]: time="2025-10-13T05:49:29.157679000Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Oct 13 05:49:29.157731 containerd[1903]: time="2025-10-13T05:49:29.157719500Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Oct 13 05:49:29.158148 containerd[1903]: time="2025-10-13T05:49:29.158101000Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Oct 13 05:49:29.158221 containerd[1903]: time="2025-10-13T05:49:29.158199100Z" level=info msg="metadata content store policy set" policy=shared Oct 13 05:49:29.175905 containerd[1903]: time="2025-10-13T05:49:29.175845100Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Oct 13 05:49:29.175966 containerd[1903]: time="2025-10-13T05:49:29.175910500Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Oct 13 05:49:29.175966 containerd[1903]: time="2025-10-13T05:49:29.175928800Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Oct 13 05:49:29.175966 containerd[1903]: time="2025-10-13T05:49:29.175941200Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Oct 13 05:49:29.175966 containerd[1903]: time="2025-10-13T05:49:29.175952800Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Oct 13 05:49:29.175966 containerd[1903]: time="2025-10-13T05:49:29.175963500Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Oct 13 05:49:29.176076 containerd[1903]: time="2025-10-13T05:49:29.175975900Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Oct 13 05:49:29.176076 containerd[1903]: time="2025-10-13T05:49:29.175987500Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Oct 13 05:49:29.176076 containerd[1903]: time="2025-10-13T05:49:29.175998100Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Oct 13 05:49:29.176076 containerd[1903]: time="2025-10-13T05:49:29.176022500Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Oct 13 05:49:29.176076 containerd[1903]: time="2025-10-13T05:49:29.176049100Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Oct 13 05:49:29.176076 containerd[1903]: time="2025-10-13T05:49:29.176062800Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Oct 13 05:49:29.176195 containerd[1903]: time="2025-10-13T05:49:29.176173600Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Oct 13 05:49:29.176217 containerd[1903]: time="2025-10-13T05:49:29.176195100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Oct 13 05:49:29.176217 containerd[1903]: time="2025-10-13T05:49:29.176209000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Oct 13 05:49:29.176264 containerd[1903]: time="2025-10-13T05:49:29.176219300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Oct 13 05:49:29.176264 containerd[1903]: time="2025-10-13T05:49:29.176229400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Oct 13 05:49:29.176264 containerd[1903]: time="2025-10-13T05:49:29.176239100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Oct 13 05:49:29.176264 containerd[1903]: time="2025-10-13T05:49:29.176249700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Oct 13 05:49:29.176264 containerd[1903]: time="2025-10-13T05:49:29.176259200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Oct 13 05:49:29.176371 containerd[1903]: time="2025-10-13T05:49:29.176271200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Oct 13 05:49:29.176371 containerd[1903]: time="2025-10-13T05:49:29.176282400Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Oct 13 05:49:29.176371 containerd[1903]: time="2025-10-13T05:49:29.176306700Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Oct 13 05:49:29.176432 containerd[1903]: time="2025-10-13T05:49:29.176370700Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Oct 13 05:49:29.176432 containerd[1903]: time="2025-10-13T05:49:29.176384400Z" level=info msg="Start snapshots syncer" Oct 13 05:49:29.176432 containerd[1903]: time="2025-10-13T05:49:29.176408200Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Oct 13 05:49:29.176690 containerd[1903]: time="2025-10-13T05:49:29.176649300Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Oct 13 05:49:29.176810 containerd[1903]: time="2025-10-13T05:49:29.176698600Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Oct 13 05:49:29.176810 containerd[1903]: time="2025-10-13T05:49:29.176765000Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Oct 13 05:49:29.176917 containerd[1903]: time="2025-10-13T05:49:29.176893600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Oct 13 05:49:29.176956 containerd[1903]: time="2025-10-13T05:49:29.176922700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Oct 13 05:49:29.176956 containerd[1903]: time="2025-10-13T05:49:29.176936200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Oct 13 05:49:29.176956 containerd[1903]: time="2025-10-13T05:49:29.176946300Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Oct 13 05:49:29.177019 containerd[1903]: time="2025-10-13T05:49:29.176957900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Oct 13 05:49:29.177019 containerd[1903]: time="2025-10-13T05:49:29.176974300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Oct 13 05:49:29.177019 containerd[1903]: time="2025-10-13T05:49:29.176985300Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Oct 13 05:49:29.177019 containerd[1903]: time="2025-10-13T05:49:29.177005800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Oct 13 05:49:29.177019 containerd[1903]: time="2025-10-13T05:49:29.177016100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Oct 13 05:49:29.177128 containerd[1903]: time="2025-10-13T05:49:29.177027700Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Oct 13 05:49:29.177128 containerd[1903]: time="2025-10-13T05:49:29.177055100Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Oct 13 05:49:29.177128 containerd[1903]: time="2025-10-13T05:49:29.177069100Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Oct 13 05:49:29.177128 containerd[1903]: time="2025-10-13T05:49:29.177077400Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Oct 13 05:49:29.177128 containerd[1903]: time="2025-10-13T05:49:29.177086800Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Oct 13 05:49:29.177128 containerd[1903]: time="2025-10-13T05:49:29.177095500Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Oct 13 05:49:29.177128 containerd[1903]: time="2025-10-13T05:49:29.177104200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Oct 13 05:49:29.177128 containerd[1903]: time="2025-10-13T05:49:29.177113800Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Oct 13 05:49:29.177288 containerd[1903]: time="2025-10-13T05:49:29.177130400Z" level=info msg="runtime interface created" Oct 13 05:49:29.177288 containerd[1903]: time="2025-10-13T05:49:29.177136000Z" level=info msg="created NRI interface" Oct 13 05:49:29.177288 containerd[1903]: time="2025-10-13T05:49:29.177144300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Oct 13 05:49:29.177288 containerd[1903]: time="2025-10-13T05:49:29.177155000Z" level=info msg="Connect containerd service" Oct 13 05:49:29.177288 containerd[1903]: time="2025-10-13T05:49:29.177179600Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Oct 13 05:49:29.177900 containerd[1903]: time="2025-10-13T05:49:29.177874700Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Oct 13 05:49:29.264640 setup-nvidia[2058]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 13 05:49:29.264964 setup-nvidia[2058]: Dload Upload Total Spent Left Speed Oct 13 05:49:29.762022 containerd[1903]: time="2025-10-13T05:49:29.761979300Z" level=info msg="Start subscribing containerd event" Oct 13 05:49:29.762135 containerd[1903]: time="2025-10-13T05:49:29.762039800Z" level=info msg="Start recovering state" Oct 13 05:49:29.762196 containerd[1903]: time="2025-10-13T05:49:29.762173500Z" level=info msg="Start event monitor" Oct 13 05:49:29.762236 containerd[1903]: time="2025-10-13T05:49:29.762200500Z" level=info msg="Start cni network conf syncer for default" Oct 13 05:49:29.762236 containerd[1903]: time="2025-10-13T05:49:29.762211800Z" level=info msg="Start streaming server" Oct 13 05:49:29.762236 containerd[1903]: time="2025-10-13T05:49:29.762221800Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Oct 13 05:49:29.762236 containerd[1903]: time="2025-10-13T05:49:29.762229000Z" level=info msg="runtime interface starting up..." Oct 13 05:49:29.762236 containerd[1903]: time="2025-10-13T05:49:29.762236600Z" level=info msg="starting plugins..." Oct 13 05:49:29.762336 containerd[1903]: time="2025-10-13T05:49:29.762174300Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Oct 13 05:49:29.762336 containerd[1903]: time="2025-10-13T05:49:29.762252000Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Oct 13 05:49:29.762385 containerd[1903]: time="2025-10-13T05:49:29.762334900Z" level=info msg=serving... address=/run/containerd/containerd.sock Oct 13 05:49:29.762519 containerd[1903]: time="2025-10-13T05:49:29.762443300Z" level=info msg="containerd successfully booted in 0.615686s" Oct 13 05:49:29.762549 systemd[1]: Started containerd.service - containerd container runtime. Oct 13 05:49:31.026385 waagent[2027]: 2025-10-13T05:49:31.026300Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Oct 13 05:49:31.030650 waagent[2027]: 2025-10-13T05:49:31.030605Z INFO Daemon Daemon OS: flatcar 4459.1.0 Oct 13 05:49:31.034172 waagent[2027]: 2025-10-13T05:49:31.034122Z INFO Daemon Daemon Python: 3.11.13 Oct 13 05:49:31.037526 waagent[2027]: 2025-10-13T05:49:31.037476Z INFO Daemon Daemon Run daemon Oct 13 05:49:31.040503 waagent[2027]: 2025-10-13T05:49:31.040455Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4459.1.0' Oct 13 05:49:31.047066 waagent[2027]: 2025-10-13T05:49:31.047015Z INFO Daemon Daemon Using waagent for provisioning Oct 13 05:49:31.051060 waagent[2027]: 2025-10-13T05:49:31.051013Z INFO Daemon Daemon Activate resource disk Oct 13 05:49:31.054578 waagent[2027]: 2025-10-13T05:49:31.054531Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Oct 13 05:49:31.065165 waagent[2027]: 2025-10-13T05:49:31.065113Z INFO Daemon Daemon Found device: sdb Oct 13 05:49:31.074797 waagent[2027]: 2025-10-13T05:49:31.074744Z INFO Daemon Daemon Examining partition table Oct 13 05:49:31.129761 waagent[2027]: 2025-10-13T05:49:31.129706Z INFO Daemon Daemon GPT not detected, determining filesystem Oct 13 05:49:31.170311 waagent[2027]: 2025-10-13T05:49:31.170261Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Oct 13 05:49:31.174838 waagent[2027]: 2025-10-13T05:49:31.174786Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Oct 13 05:49:31.516886 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#564 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 13 05:49:31.532887 kernel: sdb: sdb1 Oct 13 05:49:31.547741 waagent[2027]: 2025-10-13T05:49:31.547665Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Oct 13 05:49:31.560010 waagent[2027]: 2025-10-13T05:49:31.559957Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Oct 13 05:49:36.223520 login[2029]: pam_lastlog(login:session): file /var/log/lastlog is locked/read, retrying Oct 13 05:49:36.225232 login[2030]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Oct 13 05:49:36.231380 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Oct 13 05:49:36.232372 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Oct 13 05:49:36.238564 systemd-logind[1889]: New session 1 of user core. Oct 13 05:49:36.266050 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Oct 13 05:49:36.268711 systemd[1]: Starting user@500.service - User Manager for UID 500... Oct 13 05:49:36.300338 (systemd)[2123]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 13 05:49:36.302422 systemd-logind[1889]: New session c1 of user core. Oct 13 05:49:36.757650 systemd[2123]: Queued start job for default target default.target. Oct 13 05:49:36.780903 systemd[2123]: Created slice app.slice - User Application Slice. Oct 13 05:49:36.780931 systemd[2123]: Reached target paths.target - Paths. Oct 13 05:49:36.780977 systemd[2123]: Reached target timers.target - Timers. Oct 13 05:49:36.782186 systemd[2123]: Starting dbus.socket - D-Bus User Message Bus Socket... Oct 13 05:49:36.793054 systemd[2123]: Listening on dbus.socket - D-Bus User Message Bus Socket. Oct 13 05:49:36.793167 systemd[2123]: Reached target sockets.target - Sockets. Oct 13 05:49:36.793224 systemd[2123]: Reached target basic.target - Basic System. Oct 13 05:49:36.793274 systemd[2123]: Reached target default.target - Main User Target. Oct 13 05:49:36.793304 systemd[2123]: Startup finished in 485ms. Oct 13 05:49:36.793359 systemd[1]: Started user@500.service - User Manager for UID 500. Oct 13 05:49:36.794898 systemd[1]: Started session-1.scope - Session 1 of User core. Oct 13 05:49:37.097954 waagent[2027]: 2025-10-13T05:49:37.097801Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Oct 13 05:49:37.201729 waagent[2027]: 2025-10-13T05:49:37.201647Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Oct 13 05:49:37.201869 kernel: EXT4-fs (sdb1): mounted filesystem cd817258-b762-4bcd-b002-fb61c5470853 r/w with ordered data mode. Quota mode: none. Oct 13 05:49:37.238103 waagent[2027]: 2025-10-13T05:49:37.203894Z INFO Daemon Daemon Clean protocol and wireserver endpoint Oct 13 05:49:37.238103 waagent[2027]: 2025-10-13T05:49:37.204155Z INFO Daemon Daemon Running default provisioning handler Oct 13 05:49:37.238103 waagent[2027]: 2025-10-13T05:49:37.211041Z INFO Daemon Daemon Unable to get cloud-init enabled status from systemctl: Command '['systemctl', 'is-enabled', 'cloud-init-local.service']' returned non-zero exit status 4. Oct 13 05:49:37.238103 waagent[2027]: 2025-10-13T05:49:37.211907Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Oct 13 05:49:37.238103 waagent[2027]: 2025-10-13T05:49:37.212303Z INFO Daemon Daemon cloud-init is enabled: False Oct 13 05:49:37.238103 waagent[2027]: 2025-10-13T05:49:37.213800Z INFO Daemon Daemon Copying ovf-env.xml Oct 13 05:49:37.228107 systemd-logind[1889]: New session 2 of user core. Oct 13 05:49:37.223949 login[2029]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Oct 13 05:49:37.249980 systemd[1]: Started session-2.scope - Session 2 of User core. Oct 13 05:49:37.400117 waagent[2027]: 2025-10-13T05:49:37.395379Z INFO Daemon Daemon Successfully mounted dvd Oct 13 05:49:37.431551 waagent[2027]: 2025-10-13T05:49:37.431477Z INFO Daemon Daemon Detect protocol endpoint Oct 13 05:49:37.431630 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Oct 13 05:49:37.467879 waagent[2027]: 2025-10-13T05:49:37.433375Z INFO Daemon Daemon Clean protocol and wireserver endpoint Oct 13 05:49:37.467879 waagent[2027]: 2025-10-13T05:49:37.434875Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Oct 13 05:49:37.467879 waagent[2027]: 2025-10-13T05:49:37.436453Z INFO Daemon Daemon Test for route to 168.63.129.16 Oct 13 05:49:37.467879 waagent[2027]: 2025-10-13T05:49:37.437331Z INFO Daemon Daemon Route to 168.63.129.16 exists Oct 13 05:49:37.467879 waagent[2027]: 2025-10-13T05:49:37.438683Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Oct 13 05:49:37.467879 waagent[2027]: 2025-10-13T05:49:37.450237Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Oct 13 05:49:37.467879 waagent[2027]: 2025-10-13T05:49:37.450763Z INFO Daemon Daemon Wire protocol version:2012-11-30 Oct 13 05:49:37.467879 waagent[2027]: 2025-10-13T05:49:37.452103Z INFO Daemon Daemon Server preferred version:2015-04-05 Oct 13 05:49:37.612838 waagent[2027]: 2025-10-13T05:49:37.612765Z INFO Daemon Daemon Initializing goal state during protocol detection Oct 13 05:49:37.626732 waagent[2027]: 2025-10-13T05:49:37.614494Z INFO Daemon Daemon Forcing an update of the goal state. Oct 13 05:49:37.626732 waagent[2027]: 2025-10-13T05:49:37.619188Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Oct 13 05:49:37.650152 waagent[2027]: 2025-10-13T05:49:37.650074Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.177 Oct 13 05:49:37.678957 waagent[2027]: 2025-10-13T05:49:37.652127Z INFO Daemon Oct 13 05:49:37.678957 waagent[2027]: 2025-10-13T05:49:37.652472Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: c792167d-9199-42a1-b218-09f002fe5d7d eTag: 1467014604841783236 source: Fabric] Oct 13 05:49:37.678957 waagent[2027]: 2025-10-13T05:49:37.654190Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Oct 13 05:49:37.678957 waagent[2027]: 2025-10-13T05:49:37.654962Z INFO Daemon Oct 13 05:49:37.678957 waagent[2027]: 2025-10-13T05:49:37.655418Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Oct 13 05:49:37.678957 waagent[2027]: 2025-10-13T05:49:37.660249Z INFO Daemon Daemon Downloading artifacts profile blob Oct 13 05:49:37.780730 waagent[2027]: 2025-10-13T05:49:37.780670Z INFO Daemon Downloaded certificate {'thumbprint': '4054672541BC8AC5F587C9D5DBCA3CD624D7B893', 'hasPrivateKey': True} Oct 13 05:49:37.790923 waagent[2027]: 2025-10-13T05:49:37.782659Z INFO Daemon Fetch goal state completed Oct 13 05:49:37.820914 waagent[2027]: 2025-10-13T05:49:37.820845Z INFO Daemon Daemon Starting provisioning Oct 13 05:49:37.831809 waagent[2027]: 2025-10-13T05:49:37.822445Z INFO Daemon Daemon Handle ovf-env.xml. Oct 13 05:49:37.831809 waagent[2027]: 2025-10-13T05:49:37.823944Z INFO Daemon Daemon Set hostname [ci-4459.1.0-a-41e5bbf56a] Oct 13 05:49:37.863861 waagent[2027]: 2025-10-13T05:49:37.863810Z INFO Daemon Daemon Publish hostname [ci-4459.1.0-a-41e5bbf56a] Oct 13 05:49:37.865589 waagent[2027]: 2025-10-13T05:49:37.865539Z INFO Daemon Daemon Examine /proc/net/route for primary interface Oct 13 05:49:37.867052 waagent[2027]: 2025-10-13T05:49:37.867015Z INFO Daemon Daemon Primary interface is [eth0] Oct 13 05:49:37.884516 systemd-networkd[1585]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 13 05:49:37.884526 systemd-networkd[1585]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 13 05:49:37.884552 systemd-networkd[1585]: eth0: DHCP lease lost Oct 13 05:49:37.885016 waagent[2027]: 2025-10-13T05:49:37.884973Z INFO Daemon Daemon Create user account if not exists Oct 13 05:49:37.889407 waagent[2027]: 2025-10-13T05:49:37.889358Z INFO Daemon Daemon User core already exists, skip useradd Oct 13 05:49:37.890985 waagent[2027]: 2025-10-13T05:49:37.890944Z INFO Daemon Daemon Configure sudoer Oct 13 05:49:37.895320 waagent[2027]: 2025-10-13T05:49:37.895278Z INFO Daemon Daemon Configure sshd Oct 13 05:49:37.898554 waagent[2027]: 2025-10-13T05:49:37.898512Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Oct 13 05:49:37.898690 waagent[2027]: 2025-10-13T05:49:37.898657Z INFO Daemon Daemon Deploy ssh public key. Oct 13 05:49:37.965917 systemd-networkd[1585]: eth0: DHCPv4 address 10.200.4.18/24, gateway 10.200.4.1 acquired from 168.63.129.16 Oct 13 05:49:51.433157 chronyd[1870]: Selected source PHC0 Oct 13 05:49:51.970734 setup-nvidia[2058]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 473M 0 144k 0 0 108k 0 1:14:44 0:00:01 1:14:43 108k\u000d 1 473M 1 5056k 0 0 2140k 0 0:03:46 0:00:02 0:03:44 2140k\u000d 5 473M 5 26.3M 0 0 8241k 0 0:00:58 0:00:03 0:00:55 8241k\u000d 10 473M 10 51.7M 0 0 11.9M 0 0:00:39 0:00:04 0:00:35 11.9M\u000d 15 473M 15 74.7M 0 0 14.2M 0 0:00:33 0:00:05 0:00:28 15.3M\u000d 20 473M 20 98.9M 0 0 15.6M 0 0:00:30 0:00:06 0:00:24 19.7M\u000d 26 473M 26 124M 0 0 16.8M 0 0:00:28 0:00:07 0:00:21 23.7M\u000d 30 473M 30 146M 0 0 17.5M 0 0:00:26 0:00:08 0:00:18 23.8M\u000d 36 473M 36 171M 0 0 18.3M 0 0:00:25 0:00:09 0:00:16 23.8M\u000d 40 473M 40 193M 0 0 18.7M 0 0:00:25 0:00:10 0:00:15 23.6M\u000d 45 473M 45 216M 0 0 19.0M 0 0:00:24 0:00:11 0:00:13 23.4M\u000d 50 473M 50 240M 0 0 19.3M 0 0:00:24 0:00:12 0:00:12 23.1M\u000d 55 473M 55 260M 0 0 19.6M 0 0:00:24 0:00:13 0:00:11 22.9M\u000d 60 473M 60 285M 0 0 19.8M 0 0:00:23 0:00:14 0:00:09 22.6M\u000d 64 473M 64 305M 0 0 19.9M 0 0:00:23 0:00:15 0:00:08 22.3M\u000d 69 473M 69 329M 0 0 20.1M 0 0:00:23 0:00:16 0:00:07 22.6M\u000d 74 473M 74 351M 0 0 20.3M 0 0:00:23 0:00:17 0:00:06 22.8M\u000d 79 473M 79 373M 0 0 20.4M 0 0:00:23 0:00:18 0:00:05 22.5M\u000d 84 473M 84 398M 0 0 20.5M 0 0:00:23 0:00:19 0:00:04 22.6M\u000d 88 473M 88 418M 0 0 20.6M 0 0:00:22 0:00:20 0:00:02 22.6M\u000d 93 473M 93 442M 0 0 20.7M 0 0:00:22 0:00:21 0:00:01 22.5M\u000d 98 473M 98 465M 0 0 20.7M 0 0:00:22 0:00:22 --:--:-- 22.1M\u000d100 473M 100 473M 0 0 20.8M 0 0:00:22 0:00:22 --:--:-- 22.6M Oct 13 05:50:02.104540 setup-nvidia[1886]: Downloading NVIDIA 535.261.03 Driver Oct 13 05:50:02.108720 setup-nvidia[2193]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 13 05:50:02.108959 setup-nvidia[2193]: Dload Upload Total Spent Left Speed Oct 13 05:50:05.465053 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Oct 13 05:50:07.632407 setup-nvidia[2193]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 1 325M 1 4097k 0 0 9718k 0 0:00:34 --:--:-- 0:00:34 9709k\u000d 20 325M 20 68.0M 0 0 47.9M 0 0:00:06 0:00:01 0:00:05 47.9M\u000d 39 325M 39 130M 0 0 53.5M 0 0:00:06 0:00:02 0:00:04 53.5M\u000d 58 325M 58 192M 0 0 56.2M 0 0:00:05 0:00:03 0:00:02 56.2M\u000d 78 325M 78 256M 0 0 58.0M 0 0:00:05 0:00:04 0:00:01 58.0M\u000d 97 325M 97 318M 0 0 58.6M 0 0:00:05 0:00:05 --:--:-- 62.8M\u000d100 325M 100 325M 0 0 59.0M 0 0:00:05 0:00:05 --:--:-- 62.8M Oct 13 05:50:07.727371 setup-nvidia[1886]: Extract the NVIDIA Driver Installer 535.261.03 Oct 13 05:50:07.727577 setup-nvidia[1886]: /opt/nvidia/workdir/nvidia-workdir / Oct 13 05:50:07.789329 setup-nvidia[2196]: Creating directory NVIDIA-Linux-x86_64-535.261.03 Oct 13 05:50:08.000882 waagent[2027]: 2025-10-13T05:50:08.000725Z INFO Daemon Daemon Provisioning complete Oct 13 05:50:08.010621 waagent[2027]: 2025-10-13T05:50:08.010576Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Oct 13 05:50:08.012310 waagent[2027]: 2025-10-13T05:50:08.012268Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Oct 13 05:50:08.014467 waagent[2027]: 2025-10-13T05:50:08.014422Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Oct 13 05:50:08.122318 setup-nvidia[2196]: Verifying archive integrity... OK Oct 13 05:50:08.184299 waagent[2215]: 2025-10-13T05:50:08.184213Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Oct 13 05:50:08.184670 waagent[2215]: 2025-10-13T05:50:08.184368Z INFO ExtHandler ExtHandler OS: flatcar 4459.1.0 Oct 13 05:50:08.184670 waagent[2215]: 2025-10-13T05:50:08.184443Z INFO ExtHandler ExtHandler Python: 3.11.13 Oct 13 05:50:08.184670 waagent[2215]: 2025-10-13T05:50:08.184517Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Oct 13 05:50:08.316540 waagent[2215]: 2025-10-13T05:50:08.316405Z INFO ExtHandler ExtHandler Distro: flatcar-4459.1.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.13; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Oct 13 05:50:08.316720 waagent[2215]: 2025-10-13T05:50:08.316679Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Oct 13 05:50:08.316805 waagent[2215]: 2025-10-13T05:50:08.316771Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Oct 13 05:50:08.323525 waagent[2215]: 2025-10-13T05:50:08.323477Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Oct 13 05:50:08.333069 waagent[2215]: 2025-10-13T05:50:08.333017Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.177 Oct 13 05:50:08.333514 waagent[2215]: 2025-10-13T05:50:08.333475Z INFO ExtHandler Oct 13 05:50:08.333597 waagent[2215]: 2025-10-13T05:50:08.333566Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 82d1215b-e45a-42e9-b0b7-fc7473dec173 eTag: 1467014604841783236 source: Fabric] Oct 13 05:50:08.333935 waagent[2215]: 2025-10-13T05:50:08.333896Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Oct 13 05:50:08.334555 waagent[2215]: 2025-10-13T05:50:08.334516Z INFO ExtHandler Oct 13 05:50:08.334629 waagent[2215]: 2025-10-13T05:50:08.334598Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Oct 13 05:50:08.338018 waagent[2215]: 2025-10-13T05:50:08.337987Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Oct 13 05:50:08.408383 waagent[2215]: 2025-10-13T05:50:08.408293Z INFO ExtHandler Downloaded certificate {'thumbprint': '4054672541BC8AC5F587C9D5DBCA3CD624D7B893', 'hasPrivateKey': True} Oct 13 05:50:08.408963 waagent[2215]: 2025-10-13T05:50:08.408920Z INFO ExtHandler Fetch goal state completed Oct 13 05:50:08.426875 waagent[2215]: 2025-10-13T05:50:08.426816Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.4.2 1 Jul 2025 (Library: OpenSSL 3.4.2 1 Jul 2025) Oct 13 05:50:08.432055 waagent[2215]: 2025-10-13T05:50:08.432011Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2215 Oct 13 05:50:08.432199 waagent[2215]: 2025-10-13T05:50:08.432162Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Oct 13 05:50:08.432646 waagent[2215]: 2025-10-13T05:50:08.432595Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Oct 13 05:50:08.434326 waagent[2215]: 2025-10-13T05:50:08.434281Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4459.1.0', '', 'Flatcar Container Linux by Kinvolk'] Oct 13 05:50:08.434809 waagent[2215]: 2025-10-13T05:50:08.434763Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4459.1.0', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Oct 13 05:50:08.434981 waagent[2215]: 2025-10-13T05:50:08.434941Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Oct 13 05:50:08.435610 waagent[2215]: 2025-10-13T05:50:08.435568Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Oct 13 05:50:08.521957 waagent[2215]: 2025-10-13T05:50:08.521898Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Oct 13 05:50:08.522173 waagent[2215]: 2025-10-13T05:50:08.522130Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Oct 13 05:50:08.530256 waagent[2215]: 2025-10-13T05:50:08.530220Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Oct 13 05:50:08.537183 systemd[1]: Reload requested from client PID 2234 ('systemctl') (unit waagent.service)... Oct 13 05:50:08.537199 systemd[1]: Reloading... Oct 13 05:50:08.607906 zram_generator::config[2274]: No configuration found. Oct 13 05:50:08.884479 systemd[1]: Reloading finished in 346 ms. Oct 13 05:50:08.907292 waagent[2215]: 2025-10-13T05:50:08.907225Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Oct 13 05:50:08.907403 waagent[2215]: 2025-10-13T05:50:08.907367Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Oct 13 05:50:09.087429 setup-nvidia[2196]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.261.03 Oct 13 05:50:09.368722 waagent[2215]: 2025-10-13T05:50:09.368639Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Oct 13 05:50:09.369157 waagent[2215]: 2025-10-13T05:50:09.369018Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: 1. configuration enabled [True], 2. cgroups v1 enabled [False] OR cgroups v2 is in use and v2 resource limiting configuration enabled [False], 3. python supported: [True] Oct 13 05:50:09.369811 waagent[2215]: 2025-10-13T05:50:09.369762Z INFO ExtHandler ExtHandler Starting env monitor service. Oct 13 05:50:09.370179 waagent[2215]: 2025-10-13T05:50:09.370129Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Oct 13 05:50:09.370345 waagent[2215]: 2025-10-13T05:50:09.370302Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Oct 13 05:50:09.370495 waagent[2215]: 2025-10-13T05:50:09.370459Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Oct 13 05:50:09.370959 waagent[2215]: 2025-10-13T05:50:09.370895Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Oct 13 05:50:09.371184 waagent[2215]: 2025-10-13T05:50:09.371137Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Oct 13 05:50:09.371238 waagent[2215]: 2025-10-13T05:50:09.371182Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Oct 13 05:50:09.371634 waagent[2215]: 2025-10-13T05:50:09.371582Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Oct 13 05:50:09.371735 waagent[2215]: 2025-10-13T05:50:09.371669Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Oct 13 05:50:09.371886 waagent[2215]: 2025-10-13T05:50:09.371829Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Oct 13 05:50:09.372090 waagent[2215]: 2025-10-13T05:50:09.372024Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Oct 13 05:50:09.372423 waagent[2215]: 2025-10-13T05:50:09.372380Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Oct 13 05:50:09.372423 waagent[2215]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Oct 13 05:50:09.372423 waagent[2215]: eth0 00000000 0104C80A 0003 0 0 1024 00000000 0 0 0 Oct 13 05:50:09.372423 waagent[2215]: eth0 0004C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Oct 13 05:50:09.372423 waagent[2215]: eth0 0104C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Oct 13 05:50:09.372423 waagent[2215]: eth0 10813FA8 0104C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Oct 13 05:50:09.372423 waagent[2215]: eth0 FEA9FEA9 0104C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Oct 13 05:50:09.373160 waagent[2215]: 2025-10-13T05:50:09.373039Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Oct 13 05:50:09.373356 waagent[2215]: 2025-10-13T05:50:09.373302Z INFO EnvHandler ExtHandler Configure routes Oct 13 05:50:09.373441 waagent[2215]: 2025-10-13T05:50:09.373408Z INFO EnvHandler ExtHandler Gateway:None Oct 13 05:50:09.373514 waagent[2215]: 2025-10-13T05:50:09.373484Z INFO EnvHandler ExtHandler Routes:None Oct 13 05:50:09.381527 waagent[2215]: 2025-10-13T05:50:09.381483Z INFO ExtHandler ExtHandler Oct 13 05:50:09.381608 waagent[2215]: 2025-10-13T05:50:09.381574Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 73235308-e0b4-4e48-ac01-15d69a4625e2 correlation 266b643e-fdc7-4d9d-a10e-4008473ff007 created: 2025-10-13T05:47:46.485748Z] Oct 13 05:50:09.381988 waagent[2215]: 2025-10-13T05:50:09.381946Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Oct 13 05:50:09.382602 waagent[2215]: 2025-10-13T05:50:09.382562Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 1 ms] Oct 13 05:50:09.414327 waagent[2215]: 2025-10-13T05:50:09.414206Z WARNING ExtHandler ExtHandler Failed to get firewall packets: 'iptables -w -t security -L OUTPUT --zero OUTPUT -nxv' failed: 2 (iptables v1.8.11 (nf_tables): Illegal option `--numeric' with this command Oct 13 05:50:09.414327 waagent[2215]: Try `iptables -h' or 'iptables --help' for more information.) Oct 13 05:50:09.414784 waagent[2215]: 2025-10-13T05:50:09.414743Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 2FC6B2BE-9216-4147-9A97-B10AD7630038;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Oct 13 05:50:09.559657 waagent[2215]: 2025-10-13T05:50:09.559588Z INFO MonitorHandler ExtHandler Network interfaces: Oct 13 05:50:09.559657 waagent[2215]: Executing ['ip', '-a', '-o', 'link']: Oct 13 05:50:09.559657 waagent[2215]: 1: lo: mtu 65536 qdisc noqueue state UNKNOWN mode DEFAULT group default qlen 1000\ link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00 Oct 13 05:50:09.559657 waagent[2215]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:1e:52:65:07:5f brd ff:ff:ff:ff:ff:ff Oct 13 05:50:09.559657 waagent[2215]: Executing ['ip', '-4', '-a', '-o', 'address']: Oct 13 05:50:09.559657 waagent[2215]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Oct 13 05:50:09.559657 waagent[2215]: 2: eth0 inet 10.200.4.18/24 metric 1024 brd 10.200.4.255 scope global eth0\ valid_lft forever preferred_lft forever Oct 13 05:50:09.559657 waagent[2215]: Executing ['ip', '-6', '-a', '-o', 'address']: Oct 13 05:50:09.559657 waagent[2215]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Oct 13 05:50:09.559657 waagent[2215]: 2: eth0 inet6 fe80::7e1e:52ff:fe65:75f/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Oct 13 05:50:09.587143 waagent[2215]: 2025-10-13T05:50:09.587073Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Oct 13 05:50:09.587143 waagent[2215]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Oct 13 05:50:09.587143 waagent[2215]: pkts bytes target prot opt in out source destination Oct 13 05:50:09.587143 waagent[2215]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Oct 13 05:50:09.587143 waagent[2215]: pkts bytes target prot opt in out source destination Oct 13 05:50:09.587143 waagent[2215]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Oct 13 05:50:09.587143 waagent[2215]: pkts bytes target prot opt in out source destination Oct 13 05:50:09.587143 waagent[2215]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Oct 13 05:50:09.587143 waagent[2215]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Oct 13 05:50:09.587143 waagent[2215]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Oct 13 05:50:09.591493 waagent[2215]: 2025-10-13T05:50:09.591445Z INFO EnvHandler ExtHandler Current Firewall rules: Oct 13 05:50:09.591493 waagent[2215]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Oct 13 05:50:09.591493 waagent[2215]: pkts bytes target prot opt in out source destination Oct 13 05:50:09.591493 waagent[2215]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Oct 13 05:50:09.591493 waagent[2215]: pkts bytes target prot opt in out source destination Oct 13 05:50:09.591493 waagent[2215]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Oct 13 05:50:09.591493 waagent[2215]: pkts bytes target prot opt in out source destination Oct 13 05:50:09.591493 waagent[2215]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Oct 13 05:50:09.591493 waagent[2215]: 3 364 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Oct 13 05:50:09.591493 waagent[2215]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Oct 13 05:50:09.592545 waagent[2215]: 2025-10-13T05:50:09.592498Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Oct 13 05:50:09.592737 waagent[2215]: 2025-10-13T05:50:09.592701Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Oct 13 05:50:10.843056 setup-nvidia[2333]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Oct 13 05:50:10.931070 setup-nvidia[1886]: / Oct 13 05:50:10.931257 setup-nvidia[1886]: Spawn system-nspawn container to install the NVIDIA drivers Oct 13 05:50:11.385915 sudo[2377]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4459.1.0.bin --overlay=/usr/lib/modules::/usr/lib/modules --bind=/opt/nvidia/workdir/nvidia-workdir:/nvidia --bind=/usr/lib/nvidia/bin:/app/bin/ /app/bin/install-nvidia NVIDIA-Linux-x86_64-535.261.03 Oct 13 05:50:11.387689 sudo[2377]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 13 05:50:11.393380 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Oct 13 05:50:11.396619 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Oct 13 05:50:11.420223 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Oct 13 05:50:11.423917 systemd[1]: Starting user@0.service - User Manager for UID 0... Oct 13 05:50:11.443841 (systemd)[2380]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Oct 13 05:50:11.446116 systemd-logind[1889]: New session c3 of user root. Oct 13 05:50:11.627306 systemd[2380]: Queued start job for default target default.target. Oct 13 05:50:11.646828 systemd[2380]: Created slice app.slice - User Application Slice. Oct 13 05:50:11.646874 systemd[2380]: Reached target paths.target - Paths. Oct 13 05:50:11.646950 systemd[2380]: Reached target timers.target - Timers. Oct 13 05:50:11.648050 systemd[2380]: Starting dbus.socket - D-Bus User Message Bus Socket... Oct 13 05:50:11.658075 systemd[2380]: Listening on dbus.socket - D-Bus User Message Bus Socket. Oct 13 05:50:11.658190 systemd[2380]: Reached target sockets.target - Sockets. Oct 13 05:50:11.658243 systemd[2380]: Reached target basic.target - Basic System. Oct 13 05:50:11.658287 systemd[2380]: Reached target default.target - Main User Target. Oct 13 05:50:11.658317 systemd[2380]: Startup finished in 206ms. Oct 13 05:50:11.658345 systemd[1]: Started user@0.service - User Manager for UID 0. Oct 13 05:50:11.662442 systemd[1]: Started session-c2.scope - Session c2 of User root. Oct 13 05:50:11.721872 kernel: loop6: detected capacity change from 0 to 12644352 Oct 13 05:50:11.727876 kernel: loop6: p9 Oct 13 05:50:11.750778 setup-nvidia[2389]: ░ Spawning container flatcardevelopercontainer-4459.1.0.bin on /opt/nvidia/workdir/flatcar_developer_container-4459.1.0.bin. Oct 13 05:50:12.300887 kernel: EXT4-fs (loop6p9): write access unavailable, skipping orphan cleanup Oct 13 05:50:12.300982 kernel: EXT4-fs (loop6p9): mounted filesystem fe653036-7ca0-48cd-a35d-424b2604ac97 ro without journal. Quota mode: none. Oct 13 05:50:13.359988 update_engine[1893]: I20251013 05:50:13.359908 1893 update_attempter.cc:509] Updating boot flags... Oct 13 05:50:33.035399 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Oct 13 05:50:33.040091 systemd[1]: Started sshd@0-10.200.4.18:22-10.200.16.10:52180.service - OpenSSH per-connection server daemon (10.200.16.10:52180). Oct 13 05:50:34.171317 sshd[3289]: Accepted publickey for core from 10.200.16.10 port 52180 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:50:34.172534 sshd-session[3289]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:50:34.176808 systemd-logind[1889]: New session 3 of user core. Oct 13 05:50:34.186963 systemd[1]: Started session-3.scope - Session 3 of User core. Oct 13 05:50:34.713126 systemd[1]: Started sshd@1-10.200.4.18:22-10.200.16.10:52196.service - OpenSSH per-connection server daemon (10.200.16.10:52196). Oct 13 05:50:35.330091 sshd[3295]: Accepted publickey for core from 10.200.16.10 port 52196 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:50:35.331295 sshd-session[3295]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:50:35.335915 systemd-logind[1889]: New session 4 of user core. Oct 13 05:50:35.347974 systemd[1]: Started session-4.scope - Session 4 of User core. Oct 13 05:50:35.761349 sshd[3298]: Connection closed by 10.200.16.10 port 52196 Oct 13 05:50:35.761677 sshd-session[3295]: pam_unix(sshd:session): session closed for user core Oct 13 05:50:35.765041 systemd[1]: sshd@1-10.200.4.18:22-10.200.16.10:52196.service: Deactivated successfully. Oct 13 05:50:35.766593 systemd[1]: session-4.scope: Deactivated successfully. Oct 13 05:50:35.767342 systemd-logind[1889]: Session 4 logged out. Waiting for processes to exit. Oct 13 05:50:35.768289 systemd-logind[1889]: Removed session 4. Oct 13 05:50:35.872873 systemd[1]: Started sshd@2-10.200.4.18:22-10.200.16.10:52200.service - OpenSSH per-connection server daemon (10.200.16.10:52200). Oct 13 05:50:36.483292 sshd[3304]: Accepted publickey for core from 10.200.16.10 port 52200 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:50:36.484518 sshd-session[3304]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:50:36.488770 systemd-logind[1889]: New session 5 of user core. Oct 13 05:50:36.503021 systemd[1]: Started session-5.scope - Session 5 of User core. Oct 13 05:50:36.915043 sshd[3307]: Connection closed by 10.200.16.10 port 52200 Oct 13 05:50:36.915357 sshd-session[3304]: pam_unix(sshd:session): session closed for user core Oct 13 05:50:36.918719 systemd[1]: sshd@2-10.200.4.18:22-10.200.16.10:52200.service: Deactivated successfully. Oct 13 05:50:36.920462 systemd[1]: session-5.scope: Deactivated successfully. Oct 13 05:50:36.921193 systemd-logind[1889]: Session 5 logged out. Waiting for processes to exit. Oct 13 05:50:36.922119 systemd-logind[1889]: Removed session 5. Oct 13 05:50:47.031354 systemd[1]: Started sshd@3-10.200.4.18:22-10.200.16.10:39110.service - OpenSSH per-connection server daemon (10.200.16.10:39110). Oct 13 05:50:47.664604 sshd[6127]: Accepted publickey for core from 10.200.16.10 port 39110 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:50:47.666268 sshd-session[6127]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:50:47.671800 systemd-logind[1889]: New session 6 of user core. Oct 13 05:50:47.683068 systemd[1]: Started session-6.scope - Session 6 of User core. Oct 13 05:50:48.105708 sshd[6390]: Connection closed by 10.200.16.10 port 39110 Oct 13 05:50:48.107263 sshd-session[6127]: pam_unix(sshd:session): session closed for user core Oct 13 05:50:48.111687 systemd-logind[1889]: Session 6 logged out. Waiting for processes to exit. Oct 13 05:50:48.113475 systemd[1]: sshd@3-10.200.4.18:22-10.200.16.10:39110.service: Deactivated successfully. Oct 13 05:50:48.115795 systemd[1]: session-6.scope: Deactivated successfully. Oct 13 05:50:48.118471 systemd-logind[1889]: Removed session 6. Oct 13 05:50:48.216309 systemd[1]: Started sshd@4-10.200.4.18:22-10.200.16.10:39116.service - OpenSSH per-connection server daemon (10.200.16.10:39116). Oct 13 05:50:48.840367 sshd[6535]: Accepted publickey for core from 10.200.16.10 port 39116 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:50:48.842028 sshd-session[6535]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:50:48.847229 systemd-logind[1889]: New session 7 of user core. Oct 13 05:50:48.857001 systemd[1]: Started session-7.scope - Session 7 of User core. Oct 13 05:50:49.277508 sshd[6690]: Connection closed by 10.200.16.10 port 39116 Oct 13 05:50:49.277903 sshd-session[6535]: pam_unix(sshd:session): session closed for user core Oct 13 05:50:49.281948 systemd-logind[1889]: Session 7 logged out. Waiting for processes to exit. Oct 13 05:50:49.282680 systemd[1]: sshd@4-10.200.4.18:22-10.200.16.10:39116.service: Deactivated successfully. Oct 13 05:50:49.285789 systemd[1]: session-7.scope: Deactivated successfully. Oct 13 05:50:49.289400 systemd-logind[1889]: Removed session 7. Oct 13 05:50:59.390763 systemd[1]: Started sshd@5-10.200.4.18:22-10.200.16.10:39622.service - OpenSSH per-connection server daemon (10.200.16.10:39622). Oct 13 05:51:00.022876 sshd[10846]: Accepted publickey for core from 10.200.16.10 port 39622 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:51:00.024237 sshd-session[10846]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:51:00.031617 systemd-logind[1889]: New session 8 of user core. Oct 13 05:51:00.037983 systemd[1]: Started session-8.scope - Session 8 of User core. Oct 13 05:51:00.476911 sshd[11096]: Connection closed by 10.200.16.10 port 39622 Oct 13 05:51:00.478075 sshd-session[10846]: pam_unix(sshd:session): session closed for user core Oct 13 05:51:00.482696 systemd-logind[1889]: Session 8 logged out. Waiting for processes to exit. Oct 13 05:51:00.485424 systemd[1]: sshd@5-10.200.4.18:22-10.200.16.10:39622.service: Deactivated successfully. Oct 13 05:51:00.488170 systemd[1]: session-8.scope: Deactivated successfully. Oct 13 05:51:00.491459 systemd-logind[1889]: Removed session 8. Oct 13 05:51:00.593078 systemd[1]: Started sshd@6-10.200.4.18:22-10.200.16.10:43542.service - OpenSSH per-connection server daemon (10.200.16.10:43542). Oct 13 05:51:01.219387 sshd[11307]: Accepted publickey for core from 10.200.16.10 port 43542 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:51:01.220771 sshd-session[11307]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:51:01.227755 systemd-logind[1889]: New session 9 of user core. Oct 13 05:51:01.240894 systemd[1]: Started session-9.scope - Session 9 of User core. Oct 13 05:51:01.652293 sshd[11524]: Connection closed by 10.200.16.10 port 43542 Oct 13 05:51:01.653145 sshd-session[11307]: pam_unix(sshd:session): session closed for user core Oct 13 05:51:01.657841 systemd[1]: sshd@6-10.200.4.18:22-10.200.16.10:43542.service: Deactivated successfully. Oct 13 05:51:01.659354 systemd-logind[1889]: Session 9 logged out. Waiting for processes to exit. Oct 13 05:51:01.663151 systemd[1]: session-9.scope: Deactivated successfully. Oct 13 05:51:01.665757 systemd-logind[1889]: Removed session 9. Oct 13 05:51:11.763100 systemd[1]: Started sshd@7-10.200.4.18:22-10.200.16.10:36696.service - OpenSSH per-connection server daemon (10.200.16.10:36696). Oct 13 05:51:12.395593 sshd[14047]: Accepted publickey for core from 10.200.16.10 port 36696 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:51:12.397042 sshd-session[14047]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:51:12.403263 systemd-logind[1889]: New session 10 of user core. Oct 13 05:51:12.412953 systemd[1]: Started session-10.scope - Session 10 of User core. Oct 13 05:51:12.839764 sshd[14075]: Connection closed by 10.200.16.10 port 36696 Oct 13 05:51:12.841038 sshd-session[14047]: pam_unix(sshd:session): session closed for user core Oct 13 05:51:12.845521 systemd-logind[1889]: Session 10 logged out. Waiting for processes to exit. Oct 13 05:51:12.847328 systemd[1]: sshd@7-10.200.4.18:22-10.200.16.10:36696.service: Deactivated successfully. Oct 13 05:51:12.849030 systemd[1]: session-10.scope: Deactivated successfully. Oct 13 05:51:12.852870 systemd-logind[1889]: Removed session 10. Oct 13 05:51:12.947299 systemd[1]: Started sshd@8-10.200.4.18:22-10.200.16.10:36702.service - OpenSSH per-connection server daemon (10.200.16.10:36702). Oct 13 05:51:13.564878 sshd[14164]: Accepted publickey for core from 10.200.16.10 port 36702 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:51:13.566647 sshd-session[14164]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:51:13.573043 systemd-logind[1889]: New session 11 of user core. Oct 13 05:51:13.579586 systemd[1]: Started session-11.scope - Session 11 of User core. Oct 13 05:51:14.003304 sshd[14180]: Connection closed by 10.200.16.10 port 36702 Oct 13 05:51:14.003661 sshd-session[14164]: pam_unix(sshd:session): session closed for user core Oct 13 05:51:14.009880 systemd-logind[1889]: Session 11 logged out. Waiting for processes to exit. Oct 13 05:51:14.016919 systemd[1]: sshd@8-10.200.4.18:22-10.200.16.10:36702.service: Deactivated successfully. Oct 13 05:51:14.019527 systemd[1]: session-11.scope: Deactivated successfully. Oct 13 05:51:14.021737 systemd-logind[1889]: Removed session 11. Oct 13 05:51:24.117095 systemd[1]: Started sshd@9-10.200.4.18:22-10.200.16.10:54866.service - OpenSSH per-connection server daemon (10.200.16.10:54866). Oct 13 05:51:24.759291 sshd[14516]: Accepted publickey for core from 10.200.16.10 port 54866 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:51:24.761076 sshd-session[14516]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:51:24.767918 systemd-logind[1889]: New session 12 of user core. Oct 13 05:51:24.777038 systemd[1]: Started session-12.scope - Session 12 of User core. Oct 13 05:51:25.202370 sshd[14543]: Connection closed by 10.200.16.10 port 54866 Oct 13 05:51:25.202740 sshd-session[14516]: pam_unix(sshd:session): session closed for user core Oct 13 05:51:25.206804 systemd[1]: sshd@9-10.200.4.18:22-10.200.16.10:54866.service: Deactivated successfully. Oct 13 05:51:25.207218 systemd-logind[1889]: Session 12 logged out. Waiting for processes to exit. Oct 13 05:51:25.209157 systemd[1]: session-12.scope: Deactivated successfully. Oct 13 05:51:25.213009 systemd-logind[1889]: Removed session 12. Oct 13 05:51:25.313267 systemd[1]: Started sshd@10-10.200.4.18:22-10.200.16.10:54880.service - OpenSSH per-connection server daemon (10.200.16.10:54880). Oct 13 05:51:25.946031 sshd[14566]: Accepted publickey for core from 10.200.16.10 port 54880 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:51:25.947379 sshd-session[14566]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:51:25.954415 systemd-logind[1889]: New session 13 of user core. Oct 13 05:51:25.959001 systemd[1]: Started session-13.scope - Session 13 of User core. Oct 13 05:51:26.400966 sshd[14599]: Connection closed by 10.200.16.10 port 54880 Oct 13 05:51:26.405042 sshd-session[14566]: pam_unix(sshd:session): session closed for user core Oct 13 05:51:26.408594 systemd-logind[1889]: Session 13 logged out. Waiting for processes to exit. Oct 13 05:51:26.410303 systemd[1]: sshd@10-10.200.4.18:22-10.200.16.10:54880.service: Deactivated successfully. Oct 13 05:51:26.412027 systemd[1]: session-13.scope: Deactivated successfully. Oct 13 05:51:26.414784 systemd-logind[1889]: Removed session 13. Oct 13 05:51:36.510113 systemd[1]: Started sshd@11-10.200.4.18:22-10.200.16.10:47856.service - OpenSSH per-connection server daemon (10.200.16.10:47856). Oct 13 05:51:37.144458 sshd[14917]: Accepted publickey for core from 10.200.16.10 port 47856 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:51:37.146225 sshd-session[14917]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:51:37.152074 systemd-logind[1889]: New session 14 of user core. Oct 13 05:51:37.160021 systemd[1]: Started session-14.scope - Session 14 of User core. Oct 13 05:51:37.588721 sshd[14939]: Connection closed by 10.200.16.10 port 47856 Oct 13 05:51:37.589054 sshd-session[14917]: pam_unix(sshd:session): session closed for user core Oct 13 05:51:37.594437 systemd-logind[1889]: Session 14 logged out. Waiting for processes to exit. Oct 13 05:51:37.595615 systemd[1]: sshd@11-10.200.4.18:22-10.200.16.10:47856.service: Deactivated successfully. Oct 13 05:51:37.597505 systemd[1]: session-14.scope: Deactivated successfully. Oct 13 05:51:37.599172 systemd-logind[1889]: Removed session 14. Oct 13 05:51:37.695235 systemd[1]: Started sshd@12-10.200.4.18:22-10.200.16.10:47860.service - OpenSSH per-connection server daemon (10.200.16.10:47860). Oct 13 05:51:38.315477 sshd[14969]: Accepted publickey for core from 10.200.16.10 port 47860 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:51:38.316823 sshd-session[14969]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:51:38.322770 systemd-logind[1889]: New session 15 of user core. Oct 13 05:51:38.328012 systemd[1]: Started session-15.scope - Session 15 of User core. Oct 13 05:51:38.757077 sshd[15001]: Connection closed by 10.200.16.10 port 47860 Oct 13 05:51:38.758969 sshd-session[14969]: pam_unix(sshd:session): session closed for user core Oct 13 05:51:38.762159 systemd[1]: sshd@12-10.200.4.18:22-10.200.16.10:47860.service: Deactivated successfully. Oct 13 05:51:38.764600 systemd[1]: session-15.scope: Deactivated successfully. Oct 13 05:51:38.766910 systemd-logind[1889]: Session 15 logged out. Waiting for processes to exit. Oct 13 05:51:38.770228 systemd-logind[1889]: Removed session 15. Oct 13 05:51:48.614498 setup-nvidia[2389]: \u000d Oct 13 05:51:48.614498 setup-nvidia[2389]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Oct 13 05:51:48.614498 setup-nvidia[2389]: frequently when this kernel module was built against the wrong or Oct 13 05:51:48.614498 setup-nvidia[2389]: improperly configured kernel sources, with a version of gcc that differs Oct 13 05:51:48.614498 setup-nvidia[2389]: from the one used to build the target kernel, or if another driver, such Oct 13 05:51:48.614498 setup-nvidia[2389]: as nouveau, is present and prevents the NVIDIA kernel module from Oct 13 05:51:48.614498 setup-nvidia[2389]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Oct 13 05:51:48.614498 setup-nvidia[2389]: installed in this system is supported by this NVIDIA Linux graphics Oct 13 05:51:48.614498 setup-nvidia[2389]: driver release. Oct 13 05:51:48.614498 setup-nvidia[2389]: \u000d Oct 13 05:51:48.614498 setup-nvidia[2389]: Please see the log entries 'Kernel module load error' and 'Kernel Oct 13 05:51:48.614498 setup-nvidia[2389]: messages' at the end of the file Oct 13 05:51:48.614498 setup-nvidia[2389]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for more Oct 13 05:51:48.614498 setup-nvidia[2389]: information. Oct 13 05:51:48.614498 setup-nvidia[2389]: \u000d Oct 13 05:51:48.629455 setup-nvidia[2389]: dmesg: read kernel buffer failed: Operation not permitted Oct 13 05:51:48.647732 setup-nvidia[2389]: \u000d Oct 13 05:51:48.647732 setup-nvidia[2389]: ERROR: Installation has failed. Please see the file Oct 13 05:51:48.647732 setup-nvidia[2389]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for Oct 13 05:51:48.647732 setup-nvidia[2389]: details. You may find suggestions on fixing installation problems in Oct 13 05:51:48.647732 setup-nvidia[2389]: the README available on the Linux driver download page at Oct 13 05:51:48.647732 setup-nvidia[2389]: www.nvidia.com. Oct 13 05:51:48.647732 setup-nvidia[2389]: \u000d Oct 13 05:51:48.648175 setup-nvidia[2389]: Last 50 lines of nvidia-installer.log: Oct 13 05:51:48.648999 setup-nvidia[2389]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039059rm+0x53: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:51:48.648999 setup-nvidia[2389]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039056rm+0x90: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:51:48.648999 setup-nvidia[2389]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039049rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:51:48.648999 setup-nvidia[2389]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039105rm+0x93: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:51:48.648999 setup-nvidia[2389]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039101rm+0x36: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:51:48.648999 setup-nvidia[2389]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039100rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:51:48.648999 setup-nvidia[2389]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039102rm+0x77: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:51:48.648999 setup-nvidia[2389]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039046rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:51:48.648999 setup-nvidia[2389]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030615rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:51:48.648999 setup-nvidia[2389]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030614rm+0x27: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:51:48.648999 setup-nvidia[2389]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030618rm+0x37: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:51:48.648999 setup-nvidia[2389]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030617rm+0x24: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:51:48.648999 setup-nvidia[2389]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030616rm+0xca: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:51:48.648999 setup-nvidia[2389]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030619rm+0x193: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:51:48.648999 setup-nvidia[2389]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030621rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:51:48.648999 setup-nvidia[2389]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv011864rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:51:48.648999 setup-nvidia[2389]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039413rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build Oct 13 05:51:48.648999 setup-nvidia[2389]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/Module.symvers Oct 13 05:51:48.648999 setup-nvidia[2389]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.mod.o Oct 13 05:51:48.648999 setup-nvidia[2389]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/.module-common.o Oct 13 05:51:48.648999 setup-nvidia[2389]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.mod.o Oct 13 05:51:48.648999 setup-nvidia[2389]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.mod.o Oct 13 05:51:48.648999 setup-nvidia[2389]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.mod.o Oct 13 05:51:48.648999 setup-nvidia[2389]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.mod.o Oct 13 05:51:48.648999 setup-nvidia[2389]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Oct 13 05:51:48.648999 setup-nvidia[2389]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Oct 13 05:51:48.648999 setup-nvidia[2389]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Oct 13 05:51:48.648999 setup-nvidia[2389]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Oct 13 05:51:48.648999 setup-nvidia[2389]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Oct 13 05:51:48.648999 setup-nvidia[2389]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Oct 13 05:51:48.648999 setup-nvidia[2389]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko due to unavailability of vmlinux Oct 13 05:51:48.649764 setup-nvidia[2389]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Oct 13 05:51:48.649764 setup-nvidia[2389]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko due to unavailability of vmlinux Oct 13 05:51:48.649764 setup-nvidia[2389]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Oct 13 05:51:48.649764 setup-nvidia[2389]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko due to unavailability of vmlinux Oct 13 05:51:48.649764 setup-nvidia[2389]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Oct 13 05:51:48.649764 setup-nvidia[2389]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko due to unavailability of vmlinux Oct 13 05:51:48.649764 setup-nvidia[2389]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Oct 13 05:51:48.649764 setup-nvidia[2389]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko due to unavailability of vmlinux Oct 13 05:51:48.649764 setup-nvidia[2389]: make[2]: Leaving directory '/usr/lib/modules/6.12.51-flatcar/build' Oct 13 05:51:48.649764 setup-nvidia[2389]: make[1]: Leaving directory '/usr/lib/modules/6.12.51-flatcar/source' Oct 13 05:51:48.649764 setup-nvidia[2389]: -> done. Oct 13 05:51:48.649764 setup-nvidia[2389]: -> Kernel module compilation complete. Oct 13 05:51:48.649764 setup-nvidia[2389]: -> Unable to determine if Secure Boot is enabled: No such file or directory Oct 13 05:51:48.649764 setup-nvidia[2389]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most frequently when this kernel module was built against the wrong or improperly configured kernel sources, with a version of gcc that differs from the one used to build the target kernel, or if another driver, such as nouveau, is present and prevents the NVIDIA kernel module from obtaining ownership of the NVIDIA device(s), or no NVIDIA device installed in this system is supported by this NVIDIA Linux graphics driver release. Oct 13 05:51:48.649764 setup-nvidia[2389]: \u000d Oct 13 05:51:48.649764 setup-nvidia[2389]: Please see the log entries 'Kernel module load error' and 'Kernel messages' at the end of the file '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for more information. Oct 13 05:51:48.649764 setup-nvidia[2389]: -> Kernel module load error: Operation not permitted Oct 13 05:51:48.649764 setup-nvidia[2389]: -> Kernel messages: Oct 13 05:51:48.649764 setup-nvidia[2389]: ERROR: Installation has failed. Please see the file '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for details. You may find suggestions on fixing installation problems in the README available on the Linux driver download page at www.nvidia.com. Oct 13 05:51:48.874970 systemd[1]: Started sshd@13-10.200.4.18:22-10.200.16.10:46438.service - OpenSSH per-connection server daemon (10.200.16.10:46438). Oct 13 05:51:49.489147 sshd[15280]: Accepted publickey for core from 10.200.16.10 port 46438 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:51:49.490396 sshd-session[15280]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:51:49.494506 systemd-logind[1889]: New session 16 of user core. Oct 13 05:51:49.504966 systemd[1]: Started session-16.scope - Session 16 of User core. Oct 13 05:51:49.919440 sshd[15283]: Connection closed by 10.200.16.10 port 46438 Oct 13 05:51:49.919772 sshd-session[15280]: pam_unix(sshd:session): session closed for user core Oct 13 05:51:49.923106 systemd[1]: sshd@13-10.200.4.18:22-10.200.16.10:46438.service: Deactivated successfully. Oct 13 05:51:49.924965 systemd[1]: session-16.scope: Deactivated successfully. Oct 13 05:51:49.925761 systemd-logind[1889]: Session 16 logged out. Waiting for processes to exit. Oct 13 05:51:49.927238 systemd-logind[1889]: Removed session 16. Oct 13 05:51:50.041218 systemd[1]: Started sshd@14-10.200.4.18:22-10.200.16.10:46454.service - OpenSSH per-connection server daemon (10.200.16.10:46454). Oct 13 05:51:50.653844 sshd[15289]: Accepted publickey for core from 10.200.16.10 port 46454 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:51:50.655030 sshd-session[15289]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:51:50.659242 systemd-logind[1889]: New session 17 of user core. Oct 13 05:51:50.675980 systemd[1]: Started session-17.scope - Session 17 of User core. Oct 13 05:51:51.102563 sshd[15292]: Connection closed by 10.200.16.10 port 46454 Oct 13 05:51:51.102831 sshd-session[15289]: pam_unix(sshd:session): session closed for user core Oct 13 05:51:51.106241 systemd[1]: sshd@14-10.200.4.18:22-10.200.16.10:46454.service: Deactivated successfully. Oct 13 05:51:51.107873 systemd[1]: session-17.scope: Deactivated successfully. Oct 13 05:51:51.108533 systemd-logind[1889]: Session 17 logged out. Waiting for processes to exit. Oct 13 05:51:51.109400 systemd-logind[1889]: Removed session 17. Oct 13 05:52:01.211241 systemd[1]: Started sshd@15-10.200.4.18:22-10.200.16.10:41776.service - OpenSSH per-connection server daemon (10.200.16.10:41776). Oct 13 05:52:01.817258 sshd[15298]: Accepted publickey for core from 10.200.16.10 port 41776 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:52:01.818412 sshd-session[15298]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:52:01.822521 systemd-logind[1889]: New session 18 of user core. Oct 13 05:52:01.831967 systemd[1]: Started session-18.scope - Session 18 of User core. Oct 13 05:52:02.254655 sshd[15301]: Connection closed by 10.200.16.10 port 41776 Oct 13 05:52:02.255044 sshd-session[15298]: pam_unix(sshd:session): session closed for user core Oct 13 05:52:02.258452 systemd[1]: sshd@15-10.200.4.18:22-10.200.16.10:41776.service: Deactivated successfully. Oct 13 05:52:02.260103 systemd[1]: session-18.scope: Deactivated successfully. Oct 13 05:52:02.260795 systemd-logind[1889]: Session 18 logged out. Waiting for processes to exit. Oct 13 05:52:02.261684 systemd-logind[1889]: Removed session 18. Oct 13 05:52:02.362035 systemd[1]: Started sshd@16-10.200.4.18:22-10.200.16.10:41792.service - OpenSSH per-connection server daemon (10.200.16.10:41792). Oct 13 05:52:02.969248 sshd[15307]: Accepted publickey for core from 10.200.16.10 port 41792 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:52:02.970411 sshd-session[15307]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:52:02.974618 systemd-logind[1889]: New session 19 of user core. Oct 13 05:52:02.983961 systemd[1]: Started session-19.scope - Session 19 of User core. Oct 13 05:52:03.398528 sshd[15310]: Connection closed by 10.200.16.10 port 41792 Oct 13 05:52:03.398836 sshd-session[15307]: pam_unix(sshd:session): session closed for user core Oct 13 05:52:03.402197 systemd[1]: sshd@16-10.200.4.18:22-10.200.16.10:41792.service: Deactivated successfully. Oct 13 05:52:03.403745 systemd[1]: session-19.scope: Deactivated successfully. Oct 13 05:52:03.404440 systemd-logind[1889]: Session 19 logged out. Waiting for processes to exit. Oct 13 05:52:03.405391 systemd-logind[1889]: Removed session 19. Oct 13 05:52:13.506208 systemd[1]: Started sshd@17-10.200.4.18:22-10.200.16.10:40132.service - OpenSSH per-connection server daemon (10.200.16.10:40132). Oct 13 05:52:14.112916 sshd[15316]: Accepted publickey for core from 10.200.16.10 port 40132 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:52:14.114120 sshd-session[15316]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:52:14.118323 systemd-logind[1889]: New session 20 of user core. Oct 13 05:52:14.127107 systemd[1]: Started session-20.scope - Session 20 of User core. Oct 13 05:52:14.550586 sshd[15319]: Connection closed by 10.200.16.10 port 40132 Oct 13 05:52:14.550917 sshd-session[15316]: pam_unix(sshd:session): session closed for user core Oct 13 05:52:14.554331 systemd[1]: sshd@17-10.200.4.18:22-10.200.16.10:40132.service: Deactivated successfully. Oct 13 05:52:14.555967 systemd[1]: session-20.scope: Deactivated successfully. Oct 13 05:52:14.556755 systemd-logind[1889]: Session 20 logged out. Waiting for processes to exit. Oct 13 05:52:14.557681 systemd-logind[1889]: Removed session 20. Oct 13 05:52:14.662393 systemd[1]: Started sshd@18-10.200.4.18:22-10.200.16.10:40140.service - OpenSSH per-connection server daemon (10.200.16.10:40140). Oct 13 05:52:15.269576 sshd[15325]: Accepted publickey for core from 10.200.16.10 port 40140 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:52:15.270762 sshd-session[15325]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:52:15.275027 systemd-logind[1889]: New session 21 of user core. Oct 13 05:52:15.285964 systemd[1]: Started session-21.scope - Session 21 of User core. Oct 13 05:52:15.708219 sshd[15328]: Connection closed by 10.200.16.10 port 40140 Oct 13 05:52:15.708523 sshd-session[15325]: pam_unix(sshd:session): session closed for user core Oct 13 05:52:15.711744 systemd[1]: sshd@18-10.200.4.18:22-10.200.16.10:40140.service: Deactivated successfully. Oct 13 05:52:15.713379 systemd[1]: session-21.scope: Deactivated successfully. Oct 13 05:52:15.714057 systemd-logind[1889]: Session 21 logged out. Waiting for processes to exit. Oct 13 05:52:15.714923 systemd-logind[1889]: Removed session 21. Oct 13 05:52:19.057882 kernel: EXT4-fs (loop6p9): unmounting filesystem fe653036-7ca0-48cd-a35d-424b2604ac97. Oct 13 05:52:19.058439 setup-nvidia[2389]: Container flatcardevelopercontainer-4459.1.0.bin exited successfully. Oct 13 05:52:19.060911 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4459.1.0.bin.mount: Deactivated successfully. Oct 13 05:52:19.120667 sudo[2377]: pam_unix(sudo:session): session closed for user root Oct 13 05:52:19.122559 systemd[1]: session-c2.scope: Deactivated successfully. Oct 13 05:52:19.122872 systemd[1]: session-c2.scope: Consumed 5min 18.542s CPU time, 889.6M memory peak. Oct 13 05:52:19.546559 setup-nvidia[1886]: /opt/nvidia / Oct 13 05:52:19.547564 setup-nvidia[1886]: / Oct 13 05:52:19.556868 kernel: IPMI message handler: version 39.2 Oct 13 05:52:19.560869 kernel: ipmi device interface Oct 13 05:52:19.572531 setup-nvidia[1886]: /opt/nvidia/current/usr/lib/modules/6.12.51-flatcar/video / Oct 13 05:52:19.626464 kernel: nvidia: loading out-of-tree module taints kernel. Oct 13 05:52:19.626601 kernel: nvidia: module license 'NVIDIA' taints kernel. Oct 13 05:52:19.626625 kernel: Disabling lock debugging due to kernel taint Oct 13 05:52:19.633932 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Oct 13 05:52:19.633974 kernel: nvidia: module license taints kernel. Oct 13 05:52:20.033877 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 Oct 13 05:52:20.436138 kernel: Oct 13 05:52:20.436276 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Oct 13 05:52:20.490868 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.261.03 Sat Jun 14 16:05:50 UTC 2025 Oct 13 05:52:20.511871 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.261.03 Sat Jun 14 15:39:16 UTC 2025 Oct 13 05:52:20.531895 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Oct 13 05:52:20.589433 setup-nvidia[1886]: / Oct 13 05:52:20.589873 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. Oct 13 05:52:20.613949 setup-nvidia[15368]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 13 05:52:22.251517 setup-nvidia[15385]: Mon Oct 13 05:52:22 2025 Oct 13 05:52:22.251517 setup-nvidia[15385]: +---------------------------------------------------------------------------------------+ Oct 13 05:52:22.251517 setup-nvidia[15385]: | NVIDIA-SMI 535.261.03 Driver Version: 535.261.03 CUDA Version: 12.2 | Oct 13 05:52:22.251517 setup-nvidia[15385]: |-----------------------------------------+----------------------+----------------------+ Oct 13 05:52:22.251517 setup-nvidia[15385]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Oct 13 05:52:22.251517 setup-nvidia[15385]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Oct 13 05:52:22.251517 setup-nvidia[15385]: | | | MIG M. | Oct 13 05:52:22.251517 setup-nvidia[15385]: |=========================================+======================+======================| Oct 13 05:52:22.255075 setup-nvidia[15385]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Oct 13 05:52:22.255075 setup-nvidia[15385]: | N/A 29C P0 36W / 250W | 0MiB / 16384MiB | 1% Default | Oct 13 05:52:22.255075 setup-nvidia[15385]: | | | N/A | Oct 13 05:52:22.255075 setup-nvidia[15385]: +-----------------------------------------+----------------------+----------------------+ Oct 13 05:52:22.255184 setup-nvidia[15385]: Oct 13 05:52:22.255184 setup-nvidia[15385]: +---------------------------------------------------------------------------------------+ Oct 13 05:52:22.255184 setup-nvidia[15385]: | Processes: | Oct 13 05:52:22.255184 setup-nvidia[15385]: | GPU GI CI PID Type Process name GPU Memory | Oct 13 05:52:22.255184 setup-nvidia[15385]: | ID ID Usage | Oct 13 05:52:22.255184 setup-nvidia[15385]: |=======================================================================================| Oct 13 05:52:22.255324 setup-nvidia[15385]: | No running processes found | Oct 13 05:52:22.255324 setup-nvidia[15385]: +---------------------------------------------------------------------------------------+ Oct 13 05:52:22.627203 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Oct 13 05:52:22.629314 systemd[1]: Reached target multi-user.target - Multi-User System. Oct 13 05:52:22.629539 systemd[1]: Startup finished in 1.452s (firmware) + 39.163s (loader) + 3.766s (kernel) + 15.922s (initrd) + 3min 15.581s (userspace) = 4min 15.886s. Oct 13 05:52:25.816942 systemd[1]: Started sshd@19-10.200.4.18:22-10.200.16.10:60566.service - OpenSSH per-connection server daemon (10.200.16.10:60566). Oct 13 05:52:26.427924 sshd[15392]: Accepted publickey for core from 10.200.16.10 port 60566 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:52:26.429103 sshd-session[15392]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:52:26.433392 systemd-logind[1889]: New session 22 of user core. Oct 13 05:52:26.443969 systemd[1]: Started session-22.scope - Session 22 of User core. Oct 13 05:52:26.859958 sshd[15395]: Connection closed by 10.200.16.10 port 60566 Oct 13 05:52:26.860233 sshd-session[15392]: pam_unix(sshd:session): session closed for user core Oct 13 05:52:26.863722 systemd[1]: sshd@19-10.200.4.18:22-10.200.16.10:60566.service: Deactivated successfully. Oct 13 05:52:26.865340 systemd[1]: session-22.scope: Deactivated successfully. Oct 13 05:52:26.866048 systemd-logind[1889]: Session 22 logged out. Waiting for processes to exit. Oct 13 05:52:26.866999 systemd-logind[1889]: Removed session 22. Oct 13 05:52:26.972116 systemd[1]: Started sshd@20-10.200.4.18:22-10.200.16.10:60570.service - OpenSSH per-connection server daemon (10.200.16.10:60570). Oct 13 05:52:27.607950 sshd[15401]: Accepted publickey for core from 10.200.16.10 port 60570 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:52:27.609164 sshd-session[15401]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:52:27.613547 systemd-logind[1889]: New session 23 of user core. Oct 13 05:52:27.626973 systemd[1]: Started session-23.scope - Session 23 of User core. Oct 13 05:52:28.036022 sshd[15404]: Connection closed by 10.200.16.10 port 60570 Oct 13 05:52:28.036298 sshd-session[15401]: pam_unix(sshd:session): session closed for user core Oct 13 05:52:28.039577 systemd[1]: sshd@20-10.200.4.18:22-10.200.16.10:60570.service: Deactivated successfully. Oct 13 05:52:28.041146 systemd[1]: session-23.scope: Deactivated successfully. Oct 13 05:52:28.041793 systemd-logind[1889]: Session 23 logged out. Waiting for processes to exit. Oct 13 05:52:28.042663 systemd-logind[1889]: Removed session 23. Oct 13 05:52:28.149181 systemd[1]: Started sshd@21-10.200.4.18:22-10.200.16.10:60576.service - OpenSSH per-connection server daemon (10.200.16.10:60576). Oct 13 05:52:28.779344 sshd[15410]: Accepted publickey for core from 10.200.16.10 port 60576 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:52:28.780577 sshd-session[15410]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:52:28.784907 systemd-logind[1889]: New session 24 of user core. Oct 13 05:52:28.794971 systemd[1]: Started session-24.scope - Session 24 of User core. Oct 13 05:52:29.128335 systemd[1]: Stopping user@0.service - User Manager for UID 0... Oct 13 05:52:29.129098 systemd[2380]: Activating special unit exit.target... Oct 13 05:52:29.129178 systemd[2380]: Stopped target default.target - Main User Target. Oct 13 05:52:29.129218 systemd[2380]: Stopped target basic.target - Basic System. Oct 13 05:52:29.129247 systemd[2380]: Stopped target paths.target - Paths. Oct 13 05:52:29.129273 systemd[2380]: Stopped target sockets.target - Sockets. Oct 13 05:52:29.129298 systemd[2380]: Stopped target timers.target - Timers. Oct 13 05:52:29.129625 systemd[2380]: Closed dbus.socket - D-Bus User Message Bus Socket. Oct 13 05:52:29.129830 systemd[2380]: Removed slice app.slice - User Application Slice. Oct 13 05:52:29.129878 systemd[2380]: Reached target shutdown.target - Shutdown. Oct 13 05:52:29.130021 systemd[2380]: Finished systemd-exit.service - Exit the Session. Oct 13 05:52:29.130053 systemd[2380]: Reached target exit.target - Exit the Session. Oct 13 05:52:29.140837 (sd-pam)[2381]: pam_unix(systemd-user:session): session closed for user root Oct 13 05:52:29.142025 systemd[1]: user@0.service: Deactivated successfully. Oct 13 05:52:29.142371 systemd[1]: Stopped user@0.service - User Manager for UID 0. Oct 13 05:52:29.144525 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Oct 13 05:52:29.165212 systemd[1]: run-user-0.mount: Deactivated successfully. Oct 13 05:52:29.166225 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Oct 13 05:52:29.166465 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Oct 13 05:52:29.168092 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Oct 13 05:52:29.168200 systemd[1]: user-0.slice: Consumed 5min 18.785s CPU time, 895.6M memory peak. Oct 13 05:52:29.168651 systemd-logind[1889]: Removed session c3. Oct 13 05:52:29.220529 sshd[15413]: Connection closed by 10.200.16.10 port 60576 Oct 13 05:52:29.220877 sshd-session[15410]: pam_unix(sshd:session): session closed for user core Oct 13 05:52:29.224021 systemd[1]: sshd@21-10.200.4.18:22-10.200.16.10:60576.service: Deactivated successfully. Oct 13 05:52:29.225735 systemd[1]: session-24.scope: Deactivated successfully. Oct 13 05:52:29.226487 systemd-logind[1889]: Session 24 logged out. Waiting for processes to exit. Oct 13 05:52:29.227359 systemd-logind[1889]: Removed session 24. Oct 13 05:52:29.333138 systemd[1]: Started sshd@22-10.200.4.18:22-10.200.16.10:60586.service - OpenSSH per-connection server daemon (10.200.16.10:60586). Oct 13 05:52:29.967008 sshd[15423]: Accepted publickey for core from 10.200.16.10 port 60586 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:52:29.968144 sshd-session[15423]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:52:29.972363 systemd-logind[1889]: New session 25 of user core. Oct 13 05:52:29.985979 systemd[1]: Started session-25.scope - Session 25 of User core. Oct 13 05:52:30.410433 sshd[15426]: Connection closed by 10.200.16.10 port 60586 Oct 13 05:52:30.410803 sshd-session[15423]: pam_unix(sshd:session): session closed for user core Oct 13 05:52:30.414582 systemd[1]: sshd@22-10.200.4.18:22-10.200.16.10:60586.service: Deactivated successfully. Oct 13 05:52:30.416460 systemd[1]: session-25.scope: Deactivated successfully. Oct 13 05:52:30.417217 systemd-logind[1889]: Session 25 logged out. Waiting for processes to exit. Oct 13 05:52:30.418216 systemd-logind[1889]: Removed session 25. Oct 13 05:52:30.519115 systemd[1]: Started sshd@23-10.200.4.18:22-10.200.16.10:51882.service - OpenSSH per-connection server daemon (10.200.16.10:51882). Oct 13 05:52:31.147233 sshd[15432]: Accepted publickey for core from 10.200.16.10 port 51882 ssh2: RSA SHA256:f/WAkduZnWOijI3ei1BxeZp63WTgiswFUkbydkL13TU Oct 13 05:52:32.380292 sshd-session[15432]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 05:52:32.384943 systemd-logind[1889]: New session 26 of user core. Oct 13 05:52:32.402986 systemd[1]: Started session-26.scope - Session 26 of User core. Oct 13 05:52:34.263288 sshd[15435]: Connection closed by 10.200.16.10 port 51882 Oct 13 05:52:34.263686 sshd-session[15432]: pam_unix(sshd:session): session closed for user core Oct 13 05:52:34.267470 systemd[1]: sshd@23-10.200.4.18:22-10.200.16.10:51882.service: Deactivated successfully. Oct 13 05:52:34.269095 systemd[1]: session-26.scope: Deactivated successfully. Oct 13 05:52:34.269294 systemd[1]: session-26.scope: Consumed 1.460s CPU time, 14M memory peak. Oct 13 05:52:34.269773 systemd-logind[1889]: Session 26 logged out. Waiting for processes to exit. Oct 13 05:52:34.270677 systemd-logind[1889]: Removed session 26.