Oct 13 00:06:51.198516 kernel: Linux version 6.6.110-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.43 p3) 2.43.1) #1 SMP PREEMPT_DYNAMIC Sun Oct 12 22:36:11 -00 2025 Oct 13 00:06:51.198544 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=f22c322725201bc05beb6be7a3cc1733cdde87d870355f876093fa075b62debf Oct 13 00:06:51.198554 kernel: BIOS-provided physical RAM map: Oct 13 00:06:51.198561 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Oct 13 00:06:51.198567 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Oct 13 00:06:51.198574 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Oct 13 00:06:51.198582 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Oct 13 00:06:51.198591 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Oct 13 00:06:51.198599 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Oct 13 00:06:51.198606 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Oct 13 00:06:51.198612 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Oct 13 00:06:51.198619 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Oct 13 00:06:51.198626 kernel: printk: bootconsole [earlyser0] enabled Oct 13 00:06:51.198633 kernel: NX (Execute Disable) protection: active Oct 13 00:06:51.198644 kernel: APIC: Static calls initialized Oct 13 00:06:51.198652 kernel: efi: EFI v2.7 by Microsoft Oct 13 00:06:51.198660 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Oct 13 00:06:51.198667 kernel: random: crng init done Oct 13 00:06:51.198675 kernel: secureboot: Secure boot disabled Oct 13 00:06:51.198683 kernel: SMBIOS 3.1.0 present. Oct 13 00:06:51.198690 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Oct 13 00:06:51.198698 kernel: Hypervisor detected: Microsoft Hyper-V Oct 13 00:06:51.198706 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Oct 13 00:06:51.198713 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Oct 13 00:06:51.198723 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Oct 13 00:06:51.198731 kernel: Hyper-V: Using hypercall for remote TLB flush Oct 13 00:06:51.198738 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Oct 13 00:06:51.198746 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Oct 13 00:06:51.198754 kernel: tsc: Marking TSC unstable due to running on Hyper-V Oct 13 00:06:51.198762 kernel: tsc: Detected 2593.993 MHz processor Oct 13 00:06:51.198770 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Oct 13 00:06:51.198778 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Oct 13 00:06:51.198786 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Oct 13 00:06:51.198796 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Oct 13 00:06:51.198804 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Oct 13 00:06:51.198811 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Oct 13 00:06:51.198819 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Oct 13 00:06:51.198826 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Oct 13 00:06:51.198834 kernel: Using GB pages for direct mapping Oct 13 00:06:51.198842 kernel: ACPI: Early table checksum verification disabled Oct 13 00:06:51.198854 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Oct 13 00:06:51.198864 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 00:06:51.198873 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 00:06:51.198881 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Oct 13 00:06:51.198889 kernel: ACPI: FACS 0x000000003FFFE000 000040 Oct 13 00:06:51.198897 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 00:06:51.198905 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 00:06:51.198916 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 00:06:51.198924 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 00:06:51.198932 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 00:06:51.198940 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 00:06:51.198949 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 00:06:51.198957 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Oct 13 00:06:51.198965 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Oct 13 00:06:51.198973 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Oct 13 00:06:51.198984 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Oct 13 00:06:51.198992 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Oct 13 00:06:51.199000 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Oct 13 00:06:51.199008 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Oct 13 00:06:51.199016 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Oct 13 00:06:51.199024 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Oct 13 00:06:51.199033 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Oct 13 00:06:51.199041 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0 Oct 13 00:06:51.199049 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0 Oct 13 00:06:51.199059 kernel: SRAT: PXM 0 -> APIC 0x02 -> Node 0 Oct 13 00:06:51.199067 kernel: SRAT: PXM 0 -> APIC 0x03 -> Node 0 Oct 13 00:06:51.199076 kernel: SRAT: PXM 0 -> APIC 0x04 -> Node 0 Oct 13 00:06:51.199084 kernel: SRAT: PXM 0 -> APIC 0x05 -> Node 0 Oct 13 00:06:51.199092 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Oct 13 00:06:51.199100 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Oct 13 00:06:51.199108 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Oct 13 00:06:51.199139 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Oct 13 00:06:51.199148 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Oct 13 00:06:51.199159 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Oct 13 00:06:51.199168 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Oct 13 00:06:51.199176 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Oct 13 00:06:51.199184 kernel: NUMA: Node 0 [mem 0x00000000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00000000-0xfdfffffff] Oct 13 00:06:51.199192 kernel: NUMA: Node 0 [mem 0x00000000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00000000-0x5cbfffffff] Oct 13 00:06:51.199201 kernel: NODE_DATA(0) allocated [mem 0x5cbfff9000-0x5cbfffefff] Oct 13 00:06:51.199209 kernel: Zone ranges: Oct 13 00:06:51.199217 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Oct 13 00:06:51.199225 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Oct 13 00:06:51.199236 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Oct 13 00:06:51.199244 kernel: Movable zone start for each node Oct 13 00:06:51.199252 kernel: Early memory node ranges Oct 13 00:06:51.199260 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Oct 13 00:06:51.199268 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Oct 13 00:06:51.199276 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Oct 13 00:06:51.199285 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Oct 13 00:06:51.199293 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Oct 13 00:06:51.199301 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Oct 13 00:06:51.199312 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Oct 13 00:06:51.199320 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Oct 13 00:06:51.199328 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Oct 13 00:06:51.199336 kernel: ACPI: PM-Timer IO Port: 0x408 Oct 13 00:06:51.199344 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Oct 13 00:06:51.199352 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Oct 13 00:06:51.199360 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Oct 13 00:06:51.199369 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Oct 13 00:06:51.199377 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Oct 13 00:06:51.199387 kernel: smpboot: Allowing 6 CPUs, 0 hotplug CPUs Oct 13 00:06:51.199396 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Oct 13 00:06:51.199404 kernel: Booting paravirtualized kernel on Hyper-V Oct 13 00:06:51.199412 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Oct 13 00:06:51.199420 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Oct 13 00:06:51.199428 kernel: percpu: Embedded 58 pages/cpu s196712 r8192 d32664 u262144 Oct 13 00:06:51.199436 kernel: pcpu-alloc: s196712 r8192 d32664 u262144 alloc=1*2097152 Oct 13 00:06:51.199444 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Oct 13 00:06:51.199452 kernel: Hyper-V: PV spinlocks enabled Oct 13 00:06:51.199463 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Oct 13 00:06:51.199472 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=f22c322725201bc05beb6be7a3cc1733cdde87d870355f876093fa075b62debf Oct 13 00:06:51.199481 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Oct 13 00:06:51.199489 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Oct 13 00:06:51.199497 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Oct 13 00:06:51.199505 kernel: Fallback order for Node 0: 0 Oct 13 00:06:51.199513 kernel: Built 1 zonelists, mobility grouping on. Total pages: 28899610 Oct 13 00:06:51.199522 kernel: Policy zone: Normal Oct 13 00:06:51.199532 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 13 00:06:51.199549 kernel: software IO TLB: area num 8. Oct 13 00:06:51.199558 kernel: Memory: 115328448K/117439364K available (14336K kernel code, 2288K rwdata, 22872K rodata, 43512K init, 1568K bss, 2110660K reserved, 0K cma-reserved) Oct 13 00:06:51.199569 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Oct 13 00:06:51.199578 kernel: Kernel/User page tables isolation: enabled Oct 13 00:06:51.199586 kernel: ftrace: allocating 37951 entries in 149 pages Oct 13 00:06:51.199595 kernel: ftrace: allocated 149 pages with 4 groups Oct 13 00:06:51.199603 kernel: Dynamic Preempt: voluntary Oct 13 00:06:51.199612 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 13 00:06:51.199621 kernel: rcu: RCU event tracing is enabled. Oct 13 00:06:51.199629 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Oct 13 00:06:51.199638 kernel: Trampoline variant of Tasks RCU enabled. Oct 13 00:06:51.199650 kernel: Rude variant of Tasks RCU enabled. Oct 13 00:06:51.199658 kernel: Tracing variant of Tasks RCU enabled. Oct 13 00:06:51.199667 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 13 00:06:51.199675 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Oct 13 00:06:51.199686 kernel: Using NULL legacy PIC Oct 13 00:06:51.199695 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Oct 13 00:06:51.199704 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Oct 13 00:06:51.199712 kernel: Console: colour dummy device 80x25 Oct 13 00:06:51.199721 kernel: printk: console [tty1] enabled Oct 13 00:06:51.199729 kernel: printk: console [ttyS0] enabled Oct 13 00:06:51.199738 kernel: printk: bootconsole [earlyser0] disabled Oct 13 00:06:51.199747 kernel: ACPI: Core revision 20230628 Oct 13 00:06:51.199755 kernel: Failed to register legacy timer interrupt Oct 13 00:06:51.199764 kernel: APIC: Switch to symmetric I/O mode setup Oct 13 00:06:51.199775 kernel: Hyper-V: enabling crash_kexec_post_notifiers Oct 13 00:06:51.199783 kernel: Hyper-V: Using IPI hypercalls Oct 13 00:06:51.199792 kernel: APIC: send_IPI() replaced with hv_send_ipi() Oct 13 00:06:51.199801 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Oct 13 00:06:51.199809 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Oct 13 00:06:51.199818 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Oct 13 00:06:51.199826 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Oct 13 00:06:51.199835 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Oct 13 00:06:51.199843 kernel: Hyper-V: Using enlightened APIC (xapic mode) Oct 13 00:06:51.199854 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Oct 13 00:06:51.199863 kernel: APIC: read() replaced with hv_apic_read() Oct 13 00:06:51.199871 kernel: APIC: write() replaced with hv_apic_write() Oct 13 00:06:51.199880 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Oct 13 00:06:51.199888 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Oct 13 00:06:51.199897 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593993) Oct 13 00:06:51.199906 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Oct 13 00:06:51.199914 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Oct 13 00:06:51.199923 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Oct 13 00:06:51.199934 kernel: Spectre V2 : Mitigation: Retpolines Oct 13 00:06:51.199942 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Oct 13 00:06:51.199950 kernel: Speculative Store Bypass: Vulnerable Oct 13 00:06:51.199959 kernel: MDS: Mitigation: Clear CPU buffers Oct 13 00:06:51.199973 kernel: TAA: Mitigation: Clear CPU buffers Oct 13 00:06:51.199983 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Oct 13 00:06:51.200029 kernel: active return thunk: its_return_thunk Oct 13 00:06:51.200043 kernel: ITS: Mitigation: Aligned branch/return thunks Oct 13 00:06:51.200051 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Oct 13 00:06:51.200060 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Oct 13 00:06:51.200068 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Oct 13 00:06:51.200082 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Oct 13 00:06:51.200091 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Oct 13 00:06:51.200099 kernel: Freeing SMP alternatives memory: 32K Oct 13 00:06:51.200108 kernel: pid_max: default: 32768 minimum: 301 Oct 13 00:06:51.200130 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Oct 13 00:06:51.200139 kernel: landlock: Up and running. Oct 13 00:06:51.200147 kernel: SELinux: Initializing. Oct 13 00:06:51.200156 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Oct 13 00:06:51.200164 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Oct 13 00:06:51.200173 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Oct 13 00:06:51.200181 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Oct 13 00:06:51.200194 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Oct 13 00:06:51.200202 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Oct 13 00:06:51.200211 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Oct 13 00:06:51.200220 kernel: signal: max sigframe size: 1776 Oct 13 00:06:51.200228 kernel: rcu: Hierarchical SRCU implementation. Oct 13 00:06:51.200244 kernel: rcu: Max phase no-delay instances is 400. Oct 13 00:06:51.200254 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Oct 13 00:06:51.200262 kernel: smp: Bringing up secondary CPUs ... Oct 13 00:06:51.200271 kernel: smpboot: x86: Booting SMP configuration: Oct 13 00:06:51.200283 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Oct 13 00:06:51.200291 kernel: smp: Brought up 1 node, 6 CPUs Oct 13 00:06:51.200300 kernel: smpboot: Max logical packages: 1 Oct 13 00:06:51.200309 kernel: smpboot: Total of 6 processors activated (31127.91 BogoMIPS) Oct 13 00:06:51.200317 kernel: devtmpfs: initialized Oct 13 00:06:51.200326 kernel: x86/mm: Memory block size: 1024MB Oct 13 00:06:51.200335 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Oct 13 00:06:51.200344 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 13 00:06:51.200352 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Oct 13 00:06:51.200364 kernel: pinctrl core: initialized pinctrl subsystem Oct 13 00:06:51.200372 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Oct 13 00:06:51.200381 kernel: audit: initializing netlink subsys (disabled) Oct 13 00:06:51.200390 kernel: audit: type=2000 audit(1760314009.029:1): state=initialized audit_enabled=0 res=1 Oct 13 00:06:51.200398 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 13 00:06:51.200407 kernel: thermal_sys: Registered thermal governor 'user_space' Oct 13 00:06:51.200416 kernel: cpuidle: using governor menu Oct 13 00:06:51.200424 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 13 00:06:51.200433 kernel: dca service started, version 1.12.1 Oct 13 00:06:51.200444 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Oct 13 00:06:51.200453 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Oct 13 00:06:51.200461 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Oct 13 00:06:51.200470 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Oct 13 00:06:51.200479 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Oct 13 00:06:51.200487 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Oct 13 00:06:51.200496 kernel: ACPI: Added _OSI(Module Device) Oct 13 00:06:51.200505 kernel: ACPI: Added _OSI(Processor Device) Oct 13 00:06:51.200513 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 13 00:06:51.200524 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 13 00:06:51.200537 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Oct 13 00:06:51.200554 kernel: ACPI: Interpreter enabled Oct 13 00:06:51.200567 kernel: ACPI: PM: (supports S0 S5) Oct 13 00:06:51.200584 kernel: ACPI: Using IOAPIC for interrupt routing Oct 13 00:06:51.200594 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Oct 13 00:06:51.200603 kernel: PCI: Ignoring E820 reservations for host bridge windows Oct 13 00:06:51.200612 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Oct 13 00:06:51.200620 kernel: iommu: Default domain type: Translated Oct 13 00:06:51.200632 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Oct 13 00:06:51.200641 kernel: efivars: Registered efivars operations Oct 13 00:06:51.200649 kernel: PCI: Using ACPI for IRQ routing Oct 13 00:06:51.200658 kernel: PCI: System does not support PCI Oct 13 00:06:51.200667 kernel: vgaarb: loaded Oct 13 00:06:51.200675 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Oct 13 00:06:51.200684 kernel: VFS: Disk quotas dquot_6.6.0 Oct 13 00:06:51.200693 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 13 00:06:51.200701 kernel: pnp: PnP ACPI init Oct 13 00:06:51.200713 kernel: pnp: PnP ACPI: found 3 devices Oct 13 00:06:51.200721 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Oct 13 00:06:51.200730 kernel: NET: Registered PF_INET protocol family Oct 13 00:06:51.200739 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 13 00:06:51.200748 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Oct 13 00:06:51.200756 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Oct 13 00:06:51.200765 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 13 00:06:51.200774 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Oct 13 00:06:51.200783 kernel: TCP: Hash tables configured (established 524288 bind 65536) Oct 13 00:06:51.200794 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Oct 13 00:06:51.200802 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Oct 13 00:06:51.200811 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Oct 13 00:06:51.200820 kernel: NET: Registered PF_XDP protocol family Oct 13 00:06:51.200828 kernel: PCI: CLS 0 bytes, default 64 Oct 13 00:06:51.200837 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Oct 13 00:06:51.200846 kernel: software IO TLB: mapped [mem 0x000000003ae61000-0x000000003ee61000] (64MB) Oct 13 00:06:51.200854 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Oct 13 00:06:51.200863 kernel: Initialise system trusted keyrings Oct 13 00:06:51.200874 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Oct 13 00:06:51.200883 kernel: Key type asymmetric registered Oct 13 00:06:51.200891 kernel: Asymmetric key parser 'x509' registered Oct 13 00:06:51.200900 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Oct 13 00:06:51.200908 kernel: io scheduler mq-deadline registered Oct 13 00:06:51.200917 kernel: io scheduler kyber registered Oct 13 00:06:51.200925 kernel: io scheduler bfq registered Oct 13 00:06:51.200934 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Oct 13 00:06:51.200943 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 13 00:06:51.200954 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Oct 13 00:06:51.200963 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Oct 13 00:06:51.200971 kernel: i8042: PNP: No PS/2 controller found. Oct 13 00:06:51.201151 kernel: rtc_cmos 00:02: registered as rtc0 Oct 13 00:06:51.201266 kernel: rtc_cmos 00:02: setting system clock to 2025-10-13T00:06:50 UTC (1760314010) Oct 13 00:06:51.201384 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Oct 13 00:06:51.201399 kernel: intel_pstate: CPU model not supported Oct 13 00:06:51.201413 kernel: efifb: probing for efifb Oct 13 00:06:51.201422 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Oct 13 00:06:51.201430 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Oct 13 00:06:51.201439 kernel: efifb: scrolling: redraw Oct 13 00:06:51.201448 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Oct 13 00:06:51.201456 kernel: Console: switching to colour frame buffer device 128x48 Oct 13 00:06:51.201465 kernel: fb0: EFI VGA frame buffer device Oct 13 00:06:51.201474 kernel: pstore: Using crash dump compression: deflate Oct 13 00:06:51.201482 kernel: pstore: Registered efi_pstore as persistent store backend Oct 13 00:06:51.201494 kernel: NET: Registered PF_INET6 protocol family Oct 13 00:06:51.201502 kernel: Segment Routing with IPv6 Oct 13 00:06:51.201511 kernel: In-situ OAM (IOAM) with IPv6 Oct 13 00:06:51.201520 kernel: NET: Registered PF_PACKET protocol family Oct 13 00:06:51.201528 kernel: Key type dns_resolver registered Oct 13 00:06:51.201537 kernel: IPI shorthand broadcast: enabled Oct 13 00:06:51.201546 kernel: sched_clock: Marking stable (1657005900, 101479500)->(2091362900, -332877500) Oct 13 00:06:51.201554 kernel: registered taskstats version 1 Oct 13 00:06:51.201563 kernel: Loading compiled-in X.509 certificates Oct 13 00:06:51.201572 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.110-flatcar: 50d5efdc867bacb346c7c22eb5069c0bfc15416d' Oct 13 00:06:51.201583 kernel: Key type .fscrypt registered Oct 13 00:06:51.201591 kernel: Key type fscrypt-provisioning registered Oct 13 00:06:51.201600 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 13 00:06:51.201609 kernel: ima: Allocated hash algorithm: sha1 Oct 13 00:06:51.201617 kernel: ima: No architecture policies found Oct 13 00:06:51.201626 kernel: clk: Disabling unused clocks Oct 13 00:06:51.201635 kernel: Freeing unused kernel image (initmem) memory: 43512K Oct 13 00:06:51.201643 kernel: Write protecting the kernel read-only data: 38912k Oct 13 00:06:51.201655 kernel: Freeing unused kernel image (rodata/data gap) memory: 1704K Oct 13 00:06:51.201663 kernel: Run /init as init process Oct 13 00:06:51.201672 kernel: with arguments: Oct 13 00:06:51.201680 kernel: /init Oct 13 00:06:51.201689 kernel: with environment: Oct 13 00:06:51.201697 kernel: HOME=/ Oct 13 00:06:51.201706 kernel: TERM=linux Oct 13 00:06:51.201714 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 13 00:06:51.201724 systemd[1]: Successfully made /usr/ read-only. Oct 13 00:06:51.201738 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 00:06:51.201748 systemd[1]: Detected virtualization microsoft. Oct 13 00:06:51.201757 systemd[1]: Detected architecture x86-64. Oct 13 00:06:51.201765 systemd[1]: Running in initrd. Oct 13 00:06:51.201774 systemd[1]: No hostname configured, using default hostname. Oct 13 00:06:51.201784 systemd[1]: Hostname set to . Oct 13 00:06:51.201792 systemd[1]: Initializing machine ID from random generator. Oct 13 00:06:51.201804 systemd[1]: Queued start job for default target initrd.target. Oct 13 00:06:51.201813 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 13 00:06:51.201822 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 13 00:06:51.201832 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Oct 13 00:06:51.201841 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 13 00:06:51.201850 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Oct 13 00:06:51.201860 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Oct 13 00:06:51.201873 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 00:06:51.201882 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Oct 13 00:06:51.201891 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 13 00:06:51.201900 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 13 00:06:51.201909 systemd[1]: Reached target paths.target - Path Units. Oct 13 00:06:51.201918 systemd[1]: Reached target slices.target - Slice Units. Oct 13 00:06:51.201927 systemd[1]: Reached target swap.target - Swaps. Oct 13 00:06:51.201936 systemd[1]: Reached target timers.target - Timer Units. Oct 13 00:06:51.201945 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Oct 13 00:06:51.201957 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 13 00:06:51.201966 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Oct 13 00:06:51.201975 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Oct 13 00:06:51.201984 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 13 00:06:51.201993 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 13 00:06:51.202002 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 13 00:06:51.202011 systemd[1]: Reached target sockets.target - Socket Units. Oct 13 00:06:51.202020 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Oct 13 00:06:51.202032 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 13 00:06:51.202041 systemd[1]: Finished network-cleanup.service - Network Cleanup. Oct 13 00:06:51.202050 systemd[1]: Starting systemd-fsck-usr.service... Oct 13 00:06:51.202059 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 13 00:06:51.202068 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 13 00:06:51.202096 systemd-journald[204]: Collecting audit messages is disabled. Oct 13 00:06:51.202142 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 13 00:06:51.202153 systemd-journald[204]: Journal started Oct 13 00:06:51.202174 systemd-journald[204]: Runtime Journal (/run/log/journal/07de74adb64549bf92d8bf8225f37045) is 8M, max 2.2G, 2.1G free. Oct 13 00:06:51.210419 systemd[1]: Started systemd-journald.service - Journal Service. Oct 13 00:06:51.213814 systemd-modules-load[207]: Inserted module 'overlay' Oct 13 00:06:51.214229 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Oct 13 00:06:51.215627 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 13 00:06:51.217062 systemd[1]: Finished systemd-fsck-usr.service. Oct 13 00:06:51.219204 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 13 00:06:51.251859 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 13 00:06:51.257328 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 13 00:06:51.266196 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 13 00:06:51.282095 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 13 00:06:51.284772 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 13 00:06:51.285507 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 13 00:06:51.315200 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 13 00:06:51.329037 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 00:06:51.329061 kernel: Bridge firewalling registered Oct 13 00:06:51.331953 systemd-modules-load[207]: Inserted module 'br_netfilter' Oct 13 00:06:51.332922 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 13 00:06:51.347277 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 13 00:06:51.349126 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 13 00:06:51.360974 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Oct 13 00:06:51.365207 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 13 00:06:51.376758 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 13 00:06:51.392540 dracut-cmdline[242]: dracut-dracut-053 Oct 13 00:06:51.397856 dracut-cmdline[242]: Using kernel command line parameters: rd.driver.pre=btrfs 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=f22c322725201bc05beb6be7a3cc1733cdde87d870355f876093fa075b62debf Oct 13 00:06:51.410629 systemd-resolved[245]: Positive Trust Anchors: Oct 13 00:06:51.410637 systemd-resolved[245]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 13 00:06:51.410668 systemd-resolved[245]: 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 00:06:51.413204 systemd-resolved[245]: Defaulting to hostname 'linux'. Oct 13 00:06:51.414238 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 13 00:06:51.428249 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 13 00:06:51.530136 kernel: SCSI subsystem initialized Oct 13 00:06:51.540136 kernel: Loading iSCSI transport class v2.0-870. Oct 13 00:06:51.555142 kernel: iscsi: registered transport (tcp) Oct 13 00:06:51.579649 kernel: iscsi: registered transport (qla4xxx) Oct 13 00:06:51.579706 kernel: QLogic iSCSI HBA Driver Oct 13 00:06:51.619879 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Oct 13 00:06:51.634290 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Oct 13 00:06:51.666825 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Oct 13 00:06:51.666926 kernel: device-mapper: uevent: version 1.0.3 Oct 13 00:06:51.671325 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Oct 13 00:06:51.713143 kernel: raid6: avx2x4 gen() 31067 MB/s Oct 13 00:06:51.733138 kernel: raid6: avx2x2 gen() 30357 MB/s Oct 13 00:06:51.754230 kernel: raid6: avx2x1 gen() 27341 MB/s Oct 13 00:06:51.754294 kernel: raid6: using algorithm avx2x4 gen() 31067 MB/s Oct 13 00:06:51.776306 kernel: raid6: .... xor() 9495 MB/s, rmw enabled Oct 13 00:06:51.776366 kernel: raid6: using avx2x2 recovery algorithm Oct 13 00:06:51.801137 kernel: xor: automatically using best checksumming function avx Oct 13 00:06:51.959143 kernel: Btrfs loaded, zoned=no, fsverity=no Oct 13 00:06:51.971952 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Oct 13 00:06:51.989245 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 13 00:06:52.011807 systemd-udevd[430]: Using default interface naming scheme 'v255'. Oct 13 00:06:52.019749 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 13 00:06:52.041300 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Oct 13 00:06:52.055688 dracut-pre-trigger[436]: rd.md=0: removing MD RAID activation Oct 13 00:06:52.088318 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Oct 13 00:06:52.109315 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 13 00:06:52.170917 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 13 00:06:52.188282 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Oct 13 00:06:52.200522 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Oct 13 00:06:52.202489 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Oct 13 00:06:52.218819 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 13 00:06:52.220305 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 13 00:06:52.237970 kernel: cryptd: max_cpu_qlen set to 1000 Oct 13 00:06:52.246140 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Oct 13 00:06:52.254964 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 13 00:06:52.275205 kernel: AVX2 version of gcm_enc/dec engaged. Oct 13 00:06:52.275233 kernel: hv_vmbus: Vmbus version:4.0 Oct 13 00:06:52.255150 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 13 00:06:52.285624 kernel: AES CTR mode by8 optimization enabled Oct 13 00:06:52.262539 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 13 00:06:52.263186 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 13 00:06:52.315902 kernel: hv_vmbus: registering driver hyperv_keyboard Oct 13 00:06:52.315930 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 00:06:52.263377 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 13 00:06:52.273832 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Oct 13 00:06:52.285502 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 13 00:06:52.286690 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Oct 13 00:06:52.345273 kernel: pps_core: LinuxPPS API ver. 1 registered Oct 13 00:06:52.345316 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Oct 13 00:06:52.287048 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Oct 13 00:06:52.320334 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 13 00:06:52.320432 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 13 00:06:52.351521 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 13 00:06:52.372049 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 13 00:06:52.372202 kernel: hv_vmbus: registering driver hid_hyperv Oct 13 00:06:52.376132 kernel: hv_vmbus: registering driver hv_netvsc Oct 13 00:06:52.376162 kernel: hv_vmbus: registering driver hv_storvsc Oct 13 00:06:52.376174 kernel: PTP clock support registered Oct 13 00:06:52.377433 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Oct 13 00:06:52.377460 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Oct 13 00:06:52.394146 kernel: hv_utils: Registering HyperV Utility Driver Oct 13 00:06:52.396133 kernel: scsi host0: storvsc_host_t Oct 13 00:06:52.396186 kernel: hv_vmbus: registering driver hv_utils Oct 13 00:06:52.396201 kernel: scsi host1: storvsc_host_t Oct 13 00:06:52.400139 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Oct 13 00:06:52.402175 kernel: hv_utils: Heartbeat IC version 3.0 Oct 13 00:06:52.407872 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Oct 13 00:06:52.407934 kernel: hv_utils: Shutdown IC version 3.2 Oct 13 00:06:52.414132 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Oct 13 00:06:52.414196 kernel: hv_utils: TimeSync IC version 4.0 Oct 13 00:06:52.498460 systemd-resolved[245]: Clock change detected. Flushing caches. Oct 13 00:06:52.530061 kernel: hv_vmbus: registering driver hv_pci Oct 13 00:06:52.530100 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Oct 13 00:06:52.530346 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Oct 13 00:06:52.530507 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Oct 13 00:06:52.530668 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Oct 13 00:06:52.521251 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 13 00:06:52.540275 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Oct 13 00:06:52.546192 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 Oct 13 00:06:52.551180 kernel: pci 0001:00:00.0: reg 0x10: [mem 0x41000000-0x41ffffff] Oct 13 00:06:52.551208 kernel: pci 0001:00:00.0: reg 0x14: [mem 0x1000000000-0x13ffffffff 64bit pref] Oct 13 00:06:52.557024 kernel: pci 0001:00:00.0: reg 0x1c: [mem 0x1400000000-0x1401ffffff 64bit pref] Oct 13 00:06:52.565149 kernel: pci 0001:00:00.0: Enabling HDA controller Oct 13 00:06:52.572257 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Oct 13 00:06:52.572502 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Oct 13 00:06:52.574135 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Oct 13 00:06:52.577370 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 13 00:06:52.600963 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Oct 13 00:06:52.601176 kernel: pci 0001:00:00.0: BAR 1: assigned [mem 0x1000000000-0x13ffffffff 64bit pref] Oct 13 00:06:52.601359 kernel: pci 0001:00:00.0: BAR 3: assigned [mem 0x1400000000-0x1401ffffff 64bit pref] Oct 13 00:06:52.601501 kernel: pci 0001:00:00.0: BAR 0: assigned [mem 0x41000000-0x41ffffff] Oct 13 00:06:52.620353 kernel: sd 0:0:0:1: [sda] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Oct 13 00:06:52.621542 kernel: sd 0:0:0:0: [sdb] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Oct 13 00:06:52.621887 kernel: sd 0:0:0:1: [sda] 4096-byte physical blocks Oct 13 00:06:52.622108 kernel: sd 0:0:0:0: [sdb] 4096-byte physical blocks Oct 13 00:06:52.622330 kernel: sd 0:0:0:0: [sdb] Write Protect is off Oct 13 00:06:52.627849 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 13 00:06:52.657825 kernel: sd 0:0:0:1: [sda] Write Protect is off Oct 13 00:06:52.658055 kernel: sd 0:0:0:0: [sdb] Mode Sense: 0f 00 10 00 Oct 13 00:06:52.658352 kernel: sd 0:0:0:1: [sda] Mode Sense: 0f 00 10 00 Oct 13 00:06:52.658513 kernel: sd 0:0:0:1: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Oct 13 00:06:52.658660 kernel: sd 0:0:0:0: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Oct 13 00:06:52.658812 kernel: sda: sda1 Oct 13 00:06:52.658826 kernel: sd 0:0:0:1: [sda] Attached SCSI disk Oct 13 00:06:52.658972 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Oct 13 00:06:52.658991 kernel: sd 0:0:0:0: [sdb] Attached SCSI disk Oct 13 00:06:53.454139 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sdb6 scanned by (udev-worker) (486) Oct 13 00:06:53.461753 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Oct 13 00:06:53.483472 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Oct 13 00:06:53.513420 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Oct 13 00:06:53.552144 kernel: BTRFS: device fsid 1b3281fd-66ec-42df-bcbd-268fe4ae17be devid 1 transid 38 /dev/sdb3 scanned by (udev-worker) (497) Oct 13 00:06:53.571523 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Oct 13 00:06:53.573022 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Oct 13 00:06:53.602241 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Oct 13 00:06:53.623142 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Oct 13 00:06:53.634132 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Oct 13 00:06:54.644062 disk-uuid[639]: The operation has completed successfully. Oct 13 00:06:54.647635 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Oct 13 00:06:55.871098 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 13 00:06:55.871231 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Oct 13 00:06:55.899281 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Oct 13 00:06:55.909095 sh[726]: Success Oct 13 00:06:55.930136 kernel: device-mapper: verity: sha256 using implementation "sha256-avx2" Oct 13 00:06:56.247147 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Oct 13 00:06:56.263477 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Oct 13 00:06:56.265530 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Oct 13 00:06:56.295042 kernel: BTRFS info (device dm-0): first mount of filesystem 1b3281fd-66ec-42df-bcbd-268fe4ae17be Oct 13 00:06:56.295079 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Oct 13 00:06:56.299843 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Oct 13 00:06:56.303476 kernel: BTRFS info (device dm-0): disabling log replay at mount time Oct 13 00:06:56.306592 kernel: BTRFS info (device dm-0): using free space tree Oct 13 00:06:56.747094 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Oct 13 00:06:56.749169 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Oct 13 00:06:56.768248 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Oct 13 00:06:56.770311 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Oct 13 00:06:56.814461 kernel: BTRFS info (device sdb6): first mount of filesystem 2fdf6342-252c-4019-93cf-d9e28c1a91b4 Oct 13 00:06:56.814516 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Oct 13 00:06:56.817559 kernel: BTRFS info (device sdb6): using free space tree Oct 13 00:06:56.870347 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 13 00:06:56.891511 kernel: BTRFS info (device sdb6): auto enabling async discard Oct 13 00:06:56.891536 kernel: BTRFS info (device sdb6): last unmount of filesystem 2fdf6342-252c-4019-93cf-d9e28c1a91b4 Oct 13 00:06:56.897258 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 13 00:06:56.901617 systemd[1]: Finished ignition-setup.service - Ignition (setup). Oct 13 00:06:56.912170 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Oct 13 00:06:56.941283 systemd-networkd[903]: lo: Link UP Oct 13 00:06:56.941293 systemd-networkd[903]: lo: Gained carrier Oct 13 00:06:56.942446 systemd-networkd[903]: Enumeration completed Oct 13 00:06:56.942605 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 13 00:06:56.942814 systemd-networkd[903]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 13 00:06:56.942819 systemd-networkd[903]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 13 00:06:56.943607 systemd-networkd[903]: eth0: Link UP Oct 13 00:06:56.943779 systemd-networkd[903]: eth0: Gained carrier Oct 13 00:06:56.943788 systemd-networkd[903]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 13 00:06:56.948150 systemd[1]: Reached target network.target - Network. Oct 13 00:06:56.997171 systemd-networkd[903]: eth0: DHCPv4 address 10.200.8.12/24, gateway 10.200.8.1 acquired from 168.63.129.16 Oct 13 00:06:58.120137 ignition[908]: Ignition 2.20.0 Oct 13 00:06:58.120156 ignition[908]: Stage: fetch-offline Oct 13 00:06:58.120201 ignition[908]: no configs at "/usr/lib/ignition/base.d" Oct 13 00:06:58.120211 ignition[908]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 13 00:06:58.120318 ignition[908]: parsed url from cmdline: "" Oct 13 00:06:58.120322 ignition[908]: no config URL provided Oct 13 00:06:58.120328 ignition[908]: reading system config file "/usr/lib/ignition/user.ign" Oct 13 00:06:58.120339 ignition[908]: no config at "/usr/lib/ignition/user.ign" Oct 13 00:06:58.120345 ignition[908]: failed to fetch config: resource requires networking Oct 13 00:06:58.120525 ignition[908]: Ignition finished successfully Oct 13 00:06:58.147409 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Oct 13 00:06:58.167259 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Oct 13 00:06:58.179601 ignition[921]: Ignition 2.20.0 Oct 13 00:06:58.179613 ignition[921]: Stage: fetch Oct 13 00:06:58.179777 ignition[921]: no configs at "/usr/lib/ignition/base.d" Oct 13 00:06:58.179791 ignition[921]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 13 00:06:58.179883 ignition[921]: parsed url from cmdline: "" Oct 13 00:06:58.179887 ignition[921]: no config URL provided Oct 13 00:06:58.179893 ignition[921]: reading system config file "/usr/lib/ignition/user.ign" Oct 13 00:06:58.179904 ignition[921]: no config at "/usr/lib/ignition/user.ign" Oct 13 00:06:58.179928 ignition[921]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Oct 13 00:06:58.268933 ignition[921]: GET result: OK Oct 13 00:06:58.268977 ignition[921]: config has been read from IMDS userdata Oct 13 00:06:58.268988 ignition[921]: parsing config with SHA512: e043ea29aae31e0509cf4831371925f87170ee8bb893d32b4228d7a6847f838de5baf5a257862e81b1292a2d782b1b57c7ee80fe4795a5cd951d3e346ba8a4db Oct 13 00:06:58.273782 unknown[921]: fetched base config from "system" Oct 13 00:06:58.273995 ignition[921]: fetch: fetch complete Oct 13 00:06:58.273812 unknown[921]: fetched base config from "system" Oct 13 00:06:58.274001 ignition[921]: fetch: fetch passed Oct 13 00:06:58.273818 unknown[921]: fetched user config from "azure" Oct 13 00:06:58.274044 ignition[921]: Ignition finished successfully Oct 13 00:06:58.279946 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Oct 13 00:06:58.302305 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Oct 13 00:06:58.323255 ignition[931]: Ignition 2.20.0 Oct 13 00:06:58.323266 ignition[931]: Stage: kargs Oct 13 00:06:58.323433 ignition[931]: no configs at "/usr/lib/ignition/base.d" Oct 13 00:06:58.323446 ignition[931]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 13 00:06:58.331861 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Oct 13 00:06:58.323979 ignition[931]: kargs: kargs passed Oct 13 00:06:58.324021 ignition[931]: Ignition finished successfully Oct 13 00:06:58.351259 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Oct 13 00:06:58.362996 ignition[940]: Ignition 2.20.0 Oct 13 00:06:58.363007 ignition[940]: Stage: disks Oct 13 00:06:58.363193 ignition[940]: no configs at "/usr/lib/ignition/base.d" Oct 13 00:06:58.363207 ignition[940]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 13 00:06:58.368814 systemd[1]: Finished ignition-disks.service - Ignition (disks). Oct 13 00:06:58.363750 ignition[940]: disks: disks passed Oct 13 00:06:58.374936 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Oct 13 00:06:58.363793 ignition[940]: Ignition finished successfully Oct 13 00:06:58.376943 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Oct 13 00:06:58.388339 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 13 00:06:58.396215 systemd[1]: Reached target sysinit.target - System Initialization. Oct 13 00:06:58.403656 systemd[1]: Reached target basic.target - Basic System. Oct 13 00:06:58.433270 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Oct 13 00:06:58.517422 systemd-fsck[951]: ROOT: clean, 14/7326000 files, 477710/7359488 blocks Oct 13 00:06:58.524780 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Oct 13 00:06:58.543221 systemd[1]: Mounting sysroot.mount - /sysroot... Oct 13 00:06:58.644130 kernel: EXT4-fs (sdb9): mounted filesystem 02b6903b-203a-4032-98c5-29ee940136f6 r/w with ordered data mode. Quota mode: none. Oct 13 00:06:58.644861 systemd[1]: Mounted sysroot.mount - /sysroot. Oct 13 00:06:58.646902 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Oct 13 00:06:58.684215 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 13 00:06:58.696653 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Oct 13 00:06:58.700539 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/sdb6 scanned by mount (962) Oct 13 00:06:58.701541 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Oct 13 00:06:58.715942 kernel: BTRFS info (device sdb6): first mount of filesystem 2fdf6342-252c-4019-93cf-d9e28c1a91b4 Oct 13 00:06:58.715961 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Oct 13 00:06:58.719387 kernel: BTRFS info (device sdb6): using free space tree Oct 13 00:06:58.726008 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Oct 13 00:06:58.726050 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Oct 13 00:06:58.741396 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Oct 13 00:06:58.754198 kernel: BTRFS info (device sdb6): auto enabling async discard Oct 13 00:06:58.750643 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Oct 13 00:06:58.756261 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 13 00:06:58.961245 systemd-networkd[903]: eth0: Gained IPv6LL Oct 13 00:06:59.883408 coreos-metadata[964]: Oct 13 00:06:59.883 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Oct 13 00:06:59.891551 coreos-metadata[964]: Oct 13 00:06:59.891 INFO Fetch successful Oct 13 00:06:59.895258 coreos-metadata[964]: Oct 13 00:06:59.891 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Oct 13 00:06:59.903311 coreos-metadata[964]: Oct 13 00:06:59.899 INFO Fetch successful Oct 13 00:06:59.903311 coreos-metadata[964]: Oct 13 00:06:59.899 INFO wrote hostname ci-4230.2.4-a-01a3a7f97b to /sysroot/etc/hostname Oct 13 00:06:59.913934 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Oct 13 00:07:00.080046 initrd-setup-root[992]: cut: /sysroot/etc/passwd: No such file or directory Oct 13 00:07:00.119450 initrd-setup-root[999]: cut: /sysroot/etc/group: No such file or directory Oct 13 00:07:00.143655 initrd-setup-root[1006]: cut: /sysroot/etc/shadow: No such file or directory Oct 13 00:07:00.163712 initrd-setup-root[1013]: cut: /sysroot/etc/gshadow: No such file or directory Oct 13 00:07:01.697934 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Oct 13 00:07:01.715235 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Oct 13 00:07:01.727208 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Oct 13 00:07:01.739786 kernel: BTRFS info (device sdb6): last unmount of filesystem 2fdf6342-252c-4019-93cf-d9e28c1a91b4 Oct 13 00:07:01.735038 systemd[1]: sysroot-oem.mount: Deactivated successfully. Oct 13 00:07:01.761198 ignition[1080]: INFO : Ignition 2.20.0 Oct 13 00:07:01.761198 ignition[1080]: INFO : Stage: mount Oct 13 00:07:01.766840 ignition[1080]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 13 00:07:01.766840 ignition[1080]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 13 00:07:01.766840 ignition[1080]: INFO : mount: mount passed Oct 13 00:07:01.766840 ignition[1080]: INFO : Ignition finished successfully Oct 13 00:07:01.767925 systemd[1]: Finished ignition-mount.service - Ignition (mount). Oct 13 00:07:01.790224 systemd[1]: Starting ignition-files.service - Ignition (files)... Oct 13 00:07:01.792807 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Oct 13 00:07:01.803796 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 13 00:07:01.829145 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/sdb6 scanned by mount (1097) Oct 13 00:07:01.838181 kernel: BTRFS info (device sdb6): first mount of filesystem 2fdf6342-252c-4019-93cf-d9e28c1a91b4 Oct 13 00:07:01.838208 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Oct 13 00:07:01.841240 kernel: BTRFS info (device sdb6): using free space tree Oct 13 00:07:01.848132 kernel: BTRFS info (device sdb6): auto enabling async discard Oct 13 00:07:01.849795 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 13 00:07:01.877065 ignition[1114]: INFO : Ignition 2.20.0 Oct 13 00:07:01.877065 ignition[1114]: INFO : Stage: files Oct 13 00:07:01.882635 ignition[1114]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 13 00:07:01.882635 ignition[1114]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 13 00:07:01.882635 ignition[1114]: DEBUG : files: compiled without relabeling support, skipping Oct 13 00:07:01.923703 ignition[1114]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Oct 13 00:07:01.923703 ignition[1114]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Oct 13 00:07:02.106789 ignition[1114]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Oct 13 00:07:02.112113 ignition[1114]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Oct 13 00:07:02.112113 ignition[1114]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Oct 13 00:07:02.107556 unknown[1114]: wrote ssh authorized keys file for user: core Oct 13 00:07:02.180620 ignition[1114]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Oct 13 00:07:02.188308 ignition[1114]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Oct 13 00:07:02.188308 ignition[1114]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Oct 13 00:07:02.188308 ignition[1114]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Oct 13 00:07:02.188308 ignition[1114]: INFO : files: files passed Oct 13 00:07:02.188308 ignition[1114]: INFO : Ignition finished successfully Oct 13 00:07:02.182621 systemd[1]: Finished ignition-files.service - Ignition (files). Oct 13 00:07:02.211292 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Oct 13 00:07:02.213266 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Oct 13 00:07:02.230294 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 13 00:07:02.230391 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Oct 13 00:07:02.274657 initrd-setup-root-after-ignition[1146]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 13 00:07:02.274657 initrd-setup-root-after-ignition[1146]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Oct 13 00:07:02.282020 initrd-setup-root-after-ignition[1150]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 13 00:07:02.281583 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 13 00:07:02.291530 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Oct 13 00:07:02.324274 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Oct 13 00:07:02.350792 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 13 00:07:02.350910 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Oct 13 00:07:02.359952 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Oct 13 00:07:02.368952 systemd[1]: Reached target initrd.target - Initrd Default Target. Oct 13 00:07:02.376603 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Oct 13 00:07:02.377525 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Oct 13 00:07:02.402467 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 13 00:07:02.412712 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Oct 13 00:07:02.432343 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Oct 13 00:07:02.433996 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 13 00:07:02.442716 systemd[1]: Stopped target timers.target - Timer Units. Oct 13 00:07:02.457059 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 13 00:07:02.457225 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 13 00:07:02.469862 systemd[1]: Stopped target initrd.target - Initrd Default Target. Oct 13 00:07:02.471596 systemd[1]: Stopped target basic.target - Basic System. Oct 13 00:07:02.478897 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Oct 13 00:07:02.485054 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Oct 13 00:07:02.498153 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Oct 13 00:07:02.507026 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Oct 13 00:07:02.515344 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Oct 13 00:07:02.524008 systemd[1]: Stopped target sysinit.target - System Initialization. Oct 13 00:07:02.532942 systemd[1]: Stopped target local-fs.target - Local File Systems. Oct 13 00:07:02.540780 systemd[1]: Stopped target swap.target - Swaps. Oct 13 00:07:02.547694 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 13 00:07:02.547849 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Oct 13 00:07:02.556838 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Oct 13 00:07:02.563375 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 13 00:07:02.574966 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Oct 13 00:07:02.575075 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 13 00:07:02.584193 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 13 00:07:02.584334 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Oct 13 00:07:02.597450 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Oct 13 00:07:02.597642 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 13 00:07:02.606039 systemd[1]: ignition-files.service: Deactivated successfully. Oct 13 00:07:02.606252 systemd[1]: Stopped ignition-files.service - Ignition (files). Oct 13 00:07:02.614028 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Oct 13 00:07:02.614165 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Oct 13 00:07:02.642263 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Oct 13 00:07:02.646644 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Oct 13 00:07:02.653389 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 13 00:07:02.653998 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Oct 13 00:07:02.660681 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 13 00:07:02.660806 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Oct 13 00:07:02.690294 ignition[1170]: INFO : Ignition 2.20.0 Oct 13 00:07:02.690294 ignition[1170]: INFO : Stage: umount Oct 13 00:07:02.690294 ignition[1170]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 13 00:07:02.690294 ignition[1170]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 13 00:07:02.690294 ignition[1170]: INFO : umount: umount passed Oct 13 00:07:02.690294 ignition[1170]: INFO : Ignition finished successfully Oct 13 00:07:02.680961 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 13 00:07:02.681058 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Oct 13 00:07:02.691286 systemd[1]: ignition-mount.service: Deactivated successfully. Oct 13 00:07:02.691387 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Oct 13 00:07:02.696267 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 13 00:07:02.696319 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Oct 13 00:07:02.702662 systemd[1]: ignition-kargs.service: Deactivated successfully. Oct 13 00:07:02.702727 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Oct 13 00:07:02.710164 systemd[1]: ignition-fetch.service: Deactivated successfully. Oct 13 00:07:02.710214 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Oct 13 00:07:02.718074 systemd[1]: Stopped target network.target - Network. Oct 13 00:07:02.727441 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Oct 13 00:07:02.727496 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Oct 13 00:07:02.735986 systemd[1]: Stopped target paths.target - Path Units. Oct 13 00:07:02.742933 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 13 00:07:02.746172 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 13 00:07:02.749008 systemd[1]: Stopped target slices.target - Slice Units. Oct 13 00:07:02.757438 systemd[1]: Stopped target sockets.target - Socket Units. Oct 13 00:07:02.764588 systemd[1]: iscsid.socket: Deactivated successfully. Oct 13 00:07:02.764633 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Oct 13 00:07:02.771744 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 13 00:07:02.771786 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 13 00:07:02.779087 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 13 00:07:02.779154 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Oct 13 00:07:02.786508 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Oct 13 00:07:02.786558 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Oct 13 00:07:02.794020 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Oct 13 00:07:02.801619 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Oct 13 00:07:02.809921 systemd[1]: sysroot-boot.mount: Deactivated successfully. Oct 13 00:07:02.810536 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 13 00:07:02.810627 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Oct 13 00:07:02.816021 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 13 00:07:02.816129 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Oct 13 00:07:02.825589 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Oct 13 00:07:02.825857 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 13 00:07:02.825960 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Oct 13 00:07:02.838996 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Oct 13 00:07:02.840358 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 13 00:07:02.840444 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Oct 13 00:07:02.843569 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 13 00:07:02.843621 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Oct 13 00:07:02.873221 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Oct 13 00:07:02.875057 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Oct 13 00:07:02.875113 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 13 00:07:02.884132 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 13 00:07:02.884186 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Oct 13 00:07:02.896711 systemd[1]: systemd-modules-load.service: Deactivated successfully. Oct 13 00:07:02.896758 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Oct 13 00:07:02.904138 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 13 00:07:02.904186 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 13 00:07:02.913744 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 13 00:07:02.922452 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Oct 13 00:07:02.922521 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Oct 13 00:07:02.945630 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 13 00:07:02.946291 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 13 00:07:02.951447 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 13 00:07:02.951722 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Oct 13 00:07:02.958633 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 13 00:07:02.958675 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Oct 13 00:07:02.969071 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 13 00:07:02.969135 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Oct 13 00:07:02.981367 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 13 00:07:02.981442 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Oct 13 00:07:02.992197 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 13 00:07:02.992255 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 13 00:07:03.026344 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Oct 13 00:07:03.034080 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 13 00:07:03.034159 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 13 00:07:03.044069 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Oct 13 00:07:03.044257 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 13 00:07:03.044680 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 13 00:07:03.044751 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Oct 13 00:07:03.053941 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 13 00:07:03.053993 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 13 00:07:03.073198 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Oct 13 00:07:03.073265 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Oct 13 00:07:03.073651 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 13 00:07:03.073746 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Oct 13 00:07:03.078571 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 13 00:07:03.078668 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Oct 13 00:07:03.088439 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Oct 13 00:07:03.119406 systemd[1]: Starting initrd-switch-root.service - Switch Root... Oct 13 00:07:03.128951 systemd[1]: Switching root. Oct 13 00:07:03.231105 systemd-journald[204]: Journal stopped Oct 13 00:07:11.326373 systemd-journald[204]: Received SIGTERM from PID 1 (systemd). Oct 13 00:07:11.326456 kernel: SELinux: policy capability network_peer_controls=1 Oct 13 00:07:11.326522 kernel: SELinux: policy capability open_perms=1 Oct 13 00:07:11.326563 kernel: SELinux: policy capability extended_socket_class=1 Oct 13 00:07:11.326582 kernel: SELinux: policy capability always_check_network=0 Oct 13 00:07:11.326591 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 13 00:07:11.326601 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 13 00:07:11.326616 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 13 00:07:11.326626 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Oct 13 00:07:11.326635 kernel: audit: type=1403 audit(1760314024.408:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 13 00:07:11.326646 systemd[1]: Successfully loaded SELinux policy in 225.414ms. Oct 13 00:07:11.326662 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 15.336ms. Oct 13 00:07:11.326696 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 00:07:11.326741 systemd[1]: Detected virtualization microsoft. Oct 13 00:07:11.326809 systemd[1]: Detected architecture x86-64. Oct 13 00:07:11.326853 systemd[1]: Detected first boot. Oct 13 00:07:11.326898 systemd[1]: Hostname set to . Oct 13 00:07:11.326959 systemd[1]: Initializing machine ID from random generator. Oct 13 00:07:11.327003 zram_generator::config[1217]: No configuration found. Oct 13 00:07:11.327052 kernel: Guest personality initialized and is inactive Oct 13 00:07:11.327113 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Oct 13 00:07:11.327166 kernel: Initialized host personality Oct 13 00:07:11.327228 kernel: NET: Registered PF_VSOCK protocol family Oct 13 00:07:11.327271 systemd[1]: Populated /etc with preset unit settings. Oct 13 00:07:11.327291 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Oct 13 00:07:11.327302 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 13 00:07:11.327312 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Oct 13 00:07:11.327328 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 13 00:07:11.327340 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Oct 13 00:07:11.327351 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Oct 13 00:07:11.327361 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Oct 13 00:07:11.327372 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Oct 13 00:07:11.327437 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Oct 13 00:07:11.327482 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Oct 13 00:07:11.327552 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Oct 13 00:07:11.327596 systemd[1]: Created slice user.slice - User and Session Slice. Oct 13 00:07:11.327640 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 13 00:07:11.327704 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 13 00:07:11.327749 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Oct 13 00:07:11.327793 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Oct 13 00:07:11.327863 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Oct 13 00:07:11.327941 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 13 00:07:11.327999 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Oct 13 00:07:11.328014 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 13 00:07:11.328025 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Oct 13 00:07:11.328036 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Oct 13 00:07:11.328047 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Oct 13 00:07:11.328058 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Oct 13 00:07:11.328069 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 13 00:07:11.328110 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 13 00:07:11.328259 systemd[1]: Reached target slices.target - Slice Units. Oct 13 00:07:11.328340 systemd[1]: Reached target swap.target - Swaps. Oct 13 00:07:11.328419 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Oct 13 00:07:11.328531 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Oct 13 00:07:11.328610 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Oct 13 00:07:11.328695 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 13 00:07:11.328713 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 13 00:07:11.328725 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 13 00:07:11.328741 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Oct 13 00:07:11.328752 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Oct 13 00:07:11.328763 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Oct 13 00:07:11.328774 systemd[1]: Mounting media.mount - External Media Directory... Oct 13 00:07:11.328785 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 13 00:07:11.328895 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Oct 13 00:07:11.328968 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Oct 13 00:07:11.329014 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Oct 13 00:07:11.329079 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 00:07:11.329134 systemd[1]: Reached target machines.target - Containers. Oct 13 00:07:11.329153 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Oct 13 00:07:11.329164 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 13 00:07:11.329176 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 13 00:07:11.329200 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Oct 13 00:07:11.329211 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 13 00:07:11.329222 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 13 00:07:11.329247 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 13 00:07:11.329266 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Oct 13 00:07:11.329287 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 13 00:07:11.329308 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Oct 13 00:07:11.329329 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 13 00:07:11.329355 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Oct 13 00:07:11.329372 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 13 00:07:11.329387 systemd[1]: Stopped systemd-fsck-usr.service. Oct 13 00:07:11.329404 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 00:07:11.329425 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 13 00:07:11.329446 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 13 00:07:11.329464 kernel: loop: module loaded Oct 13 00:07:11.329483 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 13 00:07:11.329509 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Oct 13 00:07:11.329521 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Oct 13 00:07:11.329531 kernel: fuse: init (API version 7.39) Oct 13 00:07:11.329542 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 13 00:07:11.329553 systemd[1]: verity-setup.service: Deactivated successfully. Oct 13 00:07:11.329564 systemd[1]: Stopped verity-setup.service. Oct 13 00:07:11.329576 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 00:07:11.329611 systemd-journald[1324]: Collecting audit messages is disabled. Oct 13 00:07:11.329643 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Oct 13 00:07:11.329656 systemd-journald[1324]: Journal started Oct 13 00:07:11.329682 systemd-journald[1324]: Runtime Journal (/run/log/journal/832cee6bd8e841228dd633ad03dae7cf) is 8M, max 2.2G, 2.1G free. Oct 13 00:07:10.461244 systemd[1]: Queued start job for default target multi-user.target. Oct 13 00:07:10.473462 systemd[1]: Unnecessary job was removed for dev-sdb6.device - /dev/sdb6. Oct 13 00:07:10.473928 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 13 00:07:11.334137 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Oct 13 00:07:11.334169 kernel: ACPI: bus type drm_connector registered Oct 13 00:07:11.347202 systemd[1]: Started systemd-journald.service - Journal Service. Oct 13 00:07:11.351333 systemd[1]: Mounted media.mount - External Media Directory. Oct 13 00:07:11.355375 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Oct 13 00:07:11.359788 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Oct 13 00:07:11.364917 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Oct 13 00:07:11.370861 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Oct 13 00:07:11.375658 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 13 00:07:11.380839 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 13 00:07:11.381036 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Oct 13 00:07:11.385908 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 13 00:07:11.386104 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 13 00:07:11.390932 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 13 00:07:11.391133 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 13 00:07:11.395940 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 13 00:07:11.396135 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 13 00:07:11.401286 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 13 00:07:11.401466 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Oct 13 00:07:11.406990 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 13 00:07:11.407193 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 13 00:07:11.411820 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 13 00:07:11.416628 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 13 00:07:11.421934 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Oct 13 00:07:11.437089 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 13 00:07:11.450236 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Oct 13 00:07:11.455580 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Oct 13 00:07:11.460076 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 13 00:07:11.461100 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 13 00:07:11.466009 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 13 00:07:11.472674 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Oct 13 00:07:11.477482 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Oct 13 00:07:11.488922 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 13 00:07:11.507263 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Oct 13 00:07:11.515875 udevadm[1359]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Oct 13 00:07:11.591709 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 13 00:07:11.627635 systemd-tmpfiles[1353]: ACLs are not supported, ignoring. Oct 13 00:07:11.627657 systemd-tmpfiles[1353]: ACLs are not supported, ignoring. Oct 13 00:07:11.632806 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 13 00:07:12.047468 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Oct 13 00:07:12.052758 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Oct 13 00:07:12.052801 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 13 00:07:12.061085 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Oct 13 00:07:12.082253 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Oct 13 00:07:12.087830 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Oct 13 00:07:12.091678 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 13 00:07:12.103505 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Oct 13 00:07:12.108576 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Oct 13 00:07:12.112935 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 13 00:07:12.113912 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Oct 13 00:07:12.119442 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Oct 13 00:07:12.124912 systemd[1]: Starting systemd-sysusers.service - Create System Users... Oct 13 00:07:12.129659 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Oct 13 00:07:12.152639 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Oct 13 00:07:12.157646 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Oct 13 00:07:12.160946 systemd-journald[1324]: Time spent on flushing to /var/log/journal/832cee6bd8e841228dd633ad03dae7cf is 14.893ms for 953 entries. Oct 13 00:07:12.160946 systemd-journald[1324]: System Journal (/var/log/journal/832cee6bd8e841228dd633ad03dae7cf) is 8M, max 2.6G, 2.6G free. Oct 13 00:07:12.211309 systemd-journald[1324]: Received client request to flush runtime journal. Oct 13 00:07:12.211367 kernel: loop0: detected capacity change from 0 to 28272 Oct 13 00:07:12.178370 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Oct 13 00:07:12.212874 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Oct 13 00:07:12.240018 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 13 00:07:12.240802 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Oct 13 00:07:12.733082 systemd[1]: Finished systemd-sysusers.service - Create System Users. Oct 13 00:07:12.754332 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 13 00:07:12.773319 systemd-tmpfiles[1383]: ACLs are not supported, ignoring. Oct 13 00:07:12.773343 systemd-tmpfiles[1383]: ACLs are not supported, ignoring. Oct 13 00:07:12.776134 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Oct 13 00:07:12.778230 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 13 00:07:12.952143 kernel: loop1: detected capacity change from 0 to 147912 Oct 13 00:07:13.770180 kernel: loop2: detected capacity change from 0 to 138176 Oct 13 00:07:14.135813 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Oct 13 00:07:14.164480 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 13 00:07:14.188472 systemd-udevd[1389]: Using default interface naming scheme 'v255'. Oct 13 00:07:14.620143 kernel: loop3: detected capacity change from 0 to 28272 Oct 13 00:07:14.635139 kernel: loop4: detected capacity change from 0 to 147912 Oct 13 00:07:14.654138 kernel: loop5: detected capacity change from 0 to 138176 Oct 13 00:07:14.674023 (sd-merge)[1391]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Oct 13 00:07:14.674519 (sd-merge)[1391]: Merged extensions into '/usr'. Oct 13 00:07:14.677843 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Oct 13 00:07:14.696446 systemd[1]: Starting ensure-sysext.service... Oct 13 00:07:14.700351 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 13 00:07:14.733010 systemd[1]: Reload requested from client PID 1393 ('systemctl') (unit ensure-sysext.service)... Oct 13 00:07:14.733028 systemd[1]: Reloading... Oct 13 00:07:14.774179 zram_generator::config[1423]: No configuration found. Oct 13 00:07:14.774788 systemd-tmpfiles[1394]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Oct 13 00:07:14.775094 systemd-tmpfiles[1394]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Oct 13 00:07:14.776096 systemd-tmpfiles[1394]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Oct 13 00:07:14.776397 systemd-tmpfiles[1394]: ACLs are not supported, ignoring. Oct 13 00:07:14.776468 systemd-tmpfiles[1394]: ACLs are not supported, ignoring. Oct 13 00:07:14.790039 systemd-tmpfiles[1394]: Detected autofs mount point /boot during canonicalization of boot. Oct 13 00:07:14.790054 systemd-tmpfiles[1394]: Skipping /boot Oct 13 00:07:14.802011 systemd-tmpfiles[1394]: Detected autofs mount point /boot during canonicalization of boot. Oct 13 00:07:14.802026 systemd-tmpfiles[1394]: Skipping /boot Oct 13 00:07:14.928154 kernel: mousedev: PS/2 mouse device common for all mice Oct 13 00:07:14.930250 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Oct 13 00:07:14.933276 kernel: hv_vmbus: registering driver hv_balloon Oct 13 00:07:14.933325 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Oct 13 00:07:14.983146 kernel: hv_vmbus: registering driver hyperv_fb Oct 13 00:07:14.983231 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Oct 13 00:07:14.992346 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Oct 13 00:07:14.997339 kernel: Console: switching to colour dummy device 80x25 Oct 13 00:07:15.002010 kernel: Console: switching to colour frame buffer device 128x48 Oct 13 00:07:15.003137 kernel: EDAC MC: Ver: 3.0.0 Oct 13 00:07:15.037156 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Oct 13 00:07:15.037580 systemd[1]: Reloading finished in 304 ms. Oct 13 00:07:15.047238 kernel: BTRFS warning: duplicate device /dev/sdb3 devid 1 generation 38 scanned by (udev-worker) (1506) Oct 13 00:07:15.062283 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 13 00:07:15.082701 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 13 00:07:15.113695 systemd[1]: Finished ensure-sysext.service. Oct 13 00:07:15.121762 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Oct 13 00:07:15.164935 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Oct 13 00:07:15.169681 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 13 00:07:15.183250 systemd[1]: Starting audit-rules.service - Load Audit Rules... Oct 13 00:07:15.212260 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Oct 13 00:07:15.216965 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 13 00:07:15.218020 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Oct 13 00:07:15.223639 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 13 00:07:15.228705 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 13 00:07:15.233585 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 13 00:07:15.239177 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 13 00:07:15.243341 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 13 00:07:15.244244 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Oct 13 00:07:15.248773 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 00:07:15.249842 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Oct 13 00:07:15.255999 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 13 00:07:15.260093 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 13 00:07:15.264011 systemd[1]: Reached target time-set.target - System Time Set. Oct 13 00:07:15.269418 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Oct 13 00:07:15.274867 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 13 00:07:15.279095 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 00:07:15.280223 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 13 00:07:15.280411 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 13 00:07:15.285273 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 13 00:07:15.285456 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 13 00:07:15.291506 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 13 00:07:15.291696 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 13 00:07:15.297094 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 13 00:07:15.297293 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 13 00:07:15.307265 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 13 00:07:15.307424 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 13 00:07:15.308800 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Oct 13 00:07:15.331551 lvm[1599]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 13 00:07:15.332886 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Oct 13 00:07:15.368295 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Oct 13 00:07:15.387882 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Oct 13 00:07:15.390386 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 13 00:07:15.391987 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Oct 13 00:07:15.400062 lvm[1627]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 13 00:07:15.428198 systemd[1]: Started systemd-userdbd.service - User Database Manager. Oct 13 00:07:15.434847 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Oct 13 00:07:15.655603 systemd-networkd[1607]: lo: Link UP Oct 13 00:07:15.655612 systemd-networkd[1607]: lo: Gained carrier Oct 13 00:07:15.657015 systemd-networkd[1607]: Enumeration completed Oct 13 00:07:15.657150 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 13 00:07:15.657420 systemd-networkd[1607]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 13 00:07:15.657430 systemd-networkd[1607]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 13 00:07:15.658136 systemd-networkd[1607]: eth0: Link UP Oct 13 00:07:15.658284 systemd-networkd[1607]: eth0: Gained carrier Oct 13 00:07:15.658349 systemd-networkd[1607]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 13 00:07:15.673279 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Oct 13 00:07:15.675766 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Oct 13 00:07:15.705167 systemd-networkd[1607]: eth0: DHCPv4 address 10.200.8.12/24, gateway 10.200.8.1 acquired from 168.63.129.16 Oct 13 00:07:15.720638 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Oct 13 00:07:15.766483 systemd-resolved[1608]: Positive Trust Anchors: Oct 13 00:07:15.766499 systemd-resolved[1608]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 13 00:07:15.766530 systemd-resolved[1608]: 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 00:07:15.807078 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Oct 13 00:07:15.829720 augenrules[1653]: No rules Oct 13 00:07:15.830848 systemd[1]: audit-rules.service: Deactivated successfully. Oct 13 00:07:15.831080 systemd[1]: Finished audit-rules.service - Load Audit Rules. Oct 13 00:07:15.880374 systemd-resolved[1608]: Using system hostname 'ci-4230.2.4-a-01a3a7f97b'. Oct 13 00:07:15.882359 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 13 00:07:15.883980 systemd[1]: Reached target network.target - Network. Oct 13 00:07:15.884634 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 13 00:07:16.821243 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 13 00:07:17.009367 systemd-networkd[1607]: eth0: Gained IPv6LL Oct 13 00:07:17.012684 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Oct 13 00:07:17.018476 systemd[1]: Reached target network-online.target - Network is Online. Oct 13 00:07:19.048268 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Oct 13 00:07:19.053443 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 00:07:23.290194 ldconfig[1366]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 13 00:07:23.302576 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Oct 13 00:07:23.322255 systemd[1]: Starting systemd-update-done.service - Update is Completed... Oct 13 00:07:23.352695 systemd[1]: Finished systemd-update-done.service - Update is Completed. Oct 13 00:07:23.358703 systemd[1]: Reached target sysinit.target - System Initialization. Oct 13 00:07:23.363150 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Oct 13 00:07:23.368345 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 00:07:23.373787 systemd[1]: Started logrotate.timer - Daily rotation of log files. Oct 13 00:07:23.378366 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Oct 13 00:07:23.383385 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Oct 13 00:07:23.388587 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Oct 13 00:07:23.388629 systemd[1]: Reached target paths.target - Path Units. Oct 13 00:07:23.392383 systemd[1]: Reached target timers.target - Timer Units. Oct 13 00:07:23.397066 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Oct 13 00:07:23.403098 systemd[1]: Starting docker.socket - Docker Socket for the API... Oct 13 00:07:23.409882 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Oct 13 00:07:23.415254 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Oct 13 00:07:23.420394 systemd[1]: Reached target ssh-access.target - SSH Access Available. Oct 13 00:07:23.427227 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Oct 13 00:07:23.443791 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Oct 13 00:07:23.449349 systemd[1]: Listening on docker.socket - Docker Socket for the API. Oct 13 00:07:23.453744 systemd[1]: Reached target sockets.target - Socket Units. Oct 13 00:07:23.457820 systemd[1]: Reached target basic.target - Basic System. Oct 13 00:07:23.461608 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Oct 13 00:07:23.461638 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Oct 13 00:07:23.486202 systemd[1]: Starting chronyd.service - NTP client/server... Oct 13 00:07:23.490742 systemd[1]: Starting containerd.service - containerd container runtime... Oct 13 00:07:23.496523 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Oct 13 00:07:23.501479 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Oct 13 00:07:23.505274 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Oct 13 00:07:23.510374 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Oct 13 00:07:23.514659 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 00:07:23.514703 systemd[1]: hv_fcopy_daemon.service - Hyper-V FCOPY daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_fcopy). Oct 13 00:07:23.515769 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Oct 13 00:07:23.517333 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 00:07:23.520509 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Oct 13 00:07:23.525878 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Oct 13 00:07:23.527788 jq[1674]: false Oct 13 00:07:23.530742 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Oct 13 00:07:23.539105 KVP[1679]: KVP starting; pid is:1679 Oct 13 00:07:23.539329 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Oct 13 00:07:23.545365 kernel: hv_utils: KVP IC version 4.0 Oct 13 00:07:23.543246 KVP[1679]: KVP LIC Version: 3.1 Oct 13 00:07:23.539817 (chronyd)[1670]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Oct 13 00:07:23.545735 chronyd[1686]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) Oct 13 00:07:23.548225 systemd[1]: Starting systemd-logind.service - User Login Management... Oct 13 00:07:23.553058 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Oct 13 00:07:23.553556 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 13 00:07:23.554199 systemd[1]: Starting update-engine.service - Update Engine... Oct 13 00:07:23.557874 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Oct 13 00:07:23.564433 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Oct 13 00:07:23.564658 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Oct 13 00:07:23.564967 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 13 00:07:23.565167 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Oct 13 00:07:23.569850 jq[1691]: true Oct 13 00:07:23.580125 jq[1694]: true Oct 13 00:07:23.624616 (ntainerd)[1722]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Oct 13 00:07:23.624659 systemd[1]: motdgen.service: Deactivated successfully. Oct 13 00:07:23.624907 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Oct 13 00:07:23.636014 systemd-logind[1687]: New seat seat0. Oct 13 00:07:23.637279 systemd-logind[1687]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Oct 13 00:07:23.638205 systemd[1]: Started systemd-logind.service - User Login Management. Oct 13 00:07:23.645696 update_engine[1690]: I20251013 00:07:23.645610 1690 main.cc:92] Flatcar Update Engine starting Oct 13 00:07:23.664167 chronyd[1686]: Timezone right/UTC failed leap second check, ignoring Oct 13 00:07:23.664369 chronyd[1686]: Loaded seccomp filter (level 2) Oct 13 00:07:23.666040 systemd[1]: Started chronyd.service - NTP client/server. Oct 13 00:07:23.677413 extend-filesystems[1678]: Found loop3 Oct 13 00:07:23.680333 extend-filesystems[1678]: Found loop4 Oct 13 00:07:23.680333 extend-filesystems[1678]: Found loop5 Oct 13 00:07:23.680333 extend-filesystems[1678]: Found sda Oct 13 00:07:23.680333 extend-filesystems[1678]: Found sda1 Oct 13 00:07:23.680333 extend-filesystems[1678]: Found sdb Oct 13 00:07:23.680333 extend-filesystems[1678]: Found sdb1 Oct 13 00:07:23.680333 extend-filesystems[1678]: Found sdb2 Oct 13 00:07:23.680333 extend-filesystems[1678]: Found sdb3 Oct 13 00:07:23.680333 extend-filesystems[1678]: Found usr Oct 13 00:07:23.680333 extend-filesystems[1678]: Found sdb4 Oct 13 00:07:23.680333 extend-filesystems[1678]: Found sdb6 Oct 13 00:07:23.680333 extend-filesystems[1678]: Found sdb7 Oct 13 00:07:23.680333 extend-filesystems[1678]: Found sdb9 Oct 13 00:07:23.680333 extend-filesystems[1678]: Checking size of /dev/sdb9 Oct 13 00:07:23.729225 extend-filesystems[1678]: Old size kept for /dev/sdb9 Oct 13 00:07:23.736767 extend-filesystems[1678]: Found sr0 Oct 13 00:07:23.729841 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 13 00:07:23.730093 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Oct 13 00:07:23.760396 bash[1716]: Updated "/home/core/.ssh/authorized_keys" Oct 13 00:07:23.762184 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Oct 13 00:07:23.763228 kernel: BTRFS warning: duplicate device /dev/sdb3 devid 1 generation 38 scanned by (udev-worker) (1734) Oct 13 00:07:23.768878 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Oct 13 00:07:23.800928 setup-nvidia[1681]: Downloading Flatcar Container Linux Developer Container for version: 4230.2.4 Oct 13 00:07:23.874181 sshd_keygen[1717]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Oct 13 00:07:23.895760 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Oct 13 00:07:23.907267 systemd[1]: Starting issuegen.service - Generate /run/issue... Oct 13 00:07:23.913267 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Oct 13 00:07:23.919147 systemd[1]: issuegen.service: Deactivated successfully. Oct 13 00:07:23.919386 systemd[1]: Finished issuegen.service - Generate /run/issue. Oct 13 00:07:23.925935 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Oct 13 00:07:23.930555 dbus-daemon[1673]: [system] SELinux support is enabled Oct 13 00:07:23.930714 systemd[1]: Started dbus.service - D-Bus System Message Bus. Oct 13 00:07:23.932914 update_engine[1690]: I20251013 00:07:23.932864 1690 update_check_scheduler.cc:74] Next update check in 4m42s Oct 13 00:07:23.937902 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Oct 13 00:07:23.942948 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 00:07:23.942980 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Oct 13 00:07:23.943430 dbus-daemon[1673]: [system] Successfully activated service 'org.freedesktop.systemd1' Oct 13 00:07:23.948207 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 00:07:23.948235 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Oct 13 00:07:23.953586 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Oct 13 00:07:23.959084 systemd[1]: Started update-engine.service - Update Engine. Oct 13 00:07:23.974536 systemd[1]: Started getty@tty1.service - Getty on tty1. Oct 13 00:07:23.979603 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Oct 13 00:07:23.984128 systemd[1]: Reached target getty.target - Login Prompts. Oct 13 00:07:23.992413 systemd[1]: Started locksmithd.service - Cluster reboot manager. Oct 13 00:07:23.993233 coreos-metadata[1672]: Oct 13 00:07:23.993 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Oct 13 00:07:23.995997 coreos-metadata[1672]: Oct 13 00:07:23.995 INFO Fetch successful Oct 13 00:07:23.996075 coreos-metadata[1672]: Oct 13 00:07:23.996 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Oct 13 00:07:24.000460 coreos-metadata[1672]: Oct 13 00:07:24.000 INFO Fetch successful Oct 13 00:07:24.000755 coreos-metadata[1672]: Oct 13 00:07:24.000 INFO Fetching http://168.63.129.16/machine/84f5d8f7-6e3a-4849-aa2a-b31aac979396/8739967f%2Ddf83%2D481e%2D88a3%2D8cc4ac4c4555.%5Fci%2D4230.2.4%2Da%2D01a3a7f97b?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Oct 13 00:07:24.002456 coreos-metadata[1672]: Oct 13 00:07:24.002 INFO Fetch successful Oct 13 00:07:24.002626 coreos-metadata[1672]: Oct 13 00:07:24.002 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Oct 13 00:07:24.010207 coreos-metadata[1672]: Oct 13 00:07:24.010 INFO Fetch successful Oct 13 00:07:24.028531 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Oct 13 00:07:24.033315 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Oct 13 00:07:24.184183 locksmithd[1824]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 13 00:07:24.892475 containerd[1722]: time="2025-10-13T00:07:24.892392300Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Oct 13 00:07:24.910829 containerd[1722]: time="2025-10-13T00:07:24.910794500Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Oct 13 00:07:24.912395 containerd[1722]: time="2025-10-13T00:07:24.912363700Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.110-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Oct 13 00:07:24.912395 containerd[1722]: time="2025-10-13T00:07:24.912392200Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Oct 13 00:07:24.912471 containerd[1722]: time="2025-10-13T00:07:24.912408700Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Oct 13 00:07:24.912564 containerd[1722]: time="2025-10-13T00:07:24.912543300Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Oct 13 00:07:24.912594 containerd[1722]: time="2025-10-13T00:07:24.912571300Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Oct 13 00:07:24.912652 containerd[1722]: time="2025-10-13T00:07:24.912632100Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Oct 13 00:07:24.912683 containerd[1722]: time="2025-10-13T00:07:24.912650400Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Oct 13 00:07:24.912828 containerd[1722]: time="2025-10-13T00:07:24.912806700Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." 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" type=io.containerd.snapshotter.v1 Oct 13 00:07:24.912860 containerd[1722]: time="2025-10-13T00:07:24.912827400Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Oct 13 00:07:24.912860 containerd[1722]: time="2025-10-13T00:07:24.912840100Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Oct 13 00:07:24.912860 containerd[1722]: time="2025-10-13T00:07:24.912849300Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Oct 13 00:07:24.913039 containerd[1722]: time="2025-10-13T00:07:24.913015300Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Oct 13 00:07:24.913283 containerd[1722]: time="2025-10-13T00:07:24.913263400Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Oct 13 00:07:24.913415 containerd[1722]: time="2025-10-13T00:07:24.913394900Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Oct 13 00:07:24.913451 containerd[1722]: time="2025-10-13T00:07:24.913414300Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Oct 13 00:07:24.913519 containerd[1722]: time="2025-10-13T00:07:24.913501000Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Oct 13 00:07:24.913575 containerd[1722]: time="2025-10-13T00:07:24.913558200Z" level=info msg="metadata content store policy set" policy=shared Oct 13 00:07:24.933830 containerd[1722]: time="2025-10-13T00:07:24.933805300Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Oct 13 00:07:24.933941 containerd[1722]: time="2025-10-13T00:07:24.933846000Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Oct 13 00:07:24.934029 containerd[1722]: time="2025-10-13T00:07:24.934005800Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Oct 13 00:07:24.934063 containerd[1722]: time="2025-10-13T00:07:24.934039900Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Oct 13 00:07:24.934264 containerd[1722]: time="2025-10-13T00:07:24.934145700Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Oct 13 00:07:24.934338 containerd[1722]: time="2025-10-13T00:07:24.934311600Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Oct 13 00:07:24.934727 containerd[1722]: time="2025-10-13T00:07:24.934674700Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Oct 13 00:07:24.934936 containerd[1722]: time="2025-10-13T00:07:24.934912300Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Oct 13 00:07:24.934974 containerd[1722]: time="2025-10-13T00:07:24.934937800Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Oct 13 00:07:24.934974 containerd[1722]: time="2025-10-13T00:07:24.934952900Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Oct 13 00:07:24.934974 containerd[1722]: time="2025-10-13T00:07:24.934967000Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Oct 13 00:07:24.935036 containerd[1722]: time="2025-10-13T00:07:24.934979500Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Oct 13 00:07:24.935036 containerd[1722]: time="2025-10-13T00:07:24.934991600Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Oct 13 00:07:24.935036 containerd[1722]: time="2025-10-13T00:07:24.935004700Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Oct 13 00:07:24.935036 containerd[1722]: time="2025-10-13T00:07:24.935018600Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Oct 13 00:07:24.935036 containerd[1722]: time="2025-10-13T00:07:24.935030600Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Oct 13 00:07:24.935156 containerd[1722]: time="2025-10-13T00:07:24.935047100Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Oct 13 00:07:24.935156 containerd[1722]: time="2025-10-13T00:07:24.935058100Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Oct 13 00:07:24.935156 containerd[1722]: time="2025-10-13T00:07:24.935075900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Oct 13 00:07:24.935156 containerd[1722]: time="2025-10-13T00:07:24.935088400Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Oct 13 00:07:24.935156 containerd[1722]: time="2025-10-13T00:07:24.935100200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Oct 13 00:07:24.935156 containerd[1722]: time="2025-10-13T00:07:24.935112800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Oct 13 00:07:24.935156 containerd[1722]: time="2025-10-13T00:07:24.935142100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Oct 13 00:07:24.935311 containerd[1722]: time="2025-10-13T00:07:24.935157900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Oct 13 00:07:24.935311 containerd[1722]: time="2025-10-13T00:07:24.935168400Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Oct 13 00:07:24.935311 containerd[1722]: time="2025-10-13T00:07:24.935180600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Oct 13 00:07:24.935311 containerd[1722]: time="2025-10-13T00:07:24.935192100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Oct 13 00:07:24.935311 containerd[1722]: time="2025-10-13T00:07:24.935206100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Oct 13 00:07:24.935311 containerd[1722]: time="2025-10-13T00:07:24.935216400Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Oct 13 00:07:24.935311 containerd[1722]: time="2025-10-13T00:07:24.935227300Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Oct 13 00:07:24.935311 containerd[1722]: time="2025-10-13T00:07:24.935238500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Oct 13 00:07:24.935311 containerd[1722]: time="2025-10-13T00:07:24.935250500Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Oct 13 00:07:24.935311 containerd[1722]: time="2025-10-13T00:07:24.935268300Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Oct 13 00:07:24.935311 containerd[1722]: time="2025-10-13T00:07:24.935294300Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Oct 13 00:07:24.935311 containerd[1722]: time="2025-10-13T00:07:24.935308400Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Oct 13 00:07:24.935549 containerd[1722]: time="2025-10-13T00:07:24.935361900Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Oct 13 00:07:24.935549 containerd[1722]: time="2025-10-13T00:07:24.935381400Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Oct 13 00:07:24.935549 containerd[1722]: time="2025-10-13T00:07:24.935392100Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Oct 13 00:07:24.935549 containerd[1722]: time="2025-10-13T00:07:24.935402900Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Oct 13 00:07:24.935549 containerd[1722]: time="2025-10-13T00:07:24.935411700Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Oct 13 00:07:24.935549 containerd[1722]: time="2025-10-13T00:07:24.935422900Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Oct 13 00:07:24.935549 containerd[1722]: time="2025-10-13T00:07:24.935433300Z" level=info msg="NRI interface is disabled by configuration." Oct 13 00:07:24.935549 containerd[1722]: time="2025-10-13T00:07:24.935454400Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Oct 13 00:07:24.935731 containerd[1722]: time="2025-10-13T00:07:24.935690200Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} 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 00:07:24.935868 containerd[1722]: time="2025-10-13T00:07:24.935744600Z" level=info msg="Connect containerd service" Oct 13 00:07:24.935868 containerd[1722]: time="2025-10-13T00:07:24.935781100Z" level=info msg="using legacy CRI server" Oct 13 00:07:24.935868 containerd[1722]: time="2025-10-13T00:07:24.935791000Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Oct 13 00:07:24.935941 containerd[1722]: time="2025-10-13T00:07:24.935886700Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Oct 13 00:07:24.936513 containerd[1722]: time="2025-10-13T00:07:24.936487500Z" 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 00:07:24.936650 containerd[1722]: time="2025-10-13T00:07:24.936616800Z" level=info msg="Start subscribing containerd event" Oct 13 00:07:24.936704 containerd[1722]: time="2025-10-13T00:07:24.936661100Z" level=info msg="Start recovering state" Oct 13 00:07:24.936729 containerd[1722]: time="2025-10-13T00:07:24.936716400Z" level=info msg="Start event monitor" Oct 13 00:07:24.936750 containerd[1722]: time="2025-10-13T00:07:24.936728700Z" level=info msg="Start snapshots syncer" Oct 13 00:07:24.936750 containerd[1722]: time="2025-10-13T00:07:24.936737700Z" level=info msg="Start cni network conf syncer for default" Oct 13 00:07:24.936750 containerd[1722]: time="2025-10-13T00:07:24.936744900Z" level=info msg="Start streaming server" Oct 13 00:07:24.936811 containerd[1722]: time="2025-10-13T00:07:24.936758200Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Oct 13 00:07:24.936845 containerd[1722]: time="2025-10-13T00:07:24.936826000Z" level=info msg=serving... address=/run/containerd/containerd.sock Oct 13 00:07:24.936922 containerd[1722]: time="2025-10-13T00:07:24.936893600Z" level=info msg="containerd successfully booted in 0.045401s" Oct 13 00:07:24.936971 systemd[1]: Started containerd.service - containerd container runtime. Oct 13 00:07:24.968609 setup-nvidia[1845]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 13 00:07:24.968914 setup-nvidia[1845]: Dload Upload Total Spent Left Speed Oct 13 00:07:26.450947 waagent[1819]: 2025-10-13T00:07:26.450846Z INFO Daemon Daemon Azure Linux Agent Version: 2.9.1.1 Oct 13 00:07:26.455206 waagent[1819]: 2025-10-13T00:07:26.455111Z INFO Daemon Daemon OS: flatcar 4230.2.4 Oct 13 00:07:26.458623 waagent[1819]: 2025-10-13T00:07:26.458562Z INFO Daemon Daemon Python: 3.11.11 Oct 13 00:07:26.461838 waagent[1819]: 2025-10-13T00:07:26.461782Z INFO Daemon Daemon Run daemon Oct 13 00:07:26.464825 waagent[1819]: 2025-10-13T00:07:26.464774Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4230.2.4' Oct 13 00:07:26.471335 waagent[1819]: 2025-10-13T00:07:26.471270Z INFO Daemon Daemon Using waagent for provisioning Oct 13 00:07:26.475200 waagent[1819]: 2025-10-13T00:07:26.475143Z INFO Daemon Daemon Activate resource disk Oct 13 00:07:26.478715 waagent[1819]: 2025-10-13T00:07:26.478663Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Oct 13 00:07:26.486084 waagent[1819]: 2025-10-13T00:07:26.486028Z INFO Daemon Daemon Found device: sda Oct 13 00:07:26.495048 waagent[1819]: 2025-10-13T00:07:26.494993Z INFO Daemon Daemon Examining partition table Oct 13 00:07:26.539314 waagent[1819]: 2025-10-13T00:07:26.539257Z INFO Daemon Daemon GPT not detected, determining filesystem Oct 13 00:07:26.605048 waagent[1819]: 2025-10-13T00:07:26.604963Z INFO Daemon Daemon sfdisk --part-type -f /dev/sda 1 -n succeeded Oct 13 00:07:26.609673 waagent[1819]: 2025-10-13T00:07:26.609610Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Oct 13 00:07:26.976837 kernel: sda: sda1 Oct 13 00:07:26.976962 kernel: sda: sda1 Oct 13 00:07:26.990056 waagent[1819]: 2025-10-13T00:07:26.989955Z INFO Daemon Daemon sfdisk --part-type /dev/sda 1 83 succeeded Oct 13 00:07:27.006141 kernel: sda: sda1 Oct 13 00:07:27.006223 waagent[1819]: 2025-10-13T00:07:27.006093Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sda1] Oct 13 00:07:32.310884 login[1823]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Oct 13 00:07:32.533257 login[1822]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Oct 13 00:07:32.546302 systemd-logind[1687]: New session 2 of user core. Oct 13 00:07:32.547297 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Oct 13 00:07:32.563347 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Oct 13 00:07:32.576004 waagent[1819]: 2025-10-13T00:07:32.575915Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sda1 /mnt/resource] Oct 13 00:07:32.595150 kernel: EXT4-fs (sda1): mounted filesystem 8378c7a3-8798-415a-8172-62d9f25ce23e r/w with ordered data mode. Quota mode: none. Oct 13 00:07:32.595371 waagent[1819]: 2025-10-13T00:07:32.595297Z INFO Daemon Daemon Resource disk /dev/sda is mounted at /mnt/resource with ext4 Oct 13 00:07:32.597481 waagent[1819]: 2025-10-13T00:07:32.597438Z INFO Daemon Daemon Clean protocol and wireserver endpoint Oct 13 00:07:32.597722 waagent[1819]: 2025-10-13T00:07:32.597677Z INFO Daemon Daemon Running default provisioning handler Oct 13 00:07:32.600196 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Oct 13 00:07:32.621485 systemd[1]: Starting user@500.service - User Manager for UID 500... Oct 13 00:07:32.632657 waagent[1819]: 2025-10-13T00:07:32.631469Z 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 00:07:32.634781 waagent[1819]: 2025-10-13T00:07:32.634730Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Oct 13 00:07:32.635039 waagent[1819]: 2025-10-13T00:07:32.634994Z INFO Daemon Daemon cloud-init is enabled: False Oct 13 00:07:32.636439 waagent[1819]: 2025-10-13T00:07:32.636398Z INFO Daemon Daemon Copying ovf-env.xml Oct 13 00:07:32.687620 (systemd)[1934]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 13 00:07:32.690044 systemd-logind[1687]: New session c1 of user core. Oct 13 00:07:32.810022 waagent[1819]: 2025-10-13T00:07:32.809918Z INFO Daemon Daemon Successfully mounted dvd Oct 13 00:07:32.836667 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Oct 13 00:07:32.836879 waagent[1819]: 2025-10-13T00:07:32.836672Z INFO Daemon Daemon Detect protocol endpoint Oct 13 00:07:32.838366 waagent[1819]: 2025-10-13T00:07:32.838305Z INFO Daemon Daemon Clean protocol and wireserver endpoint Oct 13 00:07:32.839149 waagent[1819]: 2025-10-13T00:07:32.839092Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Oct 13 00:07:32.840556 waagent[1819]: 2025-10-13T00:07:32.840510Z INFO Daemon Daemon Test for route to 168.63.129.16 Oct 13 00:07:32.842147 waagent[1819]: 2025-10-13T00:07:32.842088Z INFO Daemon Daemon Route to 168.63.129.16 exists Oct 13 00:07:32.842751 waagent[1819]: 2025-10-13T00:07:32.842709Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Oct 13 00:07:32.919183 waagent[1819]: 2025-10-13T00:07:32.919095Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Oct 13 00:07:32.920935 waagent[1819]: 2025-10-13T00:07:32.920899Z INFO Daemon Daemon Wire protocol version:2012-11-30 Oct 13 00:07:32.922206 waagent[1819]: 2025-10-13T00:07:32.922161Z INFO Daemon Daemon Server preferred version:2015-04-05 Oct 13 00:07:33.045790 waagent[1819]: 2025-10-13T00:07:33.045681Z INFO Daemon Daemon Initializing goal state during protocol detection Oct 13 00:07:33.047492 waagent[1819]: 2025-10-13T00:07:33.047434Z INFO Daemon Daemon Forcing an update of the goal state. Oct 13 00:07:33.054335 waagent[1819]: 2025-10-13T00:07:33.054277Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Oct 13 00:07:33.068702 waagent[1819]: 2025-10-13T00:07:33.068639Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.177 Oct 13 00:07:33.072194 waagent[1819]: 2025-10-13T00:07:33.071931Z INFO Daemon Oct 13 00:07:33.074249 waagent[1819]: 2025-10-13T00:07:33.074142Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 28b3a3ff-30f6-4e52-b453-347e6d53f329 eTag: 7936097190457071140 source: Fabric] Oct 13 00:07:33.076521 waagent[1819]: 2025-10-13T00:07:33.076461Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Oct 13 00:07:33.077472 waagent[1819]: 2025-10-13T00:07:33.077415Z INFO Daemon Oct 13 00:07:33.078995 waagent[1819]: 2025-10-13T00:07:33.078945Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Oct 13 00:07:33.085627 waagent[1819]: 2025-10-13T00:07:33.085569Z INFO Daemon Daemon Downloading artifacts profile blob Oct 13 00:07:33.209801 systemd[1934]: Queued start job for default target default.target. Oct 13 00:07:33.219372 waagent[1819]: 2025-10-13T00:07:33.219218Z INFO Daemon Downloaded certificate {'thumbprint': '4338E0E11B5B9A0F6FB3F1E2E27644641B5BD6E3', 'hasPrivateKey': True} Oct 13 00:07:33.220256 systemd[1934]: Created slice app.slice - User Application Slice. Oct 13 00:07:33.220291 systemd[1934]: Reached target paths.target - Paths. Oct 13 00:07:33.220345 systemd[1934]: Reached target timers.target - Timers. Oct 13 00:07:33.221661 systemd[1934]: Starting dbus.socket - D-Bus User Message Bus Socket... Oct 13 00:07:33.222294 waagent[1819]: 2025-10-13T00:07:33.222236Z INFO Daemon Fetch goal state completed Oct 13 00:07:33.232360 systemd[1934]: Listening on dbus.socket - D-Bus User Message Bus Socket. Oct 13 00:07:33.232498 systemd[1934]: Reached target sockets.target - Sockets. Oct 13 00:07:33.232554 systemd[1934]: Reached target basic.target - Basic System. Oct 13 00:07:33.232603 systemd[1934]: Reached target default.target - Main User Target. Oct 13 00:07:33.232639 systemd[1934]: Startup finished in 535ms. Oct 13 00:07:33.232733 systemd[1]: Started user@500.service - User Manager for UID 500. Oct 13 00:07:33.234418 systemd[1]: Started session-2.scope - Session 2 of User core. Oct 13 00:07:33.260293 waagent[1819]: 2025-10-13T00:07:33.260224Z INFO Daemon Daemon Starting provisioning Oct 13 00:07:33.261961 waagent[1819]: 2025-10-13T00:07:33.261883Z INFO Daemon Daemon Handle ovf-env.xml. Oct 13 00:07:33.265576 waagent[1819]: 2025-10-13T00:07:33.265513Z INFO Daemon Daemon Set hostname [ci-4230.2.4-a-01a3a7f97b] Oct 13 00:07:33.312743 login[1823]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Oct 13 00:07:33.317798 systemd-logind[1687]: New session 1 of user core. Oct 13 00:07:33.331359 systemd[1]: Started session-1.scope - Session 1 of User core. Oct 13 00:07:33.374921 waagent[1819]: 2025-10-13T00:07:33.374835Z INFO Daemon Daemon Publish hostname [ci-4230.2.4-a-01a3a7f97b] Oct 13 00:07:33.377242 waagent[1819]: 2025-10-13T00:07:33.377018Z INFO Daemon Daemon Examine /proc/net/route for primary interface Oct 13 00:07:33.379524 waagent[1819]: 2025-10-13T00:07:33.379377Z INFO Daemon Daemon Primary interface is [eth0] Oct 13 00:07:33.390887 systemd-networkd[1607]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 13 00:07:33.390898 systemd-networkd[1607]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 13 00:07:33.390931 systemd-networkd[1607]: eth0: DHCP lease lost Oct 13 00:07:33.391473 waagent[1819]: 2025-10-13T00:07:33.391393Z INFO Daemon Daemon Create user account if not exists Oct 13 00:07:33.395727 waagent[1819]: 2025-10-13T00:07:33.395667Z INFO Daemon Daemon User core already exists, skip useradd Oct 13 00:07:33.397298 waagent[1819]: 2025-10-13T00:07:33.397247Z INFO Daemon Daemon Configure sudoer Oct 13 00:07:33.399377 waagent[1819]: 2025-10-13T00:07:33.399328Z INFO Daemon Daemon Configure sshd Oct 13 00:07:33.400998 waagent[1819]: 2025-10-13T00:07:33.400950Z 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 00:07:33.402222 waagent[1819]: 2025-10-13T00:07:33.402179Z INFO Daemon Daemon Deploy ssh public key. Oct 13 00:07:33.470173 systemd-networkd[1607]: eth0: DHCPv4 address 10.200.8.12/24, gateway 10.200.8.1 acquired from 168.63.129.16 Oct 13 00:07:34.796949 waagent[1819]: 2025-10-13T00:07:34.796858Z INFO Daemon Daemon Provisioning complete Oct 13 00:07:34.814786 waagent[1819]: 2025-10-13T00:07:34.814710Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Oct 13 00:07:34.816473 waagent[1819]: 2025-10-13T00:07:34.816410Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Oct 13 00:07:34.817176 waagent[1819]: 2025-10-13T00:07:34.817113Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.9.1.1 is the most current agent Oct 13 00:07:34.968651 waagent[1982]: 2025-10-13T00:07:34.968548Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.9.1.1) Oct 13 00:07:34.968988 waagent[1982]: 2025-10-13T00:07:34.968706Z INFO ExtHandler ExtHandler OS: flatcar 4230.2.4 Oct 13 00:07:34.968988 waagent[1982]: 2025-10-13T00:07:34.968785Z INFO ExtHandler ExtHandler Python: 3.11.11 Oct 13 00:07:35.103956 waagent[1982]: 2025-10-13T00:07:35.103796Z INFO ExtHandler ExtHandler Distro: flatcar-4230.2.4; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.11; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; Oct 13 00:07:35.104125 waagent[1982]: 2025-10-13T00:07:35.104073Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Oct 13 00:07:35.104232 waagent[1982]: 2025-10-13T00:07:35.104190Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Oct 13 00:07:35.119134 waagent[1982]: 2025-10-13T00:07:35.119050Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Oct 13 00:07:35.126239 waagent[1982]: 2025-10-13T00:07:35.126186Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.177 Oct 13 00:07:35.127970 waagent[1982]: 2025-10-13T00:07:35.127919Z INFO ExtHandler Oct 13 00:07:35.128066 waagent[1982]: 2025-10-13T00:07:35.128025Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: caeecb11-78ea-4dd1-8d97-530dc1e62762 eTag: 7936097190457071140 source: Fabric] Oct 13 00:07:35.128920 waagent[1982]: 2025-10-13T00:07:35.128868Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Oct 13 00:07:35.129629 waagent[1982]: 2025-10-13T00:07:35.129578Z INFO ExtHandler Oct 13 00:07:35.130012 waagent[1982]: 2025-10-13T00:07:35.129676Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Oct 13 00:07:35.133837 waagent[1982]: 2025-10-13T00:07:35.133794Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Oct 13 00:07:35.197286 waagent[1982]: 2025-10-13T00:07:35.197196Z INFO ExtHandler Downloaded certificate {'thumbprint': '4338E0E11B5B9A0F6FB3F1E2E27644641B5BD6E3', 'hasPrivateKey': True} Oct 13 00:07:35.197835 waagent[1982]: 2025-10-13T00:07:35.197780Z INFO ExtHandler Fetch goal state completed Oct 13 00:07:35.209586 waagent[1982]: 2025-10-13T00:07:35.209522Z INFO ExtHandler ExtHandler WALinuxAgent-2.9.1.1 running as process 1982 Oct 13 00:07:35.209735 waagent[1982]: 2025-10-13T00:07:35.209689Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Oct 13 00:07:35.211507 waagent[1982]: 2025-10-13T00:07:35.211457Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '4230.2.4', '', 'Flatcar Container Linux by Kinvolk'] Oct 13 00:07:35.211880 waagent[1982]: 2025-10-13T00:07:35.211834Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Oct 13 00:07:35.562414 waagent[1982]: 2025-10-13T00:07:35.562303Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Oct 13 00:07:35.562571 waagent[1982]: 2025-10-13T00:07:35.562525Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Oct 13 00:07:35.569449 waagent[1982]: 2025-10-13T00:07:35.569408Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Oct 13 00:07:35.579405 systemd[1]: Reload requested from client PID 1995 ('systemctl') (unit waagent.service)... Oct 13 00:07:35.579422 systemd[1]: Reloading... Oct 13 00:07:35.648147 zram_generator::config[2038]: No configuration found. Oct 13 00:07:35.788471 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Oct 13 00:07:35.959394 systemd[1]: Reloading finished in 379 ms. Oct 13 00:07:35.974852 waagent[1982]: 2025-10-13T00:07:35.974768Z INFO ExtHandler ExtHandler Executing systemctl daemon-reload for setting up waagent-network-setup.service Oct 13 00:07:35.981341 systemd[1]: Reload requested from client PID 2095 ('systemctl') (unit waagent.service)... Oct 13 00:07:35.981356 systemd[1]: Reloading... Oct 13 00:07:36.038247 zram_generator::config[2135]: No configuration found. Oct 13 00:07:36.180189 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Oct 13 00:07:36.325429 systemd[1]: Reloading finished in 343 ms. Oct 13 00:07:36.340679 waagent[1982]: 2025-10-13T00:07:36.340583Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Oct 13 00:07:36.340797 waagent[1982]: 2025-10-13T00:07:36.340753Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Oct 13 00:07:37.268218 waagent[1982]: 2025-10-13T00:07:37.268092Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Oct 13 00:07:37.268783 waagent[1982]: 2025-10-13T00:07:37.268721Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: configuration enabled [True], cgroups enabled [False], python supported: [True] Oct 13 00:07:37.269635 waagent[1982]: 2025-10-13T00:07:37.269576Z INFO ExtHandler ExtHandler Starting env monitor service. Oct 13 00:07:37.269982 waagent[1982]: 2025-10-13T00:07:37.269925Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Oct 13 00:07:37.270188 waagent[1982]: 2025-10-13T00:07:37.270139Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Oct 13 00:07:37.270431 waagent[1982]: 2025-10-13T00:07:37.270384Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Oct 13 00:07:37.271301 waagent[1982]: 2025-10-13T00:07:37.271243Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Oct 13 00:07:37.271426 waagent[1982]: 2025-10-13T00:07:37.271365Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Oct 13 00:07:37.271493 waagent[1982]: 2025-10-13T00:07:37.271445Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Oct 13 00:07:37.271692 waagent[1982]: 2025-10-13T00:07:37.271638Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Oct 13 00:07:37.272014 waagent[1982]: 2025-10-13T00:07:37.271968Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Oct 13 00:07:37.272585 waagent[1982]: 2025-10-13T00:07:37.272521Z INFO EnvHandler ExtHandler Configure routes Oct 13 00:07:37.272850 waagent[1982]: 2025-10-13T00:07:37.272783Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Oct 13 00:07:37.272918 waagent[1982]: 2025-10-13T00:07:37.272871Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Oct 13 00:07:37.273100 waagent[1982]: 2025-10-13T00:07:37.273055Z INFO EnvHandler ExtHandler Gateway:None Oct 13 00:07:37.273175 waagent[1982]: 2025-10-13T00:07:37.273105Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Oct 13 00:07:37.273175 waagent[1982]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Oct 13 00:07:37.273175 waagent[1982]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Oct 13 00:07:37.273175 waagent[1982]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Oct 13 00:07:37.273175 waagent[1982]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Oct 13 00:07:37.273175 waagent[1982]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Oct 13 00:07:37.273175 waagent[1982]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Oct 13 00:07:37.273338 waagent[1982]: 2025-10-13T00:07:37.273296Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Oct 13 00:07:37.273826 waagent[1982]: 2025-10-13T00:07:37.273771Z INFO EnvHandler ExtHandler Routes:None Oct 13 00:07:37.279306 waagent[1982]: 2025-10-13T00:07:37.279249Z INFO ExtHandler ExtHandler Oct 13 00:07:37.279631 waagent[1982]: 2025-10-13T00:07:37.279541Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: ad5cd269-c34a-418a-bcaf-02329e1897e8 correlation 28f40a16-b1b1-4088-9022-90b4212a15da created: 2025-10-13T00:05:55.247322Z] Oct 13 00:07:37.280851 waagent[1982]: 2025-10-13T00:07:37.280799Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Oct 13 00:07:37.282808 waagent[1982]: 2025-10-13T00:07:37.282757Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Oct 13 00:07:37.365949 waagent[1982]: 2025-10-13T00:07:37.365873Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.9.1.1 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 79F81413-4E42-490B-B220-6F8725F9CC79;DroppedPackets: 0;UpdateGSErrors: 0;AutoUpdate: 0] Oct 13 00:07:37.512820 waagent[1982]: 2025-10-13T00:07:37.512725Z INFO MonitorHandler ExtHandler Network interfaces: Oct 13 00:07:37.512820 waagent[1982]: Executing ['ip', '-a', '-o', 'link']: Oct 13 00:07:37.512820 waagent[1982]: 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 00:07:37.512820 waagent[1982]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:ed:8d:41:01:63 brd ff:ff:ff:ff:ff:ff Oct 13 00:07:37.512820 waagent[1982]: Executing ['ip', '-4', '-a', '-o', 'address']: Oct 13 00:07:37.512820 waagent[1982]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Oct 13 00:07:37.512820 waagent[1982]: 2: eth0 inet 10.200.8.12/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Oct 13 00:07:37.512820 waagent[1982]: Executing ['ip', '-6', '-a', '-o', 'address']: Oct 13 00:07:37.512820 waagent[1982]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Oct 13 00:07:37.512820 waagent[1982]: 2: eth0 inet6 fe80::7eed:8dff:fe41:163/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Oct 13 00:07:37.669741 waagent[1982]: 2025-10-13T00:07:37.669609Z INFO EnvHandler ExtHandler Successfully added Azure fabric firewall rules. Current Firewall rules: Oct 13 00:07:37.669741 waagent[1982]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Oct 13 00:07:37.669741 waagent[1982]: pkts bytes target prot opt in out source destination Oct 13 00:07:37.669741 waagent[1982]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Oct 13 00:07:37.669741 waagent[1982]: pkts bytes target prot opt in out source destination Oct 13 00:07:37.669741 waagent[1982]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Oct 13 00:07:37.669741 waagent[1982]: pkts bytes target prot opt in out source destination Oct 13 00:07:37.669741 waagent[1982]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Oct 13 00:07:37.669741 waagent[1982]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Oct 13 00:07:37.669741 waagent[1982]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Oct 13 00:07:37.674739 waagent[1982]: 2025-10-13T00:07:37.674678Z INFO EnvHandler ExtHandler Current Firewall rules: Oct 13 00:07:37.674739 waagent[1982]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Oct 13 00:07:37.674739 waagent[1982]: pkts bytes target prot opt in out source destination Oct 13 00:07:37.674739 waagent[1982]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Oct 13 00:07:37.674739 waagent[1982]: pkts bytes target prot opt in out source destination Oct 13 00:07:37.674739 waagent[1982]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Oct 13 00:07:37.674739 waagent[1982]: pkts bytes target prot opt in out source destination Oct 13 00:07:37.674739 waagent[1982]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Oct 13 00:07:37.674739 waagent[1982]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Oct 13 00:07:37.674739 waagent[1982]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Oct 13 00:07:37.675023 waagent[1982]: 2025-10-13T00:07:37.674981Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Oct 13 00:07:37.675220 waagent[1982]: 2025-10-13T00:07:37.675179Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Oct 13 00:07:41.894162 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Oct 13 00:07:41.905356 systemd[1]: Started sshd@0-10.200.8.12:22-10.200.16.10:48312.service - OpenSSH per-connection server daemon (10.200.16.10:48312). Oct 13 00:07:42.921737 sshd[2224]: Accepted publickey for core from 10.200.16.10 port 48312 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:07:42.923203 sshd-session[2224]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:07:42.928439 systemd-logind[1687]: New session 3 of user core. Oct 13 00:07:42.946305 systemd[1]: Started session-3.scope - Session 3 of User core. Oct 13 00:07:43.484476 systemd[1]: Started sshd@1-10.200.8.12:22-10.200.16.10:48318.service - OpenSSH per-connection server daemon (10.200.16.10:48318). Oct 13 00:07:44.142305 sshd[2229]: Accepted publickey for core from 10.200.16.10 port 48318 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:07:44.143642 sshd-session[2229]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:07:44.147977 systemd-logind[1687]: New session 4 of user core. Oct 13 00:07:44.160247 systemd[1]: Started session-4.scope - Session 4 of User core. Oct 13 00:07:44.595394 sshd[2231]: Connection closed by 10.200.16.10 port 48318 Oct 13 00:07:44.595761 sshd-session[2229]: pam_unix(sshd:session): session closed for user core Oct 13 00:07:44.598862 systemd[1]: sshd@1-10.200.8.12:22-10.200.16.10:48318.service: Deactivated successfully. Oct 13 00:07:44.600461 systemd[1]: session-4.scope: Deactivated successfully. Oct 13 00:07:44.601082 systemd-logind[1687]: Session 4 logged out. Waiting for processes to exit. Oct 13 00:07:44.601866 systemd-logind[1687]: Removed session 4. Oct 13 00:07:44.708432 systemd[1]: Started sshd@2-10.200.8.12:22-10.200.16.10:48324.service - OpenSSH per-connection server daemon (10.200.16.10:48324). Oct 13 00:07:45.353592 sshd[2237]: Accepted publickey for core from 10.200.16.10 port 48324 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:07:45.354924 sshd-session[2237]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:07:45.359007 systemd-logind[1687]: New session 5 of user core. Oct 13 00:07:45.373280 systemd[1]: Started session-5.scope - Session 5 of User core. Oct 13 00:07:45.806998 sshd[2239]: Connection closed by 10.200.16.10 port 48324 Oct 13 00:07:45.807444 sshd-session[2237]: pam_unix(sshd:session): session closed for user core Oct 13 00:07:45.810573 systemd[1]: sshd@2-10.200.8.12:22-10.200.16.10:48324.service: Deactivated successfully. Oct 13 00:07:45.812140 systemd[1]: session-5.scope: Deactivated successfully. Oct 13 00:07:45.812756 systemd-logind[1687]: Session 5 logged out. Waiting for processes to exit. Oct 13 00:07:45.813526 systemd-logind[1687]: Removed session 5. Oct 13 00:07:47.444854 chronyd[1686]: Selected source PHC0 Oct 13 00:07:48.968936 setup-nvidia[1845]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 480M 0 112k 0 0 90470 0 1:32:51 0:00:01 1:32:50 90447\u000d 2 480M 2 10.0M 0 0 4597k 0 0:01:47 0:00:02 0:01:45 4595k\u000d 6 480M 6 30.5M 0 0 9730k 0 0:00:50 0:00:03 0:00:47 9729k\u000d 10 480M 10 52.0M 0 0 12.4M 0 0:00:38 0:00:04 0:00:34 12.4M\u000d 15 480M 15 75.3M 0 0 14.2M 0 0:00:33 0:00:05 0:00:28 15.4M\u000d 20 480M 20 96.7M 0 0 15.3M 0 0:00:31 0:00:06 0:00:25 19.2M\u000d 24 480M 24 115M 0 0 16.0M 0 0:00:29 0:00:07 0:00:22 21.2M\u000d 29 480M 29 141M 0 0 17.2M 0 0:00:27 0:00:08 0:00:19 22.2M\u000d 33 480M 33 162M 0 0 17.4M 0 0:00:27 0:00:09 0:00:18 21.6M\u000d 38 480M 38 184M 0 0 17.9M 0 0:00:26 0:00:10 0:00:16 21.9M\u000d 42 480M 42 204M 0 0 18.1M 0 0:00:26 0:00:11 0:00:15 21.6M\u000d 47 480M 47 226M 0 0 18.5M 0 0:00:25 0:00:12 0:00:13 22.0M\u000d 51 480M 51 247M 0 0 18.7M 0 0:00:25 0:00:13 0:00:12 21.1M\u000d 56 480M 56 269M 0 0 19.0M 0 0:00:25 0:00:14 0:00:11 21.9M\u000d 60 480M 60 292M 0 0 19.1M 0 0:00:25 0:00:15 0:00:10 21.4M\u000d 65 480M 65 314M 0 0 19.2M 0 0:00:24 0:00:16 0:00:08 21.7M\u000d 69 480M 69 336M 0 0 19.4M 0 0:00:24 0:00:17 0:00:07 21.7M\u000d 74 480M 74 357M 0 0 19.6M 0 0:00:24 0:00:18 0:00:06 21.8M\u000d 78 480M 78 378M 0 0 19.7M 0 0:00:24 0:00:19 0:00:05 21.6M\u000d 83 480M 83 399M 0 0 19.7M 0 0:00:24 0:00:20 0:00:04 21.8M\u000d 87 480M 87 421M 0 0 19.8M 0 0:00:24 0:00:21 0:00:03 21.9M\u000d 92 480M 92 443M 0 0 19.8M 0 0:00:24 0:00:22 0:00:02 21.3M\u000d 96 480M 96 464M 0 0 19.9M 0 0:00:24 0:00:23 0:00:01 21.0M\u000d100 480M 100 480M 0 0 20.0M 0 0:00:24 0:00:24 --:--:-- 21.3M Oct 13 00:07:55.936458 systemd[1]: Started sshd@3-10.200.8.12:22-10.200.16.10:52554.service - OpenSSH per-connection server daemon (10.200.16.10:52554). Oct 13 00:07:56.580817 sshd[2255]: Accepted publickey for core from 10.200.16.10 port 52554 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:07:56.581902 sshd-session[2255]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:07:56.590595 systemd-logind[1687]: New session 6 of user core. Oct 13 00:07:56.599324 systemd[1]: Started session-6.scope - Session 6 of User core. Oct 13 00:07:57.062727 sshd[2257]: Connection closed by 10.200.16.10 port 52554 Oct 13 00:07:57.062554 sshd-session[2255]: pam_unix(sshd:session): session closed for user core Oct 13 00:07:57.065948 systemd-logind[1687]: Session 6 logged out. Waiting for processes to exit. Oct 13 00:07:57.066518 systemd[1]: sshd@3-10.200.8.12:22-10.200.16.10:52554.service: Deactivated successfully. Oct 13 00:07:57.070219 systemd[1]: session-6.scope: Deactivated successfully. Oct 13 00:07:57.072891 systemd-logind[1687]: Removed session 6. Oct 13 00:07:57.180415 systemd[1]: Started sshd@4-10.200.8.12:22-10.200.16.10:52556.service - OpenSSH per-connection server daemon (10.200.16.10:52556). Oct 13 00:07:57.824250 sshd[2263]: Accepted publickey for core from 10.200.16.10 port 52556 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:07:57.827473 sshd-session[2263]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:07:57.840578 systemd-logind[1687]: New session 7 of user core. Oct 13 00:07:57.851307 systemd[1]: Started session-7.scope - Session 7 of User core. Oct 13 00:07:58.294528 sshd[2265]: Connection closed by 10.200.16.10 port 52556 Oct 13 00:07:58.293474 sshd-session[2263]: pam_unix(sshd:session): session closed for user core Oct 13 00:07:58.301899 systemd[1]: sshd@4-10.200.8.12:22-10.200.16.10:52556.service: Deactivated successfully. Oct 13 00:07:58.303650 systemd[1]: session-7.scope: Deactivated successfully. Oct 13 00:07:58.307412 systemd-logind[1687]: Session 7 logged out. Waiting for processes to exit. Oct 13 00:07:58.308302 systemd-logind[1687]: Removed session 7. Oct 13 00:08:01.707513 setup-nvidia[1681]: Downloading NVIDIA 535.230.02 Driver Oct 13 00:08:01.711944 setup-nvidia[2271]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 13 00:08:01.712191 setup-nvidia[2271]: Dload Upload Total Spent Left Speed Oct 13 00:08:03.069446 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Oct 13 00:08:04.115998 setup-nvidia[2271]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 30 326M 30 99.1M 0 0 221M 0 0:00:01 --:--:-- 0:00:01 221M\u000d 84 326M 84 276M 0 0 190M 0 0:00:01 0:00:01 --:--:-- 190M\u000d100 326M 100 326M 0 0 135M 0 0:00:02 0:00:02 --:--:-- 135M Oct 13 00:08:05.604253 setup-nvidia[1681]: Extract the NVIDIA Driver Installer 535.230.02 Oct 13 00:08:05.604253 setup-nvidia[1681]: /opt/nvidia/workdir/nvidia-workdir / Oct 13 00:08:05.662084 setup-nvidia[2274]: Creating directory NVIDIA-Linux-x86_64-535.230.02 Oct 13 00:08:06.012335 setup-nvidia[2274]: Verifying archive integrity... OK Oct 13 00:08:08.322653 setup-nvidia[2274]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.230.02 Oct 13 00:08:08.406916 systemd[1]: Started sshd@5-10.200.8.12:22-10.200.16.10:54562.service - OpenSSH per-connection server daemon (10.200.16.10:54562). Oct 13 00:08:09.026292 update_engine[1690]: I20251013 00:08:09.026212 1690 update_attempter.cc:509] Updating boot flags... Oct 13 00:08:09.048254 sshd[2305]: Accepted publickey for core from 10.200.16.10 port 54562 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:08:09.049634 sshd-session[2305]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:08:09.058306 systemd-logind[1687]: New session 8 of user core. Oct 13 00:08:09.065263 systemd[1]: Started session-8.scope - Session 8 of User core. Oct 13 00:08:09.095470 kernel: BTRFS warning: duplicate device /dev/sdb3 devid 1 generation 38 scanned by (udev-worker) (2323) Oct 13 00:08:09.198282 kernel: BTRFS warning: duplicate device /dev/sdb3 devid 1 generation 38 scanned by (udev-worker) (2323) Oct 13 00:08:09.500717 sshd[2315]: Connection closed by 10.200.16.10 port 54562 Oct 13 00:08:09.501139 sshd-session[2305]: pam_unix(sshd:session): session closed for user core Oct 13 00:08:09.504488 systemd[1]: sshd@5-10.200.8.12:22-10.200.16.10:54562.service: Deactivated successfully. Oct 13 00:08:09.506197 systemd[1]: session-8.scope: Deactivated successfully. Oct 13 00:08:09.506822 systemd-logind[1687]: Session 8 logged out. Waiting for processes to exit. Oct 13 00:08:09.507765 systemd-logind[1687]: Removed session 8. Oct 13 00:08:09.616767 systemd[1]: Started sshd@6-10.200.8.12:22-10.200.16.10:54574.service - OpenSSH per-connection server daemon (10.200.16.10:54574). Oct 13 00:08:10.205341 setup-nvidia[2300]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Oct 13 00:08:10.256128 sshd[2427]: Accepted publickey for core from 10.200.16.10 port 54574 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:08:10.257449 sshd-session[2427]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:08:10.261647 systemd-logind[1687]: New session 9 of user core. Oct 13 00:08:10.271256 systemd[1]: Started session-9.scope - Session 9 of User core. Oct 13 00:08:10.281726 setup-nvidia[1681]: / Oct 13 00:08:10.281953 setup-nvidia[1681]: Spawn system-nspawn container to install the NVIDIA drivers Oct 13 00:08:10.557272 sudo[2433]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4230.2.4.bin --overlay=/usr/lib/modules::/usr/lib/modules --bind=/opt/nvidia/workdir/nvidia-workdir:/nvidia --bind=/usr/lib/nvidia/bin:/app/bin/ /app/bin/install-nvidia NVIDIA-Linux-x86_64-535.230.02 Oct 13 00:08:10.559020 sudo[2433]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 13 00:08:10.566345 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Oct 13 00:08:10.586334 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Oct 13 00:08:10.597355 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Oct 13 00:08:10.604911 systemd[1]: Starting user@0.service - User Manager for UID 0... Oct 13 00:08:10.610873 (systemd)[2437]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Oct 13 00:08:10.613038 systemd-logind[1687]: New session c3 of user root. Oct 13 00:08:10.710858 sshd[2432]: Connection closed by 10.200.16.10 port 54574 Oct 13 00:08:10.711347 sshd-session[2427]: pam_unix(sshd:session): session closed for user core Oct 13 00:08:10.714234 systemd[1]: sshd@6-10.200.8.12:22-10.200.16.10:54574.service: Deactivated successfully. Oct 13 00:08:10.715833 systemd[1]: session-9.scope: Deactivated successfully. Oct 13 00:08:10.716473 systemd-logind[1687]: Session 9 logged out. Waiting for processes to exit. Oct 13 00:08:10.717214 systemd-logind[1687]: Removed session 9. Oct 13 00:08:10.790412 systemd[2437]: Queued start job for default target default.target. Oct 13 00:08:10.801162 systemd[2437]: Created slice app.slice - User Application Slice. Oct 13 00:08:10.801192 systemd[2437]: Reached target paths.target - Paths. Oct 13 00:08:10.801240 systemd[2437]: Reached target timers.target - Timers. Oct 13 00:08:10.802378 systemd[2437]: Starting dbus.socket - D-Bus User Message Bus Socket... Oct 13 00:08:10.812575 systemd[2437]: Listening on dbus.socket - D-Bus User Message Bus Socket. Oct 13 00:08:10.812637 systemd[2437]: Reached target sockets.target - Sockets. Oct 13 00:08:10.812690 systemd[2437]: Reached target basic.target - Basic System. Oct 13 00:08:10.812739 systemd[2437]: Reached target default.target - Main User Target. Oct 13 00:08:10.812772 systemd[2437]: Startup finished in 194ms. Oct 13 00:08:10.812821 systemd[1]: Started user@0.service - User Manager for UID 0. Oct 13 00:08:10.815712 systemd[1]: Started session-c2.scope - Session c2 of User root. Oct 13 00:08:10.880136 kernel: loop6: detected capacity change from 0 to 12644352 Oct 13 00:08:10.888137 kernel: loop6: p9 Oct 13 00:08:11.035869 setup-nvidia[2449]: ░ Spawning container flatcardevelopercontainer-4230.2.4.bin on /opt/nvidia/workdir/flatcar_developer_container-4230.2.4.bin. Oct 13 00:08:11.045156 kernel: EXT4-fs (loop6p9): mounted filesystem aea75de1-cc22-441d-8bc9-4b30365730be ro without journal. Quota mode: none. Oct 13 00:08:20.832525 systemd[1]: Started sshd@7-10.200.8.12:22-10.200.16.10:35388.service - OpenSSH per-connection server daemon (10.200.16.10:35388). Oct 13 00:08:21.474235 sshd[3472]: Accepted publickey for core from 10.200.16.10 port 35388 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:08:21.475568 sshd-session[3472]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:08:21.479903 systemd-logind[1687]: New session 10 of user core. Oct 13 00:08:21.496237 systemd[1]: Started session-10.scope - Session 10 of User core. Oct 13 00:08:21.929190 sshd[3474]: Connection closed by 10.200.16.10 port 35388 Oct 13 00:08:21.929586 sshd-session[3472]: pam_unix(sshd:session): session closed for user core Oct 13 00:08:21.932746 systemd[1]: sshd@7-10.200.8.12:22-10.200.16.10:35388.service: Deactivated successfully. Oct 13 00:08:21.934349 systemd[1]: session-10.scope: Deactivated successfully. Oct 13 00:08:21.934963 systemd-logind[1687]: Session 10 logged out. Waiting for processes to exit. Oct 13 00:08:21.935730 systemd-logind[1687]: Removed session 10. Oct 13 00:08:22.043430 systemd[1]: Started sshd@8-10.200.8.12:22-10.200.16.10:35404.service - OpenSSH per-connection server daemon (10.200.16.10:35404). Oct 13 00:08:22.690681 sshd[3480]: Accepted publickey for core from 10.200.16.10 port 35404 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:08:22.692011 sshd-session[3480]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:08:22.696151 systemd-logind[1687]: New session 11 of user core. Oct 13 00:08:22.707767 systemd[1]: Started session-11.scope - Session 11 of User core. Oct 13 00:08:23.143901 sshd[3482]: Connection closed by 10.200.16.10 port 35404 Oct 13 00:08:23.144324 sshd-session[3480]: pam_unix(sshd:session): session closed for user core Oct 13 00:08:23.147460 systemd[1]: sshd@8-10.200.8.12:22-10.200.16.10:35404.service: Deactivated successfully. Oct 13 00:08:23.149024 systemd[1]: session-11.scope: Deactivated successfully. Oct 13 00:08:23.149688 systemd-logind[1687]: Session 11 logged out. Waiting for processes to exit. Oct 13 00:08:23.150472 systemd-logind[1687]: Removed session 11. Oct 13 00:08:33.258656 systemd[1]: Started sshd@9-10.200.8.12:22-10.200.16.10:45592.service - OpenSSH per-connection server daemon (10.200.16.10:45592). Oct 13 00:08:33.901083 sshd[5206]: Accepted publickey for core from 10.200.16.10 port 45592 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:08:33.902435 sshd-session[5206]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:08:33.906603 systemd-logind[1687]: New session 12 of user core. Oct 13 00:08:33.916234 systemd[1]: Started session-12.scope - Session 12 of User core. Oct 13 00:08:34.358312 sshd[5522]: Connection closed by 10.200.16.10 port 45592 Oct 13 00:08:34.359944 sshd-session[5206]: pam_unix(sshd:session): session closed for user core Oct 13 00:08:34.367494 systemd[1]: sshd@9-10.200.8.12:22-10.200.16.10:45592.service: Deactivated successfully. Oct 13 00:08:34.369278 systemd[1]: session-12.scope: Deactivated successfully. Oct 13 00:08:34.370090 systemd-logind[1687]: Session 12 logged out. Waiting for processes to exit. Oct 13 00:08:34.372405 systemd-logind[1687]: Removed session 12. Oct 13 00:08:34.544512 systemd[1]: Started sshd@10-10.200.8.12:22-10.200.16.10:45598.service - OpenSSH per-connection server daemon (10.200.16.10:45598). Oct 13 00:08:35.190961 sshd[5741]: Accepted publickey for core from 10.200.16.10 port 45598 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:08:35.192746 sshd-session[5741]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:08:35.198478 systemd-logind[1687]: New session 13 of user core. Oct 13 00:08:35.212593 systemd[1]: Started session-13.scope - Session 13 of User core. Oct 13 00:08:35.650803 sshd[5972]: Connection closed by 10.200.16.10 port 45598 Oct 13 00:08:35.652773 sshd-session[5741]: pam_unix(sshd:session): session closed for user core Oct 13 00:08:35.655706 systemd-logind[1687]: Session 13 logged out. Waiting for processes to exit. Oct 13 00:08:35.656478 systemd[1]: sshd@10-10.200.8.12:22-10.200.16.10:45598.service: Deactivated successfully. Oct 13 00:08:35.659356 systemd[1]: session-13.scope: Deactivated successfully. Oct 13 00:08:35.661766 systemd-logind[1687]: Removed session 13. Oct 13 00:08:45.773627 systemd[1]: Started sshd@11-10.200.8.12:22-10.200.16.10:40670.service - OpenSSH per-connection server daemon (10.200.16.10:40670). Oct 13 00:08:46.416834 sshd[8834]: Accepted publickey for core from 10.200.16.10 port 40670 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:08:46.420749 sshd-session[8834]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:08:46.426169 systemd-logind[1687]: New session 14 of user core. Oct 13 00:08:46.437292 systemd[1]: Started session-14.scope - Session 14 of User core. Oct 13 00:08:46.882736 sshd[9144]: Connection closed by 10.200.16.10 port 40670 Oct 13 00:08:46.881683 sshd-session[8834]: pam_unix(sshd:session): session closed for user core Oct 13 00:08:46.885785 systemd-logind[1687]: Session 14 logged out. Waiting for processes to exit. Oct 13 00:08:46.886784 systemd[1]: sshd@11-10.200.8.12:22-10.200.16.10:40670.service: Deactivated successfully. Oct 13 00:08:46.889322 systemd[1]: session-14.scope: Deactivated successfully. Oct 13 00:08:46.891663 systemd-logind[1687]: Removed session 14. Oct 13 00:08:47.000212 systemd[1]: Started sshd@12-10.200.8.12:22-10.200.16.10:40682.service - OpenSSH per-connection server daemon (10.200.16.10:40682). Oct 13 00:08:47.646594 sshd[9264]: Accepted publickey for core from 10.200.16.10 port 40682 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:08:47.648353 sshd-session[9264]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:08:47.653692 systemd-logind[1687]: New session 15 of user core. Oct 13 00:08:47.662278 systemd[1]: Started session-15.scope - Session 15 of User core. Oct 13 00:08:48.102494 sshd[9467]: Connection closed by 10.200.16.10 port 40682 Oct 13 00:08:48.103888 sshd-session[9264]: pam_unix(sshd:session): session closed for user core Oct 13 00:08:48.111013 systemd[1]: sshd@12-10.200.8.12:22-10.200.16.10:40682.service: Deactivated successfully. Oct 13 00:08:48.113538 systemd[1]: session-15.scope: Deactivated successfully. Oct 13 00:08:48.115436 systemd-logind[1687]: Session 15 logged out. Waiting for processes to exit. Oct 13 00:08:48.116882 systemd-logind[1687]: Removed session 15. Oct 13 00:08:58.222791 systemd[1]: Started sshd@13-10.200.8.12:22-10.200.16.10:49558.service - OpenSSH per-connection server daemon (10.200.16.10:49558). Oct 13 00:08:58.876024 sshd[11687]: Accepted publickey for core from 10.200.16.10 port 49558 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:08:58.881255 sshd-session[11687]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:08:58.887868 systemd-logind[1687]: New session 16 of user core. Oct 13 00:08:58.900127 systemd[1]: Started session-16.scope - Session 16 of User core. Oct 13 00:08:59.330989 sshd[11728]: Connection closed by 10.200.16.10 port 49558 Oct 13 00:08:59.334395 sshd-session[11687]: pam_unix(sshd:session): session closed for user core Oct 13 00:08:59.337458 systemd-logind[1687]: Session 16 logged out. Waiting for processes to exit. Oct 13 00:08:59.338150 systemd[1]: sshd@13-10.200.8.12:22-10.200.16.10:49558.service: Deactivated successfully. Oct 13 00:08:59.339900 systemd[1]: session-16.scope: Deactivated successfully. Oct 13 00:08:59.343436 systemd-logind[1687]: Removed session 16. Oct 13 00:08:59.454205 systemd[1]: Started sshd@14-10.200.8.12:22-10.200.16.10:49566.service - OpenSSH per-connection server daemon (10.200.16.10:49566). Oct 13 00:09:00.103410 sshd[11803]: Accepted publickey for core from 10.200.16.10 port 49566 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:09:00.104424 sshd-session[11803]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:09:00.112175 systemd-logind[1687]: New session 17 of user core. Oct 13 00:09:00.120852 systemd[1]: Started session-17.scope - Session 17 of User core. Oct 13 00:09:00.558936 sshd[11829]: Connection closed by 10.200.16.10 port 49566 Oct 13 00:09:00.561031 sshd-session[11803]: pam_unix(sshd:session): session closed for user core Oct 13 00:09:00.566220 systemd[1]: sshd@14-10.200.8.12:22-10.200.16.10:49566.service: Deactivated successfully. Oct 13 00:09:00.567783 systemd[1]: session-17.scope: Deactivated successfully. Oct 13 00:09:00.568898 systemd-logind[1687]: Session 17 logged out. Waiting for processes to exit. Oct 13 00:09:00.569919 systemd-logind[1687]: Removed session 17. Oct 13 00:09:10.680327 systemd[1]: Started sshd@15-10.200.8.12:22-10.200.16.10:48308.service - OpenSSH per-connection server daemon (10.200.16.10:48308). Oct 13 00:09:11.330774 sshd[12148]: Accepted publickey for core from 10.200.16.10 port 48308 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:09:11.334740 sshd-session[12148]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:09:11.341860 systemd-logind[1687]: New session 18 of user core. Oct 13 00:09:11.348916 systemd[1]: Started session-18.scope - Session 18 of User core. Oct 13 00:09:11.785560 sshd[12173]: Connection closed by 10.200.16.10 port 48308 Oct 13 00:09:11.785871 sshd-session[12148]: pam_unix(sshd:session): session closed for user core Oct 13 00:09:11.789952 systemd[1]: sshd@15-10.200.8.12:22-10.200.16.10:48308.service: Deactivated successfully. Oct 13 00:09:11.791831 systemd[1]: session-18.scope: Deactivated successfully. Oct 13 00:09:11.792420 systemd-logind[1687]: Session 18 logged out. Waiting for processes to exit. Oct 13 00:09:11.794654 systemd-logind[1687]: Removed session 18. Oct 13 00:09:11.905855 systemd[1]: Started sshd@16-10.200.8.12:22-10.200.16.10:48322.service - OpenSSH per-connection server daemon (10.200.16.10:48322). Oct 13 00:09:12.556497 sshd[12197]: Accepted publickey for core from 10.200.16.10 port 48322 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:09:12.558724 sshd-session[12197]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:09:12.565736 systemd-logind[1687]: New session 19 of user core. Oct 13 00:09:12.574263 systemd[1]: Started session-19.scope - Session 19 of User core. Oct 13 00:09:13.018369 sshd[12226]: Connection closed by 10.200.16.10 port 48322 Oct 13 00:09:13.018680 sshd-session[12197]: pam_unix(sshd:session): session closed for user core Oct 13 00:09:13.023445 systemd-logind[1687]: Session 19 logged out. Waiting for processes to exit. Oct 13 00:09:13.023858 systemd[1]: sshd@16-10.200.8.12:22-10.200.16.10:48322.service: Deactivated successfully. Oct 13 00:09:13.025755 systemd[1]: session-19.scope: Deactivated successfully. Oct 13 00:09:13.029476 systemd-logind[1687]: Removed session 19. Oct 13 00:09:23.144550 systemd[1]: Started sshd@17-10.200.8.12:22-10.200.16.10:37032.service - OpenSSH per-connection server daemon (10.200.16.10:37032). Oct 13 00:09:23.797325 sshd[12535]: Accepted publickey for core from 10.200.16.10 port 37032 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:09:23.799208 sshd-session[12535]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:09:23.805755 systemd-logind[1687]: New session 20 of user core. Oct 13 00:09:23.817324 systemd[1]: Started session-20.scope - Session 20 of User core. Oct 13 00:09:24.257546 sshd[12561]: Connection closed by 10.200.16.10 port 37032 Oct 13 00:09:24.261598 sshd-session[12535]: pam_unix(sshd:session): session closed for user core Oct 13 00:09:24.264876 systemd-logind[1687]: Session 20 logged out. Waiting for processes to exit. Oct 13 00:09:24.266449 systemd[1]: sshd@17-10.200.8.12:22-10.200.16.10:37032.service: Deactivated successfully. Oct 13 00:09:24.268038 systemd[1]: session-20.scope: Deactivated successfully. Oct 13 00:09:24.269950 systemd-logind[1687]: Removed session 20. Oct 13 00:09:24.378557 systemd[1]: Started sshd@18-10.200.8.12:22-10.200.16.10:37048.service - OpenSSH per-connection server daemon (10.200.16.10:37048). Oct 13 00:09:25.026644 sshd[12582]: Accepted publickey for core from 10.200.16.10 port 37048 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:09:25.028717 sshd-session[12582]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:09:25.034788 systemd-logind[1687]: New session 21 of user core. Oct 13 00:09:25.044982 systemd[1]: Started session-21.scope - Session 21 of User core. Oct 13 00:09:25.484490 sshd[12623]: Connection closed by 10.200.16.10 port 37048 Oct 13 00:09:25.486050 sshd-session[12582]: pam_unix(sshd:session): session closed for user core Oct 13 00:09:25.489415 systemd[1]: sshd@18-10.200.8.12:22-10.200.16.10:37048.service: Deactivated successfully. Oct 13 00:09:25.490996 systemd[1]: session-21.scope: Deactivated successfully. Oct 13 00:09:25.492484 systemd-logind[1687]: Session 21 logged out. Waiting for processes to exit. Oct 13 00:09:25.493587 systemd-logind[1687]: Removed session 21. Oct 13 00:09:35.569787 setup-nvidia[2449]: \u000d Oct 13 00:09:35.569787 setup-nvidia[2449]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Oct 13 00:09:35.569787 setup-nvidia[2449]: frequently when this kernel module was built against the wrong or Oct 13 00:09:35.569787 setup-nvidia[2449]: improperly configured kernel sources, with a version of gcc that differs Oct 13 00:09:35.569787 setup-nvidia[2449]: from the one used to build the target kernel, or if another driver, such Oct 13 00:09:35.569787 setup-nvidia[2449]: as nouveau, is present and prevents the NVIDIA kernel module from Oct 13 00:09:35.569787 setup-nvidia[2449]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Oct 13 00:09:35.569787 setup-nvidia[2449]: installed in this system is supported by this NVIDIA Linux graphics Oct 13 00:09:35.569787 setup-nvidia[2449]: driver release. Oct 13 00:09:35.569787 setup-nvidia[2449]: \u000d Oct 13 00:09:35.569787 setup-nvidia[2449]: Please see the log entries 'Kernel module load error' and 'Kernel Oct 13 00:09:35.569787 setup-nvidia[2449]: messages' at the end of the file Oct 13 00:09:35.569787 setup-nvidia[2449]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more Oct 13 00:09:35.569787 setup-nvidia[2449]: information. Oct 13 00:09:35.569787 setup-nvidia[2449]: \u000d Oct 13 00:09:35.585472 setup-nvidia[2449]: dmesg: read kernel buffer failed: Operation not permitted Oct 13 00:09:35.603346 systemd[1]: Started sshd@19-10.200.8.12:22-10.200.16.10:49424.service - OpenSSH per-connection server daemon (10.200.16.10:49424). Oct 13 00:09:35.606825 setup-nvidia[2449]: \u000d Oct 13 00:09:35.606825 setup-nvidia[2449]: ERROR: Installation has failed. Please see the file Oct 13 00:09:35.606825 setup-nvidia[2449]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for Oct 13 00:09:35.606825 setup-nvidia[2449]: details. You may find suggestions on fixing installation problems in Oct 13 00:09:35.606825 setup-nvidia[2449]: the README available on the Linux driver download page at Oct 13 00:09:35.606825 setup-nvidia[2449]: www.nvidia.com. Oct 13 00:09:35.606825 setup-nvidia[2449]: \u000d Oct 13 00:09:35.607527 setup-nvidia[2449]: Last 50 lines of nvidia-installer.log: Oct 13 00:09:35.608535 setup-nvidia[2449]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038989rm+0x32: 'naked' return found in RETHUNK build Oct 13 00:09:35.608535 setup-nvidia[2449]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039000rm+0x53: 'naked' return found in RETHUNK build Oct 13 00:09:35.608535 setup-nvidia[2449]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038997rm+0x90: 'naked' return found in RETHUNK build Oct 13 00:09:35.608535 setup-nvidia[2449]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038990rm+0x2b: 'naked' return found in RETHUNK build Oct 13 00:09:35.608535 setup-nvidia[2449]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039046rm+0x93: 'naked' return found in RETHUNK build Oct 13 00:09:35.608535 setup-nvidia[2449]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039042rm+0x36: 'naked' return found in RETHUNK build Oct 13 00:09:35.608535 setup-nvidia[2449]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039041rm+0x9d: 'naked' return found in RETHUNK build Oct 13 00:09:35.608535 setup-nvidia[2449]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039043rm+0x77: 'naked' return found in RETHUNK build Oct 13 00:09:35.608535 setup-nvidia[2449]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038987rm+0x7f: 'naked' return found in RETHUNK build Oct 13 00:09:35.608535 setup-nvidia[2449]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030558rm+0x3e: 'naked' return found in RETHUNK build Oct 13 00:09:35.608535 setup-nvidia[2449]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030557rm+0x27: 'naked' return found in RETHUNK build Oct 13 00:09:35.608535 setup-nvidia[2449]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030561rm+0x37: 'naked' return found in RETHUNK build Oct 13 00:09:35.608535 setup-nvidia[2449]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030560rm+0x24: 'naked' return found in RETHUNK build Oct 13 00:09:35.608535 setup-nvidia[2449]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030559rm+0xca: 'naked' return found in RETHUNK build Oct 13 00:09:35.608535 setup-nvidia[2449]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030562rm+0x193: 'naked' return found in RETHUNK build Oct 13 00:09:35.608535 setup-nvidia[2449]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030564rm+0x17f: 'naked' return found in RETHUNK build Oct 13 00:09:35.608535 setup-nvidia[2449]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv011860rm+0x5d: 'naked' return found in RETHUNK build Oct 13 00:09:35.608535 setup-nvidia[2449]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039354rm+0x12f: 'naked' return found in RETHUNK build Oct 13 00:09:35.608535 setup-nvidia[2449]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/Module.symvers Oct 13 00:09:35.608535 setup-nvidia[2449]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.mod.o Oct 13 00:09:35.608535 setup-nvidia[2449]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.mod.o Oct 13 00:09:35.608535 setup-nvidia[2449]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.mod.o Oct 13 00:09:35.608535 setup-nvidia[2449]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.mod.o Oct 13 00:09:35.608535 setup-nvidia[2449]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.mod.o Oct 13 00:09:35.608535 setup-nvidia[2449]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Oct 13 00:09:35.608535 setup-nvidia[2449]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Oct 13 00:09:35.608535 setup-nvidia[2449]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Oct 13 00:09:35.608535 setup-nvidia[2449]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Oct 13 00:09:35.608535 setup-nvidia[2449]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Oct 13 00:09:35.608535 setup-nvidia[2449]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Oct 13 00:09:35.608535 setup-nvidia[2449]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko due to unavailability of vmlinux Oct 13 00:09:35.608535 setup-nvidia[2449]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Oct 13 00:09:35.608535 setup-nvidia[2449]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko due to unavailability of vmlinux Oct 13 00:09:35.608535 setup-nvidia[2449]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Oct 13 00:09:35.608535 setup-nvidia[2449]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko due to unavailability of vmlinux Oct 13 00:09:35.608535 setup-nvidia[2449]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Oct 13 00:09:35.608535 setup-nvidia[2449]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko due to unavailability of vmlinux Oct 13 00:09:35.608535 setup-nvidia[2449]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Oct 13 00:09:35.608535 setup-nvidia[2449]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko due to unavailability of vmlinux Oct 13 00:09:35.608535 setup-nvidia[2449]: make[2]: Leaving directory '/usr/lib/modules/6.6.110-flatcar/build' Oct 13 00:09:35.608535 setup-nvidia[2449]: make[1]: Leaving directory '/usr/lib/modules/6.6.110-flatcar/source' Oct 13 00:09:35.608535 setup-nvidia[2449]: -> done. Oct 13 00:09:35.608535 setup-nvidia[2449]: -> Kernel module compilation complete. Oct 13 00:09:35.608535 setup-nvidia[2449]: -> Unable to determine if Secure Boot is enabled: No such file or directory Oct 13 00:09:35.608535 setup-nvidia[2449]: 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 00:09:35.608535 setup-nvidia[2449]: \u000d Oct 13 00:09:35.608535 setup-nvidia[2449]: Please see the log entries 'Kernel module load error' and 'Kernel messages' at the end of the file '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more information. Oct 13 00:09:35.609756 setup-nvidia[2449]: -> Kernel module load error: Operation not permitted Oct 13 00:09:35.609756 setup-nvidia[2449]: -> Kernel messages: Oct 13 00:09:35.609756 setup-nvidia[2449]: ERROR: Installation has failed. Please see the file '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for details. You may find suggestions on fixing installation problems in the README available on the Linux driver download page at www.nvidia.com. Oct 13 00:09:36.248406 sshd[12935]: Accepted publickey for core from 10.200.16.10 port 49424 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:09:36.249812 sshd-session[12935]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:09:36.255709 systemd-logind[1687]: New session 22 of user core. Oct 13 00:09:36.268437 systemd[1]: Started session-22.scope - Session 22 of User core. Oct 13 00:09:36.700709 sshd[12945]: Connection closed by 10.200.16.10 port 49424 Oct 13 00:09:36.701108 sshd-session[12935]: pam_unix(sshd:session): session closed for user core Oct 13 00:09:36.704274 systemd[1]: sshd@19-10.200.8.12:22-10.200.16.10:49424.service: Deactivated successfully. Oct 13 00:09:36.705988 systemd[1]: session-22.scope: Deactivated successfully. Oct 13 00:09:36.706792 systemd-logind[1687]: Session 22 logged out. Waiting for processes to exit. Oct 13 00:09:36.707644 systemd-logind[1687]: Removed session 22. Oct 13 00:09:36.819484 systemd[1]: Started sshd@20-10.200.8.12:22-10.200.16.10:49430.service - OpenSSH per-connection server daemon (10.200.16.10:49430). Oct 13 00:09:37.461559 sshd[12951]: Accepted publickey for core from 10.200.16.10 port 49430 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:09:37.463046 sshd-session[12951]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:09:37.467386 systemd-logind[1687]: New session 23 of user core. Oct 13 00:09:37.484255 systemd[1]: Started session-23.scope - Session 23 of User core. Oct 13 00:09:37.914227 sshd[12953]: Connection closed by 10.200.16.10 port 49430 Oct 13 00:09:37.914631 sshd-session[12951]: pam_unix(sshd:session): session closed for user core Oct 13 00:09:37.917783 systemd[1]: sshd@20-10.200.8.12:22-10.200.16.10:49430.service: Deactivated successfully. Oct 13 00:09:37.919336 systemd[1]: session-23.scope: Deactivated successfully. Oct 13 00:09:37.919983 systemd-logind[1687]: Session 23 logged out. Waiting for processes to exit. Oct 13 00:09:37.920784 systemd-logind[1687]: Removed session 23. Oct 13 00:09:48.031419 systemd[1]: Started sshd@21-10.200.8.12:22-10.200.16.10:51890.service - OpenSSH per-connection server daemon (10.200.16.10:51890). Oct 13 00:09:48.671382 sshd[12959]: Accepted publickey for core from 10.200.16.10 port 51890 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:09:48.672792 sshd-session[12959]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:09:48.677009 systemd-logind[1687]: New session 24 of user core. Oct 13 00:09:48.684255 systemd[1]: Started session-24.scope - Session 24 of User core. Oct 13 00:09:49.129871 sshd[12961]: Connection closed by 10.200.16.10 port 51890 Oct 13 00:09:49.130292 sshd-session[12959]: pam_unix(sshd:session): session closed for user core Oct 13 00:09:49.133541 systemd[1]: sshd@21-10.200.8.12:22-10.200.16.10:51890.service: Deactivated successfully. Oct 13 00:09:49.135366 systemd[1]: session-24.scope: Deactivated successfully. Oct 13 00:09:49.136104 systemd-logind[1687]: Session 24 logged out. Waiting for processes to exit. Oct 13 00:09:49.136886 systemd-logind[1687]: Removed session 24. Oct 13 00:09:49.244659 systemd[1]: Started sshd@22-10.200.8.12:22-10.200.16.10:51900.service - OpenSSH per-connection server daemon (10.200.16.10:51900). Oct 13 00:09:49.886684 sshd[12967]: Accepted publickey for core from 10.200.16.10 port 51900 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:09:49.888025 sshd-session[12967]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:09:49.892200 systemd-logind[1687]: New session 25 of user core. Oct 13 00:09:49.900278 systemd[1]: Started session-25.scope - Session 25 of User core. Oct 13 00:09:50.339520 sshd[12969]: Connection closed by 10.200.16.10 port 51900 Oct 13 00:09:50.339929 sshd-session[12967]: pam_unix(sshd:session): session closed for user core Oct 13 00:09:50.343141 systemd[1]: sshd@22-10.200.8.12:22-10.200.16.10:51900.service: Deactivated successfully. Oct 13 00:09:50.344687 systemd[1]: session-25.scope: Deactivated successfully. Oct 13 00:09:50.345345 systemd-logind[1687]: Session 25 logged out. Waiting for processes to exit. Oct 13 00:09:50.346098 systemd-logind[1687]: Removed session 25. Oct 13 00:10:00.456776 systemd[1]: Started sshd@23-10.200.8.12:22-10.200.16.10:39936.service - OpenSSH per-connection server daemon (10.200.16.10:39936). Oct 13 00:10:01.332329 sshd[12976]: Accepted publickey for core from 10.200.16.10 port 39936 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:10:01.105689 systemd-logind[1687]: New session 26 of user core. Oct 13 00:10:01.101425 sshd-session[12976]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:10:01.117239 systemd[1]: Started session-26.scope - Session 26 of User core. Oct 13 00:10:01.554814 sshd[12978]: Connection closed by 10.200.16.10 port 39936 Oct 13 00:10:01.555238 sshd-session[12976]: pam_unix(sshd:session): session closed for user core Oct 13 00:10:01.558490 systemd[1]: sshd@23-10.200.8.12:22-10.200.16.10:39936.service: Deactivated successfully. Oct 13 00:10:01.560044 systemd[1]: session-26.scope: Deactivated successfully. Oct 13 00:10:01.560891 systemd-logind[1687]: Session 26 logged out. Waiting for processes to exit. Oct 13 00:10:01.561889 systemd-logind[1687]: Removed session 26. Oct 13 00:10:01.668615 systemd[1]: Started sshd@24-10.200.8.12:22-10.200.16.10:39938.service - OpenSSH per-connection server daemon (10.200.16.10:39938). Oct 13 00:10:02.310553 sshd[12984]: Accepted publickey for core from 10.200.16.10 port 39938 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:10:02.311955 sshd-session[12984]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:10:02.316088 systemd-logind[1687]: New session 27 of user core. Oct 13 00:10:02.333278 systemd[1]: Started session-27.scope - Session 27 of User core. Oct 13 00:10:02.770950 sshd[12986]: Connection closed by 10.200.16.10 port 39938 Oct 13 00:10:02.771320 sshd-session[12984]: pam_unix(sshd:session): session closed for user core Oct 13 00:10:02.774494 systemd[1]: sshd@24-10.200.8.12:22-10.200.16.10:39938.service: Deactivated successfully. Oct 13 00:10:02.776095 systemd[1]: session-27.scope: Deactivated successfully. Oct 13 00:10:02.776752 systemd-logind[1687]: Session 27 logged out. Waiting for processes to exit. Oct 13 00:10:02.777541 systemd-logind[1687]: Removed session 27. Oct 13 00:10:02.902142 kernel: EXT4-fs (loop6p9): unmounting filesystem aea75de1-cc22-441d-8bc9-4b30365730be. Oct 13 00:10:02.902961 setup-nvidia[2449]: Container flatcardevelopercontainer-4230.2.4.bin exited successfully. Oct 13 00:10:02.905713 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4230.2.4.bin.mount: Deactivated successfully. Oct 13 00:10:02.962991 sudo[2433]: pam_unix(sudo:session): session closed for user root Oct 13 00:10:02.964637 systemd[1]: session-c2.scope: Deactivated successfully. Oct 13 00:10:02.964911 systemd[1]: session-c2.scope: Consumed 5min 8.657s CPU time, 883.2M memory peak. Oct 13 00:10:03.373780 setup-nvidia[1681]: /opt/nvidia / Oct 13 00:10:03.374788 setup-nvidia[1681]: / Oct 13 00:10:03.390437 setup-nvidia[13008]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 13 00:10:03.414139 kernel: IPMI message handler: version 39.2 Oct 13 00:10:03.417135 kernel: ipmi device interface Oct 13 00:10:03.418327 setup-nvidia[1681]: /opt/nvidia/current/usr/lib/modules/6.6.110-flatcar/video / Oct 13 00:10:03.470597 kernel: nvidia: loading out-of-tree module taints kernel. Oct 13 00:10:03.470730 kernel: nvidia: module license 'NVIDIA' taints kernel. Oct 13 00:10:03.470755 kernel: Disabling lock debugging due to kernel taint Oct 13 00:10:03.477625 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Oct 13 00:10:03.477685 kernel: nvidia: module license taints kernel. Oct 13 00:10:03.874149 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 245 Oct 13 00:10:04.273899 kernel: Oct 13 00:10:04.274043 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Oct 13 00:10:04.480148 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.230.02 Fri Dec 20 21:42:05 UTC 2024 Oct 13 00:10:04.501140 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.230.02 Fri Dec 20 21:52:34 UTC 2024 Oct 13 00:10:04.521158 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Oct 13 00:10:04.564951 setup-nvidia[1681]: / Oct 13 00:10:04.565333 kernel: nvidia-uvm: Loaded the UVM driver, major device number 243. Oct 13 00:10:06.331538 setup-nvidia[13038]: Mon Oct 13 00:10:06 2025 Oct 13 00:10:06.331538 setup-nvidia[13038]: +---------------------------------------------------------------------------------------+ Oct 13 00:10:06.331538 setup-nvidia[13038]: | NVIDIA-SMI 535.230.02 Driver Version: 535.230.02 CUDA Version: 12.2 | Oct 13 00:10:06.331538 setup-nvidia[13038]: |-----------------------------------------+----------------------+----------------------+ Oct 13 00:10:06.331538 setup-nvidia[13038]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Oct 13 00:10:06.331538 setup-nvidia[13038]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Oct 13 00:10:06.331538 setup-nvidia[13038]: | | | MIG M. | Oct 13 00:10:06.331538 setup-nvidia[13038]: |=========================================+======================+======================| Oct 13 00:10:06.335148 setup-nvidia[13038]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Oct 13 00:10:06.335148 setup-nvidia[13038]: | N/A 24C P0 37W / 250W | 0MiB / 16384MiB | 0% Default | Oct 13 00:10:06.335148 setup-nvidia[13038]: | | | N/A | Oct 13 00:10:06.335148 setup-nvidia[13038]: +-----------------------------------------+----------------------+----------------------+ Oct 13 00:10:06.335285 setup-nvidia[13038]: Oct 13 00:10:06.335285 setup-nvidia[13038]: +---------------------------------------------------------------------------------------+ Oct 13 00:10:06.335285 setup-nvidia[13038]: | Processes: | Oct 13 00:10:06.335285 setup-nvidia[13038]: | GPU GI CI PID Type Process name GPU Memory | Oct 13 00:10:06.335285 setup-nvidia[13038]: | ID ID Usage | Oct 13 00:10:06.335285 setup-nvidia[13038]: |=======================================================================================| Oct 13 00:10:06.335419 setup-nvidia[13038]: | No running processes found | Oct 13 00:10:06.335419 setup-nvidia[13038]: +---------------------------------------------------------------------------------------+ Oct 13 00:10:06.717484 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Oct 13 00:10:06.719848 systemd[1]: Reached target multi-user.target - Multi-User System. Oct 13 00:10:06.720374 systemd[1]: Startup finished in 1.221s (firmware) + 30.917s (loader) + 1.838s (kernel) + 13.331s (initrd) + 3min 2.535s (userspace) = 3min 49.844s. Oct 13 00:10:12.890765 systemd[1]: Started sshd@25-10.200.8.12:22-10.200.16.10:50500.service - OpenSSH per-connection server daemon (10.200.16.10:50500). Oct 13 00:10:13.043543 systemd[1]: Stopping user@0.service - User Manager for UID 0... Oct 13 00:10:13.044336 systemd[2437]: Activating special unit exit.target... Oct 13 00:10:13.044425 systemd[2437]: Stopped target default.target - Main User Target. Oct 13 00:10:13.044468 systemd[2437]: Stopped target basic.target - Basic System. Oct 13 00:10:13.044500 systemd[2437]: Stopped target paths.target - Paths. Oct 13 00:10:13.044529 systemd[2437]: Stopped target sockets.target - Sockets. Oct 13 00:10:13.044558 systemd[2437]: Stopped target timers.target - Timers. Oct 13 00:10:13.044923 systemd[2437]: Closed dbus.socket - D-Bus User Message Bus Socket. Oct 13 00:10:13.045153 systemd[2437]: Removed slice app.slice - User Application Slice. Oct 13 00:10:13.045184 systemd[2437]: Reached target shutdown.target - Shutdown. Oct 13 00:10:13.045346 systemd[2437]: Finished systemd-exit.service - Exit the Session. Oct 13 00:10:13.045386 systemd[2437]: Reached target exit.target - Exit the Session. Oct 13 00:10:13.055476 (sd-pam)[2438]: pam_unix(systemd-user:session): session closed for user root Oct 13 00:10:13.056986 systemd[1]: user@0.service: Deactivated successfully. Oct 13 00:10:13.057391 systemd[1]: Stopped user@0.service - User Manager for UID 0. Oct 13 00:10:13.073701 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Oct 13 00:10:13.085128 systemd[1]: run-user-0.mount: Deactivated successfully. Oct 13 00:10:13.086096 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Oct 13 00:10:13.086385 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Oct 13 00:10:13.088313 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Oct 13 00:10:13.088417 systemd[1]: user-0.slice: Consumed 5min 8.878s CPU time, 892.5M memory peak. Oct 13 00:10:13.088830 systemd-logind[1687]: Removed session c3. Oct 13 00:10:13.537486 sshd[13045]: Accepted publickey for core from 10.200.16.10 port 50500 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:10:13.538884 sshd-session[13045]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:10:13.543737 systemd-logind[1687]: New session 28 of user core. Oct 13 00:10:13.567283 systemd[1]: Started session-28.scope - Session 28 of User core. Oct 13 00:10:14.010519 sshd[13051]: Connection closed by 10.200.16.10 port 50500 Oct 13 00:10:14.010937 sshd-session[13045]: pam_unix(sshd:session): session closed for user core Oct 13 00:10:14.014182 systemd[1]: sshd@25-10.200.8.12:22-10.200.16.10:50500.service: Deactivated successfully. Oct 13 00:10:14.015786 systemd[1]: session-28.scope: Deactivated successfully. Oct 13 00:10:14.016440 systemd-logind[1687]: Session 28 logged out. Waiting for processes to exit. Oct 13 00:10:14.017247 systemd-logind[1687]: Removed session 28. Oct 13 00:10:14.124523 systemd[1]: Started sshd@26-10.200.8.12:22-10.200.16.10:50510.service - OpenSSH per-connection server daemon (10.200.16.10:50510). Oct 13 00:10:14.837603 sshd[13057]: Accepted publickey for core from 10.200.16.10 port 50510 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:10:14.837931 sshd-session[13057]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:10:14.842168 systemd-logind[1687]: New session 29 of user core. Oct 13 00:10:14.853243 systemd[1]: Started session-29.scope - Session 29 of User core. Oct 13 00:10:15.263103 sshd[13059]: Connection closed by 10.200.16.10 port 50510 Oct 13 00:10:15.264008 sshd-session[13057]: pam_unix(sshd:session): session closed for user core Oct 13 00:10:15.267238 systemd[1]: sshd@26-10.200.8.12:22-10.200.16.10:50510.service: Deactivated successfully. Oct 13 00:10:15.268830 systemd[1]: session-29.scope: Deactivated successfully. Oct 13 00:10:15.269531 systemd-logind[1687]: Session 29 logged out. Waiting for processes to exit. Oct 13 00:10:15.270323 systemd-logind[1687]: Removed session 29. Oct 13 00:10:15.375503 systemd[1]: Started sshd@27-10.200.8.12:22-10.200.16.10:50520.service - OpenSSH per-connection server daemon (10.200.16.10:50520). Oct 13 00:10:16.015978 sshd[13065]: Accepted publickey for core from 10.200.16.10 port 50520 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:10:16.017407 sshd-session[13065]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:10:16.021584 systemd-logind[1687]: New session 30 of user core. Oct 13 00:10:16.034244 systemd[1]: Started session-30.scope - Session 30 of User core. Oct 13 00:10:16.468762 sshd[13070]: Connection closed by 10.200.16.10 port 50520 Oct 13 00:10:16.469185 sshd-session[13065]: pam_unix(sshd:session): session closed for user core Oct 13 00:10:16.472322 systemd[1]: sshd@27-10.200.8.12:22-10.200.16.10:50520.service: Deactivated successfully. Oct 13 00:10:16.473905 systemd[1]: session-30.scope: Deactivated successfully. Oct 13 00:10:16.474533 systemd-logind[1687]: Session 30 logged out. Waiting for processes to exit. Oct 13 00:10:16.475298 systemd-logind[1687]: Removed session 30. Oct 13 00:10:16.581514 systemd[1]: Started sshd@28-10.200.8.12:22-10.200.16.10:50530.service - OpenSSH per-connection server daemon (10.200.16.10:50530). Oct 13 00:10:17.228478 sshd[13076]: Accepted publickey for core from 10.200.16.10 port 50530 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:10:17.229813 sshd-session[13076]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:10:17.234235 systemd-logind[1687]: New session 31 of user core. Oct 13 00:10:17.246237 systemd[1]: Started session-31.scope - Session 31 of User core. Oct 13 00:10:17.687894 sshd[13078]: Connection closed by 10.200.16.10 port 50530 Oct 13 00:10:17.688341 sshd-session[13076]: pam_unix(sshd:session): session closed for user core Oct 13 00:10:17.691640 systemd[1]: sshd@28-10.200.8.12:22-10.200.16.10:50530.service: Deactivated successfully. Oct 13 00:10:17.693262 systemd[1]: session-31.scope: Deactivated successfully. Oct 13 00:10:17.694356 systemd-logind[1687]: Session 31 logged out. Waiting for processes to exit. Oct 13 00:10:17.695129 systemd-logind[1687]: Removed session 31. Oct 13 00:10:17.801425 systemd[1]: Started sshd@29-10.200.8.12:22-10.200.16.10:50534.service - OpenSSH per-connection server daemon (10.200.16.10:50534). Oct 13 00:10:18.444890 sshd[13084]: Accepted publickey for core from 10.200.16.10 port 50534 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:10:18.446275 sshd-session[13084]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:10:18.450452 systemd-logind[1687]: New session 32 of user core. Oct 13 00:10:18.462354 systemd[1]: Started session-32.scope - Session 32 of User core. Oct 13 00:10:20.363182 sshd[13086]: Connection closed by 10.200.16.10 port 50534 Oct 13 00:10:20.363680 sshd-session[13084]: pam_unix(sshd:session): session closed for user core Oct 13 00:10:20.366904 systemd[1]: sshd@29-10.200.8.12:22-10.200.16.10:50534.service: Deactivated successfully. Oct 13 00:10:20.368577 systemd[1]: session-32.scope: Deactivated successfully. Oct 13 00:10:20.368786 systemd[1]: session-32.scope: Consumed 1.405s CPU time, 13.8M memory peak. Oct 13 00:10:20.369240 systemd-logind[1687]: Session 32 logged out. Waiting for processes to exit. Oct 13 00:10:20.369992 systemd-logind[1687]: Removed session 32.