Dec 16 13:03:40.165124 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 16 13:03:40.165153 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 16 13:03:40.165163 kernel: BIOS-provided physical RAM map: Dec 16 13:03:40.165169 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Dec 16 13:03:40.165176 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Dec 16 13:03:40.165182 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Dec 16 13:03:40.165190 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Dec 16 13:03:40.165199 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Dec 16 13:03:40.165206 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Dec 16 13:03:40.165212 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Dec 16 13:03:40.165219 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Dec 16 13:03:40.165225 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Dec 16 13:03:40.165232 kernel: printk: legacy bootconsole [earlyser0] enabled Dec 16 13:03:40.165239 kernel: NX (Execute Disable) protection: active Dec 16 13:03:40.165249 kernel: APIC: Static calls initialized Dec 16 13:03:40.165256 kernel: efi: EFI v2.7 by Microsoft Dec 16 13:03:40.165264 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3ee5fa98 RNG=0x3ffd1018 Dec 16 13:03:40.165272 kernel: random: crng init done Dec 16 13:03:40.165279 kernel: secureboot: Secure boot disabled Dec 16 13:03:40.165286 kernel: SMBIOS 3.1.0 present. Dec 16 13:03:40.165294 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Dec 16 13:03:40.165301 kernel: DMI: Memory slots populated: 3/3 Dec 16 13:03:40.165308 kernel: Hypervisor detected: Microsoft Hyper-V Dec 16 13:03:40.165316 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Dec 16 13:03:40.165325 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Dec 16 13:03:40.165333 kernel: Hyper-V: Using hypercall for remote TLB flush Dec 16 13:03:40.165369 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Dec 16 13:03:40.165377 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Dec 16 13:03:40.165386 kernel: tsc: Marking TSC unstable due to running on Hyper-V Dec 16 13:03:40.165393 kernel: tsc: Detected 2593.993 MHz processor Dec 16 13:03:40.165401 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Dec 16 13:03:40.165412 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Dec 16 13:03:40.165419 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Dec 16 13:03:40.165430 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Dec 16 13:03:40.165438 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Dec 16 13:03:40.165445 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Dec 16 13:03:40.165453 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Dec 16 13:03:40.165460 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Dec 16 13:03:40.165468 kernel: Using GB pages for direct mapping Dec 16 13:03:40.165475 kernel: ACPI: Early table checksum verification disabled Dec 16 13:03:40.165487 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Dec 16 13:03:40.165497 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Dec 16 13:03:40.165505 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Dec 16 13:03:40.165513 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Dec 16 13:03:40.165521 kernel: ACPI: FACS 0x000000003FFFE000 000040 Dec 16 13:03:40.165529 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Dec 16 13:03:40.165571 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Dec 16 13:03:40.165593 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Dec 16 13:03:40.165601 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Dec 16 13:03:40.165609 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Dec 16 13:03:40.165617 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Dec 16 13:03:40.165625 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Dec 16 13:03:40.165633 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Dec 16 13:03:40.165641 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Dec 16 13:03:40.165649 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Dec 16 13:03:40.165657 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Dec 16 13:03:40.165667 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Dec 16 13:03:40.165675 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Dec 16 13:03:40.165728 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Dec 16 13:03:40.165743 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Dec 16 13:03:40.165751 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Dec 16 13:03:40.165759 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Dec 16 13:03:40.165767 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Dec 16 13:03:40.165775 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Dec 16 13:03:40.165788 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Dec 16 13:03:40.165796 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Dec 16 13:03:40.165804 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Dec 16 13:03:40.165812 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Dec 16 13:03:40.165820 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Dec 16 13:03:40.165828 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Dec 16 13:03:40.165836 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] Dec 16 13:03:40.165844 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] Dec 16 13:03:40.165853 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] Dec 16 13:03:40.165863 kernel: Zone ranges: Dec 16 13:03:40.165871 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Dec 16 13:03:40.165879 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Dec 16 13:03:40.165887 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Dec 16 13:03:40.165895 kernel: Device empty Dec 16 13:03:40.165903 kernel: Movable zone start for each node Dec 16 13:03:40.165911 kernel: Early memory node ranges Dec 16 13:03:40.165919 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Dec 16 13:03:40.165926 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Dec 16 13:03:40.165937 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Dec 16 13:03:40.165945 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Dec 16 13:03:40.165953 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Dec 16 13:03:40.165961 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Dec 16 13:03:40.165968 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Dec 16 13:03:40.165976 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Dec 16 13:03:40.165984 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Dec 16 13:03:40.165992 kernel: ACPI: PM-Timer IO Port: 0x408 Dec 16 13:03:40.166000 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Dec 16 13:03:40.166010 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Dec 16 13:03:40.166018 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Dec 16 13:03:40.166026 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Dec 16 13:03:40.166034 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Dec 16 13:03:40.166042 kernel: CPU topo: Max. logical packages: 1 Dec 16 13:03:40.166050 kernel: CPU topo: Max. logical dies: 1 Dec 16 13:03:40.166058 kernel: CPU topo: Max. dies per package: 1 Dec 16 13:03:40.166066 kernel: CPU topo: Max. threads per core: 1 Dec 16 13:03:40.166074 kernel: CPU topo: Num. cores per package: 6 Dec 16 13:03:40.166084 kernel: CPU topo: Num. threads per package: 6 Dec 16 13:03:40.166092 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs Dec 16 13:03:40.166100 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Dec 16 13:03:40.166108 kernel: Booting paravirtualized kernel on Hyper-V Dec 16 13:03:40.166116 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Dec 16 13:03:40.166124 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Dec 16 13:03:40.166132 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 Dec 16 13:03:40.166140 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 Dec 16 13:03:40.166148 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Dec 16 13:03:40.166158 kernel: Hyper-V: PV spinlocks enabled Dec 16 13:03:40.166166 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Dec 16 13:03:40.166175 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 16 13:03:40.166183 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Dec 16 13:03:40.166192 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Dec 16 13:03:40.166199 kernel: Fallback order for Node 0: 0 Dec 16 13:03:40.166207 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 Dec 16 13:03:40.166215 kernel: Policy zone: Normal Dec 16 13:03:40.166226 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Dec 16 13:03:40.166241 kernel: software IO TLB: area num 8. Dec 16 13:03:40.166250 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Dec 16 13:03:40.166261 kernel: Kernel/User page tables isolation: enabled Dec 16 13:03:40.166269 kernel: ftrace: allocating 40103 entries in 157 pages Dec 16 13:03:40.166277 kernel: ftrace: allocated 157 pages with 5 groups Dec 16 13:03:40.166285 kernel: Dynamic Preempt: voluntary Dec 16 13:03:40.166294 kernel: rcu: Preemptible hierarchical RCU implementation. Dec 16 13:03:40.166303 kernel: rcu: RCU event tracing is enabled. Dec 16 13:03:40.166311 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Dec 16 13:03:40.166320 kernel: Trampoline variant of Tasks RCU enabled. Dec 16 13:03:40.166331 kernel: Rude variant of Tasks RCU enabled. Dec 16 13:03:40.166354 kernel: Tracing variant of Tasks RCU enabled. Dec 16 13:03:40.166363 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Dec 16 13:03:40.166372 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Dec 16 13:03:40.166380 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Dec 16 13:03:40.166389 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Dec 16 13:03:40.166401 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Dec 16 13:03:40.166409 kernel: Using NULL legacy PIC Dec 16 13:03:40.166417 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Dec 16 13:03:40.166426 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Dec 16 13:03:40.166434 kernel: Console: colour dummy device 80x25 Dec 16 13:03:40.166443 kernel: printk: legacy console [tty1] enabled Dec 16 13:03:40.166451 kernel: printk: legacy console [ttyS0] enabled Dec 16 13:03:40.166459 kernel: printk: legacy bootconsole [earlyser0] disabled Dec 16 13:03:40.166468 kernel: ACPI: Core revision 20240827 Dec 16 13:03:40.166478 kernel: Failed to register legacy timer interrupt Dec 16 13:03:40.166486 kernel: APIC: Switch to symmetric I/O mode setup Dec 16 13:03:40.166495 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Dec 16 13:03:40.166503 kernel: Hyper-V: enabling crash_kexec_post_notifiers Dec 16 13:03:40.166512 kernel: Hyper-V: Using IPI hypercalls Dec 16 13:03:40.166520 kernel: APIC: send_IPI() replaced with hv_send_ipi() Dec 16 13:03:40.166528 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Dec 16 13:03:40.166537 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Dec 16 13:03:40.166545 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Dec 16 13:03:40.166556 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Dec 16 13:03:40.166564 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Dec 16 13:03:40.166573 kernel: Hyper-V: Using enlightened APIC (xapic mode) Dec 16 13:03:40.166581 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Dec 16 13:03:40.166589 kernel: APIC: read() replaced with hv_apic_read() Dec 16 13:03:40.166598 kernel: APIC: write() replaced with hv_apic_write() Dec 16 13:03:40.166606 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Dec 16 13:03:40.166615 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Dec 16 13:03:40.166623 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593993) Dec 16 13:03:40.166634 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Dec 16 13:03:40.166642 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Dec 16 13:03:40.166651 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Dec 16 13:03:40.166659 kernel: Spectre V2 : Mitigation: Retpolines Dec 16 13:03:40.166667 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Dec 16 13:03:40.166675 kernel: Speculative Store Bypass: Vulnerable Dec 16 13:03:40.166683 kernel: MDS: Mitigation: Clear CPU buffers Dec 16 13:03:40.166691 kernel: TAA: Mitigation: Clear CPU buffers Dec 16 13:03:40.166700 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Dec 16 13:03:40.166708 kernel: active return thunk: its_return_thunk Dec 16 13:03:40.166716 kernel: ITS: Mitigation: Aligned branch/return thunks Dec 16 13:03:40.166726 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Dec 16 13:03:40.166735 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Dec 16 13:03:40.166743 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Dec 16 13:03:40.166751 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Dec 16 13:03:40.166759 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Dec 16 13:03:40.166768 kernel: Freeing SMP alternatives memory: 32K Dec 16 13:03:40.166776 kernel: pid_max: default: 32768 minimum: 301 Dec 16 13:03:40.166784 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Dec 16 13:03:40.166792 kernel: landlock: Up and running. Dec 16 13:03:40.166800 kernel: SELinux: Initializing. Dec 16 13:03:40.166809 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Dec 16 13:03:40.166819 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Dec 16 13:03:40.166828 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Dec 16 13:03:40.166836 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Dec 16 13:03:40.166844 kernel: signal: max sigframe size: 1776 Dec 16 13:03:40.166853 kernel: rcu: Hierarchical SRCU implementation. Dec 16 13:03:40.166861 kernel: rcu: Max phase no-delay instances is 400. Dec 16 13:03:40.166870 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Dec 16 13:03:40.166878 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Dec 16 13:03:40.166886 kernel: smp: Bringing up secondary CPUs ... Dec 16 13:03:40.166897 kernel: smpboot: x86: Booting SMP configuration: Dec 16 13:03:40.166905 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Dec 16 13:03:40.166914 kernel: smp: Brought up 1 node, 6 CPUs Dec 16 13:03:40.166922 kernel: smpboot: Total of 6 processors activated (31127.91 BogoMIPS) Dec 16 13:03:40.166931 kernel: Memory: 115330900K/117439364K available (14336K kernel code, 2444K rwdata, 26064K rodata, 46188K init, 2572K bss, 2098188K reserved, 0K cma-reserved) Dec 16 13:03:40.166939 kernel: devtmpfs: initialized Dec 16 13:03:40.166948 kernel: x86/mm: Memory block size: 1024MB Dec 16 13:03:40.166956 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Dec 16 13:03:40.166965 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Dec 16 13:03:40.166975 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Dec 16 13:03:40.166984 kernel: pinctrl core: initialized pinctrl subsystem Dec 16 13:03:40.166992 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Dec 16 13:03:40.167000 kernel: audit: initializing netlink subsys (disabled) Dec 16 13:03:40.167009 kernel: audit: type=2000 audit(1765890217.029:1): state=initialized audit_enabled=0 res=1 Dec 16 13:03:40.167017 kernel: thermal_sys: Registered thermal governor 'step_wise' Dec 16 13:03:40.167026 kernel: thermal_sys: Registered thermal governor 'user_space' Dec 16 13:03:40.167034 kernel: cpuidle: using governor menu Dec 16 13:03:40.167042 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Dec 16 13:03:40.167053 kernel: dca service started, version 1.12.1 Dec 16 13:03:40.167061 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Dec 16 13:03:40.167070 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Dec 16 13:03:40.167078 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Dec 16 13:03:40.167118 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Dec 16 13:03:40.167128 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Dec 16 13:03:40.167136 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Dec 16 13:03:40.167145 kernel: ACPI: Added _OSI(Module Device) Dec 16 13:03:40.167153 kernel: ACPI: Added _OSI(Processor Device) Dec 16 13:03:40.167166 kernel: ACPI: Added _OSI(Processor Aggregator Device) Dec 16 13:03:40.167175 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Dec 16 13:03:40.167183 kernel: ACPI: Interpreter enabled Dec 16 13:03:40.167192 kernel: ACPI: PM: (supports S0 S5) Dec 16 13:03:40.167200 kernel: ACPI: Using IOAPIC for interrupt routing Dec 16 13:03:40.167209 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Dec 16 13:03:40.167217 kernel: PCI: Ignoring E820 reservations for host bridge windows Dec 16 13:03:40.167225 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Dec 16 13:03:40.167234 kernel: iommu: Default domain type: Translated Dec 16 13:03:40.167244 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Dec 16 13:03:40.167253 kernel: efivars: Registered efivars operations Dec 16 13:03:40.167261 kernel: PCI: Using ACPI for IRQ routing Dec 16 13:03:40.167269 kernel: PCI: System does not support PCI Dec 16 13:03:40.167278 kernel: vgaarb: loaded Dec 16 13:03:40.167287 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Dec 16 13:03:40.167295 kernel: VFS: Disk quotas dquot_6.6.0 Dec 16 13:03:40.167304 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Dec 16 13:03:40.167312 kernel: pnp: PnP ACPI init Dec 16 13:03:40.167323 kernel: pnp: PnP ACPI: found 3 devices Dec 16 13:03:40.167331 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Dec 16 13:03:40.167352 kernel: NET: Registered PF_INET protocol family Dec 16 13:03:40.167361 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Dec 16 13:03:40.167369 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Dec 16 13:03:40.167378 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Dec 16 13:03:40.167386 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Dec 16 13:03:40.167395 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Dec 16 13:03:40.167403 kernel: TCP: Hash tables configured (established 524288 bind 65536) Dec 16 13:03:40.167415 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Dec 16 13:03:40.167424 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Dec 16 13:03:40.167432 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Dec 16 13:03:40.167440 kernel: NET: Registered PF_XDP protocol family Dec 16 13:03:40.167449 kernel: PCI: CLS 0 bytes, default 64 Dec 16 13:03:40.167457 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Dec 16 13:03:40.167465 kernel: software IO TLB: mapped [mem 0x000000003ae5f000-0x000000003ee5f000] (64MB) Dec 16 13:03:40.167474 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Dec 16 13:03:40.167482 kernel: Initialise system trusted keyrings Dec 16 13:03:40.167493 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Dec 16 13:03:40.167502 kernel: Key type asymmetric registered Dec 16 13:03:40.167510 kernel: Asymmetric key parser 'x509' registered Dec 16 13:03:40.167518 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Dec 16 13:03:40.167526 kernel: io scheduler mq-deadline registered Dec 16 13:03:40.167535 kernel: io scheduler kyber registered Dec 16 13:03:40.167543 kernel: io scheduler bfq registered Dec 16 13:03:40.167551 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Dec 16 13:03:40.167560 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Dec 16 13:03:40.167571 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Dec 16 13:03:40.167579 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Dec 16 13:03:40.167587 kernel: i8042: PNP: No PS/2 controller found. Dec 16 13:03:40.167740 kernel: rtc_cmos 00:02: registered as rtc0 Dec 16 13:03:40.167843 kernel: rtc_cmos 00:02: setting system clock to 2025-12-16T13:03:39 UTC (1765890219) Dec 16 13:03:40.167939 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Dec 16 13:03:40.167952 kernel: intel_pstate: CPU model not supported Dec 16 13:03:40.167965 kernel: efifb: probing for efifb Dec 16 13:03:40.167974 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Dec 16 13:03:40.167982 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Dec 16 13:03:40.167990 kernel: efifb: scrolling: redraw Dec 16 13:03:40.167999 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Dec 16 13:03:40.168007 kernel: Console: switching to colour frame buffer device 128x48 Dec 16 13:03:40.168015 kernel: fb0: EFI VGA frame buffer device Dec 16 13:03:40.168024 kernel: pstore: Using crash dump compression: deflate Dec 16 13:03:40.168032 kernel: pstore: Registered efi_pstore as persistent store backend Dec 16 13:03:40.168043 kernel: NET: Registered PF_INET6 protocol family Dec 16 13:03:40.168052 kernel: Segment Routing with IPv6 Dec 16 13:03:40.168060 kernel: In-situ OAM (IOAM) with IPv6 Dec 16 13:03:40.168068 kernel: NET: Registered PF_PACKET protocol family Dec 16 13:03:40.168077 kernel: Key type dns_resolver registered Dec 16 13:03:40.168085 kernel: IPI shorthand broadcast: enabled Dec 16 13:03:40.168093 kernel: sched_clock: Marking stable (3589006000, 107823600)->(4022560200, -325730600) Dec 16 13:03:40.168102 kernel: registered taskstats version 1 Dec 16 13:03:40.168110 kernel: Loading compiled-in X.509 certificates Dec 16 13:03:40.168118 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.61-flatcar: 0d0c78e6590cb40d27f1cef749ef9f2f3425f38d' Dec 16 13:03:40.168129 kernel: Demotion targets for Node 0: null Dec 16 13:03:40.168138 kernel: Key type .fscrypt registered Dec 16 13:03:40.168146 kernel: Key type fscrypt-provisioning registered Dec 16 13:03:40.168154 kernel: ima: No TPM chip found, activating TPM-bypass! Dec 16 13:03:40.168163 kernel: ima: Allocated hash algorithm: sha1 Dec 16 13:03:40.168171 kernel: ima: No architecture policies found Dec 16 13:03:40.168179 kernel: clk: Disabling unused clocks Dec 16 13:03:40.168188 kernel: Warning: unable to open an initial console. Dec 16 13:03:40.168196 kernel: Freeing unused kernel image (initmem) memory: 46188K Dec 16 13:03:40.168207 kernel: Write protecting the kernel read-only data: 40960k Dec 16 13:03:40.168215 kernel: Freeing unused kernel image (rodata/data gap) memory: 560K Dec 16 13:03:40.168224 kernel: Run /init as init process Dec 16 13:03:40.168232 kernel: with arguments: Dec 16 13:03:40.168240 kernel: /init Dec 16 13:03:40.168248 kernel: with environment: Dec 16 13:03:40.168256 kernel: HOME=/ Dec 16 13:03:40.168264 kernel: TERM=linux Dec 16 13:03:40.168274 systemd[1]: Successfully made /usr/ read-only. Dec 16 13:03:40.168288 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 16 13:03:40.168298 systemd[1]: Detected virtualization microsoft. Dec 16 13:03:40.168306 systemd[1]: Detected architecture x86-64. Dec 16 13:03:40.168315 systemd[1]: Running in initrd. Dec 16 13:03:40.168389 systemd[1]: No hostname configured, using default hostname. Dec 16 13:03:40.168399 systemd[1]: Hostname set to . Dec 16 13:03:40.168408 systemd[1]: Initializing machine ID from random generator. Dec 16 13:03:40.168422 systemd[1]: Queued start job for default target initrd.target. Dec 16 13:03:40.168431 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Dec 16 13:03:40.168440 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Dec 16 13:03:40.168450 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Dec 16 13:03:40.168459 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Dec 16 13:03:40.168468 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Dec 16 13:03:40.168478 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Dec 16 13:03:40.168490 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Dec 16 13:03:40.168500 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Dec 16 13:03:40.168516 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Dec 16 13:03:40.168526 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Dec 16 13:03:40.168535 systemd[1]: Reached target paths.target - Path Units. Dec 16 13:03:40.168544 systemd[1]: Reached target slices.target - Slice Units. Dec 16 13:03:40.168553 systemd[1]: Reached target swap.target - Swaps. Dec 16 13:03:40.168562 systemd[1]: Reached target timers.target - Timer Units. Dec 16 13:03:40.168576 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Dec 16 13:03:40.168585 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Dec 16 13:03:40.168594 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Dec 16 13:03:40.168604 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Dec 16 13:03:40.168613 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Dec 16 13:03:40.168622 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Dec 16 13:03:40.168631 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Dec 16 13:03:40.168640 systemd[1]: Reached target sockets.target - Socket Units. Dec 16 13:03:40.168649 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Dec 16 13:03:40.168660 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Dec 16 13:03:40.168669 systemd[1]: Finished network-cleanup.service - Network Cleanup. Dec 16 13:03:40.168678 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 16 13:03:40.168688 systemd[1]: Starting systemd-fsck-usr.service... Dec 16 13:03:40.168697 systemd[1]: Starting systemd-journald.service - Journal Service... Dec 16 13:03:40.168705 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Dec 16 13:03:40.168714 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Dec 16 13:03:40.168748 systemd-journald[212]: Collecting audit messages is disabled. Dec 16 13:03:40.168774 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Dec 16 13:03:40.168787 systemd-journald[212]: Journal started Dec 16 13:03:40.168807 systemd-journald[212]: Runtime Journal (/run/log/journal/f1da0524096e4e248647b887280360d4) is 8M, max 2.2G, 2.1G free. Dec 16 13:03:40.164413 systemd-modules-load[214]: Inserted module 'overlay' Dec 16 13:03:40.175356 systemd[1]: Started systemd-journald.service - Journal Service. Dec 16 13:03:40.175437 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Dec 16 13:03:40.175943 systemd[1]: Finished systemd-fsck-usr.service. Dec 16 13:03:40.178160 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Dec 16 13:03:40.179214 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Dec 16 13:03:40.221734 systemd-tmpfiles[223]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Dec 16 13:03:40.222648 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Dec 16 13:03:40.225540 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Dec 16 13:03:40.226360 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Dec 16 13:03:40.264900 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Dec 16 13:03:40.273878 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Dec 16 13:03:40.275536 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Dec 16 13:03:40.302363 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 16 13:03:40.306940 systemd-modules-load[214]: Inserted module 'br_netfilter' Dec 16 13:03:40.308401 kernel: Bridge firewalling registered Dec 16 13:03:40.307912 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Dec 16 13:03:40.309236 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Dec 16 13:03:40.317964 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Dec 16 13:03:40.327649 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Dec 16 13:03:40.329947 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Dec 16 13:03:40.341909 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Dec 16 13:03:40.364292 dracut-cmdline[249]: 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 16 13:03:40.380850 systemd-resolved[255]: Positive Trust Anchors: Dec 16 13:03:40.380858 systemd-resolved[255]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Dec 16 13:03:40.380891 systemd-resolved[255]: 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 16 13:03:40.383356 systemd-resolved[255]: Defaulting to hostname 'linux'. Dec 16 13:03:40.384350 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Dec 16 13:03:40.390648 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Dec 16 13:03:40.522363 kernel: SCSI subsystem initialized Dec 16 13:03:40.533356 kernel: Loading iSCSI transport class v2.0-870. Dec 16 13:03:40.545363 kernel: iscsi: registered transport (tcp) Dec 16 13:03:40.568856 kernel: iscsi: registered transport (qla4xxx) Dec 16 13:03:40.568886 kernel: QLogic iSCSI HBA Driver Dec 16 13:03:40.588210 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Dec 16 13:03:40.627075 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Dec 16 13:03:40.629918 systemd[1]: Reached target network-pre.target - Preparation for Network. Dec 16 13:03:40.684938 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Dec 16 13:03:40.690422 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Dec 16 13:03:40.760368 kernel: raid6: avx2x4 gen() 29540 MB/s Dec 16 13:03:40.779357 kernel: raid6: avx2x2 gen() 31215 MB/s Dec 16 13:03:40.800425 kernel: raid6: avx2x1 gen() 26639 MB/s Dec 16 13:03:40.800449 kernel: raid6: using algorithm avx2x2 gen() 31215 MB/s Dec 16 13:03:40.823446 kernel: raid6: .... xor() 18524 MB/s, rmw enabled Dec 16 13:03:40.823489 kernel: raid6: using avx2x2 recovery algorithm Dec 16 13:03:40.846360 kernel: xor: automatically using best checksumming function avx Dec 16 13:03:41.013370 kernel: Btrfs loaded, zoned=no, fsverity=no Dec 16 13:03:41.019962 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Dec 16 13:03:41.025640 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Dec 16 13:03:41.069087 systemd-udevd[464]: Using default interface naming scheme 'v255'. Dec 16 13:03:41.075065 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Dec 16 13:03:41.081094 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Dec 16 13:03:41.118616 dracut-pre-trigger[475]: rd.md=0: removing MD RAID activation Dec 16 13:03:41.146354 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Dec 16 13:03:41.148096 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Dec 16 13:03:41.231182 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Dec 16 13:03:41.238448 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Dec 16 13:03:41.263400 kernel: cryptd: max_cpu_qlen set to 1000 Dec 16 13:03:41.277595 kernel: AES CTR mode by8 optimization enabled Dec 16 13:03:41.280358 kernel: hv_vmbus: Vmbus version:4.0 Dec 16 13:03:41.292293 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Dec 16 13:03:41.307591 kernel: pps_core: LinuxPPS API ver. 1 registered Dec 16 13:03:41.307624 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Dec 16 13:03:41.292380 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Dec 16 13:03:41.308442 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Dec 16 13:03:41.317436 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Dec 16 13:03:41.328761 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Dec 16 13:03:41.335460 kernel: hv_vmbus: registering driver hyperv_keyboard Dec 16 13:03:41.335490 kernel: hid: raw HID events driver (C) Jiri Kosina Dec 16 13:03:41.335503 kernel: hv_vmbus: registering driver hv_netvsc Dec 16 13:03:41.338353 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 16 13:03:41.352831 kernel: PTP clock support registered Dec 16 13:03:41.352863 kernel: hv_vmbus: registering driver hv_pci Dec 16 13:03:41.351885 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Dec 16 13:03:41.362941 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Dec 16 13:03:41.351984 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Dec 16 13:03:41.398493 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Dec 16 13:03:41.398656 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Dec 16 13:03:41.398807 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Dec 16 13:03:41.398927 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Dec 16 13:03:41.399042 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint Dec 16 13:03:41.399076 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] Dec 16 13:03:41.399096 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] Dec 16 13:03:41.399113 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] Dec 16 13:03:41.393015 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Dec 16 13:03:41.414470 kernel: pci 0001:00:00.0: Enabling HDA controller Dec 16 13:03:41.420547 kernel: hv_vmbus: registering driver hv_storvsc Dec 16 13:03:41.421189 kernel: hv_vmbus: registering driver hid_hyperv Dec 16 13:03:41.422362 kernel: hv_utils: Registering HyperV Utility Driver Dec 16 13:03:41.428433 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Dec 16 13:03:41.428597 kernel: hv_vmbus: registering driver hv_utils Dec 16 13:03:41.428611 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned Dec 16 13:03:41.439673 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned Dec 16 13:03:41.444449 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned Dec 16 13:03:41.467928 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Dec 16 13:03:41.874737 kernel: hv_utils: Heartbeat IC version 3.0 Dec 16 13:03:41.874807 kernel: scsi host0: storvsc_host_t Dec 16 13:03:41.874848 kernel: hv_utils: TimeSync IC version 4.0 Dec 16 13:03:41.881280 kernel: hv_utils: Shutdown IC version 3.2 Dec 16 13:03:42.338072 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Dec 16 13:03:42.338143 systemd-resolved[255]: Clock change detected. Flushing caches. Dec 16 13:03:42.372080 kernel: scsi host1: storvsc_host_t Dec 16 13:03:42.372289 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Dec 16 13:03:42.372423 kernel: scsi 1:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Dec 16 13:03:42.372448 kernel: scsi 1:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Dec 16 13:03:42.372466 kernel: scsi 1:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Dec 16 13:03:42.388225 kernel: sd 1:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Dec 16 13:03:42.388498 kernel: sd 1:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Dec 16 13:03:42.388648 kernel: sd 1:0:0:0: [sda] 4096-byte physical blocks Dec 16 13:03:42.388779 kernel: sd 1:0:0:0: [sda] Write Protect is off Dec 16 13:03:42.388905 kernel: sd 1:0:0:1: [sdb] 4096-byte physical blocks Dec 16 13:03:42.389213 kernel: sd 1:0:0:1: [sdb] Write Protect is off Dec 16 13:03:42.390782 kernel: sd 1:0:0:0: [sda] Mode Sense: 0f 00 10 00 Dec 16 13:03:42.391038 kernel: sd 1:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Dec 16 13:03:42.395706 kernel: sd 1:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Dec 16 13:03:42.395939 kernel: sd 1:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Dec 16 13:03:42.396394 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#519 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Dec 16 13:03:42.420620 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#261 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Dec 16 13:03:42.426905 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#526 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Dec 16 13:03:42.427058 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#576 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Dec 16 13:03:42.443365 kernel: sdb: sdb1 Dec 16 13:03:42.443401 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Dec 16 13:03:42.443415 kernel: sd 1:0:0:1: [sdb] Attached SCSI disk Dec 16 13:03:42.445759 kernel: sd 1:0:0:0: [sda] Attached SCSI disk Dec 16 13:03:42.456897 kernel: sr 1:0:0:2: [sr0] scsi-1 drive Dec 16 13:03:42.457082 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Dec 16 13:03:42.459137 kernel: sr 1:0:0:2: Attached scsi CD-ROM sr0 Dec 16 13:03:43.173248 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Dec 16 13:03:43.203713 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Dec 16 13:03:43.215181 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Dec 16 13:03:43.216727 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Dec 16 13:03:43.290777 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Dec 16 13:03:43.372010 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Dec 16 13:03:44.264452 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#578 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Dec 16 13:03:44.277115 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Dec 16 13:03:44.277235 disk-uuid[691]: The operation has completed successfully. Dec 16 13:03:45.703572 systemd[1]: disk-uuid.service: Deactivated successfully. Dec 16 13:03:45.703689 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Dec 16 13:03:45.716884 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Dec 16 13:03:45.726060 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Dec 16 13:03:45.744854 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Dec 16 13:03:45.754553 systemd[1]: Reached target remote-fs.target - Remote File Systems. Dec 16 13:03:45.763072 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Dec 16 13:03:45.771722 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Dec 16 13:03:45.806172 sh[870]: Success Dec 16 13:03:45.816512 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Dec 16 13:03:45.852348 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Dec 16 13:03:45.852403 kernel: device-mapper: uevent: version 1.0.3 Dec 16 13:03:45.857144 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Dec 16 13:03:45.870115 kernel: device-mapper: verity: sha256 using shash "sha256-avx2" Dec 16 13:03:46.311214 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Dec 16 13:03:46.320213 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Dec 16 13:03:46.345903 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Dec 16 13:03:46.369371 kernel: BTRFS: device fsid a6ae7f96-a076-4d3c-81ed-46dd341492f8 devid 1 transid 37 /dev/mapper/usr (254:0) scanned by mount (895) Dec 16 13:03:46.369416 kernel: BTRFS info (device dm-0): first mount of filesystem a6ae7f96-a076-4d3c-81ed-46dd341492f8 Dec 16 13:03:46.373594 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Dec 16 13:03:47.012649 kernel: BTRFS info (device dm-0): disabling log replay at mount time Dec 16 13:03:47.012727 kernel: BTRFS info (device dm-0): enabling free space tree Dec 16 13:03:47.082577 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Dec 16 13:03:47.085250 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Dec 16 13:03:47.090944 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Dec 16 13:03:47.091760 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Dec 16 13:03:47.116700 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Dec 16 13:03:47.148111 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (925) Dec 16 13:03:47.157218 kernel: BTRFS info (device sda6): first mount of filesystem 7e9ead35-f0ec-40e8-bc31-5061934f865a Dec 16 13:03:47.157249 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Dec 16 13:03:47.182520 kernel: BTRFS info (device sda6): turning on async discard Dec 16 13:03:47.182553 kernel: BTRFS info (device sda6): enabling free space tree Dec 16 13:03:47.191111 kernel: BTRFS info (device sda6): last unmount of filesystem 7e9ead35-f0ec-40e8-bc31-5061934f865a Dec 16 13:03:47.191394 systemd[1]: Finished ignition-setup.service - Ignition (setup). Dec 16 13:03:47.195728 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Dec 16 13:03:47.229490 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Dec 16 13:03:47.232440 systemd[1]: Starting systemd-networkd.service - Network Configuration... Dec 16 13:03:47.281187 systemd-networkd[1064]: lo: Link UP Dec 16 13:03:47.281196 systemd-networkd[1064]: lo: Gained carrier Dec 16 13:03:47.282201 systemd-networkd[1064]: Enumeration completed Dec 16 13:03:47.282271 systemd[1]: Started systemd-networkd.service - Network Configuration. Dec 16 13:03:47.282557 systemd-networkd[1064]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Dec 16 13:03:47.282561 systemd-networkd[1064]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Dec 16 13:03:47.283302 systemd-networkd[1064]: eth0: Link UP Dec 16 13:03:47.283498 systemd-networkd[1064]: eth0: Gained carrier Dec 16 13:03:47.283508 systemd-networkd[1064]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Dec 16 13:03:47.288827 systemd[1]: Reached target network.target - Network. Dec 16 13:03:47.353128 systemd-networkd[1064]: eth0: DHCPv4 address 10.200.0.4/24, gateway 10.200.0.1 acquired from 168.63.129.16 Dec 16 13:03:48.907216 systemd-networkd[1064]: eth0: Gained IPv6LL Dec 16 13:03:49.266397 ignition[1035]: Ignition 2.22.0 Dec 16 13:03:49.266411 ignition[1035]: Stage: fetch-offline Dec 16 13:03:49.266535 ignition[1035]: no configs at "/usr/lib/ignition/base.d" Dec 16 13:03:49.266546 ignition[1035]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Dec 16 13:03:49.273286 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Dec 16 13:03:49.266651 ignition[1035]: parsed url from cmdline: "" Dec 16 13:03:49.277725 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Dec 16 13:03:49.266655 ignition[1035]: no config URL provided Dec 16 13:03:49.266661 ignition[1035]: reading system config file "/usr/lib/ignition/user.ign" Dec 16 13:03:49.266670 ignition[1035]: no config at "/usr/lib/ignition/user.ign" Dec 16 13:03:49.266675 ignition[1035]: failed to fetch config: resource requires networking Dec 16 13:03:49.271342 ignition[1035]: Ignition finished successfully Dec 16 13:03:49.324192 ignition[1077]: Ignition 2.22.0 Dec 16 13:03:49.324219 ignition[1077]: Stage: fetch Dec 16 13:03:49.324491 ignition[1077]: no configs at "/usr/lib/ignition/base.d" Dec 16 13:03:49.324503 ignition[1077]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Dec 16 13:03:49.324605 ignition[1077]: parsed url from cmdline: "" Dec 16 13:03:49.324609 ignition[1077]: no config URL provided Dec 16 13:03:49.324616 ignition[1077]: reading system config file "/usr/lib/ignition/user.ign" Dec 16 13:03:49.324628 ignition[1077]: no config at "/usr/lib/ignition/user.ign" Dec 16 13:03:49.324647 ignition[1077]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Dec 16 13:03:49.418830 ignition[1077]: GET result: OK Dec 16 13:03:49.418905 ignition[1077]: config has been read from IMDS userdata Dec 16 13:03:49.418925 ignition[1077]: parsing config with SHA512: eec2d4a383324a010fb1020478ed09a63ccaa7fddff060d140e087a79e565743a2e8bdacbf7c4411d0006d1d5fe5603b67b4cdabcb3b0c2382615f530f8f4db7 Dec 16 13:03:49.423909 unknown[1077]: fetched base config from "system" Dec 16 13:03:49.424121 ignition[1077]: fetch: fetch complete Dec 16 13:03:49.423917 unknown[1077]: fetched base config from "system" Dec 16 13:03:49.424130 ignition[1077]: fetch: fetch passed Dec 16 13:03:49.423927 unknown[1077]: fetched user config from "azure" Dec 16 13:03:49.424174 ignition[1077]: Ignition finished successfully Dec 16 13:03:49.429908 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Dec 16 13:03:49.437551 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Dec 16 13:03:49.497628 ignition[1087]: Ignition 2.22.0 Dec 16 13:03:49.497643 ignition[1087]: Stage: kargs Dec 16 13:03:49.497910 ignition[1087]: no configs at "/usr/lib/ignition/base.d" Dec 16 13:03:49.497922 ignition[1087]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Dec 16 13:03:49.506675 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Dec 16 13:03:49.498592 ignition[1087]: kargs: kargs passed Dec 16 13:03:49.509485 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Dec 16 13:03:49.498642 ignition[1087]: Ignition finished successfully Dec 16 13:03:49.548610 ignition[1096]: Ignition 2.22.0 Dec 16 13:03:49.548623 ignition[1096]: Stage: disks Dec 16 13:03:49.548843 ignition[1096]: no configs at "/usr/lib/ignition/base.d" Dec 16 13:03:49.548854 ignition[1096]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Dec 16 13:03:49.554486 systemd[1]: Finished ignition-disks.service - Ignition (disks). Dec 16 13:03:49.549310 ignition[1096]: disks: disks passed Dec 16 13:03:49.557321 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Dec 16 13:03:49.549352 ignition[1096]: Ignition finished successfully Dec 16 13:03:49.568632 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Dec 16 13:03:49.576724 systemd[1]: Reached target local-fs.target - Local File Systems. Dec 16 13:03:49.582881 systemd[1]: Reached target sysinit.target - System Initialization. Dec 16 13:03:49.590687 systemd[1]: Reached target basic.target - Basic System. Dec 16 13:03:49.597870 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Dec 16 13:03:49.708577 systemd-fsck[1108]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks Dec 16 13:03:49.713783 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Dec 16 13:03:49.716233 systemd[1]: Mounting sysroot.mount - /sysroot... Dec 16 13:03:50.101106 kernel: EXT4-fs (sda9): mounted filesystem e48ca59c-1206-4abd-b121-5e9b35e49852 r/w with ordered data mode. Quota mode: none. Dec 16 13:03:50.101641 systemd[1]: Mounted sysroot.mount - /sysroot. Dec 16 13:03:50.103653 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Dec 16 13:03:50.130870 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Dec 16 13:03:50.133792 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Dec 16 13:03:50.139106 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Dec 16 13:03:50.153058 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Dec 16 13:03:50.153105 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Dec 16 13:03:50.178854 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Dec 16 13:03:50.182265 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Dec 16 13:03:50.197213 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1122) Dec 16 13:03:50.206900 kernel: BTRFS info (device sda6): first mount of filesystem 7e9ead35-f0ec-40e8-bc31-5061934f865a Dec 16 13:03:50.206936 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Dec 16 13:03:50.216298 kernel: BTRFS info (device sda6): turning on async discard Dec 16 13:03:50.216326 kernel: BTRFS info (device sda6): enabling free space tree Dec 16 13:03:50.217975 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Dec 16 13:03:51.115912 coreos-metadata[1124]: Dec 16 13:03:51.115 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Dec 16 13:03:51.123380 coreos-metadata[1124]: Dec 16 13:03:51.123 INFO Fetch successful Dec 16 13:03:51.127729 coreos-metadata[1124]: Dec 16 13:03:51.123 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Dec 16 13:03:51.135949 coreos-metadata[1124]: Dec 16 13:03:51.130 INFO Fetch successful Dec 16 13:03:51.144610 coreos-metadata[1124]: Dec 16 13:03:51.144 INFO wrote hostname ci-4459.2.2-a-4acfd5b2aa to /sysroot/etc/hostname Dec 16 13:03:51.151699 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Dec 16 13:03:51.634614 initrd-setup-root[1154]: cut: /sysroot/etc/passwd: No such file or directory Dec 16 13:03:51.689525 initrd-setup-root[1161]: cut: /sysroot/etc/group: No such file or directory Dec 16 13:03:51.709999 initrd-setup-root[1168]: cut: /sysroot/etc/shadow: No such file or directory Dec 16 13:03:51.716256 initrd-setup-root[1175]: cut: /sysroot/etc/gshadow: No such file or directory Dec 16 13:03:53.166717 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Dec 16 13:03:53.175205 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Dec 16 13:03:53.177392 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Dec 16 13:03:53.223455 systemd[1]: sysroot-oem.mount: Deactivated successfully. Dec 16 13:03:53.231831 kernel: BTRFS info (device sda6): last unmount of filesystem 7e9ead35-f0ec-40e8-bc31-5061934f865a Dec 16 13:03:53.247470 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Dec 16 13:03:53.263670 ignition[1244]: INFO : Ignition 2.22.0 Dec 16 13:03:53.263670 ignition[1244]: INFO : Stage: mount Dec 16 13:03:53.269227 ignition[1244]: INFO : no configs at "/usr/lib/ignition/base.d" Dec 16 13:03:53.269227 ignition[1244]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Dec 16 13:03:53.269227 ignition[1244]: INFO : mount: mount passed Dec 16 13:03:53.269227 ignition[1244]: INFO : Ignition finished successfully Dec 16 13:03:53.268794 systemd[1]: Finished ignition-mount.service - Ignition (mount). Dec 16 13:03:53.281470 systemd[1]: Starting ignition-files.service - Ignition (files)... Dec 16 13:03:53.315929 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Dec 16 13:03:53.338107 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1260) Dec 16 13:03:53.338142 kernel: BTRFS info (device sda6): first mount of filesystem 7e9ead35-f0ec-40e8-bc31-5061934f865a Dec 16 13:03:53.346503 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Dec 16 13:03:53.356864 kernel: BTRFS info (device sda6): turning on async discard Dec 16 13:03:53.356892 kernel: BTRFS info (device sda6): enabling free space tree Dec 16 13:03:53.359006 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Dec 16 13:03:53.405235 ignition[1277]: INFO : Ignition 2.22.0 Dec 16 13:03:53.405235 ignition[1277]: INFO : Stage: files Dec 16 13:03:53.411033 ignition[1277]: INFO : no configs at "/usr/lib/ignition/base.d" Dec 16 13:03:53.411033 ignition[1277]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Dec 16 13:03:53.411033 ignition[1277]: DEBUG : files: compiled without relabeling support, skipping Dec 16 13:03:53.411033 ignition[1277]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Dec 16 13:03:53.411033 ignition[1277]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Dec 16 13:03:53.653663 ignition[1277]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Dec 16 13:03:53.659104 ignition[1277]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Dec 16 13:03:53.664200 ignition[1277]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Dec 16 13:03:53.659585 unknown[1277]: wrote ssh authorized keys file for user: core Dec 16 13:03:53.703950 ignition[1277]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Dec 16 13:03:53.711031 ignition[1277]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Dec 16 13:03:53.711031 ignition[1277]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Dec 16 13:03:53.711031 ignition[1277]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Dec 16 13:03:53.711031 ignition[1277]: INFO : files: files passed Dec 16 13:03:53.711031 ignition[1277]: INFO : Ignition finished successfully Dec 16 13:03:53.706242 systemd[1]: Finished ignition-files.service - Ignition (files). Dec 16 13:03:53.713424 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Dec 16 13:03:53.750269 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Dec 16 13:03:53.753302 systemd[1]: ignition-quench.service: Deactivated successfully. Dec 16 13:03:53.753394 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Dec 16 13:03:53.787304 initrd-setup-root-after-ignition[1310]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Dec 16 13:03:53.787304 initrd-setup-root-after-ignition[1310]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Dec 16 13:03:53.799642 initrd-setup-root-after-ignition[1314]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Dec 16 13:03:53.806784 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Dec 16 13:03:53.808436 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Dec 16 13:03:53.818714 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Dec 16 13:03:53.859724 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Dec 16 13:03:53.859831 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Dec 16 13:03:53.868252 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Dec 16 13:03:53.870155 systemd[1]: Reached target initrd.target - Initrd Default Target. Dec 16 13:03:53.881867 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Dec 16 13:03:53.882630 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Dec 16 13:03:53.926229 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Dec 16 13:03:53.928137 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Dec 16 13:03:53.945001 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Dec 16 13:03:53.954146 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Dec 16 13:03:53.955003 systemd[1]: Stopped target timers.target - Timer Units. Dec 16 13:03:53.966696 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Dec 16 13:03:53.967060 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Dec 16 13:03:53.978153 systemd[1]: Stopped target initrd.target - Initrd Default Target. Dec 16 13:03:53.986390 systemd[1]: Stopped target basic.target - Basic System. Dec 16 13:03:53.993643 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Dec 16 13:03:53.995653 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Dec 16 13:03:54.006455 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Dec 16 13:03:54.015606 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Dec 16 13:03:54.024052 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Dec 16 13:03:54.032457 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Dec 16 13:03:54.043548 systemd[1]: Stopped target sysinit.target - System Initialization. Dec 16 13:03:54.053469 systemd[1]: Stopped target local-fs.target - Local File Systems. Dec 16 13:03:54.061055 systemd[1]: Stopped target swap.target - Swaps. Dec 16 13:03:54.063283 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Dec 16 13:03:54.063428 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Dec 16 13:03:54.075176 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Dec 16 13:03:54.081231 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Dec 16 13:03:54.097925 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Dec 16 13:03:54.098140 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Dec 16 13:03:54.103543 systemd[1]: dracut-initqueue.service: Deactivated successfully. Dec 16 13:03:54.103678 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Dec 16 13:03:54.113458 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Dec 16 13:03:54.113583 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Dec 16 13:03:54.125845 systemd[1]: ignition-files.service: Deactivated successfully. Dec 16 13:03:54.125966 systemd[1]: Stopped ignition-files.service - Ignition (files). Dec 16 13:03:54.134423 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Dec 16 13:03:54.134532 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Dec 16 13:03:54.189042 ignition[1334]: INFO : Ignition 2.22.0 Dec 16 13:03:54.189042 ignition[1334]: INFO : Stage: umount Dec 16 13:03:54.189042 ignition[1334]: INFO : no configs at "/usr/lib/ignition/base.d" Dec 16 13:03:54.189042 ignition[1334]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Dec 16 13:03:54.189042 ignition[1334]: INFO : umount: umount passed Dec 16 13:03:54.189042 ignition[1334]: INFO : Ignition finished successfully Dec 16 13:03:54.142895 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Dec 16 13:03:54.151260 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Dec 16 13:03:54.151383 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Dec 16 13:03:54.170664 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Dec 16 13:03:54.172929 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Dec 16 13:03:54.173052 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Dec 16 13:03:54.189169 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Dec 16 13:03:54.189294 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Dec 16 13:03:54.200582 systemd[1]: ignition-mount.service: Deactivated successfully. Dec 16 13:03:54.200679 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Dec 16 13:03:54.205071 systemd[1]: ignition-disks.service: Deactivated successfully. Dec 16 13:03:54.205192 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Dec 16 13:03:54.212403 systemd[1]: ignition-kargs.service: Deactivated successfully. Dec 16 13:03:54.212451 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Dec 16 13:03:54.220229 systemd[1]: ignition-fetch.service: Deactivated successfully. Dec 16 13:03:54.220279 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Dec 16 13:03:54.229620 systemd[1]: Stopped target network.target - Network. Dec 16 13:03:54.236521 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Dec 16 13:03:54.236577 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Dec 16 13:03:54.244630 systemd[1]: Stopped target paths.target - Path Units. Dec 16 13:03:54.252652 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Dec 16 13:03:54.256164 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Dec 16 13:03:54.326644 systemd[1]: Stopped target slices.target - Slice Units. Dec 16 13:03:54.328117 systemd[1]: Stopped target sockets.target - Socket Units. Dec 16 13:03:54.335036 systemd[1]: iscsid.socket: Deactivated successfully. Dec 16 13:03:54.335099 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Dec 16 13:03:54.343319 systemd[1]: iscsiuio.socket: Deactivated successfully. Dec 16 13:03:54.343366 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Dec 16 13:03:54.351082 systemd[1]: ignition-setup.service: Deactivated successfully. Dec 16 13:03:54.351169 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Dec 16 13:03:54.357967 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Dec 16 13:03:54.358014 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Dec 16 13:03:54.365198 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Dec 16 13:03:54.372102 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Dec 16 13:03:54.387327 systemd[1]: sysroot-boot.mount: Deactivated successfully. Dec 16 13:03:54.388112 systemd[1]: systemd-resolved.service: Deactivated successfully. Dec 16 13:03:54.388218 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Dec 16 13:03:54.399195 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Dec 16 13:03:54.399519 systemd[1]: systemd-networkd.service: Deactivated successfully. Dec 16 13:03:54.399616 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Dec 16 13:03:54.411910 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Dec 16 13:03:54.412184 systemd[1]: initrd-cleanup.service: Deactivated successfully. Dec 16 13:03:54.412276 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Dec 16 13:03:54.419374 systemd[1]: sysroot-boot.service: Deactivated successfully. Dec 16 13:03:54.419465 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Dec 16 13:03:54.429474 systemd[1]: Stopped target network-pre.target - Preparation for Network. Dec 16 13:03:54.431058 systemd[1]: systemd-networkd.socket: Deactivated successfully. Dec 16 13:03:54.431138 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Dec 16 13:03:54.443457 systemd[1]: initrd-setup-root.service: Deactivated successfully. Dec 16 13:03:54.443521 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Dec 16 13:03:54.452000 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Dec 16 13:03:54.458562 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Dec 16 13:03:54.458612 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Dec 16 13:03:54.468216 systemd[1]: systemd-sysctl.service: Deactivated successfully. Dec 16 13:03:54.468262 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Dec 16 13:03:54.476546 systemd[1]: systemd-modules-load.service: Deactivated successfully. Dec 16 13:03:54.476590 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Dec 16 13:03:54.484601 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Dec 16 13:03:54.484649 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Dec 16 13:03:54.493641 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Dec 16 13:03:54.501526 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Dec 16 13:03:54.501592 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Dec 16 13:03:54.532810 systemd[1]: systemd-udevd.service: Deactivated successfully. Dec 16 13:03:54.533016 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Dec 16 13:03:54.535519 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Dec 16 13:03:54.535567 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Dec 16 13:03:54.543509 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Dec 16 13:03:54.543544 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Dec 16 13:03:54.551418 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Dec 16 13:03:54.551468 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Dec 16 13:03:54.567881 systemd[1]: dracut-cmdline.service: Deactivated successfully. Dec 16 13:03:54.567929 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Dec 16 13:03:54.583162 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Dec 16 13:03:54.583213 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Dec 16 13:03:54.598239 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Dec 16 13:03:54.605101 systemd[1]: systemd-network-generator.service: Deactivated successfully. Dec 16 13:03:54.605159 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Dec 16 13:03:54.615443 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Dec 16 13:03:54.615489 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Dec 16 13:03:54.624278 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Dec 16 13:03:54.624324 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Dec 16 13:03:54.633528 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Dec 16 13:03:54.633585 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Dec 16 13:03:54.633627 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Dec 16 13:03:54.633974 systemd[1]: network-cleanup.service: Deactivated successfully. Dec 16 13:03:54.634065 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Dec 16 13:03:54.637196 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Dec 16 13:03:54.637280 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Dec 16 13:03:54.644286 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Dec 16 13:03:54.657314 systemd[1]: Starting initrd-switch-root.service - Switch Root... Dec 16 13:03:54.700511 systemd[1]: Switching root. Dec 16 13:03:54.851665 systemd-journald[212]: Journal stopped Dec 16 13:04:03.695735 systemd-journald[212]: Received SIGTERM from PID 1 (systemd). Dec 16 13:04:03.695815 kernel: SELinux: policy capability network_peer_controls=1 Dec 16 13:04:03.695859 kernel: SELinux: policy capability open_perms=1 Dec 16 13:04:03.695900 kernel: SELinux: policy capability extended_socket_class=1 Dec 16 13:04:03.695940 kernel: SELinux: policy capability always_check_network=0 Dec 16 13:04:03.695951 kernel: SELinux: policy capability cgroup_seclabel=1 Dec 16 13:04:03.695986 kernel: SELinux: policy capability nnp_nosuid_transition=1 Dec 16 13:04:03.696011 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Dec 16 13:04:03.696021 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Dec 16 13:04:03.696031 kernel: SELinux: policy capability userspace_initial_context=0 Dec 16 13:04:03.696041 kernel: audit: type=1403 audit(1765890236.721:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Dec 16 13:04:03.696052 systemd[1]: Successfully loaded SELinux policy in 399.879ms. Dec 16 13:04:03.696064 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 8.240ms. Dec 16 13:04:03.696075 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 16 13:04:03.696102 systemd[1]: Detected virtualization microsoft. Dec 16 13:04:03.696114 systemd[1]: Detected architecture x86-64. Dec 16 13:04:03.696125 systemd[1]: Detected first boot. Dec 16 13:04:03.696136 systemd[1]: Hostname set to . Dec 16 13:04:03.696146 systemd[1]: Initializing machine ID from random generator. Dec 16 13:04:03.696160 zram_generator::config[1380]: No configuration found. Dec 16 13:04:03.696171 kernel: Guest personality initialized and is inactive Dec 16 13:04:03.696181 kernel: VMCI host device registered (name=vmci, major=10, minor=259) Dec 16 13:04:03.696191 kernel: Initialized host personality Dec 16 13:04:03.696201 kernel: NET: Registered PF_VSOCK protocol family Dec 16 13:04:03.696211 systemd[1]: Populated /etc with preset unit settings. Dec 16 13:04:03.696222 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Dec 16 13:04:03.696236 systemd[1]: initrd-switch-root.service: Deactivated successfully. Dec 16 13:04:03.696250 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Dec 16 13:04:03.696261 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Dec 16 13:04:03.696272 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Dec 16 13:04:03.696283 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Dec 16 13:04:03.696294 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Dec 16 13:04:03.696304 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Dec 16 13:04:03.696318 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Dec 16 13:04:03.696329 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Dec 16 13:04:03.696340 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Dec 16 13:04:03.696351 systemd[1]: Created slice user.slice - User and Session Slice. Dec 16 13:04:03.696362 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Dec 16 13:04:03.696373 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Dec 16 13:04:03.696384 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Dec 16 13:04:03.696398 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Dec 16 13:04:03.696410 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Dec 16 13:04:03.696423 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Dec 16 13:04:03.696435 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Dec 16 13:04:03.696446 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Dec 16 13:04:03.696457 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Dec 16 13:04:03.696468 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Dec 16 13:04:03.696478 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Dec 16 13:04:03.696493 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Dec 16 13:04:03.696507 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Dec 16 13:04:03.696521 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Dec 16 13:04:03.696533 systemd[1]: Reached target remote-fs.target - Remote File Systems. Dec 16 13:04:03.696544 systemd[1]: Reached target slices.target - Slice Units. Dec 16 13:04:03.696555 systemd[1]: Reached target swap.target - Swaps. Dec 16 13:04:03.696566 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Dec 16 13:04:03.696577 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Dec 16 13:04:03.696591 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Dec 16 13:04:03.696603 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Dec 16 13:04:03.696614 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Dec 16 13:04:03.696625 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Dec 16 13:04:03.696636 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Dec 16 13:04:03.696647 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Dec 16 13:04:03.696661 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Dec 16 13:04:03.696673 systemd[1]: Mounting media.mount - External Media Directory... Dec 16 13:04:03.696684 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Dec 16 13:04:03.696695 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Dec 16 13:04:03.696707 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Dec 16 13:04:03.696718 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Dec 16 13:04:03.696757 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 16 13:04:03.696825 systemd[1]: Reached target machines.target - Containers. Dec 16 13:04:03.696893 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Dec 16 13:04:03.696937 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Dec 16 13:04:03.696982 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Dec 16 13:04:03.697044 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Dec 16 13:04:03.697119 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Dec 16 13:04:03.697164 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Dec 16 13:04:03.697207 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Dec 16 13:04:03.697271 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Dec 16 13:04:03.697320 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Dec 16 13:04:03.697339 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Dec 16 13:04:03.697354 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Dec 16 13:04:03.697366 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Dec 16 13:04:03.697377 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Dec 16 13:04:03.697389 systemd[1]: Stopped systemd-fsck-usr.service. Dec 16 13:04:03.697429 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 16 13:04:03.697492 systemd[1]: Starting systemd-journald.service - Journal Service... Dec 16 13:04:03.697535 kernel: loop: module loaded Dec 16 13:04:03.697585 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Dec 16 13:04:03.697647 kernel: fuse: init (API version 7.41) Dec 16 13:04:03.697690 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Dec 16 13:04:03.697739 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Dec 16 13:04:03.697854 systemd-journald[1480]: Collecting audit messages is disabled. Dec 16 13:04:03.697932 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Dec 16 13:04:03.697974 systemd-journald[1480]: Journal started Dec 16 13:04:03.698025 systemd-journald[1480]: Runtime Journal (/run/log/journal/0474cb54a3084268859bb9a59e991131) is 8M, max 2.2G, 2.1G free. Dec 16 13:04:02.952237 systemd[1]: Queued start job for default target multi-user.target. Dec 16 13:04:02.979477 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Dec 16 13:04:02.980008 systemd[1]: systemd-journald.service: Deactivated successfully. Dec 16 13:04:03.709595 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Dec 16 13:04:03.719260 systemd[1]: verity-setup.service: Deactivated successfully. Dec 16 13:04:03.719299 systemd[1]: Stopped verity-setup.service. Dec 16 13:04:03.733115 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Dec 16 13:04:03.739790 systemd[1]: Started systemd-journald.service - Journal Service. Dec 16 13:04:03.744986 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Dec 16 13:04:03.749189 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Dec 16 13:04:03.753664 systemd[1]: Mounted media.mount - External Media Directory. Dec 16 13:04:03.758101 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Dec 16 13:04:03.762421 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Dec 16 13:04:03.766838 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Dec 16 13:04:03.770853 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Dec 16 13:04:03.775905 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Dec 16 13:04:03.780876 systemd[1]: modprobe@configfs.service: Deactivated successfully. Dec 16 13:04:03.781064 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Dec 16 13:04:03.785938 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Dec 16 13:04:03.786132 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Dec 16 13:04:03.790857 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Dec 16 13:04:03.791038 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Dec 16 13:04:03.795963 systemd[1]: modprobe@fuse.service: Deactivated successfully. Dec 16 13:04:03.796154 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Dec 16 13:04:03.800639 systemd[1]: modprobe@loop.service: Deactivated successfully. Dec 16 13:04:03.800821 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Dec 16 13:04:03.805328 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Dec 16 13:04:03.809658 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Dec 16 13:04:03.814763 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Dec 16 13:04:03.819794 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Dec 16 13:04:03.834673 systemd[1]: Reached target network-pre.target - Preparation for Network. Dec 16 13:04:03.840949 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Dec 16 13:04:03.846253 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Dec 16 13:04:03.847762 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Dec 16 13:04:03.847987 systemd[1]: Reached target local-fs.target - Local File Systems. Dec 16 13:04:03.855490 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Dec 16 13:04:03.869972 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Dec 16 13:04:03.874233 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Dec 16 13:04:03.875782 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Dec 16 13:04:03.881204 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Dec 16 13:04:03.885753 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Dec 16 13:04:03.886811 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Dec 16 13:04:03.890886 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Dec 16 13:04:03.891905 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Dec 16 13:04:03.896989 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Dec 16 13:04:03.902757 systemd[1]: Starting systemd-sysusers.service - Create System Users... Dec 16 13:04:03.906099 kernel: ACPI: bus type drm_connector registered Dec 16 13:04:03.912882 systemd[1]: modprobe@drm.service: Deactivated successfully. Dec 16 13:04:03.913101 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Dec 16 13:04:03.917612 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Dec 16 13:04:03.922521 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Dec 16 13:04:03.927286 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Dec 16 13:04:03.941426 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Dec 16 13:04:03.946077 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Dec 16 13:04:03.952184 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Dec 16 13:04:03.954363 systemd-journald[1480]: Time spent on flushing to /var/log/journal/0474cb54a3084268859bb9a59e991131 is 13.970ms for 952 entries. Dec 16 13:04:03.954363 systemd-journald[1480]: System Journal (/var/log/journal/0474cb54a3084268859bb9a59e991131) is 8M, max 2.6G, 2.6G free. Dec 16 13:04:04.007368 systemd-journald[1480]: Received client request to flush runtime journal. Dec 16 13:04:04.007510 kernel: loop0: detected capacity change from 0 to 110984 Dec 16 13:04:04.008783 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Dec 16 13:04:04.035407 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Dec 16 13:04:04.036112 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Dec 16 13:04:04.068931 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Dec 16 13:04:04.121078 systemd[1]: Finished systemd-sysusers.service - Create System Users. Dec 16 13:04:04.126697 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Dec 16 13:04:04.360134 systemd-tmpfiles[1538]: ACLs are not supported, ignoring. Dec 16 13:04:04.360157 systemd-tmpfiles[1538]: ACLs are not supported, ignoring. Dec 16 13:04:04.364721 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Dec 16 13:04:04.589115 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Dec 16 13:04:04.641114 kernel: loop1: detected capacity change from 0 to 27936 Dec 16 13:04:04.812550 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Dec 16 13:04:04.818921 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Dec 16 13:04:04.867261 systemd-udevd[1544]: Using default interface naming scheme 'v255'. Dec 16 13:04:05.099117 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Dec 16 13:04:05.107318 systemd[1]: Starting systemd-networkd.service - Network Configuration... Dec 16 13:04:05.153822 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Dec 16 13:04:05.178116 kernel: hv_vmbus: registering driver hyperv_fb Dec 16 13:04:05.182118 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Dec 16 13:04:05.182175 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Dec 16 13:04:05.190285 kernel: Console: switching to colour dummy device 80x25 Dec 16 13:04:05.195656 kernel: Console: switching to colour frame buffer device 128x48 Dec 16 13:04:05.206354 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Dec 16 13:04:05.219898 kernel: mousedev: PS/2 mouse device common for all mice Dec 16 13:04:05.219952 kernel: hv_vmbus: registering driver hv_balloon Dec 16 13:04:05.230114 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Dec 16 13:04:05.321284 systemd[1]: Started systemd-userdbd.service - User Database Manager. Dec 16 13:04:05.331859 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Dec 16 13:04:05.345591 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Dec 16 13:04:05.345858 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Dec 16 13:04:05.351320 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Dec 16 13:04:05.362128 kernel: EDAC MC: Ver: 3.0.0 Dec 16 13:04:05.418111 kernel: loop2: detected capacity change from 0 to 128560 Dec 16 13:04:05.481888 systemd-networkd[1566]: lo: Link UP Dec 16 13:04:05.481899 systemd-networkd[1566]: lo: Gained carrier Dec 16 13:04:05.483259 systemd-networkd[1566]: Enumeration completed Dec 16 13:04:05.483371 systemd[1]: Started systemd-networkd.service - Network Configuration. Dec 16 13:04:05.484255 systemd-networkd[1566]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Dec 16 13:04:05.484297 systemd-networkd[1566]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Dec 16 13:04:05.486337 systemd-networkd[1566]: eth0: Link UP Dec 16 13:04:05.486406 systemd-networkd[1566]: eth0: Gained carrier Dec 16 13:04:05.486486 systemd-networkd[1566]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Dec 16 13:04:05.488034 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Dec 16 13:04:05.490428 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Dec 16 13:04:05.535137 systemd-networkd[1566]: eth0: DHCPv4 address 10.200.0.4/24, gateway 10.200.0.1 acquired from 168.63.129.16 Dec 16 13:04:05.558284 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Dec 16 13:04:05.813274 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Dec 16 13:04:05.815888 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Dec 16 13:04:05.875487 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Dec 16 13:04:06.360601 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Dec 16 13:04:06.686115 kernel: loop3: detected capacity change from 0 to 110984 Dec 16 13:04:06.704120 kernel: loop4: detected capacity change from 0 to 27936 Dec 16 13:04:06.750120 kernel: loop5: detected capacity change from 0 to 128560 Dec 16 13:04:07.188991 (sd-merge)[1703]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Dec 16 13:04:07.189494 (sd-merge)[1703]: Merged extensions into '/usr'. Dec 16 13:04:07.200755 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Dec 16 13:04:07.209252 systemd[1]: Starting ensure-sysext.service... Dec 16 13:04:07.212818 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Dec 16 13:04:07.246818 systemd[1]: Reload requested from client PID 1705 ('systemctl') (unit ensure-sysext.service)... Dec 16 13:04:07.246836 systemd[1]: Reloading... Dec 16 13:04:07.275197 systemd-networkd[1566]: eth0: Gained IPv6LL Dec 16 13:04:07.292590 systemd-tmpfiles[1706]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Dec 16 13:04:07.292634 systemd-tmpfiles[1706]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Dec 16 13:04:07.293019 systemd-tmpfiles[1706]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Dec 16 13:04:07.293343 systemd-tmpfiles[1706]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Dec 16 13:04:07.294212 systemd-tmpfiles[1706]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Dec 16 13:04:07.294512 systemd-tmpfiles[1706]: ACLs are not supported, ignoring. Dec 16 13:04:07.294570 systemd-tmpfiles[1706]: ACLs are not supported, ignoring. Dec 16 13:04:07.295115 zram_generator::config[1736]: No configuration found. Dec 16 13:04:07.450776 systemd-tmpfiles[1706]: Detected autofs mount point /boot during canonicalization of boot. Dec 16 13:04:07.450793 systemd-tmpfiles[1706]: Skipping /boot Dec 16 13:04:07.459365 systemd-tmpfiles[1706]: Detected autofs mount point /boot during canonicalization of boot. Dec 16 13:04:07.459380 systemd-tmpfiles[1706]: Skipping /boot Dec 16 13:04:07.736991 systemd[1]: Reloading finished in 489 ms. Dec 16 13:04:07.772935 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Dec 16 13:04:07.810215 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Dec 16 13:04:07.822682 systemd[1]: Starting audit-rules.service - Load Audit Rules... Dec 16 13:04:07.828062 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Dec 16 13:04:07.834063 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Dec 16 13:04:07.843587 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Dec 16 13:04:07.848947 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Dec 16 13:04:07.857956 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Dec 16 13:04:07.858191 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Dec 16 13:04:07.859488 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Dec 16 13:04:07.866671 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Dec 16 13:04:07.872290 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Dec 16 13:04:07.876451 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Dec 16 13:04:07.876601 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 16 13:04:07.876711 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Dec 16 13:04:07.879847 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Dec 16 13:04:07.880031 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Dec 16 13:04:07.884983 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Dec 16 13:04:07.885179 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Dec 16 13:04:07.890524 systemd[1]: modprobe@loop.service: Deactivated successfully. Dec 16 13:04:07.890719 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Dec 16 13:04:07.903668 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Dec 16 13:04:07.903957 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Dec 16 13:04:07.905357 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Dec 16 13:04:07.928881 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Dec 16 13:04:07.934206 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Dec 16 13:04:07.939856 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Dec 16 13:04:07.944917 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Dec 16 13:04:07.945061 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 16 13:04:07.945302 systemd[1]: Reached target time-set.target - System Time Set. Dec 16 13:04:07.949636 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Dec 16 13:04:07.951657 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Dec 16 13:04:07.956891 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Dec 16 13:04:07.957083 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Dec 16 13:04:07.961994 systemd[1]: modprobe@drm.service: Deactivated successfully. Dec 16 13:04:07.962196 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Dec 16 13:04:07.966920 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Dec 16 13:04:07.967119 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Dec 16 13:04:07.972384 systemd[1]: modprobe@loop.service: Deactivated successfully. Dec 16 13:04:07.972573 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Dec 16 13:04:07.979364 systemd[1]: Finished ensure-sysext.service. Dec 16 13:04:07.987118 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Dec 16 13:04:07.987179 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Dec 16 13:04:08.104712 systemd-resolved[1801]: Positive Trust Anchors: Dec 16 13:04:08.104727 systemd-resolved[1801]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Dec 16 13:04:08.104759 systemd-resolved[1801]: 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 16 13:04:08.180504 systemd-resolved[1801]: Using system hostname 'ci-4459.2.2-a-4acfd5b2aa'. Dec 16 13:04:08.182195 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Dec 16 13:04:08.186570 systemd[1]: Reached target network.target - Network. Dec 16 13:04:08.190132 systemd[1]: Reached target network-online.target - Network is Online. Dec 16 13:04:08.194427 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Dec 16 13:04:08.661451 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Dec 16 13:04:08.803522 augenrules[1843]: No rules Dec 16 13:04:08.804832 systemd[1]: audit-rules.service: Deactivated successfully. Dec 16 13:04:08.805081 systemd[1]: Finished audit-rules.service - Load Audit Rules. Dec 16 13:04:12.152739 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Dec 16 13:04:12.158391 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 16 13:04:18.905939 ldconfig[1515]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Dec 16 13:04:18.916976 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Dec 16 13:04:18.924152 systemd[1]: Starting systemd-update-done.service - Update is Completed... Dec 16 13:04:18.958786 systemd[1]: Finished systemd-update-done.service - Update is Completed. Dec 16 13:04:18.963467 systemd[1]: Reached target sysinit.target - System Initialization. Dec 16 13:04:18.967618 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Dec 16 13:04:18.972538 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 16 13:04:18.977569 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Dec 16 13:04:18.982320 systemd[1]: Started logrotate.timer - Daily rotation of log files. Dec 16 13:04:18.986683 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Dec 16 13:04:18.991612 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Dec 16 13:04:18.996519 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Dec 16 13:04:18.996586 systemd[1]: Reached target paths.target - Path Units. Dec 16 13:04:19.000103 systemd[1]: Reached target timers.target - Timer Units. Dec 16 13:04:19.136630 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Dec 16 13:04:19.143446 systemd[1]: Starting docker.socket - Docker Socket for the API... Dec 16 13:04:19.149539 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Dec 16 13:04:19.154759 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Dec 16 13:04:19.159718 systemd[1]: Reached target ssh-access.target - SSH Access Available. Dec 16 13:04:19.165998 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Dec 16 13:04:19.171031 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Dec 16 13:04:19.176525 systemd[1]: Listening on docker.socket - Docker Socket for the API. Dec 16 13:04:19.181763 systemd[1]: Reached target sockets.target - Socket Units. Dec 16 13:04:19.185687 systemd[1]: Reached target basic.target - Basic System. Dec 16 13:04:19.189466 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Dec 16 13:04:19.189513 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Dec 16 13:04:19.192384 systemd[1]: Starting chronyd.service - NTP client/server... Dec 16 13:04:19.196840 systemd[1]: Starting containerd.service - containerd container runtime... Dec 16 13:04:19.223148 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Dec 16 13:04:19.232122 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Dec 16 13:04:19.238963 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Dec 16 13:04:19.244331 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Dec 16 13:04:19.246789 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Dec 16 13:04:19.252369 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 16 13:04:19.262564 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Dec 16 13:04:19.266595 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 16 13:04:19.267076 jq[1863]: false Dec 16 13:04:19.267922 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Dec 16 13:04:19.272003 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Dec 16 13:04:19.273932 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Dec 16 13:04:19.277998 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Dec 16 13:04:19.282688 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Dec 16 13:04:19.288247 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Dec 16 13:04:19.293206 extend-filesystems[1864]: Found /dev/sda6 Dec 16 13:04:19.295588 systemd[1]: Starting systemd-logind.service - User Login Management... Dec 16 13:04:19.299967 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Dec 16 13:04:19.300482 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Dec 16 13:04:19.301084 systemd[1]: Starting update-engine.service - Update Engine... Dec 16 13:04:19.306665 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Dec 16 13:04:19.313793 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Dec 16 13:04:19.314566 jq[1877]: true Dec 16 13:04:19.319153 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Dec 16 13:04:19.319378 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Dec 16 13:04:19.319473 KVP[1869]: KVP starting; pid is:1869 Dec 16 13:04:19.319762 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Dec 16 13:04:19.319958 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Dec 16 13:04:19.323407 KVP[1869]: KVP LIC Version: 3.1 Dec 16 13:04:19.324111 kernel: hv_utils: KVP IC version 4.0 Dec 16 13:04:19.326015 chronyd[1855]: chronyd version 4.7 starting (+CMDMON +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +NTS +SECHASH +IPV6 -DEBUG) Dec 16 13:04:19.326389 extend-filesystems[1864]: Found /dev/sda9 Dec 16 13:04:19.330866 extend-filesystems[1864]: Checking size of /dev/sda9 Dec 16 13:04:19.334159 google_oslogin_nss_cache[1865]: oslogin_cache_refresh[1865]: Refreshing passwd entry cache Dec 16 13:04:19.332102 oslogin_cache_refresh[1865]: Refreshing passwd entry cache Dec 16 13:04:19.338081 jq[1882]: true Dec 16 13:04:19.343168 google_oslogin_nss_cache[1865]: oslogin_cache_refresh[1865]: Failure getting users, quitting Dec 16 13:04:19.343168 google_oslogin_nss_cache[1865]: oslogin_cache_refresh[1865]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Dec 16 13:04:19.343168 google_oslogin_nss_cache[1865]: oslogin_cache_refresh[1865]: Refreshing group entry cache Dec 16 13:04:19.342697 oslogin_cache_refresh[1865]: Failure getting users, quitting Dec 16 13:04:19.342717 oslogin_cache_refresh[1865]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Dec 16 13:04:19.342765 oslogin_cache_refresh[1865]: Refreshing group entry cache Dec 16 13:04:19.349176 google_oslogin_nss_cache[1865]: oslogin_cache_refresh[1865]: Failure getting groups, quitting Dec 16 13:04:19.349176 google_oslogin_nss_cache[1865]: oslogin_cache_refresh[1865]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Dec 16 13:04:19.349163 oslogin_cache_refresh[1865]: Failure getting groups, quitting Dec 16 13:04:19.349175 oslogin_cache_refresh[1865]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Dec 16 13:04:19.351363 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Dec 16 13:04:19.351608 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Dec 16 13:04:19.364718 extend-filesystems[1864]: Old size kept for /dev/sda9 Dec 16 13:04:19.365786 systemd[1]: extend-filesystems.service: Deactivated successfully. Dec 16 13:04:19.366044 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Dec 16 13:04:19.379407 systemd[1]: motdgen.service: Deactivated successfully. Dec 16 13:04:19.379650 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Dec 16 13:04:19.393898 (ntainerd)[1917]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Dec 16 13:04:19.505529 chronyd[1855]: Timezone right/UTC failed leap second check, ignoring Dec 16 13:04:19.505758 chronyd[1855]: Loaded seccomp filter (level 2) Dec 16 13:04:19.505904 systemd[1]: Started chronyd.service - NTP client/server. Dec 16 13:04:19.523576 update_engine[1876]: I20251216 13:04:19.523483 1876 main.cc:92] Flatcar Update Engine starting Dec 16 13:04:19.872031 systemd-logind[1875]: New seat seat0. Dec 16 13:04:19.874041 systemd-logind[1875]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Dec 16 13:04:19.874253 systemd[1]: Started systemd-logind.service - User Login Management. Dec 16 13:04:20.071826 setup-nvidia[1871]: Downloading Flatcar Container Linux Developer Container for version: 4459.2.2 Dec 16 13:04:20.807434 dbus-daemon[1858]: [system] SELinux support is enabled Dec 16 13:04:20.807652 systemd[1]: Started dbus.service - D-Bus System Message Bus. Dec 16 13:04:20.811735 update_engine[1876]: I20251216 13:04:20.810617 1876 update_check_scheduler.cc:74] Next update check in 4m42s Dec 16 13:04:20.813930 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 16 13:04:20.813963 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Dec 16 13:04:20.819037 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 16 13:04:20.814773 dbus-daemon[1858]: [system] Successfully activated service 'org.freedesktop.systemd1' Dec 16 13:04:20.819056 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Dec 16 13:04:20.824223 systemd[1]: Started update-engine.service - Update Engine. Dec 16 13:04:20.827977 coreos-metadata[1857]: Dec 16 13:04:20.825 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Dec 16 13:04:20.828197 coreos-metadata[1857]: Dec 16 13:04:20.827 INFO Fetch successful Dec 16 13:04:20.828197 coreos-metadata[1857]: Dec 16 13:04:20.828 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Dec 16 13:04:20.829925 systemd[1]: Started locksmithd.service - Cluster reboot manager. Dec 16 13:04:20.834196 coreos-metadata[1857]: Dec 16 13:04:20.832 INFO Fetch successful Dec 16 13:04:20.834196 coreos-metadata[1857]: Dec 16 13:04:20.832 INFO Fetching http://168.63.129.16/machine/b439443f-fbbe-441a-af0b-5f6f600bcaca/a6cd5770%2Daa39%2D4c5d%2D918c%2D500511c80df8.%5Fci%2D4459.2.2%2Da%2D4acfd5b2aa?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Dec 16 13:04:20.867672 coreos-metadata[1857]: Dec 16 13:04:20.867 INFO Fetch successful Dec 16 13:04:20.867807 coreos-metadata[1857]: Dec 16 13:04:20.867 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Dec 16 13:04:20.874905 coreos-metadata[1857]: Dec 16 13:04:20.874 INFO Fetch successful Dec 16 13:04:20.910350 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Dec 16 13:04:20.918442 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Dec 16 13:04:21.207822 setup-nvidia[2001]: % Total % Received % Xferd Average Speed Time Time Time Current Dec 16 13:04:21.208250 setup-nvidia[2001]: Dload Upload Total Spent Left Speed Dec 16 13:04:21.843626 locksmithd[1995]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Dec 16 13:04:22.401911 bash[1909]: Updated "/home/core/.ssh/authorized_keys" Dec 16 13:04:22.403569 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Dec 16 13:04:22.403926 sshd_keygen[1957]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Dec 16 13:04:22.412236 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Dec 16 13:04:22.431970 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Dec 16 13:04:22.438481 systemd[1]: Starting issuegen.service - Generate /run/issue... Dec 16 13:04:22.443258 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Dec 16 13:04:22.459767 systemd[1]: issuegen.service: Deactivated successfully. Dec 16 13:04:22.460026 systemd[1]: Finished issuegen.service - Generate /run/issue. Dec 16 13:04:22.466624 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Dec 16 13:04:22.471975 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Dec 16 13:04:22.483049 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Dec 16 13:04:22.489661 systemd[1]: Started getty@tty1.service - Getty on tty1. Dec 16 13:04:22.494656 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Dec 16 13:04:22.499244 systemd[1]: Reached target getty.target - Login Prompts. Dec 16 13:04:22.845062 containerd[1917]: time="2025-12-16T13:04:22Z" 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 16 13:04:22.845759 containerd[1917]: time="2025-12-16T13:04:22.845726700Z" level=info msg="starting containerd" revision=4ac6c20c7bbf8177f29e46bbdc658fec02ffb8ad version=v2.0.7 Dec 16 13:04:22.853904 containerd[1917]: time="2025-12-16T13:04:22.853869000Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="9.2µs" Dec 16 13:04:22.853904 containerd[1917]: time="2025-12-16T13:04:22.853897600Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Dec 16 13:04:22.853991 containerd[1917]: time="2025-12-16T13:04:22.853915700Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Dec 16 13:04:22.854076 containerd[1917]: time="2025-12-16T13:04:22.854053600Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Dec 16 13:04:22.854129 containerd[1917]: time="2025-12-16T13:04:22.854075100Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Dec 16 13:04:22.854129 containerd[1917]: time="2025-12-16T13:04:22.854115000Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Dec 16 13:04:22.854205 containerd[1917]: time="2025-12-16T13:04:22.854179400Z" 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 16 13:04:22.854205 containerd[1917]: time="2025-12-16T13:04:22.854197600Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Dec 16 13:04:22.854411 containerd[1917]: time="2025-12-16T13:04:22.854388900Z" 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 16 13:04:22.854411 containerd[1917]: time="2025-12-16T13:04:22.854408200Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Dec 16 13:04:22.854464 containerd[1917]: time="2025-12-16T13:04:22.854420500Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Dec 16 13:04:22.854464 containerd[1917]: time="2025-12-16T13:04:22.854428700Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Dec 16 13:04:22.854540 containerd[1917]: time="2025-12-16T13:04:22.854517800Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Dec 16 13:04:22.854770 containerd[1917]: time="2025-12-16T13:04:22.854749800Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Dec 16 13:04:22.854805 containerd[1917]: time="2025-12-16T13:04:22.854786300Z" 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 16 13:04:22.854805 containerd[1917]: time="2025-12-16T13:04:22.854797200Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Dec 16 13:04:22.854854 containerd[1917]: time="2025-12-16T13:04:22.854825500Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Dec 16 13:04:22.855029 containerd[1917]: time="2025-12-16T13:04:22.855008500Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Dec 16 13:04:22.855103 containerd[1917]: time="2025-12-16T13:04:22.855074900Z" level=info msg="metadata content store policy set" policy=shared Dec 16 13:04:22.898430 containerd[1917]: time="2025-12-16T13:04:22.898369600Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Dec 16 13:04:22.898430 containerd[1917]: time="2025-12-16T13:04:22.898434500Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Dec 16 13:04:22.898583 containerd[1917]: time="2025-12-16T13:04:22.898453500Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Dec 16 13:04:22.898583 containerd[1917]: time="2025-12-16T13:04:22.898469200Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Dec 16 13:04:22.898583 containerd[1917]: time="2025-12-16T13:04:22.898484600Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Dec 16 13:04:22.898583 containerd[1917]: time="2025-12-16T13:04:22.898495600Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Dec 16 13:04:22.898583 containerd[1917]: time="2025-12-16T13:04:22.898509500Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Dec 16 13:04:22.898583 containerd[1917]: time="2025-12-16T13:04:22.898521900Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Dec 16 13:04:22.898583 containerd[1917]: time="2025-12-16T13:04:22.898533700Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Dec 16 13:04:22.898583 containerd[1917]: time="2025-12-16T13:04:22.898544100Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Dec 16 13:04:22.898583 containerd[1917]: time="2025-12-16T13:04:22.898554300Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Dec 16 13:04:22.898583 containerd[1917]: time="2025-12-16T13:04:22.898567800Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Dec 16 13:04:22.898800 containerd[1917]: time="2025-12-16T13:04:22.898705400Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Dec 16 13:04:22.898800 containerd[1917]: time="2025-12-16T13:04:22.898725400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Dec 16 13:04:22.898800 containerd[1917]: time="2025-12-16T13:04:22.898739500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Dec 16 13:04:22.898800 containerd[1917]: time="2025-12-16T13:04:22.898752500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Dec 16 13:04:22.898800 containerd[1917]: time="2025-12-16T13:04:22.898763200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Dec 16 13:04:22.898800 containerd[1917]: time="2025-12-16T13:04:22.898776100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Dec 16 13:04:22.898800 containerd[1917]: time="2025-12-16T13:04:22.898787700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Dec 16 13:04:22.898800 containerd[1917]: time="2025-12-16T13:04:22.898799200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Dec 16 13:04:22.898972 containerd[1917]: time="2025-12-16T13:04:22.898823700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Dec 16 13:04:22.898972 containerd[1917]: time="2025-12-16T13:04:22.898838300Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Dec 16 13:04:22.898972 containerd[1917]: time="2025-12-16T13:04:22.898849700Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Dec 16 13:04:22.898972 containerd[1917]: time="2025-12-16T13:04:22.898894600Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Dec 16 13:04:22.898972 containerd[1917]: time="2025-12-16T13:04:22.898908200Z" level=info msg="Start snapshots syncer" Dec 16 13:04:22.898972 containerd[1917]: time="2025-12-16T13:04:22.898952200Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Dec 16 13:04:22.899276 containerd[1917]: time="2025-12-16T13:04:22.899233600Z" 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 16 13:04:22.899411 containerd[1917]: time="2025-12-16T13:04:22.899294000Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Dec 16 13:04:22.899411 containerd[1917]: time="2025-12-16T13:04:22.899353800Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Dec 16 13:04:22.899490 containerd[1917]: time="2025-12-16T13:04:22.899464600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Dec 16 13:04:22.899525 containerd[1917]: time="2025-12-16T13:04:22.899503400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Dec 16 13:04:22.899551 containerd[1917]: time="2025-12-16T13:04:22.899522700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Dec 16 13:04:22.899551 containerd[1917]: time="2025-12-16T13:04:22.899533500Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Dec 16 13:04:22.899551 containerd[1917]: time="2025-12-16T13:04:22.899546200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Dec 16 13:04:22.899621 containerd[1917]: time="2025-12-16T13:04:22.899558500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Dec 16 13:04:22.899621 containerd[1917]: time="2025-12-16T13:04:22.899569600Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Dec 16 13:04:22.899621 containerd[1917]: time="2025-12-16T13:04:22.899595200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Dec 16 13:04:22.899621 containerd[1917]: time="2025-12-16T13:04:22.899606900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Dec 16 13:04:22.899621 containerd[1917]: time="2025-12-16T13:04:22.899619300Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Dec 16 13:04:22.899737 containerd[1917]: time="2025-12-16T13:04:22.899646500Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Dec 16 13:04:22.899737 containerd[1917]: time="2025-12-16T13:04:22.899662800Z" 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 16 13:04:22.899737 containerd[1917]: time="2025-12-16T13:04:22.899672600Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Dec 16 13:04:22.899737 containerd[1917]: time="2025-12-16T13:04:22.899682300Z" 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 16 13:04:22.899737 containerd[1917]: time="2025-12-16T13:04:22.899690300Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Dec 16 13:04:22.899737 containerd[1917]: time="2025-12-16T13:04:22.899709700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Dec 16 13:04:22.899737 containerd[1917]: time="2025-12-16T13:04:22.899728800Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Dec 16 13:04:22.899891 containerd[1917]: time="2025-12-16T13:04:22.899745400Z" level=info msg="runtime interface created" Dec 16 13:04:22.899891 containerd[1917]: time="2025-12-16T13:04:22.899750600Z" level=info msg="created NRI interface" Dec 16 13:04:22.899891 containerd[1917]: time="2025-12-16T13:04:22.899759200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Dec 16 13:04:22.899891 containerd[1917]: time="2025-12-16T13:04:22.899769600Z" level=info msg="Connect containerd service" Dec 16 13:04:22.899891 containerd[1917]: time="2025-12-16T13:04:22.899800000Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Dec 16 13:04:22.900494 containerd[1917]: time="2025-12-16T13:04:22.900465800Z" 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 16 13:04:23.861887 containerd[1917]: time="2025-12-16T13:04:23.861836700Z" level=info msg="Start subscribing containerd event" Dec 16 13:04:23.862282 containerd[1917]: time="2025-12-16T13:04:23.861899100Z" level=info msg="Start recovering state" Dec 16 13:04:23.862282 containerd[1917]: time="2025-12-16T13:04:23.862020900Z" level=info msg="Start event monitor" Dec 16 13:04:23.862282 containerd[1917]: time="2025-12-16T13:04:23.862041600Z" level=info msg="Start cni network conf syncer for default" Dec 16 13:04:23.862282 containerd[1917]: time="2025-12-16T13:04:23.862049400Z" level=info msg="Start streaming server" Dec 16 13:04:23.862282 containerd[1917]: time="2025-12-16T13:04:23.862059000Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Dec 16 13:04:23.862282 containerd[1917]: time="2025-12-16T13:04:23.862075100Z" level=info msg="runtime interface starting up..." Dec 16 13:04:23.862282 containerd[1917]: time="2025-12-16T13:04:23.862081300Z" level=info msg="starting plugins..." Dec 16 13:04:23.862282 containerd[1917]: time="2025-12-16T13:04:23.862110600Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Dec 16 13:04:23.862282 containerd[1917]: time="2025-12-16T13:04:23.862048500Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Dec 16 13:04:23.862282 containerd[1917]: time="2025-12-16T13:04:23.862255900Z" level=info msg=serving... address=/run/containerd/containerd.sock Dec 16 13:04:23.862410 systemd[1]: Started containerd.service - containerd container runtime. Dec 16 13:04:23.864231 containerd[1917]: time="2025-12-16T13:04:23.864199700Z" level=info msg="containerd successfully booted in 1.019623s" Dec 16 13:04:24.865642 setup-nvidia[2001]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 473M 0 4575k 0 0 11.0M 0 0:00:42 --:--:-- 0:00:42 11.0M\u000d 17 473M 17 82.4M 0 0 58.6M 0 0:00:08 0:00:01 0:00:07 58.6M\u000d 50 473M 50 239M 0 0 99.1M 0 0:00:04 0:00:02 0:00:02 99.1M\u000d 89 473M 89 422M 0 0 124M 0 0:00:03 0:00:03 --:--:-- 124M\u000d100 473M 100 473M 0 0 129M 0 0:00:03 0:00:03 --:--:-- 129M Dec 16 13:04:27.582694 waagent[2030]: 2025-12-16T13:04:27.582605Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Dec 16 13:04:27.583918 waagent[2030]: 2025-12-16T13:04:27.583876Z INFO Daemon Daemon OS: flatcar 4459.2.2 Dec 16 13:04:27.585331 waagent[2030]: 2025-12-16T13:04:27.585288Z INFO Daemon Daemon Python: 3.11.13 Dec 16 13:04:27.586250 waagent[2030]: 2025-12-16T13:04:27.586208Z INFO Daemon Daemon Run daemon Dec 16 13:04:27.586808 waagent[2030]: 2025-12-16T13:04:27.586771Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4459.2.2' Dec 16 13:04:27.588123 waagent[2030]: 2025-12-16T13:04:27.588075Z INFO Daemon Daemon Using waagent for provisioning Dec 16 13:04:27.588967 waagent[2030]: 2025-12-16T13:04:27.588927Z INFO Daemon Daemon Activate resource disk Dec 16 13:04:27.590219 waagent[2030]: 2025-12-16T13:04:27.590183Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Dec 16 13:04:27.599371 waagent[2030]: 2025-12-16T13:04:27.597847Z INFO Daemon Daemon Found device: sdb Dec 16 13:04:27.606869 waagent[2030]: 2025-12-16T13:04:27.606809Z INFO Daemon Daemon Examining partition table Dec 16 13:04:27.764098 waagent[2030]: 2025-12-16T13:04:27.761716Z INFO Daemon Daemon GPT not detected, determining filesystem Dec 16 13:04:27.878188 waagent[2030]: 2025-12-16T13:04:27.878041Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Dec 16 13:04:27.880044 waagent[2030]: 2025-12-16T13:04:27.880002Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Dec 16 13:04:31.907196 login[2034]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Dec 16 13:04:31.907638 login[2033]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Dec 16 13:04:31.915992 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Dec 16 13:04:31.917300 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Dec 16 13:04:31.928700 systemd-logind[1875]: New session 1 of user core. Dec 16 13:04:31.933553 systemd-logind[1875]: New session 2 of user core. Dec 16 13:04:40.912473 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Dec 16 13:04:40.936500 (systemd)[2097]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Dec 16 13:04:40.915013 systemd[1]: Starting user@500.service - User Manager for UID 500... Dec 16 13:04:40.938952 systemd-logind[1875]: New session c1 of user core. Dec 16 13:04:41.464528 systemd[2097]: Queued start job for default target default.target. Dec 16 13:04:41.488164 systemd[2097]: Created slice app.slice - User Application Slice. Dec 16 13:04:41.488189 systemd[2097]: Reached target paths.target - Paths. Dec 16 13:04:41.488240 systemd[2097]: Reached target timers.target - Timers. Dec 16 13:04:41.489509 systemd[2097]: Starting dbus.socket - D-Bus User Message Bus Socket... Dec 16 13:04:41.500268 systemd[2097]: Listening on dbus.socket - D-Bus User Message Bus Socket. Dec 16 13:04:41.500378 systemd[2097]: Reached target sockets.target - Sockets. Dec 16 13:04:41.500428 systemd[2097]: Reached target basic.target - Basic System. Dec 16 13:04:41.500473 systemd[2097]: Reached target default.target - Main User Target. Dec 16 13:04:41.500507 systemd[2097]: Startup finished in 555ms. Dec 16 13:04:41.500521 systemd[1]: Started user@500.service - User Manager for UID 500. Dec 16 13:04:41.501677 systemd[1]: Started session-1.scope - Session 1 of User core. Dec 16 13:04:41.502393 systemd[1]: Started session-2.scope - Session 2 of User core. Dec 16 13:04:43.288123 chronyd[1855]: Selected source PHC0 Dec 16 13:04:45.635242 setup-nvidia[1871]: Downloading NVIDIA 535.261.03 Driver Dec 16 13:04:45.639741 setup-nvidia[2132]: % Total % Received % Xferd Average Speed Time Time Time Current Dec 16 13:04:45.640007 setup-nvidia[2132]: Dload Upload Total Spent Left Speed Dec 16 13:04:53.337263 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Dec 16 13:05:06.455705 update_engine[1876]: I20251216 13:05:06.455624 1876 update_attempter.cc:509] Updating boot flags... Dec 16 13:05:13.071106 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#614 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Dec 16 13:05:13.083251 kernel: sdb: sdb1 Dec 16 13:05:13.203642 waagent[2030]: 2025-12-16T13:05:13.203554Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Dec 16 13:05:13.219106 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#386 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Dec 16 13:05:13.241109 kernel: sdb: sdb1 Dec 16 13:05:13.241159 waagent[2030]: 2025-12-16T13:05:13.241121Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Dec 16 13:05:18.879400 waagent[2030]: 2025-12-16T13:05:18.879308Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Dec 16 13:05:19.049151 kernel: EXT4-fs (sdb1): mounted filesystem 2d1f356d-39a8-4c49-a141-a451b04e718e r/w with ordered data mode. Quota mode: none. Dec 16 13:05:19.049468 waagent[2030]: 2025-12-16T13:05:19.049397Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Dec 16 13:05:19.051775 waagent[2030]: 2025-12-16T13:05:19.051713Z INFO Daemon Daemon Clean protocol and wireserver endpoint Dec 16 13:05:19.053503 waagent[2030]: 2025-12-16T13:05:19.053447Z INFO Daemon Daemon Running default provisioning handler Dec 16 13:05:19.064839 waagent[2030]: 2025-12-16T13:05:19.064780Z 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 16 13:05:19.066794 waagent[2030]: 2025-12-16T13:05:19.066747Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Dec 16 13:05:19.067156 waagent[2030]: 2025-12-16T13:05:19.067118Z INFO Daemon Daemon cloud-init is enabled: False Dec 16 13:05:19.068629 waagent[2030]: 2025-12-16T13:05:19.068581Z INFO Daemon Daemon Copying ovf-env.xml Dec 16 13:05:19.173143 waagent[2030]: 2025-12-16T13:05:19.173009Z INFO Daemon Daemon Successfully mounted dvd Dec 16 13:05:19.260109 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Dec 16 13:05:19.260444 waagent[2030]: 2025-12-16T13:05:19.260298Z INFO Daemon Daemon Detect protocol endpoint Dec 16 13:05:19.261978 waagent[2030]: 2025-12-16T13:05:19.261930Z INFO Daemon Daemon Clean protocol and wireserver endpoint Dec 16 13:05:19.263435 waagent[2030]: 2025-12-16T13:05:19.263394Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Dec 16 13:05:19.264868 waagent[2030]: 2025-12-16T13:05:19.264831Z INFO Daemon Daemon Test for route to 168.63.129.16 Dec 16 13:05:19.267022 waagent[2030]: 2025-12-16T13:05:19.266413Z INFO Daemon Daemon Route to 168.63.129.16 exists Dec 16 13:05:19.267073 waagent[2030]: 2025-12-16T13:05:19.267040Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Dec 16 13:05:19.279314 waagent[2030]: 2025-12-16T13:05:19.279256Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Dec 16 13:05:19.279703 waagent[2030]: 2025-12-16T13:05:19.279674Z INFO Daemon Daemon Wire protocol version:2012-11-30 Dec 16 13:05:19.281008 waagent[2030]: 2025-12-16T13:05:19.280971Z INFO Daemon Daemon Server preferred version:2015-04-05 Dec 16 13:05:19.403190 waagent[2030]: 2025-12-16T13:05:19.403081Z INFO Daemon Daemon Initializing goal state during protocol detection Dec 16 13:05:19.404730 waagent[2030]: 2025-12-16T13:05:19.404671Z INFO Daemon Daemon Forcing an update of the goal state. Dec 16 13:05:19.409205 waagent[2030]: 2025-12-16T13:05:19.409140Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Dec 16 13:05:19.453014 waagent[2030]: 2025-12-16T13:05:19.452918Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.177 Dec 16 13:05:19.455446 waagent[2030]: 2025-12-16T13:05:19.455391Z INFO Daemon Dec 16 13:05:19.455707 waagent[2030]: 2025-12-16T13:05:19.455669Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 61964a0d-2ff8-4ae4-9a9c-bc245ff3b586 eTag: 16282932620188868432 source: Fabric] Dec 16 13:05:19.458508 waagent[2030]: 2025-12-16T13:05:19.458466Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Dec 16 13:05:19.459236 waagent[2030]: 2025-12-16T13:05:19.459195Z INFO Daemon Dec 16 13:05:19.460376 waagent[2030]: 2025-12-16T13:05:19.460332Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Dec 16 13:05:19.464746 waagent[2030]: 2025-12-16T13:05:19.464716Z INFO Daemon Daemon Downloading artifacts profile blob Dec 16 13:05:19.595807 waagent[2030]: 2025-12-16T13:05:19.595733Z INFO Daemon Downloaded certificate {'thumbprint': '7D7B249FB5F046F90D2E01EC61519BD567AD0818', 'hasPrivateKey': True} Dec 16 13:05:19.597790 waagent[2030]: 2025-12-16T13:05:19.597744Z INFO Daemon Fetch goal state completed Dec 16 13:05:19.632797 waagent[2030]: 2025-12-16T13:05:19.632738Z INFO Daemon Daemon Starting provisioning Dec 16 13:05:19.634563 waagent[2030]: 2025-12-16T13:05:19.634519Z INFO Daemon Daemon Handle ovf-env.xml. Dec 16 13:05:19.635944 waagent[2030]: 2025-12-16T13:05:19.635908Z INFO Daemon Daemon Set hostname [ci-4459.2.2-a-4acfd5b2aa] Dec 16 13:05:19.652697 systemd-networkd[1566]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Dec 16 13:05:19.668201 waagent[2030]: 2025-12-16T13:05:19.639335Z INFO Daemon Daemon Publish hostname [ci-4459.2.2-a-4acfd5b2aa] Dec 16 13:05:19.668201 waagent[2030]: 2025-12-16T13:05:19.640967Z INFO Daemon Daemon Examine /proc/net/route for primary interface Dec 16 13:05:19.668201 waagent[2030]: 2025-12-16T13:05:19.642654Z INFO Daemon Daemon Primary interface is [eth0] Dec 16 13:05:19.668201 waagent[2030]: 2025-12-16T13:05:19.653173Z INFO Daemon Daemon Create user account if not exists Dec 16 13:05:19.668201 waagent[2030]: 2025-12-16T13:05:19.653851Z INFO Daemon Daemon User core already exists, skip useradd Dec 16 13:05:19.668201 waagent[2030]: 2025-12-16T13:05:19.655122Z INFO Daemon Daemon Configure sudoer Dec 16 13:05:19.668201 waagent[2030]: 2025-12-16T13:05:19.660183Z INFO Daemon Daemon Configure sshd Dec 16 13:05:19.668201 waagent[2030]: 2025-12-16T13:05:19.664648Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Dec 16 13:05:19.668201 waagent[2030]: 2025-12-16T13:05:19.665865Z INFO Daemon Daemon Deploy ssh public key. Dec 16 13:05:19.652703 systemd-networkd[1566]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Dec 16 13:05:19.652730 systemd-networkd[1566]: eth0: DHCP lease lost Dec 16 13:05:19.742149 systemd-networkd[1566]: eth0: DHCPv4 address 10.200.0.4/24, gateway 10.200.0.1 acquired from 168.63.129.16 Dec 16 13:05:20.848505 waagent[2030]: 2025-12-16T13:05:20.848437Z INFO Daemon Daemon Provisioning complete Dec 16 13:05:20.858379 waagent[2030]: 2025-12-16T13:05:20.858337Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Dec 16 13:05:20.869049 waagent[2030]: 2025-12-16T13:05:20.859895Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Dec 16 13:05:20.869049 waagent[2030]: 2025-12-16T13:05:20.861274Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Dec 16 13:05:21.040344 waagent[2309]: 2025-12-16T13:05:21.040251Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Dec 16 13:05:21.040710 waagent[2309]: 2025-12-16T13:05:21.040409Z INFO ExtHandler ExtHandler OS: flatcar 4459.2.2 Dec 16 13:05:21.040710 waagent[2309]: 2025-12-16T13:05:21.040478Z INFO ExtHandler ExtHandler Python: 3.11.13 Dec 16 13:05:21.040710 waagent[2309]: 2025-12-16T13:05:21.040545Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Dec 16 13:05:21.106571 waagent[2309]: 2025-12-16T13:05:21.106431Z 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 16 13:05:21.106738 waagent[2309]: 2025-12-16T13:05:21.106685Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Dec 16 13:05:21.106811 waagent[2309]: 2025-12-16T13:05:21.106771Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Dec 16 13:05:21.113509 waagent[2309]: 2025-12-16T13:05:21.113463Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Dec 16 13:05:21.118245 waagent[2309]: 2025-12-16T13:05:21.118210Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.177 Dec 16 13:05:21.118680 waagent[2309]: 2025-12-16T13:05:21.118641Z INFO ExtHandler Dec 16 13:05:21.118758 waagent[2309]: 2025-12-16T13:05:21.118728Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: b8f1af3b-8a1c-4e83-b451-023246a5534d eTag: 16282932620188868432 source: Fabric] Dec 16 13:05:21.119065 waagent[2309]: 2025-12-16T13:05:21.119030Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Dec 16 13:05:21.119680 waagent[2309]: 2025-12-16T13:05:21.119643Z INFO ExtHandler Dec 16 13:05:21.119749 waagent[2309]: 2025-12-16T13:05:21.119719Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Dec 16 13:05:21.123274 waagent[2309]: 2025-12-16T13:05:21.123237Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Dec 16 13:05:21.192309 waagent[2309]: 2025-12-16T13:05:21.192233Z INFO ExtHandler Downloaded certificate {'thumbprint': '7D7B249FB5F046F90D2E01EC61519BD567AD0818', 'hasPrivateKey': True} Dec 16 13:05:21.192856 waagent[2309]: 2025-12-16T13:05:21.192811Z INFO ExtHandler Fetch goal state completed Dec 16 13:05:21.205750 waagent[2309]: 2025-12-16T13:05:21.205706Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.4.2 1 Jul 2025 (Library: OpenSSL 3.4.2 1 Jul 2025) Dec 16 13:05:21.210834 waagent[2309]: 2025-12-16T13:05:21.210790Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2309 Dec 16 13:05:21.210970 waagent[2309]: 2025-12-16T13:05:21.210934Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Dec 16 13:05:21.211323 waagent[2309]: 2025-12-16T13:05:21.211284Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Dec 16 13:05:21.212904 waagent[2309]: 2025-12-16T13:05:21.212857Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4459.2.2', '', 'Flatcar Container Linux by Kinvolk'] Dec 16 13:05:21.213346 waagent[2309]: 2025-12-16T13:05:21.213306Z 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 16 13:05:21.213498 waagent[2309]: 2025-12-16T13:05:21.213464Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Dec 16 13:05:21.214131 waagent[2309]: 2025-12-16T13:05:21.214076Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Dec 16 13:05:21.282370 waagent[2309]: 2025-12-16T13:05:21.282319Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Dec 16 13:05:21.282574 waagent[2309]: 2025-12-16T13:05:21.282528Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Dec 16 13:05:21.289038 waagent[2309]: 2025-12-16T13:05:21.289003Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Dec 16 13:05:21.297318 systemd[1]: Reload requested from client PID 2324 ('systemctl') (unit waagent.service)... Dec 16 13:05:21.297336 systemd[1]: Reloading... Dec 16 13:05:21.365116 zram_generator::config[2364]: No configuration found. Dec 16 13:05:21.644535 systemd[1]: Reloading finished in 346 ms. Dec 16 13:05:21.669978 waagent[2309]: 2025-12-16T13:05:21.669903Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Dec 16 13:05:21.670084 waagent[2309]: 2025-12-16T13:05:21.670052Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Dec 16 13:05:22.376005 waagent[2309]: 2025-12-16T13:05:22.375919Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Dec 16 13:05:22.376396 waagent[2309]: 2025-12-16T13:05:22.376346Z 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 16 13:05:22.377159 waagent[2309]: 2025-12-16T13:05:22.377111Z INFO ExtHandler ExtHandler Starting env monitor service. Dec 16 13:05:22.377608 waagent[2309]: 2025-12-16T13:05:22.377566Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Dec 16 13:05:22.377684 waagent[2309]: 2025-12-16T13:05:22.377639Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Dec 16 13:05:22.378126 waagent[2309]: 2025-12-16T13:05:22.378063Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Dec 16 13:05:22.378183 waagent[2309]: 2025-12-16T13:05:22.378146Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Dec 16 13:05:22.378306 waagent[2309]: 2025-12-16T13:05:22.378247Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Dec 16 13:05:22.378445 waagent[2309]: 2025-12-16T13:05:22.378403Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Dec 16 13:05:22.378749 waagent[2309]: 2025-12-16T13:05:22.378706Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Dec 16 13:05:22.378900 waagent[2309]: 2025-12-16T13:05:22.378862Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Dec 16 13:05:22.379319 waagent[2309]: 2025-12-16T13:05:22.379270Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Dec 16 13:05:22.379391 waagent[2309]: 2025-12-16T13:05:22.379351Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Dec 16 13:05:22.379707 waagent[2309]: 2025-12-16T13:05:22.379661Z INFO EnvHandler ExtHandler Configure routes Dec 16 13:05:22.379893 waagent[2309]: 2025-12-16T13:05:22.379856Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Dec 16 13:05:22.380021 waagent[2309]: 2025-12-16T13:05:22.379987Z INFO EnvHandler ExtHandler Gateway:None Dec 16 13:05:22.380597 waagent[2309]: 2025-12-16T13:05:22.380562Z INFO EnvHandler ExtHandler Routes:None Dec 16 13:05:22.380726 waagent[2309]: 2025-12-16T13:05:22.380674Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Dec 16 13:05:22.380726 waagent[2309]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Dec 16 13:05:22.380726 waagent[2309]: eth0 00000000 0100C80A 0003 0 0 1024 00000000 0 0 0 Dec 16 13:05:22.380726 waagent[2309]: eth0 0000C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Dec 16 13:05:22.380726 waagent[2309]: eth0 0100C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Dec 16 13:05:22.380726 waagent[2309]: eth0 10813FA8 0100C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Dec 16 13:05:22.380726 waagent[2309]: eth0 FEA9FEA9 0100C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Dec 16 13:05:22.385567 waagent[2309]: 2025-12-16T13:05:22.385520Z INFO ExtHandler ExtHandler Dec 16 13:05:22.385848 waagent[2309]: 2025-12-16T13:05:22.385807Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 46ef2e5e-ddbb-42a3-924d-d195f5352754 correlation 31e846c5-401f-4d6e-aad3-4b6b43dd8388 created: 2025-12-16T13:02:48.197495Z] Dec 16 13:05:22.386904 waagent[2309]: 2025-12-16T13:05:22.386861Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Dec 16 13:05:22.389178 waagent[2309]: 2025-12-16T13:05:22.389136Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Dec 16 13:05:22.419909 waagent[2309]: 2025-12-16T13:05:22.419854Z 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 16 13:05:22.419909 waagent[2309]: Try `iptables -h' or 'iptables --help' for more information.) Dec 16 13:05:22.420323 waagent[2309]: 2025-12-16T13:05:22.420285Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 1DE8292A-688F-4067-A1B0-52DDC40E88F9;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Dec 16 13:05:22.505213 waagent[2309]: 2025-12-16T13:05:22.505133Z INFO MonitorHandler ExtHandler Network interfaces: Dec 16 13:05:22.505213 waagent[2309]: Executing ['ip', '-a', '-o', 'link']: Dec 16 13:05:22.505213 waagent[2309]: 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 16 13:05:22.505213 waagent[2309]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:ed:8d:8f:59:a2 brd ff:ff:ff:ff:ff:ff Dec 16 13:05:22.505213 waagent[2309]: Executing ['ip', '-4', '-a', '-o', 'address']: Dec 16 13:05:22.505213 waagent[2309]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Dec 16 13:05:22.505213 waagent[2309]: 2: eth0 inet 10.200.0.4/24 metric 1024 brd 10.200.0.255 scope global eth0\ valid_lft forever preferred_lft forever Dec 16 13:05:22.505213 waagent[2309]: Executing ['ip', '-6', '-a', '-o', 'address']: Dec 16 13:05:22.505213 waagent[2309]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Dec 16 13:05:22.505213 waagent[2309]: 2: eth0 inet6 fe80::7eed:8dff:fe8f:59a2/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Dec 16 13:05:22.618628 waagent[2309]: 2025-12-16T13:05:22.618565Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Dec 16 13:05:22.618628 waagent[2309]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Dec 16 13:05:22.618628 waagent[2309]: pkts bytes target prot opt in out source destination Dec 16 13:05:22.618628 waagent[2309]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Dec 16 13:05:22.618628 waagent[2309]: pkts bytes target prot opt in out source destination Dec 16 13:05:22.618628 waagent[2309]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Dec 16 13:05:22.618628 waagent[2309]: pkts bytes target prot opt in out source destination Dec 16 13:05:22.618628 waagent[2309]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Dec 16 13:05:22.618628 waagent[2309]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Dec 16 13:05:22.618628 waagent[2309]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Dec 16 13:05:22.622034 waagent[2309]: 2025-12-16T13:05:22.621985Z INFO EnvHandler ExtHandler Current Firewall rules: Dec 16 13:05:22.622034 waagent[2309]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Dec 16 13:05:22.622034 waagent[2309]: pkts bytes target prot opt in out source destination Dec 16 13:05:22.622034 waagent[2309]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Dec 16 13:05:22.622034 waagent[2309]: pkts bytes target prot opt in out source destination Dec 16 13:05:22.622034 waagent[2309]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Dec 16 13:05:22.622034 waagent[2309]: pkts bytes target prot opt in out source destination Dec 16 13:05:22.622034 waagent[2309]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Dec 16 13:05:22.622034 waagent[2309]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Dec 16 13:05:22.622034 waagent[2309]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Dec 16 13:05:22.622285 waagent[2309]: 2025-12-16T13:05:22.622259Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Dec 16 13:05:22.622438 waagent[2309]: 2025-12-16T13:05:22.622404Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Dec 16 13:05:35.729446 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Dec 16 13:05:35.732184 systemd[1]: Started sshd@0-10.200.0.4:22-10.200.16.10:57410.service - OpenSSH per-connection server daemon (10.200.16.10:57410). Dec 16 13:05:36.513535 sshd[2456]: Accepted publickey for core from 10.200.16.10 port 57410 ssh2: RSA SHA256:MCL+xU0rVZ+W8lZTao6YvClPjRaWoIZn7yaQaIgbhxY Dec 16 13:05:36.514703 sshd-session[2456]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 16 13:05:36.518759 systemd-logind[1875]: New session 3 of user core. Dec 16 13:05:36.529199 systemd[1]: Started session-3.scope - Session 3 of User core. Dec 16 13:05:37.021978 systemd[1]: Started sshd@1-10.200.0.4:22-10.200.16.10:57418.service - OpenSSH per-connection server daemon (10.200.16.10:57418). Dec 16 13:05:37.595071 sshd[2462]: Accepted publickey for core from 10.200.16.10 port 57418 ssh2: RSA SHA256:MCL+xU0rVZ+W8lZTao6YvClPjRaWoIZn7yaQaIgbhxY Dec 16 13:05:37.596296 sshd-session[2462]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 16 13:05:37.600428 systemd-logind[1875]: New session 4 of user core. Dec 16 13:05:37.613231 systemd[1]: Started session-4.scope - Session 4 of User core. Dec 16 13:05:38.021973 sshd[2465]: Connection closed by 10.200.16.10 port 57418 Dec 16 13:05:38.022406 sshd-session[2462]: pam_unix(sshd:session): session closed for user core Dec 16 13:05:38.025828 systemd[1]: sshd@1-10.200.0.4:22-10.200.16.10:57418.service: Deactivated successfully. Dec 16 13:05:38.027682 systemd[1]: session-4.scope: Deactivated successfully. Dec 16 13:05:38.028494 systemd-logind[1875]: Session 4 logged out. Waiting for processes to exit. Dec 16 13:05:38.029393 systemd-logind[1875]: Removed session 4. Dec 16 13:05:38.132822 systemd[1]: Started sshd@2-10.200.0.4:22-10.200.16.10:57420.service - OpenSSH per-connection server daemon (10.200.16.10:57420). Dec 16 13:05:38.747486 sshd[2471]: Accepted publickey for core from 10.200.16.10 port 57420 ssh2: RSA SHA256:MCL+xU0rVZ+W8lZTao6YvClPjRaWoIZn7yaQaIgbhxY Dec 16 13:05:38.748691 sshd-session[2471]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 16 13:05:38.752998 systemd-logind[1875]: New session 5 of user core. Dec 16 13:05:38.763244 systemd[1]: Started session-5.scope - Session 5 of User core. Dec 16 13:05:39.171570 sshd[2474]: Connection closed by 10.200.16.10 port 57420 Dec 16 13:05:39.171941 sshd-session[2471]: pam_unix(sshd:session): session closed for user core Dec 16 13:05:39.174860 systemd[1]: sshd@2-10.200.0.4:22-10.200.16.10:57420.service: Deactivated successfully. Dec 16 13:05:39.176592 systemd[1]: session-5.scope: Deactivated successfully. Dec 16 13:05:39.177824 systemd-logind[1875]: Session 5 logged out. Waiting for processes to exit. Dec 16 13:05:39.178765 systemd-logind[1875]: Removed session 5. Dec 16 13:05:47.149294 setup-nvidia[2132]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 3 325M 3 10.0M 0 0 9494k 0 0:00:35 0:00:01 0:00:34 9500k\u000d 5 325M 5 18.0M 0 0 9208k 0 0:00:36 0:00:02 0:00:34 9211k\u000d 7 325M 7 24.0M 0 0 8001k 0 0:00:41 0:00:03 0:00:38 8003k\u000d 9 325M 9 30.0M 0 0 7369k 0 0:00:45 0:00:04 0:00:41 7370k\u000d 11 325M 11 36.0M 0 0 6997k 0 0:00:47 0:00:05 0:00:42 6999k\u000d 12 325M 12 40.0M 0 0 6536k 0 0:00:51 0:00:06 0:00:45 5922k\u000d 13 325M 13 44.0M 0 0 6324k 0 0:00:52 0:00:07 0:00:45 5198k\u000d 15 325M 15 50.0M 0 0 6186k 0 0:00:53 0:00:08 0:00:45 5116k\u000d 16 325M 16 54.0M 0 0 6115k 0 0:00:54 0:00:09 0:00:45 5043k\u000d 18 325M 18 60.0M 0 0 6130k 0 0:00:54 0:00:10 0:00:44 5168k\u000d 19 325M 19 64.1M 0 0 5969k 0 0:00:55 0:00:10 0:00:45 5216k\u000d 21 325M 21 70.0M 0 0 5884k 0 0:00:56 0:00:12 0:00:44 5265k\u000d 23 325M 23 75.2M 0 0 5939k 0 0:00:56 0:00:12 0:00:44 5503k\u000d 24 325M 24 80.0M 0 0 5810k 0 0:00:57 0:00:14 0:00:43 5264k\u000d 26 325M 26 86.0M 0 0 5781k 0 0:00:57 0:00:15 0:00:42 5109k\u000d 27 325M 27 90.0M 0 0 5686k 0 0:00:58 0:00:16 0:00:42 5090k\u000d 30 325M 30 98.0M 0 0 5792k 0 0:00:57 0:00:17 0:00:40 5574k\u000d 31 325M 31 102M 0 0 5776k 0 0:00:57 0:00:18 0:00:39 5364k\u000d 33 325M 33 108M 0 0 5736k 0 0:00:58 0:00:19 0:00:39 5537k\u000d 34 325M 34 112M 0 0 5733k 0 0:00:58 0:00:20 0:00:38 5582k\u000d 36 325M 36 118M 0 0 5690k 0 0:00:58 0:00:21 0:00:37 5706k\u000d 37 325M 37 122M 0 0 5675k 0 0:00:58 0:00:22 0:00:36 5242k\u000d 38 325M 38 126M 0 0 5613k 0 0:00:59 0:00:23 0:00:36 5013k\u000d 40 325M 40 132M 0 0 5592k 0 0:00:59 0:00:24 0:00:35 5023k\u000d 42 325M 42 138M 0 0 5581k 0 0:00:59 0:00:25 0:00:34 5006k\u000d 43 325M 43 142M 0 0 5572k 0 0:00:59 0:00:26 0:00:33 5054k\u000d 45 325M 45 148M 0 0 5563k 0 0:00:59 0:00:27 0:00:32 5090k\u000d 46 325M 46 152M 0 0 5553k 0 0:01:00 0:00:28 0:00:32 5276k\u000d 48 325M 48 158M 0 0 5529k 0 0:01:00 0:00:29 0:00:31 5230k\u000d 49 325M 49 162M 0 0 5509k 0 0:01:00 0:00:30 0:00:30 5129k\u000d 51 325M 51 168M 0 0 5497k 0 0:01:00 0:00:31 0:00:29 5120k\u000d 52 325M 52 172M 0 0 5490k 0 0:01:00 0:00:32 0:00:28 5084k\u000d 54 325M 54 178M 0 0 5475k 0 0:01:00 0:00:33 0:00:27 5061k\u000d 55 325M 55 182M 0 0 5467k 0 0:01:01 0:00:34 0:00:27 5090k\u000d 57 325M 57 188M 0 0 5455k 0 0:01:01 0:00:35 0:00:26 5141k\u000d 58 325M 58 192M 0 0 5450k 0 0:01:01 0:00:36 0:00:25 5144k\u000d 60 325M 60 198M 0 0 5448k 0 0:01:01 0:00:37 0:00:24 5181k\u000d 61 325M 61 202M 0 0 5446k 0 0:01:01 0:00:37 0:00:24 5241k\u000d 63 325M 63 208M 0 0 5436k 0 0:01:01 0:00:39 0:00:22 5231k\u000d 65 325M 65 214M 0 0 5437k 0 0:01:01 0:00:40 0:00:21 5315k\u000d 66 325M 66 218M 0 0 5435k 0 0:01:01 0:00:41 0:00:20 5330k\u000d 68 325M 68 224M 0 0 5455k 0 0:01:01 0:00:42 0:00:19 5507k\u000d 70 325M 70 230M 0 0 5444k 0 0:01:01 0:00:43 0:00:18 5434k\u000d 71 325M 71 234M 0 0 5440k 0 0:01:01 0:00:44 0:00:17 5469k\u000d 73 325M 73 240M 0 0 5437k 0 0:01:01 0:00:45 0:00:16 5435k\u000d 74 325M 74 244M 0 0 5440k 0 0:01:01 0:00:45 0:00:16 5474k\u000d 76 325M 76 250M 0 0 5436k 0 0:01:01 0:00:47 0:00:14 5279k\u000d 78 325M 78 256M 0 0 5431k 0 0:01:01 0:00:48 0:00:13 5316k\u000d 79 325M 79 260M 0 0 5431k 0 0:01:01 0:00:49 0:00:12 5354k\u000d 81 325M 81 266M 0 0 5425k 0 0:01:01 0:00:50 0:00:11 5319k\u000d 82 325M 82 270M 0 0 5423k 0 0:01:01 0:00:50 0:00:11 5272k\u000d 84 325M 84 276M 0 0 5423k 0 0:01:01 0:00:52 0:00:09 5300k\u000d 86 325M 86 282M 0 0 5420k 0 0:01:01 0:00:53 0:00:08 5317k\u000d 87 325M 87 286M 0 0 5420k 0 0:01:01 0:00:54 0:00:07 5316k\u000d 89 325M 89 292M 0 0 5419k 0 0:01:01 0:00:55 0:00:06 5350k\u000d 91 325M 91 298M 0 0 5418k 0 0:01:01 0:00:56 0:00:05 5369k\u000d 92 325M 92 302M 0 0 5416k 0 0:01:01 0:00:57 0:00:04 5345k\u000d 94 325M 94 307M 0 0 5434k 0 0:01:01 0:00:57 0:00:04 5586k\u000d 95 325M 95 312M 0 0 5404k 0 0:01:01 0:00:59 0:00:02 5227k\u000d 97 325M 97 318M 0 0 5403k 0 0:01:01 0:01:00 0:00:01 5230k\u000d 98 325M 98 322M 0 0 5396k 0 0:01:01 0:01:01 --:--:-- 5140k\u000d100 325M 100 325M 0 0 5426k 0 0:01:01 0:01:01 --:--:-- 5554k Dec 16 13:05:47.248189 setup-nvidia[1871]: Extract the NVIDIA Driver Installer 535.261.03 Dec 16 13:05:47.248189 setup-nvidia[1871]: /opt/nvidia/workdir/nvidia-workdir / Dec 16 13:05:47.284921 setup-nvidia[2481]: Creating directory NVIDIA-Linux-x86_64-535.261.03 Dec 16 13:05:47.553639 setup-nvidia[2481]: Verifying archive integrity... OK Dec 16 13:05:48.361955 setup-nvidia[2481]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.261.03 Dec 16 13:05:49.279240 systemd[1]: Started sshd@3-10.200.0.4:22-10.200.16.10:47282.service - OpenSSH per-connection server daemon (10.200.16.10:47282). Dec 16 13:05:49.846853 sshd[2512]: Accepted publickey for core from 10.200.16.10 port 47282 ssh2: RSA SHA256:MCL+xU0rVZ+W8lZTao6YvClPjRaWoIZn7yaQaIgbhxY Dec 16 13:05:49.848136 sshd-session[2512]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 16 13:05:49.852706 systemd-logind[1875]: New session 6 of user core. Dec 16 13:05:49.860250 systemd[1]: Started session-6.scope - Session 6 of User core. Dec 16 13:05:50.195464 setup-nvidia[2507]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Dec 16 13:05:50.241865 sshd[2515]: Connection closed by 10.200.16.10 port 47282 Dec 16 13:05:50.242242 sshd-session[2512]: pam_unix(sshd:session): session closed for user core Dec 16 13:05:50.245561 systemd[1]: sshd@3-10.200.0.4:22-10.200.16.10:47282.service: Deactivated successfully. Dec 16 13:05:50.247218 systemd[1]: session-6.scope: Deactivated successfully. Dec 16 13:05:50.247894 systemd-logind[1875]: Session 6 logged out. Waiting for processes to exit. Dec 16 13:05:50.248814 systemd-logind[1875]: Removed session 6. Dec 16 13:05:50.262680 setup-nvidia[1871]: / Dec 16 13:05:50.262889 setup-nvidia[1871]: Spawn system-nspawn container to install the NVIDIA drivers Dec 16 13:05:50.350164 systemd[1]: Started sshd@4-10.200.0.4:22-10.200.16.10:34048.service - OpenSSH per-connection server daemon (10.200.16.10:34048). Dec 16 13:05:50.509480 sudo[2524]: 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 16 13:05:50.511206 sudo[2524]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 16 13:05:50.516813 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Dec 16 13:05:50.519268 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Dec 16 13:05:50.548844 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Dec 16 13:05:50.552740 systemd[1]: Starting user@0.service - User Manager for UID 0... Dec 16 13:05:50.572072 (systemd)[2531]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Dec 16 13:05:50.578031 systemd-logind[1875]: New session c3 of user root. Dec 16 13:05:50.757144 systemd[2531]: Queued start job for default target default.target. Dec 16 13:05:50.775216 systemd[2531]: Created slice app.slice - User Application Slice. Dec 16 13:05:50.775244 systemd[2531]: Reached target paths.target - Paths. Dec 16 13:05:50.775290 systemd[2531]: Reached target timers.target - Timers. Dec 16 13:05:50.776409 systemd[2531]: Starting dbus.socket - D-Bus User Message Bus Socket... Dec 16 13:05:50.787073 systemd[2531]: Listening on dbus.socket - D-Bus User Message Bus Socket. Dec 16 13:05:50.787254 systemd[2531]: Reached target sockets.target - Sockets. Dec 16 13:05:50.787307 systemd[2531]: Reached target basic.target - Basic System. Dec 16 13:05:50.787350 systemd[2531]: Reached target default.target - Main User Target. Dec 16 13:05:50.787386 systemd[2531]: Startup finished in 202ms. Dec 16 13:05:50.787416 systemd[1]: Started user@0.service - User Manager for UID 0. Dec 16 13:05:50.790832 systemd[1]: Started session-c2.scope - Session c2 of User root. Dec 16 13:05:50.857120 kernel: loop6: detected capacity change from 0 to 12644352 Dec 16 13:05:50.929951 sshd[2526]: Accepted publickey for core from 10.200.16.10 port 34048 ssh2: RSA SHA256:MCL+xU0rVZ+W8lZTao6YvClPjRaWoIZn7yaQaIgbhxY Dec 16 13:05:50.931572 sshd-session[2526]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 16 13:05:50.936409 systemd-logind[1875]: New session 7 of user core. Dec 16 13:05:50.947211 systemd[1]: Started session-7.scope - Session 7 of User core. Dec 16 13:05:51.009120 kernel: loop6: p9 Dec 16 13:05:51.054440 setup-nvidia[2540]: ░ Spawning container flatcardevelopercontainer-4459.2.2.bin on /opt/nvidia/workdir/flatcar_developer_container-4459.2.2.bin. Dec 16 13:05:51.326538 sshd[2542]: Connection closed by 10.200.16.10 port 34048 Dec 16 13:05:51.326833 sshd-session[2526]: pam_unix(sshd:session): session closed for user core Dec 16 13:05:51.329836 systemd[1]: sshd@4-10.200.0.4:22-10.200.16.10:34048.service: Deactivated successfully. Dec 16 13:05:51.331461 systemd[1]: session-7.scope: Deactivated successfully. Dec 16 13:05:51.332137 systemd-logind[1875]: Session 7 logged out. Waiting for processes to exit. Dec 16 13:05:51.333502 systemd-logind[1875]: Removed session 7. Dec 16 13:05:51.459922 kernel: EXT4-fs (loop6p9): write access unavailable, skipping orphan cleanup Dec 16 13:05:51.460039 kernel: EXT4-fs (loop6p9): mounted filesystem 02782f9c-2fea-4b2e-9279-7381cb6c9c28 ro without journal. Quota mode: none. Dec 16 13:06:01.430444 systemd[1]: Started sshd@5-10.200.0.4:22-10.200.16.10:44392.service - OpenSSH per-connection server daemon (10.200.16.10:44392). Dec 16 13:06:01.995046 sshd[3605]: Accepted publickey for core from 10.200.16.10 port 44392 ssh2: RSA SHA256:MCL+xU0rVZ+W8lZTao6YvClPjRaWoIZn7yaQaIgbhxY Dec 16 13:06:01.996316 sshd-session[3605]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 16 13:06:02.000510 systemd-logind[1875]: New session 8 of user core. Dec 16 13:06:02.013240 systemd[1]: Started session-8.scope - Session 8 of User core. Dec 16 13:06:02.391965 sshd[3919]: Connection closed by 10.200.16.10 port 44392 Dec 16 13:06:02.392295 sshd-session[3605]: pam_unix(sshd:session): session closed for user core Dec 16 13:06:02.396045 systemd[1]: sshd@5-10.200.0.4:22-10.200.16.10:44392.service: Deactivated successfully. Dec 16 13:06:02.397668 systemd[1]: session-8.scope: Deactivated successfully. Dec 16 13:06:02.398711 systemd-logind[1875]: Session 8 logged out. Waiting for processes to exit. Dec 16 13:06:02.399880 systemd-logind[1875]: Removed session 8. Dec 16 13:06:02.502606 systemd[1]: Started sshd@6-10.200.0.4:22-10.200.16.10:44396.service - OpenSSH per-connection server daemon (10.200.16.10:44396). Dec 16 13:06:03.061403 sshd[4156]: Accepted publickey for core from 10.200.16.10 port 44396 ssh2: RSA SHA256:MCL+xU0rVZ+W8lZTao6YvClPjRaWoIZn7yaQaIgbhxY Dec 16 13:06:03.062778 sshd-session[4156]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 16 13:06:03.068107 systemd-logind[1875]: New session 9 of user core. Dec 16 13:06:03.077335 systemd[1]: Started session-9.scope - Session 9 of User core. Dec 16 13:06:03.458600 sshd[4482]: Connection closed by 10.200.16.10 port 44396 Dec 16 13:06:03.458887 sshd-session[4156]: pam_unix(sshd:session): session closed for user core Dec 16 13:06:03.465491 systemd[1]: sshd@6-10.200.0.4:22-10.200.16.10:44396.service: Deactivated successfully. Dec 16 13:06:03.467120 systemd[1]: session-9.scope: Deactivated successfully. Dec 16 13:06:03.467820 systemd-logind[1875]: Session 9 logged out. Waiting for processes to exit. Dec 16 13:06:03.468949 systemd-logind[1875]: Removed session 9. Dec 16 13:06:13.569897 systemd[1]: Started sshd@7-10.200.0.4:22-10.200.16.10:55602.service - OpenSSH per-connection server daemon (10.200.16.10:55602). Dec 16 13:06:14.134476 sshd[8728]: Accepted publickey for core from 10.200.16.10 port 55602 ssh2: RSA SHA256:MCL+xU0rVZ+W8lZTao6YvClPjRaWoIZn7yaQaIgbhxY Dec 16 13:06:14.136334 sshd-session[8728]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 16 13:06:14.144527 systemd-logind[1875]: New session 10 of user core. Dec 16 13:06:14.152177 systemd[1]: Started session-10.scope - Session 10 of User core. Dec 16 13:06:14.536209 sshd[9019]: Connection closed by 10.200.16.10 port 55602 Dec 16 13:06:14.538038 sshd-session[8728]: pam_unix(sshd:session): session closed for user core Dec 16 13:06:14.542449 systemd-logind[1875]: Session 10 logged out. Waiting for processes to exit. Dec 16 13:06:14.544462 systemd[1]: sshd@7-10.200.0.4:22-10.200.16.10:55602.service: Deactivated successfully. Dec 16 13:06:14.546838 systemd[1]: session-10.scope: Deactivated successfully. Dec 16 13:06:14.549532 systemd-logind[1875]: Removed session 10. Dec 16 13:06:14.642596 systemd[1]: Started sshd@8-10.200.0.4:22-10.200.16.10:55612.service - OpenSSH per-connection server daemon (10.200.16.10:55612). Dec 16 13:06:15.222011 sshd[9087]: Accepted publickey for core from 10.200.16.10 port 55612 ssh2: RSA SHA256:MCL+xU0rVZ+W8lZTao6YvClPjRaWoIZn7yaQaIgbhxY Dec 16 13:06:15.223342 sshd-session[9087]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 16 13:06:15.229653 systemd-logind[1875]: New session 11 of user core. Dec 16 13:06:15.235239 systemd[1]: Started session-11.scope - Session 11 of User core. Dec 16 13:06:15.621956 sshd[9213]: Connection closed by 10.200.16.10 port 55612 Dec 16 13:06:15.623900 sshd-session[9087]: pam_unix(sshd:session): session closed for user core Dec 16 13:06:15.627558 systemd[1]: sshd@8-10.200.0.4:22-10.200.16.10:55612.service: Deactivated successfully. Dec 16 13:06:15.630243 systemd[1]: session-11.scope: Deactivated successfully. Dec 16 13:06:15.631488 systemd-logind[1875]: Session 11 logged out. Waiting for processes to exit. Dec 16 13:06:15.633505 systemd-logind[1875]: Removed session 11. Dec 16 13:06:25.727307 systemd[1]: Started sshd@9-10.200.0.4:22-10.200.16.10:52842.service - OpenSSH per-connection server daemon (10.200.16.10:52842). Dec 16 13:06:26.298939 sshd[12645]: Accepted publickey for core from 10.200.16.10 port 52842 ssh2: RSA SHA256:MCL+xU0rVZ+W8lZTao6YvClPjRaWoIZn7yaQaIgbhxY Dec 16 13:06:26.300668 sshd-session[12645]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 16 13:06:30.994907 sshd[12832]: Connection closed by 10.200.16.10 port 52842 Dec 16 13:06:26.305498 systemd-logind[1875]: New session 12 of user core. Dec 16 13:06:26.694956 sshd-session[12645]: pam_unix(sshd:session): session closed for user core Dec 16 13:06:30.995354 sshd[12842]: Accepted publickey for core from 10.200.16.10 port 52850 ssh2: RSA SHA256:MCL+xU0rVZ+W8lZTao6YvClPjRaWoIZn7yaQaIgbhxY Dec 16 13:06:26.314238 systemd[1]: Started session-12.scope - Session 12 of User core. Dec 16 13:06:27.355558 sshd-session[12842]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 16 13:06:30.996033 sshd[12845]: Connection closed by 10.200.16.10 port 52850 Dec 16 13:06:26.698586 systemd[1]: sshd@9-10.200.0.4:22-10.200.16.10:52842.service: Deactivated successfully. Dec 16 13:06:27.749546 sshd-session[12842]: pam_unix(sshd:session): session closed for user core Dec 16 13:06:26.700169 systemd[1]: session-12.scope: Deactivated successfully. Dec 16 13:06:26.700874 systemd-logind[1875]: Session 12 logged out. Waiting for processes to exit. Dec 16 13:06:26.701872 systemd-logind[1875]: Removed session 12. Dec 16 13:06:26.797638 systemd[1]: Started sshd@10-10.200.0.4:22-10.200.16.10:52850.service - OpenSSH per-connection server daemon (10.200.16.10:52850). Dec 16 13:06:27.359663 systemd-logind[1875]: New session 13 of user core. Dec 16 13:06:27.368241 systemd[1]: Started session-13.scope - Session 13 of User core. Dec 16 13:06:27.752507 systemd[1]: sshd@10-10.200.0.4:22-10.200.16.10:52850.service: Deactivated successfully. Dec 16 13:06:27.754439 systemd[1]: session-13.scope: Deactivated successfully. Dec 16 13:06:27.755220 systemd-logind[1875]: Session 13 logged out. Waiting for processes to exit. Dec 16 13:06:27.756151 systemd-logind[1875]: Removed session 13. Dec 16 13:06:37.852885 systemd[1]: Started sshd@11-10.200.0.4:22-10.200.16.10:34518.service - OpenSSH per-connection server daemon (10.200.16.10:34518). Dec 16 13:06:42.851460 sshd[14011]: Accepted publickey for core from 10.200.16.10 port 34518 ssh2: RSA SHA256:MCL+xU0rVZ+W8lZTao6YvClPjRaWoIZn7yaQaIgbhxY Dec 16 13:06:38.426440 sshd-session[14011]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 16 13:06:38.430823 systemd-logind[1875]: New session 14 of user core. Dec 16 13:06:42.870369 sshd[14014]: Connection closed by 10.200.16.10 port 34518 Dec 16 13:06:42.870502 sshd[14020]: Accepted publickey for core from 10.200.16.10 port 34522 ssh2: RSA SHA256:MCL+xU0rVZ+W8lZTao6YvClPjRaWoIZn7yaQaIgbhxY Dec 16 13:06:38.820711 sshd-session[14011]: pam_unix(sshd:session): session closed for user core Dec 16 13:06:38.452279 systemd[1]: Started session-14.scope - Session 14 of User core. Dec 16 13:06:42.903131 sshd[14023]: Connection closed by 10.200.16.10 port 34522 Dec 16 13:06:39.481850 sshd-session[14020]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 16 13:06:38.825642 systemd[1]: sshd@11-10.200.0.4:22-10.200.16.10:34518.service: Deactivated successfully. Dec 16 13:06:39.875566 sshd-session[14020]: pam_unix(sshd:session): session closed for user core Dec 16 13:06:38.827261 systemd[1]: session-14.scope: Deactivated successfully. Dec 16 13:06:38.827920 systemd-logind[1875]: Session 14 logged out. Waiting for processes to exit. Dec 16 13:06:38.828901 systemd-logind[1875]: Removed session 14. Dec 16 13:06:38.923355 systemd[1]: Started sshd@12-10.200.0.4:22-10.200.16.10:34522.service - OpenSSH per-connection server daemon (10.200.16.10:34522). Dec 16 13:06:39.486072 systemd-logind[1875]: New session 15 of user core. Dec 16 13:06:39.509216 systemd[1]: Started session-15.scope - Session 15 of User core. Dec 16 13:06:39.878357 systemd[1]: sshd@12-10.200.0.4:22-10.200.16.10:34522.service: Deactivated successfully. Dec 16 13:06:39.879956 systemd[1]: session-15.scope: Deactivated successfully. Dec 16 13:06:39.880637 systemd-logind[1875]: Session 15 logged out. Waiting for processes to exit. Dec 16 13:06:39.881516 systemd-logind[1875]: Removed session 15. Dec 16 13:06:48.889118 waagent[2309]: 2025-12-16T13:06:48.887754Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 2] Dec 16 13:06:48.900114 waagent[2309]: 2025-12-16T13:06:48.898666Z INFO ExtHandler Dec 16 13:06:48.900114 waagent[2309]: 2025-12-16T13:06:48.898788Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: e3cb5268-89f7-4953-a1c0-dfc7d2b7027d eTag: 11190373116758389593 source: Fabric] Dec 16 13:06:48.900114 waagent[2309]: 2025-12-16T13:06:48.899188Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Dec 16 13:06:48.900114 waagent[2309]: 2025-12-16T13:06:48.899867Z INFO ExtHandler Dec 16 13:06:48.900114 waagent[2309]: 2025-12-16T13:06:48.899946Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 2] Dec 16 13:06:48.952835 waagent[2309]: 2025-12-16T13:06:48.952792Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Dec 16 13:06:49.030612 waagent[2309]: 2025-12-16T13:06:49.030525Z INFO ExtHandler Downloaded certificate {'thumbprint': '7D7B249FB5F046F90D2E01EC61519BD567AD0818', 'hasPrivateKey': True} Dec 16 13:06:49.036111 waagent[2309]: 2025-12-16T13:06:49.034152Z INFO ExtHandler Fetch goal state completed Dec 16 13:06:49.036111 waagent[2309]: 2025-12-16T13:06:49.034601Z INFO ExtHandler ExtHandler Dec 16 13:06:49.036111 waagent[2309]: 2025-12-16T13:06:49.034674Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_2 channel: WireServer source: Fabric activity: 9efd0cb2-9d28-48b9-aaf1-370a394feacc correlation 31e846c5-401f-4d6e-aad3-4b6b43dd8388 created: 2025-12-16T13:06:40.226753Z] Dec 16 13:06:49.036111 waagent[2309]: 2025-12-16T13:06:49.034976Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Dec 16 13:06:49.036111 waagent[2309]: 2025-12-16T13:06:49.035507Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_2 0 ms] Dec 16 13:06:49.980694 systemd[1]: Started sshd@13-10.200.0.4:22-10.200.16.10:44084.service - OpenSSH per-connection server daemon (10.200.16.10:44084). Dec 16 13:06:50.562976 sshd[14376]: Accepted publickey for core from 10.200.16.10 port 44084 ssh2: RSA SHA256:MCL+xU0rVZ+W8lZTao6YvClPjRaWoIZn7yaQaIgbhxY Dec 16 13:06:50.564699 sshd-session[14376]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 16 13:06:50.575153 systemd-logind[1875]: New session 16 of user core. Dec 16 13:06:50.580258 systemd[1]: Started session-16.scope - Session 16 of User core. Dec 16 13:06:50.967680 sshd[14391]: Connection closed by 10.200.16.10 port 44084 Dec 16 13:06:50.967705 sshd-session[14376]: pam_unix(sshd:session): session closed for user core Dec 16 13:06:50.971299 systemd-logind[1875]: Session 16 logged out. Waiting for processes to exit. Dec 16 13:06:50.973123 systemd[1]: sshd@13-10.200.0.4:22-10.200.16.10:44084.service: Deactivated successfully. Dec 16 13:06:50.974865 systemd[1]: session-16.scope: Deactivated successfully. Dec 16 13:06:50.978405 systemd-logind[1875]: Removed session 16. Dec 16 13:06:51.073327 systemd[1]: Started sshd@14-10.200.0.4:22-10.200.16.10:43582.service - OpenSSH per-connection server daemon (10.200.16.10:43582). Dec 16 13:06:51.655064 sshd[14405]: Accepted publickey for core from 10.200.16.10 port 43582 ssh2: RSA SHA256:MCL+xU0rVZ+W8lZTao6YvClPjRaWoIZn7yaQaIgbhxY Dec 16 13:06:51.656797 sshd-session[14405]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 16 13:06:51.665581 systemd-logind[1875]: New session 17 of user core. Dec 16 13:06:51.673594 systemd[1]: Started session-17.scope - Session 17 of User core. Dec 16 13:06:52.056503 sshd[14425]: Connection closed by 10.200.16.10 port 43582 Dec 16 13:06:52.057280 sshd-session[14405]: pam_unix(sshd:session): session closed for user core Dec 16 13:06:52.062635 systemd-logind[1875]: Session 17 logged out. Waiting for processes to exit. Dec 16 13:06:52.065466 systemd[1]: sshd@14-10.200.0.4:22-10.200.16.10:43582.service: Deactivated successfully. Dec 16 13:06:52.067245 systemd[1]: session-17.scope: Deactivated successfully. Dec 16 13:06:52.070477 systemd-logind[1875]: Removed session 17. Dec 16 13:07:02.161759 systemd[1]: Started sshd@15-10.200.0.4:22-10.200.16.10:35510.service - OpenSSH per-connection server daemon (10.200.16.10:35510). Dec 16 13:07:02.750041 sshd[14766]: Accepted publickey for core from 10.200.16.10 port 35510 ssh2: RSA SHA256:MCL+xU0rVZ+W8lZTao6YvClPjRaWoIZn7yaQaIgbhxY Dec 16 13:07:02.751440 sshd-session[14766]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 16 13:07:02.759053 systemd-logind[1875]: New session 18 of user core. Dec 16 13:07:02.766808 systemd[1]: Started session-18.scope - Session 18 of User core. Dec 16 13:07:03.148568 sshd[14783]: Connection closed by 10.200.16.10 port 35510 Dec 16 13:07:03.155212 sshd-session[14766]: pam_unix(sshd:session): session closed for user core Dec 16 13:07:03.159015 systemd-logind[1875]: Session 18 logged out. Waiting for processes to exit. Dec 16 13:07:03.160508 systemd[1]: sshd@15-10.200.0.4:22-10.200.16.10:35510.service: Deactivated successfully. Dec 16 13:07:03.162721 systemd[1]: session-18.scope: Deactivated successfully. Dec 16 13:07:03.167611 systemd-logind[1875]: Removed session 18. Dec 16 13:07:03.257422 systemd[1]: Started sshd@16-10.200.0.4:22-10.200.16.10:35524.service - OpenSSH per-connection server daemon (10.200.16.10:35524). Dec 16 13:07:03.839120 sshd[14808]: Accepted publickey for core from 10.200.16.10 port 35524 ssh2: RSA SHA256:MCL+xU0rVZ+W8lZTao6YvClPjRaWoIZn7yaQaIgbhxY Dec 16 13:07:03.840365 sshd-session[14808]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 16 13:07:03.849621 systemd-logind[1875]: New session 19 of user core. Dec 16 13:07:03.855737 systemd[1]: Started session-19.scope - Session 19 of User core. Dec 16 13:07:04.239002 sshd[14826]: Connection closed by 10.200.16.10 port 35524 Dec 16 13:07:04.242937 sshd-session[14808]: pam_unix(sshd:session): session closed for user core Dec 16 13:07:04.246462 systemd-logind[1875]: Session 19 logged out. Waiting for processes to exit. Dec 16 13:07:04.247824 systemd[1]: sshd@16-10.200.0.4:22-10.200.16.10:35524.service: Deactivated successfully. Dec 16 13:07:04.249580 systemd[1]: session-19.scope: Deactivated successfully. Dec 16 13:07:04.252041 systemd-logind[1875]: Removed session 19. Dec 16 13:07:14.347747 systemd[1]: Started sshd@17-10.200.0.4:22-10.200.16.10:32918.service - OpenSSH per-connection server daemon (10.200.16.10:32918). Dec 16 13:07:14.914306 sshd[15176]: Accepted publickey for core from 10.200.16.10 port 32918 ssh2: RSA SHA256:MCL+xU0rVZ+W8lZTao6YvClPjRaWoIZn7yaQaIgbhxY Dec 16 13:07:14.915531 sshd-session[15176]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 16 13:07:14.919786 systemd-logind[1875]: New session 20 of user core. Dec 16 13:07:14.940976 systemd[1]: Started session-20.scope - Session 20 of User core. Dec 16 13:07:15.310280 sshd[15198]: Connection closed by 10.200.16.10 port 32918 Dec 16 13:07:15.310680 sshd-session[15176]: pam_unix(sshd:session): session closed for user core Dec 16 13:07:15.314554 systemd[1]: sshd@17-10.200.0.4:22-10.200.16.10:32918.service: Deactivated successfully. Dec 16 13:07:15.316189 systemd[1]: session-20.scope: Deactivated successfully. Dec 16 13:07:15.316890 systemd-logind[1875]: Session 20 logged out. Waiting for processes to exit. Dec 16 13:07:15.317830 systemd-logind[1875]: Removed session 20. Dec 16 13:07:15.412769 systemd[1]: Started sshd@18-10.200.0.4:22-10.200.16.10:32932.service - OpenSSH per-connection server daemon (10.200.16.10:32932). Dec 16 13:07:15.994608 sshd[15204]: Accepted publickey for core from 10.200.16.10 port 32932 ssh2: RSA SHA256:MCL+xU0rVZ+W8lZTao6YvClPjRaWoIZn7yaQaIgbhxY Dec 16 13:07:15.998057 sshd-session[15204]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 16 13:07:16.004242 systemd-logind[1875]: New session 21 of user core. Dec 16 13:07:16.016272 systemd[1]: Started session-21.scope - Session 21 of User core. Dec 16 13:07:16.393669 sshd[15207]: Connection closed by 10.200.16.10 port 32932 Dec 16 13:07:16.394065 sshd-session[15204]: pam_unix(sshd:session): session closed for user core Dec 16 13:07:16.397751 systemd[1]: sshd@18-10.200.0.4:22-10.200.16.10:32932.service: Deactivated successfully. Dec 16 13:07:16.399343 systemd[1]: session-21.scope: Deactivated successfully. Dec 16 13:07:16.400026 systemd-logind[1875]: Session 21 logged out. Waiting for processes to exit. Dec 16 13:07:16.400926 systemd-logind[1875]: Removed session 21. Dec 16 13:07:23.665899 setup-nvidia[2540]: \u000d Dec 16 13:07:23.665899 setup-nvidia[2540]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Dec 16 13:07:23.665899 setup-nvidia[2540]: frequently when this kernel module was built against the wrong or Dec 16 13:07:23.665899 setup-nvidia[2540]: improperly configured kernel sources, with a version of gcc that differs Dec 16 13:07:23.665899 setup-nvidia[2540]: from the one used to build the target kernel, or if another driver, such Dec 16 13:07:23.665899 setup-nvidia[2540]: as nouveau, is present and prevents the NVIDIA kernel module from Dec 16 13:07:23.665899 setup-nvidia[2540]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Dec 16 13:07:23.665899 setup-nvidia[2540]: installed in this system is supported by this NVIDIA Linux graphics Dec 16 13:07:23.665899 setup-nvidia[2540]: driver release. Dec 16 13:07:23.665899 setup-nvidia[2540]: \u000d Dec 16 13:07:23.665899 setup-nvidia[2540]: Please see the log entries 'Kernel module load error' and 'Kernel Dec 16 13:07:23.665899 setup-nvidia[2540]: messages' at the end of the file Dec 16 13:07:23.665899 setup-nvidia[2540]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for more Dec 16 13:07:23.665899 setup-nvidia[2540]: information. Dec 16 13:07:23.665899 setup-nvidia[2540]: \u000d Dec 16 13:07:23.680679 setup-nvidia[2540]: dmesg: read kernel buffer failed: Operation not permitted Dec 16 13:07:23.699240 setup-nvidia[2540]: \u000d Dec 16 13:07:23.699240 setup-nvidia[2540]: ERROR: Installation has failed. Please see the file Dec 16 13:07:23.699240 setup-nvidia[2540]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for Dec 16 13:07:23.699240 setup-nvidia[2540]: details. You may find suggestions on fixing installation problems in Dec 16 13:07:23.699240 setup-nvidia[2540]: the README available on the Linux driver download page at Dec 16 13:07:23.699240 setup-nvidia[2540]: www.nvidia.com. Dec 16 13:07:23.699240 setup-nvidia[2540]: \u000d Dec 16 13:07:23.699595 setup-nvidia[2540]: Last 50 lines of nvidia-installer.log: Dec 16 13:07:23.700435 setup-nvidia[2540]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039059rm+0x53: 'naked' return found in MITIGATION_RETHUNK build Dec 16 13:07:23.700435 setup-nvidia[2540]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039056rm+0x90: 'naked' return found in MITIGATION_RETHUNK build Dec 16 13:07:23.700435 setup-nvidia[2540]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039049rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build Dec 16 13:07:23.700435 setup-nvidia[2540]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039105rm+0x93: 'naked' return found in MITIGATION_RETHUNK build Dec 16 13:07:23.700435 setup-nvidia[2540]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039101rm+0x36: 'naked' return found in MITIGATION_RETHUNK build Dec 16 13:07:23.700435 setup-nvidia[2540]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039100rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build Dec 16 13:07:23.700435 setup-nvidia[2540]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039102rm+0x77: 'naked' return found in MITIGATION_RETHUNK build Dec 16 13:07:23.700435 setup-nvidia[2540]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039046rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build Dec 16 13:07:23.700435 setup-nvidia[2540]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030615rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build Dec 16 13:07:23.700435 setup-nvidia[2540]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030614rm+0x27: 'naked' return found in MITIGATION_RETHUNK build Dec 16 13:07:23.700435 setup-nvidia[2540]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030618rm+0x37: 'naked' return found in MITIGATION_RETHUNK build Dec 16 13:07:23.700435 setup-nvidia[2540]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030617rm+0x24: 'naked' return found in MITIGATION_RETHUNK build Dec 16 13:07:23.700435 setup-nvidia[2540]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030616rm+0xca: 'naked' return found in MITIGATION_RETHUNK build Dec 16 13:07:23.700435 setup-nvidia[2540]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030619rm+0x193: 'naked' return found in MITIGATION_RETHUNK build Dec 16 13:07:23.700435 setup-nvidia[2540]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030621rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build Dec 16 13:07:23.700435 setup-nvidia[2540]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv011864rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build Dec 16 13:07:23.700435 setup-nvidia[2540]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039413rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build Dec 16 13:07:23.700435 setup-nvidia[2540]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/Module.symvers Dec 16 13:07:23.700435 setup-nvidia[2540]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.mod.o Dec 16 13:07:23.700435 setup-nvidia[2540]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/.module-common.o Dec 16 13:07:23.700435 setup-nvidia[2540]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.mod.o Dec 16 13:07:23.700435 setup-nvidia[2540]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.mod.o Dec 16 13:07:23.700435 setup-nvidia[2540]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.mod.o Dec 16 13:07:23.700435 setup-nvidia[2540]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.mod.o Dec 16 13:07:23.700435 setup-nvidia[2540]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Dec 16 13:07:23.700435 setup-nvidia[2540]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Dec 16 13:07:23.700435 setup-nvidia[2540]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Dec 16 13:07:23.700435 setup-nvidia[2540]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Dec 16 13:07:23.700435 setup-nvidia[2540]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Dec 16 13:07:23.700435 setup-nvidia[2540]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko due to unavailability of vmlinux Dec 16 13:07:23.700435 setup-nvidia[2540]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Dec 16 13:07:23.700435 setup-nvidia[2540]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko due to unavailability of vmlinux Dec 16 13:07:23.700435 setup-nvidia[2540]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Dec 16 13:07:23.700435 setup-nvidia[2540]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko due to unavailability of vmlinux Dec 16 13:07:23.700435 setup-nvidia[2540]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Dec 16 13:07:23.700435 setup-nvidia[2540]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Dec 16 13:07:23.700435 setup-nvidia[2540]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko due to unavailability of vmlinux Dec 16 13:07:23.700435 setup-nvidia[2540]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Dec 16 13:07:23.700435 setup-nvidia[2540]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko due to unavailability of vmlinux Dec 16 13:07:23.700435 setup-nvidia[2540]: make[2]: Leaving directory '/usr/lib/modules/6.12.61-flatcar/build' Dec 16 13:07:23.700435 setup-nvidia[2540]: make[1]: Leaving directory '/usr/lib/modules/6.12.61-flatcar/source' Dec 16 13:07:23.700435 setup-nvidia[2540]: -> done. Dec 16 13:07:23.700435 setup-nvidia[2540]: -> Kernel module compilation complete. Dec 16 13:07:23.700435 setup-nvidia[2540]: -> Unable to determine if Secure Boot is enabled: No such file or directory Dec 16 13:07:23.701529 setup-nvidia[2540]: 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 16 13:07:23.701529 setup-nvidia[2540]: \u000d Dec 16 13:07:23.701529 setup-nvidia[2540]: 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 16 13:07:23.701529 setup-nvidia[2540]: -> Kernel module load error: Operation not permitted Dec 16 13:07:23.701529 setup-nvidia[2540]: -> Kernel messages: Dec 16 13:07:23.701529 setup-nvidia[2540]: 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 16 13:07:26.498400 systemd[1]: Started sshd@19-10.200.0.4:22-10.200.16.10:37076.service - OpenSSH per-connection server daemon (10.200.16.10:37076). Dec 16 13:07:27.057736 sshd[15393]: Accepted publickey for core from 10.200.16.10 port 37076 ssh2: RSA SHA256:MCL+xU0rVZ+W8lZTao6YvClPjRaWoIZn7yaQaIgbhxY Dec 16 13:07:27.058968 sshd-session[15393]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 16 13:07:27.064623 systemd-logind[1875]: New session 22 of user core. Dec 16 13:07:27.074246 systemd[1]: Started session-22.scope - Session 22 of User core. Dec 16 13:07:27.459661 sshd[15396]: Connection closed by 10.200.16.10 port 37076 Dec 16 13:07:27.459949 sshd-session[15393]: pam_unix(sshd:session): session closed for user core Dec 16 13:07:27.463416 systemd[1]: sshd@19-10.200.0.4:22-10.200.16.10:37076.service: Deactivated successfully. Dec 16 13:07:27.464993 systemd[1]: session-22.scope: Deactivated successfully. Dec 16 13:07:27.465752 systemd-logind[1875]: Session 22 logged out. Waiting for processes to exit. Dec 16 13:07:27.466668 systemd-logind[1875]: Removed session 22. Dec 16 13:07:27.557372 systemd[1]: Started sshd@20-10.200.0.4:22-10.200.16.10:37090.service - OpenSSH per-connection server daemon (10.200.16.10:37090). Dec 16 13:07:28.136782 sshd[15402]: Accepted publickey for core from 10.200.16.10 port 37090 ssh2: RSA SHA256:MCL+xU0rVZ+W8lZTao6YvClPjRaWoIZn7yaQaIgbhxY Dec 16 13:07:28.138003 sshd-session[15402]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 16 13:07:28.142297 systemd-logind[1875]: New session 23 of user core. Dec 16 13:07:28.152214 systemd[1]: Started session-23.scope - Session 23 of User core. Dec 16 13:07:28.530931 sshd[15405]: Connection closed by 10.200.16.10 port 37090 Dec 16 13:07:28.531328 sshd-session[15402]: pam_unix(sshd:session): session closed for user core Dec 16 13:07:28.534906 systemd[1]: sshd@20-10.200.0.4:22-10.200.16.10:37090.service: Deactivated successfully. Dec 16 13:07:28.536649 systemd[1]: session-23.scope: Deactivated successfully. Dec 16 13:07:28.537398 systemd-logind[1875]: Session 23 logged out. Waiting for processes to exit. Dec 16 13:07:28.538418 systemd-logind[1875]: Removed session 23. Dec 16 13:07:38.635841 systemd[1]: Started sshd@21-10.200.0.4:22-10.200.16.10:53790.service - OpenSSH per-connection server daemon (10.200.16.10:53790). Dec 16 13:07:39.193636 sshd[15412]: Accepted publickey for core from 10.200.16.10 port 53790 ssh2: RSA SHA256:MCL+xU0rVZ+W8lZTao6YvClPjRaWoIZn7yaQaIgbhxY Dec 16 13:07:39.194883 sshd-session[15412]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 16 13:07:39.199097 systemd-logind[1875]: New session 24 of user core. Dec 16 13:07:39.206213 systemd[1]: Started session-24.scope - Session 24 of User core. Dec 16 13:07:39.590320 sshd[15415]: Connection closed by 10.200.16.10 port 53790 Dec 16 13:07:39.590699 sshd-session[15412]: pam_unix(sshd:session): session closed for user core Dec 16 13:07:39.594204 systemd[1]: sshd@21-10.200.0.4:22-10.200.16.10:53790.service: Deactivated successfully. Dec 16 13:07:39.595942 systemd[1]: session-24.scope: Deactivated successfully. Dec 16 13:07:39.596698 systemd-logind[1875]: Session 24 logged out. Waiting for processes to exit. Dec 16 13:07:39.597600 systemd-logind[1875]: Removed session 24. Dec 16 13:07:39.689758 systemd[1]: Started sshd@22-10.200.0.4:22-10.200.16.10:53802.service - OpenSSH per-connection server daemon (10.200.16.10:53802). Dec 16 13:07:40.265341 sshd[15421]: Accepted publickey for core from 10.200.16.10 port 53802 ssh2: RSA SHA256:MCL+xU0rVZ+W8lZTao6YvClPjRaWoIZn7yaQaIgbhxY Dec 16 13:07:40.266609 sshd-session[15421]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 16 13:07:40.270921 systemd-logind[1875]: New session 25 of user core. Dec 16 13:07:40.289230 systemd[1]: Started session-25.scope - Session 25 of User core. Dec 16 13:07:40.311110 kernel: EXT4-fs (loop6p9): unmounting filesystem 02782f9c-2fea-4b2e-9279-7381cb6c9c28. Dec 16 13:07:40.311587 setup-nvidia[2540]: Container flatcardevelopercontainer-4459.2.2.bin exited successfully. Dec 16 13:07:40.313748 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4459.2.2.bin.mount: Deactivated successfully. Dec 16 13:07:40.370857 sudo[2524]: pam_unix(sudo:session): session closed for user root Dec 16 13:07:40.372728 systemd[1]: session-c2.scope: Deactivated successfully. Dec 16 13:07:40.372990 systemd[1]: session-c2.scope: Consumed 5min 25.063s CPU time, 889.8M memory peak. Dec 16 13:07:40.661924 sshd[15424]: Connection closed by 10.200.16.10 port 53802 Dec 16 13:07:40.662247 sshd-session[15421]: pam_unix(sshd:session): session closed for user core Dec 16 13:07:40.666226 systemd[1]: sshd@22-10.200.0.4:22-10.200.16.10:53802.service: Deactivated successfully. Dec 16 13:07:40.667822 systemd[1]: session-25.scope: Deactivated successfully. Dec 16 13:07:40.669440 systemd-logind[1875]: Session 25 logged out. Waiting for processes to exit. Dec 16 13:07:40.670392 systemd-logind[1875]: Removed session 25. Dec 16 13:07:40.838043 setup-nvidia[1871]: /opt/nvidia / Dec 16 13:07:40.839049 setup-nvidia[1871]: / Dec 16 13:07:40.854116 kernel: IPMI message handler: version 39.2 Dec 16 13:07:40.857120 kernel: ipmi device interface Dec 16 13:07:40.870375 setup-nvidia[1871]: /opt/nvidia/current/usr/lib/modules/6.12.61-flatcar/video / Dec 16 13:07:40.925828 kernel: nvidia: loading out-of-tree module taints kernel. Dec 16 13:07:40.925972 kernel: nvidia: module license 'NVIDIA' taints kernel. Dec 16 13:07:40.925998 kernel: Disabling lock debugging due to kernel taint Dec 16 13:07:40.935936 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Dec 16 13:07:40.936002 kernel: nvidia: module license taints kernel. Dec 16 13:07:41.334123 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 Dec 16 13:07:41.744052 kernel: Dec 16 13:07:41.744206 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Dec 16 13:07:41.952110 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.261.03 Sat Jun 14 16:05:50 UTC 2025 Dec 16 13:07:41.972114 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.261.03 Sat Jun 14 15:39:16 UTC 2025 Dec 16 13:07:41.991123 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Dec 16 13:07:42.038115 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. Dec 16 13:07:42.038194 setup-nvidia[1871]: / Dec 16 13:07:42.053134 setup-nvidia[15463]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Dec 16 13:07:43.843506 setup-nvidia[15480]: Tue Dec 16 13:07:43 2025 Dec 16 13:07:43.843506 setup-nvidia[15480]: +---------------------------------------------------------------------------------------+ Dec 16 13:07:43.843506 setup-nvidia[15480]: | NVIDIA-SMI 535.261.03 Driver Version: 535.261.03 CUDA Version: 12.2 | Dec 16 13:07:43.843506 setup-nvidia[15480]: |-----------------------------------------+----------------------+----------------------+ Dec 16 13:07:43.843506 setup-nvidia[15480]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Dec 16 13:07:43.843506 setup-nvidia[15480]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Dec 16 13:07:43.843506 setup-nvidia[15480]: | | | MIG M. | Dec 16 13:07:43.843506 setup-nvidia[15480]: |=========================================+======================+======================| Dec 16 13:07:43.847012 setup-nvidia[15480]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Dec 16 13:07:43.847012 setup-nvidia[15480]: | N/A 31C P0 37W / 250W | 0MiB / 16384MiB | 0% Default | Dec 16 13:07:43.847012 setup-nvidia[15480]: | | | N/A | Dec 16 13:07:43.847012 setup-nvidia[15480]: +-----------------------------------------+----------------------+----------------------+ Dec 16 13:07:43.847149 setup-nvidia[15480]: Dec 16 13:07:43.847149 setup-nvidia[15480]: +---------------------------------------------------------------------------------------+ Dec 16 13:07:43.847149 setup-nvidia[15480]: | Processes: | Dec 16 13:07:43.847149 setup-nvidia[15480]: | GPU GI CI PID Type Process name GPU Memory | Dec 16 13:07:43.847149 setup-nvidia[15480]: | ID ID Usage | Dec 16 13:07:43.847149 setup-nvidia[15480]: |=======================================================================================| Dec 16 13:07:43.847278 setup-nvidia[15480]: | No running processes found | Dec 16 13:07:43.847278 setup-nvidia[15480]: +---------------------------------------------------------------------------------------+ Dec 16 13:07:44.224019 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Dec 16 13:07:44.226020 systemd[1]: Reached target multi-user.target - Multi-User System. Dec 16 13:07:44.226436 systemd[1]: Startup finished in 1.321s (firmware) + 25.441s (loader) + 3.705s (kernel) + 16.166s (initrd) + 3min 47.904s (userspace) = 4min 34.540s. Dec 16 13:07:50.502861 systemd[1]: Stopping user@0.service - User Manager for UID 0... Dec 16 13:07:50.503641 systemd[2531]: Activating special unit exit.target... Dec 16 13:07:50.503721 systemd[2531]: Stopped target default.target - Main User Target. Dec 16 13:07:50.503759 systemd[2531]: Stopped target basic.target - Basic System. Dec 16 13:07:50.503785 systemd[2531]: Stopped target paths.target - Paths. Dec 16 13:07:50.503811 systemd[2531]: Stopped target sockets.target - Sockets. Dec 16 13:07:50.503836 systemd[2531]: Stopped target timers.target - Timers. Dec 16 13:07:50.504203 systemd[2531]: Closed dbus.socket - D-Bus User Message Bus Socket. Dec 16 13:07:50.504414 systemd[2531]: Removed slice app.slice - User Application Slice. Dec 16 13:07:50.504446 systemd[2531]: Reached target shutdown.target - Shutdown. Dec 16 13:07:50.504578 systemd[2531]: Finished systemd-exit.service - Exit the Session. Dec 16 13:07:50.504613 systemd[2531]: Reached target exit.target - Exit the Session. Dec 16 13:07:50.518618 (sd-pam)[2532]: pam_unix(systemd-user:session): session closed for user root Dec 16 13:07:50.519965 systemd[1]: user@0.service: Deactivated successfully. Dec 16 13:07:50.520350 systemd[1]: Stopped user@0.service - User Manager for UID 0. Dec 16 13:07:50.522618 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Dec 16 13:07:50.551954 systemd[1]: run-user-0.mount: Deactivated successfully. Dec 16 13:07:50.552907 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Dec 16 13:07:50.553126 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Dec 16 13:07:50.554718 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Dec 16 13:07:50.554807 systemd[1]: user-0.slice: Consumed 5min 25.292s CPU time, 895.9M memory peak. Dec 16 13:07:50.555219 systemd-logind[1875]: Removed session c3. Dec 16 13:07:50.767704 systemd[1]: Started sshd@23-10.200.0.4:22-10.200.16.10:44316.service - OpenSSH per-connection server daemon (10.200.16.10:44316). Dec 16 13:07:51.339532 sshd[15491]: Accepted publickey for core from 10.200.16.10 port 44316 ssh2: RSA SHA256:MCL+xU0rVZ+W8lZTao6YvClPjRaWoIZn7yaQaIgbhxY Dec 16 13:07:51.340763 sshd-session[15491]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 16 13:07:51.345213 systemd-logind[1875]: New session 26 of user core. Dec 16 13:07:51.365211 systemd[1]: Started session-26.scope - Session 26 of User core. Dec 16 13:07:51.734984 sshd[15494]: Connection closed by 10.200.16.10 port 44316 Dec 16 13:07:51.735317 sshd-session[15491]: pam_unix(sshd:session): session closed for user core Dec 16 13:07:51.738900 systemd[1]: sshd@23-10.200.0.4:22-10.200.16.10:44316.service: Deactivated successfully. Dec 16 13:07:51.741023 systemd[1]: session-26.scope: Deactivated successfully. Dec 16 13:07:51.741726 systemd-logind[1875]: Session 26 logged out. Waiting for processes to exit. Dec 16 13:07:51.742617 systemd-logind[1875]: Removed session 26. Dec 16 13:07:51.837530 systemd[1]: Started sshd@24-10.200.0.4:22-10.200.16.10:44322.service - OpenSSH per-connection server daemon (10.200.16.10:44322). Dec 16 13:07:52.418076 sshd[15500]: Accepted publickey for core from 10.200.16.10 port 44322 ssh2: RSA SHA256:MCL+xU0rVZ+W8lZTao6YvClPjRaWoIZn7yaQaIgbhxY Dec 16 13:07:52.419326 sshd-session[15500]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 16 13:07:52.423564 systemd-logind[1875]: New session 27 of user core. Dec 16 13:07:52.434214 systemd[1]: Started session-27.scope - Session 27 of User core. Dec 16 13:07:52.808388 sshd[15503]: Connection closed by 10.200.16.10 port 44322 Dec 16 13:07:52.808771 sshd-session[15500]: pam_unix(sshd:session): session closed for user core Dec 16 13:07:52.812325 systemd[1]: sshd@24-10.200.0.4:22-10.200.16.10:44322.service: Deactivated successfully. Dec 16 13:07:52.813906 systemd[1]: session-27.scope: Deactivated successfully. Dec 16 13:07:52.814715 systemd-logind[1875]: Session 27 logged out. Waiting for processes to exit. Dec 16 13:07:52.815616 systemd-logind[1875]: Removed session 27. Dec 16 13:07:52.911461 systemd[1]: Started sshd@25-10.200.0.4:22-10.200.16.10:44330.service - OpenSSH per-connection server daemon (10.200.16.10:44330). Dec 16 13:07:53.489798 sshd[15509]: Accepted publickey for core from 10.200.16.10 port 44330 ssh2: RSA SHA256:MCL+xU0rVZ+W8lZTao6YvClPjRaWoIZn7yaQaIgbhxY Dec 16 13:07:53.491028 sshd-session[15509]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 16 13:07:53.495417 systemd-logind[1875]: New session 28 of user core. Dec 16 13:07:53.506220 systemd[1]: Started session-28.scope - Session 28 of User core. Dec 16 13:07:53.885466 sshd[15513]: Connection closed by 10.200.16.10 port 44330 Dec 16 13:07:53.885852 sshd-session[15509]: pam_unix(sshd:session): session closed for user core Dec 16 13:07:53.889474 systemd[1]: sshd@25-10.200.0.4:22-10.200.16.10:44330.service: Deactivated successfully. Dec 16 13:07:53.891054 systemd[1]: session-28.scope: Deactivated successfully. Dec 16 13:07:53.891771 systemd-logind[1875]: Session 28 logged out. Waiting for processes to exit. Dec 16 13:07:53.892653 systemd-logind[1875]: Removed session 28. Dec 16 13:07:53.988483 systemd[1]: Started sshd@26-10.200.0.4:22-10.200.16.10:44340.service - OpenSSH per-connection server daemon (10.200.16.10:44340). Dec 16 13:07:54.564995 sshd[15519]: Accepted publickey for core from 10.200.16.10 port 44340 ssh2: RSA SHA256:MCL+xU0rVZ+W8lZTao6YvClPjRaWoIZn7yaQaIgbhxY Dec 16 13:07:54.566288 sshd-session[15519]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 16 13:07:54.570586 systemd-logind[1875]: New session 29 of user core. Dec 16 13:07:54.582258 systemd[1]: Started session-29.scope - Session 29 of User core. Dec 16 13:07:54.963406 sshd[15523]: Connection closed by 10.200.16.10 port 44340 Dec 16 13:07:54.963818 sshd-session[15519]: pam_unix(sshd:session): session closed for user core Dec 16 13:07:54.967588 systemd[1]: sshd@26-10.200.0.4:22-10.200.16.10:44340.service: Deactivated successfully. Dec 16 13:07:54.969262 systemd[1]: session-29.scope: Deactivated successfully. Dec 16 13:07:54.969905 systemd-logind[1875]: Session 29 logged out. Waiting for processes to exit. Dec 16 13:07:54.970799 systemd-logind[1875]: Removed session 29. Dec 16 13:07:55.067789 systemd[1]: Started sshd@27-10.200.0.4:22-10.200.16.10:44346.service - OpenSSH per-connection server daemon (10.200.16.10:44346). Dec 16 13:07:55.623610 sshd[15530]: Accepted publickey for core from 10.200.16.10 port 44346 ssh2: RSA SHA256:MCL+xU0rVZ+W8lZTao6YvClPjRaWoIZn7yaQaIgbhxY Dec 16 13:07:55.624850 sshd-session[15530]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 16 13:07:55.630752 systemd-logind[1875]: New session 30 of user core. Dec 16 13:07:55.646205 systemd[1]: Started session-30.scope - Session 30 of User core. Dec 16 13:07:57.515699 sshd[15533]: Connection closed by 10.200.16.10 port 44346 Dec 16 13:07:57.516115 sshd-session[15530]: pam_unix(sshd:session): session closed for user core Dec 16 13:07:57.519947 systemd[1]: sshd@27-10.200.0.4:22-10.200.16.10:44346.service: Deactivated successfully. Dec 16 13:07:57.521539 systemd[1]: session-30.scope: Deactivated successfully. Dec 16 13:07:57.521737 systemd[1]: session-30.scope: Consumed 1.435s CPU time, 14.1M memory peak. Dec 16 13:07:57.522248 systemd-logind[1875]: Session 30 logged out. Waiting for processes to exit. Dec 16 13:07:57.523119 systemd-logind[1875]: Removed session 30.