Dec 12 18:38:14.211662 kernel: Linux version 6.12.61-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 Fri Dec 12 15:21:28 -00 2025 Dec 12 18:38:14.211692 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=a214a2d85e162c493e8b13db2df50a43e1005a0e4854a1ae089a14f442a30022 Dec 12 18:38:14.211702 kernel: BIOS-provided physical RAM map: Dec 12 18:38:14.211708 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Dec 12 18:38:14.211715 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Dec 12 18:38:14.211721 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Dec 12 18:38:14.211729 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Dec 12 18:38:14.211739 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Dec 12 18:38:14.211746 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Dec 12 18:38:14.211752 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Dec 12 18:38:14.211759 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Dec 12 18:38:14.211766 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Dec 12 18:38:14.211772 kernel: printk: legacy bootconsole [earlyser0] enabled Dec 12 18:38:14.211779 kernel: NX (Execute Disable) protection: active Dec 12 18:38:14.211790 kernel: APIC: Static calls initialized Dec 12 18:38:14.211798 kernel: efi: EFI v2.7 by Microsoft Dec 12 18:38:14.211805 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Dec 12 18:38:14.211812 kernel: random: crng init done Dec 12 18:38:14.211820 kernel: secureboot: Secure boot disabled Dec 12 18:38:14.211827 kernel: SMBIOS 3.1.0 present. Dec 12 18:38:14.211834 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Dec 12 18:38:14.211842 kernel: DMI: Memory slots populated: 3/3 Dec 12 18:38:14.211849 kernel: Hypervisor detected: Microsoft Hyper-V Dec 12 18:38:14.211856 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Dec 12 18:38:14.211866 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Dec 12 18:38:14.211873 kernel: Hyper-V: Using hypercall for remote TLB flush Dec 12 18:38:14.211880 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Dec 12 18:38:14.211888 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Dec 12 18:38:14.211896 kernel: tsc: Marking TSC unstable due to running on Hyper-V Dec 12 18:38:14.211904 kernel: tsc: Detected 2593.992 MHz processor Dec 12 18:38:14.211912 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Dec 12 18:38:14.211919 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Dec 12 18:38:14.211927 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Dec 12 18:38:14.211937 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Dec 12 18:38:14.211944 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Dec 12 18:38:14.211952 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Dec 12 18:38:14.211959 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Dec 12 18:38:14.211967 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Dec 12 18:38:14.211974 kernel: Using GB pages for direct mapping Dec 12 18:38:14.211982 kernel: ACPI: Early table checksum verification disabled Dec 12 18:38:14.211993 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Dec 12 18:38:14.212004 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Dec 12 18:38:14.212012 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Dec 12 18:38:14.212020 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Dec 12 18:38:14.212028 kernel: ACPI: FACS 0x000000003FFFE000 000040 Dec 12 18:38:14.212036 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Dec 12 18:38:14.212044 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Dec 12 18:38:14.212054 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Dec 12 18:38:14.212062 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Dec 12 18:38:14.212070 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Dec 12 18:38:14.212078 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Dec 12 18:38:14.212086 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Dec 12 18:38:14.212094 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Dec 12 18:38:14.212102 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Dec 12 18:38:14.212110 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Dec 12 18:38:14.212118 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Dec 12 18:38:14.212129 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Dec 12 18:38:14.212137 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Dec 12 18:38:14.212145 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Dec 12 18:38:14.212153 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Dec 12 18:38:14.212161 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Dec 12 18:38:14.212168 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Dec 12 18:38:14.212176 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Dec 12 18:38:14.212184 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Dec 12 18:38:14.212195 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Dec 12 18:38:14.212203 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Dec 12 18:38:14.212211 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Dec 12 18:38:14.212218 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Dec 12 18:38:14.212226 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Dec 12 18:38:14.212234 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Dec 12 18:38:14.212242 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] Dec 12 18:38:14.212250 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] Dec 12 18:38:14.212258 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] Dec 12 18:38:14.212269 kernel: Zone ranges: Dec 12 18:38:14.212277 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Dec 12 18:38:14.212285 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Dec 12 18:38:14.212301 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Dec 12 18:38:14.212309 kernel: Device empty Dec 12 18:38:14.212317 kernel: Movable zone start for each node Dec 12 18:38:14.212325 kernel: Early memory node ranges Dec 12 18:38:14.212333 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Dec 12 18:38:14.212341 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Dec 12 18:38:14.212353 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Dec 12 18:38:14.212369 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Dec 12 18:38:14.212378 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Dec 12 18:38:14.212386 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Dec 12 18:38:14.212394 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Dec 12 18:38:14.212413 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Dec 12 18:38:14.212421 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Dec 12 18:38:14.212429 kernel: ACPI: PM-Timer IO Port: 0x408 Dec 12 18:38:14.212437 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Dec 12 18:38:14.212451 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Dec 12 18:38:14.212459 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Dec 12 18:38:14.212467 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Dec 12 18:38:14.212475 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Dec 12 18:38:14.212483 kernel: CPU topo: Max. logical packages: 1 Dec 12 18:38:14.212491 kernel: CPU topo: Max. logical dies: 1 Dec 12 18:38:14.212499 kernel: CPU topo: Max. dies per package: 1 Dec 12 18:38:14.212507 kernel: CPU topo: Max. threads per core: 1 Dec 12 18:38:14.212515 kernel: CPU topo: Num. cores per package: 6 Dec 12 18:38:14.212525 kernel: CPU topo: Num. threads per package: 6 Dec 12 18:38:14.212533 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs Dec 12 18:38:14.212542 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Dec 12 18:38:14.212549 kernel: Booting paravirtualized kernel on Hyper-V Dec 12 18:38:14.212558 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Dec 12 18:38:14.212566 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Dec 12 18:38:14.212574 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 Dec 12 18:38:14.212644 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 Dec 12 18:38:14.212653 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Dec 12 18:38:14.212666 kernel: Hyper-V: PV spinlocks enabled Dec 12 18:38:14.212675 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Dec 12 18:38:14.212684 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=a214a2d85e162c493e8b13db2df50a43e1005a0e4854a1ae089a14f442a30022 Dec 12 18:38:14.212693 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Dec 12 18:38:14.212701 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Dec 12 18:38:14.212709 kernel: Fallback order for Node 0: 0 Dec 12 18:38:14.212717 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 Dec 12 18:38:14.212725 kernel: Policy zone: Normal Dec 12 18:38:14.212736 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Dec 12 18:38:14.212752 kernel: software IO TLB: area num 8. Dec 12 18:38:14.212760 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Dec 12 18:38:14.212771 kernel: Kernel/User page tables isolation: enabled Dec 12 18:38:14.212780 kernel: ftrace: allocating 40103 entries in 157 pages Dec 12 18:38:14.212788 kernel: ftrace: allocated 157 pages with 5 groups Dec 12 18:38:14.212804 kernel: Dynamic Preempt: voluntary Dec 12 18:38:14.212813 kernel: rcu: Preemptible hierarchical RCU implementation. Dec 12 18:38:14.212822 kernel: rcu: RCU event tracing is enabled. Dec 12 18:38:14.212831 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Dec 12 18:38:14.212839 kernel: Trampoline variant of Tasks RCU enabled. Dec 12 18:38:14.212851 kernel: Rude variant of Tasks RCU enabled. Dec 12 18:38:14.212859 kernel: Tracing variant of Tasks RCU enabled. Dec 12 18:38:14.212868 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Dec 12 18:38:14.212876 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Dec 12 18:38:14.212885 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Dec 12 18:38:14.212894 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Dec 12 18:38:14.212905 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Dec 12 18:38:14.212913 kernel: Using NULL legacy PIC Dec 12 18:38:14.212922 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Dec 12 18:38:14.212930 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Dec 12 18:38:14.212939 kernel: Console: colour dummy device 80x25 Dec 12 18:38:14.212947 kernel: printk: legacy console [tty1] enabled Dec 12 18:38:14.212956 kernel: printk: legacy console [ttyS0] enabled Dec 12 18:38:14.212964 kernel: printk: legacy bootconsole [earlyser0] disabled Dec 12 18:38:14.212972 kernel: ACPI: Core revision 20240827 Dec 12 18:38:14.212983 kernel: Failed to register legacy timer interrupt Dec 12 18:38:14.212992 kernel: APIC: Switch to symmetric I/O mode setup Dec 12 18:38:14.213000 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Dec 12 18:38:14.213008 kernel: Hyper-V: enabling crash_kexec_post_notifiers Dec 12 18:38:14.213017 kernel: Hyper-V: Using IPI hypercalls Dec 12 18:38:14.213025 kernel: APIC: send_IPI() replaced with hv_send_ipi() Dec 12 18:38:14.213034 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Dec 12 18:38:14.213042 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Dec 12 18:38:14.213051 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Dec 12 18:38:14.213061 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Dec 12 18:38:14.213073 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Dec 12 18:38:14.213084 kernel: Hyper-V: Using enlightened APIC (xapic mode) Dec 12 18:38:14.213092 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Dec 12 18:38:14.213103 kernel: APIC: read() replaced with hv_apic_read() Dec 12 18:38:14.213117 kernel: APIC: write() replaced with hv_apic_write() Dec 12 18:38:14.213127 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Dec 12 18:38:14.213140 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Dec 12 18:38:14.213151 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593992) Dec 12 18:38:14.213162 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Dec 12 18:38:14.213171 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Dec 12 18:38:14.213179 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Dec 12 18:38:14.213188 kernel: Spectre V2 : Mitigation: Retpolines Dec 12 18:38:14.213196 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Dec 12 18:38:14.213204 kernel: Speculative Store Bypass: Vulnerable Dec 12 18:38:14.213212 kernel: MDS: Mitigation: Clear CPU buffers Dec 12 18:38:14.213220 kernel: TAA: Mitigation: Clear CPU buffers Dec 12 18:38:14.213229 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Dec 12 18:38:14.213237 kernel: active return thunk: its_return_thunk Dec 12 18:38:14.213245 kernel: ITS: Mitigation: Aligned branch/return thunks Dec 12 18:38:14.213256 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Dec 12 18:38:14.213264 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Dec 12 18:38:14.213272 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Dec 12 18:38:14.213281 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Dec 12 18:38:14.213293 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Dec 12 18:38:14.213308 kernel: Freeing SMP alternatives memory: 32K Dec 12 18:38:14.213318 kernel: pid_max: default: 32768 minimum: 301 Dec 12 18:38:14.213326 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Dec 12 18:38:14.213334 kernel: landlock: Up and running. Dec 12 18:38:14.213342 kernel: SELinux: Initializing. Dec 12 18:38:14.213351 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Dec 12 18:38:14.213363 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Dec 12 18:38:14.213371 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Dec 12 18:38:14.213380 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Dec 12 18:38:14.213388 kernel: signal: max sigframe size: 1776 Dec 12 18:38:14.213397 kernel: rcu: Hierarchical SRCU implementation. Dec 12 18:38:14.213418 kernel: rcu: Max phase no-delay instances is 400. Dec 12 18:38:14.213427 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Dec 12 18:38:14.213435 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Dec 12 18:38:14.213443 kernel: smp: Bringing up secondary CPUs ... Dec 12 18:38:14.213455 kernel: smpboot: x86: Booting SMP configuration: Dec 12 18:38:14.213464 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Dec 12 18:38:14.213472 kernel: smp: Brought up 1 node, 6 CPUs Dec 12 18:38:14.213481 kernel: smpboot: Total of 6 processors activated (31127.90 BogoMIPS) Dec 12 18:38:14.213490 kernel: Memory: 115330900K/117439364K available (14336K kernel code, 2444K rwdata, 26064K rodata, 46188K init, 2572K bss, 2098188K reserved, 0K cma-reserved) Dec 12 18:38:14.213498 kernel: devtmpfs: initialized Dec 12 18:38:14.213507 kernel: x86/mm: Memory block size: 1024MB Dec 12 18:38:14.213515 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Dec 12 18:38:14.213524 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Dec 12 18:38:14.213535 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Dec 12 18:38:14.213543 kernel: pinctrl core: initialized pinctrl subsystem Dec 12 18:38:14.213552 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Dec 12 18:38:14.213560 kernel: audit: initializing netlink subsys (disabled) Dec 12 18:38:14.213569 kernel: audit: type=2000 audit(1765564690.028:1): state=initialized audit_enabled=0 res=1 Dec 12 18:38:14.213577 kernel: thermal_sys: Registered thermal governor 'step_wise' Dec 12 18:38:14.213586 kernel: thermal_sys: Registered thermal governor 'user_space' Dec 12 18:38:14.213594 kernel: cpuidle: using governor menu Dec 12 18:38:14.213602 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Dec 12 18:38:14.213613 kernel: dca service started, version 1.12.1 Dec 12 18:38:14.213622 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Dec 12 18:38:14.213630 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Dec 12 18:38:14.213639 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Dec 12 18:38:14.213647 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Dec 12 18:38:14.213656 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Dec 12 18:38:14.213664 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Dec 12 18:38:14.213672 kernel: ACPI: Added _OSI(Module Device) Dec 12 18:38:14.213681 kernel: ACPI: Added _OSI(Processor Device) Dec 12 18:38:14.213692 kernel: ACPI: Added _OSI(Processor Aggregator Device) Dec 12 18:38:14.213700 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Dec 12 18:38:14.213709 kernel: ACPI: Interpreter enabled Dec 12 18:38:14.213717 kernel: ACPI: PM: (supports S0 S5) Dec 12 18:38:14.213725 kernel: ACPI: Using IOAPIC for interrupt routing Dec 12 18:38:14.213734 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Dec 12 18:38:14.213742 kernel: PCI: Ignoring E820 reservations for host bridge windows Dec 12 18:38:14.213751 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Dec 12 18:38:14.213759 kernel: iommu: Default domain type: Translated Dec 12 18:38:14.213770 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Dec 12 18:38:14.213778 kernel: efivars: Registered efivars operations Dec 12 18:38:14.213787 kernel: PCI: Using ACPI for IRQ routing Dec 12 18:38:14.213795 kernel: PCI: System does not support PCI Dec 12 18:38:14.213804 kernel: vgaarb: loaded Dec 12 18:38:14.213812 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Dec 12 18:38:14.213820 kernel: VFS: Disk quotas dquot_6.6.0 Dec 12 18:38:14.213829 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Dec 12 18:38:14.213837 kernel: pnp: PnP ACPI init Dec 12 18:38:14.213848 kernel: pnp: PnP ACPI: found 3 devices Dec 12 18:38:14.213856 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Dec 12 18:38:14.213865 kernel: NET: Registered PF_INET protocol family Dec 12 18:38:14.213873 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Dec 12 18:38:14.213882 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Dec 12 18:38:14.213890 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Dec 12 18:38:14.213899 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Dec 12 18:38:14.213907 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Dec 12 18:38:14.213916 kernel: TCP: Hash tables configured (established 524288 bind 65536) Dec 12 18:38:14.213926 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Dec 12 18:38:14.213935 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Dec 12 18:38:14.213943 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Dec 12 18:38:14.213952 kernel: NET: Registered PF_XDP protocol family Dec 12 18:38:14.213960 kernel: PCI: CLS 0 bytes, default 64 Dec 12 18:38:14.213968 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Dec 12 18:38:14.213977 kernel: software IO TLB: mapped [mem 0x000000003b5bf000-0x000000003f5bf000] (64MB) Dec 12 18:38:14.213985 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Dec 12 18:38:14.213994 kernel: Initialise system trusted keyrings Dec 12 18:38:14.214005 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Dec 12 18:38:14.214013 kernel: Key type asymmetric registered Dec 12 18:38:14.214021 kernel: Asymmetric key parser 'x509' registered Dec 12 18:38:14.214030 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Dec 12 18:38:14.214038 kernel: io scheduler mq-deadline registered Dec 12 18:38:14.214046 kernel: io scheduler kyber registered Dec 12 18:38:14.214055 kernel: io scheduler bfq registered Dec 12 18:38:14.214063 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Dec 12 18:38:14.214071 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Dec 12 18:38:14.214082 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Dec 12 18:38:14.214091 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Dec 12 18:38:14.214099 kernel: i8042: PNP: No PS/2 controller found. Dec 12 18:38:14.214250 kernel: rtc_cmos 00:02: registered as rtc0 Dec 12 18:38:14.214364 kernel: rtc_cmos 00:02: setting system clock to 2025-12-12T18:38:13 UTC (1765564693) Dec 12 18:38:14.214533 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Dec 12 18:38:14.214548 kernel: intel_pstate: CPU model not supported Dec 12 18:38:14.214562 kernel: efifb: probing for efifb Dec 12 18:38:14.214571 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Dec 12 18:38:14.214580 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Dec 12 18:38:14.214588 kernel: efifb: scrolling: redraw Dec 12 18:38:14.214596 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Dec 12 18:38:14.214605 kernel: Console: switching to colour frame buffer device 128x48 Dec 12 18:38:14.214613 kernel: fb0: EFI VGA frame buffer device Dec 12 18:38:14.214622 kernel: pstore: Using crash dump compression: deflate Dec 12 18:38:14.214630 kernel: pstore: Registered efi_pstore as persistent store backend Dec 12 18:38:14.214641 kernel: NET: Registered PF_INET6 protocol family Dec 12 18:38:14.214650 kernel: Segment Routing with IPv6 Dec 12 18:38:14.214658 kernel: In-situ OAM (IOAM) with IPv6 Dec 12 18:38:14.214667 kernel: NET: Registered PF_PACKET protocol family Dec 12 18:38:14.214675 kernel: Key type dns_resolver registered Dec 12 18:38:14.214683 kernel: IPI shorthand broadcast: enabled Dec 12 18:38:14.214692 kernel: sched_clock: Marking stable (3673005100, 109866400)->(4133217600, -350346100) Dec 12 18:38:14.214700 kernel: registered taskstats version 1 Dec 12 18:38:14.214708 kernel: Loading compiled-in X.509 certificates Dec 12 18:38:14.214717 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.61-flatcar: 0d0c78e6590cb40d27f1cef749ef9f2f3425f38d' Dec 12 18:38:14.214728 kernel: Demotion targets for Node 0: null Dec 12 18:38:14.214736 kernel: Key type .fscrypt registered Dec 12 18:38:14.214745 kernel: Key type fscrypt-provisioning registered Dec 12 18:38:14.214753 kernel: ima: No TPM chip found, activating TPM-bypass! Dec 12 18:38:14.214761 kernel: ima: Allocated hash algorithm: sha1 Dec 12 18:38:14.214770 kernel: ima: No architecture policies found Dec 12 18:38:14.214778 kernel: clk: Disabling unused clocks Dec 12 18:38:14.214787 kernel: Warning: unable to open an initial console. Dec 12 18:38:14.214795 kernel: Freeing unused kernel image (initmem) memory: 46188K Dec 12 18:38:14.214806 kernel: Write protecting the kernel read-only data: 40960k Dec 12 18:38:14.214815 kernel: Freeing unused kernel image (rodata/data gap) memory: 560K Dec 12 18:38:14.214823 kernel: Run /init as init process Dec 12 18:38:14.214832 kernel: with arguments: Dec 12 18:38:14.214840 kernel: /init Dec 12 18:38:14.214848 kernel: with environment: Dec 12 18:38:14.214856 kernel: HOME=/ Dec 12 18:38:14.214864 kernel: TERM=linux Dec 12 18:38:14.214874 systemd[1]: Successfully made /usr/ read-only. Dec 12 18:38:14.214888 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) Dec 12 18:38:14.214897 systemd[1]: Detected virtualization microsoft. Dec 12 18:38:14.214906 systemd[1]: Detected architecture x86-64. Dec 12 18:38:14.214915 systemd[1]: Running in initrd. Dec 12 18:38:14.214923 systemd[1]: No hostname configured, using default hostname. Dec 12 18:38:14.214932 systemd[1]: Hostname set to . Dec 12 18:38:14.214941 systemd[1]: Initializing machine ID from random generator. Dec 12 18:38:14.214952 systemd[1]: Queued start job for default target initrd.target. Dec 12 18:38:14.214961 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Dec 12 18:38:14.214970 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Dec 12 18:38:14.214980 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Dec 12 18:38:14.214989 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Dec 12 18:38:14.214998 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Dec 12 18:38:14.215008 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Dec 12 18:38:14.215020 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Dec 12 18:38:14.215029 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Dec 12 18:38:14.215038 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Dec 12 18:38:14.215047 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Dec 12 18:38:14.215056 systemd[1]: Reached target paths.target - Path Units. Dec 12 18:38:14.215065 systemd[1]: Reached target slices.target - Slice Units. Dec 12 18:38:14.215074 systemd[1]: Reached target swap.target - Swaps. Dec 12 18:38:14.215083 systemd[1]: Reached target timers.target - Timer Units. Dec 12 18:38:14.215094 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Dec 12 18:38:14.215103 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Dec 12 18:38:14.215112 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Dec 12 18:38:14.215121 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Dec 12 18:38:14.215130 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Dec 12 18:38:14.215139 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Dec 12 18:38:14.215148 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Dec 12 18:38:14.215157 systemd[1]: Reached target sockets.target - Socket Units. Dec 12 18:38:14.215165 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Dec 12 18:38:14.215177 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Dec 12 18:38:14.215186 systemd[1]: Finished network-cleanup.service - Network Cleanup. Dec 12 18:38:14.215195 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). Dec 12 18:38:14.215204 systemd[1]: Starting systemd-fsck-usr.service... Dec 12 18:38:14.215213 systemd[1]: Starting systemd-journald.service - Journal Service... Dec 12 18:38:14.215222 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Dec 12 18:38:14.215231 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Dec 12 18:38:14.215259 systemd-journald[213]: Collecting audit messages is disabled. Dec 12 18:38:14.215284 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Dec 12 18:38:14.215297 systemd-journald[213]: Journal started Dec 12 18:38:14.215317 systemd-journald[213]: Runtime Journal (/run/log/journal/4ec5feb5ed324bbfaa70d258918ec832) is 8M, max 2.2G, 2.1G free. Dec 12 18:38:14.213331 systemd-modules-load[216]: Inserted module 'overlay' Dec 12 18:38:14.230167 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Dec 12 18:38:14.230544 systemd[1]: Started systemd-journald.service - Journal Service. Dec 12 18:38:14.230666 systemd[1]: Finished systemd-fsck-usr.service. Dec 12 18:38:14.232819 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Dec 12 18:38:14.234069 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Dec 12 18:38:14.282478 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Dec 12 18:38:14.283876 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Dec 12 18:38:14.295252 kernel: Bridge firewalling registered Dec 12 18:38:14.286143 systemd-modules-load[216]: Inserted module 'br_netfilter' Dec 12 18:38:14.286819 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Dec 12 18:38:14.299051 systemd-tmpfiles[226]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Dec 12 18:38:14.300038 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Dec 12 18:38:14.303703 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Dec 12 18:38:14.319797 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Dec 12 18:38:14.324732 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Dec 12 18:38:14.363650 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Dec 12 18:38:14.378531 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Dec 12 18:38:14.379171 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Dec 12 18:38:14.380645 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Dec 12 18:38:14.386899 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Dec 12 18:38:14.397307 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Dec 12 18:38:14.418278 dracut-cmdline[257]: 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=a214a2d85e162c493e8b13db2df50a43e1005a0e4854a1ae089a14f442a30022 Dec 12 18:38:14.430671 systemd-resolved[249]: Positive Trust Anchors: Dec 12 18:38:14.430680 systemd-resolved[249]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Dec 12 18:38:14.430711 systemd-resolved[249]: 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 Dec 12 18:38:14.433173 systemd-resolved[249]: Defaulting to hostname 'linux'. Dec 12 18:38:14.434143 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Dec 12 18:38:14.447671 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Dec 12 18:38:14.577430 kernel: SCSI subsystem initialized Dec 12 18:38:14.587422 kernel: Loading iSCSI transport class v2.0-870. Dec 12 18:38:14.599424 kernel: iscsi: registered transport (tcp) Dec 12 18:38:14.622308 kernel: iscsi: registered transport (qla4xxx) Dec 12 18:38:14.622339 kernel: QLogic iSCSI HBA Driver Dec 12 18:38:14.640380 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Dec 12 18:38:14.674820 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Dec 12 18:38:14.686839 systemd[1]: Reached target network-pre.target - Preparation for Network. Dec 12 18:38:14.733981 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Dec 12 18:38:14.735265 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Dec 12 18:38:14.810425 kernel: raid6: avx2x4 gen() 30104 MB/s Dec 12 18:38:14.829415 kernel: raid6: avx2x2 gen() 30496 MB/s Dec 12 18:38:14.851636 kernel: raid6: avx2x1 gen() 26471 MB/s Dec 12 18:38:14.851665 kernel: raid6: using algorithm avx2x2 gen() 30496 MB/s Dec 12 18:38:14.873673 kernel: raid6: .... xor() 18397 MB/s, rmw enabled Dec 12 18:38:14.873698 kernel: raid6: using avx2x2 recovery algorithm Dec 12 18:38:14.896424 kernel: xor: automatically using best checksumming function avx Dec 12 18:38:15.066431 kernel: Btrfs loaded, zoned=no, fsverity=no Dec 12 18:38:15.072984 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Dec 12 18:38:15.074520 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Dec 12 18:38:15.113481 systemd-udevd[467]: Using default interface naming scheme 'v255'. Dec 12 18:38:15.119617 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Dec 12 18:38:15.120493 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Dec 12 18:38:15.162757 dracut-pre-trigger[471]: rd.md=0: removing MD RAID activation Dec 12 18:38:15.190220 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Dec 12 18:38:15.191369 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Dec 12 18:38:15.265824 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Dec 12 18:38:15.280426 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Dec 12 18:38:15.295419 kernel: cryptd: max_cpu_qlen set to 1000 Dec 12 18:38:15.312168 kernel: AES CTR mode by8 optimization enabled Dec 12 18:38:15.317425 kernel: hv_vmbus: Vmbus version:4.0 Dec 12 18:38:15.324425 kernel: hv_vmbus: registering driver hyperv_keyboard Dec 12 18:38:15.324466 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 Dec 12 18:38:15.339832 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Dec 12 18:38:15.345639 kernel: pps_core: LinuxPPS API ver. 1 registered Dec 12 18:38:15.345660 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Dec 12 18:38:15.341622 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Dec 12 18:38:15.355030 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Dec 12 18:38:15.363560 kernel: PTP clock support registered Dec 12 18:38:15.368845 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Dec 12 18:38:15.372576 kernel: hv_vmbus: registering driver hv_storvsc Dec 12 18:38:15.372965 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Dec 12 18:38:15.390297 kernel: hid: raw HID events driver (C) Jiri Kosina Dec 12 18:38:15.390317 kernel: scsi host0: storvsc_host_t Dec 12 18:38:15.395423 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Dec 12 18:38:15.395584 kernel: scsi host1: storvsc_host_t Dec 12 18:38:15.398426 kernel: hv_utils: Registering HyperV Utility Driver Dec 12 18:38:15.398461 kernel: hv_vmbus: registering driver hv_netvsc Dec 12 18:38:15.399416 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Dec 12 18:38:15.400452 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Dec 12 18:38:15.400489 kernel: hv_vmbus: registering driver hv_pci Dec 12 18:38:15.401463 kernel: hv_vmbus: registering driver hv_utils Dec 12 18:38:15.401493 kernel: hv_utils: Shutdown IC version 3.2 Dec 12 18:38:15.417894 kernel: hv_vmbus: registering driver hid_hyperv Dec 12 18:38:15.417925 kernel: hv_utils: Heartbeat IC version 3.0 Dec 12 18:38:15.936791 kernel: hv_utils: TimeSync IC version 4.0 Dec 12 18:38:15.936820 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Dec 12 18:38:15.937241 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Dec 12 18:38:15.937257 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Dec 12 18:38:15.937409 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Dec 12 18:38:15.937590 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Dec 12 18:38:15.937792 kernel: sd 0:0:0:0: [sda] Write Protect is off Dec 12 18:38:15.937932 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Dec 12 18:38:15.939516 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Dec 12 18:38:15.939846 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#583 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Dec 12 18:38:15.939991 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#590 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Dec 12 18:38:15.940110 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Dec 12 18:38:15.938104 systemd-resolved[249]: Clock change detected. Flushing caches. Dec 12 18:38:16.033442 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Dec 12 18:38:16.033627 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks Dec 12 18:38:16.033780 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Dec 12 18:38:16.033924 kernel: sd 0:0:0:1: [sdb] Write Protect is off Dec 12 18:38:16.034056 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Dec 12 18:38:16.034175 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Dec 12 18:38:16.034306 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Dec 12 18:38:16.034440 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Dec 12 18:38:16.034576 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint Dec 12 18:38:16.034605 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#595 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Dec 12 18:38:16.034725 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] Dec 12 18:38:16.034746 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#602 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Dec 12 18:38:16.034860 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] Dec 12 18:38:16.034885 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] Dec 12 18:38:16.043529 kernel: pci 0001:00:00.0: Enabling HDA controller Dec 12 18:38:16.056444 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Dec 12 18:38:16.060251 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Dec 12 18:38:16.086629 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Dec 12 18:38:16.086809 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Dec 12 18:38:16.086964 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned Dec 12 18:38:16.087121 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned Dec 12 18:38:16.087255 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned Dec 12 18:38:16.087382 kernel: sdb: sdb1 Dec 12 18:38:16.087395 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk Dec 12 18:38:16.097342 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Dec 12 18:38:16.097546 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Dec 12 18:38:16.100457 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Dec 12 18:38:16.573745 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Dec 12 18:38:16.585249 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Dec 12 18:38:16.596753 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Dec 12 18:38:16.628653 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Dec 12 18:38:16.633864 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Dec 12 18:38:16.646300 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Dec 12 18:38:16.681439 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#56 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Dec 12 18:38:16.703454 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Dec 12 18:38:16.712453 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#203 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Dec 12 18:38:16.737447 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Dec 12 18:38:17.744693 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#570 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Dec 12 18:38:17.762346 disk-uuid[704]: The operation has completed successfully. Dec 12 18:38:17.766214 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Dec 12 18:38:18.483392 systemd[1]: disk-uuid.service: Deactivated successfully. Dec 12 18:38:18.483561 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Dec 12 18:38:18.492970 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Dec 12 18:38:18.511585 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Dec 12 18:38:18.514478 sh[867]: Success Dec 12 18:38:18.520780 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Dec 12 18:38:18.522109 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Dec 12 18:38:18.532057 systemd[1]: Reached target remote-fs.target - Remote File Systems. Dec 12 18:38:18.542108 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Dec 12 18:38:18.569437 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Dec 12 18:38:18.569470 kernel: device-mapper: uevent: version 1.0.3 Dec 12 18:38:18.578439 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Dec 12 18:38:18.584943 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Dec 12 18:38:18.596900 kernel: device-mapper: verity: sha256 using shash "sha256-avx2" Dec 12 18:38:18.832183 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Dec 12 18:38:18.834290 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Dec 12 18:38:18.857198 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Dec 12 18:38:18.874491 kernel: BTRFS: device fsid a6ae7f96-a076-4d3c-81ed-46dd341492f8 devid 1 transid 37 /dev/mapper/usr (254:0) scanned by mount (894) Dec 12 18:38:18.883526 kernel: BTRFS info (device dm-0): first mount of filesystem a6ae7f96-a076-4d3c-81ed-46dd341492f8 Dec 12 18:38:18.883550 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Dec 12 18:38:19.237373 kernel: BTRFS info (device dm-0): disabling log replay at mount time Dec 12 18:38:19.237443 kernel: BTRFS info (device dm-0): enabling free space tree Dec 12 18:38:19.303150 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Dec 12 18:38:19.303747 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Dec 12 18:38:19.310110 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Dec 12 18:38:19.310924 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Dec 12 18:38:19.337678 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Dec 12 18:38:19.372453 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (925) Dec 12 18:38:19.381551 kernel: BTRFS info (device sda6): first mount of filesystem 7e9ead35-f0ec-40e8-bc31-5061934f865a Dec 12 18:38:19.381588 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Dec 12 18:38:19.403831 kernel: BTRFS info (device sda6): turning on async discard Dec 12 18:38:19.403863 kernel: BTRFS info (device sda6): enabling free space tree Dec 12 18:38:19.412435 kernel: BTRFS info (device sda6): last unmount of filesystem 7e9ead35-f0ec-40e8-bc31-5061934f865a Dec 12 18:38:19.412822 systemd[1]: Finished ignition-setup.service - Ignition (setup). Dec 12 18:38:19.413816 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Dec 12 18:38:19.452655 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Dec 12 18:38:19.463258 systemd[1]: Starting systemd-networkd.service - Network Configuration... Dec 12 18:38:19.526702 systemd-networkd[1063]: lo: Link UP Dec 12 18:38:19.526712 systemd-networkd[1063]: lo: Gained carrier Dec 12 18:38:19.527704 systemd-networkd[1063]: Enumeration completed Dec 12 18:38:19.527805 systemd[1]: Started systemd-networkd.service - Network Configuration. Dec 12 18:38:19.528058 systemd-networkd[1063]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Dec 12 18:38:19.528062 systemd-networkd[1063]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Dec 12 18:38:19.528877 systemd-networkd[1063]: eth0: Link UP Dec 12 18:38:19.529040 systemd-networkd[1063]: eth0: Gained carrier Dec 12 18:38:19.529050 systemd-networkd[1063]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Dec 12 18:38:19.529676 systemd[1]: Reached target network.target - Network. Dec 12 18:38:19.588466 systemd-networkd[1063]: eth0: DHCPv4 address 10.200.4.28/24, gateway 10.200.4.1 acquired from 168.63.129.16 Dec 12 18:38:20.707560 systemd-networkd[1063]: eth0: Gained IPv6LL Dec 12 18:38:20.711777 ignition[1037]: Ignition 2.22.0 Dec 12 18:38:20.711791 ignition[1037]: Stage: fetch-offline Dec 12 18:38:20.713875 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Dec 12 18:38:20.711926 ignition[1037]: no configs at "/usr/lib/ignition/base.d" Dec 12 18:38:20.715557 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Dec 12 18:38:20.711936 ignition[1037]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Dec 12 18:38:20.712059 ignition[1037]: parsed url from cmdline: "" Dec 12 18:38:20.712063 ignition[1037]: no config URL provided Dec 12 18:38:20.712069 ignition[1037]: reading system config file "/usr/lib/ignition/user.ign" Dec 12 18:38:20.712078 ignition[1037]: no config at "/usr/lib/ignition/user.ign" Dec 12 18:38:20.712083 ignition[1037]: failed to fetch config: resource requires networking Dec 12 18:38:20.712266 ignition[1037]: Ignition finished successfully Dec 12 18:38:20.763125 ignition[1075]: Ignition 2.22.0 Dec 12 18:38:20.763131 ignition[1075]: Stage: fetch Dec 12 18:38:20.763361 ignition[1075]: no configs at "/usr/lib/ignition/base.d" Dec 12 18:38:20.763372 ignition[1075]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Dec 12 18:38:20.763495 ignition[1075]: parsed url from cmdline: "" Dec 12 18:38:20.763499 ignition[1075]: no config URL provided Dec 12 18:38:20.763504 ignition[1075]: reading system config file "/usr/lib/ignition/user.ign" Dec 12 18:38:20.763514 ignition[1075]: no config at "/usr/lib/ignition/user.ign" Dec 12 18:38:20.763533 ignition[1075]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Dec 12 18:38:20.872227 ignition[1075]: GET result: OK Dec 12 18:38:20.872273 ignition[1075]: config has been read from IMDS userdata Dec 12 18:38:20.872285 ignition[1075]: parsing config with SHA512: d1f34cafadd185b4b51915973accedc5e7e5954bb07630d3cb5dcc609511e0059d9986183ec5869e9346b536333c72d0e7af1159d194ddeadb05b65a2ff0ccf0 Dec 12 18:38:20.875366 unknown[1075]: fetched base config from "system" Dec 12 18:38:20.875526 ignition[1075]: fetch: fetch complete Dec 12 18:38:20.875374 unknown[1075]: fetched base config from "system" Dec 12 18:38:20.875531 ignition[1075]: fetch: fetch passed Dec 12 18:38:20.875380 unknown[1075]: fetched user config from "azure" Dec 12 18:38:20.875571 ignition[1075]: Ignition finished successfully Dec 12 18:38:20.886444 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Dec 12 18:38:20.889607 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Dec 12 18:38:20.947954 ignition[1084]: Ignition 2.22.0 Dec 12 18:38:20.947970 ignition[1084]: Stage: kargs Dec 12 18:38:20.948228 ignition[1084]: no configs at "/usr/lib/ignition/base.d" Dec 12 18:38:20.948249 ignition[1084]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Dec 12 18:38:20.948898 ignition[1084]: kargs: kargs passed Dec 12 18:38:20.948951 ignition[1084]: Ignition finished successfully Dec 12 18:38:20.964774 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Dec 12 18:38:20.972409 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Dec 12 18:38:21.019371 ignition[1094]: Ignition 2.22.0 Dec 12 18:38:21.019385 ignition[1094]: Stage: disks Dec 12 18:38:21.019623 ignition[1094]: no configs at "/usr/lib/ignition/base.d" Dec 12 18:38:21.019633 ignition[1094]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Dec 12 18:38:21.025673 systemd[1]: Finished ignition-disks.service - Ignition (disks). Dec 12 18:38:21.020119 ignition[1094]: disks: disks passed Dec 12 18:38:21.028910 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Dec 12 18:38:21.020160 ignition[1094]: Ignition finished successfully Dec 12 18:38:21.037266 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Dec 12 18:38:21.050869 systemd[1]: Reached target local-fs.target - Local File Systems. Dec 12 18:38:21.057543 systemd[1]: Reached target sysinit.target - System Initialization. Dec 12 18:38:21.066388 systemd[1]: Reached target basic.target - Basic System. Dec 12 18:38:21.074057 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Dec 12 18:38:21.152247 systemd-fsck[1105]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks Dec 12 18:38:21.159415 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Dec 12 18:38:21.160404 systemd[1]: Mounting sysroot.mount - /sysroot... Dec 12 18:38:21.417437 kernel: EXT4-fs (sda9): mounted filesystem e48ca59c-1206-4abd-b121-5e9b35e49852 r/w with ordered data mode. Quota mode: none. Dec 12 18:38:21.418062 systemd[1]: Mounted sysroot.mount - /sysroot. Dec 12 18:38:21.418699 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Dec 12 18:38:21.430889 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Dec 12 18:38:21.459926 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Dec 12 18:38:21.496295 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1119) Dec 12 18:38:21.496325 kernel: BTRFS info (device sda6): first mount of filesystem 7e9ead35-f0ec-40e8-bc31-5061934f865a Dec 12 18:38:21.496339 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Dec 12 18:38:21.461026 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Dec 12 18:38:21.461582 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Dec 12 18:38:21.461611 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Dec 12 18:38:21.521554 kernel: BTRFS info (device sda6): turning on async discard Dec 12 18:38:21.521575 kernel: BTRFS info (device sda6): enabling free space tree Dec 12 18:38:21.466886 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Dec 12 18:38:21.468172 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Dec 12 18:38:21.526693 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Dec 12 18:38:22.208942 coreos-metadata[1121]: Dec 12 18:38:22.208 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Dec 12 18:38:22.215866 coreos-metadata[1121]: Dec 12 18:38:22.215 INFO Fetch successful Dec 12 18:38:22.215866 coreos-metadata[1121]: Dec 12 18:38:22.215 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Dec 12 18:38:22.228129 coreos-metadata[1121]: Dec 12 18:38:22.219 INFO Fetch successful Dec 12 18:38:22.232195 coreos-metadata[1121]: Dec 12 18:38:22.232 INFO wrote hostname ci-4459.2.2-a-cd988c9c36 to /sysroot/etc/hostname Dec 12 18:38:22.233343 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Dec 12 18:38:22.673893 initrd-setup-root[1149]: cut: /sysroot/etc/passwd: No such file or directory Dec 12 18:38:22.728496 initrd-setup-root[1156]: cut: /sysroot/etc/group: No such file or directory Dec 12 18:38:22.743911 initrd-setup-root[1163]: cut: /sysroot/etc/shadow: No such file or directory Dec 12 18:38:22.748705 initrd-setup-root[1170]: cut: /sysroot/etc/gshadow: No such file or directory Dec 12 18:38:23.592243 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Dec 12 18:38:23.598114 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Dec 12 18:38:23.605962 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Dec 12 18:38:23.627459 systemd[1]: sysroot-oem.mount: Deactivated successfully. Dec 12 18:38:23.636593 kernel: BTRFS info (device sda6): last unmount of filesystem 7e9ead35-f0ec-40e8-bc31-5061934f865a Dec 12 18:38:23.658745 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Dec 12 18:38:23.668263 ignition[1239]: INFO : Ignition 2.22.0 Dec 12 18:38:23.668263 ignition[1239]: INFO : Stage: mount Dec 12 18:38:23.674554 ignition[1239]: INFO : no configs at "/usr/lib/ignition/base.d" Dec 12 18:38:23.674554 ignition[1239]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Dec 12 18:38:23.674554 ignition[1239]: INFO : mount: mount passed Dec 12 18:38:23.674554 ignition[1239]: INFO : Ignition finished successfully Dec 12 18:38:23.675387 systemd[1]: Finished ignition-mount.service - Ignition (mount). Dec 12 18:38:23.691891 systemd[1]: Starting ignition-files.service - Ignition (files)... Dec 12 18:38:23.732356 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Dec 12 18:38:23.758442 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1252) Dec 12 18:38:23.767732 kernel: BTRFS info (device sda6): first mount of filesystem 7e9ead35-f0ec-40e8-bc31-5061934f865a Dec 12 18:38:23.767761 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Dec 12 18:38:23.780579 kernel: BTRFS info (device sda6): turning on async discard Dec 12 18:38:23.780608 kernel: BTRFS info (device sda6): enabling free space tree Dec 12 18:38:23.782749 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Dec 12 18:38:23.824576 ignition[1269]: INFO : Ignition 2.22.0 Dec 12 18:38:23.824576 ignition[1269]: INFO : Stage: files Dec 12 18:38:23.831065 ignition[1269]: INFO : no configs at "/usr/lib/ignition/base.d" Dec 12 18:38:23.831065 ignition[1269]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Dec 12 18:38:23.831065 ignition[1269]: DEBUG : files: compiled without relabeling support, skipping Dec 12 18:38:23.831065 ignition[1269]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Dec 12 18:38:23.831065 ignition[1269]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Dec 12 18:38:23.922475 ignition[1269]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Dec 12 18:38:23.928266 ignition[1269]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Dec 12 18:38:23.928266 ignition[1269]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Dec 12 18:38:23.923141 unknown[1269]: wrote ssh authorized keys file for user: core Dec 12 18:38:23.954460 ignition[1269]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Dec 12 18:38:23.961905 ignition[1269]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Dec 12 18:38:23.961905 ignition[1269]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Dec 12 18:38:23.961905 ignition[1269]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Dec 12 18:38:23.961905 ignition[1269]: INFO : files: files passed Dec 12 18:38:23.961905 ignition[1269]: INFO : Ignition finished successfully Dec 12 18:38:23.977773 systemd[1]: Finished ignition-files.service - Ignition (files). Dec 12 18:38:23.984488 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Dec 12 18:38:24.016645 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Dec 12 18:38:24.020023 systemd[1]: ignition-quench.service: Deactivated successfully. Dec 12 18:38:24.020119 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Dec 12 18:38:24.047696 initrd-setup-root-after-ignition[1302]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Dec 12 18:38:24.047696 initrd-setup-root-after-ignition[1302]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Dec 12 18:38:24.060723 initrd-setup-root-after-ignition[1306]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Dec 12 18:38:24.069186 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Dec 12 18:38:24.069533 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Dec 12 18:38:24.079998 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Dec 12 18:38:24.126874 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Dec 12 18:38:24.126978 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Dec 12 18:38:24.131038 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Dec 12 18:38:24.140790 systemd[1]: Reached target initrd.target - Initrd Default Target. Dec 12 18:38:24.148921 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Dec 12 18:38:24.149652 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Dec 12 18:38:24.207513 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Dec 12 18:38:24.208766 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Dec 12 18:38:24.244562 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Dec 12 18:38:24.244769 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Dec 12 18:38:24.254270 systemd[1]: Stopped target timers.target - Timer Units. Dec 12 18:38:24.263718 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Dec 12 18:38:24.263833 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Dec 12 18:38:24.279418 systemd[1]: Stopped target initrd.target - Initrd Default Target. Dec 12 18:38:24.284278 systemd[1]: Stopped target basic.target - Basic System. Dec 12 18:38:24.292381 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Dec 12 18:38:24.299572 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Dec 12 18:38:24.308348 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Dec 12 18:38:24.317685 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Dec 12 18:38:24.326359 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Dec 12 18:38:24.344021 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Dec 12 18:38:24.344241 systemd[1]: Stopped target sysinit.target - System Initialization. Dec 12 18:38:24.354196 systemd[1]: Stopped target local-fs.target - Local File Systems. Dec 12 18:38:24.362208 systemd[1]: Stopped target swap.target - Swaps. Dec 12 18:38:24.370029 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Dec 12 18:38:24.370168 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Dec 12 18:38:24.384523 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Dec 12 18:38:24.388730 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Dec 12 18:38:24.397312 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Dec 12 18:38:24.397455 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Dec 12 18:38:24.406518 systemd[1]: dracut-initqueue.service: Deactivated successfully. Dec 12 18:38:24.406637 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Dec 12 18:38:24.424999 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Dec 12 18:38:24.425120 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Dec 12 18:38:24.428916 systemd[1]: ignition-files.service: Deactivated successfully. Dec 12 18:38:24.429035 systemd[1]: Stopped ignition-files.service - Ignition (files). Dec 12 18:38:24.438058 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Dec 12 18:38:24.438181 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Dec 12 18:38:24.447410 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Dec 12 18:38:24.475750 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Dec 12 18:38:24.477053 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Dec 12 18:38:24.501699 ignition[1327]: INFO : Ignition 2.22.0 Dec 12 18:38:24.501699 ignition[1327]: INFO : Stage: umount Dec 12 18:38:24.501699 ignition[1327]: INFO : no configs at "/usr/lib/ignition/base.d" Dec 12 18:38:24.501699 ignition[1327]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Dec 12 18:38:24.501699 ignition[1327]: INFO : umount: umount passed Dec 12 18:38:24.501699 ignition[1327]: INFO : Ignition finished successfully Dec 12 18:38:24.477183 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Dec 12 18:38:24.489198 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Dec 12 18:38:24.489317 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Dec 12 18:38:24.503386 systemd[1]: ignition-mount.service: Deactivated successfully. Dec 12 18:38:24.503499 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Dec 12 18:38:24.513833 systemd[1]: initrd-cleanup.service: Deactivated successfully. Dec 12 18:38:24.513938 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Dec 12 18:38:24.518335 systemd[1]: sysroot-boot.mount: Deactivated successfully. Dec 12 18:38:24.519099 systemd[1]: ignition-disks.service: Deactivated successfully. Dec 12 18:38:24.519163 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Dec 12 18:38:24.532800 systemd[1]: ignition-kargs.service: Deactivated successfully. Dec 12 18:38:24.532852 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Dec 12 18:38:24.536416 systemd[1]: ignition-fetch.service: Deactivated successfully. Dec 12 18:38:24.536522 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Dec 12 18:38:24.549052 systemd[1]: Stopped target network.target - Network. Dec 12 18:38:24.556318 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Dec 12 18:38:24.556370 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Dec 12 18:38:24.565520 systemd[1]: Stopped target paths.target - Path Units. Dec 12 18:38:24.572786 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Dec 12 18:38:24.580473 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Dec 12 18:38:24.585338 systemd[1]: Stopped target slices.target - Slice Units. Dec 12 18:38:24.597572 systemd[1]: Stopped target sockets.target - Socket Units. Dec 12 18:38:24.605106 systemd[1]: iscsid.socket: Deactivated successfully. Dec 12 18:38:24.605150 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Dec 12 18:38:24.609001 systemd[1]: iscsiuio.socket: Deactivated successfully. Dec 12 18:38:24.609043 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Dec 12 18:38:24.616740 systemd[1]: ignition-setup.service: Deactivated successfully. Dec 12 18:38:24.616790 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Dec 12 18:38:24.624749 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Dec 12 18:38:24.624796 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Dec 12 18:38:24.632766 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Dec 12 18:38:24.640768 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Dec 12 18:38:24.648314 systemd[1]: systemd-resolved.service: Deactivated successfully. Dec 12 18:38:24.648412 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Dec 12 18:38:24.666623 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Dec 12 18:38:24.666967 systemd[1]: sysroot-boot.service: Deactivated successfully. Dec 12 18:38:24.667058 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Dec 12 18:38:24.669211 systemd[1]: systemd-networkd.service: Deactivated successfully. Dec 12 18:38:24.669308 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Dec 12 18:38:24.688735 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Dec 12 18:38:24.690010 systemd[1]: Stopped target network-pre.target - Preparation for Network. Dec 12 18:38:24.691621 systemd[1]: systemd-networkd.socket: Deactivated successfully. Dec 12 18:38:24.691678 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Dec 12 18:38:24.699661 systemd[1]: initrd-setup-root.service: Deactivated successfully. Dec 12 18:38:24.699723 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Dec 12 18:38:24.708552 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Dec 12 18:38:24.715728 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Dec 12 18:38:24.715783 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Dec 12 18:38:24.722855 systemd[1]: systemd-sysctl.service: Deactivated successfully. Dec 12 18:38:24.722901 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Dec 12 18:38:24.739742 systemd[1]: systemd-modules-load.service: Deactivated successfully. Dec 12 18:38:24.739787 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Dec 12 18:38:24.743617 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Dec 12 18:38:24.743666 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Dec 12 18:38:24.757194 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Dec 12 18:38:24.766831 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Dec 12 18:38:24.766902 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Dec 12 18:38:24.788339 systemd[1]: systemd-udevd.service: Deactivated successfully. Dec 12 18:38:24.795556 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Dec 12 18:38:24.799396 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Dec 12 18:38:24.799459 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Dec 12 18:38:24.808351 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Dec 12 18:38:24.808384 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Dec 12 18:38:24.822042 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Dec 12 18:38:24.822091 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Dec 12 18:38:24.834373 systemd[1]: dracut-cmdline.service: Deactivated successfully. Dec 12 18:38:24.834437 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Dec 12 18:38:24.846785 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Dec 12 18:38:24.846834 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Dec 12 18:38:24.860686 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Dec 12 18:38:24.864215 systemd[1]: systemd-network-generator.service: Deactivated successfully. Dec 12 18:38:24.864269 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Dec 12 18:38:24.882485 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Dec 12 18:38:24.882536 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Dec 12 18:38:24.895677 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Dec 12 18:38:24.895723 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Dec 12 18:38:24.909220 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Dec 12 18:38:24.909268 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Dec 12 18:38:24.919557 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Dec 12 18:38:24.919602 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Dec 12 18:38:24.935607 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Dec 12 18:38:24.935705 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev\x2dearly.service.mount: Deactivated successfully. Dec 12 18:38:24.935753 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Dec 12 18:38:24.935796 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Dec 12 18:38:24.936185 systemd[1]: network-cleanup.service: Deactivated successfully. Dec 12 18:38:24.936277 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Dec 12 18:38:24.940274 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Dec 12 18:38:24.940358 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Dec 12 18:38:24.948624 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Dec 12 18:38:24.958439 systemd[1]: Starting initrd-switch-root.service - Switch Root... Dec 12 18:38:25.111618 systemd-journald[213]: Received SIGTERM from PID 1 (systemd). Dec 12 18:38:24.992005 systemd[1]: Switching root. Dec 12 18:38:25.114512 systemd-journald[213]: Journal stopped Dec 12 18:38:29.924787 kernel: SELinux: policy capability network_peer_controls=1 Dec 12 18:38:29.924821 kernel: SELinux: policy capability open_perms=1 Dec 12 18:38:29.924833 kernel: SELinux: policy capability extended_socket_class=1 Dec 12 18:38:29.924846 kernel: SELinux: policy capability always_check_network=0 Dec 12 18:38:29.924855 kernel: SELinux: policy capability cgroup_seclabel=1 Dec 12 18:38:29.924864 kernel: SELinux: policy capability nnp_nosuid_transition=1 Dec 12 18:38:29.924874 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Dec 12 18:38:29.924886 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Dec 12 18:38:29.924895 kernel: SELinux: policy capability userspace_initial_context=0 Dec 12 18:38:29.924904 kernel: audit: type=1403 audit(1765564706.233:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Dec 12 18:38:29.924916 systemd[1]: Successfully loaded SELinux policy in 247.314ms. Dec 12 18:38:29.924928 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 7.584ms. Dec 12 18:38:29.924939 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) Dec 12 18:38:29.924950 systemd[1]: Detected virtualization microsoft. Dec 12 18:38:29.924963 systemd[1]: Detected architecture x86-64. Dec 12 18:38:29.924974 systemd[1]: Detected first boot. Dec 12 18:38:29.924985 systemd[1]: Hostname set to . Dec 12 18:38:29.924996 systemd[1]: Initializing machine ID from random generator. Dec 12 18:38:29.925007 zram_generator::config[1373]: No configuration found. Dec 12 18:38:29.925020 kernel: Guest personality initialized and is inactive Dec 12 18:38:29.925030 kernel: VMCI host device registered (name=vmci, major=10, minor=259) Dec 12 18:38:29.925040 kernel: Initialized host personality Dec 12 18:38:29.925049 kernel: NET: Registered PF_VSOCK protocol family Dec 12 18:38:29.925059 systemd[1]: Populated /etc with preset unit settings. Dec 12 18:38:29.925075 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Dec 12 18:38:29.925085 systemd[1]: initrd-switch-root.service: Deactivated successfully. Dec 12 18:38:29.925098 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Dec 12 18:38:29.925109 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Dec 12 18:38:29.925120 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Dec 12 18:38:29.925131 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Dec 12 18:38:29.925142 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Dec 12 18:38:29.925153 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Dec 12 18:38:29.925164 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Dec 12 18:38:29.925175 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Dec 12 18:38:29.925188 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Dec 12 18:38:29.925199 systemd[1]: Created slice user.slice - User and Session Slice. Dec 12 18:38:29.925210 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Dec 12 18:38:29.925221 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Dec 12 18:38:29.925231 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Dec 12 18:38:29.925242 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Dec 12 18:38:29.925257 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Dec 12 18:38:29.925268 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Dec 12 18:38:29.925282 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Dec 12 18:38:29.925293 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Dec 12 18:38:29.925304 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Dec 12 18:38:29.925318 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Dec 12 18:38:29.925329 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Dec 12 18:38:29.925340 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Dec 12 18:38:29.925352 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Dec 12 18:38:29.925366 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Dec 12 18:38:29.925377 systemd[1]: Reached target remote-fs.target - Remote File Systems. Dec 12 18:38:29.925388 systemd[1]: Reached target slices.target - Slice Units. Dec 12 18:38:29.925399 systemd[1]: Reached target swap.target - Swaps. Dec 12 18:38:29.925410 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Dec 12 18:38:29.925431 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Dec 12 18:38:29.925447 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Dec 12 18:38:29.925459 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Dec 12 18:38:29.925470 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Dec 12 18:38:29.925481 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Dec 12 18:38:29.925492 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Dec 12 18:38:29.925503 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Dec 12 18:38:29.925514 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Dec 12 18:38:29.925528 systemd[1]: Mounting media.mount - External Media Directory... Dec 12 18:38:29.925539 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Dec 12 18:38:29.925550 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Dec 12 18:38:29.925562 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Dec 12 18:38:29.925576 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Dec 12 18:38:29.925588 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). Dec 12 18:38:29.925599 systemd[1]: Reached target machines.target - Containers. Dec 12 18:38:29.925610 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Dec 12 18:38:29.925622 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Dec 12 18:38:29.925636 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Dec 12 18:38:29.925648 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Dec 12 18:38:29.925659 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Dec 12 18:38:29.925670 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Dec 12 18:38:29.925681 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Dec 12 18:38:29.925693 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Dec 12 18:38:29.925703 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Dec 12 18:38:29.925715 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Dec 12 18:38:29.925729 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Dec 12 18:38:29.925740 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Dec 12 18:38:29.925751 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Dec 12 18:38:29.925762 systemd[1]: Stopped systemd-fsck-usr.service. Dec 12 18:38:29.925774 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). Dec 12 18:38:29.925785 kernel: fuse: init (API version 7.41) Dec 12 18:38:29.925795 systemd[1]: Starting systemd-journald.service - Journal Service... Dec 12 18:38:29.925806 kernel: loop: module loaded Dec 12 18:38:29.925822 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Dec 12 18:38:29.925834 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Dec 12 18:38:29.925868 systemd-journald[1481]: Collecting audit messages is disabled. Dec 12 18:38:29.925893 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Dec 12 18:38:29.925909 systemd-journald[1481]: Journal started Dec 12 18:38:29.925931 systemd-journald[1481]: Runtime Journal (/run/log/journal/35765259398a446eb259c3a312231632) is 8M, max 2.2G, 2.1G free. Dec 12 18:38:29.196971 systemd[1]: Queued start job for default target multi-user.target. Dec 12 18:38:29.221743 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Dec 12 18:38:29.222224 systemd[1]: systemd-journald.service: Deactivated successfully. Dec 12 18:38:29.937443 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Dec 12 18:38:29.948439 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Dec 12 18:38:29.959159 systemd[1]: verity-setup.service: Deactivated successfully. Dec 12 18:38:29.959203 systemd[1]: Stopped verity-setup.service. Dec 12 18:38:29.972440 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Dec 12 18:38:29.979441 systemd[1]: Started systemd-journald.service - Journal Service. Dec 12 18:38:29.983949 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Dec 12 18:38:29.988480 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Dec 12 18:38:29.993231 systemd[1]: Mounted media.mount - External Media Directory. Dec 12 18:38:29.997495 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Dec 12 18:38:30.002094 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Dec 12 18:38:30.006915 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Dec 12 18:38:30.011285 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Dec 12 18:38:30.016536 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Dec 12 18:38:30.022061 systemd[1]: modprobe@configfs.service: Deactivated successfully. Dec 12 18:38:30.022252 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Dec 12 18:38:30.027541 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Dec 12 18:38:30.027721 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Dec 12 18:38:30.032701 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Dec 12 18:38:30.032890 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Dec 12 18:38:30.038321 systemd[1]: modprobe@fuse.service: Deactivated successfully. Dec 12 18:38:30.038528 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Dec 12 18:38:30.043438 kernel: ACPI: bus type drm_connector registered Dec 12 18:38:30.045676 systemd[1]: modprobe@loop.service: Deactivated successfully. Dec 12 18:38:30.045861 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Dec 12 18:38:30.050650 systemd[1]: modprobe@drm.service: Deactivated successfully. Dec 12 18:38:30.050834 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Dec 12 18:38:30.055603 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Dec 12 18:38:30.061782 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Dec 12 18:38:30.067302 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Dec 12 18:38:30.073064 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Dec 12 18:38:30.080358 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Dec 12 18:38:30.093092 systemd[1]: Reached target network-pre.target - Preparation for Network. Dec 12 18:38:30.098884 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Dec 12 18:38:30.124662 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Dec 12 18:38:30.129328 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Dec 12 18:38:30.129385 systemd[1]: Reached target local-fs.target - Local File Systems. Dec 12 18:38:30.134717 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Dec 12 18:38:30.140957 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Dec 12 18:38:30.145244 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Dec 12 18:38:30.146271 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Dec 12 18:38:30.151818 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Dec 12 18:38:30.156816 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Dec 12 18:38:30.157894 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Dec 12 18:38:30.162178 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Dec 12 18:38:30.163111 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Dec 12 18:38:30.168785 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Dec 12 18:38:30.174747 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Dec 12 18:38:30.181360 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Dec 12 18:38:30.186341 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Dec 12 18:38:30.191700 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Dec 12 18:38:30.196810 systemd-journald[1481]: Time spent on flushing to /var/log/journal/35765259398a446eb259c3a312231632 is 18.381ms for 954 entries. Dec 12 18:38:30.196810 systemd-journald[1481]: System Journal (/var/log/journal/35765259398a446eb259c3a312231632) is 8M, max 2.6G, 2.6G free. Dec 12 18:38:30.282713 systemd-journald[1481]: Received client request to flush runtime journal. Dec 12 18:38:30.282782 kernel: loop0: detected capacity change from 0 to 110984 Dec 12 18:38:30.199523 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Dec 12 18:38:30.208815 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Dec 12 18:38:30.250380 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Dec 12 18:38:30.266957 systemd-tmpfiles[1519]: ACLs are not supported, ignoring. Dec 12 18:38:30.266972 systemd-tmpfiles[1519]: ACLs are not supported, ignoring. Dec 12 18:38:30.271861 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Dec 12 18:38:30.278613 systemd[1]: Starting systemd-sysusers.service - Create System Users... Dec 12 18:38:30.300572 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Dec 12 18:38:30.308936 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Dec 12 18:38:30.309591 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Dec 12 18:38:30.469843 systemd[1]: Finished systemd-sysusers.service - Create System Users. Dec 12 18:38:30.475688 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Dec 12 18:38:30.501300 systemd-tmpfiles[1536]: ACLs are not supported, ignoring. Dec 12 18:38:30.501321 systemd-tmpfiles[1536]: ACLs are not supported, ignoring. Dec 12 18:38:30.505134 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Dec 12 18:38:30.762452 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Dec 12 18:38:30.820456 kernel: loop1: detected capacity change from 0 to 27936 Dec 12 18:38:30.891102 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Dec 12 18:38:30.897819 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Dec 12 18:38:30.939109 systemd-udevd[1542]: Using default interface naming scheme 'v255'. Dec 12 18:38:31.187603 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Dec 12 18:38:31.198604 systemd[1]: Starting systemd-networkd.service - Network Configuration... Dec 12 18:38:31.243751 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Dec 12 18:38:31.268445 kernel: hv_vmbus: registering driver hyperv_fb Dec 12 18:38:31.268524 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Dec 12 18:38:31.276760 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Dec 12 18:38:31.280715 kernel: Console: switching to colour dummy device 80x25 Dec 12 18:38:31.284444 kernel: Console: switching to colour frame buffer device 128x48 Dec 12 18:38:31.293486 kernel: hv_vmbus: registering driver hv_balloon Dec 12 18:38:31.296845 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Dec 12 18:38:31.301562 kernel: mousedev: PS/2 mouse device common for all mice Dec 12 18:38:31.309442 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Dec 12 18:38:31.370448 kernel: EDAC MC: Ver: 3.0.0 Dec 12 18:38:31.376564 systemd[1]: Started systemd-userdbd.service - User Database Manager. Dec 12 18:38:31.390571 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Dec 12 18:38:31.399651 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Dec 12 18:38:31.399845 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Dec 12 18:38:31.405612 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Dec 12 18:38:31.427441 kernel: loop2: detected capacity change from 0 to 128560 Dec 12 18:38:31.515711 systemd-networkd[1569]: lo: Link UP Dec 12 18:38:31.517119 systemd-networkd[1569]: lo: Gained carrier Dec 12 18:38:31.518862 systemd-networkd[1569]: Enumeration completed Dec 12 18:38:31.518980 systemd[1]: Started systemd-networkd.service - Network Configuration. Dec 12 18:38:31.520318 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Dec 12 18:38:31.521531 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Dec 12 18:38:31.532992 systemd-networkd[1569]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Dec 12 18:38:31.533004 systemd-networkd[1569]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Dec 12 18:38:31.533611 systemd-networkd[1569]: eth0: Link UP Dec 12 18:38:31.533697 systemd-networkd[1569]: eth0: Gained carrier Dec 12 18:38:31.533771 systemd-networkd[1569]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Dec 12 18:38:31.548759 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Dec 12 18:38:31.549862 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Dec 12 18:38:31.582684 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Dec 12 18:38:31.588468 systemd-networkd[1569]: eth0: DHCPv4 address 10.200.4.28/24, gateway 10.200.4.1 acquired from 168.63.129.16 Dec 12 18:38:31.590074 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Dec 12 18:38:31.782448 kernel: loop3: detected capacity change from 0 to 110984 Dec 12 18:38:31.802443 kernel: loop4: detected capacity change from 0 to 27936 Dec 12 18:38:31.820467 kernel: loop5: detected capacity change from 0 to 128560 Dec 12 18:38:31.835395 (sd-merge)[1698]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Dec 12 18:38:31.835869 (sd-merge)[1698]: Merged extensions into '/usr'. Dec 12 18:38:31.839113 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Dec 12 18:38:31.844597 systemd[1]: Starting ensure-sysext.service... Dec 12 18:38:31.845548 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Dec 12 18:38:31.880519 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Dec 12 18:38:31.888289 systemd[1]: Reload requested from client PID 1701 ('systemctl') (unit ensure-sysext.service)... Dec 12 18:38:31.888305 systemd[1]: Reloading... Dec 12 18:38:31.893417 systemd-tmpfiles[1702]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Dec 12 18:38:31.893470 systemd-tmpfiles[1702]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Dec 12 18:38:31.893765 systemd-tmpfiles[1702]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Dec 12 18:38:31.894051 systemd-tmpfiles[1702]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Dec 12 18:38:31.894930 systemd-tmpfiles[1702]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Dec 12 18:38:31.895211 systemd-tmpfiles[1702]: ACLs are not supported, ignoring. Dec 12 18:38:31.895278 systemd-tmpfiles[1702]: ACLs are not supported, ignoring. Dec 12 18:38:31.913278 systemd-tmpfiles[1702]: Detected autofs mount point /boot during canonicalization of boot. Dec 12 18:38:31.913292 systemd-tmpfiles[1702]: Skipping /boot Dec 12 18:38:31.923947 systemd-tmpfiles[1702]: Detected autofs mount point /boot during canonicalization of boot. Dec 12 18:38:31.924050 systemd-tmpfiles[1702]: Skipping /boot Dec 12 18:38:31.927455 zram_generator::config[1737]: No configuration found. Dec 12 18:38:32.192335 systemd[1]: Reloading finished in 303 ms. Dec 12 18:38:32.243154 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Dec 12 18:38:32.256561 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Dec 12 18:38:32.257754 systemd[1]: Starting audit-rules.service - Load Audit Rules... Dec 12 18:38:32.263631 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Dec 12 18:38:32.268564 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Dec 12 18:38:32.287153 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Dec 12 18:38:32.292495 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Dec 12 18:38:32.298134 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Dec 12 18:38:32.302454 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Dec 12 18:38:32.302572 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). Dec 12 18:38:32.303982 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Dec 12 18:38:32.310468 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Dec 12 18:38:32.315816 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Dec 12 18:38:32.320683 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Dec 12 18:38:32.323080 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Dec 12 18:38:32.323276 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Dec 12 18:38:32.328603 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Dec 12 18:38:32.328806 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Dec 12 18:38:32.334612 systemd[1]: modprobe@loop.service: Deactivated successfully. Dec 12 18:38:32.334797 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Dec 12 18:38:32.350572 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Dec 12 18:38:32.361562 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Dec 12 18:38:32.361852 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Dec 12 18:38:32.363074 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Dec 12 18:38:32.379918 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Dec 12 18:38:32.385069 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Dec 12 18:38:32.390801 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Dec 12 18:38:32.395165 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Dec 12 18:38:32.395297 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). Dec 12 18:38:32.395489 systemd[1]: Reached target time-set.target - System Time Set. Dec 12 18:38:32.399831 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Dec 12 18:38:32.401419 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Dec 12 18:38:32.401625 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Dec 12 18:38:32.407116 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Dec 12 18:38:32.412962 systemd[1]: modprobe@drm.service: Deactivated successfully. Dec 12 18:38:32.413146 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Dec 12 18:38:32.418290 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Dec 12 18:38:32.418496 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Dec 12 18:38:32.424134 systemd[1]: modprobe@loop.service: Deactivated successfully. Dec 12 18:38:32.424311 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Dec 12 18:38:32.431655 systemd[1]: Finished ensure-sysext.service. Dec 12 18:38:32.432775 systemd-resolved[1802]: Positive Trust Anchors: Dec 12 18:38:32.432788 systemd-resolved[1802]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Dec 12 18:38:32.432820 systemd-resolved[1802]: 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 Dec 12 18:38:32.440034 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Dec 12 18:38:32.440093 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Dec 12 18:38:32.475781 systemd-resolved[1802]: Using system hostname 'ci-4459.2.2-a-cd988c9c36'. Dec 12 18:38:32.477360 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Dec 12 18:38:32.482070 systemd[1]: Reached target network.target - Network. Dec 12 18:38:32.485792 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Dec 12 18:38:32.493135 augenrules[1838]: No rules Dec 12 18:38:32.494569 systemd[1]: audit-rules.service: Deactivated successfully. Dec 12 18:38:32.494806 systemd[1]: Finished audit-rules.service - Load Audit Rules. Dec 12 18:38:32.931568 systemd-networkd[1569]: eth0: Gained IPv6LL Dec 12 18:38:32.934146 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Dec 12 18:38:32.939773 systemd[1]: Reached target network-online.target - Network is Online. Dec 12 18:38:33.208577 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Dec 12 18:38:33.214293 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). Dec 12 18:38:35.520134 ldconfig[1512]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Dec 12 18:38:35.530021 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Dec 12 18:38:35.536823 systemd[1]: Starting systemd-update-done.service - Update is Completed... Dec 12 18:38:35.567448 systemd[1]: Finished systemd-update-done.service - Update is Completed. Dec 12 18:38:35.572322 systemd[1]: Reached target sysinit.target - System Initialization. Dec 12 18:38:35.576905 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Dec 12 18:38:35.582097 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Dec 12 18:38:35.587368 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Dec 12 18:38:35.592334 systemd[1]: Started logrotate.timer - Daily rotation of log files. Dec 12 18:38:35.596990 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Dec 12 18:38:35.602127 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Dec 12 18:38:35.607376 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Dec 12 18:38:35.607409 systemd[1]: Reached target paths.target - Path Units. Dec 12 18:38:35.611233 systemd[1]: Reached target timers.target - Timer Units. Dec 12 18:38:35.616584 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Dec 12 18:38:35.622468 systemd[1]: Starting docker.socket - Docker Socket for the API... Dec 12 18:38:35.628475 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Dec 12 18:38:35.633897 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Dec 12 18:38:35.639280 systemd[1]: Reached target ssh-access.target - SSH Access Available. Dec 12 18:38:35.645497 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Dec 12 18:38:35.650471 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Dec 12 18:38:35.655981 systemd[1]: Listening on docker.socket - Docker Socket for the API. Dec 12 18:38:35.661140 systemd[1]: Reached target sockets.target - Socket Units. Dec 12 18:38:35.665385 systemd[1]: Reached target basic.target - Basic System. Dec 12 18:38:35.669146 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Dec 12 18:38:35.669179 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Dec 12 18:38:35.672153 systemd[1]: Starting chronyd.service - NTP client/server... Dec 12 18:38:35.676582 systemd[1]: Starting containerd.service - containerd container runtime... Dec 12 18:38:35.682057 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Dec 12 18:38:35.687552 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Dec 12 18:38:35.692220 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Dec 12 18:38:35.697950 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Dec 12 18:38:35.703066 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Dec 12 18:38:35.705872 jq[1859]: false Dec 12 18:38:35.707293 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). Dec 12 18:38:35.708305 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Dec 12 18:38:35.712505 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). Dec 12 18:38:35.713485 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Dec 12 18:38:35.717744 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Dec 12 18:38:35.718749 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Dec 12 18:38:35.724018 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Dec 12 18:38:35.729566 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Dec 12 18:38:35.737546 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Dec 12 18:38:35.744754 systemd[1]: Starting systemd-logind.service - User Login Management... Dec 12 18:38:35.744981 KVP[1865]: KVP starting; pid is:1865 Dec 12 18:38:35.745626 chronyd[1851]: chronyd version 4.7 starting (+CMDMON +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +NTS +SECHASH +IPV6 -DEBUG) Dec 12 18:38:35.749446 kernel: hv_utils: KVP IC version 4.0 Dec 12 18:38:35.749531 KVP[1865]: KVP LIC Version: 3.1 Dec 12 18:38:35.750310 extend-filesystems[1860]: Found /dev/sda6 Dec 12 18:38:35.751740 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Dec 12 18:38:35.752164 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Dec 12 18:38:35.752710 systemd[1]: Starting update-engine.service - Update Engine... Dec 12 18:38:35.758294 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Dec 12 18:38:35.765419 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Dec 12 18:38:35.766266 jq[1877]: true Dec 12 18:38:35.770807 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Dec 12 18:38:35.771026 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Dec 12 18:38:35.771311 systemd[1]: motdgen.service: Deactivated successfully. Dec 12 18:38:35.771525 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Dec 12 18:38:35.772949 extend-filesystems[1860]: Found /dev/sda9 Dec 12 18:38:35.776549 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Dec 12 18:38:35.776797 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Dec 12 18:38:35.788169 extend-filesystems[1860]: Checking size of /dev/sda9 Dec 12 18:38:35.788505 chronyd[1851]: Timezone right/UTC failed leap second check, ignoring Dec 12 18:38:35.788963 systemd[1]: Started chronyd.service - NTP client/server. Dec 12 18:38:35.788671 chronyd[1851]: Loaded seccomp filter (level 2) Dec 12 18:38:35.795577 jq[1887]: true Dec 12 18:38:35.796965 (ntainerd)[1889]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Dec 12 18:38:35.803508 google_oslogin_nss_cache[1861]: oslogin_cache_refresh[1861]: Refreshing passwd entry cache Dec 12 18:38:35.803514 oslogin_cache_refresh[1861]: Refreshing passwd entry cache Dec 12 18:38:35.812921 update_engine[1875]: I20251212 18:38:35.812848 1875 main.cc:92] Flatcar Update Engine starting Dec 12 18:38:35.816581 extend-filesystems[1860]: Old size kept for /dev/sda9 Dec 12 18:38:35.820574 systemd[1]: extend-filesystems.service: Deactivated successfully. Dec 12 18:38:35.820820 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Dec 12 18:38:35.825367 google_oslogin_nss_cache[1861]: oslogin_cache_refresh[1861]: Failure getting users, quitting Dec 12 18:38:35.825360 oslogin_cache_refresh[1861]: Failure getting users, quitting Dec 12 18:38:35.825473 google_oslogin_nss_cache[1861]: oslogin_cache_refresh[1861]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Dec 12 18:38:35.825473 google_oslogin_nss_cache[1861]: oslogin_cache_refresh[1861]: Refreshing group entry cache Dec 12 18:38:35.825378 oslogin_cache_refresh[1861]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Dec 12 18:38:35.825437 oslogin_cache_refresh[1861]: Refreshing group entry cache Dec 12 18:38:35.830839 google_oslogin_nss_cache[1861]: oslogin_cache_refresh[1861]: Failure getting groups, quitting Dec 12 18:38:35.830839 google_oslogin_nss_cache[1861]: oslogin_cache_refresh[1861]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Dec 12 18:38:35.830828 oslogin_cache_refresh[1861]: Failure getting groups, quitting Dec 12 18:38:35.830841 oslogin_cache_refresh[1861]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Dec 12 18:38:35.832318 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Dec 12 18:38:35.832587 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Dec 12 18:38:35.894850 setup-nvidia[1867]: Downloading Flatcar Container Linux Developer Container for version: 4459.2.2 Dec 12 18:38:35.915073 systemd-logind[1872]: New seat seat0. Dec 12 18:38:35.918552 systemd-logind[1872]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Dec 12 18:38:35.918752 systemd[1]: Started systemd-logind.service - User Login Management. Dec 12 18:38:35.947683 bash[1915]: Updated "/home/core/.ssh/authorized_keys" Dec 12 18:38:35.949297 sshd_keygen[1900]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Dec 12 18:38:35.949283 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Dec 12 18:38:35.955153 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Dec 12 18:38:35.970715 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Dec 12 18:38:35.973186 dbus-daemon[1854]: [system] SELinux support is enabled Dec 12 18:38:35.975371 systemd[1]: Started dbus.service - D-Bus System Message Bus. Dec 12 18:38:35.976039 update_engine[1875]: I20251212 18:38:35.975991 1875 update_check_scheduler.cc:74] Next update check in 11m22s Dec 12 18:38:35.983016 dbus-daemon[1854]: [system] Successfully activated service 'org.freedesktop.systemd1' Dec 12 18:38:35.983256 systemd[1]: Starting issuegen.service - Generate /run/issue... Dec 12 18:38:35.987056 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). Dec 12 18:38:35.987089 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Dec 12 18:38:35.992388 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). Dec 12 18:38:35.992412 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Dec 12 18:38:36.002255 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Dec 12 18:38:36.006619 systemd[1]: Started update-engine.service - Update Engine. Dec 12 18:38:36.011128 systemd[1]: issuegen.service: Deactivated successfully. Dec 12 18:38:36.011336 systemd[1]: Finished issuegen.service - Generate /run/issue. Dec 12 18:38:36.018910 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Dec 12 18:38:36.024451 systemd[1]: Started locksmithd.service - Cluster reboot manager. Dec 12 18:38:36.029902 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Dec 12 18:38:36.031127 coreos-metadata[1853]: Dec 12 18:38:36.031 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Dec 12 18:38:36.034800 coreos-metadata[1853]: Dec 12 18:38:36.034 INFO Fetch successful Dec 12 18:38:36.034974 coreos-metadata[1853]: Dec 12 18:38:36.034 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Dec 12 18:38:36.035070 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Dec 12 18:38:36.039287 coreos-metadata[1853]: Dec 12 18:38:36.039 INFO Fetch successful Dec 12 18:38:36.040717 coreos-metadata[1853]: Dec 12 18:38:36.039 INFO Fetching http://168.63.129.16/machine/5b9f1044-2d2c-4f71-bd26-7fec0dff462b/c6aad0e4%2D8c83%2D4633%2D88f9%2D4fb41ec6edb9.%5Fci%2D4459.2.2%2Da%2Dcd988c9c36?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Dec 12 18:38:36.041459 coreos-metadata[1853]: Dec 12 18:38:36.041 INFO Fetch successful Dec 12 18:38:36.041696 coreos-metadata[1853]: Dec 12 18:38:36.041 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Dec 12 18:38:36.043179 systemd[1]: Started getty@tty1.service - Getty on tty1. Dec 12 18:38:36.048905 coreos-metadata[1853]: Dec 12 18:38:36.048 INFO Fetch successful Dec 12 18:38:36.048969 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Dec 12 18:38:36.053660 systemd[1]: Reached target getty.target - Login Prompts. Dec 12 18:38:36.088023 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Dec 12 18:38:36.093400 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Dec 12 18:38:36.240251 locksmithd[2007]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Dec 12 18:38:36.298361 setup-nvidia[2023]: % Total % Received % Xferd Average Speed Time Time Time Current Dec 12 18:38:36.298637 setup-nvidia[2023]: Dload Upload Total Spent Left Speed Dec 12 18:38:36.926159 containerd[1889]: time="2025-12-12T18:38:36Z" 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 Dec 12 18:38:36.927568 containerd[1889]: time="2025-12-12T18:38:36.927529800Z" level=info msg="starting containerd" revision=4ac6c20c7bbf8177f29e46bbdc658fec02ffb8ad version=v2.0.7 Dec 12 18:38:36.934625 containerd[1889]: time="2025-12-12T18:38:36.934576500Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="7.3µs" Dec 12 18:38:36.934703 containerd[1889]: time="2025-12-12T18:38:36.934603900Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Dec 12 18:38:36.934703 containerd[1889]: time="2025-12-12T18:38:36.934666800Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Dec 12 18:38:36.934868 containerd[1889]: time="2025-12-12T18:38:36.934842400Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Dec 12 18:38:36.934903 containerd[1889]: time="2025-12-12T18:38:36.934866400Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Dec 12 18:38:36.934903 containerd[1889]: time="2025-12-12T18:38:36.934890600Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Dec 12 18:38:36.935091 containerd[1889]: time="2025-12-12T18:38:36.935066300Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Dec 12 18:38:36.935091 containerd[1889]: time="2025-12-12T18:38:36.935086800Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Dec 12 18:38:36.935366 containerd[1889]: time="2025-12-12T18:38:36.935340200Z" 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 Dec 12 18:38:36.935366 containerd[1889]: time="2025-12-12T18:38:36.935362400Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Dec 12 18:38:36.935414 containerd[1889]: time="2025-12-12T18:38:36.935374300Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Dec 12 18:38:36.935414 containerd[1889]: time="2025-12-12T18:38:36.935382900Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Dec 12 18:38:36.935557 containerd[1889]: time="2025-12-12T18:38:36.935534800Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Dec 12 18:38:36.935891 containerd[1889]: time="2025-12-12T18:38:36.935870800Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Dec 12 18:38:36.935958 containerd[1889]: time="2025-12-12T18:38:36.935936000Z" 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 Dec 12 18:38:36.935987 containerd[1889]: time="2025-12-12T18:38:36.935954900Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Dec 12 18:38:36.936076 containerd[1889]: time="2025-12-12T18:38:36.936052400Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Dec 12 18:38:36.936263 containerd[1889]: time="2025-12-12T18:38:36.936243900Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Dec 12 18:38:36.936381 containerd[1889]: time="2025-12-12T18:38:36.936360300Z" level=info msg="metadata content store policy set" policy=shared Dec 12 18:38:36.948223 containerd[1889]: time="2025-12-12T18:38:36.948182200Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Dec 12 18:38:36.948270 containerd[1889]: time="2025-12-12T18:38:36.948236700Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Dec 12 18:38:36.948270 containerd[1889]: time="2025-12-12T18:38:36.948252800Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Dec 12 18:38:36.948270 containerd[1889]: time="2025-12-12T18:38:36.948264000Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Dec 12 18:38:36.948345 containerd[1889]: time="2025-12-12T18:38:36.948276100Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Dec 12 18:38:36.948345 containerd[1889]: time="2025-12-12T18:38:36.948285900Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Dec 12 18:38:36.948345 containerd[1889]: time="2025-12-12T18:38:36.948298000Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Dec 12 18:38:36.948345 containerd[1889]: time="2025-12-12T18:38:36.948308500Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Dec 12 18:38:36.948345 containerd[1889]: time="2025-12-12T18:38:36.948318000Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Dec 12 18:38:36.948345 containerd[1889]: time="2025-12-12T18:38:36.948326700Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Dec 12 18:38:36.948345 containerd[1889]: time="2025-12-12T18:38:36.948334800Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Dec 12 18:38:36.948345 containerd[1889]: time="2025-12-12T18:38:36.948345300Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Dec 12 18:38:36.948525 containerd[1889]: time="2025-12-12T18:38:36.948463400Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Dec 12 18:38:36.948525 containerd[1889]: time="2025-12-12T18:38:36.948484900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Dec 12 18:38:36.948525 containerd[1889]: time="2025-12-12T18:38:36.948512200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Dec 12 18:38:36.948589 containerd[1889]: time="2025-12-12T18:38:36.948529500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Dec 12 18:38:36.948589 containerd[1889]: time="2025-12-12T18:38:36.948540700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Dec 12 18:38:36.948589 containerd[1889]: time="2025-12-12T18:38:36.948549700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Dec 12 18:38:36.948589 containerd[1889]: time="2025-12-12T18:38:36.948559200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Dec 12 18:38:36.948589 containerd[1889]: time="2025-12-12T18:38:36.948568600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Dec 12 18:38:36.948589 containerd[1889]: time="2025-12-12T18:38:36.948578800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Dec 12 18:38:36.948589 containerd[1889]: time="2025-12-12T18:38:36.948588500Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Dec 12 18:38:36.948735 containerd[1889]: time="2025-12-12T18:38:36.948598100Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Dec 12 18:38:36.948735 containerd[1889]: time="2025-12-12T18:38:36.948634500Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Dec 12 18:38:36.948735 containerd[1889]: time="2025-12-12T18:38:36.948647200Z" level=info msg="Start snapshots syncer" Dec 12 18:38:36.948735 containerd[1889]: time="2025-12-12T18:38:36.948688200Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Dec 12 18:38:36.948970 containerd[1889]: time="2025-12-12T18:38:36.948923300Z" 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\"}" Dec 12 18:38:36.949090 containerd[1889]: time="2025-12-12T18:38:36.948986000Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Dec 12 18:38:36.949090 containerd[1889]: time="2025-12-12T18:38:36.949039200Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Dec 12 18:38:36.949168 containerd[1889]: time="2025-12-12T18:38:36.949145500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Dec 12 18:38:36.949251 containerd[1889]: time="2025-12-12T18:38:36.949171800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Dec 12 18:38:36.949251 containerd[1889]: time="2025-12-12T18:38:36.949183100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Dec 12 18:38:36.949350 containerd[1889]: time="2025-12-12T18:38:36.949291100Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Dec 12 18:38:36.949495 containerd[1889]: time="2025-12-12T18:38:36.949399900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Dec 12 18:38:36.949577 containerd[1889]: time="2025-12-12T18:38:36.949534600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Dec 12 18:38:36.949652 containerd[1889]: time="2025-12-12T18:38:36.949599100Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Dec 12 18:38:36.949771 containerd[1889]: time="2025-12-12T18:38:36.949710900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Dec 12 18:38:36.954005 containerd[1889]: time="2025-12-12T18:38:36.949858600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Dec 12 18:38:36.954047 containerd[1889]: time="2025-12-12T18:38:36.954023700Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Dec 12 18:38:36.954074 containerd[1889]: time="2025-12-12T18:38:36.954063100Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Dec 12 18:38:36.954101 containerd[1889]: time="2025-12-12T18:38:36.954079800Z" 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 Dec 12 18:38:36.954101 containerd[1889]: time="2025-12-12T18:38:36.954089000Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Dec 12 18:38:36.954153 containerd[1889]: time="2025-12-12T18:38:36.954099100Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Dec 12 18:38:36.954153 containerd[1889]: time="2025-12-12T18:38:36.954106700Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Dec 12 18:38:36.954153 containerd[1889]: time="2025-12-12T18:38:36.954115900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Dec 12 18:38:36.954153 containerd[1889]: time="2025-12-12T18:38:36.954134000Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Dec 12 18:38:36.954153 containerd[1889]: time="2025-12-12T18:38:36.954150100Z" level=info msg="runtime interface created" Dec 12 18:38:36.954260 containerd[1889]: time="2025-12-12T18:38:36.954157300Z" level=info msg="created NRI interface" Dec 12 18:38:36.954260 containerd[1889]: time="2025-12-12T18:38:36.954178200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Dec 12 18:38:36.954260 containerd[1889]: time="2025-12-12T18:38:36.954192600Z" level=info msg="Connect containerd service" Dec 12 18:38:36.954260 containerd[1889]: time="2025-12-12T18:38:36.954222700Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Dec 12 18:38:36.954934 containerd[1889]: time="2025-12-12T18:38:36.954899700Z" 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" Dec 12 18:38:37.490504 containerd[1889]: time="2025-12-12T18:38:37.490449700Z" level=info msg="Start subscribing containerd event" Dec 12 18:38:37.490619 containerd[1889]: time="2025-12-12T18:38:37.490516100Z" level=info msg="Start recovering state" Dec 12 18:38:37.490708 containerd[1889]: time="2025-12-12T18:38:37.490634700Z" level=info msg="Start event monitor" Dec 12 18:38:37.490708 containerd[1889]: time="2025-12-12T18:38:37.490665400Z" level=info msg="Start cni network conf syncer for default" Dec 12 18:38:37.490708 containerd[1889]: time="2025-12-12T18:38:37.490691000Z" level=info msg="Start streaming server" Dec 12 18:38:37.490708 containerd[1889]: time="2025-12-12T18:38:37.490703000Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Dec 12 18:38:37.490792 containerd[1889]: time="2025-12-12T18:38:37.490712500Z" level=info msg="runtime interface starting up..." Dec 12 18:38:37.490792 containerd[1889]: time="2025-12-12T18:38:37.490721100Z" level=info msg="starting plugins..." Dec 12 18:38:37.490792 containerd[1889]: time="2025-12-12T18:38:37.490738500Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Dec 12 18:38:37.490893 containerd[1889]: time="2025-12-12T18:38:37.490715500Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Dec 12 18:38:37.490928 containerd[1889]: time="2025-12-12T18:38:37.490893100Z" level=info msg=serving... address=/run/containerd/containerd.sock Dec 12 18:38:37.490989 containerd[1889]: time="2025-12-12T18:38:37.490968300Z" level=info msg="containerd successfully booted in 0.565317s" Dec 12 18:38:37.491084 systemd[1]: Started containerd.service - containerd container runtime. Dec 12 18:38:37.734645 waagent[2009]: 2025-12-12T18:38:37.734567Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Dec 12 18:38:37.738984 waagent[2009]: 2025-12-12T18:38:37.738941Z INFO Daemon Daemon OS: flatcar 4459.2.2 Dec 12 18:38:37.742393 waagent[2009]: 2025-12-12T18:38:37.742351Z INFO Daemon Daemon Python: 3.11.13 Dec 12 18:38:37.745850 waagent[2009]: 2025-12-12T18:38:37.745810Z INFO Daemon Daemon Run daemon Dec 12 18:38:37.749200 waagent[2009]: 2025-12-12T18:38:37.749154Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4459.2.2' Dec 12 18:38:37.755958 waagent[2009]: 2025-12-12T18:38:37.755912Z INFO Daemon Daemon Using waagent for provisioning Dec 12 18:38:37.760078 waagent[2009]: 2025-12-12T18:38:37.760031Z INFO Daemon Daemon Activate resource disk Dec 12 18:38:37.763710 waagent[2009]: 2025-12-12T18:38:37.763670Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Dec 12 18:38:37.771164 waagent[2009]: 2025-12-12T18:38:37.771119Z INFO Daemon Daemon Found device: sdb Dec 12 18:38:37.780370 waagent[2009]: 2025-12-12T18:38:37.780321Z INFO Daemon Daemon Examining partition table Dec 12 18:38:37.832707 waagent[2009]: 2025-12-12T18:38:37.832604Z INFO Daemon Daemon GPT not detected, determining filesystem Dec 12 18:38:37.879711 waagent[2009]: 2025-12-12T18:38:37.879644Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Dec 12 18:38:37.884508 waagent[2009]: 2025-12-12T18:38:37.884458Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Dec 12 18:38:38.793834 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#238 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Dec 12 18:38:38.818441 kernel: sdb: sdb1 Dec 12 18:38:38.896400 waagent[2009]: 2025-12-12T18:38:38.896317Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Dec 12 18:38:38.922448 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#578 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Dec 12 18:38:38.940454 kernel: sdb: sdb1 Dec 12 18:38:38.940629 waagent[2009]: 2025-12-12T18:38:38.940555Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Dec 12 18:38:43.157765 login[2012]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Dec 12 18:38:43.207663 login[2013]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Dec 12 18:38:43.214600 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Dec 12 18:38:43.215861 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Dec 12 18:38:43.222300 systemd-logind[1872]: New session 2 of user core. Dec 12 18:38:43.327943 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Dec 12 18:38:43.330684 systemd[1]: Starting user@500.service - User Manager for UID 500... Dec 12 18:38:43.508999 (systemd)[2131]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Dec 12 18:38:43.511351 systemd-logind[1872]: New session c1 of user core. Dec 12 18:38:44.158161 login[2012]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Dec 12 18:38:44.162804 systemd-logind[1872]: New session 1 of user core. Dec 12 18:38:44.355608 waagent[2009]: 2025-12-12T18:38:44.355535Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Dec 12 18:38:44.379469 systemd[2131]: Queued start job for default target default.target. Dec 12 18:38:44.402640 systemd[2131]: Created slice app.slice - User Application Slice. Dec 12 18:38:44.402687 systemd[2131]: Reached target paths.target - Paths. Dec 12 18:38:44.402742 systemd[2131]: Reached target timers.target - Timers. Dec 12 18:38:44.404090 systemd[2131]: Starting dbus.socket - D-Bus User Message Bus Socket... Dec 12 18:38:44.415244 systemd[2131]: Listening on dbus.socket - D-Bus User Message Bus Socket. Dec 12 18:38:44.415390 systemd[2131]: Reached target sockets.target - Sockets. Dec 12 18:38:44.415471 systemd[2131]: Reached target basic.target - Basic System. Dec 12 18:38:44.415525 systemd[2131]: Reached target default.target - Main User Target. Dec 12 18:38:44.415570 systemd[1]: Started user@500.service - User Manager for UID 500. Dec 12 18:38:44.415575 systemd[2131]: Startup finished in 897ms. Dec 12 18:38:44.416847 systemd[1]: Started session-1.scope - Session 1 of User core. Dec 12 18:38:44.417565 systemd[1]: Started session-2.scope - Session 2 of User core. Dec 12 18:38:44.475459 kernel: EXT4-fs (sdb1): mounted filesystem 5a5c47da-4f93-47b0-8430-b7a656f078d6 r/w with ordered data mode. Quota mode: none. Dec 12 18:38:44.475544 waagent[2009]: 2025-12-12T18:38:44.475406Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Dec 12 18:38:44.476283 waagent[2009]: 2025-12-12T18:38:44.476237Z INFO Daemon Daemon Clean protocol and wireserver endpoint Dec 12 18:38:44.477311 waagent[2009]: 2025-12-12T18:38:44.477273Z INFO Daemon Daemon Running default provisioning handler Dec 12 18:38:44.485305 waagent[2009]: 2025-12-12T18:38:44.485249Z 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. Dec 12 18:38:44.487186 waagent[2009]: 2025-12-12T18:38:44.487145Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Dec 12 18:38:44.488306 waagent[2009]: 2025-12-12T18:38:44.488270Z INFO Daemon Daemon cloud-init is enabled: False Dec 12 18:38:44.489909 waagent[2009]: 2025-12-12T18:38:44.489871Z INFO Daemon Daemon Copying ovf-env.xml Dec 12 18:38:44.573512 waagent[2009]: 2025-12-12T18:38:44.573418Z INFO Daemon Daemon Successfully mounted dvd Dec 12 18:38:44.597836 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Dec 12 18:38:44.598004 waagent[2009]: 2025-12-12T18:38:44.597957Z INFO Daemon Daemon Detect protocol endpoint Dec 12 18:38:44.598211 waagent[2009]: 2025-12-12T18:38:44.598171Z INFO Daemon Daemon Clean protocol and wireserver endpoint Dec 12 18:38:44.599717 waagent[2009]: 2025-12-12T18:38:44.599682Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Dec 12 18:38:44.600569 waagent[2009]: 2025-12-12T18:38:44.600532Z INFO Daemon Daemon Test for route to 168.63.129.16 Dec 12 18:38:44.602443 waagent[2009]: 2025-12-12T18:38:44.602360Z INFO Daemon Daemon Route to 168.63.129.16 exists Dec 12 18:38:44.603034 waagent[2009]: 2025-12-12T18:38:44.602998Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Dec 12 18:38:44.613236 waagent[2009]: 2025-12-12T18:38:44.613198Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Dec 12 18:38:44.613557 waagent[2009]: 2025-12-12T18:38:44.613533Z INFO Daemon Daemon Wire protocol version:2012-11-30 Dec 12 18:38:44.614161 waagent[2009]: 2025-12-12T18:38:44.614132Z INFO Daemon Daemon Server preferred version:2015-04-05 Dec 12 18:38:44.827552 waagent[2009]: 2025-12-12T18:38:44.827467Z INFO Daemon Daemon Initializing goal state during protocol detection Dec 12 18:38:44.827818 waagent[2009]: 2025-12-12T18:38:44.827776Z INFO Daemon Daemon Forcing an update of the goal state. Dec 12 18:38:44.838819 waagent[2009]: 2025-12-12T18:38:44.838772Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Dec 12 18:38:44.851391 waagent[2009]: 2025-12-12T18:38:44.851348Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.179 Dec 12 18:38:44.851967 waagent[2009]: 2025-12-12T18:38:44.851927Z INFO Daemon Dec 12 18:38:44.852344 waagent[2009]: 2025-12-12T18:38:44.852310Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: af1cee9a-7c7f-472d-a938-89fc4a336001 eTag: 8296466777811478468 source: Fabric] Dec 12 18:38:44.854072 waagent[2009]: 2025-12-12T18:38:44.854032Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Dec 12 18:38:44.855589 waagent[2009]: 2025-12-12T18:38:44.855550Z INFO Daemon Dec 12 18:38:44.856089 waagent[2009]: 2025-12-12T18:38:44.856017Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Dec 12 18:38:44.879405 waagent[2009]: 2025-12-12T18:38:44.879367Z INFO Daemon Daemon Downloading artifacts profile blob Dec 12 18:38:44.990203 waagent[2009]: 2025-12-12T18:38:44.990145Z INFO Daemon Downloaded certificate {'thumbprint': 'A03293469EC24E8F69F5D66C6A000EC474614C08', 'hasPrivateKey': True} Dec 12 18:38:44.990742 waagent[2009]: 2025-12-12T18:38:44.990701Z INFO Daemon Fetch goal state completed Dec 12 18:38:45.023276 waagent[2009]: 2025-12-12T18:38:45.023231Z INFO Daemon Daemon Starting provisioning Dec 12 18:38:45.023511 waagent[2009]: 2025-12-12T18:38:45.023470Z INFO Daemon Daemon Handle ovf-env.xml. Dec 12 18:38:45.025285 waagent[2009]: 2025-12-12T18:38:45.025161Z INFO Daemon Daemon Set hostname [ci-4459.2.2-a-cd988c9c36] Dec 12 18:38:45.036543 waagent[2009]: 2025-12-12T18:38:45.036500Z INFO Daemon Daemon Publish hostname [ci-4459.2.2-a-cd988c9c36] Dec 12 18:38:45.036873 waagent[2009]: 2025-12-12T18:38:45.036830Z INFO Daemon Daemon Examine /proc/net/route for primary interface Dec 12 18:38:45.038500 waagent[2009]: 2025-12-12T18:38:45.038442Z INFO Daemon Daemon Primary interface is [eth0] Dec 12 18:38:45.058838 systemd-networkd[1569]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Dec 12 18:38:45.058847 systemd-networkd[1569]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Dec 12 18:38:45.058870 systemd-networkd[1569]: eth0: DHCP lease lost Dec 12 18:38:45.059287 waagent[2009]: 2025-12-12T18:38:45.059239Z INFO Daemon Daemon Create user account if not exists Dec 12 18:38:45.059547 waagent[2009]: 2025-12-12T18:38:45.059507Z INFO Daemon Daemon User core already exists, skip useradd Dec 12 18:38:45.060247 waagent[2009]: 2025-12-12T18:38:45.060213Z INFO Daemon Daemon Configure sudoer Dec 12 18:38:45.066485 waagent[2009]: 2025-12-12T18:38:45.066442Z INFO Daemon Daemon Configure sshd Dec 12 18:38:45.069614 waagent[2009]: 2025-12-12T18:38:45.069572Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Dec 12 18:38:45.070122 waagent[2009]: 2025-12-12T18:38:45.070087Z INFO Daemon Daemon Deploy ssh public key. Dec 12 18:38:45.148475 systemd-networkd[1569]: eth0: DHCPv4 address 10.200.4.28/24, gateway 10.200.4.1 acquired from 168.63.129.16 Dec 12 18:38:45.700550 setup-nvidia[2023]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 2 473M 2 11.0M 0 0 31.2M 0 0:00:15 --:--:-- 0:00:15 31.2M\u000d 17 473M 17 80.7M 0 0 59.5M 0 0:00:07 0:00:01 0:00:06 59.5M\u000d 22 473M 22 107M 0 0 45.3M 0 0:00:10 0:00:02 0:00:08 45.3M\u000d 35 473M 35 168M 0 0 50.1M 0 0:00:09 0:00:03 0:00:06 50.1M\u000d 53 473M 53 251M 0 0 57.6M 0 0:00:08 0:00:04 0:00:04 57.6M\u000d 67 473M 67 317M 0 0 59.3M 0 0:00:07 0:00:05 0:00:02 61.3M\u000d 79 473M 79 375M 0 0 58.9M 0 0:00:08 0:00:06 0:00:02 58.7M\u000d 87 473M 87 414M 0 0 56.2M 0 0:00:08 0:00:07 0:00:01 61.4M\u000d 92 473M 92 439M 0 0 52.4M 0 0:00:09 0:00:08 0:00:01 54.0M\u000d 98 473M 98 464M 0 0 49.6M 0 0:00:09 0:00:09 --:--:-- 42.6M\u000d100 473M 100 473M 0 0 50.4M 0 0:00:09 0:00:09 --:--:-- 38.5M Dec 12 18:38:55.614229 setup-nvidia[1867]: Downloading NVIDIA 535.261.03 Driver Dec 12 18:38:55.618529 setup-nvidia[2200]: % Total % Received % Xferd Average Speed Time Time Time Current Dec 12 18:38:55.618765 setup-nvidia[2200]: Dload Upload Total Spent Left Speed Dec 12 18:38:59.575861 chronyd[1851]: Selected source PHC0 Dec 12 18:39:01.428568 setup-nvidia[2200]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 8 325M 8 28.0M 0 0 27.0M 0 0:00:12 0:00:01 0:00:11 27.0M\u000d 27 325M 27 90.0M 0 0 44.1M 0 0:00:07 0:00:02 0:00:05 44.1M\u000d 48 325M 48 158M 0 0 51.9M 0 0:00:06 0:00:03 0:00:03 51.9M\u000d 68 325M 68 224M 0 0 55.4M 0 0:00:05 0:00:04 0:00:01 55.4M\u000d 88 325M 88 287M 0 0 57.1M 0 0:00:05 0:00:05 --:--:-- 57.6M\u000d100 325M 100 325M 0 0 56.0M 0 0:00:05 0:00:05 --:--:-- 62.3M Dec 12 18:39:01.463072 setup-nvidia[1867]: Extract the NVIDIA Driver Installer 535.261.03 Dec 12 18:39:01.463303 setup-nvidia[1867]: /opt/nvidia/workdir/nvidia-workdir / Dec 12 18:39:01.482973 setup-nvidia[2203]: Creating directory NVIDIA-Linux-x86_64-535.261.03 Dec 12 18:39:01.731920 setup-nvidia[2203]: Verifying archive integrity... OK Dec 12 18:39:02.391414 setup-nvidia[2203]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.261.03 Dec 12 18:39:04.203895 setup-nvidia[2229]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Dec 12 18:39:04.242167 setup-nvidia[1867]: / Dec 12 18:39:04.242167 setup-nvidia[1867]: Spawn system-nspawn container to install the NVIDIA drivers Dec 12 18:39:04.363174 sudo[2236]: 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.2.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 Dec 12 18:39:04.364892 sudo[2236]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 12 18:39:04.370406 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Dec 12 18:39:04.371414 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Dec 12 18:39:04.392315 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Dec 12 18:39:04.394594 systemd[1]: Starting user@0.service - User Manager for UID 0... Dec 12 18:39:04.416303 (systemd)[2239]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Dec 12 18:39:04.418444 systemd-logind[1872]: New session c3 of user root. Dec 12 18:39:04.612625 systemd[2239]: Queued start job for default target default.target. Dec 12 18:39:04.629518 systemd[2239]: Created slice app.slice - User Application Slice. Dec 12 18:39:04.629546 systemd[2239]: Reached target paths.target - Paths. Dec 12 18:39:04.629593 systemd[2239]: Reached target timers.target - Timers. Dec 12 18:39:04.630727 systemd[2239]: Starting dbus.socket - D-Bus User Message Bus Socket... Dec 12 18:39:04.648195 systemd[2239]: Listening on dbus.socket - D-Bus User Message Bus Socket. Dec 12 18:39:04.648317 systemd[2239]: Reached target sockets.target - Sockets. Dec 12 18:39:04.648369 systemd[2239]: Reached target basic.target - Basic System. Dec 12 18:39:04.648415 systemd[2239]: Reached target default.target - Main User Target. Dec 12 18:39:04.648463 systemd[2239]: Startup finished in 224ms. Dec 12 18:39:04.648508 systemd[1]: Started user@0.service - User Manager for UID 0. Dec 12 18:39:04.649984 systemd[1]: Started session-c2.scope - Session c2 of User root. Dec 12 18:39:04.700453 kernel: loop6: detected capacity change from 0 to 12644352 Dec 12 18:39:04.754446 kernel: loop6: p9 Dec 12 18:39:04.828623 setup-nvidia[2248]: ░ Spawning container flatcardevelopercontainer-4459.2.2.bin on /opt/nvidia/workdir/flatcar_developer_container-4459.2.2.bin. Dec 12 18:39:05.923885 kernel: EXT4-fs (loop6p9): write access unavailable, skipping orphan cleanup Dec 12 18:39:05.924012 kernel: EXT4-fs (loop6p9): mounted filesystem 02782f9c-2fea-4b2e-9279-7381cb6c9c28 ro without journal. Quota mode: none. Dec 12 18:39:15.183186 waagent[2009]: 2025-12-12T18:39:15.183126Z INFO Daemon Daemon Provisioning complete Dec 12 18:39:15.191892 waagent[2009]: 2025-12-12T18:39:15.191842Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Dec 12 18:39:15.192156 waagent[2009]: 2025-12-12T18:39:15.192114Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Dec 12 18:39:15.193693 waagent[2009]: 2025-12-12T18:39:15.193648Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Dec 12 18:39:15.352068 waagent[2640]: 2025-12-12T18:39:15.351989Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Dec 12 18:39:15.352491 waagent[2640]: 2025-12-12T18:39:15.352140Z INFO ExtHandler ExtHandler OS: flatcar 4459.2.2 Dec 12 18:39:15.352491 waagent[2640]: 2025-12-12T18:39:15.352213Z INFO ExtHandler ExtHandler Python: 3.11.13 Dec 12 18:39:15.352491 waagent[2640]: 2025-12-12T18:39:15.352284Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Dec 12 18:39:15.430606 waagent[2640]: 2025-12-12T18:39:15.430525Z INFO ExtHandler ExtHandler Distro: flatcar-4459.2.2; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.13; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Dec 12 18:39:15.431031 waagent[2640]: 2025-12-12T18:39:15.430891Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Dec 12 18:39:15.431031 waagent[2640]: 2025-12-12T18:39:15.430997Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Dec 12 18:39:15.438071 waagent[2640]: 2025-12-12T18:39:15.437982Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Dec 12 18:39:15.447637 waagent[2640]: 2025-12-12T18:39:15.447591Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.179 Dec 12 18:39:15.448102 waagent[2640]: 2025-12-12T18:39:15.448060Z INFO ExtHandler Dec 12 18:39:15.448185 waagent[2640]: 2025-12-12T18:39:15.448153Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: a17f7d8f-66c3-4cf4-b406-476be57a2ee8 eTag: 8296466777811478468 source: Fabric] Dec 12 18:39:15.448526 waagent[2640]: 2025-12-12T18:39:15.448479Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Dec 12 18:39:15.449129 waagent[2640]: 2025-12-12T18:39:15.449086Z INFO ExtHandler Dec 12 18:39:15.449199 waagent[2640]: 2025-12-12T18:39:15.449166Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Dec 12 18:39:15.452481 waagent[2640]: 2025-12-12T18:39:15.452440Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Dec 12 18:39:15.510908 waagent[2640]: 2025-12-12T18:39:15.510761Z INFO ExtHandler Downloaded certificate {'thumbprint': 'A03293469EC24E8F69F5D66C6A000EC474614C08', 'hasPrivateKey': True} Dec 12 18:39:15.511332 waagent[2640]: 2025-12-12T18:39:15.511291Z INFO ExtHandler Fetch goal state completed Dec 12 18:39:15.522111 waagent[2640]: 2025-12-12T18:39:15.522062Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.4.2 1 Jul 2025 (Library: OpenSSL 3.4.2 1 Jul 2025) Dec 12 18:39:15.527082 waagent[2640]: 2025-12-12T18:39:15.527032Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2640 Dec 12 18:39:15.527220 waagent[2640]: 2025-12-12T18:39:15.527183Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Dec 12 18:39:15.527582 waagent[2640]: 2025-12-12T18:39:15.527546Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Dec 12 18:39:15.529269 waagent[2640]: 2025-12-12T18:39:15.529193Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4459.2.2', '', 'Flatcar Container Linux by Kinvolk'] Dec 12 18:39:15.529829 waagent[2640]: 2025-12-12T18:39:15.529644Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4459.2.2', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Dec 12 18:39:15.529829 waagent[2640]: 2025-12-12T18:39:15.529808Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Dec 12 18:39:15.530600 waagent[2640]: 2025-12-12T18:39:15.530385Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Dec 12 18:39:15.573927 waagent[2640]: 2025-12-12T18:39:15.573878Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Dec 12 18:39:15.574111 waagent[2640]: 2025-12-12T18:39:15.574075Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Dec 12 18:39:15.580714 waagent[2640]: 2025-12-12T18:39:15.580685Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Dec 12 18:39:15.587444 systemd[1]: Reload requested from client PID 2692 ('systemctl') (unit waagent.service)... Dec 12 18:39:15.587461 systemd[1]: Reloading... Dec 12 18:39:15.660484 zram_generator::config[2785]: No configuration found. Dec 12 18:39:15.969184 systemd[1]: Reloading finished in 381 ms. Dec 12 18:39:15.982679 waagent[2640]: 2025-12-12T18:39:15.982607Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Dec 12 18:39:15.982788 waagent[2640]: 2025-12-12T18:39:15.982755Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Dec 12 18:39:16.354008 waagent[2640]: 2025-12-12T18:39:16.353931Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Dec 12 18:39:16.354345 waagent[2640]: 2025-12-12T18:39:16.354267Z 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] Dec 12 18:39:16.355133 waagent[2640]: 2025-12-12T18:39:16.355073Z INFO ExtHandler ExtHandler Starting env monitor service. Dec 12 18:39:16.355382 waagent[2640]: 2025-12-12T18:39:16.355332Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Dec 12 18:39:16.355734 waagent[2640]: 2025-12-12T18:39:16.355689Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Dec 12 18:39:16.355796 waagent[2640]: 2025-12-12T18:39:16.355753Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Dec 12 18:39:16.356255 waagent[2640]: 2025-12-12T18:39:16.356208Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Dec 12 18:39:16.356476 waagent[2640]: 2025-12-12T18:39:16.356414Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Dec 12 18:39:16.356628 waagent[2640]: 2025-12-12T18:39:16.356574Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Dec 12 18:39:16.356747 waagent[2640]: 2025-12-12T18:39:16.356695Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Dec 12 18:39:16.356975 waagent[2640]: 2025-12-12T18:39:16.356935Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Dec 12 18:39:16.357141 waagent[2640]: 2025-12-12T18:39:16.357100Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Dec 12 18:39:16.357141 waagent[2640]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Dec 12 18:39:16.357141 waagent[2640]: eth0 00000000 0104C80A 0003 0 0 1024 00000000 0 0 0 Dec 12 18:39:16.357141 waagent[2640]: eth0 0004C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Dec 12 18:39:16.357141 waagent[2640]: eth0 0104C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Dec 12 18:39:16.357141 waagent[2640]: eth0 10813FA8 0104C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Dec 12 18:39:16.357141 waagent[2640]: eth0 FEA9FEA9 0104C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Dec 12 18:39:16.357717 waagent[2640]: 2025-12-12T18:39:16.357663Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Dec 12 18:39:16.357833 waagent[2640]: 2025-12-12T18:39:16.357786Z INFO EnvHandler ExtHandler Configure routes Dec 12 18:39:16.357892 waagent[2640]: 2025-12-12T18:39:16.357849Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Dec 12 18:39:16.358200 waagent[2640]: 2025-12-12T18:39:16.358164Z INFO EnvHandler ExtHandler Gateway:None Dec 12 18:39:16.358486 waagent[2640]: 2025-12-12T18:39:16.358442Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Dec 12 18:39:16.358828 waagent[2640]: 2025-12-12T18:39:16.358785Z INFO EnvHandler ExtHandler Routes:None Dec 12 18:39:16.369018 waagent[2640]: 2025-12-12T18:39:16.368975Z INFO ExtHandler ExtHandler Dec 12 18:39:16.369099 waagent[2640]: 2025-12-12T18:39:16.369062Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: fbedfdea-b4c0-4268-81d4-b935563e1597 correlation 68b01a44-5825-4060-84c9-a956a44f288f created: 2025-12-12T18:37:32.161179Z] Dec 12 18:39:16.369476 waagent[2640]: 2025-12-12T18:39:16.369410Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Dec 12 18:39:16.370064 waagent[2640]: 2025-12-12T18:39:16.370022Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 1 ms] Dec 12 18:39:16.391159 waagent[2640]: 2025-12-12T18:39:16.391115Z 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 Dec 12 18:39:16.391159 waagent[2640]: Try `iptables -h' or 'iptables --help' for more information.) Dec 12 18:39:16.391543 waagent[2640]: 2025-12-12T18:39:16.391503Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: B7943DE3-F644-4AA0-A246-BAEFDF275BF2;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Dec 12 18:39:16.429269 waagent[2640]: 2025-12-12T18:39:16.429217Z INFO MonitorHandler ExtHandler Network interfaces: Dec 12 18:39:16.429269 waagent[2640]: Executing ['ip', '-a', '-o', 'link']: Dec 12 18:39:16.429269 waagent[2640]: 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 Dec 12 18:39:16.429269 waagent[2640]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:0d:3a:7f:25:34 brd ff:ff:ff:ff:ff:ff Dec 12 18:39:16.429269 waagent[2640]: Executing ['ip', '-4', '-a', '-o', 'address']: Dec 12 18:39:16.429269 waagent[2640]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Dec 12 18:39:16.429269 waagent[2640]: 2: eth0 inet 10.200.4.28/24 metric 1024 brd 10.200.4.255 scope global eth0\ valid_lft forever preferred_lft forever Dec 12 18:39:16.429269 waagent[2640]: Executing ['ip', '-6', '-a', '-o', 'address']: Dec 12 18:39:16.429269 waagent[2640]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Dec 12 18:39:16.429269 waagent[2640]: 2: eth0 inet6 fe80::20d:3aff:fe7f:2534/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Dec 12 18:39:16.489303 waagent[2640]: 2025-12-12T18:39:16.489244Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Dec 12 18:39:16.489303 waagent[2640]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Dec 12 18:39:16.489303 waagent[2640]: pkts bytes target prot opt in out source destination Dec 12 18:39:16.489303 waagent[2640]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Dec 12 18:39:16.489303 waagent[2640]: pkts bytes target prot opt in out source destination Dec 12 18:39:16.489303 waagent[2640]: Chain OUTPUT (policy ACCEPT 13 packets, 1396 bytes) Dec 12 18:39:16.489303 waagent[2640]: pkts bytes target prot opt in out source destination Dec 12 18:39:16.489303 waagent[2640]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Dec 12 18:39:16.489303 waagent[2640]: 1 52 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Dec 12 18:39:16.489303 waagent[2640]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Dec 12 18:39:16.493414 waagent[2640]: 2025-12-12T18:39:16.493363Z INFO EnvHandler ExtHandler Current Firewall rules: Dec 12 18:39:16.493414 waagent[2640]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Dec 12 18:39:16.493414 waagent[2640]: pkts bytes target prot opt in out source destination Dec 12 18:39:16.493414 waagent[2640]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Dec 12 18:39:16.493414 waagent[2640]: pkts bytes target prot opt in out source destination Dec 12 18:39:16.493414 waagent[2640]: Chain OUTPUT (policy ACCEPT 13 packets, 1396 bytes) Dec 12 18:39:16.493414 waagent[2640]: pkts bytes target prot opt in out source destination Dec 12 18:39:16.493414 waagent[2640]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Dec 12 18:39:16.493414 waagent[2640]: 1 52 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Dec 12 18:39:16.493414 waagent[2640]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Dec 12 18:39:16.493988 waagent[2640]: 2025-12-12T18:39:16.493852Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Dec 12 18:39:16.494338 waagent[2640]: 2025-12-12T18:39:16.494305Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Dec 12 18:39:18.709078 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Dec 12 18:39:18.710367 systemd[1]: Started sshd@0-10.200.4.28:22-10.200.16.10:45516.service - OpenSSH per-connection server daemon (10.200.16.10:45516). Dec 12 18:39:19.409752 sshd[3329]: Accepted publickey for core from 10.200.16.10 port 45516 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:39:19.410978 sshd-session[3329]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:39:19.415553 systemd-logind[1872]: New session 3 of user core. Dec 12 18:39:19.421586 systemd[1]: Started session-3.scope - Session 3 of User core. Dec 12 18:39:19.441463 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Dec 12 18:39:19.942715 systemd[1]: Started sshd@1-10.200.4.28:22-10.200.16.10:45524.service - OpenSSH per-connection server daemon (10.200.16.10:45524). Dec 12 18:39:20.553339 sshd[3823]: Accepted publickey for core from 10.200.16.10 port 45524 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:39:20.554737 sshd-session[3823]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:39:20.560023 systemd-logind[1872]: New session 4 of user core. Dec 12 18:39:20.569598 systemd[1]: Started session-4.scope - Session 4 of User core. Dec 12 18:39:20.994622 sshd[4050]: Connection closed by 10.200.16.10 port 45524 Dec 12 18:39:20.995186 sshd-session[3823]: pam_unix(sshd:session): session closed for user core Dec 12 18:39:20.998927 systemd[1]: sshd@1-10.200.4.28:22-10.200.16.10:45524.service: Deactivated successfully. Dec 12 18:39:21.001249 systemd[1]: session-4.scope: Deactivated successfully. Dec 12 18:39:21.001930 systemd-logind[1872]: Session 4 logged out. Waiting for processes to exit. Dec 12 18:39:21.003131 systemd-logind[1872]: Removed session 4. Dec 12 18:39:21.106717 systemd[1]: Started sshd@2-10.200.4.28:22-10.200.16.10:40278.service - OpenSSH per-connection server daemon (10.200.16.10:40278). Dec 12 18:39:21.424585 update_engine[1875]: I20251212 18:39:21.424461 1875 update_attempter.cc:509] Updating boot flags... Dec 12 18:39:21.708319 sshd[4351]: Accepted publickey for core from 10.200.16.10 port 40278 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:39:21.710014 sshd-session[4351]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:39:21.715508 systemd-logind[1872]: New session 5 of user core. Dec 12 18:39:21.725590 systemd[1]: Started session-5.scope - Session 5 of User core. Dec 12 18:39:22.135531 sshd[4777]: Connection closed by 10.200.16.10 port 40278 Dec 12 18:39:22.135891 sshd-session[4351]: pam_unix(sshd:session): session closed for user core Dec 12 18:39:22.139376 systemd[1]: sshd@2-10.200.4.28:22-10.200.16.10:40278.service: Deactivated successfully. Dec 12 18:39:22.140941 systemd[1]: session-5.scope: Deactivated successfully. Dec 12 18:39:22.141675 systemd-logind[1872]: Session 5 logged out. Waiting for processes to exit. Dec 12 18:39:22.142729 systemd-logind[1872]: Removed session 5. Dec 12 18:39:32.245891 systemd[1]: Started sshd@3-10.200.4.28:22-10.200.16.10:53608.service - OpenSSH per-connection server daemon (10.200.16.10:53608). Dec 12 18:39:32.865183 sshd[7825]: Accepted publickey for core from 10.200.16.10 port 53608 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:39:32.866539 sshd-session[7825]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:39:32.871286 systemd-logind[1872]: New session 6 of user core. Dec 12 18:39:32.879739 systemd[1]: Started session-6.scope - Session 6 of User core. Dec 12 18:39:33.296566 sshd[8096]: Connection closed by 10.200.16.10 port 53608 Dec 12 18:39:33.296944 sshd-session[7825]: pam_unix(sshd:session): session closed for user core Dec 12 18:39:33.301789 systemd-logind[1872]: Session 6 logged out. Waiting for processes to exit. Dec 12 18:39:33.302522 systemd[1]: sshd@3-10.200.4.28:22-10.200.16.10:53608.service: Deactivated successfully. Dec 12 18:39:33.307015 systemd[1]: session-6.scope: Deactivated successfully. Dec 12 18:39:33.314114 systemd-logind[1872]: Removed session 6. Dec 12 18:39:33.402975 systemd[1]: Started sshd@4-10.200.4.28:22-10.200.16.10:53618.service - OpenSSH per-connection server daemon (10.200.16.10:53618). Dec 12 18:39:34.019613 sshd[8258]: Accepted publickey for core from 10.200.16.10 port 53618 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:39:34.023018 sshd-session[8258]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:39:34.029078 systemd-logind[1872]: New session 7 of user core. Dec 12 18:39:34.037069 systemd[1]: Started session-7.scope - Session 7 of User core. Dec 12 18:39:34.454459 sshd[8525]: Connection closed by 10.200.16.10 port 53618 Dec 12 18:39:34.457517 sshd-session[8258]: pam_unix(sshd:session): session closed for user core Dec 12 18:39:34.461321 systemd-logind[1872]: Session 7 logged out. Waiting for processes to exit. Dec 12 18:39:34.463871 systemd[1]: sshd@4-10.200.4.28:22-10.200.16.10:53618.service: Deactivated successfully. Dec 12 18:39:34.466394 systemd[1]: session-7.scope: Deactivated successfully. Dec 12 18:39:34.469951 systemd-logind[1872]: Removed session 7. Dec 12 18:39:44.569214 systemd[1]: Started sshd@5-10.200.4.28:22-10.200.16.10:49192.service - OpenSSH per-connection server daemon (10.200.16.10:49192). Dec 12 18:39:45.200028 sshd[12454]: Accepted publickey for core from 10.200.16.10 port 49192 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:39:45.201759 sshd-session[12454]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:39:45.207832 systemd-logind[1872]: New session 8 of user core. Dec 12 18:39:45.214091 systemd[1]: Started session-8.scope - Session 8 of User core. Dec 12 18:39:45.634337 sshd[12636]: Connection closed by 10.200.16.10 port 49192 Dec 12 18:39:45.635606 sshd-session[12454]: pam_unix(sshd:session): session closed for user core Dec 12 18:39:45.641471 systemd-logind[1872]: Session 8 logged out. Waiting for processes to exit. Dec 12 18:39:45.642269 systemd[1]: sshd@5-10.200.4.28:22-10.200.16.10:49192.service: Deactivated successfully. Dec 12 18:39:45.644308 systemd[1]: session-8.scope: Deactivated successfully. Dec 12 18:39:45.646362 systemd-logind[1872]: Removed session 8. Dec 12 18:39:45.743507 systemd[1]: Started sshd@6-10.200.4.28:22-10.200.16.10:49198.service - OpenSSH per-connection server daemon (10.200.16.10:49198). Dec 12 18:39:46.368550 sshd[12817]: Accepted publickey for core from 10.200.16.10 port 49198 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:39:46.369898 sshd-session[12817]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:39:46.376565 systemd-logind[1872]: New session 9 of user core. Dec 12 18:39:46.386518 systemd[1]: Started session-9.scope - Session 9 of User core. Dec 12 18:39:46.797346 sshd[13053]: Connection closed by 10.200.16.10 port 49198 Dec 12 18:39:46.797922 sshd-session[12817]: pam_unix(sshd:session): session closed for user core Dec 12 18:39:46.802376 systemd-logind[1872]: Session 9 logged out. Waiting for processes to exit. Dec 12 18:39:46.804124 systemd[1]: sshd@6-10.200.4.28:22-10.200.16.10:49198.service: Deactivated successfully. Dec 12 18:39:46.807376 systemd[1]: session-9.scope: Deactivated successfully. Dec 12 18:39:46.810098 systemd-logind[1872]: Removed session 9. Dec 12 18:39:56.911597 systemd[1]: Started sshd@7-10.200.4.28:22-10.200.16.10:43910.service - OpenSSH per-connection server daemon (10.200.16.10:43910). Dec 12 18:39:57.535911 sshd[14388]: Accepted publickey for core from 10.200.16.10 port 43910 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:39:57.537819 sshd-session[14388]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:39:57.544899 systemd-logind[1872]: New session 10 of user core. Dec 12 18:39:57.551603 systemd[1]: Started session-10.scope - Session 10 of User core. Dec 12 18:39:57.973649 sshd[14399]: Connection closed by 10.200.16.10 port 43910 Dec 12 18:39:57.974465 sshd-session[14388]: pam_unix(sshd:session): session closed for user core Dec 12 18:39:57.978216 systemd-logind[1872]: Session 10 logged out. Waiting for processes to exit. Dec 12 18:39:57.978959 systemd[1]: sshd@7-10.200.4.28:22-10.200.16.10:43910.service: Deactivated successfully. Dec 12 18:39:57.982776 systemd[1]: session-10.scope: Deactivated successfully. Dec 12 18:39:57.988394 systemd-logind[1872]: Removed session 10. Dec 12 18:39:58.082023 systemd[1]: Started sshd@8-10.200.4.28:22-10.200.16.10:43922.service - OpenSSH per-connection server daemon (10.200.16.10:43922). Dec 12 18:39:58.702992 sshd[14427]: Accepted publickey for core from 10.200.16.10 port 43922 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:39:58.704321 sshd-session[14427]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:39:58.711951 systemd-logind[1872]: New session 11 of user core. Dec 12 18:39:58.717539 systemd[1]: Started session-11.scope - Session 11 of User core. Dec 12 18:39:59.147845 sshd[14444]: Connection closed by 10.200.16.10 port 43922 Dec 12 18:39:59.148219 sshd-session[14427]: pam_unix(sshd:session): session closed for user core Dec 12 18:39:59.153039 systemd-logind[1872]: Session 11 logged out. Waiting for processes to exit. Dec 12 18:39:59.154394 systemd[1]: sshd@8-10.200.4.28:22-10.200.16.10:43922.service: Deactivated successfully. Dec 12 18:39:59.157687 systemd[1]: session-11.scope: Deactivated successfully. Dec 12 18:39:59.160973 systemd-logind[1872]: Removed session 11. Dec 12 18:40:09.259724 systemd[1]: Started sshd@9-10.200.4.28:22-10.200.16.10:37274.service - OpenSSH per-connection server daemon (10.200.16.10:37274). Dec 12 18:40:09.894602 sshd[14802]: Accepted publickey for core from 10.200.16.10 port 37274 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:40:09.896411 sshd-session[14802]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:40:09.904131 systemd-logind[1872]: New session 12 of user core. Dec 12 18:40:09.913739 systemd[1]: Started session-12.scope - Session 12 of User core. Dec 12 18:40:10.343921 sshd[14817]: Connection closed by 10.200.16.10 port 37274 Dec 12 18:40:10.344846 sshd-session[14802]: pam_unix(sshd:session): session closed for user core Dec 12 18:40:10.350314 systemd-logind[1872]: Session 12 logged out. Waiting for processes to exit. Dec 12 18:40:10.352788 systemd[1]: sshd@9-10.200.4.28:22-10.200.16.10:37274.service: Deactivated successfully. Dec 12 18:40:10.355119 systemd[1]: session-12.scope: Deactivated successfully. Dec 12 18:40:10.358471 systemd-logind[1872]: Removed session 12. Dec 12 18:40:10.454820 systemd[1]: Started sshd@10-10.200.4.28:22-10.200.16.10:51928.service - OpenSSH per-connection server daemon (10.200.16.10:51928). Dec 12 18:40:11.082255 sshd[14841]: Accepted publickey for core from 10.200.16.10 port 51928 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:40:11.084043 sshd-session[14841]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:40:11.090755 systemd-logind[1872]: New session 13 of user core. Dec 12 18:40:11.097073 systemd[1]: Started session-13.scope - Session 13 of User core. Dec 12 18:40:11.522446 sshd[14856]: Connection closed by 10.200.16.10 port 51928 Dec 12 18:40:11.524611 sshd-session[14841]: pam_unix(sshd:session): session closed for user core Dec 12 18:40:11.528353 systemd-logind[1872]: Session 13 logged out. Waiting for processes to exit. Dec 12 18:40:11.529740 systemd[1]: sshd@10-10.200.4.28:22-10.200.16.10:51928.service: Deactivated successfully. Dec 12 18:40:11.531594 systemd[1]: session-13.scope: Deactivated successfully. Dec 12 18:40:11.535307 systemd-logind[1872]: Removed session 13. Dec 12 18:40:21.635685 systemd[1]: Started sshd@11-10.200.4.28:22-10.200.16.10:32888.service - OpenSSH per-connection server daemon (10.200.16.10:32888). Dec 12 18:40:22.238962 sshd[15201]: Accepted publickey for core from 10.200.16.10 port 32888 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:40:22.240229 sshd-session[15201]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:40:22.244459 systemd-logind[1872]: New session 14 of user core. Dec 12 18:40:22.252543 systemd[1]: Started session-14.scope - Session 14 of User core. Dec 12 18:40:22.681106 sshd[15204]: Connection closed by 10.200.16.10 port 32888 Dec 12 18:40:22.681498 sshd-session[15201]: pam_unix(sshd:session): session closed for user core Dec 12 18:40:22.684933 systemd[1]: sshd@11-10.200.4.28:22-10.200.16.10:32888.service: Deactivated successfully. Dec 12 18:40:22.686605 systemd[1]: session-14.scope: Deactivated successfully. Dec 12 18:40:22.687315 systemd-logind[1872]: Session 14 logged out. Waiting for processes to exit. Dec 12 18:40:22.688300 systemd-logind[1872]: Removed session 14. Dec 12 18:40:22.797700 systemd[1]: Started sshd@12-10.200.4.28:22-10.200.16.10:32904.service - OpenSSH per-connection server daemon (10.200.16.10:32904). Dec 12 18:40:23.398938 sshd[15210]: Accepted publickey for core from 10.200.16.10 port 32904 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:40:23.400173 sshd-session[15210]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:40:23.404626 systemd-logind[1872]: New session 15 of user core. Dec 12 18:40:23.415594 systemd[1]: Started session-15.scope - Session 15 of User core. Dec 12 18:40:23.822818 sshd[15213]: Connection closed by 10.200.16.10 port 32904 Dec 12 18:40:23.823191 sshd-session[15210]: pam_unix(sshd:session): session closed for user core Dec 12 18:40:23.826945 systemd[1]: sshd@12-10.200.4.28:22-10.200.16.10:32904.service: Deactivated successfully. Dec 12 18:40:23.828746 systemd[1]: session-15.scope: Deactivated successfully. Dec 12 18:40:23.829498 systemd-logind[1872]: Session 15 logged out. Waiting for processes to exit. Dec 12 18:40:23.830398 systemd-logind[1872]: Removed session 15. Dec 12 18:40:29.772418 setup-nvidia[2248]: \u000d Dec 12 18:40:29.772418 setup-nvidia[2248]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Dec 12 18:40:29.772418 setup-nvidia[2248]: frequently when this kernel module was built against the wrong or Dec 12 18:40:29.772418 setup-nvidia[2248]: improperly configured kernel sources, with a version of gcc that differs Dec 12 18:40:29.772418 setup-nvidia[2248]: from the one used to build the target kernel, or if another driver, such Dec 12 18:40:29.772418 setup-nvidia[2248]: as nouveau, is present and prevents the NVIDIA kernel module from Dec 12 18:40:29.772418 setup-nvidia[2248]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Dec 12 18:40:29.772418 setup-nvidia[2248]: installed in this system is supported by this NVIDIA Linux graphics Dec 12 18:40:29.772418 setup-nvidia[2248]: driver release. Dec 12 18:40:29.772418 setup-nvidia[2248]: \u000d Dec 12 18:40:29.772418 setup-nvidia[2248]: Please see the log entries 'Kernel module load error' and 'Kernel Dec 12 18:40:29.772418 setup-nvidia[2248]: messages' at the end of the file Dec 12 18:40:29.772418 setup-nvidia[2248]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for more Dec 12 18:40:29.772418 setup-nvidia[2248]: information. Dec 12 18:40:29.772418 setup-nvidia[2248]: \u000d Dec 12 18:40:29.787608 setup-nvidia[2248]: dmesg: read kernel buffer failed: Operation not permitted Dec 12 18:40:29.807911 setup-nvidia[2248]: \u000d Dec 12 18:40:29.807911 setup-nvidia[2248]: ERROR: Installation has failed. Please see the file Dec 12 18:40:29.807911 setup-nvidia[2248]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for Dec 12 18:40:29.807911 setup-nvidia[2248]: details. You may find suggestions on fixing installation problems in Dec 12 18:40:29.807911 setup-nvidia[2248]: the README available on the Linux driver download page at Dec 12 18:40:29.807911 setup-nvidia[2248]: www.nvidia.com. Dec 12 18:40:29.807911 setup-nvidia[2248]: \u000d Dec 12 18:40:29.808331 setup-nvidia[2248]: Last 50 lines of nvidia-installer.log: Dec 12 18:40:29.809306 setup-nvidia[2248]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039059rm+0x53: 'naked' return found in MITIGATION_RETHUNK build Dec 12 18:40:29.809306 setup-nvidia[2248]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039056rm+0x90: 'naked' return found in MITIGATION_RETHUNK build Dec 12 18:40:29.809306 setup-nvidia[2248]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039049rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build Dec 12 18:40:29.809306 setup-nvidia[2248]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039105rm+0x93: 'naked' return found in MITIGATION_RETHUNK build Dec 12 18:40:29.809306 setup-nvidia[2248]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039101rm+0x36: 'naked' return found in MITIGATION_RETHUNK build Dec 12 18:40:29.809306 setup-nvidia[2248]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039100rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build Dec 12 18:40:29.809306 setup-nvidia[2248]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039102rm+0x77: 'naked' return found in MITIGATION_RETHUNK build Dec 12 18:40:29.809306 setup-nvidia[2248]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039046rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build Dec 12 18:40:29.809306 setup-nvidia[2248]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030615rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build Dec 12 18:40:29.809306 setup-nvidia[2248]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030614rm+0x27: 'naked' return found in MITIGATION_RETHUNK build Dec 12 18:40:29.809306 setup-nvidia[2248]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030618rm+0x37: 'naked' return found in MITIGATION_RETHUNK build Dec 12 18:40:29.809306 setup-nvidia[2248]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030617rm+0x24: 'naked' return found in MITIGATION_RETHUNK build Dec 12 18:40:29.809306 setup-nvidia[2248]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030616rm+0xca: 'naked' return found in MITIGATION_RETHUNK build Dec 12 18:40:29.809306 setup-nvidia[2248]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030619rm+0x193: 'naked' return found in MITIGATION_RETHUNK build Dec 12 18:40:29.809703 setup-nvidia[2248]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030621rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build Dec 12 18:40:29.809703 setup-nvidia[2248]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv011864rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build Dec 12 18:40:29.809703 setup-nvidia[2248]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039413rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build Dec 12 18:40:29.809703 setup-nvidia[2248]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/Module.symvers Dec 12 18:40:29.809703 setup-nvidia[2248]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.mod.o Dec 12 18:40:29.809703 setup-nvidia[2248]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/.module-common.o Dec 12 18:40:29.809703 setup-nvidia[2248]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.mod.o Dec 12 18:40:29.809703 setup-nvidia[2248]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.mod.o Dec 12 18:40:29.809703 setup-nvidia[2248]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.mod.o Dec 12 18:40:29.809703 setup-nvidia[2248]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.mod.o Dec 12 18:40:29.809703 setup-nvidia[2248]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Dec 12 18:40:29.809703 setup-nvidia[2248]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Dec 12 18:40:29.809703 setup-nvidia[2248]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Dec 12 18:40:29.809703 setup-nvidia[2248]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Dec 12 18:40:29.809703 setup-nvidia[2248]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Dec 12 18:40:29.809703 setup-nvidia[2248]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko due to unavailability of vmlinux Dec 12 18:40:29.809703 setup-nvidia[2248]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Dec 12 18:40:29.809703 setup-nvidia[2248]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Dec 12 18:40:29.809703 setup-nvidia[2248]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko due to unavailability of vmlinux Dec 12 18:40:29.809703 setup-nvidia[2248]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Dec 12 18:40:29.809703 setup-nvidia[2248]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko due to unavailability of vmlinux Dec 12 18:40:29.809703 setup-nvidia[2248]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Dec 12 18:40:29.810220 setup-nvidia[2248]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko due to unavailability of vmlinux Dec 12 18:40:29.810220 setup-nvidia[2248]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Dec 12 18:40:29.810220 setup-nvidia[2248]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko due to unavailability of vmlinux Dec 12 18:40:29.810220 setup-nvidia[2248]: make[2]: Leaving directory '/usr/lib/modules/6.12.61-flatcar/build' Dec 12 18:40:29.810220 setup-nvidia[2248]: make[1]: Leaving directory '/usr/lib/modules/6.12.61-flatcar/source' Dec 12 18:40:29.810220 setup-nvidia[2248]: -> done. Dec 12 18:40:29.810220 setup-nvidia[2248]: -> Kernel module compilation complete. Dec 12 18:40:29.810220 setup-nvidia[2248]: -> Unable to determine if Secure Boot is enabled: No such file or directory Dec 12 18:40:29.810220 setup-nvidia[2248]: 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. Dec 12 18:40:29.810220 setup-nvidia[2248]: \u000d Dec 12 18:40:29.810220 setup-nvidia[2248]: 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. Dec 12 18:40:29.810220 setup-nvidia[2248]: -> Kernel module load error: Operation not permitted Dec 12 18:40:29.810220 setup-nvidia[2248]: -> Kernel messages: Dec 12 18:40:29.810220 setup-nvidia[2248]: 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. Dec 12 18:40:33.935655 systemd[1]: Started sshd@13-10.200.4.28:22-10.200.16.10:59972.service - OpenSSH per-connection server daemon (10.200.16.10:59972). Dec 12 18:40:34.535020 sshd[15399]: Accepted publickey for core from 10.200.16.10 port 59972 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:40:34.536198 sshd-session[15399]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:40:34.540349 systemd-logind[1872]: New session 16 of user core. Dec 12 18:40:34.556563 systemd[1]: Started session-16.scope - Session 16 of User core. Dec 12 18:40:34.978530 sshd[15402]: Connection closed by 10.200.16.10 port 59972 Dec 12 18:40:34.978793 sshd-session[15399]: pam_unix(sshd:session): session closed for user core Dec 12 18:40:34.981567 systemd[1]: sshd@13-10.200.4.28:22-10.200.16.10:59972.service: Deactivated successfully. Dec 12 18:40:34.983144 systemd[1]: session-16.scope: Deactivated successfully. Dec 12 18:40:34.984379 systemd-logind[1872]: Session 16 logged out. Waiting for processes to exit. Dec 12 18:40:34.985526 systemd-logind[1872]: Removed session 16. Dec 12 18:40:35.084625 systemd[1]: Started sshd@14-10.200.4.28:22-10.200.16.10:59976.service - OpenSSH per-connection server daemon (10.200.16.10:59976). Dec 12 18:40:35.684311 sshd[15408]: Accepted publickey for core from 10.200.16.10 port 59976 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:40:35.685557 sshd-session[15408]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:40:35.689749 systemd-logind[1872]: New session 17 of user core. Dec 12 18:40:35.700692 systemd[1]: Started session-17.scope - Session 17 of User core. Dec 12 18:40:36.111947 sshd[15411]: Connection closed by 10.200.16.10 port 59976 Dec 12 18:40:36.112344 sshd-session[15408]: pam_unix(sshd:session): session closed for user core Dec 12 18:40:36.115827 systemd[1]: sshd@14-10.200.4.28:22-10.200.16.10:59976.service: Deactivated successfully. Dec 12 18:40:36.117448 systemd[1]: session-17.scope: Deactivated successfully. Dec 12 18:40:36.118127 systemd-logind[1872]: Session 17 logged out. Waiting for processes to exit. Dec 12 18:40:36.119019 systemd-logind[1872]: Removed session 17. Dec 12 18:40:43.569449 kernel: EXT4-fs (loop6p9): unmounting filesystem 02782f9c-2fea-4b2e-9279-7381cb6c9c28. Dec 12 18:40:43.570570 setup-nvidia[2248]: Container flatcardevelopercontainer-4459.2.2.bin exited successfully. Dec 12 18:40:43.572867 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4459.2.2.bin.mount: Deactivated successfully. Dec 12 18:40:43.625248 sudo[2236]: pam_unix(sudo:session): session closed for user root Dec 12 18:40:43.627141 systemd[1]: session-c2.scope: Deactivated successfully. Dec 12 18:40:43.627401 systemd[1]: session-c2.scope: Consumed 5min 19.187s CPU time, 889.7M memory peak. Dec 12 18:40:44.055834 setup-nvidia[1867]: /opt/nvidia / Dec 12 18:40:44.056292 setup-nvidia[1867]: / Dec 12 18:40:44.070444 kernel: IPMI message handler: version 39.2 Dec 12 18:40:44.073444 kernel: ipmi device interface Dec 12 18:40:44.086454 setup-nvidia[1867]: /opt/nvidia/current/usr/lib/modules/6.12.61-flatcar/video / Dec 12 18:40:44.139055 kernel: nvidia: loading out-of-tree module taints kernel. Dec 12 18:40:44.139188 kernel: nvidia: module license 'NVIDIA' taints kernel. Dec 12 18:40:44.139211 kernel: Disabling lock debugging due to kernel taint Dec 12 18:40:44.146272 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Dec 12 18:40:44.146317 kernel: nvidia: module license taints kernel. Dec 12 18:40:44.536449 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 Dec 12 18:40:44.940522 kernel: Dec 12 18:40:44.940661 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Dec 12 18:40:44.993444 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.261.03 Sat Jun 14 16:05:50 UTC 2025 Dec 12 18:40:45.015444 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.261.03 Sat Jun 14 15:39:16 UTC 2025 Dec 12 18:40:45.035452 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Dec 12 18:40:45.080448 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. Dec 12 18:40:45.080600 setup-nvidia[1867]: / Dec 12 18:40:45.095983 setup-nvidia[15451]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Dec 12 18:40:46.218920 systemd[1]: Started sshd@15-10.200.4.28:22-10.200.16.10:44846.service - OpenSSH per-connection server daemon (10.200.16.10:44846). Dec 12 18:40:46.736268 setup-nvidia[15468]: Fri Dec 12 18:40:46 2025 Dec 12 18:40:46.736268 setup-nvidia[15468]: +---------------------------------------------------------------------------------------+ Dec 12 18:40:46.736268 setup-nvidia[15468]: | NVIDIA-SMI 535.261.03 Driver Version: 535.261.03 CUDA Version: 12.2 | Dec 12 18:40:46.736268 setup-nvidia[15468]: |-----------------------------------------+----------------------+----------------------+ Dec 12 18:40:46.736268 setup-nvidia[15468]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Dec 12 18:40:46.736268 setup-nvidia[15468]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Dec 12 18:40:46.736268 setup-nvidia[15468]: | | | MIG M. | Dec 12 18:40:46.736268 setup-nvidia[15468]: |=========================================+======================+======================| Dec 12 18:40:46.739839 setup-nvidia[15468]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Dec 12 18:40:46.739839 setup-nvidia[15468]: | N/A 29C P0 34W / 250W | 0MiB / 16384MiB | 2% Default | Dec 12 18:40:46.739839 setup-nvidia[15468]: | | | N/A | Dec 12 18:40:46.739839 setup-nvidia[15468]: +-----------------------------------------+----------------------+----------------------+ Dec 12 18:40:46.739839 setup-nvidia[15468]: Dec 12 18:40:46.739839 setup-nvidia[15468]: +---------------------------------------------------------------------------------------+ Dec 12 18:40:46.739839 setup-nvidia[15468]: | Processes: | Dec 12 18:40:46.739839 setup-nvidia[15468]: | GPU GI CI PID Type Process name GPU Memory | Dec 12 18:40:46.739839 setup-nvidia[15468]: | ID ID Usage | Dec 12 18:40:46.739839 setup-nvidia[15468]: |=======================================================================================| Dec 12 18:40:46.740083 setup-nvidia[15468]: | No running processes found | Dec 12 18:40:46.740083 setup-nvidia[15468]: +---------------------------------------------------------------------------------------+ Dec 12 18:40:46.819026 sshd[15473]: Accepted publickey for core from 10.200.16.10 port 44846 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:40:46.820274 sshd-session[15473]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:40:46.824402 systemd-logind[1872]: New session 18 of user core. Dec 12 18:40:46.839562 systemd[1]: Started session-18.scope - Session 18 of User core. Dec 12 18:40:47.119446 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Dec 12 18:40:47.120068 systemd[1]: Reached target multi-user.target - Multi-User System. Dec 12 18:40:47.120479 systemd[1]: Startup finished in 1.037s (firmware) + 16.474s (loader) + 3.790s (kernel) + 11.772s (initrd) + 2min 21.133s (userspace) = 2min 54.207s. Dec 12 18:40:47.428624 sshd[15476]: Connection closed by 10.200.16.10 port 44846 Dec 12 18:40:47.428947 sshd-session[15473]: pam_unix(sshd:session): session closed for user core Dec 12 18:40:47.431953 systemd[1]: sshd@15-10.200.4.28:22-10.200.16.10:44846.service: Deactivated successfully. Dec 12 18:40:47.433645 systemd[1]: session-18.scope: Deactivated successfully. Dec 12 18:40:47.434924 systemd-logind[1872]: Session 18 logged out. Waiting for processes to exit. Dec 12 18:40:47.435920 systemd-logind[1872]: Removed session 18. Dec 12 18:40:47.545412 systemd[1]: Started sshd@16-10.200.4.28:22-10.200.16.10:44854.service - OpenSSH per-connection server daemon (10.200.16.10:44854). Dec 12 18:40:48.142768 sshd[15484]: Accepted publickey for core from 10.200.16.10 port 44854 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:40:48.143963 sshd-session[15484]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:40:48.148101 systemd-logind[1872]: New session 19 of user core. Dec 12 18:40:48.156550 systemd[1]: Started session-19.scope - Session 19 of User core. Dec 12 18:40:48.566115 sshd[15487]: Connection closed by 10.200.16.10 port 44854 Dec 12 18:40:48.566484 sshd-session[15484]: pam_unix(sshd:session): session closed for user core Dec 12 18:40:48.570391 systemd[1]: sshd@16-10.200.4.28:22-10.200.16.10:44854.service: Deactivated successfully. Dec 12 18:40:48.572002 systemd[1]: session-19.scope: Deactivated successfully. Dec 12 18:40:48.572721 systemd-logind[1872]: Session 19 logged out. Waiting for processes to exit. Dec 12 18:40:48.573757 systemd-logind[1872]: Removed session 19. Dec 12 18:40:48.683483 systemd[1]: Started sshd@17-10.200.4.28:22-10.200.16.10:44864.service - OpenSSH per-connection server daemon (10.200.16.10:44864). Dec 12 18:40:49.293814 sshd[15493]: Accepted publickey for core from 10.200.16.10 port 44864 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:40:49.295032 sshd-session[15493]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:40:49.299272 systemd-logind[1872]: New session 20 of user core. Dec 12 18:40:49.308547 systemd[1]: Started session-20.scope - Session 20 of User core. Dec 12 18:40:49.731015 sshd[15496]: Connection closed by 10.200.16.10 port 44864 Dec 12 18:40:49.731252 sshd-session[15493]: pam_unix(sshd:session): session closed for user core Dec 12 18:40:49.734496 systemd[1]: sshd@17-10.200.4.28:22-10.200.16.10:44864.service: Deactivated successfully. Dec 12 18:40:49.736332 systemd[1]: session-20.scope: Deactivated successfully. Dec 12 18:40:49.737153 systemd-logind[1872]: Session 20 logged out. Waiting for processes to exit. Dec 12 18:40:49.738134 systemd-logind[1872]: Removed session 20. Dec 12 18:40:49.841843 systemd[1]: Started sshd@18-10.200.4.28:22-10.200.16.10:44868.service - OpenSSH per-connection server daemon (10.200.16.10:44868). Dec 12 18:40:50.444999 sshd[15502]: Accepted publickey for core from 10.200.16.10 port 44868 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:40:50.447471 sshd-session[15502]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:40:50.451722 systemd-logind[1872]: New session 21 of user core. Dec 12 18:40:50.463587 systemd[1]: Started session-21.scope - Session 21 of User core. Dec 12 18:40:50.880541 sshd[15505]: Connection closed by 10.200.16.10 port 44868 Dec 12 18:40:50.880866 sshd-session[15502]: pam_unix(sshd:session): session closed for user core Dec 12 18:40:50.884440 systemd[1]: sshd@18-10.200.4.28:22-10.200.16.10:44868.service: Deactivated successfully. Dec 12 18:40:50.886019 systemd[1]: session-21.scope: Deactivated successfully. Dec 12 18:40:50.886714 systemd-logind[1872]: Session 21 logged out. Waiting for processes to exit. Dec 12 18:40:50.887589 systemd-logind[1872]: Removed session 21. Dec 12 18:40:50.990721 systemd[1]: Started sshd@19-10.200.4.28:22-10.200.16.10:59210.service - OpenSSH per-connection server daemon (10.200.16.10:59210). Dec 12 18:40:51.589678 sshd[15511]: Accepted publickey for core from 10.200.16.10 port 59210 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:40:51.590923 sshd-session[15511]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:40:51.595105 systemd-logind[1872]: New session 22 of user core. Dec 12 18:40:51.603551 systemd[1]: Started session-22.scope - Session 22 of User core. Dec 12 18:40:53.485676 sshd[15514]: Connection closed by 10.200.16.10 port 59210 Dec 12 18:40:53.486061 sshd-session[15511]: pam_unix(sshd:session): session closed for user core Dec 12 18:40:53.489767 systemd[1]: sshd@19-10.200.4.28:22-10.200.16.10:59210.service: Deactivated successfully. Dec 12 18:40:53.491362 systemd[1]: session-22.scope: Deactivated successfully. Dec 12 18:40:53.491590 systemd[1]: session-22.scope: Consumed 1.407s CPU time, 14.2M memory peak. Dec 12 18:40:53.492061 systemd-logind[1872]: Session 22 logged out. Waiting for processes to exit. Dec 12 18:40:53.492982 systemd-logind[1872]: Removed session 22. Dec 12 18:40:53.742969 systemd[1]: Stopping user@0.service - User Manager for UID 0... Dec 12 18:40:53.743911 systemd[2239]: Activating special unit exit.target... Dec 12 18:40:53.744004 systemd[2239]: Stopped target default.target - Main User Target. Dec 12 18:40:53.744052 systemd[2239]: Stopped target basic.target - Basic System. Dec 12 18:40:53.744088 systemd[2239]: Stopped target paths.target - Paths. Dec 12 18:40:53.744120 systemd[2239]: Stopped target sockets.target - Sockets. Dec 12 18:40:53.744153 systemd[2239]: Stopped target timers.target - Timers. Dec 12 18:40:53.744629 systemd[2239]: Closed dbus.socket - D-Bus User Message Bus Socket. Dec 12 18:40:53.744884 systemd[2239]: Removed slice app.slice - User Application Slice. Dec 12 18:40:53.744920 systemd[2239]: Reached target shutdown.target - Shutdown. Dec 12 18:40:53.745077 systemd[2239]: Finished systemd-exit.service - Exit the Session. Dec 12 18:40:53.745118 systemd[2239]: Reached target exit.target - Exit the Session. Dec 12 18:40:53.759240 (sd-pam)[2240]: pam_unix(systemd-user:session): session closed for user root Dec 12 18:40:53.760661 systemd[1]: user@0.service: Deactivated successfully. Dec 12 18:40:53.761028 systemd[1]: Stopped user@0.service - User Manager for UID 0. Dec 12 18:40:53.763218 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Dec 12 18:40:53.793692 systemd[1]: run-user-0.mount: Deactivated successfully. Dec 12 18:40:53.794600 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Dec 12 18:40:53.794789 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Dec 12 18:40:53.796331 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Dec 12 18:40:53.796433 systemd[1]: user-0.slice: Consumed 5min 19.495s CPU time, 895.9M memory peak. Dec 12 18:40:53.797020 systemd-logind[1872]: Removed session c3.