Oct 31 00:15:56.233275 kernel: Linux version 6.6.113-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 Thu Oct 30 22:22:41 -00 2025 Oct 31 00:15:56.233304 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=c9ef242fe5d15fa45a0fd5694c2f4f78f371b25aaae8839a90a10ba46b28fcaa Oct 31 00:15:56.233314 kernel: BIOS-provided physical RAM map: Oct 31 00:15:56.233321 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Oct 31 00:15:56.233327 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Oct 31 00:15:56.233334 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Oct 31 00:15:56.233342 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Oct 31 00:15:56.233352 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Oct 31 00:15:56.233359 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Oct 31 00:15:56.233365 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Oct 31 00:15:56.233372 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Oct 31 00:15:56.233379 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Oct 31 00:15:56.233386 kernel: printk: bootconsole [earlyser0] enabled Oct 31 00:15:56.233393 kernel: NX (Execute Disable) protection: active Oct 31 00:15:56.233404 kernel: APIC: Static calls initialized Oct 31 00:15:56.233412 kernel: efi: EFI v2.7 by Microsoft Oct 31 00:15:56.233419 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Oct 31 00:15:56.233438 kernel: random: crng init done Oct 31 00:15:56.233446 kernel: secureboot: Secure boot disabled Oct 31 00:15:56.233454 kernel: SMBIOS 3.1.0 present. Oct 31 00:15:56.233461 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Oct 31 00:15:56.233469 kernel: Hypervisor detected: Microsoft Hyper-V Oct 31 00:15:56.233476 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Oct 31 00:15:56.233484 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Oct 31 00:15:56.233494 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Oct 31 00:15:56.233502 kernel: Hyper-V: Using hypercall for remote TLB flush Oct 31 00:15:56.233509 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Oct 31 00:15:56.233517 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Oct 31 00:15:56.233525 kernel: tsc: Marking TSC unstable due to running on Hyper-V Oct 31 00:15:56.233533 kernel: tsc: Detected 2593.992 MHz processor Oct 31 00:15:56.233541 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Oct 31 00:15:56.233549 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Oct 31 00:15:56.233556 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Oct 31 00:15:56.233567 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Oct 31 00:15:56.233574 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Oct 31 00:15:56.233582 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Oct 31 00:15:56.233590 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Oct 31 00:15:56.233597 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Oct 31 00:15:56.233605 kernel: Using GB pages for direct mapping Oct 31 00:15:56.233612 kernel: ACPI: Early table checksum verification disabled Oct 31 00:15:56.233624 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Oct 31 00:15:56.233635 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 31 00:15:56.233643 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 31 00:15:56.233651 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Oct 31 00:15:56.233659 kernel: ACPI: FACS 0x000000003FFFE000 000040 Oct 31 00:15:56.233667 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 31 00:15:56.233676 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 31 00:15:56.233686 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 31 00:15:56.233695 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 31 00:15:56.233703 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 31 00:15:56.233711 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 31 00:15:56.233719 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 31 00:15:56.233727 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Oct 31 00:15:56.233735 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Oct 31 00:15:56.233743 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Oct 31 00:15:56.233754 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Oct 31 00:15:56.233762 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Oct 31 00:15:56.233770 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Oct 31 00:15:56.233778 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Oct 31 00:15:56.233786 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Oct 31 00:15:56.233795 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Oct 31 00:15:56.233803 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Oct 31 00:15:56.233811 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0 Oct 31 00:15:56.233819 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0 Oct 31 00:15:56.233829 kernel: SRAT: PXM 0 -> APIC 0x02 -> Node 0 Oct 31 00:15:56.233837 kernel: SRAT: PXM 0 -> APIC 0x03 -> Node 0 Oct 31 00:15:56.233845 kernel: SRAT: PXM 0 -> APIC 0x04 -> Node 0 Oct 31 00:15:56.233853 kernel: SRAT: PXM 0 -> APIC 0x05 -> Node 0 Oct 31 00:15:56.233861 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Oct 31 00:15:56.233869 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Oct 31 00:15:56.233885 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Oct 31 00:15:56.233893 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Oct 31 00:15:56.233905 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Oct 31 00:15:56.233921 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Oct 31 00:15:56.233935 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Oct 31 00:15:56.233955 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Oct 31 00:15:56.233968 kernel: NUMA: Node 0 [mem 0x00000000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00000000-0xfdfffffff] Oct 31 00:15:56.233988 kernel: NUMA: Node 0 [mem 0x00000000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00000000-0x5cbfffffff] Oct 31 00:15:56.234001 kernel: NODE_DATA(0) allocated [mem 0x5cbfff9000-0x5cbfffefff] Oct 31 00:15:56.234014 kernel: Zone ranges: Oct 31 00:15:56.234023 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Oct 31 00:15:56.234031 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Oct 31 00:15:56.234042 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Oct 31 00:15:56.234051 kernel: Movable zone start for each node Oct 31 00:15:56.234059 kernel: Early memory node ranges Oct 31 00:15:56.234067 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Oct 31 00:15:56.234075 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Oct 31 00:15:56.234083 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Oct 31 00:15:56.234091 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Oct 31 00:15:56.234099 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Oct 31 00:15:56.234107 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Oct 31 00:15:56.234118 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Oct 31 00:15:56.234126 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Oct 31 00:15:56.234134 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Oct 31 00:15:56.234142 kernel: ACPI: PM-Timer IO Port: 0x408 Oct 31 00:15:56.234150 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Oct 31 00:15:56.234158 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Oct 31 00:15:56.234167 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Oct 31 00:15:56.234175 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Oct 31 00:15:56.234183 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Oct 31 00:15:56.234194 kernel: smpboot: Allowing 6 CPUs, 0 hotplug CPUs Oct 31 00:15:56.234202 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Oct 31 00:15:56.234213 kernel: Booting paravirtualized kernel on Hyper-V Oct 31 00:15:56.234221 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Oct 31 00:15:56.234229 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Oct 31 00:15:56.234237 kernel: percpu: Embedded 58 pages/cpu s196712 r8192 d32664 u262144 Oct 31 00:15:56.234246 kernel: pcpu-alloc: s196712 r8192 d32664 u262144 alloc=1*2097152 Oct 31 00:15:56.234254 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Oct 31 00:15:56.234262 kernel: Hyper-V: PV spinlocks enabled Oct 31 00:15:56.234272 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Oct 31 00:15:56.234282 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=c9ef242fe5d15fa45a0fd5694c2f4f78f371b25aaae8839a90a10ba46b28fcaa Oct 31 00:15:56.234290 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Oct 31 00:15:56.234298 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Oct 31 00:15:56.234307 kernel: Fallback order for Node 0: 0 Oct 31 00:15:56.234315 kernel: Built 1 zonelists, mobility grouping on. Total pages: 28899610 Oct 31 00:15:56.234323 kernel: Policy zone: Normal Oct 31 00:15:56.234331 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 31 00:15:56.234342 kernel: software IO TLB: area num 8. Oct 31 00:15:56.234358 kernel: Memory: 115328448K/117439364K available (14336K kernel code, 2288K rwdata, 22872K rodata, 43520K init, 1560K bss, 2110660K reserved, 0K cma-reserved) Oct 31 00:15:56.234367 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Oct 31 00:15:56.234378 kernel: Kernel/User page tables isolation: enabled Oct 31 00:15:56.234387 kernel: ftrace: allocating 37954 entries in 149 pages Oct 31 00:15:56.234396 kernel: ftrace: allocated 149 pages with 4 groups Oct 31 00:15:56.234404 kernel: Dynamic Preempt: voluntary Oct 31 00:15:56.234413 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 31 00:15:56.234422 kernel: rcu: RCU event tracing is enabled. Oct 31 00:15:56.234492 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Oct 31 00:15:56.234501 kernel: Trampoline variant of Tasks RCU enabled. Oct 31 00:15:56.234513 kernel: Rude variant of Tasks RCU enabled. Oct 31 00:15:56.234522 kernel: Tracing variant of Tasks RCU enabled. Oct 31 00:15:56.234531 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 31 00:15:56.234540 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Oct 31 00:15:56.234548 kernel: Using NULL legacy PIC Oct 31 00:15:56.234557 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Oct 31 00:15:56.234568 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Oct 31 00:15:56.234577 kernel: Console: colour dummy device 80x25 Oct 31 00:15:56.234585 kernel: printk: console [tty1] enabled Oct 31 00:15:56.234594 kernel: printk: console [ttyS0] enabled Oct 31 00:15:56.234602 kernel: printk: bootconsole [earlyser0] disabled Oct 31 00:15:56.234611 kernel: ACPI: Core revision 20230628 Oct 31 00:15:56.234619 kernel: Failed to register legacy timer interrupt Oct 31 00:15:56.234628 kernel: APIC: Switch to symmetric I/O mode setup Oct 31 00:15:56.234636 kernel: Hyper-V: enabling crash_kexec_post_notifiers Oct 31 00:15:56.234648 kernel: Hyper-V: Using IPI hypercalls Oct 31 00:15:56.234656 kernel: APIC: send_IPI() replaced with hv_send_ipi() Oct 31 00:15:56.234665 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Oct 31 00:15:56.234673 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Oct 31 00:15:56.234682 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Oct 31 00:15:56.234690 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Oct 31 00:15:56.234699 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Oct 31 00:15:56.234708 kernel: Hyper-V: Using enlightened APIC (xapic mode) Oct 31 00:15:56.234717 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Oct 31 00:15:56.234728 kernel: APIC: read() replaced with hv_apic_read() Oct 31 00:15:56.234736 kernel: APIC: write() replaced with hv_apic_write() Oct 31 00:15:56.234745 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Oct 31 00:15:56.234754 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Oct 31 00:15:56.234762 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593992) Oct 31 00:15:56.234771 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Oct 31 00:15:56.234780 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Oct 31 00:15:56.234788 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Oct 31 00:15:56.234796 kernel: Spectre V2 : Mitigation: Retpolines Oct 31 00:15:56.234807 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Oct 31 00:15:56.234816 kernel: Speculative Store Bypass: Vulnerable Oct 31 00:15:56.234824 kernel: MDS: Mitigation: Clear CPU buffers Oct 31 00:15:56.234832 kernel: TAA: Mitigation: Clear CPU buffers Oct 31 00:15:56.234841 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Oct 31 00:15:56.234849 kernel: active return thunk: its_return_thunk Oct 31 00:15:56.234857 kernel: ITS: Mitigation: Aligned branch/return thunks Oct 31 00:15:56.234866 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Oct 31 00:15:56.234874 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Oct 31 00:15:56.234883 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Oct 31 00:15:56.234891 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Oct 31 00:15:56.234902 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Oct 31 00:15:56.234911 kernel: Freeing SMP alternatives memory: 32K Oct 31 00:15:56.234925 kernel: pid_max: default: 32768 minimum: 301 Oct 31 00:15:56.234938 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Oct 31 00:15:56.234951 kernel: landlock: Up and running. Oct 31 00:15:56.234963 kernel: SELinux: Initializing. Oct 31 00:15:56.234974 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Oct 31 00:15:56.234987 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Oct 31 00:15:56.235000 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Oct 31 00:15:56.235013 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Oct 31 00:15:56.235026 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Oct 31 00:15:56.235042 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Oct 31 00:15:56.235056 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Oct 31 00:15:56.235069 kernel: signal: max sigframe size: 1776 Oct 31 00:15:56.235082 kernel: rcu: Hierarchical SRCU implementation. Oct 31 00:15:56.235095 kernel: rcu: Max phase no-delay instances is 400. Oct 31 00:15:56.235104 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Oct 31 00:15:56.235112 kernel: smp: Bringing up secondary CPUs ... Oct 31 00:15:56.235121 kernel: smpboot: x86: Booting SMP configuration: Oct 31 00:15:56.235129 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Oct 31 00:15:56.235141 kernel: smp: Brought up 1 node, 6 CPUs Oct 31 00:15:56.235149 kernel: smpboot: Max logical packages: 1 Oct 31 00:15:56.235158 kernel: smpboot: Total of 6 processors activated (31127.90 BogoMIPS) Oct 31 00:15:56.235166 kernel: devtmpfs: initialized Oct 31 00:15:56.235175 kernel: x86/mm: Memory block size: 1024MB Oct 31 00:15:56.235183 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Oct 31 00:15:56.235192 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 31 00:15:56.235201 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Oct 31 00:15:56.235210 kernel: pinctrl core: initialized pinctrl subsystem Oct 31 00:15:56.235221 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Oct 31 00:15:56.235229 kernel: audit: initializing netlink subsys (disabled) Oct 31 00:15:56.235238 kernel: audit: type=2000 audit(1761869754.030:1): state=initialized audit_enabled=0 res=1 Oct 31 00:15:56.235246 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 31 00:15:56.235255 kernel: thermal_sys: Registered thermal governor 'user_space' Oct 31 00:15:56.235263 kernel: cpuidle: using governor menu Oct 31 00:15:56.235272 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 31 00:15:56.235280 kernel: dca service started, version 1.12.1 Oct 31 00:15:56.235289 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Oct 31 00:15:56.235300 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Oct 31 00:15:56.235309 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Oct 31 00:15:56.235317 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Oct 31 00:15:56.235326 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Oct 31 00:15:56.235335 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Oct 31 00:15:56.235343 kernel: ACPI: Added _OSI(Module Device) Oct 31 00:15:56.235352 kernel: ACPI: Added _OSI(Processor Device) Oct 31 00:15:56.235360 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 31 00:15:56.235369 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 31 00:15:56.235380 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Oct 31 00:15:56.235388 kernel: ACPI: Interpreter enabled Oct 31 00:15:56.235397 kernel: ACPI: PM: (supports S0 S5) Oct 31 00:15:56.235405 kernel: ACPI: Using IOAPIC for interrupt routing Oct 31 00:15:56.235414 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Oct 31 00:15:56.235422 kernel: PCI: Ignoring E820 reservations for host bridge windows Oct 31 00:15:56.235439 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Oct 31 00:15:56.235447 kernel: iommu: Default domain type: Translated Oct 31 00:15:56.235456 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Oct 31 00:15:56.235467 kernel: efivars: Registered efivars operations Oct 31 00:15:56.235476 kernel: PCI: Using ACPI for IRQ routing Oct 31 00:15:56.235485 kernel: PCI: System does not support PCI Oct 31 00:15:56.235493 kernel: vgaarb: loaded Oct 31 00:15:56.235502 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Oct 31 00:15:56.235511 kernel: VFS: Disk quotas dquot_6.6.0 Oct 31 00:15:56.235519 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 31 00:15:56.235528 kernel: pnp: PnP ACPI init Oct 31 00:15:56.235536 kernel: pnp: PnP ACPI: found 3 devices Oct 31 00:15:56.235548 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Oct 31 00:15:56.235556 kernel: NET: Registered PF_INET protocol family Oct 31 00:15:56.235565 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 31 00:15:56.235574 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Oct 31 00:15:56.235582 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Oct 31 00:15:56.235591 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 31 00:15:56.235600 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Oct 31 00:15:56.235608 kernel: TCP: Hash tables configured (established 524288 bind 65536) Oct 31 00:15:56.235617 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Oct 31 00:15:56.235628 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Oct 31 00:15:56.235637 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Oct 31 00:15:56.235645 kernel: NET: Registered PF_XDP protocol family Oct 31 00:15:56.235654 kernel: PCI: CLS 0 bytes, default 64 Oct 31 00:15:56.235662 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Oct 31 00:15:56.235671 kernel: software IO TLB: mapped [mem 0x000000003ae61000-0x000000003ee61000] (64MB) Oct 31 00:15:56.235680 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Oct 31 00:15:56.235688 kernel: Initialise system trusted keyrings Oct 31 00:15:56.235697 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Oct 31 00:15:56.235708 kernel: Key type asymmetric registered Oct 31 00:15:56.235716 kernel: Asymmetric key parser 'x509' registered Oct 31 00:15:56.235725 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Oct 31 00:15:56.235733 kernel: io scheduler mq-deadline registered Oct 31 00:15:56.235742 kernel: io scheduler kyber registered Oct 31 00:15:56.235750 kernel: io scheduler bfq registered Oct 31 00:15:56.235759 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Oct 31 00:15:56.235767 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 31 00:15:56.235776 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Oct 31 00:15:56.235787 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Oct 31 00:15:56.235796 kernel: i8042: PNP: No PS/2 controller found. Oct 31 00:15:56.235952 kernel: rtc_cmos 00:02: registered as rtc0 Oct 31 00:15:56.236060 kernel: rtc_cmos 00:02: setting system clock to 2025-10-31T00:15:55 UTC (1761869755) Oct 31 00:15:56.236158 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Oct 31 00:15:56.236170 kernel: intel_pstate: CPU model not supported Oct 31 00:15:56.236179 kernel: efifb: probing for efifb Oct 31 00:15:56.236192 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Oct 31 00:15:56.236201 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Oct 31 00:15:56.236209 kernel: efifb: scrolling: redraw Oct 31 00:15:56.236218 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Oct 31 00:15:56.236227 kernel: Console: switching to colour frame buffer device 128x48 Oct 31 00:15:56.236235 kernel: fb0: EFI VGA frame buffer device Oct 31 00:15:56.236244 kernel: pstore: Using crash dump compression: deflate Oct 31 00:15:56.236252 kernel: pstore: Registered efi_pstore as persistent store backend Oct 31 00:15:56.236261 kernel: NET: Registered PF_INET6 protocol family Oct 31 00:15:56.236272 kernel: Segment Routing with IPv6 Oct 31 00:15:56.236281 kernel: In-situ OAM (IOAM) with IPv6 Oct 31 00:15:56.236289 kernel: NET: Registered PF_PACKET protocol family Oct 31 00:15:56.236298 kernel: Key type dns_resolver registered Oct 31 00:15:56.236306 kernel: IPI shorthand broadcast: enabled Oct 31 00:15:56.236315 kernel: sched_clock: Marking stable (1656005600, 105752000)->(2108073600, -346316000) Oct 31 00:15:56.236323 kernel: registered taskstats version 1 Oct 31 00:15:56.236332 kernel: Loading compiled-in X.509 certificates Oct 31 00:15:56.236341 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.113-flatcar: 8aa749fd6ae7d102bbcccd9368b753f4483252f4' Oct 31 00:15:56.236349 kernel: Key type .fscrypt registered Oct 31 00:15:56.236360 kernel: Key type fscrypt-provisioning registered Oct 31 00:15:56.236368 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 31 00:15:56.236377 kernel: ima: Allocated hash algorithm: sha1 Oct 31 00:15:56.236385 kernel: ima: No architecture policies found Oct 31 00:15:56.236394 kernel: clk: Disabling unused clocks Oct 31 00:15:56.236402 kernel: Freeing unused kernel image (initmem) memory: 43520K Oct 31 00:15:56.236411 kernel: Write protecting the kernel read-only data: 38912k Oct 31 00:15:56.236419 kernel: Freeing unused kernel image (rodata/data gap) memory: 1704K Oct 31 00:15:56.236440 kernel: Run /init as init process Oct 31 00:15:56.236452 kernel: with arguments: Oct 31 00:15:56.236460 kernel: /init Oct 31 00:15:56.236469 kernel: with environment: Oct 31 00:15:56.236477 kernel: HOME=/ Oct 31 00:15:56.236485 kernel: TERM=linux Oct 31 00:15:56.236495 systemd[1]: Successfully made /usr/ read-only. Oct 31 00:15:56.236506 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 31 00:15:56.236519 systemd[1]: Detected virtualization microsoft. Oct 31 00:15:56.236557 systemd[1]: Detected architecture x86-64. Oct 31 00:15:56.236573 systemd[1]: Running in initrd. Oct 31 00:15:56.236583 systemd[1]: No hostname configured, using default hostname. Oct 31 00:15:56.236592 systemd[1]: Hostname set to . Oct 31 00:15:56.236601 systemd[1]: Initializing machine ID from random generator. Oct 31 00:15:56.236610 systemd[1]: Queued start job for default target initrd.target. Oct 31 00:15:56.236619 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 31 00:15:56.236628 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 31 00:15:56.236643 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Oct 31 00:15:56.236653 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 31 00:15:56.236662 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Oct 31 00:15:56.236672 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Oct 31 00:15:56.236682 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Oct 31 00:15:56.236692 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Oct 31 00:15:56.236704 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 31 00:15:56.236713 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 31 00:15:56.236722 systemd[1]: Reached target paths.target - Path Units. Oct 31 00:15:56.236731 systemd[1]: Reached target slices.target - Slice Units. Oct 31 00:15:56.236740 systemd[1]: Reached target swap.target - Swaps. Oct 31 00:15:56.236749 systemd[1]: Reached target timers.target - Timer Units. Oct 31 00:15:56.236758 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Oct 31 00:15:56.236767 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 31 00:15:56.236776 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Oct 31 00:15:56.236788 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Oct 31 00:15:56.236797 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 31 00:15:56.236806 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 31 00:15:56.236815 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 31 00:15:56.236824 systemd[1]: Reached target sockets.target - Socket Units. Oct 31 00:15:56.236833 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Oct 31 00:15:56.236842 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 31 00:15:56.236851 systemd[1]: Finished network-cleanup.service - Network Cleanup. Oct 31 00:15:56.236863 systemd[1]: Starting systemd-fsck-usr.service... Oct 31 00:15:56.236872 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 31 00:15:56.236881 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 31 00:15:56.236890 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 31 00:15:56.236923 systemd-journald[205]: Collecting audit messages is disabled. Oct 31 00:15:56.236950 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Oct 31 00:15:56.236960 systemd-journald[205]: Journal started Oct 31 00:15:56.236980 systemd-journald[205]: Runtime Journal (/run/log/journal/0d7d99bac4914de1b6b23afcc72d49e9) is 8M, max 2.2G, 2.1G free. Oct 31 00:15:56.239266 systemd-modules-load[207]: Inserted module 'overlay' Oct 31 00:15:56.248794 systemd[1]: Started systemd-journald.service - Journal Service. Oct 31 00:15:56.249162 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 31 00:15:56.264325 systemd[1]: Finished systemd-fsck-usr.service. Oct 31 00:15:56.264606 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 31 00:15:56.285581 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 31 00:15:56.295514 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 31 00:15:56.296237 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 31 00:15:56.319970 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 31 00:15:56.329225 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 31 00:15:56.327829 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 31 00:15:56.337395 systemd-modules-load[207]: Inserted module 'br_netfilter' Oct 31 00:15:56.340997 kernel: Bridge firewalling registered Oct 31 00:15:56.341604 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 31 00:15:56.351410 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 31 00:15:56.351711 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 31 00:15:56.361627 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Oct 31 00:15:56.371709 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 31 00:15:56.380563 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 31 00:15:56.398844 dracut-cmdline[238]: dracut-dracut-053 Oct 31 00:15:56.398844 dracut-cmdline[238]: 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=c9ef242fe5d15fa45a0fd5694c2f4f78f371b25aaae8839a90a10ba46b28fcaa Oct 31 00:15:56.432198 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 31 00:15:56.452549 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 31 00:15:56.471445 kernel: SCSI subsystem initialized Oct 31 00:15:56.481442 kernel: Loading iSCSI transport class v2.0-870. Oct 31 00:15:56.488934 systemd-resolved[298]: Positive Trust Anchors: Oct 31 00:15:56.488948 systemd-resolved[298]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 31 00:15:56.488979 systemd-resolved[298]: 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 31 00:15:56.491605 systemd-resolved[298]: Defaulting to hostname 'linux'. Oct 31 00:15:56.492855 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 31 00:15:56.498156 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 31 00:15:56.547316 kernel: iscsi: registered transport (tcp) Oct 31 00:15:56.571121 kernel: iscsi: registered transport (qla4xxx) Oct 31 00:15:56.571148 kernel: QLogic iSCSI HBA Driver Oct 31 00:15:56.611952 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Oct 31 00:15:56.629559 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Oct 31 00:15:56.664015 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Oct 31 00:15:56.664055 kernel: device-mapper: uevent: version 1.0.3 Oct 31 00:15:56.668766 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Oct 31 00:15:56.710443 kernel: raid6: avx2x4 gen() 31016 MB/s Oct 31 00:15:56.730443 kernel: raid6: avx2x2 gen() 31163 MB/s Oct 31 00:15:56.751640 kernel: raid6: avx2x1 gen() 27865 MB/s Oct 31 00:15:56.751669 kernel: raid6: using algorithm avx2x2 gen() 31163 MB/s Oct 31 00:15:56.773691 kernel: raid6: .... xor() 18307 MB/s, rmw enabled Oct 31 00:15:56.773719 kernel: raid6: using avx2x2 recovery algorithm Oct 31 00:15:56.797445 kernel: xor: automatically using best checksumming function avx Oct 31 00:15:56.943456 kernel: Btrfs loaded, zoned=no, fsverity=no Oct 31 00:15:56.954633 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Oct 31 00:15:56.984566 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 31 00:15:57.007809 systemd-udevd[429]: Using default interface naming scheme 'v255'. Oct 31 00:15:57.015561 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 31 00:15:57.016509 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Oct 31 00:15:57.046473 dracut-pre-trigger[432]: rd.md=0: removing MD RAID activation Oct 31 00:15:57.078078 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Oct 31 00:15:57.099605 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 31 00:15:57.159202 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 31 00:15:57.172961 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Oct 31 00:15:57.185622 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Oct 31 00:15:57.197546 kernel: cryptd: max_cpu_qlen set to 1000 Oct 31 00:15:57.198030 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Oct 31 00:15:57.209190 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 31 00:15:57.214402 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 31 00:15:57.238878 kernel: hv_vmbus: Vmbus version:4.0 Oct 31 00:15:57.238915 kernel: AVX2 version of gcm_enc/dec engaged. Oct 31 00:15:57.238439 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Oct 31 00:15:57.252616 kernel: AES CTR mode by8 optimization enabled Oct 31 00:15:57.254228 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Oct 31 00:15:57.266617 kernel: hv_vmbus: registering driver hyperv_keyboard Oct 31 00:15:57.266638 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 31 00:15:57.272614 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 31 00:15:57.279002 kernel: pps_core: LinuxPPS API ver. 1 registered Oct 31 00:15:57.279034 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Oct 31 00:15:57.272796 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 31 00:15:57.295902 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 31 00:15:57.290846 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 31 00:15:57.305960 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 31 00:15:57.331913 kernel: PTP clock support registered Oct 31 00:15:57.331939 kernel: hv_vmbus: registering driver hid_hyperv Oct 31 00:15:57.331952 kernel: hv_vmbus: registering driver hv_netvsc Oct 31 00:15:57.331964 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Oct 31 00:15:57.331976 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Oct 31 00:15:57.306287 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 31 00:15:57.331951 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Oct 31 00:15:57.350434 kernel: hv_vmbus: registering driver hv_pci Oct 31 00:15:57.350467 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Oct 31 00:15:57.353453 kernel: hv_utils: Registering HyperV Utility Driver Oct 31 00:15:57.360568 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Oct 31 00:15:57.360734 kernel: hv_vmbus: registering driver hv_utils Oct 31 00:15:57.360749 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Oct 31 00:15:57.366305 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 31 00:15:57.483633 kernel: hv_utils: Heartbeat IC version 3.0 Oct 31 00:15:57.483664 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Oct 31 00:15:57.483830 kernel: hv_utils: Shutdown IC version 3.2 Oct 31 00:15:57.483844 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Oct 31 00:15:57.483973 kernel: hv_utils: TimeSync IC version 4.0 Oct 31 00:15:57.483987 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 Oct 31 00:15:57.484014 kernel: hv_vmbus: registering driver hv_storvsc Oct 31 00:15:57.484025 kernel: pci 0001:00:00.0: reg 0x10: [mem 0x41000000-0x41ffffff] Oct 31 00:15:57.484049 kernel: scsi host1: storvsc_host_t Oct 31 00:15:57.484197 kernel: pci 0001:00:00.0: reg 0x14: [mem 0x1000000000-0x13ffffffff 64bit pref] Oct 31 00:15:57.484240 kernel: pci 0001:00:00.0: reg 0x1c: [mem 0x1400000000-0x1401ffffff 64bit pref] Oct 31 00:15:57.484262 kernel: scsi 1:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Oct 31 00:15:57.484285 kernel: pci 0001:00:00.0: Enabling HDA controller Oct 31 00:15:57.484303 kernel: scsi host0: storvsc_host_t Oct 31 00:15:57.484321 kernel: scsi 1:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Oct 31 00:15:57.458295 systemd-resolved[298]: Clock change detected. Flushing caches. Oct 31 00:15:57.521724 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Oct 31 00:15:57.521887 kernel: pci 0001:00:00.0: BAR 1: assigned [mem 0x1000000000-0x13ffffffff 64bit pref] Oct 31 00:15:57.522058 kernel: pci 0001:00:00.0: BAR 3: assigned [mem 0x1400000000-0x1401ffffff 64bit pref] Oct 31 00:15:57.522393 kernel: scsi 1:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Oct 31 00:15:57.522420 kernel: pci 0001:00:00.0: BAR 0: assigned [mem 0x41000000-0x41ffffff] Oct 31 00:15:57.523757 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 31 00:15:57.523869 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 31 00:15:57.537650 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Oct 31 00:15:57.551007 kernel: sr 1:0:0:2: [sr0] scsi-1 drive Oct 31 00:15:57.551229 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Oct 31 00:15:57.551250 kernel: sr 1:0:0:2: Attached scsi CD-ROM sr0 Oct 31 00:15:57.551157 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 31 00:15:57.567301 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 31 00:15:57.610093 kernel: sd 1:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Oct 31 00:15:57.610311 kernel: sd 1:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Oct 31 00:15:57.610489 kernel: sd 1:0:0:0: [sda] 4096-byte physical blocks Oct 31 00:15:57.610659 kernel: sd 1:0:0:1: [sdb] 4096-byte physical blocks Oct 31 00:15:57.610808 kernel: sd 1:0:0:0: [sda] Write Protect is off Oct 31 00:15:57.610966 kernel: sd 1:0:0:1: [sdb] Write Protect is off Oct 31 00:15:57.611122 kernel: sd 1:0:0:0: [sda] Mode Sense: 0f 00 10 00 Oct 31 00:15:57.611279 kernel: sd 1:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Oct 31 00:15:57.611434 kernel: sd 1:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Oct 31 00:15:57.611590 kernel: sd 1:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Oct 31 00:15:57.611732 kernel: sdb: sdb1 Oct 31 00:15:57.611751 kernel: sd 1:0:0:1: [sdb] Attached SCSI disk Oct 31 00:15:57.617222 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Oct 31 00:15:57.617251 kernel: sd 1:0:0:0: [sda] Attached SCSI disk Oct 31 00:15:57.619073 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 31 00:15:57.639106 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 31 00:15:58.143229 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 scanned by (udev-worker) (494) Oct 31 00:15:58.150270 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Oct 31 00:15:58.163827 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Oct 31 00:15:58.175597 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Oct 31 00:15:58.208222 kernel: BTRFS: device fsid 311007bd-ab96-441d-977a-c6c513017d1c devid 1 transid 37 /dev/sda3 scanned by (udev-worker) (483) Oct 31 00:15:58.231317 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Oct 31 00:15:58.231451 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Oct 31 00:15:58.260330 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Oct 31 00:15:58.282226 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Oct 31 00:15:59.297749 disk-uuid[637]: The operation has completed successfully. Oct 31 00:15:59.303276 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Oct 31 00:16:01.296224 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 31 00:16:01.296341 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Oct 31 00:16:01.312356 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Oct 31 00:16:01.327667 sh[724]: Success Oct 31 00:16:01.355238 kernel: device-mapper: verity: sha256 using implementation "sha256-avx2" Oct 31 00:16:01.669673 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Oct 31 00:16:01.684649 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Oct 31 00:16:01.692972 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Oct 31 00:16:01.716534 kernel: BTRFS info (device dm-0): first mount of filesystem 311007bd-ab96-441d-977a-c6c513017d1c Oct 31 00:16:01.716568 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Oct 31 00:16:01.721595 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Oct 31 00:16:01.725404 kernel: BTRFS info (device dm-0): disabling log replay at mount time Oct 31 00:16:01.728790 kernel: BTRFS info (device dm-0): using free space tree Oct 31 00:16:01.951937 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Oct 31 00:16:01.952680 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Oct 31 00:16:01.969342 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Oct 31 00:16:01.970092 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Oct 31 00:16:02.013605 kernel: BTRFS info (device sda6): first mount of filesystem fa4bc9fa-c5cf-41d8-8cd9-b896e25c00f7 Oct 31 00:16:02.013641 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Oct 31 00:16:02.016783 kernel: BTRFS info (device sda6): using free space tree Oct 31 00:16:02.036224 kernel: BTRFS info (device sda6): auto enabling async discard Oct 31 00:16:02.044231 kernel: BTRFS info (device sda6): last unmount of filesystem fa4bc9fa-c5cf-41d8-8cd9-b896e25c00f7 Oct 31 00:16:02.048887 systemd[1]: Finished ignition-setup.service - Ignition (setup). Oct 31 00:16:02.062517 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Oct 31 00:16:02.062894 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 31 00:16:02.077726 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 31 00:16:02.118337 systemd-networkd[905]: lo: Link UP Oct 31 00:16:02.118346 systemd-networkd[905]: lo: Gained carrier Oct 31 00:16:02.119670 systemd-networkd[905]: Enumeration completed Oct 31 00:16:02.119789 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 31 00:16:02.120093 systemd-networkd[905]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 31 00:16:02.120098 systemd-networkd[905]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 31 00:16:02.121017 systemd-networkd[905]: eth0: Link UP Oct 31 00:16:02.121186 systemd-networkd[905]: eth0: Gained carrier Oct 31 00:16:02.121195 systemd-networkd[905]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 31 00:16:02.125374 systemd[1]: Reached target network.target - Network. Oct 31 00:16:02.182241 systemd-networkd[905]: eth0: DHCPv4 address 10.200.8.27/24, gateway 10.200.8.1 acquired from 168.63.129.16 Oct 31 00:16:03.003147 ignition[896]: Ignition 2.20.0 Oct 31 00:16:03.003160 ignition[896]: Stage: fetch-offline Oct 31 00:16:03.003199 ignition[896]: no configs at "/usr/lib/ignition/base.d" Oct 31 00:16:03.003223 ignition[896]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 31 00:16:03.003328 ignition[896]: parsed url from cmdline: "" Oct 31 00:16:03.003332 ignition[896]: no config URL provided Oct 31 00:16:03.003337 ignition[896]: reading system config file "/usr/lib/ignition/user.ign" Oct 31 00:16:03.003347 ignition[896]: no config at "/usr/lib/ignition/user.ign" Oct 31 00:16:03.003352 ignition[896]: failed to fetch config: resource requires networking Oct 31 00:16:03.003527 ignition[896]: Ignition finished successfully Oct 31 00:16:03.031526 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Oct 31 00:16:03.053342 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Oct 31 00:16:03.069454 ignition[919]: Ignition 2.20.0 Oct 31 00:16:03.069467 ignition[919]: Stage: fetch Oct 31 00:16:03.069621 ignition[919]: no configs at "/usr/lib/ignition/base.d" Oct 31 00:16:03.069632 ignition[919]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 31 00:16:03.069736 ignition[919]: parsed url from cmdline: "" Oct 31 00:16:03.069740 ignition[919]: no config URL provided Oct 31 00:16:03.069747 ignition[919]: reading system config file "/usr/lib/ignition/user.ign" Oct 31 00:16:03.069756 ignition[919]: no config at "/usr/lib/ignition/user.ign" Oct 31 00:16:03.069780 ignition[919]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Oct 31 00:16:03.161501 ignition[919]: GET result: OK Oct 31 00:16:03.161545 ignition[919]: config has been read from IMDS userdata Oct 31 00:16:03.161555 ignition[919]: parsing config with SHA512: 0f4916dee65ba0cca419ccd3ef450791b5073c9421ec421c5b8643792f80a68b3219410a6f12da5f7e4ac3485825597645d22879852468c56d2d046e4a128e81 Oct 31 00:16:03.167311 unknown[919]: fetched base config from "system" Oct 31 00:16:03.167510 ignition[919]: fetch: fetch complete Oct 31 00:16:03.167338 unknown[919]: fetched base config from "system" Oct 31 00:16:03.167515 ignition[919]: fetch: fetch passed Oct 31 00:16:03.167344 unknown[919]: fetched user config from "azure" Oct 31 00:16:03.167560 ignition[919]: Ignition finished successfully Oct 31 00:16:03.174223 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Oct 31 00:16:03.195340 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Oct 31 00:16:03.218092 ignition[928]: Ignition 2.20.0 Oct 31 00:16:03.218104 ignition[928]: Stage: kargs Oct 31 00:16:03.218282 ignition[928]: no configs at "/usr/lib/ignition/base.d" Oct 31 00:16:03.218296 ignition[928]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 31 00:16:03.226761 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Oct 31 00:16:03.218816 ignition[928]: kargs: kargs passed Oct 31 00:16:03.218858 ignition[928]: Ignition finished successfully Oct 31 00:16:03.246382 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Oct 31 00:16:03.257595 ignition[938]: Ignition 2.20.0 Oct 31 00:16:03.257606 ignition[938]: Stage: disks Oct 31 00:16:03.257772 ignition[938]: no configs at "/usr/lib/ignition/base.d" Oct 31 00:16:03.257784 ignition[938]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 31 00:16:03.258314 ignition[938]: disks: disks passed Oct 31 00:16:03.266596 systemd[1]: Finished ignition-disks.service - Ignition (disks). Oct 31 00:16:03.258356 ignition[938]: Ignition finished successfully Oct 31 00:16:03.270353 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Oct 31 00:16:03.276473 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Oct 31 00:16:03.289812 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 31 00:16:03.296620 systemd[1]: Reached target sysinit.target - System Initialization. Oct 31 00:16:03.304890 systemd[1]: Reached target basic.target - Basic System. Oct 31 00:16:03.331336 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Oct 31 00:16:03.410021 systemd-fsck[951]: ROOT: clean, 14/7326000 files, 477710/7359488 blocks Oct 31 00:16:03.415337 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Oct 31 00:16:03.433316 systemd[1]: Mounting sysroot.mount - /sysroot... Oct 31 00:16:03.529219 kernel: EXT4-fs (sda9): mounted filesystem 7feab3bb-a335-45a4-937e-704f6ca28dd4 r/w with ordered data mode. Quota mode: none. Oct 31 00:16:03.529389 systemd[1]: Mounted sysroot.mount - /sysroot. Oct 31 00:16:03.530019 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Oct 31 00:16:03.573284 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 31 00:16:03.589081 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Oct 31 00:16:03.606855 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/sda6 scanned by mount (962) Oct 31 00:16:03.606880 kernel: BTRFS info (device sda6): first mount of filesystem fa4bc9fa-c5cf-41d8-8cd9-b896e25c00f7 Oct 31 00:16:03.606893 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Oct 31 00:16:03.606905 kernel: BTRFS info (device sda6): using free space tree Oct 31 00:16:03.590301 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Oct 31 00:16:03.606942 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Oct 31 00:16:03.606974 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Oct 31 00:16:03.624175 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Oct 31 00:16:03.653863 kernel: BTRFS info (device sda6): auto enabling async discard Oct 31 00:16:03.637062 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Oct 31 00:16:03.651306 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 31 00:16:04.025340 systemd-networkd[905]: eth0: Gained IPv6LL Oct 31 00:16:04.598192 coreos-metadata[964]: Oct 31 00:16:04.598 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Oct 31 00:16:04.604283 coreos-metadata[964]: Oct 31 00:16:04.604 INFO Fetch successful Oct 31 00:16:04.604283 coreos-metadata[964]: Oct 31 00:16:04.604 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Oct 31 00:16:04.616164 coreos-metadata[964]: Oct 31 00:16:04.616 INFO Fetch successful Oct 31 00:16:04.620089 coreos-metadata[964]: Oct 31 00:16:04.616 INFO wrote hostname ci-4230.2.4-n-a0bcf4b83b to /sysroot/etc/hostname Oct 31 00:16:04.617431 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Oct 31 00:16:04.713394 initrd-setup-root[992]: cut: /sysroot/etc/passwd: No such file or directory Oct 31 00:16:04.757990 initrd-setup-root[999]: cut: /sysroot/etc/group: No such file or directory Oct 31 00:16:04.871370 initrd-setup-root[1006]: cut: /sysroot/etc/shadow: No such file or directory Oct 31 00:16:04.926017 initrd-setup-root[1013]: cut: /sysroot/etc/gshadow: No such file or directory Oct 31 00:16:06.207979 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Oct 31 00:16:06.229289 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Oct 31 00:16:06.245581 kernel: BTRFS info (device sda6): last unmount of filesystem fa4bc9fa-c5cf-41d8-8cd9-b896e25c00f7 Oct 31 00:16:06.233856 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Oct 31 00:16:06.246311 systemd[1]: sysroot-oem.mount: Deactivated successfully. Oct 31 00:16:06.271318 ignition[1080]: INFO : Ignition 2.20.0 Oct 31 00:16:06.271318 ignition[1080]: INFO : Stage: mount Oct 31 00:16:06.282247 ignition[1080]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 31 00:16:06.282247 ignition[1080]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 31 00:16:06.282247 ignition[1080]: INFO : mount: mount passed Oct 31 00:16:06.282247 ignition[1080]: INFO : Ignition finished successfully Oct 31 00:16:06.272898 systemd[1]: Finished ignition-mount.service - Ignition (mount). Oct 31 00:16:06.296298 systemd[1]: Starting ignition-files.service - Ignition (files)... Oct 31 00:16:06.307346 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 31 00:16:06.327588 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Oct 31 00:16:06.338837 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/sda6 scanned by mount (1093) Oct 31 00:16:06.347809 kernel: BTRFS info (device sda6): first mount of filesystem fa4bc9fa-c5cf-41d8-8cd9-b896e25c00f7 Oct 31 00:16:06.347837 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Oct 31 00:16:06.350917 kernel: BTRFS info (device sda6): using free space tree Oct 31 00:16:06.358219 kernel: BTRFS info (device sda6): auto enabling async discard Oct 31 00:16:06.359603 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 31 00:16:06.389932 ignition[1113]: INFO : Ignition 2.20.0 Oct 31 00:16:06.389932 ignition[1113]: INFO : Stage: files Oct 31 00:16:06.396141 ignition[1113]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 31 00:16:06.396141 ignition[1113]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 31 00:16:06.396141 ignition[1113]: DEBUG : files: compiled without relabeling support, skipping Oct 31 00:16:06.396141 ignition[1113]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Oct 31 00:16:06.396141 ignition[1113]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Oct 31 00:16:06.524904 ignition[1113]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Oct 31 00:16:06.530613 ignition[1113]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Oct 31 00:16:06.530613 ignition[1113]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Oct 31 00:16:06.525644 unknown[1113]: wrote ssh authorized keys file for user: core Oct 31 00:16:06.550733 ignition[1113]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Oct 31 00:16:06.558042 ignition[1113]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Oct 31 00:16:06.558042 ignition[1113]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Oct 31 00:16:06.558042 ignition[1113]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Oct 31 00:16:06.558042 ignition[1113]: INFO : files: files passed Oct 31 00:16:06.558042 ignition[1113]: INFO : Ignition finished successfully Oct 31 00:16:06.567441 systemd[1]: Finished ignition-files.service - Ignition (files). Oct 31 00:16:06.600345 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Oct 31 00:16:06.601101 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Oct 31 00:16:06.610533 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 31 00:16:06.610647 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Oct 31 00:16:06.633091 initrd-setup-root-after-ignition[1145]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 31 00:16:06.633091 initrd-setup-root-after-ignition[1145]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Oct 31 00:16:06.645964 initrd-setup-root-after-ignition[1149]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 31 00:16:06.653377 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 31 00:16:06.653669 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Oct 31 00:16:06.677374 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Oct 31 00:16:06.699887 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 31 00:16:06.699991 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Oct 31 00:16:06.704124 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Oct 31 00:16:06.713825 systemd[1]: Reached target initrd.target - Initrd Default Target. Oct 31 00:16:06.721953 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Oct 31 00:16:06.722712 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Oct 31 00:16:06.756677 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 31 00:16:06.758035 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Oct 31 00:16:06.784769 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Oct 31 00:16:06.784986 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 31 00:16:06.793819 systemd[1]: Stopped target timers.target - Timer Units. Oct 31 00:16:06.803552 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 31 00:16:06.803686 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 31 00:16:06.818922 systemd[1]: Stopped target initrd.target - Initrd Default Target. Oct 31 00:16:06.823531 systemd[1]: Stopped target basic.target - Basic System. Oct 31 00:16:06.831374 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Oct 31 00:16:06.838342 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Oct 31 00:16:06.846675 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Oct 31 00:16:06.855649 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Oct 31 00:16:06.864216 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Oct 31 00:16:06.871925 systemd[1]: Stopped target sysinit.target - System Initialization. Oct 31 00:16:06.881534 systemd[1]: Stopped target local-fs.target - Local File Systems. Oct 31 00:16:06.889366 systemd[1]: Stopped target swap.target - Swaps. Oct 31 00:16:06.897451 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 31 00:16:06.897596 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Oct 31 00:16:06.911520 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Oct 31 00:16:06.915665 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 31 00:16:06.923961 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Oct 31 00:16:06.924079 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 31 00:16:06.933289 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 31 00:16:06.933419 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Oct 31 00:16:06.951365 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Oct 31 00:16:06.951502 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 31 00:16:06.955144 systemd[1]: ignition-files.service: Deactivated successfully. Oct 31 00:16:06.955448 systemd[1]: Stopped ignition-files.service - Ignition (files). Oct 31 00:16:06.965321 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Oct 31 00:16:06.965449 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Oct 31 00:16:06.993363 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Oct 31 00:16:06.994835 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 31 00:16:06.994967 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Oct 31 00:16:07.024085 ignition[1169]: INFO : Ignition 2.20.0 Oct 31 00:16:07.024085 ignition[1169]: INFO : Stage: umount Oct 31 00:16:07.024085 ignition[1169]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 31 00:16:07.024085 ignition[1169]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 31 00:16:07.024085 ignition[1169]: INFO : umount: umount passed Oct 31 00:16:07.024085 ignition[1169]: INFO : Ignition finished successfully Oct 31 00:16:07.004868 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Oct 31 00:16:07.015453 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 31 00:16:07.015597 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Oct 31 00:16:07.028959 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 31 00:16:07.029086 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Oct 31 00:16:07.042003 systemd[1]: sysroot-boot.mount: Deactivated successfully. Oct 31 00:16:07.042961 systemd[1]: ignition-mount.service: Deactivated successfully. Oct 31 00:16:07.043059 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Oct 31 00:16:07.050103 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 31 00:16:07.050196 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Oct 31 00:16:07.054873 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 31 00:16:07.054939 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Oct 31 00:16:07.072782 systemd[1]: ignition-kargs.service: Deactivated successfully. Oct 31 00:16:07.072843 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Oct 31 00:16:07.080441 systemd[1]: ignition-fetch.service: Deactivated successfully. Oct 31 00:16:07.080492 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Oct 31 00:16:07.084471 systemd[1]: Stopped target network.target - Network. Oct 31 00:16:07.092716 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Oct 31 00:16:07.092772 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Oct 31 00:16:07.099961 systemd[1]: Stopped target paths.target - Path Units. Oct 31 00:16:07.108488 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 31 00:16:07.112254 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 31 00:16:07.115422 systemd[1]: Stopped target slices.target - Slice Units. Oct 31 00:16:07.123877 systemd[1]: Stopped target sockets.target - Socket Units. Oct 31 00:16:07.131095 systemd[1]: iscsid.socket: Deactivated successfully. Oct 31 00:16:07.131146 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Oct 31 00:16:07.138361 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 31 00:16:07.138411 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 31 00:16:07.146806 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 31 00:16:07.146866 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Oct 31 00:16:07.154290 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Oct 31 00:16:07.154341 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Oct 31 00:16:07.161911 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Oct 31 00:16:07.169578 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Oct 31 00:16:07.177425 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 31 00:16:07.177532 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Oct 31 00:16:07.192978 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 31 00:16:07.193094 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Oct 31 00:16:07.204378 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 31 00:16:07.204482 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Oct 31 00:16:07.214151 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Oct 31 00:16:07.214229 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Oct 31 00:16:07.215840 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 31 00:16:07.215889 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Oct 31 00:16:07.216256 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 31 00:16:07.216308 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Oct 31 00:16:07.246299 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Oct 31 00:16:07.250004 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Oct 31 00:16:07.250062 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 31 00:16:07.257192 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 31 00:16:07.257259 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Oct 31 00:16:07.270135 systemd[1]: systemd-modules-load.service: Deactivated successfully. Oct 31 00:16:07.270183 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Oct 31 00:16:07.273886 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 31 00:16:07.273934 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 31 00:16:07.290771 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 31 00:16:07.300148 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Oct 31 00:16:07.300229 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Oct 31 00:16:07.330885 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 31 00:16:07.330994 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Oct 31 00:16:07.336848 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 31 00:16:07.336989 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 31 00:16:07.346159 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 31 00:16:07.346216 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Oct 31 00:16:07.351001 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 31 00:16:07.351039 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Oct 31 00:16:07.359029 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 31 00:16:07.359077 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Oct 31 00:16:07.375641 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 31 00:16:07.375687 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Oct 31 00:16:07.387812 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 31 00:16:07.387858 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 31 00:16:07.421335 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Oct 31 00:16:07.422498 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 31 00:16:07.422549 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 31 00:16:07.437001 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 31 00:16:07.437050 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 31 00:16:07.448123 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Oct 31 00:16:07.448185 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Oct 31 00:16:07.448517 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 31 00:16:07.448607 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Oct 31 00:16:07.457044 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Oct 31 00:16:07.462940 systemd[1]: Starting initrd-switch-root.service - Switch Root... Oct 31 00:16:10.682370 systemd[1]: Switching root. Oct 31 00:16:10.772861 systemd-journald[205]: Journal stopped Oct 31 00:16:16.030600 systemd-journald[205]: Received SIGTERM from PID 1 (systemd). Oct 31 00:16:16.030633 kernel: SELinux: policy capability network_peer_controls=1 Oct 31 00:16:16.030647 kernel: SELinux: policy capability open_perms=1 Oct 31 00:16:16.030658 kernel: SELinux: policy capability extended_socket_class=1 Oct 31 00:16:16.030667 kernel: SELinux: policy capability always_check_network=0 Oct 31 00:16:16.030677 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 31 00:16:16.030687 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 31 00:16:16.030700 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 31 00:16:16.030709 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Oct 31 00:16:16.030719 kernel: audit: type=1403 audit(1761869771.873:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 31 00:16:16.030730 systemd[1]: Successfully loaded SELinux policy in 256.188ms. Oct 31 00:16:16.030742 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 16.086ms. Oct 31 00:16:16.030754 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 31 00:16:16.030765 systemd[1]: Detected virtualization microsoft. Oct 31 00:16:16.030779 systemd[1]: Detected architecture x86-64. Oct 31 00:16:16.030790 systemd[1]: Detected first boot. Oct 31 00:16:16.030802 systemd[1]: Hostname set to . Oct 31 00:16:16.030813 systemd[1]: Initializing machine ID from random generator. Oct 31 00:16:16.030824 zram_generator::config[1216]: No configuration found. Oct 31 00:16:16.030838 kernel: Guest personality initialized and is inactive Oct 31 00:16:16.030849 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Oct 31 00:16:16.030859 kernel: Initialized host personality Oct 31 00:16:16.030869 kernel: NET: Registered PF_VSOCK protocol family Oct 31 00:16:16.030879 systemd[1]: Populated /etc with preset unit settings. Oct 31 00:16:16.030891 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Oct 31 00:16:16.030902 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 31 00:16:16.030913 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Oct 31 00:16:16.030930 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 31 00:16:16.030941 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Oct 31 00:16:16.030953 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Oct 31 00:16:16.030964 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Oct 31 00:16:16.030975 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Oct 31 00:16:16.030987 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Oct 31 00:16:16.030998 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Oct 31 00:16:16.031012 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Oct 31 00:16:16.031023 systemd[1]: Created slice user.slice - User and Session Slice. Oct 31 00:16:16.031034 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 31 00:16:16.031045 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 31 00:16:16.031057 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Oct 31 00:16:16.031068 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Oct 31 00:16:16.031083 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Oct 31 00:16:16.031095 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 31 00:16:16.031109 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Oct 31 00:16:16.031121 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 31 00:16:16.031133 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Oct 31 00:16:16.031144 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Oct 31 00:16:16.031155 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Oct 31 00:16:16.031167 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Oct 31 00:16:16.031181 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 31 00:16:16.031192 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 31 00:16:16.031216 systemd[1]: Reached target slices.target - Slice Units. Oct 31 00:16:16.031229 systemd[1]: Reached target swap.target - Swaps. Oct 31 00:16:16.031240 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Oct 31 00:16:16.031252 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Oct 31 00:16:16.031264 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Oct 31 00:16:16.031279 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 31 00:16:16.031291 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 31 00:16:16.031303 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 31 00:16:16.031314 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Oct 31 00:16:16.031326 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Oct 31 00:16:16.031337 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Oct 31 00:16:16.031349 systemd[1]: Mounting media.mount - External Media Directory... Oct 31 00:16:16.031404 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 31 00:16:16.031431 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Oct 31 00:16:16.031443 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Oct 31 00:16:16.031455 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Oct 31 00:16:16.031468 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 31 00:16:16.031480 systemd[1]: Reached target machines.target - Containers. Oct 31 00:16:16.031491 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Oct 31 00:16:16.031515 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 31 00:16:16.031530 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 31 00:16:16.031545 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Oct 31 00:16:16.031558 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 31 00:16:16.031574 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 31 00:16:16.031587 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 31 00:16:16.031599 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Oct 31 00:16:16.031610 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 31 00:16:16.031622 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Oct 31 00:16:16.031634 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 31 00:16:16.031649 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Oct 31 00:16:16.031661 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 31 00:16:16.031672 systemd[1]: Stopped systemd-fsck-usr.service. Oct 31 00:16:16.031685 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 31 00:16:16.031697 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 31 00:16:16.031709 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 31 00:16:16.031721 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 31 00:16:16.031732 kernel: loop: module loaded Oct 31 00:16:16.031747 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Oct 31 00:16:16.031758 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Oct 31 00:16:16.031794 systemd-journald[1319]: Collecting audit messages is disabled. Oct 31 00:16:16.031823 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 31 00:16:16.031840 systemd-journald[1319]: Journal started Oct 31 00:16:16.031863 systemd-journald[1319]: Runtime Journal (/run/log/journal/e539db5657ab47f7a4be993d60d1967d) is 8M, max 2.2G, 2.1G free. Oct 31 00:16:15.296237 systemd[1]: Queued start job for default target multi-user.target. Oct 31 00:16:15.307626 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Oct 31 00:16:15.308084 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 31 00:16:16.044124 systemd[1]: verity-setup.service: Deactivated successfully. Oct 31 00:16:16.044174 systemd[1]: Stopped verity-setup.service. Oct 31 00:16:16.056224 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 31 00:16:16.063224 systemd[1]: Started systemd-journald.service - Journal Service. Oct 31 00:16:16.068365 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Oct 31 00:16:16.071226 kernel: fuse: init (API version 7.39) Oct 31 00:16:16.075076 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Oct 31 00:16:16.079739 systemd[1]: Mounted media.mount - External Media Directory. Oct 31 00:16:16.083847 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Oct 31 00:16:16.088414 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Oct 31 00:16:16.093142 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Oct 31 00:16:16.097598 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Oct 31 00:16:16.102889 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 31 00:16:16.108447 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 31 00:16:16.108643 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Oct 31 00:16:16.114252 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 31 00:16:16.114451 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 31 00:16:16.120230 kernel: ACPI: bus type drm_connector registered Oct 31 00:16:16.122448 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 31 00:16:16.122637 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 31 00:16:16.128703 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 31 00:16:16.128889 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 31 00:16:16.133620 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 31 00:16:16.133819 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Oct 31 00:16:16.138506 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 31 00:16:16.138690 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 31 00:16:16.143567 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 31 00:16:16.148344 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 31 00:16:16.153657 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Oct 31 00:16:16.159063 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Oct 31 00:16:16.173611 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 31 00:16:16.190308 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Oct 31 00:16:16.195634 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Oct 31 00:16:16.200048 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Oct 31 00:16:16.200085 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 31 00:16:16.205246 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Oct 31 00:16:16.211241 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Oct 31 00:16:16.216789 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Oct 31 00:16:16.220918 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 31 00:16:16.222006 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Oct 31 00:16:16.227187 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Oct 31 00:16:16.231770 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 31 00:16:16.232713 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Oct 31 00:16:16.237034 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 31 00:16:16.238012 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 31 00:16:16.242915 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Oct 31 00:16:16.254029 systemd[1]: Starting systemd-sysusers.service - Create System Users... Oct 31 00:16:16.263619 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 31 00:16:16.268590 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Oct 31 00:16:16.277563 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Oct 31 00:16:16.282694 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Oct 31 00:16:16.287952 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Oct 31 00:16:16.295320 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Oct 31 00:16:16.303522 systemd-journald[1319]: Time spent on flushing to /var/log/journal/e539db5657ab47f7a4be993d60d1967d is 15.288ms for 941 entries. Oct 31 00:16:16.303522 systemd-journald[1319]: System Journal (/var/log/journal/e539db5657ab47f7a4be993d60d1967d) is 8M, max 2.6G, 2.6G free. Oct 31 00:16:16.365625 systemd-journald[1319]: Received client request to flush runtime journal. Oct 31 00:16:16.365689 kernel: loop0: detected capacity change from 0 to 28272 Oct 31 00:16:16.311466 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Oct 31 00:16:16.317199 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Oct 31 00:16:16.331473 udevadm[1371]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Oct 31 00:16:16.366621 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Oct 31 00:16:16.397028 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 31 00:16:16.404102 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 31 00:16:16.404814 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Oct 31 00:16:16.438502 systemd[1]: Finished systemd-sysusers.service - Create System Users. Oct 31 00:16:16.451361 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 31 00:16:16.532246 systemd-tmpfiles[1377]: ACLs are not supported, ignoring. Oct 31 00:16:16.532266 systemd-tmpfiles[1377]: ACLs are not supported, ignoring. Oct 31 00:16:16.549095 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 31 00:16:17.113518 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Oct 31 00:16:17.130227 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Oct 31 00:16:17.131398 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 31 00:16:17.156320 systemd-udevd[1383]: Using default interface naming scheme 'v255'. Oct 31 00:16:17.234051 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 31 00:16:17.238395 kernel: loop1: detected capacity change from 0 to 138176 Oct 31 00:16:17.261168 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 31 00:16:17.267080 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Oct 31 00:16:17.322708 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Oct 31 00:16:17.337140 kernel: mousedev: PS/2 mouse device common for all mice Oct 31 00:16:17.337200 kernel: hv_vmbus: registering driver hv_balloon Oct 31 00:16:17.342259 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Oct 31 00:16:17.359228 kernel: hv_vmbus: registering driver hyperv_fb Oct 31 00:16:17.368000 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Oct 31 00:16:17.368071 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Oct 31 00:16:17.366373 systemd[1]: Started systemd-userdbd.service - User Database Manager. Oct 31 00:16:17.372813 kernel: Console: switching to colour dummy device 80x25 Oct 31 00:16:17.374924 kernel: Console: switching to colour frame buffer device 128x48 Oct 31 00:16:17.380234 kernel: EDAC MC: Ver: 3.0.0 Oct 31 00:16:17.491704 systemd-networkd[1410]: lo: Link UP Oct 31 00:16:17.491716 systemd-networkd[1410]: lo: Gained carrier Oct 31 00:16:17.492432 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 31 00:16:17.493104 systemd-networkd[1410]: Enumeration completed Oct 31 00:16:17.493556 systemd-networkd[1410]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 31 00:16:17.493569 systemd-networkd[1410]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 31 00:16:17.494200 systemd-networkd[1410]: eth0: Link UP Oct 31 00:16:17.494221 systemd-networkd[1410]: eth0: Gained carrier Oct 31 00:16:17.494234 systemd-networkd[1410]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 31 00:16:17.496898 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 31 00:16:17.505387 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Oct 31 00:16:17.511292 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Oct 31 00:16:17.523540 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 31 00:16:17.523854 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 31 00:16:17.529890 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Oct 31 00:16:17.531175 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 31 00:16:17.538259 systemd-networkd[1410]: eth0: DHCPv4 address 10.200.8.27/24, gateway 10.200.8.1 acquired from 168.63.129.16 Oct 31 00:16:17.561177 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Oct 31 00:16:17.612229 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 37 scanned by (udev-worker) (1397) Oct 31 00:16:17.655720 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Oct 31 00:16:17.681361 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Oct 31 00:16:17.686985 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Oct 31 00:16:17.695283 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Oct 31 00:16:17.734226 kernel: loop2: detected capacity change from 0 to 147912 Oct 31 00:16:17.764715 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Oct 31 00:16:17.809848 lvm[1517]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 31 00:16:17.859976 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Oct 31 00:16:17.865718 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 31 00:16:17.880394 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Oct 31 00:16:17.889820 lvm[1521]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 31 00:16:17.926937 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Oct 31 00:16:18.224268 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 31 00:16:18.595233 kernel: loop3: detected capacity change from 0 to 28272 Oct 31 00:16:18.610237 kernel: loop4: detected capacity change from 0 to 138176 Oct 31 00:16:18.632229 kernel: loop5: detected capacity change from 0 to 147912 Oct 31 00:16:18.659278 (sd-merge)[1528]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Oct 31 00:16:18.659793 (sd-merge)[1528]: Merged extensions into '/usr'. Oct 31 00:16:18.663342 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Oct 31 00:16:18.679369 systemd[1]: Starting ensure-sysext.service... Oct 31 00:16:18.683816 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 31 00:16:18.691405 systemd[1]: Reload requested from client PID 1530 ('systemctl') (unit ensure-sysext.service)... Oct 31 00:16:18.691424 systemd[1]: Reloading... Oct 31 00:16:18.727582 systemd-tmpfiles[1531]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Oct 31 00:16:18.727892 systemd-tmpfiles[1531]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Oct 31 00:16:18.728828 systemd-tmpfiles[1531]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Oct 31 00:16:18.729113 systemd-tmpfiles[1531]: ACLs are not supported, ignoring. Oct 31 00:16:18.729174 systemd-tmpfiles[1531]: ACLs are not supported, ignoring. Oct 31 00:16:18.736230 zram_generator::config[1566]: No configuration found. Oct 31 00:16:18.780472 systemd-tmpfiles[1531]: Detected autofs mount point /boot during canonicalization of boot. Oct 31 00:16:18.780487 systemd-tmpfiles[1531]: Skipping /boot Oct 31 00:16:18.792167 systemd-tmpfiles[1531]: Detected autofs mount point /boot during canonicalization of boot. Oct 31 00:16:18.792181 systemd-tmpfiles[1531]: Skipping /boot Oct 31 00:16:18.873311 systemd-networkd[1410]: eth0: Gained IPv6LL Oct 31 00:16:18.875223 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 31 00:16:19.011750 systemd[1]: Reloading finished in 319 ms. Oct 31 00:16:19.043831 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Oct 31 00:16:19.068381 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 31 00:16:19.084464 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 31 00:16:19.094458 systemd[1]: Starting audit-rules.service - Load Audit Rules... Oct 31 00:16:19.099608 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Oct 31 00:16:19.105793 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 31 00:16:19.106921 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 31 00:16:19.112088 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 31 00:16:19.117940 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 31 00:16:19.122170 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 31 00:16:19.122311 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 31 00:16:19.123500 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Oct 31 00:16:19.130032 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 31 00:16:19.135284 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Oct 31 00:16:19.139875 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 31 00:16:19.142240 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 31 00:16:19.142439 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 31 00:16:19.147521 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 31 00:16:19.147710 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 31 00:16:19.153623 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 31 00:16:19.153809 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 31 00:16:19.164295 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 31 00:16:19.164510 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 31 00:16:19.165979 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 31 00:16:19.171322 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 31 00:16:19.176969 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 31 00:16:19.181416 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 31 00:16:19.181547 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 31 00:16:19.181664 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 31 00:16:19.184980 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 31 00:16:19.185165 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 31 00:16:19.190180 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 31 00:16:19.190376 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 31 00:16:19.195705 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 31 00:16:19.195892 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 31 00:16:19.209444 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 31 00:16:19.209777 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 31 00:16:19.220429 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 31 00:16:19.225537 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 31 00:16:19.230668 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 31 00:16:19.236549 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 31 00:16:19.240806 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 31 00:16:19.240858 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 31 00:16:19.240923 systemd[1]: Reached target time-set.target - System Time Set. Oct 31 00:16:19.245046 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 31 00:16:19.246069 systemd[1]: Finished ensure-sysext.service. Oct 31 00:16:19.249703 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Oct 31 00:16:19.255201 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Oct 31 00:16:19.260508 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 31 00:16:19.260698 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 31 00:16:19.265729 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 31 00:16:19.265993 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 31 00:16:19.270565 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 31 00:16:19.270808 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 31 00:16:19.276087 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 31 00:16:19.276280 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 31 00:16:19.287230 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 31 00:16:19.287327 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 31 00:16:19.337911 systemd-resolved[1636]: Positive Trust Anchors: Oct 31 00:16:19.337930 systemd-resolved[1636]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 31 00:16:19.337962 systemd-resolved[1636]: 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 31 00:16:19.377045 systemd-resolved[1636]: Using system hostname 'ci-4230.2.4-n-a0bcf4b83b'. Oct 31 00:16:19.378988 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 31 00:16:19.383674 systemd[1]: Reached target network.target - Network. Oct 31 00:16:19.387349 systemd[1]: Reached target network-online.target - Network is Online. Oct 31 00:16:19.391773 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 31 00:16:19.403781 augenrules[1672]: No rules Oct 31 00:16:19.405018 systemd[1]: audit-rules.service: Deactivated successfully. Oct 31 00:16:19.405328 systemd[1]: Finished audit-rules.service - Load Audit Rules. Oct 31 00:16:20.140634 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Oct 31 00:16:20.146708 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 31 00:16:24.272290 ldconfig[1353]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 31 00:16:24.548850 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Oct 31 00:16:24.565364 systemd[1]: Starting systemd-update-done.service - Update is Completed... Oct 31 00:16:24.578590 systemd[1]: Finished systemd-update-done.service - Update is Completed. Oct 31 00:16:24.583223 systemd[1]: Reached target sysinit.target - System Initialization. Oct 31 00:16:24.587523 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Oct 31 00:16:24.592524 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 31 00:16:24.598453 systemd[1]: Started logrotate.timer - Daily rotation of log files. Oct 31 00:16:24.603603 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Oct 31 00:16:24.608926 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Oct 31 00:16:24.613945 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Oct 31 00:16:24.613985 systemd[1]: Reached target paths.target - Path Units. Oct 31 00:16:24.617656 systemd[1]: Reached target timers.target - Timer Units. Oct 31 00:16:24.622655 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Oct 31 00:16:24.628315 systemd[1]: Starting docker.socket - Docker Socket for the API... Oct 31 00:16:24.635020 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Oct 31 00:16:24.640128 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Oct 31 00:16:24.645047 systemd[1]: Reached target ssh-access.target - SSH Access Available. Oct 31 00:16:24.654271 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Oct 31 00:16:24.658648 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Oct 31 00:16:24.664031 systemd[1]: Listening on docker.socket - Docker Socket for the API. Oct 31 00:16:24.668473 systemd[1]: Reached target sockets.target - Socket Units. Oct 31 00:16:24.672249 systemd[1]: Reached target basic.target - Basic System. Oct 31 00:16:24.675980 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Oct 31 00:16:24.676010 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Oct 31 00:16:24.867309 systemd[1]: Starting chronyd.service - NTP client/server... Oct 31 00:16:24.871925 systemd[1]: Starting containerd.service - containerd container runtime... Oct 31 00:16:24.877439 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Oct 31 00:16:24.882506 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Oct 31 00:16:24.887083 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Oct 31 00:16:24.892428 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Oct 31 00:16:24.896557 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 31 00:16:24.896604 systemd[1]: hv_fcopy_daemon.service - Hyper-V FCOPY daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_fcopy). Oct 31 00:16:24.897580 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Oct 31 00:16:24.901665 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Oct 31 00:16:24.902743 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Oct 31 00:16:24.907792 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Oct 31 00:16:24.912700 KVP[1693]: KVP starting; pid is:1693 Oct 31 00:16:24.912661 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Oct 31 00:16:24.915219 KVP[1693]: KVP LIC Version: 3.1 Oct 31 00:16:24.917224 kernel: hv_utils: KVP IC version 4.0 Oct 31 00:16:24.919534 jq[1691]: false Oct 31 00:16:24.920378 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Oct 31 00:16:24.927325 systemd[1]: Starting systemd-logind.service - User Login Management... Oct 31 00:16:24.932255 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Oct 31 00:16:24.932683 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 31 00:16:24.934526 systemd[1]: Starting update-engine.service - Update Engine... Oct 31 00:16:24.941324 (chronyd)[1684]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Oct 31 00:16:24.942096 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Oct 31 00:16:24.947581 chronyd[1714]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) Oct 31 00:16:24.949232 extend-filesystems[1692]: Found loop3 Oct 31 00:16:24.949232 extend-filesystems[1692]: Found loop4 Oct 31 00:16:24.949232 extend-filesystems[1692]: Found loop5 Oct 31 00:16:24.949232 extend-filesystems[1692]: Found sda Oct 31 00:16:24.949232 extend-filesystems[1692]: Found sda1 Oct 31 00:16:24.949232 extend-filesystems[1692]: Found sda2 Oct 31 00:16:24.949232 extend-filesystems[1692]: Found sda3 Oct 31 00:16:24.949232 extend-filesystems[1692]: Found usr Oct 31 00:16:24.949232 extend-filesystems[1692]: Found sda4 Oct 31 00:16:24.949232 extend-filesystems[1692]: Found sda6 Oct 31 00:16:24.986336 extend-filesystems[1692]: Found sda7 Oct 31 00:16:24.986336 extend-filesystems[1692]: Found sda9 Oct 31 00:16:24.986336 extend-filesystems[1692]: Checking size of /dev/sda9 Oct 31 00:16:24.995450 jq[1711]: true Oct 31 00:16:24.949231 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Oct 31 00:16:24.949463 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Oct 31 00:16:24.995881 jq[1718]: true Oct 31 00:16:24.949952 systemd[1]: motdgen.service: Deactivated successfully. Oct 31 00:16:24.950162 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Oct 31 00:16:24.952593 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 31 00:16:24.952783 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Oct 31 00:16:24.980069 (ntainerd)[1724]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Oct 31 00:16:25.006096 update_engine[1709]: I20251031 00:16:25.006026 1709 main.cc:92] Flatcar Update Engine starting Oct 31 00:16:25.365625 extend-filesystems[1692]: Old size kept for /dev/sda9 Oct 31 00:16:25.369058 extend-filesystems[1692]: Found sdb Oct 31 00:16:25.369058 extend-filesystems[1692]: Found sdb1 Oct 31 00:16:25.369058 extend-filesystems[1692]: Found sr0 Oct 31 00:16:25.366934 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 31 00:16:25.376813 chronyd[1714]: Timezone right/UTC failed leap second check, ignoring Oct 31 00:16:25.367175 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Oct 31 00:16:25.376994 chronyd[1714]: Loaded seccomp filter (level 2) Oct 31 00:16:25.378114 systemd[1]: Started chronyd.service - NTP client/server. Oct 31 00:16:25.410986 systemd-logind[1701]: New seat seat0. Oct 31 00:16:25.417228 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 37 scanned by (udev-worker) (1758) Oct 31 00:16:25.421401 systemd-logind[1701]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Oct 31 00:16:25.421622 systemd[1]: Started systemd-logind.service - User Login Management. Oct 31 00:16:25.447274 dbus-daemon[1687]: [system] SELinux support is enabled Oct 31 00:16:25.449188 systemd[1]: Started dbus.service - D-Bus System Message Bus. Oct 31 00:16:25.453580 update_engine[1709]: I20251031 00:16:25.450799 1709 update_check_scheduler.cc:74] Next update check in 4m48s Oct 31 00:16:25.454868 setup-nvidia[1695]: Downloading Flatcar Container Linux Developer Container for version: 4230.2.4+nightly-20251030-2100 Oct 31 00:16:25.464181 dbus-daemon[1687]: [system] Successfully activated service 'org.freedesktop.systemd1' Oct 31 00:16:25.798830 coreos-metadata[1686]: Oct 31 00:16:25.652 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Oct 31 00:16:25.798830 coreos-metadata[1686]: Oct 31 00:16:25.654 INFO Fetch successful Oct 31 00:16:25.798830 coreos-metadata[1686]: Oct 31 00:16:25.654 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Oct 31 00:16:25.798830 coreos-metadata[1686]: Oct 31 00:16:25.658 INFO Fetch successful Oct 31 00:16:25.798830 coreos-metadata[1686]: Oct 31 00:16:25.658 INFO Fetching http://168.63.129.16/machine/9585266f-9515-412a-b579-59c9bbed082f/10236ba1%2Dfef1%2D42f6%2D9d52%2D01d5cffa90c6.%5Fci%2D4230.2.4%2Dn%2Da0bcf4b83b?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Oct 31 00:16:25.798830 coreos-metadata[1686]: Oct 31 00:16:25.660 INFO Fetch successful Oct 31 00:16:25.798830 coreos-metadata[1686]: Oct 31 00:16:25.660 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Oct 31 00:16:25.798830 coreos-metadata[1686]: Oct 31 00:16:25.667 INFO Fetch successful Oct 31 00:16:25.466239 systemd[1]: Started update-engine.service - Update Engine. Oct 31 00:16:25.471647 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 31 00:16:25.471809 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Oct 31 00:16:25.476981 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 31 00:16:25.477107 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Oct 31 00:16:25.494420 systemd[1]: Started locksmithd.service - Cluster reboot manager. Oct 31 00:16:25.695577 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Oct 31 00:16:25.700570 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Oct 31 00:16:25.800618 sshd_keygen[1710]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Oct 31 00:16:25.821836 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Oct 31 00:16:25.836504 systemd[1]: Starting issuegen.service - Generate /run/issue... Oct 31 00:16:25.841228 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Oct 31 00:16:25.845623 systemd[1]: issuegen.service: Deactivated successfully. Oct 31 00:16:25.845815 systemd[1]: Finished issuegen.service - Generate /run/issue. Oct 31 00:16:25.853364 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Oct 31 00:16:25.863464 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Oct 31 00:16:25.906958 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Oct 31 00:16:25.928483 systemd[1]: Started getty@tty1.service - Getty on tty1. Oct 31 00:16:25.933664 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Oct 31 00:16:25.938875 systemd[1]: Reached target getty.target - Login Prompts. Oct 31 00:16:26.376194 locksmithd[1822]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 31 00:16:26.502055 setup-nvidia[1855]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 31 00:16:26.944754 setup-nvidia[1855]: Dload Upload Total Spent Left Speed Oct 31 00:16:26.946474 bash[1742]: Updated "/home/core/.ssh/authorized_keys" Oct 31 00:16:26.948112 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Oct 31 00:16:26.954163 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Oct 31 00:16:28.122872 containerd[1724]: time="2025-10-31T00:16:28.122785800Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Oct 31 00:16:28.141310 containerd[1724]: time="2025-10-31T00:16:28.141272200Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Oct 31 00:16:28.142815 containerd[1724]: time="2025-10-31T00:16:28.142774900Z" 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.113-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Oct 31 00:16:28.142815 containerd[1724]: time="2025-10-31T00:16:28.142804100Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Oct 31 00:16:28.142895 containerd[1724]: time="2025-10-31T00:16:28.142819500Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Oct 31 00:16:28.142978 containerd[1724]: time="2025-10-31T00:16:28.142956900Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Oct 31 00:16:28.143008 containerd[1724]: time="2025-10-31T00:16:28.142978700Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Oct 31 00:16:28.143061 containerd[1724]: time="2025-10-31T00:16:28.143037500Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Oct 31 00:16:28.143061 containerd[1724]: time="2025-10-31T00:16:28.143056900Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Oct 31 00:16:28.143266 containerd[1724]: time="2025-10-31T00:16:28.143245300Z" 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 31 00:16:28.143266 containerd[1724]: time="2025-10-31T00:16:28.143264400Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Oct 31 00:16:28.143316 containerd[1724]: time="2025-10-31T00:16:28.143277100Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Oct 31 00:16:28.143316 containerd[1724]: time="2025-10-31T00:16:28.143287000Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Oct 31 00:16:28.143387 containerd[1724]: time="2025-10-31T00:16:28.143368600Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Oct 31 00:16:28.143598 containerd[1724]: time="2025-10-31T00:16:28.143579800Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Oct 31 00:16:28.143721 containerd[1724]: time="2025-10-31T00:16:28.143701600Z" 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 31 00:16:28.143756 containerd[1724]: time="2025-10-31T00:16:28.143719800Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Oct 31 00:16:28.143820 containerd[1724]: time="2025-10-31T00:16:28.143802400Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Oct 31 00:16:28.143888 containerd[1724]: time="2025-10-31T00:16:28.143871000Z" level=info msg="metadata content store policy set" policy=shared Oct 31 00:16:28.161930 containerd[1724]: time="2025-10-31T00:16:28.161897900Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Oct 31 00:16:28.161985 containerd[1724]: time="2025-10-31T00:16:28.161947100Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Oct 31 00:16:28.161985 containerd[1724]: time="2025-10-31T00:16:28.161965700Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Oct 31 00:16:28.161985 containerd[1724]: time="2025-10-31T00:16:28.161981900Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Oct 31 00:16:28.162073 containerd[1724]: time="2025-10-31T00:16:28.161995300Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Oct 31 00:16:28.162168 containerd[1724]: time="2025-10-31T00:16:28.162143800Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Oct 31 00:16:28.162384 containerd[1724]: time="2025-10-31T00:16:28.162363700Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Oct 31 00:16:28.162498 containerd[1724]: time="2025-10-31T00:16:28.162479100Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Oct 31 00:16:28.162527 containerd[1724]: time="2025-10-31T00:16:28.162501800Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Oct 31 00:16:28.162527 containerd[1724]: time="2025-10-31T00:16:28.162517300Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Oct 31 00:16:28.162581 containerd[1724]: time="2025-10-31T00:16:28.162531800Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Oct 31 00:16:28.162581 containerd[1724]: time="2025-10-31T00:16:28.162544600Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Oct 31 00:16:28.162581 containerd[1724]: time="2025-10-31T00:16:28.162555700Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Oct 31 00:16:28.162581 containerd[1724]: time="2025-10-31T00:16:28.162568300Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Oct 31 00:16:28.162668 containerd[1724]: time="2025-10-31T00:16:28.162582600Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Oct 31 00:16:28.162668 containerd[1724]: time="2025-10-31T00:16:28.162595100Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Oct 31 00:16:28.162895 containerd[1724]: time="2025-10-31T00:16:28.162871100Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Oct 31 00:16:28.162934 containerd[1724]: time="2025-10-31T00:16:28.162895500Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Oct 31 00:16:28.162934 containerd[1724]: time="2025-10-31T00:16:28.162915800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Oct 31 00:16:28.162978 containerd[1724]: time="2025-10-31T00:16:28.162936200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Oct 31 00:16:28.162978 containerd[1724]: time="2025-10-31T00:16:28.162949300Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Oct 31 00:16:28.162978 containerd[1724]: time="2025-10-31T00:16:28.162961400Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Oct 31 00:16:28.162978 containerd[1724]: time="2025-10-31T00:16:28.162972400Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Oct 31 00:16:28.163077 containerd[1724]: time="2025-10-31T00:16:28.162984700Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Oct 31 00:16:28.163077 containerd[1724]: time="2025-10-31T00:16:28.162996900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Oct 31 00:16:28.163077 containerd[1724]: time="2025-10-31T00:16:28.163008200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Oct 31 00:16:28.163077 containerd[1724]: time="2025-10-31T00:16:28.163033600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Oct 31 00:16:28.163077 containerd[1724]: time="2025-10-31T00:16:28.163051700Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Oct 31 00:16:28.163077 containerd[1724]: time="2025-10-31T00:16:28.163064100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Oct 31 00:16:28.163077 containerd[1724]: time="2025-10-31T00:16:28.163075800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Oct 31 00:16:28.163246 containerd[1724]: time="2025-10-31T00:16:28.163088000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Oct 31 00:16:28.163246 containerd[1724]: time="2025-10-31T00:16:28.163101600Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Oct 31 00:16:28.163246 containerd[1724]: time="2025-10-31T00:16:28.163121100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Oct 31 00:16:28.163246 containerd[1724]: time="2025-10-31T00:16:28.163134900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Oct 31 00:16:28.163246 containerd[1724]: time="2025-10-31T00:16:28.163144500Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Oct 31 00:16:28.163363 containerd[1724]: time="2025-10-31T00:16:28.163279500Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Oct 31 00:16:28.163363 containerd[1724]: time="2025-10-31T00:16:28.163303200Z" 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 31 00:16:28.163363 containerd[1724]: time="2025-10-31T00:16:28.163314000Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Oct 31 00:16:28.163363 containerd[1724]: time="2025-10-31T00:16:28.163325900Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Oct 31 00:16:28.163363 containerd[1724]: time="2025-10-31T00:16:28.163335900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Oct 31 00:16:28.163363 containerd[1724]: time="2025-10-31T00:16:28.163346900Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Oct 31 00:16:28.163363 containerd[1724]: time="2025-10-31T00:16:28.163356100Z" level=info msg="NRI interface is disabled by configuration." Oct 31 00:16:28.163363 containerd[1724]: time="2025-10-31T00:16:28.163365500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Oct 31 00:16:28.163719 containerd[1724]: time="2025-10-31T00:16:28.163666100Z" 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 31 00:16:28.163848 containerd[1724]: time="2025-10-31T00:16:28.163721600Z" level=info msg="Connect containerd service" Oct 31 00:16:28.163848 containerd[1724]: time="2025-10-31T00:16:28.163820700Z" level=info msg="using legacy CRI server" Oct 31 00:16:28.163848 containerd[1724]: time="2025-10-31T00:16:28.163836400Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Oct 31 00:16:28.163970 containerd[1724]: time="2025-10-31T00:16:28.163947800Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Oct 31 00:16:28.164803 containerd[1724]: time="2025-10-31T00:16:28.164776800Z" 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 31 00:16:28.164958 containerd[1724]: time="2025-10-31T00:16:28.164920000Z" level=info msg="Start subscribing containerd event" Oct 31 00:16:28.164987 containerd[1724]: time="2025-10-31T00:16:28.164976100Z" level=info msg="Start recovering state" Oct 31 00:16:28.165056 containerd[1724]: time="2025-10-31T00:16:28.165039100Z" level=info msg="Start event monitor" Oct 31 00:16:28.165095 containerd[1724]: time="2025-10-31T00:16:28.165071800Z" level=info msg="Start snapshots syncer" Oct 31 00:16:28.165095 containerd[1724]: time="2025-10-31T00:16:28.165082200Z" level=info msg="Start cni network conf syncer for default" Oct 31 00:16:28.165095 containerd[1724]: time="2025-10-31T00:16:28.165091200Z" level=info msg="Start streaming server" Oct 31 00:16:28.165161 containerd[1724]: time="2025-10-31T00:16:28.165149100Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Oct 31 00:16:28.165242 containerd[1724]: time="2025-10-31T00:16:28.165219000Z" level=info msg=serving... address=/run/containerd/containerd.sock Oct 31 00:16:28.165308 containerd[1724]: time="2025-10-31T00:16:28.165292800Z" level=info msg="containerd successfully booted in 0.043790s" Oct 31 00:16:28.165378 systemd[1]: Started containerd.service - containerd container runtime. Oct 31 00:16:32.371484 waagent[1847]: 2025-10-31T00:16:32.371377Z INFO Daemon Daemon Azure Linux Agent Version: 2.9.1.1 Oct 31 00:16:32.390770 waagent[1847]: 2025-10-31T00:16:32.371775Z INFO Daemon Daemon OS: flatcar 4230.2.4 Oct 31 00:16:32.390770 waagent[1847]: 2025-10-31T00:16:32.373230Z INFO Daemon Daemon Python: 3.11.11 Oct 31 00:16:32.390770 waagent[1847]: 2025-10-31T00:16:32.374145Z INFO Daemon Daemon Run daemon Oct 31 00:16:32.390770 waagent[1847]: 2025-10-31T00:16:32.374765Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4230.2.4' Oct 31 00:16:32.390770 waagent[1847]: 2025-10-31T00:16:32.376139Z INFO Daemon Daemon Using waagent for provisioning Oct 31 00:16:32.390770 waagent[1847]: 2025-10-31T00:16:32.377039Z INFO Daemon Daemon Activate resource disk Oct 31 00:16:32.390770 waagent[1847]: 2025-10-31T00:16:32.378419Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Oct 31 00:16:32.390770 waagent[1847]: 2025-10-31T00:16:32.385416Z INFO Daemon Daemon Found device: sdb Oct 31 00:16:32.394719 waagent[1847]: 2025-10-31T00:16:32.393521Z INFO Daemon Daemon Examining partition table Oct 31 00:16:32.596507 waagent[1847]: 2025-10-31T00:16:32.596407Z INFO Daemon Daemon GPT not detected, determining filesystem Oct 31 00:16:32.790057 waagent[1847]: 2025-10-31T00:16:32.789900Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Oct 31 00:16:32.790312 waagent[1847]: 2025-10-31T00:16:32.790260Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Oct 31 00:16:33.338998 login[1850]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Oct 31 00:16:33.655456 systemd-logind[1701]: New session 1 of user core. Oct 31 00:16:33.644651 login[1849]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Oct 31 00:16:33.656626 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Oct 31 00:16:33.690327 (systemd)[1907]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 31 00:16:33.669420 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Oct 31 00:16:33.680203 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Oct 31 00:16:33.681514 systemd[1]: Starting user@500.service - User Manager for UID 500... Oct 31 00:16:33.692635 systemd-logind[1701]: New session c1 of user core. Oct 31 00:16:34.295055 systemd[1907]: Queued start job for default target default.target. Oct 31 00:16:34.308304 systemd[1907]: Created slice app.slice - User Application Slice. Oct 31 00:16:34.308335 systemd[1907]: Reached target paths.target - Paths. Oct 31 00:16:34.308384 systemd[1907]: Reached target timers.target - Timers. Oct 31 00:16:34.309708 systemd[1907]: Starting dbus.socket - D-Bus User Message Bus Socket... Oct 31 00:16:34.320147 systemd[1907]: Listening on dbus.socket - D-Bus User Message Bus Socket. Oct 31 00:16:34.320221 systemd[1907]: Reached target sockets.target - Sockets. Oct 31 00:16:34.320269 systemd[1907]: Reached target basic.target - Basic System. Oct 31 00:16:34.320315 systemd[1907]: Reached target default.target - Main User Target. Oct 31 00:16:34.320346 systemd[1907]: Startup finished in 621ms. Oct 31 00:16:34.320467 systemd[1]: Started user@500.service - User Manager for UID 500. Oct 31 00:16:34.321909 systemd[1]: Started session-1.scope - Session 1 of User core. Oct 31 00:16:34.340828 login[1850]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Oct 31 00:16:34.344953 systemd-logind[1701]: New session 2 of user core. Oct 31 00:16:34.355347 systemd[1]: Started session-2.scope - Session 2 of User core. Oct 31 00:16:41.357230 kernel: sdb: sdb1 Oct 31 00:16:41.362235 kernel: sdb: sdb1 Oct 31 00:16:41.690965 waagent[1847]: 2025-10-31T00:16:41.690797Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Oct 31 00:16:41.701228 kernel: sdb: sdb1 Oct 31 00:16:41.701414 waagent[1847]: 2025-10-31T00:16:41.701362Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Oct 31 00:16:47.425348 waagent[1847]: 2025-10-31T00:16:47.425248Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Oct 31 00:16:47.444913 waagent[1847]: 2025-10-31T00:16:47.444830Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Oct 31 00:16:47.445224 kernel: EXT4-fs (sdb1): mounted filesystem 7db195d3-7f5f-42ae-b7c3-b2d03722441e r/w with ordered data mode. Quota mode: none. Oct 31 00:16:47.445870 waagent[1847]: 2025-10-31T00:16:47.445790Z INFO Daemon Daemon Clean protocol and wireserver endpoint Oct 31 00:16:47.447534 waagent[1847]: 2025-10-31T00:16:47.447459Z INFO Daemon Daemon Running default provisioning handler Oct 31 00:16:47.455769 waagent[1847]: 2025-10-31T00:16:47.455702Z 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 31 00:16:47.457761 waagent[1847]: 2025-10-31T00:16:47.457714Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Oct 31 00:16:47.458088 waagent[1847]: 2025-10-31T00:16:47.458047Z INFO Daemon Daemon cloud-init is enabled: False Oct 31 00:16:47.458843 waagent[1847]: 2025-10-31T00:16:47.458806Z INFO Daemon Daemon Copying ovf-env.xml Oct 31 00:16:47.705219 waagent[1847]: 2025-10-31T00:16:47.705054Z INFO Daemon Daemon Successfully mounted dvd Oct 31 00:16:47.716724 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Oct 31 00:16:47.717043 waagent[1847]: 2025-10-31T00:16:47.716831Z INFO Daemon Daemon Detect protocol endpoint Oct 31 00:16:47.717087 waagent[1847]: 2025-10-31T00:16:47.717048Z INFO Daemon Daemon Clean protocol and wireserver endpoint Oct 31 00:16:47.718609 waagent[1847]: 2025-10-31T00:16:47.718566Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Oct 31 00:16:47.720032 waagent[1847]: 2025-10-31T00:16:47.719993Z INFO Daemon Daemon Test for route to 168.63.129.16 Oct 31 00:16:47.720898 waagent[1847]: 2025-10-31T00:16:47.720857Z INFO Daemon Daemon Route to 168.63.129.16 exists Oct 31 00:16:47.722227 waagent[1847]: 2025-10-31T00:16:47.722177Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Oct 31 00:16:47.759322 waagent[1847]: 2025-10-31T00:16:47.759267Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Oct 31 00:16:47.759720 waagent[1847]: 2025-10-31T00:16:47.759688Z INFO Daemon Daemon Wire protocol version:2012-11-30 Oct 31 00:16:47.760977 waagent[1847]: 2025-10-31T00:16:47.760935Z INFO Daemon Daemon Server preferred version:2015-04-05 Oct 31 00:16:48.084734 waagent[1847]: 2025-10-31T00:16:48.084634Z INFO Daemon Daemon Initializing goal state during protocol detection Oct 31 00:16:48.098476 waagent[1847]: 2025-10-31T00:16:48.084978Z INFO Daemon Daemon Forcing an update of the goal state. Oct 31 00:16:48.098476 waagent[1847]: 2025-10-31T00:16:48.089539Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Oct 31 00:16:48.135764 waagent[1847]: 2025-10-31T00:16:48.135702Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.177 Oct 31 00:16:48.164079 waagent[1847]: 2025-10-31T00:16:48.136403Z INFO Daemon Oct 31 00:16:48.164079 waagent[1847]: 2025-10-31T00:16:48.136778Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 60b21c1c-9f7c-4fcc-98a5-55539fa7fae1 eTag: 12593628500138422331 source: Fabric] Oct 31 00:16:48.164079 waagent[1847]: 2025-10-31T00:16:48.138461Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Oct 31 00:16:48.164079 waagent[1847]: 2025-10-31T00:16:48.139475Z INFO Daemon Oct 31 00:16:48.164079 waagent[1847]: 2025-10-31T00:16:48.139633Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Oct 31 00:16:48.164079 waagent[1847]: 2025-10-31T00:16:48.144797Z INFO Daemon Daemon Downloading artifacts profile blob Oct 31 00:16:48.258402 waagent[1847]: 2025-10-31T00:16:48.258331Z INFO Daemon Downloaded certificate {'thumbprint': 'ACED2D6161E8266223766B8D4AB2B8531E6C7206', 'hasPrivateKey': True} Oct 31 00:16:48.259107 waagent[1847]: 2025-10-31T00:16:48.259057Z INFO Daemon Fetch goal state completed Oct 31 00:16:48.289676 waagent[1847]: 2025-10-31T00:16:48.289629Z INFO Daemon Daemon Starting provisioning Oct 31 00:16:48.289908 waagent[1847]: 2025-10-31T00:16:48.289859Z INFO Daemon Daemon Handle ovf-env.xml. Oct 31 00:16:48.291517 waagent[1847]: 2025-10-31T00:16:48.291460Z INFO Daemon Daemon Set hostname [ci-4230.2.4-n-a0bcf4b83b] Oct 31 00:16:48.357278 waagent[1847]: 2025-10-31T00:16:48.357187Z INFO Daemon Daemon Publish hostname [ci-4230.2.4-n-a0bcf4b83b] Oct 31 00:16:48.357718 waagent[1847]: 2025-10-31T00:16:48.357665Z INFO Daemon Daemon Examine /proc/net/route for primary interface Oct 31 00:16:48.359308 waagent[1847]: 2025-10-31T00:16:48.359253Z INFO Daemon Daemon Primary interface is [eth0] Oct 31 00:16:48.378385 systemd-networkd[1410]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 31 00:16:48.378397 systemd-networkd[1410]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 31 00:16:48.378423 systemd-networkd[1410]: eth0: DHCP lease lost Oct 31 00:16:48.378845 waagent[1847]: 2025-10-31T00:16:48.378782Z INFO Daemon Daemon Create user account if not exists Oct 31 00:16:48.379129 waagent[1847]: 2025-10-31T00:16:48.379083Z INFO Daemon Daemon User core already exists, skip useradd Oct 31 00:16:48.380610 waagent[1847]: 2025-10-31T00:16:48.380561Z INFO Daemon Daemon Configure sudoer Oct 31 00:16:48.382790 waagent[1847]: 2025-10-31T00:16:48.382695Z INFO Daemon Daemon Configure sshd Oct 31 00:16:48.384770 waagent[1847]: 2025-10-31T00:16:48.384672Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Oct 31 00:16:48.385937 waagent[1847]: 2025-10-31T00:16:48.385892Z INFO Daemon Daemon Deploy ssh public key. Oct 31 00:16:48.444246 systemd-networkd[1410]: eth0: DHCPv4 address 10.200.8.27/24, gateway 10.200.8.1 acquired from 168.63.129.16 Oct 31 00:16:49.157752 chronyd[1714]: Selected source PHC0 Oct 31 00:16:51.940174 setup-nvidia[1855]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 535M 0 49152 0 0 73392 0 2:07:36 --:--:-- 2:07:36 73361\u000d 1 535M 1 10.4M 0 0 6196k 0 0:01:28 0:00:01 0:01:27 6196k\u000d 6 535M 6 35.6M 0 0 13.2M 0 0:00:40 0:00:02 0:00:38 13.2M\u000d 11 535M 11 63.6M 0 0 16.8M 0 0:00:31 0:00:03 0:00:28 16.8M\u000d 16 535M 16 88.9M 0 0 18.8M 0 0:00:28 0:00:04 0:00:24 18.8M\u000d 21 535M 21 114M 0 0 20.1M 0 0:00:26 0:00:05 0:00:21 22.8M\u000d 26 535M 26 142M 0 0 21.0M 0 0:00:25 0:00:06 0:00:19 26.2M\u000d 29 535M 29 156M 0 0 20.2M 0 0:00:26 0:00:07 0:00:19 23.9M\u000d 33 535M 33 180M 0 0 20.7M 0 0:00:25 0:00:08 0:00:17 23.7M\u000d 38 535M 38 207M 0 0 21.2M 0 0:00:25 0:00:09 0:00:16 23.5M\u000d 43 535M 43 233M 0 0 21.7M 0 0:00:24 0:00:10 0:00:14 23.5M\u000d 48 535M 48 257M 0 0 22.1M 0 0:00:24 0:00:11 0:00:13 23.5M\u000d 50 535M 50 272M 0 0 18.7M 0 0:00:28 0:00:14 0:00:14 16.9M\u000d 51 535M 51 276M 0 0 18.7M 0 0:00:28 0:00:14 0:00:14 15.8M\u000d 54 535M 54 290M 0 0 18.4M 0 0:00:29 0:00:15 0:00:14 13.8M\u000d 58 535M 58 311M 0 0 18.6M 0 0:00:28 0:00:16 0:00:12 13.0M\u000d 63 535M 63 339M 0 0 19.1M 0 0:00:27 0:00:17 0:00:10 13.5M\u000d 68 535M 68 365M 0 0 19.4M 0 0:00:27 0:00:18 0:00:09 22.1M\u000d 72 535M 72 389M 0 0 19.7M 0 0:00:27 0:00:19 0:00:08 23.0M\u000d 78 535M 78 418M 0 0 20.2M 0 0:00:26 0:00:20 0:00:06 26.0M\u000d 82 535M 82 443M 0 0 20.4M 0 0:00:26 0:00:21 0:00:05 26.2M\u000d 87 535M 87 468M 0 0 20.6M 0 0:00:25 0:00:22 0:00:03 25.7M\u000d 92 535M 92 495M 0 0 20.8M 0 0:00:25 0:00:23 0:00:02 25.9M\u000d 97 535M 97 520M 0 0 21.0M 0 0:00:25 0:00:24 0:00:01 25.9M\u000d100 535M 100 535M 0 0 21.0M 0 0:00:25 0:00:25 --:--:-- 24.6M Oct 31 00:17:02.574294 setup-nvidia[1695]: Downloading NVIDIA 535.230.02 Driver Oct 31 00:17:02.578723 setup-nvidia[2027]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 31 00:17:02.578951 setup-nvidia[2027]: Dload Upload Total Spent Left Speed Oct 31 00:17:05.438299 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Oct 31 00:17:10.027423 setup-nvidia[2027]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 14 326M 14 46.0M 0 0 73.7M 0 0:00:04 --:--:-- 0:00:04 73.7M\u000d 26 326M 26 88.0M 0 0 54.9M 0 0:00:05 0:00:01 0:00:04 54.9M\u000d 37 326M 37 122M 0 0 46.9M 0 0:00:06 0:00:02 0:00:04 46.9M\u000d 51 326M 51 166M 0 0 46.4M 0 0:00:07 0:00:03 0:00:04 46.4M\u000d 62 326M 62 204M 0 0 44.6M 0 0:00:07 0:00:04 0:00:03 44.6M\u000d 75 326M 75 246M 0 0 43.9M 0 0:00:07 0:00:05 0:00:02 40.1M\u000d 89 326M 89 292M 0 0 44.1M 0 0:00:07 0:00:06 0:00:01 40.7M\u000d100 326M 100 326M 0 0 43.7M 0 0:00:07 0:00:07 --:--:-- 42.0M Oct 31 00:17:10.076516 setup-nvidia[1695]: Extract the NVIDIA Driver Installer 535.230.02 Oct 31 00:17:10.076808 setup-nvidia[1695]: /opt/nvidia/workdir/nvidia-workdir / Oct 31 00:17:10.094846 setup-nvidia[2030]: Creating directory NVIDIA-Linux-x86_64-535.230.02 Oct 31 00:17:10.376996 setup-nvidia[2030]: Verifying archive integrity... OK Oct 31 00:17:11.036358 update_engine[1709]: I20251031 00:17:11.036270 1709 update_attempter.cc:509] Updating boot flags... Oct 31 00:17:11.111318 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 37 scanned by (udev-worker) (2073) Oct 31 00:17:11.148490 setup-nvidia[2030]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.230.02 Oct 31 00:17:13.002011 setup-nvidia[2057]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Oct 31 00:17:13.067531 setup-nvidia[1695]: / Oct 31 00:17:13.067795 setup-nvidia[1695]: Spawn system-nspawn container to install the NVIDIA drivers Oct 31 00:17:13.489309 sudo[2126]: 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+nightly-20251030-2100.bin --overlay=/usr/lib/modules::/usr/lib/modules --bind=/opt/nvidia/workdir/nvidia-workdir:/nvidia --bind=/usr/lib/nvidia/bin:/app/bin/ /app/bin/install-nvidia NVIDIA-Linux-x86_64-535.230.02 Oct 31 00:17:13.491046 sudo[2126]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 31 00:17:13.498444 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Oct 31 00:17:13.509437 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Oct 31 00:17:13.519805 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Oct 31 00:17:13.522140 systemd[1]: Starting user@0.service - User Manager for UID 0... Oct 31 00:17:13.528555 (systemd)[2129]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Oct 31 00:17:13.530808 systemd-logind[1701]: New session c3 of user root. Oct 31 00:17:13.726508 systemd[2129]: Queued start job for default target default.target. Oct 31 00:17:13.737342 systemd[2129]: Created slice app.slice - User Application Slice. Oct 31 00:17:13.737374 systemd[2129]: Reached target paths.target - Paths. Oct 31 00:17:13.737421 systemd[2129]: Reached target timers.target - Timers. Oct 31 00:17:13.738682 systemd[2129]: Starting dbus.socket - D-Bus User Message Bus Socket... Oct 31 00:17:13.750135 systemd[2129]: Listening on dbus.socket - D-Bus User Message Bus Socket. Oct 31 00:17:13.750296 systemd[2129]: Reached target sockets.target - Sockets. Oct 31 00:17:13.750353 systemd[2129]: Reached target basic.target - Basic System. Oct 31 00:17:13.750402 systemd[2129]: Reached target default.target - Main User Target. Oct 31 00:17:13.750434 systemd[2129]: Startup finished in 213ms. Oct 31 00:17:13.750504 systemd[1]: Started user@0.service - User Manager for UID 0. Oct 31 00:17:13.751955 systemd[1]: Started session-c2.scope - Session c2 of User root. Oct 31 00:17:13.787233 kernel: loop6: detected capacity change from 0 to 12644352 Oct 31 00:17:13.833229 kernel: loop6: p9 Oct 31 00:17:14.101134 setup-nvidia[2138]: ░ Spawning container flatcardevelopercontainer-4230.2.4nightly-20251030-2100.bin on /opt/nvidia/workdir/flatcar_developer_container-4230.2.4+nightly-20251030-2100.bin. Oct 31 00:17:14.148227 kernel: EXT4-fs (loop6p9): mounted filesystem ef0d47f4-cb5a-40f9-985f-abe2ba1c953f ro without journal. Quota mode: none. Oct 31 00:17:18.468386 waagent[1847]: 2025-10-31T00:17:18.468316Z INFO Daemon Daemon Provisioning complete Oct 31 00:17:18.476546 waagent[1847]: 2025-10-31T00:17:18.476496Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Oct 31 00:17:18.479658 waagent[1847]: 2025-10-31T00:17:18.476751Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Oct 31 00:17:18.479658 waagent[1847]: 2025-10-31T00:17:18.478199Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.9.1.1 is the most current agent Oct 31 00:17:18.646450 waagent[2153]: 2025-10-31T00:17:18.646340Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.9.1.1) Oct 31 00:17:18.646835 waagent[2153]: 2025-10-31T00:17:18.646505Z INFO ExtHandler ExtHandler OS: flatcar 4230.2.4 Oct 31 00:17:18.646835 waagent[2153]: 2025-10-31T00:17:18.646587Z INFO ExtHandler ExtHandler Python: 3.11.11 Oct 31 00:17:18.743301 waagent[2153]: 2025-10-31T00:17:18.743136Z 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 31 00:17:18.743478 waagent[2153]: 2025-10-31T00:17:18.743429Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Oct 31 00:17:18.743572 waagent[2153]: 2025-10-31T00:17:18.743530Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Oct 31 00:17:18.751409 waagent[2153]: 2025-10-31T00:17:18.751345Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Oct 31 00:17:18.759810 waagent[2153]: 2025-10-31T00:17:18.759754Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.177 Oct 31 00:17:18.760284 waagent[2153]: 2025-10-31T00:17:18.760234Z INFO ExtHandler Oct 31 00:17:18.760376 waagent[2153]: 2025-10-31T00:17:18.760336Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 07ebd624-b225-4729-b0a8-ad57f7c98c25 eTag: 12593628500138422331 source: Fabric] Oct 31 00:17:18.760713 waagent[2153]: 2025-10-31T00:17:18.760667Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Oct 31 00:17:18.761398 waagent[2153]: 2025-10-31T00:17:18.761344Z INFO ExtHandler Oct 31 00:17:18.761479 waagent[2153]: 2025-10-31T00:17:18.761440Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Oct 31 00:17:18.764968 waagent[2153]: 2025-10-31T00:17:18.764926Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Oct 31 00:17:18.830099 waagent[2153]: 2025-10-31T00:17:18.830019Z INFO ExtHandler Downloaded certificate {'thumbprint': 'ACED2D6161E8266223766B8D4AB2B8531E6C7206', 'hasPrivateKey': True} Oct 31 00:17:18.830638 waagent[2153]: 2025-10-31T00:17:18.830588Z INFO ExtHandler Fetch goal state completed Oct 31 00:17:18.841748 waagent[2153]: 2025-10-31T00:17:18.841686Z INFO ExtHandler ExtHandler WALinuxAgent-2.9.1.1 running as process 2153 Oct 31 00:17:18.841888 waagent[2153]: 2025-10-31T00:17:18.841843Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Oct 31 00:17:18.844068 waagent[2153]: 2025-10-31T00:17:18.844008Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '4230.2.4', '', 'Flatcar Container Linux by Kinvolk'] Oct 31 00:17:18.844527 waagent[2153]: 2025-10-31T00:17:18.844476Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Oct 31 00:17:18.892064 waagent[2153]: 2025-10-31T00:17:18.892005Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Oct 31 00:17:18.892347 waagent[2153]: 2025-10-31T00:17:18.892294Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Oct 31 00:17:18.901106 waagent[2153]: 2025-10-31T00:17:18.901059Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Oct 31 00:17:18.908504 systemd[1]: Reload requested from client PID 2167 ('systemctl') (unit waagent.service)... Oct 31 00:17:18.908519 systemd[1]: Reloading... Oct 31 00:17:18.983242 zram_generator::config[2210]: No configuration found. Oct 31 00:17:19.134013 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 31 00:17:19.309043 systemd[1]: Reloading finished in 400 ms. Oct 31 00:17:19.327410 waagent[2153]: 2025-10-31T00:17:19.327327Z INFO ExtHandler ExtHandler Executing systemctl daemon-reload for setting up waagent-network-setup.service Oct 31 00:17:19.335512 systemd[1]: Reload requested from client PID 2268 ('systemctl') (unit waagent.service)... Oct 31 00:17:19.335528 systemd[1]: Reloading... Oct 31 00:17:19.399238 zram_generator::config[2312]: No configuration found. Oct 31 00:17:19.551767 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 31 00:17:19.712805 systemd[1]: Reloading finished in 376 ms. Oct 31 00:17:19.729234 waagent[2153]: 2025-10-31T00:17:19.729142Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Oct 31 00:17:19.729524 waagent[2153]: 2025-10-31T00:17:19.729321Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Oct 31 00:17:20.155449 waagent[2153]: 2025-10-31T00:17:20.155337Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Oct 31 00:17:20.155992 waagent[2153]: 2025-10-31T00:17:20.155932Z 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 31 00:17:20.156803 waagent[2153]: 2025-10-31T00:17:20.156747Z INFO ExtHandler ExtHandler Starting env monitor service. Oct 31 00:17:20.157163 waagent[2153]: 2025-10-31T00:17:20.157107Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Oct 31 00:17:20.157569 waagent[2153]: 2025-10-31T00:17:20.157509Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Oct 31 00:17:20.157719 waagent[2153]: 2025-10-31T00:17:20.157670Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Oct 31 00:17:20.157952 waagent[2153]: 2025-10-31T00:17:20.157898Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Oct 31 00:17:20.158556 waagent[2153]: 2025-10-31T00:17:20.158489Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Oct 31 00:17:20.158653 waagent[2153]: 2025-10-31T00:17:20.158587Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Oct 31 00:17:20.158764 waagent[2153]: 2025-10-31T00:17:20.158709Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Oct 31 00:17:20.159253 waagent[2153]: 2025-10-31T00:17:20.159116Z INFO EnvHandler ExtHandler Configure routes Oct 31 00:17:20.159308 waagent[2153]: 2025-10-31T00:17:20.159231Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Oct 31 00:17:20.159847 waagent[2153]: 2025-10-31T00:17:20.159783Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Oct 31 00:17:20.159847 waagent[2153]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Oct 31 00:17:20.159847 waagent[2153]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Oct 31 00:17:20.159847 waagent[2153]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Oct 31 00:17:20.159847 waagent[2153]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Oct 31 00:17:20.159847 waagent[2153]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Oct 31 00:17:20.159847 waagent[2153]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Oct 31 00:17:20.160352 waagent[2153]: 2025-10-31T00:17:20.160291Z INFO EnvHandler ExtHandler Gateway:None Oct 31 00:17:20.160465 waagent[2153]: 2025-10-31T00:17:20.160399Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Oct 31 00:17:20.160628 waagent[2153]: 2025-10-31T00:17:20.160575Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Oct 31 00:17:20.161394 waagent[2153]: 2025-10-31T00:17:20.161349Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Oct 31 00:17:20.161927 waagent[2153]: 2025-10-31T00:17:20.161871Z INFO EnvHandler ExtHandler Routes:None Oct 31 00:17:20.166237 waagent[2153]: 2025-10-31T00:17:20.166174Z INFO ExtHandler ExtHandler Oct 31 00:17:20.166572 waagent[2153]: 2025-10-31T00:17:20.166519Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: e1128070-20d1-4b1f-b616-6f23508bb7a6 correlation db5a1c17-8632-4e41-a9ad-e13be838c086 created: 2025-10-31T00:15:17.476265Z] Oct 31 00:17:20.167510 waagent[2153]: 2025-10-31T00:17:20.167455Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Oct 31 00:17:20.169328 waagent[2153]: 2025-10-31T00:17:20.169271Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Oct 31 00:17:20.313365 waagent[2153]: 2025-10-31T00:17:20.313303Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.9.1.1 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: E9125556-38F9-4A10-9D31-F1AD92AFDE11;DroppedPackets: 0;UpdateGSErrors: 0;AutoUpdate: 0] Oct 31 00:17:20.445702 waagent[2153]: 2025-10-31T00:17:20.445578Z INFO MonitorHandler ExtHandler Network interfaces: Oct 31 00:17:20.445702 waagent[2153]: Executing ['ip', '-a', '-o', 'link']: Oct 31 00:17:20.445702 waagent[2153]: 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 31 00:17:20.445702 waagent[2153]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:0d:3a:ba:62:a5 brd ff:ff:ff:ff:ff:ff Oct 31 00:17:20.445702 waagent[2153]: Executing ['ip', '-4', '-a', '-o', 'address']: Oct 31 00:17:20.445702 waagent[2153]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Oct 31 00:17:20.445702 waagent[2153]: 2: eth0 inet 10.200.8.27/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Oct 31 00:17:20.445702 waagent[2153]: Executing ['ip', '-6', '-a', '-o', 'address']: Oct 31 00:17:20.445702 waagent[2153]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Oct 31 00:17:20.445702 waagent[2153]: 2: eth0 inet6 fe80::20d:3aff:feba:62a5/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Oct 31 00:17:20.599913 waagent[2153]: 2025-10-31T00:17:20.599832Z INFO EnvHandler ExtHandler Successfully added Azure fabric firewall rules. Current Firewall rules: Oct 31 00:17:20.599913 waagent[2153]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Oct 31 00:17:20.599913 waagent[2153]: pkts bytes target prot opt in out source destination Oct 31 00:17:20.599913 waagent[2153]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Oct 31 00:17:20.599913 waagent[2153]: pkts bytes target prot opt in out source destination Oct 31 00:17:20.599913 waagent[2153]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Oct 31 00:17:20.599913 waagent[2153]: pkts bytes target prot opt in out source destination Oct 31 00:17:20.599913 waagent[2153]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Oct 31 00:17:20.599913 waagent[2153]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Oct 31 00:17:20.599913 waagent[2153]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Oct 31 00:17:20.603673 waagent[2153]: 2025-10-31T00:17:20.603619Z INFO EnvHandler ExtHandler Current Firewall rules: Oct 31 00:17:20.603673 waagent[2153]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Oct 31 00:17:20.603673 waagent[2153]: pkts bytes target prot opt in out source destination Oct 31 00:17:20.603673 waagent[2153]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Oct 31 00:17:20.603673 waagent[2153]: pkts bytes target prot opt in out source destination Oct 31 00:17:20.603673 waagent[2153]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Oct 31 00:17:20.603673 waagent[2153]: pkts bytes target prot opt in out source destination Oct 31 00:17:20.603673 waagent[2153]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Oct 31 00:17:20.603673 waagent[2153]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Oct 31 00:17:20.603673 waagent[2153]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Oct 31 00:17:20.603939 waagent[2153]: 2025-10-31T00:17:20.603899Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Oct 31 00:17:20.604103 waagent[2153]: 2025-10-31T00:17:20.604065Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Oct 31 00:17:33.830456 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Oct 31 00:17:33.838466 systemd[1]: Started sshd@0-10.200.8.27:22-10.200.16.10:57720.service - OpenSSH per-connection server daemon (10.200.16.10:57720). Oct 31 00:17:38.055730 sshd[2447]: Accepted publickey for core from 10.200.16.10 port 57720 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:17:38.057030 sshd-session[2447]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:17:38.063000 systemd-logind[1701]: New session 3 of user core. Oct 31 00:17:38.079348 systemd[1]: Started session-3.scope - Session 3 of User core. Oct 31 00:17:38.634011 systemd[1]: Started sshd@1-10.200.8.27:22-10.200.16.10:57726.service - OpenSSH per-connection server daemon (10.200.16.10:57726). Oct 31 00:17:39.267333 sshd[2452]: Accepted publickey for core from 10.200.16.10 port 57726 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:17:39.268800 sshd-session[2452]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:17:39.273103 systemd-logind[1701]: New session 4 of user core. Oct 31 00:17:39.285329 systemd[1]: Started session-4.scope - Session 4 of User core. Oct 31 00:17:39.711336 sshd[2454]: Connection closed by 10.200.16.10 port 57726 Oct 31 00:17:39.711697 sshd-session[2452]: pam_unix(sshd:session): session closed for user core Oct 31 00:17:39.714883 systemd[1]: sshd@1-10.200.8.27:22-10.200.16.10:57726.service: Deactivated successfully. Oct 31 00:17:39.716561 systemd[1]: session-4.scope: Deactivated successfully. Oct 31 00:17:39.717196 systemd-logind[1701]: Session 4 logged out. Waiting for processes to exit. Oct 31 00:17:39.717962 systemd-logind[1701]: Removed session 4. Oct 31 00:17:39.823844 systemd[1]: Started sshd@2-10.200.8.27:22-10.200.16.10:57738.service - OpenSSH per-connection server daemon (10.200.16.10:57738). Oct 31 00:17:40.457602 sshd[2460]: Accepted publickey for core from 10.200.16.10 port 57738 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:17:40.458954 sshd-session[2460]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:17:40.463132 systemd-logind[1701]: New session 5 of user core. Oct 31 00:17:40.473332 systemd[1]: Started session-5.scope - Session 5 of User core. Oct 31 00:17:40.905527 sshd[2462]: Connection closed by 10.200.16.10 port 57738 Oct 31 00:17:40.905955 sshd-session[2460]: pam_unix(sshd:session): session closed for user core Oct 31 00:17:40.909151 systemd[1]: sshd@2-10.200.8.27:22-10.200.16.10:57738.service: Deactivated successfully. Oct 31 00:17:40.910801 systemd[1]: session-5.scope: Deactivated successfully. Oct 31 00:17:40.911546 systemd-logind[1701]: Session 5 logged out. Waiting for processes to exit. Oct 31 00:17:40.912330 systemd-logind[1701]: Removed session 5. Oct 31 00:17:51.025872 systemd[1]: Started sshd@3-10.200.8.27:22-10.200.16.10:49040.service - OpenSSH per-connection server daemon (10.200.16.10:49040). Oct 31 00:17:51.661886 sshd[2468]: Accepted publickey for core from 10.200.16.10 port 49040 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:17:51.663270 sshd-session[2468]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:17:51.667588 systemd-logind[1701]: New session 6 of user core. Oct 31 00:17:51.677357 systemd[1]: Started session-6.scope - Session 6 of User core. Oct 31 00:17:52.119508 sshd[2470]: Connection closed by 10.200.16.10 port 49040 Oct 31 00:17:52.119927 sshd-session[2468]: pam_unix(sshd:session): session closed for user core Oct 31 00:17:52.123362 systemd[1]: sshd@3-10.200.8.27:22-10.200.16.10:49040.service: Deactivated successfully. Oct 31 00:17:52.125337 systemd[1]: session-6.scope: Deactivated successfully. Oct 31 00:17:52.126035 systemd-logind[1701]: Session 6 logged out. Waiting for processes to exit. Oct 31 00:17:52.126891 systemd-logind[1701]: Removed session 6. Oct 31 00:17:52.235387 systemd[1]: Started sshd@4-10.200.8.27:22-10.200.16.10:49054.service - OpenSSH per-connection server daemon (10.200.16.10:49054). Oct 31 00:17:52.871821 sshd[2476]: Accepted publickey for core from 10.200.16.10 port 49054 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:17:52.873162 sshd-session[2476]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:17:52.877381 systemd-logind[1701]: New session 7 of user core. Oct 31 00:17:52.891405 systemd[1]: Started session-7.scope - Session 7 of User core. Oct 31 00:17:53.317468 sshd[2478]: Connection closed by 10.200.16.10 port 49054 Oct 31 00:17:53.317865 sshd-session[2476]: pam_unix(sshd:session): session closed for user core Oct 31 00:17:53.321185 systemd[1]: sshd@4-10.200.8.27:22-10.200.16.10:49054.service: Deactivated successfully. Oct 31 00:17:53.322882 systemd[1]: session-7.scope: Deactivated successfully. Oct 31 00:17:53.323762 systemd-logind[1701]: Session 7 logged out. Waiting for processes to exit. Oct 31 00:17:53.324627 systemd-logind[1701]: Removed session 7. Oct 31 00:18:03.448823 systemd[1]: Started sshd@5-10.200.8.27:22-10.200.16.10:43754.service - OpenSSH per-connection server daemon (10.200.16.10:43754). Oct 31 00:18:05.982839 sshd[2484]: Accepted publickey for core from 10.200.16.10 port 43754 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:18:05.982964 sshd-session[2484]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:18:05.987275 systemd-logind[1701]: New session 8 of user core. Oct 31 00:18:06.001329 systemd[1]: Started session-8.scope - Session 8 of User core. Oct 31 00:18:06.361563 sshd[2486]: Connection closed by 10.200.16.10 port 43754 Oct 31 00:18:06.361954 sshd-session[2484]: pam_unix(sshd:session): session closed for user core Oct 31 00:18:06.365273 systemd[1]: sshd@5-10.200.8.27:22-10.200.16.10:43754.service: Deactivated successfully. Oct 31 00:18:06.366908 systemd[1]: session-8.scope: Deactivated successfully. Oct 31 00:18:06.367577 systemd-logind[1701]: Session 8 logged out. Waiting for processes to exit. Oct 31 00:18:06.368379 systemd-logind[1701]: Removed session 8. Oct 31 00:18:06.472689 systemd[1]: Started sshd@6-10.200.8.27:22-10.200.16.10:43768.service - OpenSSH per-connection server daemon (10.200.16.10:43768). Oct 31 00:18:07.104392 sshd[2492]: Accepted publickey for core from 10.200.16.10 port 43768 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:18:07.105711 sshd-session[2492]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:18:07.109893 systemd-logind[1701]: New session 9 of user core. Oct 31 00:18:07.120334 systemd[1]: Started session-9.scope - Session 9 of User core. Oct 31 00:18:07.551477 sshd[2494]: Connection closed by 10.200.16.10 port 43768 Oct 31 00:18:07.551795 sshd-session[2492]: pam_unix(sshd:session): session closed for user core Oct 31 00:18:07.555074 systemd[1]: sshd@6-10.200.8.27:22-10.200.16.10:43768.service: Deactivated successfully. Oct 31 00:18:07.556765 systemd[1]: session-9.scope: Deactivated successfully. Oct 31 00:18:07.557464 systemd-logind[1701]: Session 9 logged out. Waiting for processes to exit. Oct 31 00:18:07.558416 systemd-logind[1701]: Removed session 9. Oct 31 00:18:17.669135 systemd[1]: Started sshd@7-10.200.8.27:22-10.200.16.10:58784.service - OpenSSH per-connection server daemon (10.200.16.10:58784). Oct 31 00:18:18.302478 sshd[2553]: Accepted publickey for core from 10.200.16.10 port 58784 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:18:18.303773 sshd-session[2553]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:18:18.307960 systemd-logind[1701]: New session 10 of user core. Oct 31 00:18:18.318353 systemd[1]: Started session-10.scope - Session 10 of User core. Oct 31 00:18:18.749181 sshd[2789]: Connection closed by 10.200.16.10 port 58784 Oct 31 00:18:18.749555 sshd-session[2553]: pam_unix(sshd:session): session closed for user core Oct 31 00:18:18.752629 systemd[1]: sshd@7-10.200.8.27:22-10.200.16.10:58784.service: Deactivated successfully. Oct 31 00:18:18.754238 systemd[1]: session-10.scope: Deactivated successfully. Oct 31 00:18:18.754930 systemd-logind[1701]: Session 10 logged out. Waiting for processes to exit. Oct 31 00:18:18.755754 systemd-logind[1701]: Removed session 10. Oct 31 00:18:18.868053 systemd[1]: Started sshd@8-10.200.8.27:22-10.200.16.10:58792.service - OpenSSH per-connection server daemon (10.200.16.10:58792). Oct 31 00:18:19.498747 sshd[3068]: Accepted publickey for core from 10.200.16.10 port 58792 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:18:19.500244 sshd-session[3068]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:18:19.505090 systemd-logind[1701]: New session 11 of user core. Oct 31 00:18:19.516379 systemd[1]: Started session-11.scope - Session 11 of User core. Oct 31 00:18:19.953700 sshd[3328]: Connection closed by 10.200.16.10 port 58792 Oct 31 00:18:19.954116 sshd-session[3068]: pam_unix(sshd:session): session closed for user core Oct 31 00:18:19.957419 systemd[1]: sshd@8-10.200.8.27:22-10.200.16.10:58792.service: Deactivated successfully. Oct 31 00:18:19.959097 systemd[1]: session-11.scope: Deactivated successfully. Oct 31 00:18:19.959746 systemd-logind[1701]: Session 11 logged out. Waiting for processes to exit. Oct 31 00:18:19.960623 systemd-logind[1701]: Removed session 11. Oct 31 00:18:30.088541 systemd[1]: Started sshd@9-10.200.8.27:22-10.200.16.10:46516.service - OpenSSH per-connection server daemon (10.200.16.10:46516). Oct 31 00:18:30.719977 sshd[6596]: Accepted publickey for core from 10.200.16.10 port 46516 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:18:30.721832 sshd-session[6596]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:18:30.730193 systemd-logind[1701]: New session 12 of user core. Oct 31 00:18:30.737384 systemd[1]: Started session-12.scope - Session 12 of User core. Oct 31 00:18:31.175718 sshd[6806]: Connection closed by 10.200.16.10 port 46516 Oct 31 00:18:31.177408 sshd-session[6596]: pam_unix(sshd:session): session closed for user core Oct 31 00:18:31.180542 systemd-logind[1701]: Session 12 logged out. Waiting for processes to exit. Oct 31 00:18:31.182341 systemd[1]: sshd@9-10.200.8.27:22-10.200.16.10:46516.service: Deactivated successfully. Oct 31 00:18:31.184988 systemd[1]: session-12.scope: Deactivated successfully. Oct 31 00:18:31.186710 systemd-logind[1701]: Removed session 12. Oct 31 00:18:31.312299 systemd[1]: Started sshd@10-10.200.8.27:22-10.200.16.10:46532.service - OpenSSH per-connection server daemon (10.200.16.10:46532). Oct 31 00:18:31.943840 sshd[6940]: Accepted publickey for core from 10.200.16.10 port 46532 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:18:31.945701 sshd-session[6940]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:18:31.955180 systemd-logind[1701]: New session 13 of user core. Oct 31 00:18:31.965376 systemd[1]: Started session-13.scope - Session 13 of User core. Oct 31 00:18:32.432360 sshd[7274]: Connection closed by 10.200.16.10 port 46532 Oct 31 00:18:32.432739 sshd-session[6940]: pam_unix(sshd:session): session closed for user core Oct 31 00:18:32.435825 systemd-logind[1701]: Session 13 logged out. Waiting for processes to exit. Oct 31 00:18:32.436708 systemd[1]: sshd@10-10.200.8.27:22-10.200.16.10:46532.service: Deactivated successfully. Oct 31 00:18:32.438396 systemd[1]: session-13.scope: Deactivated successfully. Oct 31 00:18:32.440432 systemd-logind[1701]: Removed session 13. Oct 31 00:18:42.554177 systemd[1]: Started sshd@11-10.200.8.27:22-10.200.16.10:47714.service - OpenSSH per-connection server daemon (10.200.16.10:47714). Oct 31 00:18:43.183308 sshd[10955]: Accepted publickey for core from 10.200.16.10 port 47714 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:18:43.184684 sshd-session[10955]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:18:43.190172 systemd-logind[1701]: New session 14 of user core. Oct 31 00:18:43.194376 systemd[1]: Started session-14.scope - Session 14 of User core. Oct 31 00:18:43.634462 sshd[11187]: Connection closed by 10.200.16.10 port 47714 Oct 31 00:18:43.637399 sshd-session[10955]: pam_unix(sshd:session): session closed for user core Oct 31 00:18:43.640102 systemd[1]: sshd@11-10.200.8.27:22-10.200.16.10:47714.service: Deactivated successfully. Oct 31 00:18:43.645026 systemd[1]: session-14.scope: Deactivated successfully. Oct 31 00:18:43.646570 systemd-logind[1701]: Session 14 logged out. Waiting for processes to exit. Oct 31 00:18:43.647711 systemd-logind[1701]: Removed session 14. Oct 31 00:18:43.752176 systemd[1]: Started sshd@12-10.200.8.27:22-10.200.16.10:47728.service - OpenSSH per-connection server daemon (10.200.16.10:47728). Oct 31 00:18:44.384239 sshd[11498]: Accepted publickey for core from 10.200.16.10 port 47728 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:18:44.385617 sshd-session[11498]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:18:44.390026 systemd-logind[1701]: New session 15 of user core. Oct 31 00:18:44.407333 systemd[1]: Started session-15.scope - Session 15 of User core. Oct 31 00:18:44.837023 sshd[11518]: Connection closed by 10.200.16.10 port 47728 Oct 31 00:18:44.838406 sshd-session[11498]: pam_unix(sshd:session): session closed for user core Oct 31 00:18:44.845540 systemd-logind[1701]: Session 15 logged out. Waiting for processes to exit. Oct 31 00:18:44.847164 systemd[1]: sshd@12-10.200.8.27:22-10.200.16.10:47728.service: Deactivated successfully. Oct 31 00:18:44.851427 systemd[1]: session-15.scope: Deactivated successfully. Oct 31 00:18:44.852861 systemd-logind[1701]: Removed session 15. Oct 31 00:18:54.950341 systemd[1]: Started sshd@13-10.200.8.27:22-10.200.16.10:34928.service - OpenSSH per-connection server daemon (10.200.16.10:34928). Oct 31 00:18:55.611541 sshd[11969]: Accepted publickey for core from 10.200.16.10 port 34928 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:18:55.613422 sshd-session[11969]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:18:55.622091 systemd-logind[1701]: New session 16 of user core. Oct 31 00:18:55.631391 systemd[1]: Started session-16.scope - Session 16 of User core. Oct 31 00:18:56.058675 sshd[11995]: Connection closed by 10.200.16.10 port 34928 Oct 31 00:18:56.063414 sshd-session[11969]: pam_unix(sshd:session): session closed for user core Oct 31 00:18:56.066712 systemd[1]: sshd@13-10.200.8.27:22-10.200.16.10:34928.service: Deactivated successfully. Oct 31 00:18:56.069551 systemd[1]: session-16.scope: Deactivated successfully. Oct 31 00:18:56.078448 systemd-logind[1701]: Session 16 logged out. Waiting for processes to exit. Oct 31 00:18:56.080428 systemd-logind[1701]: Removed session 16. Oct 31 00:18:56.183497 systemd[1]: Started sshd@14-10.200.8.27:22-10.200.16.10:34944.service - OpenSSH per-connection server daemon (10.200.16.10:34944). Oct 31 00:18:56.819297 sshd[12007]: Accepted publickey for core from 10.200.16.10 port 34944 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:18:56.820735 sshd-session[12007]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:18:56.827342 systemd-logind[1701]: New session 17 of user core. Oct 31 00:18:56.836392 systemd[1]: Started session-17.scope - Session 17 of User core. Oct 31 00:18:57.269475 sshd[12017]: Connection closed by 10.200.16.10 port 34944 Oct 31 00:18:57.270317 sshd-session[12007]: pam_unix(sshd:session): session closed for user core Oct 31 00:18:57.274258 systemd-logind[1701]: Session 17 logged out. Waiting for processes to exit. Oct 31 00:18:57.276017 systemd[1]: sshd@14-10.200.8.27:22-10.200.16.10:34944.service: Deactivated successfully. Oct 31 00:18:57.277899 systemd[1]: session-17.scope: Deactivated successfully. Oct 31 00:18:57.280555 systemd-logind[1701]: Removed session 17. Oct 31 00:19:07.395625 systemd[1]: Started sshd@15-10.200.8.27:22-10.200.16.10:44300.service - OpenSSH per-connection server daemon (10.200.16.10:44300). Oct 31 00:19:08.027416 sshd[12365]: Accepted publickey for core from 10.200.16.10 port 44300 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:19:08.028822 sshd-session[12365]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:19:08.038091 systemd-logind[1701]: New session 18 of user core. Oct 31 00:19:08.044419 systemd[1]: Started session-18.scope - Session 18 of User core. Oct 31 00:19:08.477857 sshd[12377]: Connection closed by 10.200.16.10 port 44300 Oct 31 00:19:08.481393 sshd-session[12365]: pam_unix(sshd:session): session closed for user core Oct 31 00:19:08.484333 systemd[1]: sshd@15-10.200.8.27:22-10.200.16.10:44300.service: Deactivated successfully. Oct 31 00:19:08.486666 systemd[1]: session-18.scope: Deactivated successfully. Oct 31 00:19:08.488497 systemd-logind[1701]: Session 18 logged out. Waiting for processes to exit. Oct 31 00:19:08.489890 systemd-logind[1701]: Removed session 18. Oct 31 00:19:08.610533 systemd[1]: Started sshd@16-10.200.8.27:22-10.200.16.10:44316.service - OpenSSH per-connection server daemon (10.200.16.10:44316). Oct 31 00:19:09.246310 sshd[12413]: Accepted publickey for core from 10.200.16.10 port 44316 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:19:09.247710 sshd-session[12413]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:19:09.265887 systemd-logind[1701]: New session 19 of user core. Oct 31 00:19:09.277444 systemd[1]: Started session-19.scope - Session 19 of User core. Oct 31 00:19:09.695256 sshd[12433]: Connection closed by 10.200.16.10 port 44316 Oct 31 00:19:09.696775 sshd-session[12413]: pam_unix(sshd:session): session closed for user core Oct 31 00:19:09.702250 systemd-logind[1701]: Session 19 logged out. Waiting for processes to exit. Oct 31 00:19:09.703903 systemd[1]: sshd@16-10.200.8.27:22-10.200.16.10:44316.service: Deactivated successfully. Oct 31 00:19:09.705918 systemd[1]: session-19.scope: Deactivated successfully. Oct 31 00:19:09.707515 systemd-logind[1701]: Removed session 19. Oct 31 00:19:19.810968 systemd[1]: Started sshd@17-10.200.8.27:22-10.200.16.10:40182.service - OpenSSH per-connection server daemon (10.200.16.10:40182). Oct 31 00:19:20.446618 sshd[12706]: Accepted publickey for core from 10.200.16.10 port 40182 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:19:20.447996 sshd-session[12706]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:19:20.452364 systemd-logind[1701]: New session 20 of user core. Oct 31 00:19:20.463343 systemd[1]: Started session-20.scope - Session 20 of User core. Oct 31 00:19:20.893226 sshd[12708]: Connection closed by 10.200.16.10 port 40182 Oct 31 00:19:20.893790 sshd-session[12706]: pam_unix(sshd:session): session closed for user core Oct 31 00:19:20.897090 systemd[1]: sshd@17-10.200.8.27:22-10.200.16.10:40182.service: Deactivated successfully. Oct 31 00:19:20.898771 systemd[1]: session-20.scope: Deactivated successfully. Oct 31 00:19:20.899448 systemd-logind[1701]: Session 20 logged out. Waiting for processes to exit. Oct 31 00:19:20.900270 systemd-logind[1701]: Removed session 20. Oct 31 00:19:21.009866 systemd[1]: Started sshd@18-10.200.8.27:22-10.200.16.10:33476.service - OpenSSH per-connection server daemon (10.200.16.10:33476). Oct 31 00:19:21.644298 sshd[12714]: Accepted publickey for core from 10.200.16.10 port 33476 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:19:21.645636 sshd-session[12714]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:19:21.649888 systemd-logind[1701]: New session 21 of user core. Oct 31 00:19:21.660336 systemd[1]: Started session-21.scope - Session 21 of User core. Oct 31 00:19:22.097061 sshd[12716]: Connection closed by 10.200.16.10 port 33476 Oct 31 00:19:22.097587 sshd-session[12714]: pam_unix(sshd:session): session closed for user core Oct 31 00:19:22.100866 systemd[1]: sshd@18-10.200.8.27:22-10.200.16.10:33476.service: Deactivated successfully. Oct 31 00:19:22.102576 systemd[1]: session-21.scope: Deactivated successfully. Oct 31 00:19:22.103232 systemd-logind[1701]: Session 21 logged out. Waiting for processes to exit. Oct 31 00:19:22.104046 systemd-logind[1701]: Removed session 21. Oct 31 00:19:25.263275 setup-nvidia[2138]: \u000d Oct 31 00:19:25.263275 setup-nvidia[2138]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Oct 31 00:19:25.263275 setup-nvidia[2138]: frequently when this kernel module was built against the wrong or Oct 31 00:19:25.263275 setup-nvidia[2138]: improperly configured kernel sources, with a version of gcc that differs Oct 31 00:19:25.263275 setup-nvidia[2138]: from the one used to build the target kernel, or if another driver, such Oct 31 00:19:25.263275 setup-nvidia[2138]: as nouveau, is present and prevents the NVIDIA kernel module from Oct 31 00:19:25.263275 setup-nvidia[2138]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Oct 31 00:19:25.263275 setup-nvidia[2138]: installed in this system is supported by this NVIDIA Linux graphics Oct 31 00:19:25.263275 setup-nvidia[2138]: driver release. Oct 31 00:19:25.263275 setup-nvidia[2138]: \u000d Oct 31 00:19:25.263275 setup-nvidia[2138]: Please see the log entries 'Kernel module load error' and 'Kernel Oct 31 00:19:25.263275 setup-nvidia[2138]: messages' at the end of the file Oct 31 00:19:25.263275 setup-nvidia[2138]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more Oct 31 00:19:25.263275 setup-nvidia[2138]: information. Oct 31 00:19:25.263275 setup-nvidia[2138]: \u000d Oct 31 00:19:25.278086 setup-nvidia[2138]: dmesg: read kernel buffer failed: Operation not permitted Oct 31 00:19:25.299706 setup-nvidia[2138]: \u000d Oct 31 00:19:25.299706 setup-nvidia[2138]: ERROR: Installation has failed. Please see the file Oct 31 00:19:25.299706 setup-nvidia[2138]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for Oct 31 00:19:25.299706 setup-nvidia[2138]: details. You may find suggestions on fixing installation problems in Oct 31 00:19:25.299706 setup-nvidia[2138]: the README available on the Linux driver download page at Oct 31 00:19:25.299706 setup-nvidia[2138]: www.nvidia.com. Oct 31 00:19:25.299706 setup-nvidia[2138]: \u000d Oct 31 00:19:25.300155 setup-nvidia[2138]: Last 50 lines of nvidia-installer.log: Oct 31 00:19:25.300927 setup-nvidia[2138]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038989rm+0x32: 'naked' return found in RETHUNK build Oct 31 00:19:25.300927 setup-nvidia[2138]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039000rm+0x53: 'naked' return found in RETHUNK build Oct 31 00:19:25.300927 setup-nvidia[2138]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038997rm+0x90: 'naked' return found in RETHUNK build Oct 31 00:19:25.300927 setup-nvidia[2138]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038990rm+0x2b: 'naked' return found in RETHUNK build Oct 31 00:19:25.300927 setup-nvidia[2138]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039046rm+0x93: 'naked' return found in RETHUNK build Oct 31 00:19:25.300927 setup-nvidia[2138]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039042rm+0x36: 'naked' return found in RETHUNK build Oct 31 00:19:25.300927 setup-nvidia[2138]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039041rm+0x9d: 'naked' return found in RETHUNK build Oct 31 00:19:25.300927 setup-nvidia[2138]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039043rm+0x77: 'naked' return found in RETHUNK build Oct 31 00:19:25.300927 setup-nvidia[2138]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038987rm+0x7f: 'naked' return found in RETHUNK build Oct 31 00:19:25.300927 setup-nvidia[2138]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030558rm+0x3e: 'naked' return found in RETHUNK build Oct 31 00:19:25.300927 setup-nvidia[2138]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030557rm+0x27: 'naked' return found in RETHUNK build Oct 31 00:19:25.300927 setup-nvidia[2138]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030561rm+0x37: 'naked' return found in RETHUNK build Oct 31 00:19:25.300927 setup-nvidia[2138]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030560rm+0x24: 'naked' return found in RETHUNK build Oct 31 00:19:25.300927 setup-nvidia[2138]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030559rm+0xca: 'naked' return found in RETHUNK build Oct 31 00:19:25.300927 setup-nvidia[2138]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030562rm+0x193: 'naked' return found in RETHUNK build Oct 31 00:19:25.300927 setup-nvidia[2138]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030564rm+0x17f: 'naked' return found in RETHUNK build Oct 31 00:19:25.300927 setup-nvidia[2138]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv011860rm+0x5d: 'naked' return found in RETHUNK build Oct 31 00:19:25.300927 setup-nvidia[2138]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039354rm+0x12f: 'naked' return found in RETHUNK build Oct 31 00:19:25.300927 setup-nvidia[2138]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/Module.symvers Oct 31 00:19:25.300927 setup-nvidia[2138]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.mod.o Oct 31 00:19:25.300927 setup-nvidia[2138]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.mod.o Oct 31 00:19:25.300927 setup-nvidia[2138]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.mod.o Oct 31 00:19:25.300927 setup-nvidia[2138]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.mod.o Oct 31 00:19:25.300927 setup-nvidia[2138]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.mod.o Oct 31 00:19:25.300927 setup-nvidia[2138]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Oct 31 00:19:25.300927 setup-nvidia[2138]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Oct 31 00:19:25.300927 setup-nvidia[2138]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Oct 31 00:19:25.300927 setup-nvidia[2138]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Oct 31 00:19:25.300927 setup-nvidia[2138]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Oct 31 00:19:25.300927 setup-nvidia[2138]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Oct 31 00:19:25.300927 setup-nvidia[2138]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko due to unavailability of vmlinux Oct 31 00:19:25.300927 setup-nvidia[2138]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Oct 31 00:19:25.300927 setup-nvidia[2138]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Oct 31 00:19:25.300927 setup-nvidia[2138]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko due to unavailability of vmlinux Oct 31 00:19:25.300927 setup-nvidia[2138]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko due to unavailability of vmlinux Oct 31 00:19:25.300927 setup-nvidia[2138]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Oct 31 00:19:25.300927 setup-nvidia[2138]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko due to unavailability of vmlinux Oct 31 00:19:25.300927 setup-nvidia[2138]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Oct 31 00:19:25.300927 setup-nvidia[2138]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko due to unavailability of vmlinux Oct 31 00:19:25.300927 setup-nvidia[2138]: make[2]: Leaving directory '/usr/lib/modules/6.6.113-flatcar/build' Oct 31 00:19:25.300927 setup-nvidia[2138]: make[1]: Leaving directory '/usr/lib/modules/6.6.113-flatcar/source' Oct 31 00:19:25.300927 setup-nvidia[2138]: -> done. Oct 31 00:19:25.300927 setup-nvidia[2138]: -> Kernel module compilation complete. Oct 31 00:19:25.301991 setup-nvidia[2138]: -> Unable to determine if Secure Boot is enabled: No such file or directory Oct 31 00:19:25.301991 setup-nvidia[2138]: 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 31 00:19:25.301991 setup-nvidia[2138]: \u000d Oct 31 00:19:25.301991 setup-nvidia[2138]: 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 31 00:19:25.301991 setup-nvidia[2138]: -> Kernel module load error: Operation not permitted Oct 31 00:19:25.301991 setup-nvidia[2138]: -> Kernel messages: Oct 31 00:19:25.301991 setup-nvidia[2138]: 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 31 00:19:32.209794 systemd[1]: Started sshd@19-10.200.8.27:22-10.200.16.10:33554.service - OpenSSH per-connection server daemon (10.200.16.10:33554). Oct 31 00:19:32.842873 sshd[12931]: Accepted publickey for core from 10.200.16.10 port 33554 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:19:32.844241 sshd-session[12931]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:19:32.848554 systemd-logind[1701]: New session 22 of user core. Oct 31 00:19:32.868336 systemd[1]: Started session-22.scope - Session 22 of User core. Oct 31 00:19:33.289518 sshd[12933]: Connection closed by 10.200.16.10 port 33554 Oct 31 00:19:33.289896 sshd-session[12931]: pam_unix(sshd:session): session closed for user core Oct 31 00:19:33.293102 systemd[1]: sshd@19-10.200.8.27:22-10.200.16.10:33554.service: Deactivated successfully. Oct 31 00:19:33.294796 systemd[1]: session-22.scope: Deactivated successfully. Oct 31 00:19:33.295653 systemd-logind[1701]: Session 22 logged out. Waiting for processes to exit. Oct 31 00:19:33.296653 systemd-logind[1701]: Removed session 22. Oct 31 00:19:33.400689 systemd[1]: Started sshd@20-10.200.8.27:22-10.200.16.10:33556.service - OpenSSH per-connection server daemon (10.200.16.10:33556). Oct 31 00:19:34.032618 sshd[12939]: Accepted publickey for core from 10.200.16.10 port 33556 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:19:34.034006 sshd-session[12939]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:19:34.038313 systemd-logind[1701]: New session 23 of user core. Oct 31 00:19:34.047325 systemd[1]: Started session-23.scope - Session 23 of User core. Oct 31 00:19:34.481480 sshd[12941]: Connection closed by 10.200.16.10 port 33556 Oct 31 00:19:34.482037 sshd-session[12939]: pam_unix(sshd:session): session closed for user core Oct 31 00:19:34.485200 systemd[1]: sshd@20-10.200.8.27:22-10.200.16.10:33556.service: Deactivated successfully. Oct 31 00:19:34.486883 systemd[1]: session-23.scope: Deactivated successfully. Oct 31 00:19:34.487580 systemd-logind[1701]: Session 23 logged out. Waiting for processes to exit. Oct 31 00:19:34.488361 systemd-logind[1701]: Removed session 23. Oct 31 00:19:39.972240 kernel: EXT4-fs (loop6p9): unmounting filesystem ef0d47f4-cb5a-40f9-985f-abe2ba1c953f. Oct 31 00:19:39.972833 setup-nvidia[2138]: Container flatcardevelopercontainer-4230.2.4nightly-20251030-2100.bin exited successfully. Oct 31 00:19:39.975323 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4230.2.4nightly\x2d20251030\x2d2100.bin.mount: Deactivated successfully. Oct 31 00:19:40.042023 sudo[2126]: pam_unix(sudo:session): session closed for user root Oct 31 00:19:40.043823 systemd[1]: session-c2.scope: Deactivated successfully. Oct 31 00:19:40.044091 systemd[1]: session-c2.scope: Consumed 5min 7.683s CPU time, 882.9M memory peak. Oct 31 00:19:40.453377 setup-nvidia[1695]: /opt/nvidia / Oct 31 00:19:40.454449 setup-nvidia[1695]: / Oct 31 00:19:40.471121 setup-nvidia[12964]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 31 00:19:40.499231 kernel: IPMI message handler: version 39.2 Oct 31 00:19:40.503225 kernel: ipmi device interface Oct 31 00:19:40.504186 setup-nvidia[1695]: /opt/nvidia/current/usr/lib/modules/6.6.113-flatcar/video / Oct 31 00:19:40.558528 kernel: nvidia: loading out-of-tree module taints kernel. Oct 31 00:19:40.558650 kernel: nvidia: module license 'NVIDIA' taints kernel. Oct 31 00:19:40.558673 kernel: Disabling lock debugging due to kernel taint Oct 31 00:19:40.566264 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Oct 31 00:19:40.566319 kernel: nvidia: module license taints kernel. Oct 31 00:19:40.960237 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 245 Oct 31 00:19:41.360261 kernel: Oct 31 00:19:41.360394 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Oct 31 00:19:41.567235 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.230.02 Fri Dec 20 21:42:05 UTC 2024 Oct 31 00:19:41.589227 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.230.02 Fri Dec 20 21:52:34 UTC 2024 Oct 31 00:19:41.609243 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Oct 31 00:19:41.651233 kernel: nvidia-uvm: Loaded the UVM driver, major device number 243. Oct 31 00:19:41.651486 setup-nvidia[1695]: / Oct 31 00:19:43.362620 setup-nvidia[12994]: Fri Oct 31 00:19:43 2025 Oct 31 00:19:43.362620 setup-nvidia[12994]: +---------------------------------------------------------------------------------------+ Oct 31 00:19:43.362620 setup-nvidia[12994]: | NVIDIA-SMI 535.230.02 Driver Version: 535.230.02 CUDA Version: 12.2 | Oct 31 00:19:43.362620 setup-nvidia[12994]: |-----------------------------------------+----------------------+----------------------+ Oct 31 00:19:43.362620 setup-nvidia[12994]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Oct 31 00:19:43.362620 setup-nvidia[12994]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Oct 31 00:19:43.362620 setup-nvidia[12994]: | | | MIG M. | Oct 31 00:19:43.362620 setup-nvidia[12994]: |=========================================+======================+======================| Oct 31 00:19:43.366358 setup-nvidia[12994]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Oct 31 00:19:43.366358 setup-nvidia[12994]: | N/A 25C P0 35W / 250W | 0MiB / 16384MiB | 0% Default | Oct 31 00:19:43.366358 setup-nvidia[12994]: | | | N/A | Oct 31 00:19:43.366358 setup-nvidia[12994]: +-----------------------------------------+----------------------+----------------------+ Oct 31 00:19:43.366358 setup-nvidia[12994]: Oct 31 00:19:43.366358 setup-nvidia[12994]: +---------------------------------------------------------------------------------------+ Oct 31 00:19:43.366358 setup-nvidia[12994]: | Processes: | Oct 31 00:19:43.366358 setup-nvidia[12994]: | GPU GI CI PID Type Process name GPU Memory | Oct 31 00:19:43.366358 setup-nvidia[12994]: | ID ID Usage | Oct 31 00:19:43.366358 setup-nvidia[12994]: |=======================================================================================| Oct 31 00:19:43.366612 setup-nvidia[12994]: | No running processes found | Oct 31 00:19:43.366612 setup-nvidia[12994]: +---------------------------------------------------------------------------------------+ Oct 31 00:19:43.738780 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Oct 31 00:19:43.739469 systemd[1]: Reached target multi-user.target - Multi-User System. Oct 31 00:19:43.740406 systemd[1]: Startup finished in 1.272s (firmware) + 13.776s (loader) + 1.836s (kernel) + 15.768s (initrd) + 3min 32.122s (userspace) = 4min 4.776s. Oct 31 00:19:44.596853 systemd[1]: Started sshd@21-10.200.8.27:22-10.200.16.10:57456.service - OpenSSH per-connection server daemon (10.200.16.10:57456). Oct 31 00:19:45.227074 sshd[13001]: Accepted publickey for core from 10.200.16.10 port 57456 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:19:45.228466 sshd-session[13001]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:19:45.232554 systemd-logind[1701]: New session 24 of user core. Oct 31 00:19:45.242334 systemd[1]: Started session-24.scope - Session 24 of User core. Oct 31 00:19:45.670513 sshd[13003]: Connection closed by 10.200.16.10 port 57456 Oct 31 00:19:45.670919 sshd-session[13001]: pam_unix(sshd:session): session closed for user core Oct 31 00:19:45.674749 systemd[1]: sshd@21-10.200.8.27:22-10.200.16.10:57456.service: Deactivated successfully. Oct 31 00:19:45.676743 systemd[1]: session-24.scope: Deactivated successfully. Oct 31 00:19:45.677563 systemd-logind[1701]: Session 24 logged out. Waiting for processes to exit. Oct 31 00:19:45.678411 systemd-logind[1701]: Removed session 24. Oct 31 00:19:45.782795 systemd[1]: Started sshd@22-10.200.8.27:22-10.200.16.10:57464.service - OpenSSH per-connection server daemon (10.200.16.10:57464). Oct 31 00:19:46.414655 sshd[13009]: Accepted publickey for core from 10.200.16.10 port 57464 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:19:46.415955 sshd-session[13009]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:19:46.420231 systemd-logind[1701]: New session 25 of user core. Oct 31 00:19:46.430335 systemd[1]: Started session-25.scope - Session 25 of User core. Oct 31 00:19:46.859681 sshd[13011]: Connection closed by 10.200.16.10 port 57464 Oct 31 00:19:46.860072 sshd-session[13009]: pam_unix(sshd:session): session closed for user core Oct 31 00:19:46.863363 systemd[1]: sshd@22-10.200.8.27:22-10.200.16.10:57464.service: Deactivated successfully. Oct 31 00:19:46.865338 systemd[1]: session-25.scope: Deactivated successfully. Oct 31 00:19:46.865988 systemd-logind[1701]: Session 25 logged out. Waiting for processes to exit. Oct 31 00:19:46.866814 systemd-logind[1701]: Removed session 25. Oct 31 00:19:46.971585 systemd[1]: Started sshd@23-10.200.8.27:22-10.200.16.10:57474.service - OpenSSH per-connection server daemon (10.200.16.10:57474). Oct 31 00:19:47.604755 sshd[13017]: Accepted publickey for core from 10.200.16.10 port 57474 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:19:47.606148 sshd-session[13017]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:19:47.610328 systemd-logind[1701]: New session 26 of user core. Oct 31 00:19:47.637334 systemd[1]: Started session-26.scope - Session 26 of User core. Oct 31 00:19:48.050301 sshd[13019]: Connection closed by 10.200.16.10 port 57474 Oct 31 00:19:48.050610 sshd-session[13017]: pam_unix(sshd:session): session closed for user core Oct 31 00:19:48.053820 systemd[1]: sshd@23-10.200.8.27:22-10.200.16.10:57474.service: Deactivated successfully. Oct 31 00:19:48.055534 systemd[1]: session-26.scope: Deactivated successfully. Oct 31 00:19:48.056176 systemd-logind[1701]: Session 26 logged out. Waiting for processes to exit. Oct 31 00:19:48.056960 systemd-logind[1701]: Removed session 26. Oct 31 00:19:48.162793 systemd[1]: Started sshd@24-10.200.8.27:22-10.200.16.10:57490.service - OpenSSH per-connection server daemon (10.200.16.10:57490). Oct 31 00:19:48.796228 sshd[13025]: Accepted publickey for core from 10.200.16.10 port 57490 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:19:48.797678 sshd-session[13025]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:19:48.801921 systemd-logind[1701]: New session 27 of user core. Oct 31 00:19:48.817359 systemd[1]: Started session-27.scope - Session 27 of User core. Oct 31 00:19:49.248487 sshd[13027]: Connection closed by 10.200.16.10 port 57490 Oct 31 00:19:49.248918 sshd-session[13025]: pam_unix(sshd:session): session closed for user core Oct 31 00:19:49.252077 systemd[1]: sshd@24-10.200.8.27:22-10.200.16.10:57490.service: Deactivated successfully. Oct 31 00:19:49.254109 systemd[1]: session-27.scope: Deactivated successfully. Oct 31 00:19:49.254953 systemd-logind[1701]: Session 27 logged out. Waiting for processes to exit. Oct 31 00:19:49.256122 systemd-logind[1701]: Removed session 27. Oct 31 00:19:49.359816 systemd[1]: Started sshd@25-10.200.8.27:22-10.200.16.10:57496.service - OpenSSH per-connection server daemon (10.200.16.10:57496). Oct 31 00:19:49.992449 sshd[13033]: Accepted publickey for core from 10.200.16.10 port 57496 ssh2: RSA SHA256:3IBNfHRcRPALA/IliHTXMCc00BXrTg4WLQbmAWEe4fE Oct 31 00:19:49.993755 sshd-session[13033]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 00:19:49.998089 systemd-logind[1701]: New session 28 of user core. Oct 31 00:19:50.007336 systemd[1]: Started session-28.scope - Session 28 of User core. Oct 31 00:19:50.060733 systemd[1]: Stopping user@0.service - User Manager for UID 0... Oct 31 00:19:50.061495 systemd[2129]: Activating special unit exit.target... Oct 31 00:19:50.061563 systemd[2129]: Stopped target default.target - Main User Target. Oct 31 00:19:50.061601 systemd[2129]: Stopped target basic.target - Basic System. Oct 31 00:19:50.061630 systemd[2129]: Stopped target paths.target - Paths. Oct 31 00:19:50.061656 systemd[2129]: Stopped target sockets.target - Sockets. Oct 31 00:19:50.061682 systemd[2129]: Stopped target timers.target - Timers. Oct 31 00:19:50.062022 systemd[2129]: Closed dbus.socket - D-Bus User Message Bus Socket. Oct 31 00:19:50.062241 systemd[2129]: Removed slice app.slice - User Application Slice. Oct 31 00:19:50.062273 systemd[2129]: Reached target shutdown.target - Shutdown. Oct 31 00:19:50.062429 systemd[2129]: Finished systemd-exit.service - Exit the Session. Oct 31 00:19:50.062468 systemd[2129]: Reached target exit.target - Exit the Session. Oct 31 00:19:50.075227 (sd-pam)[2130]: pam_unix(systemd-user:session): session closed for user root Oct 31 00:19:50.076702 systemd[1]: user@0.service: Deactivated successfully. Oct 31 00:19:50.077056 systemd[1]: Stopped user@0.service - User Manager for UID 0. Oct 31 00:19:50.089413 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Oct 31 00:19:50.097937 systemd[1]: run-user-0.mount: Deactivated successfully. Oct 31 00:19:50.098882 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Oct 31 00:19:50.099099 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Oct 31 00:19:50.100910 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Oct 31 00:19:50.101015 systemd[1]: user-0.slice: Consumed 5min 7.930s CPU time, 892.3M memory peak. Oct 31 00:19:50.101495 systemd-logind[1701]: Removed session c3. Oct 31 00:19:51.932023 sshd[13035]: Connection closed by 10.200.16.10 port 57496 Oct 31 00:19:51.932436 sshd-session[13033]: pam_unix(sshd:session): session closed for user core Oct 31 00:19:51.937302 systemd[1]: sshd@25-10.200.8.27:22-10.200.16.10:57496.service: Deactivated successfully. Oct 31 00:19:51.939020 systemd[1]: session-28.scope: Deactivated successfully. Oct 31 00:19:51.939247 systemd[1]: session-28.scope: Consumed 1.433s CPU time, 14M memory peak. Oct 31 00:19:51.939725 systemd-logind[1701]: Session 28 logged out. Waiting for processes to exit. Oct 31 00:19:51.940542 systemd-logind[1701]: Removed session 28.