Nov 24 00:19:44.464629 kernel: Linux version 6.12.58-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.0 p8) 14.3.0, GNU ld (Gentoo 2.44 p4) 2.44.0) #1 SMP PREEMPT_DYNAMIC Sun Nov 23 20:54:38 -00 2025 Nov 24 00:19:44.464659 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=1969a6ee0c0ec5507eb68849c160e94c58e52d2291c767873af68a1f52b30801 Nov 24 00:19:44.464669 kernel: BIOS-provided physical RAM map: Nov 24 00:19:44.464675 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Nov 24 00:19:44.464681 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Nov 24 00:19:44.464688 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Nov 24 00:19:44.464696 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Nov 24 00:19:44.464705 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Nov 24 00:19:44.464712 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Nov 24 00:19:44.464719 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Nov 24 00:19:44.464725 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Nov 24 00:19:44.464732 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Nov 24 00:19:44.464739 kernel: printk: legacy bootconsole [earlyser0] enabled Nov 24 00:19:44.464745 kernel: NX (Execute Disable) protection: active Nov 24 00:19:44.464771 kernel: APIC: Static calls initialized Nov 24 00:19:44.464779 kernel: efi: EFI v2.7 by Microsoft Nov 24 00:19:44.464786 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Nov 24 00:19:44.464794 kernel: random: crng init done Nov 24 00:19:44.464801 kernel: secureboot: Secure boot disabled Nov 24 00:19:44.464809 kernel: SMBIOS 3.1.0 present. Nov 24 00:19:44.464816 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Nov 24 00:19:44.464823 kernel: DMI: Memory slots populated: 3/3 Nov 24 00:19:44.464831 kernel: Hypervisor detected: Microsoft Hyper-V Nov 24 00:19:44.464838 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Nov 24 00:19:44.464848 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Nov 24 00:19:44.464855 kernel: Hyper-V: Using hypercall for remote TLB flush Nov 24 00:19:44.464862 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Nov 24 00:19:44.464870 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Nov 24 00:19:44.464878 kernel: tsc: Marking TSC unstable due to running on Hyper-V Nov 24 00:19:44.464886 kernel: tsc: Detected 2593.993 MHz processor Nov 24 00:19:44.464894 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Nov 24 00:19:44.464902 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Nov 24 00:19:44.464909 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Nov 24 00:19:44.464919 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Nov 24 00:19:44.464927 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Nov 24 00:19:44.464934 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Nov 24 00:19:44.464941 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Nov 24 00:19:44.464949 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Nov 24 00:19:44.464956 kernel: Using GB pages for direct mapping Nov 24 00:19:44.464964 kernel: ACPI: Early table checksum verification disabled Nov 24 00:19:44.464975 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Nov 24 00:19:44.464985 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 24 00:19:44.464993 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 24 00:19:44.465001 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Nov 24 00:19:44.465010 kernel: ACPI: FACS 0x000000003FFFE000 000040 Nov 24 00:19:44.465017 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 24 00:19:44.465026 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 24 00:19:44.465036 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 24 00:19:44.465044 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 24 00:19:44.465052 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 24 00:19:44.465060 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 24 00:19:44.465068 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 24 00:19:44.465076 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Nov 24 00:19:44.465084 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Nov 24 00:19:44.465092 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Nov 24 00:19:44.465102 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Nov 24 00:19:44.465110 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Nov 24 00:19:44.465118 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Nov 24 00:19:44.465126 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Nov 24 00:19:44.465134 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Nov 24 00:19:44.465142 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Nov 24 00:19:44.465150 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Nov 24 00:19:44.465158 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Nov 24 00:19:44.465166 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Nov 24 00:19:44.465176 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Nov 24 00:19:44.465184 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Nov 24 00:19:44.465192 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Nov 24 00:19:44.465199 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Nov 24 00:19:44.465207 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Nov 24 00:19:44.465215 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Nov 24 00:19:44.465224 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] Nov 24 00:19:44.465232 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] Nov 24 00:19:44.465240 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] Nov 24 00:19:44.465250 kernel: Zone ranges: Nov 24 00:19:44.465258 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Nov 24 00:19:44.465266 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Nov 24 00:19:44.465274 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Nov 24 00:19:44.465282 kernel: Device empty Nov 24 00:19:44.465290 kernel: Movable zone start for each node Nov 24 00:19:44.465298 kernel: Early memory node ranges Nov 24 00:19:44.465306 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Nov 24 00:19:44.465313 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Nov 24 00:19:44.465324 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Nov 24 00:19:44.465332 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Nov 24 00:19:44.465340 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Nov 24 00:19:44.465347 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Nov 24 00:19:44.465355 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Nov 24 00:19:44.465363 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Nov 24 00:19:44.465371 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Nov 24 00:19:44.465379 kernel: ACPI: PM-Timer IO Port: 0x408 Nov 24 00:19:44.465387 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Nov 24 00:19:44.465397 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Nov 24 00:19:44.465405 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Nov 24 00:19:44.465413 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Nov 24 00:19:44.465421 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Nov 24 00:19:44.465429 kernel: CPU topo: Max. logical packages: 1 Nov 24 00:19:44.465437 kernel: CPU topo: Max. logical dies: 1 Nov 24 00:19:44.465445 kernel: CPU topo: Max. dies per package: 1 Nov 24 00:19:44.465453 kernel: CPU topo: Max. threads per core: 1 Nov 24 00:19:44.465461 kernel: CPU topo: Num. cores per package: 6 Nov 24 00:19:44.465471 kernel: CPU topo: Num. threads per package: 6 Nov 24 00:19:44.465479 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs Nov 24 00:19:44.465487 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Nov 24 00:19:44.465495 kernel: Booting paravirtualized kernel on Hyper-V Nov 24 00:19:44.465503 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Nov 24 00:19:44.465511 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Nov 24 00:19:44.465519 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 Nov 24 00:19:44.465527 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 Nov 24 00:19:44.465535 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Nov 24 00:19:44.465545 kernel: Hyper-V: PV spinlocks enabled Nov 24 00:19:44.465553 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Nov 24 00:19:44.465562 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=1969a6ee0c0ec5507eb68849c160e94c58e52d2291c767873af68a1f52b30801 Nov 24 00:19:44.465570 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Nov 24 00:19:44.465578 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Nov 24 00:19:44.465586 kernel: Fallback order for Node 0: 0 Nov 24 00:19:44.465594 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 Nov 24 00:19:44.465602 kernel: Policy zone: Normal Nov 24 00:19:44.465613 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Nov 24 00:19:44.465628 kernel: software IO TLB: area num 8. Nov 24 00:19:44.465637 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Nov 24 00:19:44.465648 kernel: Kernel/User page tables isolation: enabled Nov 24 00:19:44.465656 kernel: ftrace: allocating 40103 entries in 157 pages Nov 24 00:19:44.465665 kernel: ftrace: allocated 157 pages with 5 groups Nov 24 00:19:44.465673 kernel: Dynamic Preempt: voluntary Nov 24 00:19:44.465681 kernel: rcu: Preemptible hierarchical RCU implementation. Nov 24 00:19:44.465694 kernel: rcu: RCU event tracing is enabled. Nov 24 00:19:44.465703 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Nov 24 00:19:44.465711 kernel: Trampoline variant of Tasks RCU enabled. Nov 24 00:19:44.465722 kernel: Rude variant of Tasks RCU enabled. Nov 24 00:19:44.465731 kernel: Tracing variant of Tasks RCU enabled. Nov 24 00:19:44.465739 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Nov 24 00:19:44.465747 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Nov 24 00:19:44.465764 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Nov 24 00:19:44.465773 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Nov 24 00:19:44.465784 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Nov 24 00:19:44.465793 kernel: Using NULL legacy PIC Nov 24 00:19:44.465801 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Nov 24 00:19:44.465810 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Nov 24 00:19:44.465818 kernel: Console: colour dummy device 80x25 Nov 24 00:19:44.465827 kernel: printk: legacy console [tty1] enabled Nov 24 00:19:44.465835 kernel: printk: legacy console [ttyS0] enabled Nov 24 00:19:44.465844 kernel: printk: legacy bootconsole [earlyser0] disabled Nov 24 00:19:44.465852 kernel: ACPI: Core revision 20240827 Nov 24 00:19:44.465863 kernel: Failed to register legacy timer interrupt Nov 24 00:19:44.465871 kernel: APIC: Switch to symmetric I/O mode setup Nov 24 00:19:44.465880 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Nov 24 00:19:44.465895 kernel: Hyper-V: enabling crash_kexec_post_notifiers Nov 24 00:19:44.465904 kernel: Hyper-V: Using IPI hypercalls Nov 24 00:19:44.465913 kernel: APIC: send_IPI() replaced with hv_send_ipi() Nov 24 00:19:44.465921 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Nov 24 00:19:44.465930 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Nov 24 00:19:44.465938 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Nov 24 00:19:44.465950 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Nov 24 00:19:44.465958 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Nov 24 00:19:44.465967 kernel: Hyper-V: Using enlightened APIC (xapic mode) Nov 24 00:19:44.465975 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Nov 24 00:19:44.465984 kernel: APIC: read() replaced with hv_apic_read() Nov 24 00:19:44.465992 kernel: APIC: write() replaced with hv_apic_write() Nov 24 00:19:44.466001 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Nov 24 00:19:44.466009 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Nov 24 00:19:44.466018 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593993) Nov 24 00:19:44.466029 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Nov 24 00:19:44.466037 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Nov 24 00:19:44.466046 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Nov 24 00:19:44.466054 kernel: Spectre V2 : Mitigation: Retpolines Nov 24 00:19:44.466062 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Nov 24 00:19:44.466070 kernel: Speculative Store Bypass: Vulnerable Nov 24 00:19:44.466079 kernel: MDS: Mitigation: Clear CPU buffers Nov 24 00:19:44.466087 kernel: TAA: Mitigation: Clear CPU buffers Nov 24 00:19:44.466095 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Nov 24 00:19:44.466103 kernel: active return thunk: its_return_thunk Nov 24 00:19:44.466111 kernel: ITS: Mitigation: Aligned branch/return thunks Nov 24 00:19:44.466122 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Nov 24 00:19:44.466130 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Nov 24 00:19:44.466139 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Nov 24 00:19:44.466147 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Nov 24 00:19:44.466155 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Nov 24 00:19:44.466164 kernel: Freeing SMP alternatives memory: 32K Nov 24 00:19:44.466172 kernel: pid_max: default: 32768 minimum: 301 Nov 24 00:19:44.466180 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Nov 24 00:19:44.466189 kernel: landlock: Up and running. Nov 24 00:19:44.466197 kernel: SELinux: Initializing. Nov 24 00:19:44.466205 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Nov 24 00:19:44.466216 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Nov 24 00:19:44.466224 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Nov 24 00:19:44.466233 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Nov 24 00:19:44.466241 kernel: signal: max sigframe size: 1776 Nov 24 00:19:44.466250 kernel: rcu: Hierarchical SRCU implementation. Nov 24 00:19:44.466258 kernel: rcu: Max phase no-delay instances is 400. Nov 24 00:19:44.466267 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Nov 24 00:19:44.466275 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Nov 24 00:19:44.466284 kernel: smp: Bringing up secondary CPUs ... Nov 24 00:19:44.466295 kernel: smpboot: x86: Booting SMP configuration: Nov 24 00:19:44.466303 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Nov 24 00:19:44.466311 kernel: smp: Brought up 1 node, 6 CPUs Nov 24 00:19:44.466320 kernel: smpboot: Total of 6 processors activated (31127.91 BogoMIPS) Nov 24 00:19:44.466329 kernel: Memory: 115330900K/117439364K available (14336K kernel code, 2444K rwdata, 26064K rodata, 46188K init, 2572K bss, 2098188K reserved, 0K cma-reserved) Nov 24 00:19:44.466337 kernel: devtmpfs: initialized Nov 24 00:19:44.466346 kernel: x86/mm: Memory block size: 1024MB Nov 24 00:19:44.466354 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Nov 24 00:19:44.466362 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Nov 24 00:19:44.466373 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Nov 24 00:19:44.466382 kernel: pinctrl core: initialized pinctrl subsystem Nov 24 00:19:44.466390 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Nov 24 00:19:44.466398 kernel: audit: initializing netlink subsys (disabled) Nov 24 00:19:44.466407 kernel: audit: type=2000 audit(1763943580.029:1): state=initialized audit_enabled=0 res=1 Nov 24 00:19:44.466415 kernel: thermal_sys: Registered thermal governor 'step_wise' Nov 24 00:19:44.466424 kernel: thermal_sys: Registered thermal governor 'user_space' Nov 24 00:19:44.466432 kernel: cpuidle: using governor menu Nov 24 00:19:44.466440 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Nov 24 00:19:44.466451 kernel: dca service started, version 1.12.1 Nov 24 00:19:44.466460 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Nov 24 00:19:44.466468 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Nov 24 00:19:44.466477 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Nov 24 00:19:44.466485 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Nov 24 00:19:44.466493 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Nov 24 00:19:44.466502 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Nov 24 00:19:44.466510 kernel: ACPI: Added _OSI(Module Device) Nov 24 00:19:44.466518 kernel: ACPI: Added _OSI(Processor Device) Nov 24 00:19:44.466529 kernel: ACPI: Added _OSI(Processor Aggregator Device) Nov 24 00:19:44.466538 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Nov 24 00:19:44.466546 kernel: ACPI: Interpreter enabled Nov 24 00:19:44.466554 kernel: ACPI: PM: (supports S0 S5) Nov 24 00:19:44.466563 kernel: ACPI: Using IOAPIC for interrupt routing Nov 24 00:19:44.466571 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Nov 24 00:19:44.466579 kernel: PCI: Ignoring E820 reservations for host bridge windows Nov 24 00:19:44.466588 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Nov 24 00:19:44.466596 kernel: iommu: Default domain type: Translated Nov 24 00:19:44.466607 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Nov 24 00:19:44.466615 kernel: efivars: Registered efivars operations Nov 24 00:19:44.466623 kernel: PCI: Using ACPI for IRQ routing Nov 24 00:19:44.466632 kernel: PCI: System does not support PCI Nov 24 00:19:44.466640 kernel: vgaarb: loaded Nov 24 00:19:44.466649 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Nov 24 00:19:44.466657 kernel: VFS: Disk quotas dquot_6.6.0 Nov 24 00:19:44.466665 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Nov 24 00:19:44.466674 kernel: pnp: PnP ACPI init Nov 24 00:19:44.466684 kernel: pnp: PnP ACPI: found 3 devices Nov 24 00:19:44.466693 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Nov 24 00:19:44.466701 kernel: NET: Registered PF_INET protocol family Nov 24 00:19:44.466710 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Nov 24 00:19:44.466718 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Nov 24 00:19:44.466727 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Nov 24 00:19:44.466735 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Nov 24 00:19:44.466744 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Nov 24 00:19:44.466760 kernel: TCP: Hash tables configured (established 524288 bind 65536) Nov 24 00:19:44.466771 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Nov 24 00:19:44.466780 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Nov 24 00:19:44.466788 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Nov 24 00:19:44.466797 kernel: NET: Registered PF_XDP protocol family Nov 24 00:19:44.466805 kernel: PCI: CLS 0 bytes, default 64 Nov 24 00:19:44.466813 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Nov 24 00:19:44.466822 kernel: software IO TLB: mapped [mem 0x000000003b5bf000-0x000000003f5bf000] (64MB) Nov 24 00:19:44.466830 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Nov 24 00:19:44.466839 kernel: Initialise system trusted keyrings Nov 24 00:19:44.466849 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Nov 24 00:19:44.466858 kernel: Key type asymmetric registered Nov 24 00:19:44.466866 kernel: Asymmetric key parser 'x509' registered Nov 24 00:19:44.466874 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Nov 24 00:19:44.466883 kernel: io scheduler mq-deadline registered Nov 24 00:19:44.466891 kernel: io scheduler kyber registered Nov 24 00:19:44.466899 kernel: io scheduler bfq registered Nov 24 00:19:44.466908 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Nov 24 00:19:44.466916 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Nov 24 00:19:44.466927 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Nov 24 00:19:44.466936 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Nov 24 00:19:44.466944 kernel: i8042: PNP: No PS/2 controller found. Nov 24 00:19:44.467100 kernel: rtc_cmos 00:02: registered as rtc0 Nov 24 00:19:44.467206 kernel: rtc_cmos 00:02: setting system clock to 2025-11-24T00:19:43 UTC (1763943583) Nov 24 00:19:44.467305 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Nov 24 00:19:44.467318 kernel: intel_pstate: CPU model not supported Nov 24 00:19:44.467331 kernel: efifb: probing for efifb Nov 24 00:19:44.467339 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Nov 24 00:19:44.467348 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Nov 24 00:19:44.467356 kernel: efifb: scrolling: redraw Nov 24 00:19:44.467365 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Nov 24 00:19:44.467373 kernel: Console: switching to colour frame buffer device 128x48 Nov 24 00:19:44.467382 kernel: fb0: EFI VGA frame buffer device Nov 24 00:19:44.467391 kernel: pstore: Using crash dump compression: deflate Nov 24 00:19:44.467399 kernel: pstore: Registered efi_pstore as persistent store backend Nov 24 00:19:44.467410 kernel: NET: Registered PF_INET6 protocol family Nov 24 00:19:44.467418 kernel: Segment Routing with IPv6 Nov 24 00:19:44.467427 kernel: In-situ OAM (IOAM) with IPv6 Nov 24 00:19:44.467435 kernel: NET: Registered PF_PACKET protocol family Nov 24 00:19:44.467443 kernel: Key type dns_resolver registered Nov 24 00:19:44.467452 kernel: IPI shorthand broadcast: enabled Nov 24 00:19:44.467460 kernel: sched_clock: Marking stable (4020005300, 173691000)->(4748784700, -555088400) Nov 24 00:19:44.467469 kernel: registered taskstats version 1 Nov 24 00:19:44.467477 kernel: Loading compiled-in X.509 certificates Nov 24 00:19:44.467486 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.58-flatcar: 5d380f93d180914be04be8068ab300f495c35900' Nov 24 00:19:44.467496 kernel: Demotion targets for Node 0: null Nov 24 00:19:44.467505 kernel: Key type .fscrypt registered Nov 24 00:19:44.467513 kernel: Key type fscrypt-provisioning registered Nov 24 00:19:44.467521 kernel: ima: No TPM chip found, activating TPM-bypass! Nov 24 00:19:44.467530 kernel: ima: Allocated hash algorithm: sha1 Nov 24 00:19:44.467538 kernel: ima: No architecture policies found Nov 24 00:19:44.467546 kernel: clk: Disabling unused clocks Nov 24 00:19:44.467555 kernel: Warning: unable to open an initial console. Nov 24 00:19:44.467563 kernel: Freeing unused kernel image (initmem) memory: 46188K Nov 24 00:19:44.467574 kernel: Write protecting the kernel read-only data: 40960k Nov 24 00:19:44.467583 kernel: Freeing unused kernel image (rodata/data gap) memory: 560K Nov 24 00:19:44.467591 kernel: Run /init as init process Nov 24 00:19:44.467600 kernel: with arguments: Nov 24 00:19:44.467608 kernel: /init Nov 24 00:19:44.467616 kernel: with environment: Nov 24 00:19:44.467624 kernel: HOME=/ Nov 24 00:19:44.467633 kernel: TERM=linux Nov 24 00:19:44.467642 systemd[1]: Successfully made /usr/ read-only. Nov 24 00:19:44.467656 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) Nov 24 00:19:44.467666 systemd[1]: Detected virtualization microsoft. Nov 24 00:19:44.467674 systemd[1]: Detected architecture x86-64. Nov 24 00:19:44.467683 systemd[1]: Running in initrd. Nov 24 00:19:44.467692 systemd[1]: No hostname configured, using default hostname. Nov 24 00:19:44.467701 systemd[1]: Hostname set to . Nov 24 00:19:44.467710 systemd[1]: Initializing machine ID from random generator. Nov 24 00:19:44.467721 systemd[1]: Queued start job for default target initrd.target. Nov 24 00:19:44.467730 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 24 00:19:44.467739 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 24 00:19:44.467759 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Nov 24 00:19:44.467770 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 24 00:19:44.467779 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Nov 24 00:19:44.467788 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Nov 24 00:19:44.467801 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Nov 24 00:19:44.467811 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Nov 24 00:19:44.467820 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 24 00:19:44.467829 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 24 00:19:44.467838 systemd[1]: Reached target paths.target - Path Units. Nov 24 00:19:44.467847 systemd[1]: Reached target slices.target - Slice Units. Nov 24 00:19:44.467856 systemd[1]: Reached target swap.target - Swaps. Nov 24 00:19:44.467864 systemd[1]: Reached target timers.target - Timer Units. Nov 24 00:19:44.467876 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Nov 24 00:19:44.467885 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 24 00:19:44.467894 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Nov 24 00:19:44.467903 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Nov 24 00:19:44.467912 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 24 00:19:44.467921 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 24 00:19:44.467930 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 24 00:19:44.467939 systemd[1]: Reached target sockets.target - Socket Units. Nov 24 00:19:44.467948 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Nov 24 00:19:44.467959 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 24 00:19:44.467968 systemd[1]: Finished network-cleanup.service - Network Cleanup. Nov 24 00:19:44.467977 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). Nov 24 00:19:44.467986 systemd[1]: Starting systemd-fsck-usr.service... Nov 24 00:19:44.467995 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 24 00:19:44.468004 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 24 00:19:44.468033 systemd-journald[213]: Collecting audit messages is disabled. Nov 24 00:19:44.468059 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 24 00:19:44.468069 systemd-journald[213]: Journal started Nov 24 00:19:44.468092 systemd-journald[213]: Runtime Journal (/run/log/journal/5733fc88c0e74e7f83d96e1e1d7f8f15) is 8M, max 2.2G, 2.1G free. Nov 24 00:19:44.478933 systemd-modules-load[215]: Inserted module 'overlay' Nov 24 00:19:44.488975 systemd[1]: Started systemd-journald.service - Journal Service. Nov 24 00:19:44.489299 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Nov 24 00:19:44.490325 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 24 00:19:44.492378 systemd[1]: Finished systemd-fsck-usr.service. Nov 24 00:19:44.495098 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 24 00:19:44.496412 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 24 00:19:44.571255 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 24 00:19:44.575231 systemd-tmpfiles[225]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Nov 24 00:19:44.577166 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 24 00:19:44.591638 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 24 00:19:44.616920 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 24 00:19:44.646976 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 24 00:19:44.671876 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 24 00:19:44.681720 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Nov 24 00:19:44.692522 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 24 00:19:44.695851 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Nov 24 00:19:44.715059 kernel: Bridge firewalling registered Nov 24 00:19:44.725027 systemd-modules-load[215]: Inserted module 'br_netfilter' Nov 24 00:19:44.726282 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 24 00:19:44.729538 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 24 00:19:44.758386 dracut-cmdline[250]: Using kernel command line parameters: rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1 rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=1969a6ee0c0ec5507eb68849c160e94c58e52d2291c767873af68a1f52b30801 Nov 24 00:19:44.800979 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 24 00:19:44.804550 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 24 00:19:44.852092 systemd-resolved[322]: Positive Trust Anchors: Nov 24 00:19:44.852109 systemd-resolved[322]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 24 00:19:44.852140 systemd-resolved[322]: 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 Nov 24 00:19:44.854569 systemd-resolved[322]: Defaulting to hostname 'linux'. Nov 24 00:19:44.855556 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 24 00:19:44.859681 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 24 00:19:44.947768 kernel: SCSI subsystem initialized Nov 24 00:19:44.959769 kernel: Loading iSCSI transport class v2.0-870. Nov 24 00:19:44.973773 kernel: iscsi: registered transport (tcp) Nov 24 00:19:45.002807 kernel: iscsi: registered transport (qla4xxx) Nov 24 00:19:45.002839 kernel: QLogic iSCSI HBA Driver Nov 24 00:19:45.022743 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 24 00:19:45.058162 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 24 00:19:45.061587 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 24 00:19:45.119544 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Nov 24 00:19:45.122903 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Nov 24 00:19:45.203770 kernel: raid6: avx2x4 gen() 32307 MB/s Nov 24 00:19:45.223762 kernel: raid6: avx2x2 gen() 30760 MB/s Nov 24 00:19:45.249794 kernel: raid6: avx2x1 gen() 27487 MB/s Nov 24 00:19:45.249822 kernel: raid6: using algorithm avx2x4 gen() 32307 MB/s Nov 24 00:19:45.274691 kernel: raid6: .... xor() 10363 MB/s, rmw enabled Nov 24 00:19:45.274726 kernel: raid6: using avx2x2 recovery algorithm Nov 24 00:19:45.301776 kernel: xor: automatically using best checksumming function avx Nov 24 00:19:45.475784 kernel: Btrfs loaded, zoned=no, fsverity=no Nov 24 00:19:45.483017 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Nov 24 00:19:45.486978 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 24 00:19:45.536639 systemd-udevd[467]: Using default interface naming scheme 'v255'. Nov 24 00:19:45.542691 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 24 00:19:45.546230 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Nov 24 00:19:45.597152 dracut-pre-trigger[469]: rd.md=0: removing MD RAID activation Nov 24 00:19:45.628638 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Nov 24 00:19:45.632063 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 24 00:19:45.712301 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 24 00:19:45.732280 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Nov 24 00:19:45.752774 kernel: cryptd: max_cpu_qlen set to 1000 Nov 24 00:19:45.758800 kernel: hv_vmbus: Vmbus version:4.0 Nov 24 00:19:45.764239 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 24 00:19:45.764368 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 24 00:19:45.778936 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Nov 24 00:19:45.805836 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 24 00:19:45.833287 kernel: pps_core: LinuxPPS API ver. 1 registered Nov 24 00:19:45.833325 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Nov 24 00:19:45.833346 kernel: hid: raw HID events driver (C) Jiri Kosina Nov 24 00:19:45.826590 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Nov 24 00:19:45.835167 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 24 00:19:45.835316 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 24 00:19:45.894152 kernel: hv_vmbus: registering driver hyperv_keyboard Nov 24 00:19:45.894178 kernel: hv_vmbus: registering driver hv_pci Nov 24 00:19:45.894191 kernel: hv_vmbus: registering driver hv_netvsc Nov 24 00:19:45.894203 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 Nov 24 00:19:45.894216 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Nov 24 00:19:45.894413 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Nov 24 00:19:45.894546 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Nov 24 00:19:45.894690 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Nov 24 00:19:45.894901 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Nov 24 00:19:45.895025 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint Nov 24 00:19:45.836514 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 24 00:19:45.946298 kernel: hv_vmbus: registering driver hv_storvsc Nov 24 00:19:45.946322 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] Nov 24 00:19:45.953339 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] Nov 24 00:19:45.961827 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] Nov 24 00:19:45.966759 kernel: hv_vmbus: registering driver hid_hyperv Nov 24 00:19:45.966794 kernel: PTP clock support registered Nov 24 00:19:45.966807 kernel: pci 0001:00:00.0: Enabling HDA controller Nov 24 00:19:45.975771 kernel: AES CTR mode by8 optimization enabled Nov 24 00:19:45.982148 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 24 00:19:45.999617 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Nov 24 00:19:45.999800 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned Nov 24 00:19:46.016534 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned Nov 24 00:19:46.016705 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned Nov 24 00:19:46.032768 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Nov 24 00:19:46.032796 kernel: scsi host1: storvsc_host_t Nov 24 00:19:46.032828 kernel: scsi host0: storvsc_host_t Nov 24 00:19:46.032857 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Nov 24 00:19:46.054787 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Nov 24 00:19:46.064768 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Nov 24 00:19:46.071771 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Nov 24 00:19:46.087734 kernel: hv_utils: Registering HyperV Utility Driver Nov 24 00:19:46.087781 kernel: hv_vmbus: registering driver hv_utils Nov 24 00:19:46.088823 kernel: hv_utils: Heartbeat IC version 3.0 Nov 24 00:19:46.096723 kernel: hv_utils: Shutdown IC version 3.2 Nov 24 00:19:46.097771 kernel: hv_utils: TimeSync IC version 4.0 Nov 24 00:19:46.097862 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Nov 24 00:19:46.098174 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Nov 24 00:19:46.098347 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks Nov 24 00:19:46.098492 kernel: sd 0:0:0:1: [sdb] Write Protect is off Nov 24 00:19:46.098628 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Nov 24 00:19:46.098771 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Nov 24 00:19:46.098907 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#194 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 24 00:19:46.675990 systemd-resolved[322]: Clock change detected. Flushing caches. Nov 24 00:19:46.735668 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#71 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 24 00:19:46.735834 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Nov 24 00:19:46.735983 kernel: sd 0:0:0:0: [sda] Write Protect is off Nov 24 00:19:46.743383 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Nov 24 00:19:46.743557 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Nov 24 00:19:46.744010 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#451 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 24 00:19:46.761654 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#458 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 24 00:19:46.768443 kernel: sdb: sdb1 Nov 24 00:19:46.768471 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk Nov 24 00:19:46.787011 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Nov 24 00:19:46.792012 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Nov 24 00:19:46.802120 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Nov 24 00:19:46.802321 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Nov 24 00:19:46.804015 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Nov 24 00:19:47.338296 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Nov 24 00:19:47.380425 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Nov 24 00:19:47.408612 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Nov 24 00:19:47.514189 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Nov 24 00:19:47.516429 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Nov 24 00:19:47.535983 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Nov 24 00:19:47.592007 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#279 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 24 00:19:47.610015 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Nov 24 00:19:47.621184 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#296 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 24 00:19:47.649013 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Nov 24 00:19:48.363700 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Nov 24 00:19:48.377397 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Nov 24 00:19:48.379640 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 24 00:19:48.408038 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 24 00:19:48.421892 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Nov 24 00:19:48.468638 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Nov 24 00:19:48.659739 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#563 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 24 00:19:48.679023 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Nov 24 00:19:48.679435 disk-uuid[710]: The operation has completed successfully. Nov 24 00:19:48.736822 systemd[1]: disk-uuid.service: Deactivated successfully. Nov 24 00:19:48.736928 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Nov 24 00:19:48.808003 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Nov 24 00:19:48.834765 sh[882]: Success Nov 24 00:19:48.877200 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Nov 24 00:19:48.877235 kernel: device-mapper: uevent: version 1.0.3 Nov 24 00:19:48.884374 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Nov 24 00:19:48.900011 kernel: device-mapper: verity: sha256 using shash "sha256-avx2" Nov 24 00:19:49.280272 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Nov 24 00:19:49.295289 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Nov 24 00:19:49.327618 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Nov 24 00:19:49.365745 kernel: BTRFS: device fsid c993ebd2-0e38-4cfc-8615-2c75294bea72 devid 1 transid 36 /dev/mapper/usr (254:0) scanned by mount (900) Nov 24 00:19:49.365770 kernel: BTRFS info (device dm-0): first mount of filesystem c993ebd2-0e38-4cfc-8615-2c75294bea72 Nov 24 00:19:49.365783 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Nov 24 00:19:49.653165 kernel: BTRFS info (device dm-0): disabling log replay at mount time Nov 24 00:19:49.653241 kernel: BTRFS info (device dm-0): enabling free space tree Nov 24 00:19:49.716554 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Nov 24 00:19:49.727302 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Nov 24 00:19:49.741907 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Nov 24 00:19:49.754592 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Nov 24 00:19:49.757558 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Nov 24 00:19:49.816013 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (933) Nov 24 00:19:49.830158 kernel: BTRFS info (device sda6): first mount of filesystem 8f3e7759-f869-465c-a676-2cd550a2d4e4 Nov 24 00:19:49.830187 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Nov 24 00:19:49.855518 kernel: BTRFS info (device sda6): turning on async discard Nov 24 00:19:49.855550 kernel: BTRFS info (device sda6): enabling free space tree Nov 24 00:19:49.867040 kernel: BTRFS info (device sda6): last unmount of filesystem 8f3e7759-f869-465c-a676-2cd550a2d4e4 Nov 24 00:19:49.867181 systemd[1]: Finished ignition-setup.service - Ignition (setup). Nov 24 00:19:49.870572 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Nov 24 00:19:49.896081 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 24 00:19:49.899799 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 24 00:19:49.948442 systemd-networkd[1069]: lo: Link UP Nov 24 00:19:49.948451 systemd-networkd[1069]: lo: Gained carrier Nov 24 00:19:49.949462 systemd-networkd[1069]: Enumeration completed Nov 24 00:19:49.949540 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 24 00:19:49.949817 systemd-networkd[1069]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 24 00:19:49.949822 systemd-networkd[1069]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 24 00:19:49.951497 systemd-networkd[1069]: eth0: Link UP Nov 24 00:19:49.951668 systemd-networkd[1069]: eth0: Gained carrier Nov 24 00:19:49.951678 systemd-networkd[1069]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 24 00:19:49.963291 systemd[1]: Reached target network.target - Network. Nov 24 00:19:50.032029 systemd-networkd[1069]: eth0: DHCPv4 address 10.200.0.12/24, gateway 10.200.0.1 acquired from 168.63.129.16 Nov 24 00:19:50.877784 ignition[1064]: Ignition 2.22.0 Nov 24 00:19:50.877813 ignition[1064]: Stage: fetch-offline Nov 24 00:19:50.877980 ignition[1064]: no configs at "/usr/lib/ignition/base.d" Nov 24 00:19:50.878010 ignition[1064]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 24 00:19:50.878131 ignition[1064]: parsed url from cmdline: "" Nov 24 00:19:50.878136 ignition[1064]: no config URL provided Nov 24 00:19:50.878142 ignition[1064]: reading system config file "/usr/lib/ignition/user.ign" Nov 24 00:19:50.878151 ignition[1064]: no config at "/usr/lib/ignition/user.ign" Nov 24 00:19:50.878157 ignition[1064]: failed to fetch config: resource requires networking Nov 24 00:19:50.878511 ignition[1064]: Ignition finished successfully Nov 24 00:19:50.927649 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Nov 24 00:19:50.942921 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Nov 24 00:19:50.986081 ignition[1081]: Ignition 2.22.0 Nov 24 00:19:50.986094 ignition[1081]: Stage: fetch Nov 24 00:19:50.986324 ignition[1081]: no configs at "/usr/lib/ignition/base.d" Nov 24 00:19:50.986335 ignition[1081]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 24 00:19:50.986422 ignition[1081]: parsed url from cmdline: "" Nov 24 00:19:50.986426 ignition[1081]: no config URL provided Nov 24 00:19:50.986432 ignition[1081]: reading system config file "/usr/lib/ignition/user.ign" Nov 24 00:19:50.986441 ignition[1081]: no config at "/usr/lib/ignition/user.ign" Nov 24 00:19:50.986459 ignition[1081]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Nov 24 00:19:51.091112 systemd-networkd[1069]: eth0: Gained IPv6LL Nov 24 00:19:51.206797 ignition[1081]: GET result: OK Nov 24 00:19:51.206848 ignition[1081]: config has been read from IMDS userdata Nov 24 00:19:51.206859 ignition[1081]: parsing config with SHA512: fb574f0a45c0431d6bff2ed0f9b7855374650faa92fd517c4382b72060b127d21afb58141516d86c85af2b252ab408967e562e039fe9f684a5c8e8487201592b Nov 24 00:19:51.211325 unknown[1081]: fetched base config from "system" Nov 24 00:19:51.211494 ignition[1081]: fetch: fetch complete Nov 24 00:19:51.211332 unknown[1081]: fetched base config from "system" Nov 24 00:19:51.211500 ignition[1081]: fetch: fetch passed Nov 24 00:19:51.211337 unknown[1081]: fetched user config from "azure" Nov 24 00:19:51.211542 ignition[1081]: Ignition finished successfully Nov 24 00:19:51.227791 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Nov 24 00:19:51.238409 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Nov 24 00:19:51.288589 ignition[1090]: Ignition 2.22.0 Nov 24 00:19:51.288602 ignition[1090]: Stage: kargs Nov 24 00:19:51.288798 ignition[1090]: no configs at "/usr/lib/ignition/base.d" Nov 24 00:19:51.288808 ignition[1090]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 24 00:19:51.289257 ignition[1090]: kargs: kargs passed Nov 24 00:19:51.302086 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Nov 24 00:19:51.289299 ignition[1090]: Ignition finished successfully Nov 24 00:19:51.315656 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Nov 24 00:19:51.371504 ignition[1100]: Ignition 2.22.0 Nov 24 00:19:51.371518 ignition[1100]: Stage: disks Nov 24 00:19:51.371792 ignition[1100]: no configs at "/usr/lib/ignition/base.d" Nov 24 00:19:51.371805 ignition[1100]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 24 00:19:51.380469 systemd[1]: Finished ignition-disks.service - Ignition (disks). Nov 24 00:19:51.372478 ignition[1100]: disks: disks passed Nov 24 00:19:51.391585 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Nov 24 00:19:51.372525 ignition[1100]: Ignition finished successfully Nov 24 00:19:51.405449 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Nov 24 00:19:51.419442 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 24 00:19:51.430377 systemd[1]: Reached target sysinit.target - System Initialization. Nov 24 00:19:51.443460 systemd[1]: Reached target basic.target - Basic System. Nov 24 00:19:51.454628 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Nov 24 00:19:51.539836 systemd-fsck[1111]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks Nov 24 00:19:51.552459 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Nov 24 00:19:51.555553 systemd[1]: Mounting sysroot.mount - /sysroot... Nov 24 00:19:51.865009 kernel: EXT4-fs (sda9): mounted filesystem 5d9d0447-100f-4769-adb5-76fdba966eb2 r/w with ordered data mode. Quota mode: none. Nov 24 00:19:51.865182 systemd[1]: Mounted sysroot.mount - /sysroot. Nov 24 00:19:51.867911 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Nov 24 00:19:51.890920 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 24 00:19:51.894129 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Nov 24 00:19:51.918524 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Nov 24 00:19:51.940183 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1125) Nov 24 00:19:51.920788 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Nov 24 00:19:51.983325 kernel: BTRFS info (device sda6): first mount of filesystem 8f3e7759-f869-465c-a676-2cd550a2d4e4 Nov 24 00:19:51.983345 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Nov 24 00:19:51.983358 kernel: BTRFS info (device sda6): turning on async discard Nov 24 00:19:51.983370 kernel: BTRFS info (device sda6): enabling free space tree Nov 24 00:19:51.920818 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Nov 24 00:19:51.943378 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Nov 24 00:19:51.986480 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 24 00:19:51.998409 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Nov 24 00:19:52.634236 coreos-metadata[1127]: Nov 24 00:19:52.634 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Nov 24 00:19:52.645725 coreos-metadata[1127]: Nov 24 00:19:52.645 INFO Fetch successful Nov 24 00:19:52.652237 coreos-metadata[1127]: Nov 24 00:19:52.645 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Nov 24 00:19:52.665753 coreos-metadata[1127]: Nov 24 00:19:52.665 INFO Fetch successful Nov 24 00:19:52.678374 coreos-metadata[1127]: Nov 24 00:19:52.678 INFO wrote hostname ci-4459.1.2-a-ae1deb39ef to /sysroot/etc/hostname Nov 24 00:19:52.689286 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Nov 24 00:19:52.977007 initrd-setup-root[1155]: cut: /sysroot/etc/passwd: No such file or directory Nov 24 00:19:53.038709 initrd-setup-root[1162]: cut: /sysroot/etc/group: No such file or directory Nov 24 00:19:53.074829 initrd-setup-root[1169]: cut: /sysroot/etc/shadow: No such file or directory Nov 24 00:19:53.091035 initrd-setup-root[1176]: cut: /sysroot/etc/gshadow: No such file or directory Nov 24 00:19:54.120740 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Nov 24 00:19:54.124521 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Nov 24 00:19:54.152601 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Nov 24 00:19:54.168588 systemd[1]: sysroot-oem.mount: Deactivated successfully. Nov 24 00:19:54.182773 kernel: BTRFS info (device sda6): last unmount of filesystem 8f3e7759-f869-465c-a676-2cd550a2d4e4 Nov 24 00:19:54.209952 ignition[1245]: INFO : Ignition 2.22.0 Nov 24 00:19:54.209952 ignition[1245]: INFO : Stage: mount Nov 24 00:19:54.225571 ignition[1245]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 24 00:19:54.225571 ignition[1245]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 24 00:19:54.225571 ignition[1245]: INFO : mount: mount passed Nov 24 00:19:54.225571 ignition[1245]: INFO : Ignition finished successfully Nov 24 00:19:54.214138 systemd[1]: Finished ignition-mount.service - Ignition (mount). Nov 24 00:19:54.218225 systemd[1]: Starting ignition-files.service - Ignition (files)... Nov 24 00:19:54.265095 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Nov 24 00:19:54.274739 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 24 00:19:54.315838 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1259) Nov 24 00:19:54.315876 kernel: BTRFS info (device sda6): first mount of filesystem 8f3e7759-f869-465c-a676-2cd550a2d4e4 Nov 24 00:19:54.322527 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Nov 24 00:19:54.336051 kernel: BTRFS info (device sda6): turning on async discard Nov 24 00:19:54.336077 kernel: BTRFS info (device sda6): enabling free space tree Nov 24 00:19:54.338270 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 24 00:19:54.380265 ignition[1276]: INFO : Ignition 2.22.0 Nov 24 00:19:54.380265 ignition[1276]: INFO : Stage: files Nov 24 00:19:54.389624 ignition[1276]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 24 00:19:54.389624 ignition[1276]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 24 00:19:54.389624 ignition[1276]: DEBUG : files: compiled without relabeling support, skipping Nov 24 00:19:54.389624 ignition[1276]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Nov 24 00:19:54.389624 ignition[1276]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Nov 24 00:19:54.439834 ignition[1276]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Nov 24 00:19:54.447982 ignition[1276]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Nov 24 00:19:54.447982 ignition[1276]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Nov 24 00:19:54.440577 unknown[1276]: wrote ssh authorized keys file for user: core Nov 24 00:19:54.470006 ignition[1276]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Nov 24 00:19:54.470006 ignition[1276]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Nov 24 00:19:54.470006 ignition[1276]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Nov 24 00:19:54.470006 ignition[1276]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Nov 24 00:19:54.470006 ignition[1276]: INFO : files: files passed Nov 24 00:19:54.470006 ignition[1276]: INFO : Ignition finished successfully Nov 24 00:19:54.471552 systemd[1]: Finished ignition-files.service - Ignition (files). Nov 24 00:19:54.489217 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Nov 24 00:19:54.507407 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Nov 24 00:19:54.541164 systemd[1]: ignition-quench.service: Deactivated successfully. Nov 24 00:19:54.541252 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Nov 24 00:19:54.616028 initrd-setup-root-after-ignition[1309]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 24 00:19:54.625924 initrd-setup-root-after-ignition[1309]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Nov 24 00:19:54.636474 initrd-setup-root-after-ignition[1313]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 24 00:19:54.647430 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 24 00:19:54.649943 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Nov 24 00:19:54.666779 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Nov 24 00:19:54.722847 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Nov 24 00:19:54.722951 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Nov 24 00:19:54.736307 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Nov 24 00:19:54.750674 systemd[1]: Reached target initrd.target - Initrd Default Target. Nov 24 00:19:54.763749 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Nov 24 00:19:54.764515 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Nov 24 00:19:54.825490 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 24 00:19:54.842089 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Nov 24 00:19:54.882050 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Nov 24 00:19:54.884726 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 24 00:19:54.899154 systemd[1]: Stopped target timers.target - Timer Units. Nov 24 00:19:54.913596 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Nov 24 00:19:54.913717 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 24 00:19:54.936463 systemd[1]: Stopped target initrd.target - Initrd Default Target. Nov 24 00:19:54.950093 systemd[1]: Stopped target basic.target - Basic System. Nov 24 00:19:54.962230 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Nov 24 00:19:54.974869 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Nov 24 00:19:54.978470 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Nov 24 00:19:55.010646 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Nov 24 00:19:55.013166 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Nov 24 00:19:55.027482 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Nov 24 00:19:55.040450 systemd[1]: Stopped target sysinit.target - System Initialization. Nov 24 00:19:55.064250 systemd[1]: Stopped target local-fs.target - Local File Systems. Nov 24 00:19:55.075605 systemd[1]: Stopped target swap.target - Swaps. Nov 24 00:19:55.085636 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Nov 24 00:19:55.091645 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Nov 24 00:19:55.104038 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Nov 24 00:19:55.106172 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 24 00:19:55.118872 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Nov 24 00:19:55.119022 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 24 00:19:55.133081 systemd[1]: dracut-initqueue.service: Deactivated successfully. Nov 24 00:19:55.133198 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Nov 24 00:19:55.157986 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Nov 24 00:19:55.158155 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 24 00:19:55.172472 systemd[1]: ignition-files.service: Deactivated successfully. Nov 24 00:19:55.172594 systemd[1]: Stopped ignition-files.service - Ignition (files). Nov 24 00:19:55.183989 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Nov 24 00:19:55.184123 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Nov 24 00:19:55.198812 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Nov 24 00:19:55.211014 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Nov 24 00:19:55.211141 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Nov 24 00:19:55.245525 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Nov 24 00:19:55.256073 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Nov 24 00:19:55.256211 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Nov 24 00:19:55.308852 ignition[1333]: INFO : Ignition 2.22.0 Nov 24 00:19:55.308852 ignition[1333]: INFO : Stage: umount Nov 24 00:19:55.308852 ignition[1333]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 24 00:19:55.308852 ignition[1333]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 24 00:19:55.308852 ignition[1333]: INFO : umount: umount passed Nov 24 00:19:55.308852 ignition[1333]: INFO : Ignition finished successfully Nov 24 00:19:55.259448 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Nov 24 00:19:55.259564 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Nov 24 00:19:55.291528 systemd[1]: sysroot-boot.mount: Deactivated successfully. Nov 24 00:19:55.294106 systemd[1]: initrd-cleanup.service: Deactivated successfully. Nov 24 00:19:55.294203 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Nov 24 00:19:55.300107 systemd[1]: ignition-mount.service: Deactivated successfully. Nov 24 00:19:55.300204 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Nov 24 00:19:55.310899 systemd[1]: ignition-disks.service: Deactivated successfully. Nov 24 00:19:55.310949 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Nov 24 00:19:55.321986 systemd[1]: ignition-kargs.service: Deactivated successfully. Nov 24 00:19:55.322047 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Nov 24 00:19:55.342871 systemd[1]: ignition-fetch.service: Deactivated successfully. Nov 24 00:19:55.342919 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Nov 24 00:19:55.355931 systemd[1]: Stopped target network.target - Network. Nov 24 00:19:55.365498 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Nov 24 00:19:55.365552 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Nov 24 00:19:55.377683 systemd[1]: Stopped target paths.target - Path Units. Nov 24 00:19:55.389291 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Nov 24 00:19:55.393049 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 24 00:19:55.398340 systemd[1]: Stopped target slices.target - Slice Units. Nov 24 00:19:55.411637 systemd[1]: Stopped target sockets.target - Socket Units. Nov 24 00:19:55.423604 systemd[1]: iscsid.socket: Deactivated successfully. Nov 24 00:19:55.423645 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Nov 24 00:19:55.437523 systemd[1]: iscsiuio.socket: Deactivated successfully. Nov 24 00:19:55.437568 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 24 00:19:55.449110 systemd[1]: ignition-setup.service: Deactivated successfully. Nov 24 00:19:55.449166 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Nov 24 00:19:55.461604 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Nov 24 00:19:55.461651 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Nov 24 00:19:55.472005 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Nov 24 00:19:55.485090 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Nov 24 00:19:55.497698 systemd[1]: sysroot-boot.service: Deactivated successfully. Nov 24 00:19:55.497793 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Nov 24 00:19:55.509925 systemd[1]: initrd-setup-root.service: Deactivated successfully. Nov 24 00:19:55.510032 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Nov 24 00:19:55.519243 systemd[1]: systemd-resolved.service: Deactivated successfully. Nov 24 00:19:55.519346 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Nov 24 00:19:55.542341 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Nov 24 00:19:55.542566 systemd[1]: systemd-networkd.service: Deactivated successfully. Nov 24 00:19:55.542677 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Nov 24 00:19:55.554453 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Nov 24 00:19:55.555381 systemd[1]: Stopped target network-pre.target - Preparation for Network. Nov 24 00:19:55.562518 systemd[1]: systemd-networkd.socket: Deactivated successfully. Nov 24 00:19:55.562557 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Nov 24 00:19:55.587527 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Nov 24 00:19:55.600990 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Nov 24 00:19:55.601056 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 24 00:19:55.603116 systemd[1]: systemd-sysctl.service: Deactivated successfully. Nov 24 00:19:55.603164 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Nov 24 00:19:55.629448 systemd[1]: systemd-modules-load.service: Deactivated successfully. Nov 24 00:19:55.629500 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Nov 24 00:19:55.641293 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Nov 24 00:19:55.641345 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 24 00:19:55.659175 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 24 00:19:55.672518 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Nov 24 00:19:55.672582 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Nov 24 00:19:55.725863 systemd[1]: systemd-udevd.service: Deactivated successfully. Nov 24 00:19:55.726030 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 24 00:19:55.739410 systemd[1]: network-cleanup.service: Deactivated successfully. Nov 24 00:19:55.739510 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Nov 24 00:19:55.750767 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Nov 24 00:19:55.751055 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Nov 24 00:19:55.762838 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Nov 24 00:19:55.762876 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Nov 24 00:19:55.774578 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Nov 24 00:19:55.774629 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Nov 24 00:19:55.793768 systemd[1]: dracut-cmdline.service: Deactivated successfully. Nov 24 00:19:55.793823 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Nov 24 00:19:55.811527 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 24 00:19:55.811579 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 24 00:19:55.834144 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Nov 24 00:19:56.079337 systemd-journald[213]: Received SIGTERM from PID 1 (systemd). Nov 24 00:19:55.846279 systemd[1]: systemd-network-generator.service: Deactivated successfully. Nov 24 00:19:55.846331 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Nov 24 00:19:55.861250 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Nov 24 00:19:55.861301 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 24 00:19:55.874188 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 24 00:19:55.874238 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 24 00:19:55.888266 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Nov 24 00:19:55.888321 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Nov 24 00:19:55.888363 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Nov 24 00:19:55.888690 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Nov 24 00:19:55.888772 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Nov 24 00:19:55.906141 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Nov 24 00:19:55.918624 systemd[1]: Starting initrd-switch-root.service - Switch Root... Nov 24 00:19:55.961176 systemd[1]: Switching root. Nov 24 00:19:56.085985 systemd-journald[213]: Journal stopped Nov 24 00:20:01.464977 kernel: SELinux: policy capability network_peer_controls=1 Nov 24 00:20:01.465024 kernel: SELinux: policy capability open_perms=1 Nov 24 00:20:01.465037 kernel: SELinux: policy capability extended_socket_class=1 Nov 24 00:20:01.465046 kernel: SELinux: policy capability always_check_network=0 Nov 24 00:20:01.465055 kernel: SELinux: policy capability cgroup_seclabel=1 Nov 24 00:20:01.465064 kernel: SELinux: policy capability nnp_nosuid_transition=1 Nov 24 00:20:01.465074 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Nov 24 00:20:01.465086 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Nov 24 00:20:01.465096 kernel: SELinux: policy capability userspace_initial_context=0 Nov 24 00:20:01.465105 kernel: audit: type=1403 audit(1763943597.011:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Nov 24 00:20:01.465116 systemd[1]: Successfully loaded SELinux policy in 341.794ms. Nov 24 00:20:01.465128 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 7.735ms. Nov 24 00:20:01.465139 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) Nov 24 00:20:01.465149 systemd[1]: Detected virtualization microsoft. Nov 24 00:20:01.465163 systemd[1]: Detected architecture x86-64. Nov 24 00:20:01.465173 systemd[1]: Detected first boot. Nov 24 00:20:01.465184 systemd[1]: Hostname set to . Nov 24 00:20:01.465194 systemd[1]: Initializing machine ID from random generator. Nov 24 00:20:01.465204 zram_generator::config[1379]: No configuration found. Nov 24 00:20:01.465218 kernel: Guest personality initialized and is inactive Nov 24 00:20:01.465228 kernel: VMCI host device registered (name=vmci, major=10, minor=259) Nov 24 00:20:01.465244 kernel: Initialized host personality Nov 24 00:20:01.465254 kernel: NET: Registered PF_VSOCK protocol family Nov 24 00:20:01.465265 systemd[1]: Populated /etc with preset unit settings. Nov 24 00:20:01.465276 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Nov 24 00:20:01.465286 systemd[1]: initrd-switch-root.service: Deactivated successfully. Nov 24 00:20:01.465300 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Nov 24 00:20:01.465310 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Nov 24 00:20:01.465321 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Nov 24 00:20:01.465332 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Nov 24 00:20:01.465342 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Nov 24 00:20:01.465353 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Nov 24 00:20:01.465364 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Nov 24 00:20:01.465374 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Nov 24 00:20:01.465387 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Nov 24 00:20:01.465398 systemd[1]: Created slice user.slice - User and Session Slice. Nov 24 00:20:01.465408 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 24 00:20:01.465419 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 24 00:20:01.465430 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Nov 24 00:20:01.465440 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Nov 24 00:20:01.465455 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Nov 24 00:20:01.465466 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 24 00:20:01.465483 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Nov 24 00:20:01.465494 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 24 00:20:01.465505 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 24 00:20:01.465516 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Nov 24 00:20:01.465527 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Nov 24 00:20:01.465538 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Nov 24 00:20:01.465549 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Nov 24 00:20:01.465562 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 24 00:20:01.465573 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 24 00:20:01.465584 systemd[1]: Reached target slices.target - Slice Units. Nov 24 00:20:01.465595 systemd[1]: Reached target swap.target - Swaps. Nov 24 00:20:01.465605 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Nov 24 00:20:01.465616 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Nov 24 00:20:01.465630 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Nov 24 00:20:01.465641 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 24 00:20:01.465652 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 24 00:20:01.465663 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 24 00:20:01.465674 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Nov 24 00:20:01.465685 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Nov 24 00:20:01.465696 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Nov 24 00:20:01.465709 systemd[1]: Mounting media.mount - External Media Directory... Nov 24 00:20:01.465724 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 24 00:20:01.465735 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Nov 24 00:20:01.465746 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Nov 24 00:20:01.465757 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Nov 24 00:20:01.465768 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). Nov 24 00:20:01.465779 systemd[1]: Reached target machines.target - Containers. Nov 24 00:20:01.465790 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Nov 24 00:20:01.465802 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 24 00:20:01.465815 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 24 00:20:01.465827 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Nov 24 00:20:01.465838 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 24 00:20:01.465848 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 24 00:20:01.465860 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 24 00:20:01.465871 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Nov 24 00:20:01.465881 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 24 00:20:01.465892 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Nov 24 00:20:01.465906 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Nov 24 00:20:01.465917 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Nov 24 00:20:01.465928 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Nov 24 00:20:01.465939 systemd[1]: Stopped systemd-fsck-usr.service. Nov 24 00:20:01.465951 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). Nov 24 00:20:01.465965 kernel: loop: module loaded Nov 24 00:20:01.465976 kernel: fuse: init (API version 7.41) Nov 24 00:20:01.465986 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 24 00:20:01.466010 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 24 00:20:01.466022 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 24 00:20:01.466033 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Nov 24 00:20:01.466069 systemd-journald[1486]: Collecting audit messages is disabled. Nov 24 00:20:01.466098 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Nov 24 00:20:01.466110 systemd-journald[1486]: Journal started Nov 24 00:20:01.466132 systemd-journald[1486]: Runtime Journal (/run/log/journal/5196175e202647649b8b023e797df55d) is 8M, max 2.2G, 2.1G free. Nov 24 00:20:00.389597 systemd[1]: Queued start job for default target multi-user.target. Nov 24 00:20:00.414330 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Nov 24 00:20:00.414815 systemd[1]: systemd-journald.service: Deactivated successfully. Nov 24 00:20:01.487472 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 24 00:20:01.503765 systemd[1]: verity-setup.service: Deactivated successfully. Nov 24 00:20:01.503807 systemd[1]: Stopped verity-setup.service. Nov 24 00:20:01.522011 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 24 00:20:01.522044 kernel: ACPI: bus type drm_connector registered Nov 24 00:20:01.526009 systemd[1]: Started systemd-journald.service - Journal Service. Nov 24 00:20:01.540749 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Nov 24 00:20:01.547808 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Nov 24 00:20:01.555046 systemd[1]: Mounted media.mount - External Media Directory. Nov 24 00:20:01.560904 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Nov 24 00:20:01.567872 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Nov 24 00:20:01.575340 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Nov 24 00:20:01.582133 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Nov 24 00:20:01.590271 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 24 00:20:01.598822 systemd[1]: modprobe@configfs.service: Deactivated successfully. Nov 24 00:20:01.599027 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Nov 24 00:20:01.607104 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 24 00:20:01.607292 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 24 00:20:01.615208 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 24 00:20:01.615395 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 24 00:20:01.622828 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 24 00:20:01.623032 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 24 00:20:01.631538 systemd[1]: modprobe@fuse.service: Deactivated successfully. Nov 24 00:20:01.631725 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Nov 24 00:20:01.639376 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 24 00:20:01.639562 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 24 00:20:01.646952 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 24 00:20:01.653849 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 24 00:20:01.662957 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Nov 24 00:20:01.674227 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Nov 24 00:20:01.682015 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 24 00:20:01.700024 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 24 00:20:01.708735 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Nov 24 00:20:01.733230 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Nov 24 00:20:01.739963 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Nov 24 00:20:01.740009 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 24 00:20:01.747849 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Nov 24 00:20:01.756709 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Nov 24 00:20:01.763839 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 24 00:20:01.778075 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Nov 24 00:20:01.786062 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Nov 24 00:20:01.793340 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 24 00:20:01.794245 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Nov 24 00:20:01.801190 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 24 00:20:01.802177 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 24 00:20:01.809846 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Nov 24 00:20:01.818704 systemd[1]: Starting systemd-sysusers.service - Create System Users... Nov 24 00:20:01.819214 systemd-journald[1486]: Time spent on flushing to /var/log/journal/5196175e202647649b8b023e797df55d is 14.115ms for 951 entries. Nov 24 00:20:01.819214 systemd-journald[1486]: System Journal (/var/log/journal/5196175e202647649b8b023e797df55d) is 8M, max 2.6G, 2.6G free. Nov 24 00:20:01.888570 systemd-journald[1486]: Received client request to flush runtime journal. Nov 24 00:20:01.834851 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Nov 24 00:20:01.842892 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Nov 24 00:20:01.851490 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Nov 24 00:20:01.862092 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Nov 24 00:20:01.871329 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Nov 24 00:20:01.890599 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Nov 24 00:20:01.900259 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 24 00:20:01.932755 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Nov 24 00:20:01.933427 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Nov 24 00:20:01.942378 systemd[1]: Finished systemd-sysusers.service - Create System Users. Nov 24 00:20:01.953409 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 24 00:20:01.971016 kernel: loop0: detected capacity change from 0 to 110984 Nov 24 00:20:02.018946 systemd-tmpfiles[1536]: ACLs are not supported, ignoring. Nov 24 00:20:02.018968 systemd-tmpfiles[1536]: ACLs are not supported, ignoring. Nov 24 00:20:02.022798 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 24 00:20:02.536019 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Nov 24 00:20:02.602020 kernel: loop1: detected capacity change from 0 to 27936 Nov 24 00:20:02.756400 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Nov 24 00:20:02.766006 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 24 00:20:02.811028 systemd-udevd[1543]: Using default interface naming scheme 'v255'. Nov 24 00:20:03.081882 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 24 00:20:03.095377 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 24 00:20:03.116907 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Nov 24 00:20:03.175025 kernel: hv_vmbus: registering driver hyperv_fb Nov 24 00:20:03.187182 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Nov 24 00:20:03.187246 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Nov 24 00:20:03.192329 kernel: Console: switching to colour dummy device 80x25 Nov 24 00:20:03.198600 kernel: hv_vmbus: registering driver hv_balloon Nov 24 00:20:03.198650 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Nov 24 00:20:03.203124 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Nov 24 00:20:03.216384 kernel: Console: switching to colour frame buffer device 128x48 Nov 24 00:20:03.229023 kernel: mousedev: PS/2 mouse device common for all mice Nov 24 00:20:03.241035 kernel: loop2: detected capacity change from 0 to 128560 Nov 24 00:20:03.272634 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 24 00:20:03.279580 systemd[1]: Started systemd-userdbd.service - User Database Manager. Nov 24 00:20:03.291032 kernel: EDAC MC: Ver: 3.0.0 Nov 24 00:20:03.293804 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 24 00:20:03.294044 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 24 00:20:03.305576 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 24 00:20:03.314769 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 24 00:20:03.314961 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 24 00:20:03.328255 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 24 00:20:03.390168 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Nov 24 00:20:03.393953 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Nov 24 00:20:03.400083 systemd-networkd[1574]: lo: Link UP Nov 24 00:20:03.400087 systemd-networkd[1574]: lo: Gained carrier Nov 24 00:20:03.401396 systemd-networkd[1574]: Enumeration completed Nov 24 00:20:03.401485 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 24 00:20:03.419459 systemd-networkd[1574]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 24 00:20:03.419470 systemd-networkd[1574]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 24 00:20:03.420043 systemd-networkd[1574]: eth0: Link UP Nov 24 00:20:03.420052 systemd-networkd[1574]: eth0: Gained carrier Nov 24 00:20:03.420067 systemd-networkd[1574]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 24 00:20:03.420112 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Nov 24 00:20:03.423598 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Nov 24 00:20:03.473591 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Nov 24 00:20:03.509040 systemd-networkd[1574]: eth0: DHCPv4 address 10.200.0.12/24, gateway 10.200.0.1 acquired from 168.63.129.16 Nov 24 00:20:03.519404 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Nov 24 00:20:03.634016 kernel: loop3: detected capacity change from 0 to 110984 Nov 24 00:20:03.660021 kernel: loop4: detected capacity change from 0 to 27936 Nov 24 00:20:03.676014 kernel: loop5: detected capacity change from 0 to 128560 Nov 24 00:20:03.687728 (sd-merge)[1700]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Nov 24 00:20:03.688242 (sd-merge)[1700]: Merged extensions into '/usr'. Nov 24 00:20:03.691700 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Nov 24 00:20:03.695594 systemd[1]: Starting ensure-sysext.service... Nov 24 00:20:03.697432 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 24 00:20:03.746686 systemd[1]: Reload requested from client PID 1702 ('systemctl') (unit ensure-sysext.service)... Nov 24 00:20:03.746703 systemd[1]: Reloading... Nov 24 00:20:03.756151 systemd-tmpfiles[1703]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Nov 24 00:20:03.756191 systemd-tmpfiles[1703]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Nov 24 00:20:03.756468 systemd-tmpfiles[1703]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Nov 24 00:20:03.756792 systemd-tmpfiles[1703]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Nov 24 00:20:03.757806 systemd-tmpfiles[1703]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Nov 24 00:20:03.758222 systemd-tmpfiles[1703]: ACLs are not supported, ignoring. Nov 24 00:20:03.758290 systemd-tmpfiles[1703]: ACLs are not supported, ignoring. Nov 24 00:20:03.763242 systemd-tmpfiles[1703]: Detected autofs mount point /boot during canonicalization of boot. Nov 24 00:20:03.763255 systemd-tmpfiles[1703]: Skipping /boot Nov 24 00:20:03.772311 systemd-tmpfiles[1703]: Detected autofs mount point /boot during canonicalization of boot. Nov 24 00:20:03.772324 systemd-tmpfiles[1703]: Skipping /boot Nov 24 00:20:03.798020 zram_generator::config[1737]: No configuration found. Nov 24 00:20:04.074850 systemd[1]: Reloading finished in 327 ms. Nov 24 00:20:04.111659 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 24 00:20:04.141698 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 24 00:20:04.162477 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 24 00:20:04.163697 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 24 00:20:04.171473 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Nov 24 00:20:04.179403 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 24 00:20:04.180542 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 24 00:20:04.188260 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 24 00:20:04.196468 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 24 00:20:04.199021 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 24 00:20:04.199145 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). Nov 24 00:20:04.200271 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Nov 24 00:20:04.207557 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 24 00:20:04.218073 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Nov 24 00:20:04.225852 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 24 00:20:04.228380 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 24 00:20:04.228592 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 24 00:20:04.236613 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 24 00:20:04.236795 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 24 00:20:04.244911 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 24 00:20:04.245123 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 24 00:20:04.257915 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 24 00:20:04.258188 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 24 00:20:04.265976 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 24 00:20:04.274133 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 24 00:20:04.282296 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 24 00:20:04.287415 augenrules[1832]: No rules Nov 24 00:20:04.288686 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 24 00:20:04.288877 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). Nov 24 00:20:04.289062 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 24 00:20:04.293017 systemd[1]: audit-rules.service: Deactivated successfully. Nov 24 00:20:04.293249 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 24 00:20:04.299980 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 24 00:20:04.300175 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 24 00:20:04.307573 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 24 00:20:04.307753 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 24 00:20:04.315625 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 24 00:20:04.315805 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 24 00:20:04.324372 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Nov 24 00:20:04.337954 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 24 00:20:04.339157 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 24 00:20:04.344874 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 24 00:20:04.360702 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 24 00:20:04.368745 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 24 00:20:04.375738 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 24 00:20:04.382393 augenrules[1842]: /sbin/augenrules: No change Nov 24 00:20:04.383585 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 24 00:20:04.384113 augenrules[1863]: No rules Nov 24 00:20:04.384420 systemd-resolved[1810]: Positive Trust Anchors: Nov 24 00:20:04.384429 systemd-resolved[1810]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 24 00:20:04.384461 systemd-resolved[1810]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Nov 24 00:20:04.389696 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 24 00:20:04.389825 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). Nov 24 00:20:04.390031 systemd[1]: Reached target time-set.target - System Time Set. Nov 24 00:20:04.396352 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 24 00:20:04.398338 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Nov 24 00:20:04.400940 systemd-resolved[1810]: Using system hostname 'ci-4459.1.2-a-ae1deb39ef'. Nov 24 00:20:04.406964 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 24 00:20:04.413972 systemd[1]: audit-rules.service: Deactivated successfully. Nov 24 00:20:04.414210 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 24 00:20:04.420641 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 24 00:20:04.420820 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 24 00:20:04.428249 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 24 00:20:04.428446 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 24 00:20:04.435633 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 24 00:20:04.435811 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 24 00:20:04.445973 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 24 00:20:04.446176 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 24 00:20:04.456820 systemd[1]: Finished ensure-sysext.service. Nov 24 00:20:04.465719 systemd[1]: Reached target network.target - Network. Nov 24 00:20:04.471459 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 24 00:20:04.479356 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 24 00:20:04.479416 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 24 00:20:04.851120 systemd-networkd[1574]: eth0: Gained IPv6LL Nov 24 00:20:04.853630 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Nov 24 00:20:04.861597 systemd[1]: Reached target network-online.target - Network is Online. Nov 24 00:20:04.924126 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Nov 24 00:20:04.932807 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). Nov 24 00:20:09.429354 ldconfig[1516]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Nov 24 00:20:09.450650 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Nov 24 00:20:09.457244 systemd[1]: Starting systemd-update-done.service - Update is Completed... Nov 24 00:20:09.490881 systemd[1]: Finished systemd-update-done.service - Update is Completed. Nov 24 00:20:09.495643 systemd[1]: Reached target sysinit.target - System Initialization. Nov 24 00:20:09.500195 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Nov 24 00:20:09.505240 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Nov 24 00:20:09.510431 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Nov 24 00:20:09.515325 systemd[1]: Started logrotate.timer - Daily rotation of log files. Nov 24 00:20:09.519761 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Nov 24 00:20:09.524917 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Nov 24 00:20:09.530063 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Nov 24 00:20:09.530103 systemd[1]: Reached target paths.target - Path Units. Nov 24 00:20:09.533813 systemd[1]: Reached target timers.target - Timer Units. Nov 24 00:20:09.538693 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Nov 24 00:20:09.544324 systemd[1]: Starting docker.socket - Docker Socket for the API... Nov 24 00:20:09.550354 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Nov 24 00:20:09.555754 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Nov 24 00:20:09.561300 systemd[1]: Reached target ssh-access.target - SSH Access Available. Nov 24 00:20:09.567359 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Nov 24 00:20:09.571645 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Nov 24 00:20:09.576976 systemd[1]: Listening on docker.socket - Docker Socket for the API. Nov 24 00:20:09.582277 systemd[1]: Reached target sockets.target - Socket Units. Nov 24 00:20:09.586123 systemd[1]: Reached target basic.target - Basic System. Nov 24 00:20:09.589877 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Nov 24 00:20:09.589919 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Nov 24 00:20:09.592348 systemd[1]: Starting chronyd.service - NTP client/server... Nov 24 00:20:09.596647 systemd[1]: Starting containerd.service - containerd container runtime... Nov 24 00:20:09.605808 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Nov 24 00:20:09.610761 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Nov 24 00:20:09.615295 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Nov 24 00:20:09.620706 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Nov 24 00:20:09.625659 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Nov 24 00:20:09.629077 jq[1891]: false Nov 24 00:20:09.629767 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). Nov 24 00:20:09.630731 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Nov 24 00:20:09.634794 systemd[1]: hv_fcopy_uio_daemon.service - Hyper-V FCOPY UIO daemon was skipped because of an unmet condition check (ConditionPathExists=/sys/bus/vmbus/devices/eb765408-105f-49b6-b4aa-c123b64d17d4/uio). Nov 24 00:20:09.635824 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Nov 24 00:20:09.640015 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Nov 24 00:20:09.640941 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Nov 24 00:20:09.645653 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Nov 24 00:20:09.650832 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Nov 24 00:20:09.656358 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Nov 24 00:20:09.662838 systemd[1]: Starting systemd-logind.service - User Login Management... Nov 24 00:20:09.663539 KVP[1894]: KVP starting; pid is:1894 Nov 24 00:20:09.666455 KVP[1894]: KVP LIC Version: 3.1 Nov 24 00:20:09.668040 kernel: hv_utils: KVP IC version 4.0 Nov 24 00:20:09.668494 google_oslogin_nss_cache[1893]: oslogin_cache_refresh[1893]: Refreshing passwd entry cache Nov 24 00:20:09.668503 oslogin_cache_refresh[1893]: Refreshing passwd entry cache Nov 24 00:20:09.669524 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Nov 24 00:20:09.669958 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Nov 24 00:20:09.670374 chronyd[1883]: chronyd version 4.7 starting (+CMDMON +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +NTS +SECHASH +IPV6 -DEBUG) Nov 24 00:20:09.671174 systemd[1]: Starting update-engine.service - Update Engine... Nov 24 00:20:09.677089 google_oslogin_nss_cache[1893]: oslogin_cache_refresh[1893]: Failure getting users, quitting Nov 24 00:20:09.677089 google_oslogin_nss_cache[1893]: oslogin_cache_refresh[1893]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Nov 24 00:20:09.677078 oslogin_cache_refresh[1893]: Failure getting users, quitting Nov 24 00:20:09.677213 google_oslogin_nss_cache[1893]: oslogin_cache_refresh[1893]: Refreshing group entry cache Nov 24 00:20:09.677094 oslogin_cache_refresh[1893]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Nov 24 00:20:09.677135 oslogin_cache_refresh[1893]: Refreshing group entry cache Nov 24 00:20:09.678301 extend-filesystems[1892]: Found /dev/sda6 Nov 24 00:20:09.680112 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Nov 24 00:20:09.682988 google_oslogin_nss_cache[1893]: oslogin_cache_refresh[1893]: Failure getting groups, quitting Nov 24 00:20:09.682988 google_oslogin_nss_cache[1893]: oslogin_cache_refresh[1893]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Nov 24 00:20:09.682978 oslogin_cache_refresh[1893]: Failure getting groups, quitting Nov 24 00:20:09.683017 oslogin_cache_refresh[1893]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Nov 24 00:20:09.688869 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Nov 24 00:20:09.690111 jq[1915]: true Nov 24 00:20:09.694847 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Nov 24 00:20:09.695078 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Nov 24 00:20:09.695367 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Nov 24 00:20:09.695577 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Nov 24 00:20:09.700499 systemd[1]: motdgen.service: Deactivated successfully. Nov 24 00:20:09.700714 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Nov 24 00:20:09.701845 extend-filesystems[1892]: Found /dev/sda9 Nov 24 00:20:09.707756 extend-filesystems[1892]: Checking size of /dev/sda9 Nov 24 00:20:09.702462 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Nov 24 00:20:09.702665 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Nov 24 00:20:09.716524 chronyd[1883]: Timezone right/UTC failed leap second check, ignoring Nov 24 00:20:09.716903 chronyd[1883]: Loaded seccomp filter (level 2) Nov 24 00:20:09.717108 systemd[1]: Started chronyd.service - NTP client/server. Nov 24 00:20:09.717643 (ntainerd)[1922]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Nov 24 00:20:09.720096 jq[1921]: true Nov 24 00:20:09.761651 extend-filesystems[1892]: Old size kept for /dev/sda9 Nov 24 00:20:09.762678 systemd[1]: extend-filesystems.service: Deactivated successfully. Nov 24 00:20:09.762957 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Nov 24 00:20:09.803609 systemd-logind[1904]: New seat seat0. Nov 24 00:20:09.804657 systemd-logind[1904]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Nov 24 00:20:09.804839 systemd[1]: Started systemd-logind.service - User Login Management. Nov 24 00:20:09.818172 update_engine[1908]: I20251124 00:20:09.817773 1908 main.cc:92] Flatcar Update Engine starting Nov 24 00:20:09.851825 setup-nvidia[1899]: Downloading Flatcar Container Linux Developer Container for version: 4459.1.2 Nov 24 00:20:09.967473 bash[1947]: Updated "/home/core/.ssh/authorized_keys" Nov 24 00:20:09.969066 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Nov 24 00:20:09.971915 sshd_keygen[1909]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Nov 24 00:20:09.975050 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Nov 24 00:20:09.992593 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Nov 24 00:20:09.998729 systemd[1]: Starting issuegen.service - Generate /run/issue... Nov 24 00:20:09.999302 dbus-daemon[1886]: [system] SELinux support is enabled Nov 24 00:20:10.002099 update_engine[1908]: I20251124 00:20:10.002041 1908 update_check_scheduler.cc:74] Next update check in 11m11s Nov 24 00:20:10.012729 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Nov 24 00:20:10.016920 systemd[1]: Started dbus.service - D-Bus System Message Bus. Nov 24 00:20:10.023761 systemd[1]: issuegen.service: Deactivated successfully. Nov 24 00:20:10.024022 systemd[1]: Finished issuegen.service - Generate /run/issue. Nov 24 00:20:10.030071 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). Nov 24 00:20:10.030107 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Nov 24 00:20:10.030849 dbus-daemon[1886]: [system] Successfully activated service 'org.freedesktop.systemd1' Nov 24 00:20:10.039590 coreos-metadata[1885]: Nov 24 00:20:10.039 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Nov 24 00:20:10.041865 coreos-metadata[1885]: Nov 24 00:20:10.041 INFO Fetch successful Nov 24 00:20:10.042044 coreos-metadata[1885]: Nov 24 00:20:10.042 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Nov 24 00:20:10.046582 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Nov 24 00:20:10.050813 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). Nov 24 00:20:10.050847 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Nov 24 00:20:10.056560 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Nov 24 00:20:10.062499 systemd[1]: Started update-engine.service - Update Engine. Nov 24 00:20:10.068187 systemd[1]: Started locksmithd.service - Cluster reboot manager. Nov 24 00:20:10.072843 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Nov 24 00:20:10.077534 coreos-metadata[1885]: Nov 24 00:20:10.077 INFO Fetch successful Nov 24 00:20:10.077585 coreos-metadata[1885]: Nov 24 00:20:10.077 INFO Fetching http://168.63.129.16/machine/16baf30a-3ade-4b40-862e-b0c016cc0756/ca8cc12b%2D7aad%2D4498%2D876f%2De98a05473825.%5Fci%2D4459.1.2%2Da%2Dae1deb39ef?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Nov 24 00:20:10.079398 systemd[1]: Started getty@tty1.service - Getty on tty1. Nov 24 00:20:10.084312 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Nov 24 00:20:10.089075 systemd[1]: Reached target getty.target - Login Prompts. Nov 24 00:20:10.101454 coreos-metadata[1885]: Nov 24 00:20:10.101 INFO Fetch successful Nov 24 00:20:10.101626 coreos-metadata[1885]: Nov 24 00:20:10.101 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Nov 24 00:20:10.108922 coreos-metadata[1885]: Nov 24 00:20:10.108 INFO Fetch successful Nov 24 00:20:10.140584 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Nov 24 00:20:10.145374 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Nov 24 00:20:10.220589 locksmithd[2052]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Nov 24 00:20:10.578382 setup-nvidia[2070]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 24 00:20:10.578728 setup-nvidia[2070]: Dload Upload Total Spent Left Speed Nov 24 00:20:11.341848 containerd[1922]: time="2025-11-24T00:20:11Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Nov 24 00:20:11.342485 containerd[1922]: time="2025-11-24T00:20:11.342452700Z" level=info msg="starting containerd" revision=4ac6c20c7bbf8177f29e46bbdc658fec02ffb8ad version=v2.0.7 Nov 24 00:20:11.351474 containerd[1922]: time="2025-11-24T00:20:11.351409100Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="8.3µs" Nov 24 00:20:11.351474 containerd[1922]: time="2025-11-24T00:20:11.351438700Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Nov 24 00:20:11.351474 containerd[1922]: time="2025-11-24T00:20:11.351456900Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Nov 24 00:20:11.351618 containerd[1922]: time="2025-11-24T00:20:11.351595100Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Nov 24 00:20:11.351650 containerd[1922]: time="2025-11-24T00:20:11.351618200Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Nov 24 00:20:11.351650 containerd[1922]: time="2025-11-24T00:20:11.351642500Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Nov 24 00:20:11.351719 containerd[1922]: time="2025-11-24T00:20:11.351697900Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Nov 24 00:20:11.351719 containerd[1922]: time="2025-11-24T00:20:11.351715900Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Nov 24 00:20:11.351919 containerd[1922]: time="2025-11-24T00:20:11.351896900Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Nov 24 00:20:11.351919 containerd[1922]: time="2025-11-24T00:20:11.351916500Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Nov 24 00:20:11.351964 containerd[1922]: time="2025-11-24T00:20:11.351928100Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Nov 24 00:20:11.351964 containerd[1922]: time="2025-11-24T00:20:11.351936400Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Nov 24 00:20:11.352063 containerd[1922]: time="2025-11-24T00:20:11.352041400Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Nov 24 00:20:11.352283 containerd[1922]: time="2025-11-24T00:20:11.352262600Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Nov 24 00:20:11.352323 containerd[1922]: time="2025-11-24T00:20:11.352297600Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Nov 24 00:20:11.352323 containerd[1922]: time="2025-11-24T00:20:11.352308000Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Nov 24 00:20:11.352372 containerd[1922]: time="2025-11-24T00:20:11.352336500Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Nov 24 00:20:11.352525 containerd[1922]: time="2025-11-24T00:20:11.352503300Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Nov 24 00:20:11.352589 containerd[1922]: time="2025-11-24T00:20:11.352571000Z" level=info msg="metadata content store policy set" policy=shared Nov 24 00:20:11.365754 containerd[1922]: time="2025-11-24T00:20:11.365720100Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Nov 24 00:20:11.365832 containerd[1922]: time="2025-11-24T00:20:11.365764900Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Nov 24 00:20:11.365832 containerd[1922]: time="2025-11-24T00:20:11.365782000Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Nov 24 00:20:11.365832 containerd[1922]: time="2025-11-24T00:20:11.365808900Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Nov 24 00:20:11.365832 containerd[1922]: time="2025-11-24T00:20:11.365826100Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Nov 24 00:20:11.365922 containerd[1922]: time="2025-11-24T00:20:11.365838500Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Nov 24 00:20:11.365922 containerd[1922]: time="2025-11-24T00:20:11.365851400Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Nov 24 00:20:11.365922 containerd[1922]: time="2025-11-24T00:20:11.365862300Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Nov 24 00:20:11.365922 containerd[1922]: time="2025-11-24T00:20:11.365871900Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Nov 24 00:20:11.365922 containerd[1922]: time="2025-11-24T00:20:11.365881300Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Nov 24 00:20:11.365922 containerd[1922]: time="2025-11-24T00:20:11.365889600Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Nov 24 00:20:11.366061 containerd[1922]: time="2025-11-24T00:20:11.365927100Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Nov 24 00:20:11.366084 containerd[1922]: time="2025-11-24T00:20:11.366058100Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Nov 24 00:20:11.366084 containerd[1922]: time="2025-11-24T00:20:11.366078200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Nov 24 00:20:11.366134 containerd[1922]: time="2025-11-24T00:20:11.366090800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Nov 24 00:20:11.366134 containerd[1922]: time="2025-11-24T00:20:11.366102700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Nov 24 00:20:11.366178 containerd[1922]: time="2025-11-24T00:20:11.366136300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Nov 24 00:20:11.366178 containerd[1922]: time="2025-11-24T00:20:11.366151100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Nov 24 00:20:11.366178 containerd[1922]: time="2025-11-24T00:20:11.366161600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Nov 24 00:20:11.366178 containerd[1922]: time="2025-11-24T00:20:11.366170900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Nov 24 00:20:11.366267 containerd[1922]: time="2025-11-24T00:20:11.366189800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Nov 24 00:20:11.366267 containerd[1922]: time="2025-11-24T00:20:11.366204600Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Nov 24 00:20:11.366267 containerd[1922]: time="2025-11-24T00:20:11.366215000Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Nov 24 00:20:11.366334 containerd[1922]: time="2025-11-24T00:20:11.366276100Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Nov 24 00:20:11.366334 containerd[1922]: time="2025-11-24T00:20:11.366293000Z" level=info msg="Start snapshots syncer" Nov 24 00:20:11.366334 containerd[1922]: time="2025-11-24T00:20:11.366321300Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Nov 24 00:20:11.366600 containerd[1922]: time="2025-11-24T00:20:11.366557500Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Nov 24 00:20:11.366716 containerd[1922]: time="2025-11-24T00:20:11.366625600Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Nov 24 00:20:11.366716 containerd[1922]: time="2025-11-24T00:20:11.366667600Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Nov 24 00:20:11.366785 containerd[1922]: time="2025-11-24T00:20:11.366766700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Nov 24 00:20:11.366814 containerd[1922]: time="2025-11-24T00:20:11.366789300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Nov 24 00:20:11.366814 containerd[1922]: time="2025-11-24T00:20:11.366799400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Nov 24 00:20:11.366814 containerd[1922]: time="2025-11-24T00:20:11.366809100Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Nov 24 00:20:11.366925 containerd[1922]: time="2025-11-24T00:20:11.366821800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Nov 24 00:20:11.366925 containerd[1922]: time="2025-11-24T00:20:11.366832700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Nov 24 00:20:11.366925 containerd[1922]: time="2025-11-24T00:20:11.366842300Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Nov 24 00:20:11.366925 containerd[1922]: time="2025-11-24T00:20:11.366861000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Nov 24 00:20:11.366925 containerd[1922]: time="2025-11-24T00:20:11.366871300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Nov 24 00:20:11.366925 containerd[1922]: time="2025-11-24T00:20:11.366880400Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Nov 24 00:20:11.366925 containerd[1922]: time="2025-11-24T00:20:11.366906100Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Nov 24 00:20:11.366925 containerd[1922]: time="2025-11-24T00:20:11.366921300Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Nov 24 00:20:11.367111 containerd[1922]: time="2025-11-24T00:20:11.366929900Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Nov 24 00:20:11.367111 containerd[1922]: time="2025-11-24T00:20:11.366938300Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Nov 24 00:20:11.367111 containerd[1922]: time="2025-11-24T00:20:11.366964300Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Nov 24 00:20:11.367111 containerd[1922]: time="2025-11-24T00:20:11.366983900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Nov 24 00:20:11.367111 containerd[1922]: time="2025-11-24T00:20:11.367018800Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Nov 24 00:20:11.367111 containerd[1922]: time="2025-11-24T00:20:11.367036300Z" level=info msg="runtime interface created" Nov 24 00:20:11.367111 containerd[1922]: time="2025-11-24T00:20:11.367041600Z" level=info msg="created NRI interface" Nov 24 00:20:11.367111 containerd[1922]: time="2025-11-24T00:20:11.367049500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Nov 24 00:20:11.367111 containerd[1922]: time="2025-11-24T00:20:11.367059200Z" level=info msg="Connect containerd service" Nov 24 00:20:11.367111 containerd[1922]: time="2025-11-24T00:20:11.367076700Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Nov 24 00:20:11.367737 containerd[1922]: time="2025-11-24T00:20:11.367707900Z" 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" Nov 24 00:20:11.972687 containerd[1922]: time="2025-11-24T00:20:11.972636700Z" level=info msg="Start subscribing containerd event" Nov 24 00:20:11.972806 containerd[1922]: time="2025-11-24T00:20:11.972696700Z" level=info msg="Start recovering state" Nov 24 00:20:11.972835 containerd[1922]: time="2025-11-24T00:20:11.972815000Z" level=info msg="Start event monitor" Nov 24 00:20:11.972876 containerd[1922]: time="2025-11-24T00:20:11.972840600Z" level=info msg="Start cni network conf syncer for default" Nov 24 00:20:11.972876 containerd[1922]: time="2025-11-24T00:20:11.972857300Z" level=info msg="Start streaming server" Nov 24 00:20:11.972876 containerd[1922]: time="2025-11-24T00:20:11.972866900Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Nov 24 00:20:11.972876 containerd[1922]: time="2025-11-24T00:20:11.972873900Z" level=info msg="runtime interface starting up..." Nov 24 00:20:11.972971 containerd[1922]: time="2025-11-24T00:20:11.972879900Z" level=info msg="starting plugins..." Nov 24 00:20:11.972971 containerd[1922]: time="2025-11-24T00:20:11.972893800Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Nov 24 00:20:11.973601 containerd[1922]: time="2025-11-24T00:20:11.973345400Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Nov 24 00:20:11.973601 containerd[1922]: time="2025-11-24T00:20:11.973424200Z" level=info msg=serving... address=/run/containerd/containerd.sock Nov 24 00:20:11.973605 systemd[1]: Started containerd.service - containerd container runtime. Nov 24 00:20:11.974077 containerd[1922]: time="2025-11-24T00:20:11.974042300Z" level=info msg="containerd successfully booted in 0.632779s" Nov 24 00:20:12.401083 waagent[2050]: 2025-11-24T00:20:12.400945Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Nov 24 00:20:12.405276 waagent[2050]: 2025-11-24T00:20:12.405228Z INFO Daemon Daemon OS: flatcar 4459.1.2 Nov 24 00:20:12.408640 waagent[2050]: 2025-11-24T00:20:12.408595Z INFO Daemon Daemon Python: 3.11.13 Nov 24 00:20:12.411850 waagent[2050]: 2025-11-24T00:20:12.411781Z INFO Daemon Daemon Run daemon Nov 24 00:20:12.414795 waagent[2050]: 2025-11-24T00:20:12.414743Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4459.1.2' Nov 24 00:20:12.421322 waagent[2050]: 2025-11-24T00:20:12.421273Z INFO Daemon Daemon Using waagent for provisioning Nov 24 00:20:12.425172 waagent[2050]: 2025-11-24T00:20:12.425131Z INFO Daemon Daemon Activate resource disk Nov 24 00:20:12.428584 waagent[2050]: 2025-11-24T00:20:12.428535Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Nov 24 00:20:12.435859 waagent[2050]: 2025-11-24T00:20:12.435818Z INFO Daemon Daemon Found device: sdb Nov 24 00:20:12.444654 waagent[2050]: 2025-11-24T00:20:12.444607Z INFO Daemon Daemon Examining partition table Nov 24 00:20:12.498912 waagent[2050]: 2025-11-24T00:20:12.498860Z INFO Daemon Daemon GPT not detected, determining filesystem Nov 24 00:20:12.528208 waagent[2050]: 2025-11-24T00:20:12.528158Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Nov 24 00:20:12.532753 waagent[2050]: 2025-11-24T00:20:12.532708Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Nov 24 00:20:13.579883 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#475 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 24 00:20:13.591019 kernel: sdb: sdb1 Nov 24 00:20:13.602017 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#31 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 24 00:20:13.618013 kernel: sdb: sdb1 Nov 24 00:20:13.826945 waagent[2050]: 2025-11-24T00:20:13.826852Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Nov 24 00:20:13.846020 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#219 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 24 00:20:13.866713 waagent[2050]: 2025-11-24T00:20:13.866659Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Nov 24 00:20:13.867013 kernel: sdb: sdb1 Nov 24 00:20:18.341417 login[2055]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Nov 24 00:20:18.341704 login[2054]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Nov 24 00:20:18.348023 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Nov 24 00:20:18.349060 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Nov 24 00:20:18.355522 systemd-logind[1904]: New session 1 of user core. Nov 24 00:20:18.451426 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Nov 24 00:20:18.453987 systemd[1]: Starting user@500.service - User Manager for UID 500... Nov 24 00:20:18.536656 (systemd)[2167]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Nov 24 00:20:18.538946 systemd-logind[1904]: New session c1 of user core. Nov 24 00:20:19.343272 login[2055]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Nov 24 00:20:19.347703 systemd-logind[1904]: New session 2 of user core. Nov 24 00:20:19.675546 waagent[2050]: 2025-11-24T00:20:19.675476Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Nov 24 00:20:19.755835 systemd[2167]: Queued start job for default target default.target. Nov 24 00:20:19.774013 kernel: EXT4-fs (sdb1): mounted filesystem a2358847-ea5d-4902-b65a-fecbfef1fe70 r/w with ordered data mode. Quota mode: none. Nov 24 00:20:19.774103 waagent[2050]: 2025-11-24T00:20:19.774039Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Nov 24 00:20:19.814624 waagent[2050]: 2025-11-24T00:20:19.776297Z INFO Daemon Daemon Clean protocol and wireserver endpoint Nov 24 00:20:19.814624 waagent[2050]: 2025-11-24T00:20:19.776577Z INFO Daemon Daemon Running default provisioning handler Nov 24 00:20:19.814624 waagent[2050]: 2025-11-24T00:20:19.784146Z 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. Nov 24 00:20:19.814624 waagent[2050]: 2025-11-24T00:20:19.785007Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Nov 24 00:20:19.814624 waagent[2050]: 2025-11-24T00:20:19.785418Z INFO Daemon Daemon cloud-init is enabled: False Nov 24 00:20:19.814624 waagent[2050]: 2025-11-24T00:20:19.786913Z INFO Daemon Daemon Copying ovf-env.xml Nov 24 00:20:19.777942 systemd[2167]: Created slice app.slice - User Application Slice. Nov 24 00:20:19.777979 systemd[2167]: Reached target paths.target - Paths. Nov 24 00:20:19.778042 systemd[2167]: Reached target timers.target - Timers. Nov 24 00:20:19.779284 systemd[2167]: Starting dbus.socket - D-Bus User Message Bus Socket... Nov 24 00:20:19.789584 systemd[2167]: Listening on dbus.socket - D-Bus User Message Bus Socket. Nov 24 00:20:19.789635 systemd[2167]: Reached target sockets.target - Sockets. Nov 24 00:20:19.789686 systemd[2167]: Reached target basic.target - Basic System. Nov 24 00:20:19.789730 systemd[2167]: Reached target default.target - Main User Target. Nov 24 00:20:19.789763 systemd[2167]: Startup finished in 1.244s. Nov 24 00:20:19.789769 systemd[1]: Started user@500.service - User Manager for UID 500. Nov 24 00:20:19.790841 systemd[1]: Started session-1.scope - Session 1 of User core. Nov 24 00:20:19.791548 systemd[1]: Started session-2.scope - Session 2 of User core. Nov 24 00:20:19.893665 waagent[2050]: 2025-11-24T00:20:19.893595Z INFO Daemon Daemon Successfully mounted dvd Nov 24 00:20:19.921525 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Nov 24 00:20:19.921653 waagent[2050]: 2025-11-24T00:20:19.921511Z INFO Daemon Daemon Detect protocol endpoint Nov 24 00:20:19.958045 waagent[2050]: 2025-11-24T00:20:19.923149Z INFO Daemon Daemon Clean protocol and wireserver endpoint Nov 24 00:20:19.958045 waagent[2050]: 2025-11-24T00:20:19.923952Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Nov 24 00:20:19.958045 waagent[2050]: 2025-11-24T00:20:19.924678Z INFO Daemon Daemon Test for route to 168.63.129.16 Nov 24 00:20:19.958045 waagent[2050]: 2025-11-24T00:20:19.925560Z INFO Daemon Daemon Route to 168.63.129.16 exists Nov 24 00:20:19.958045 waagent[2050]: 2025-11-24T00:20:19.926936Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Nov 24 00:20:19.958045 waagent[2050]: 2025-11-24T00:20:19.937223Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Nov 24 00:20:19.958045 waagent[2050]: 2025-11-24T00:20:19.937749Z INFO Daemon Daemon Wire protocol version:2012-11-30 Nov 24 00:20:19.958045 waagent[2050]: 2025-11-24T00:20:19.939135Z INFO Daemon Daemon Server preferred version:2015-04-05 Nov 24 00:20:20.085085 waagent[2050]: 2025-11-24T00:20:20.084981Z INFO Daemon Daemon Initializing goal state during protocol detection Nov 24 00:20:20.090134 waagent[2050]: 2025-11-24T00:20:20.090091Z INFO Daemon Daemon Forcing an update of the goal state. Nov 24 00:20:20.097621 waagent[2050]: 2025-11-24T00:20:20.097572Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Nov 24 00:20:20.126621 waagent[2050]: 2025-11-24T00:20:20.126571Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.177 Nov 24 00:20:20.128578 waagent[2050]: 2025-11-24T00:20:20.128533Z INFO Daemon Nov 24 00:20:20.128916 waagent[2050]: 2025-11-24T00:20:20.128882Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: b3703d5f-c1e1-4603-854d-3d21a326b92e eTag: 14818258978678399792 source: Fabric] Nov 24 00:20:20.130617 waagent[2050]: 2025-11-24T00:20:20.130572Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Nov 24 00:20:20.132111 waagent[2050]: 2025-11-24T00:20:20.132071Z INFO Daemon Nov 24 00:20:20.132567 waagent[2050]: 2025-11-24T00:20:20.132534Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Nov 24 00:20:20.137387 waagent[2050]: 2025-11-24T00:20:20.137350Z INFO Daemon Daemon Downloading artifacts profile blob Nov 24 00:20:20.248127 waagent[2050]: 2025-11-24T00:20:20.247985Z INFO Daemon Downloaded certificate {'thumbprint': '8F907D341136EA788E92A5AAA0116CB4D32B5945', 'hasPrivateKey': True} Nov 24 00:20:20.250170 waagent[2050]: 2025-11-24T00:20:20.250121Z INFO Daemon Fetch goal state completed Nov 24 00:20:20.282329 waagent[2050]: 2025-11-24T00:20:20.282262Z INFO Daemon Daemon Starting provisioning Nov 24 00:20:20.284102 waagent[2050]: 2025-11-24T00:20:20.284057Z INFO Daemon Daemon Handle ovf-env.xml. Nov 24 00:20:20.284843 waagent[2050]: 2025-11-24T00:20:20.284808Z INFO Daemon Daemon Set hostname [ci-4459.1.2-a-ae1deb39ef] Nov 24 00:20:20.337290 waagent[2050]: 2025-11-24T00:20:20.337215Z INFO Daemon Daemon Publish hostname [ci-4459.1.2-a-ae1deb39ef] Nov 24 00:20:20.339015 waagent[2050]: 2025-11-24T00:20:20.338943Z INFO Daemon Daemon Examine /proc/net/route for primary interface Nov 24 00:20:20.339707 waagent[2050]: 2025-11-24T00:20:20.339662Z INFO Daemon Daemon Primary interface is [eth0] Nov 24 00:20:20.357222 systemd-networkd[1574]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 24 00:20:20.357232 systemd-networkd[1574]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 24 00:20:20.357256 systemd-networkd[1574]: eth0: DHCP lease lost Nov 24 00:20:20.357695 waagent[2050]: 2025-11-24T00:20:20.357643Z INFO Daemon Daemon Create user account if not exists Nov 24 00:20:20.359383 waagent[2050]: 2025-11-24T00:20:20.359343Z INFO Daemon Daemon User core already exists, skip useradd Nov 24 00:20:20.360825 waagent[2050]: 2025-11-24T00:20:20.360791Z INFO Daemon Daemon Configure sudoer Nov 24 00:20:20.369178 waagent[2050]: 2025-11-24T00:20:20.369137Z INFO Daemon Daemon Configure sshd Nov 24 00:20:20.383066 waagent[2050]: 2025-11-24T00:20:20.383018Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Nov 24 00:20:20.384703 waagent[2050]: 2025-11-24T00:20:20.384657Z INFO Daemon Daemon Deploy ssh public key. Nov 24 00:20:20.432031 systemd-networkd[1574]: eth0: DHCPv4 address 10.200.0.12/24, gateway 10.200.0.1 acquired from 168.63.129.16 Nov 24 00:20:22.116139 setup-nvidia[2070]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 473M 0 10665 0 0 29987 0 4:36:08 --:--:-- 4:36:08 29957\u000d 10 473M 10 51.4M 0 0 47.4M 0 0:00:09 0:00:01 0:00:08 47.4M\u000d 22 473M 22 105M 0 0 50.4M 0 0:00:09 0:00:02 0:00:07 50.4M\u000d 29 473M 29 138M 0 0 45.1M 0 0:00:10 0:00:03 0:00:07 45.1M\u000d 44 473M 44 208M 0 0 51.2M 0 0:00:09 0:00:04 0:00:05 51.2M\u000d 55 473M 55 260M 0 0 49.5M 0 0:00:09 0:00:05 0:00:04 53.1M\u000d 61 473M 61 289M 0 0 47.5M 0 0:00:09 0:00:06 0:00:03 47.5M\u000d 66 473M 66 313M 0 0 44.3M 0 0:00:10 0:00:07 0:00:03 41.7M\u000d 77 473M 77 369M 0 0 45.6M 0 0:00:10 0:00:08 0:00:02 45.9M\u000d 85 473M 85 403M 0 0 44.4M 0 0:00:10 0:00:09 0:00:01 39.0M\u000d 92 473M 92 437M 0 0 43.3M 0 0:00:10 0:00:10 --:--:-- 36.6M\u000d 98 473M 98 466M 0 0 42.1M 0 0:00:11 0:00:11 --:--:-- 35.5M\u000d100 473M 100 473M 0 0 41.0M 0 0:00:11 0:00:11 --:--:-- 35.9M Nov 24 00:20:25.445128 kernel: hrtimer: interrupt took 3063000 ns Nov 24 00:20:32.096871 setup-nvidia[1899]: Downloading NVIDIA 535.261.03 Driver Nov 24 00:20:32.101094 setup-nvidia[2236]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 24 00:20:32.101336 setup-nvidia[2236]: Dload Upload Total Spent Left Speed Nov 24 00:20:33.504283 chronyd[1883]: Selected source PHC0 Nov 24 00:20:50.459060 waagent[2050]: 2025-11-24T00:20:50.458973Z INFO Daemon Daemon Provisioning complete Nov 24 00:20:50.469391 waagent[2050]: 2025-11-24T00:20:50.469347Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Nov 24 00:20:50.471006 waagent[2050]: 2025-11-24T00:20:50.470954Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Nov 24 00:20:50.471702 waagent[2050]: 2025-11-24T00:20:50.471665Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Nov 24 00:20:50.625566 waagent[2237]: 2025-11-24T00:20:50.625490Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Nov 24 00:20:50.642119 waagent[2237]: 2025-11-24T00:20:50.625629Z INFO ExtHandler ExtHandler OS: flatcar 4459.1.2 Nov 24 00:20:50.642119 waagent[2237]: 2025-11-24T00:20:50.642083Z INFO ExtHandler ExtHandler Python: 3.11.13 Nov 24 00:20:50.642210 waagent[2237]: 2025-11-24T00:20:50.642174Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Nov 24 00:20:50.736621 waagent[2237]: 2025-11-24T00:20:50.736531Z INFO ExtHandler ExtHandler Distro: flatcar-4459.1.2; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.13; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Nov 24 00:20:50.736763 waagent[2237]: 2025-11-24T00:20:50.736723Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Nov 24 00:20:50.736842 waagent[2237]: 2025-11-24T00:20:50.736809Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Nov 24 00:20:50.742775 waagent[2237]: 2025-11-24T00:20:50.742724Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Nov 24 00:20:50.751767 waagent[2237]: 2025-11-24T00:20:50.751727Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.177 Nov 24 00:20:50.752239 waagent[2237]: 2025-11-24T00:20:50.752200Z INFO ExtHandler Nov 24 00:20:50.752323 waagent[2237]: 2025-11-24T00:20:50.752291Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 4733b47c-0103-4900-8955-8341860dc759 eTag: 14818258978678399792 source: Fabric] Nov 24 00:20:50.752648 waagent[2237]: 2025-11-24T00:20:50.752610Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Nov 24 00:20:50.753343 waagent[2237]: 2025-11-24T00:20:50.753297Z INFO ExtHandler Nov 24 00:20:50.753414 waagent[2237]: 2025-11-24T00:20:50.753380Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Nov 24 00:20:50.756641 waagent[2237]: 2025-11-24T00:20:50.756611Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Nov 24 00:20:50.813394 waagent[2237]: 2025-11-24T00:20:50.813335Z INFO ExtHandler Downloaded certificate {'thumbprint': '8F907D341136EA788E92A5AAA0116CB4D32B5945', 'hasPrivateKey': True} Nov 24 00:20:50.813869 waagent[2237]: 2025-11-24T00:20:50.813827Z INFO ExtHandler Fetch goal state completed Nov 24 00:20:50.824528 waagent[2237]: 2025-11-24T00:20:50.824484Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.4.2 1 Jul 2025 (Library: OpenSSL 3.4.2 1 Jul 2025) Nov 24 00:20:50.829181 waagent[2237]: 2025-11-24T00:20:50.829142Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2237 Nov 24 00:20:50.829324 waagent[2237]: 2025-11-24T00:20:50.829289Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Nov 24 00:20:50.829669 waagent[2237]: 2025-11-24T00:20:50.829632Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Nov 24 00:20:50.831400 waagent[2237]: 2025-11-24T00:20:50.831354Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4459.1.2', '', 'Flatcar Container Linux by Kinvolk'] Nov 24 00:20:50.831827 waagent[2237]: 2025-11-24T00:20:50.831789Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4459.1.2', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Nov 24 00:20:50.831981 waagent[2237]: 2025-11-24T00:20:50.831946Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Nov 24 00:20:50.832781 waagent[2237]: 2025-11-24T00:20:50.832714Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Nov 24 00:20:50.925417 waagent[2237]: 2025-11-24T00:20:50.925381Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Nov 24 00:20:50.925587 waagent[2237]: 2025-11-24T00:20:50.925550Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Nov 24 00:20:50.931906 waagent[2237]: 2025-11-24T00:20:50.931874Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Nov 24 00:20:50.938195 systemd[1]: Reload requested from client PID 2252 ('systemctl') (unit waagent.service)... Nov 24 00:20:50.938210 systemd[1]: Reloading... Nov 24 00:20:50.998118 zram_generator::config[2294]: No configuration found. Nov 24 00:20:51.271210 systemd[1]: Reloading finished in 332 ms. Nov 24 00:20:51.291435 waagent[2237]: 2025-11-24T00:20:51.291371Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Nov 24 00:20:51.291549 waagent[2237]: 2025-11-24T00:20:51.291513Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Nov 24 00:20:51.300018 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Nov 24 00:20:51.632049 waagent[2237]: 2025-11-24T00:20:51.631925Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Nov 24 00:20:51.632368 waagent[2237]: 2025-11-24T00:20:51.632280Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: 1. configuration enabled [True], 2. cgroups v1 enabled [False] OR cgroups v2 is in use and v2 resource limiting configuration enabled [False], 3. python supported: [True] Nov 24 00:20:51.633125 waagent[2237]: 2025-11-24T00:20:51.633077Z INFO ExtHandler ExtHandler Starting env monitor service. Nov 24 00:20:51.633507 waagent[2237]: 2025-11-24T00:20:51.633431Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Nov 24 00:20:51.633700 waagent[2237]: 2025-11-24T00:20:51.633659Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Nov 24 00:20:51.634072 waagent[2237]: 2025-11-24T00:20:51.634019Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Nov 24 00:20:51.634120 waagent[2237]: 2025-11-24T00:20:51.634082Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Nov 24 00:20:51.634180 waagent[2237]: 2025-11-24T00:20:51.634140Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Nov 24 00:20:51.634804 waagent[2237]: 2025-11-24T00:20:51.634754Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Nov 24 00:20:51.635028 waagent[2237]: 2025-11-24T00:20:51.634971Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Nov 24 00:20:51.635299 waagent[2237]: 2025-11-24T00:20:51.635239Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Nov 24 00:20:51.635368 waagent[2237]: 2025-11-24T00:20:51.635328Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Nov 24 00:20:51.635669 waagent[2237]: 2025-11-24T00:20:51.635628Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Nov 24 00:20:51.635778 waagent[2237]: 2025-11-24T00:20:51.635735Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Nov 24 00:20:51.636371 waagent[2237]: 2025-11-24T00:20:51.636324Z INFO EnvHandler ExtHandler Configure routes Nov 24 00:20:51.636847 waagent[2237]: 2025-11-24T00:20:51.636813Z INFO EnvHandler ExtHandler Gateway:None Nov 24 00:20:51.636920 waagent[2237]: 2025-11-24T00:20:51.636855Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Nov 24 00:20:51.636920 waagent[2237]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Nov 24 00:20:51.636920 waagent[2237]: eth0 00000000 0100C80A 0003 0 0 1024 00000000 0 0 0 Nov 24 00:20:51.636920 waagent[2237]: eth0 0000C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Nov 24 00:20:51.636920 waagent[2237]: eth0 0100C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Nov 24 00:20:51.636920 waagent[2237]: eth0 10813FA8 0100C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Nov 24 00:20:51.636920 waagent[2237]: eth0 FEA9FEA9 0100C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Nov 24 00:20:51.637523 waagent[2237]: 2025-11-24T00:20:51.637484Z INFO EnvHandler ExtHandler Routes:None Nov 24 00:20:51.641826 waagent[2237]: 2025-11-24T00:20:51.641778Z INFO ExtHandler ExtHandler Nov 24 00:20:51.642115 waagent[2237]: 2025-11-24T00:20:51.642077Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 9e163831-5b2d-49a9-a96d-a15c2f56ccf9 correlation 724872c5-ae52-46b2-916a-2e4bb16a130c created: 2025-11-24T00:18:59.879517Z] Nov 24 00:20:51.643239 waagent[2237]: 2025-11-24T00:20:51.643197Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Nov 24 00:20:51.645035 waagent[2237]: 2025-11-24T00:20:51.644978Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Nov 24 00:20:51.742278 waagent[2237]: 2025-11-24T00:20:51.742231Z WARNING ExtHandler ExtHandler Failed to get firewall packets: 'iptables -w -t security -L OUTPUT --zero OUTPUT -nxv' failed: 2 (iptables v1.8.11 (nf_tables): Illegal option `--numeric' with this command Nov 24 00:20:51.742278 waagent[2237]: Try `iptables -h' or 'iptables --help' for more information.) Nov 24 00:20:51.742639 waagent[2237]: 2025-11-24T00:20:51.742600Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 9AA56693-3381-43D8-B3A5-EF65A4AEA7BA;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Nov 24 00:20:51.775711 waagent[2237]: 2025-11-24T00:20:51.775660Z INFO MonitorHandler ExtHandler Network interfaces: Nov 24 00:20:51.775711 waagent[2237]: Executing ['ip', '-a', '-o', 'link']: Nov 24 00:20:51.775711 waagent[2237]: 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 Nov 24 00:20:51.775711 waagent[2237]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:1e:52:12:3e:86 brd ff:ff:ff:ff:ff:ff Nov 24 00:20:51.775711 waagent[2237]: Executing ['ip', '-4', '-a', '-o', 'address']: Nov 24 00:20:51.775711 waagent[2237]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Nov 24 00:20:51.775711 waagent[2237]: 2: eth0 inet 10.200.0.12/24 metric 1024 brd 10.200.0.255 scope global eth0\ valid_lft forever preferred_lft forever Nov 24 00:20:51.775711 waagent[2237]: Executing ['ip', '-6', '-a', '-o', 'address']: Nov 24 00:20:51.775711 waagent[2237]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Nov 24 00:20:51.775711 waagent[2237]: 2: eth0 inet6 fe80::7e1e:52ff:fe12:3e86/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Nov 24 00:20:51.887145 waagent[2237]: 2025-11-24T00:20:51.887061Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Nov 24 00:20:51.887145 waagent[2237]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Nov 24 00:20:51.887145 waagent[2237]: pkts bytes target prot opt in out source destination Nov 24 00:20:51.887145 waagent[2237]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Nov 24 00:20:51.887145 waagent[2237]: pkts bytes target prot opt in out source destination Nov 24 00:20:51.887145 waagent[2237]: Chain OUTPUT (policy ACCEPT 31 packets, 1612 bytes) Nov 24 00:20:51.887145 waagent[2237]: pkts bytes target prot opt in out source destination Nov 24 00:20:51.887145 waagent[2237]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Nov 24 00:20:51.887145 waagent[2237]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Nov 24 00:20:51.887145 waagent[2237]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Nov 24 00:20:51.890673 waagent[2237]: 2025-11-24T00:20:51.890633Z INFO EnvHandler ExtHandler Current Firewall rules: Nov 24 00:20:51.890673 waagent[2237]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Nov 24 00:20:51.890673 waagent[2237]: pkts bytes target prot opt in out source destination Nov 24 00:20:51.890673 waagent[2237]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Nov 24 00:20:51.890673 waagent[2237]: pkts bytes target prot opt in out source destination Nov 24 00:20:51.890673 waagent[2237]: Chain OUTPUT (policy ACCEPT 45 packets, 2340 bytes) Nov 24 00:20:51.890673 waagent[2237]: pkts bytes target prot opt in out source destination Nov 24 00:20:51.890673 waagent[2237]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Nov 24 00:20:51.890673 waagent[2237]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Nov 24 00:20:51.890673 waagent[2237]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Nov 24 00:20:51.890922 waagent[2237]: 2025-11-24T00:20:51.890888Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Nov 24 00:20:51.891092 waagent[2237]: 2025-11-24T00:20:51.891062Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Nov 24 00:20:55.221942 update_engine[1908]: I20251124 00:20:55.221863 1908 update_attempter.cc:509] Updating boot flags... Nov 24 00:20:55.908240 setup-nvidia[2236]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 325M 0 16144 0 0 19780 0 4:47:58 --:--:-- 4:47:58 19784\u000d 0 325M 0 2049k 0 0 1193k 0 0:04:39 0:00:01 0:04:38 1192k\u000d 4 325M 4 16.0M 0 0 6316k 0 0:00:52 0:00:02 0:00:50 6316k\u000d 9 325M 9 30.0M 0 0 8554k 0 0:00:39 0:00:03 0:00:36 8555k\u000d 13 325M 13 44.1M 0 0 9924k 0 0:00:33 0:00:04 0:00:29 9924k\u000d 17 325M 17 57.9M 0 0 10.4M 0 0:00:31 0:00:05 0:00:26 12.2M\u000d 22 325M 22 72.0M 0 0 10.8M 0 0:00:30 0:00:06 0:00:24 14.2M\u000d 26 325M 26 86.0M 0 0 11.2M 0 0:00:28 0:00:07 0:00:21 13.9M\u000d 30 325M 30 100M 0 0 11.6M 0 0:00:28 0:00:08 0:00:20 13.9M\u000d 34 325M 34 114M 0 0 11.9M 0 0:00:27 0:00:09 0:00:18 13.9M\u000d 39 325M 39 128M 0 0 12.0M 0 0:00:27 0:00:10 0:00:17 13.7M\u000d 43 325M 43 142M 0 0 12.2M 0 0:00:26 0:00:11 0:00:15 14.1M\u000d 48 325M 48 158M 0 0 12.5M 0 0:00:26 0:00:12 0:00:14 14.4M\u000d 53 325M 53 174M 0 0 12.8M 0 0:00:25 0:00:13 0:00:12 14.8M\u000d 57 325M 57 188M 0 0 12.9M 0 0:00:25 0:00:14 0:00:11 14.7M\u000d 62 325M 62 202M 0 0 13.0M 0 0:00:25 0:00:15 0:00:10 15.1M\u000d 66 325M 66 216M 0 0 13.0M 0 0:00:24 0:00:16 0:00:08 14.9M\u000d 71 325M 71 232M 0 0 13.1M 0 0:00:24 0:00:17 0:00:07 14.8M\u000d 75 325M 75 246M 0 0 13.2M 0 0:00:24 0:00:18 0:00:06 14.5M\u000d 80 325M 80 262M 0 0 13.3M 0 0:00:24 0:00:19 0:00:05 14.5M\u000d 84 325M 84 276M 0 0 13.4M 0 0:00:24 0:00:20 0:00:04 14.7M\u000d 89 325M 89 290M 0 0 13.4M 0 0:00:24 0:00:21 0:00:03 14.8M\u000d 93 325M 93 306M 0 0 13.5M 0 0:00:24 0:00:22 0:00:02 14.7M\u000d 98 325M 98 320M 0 0 13.5M 0 0:00:24 0:00:23 0:00:01 14.3M\u000d100 325M 100 325M 0 0 13.6M 0 0:00:23 0:00:23 --:--:-- 15.3M Nov 24 00:20:56.007077 setup-nvidia[1899]: Extract the NVIDIA Driver Installer 535.261.03 Nov 24 00:20:56.007077 setup-nvidia[1899]: /opt/nvidia/workdir/nvidia-workdir / Nov 24 00:20:56.034220 setup-nvidia[2503]: Creating directory NVIDIA-Linux-x86_64-535.261.03 Nov 24 00:20:56.353855 setup-nvidia[2503]: Verifying archive integrity... OK Nov 24 00:20:59.323761 setup-nvidia[2503]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.261.03 Nov 24 00:21:02.370260 setup-nvidia[2529]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Nov 24 00:21:07.507736 setup-nvidia[1899]: / Nov 24 00:21:07.507736 setup-nvidia[1899]: Spawn system-nspawn container to install the NVIDIA drivers Nov 24 00:21:08.166874 sudo[2536]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4459.1.2.bin --overlay=/usr/lib/modules::/usr/lib/modules --bind=/opt/nvidia/workdir/nvidia-workdir:/nvidia --bind=/usr/lib/nvidia/bin:/app/bin/ /app/bin/install-nvidia NVIDIA-Linux-x86_64-535.261.03 Nov 24 00:21:08.168619 sudo[2536]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Nov 24 00:21:08.174272 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Nov 24 00:21:08.177473 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Nov 24 00:21:08.214250 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Nov 24 00:21:08.218371 systemd[1]: Starting user@0.service - User Manager for UID 0... Nov 24 00:21:08.241887 (systemd)[2539]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Nov 24 00:21:08.244090 systemd-logind[1904]: New session c3 of user root. Nov 24 00:21:08.420353 systemd[2539]: Queued start job for default target default.target. Nov 24 00:21:08.427029 systemd[2539]: Created slice app.slice - User Application Slice. Nov 24 00:21:08.427052 systemd[2539]: Reached target paths.target - Paths. Nov 24 00:21:08.427098 systemd[2539]: Reached target timers.target - Timers. Nov 24 00:21:08.428273 systemd[2539]: Starting dbus.socket - D-Bus User Message Bus Socket... Nov 24 00:21:08.438797 systemd[2539]: Listening on dbus.socket - D-Bus User Message Bus Socket. Nov 24 00:21:08.438849 systemd[2539]: Reached target sockets.target - Sockets. Nov 24 00:21:08.438899 systemd[2539]: Reached target basic.target - Basic System. Nov 24 00:21:08.438943 systemd[2539]: Reached target default.target - Main User Target. Nov 24 00:21:08.438974 systemd[2539]: Startup finished in 189ms. Nov 24 00:21:08.438978 systemd[1]: Started user@0.service - User Manager for UID 0. Nov 24 00:21:08.441521 systemd[1]: Started session-c2.scope - Session c2 of User root. Nov 24 00:21:09.520022 kernel: loop6: detected capacity change from 0 to 12644352 Nov 24 00:21:09.889033 kernel: loop6: p9 Nov 24 00:21:10.195907 setup-nvidia[2547]: ░ Spawning container flatcardevelopercontainer-4459.1.2.bin on /opt/nvidia/workdir/flatcar_developer_container-4459.1.2.bin. Nov 24 00:21:10.648670 kernel: EXT4-fs (loop6p9): write access unavailable, skipping orphan cleanup Nov 24 00:21:10.648807 kernel: EXT4-fs (loop6p9): mounted filesystem 60190da1-d0f3-413a-a3e8-c35679f803bc ro without journal. Quota mode: none. Nov 24 00:21:16.584057 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Nov 24 00:21:16.589718 systemd[1]: Started sshd@0-10.200.0.12:22-10.200.16.10:39876.service - OpenSSH per-connection server daemon (10.200.16.10:39876). Nov 24 00:21:20.292395 sshd[2553]: Accepted publickey for core from 10.200.16.10 port 39876 ssh2: RSA SHA256:ZsGZdPSMLsIMFvMfgveQ3HAPJ+x9oI8EZIYVEgGiY/o Nov 24 00:21:20.293572 sshd-session[2553]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:21:20.297682 systemd-logind[1904]: New session 3 of user core. Nov 24 00:21:20.307112 systemd[1]: Started session-3.scope - Session 3 of User core. Nov 24 00:21:20.787415 systemd[1]: Started sshd@1-10.200.0.12:22-10.200.16.10:53322.service - OpenSSH per-connection server daemon (10.200.16.10:53322). Nov 24 00:21:21.342499 sshd[2561]: Accepted publickey for core from 10.200.16.10 port 53322 ssh2: RSA SHA256:ZsGZdPSMLsIMFvMfgveQ3HAPJ+x9oI8EZIYVEgGiY/o Nov 24 00:21:21.343732 sshd-session[2561]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:21:21.347783 systemd-logind[1904]: New session 4 of user core. Nov 24 00:21:21.361106 systemd[1]: Started session-4.scope - Session 4 of User core. Nov 24 00:21:21.745032 sshd[2564]: Connection closed by 10.200.16.10 port 53322 Nov 24 00:21:21.745339 sshd-session[2561]: pam_unix(sshd:session): session closed for user core Nov 24 00:21:21.748614 systemd[1]: sshd@1-10.200.0.12:22-10.200.16.10:53322.service: Deactivated successfully. Nov 24 00:21:21.750433 systemd[1]: session-4.scope: Deactivated successfully. Nov 24 00:21:21.751204 systemd-logind[1904]: Session 4 logged out. Waiting for processes to exit. Nov 24 00:21:21.752124 systemd-logind[1904]: Removed session 4. Nov 24 00:21:21.850245 systemd[1]: Started sshd@2-10.200.0.12:22-10.200.16.10:53330.service - OpenSSH per-connection server daemon (10.200.16.10:53330). Nov 24 00:21:22.403319 sshd[2614]: Accepted publickey for core from 10.200.16.10 port 53330 ssh2: RSA SHA256:ZsGZdPSMLsIMFvMfgveQ3HAPJ+x9oI8EZIYVEgGiY/o Nov 24 00:21:22.404483 sshd-session[2614]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:21:22.408706 systemd-logind[1904]: New session 5 of user core. Nov 24 00:21:22.422135 systemd[1]: Started session-5.scope - Session 5 of User core. Nov 24 00:21:22.796005 sshd[2632]: Connection closed by 10.200.16.10 port 53330 Nov 24 00:21:22.796340 sshd-session[2614]: pam_unix(sshd:session): session closed for user core Nov 24 00:21:22.799695 systemd[1]: sshd@2-10.200.0.12:22-10.200.16.10:53330.service: Deactivated successfully. Nov 24 00:21:22.801345 systemd[1]: session-5.scope: Deactivated successfully. Nov 24 00:21:22.802019 systemd-logind[1904]: Session 5 logged out. Waiting for processes to exit. Nov 24 00:21:22.803246 systemd-logind[1904]: Removed session 5. Nov 24 00:21:32.898314 systemd[1]: Started sshd@3-10.200.0.12:22-10.200.16.10:41798.service - OpenSSH per-connection server daemon (10.200.16.10:41798). Nov 24 00:21:33.454357 sshd[5579]: Accepted publickey for core from 10.200.16.10 port 41798 ssh2: RSA SHA256:ZsGZdPSMLsIMFvMfgveQ3HAPJ+x9oI8EZIYVEgGiY/o Nov 24 00:21:33.455562 sshd-session[5579]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:21:33.459782 systemd-logind[1904]: New session 6 of user core. Nov 24 00:21:33.471146 systemd[1]: Started session-6.scope - Session 6 of User core. Nov 24 00:21:33.847201 sshd[5905]: Connection closed by 10.200.16.10 port 41798 Nov 24 00:21:33.847501 sshd-session[5579]: pam_unix(sshd:session): session closed for user core Nov 24 00:21:33.851005 systemd[1]: sshd@3-10.200.0.12:22-10.200.16.10:41798.service: Deactivated successfully. Nov 24 00:21:33.852823 systemd[1]: session-6.scope: Deactivated successfully. Nov 24 00:21:33.853501 systemd-logind[1904]: Session 6 logged out. Waiting for processes to exit. Nov 24 00:21:33.854590 systemd-logind[1904]: Removed session 6. Nov 24 00:21:33.954280 systemd[1]: Started sshd@4-10.200.0.12:22-10.200.16.10:41812.service - OpenSSH per-connection server daemon (10.200.16.10:41812). Nov 24 00:21:34.524329 sshd[5981]: Accepted publickey for core from 10.200.16.10 port 41812 ssh2: RSA SHA256:ZsGZdPSMLsIMFvMfgveQ3HAPJ+x9oI8EZIYVEgGiY/o Nov 24 00:21:34.525587 sshd-session[5981]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:21:34.529692 systemd-logind[1904]: New session 7 of user core. Nov 24 00:21:34.539167 systemd[1]: Started session-7.scope - Session 7 of User core. Nov 24 00:21:34.924087 sshd[6152]: Connection closed by 10.200.16.10 port 41812 Nov 24 00:21:34.924181 sshd-session[5981]: pam_unix(sshd:session): session closed for user core Nov 24 00:21:34.927887 systemd-logind[1904]: Session 7 logged out. Waiting for processes to exit. Nov 24 00:21:34.928519 systemd[1]: sshd@4-10.200.0.12:22-10.200.16.10:41812.service: Deactivated successfully. Nov 24 00:21:34.930123 systemd[1]: session-7.scope: Deactivated successfully. Nov 24 00:21:34.934585 systemd-logind[1904]: Removed session 7. Nov 24 00:21:45.032450 systemd[1]: Started sshd@5-10.200.0.12:22-10.200.16.10:46908.service - OpenSSH per-connection server daemon (10.200.16.10:46908). Nov 24 00:21:45.596068 sshd[9722]: Accepted publickey for core from 10.200.16.10 port 46908 ssh2: RSA SHA256:ZsGZdPSMLsIMFvMfgveQ3HAPJ+x9oI8EZIYVEgGiY/o Nov 24 00:21:45.597268 sshd-session[9722]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:21:45.601437 systemd-logind[1904]: New session 8 of user core. Nov 24 00:21:45.612132 systemd[1]: Started session-8.scope - Session 8 of User core. Nov 24 00:21:45.996394 sshd[10077]: Connection closed by 10.200.16.10 port 46908 Nov 24 00:21:45.996735 sshd-session[9722]: pam_unix(sshd:session): session closed for user core Nov 24 00:21:46.000089 systemd[1]: sshd@5-10.200.0.12:22-10.200.16.10:46908.service: Deactivated successfully. Nov 24 00:21:46.001689 systemd[1]: session-8.scope: Deactivated successfully. Nov 24 00:21:46.002436 systemd-logind[1904]: Session 8 logged out. Waiting for processes to exit. Nov 24 00:21:46.003402 systemd-logind[1904]: Removed session 8. Nov 24 00:21:46.098538 systemd[1]: Started sshd@6-10.200.0.12:22-10.200.16.10:46910.service - OpenSSH per-connection server daemon (10.200.16.10:46910). Nov 24 00:21:46.654338 sshd[10359]: Accepted publickey for core from 10.200.16.10 port 46910 ssh2: RSA SHA256:ZsGZdPSMLsIMFvMfgveQ3HAPJ+x9oI8EZIYVEgGiY/o Nov 24 00:21:46.655603 sshd-session[10359]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:21:46.666226 systemd-logind[1904]: New session 9 of user core. Nov 24 00:21:46.675165 systemd[1]: Started session-9.scope - Session 9 of User core. Nov 24 00:21:47.058047 sshd[10694]: Connection closed by 10.200.16.10 port 46910 Nov 24 00:21:47.058546 sshd-session[10359]: pam_unix(sshd:session): session closed for user core Nov 24 00:21:47.065049 systemd[1]: sshd@6-10.200.0.12:22-10.200.16.10:46910.service: Deactivated successfully. Nov 24 00:21:47.068566 systemd[1]: session-9.scope: Deactivated successfully. Nov 24 00:21:47.069406 systemd-logind[1904]: Session 9 logged out. Waiting for processes to exit. Nov 24 00:21:47.070666 systemd-logind[1904]: Removed session 9. Nov 24 00:21:57.157885 systemd[1]: Started sshd@7-10.200.0.12:22-10.200.16.10:50214.service - OpenSSH per-connection server daemon (10.200.16.10:50214). Nov 24 00:21:57.756581 sshd[14051]: Accepted publickey for core from 10.200.16.10 port 50214 ssh2: RSA SHA256:ZsGZdPSMLsIMFvMfgveQ3HAPJ+x9oI8EZIYVEgGiY/o Nov 24 00:21:57.758380 sshd-session[14051]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:21:57.767747 systemd-logind[1904]: New session 10 of user core. Nov 24 00:21:57.775629 systemd[1]: Started session-10.scope - Session 10 of User core. Nov 24 00:21:58.156808 sshd[14072]: Connection closed by 10.200.16.10 port 50214 Nov 24 00:21:58.158590 sshd-session[14051]: pam_unix(sshd:session): session closed for user core Nov 24 00:21:58.161652 systemd[1]: sshd@7-10.200.0.12:22-10.200.16.10:50214.service: Deactivated successfully. Nov 24 00:21:58.166066 systemd[1]: session-10.scope: Deactivated successfully. Nov 24 00:21:58.168152 systemd-logind[1904]: Session 10 logged out. Waiting for processes to exit. Nov 24 00:21:58.169725 systemd-logind[1904]: Removed session 10. Nov 24 00:21:58.257433 systemd[1]: Started sshd@8-10.200.0.12:22-10.200.16.10:50222.service - OpenSSH per-connection server daemon (10.200.16.10:50222). Nov 24 00:21:58.834047 sshd[14096]: Accepted publickey for core from 10.200.16.10 port 50222 ssh2: RSA SHA256:ZsGZdPSMLsIMFvMfgveQ3HAPJ+x9oI8EZIYVEgGiY/o Nov 24 00:21:58.835389 sshd-session[14096]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:21:58.842182 systemd-logind[1904]: New session 11 of user core. Nov 24 00:21:58.851081 systemd[1]: Started session-11.scope - Session 11 of User core. Nov 24 00:21:59.241658 sshd[14125]: Connection closed by 10.200.16.10 port 50222 Nov 24 00:21:59.242041 sshd-session[14096]: pam_unix(sshd:session): session closed for user core Nov 24 00:21:59.245398 systemd-logind[1904]: Session 11 logged out. Waiting for processes to exit. Nov 24 00:21:59.246718 systemd[1]: sshd@8-10.200.0.12:22-10.200.16.10:50222.service: Deactivated successfully. Nov 24 00:21:59.248288 systemd[1]: session-11.scope: Deactivated successfully. Nov 24 00:21:59.251837 systemd-logind[1904]: Removed session 11. Nov 24 00:22:09.343582 systemd[1]: Started sshd@9-10.200.0.12:22-10.200.16.10:54510.service - OpenSSH per-connection server daemon (10.200.16.10:54510). Nov 24 00:22:09.913552 sshd[14543]: Accepted publickey for core from 10.200.16.10 port 54510 ssh2: RSA SHA256:ZsGZdPSMLsIMFvMfgveQ3HAPJ+x9oI8EZIYVEgGiY/o Nov 24 00:22:09.915648 sshd-session[14543]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:22:09.922390 systemd-logind[1904]: New session 12 of user core. Nov 24 00:22:09.928255 systemd[1]: Started session-12.scope - Session 12 of User core. Nov 24 00:22:10.311685 sshd[14559]: Connection closed by 10.200.16.10 port 54510 Nov 24 00:22:10.312065 sshd-session[14543]: pam_unix(sshd:session): session closed for user core Nov 24 00:22:10.317613 systemd[1]: sshd@9-10.200.0.12:22-10.200.16.10:54510.service: Deactivated successfully. Nov 24 00:22:10.319288 systemd[1]: session-12.scope: Deactivated successfully. Nov 24 00:22:10.320827 systemd-logind[1904]: Session 12 logged out. Waiting for processes to exit. Nov 24 00:22:10.322725 systemd-logind[1904]: Removed session 12. Nov 24 00:22:10.413640 systemd[1]: Started sshd@10-10.200.0.12:22-10.200.16.10:38078.service - OpenSSH per-connection server daemon (10.200.16.10:38078). Nov 24 00:22:10.995406 sshd[14582]: Accepted publickey for core from 10.200.16.10 port 38078 ssh2: RSA SHA256:ZsGZdPSMLsIMFvMfgveQ3HAPJ+x9oI8EZIYVEgGiY/o Nov 24 00:22:10.997160 sshd-session[14582]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:22:11.004195 systemd-logind[1904]: New session 13 of user core. Nov 24 00:22:11.010149 systemd[1]: Started session-13.scope - Session 13 of User core. Nov 24 00:22:11.395662 sshd[14603]: Connection closed by 10.200.16.10 port 38078 Nov 24 00:22:11.397354 sshd-session[14582]: pam_unix(sshd:session): session closed for user core Nov 24 00:22:11.400689 systemd-logind[1904]: Session 13 logged out. Waiting for processes to exit. Nov 24 00:22:11.405379 systemd[1]: sshd@10-10.200.0.12:22-10.200.16.10:38078.service: Deactivated successfully. Nov 24 00:22:11.407364 systemd[1]: session-13.scope: Deactivated successfully. Nov 24 00:22:11.409932 systemd-logind[1904]: Removed session 13. Nov 24 00:22:21.498721 systemd[1]: Started sshd@11-10.200.0.12:22-10.200.16.10:39798.service - OpenSSH per-connection server daemon (10.200.16.10:39798). Nov 24 00:22:22.081914 sshd[14946]: Accepted publickey for core from 10.200.16.10 port 39798 ssh2: RSA SHA256:ZsGZdPSMLsIMFvMfgveQ3HAPJ+x9oI8EZIYVEgGiY/o Nov 24 00:22:22.084962 sshd-session[14946]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:22:22.090241 systemd-logind[1904]: New session 14 of user core. Nov 24 00:22:22.101172 systemd[1]: Started session-14.scope - Session 14 of User core. Nov 24 00:22:22.487430 sshd[14964]: Connection closed by 10.200.16.10 port 39798 Nov 24 00:22:22.489793 sshd-session[14946]: pam_unix(sshd:session): session closed for user core Nov 24 00:22:22.493525 systemd[1]: sshd@11-10.200.0.12:22-10.200.16.10:39798.service: Deactivated successfully. Nov 24 00:22:22.495795 systemd[1]: session-14.scope: Deactivated successfully. Nov 24 00:22:22.497149 systemd-logind[1904]: Session 14 logged out. Waiting for processes to exit. Nov 24 00:22:22.499144 systemd-logind[1904]: Removed session 14. Nov 24 00:22:22.590269 systemd[1]: Started sshd@12-10.200.0.12:22-10.200.16.10:39810.service - OpenSSH per-connection server daemon (10.200.16.10:39810). Nov 24 00:22:23.169049 sshd[14991]: Accepted publickey for core from 10.200.16.10 port 39810 ssh2: RSA SHA256:ZsGZdPSMLsIMFvMfgveQ3HAPJ+x9oI8EZIYVEgGiY/o Nov 24 00:22:23.170426 sshd-session[14991]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:22:23.186079 systemd-logind[1904]: New session 15 of user core. Nov 24 00:22:23.193265 systemd[1]: Started session-15.scope - Session 15 of User core. Nov 24 00:22:23.571701 sshd[15000]: Connection closed by 10.200.16.10 port 39810 Nov 24 00:22:23.572111 sshd-session[14991]: pam_unix(sshd:session): session closed for user core Nov 24 00:22:23.575600 systemd-logind[1904]: Session 15 logged out. Waiting for processes to exit. Nov 24 00:22:23.576951 systemd[1]: sshd@12-10.200.0.12:22-10.200.16.10:39810.service: Deactivated successfully. Nov 24 00:22:23.578532 systemd[1]: session-15.scope: Deactivated successfully. Nov 24 00:22:23.580719 systemd-logind[1904]: Removed session 15. Nov 24 00:22:33.681462 systemd[1]: Started sshd@13-10.200.0.12:22-10.200.16.10:49496.service - OpenSSH per-connection server daemon (10.200.16.10:49496). Nov 24 00:22:34.239728 sshd[15200]: Accepted publickey for core from 10.200.16.10 port 49496 ssh2: RSA SHA256:ZsGZdPSMLsIMFvMfgveQ3HAPJ+x9oI8EZIYVEgGiY/o Nov 24 00:22:34.240915 sshd-session[15200]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:22:34.245113 systemd-logind[1904]: New session 16 of user core. Nov 24 00:22:34.254112 systemd[1]: Started session-16.scope - Session 16 of User core. Nov 24 00:22:34.633990 sshd[15212]: Connection closed by 10.200.16.10 port 49496 Nov 24 00:22:34.634251 sshd-session[15200]: pam_unix(sshd:session): session closed for user core Nov 24 00:22:34.637534 systemd[1]: sshd@13-10.200.0.12:22-10.200.16.10:49496.service: Deactivated successfully. Nov 24 00:22:34.639123 systemd[1]: session-16.scope: Deactivated successfully. Nov 24 00:22:34.639780 systemd-logind[1904]: Session 16 logged out. Waiting for processes to exit. Nov 24 00:22:34.640674 systemd-logind[1904]: Removed session 16. Nov 24 00:22:34.740210 systemd[1]: Started sshd@14-10.200.0.12:22-10.200.16.10:49502.service - OpenSSH per-connection server daemon (10.200.16.10:49502). Nov 24 00:22:35.319242 sshd[15241]: Accepted publickey for core from 10.200.16.10 port 49502 ssh2: RSA SHA256:ZsGZdPSMLsIMFvMfgveQ3HAPJ+x9oI8EZIYVEgGiY/o Nov 24 00:22:35.320424 sshd-session[15241]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:22:35.324432 systemd-logind[1904]: New session 17 of user core. Nov 24 00:22:35.336126 systemd[1]: Started session-17.scope - Session 17 of User core. Nov 24 00:22:35.724868 sshd[15244]: Connection closed by 10.200.16.10 port 49502 Nov 24 00:22:35.725255 sshd-session[15241]: pam_unix(sshd:session): session closed for user core Nov 24 00:22:35.731079 systemd-logind[1904]: Session 17 logged out. Waiting for processes to exit. Nov 24 00:22:35.732858 systemd[1]: sshd@14-10.200.0.12:22-10.200.16.10:49502.service: Deactivated successfully. Nov 24 00:22:35.737792 systemd[1]: session-17.scope: Deactivated successfully. Nov 24 00:22:35.740019 systemd-logind[1904]: Removed session 17. Nov 24 00:22:36.622259 setup-nvidia[2547]: \u000d Nov 24 00:22:36.622259 setup-nvidia[2547]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Nov 24 00:22:36.622259 setup-nvidia[2547]: frequently when this kernel module was built against the wrong or Nov 24 00:22:36.622259 setup-nvidia[2547]: improperly configured kernel sources, with a version of gcc that differs Nov 24 00:22:36.622259 setup-nvidia[2547]: from the one used to build the target kernel, or if another driver, such Nov 24 00:22:36.622259 setup-nvidia[2547]: as nouveau, is present and prevents the NVIDIA kernel module from Nov 24 00:22:36.622259 setup-nvidia[2547]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Nov 24 00:22:36.622259 setup-nvidia[2547]: installed in this system is supported by this NVIDIA Linux graphics Nov 24 00:22:36.622259 setup-nvidia[2547]: driver release. Nov 24 00:22:36.622259 setup-nvidia[2547]: \u000d Nov 24 00:22:36.622259 setup-nvidia[2547]: Please see the log entries 'Kernel module load error' and 'Kernel Nov 24 00:22:36.622259 setup-nvidia[2547]: messages' at the end of the file Nov 24 00:22:36.622259 setup-nvidia[2547]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for more Nov 24 00:22:36.622259 setup-nvidia[2547]: information. Nov 24 00:22:36.622259 setup-nvidia[2547]: \u000d Nov 24 00:22:36.636852 setup-nvidia[2547]: dmesg: read kernel buffer failed: Operation not permitted Nov 24 00:22:36.655155 setup-nvidia[2547]: \u000d Nov 24 00:22:36.655155 setup-nvidia[2547]: ERROR: Installation has failed. Please see the file Nov 24 00:22:36.655155 setup-nvidia[2547]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for Nov 24 00:22:36.655155 setup-nvidia[2547]: details. You may find suggestions on fixing installation problems in Nov 24 00:22:36.655155 setup-nvidia[2547]: the README available on the Linux driver download page at Nov 24 00:22:36.655155 setup-nvidia[2547]: www.nvidia.com. Nov 24 00:22:36.655155 setup-nvidia[2547]: \u000d Nov 24 00:22:36.655596 setup-nvidia[2547]: Last 50 lines of nvidia-installer.log: Nov 24 00:22:36.656395 setup-nvidia[2547]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039059rm+0x53: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:22:36.656395 setup-nvidia[2547]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039056rm+0x90: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:22:36.656395 setup-nvidia[2547]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039049rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:22:36.656395 setup-nvidia[2547]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039105rm+0x93: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:22:36.656395 setup-nvidia[2547]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039101rm+0x36: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:22:36.656395 setup-nvidia[2547]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039100rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:22:36.656395 setup-nvidia[2547]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039102rm+0x77: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:22:36.656395 setup-nvidia[2547]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039046rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:22:36.656395 setup-nvidia[2547]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030615rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:22:36.656395 setup-nvidia[2547]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030614rm+0x27: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:22:36.656640 setup-nvidia[2547]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030618rm+0x37: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:22:36.656640 setup-nvidia[2547]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030617rm+0x24: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:22:36.656640 setup-nvidia[2547]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030616rm+0xca: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:22:36.656640 setup-nvidia[2547]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030619rm+0x193: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:22:36.656640 setup-nvidia[2547]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030621rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:22:36.656640 setup-nvidia[2547]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv011864rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:22:36.656640 setup-nvidia[2547]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039413rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:22:36.656640 setup-nvidia[2547]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/Module.symvers Nov 24 00:22:36.656640 setup-nvidia[2547]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.mod.o Nov 24 00:22:36.656640 setup-nvidia[2547]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/.module-common.o Nov 24 00:22:36.656640 setup-nvidia[2547]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.mod.o Nov 24 00:22:36.656640 setup-nvidia[2547]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.mod.o Nov 24 00:22:36.656640 setup-nvidia[2547]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.mod.o Nov 24 00:22:36.656640 setup-nvidia[2547]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.mod.o Nov 24 00:22:36.656640 setup-nvidia[2547]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Nov 24 00:22:36.656640 setup-nvidia[2547]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Nov 24 00:22:36.656640 setup-nvidia[2547]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Nov 24 00:22:36.656640 setup-nvidia[2547]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Nov 24 00:22:36.656640 setup-nvidia[2547]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Nov 24 00:22:36.656640 setup-nvidia[2547]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Nov 24 00:22:36.656640 setup-nvidia[2547]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko due to unavailability of vmlinux Nov 24 00:22:36.656640 setup-nvidia[2547]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Nov 24 00:22:36.656640 setup-nvidia[2547]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko due to unavailability of vmlinux Nov 24 00:22:36.656640 setup-nvidia[2547]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Nov 24 00:22:36.656640 setup-nvidia[2547]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko due to unavailability of vmlinux Nov 24 00:22:36.656640 setup-nvidia[2547]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Nov 24 00:22:36.656640 setup-nvidia[2547]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko due to unavailability of vmlinux Nov 24 00:22:36.656640 setup-nvidia[2547]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Nov 24 00:22:36.656640 setup-nvidia[2547]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko due to unavailability of vmlinux Nov 24 00:22:36.656640 setup-nvidia[2547]: make[2]: Leaving directory '/usr/lib/modules/6.12.58-flatcar/build' Nov 24 00:22:36.656640 setup-nvidia[2547]: make[1]: Leaving directory '/usr/lib/modules/6.12.58-flatcar/source' Nov 24 00:22:36.656640 setup-nvidia[2547]: -> done. Nov 24 00:22:36.656640 setup-nvidia[2547]: -> Kernel module compilation complete. Nov 24 00:22:36.656640 setup-nvidia[2547]: -> Unable to determine if Secure Boot is enabled: No such file or directory Nov 24 00:22:36.656640 setup-nvidia[2547]: 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. Nov 24 00:22:36.656640 setup-nvidia[2547]: \u000d Nov 24 00:22:36.656640 setup-nvidia[2547]: Please see the log entries 'Kernel module load error' and 'Kernel messages' at the end of the file '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for more information. Nov 24 00:22:36.656640 setup-nvidia[2547]: -> Kernel module load error: Operation not permitted Nov 24 00:22:36.656640 setup-nvidia[2547]: -> Kernel messages: Nov 24 00:22:36.656640 setup-nvidia[2547]: ERROR: Installation has failed. Please see the file '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for details. You may find suggestions on fixing installation problems in the README available on the Linux driver download page at www.nvidia.com. Nov 24 00:22:45.840830 systemd[1]: Started sshd@15-10.200.0.12:22-10.200.16.10:50568.service - OpenSSH per-connection server daemon (10.200.16.10:50568). Nov 24 00:22:46.399503 sshd[15398]: Accepted publickey for core from 10.200.16.10 port 50568 ssh2: RSA SHA256:ZsGZdPSMLsIMFvMfgveQ3HAPJ+x9oI8EZIYVEgGiY/o Nov 24 00:22:46.400663 sshd-session[15398]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:22:46.404673 systemd-logind[1904]: New session 18 of user core. Nov 24 00:22:46.421107 systemd[1]: Started session-18.scope - Session 18 of User core. Nov 24 00:22:46.802512 sshd[15401]: Connection closed by 10.200.16.10 port 50568 Nov 24 00:22:46.802860 sshd-session[15398]: pam_unix(sshd:session): session closed for user core Nov 24 00:22:46.806358 systemd[1]: sshd@15-10.200.0.12:22-10.200.16.10:50568.service: Deactivated successfully. Nov 24 00:22:46.808189 systemd[1]: session-18.scope: Deactivated successfully. Nov 24 00:22:46.808969 systemd-logind[1904]: Session 18 logged out. Waiting for processes to exit. Nov 24 00:22:46.809885 systemd-logind[1904]: Removed session 18. Nov 24 00:22:46.904191 systemd[1]: Started sshd@16-10.200.0.12:22-10.200.16.10:50572.service - OpenSSH per-connection server daemon (10.200.16.10:50572). Nov 24 00:22:47.460712 sshd[15407]: Accepted publickey for core from 10.200.16.10 port 50572 ssh2: RSA SHA256:ZsGZdPSMLsIMFvMfgveQ3HAPJ+x9oI8EZIYVEgGiY/o Nov 24 00:22:47.461960 sshd-session[15407]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:22:47.467638 systemd-logind[1904]: New session 19 of user core. Nov 24 00:22:47.487115 systemd[1]: Started session-19.scope - Session 19 of User core. Nov 24 00:22:47.853938 sshd[15410]: Connection closed by 10.200.16.10 port 50572 Nov 24 00:22:47.854252 sshd-session[15407]: pam_unix(sshd:session): session closed for user core Nov 24 00:22:47.857742 systemd[1]: sshd@16-10.200.0.12:22-10.200.16.10:50572.service: Deactivated successfully. Nov 24 00:22:47.859419 systemd[1]: session-19.scope: Deactivated successfully. Nov 24 00:22:47.860122 systemd-logind[1904]: Session 19 logged out. Waiting for processes to exit. Nov 24 00:22:47.860977 systemd-logind[1904]: Removed session 19. Nov 24 00:22:51.757021 kernel: EXT4-fs (loop6p9): unmounting filesystem 60190da1-d0f3-413a-a3e8-c35679f803bc. Nov 24 00:22:51.757594 setup-nvidia[2547]: Container flatcardevelopercontainer-4459.1.2.bin exited successfully. Nov 24 00:22:51.760031 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4459.1.2.bin.mount: Deactivated successfully. Nov 24 00:22:51.806842 sudo[2536]: pam_unix(sudo:session): session closed for user root Nov 24 00:22:51.810849 systemd[1]: session-c2.scope: Deactivated successfully. Nov 24 00:22:51.811164 systemd[1]: session-c2.scope: Consumed 5min 16.957s CPU time, 890.2M memory peak. Nov 24 00:22:52.227086 setup-nvidia[1899]: /opt/nvidia / Nov 24 00:22:52.228048 setup-nvidia[1899]: / Nov 24 00:22:52.237011 kernel: IPMI message handler: version 39.2 Nov 24 00:22:52.241024 kernel: ipmi device interface Nov 24 00:22:52.252910 setup-nvidia[1899]: /opt/nvidia/current/usr/lib/modules/6.12.58-flatcar/video / Nov 24 00:22:52.305984 kernel: nvidia: loading out-of-tree module taints kernel. Nov 24 00:22:52.306133 kernel: nvidia: module license 'NVIDIA' taints kernel. Nov 24 00:22:52.306156 kernel: Disabling lock debugging due to kernel taint Nov 24 00:22:52.313596 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Nov 24 00:22:52.313638 kernel: nvidia: module license taints kernel. Nov 24 00:22:52.716021 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 Nov 24 00:22:53.110358 kernel: Nov 24 00:22:53.110503 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Nov 24 00:22:53.317015 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.261.03 Sat Jun 14 16:05:50 UTC 2025 Nov 24 00:22:53.338020 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.261.03 Sat Jun 14 15:39:16 UTC 2025 Nov 24 00:22:53.357030 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Nov 24 00:22:53.403871 setup-nvidia[1899]: / Nov 24 00:22:53.404308 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. Nov 24 00:22:53.419435 setup-nvidia[15450]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Nov 24 00:22:55.177890 setup-nvidia[15467]: Mon Nov 24 00:22:55 2025 Nov 24 00:22:55.177890 setup-nvidia[15467]: +---------------------------------------------------------------------------------------+ Nov 24 00:22:55.177890 setup-nvidia[15467]: | NVIDIA-SMI 535.261.03 Driver Version: 535.261.03 CUDA Version: 12.2 | Nov 24 00:22:55.177890 setup-nvidia[15467]: |-----------------------------------------+----------------------+----------------------+ Nov 24 00:22:55.177890 setup-nvidia[15467]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Nov 24 00:22:55.177890 setup-nvidia[15467]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Nov 24 00:22:55.177890 setup-nvidia[15467]: | | | MIG M. | Nov 24 00:22:55.177890 setup-nvidia[15467]: |=========================================+======================+======================| Nov 24 00:22:55.181469 setup-nvidia[15467]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Nov 24 00:22:55.181469 setup-nvidia[15467]: | N/A 29C P0 36W / 250W | 0MiB / 16384MiB | 0% Default | Nov 24 00:22:55.181469 setup-nvidia[15467]: | | | N/A | Nov 24 00:22:55.181469 setup-nvidia[15467]: +-----------------------------------------+----------------------+----------------------+ Nov 24 00:22:55.181586 setup-nvidia[15467]: Nov 24 00:22:55.181586 setup-nvidia[15467]: +---------------------------------------------------------------------------------------+ Nov 24 00:22:55.181586 setup-nvidia[15467]: | Processes: | Nov 24 00:22:55.181586 setup-nvidia[15467]: | GPU GI CI PID Type Process name GPU Memory | Nov 24 00:22:55.181586 setup-nvidia[15467]: | ID ID Usage | Nov 24 00:22:55.181586 setup-nvidia[15467]: |=======================================================================================| Nov 24 00:22:55.181727 setup-nvidia[15467]: | No running processes found | Nov 24 00:22:55.181727 setup-nvidia[15467]: +---------------------------------------------------------------------------------------+ Nov 24 00:22:55.556232 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Nov 24 00:22:55.558287 systemd[1]: Reached target multi-user.target - Multi-User System. Nov 24 00:22:55.558650 systemd[1]: Startup finished in 1.002s (firmware) + 21.558s (loader) + 4.196s (kernel) + 12.286s (initrd) + 2min 58.888s (userspace) = 3min 37.932s. Nov 24 00:22:57.958429 systemd[1]: Started sshd@17-10.200.0.12:22-10.200.16.10:54542.service - OpenSSH per-connection server daemon (10.200.16.10:54542). Nov 24 00:22:58.515679 sshd[15474]: Accepted publickey for core from 10.200.16.10 port 54542 ssh2: RSA SHA256:ZsGZdPSMLsIMFvMfgveQ3HAPJ+x9oI8EZIYVEgGiY/o Nov 24 00:22:58.516859 sshd-session[15474]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:22:58.521015 systemd-logind[1904]: New session 20 of user core. Nov 24 00:22:58.533115 systemd[1]: Started session-20.scope - Session 20 of User core. Nov 24 00:22:58.911770 sshd[15477]: Connection closed by 10.200.16.10 port 54542 Nov 24 00:22:58.912032 sshd-session[15474]: pam_unix(sshd:session): session closed for user core Nov 24 00:22:58.915199 systemd[1]: sshd@17-10.200.0.12:22-10.200.16.10:54542.service: Deactivated successfully. Nov 24 00:22:58.916781 systemd[1]: session-20.scope: Deactivated successfully. Nov 24 00:22:58.917477 systemd-logind[1904]: Session 20 logged out. Waiting for processes to exit. Nov 24 00:22:58.918389 systemd-logind[1904]: Removed session 20. Nov 24 00:22:59.012887 systemd[1]: Started sshd@18-10.200.0.12:22-10.200.16.10:54546.service - OpenSSH per-connection server daemon (10.200.16.10:54546). Nov 24 00:22:59.589375 sshd[15483]: Accepted publickey for core from 10.200.16.10 port 54546 ssh2: RSA SHA256:ZsGZdPSMLsIMFvMfgveQ3HAPJ+x9oI8EZIYVEgGiY/o Nov 24 00:22:59.590568 sshd-session[15483]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:22:59.594713 systemd-logind[1904]: New session 21 of user core. Nov 24 00:22:59.607114 systemd[1]: Started session-21.scope - Session 21 of User core. Nov 24 00:22:59.979569 sshd[15486]: Connection closed by 10.200.16.10 port 54546 Nov 24 00:22:59.979954 sshd-session[15483]: pam_unix(sshd:session): session closed for user core Nov 24 00:22:59.983429 systemd[1]: sshd@18-10.200.0.12:22-10.200.16.10:54546.service: Deactivated successfully. Nov 24 00:22:59.985076 systemd[1]: session-21.scope: Deactivated successfully. Nov 24 00:22:59.985735 systemd-logind[1904]: Session 21 logged out. Waiting for processes to exit. Nov 24 00:22:59.986628 systemd-logind[1904]: Removed session 21. Nov 24 00:23:00.081977 systemd[1]: Started sshd@19-10.200.0.12:22-10.200.16.10:37788.service - OpenSSH per-connection server daemon (10.200.16.10:37788). Nov 24 00:23:00.652608 sshd[15492]: Accepted publickey for core from 10.200.16.10 port 37788 ssh2: RSA SHA256:ZsGZdPSMLsIMFvMfgveQ3HAPJ+x9oI8EZIYVEgGiY/o Nov 24 00:23:00.653787 sshd-session[15492]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:23:00.657864 systemd-logind[1904]: New session 22 of user core. Nov 24 00:23:00.668123 systemd[1]: Started session-22.scope - Session 22 of User core. Nov 24 00:23:01.045793 sshd[15495]: Connection closed by 10.200.16.10 port 37788 Nov 24 00:23:01.046115 sshd-session[15492]: pam_unix(sshd:session): session closed for user core Nov 24 00:23:01.049414 systemd[1]: sshd@19-10.200.0.12:22-10.200.16.10:37788.service: Deactivated successfully. Nov 24 00:23:01.051041 systemd[1]: session-22.scope: Deactivated successfully. Nov 24 00:23:01.053558 systemd-logind[1904]: Session 22 logged out. Waiting for processes to exit. Nov 24 00:23:01.054552 systemd-logind[1904]: Removed session 22. Nov 24 00:23:01.150133 systemd[1]: Started sshd@20-10.200.0.12:22-10.200.16.10:37790.service - OpenSSH per-connection server daemon (10.200.16.10:37790). Nov 24 00:23:01.724664 sshd[15501]: Accepted publickey for core from 10.200.16.10 port 37790 ssh2: RSA SHA256:ZsGZdPSMLsIMFvMfgveQ3HAPJ+x9oI8EZIYVEgGiY/o Nov 24 00:23:01.725855 sshd-session[15501]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:23:01.729894 systemd-logind[1904]: New session 23 of user core. Nov 24 00:23:01.742122 systemd[1]: Started session-23.scope - Session 23 of User core. Nov 24 00:23:01.937196 systemd[1]: Stopping user@0.service - User Manager for UID 0... Nov 24 00:23:01.937938 systemd[2539]: Activating special unit exit.target... Nov 24 00:23:01.938022 systemd[2539]: Stopped target default.target - Main User Target. Nov 24 00:23:01.938064 systemd[2539]: Stopped target basic.target - Basic System. Nov 24 00:23:01.938093 systemd[2539]: Stopped target paths.target - Paths. Nov 24 00:23:01.938118 systemd[2539]: Stopped target sockets.target - Sockets. Nov 24 00:23:01.938143 systemd[2539]: Stopped target timers.target - Timers. Nov 24 00:23:01.938463 systemd[2539]: Closed dbus.socket - D-Bus User Message Bus Socket. Nov 24 00:23:01.938668 systemd[2539]: Removed slice app.slice - User Application Slice. Nov 24 00:23:01.938699 systemd[2539]: Reached target shutdown.target - Shutdown. Nov 24 00:23:01.938837 systemd[2539]: Finished systemd-exit.service - Exit the Session. Nov 24 00:23:01.938872 systemd[2539]: Reached target exit.target - Exit the Session. Nov 24 00:23:01.953333 (sd-pam)[2540]: pam_unix(systemd-user:session): session closed for user root Nov 24 00:23:01.954551 systemd[1]: user@0.service: Deactivated successfully. Nov 24 00:23:01.954908 systemd[1]: Stopped user@0.service - User Manager for UID 0. Nov 24 00:23:01.957084 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Nov 24 00:23:01.978178 systemd[1]: run-user-0.mount: Deactivated successfully. Nov 24 00:23:01.979098 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Nov 24 00:23:01.979283 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Nov 24 00:23:01.980758 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Nov 24 00:23:01.980852 systemd[1]: user-0.slice: Consumed 5min 17.174s CPU time, 896.4M memory peak. Nov 24 00:23:01.981297 systemd-logind[1904]: Removed session c3. Nov 24 00:23:02.122972 sshd[15504]: Connection closed by 10.200.16.10 port 37790 Nov 24 00:23:02.123340 sshd-session[15501]: pam_unix(sshd:session): session closed for user core Nov 24 00:23:02.126803 systemd[1]: sshd@20-10.200.0.12:22-10.200.16.10:37790.service: Deactivated successfully. Nov 24 00:23:02.128443 systemd[1]: session-23.scope: Deactivated successfully. Nov 24 00:23:02.129151 systemd-logind[1904]: Session 23 logged out. Waiting for processes to exit. Nov 24 00:23:02.130067 systemd-logind[1904]: Removed session 23. Nov 24 00:23:02.224967 systemd[1]: Started sshd@21-10.200.0.12:22-10.200.16.10:37800.service - OpenSSH per-connection server daemon (10.200.16.10:37800). Nov 24 00:23:02.669784 waagent[2237]: 2025-11-24T00:23:02.669725Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 2] Nov 24 00:23:02.676769 waagent[2237]: 2025-11-24T00:23:02.676728Z INFO ExtHandler Nov 24 00:23:02.676858 waagent[2237]: 2025-11-24T00:23:02.676826Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: c8f380cf-8ea5-445a-8dbb-dade61249b70 eTag: 10243284135640783679 source: Fabric] Nov 24 00:23:02.677231 waagent[2237]: 2025-11-24T00:23:02.677193Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Nov 24 00:23:02.677877 waagent[2237]: 2025-11-24T00:23:02.677838Z INFO ExtHandler Nov 24 00:23:02.678155 waagent[2237]: 2025-11-24T00:23:02.677929Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 2] Nov 24 00:23:02.732522 waagent[2237]: 2025-11-24T00:23:02.732486Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Nov 24 00:23:02.788075 waagent[2237]: 2025-11-24T00:23:02.788025Z INFO ExtHandler Downloaded certificate {'thumbprint': '8F907D341136EA788E92A5AAA0116CB4D32B5945', 'hasPrivateKey': True} Nov 24 00:23:02.788520 waagent[2237]: 2025-11-24T00:23:02.788480Z INFO ExtHandler Fetch goal state completed Nov 24 00:23:02.788894 waagent[2237]: 2025-11-24T00:23:02.788856Z INFO ExtHandler ExtHandler Nov 24 00:23:02.788967 waagent[2237]: 2025-11-24T00:23:02.788936Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_2 channel: WireServer source: Fabric activity: 6153a917-678a-4864-8dec-2ba4f3a1ea25 correlation 724872c5-ae52-46b2-916a-2e4bb16a130c created: 2025-11-24T00:22:54.238991Z] Nov 24 00:23:02.789351 waagent[2237]: 2025-11-24T00:23:02.789304Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Nov 24 00:23:02.789885 waagent[2237]: 2025-11-24T00:23:02.789849Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_2 0 ms] Nov 24 00:23:02.796266 sshd[15514]: Accepted publickey for core from 10.200.16.10 port 37800 ssh2: RSA SHA256:ZsGZdPSMLsIMFvMfgveQ3HAPJ+x9oI8EZIYVEgGiY/o Nov 24 00:23:02.797367 sshd-session[15514]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:23:02.801593 systemd-logind[1904]: New session 24 of user core. Nov 24 00:23:02.819121 systemd[1]: Started session-24.scope - Session 24 of User core. Nov 24 00:23:04.675785 sshd[15522]: Connection closed by 10.200.16.10 port 37800 Nov 24 00:23:04.790610 sshd-session[15514]: pam_unix(sshd:session): session closed for user core Nov 24 00:23:04.794345 systemd[1]: sshd@21-10.200.0.12:22-10.200.16.10:37800.service: Deactivated successfully. Nov 24 00:23:04.795968 systemd[1]: session-24.scope: Deactivated successfully. Nov 24 00:23:04.796186 systemd[1]: session-24.scope: Consumed 1.422s CPU time, 14M memory peak. Nov 24 00:23:04.796682 systemd-logind[1904]: Session 24 logged out. Waiting for processes to exit. Nov 24 00:23:04.797871 systemd-logind[1904]: Removed session 24.