Jan 23 01:02:46.181520 kernel: Linux version 6.12.66-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 Thu Jan 22 22:22:03 -00 2026 Jan 23 01:02:46.181552 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=e8d7116310bea9a494780b8becdce41e7cc03ed509d8e2363e08981a47b3edc6 Jan 23 01:02:46.181562 kernel: BIOS-provided physical RAM map: Jan 23 01:02:46.181569 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Jan 23 01:02:46.181576 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Jan 23 01:02:46.181583 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Jan 23 01:02:46.181591 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Jan 23 01:02:46.181602 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Jan 23 01:02:46.181610 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Jan 23 01:02:46.181617 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Jan 23 01:02:46.181624 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Jan 23 01:02:46.181631 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Jan 23 01:02:46.181639 kernel: printk: legacy bootconsole [earlyser0] enabled Jan 23 01:02:46.181646 kernel: NX (Execute Disable) protection: active Jan 23 01:02:46.181658 kernel: APIC: Static calls initialized Jan 23 01:02:46.181666 kernel: efi: EFI v2.7 by Microsoft Jan 23 01:02:46.181675 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Jan 23 01:02:46.181683 kernel: random: crng init done Jan 23 01:02:46.181690 kernel: secureboot: Secure boot disabled Jan 23 01:02:46.181698 kernel: SMBIOS 3.1.0 present. Jan 23 01:02:46.181706 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Jan 23 01:02:46.181714 kernel: DMI: Memory slots populated: 3/3 Jan 23 01:02:46.181722 kernel: Hypervisor detected: Microsoft Hyper-V Jan 23 01:02:46.181730 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Jan 23 01:02:46.181741 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Jan 23 01:02:46.181749 kernel: Hyper-V: Using hypercall for remote TLB flush Jan 23 01:02:46.181757 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Jan 23 01:02:46.181778 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Jan 23 01:02:46.181793 kernel: tsc: Marking TSC unstable due to running on Hyper-V Jan 23 01:02:46.181808 kernel: tsc: Detected 2593.992 MHz processor Jan 23 01:02:46.181816 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Jan 23 01:02:46.181825 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Jan 23 01:02:46.181839 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Jan 23 01:02:46.181864 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Jan 23 01:02:46.181882 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Jan 23 01:02:46.181891 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Jan 23 01:02:46.181900 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Jan 23 01:02:46.181908 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Jan 23 01:02:46.181916 kernel: Using GB pages for direct mapping Jan 23 01:02:46.181925 kernel: ACPI: Early table checksum verification disabled Jan 23 01:02:46.181938 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Jan 23 01:02:46.181950 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 23 01:02:46.181959 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 23 01:02:46.181968 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Jan 23 01:02:46.181977 kernel: ACPI: FACS 0x000000003FFFE000 000040 Jan 23 01:02:46.181985 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 23 01:02:46.181994 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 23 01:02:46.182006 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 23 01:02:46.182019 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 23 01:02:46.182028 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 23 01:02:46.182037 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 23 01:02:46.182045 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 23 01:02:46.182054 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Jan 23 01:02:46.182063 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Jan 23 01:02:46.182072 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Jan 23 01:02:46.182083 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Jan 23 01:02:46.182092 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Jan 23 01:02:46.182101 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Jan 23 01:02:46.182110 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Jan 23 01:02:46.182118 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Jan 23 01:02:46.182127 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Jan 23 01:02:46.182136 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Jan 23 01:02:46.182144 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Jan 23 01:02:46.182153 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Jan 23 01:02:46.182165 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Jan 23 01:02:46.182174 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Jan 23 01:02:46.182183 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Jan 23 01:02:46.182191 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Jan 23 01:02:46.182200 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Jan 23 01:02:46.182209 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Jan 23 01:02:46.182218 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] Jan 23 01:02:46.182239 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] Jan 23 01:02:46.182249 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] Jan 23 01:02:46.182263 kernel: Zone ranges: Jan 23 01:02:46.182271 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Jan 23 01:02:46.182280 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Jan 23 01:02:46.182289 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Jan 23 01:02:46.182298 kernel: Device empty Jan 23 01:02:46.182307 kernel: Movable zone start for each node Jan 23 01:02:46.182316 kernel: Early memory node ranges Jan 23 01:02:46.182328 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Jan 23 01:02:46.182337 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Jan 23 01:02:46.182349 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Jan 23 01:02:46.182358 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Jan 23 01:02:46.182366 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Jan 23 01:02:46.182375 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Jan 23 01:02:46.182384 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Jan 23 01:02:46.182392 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Jan 23 01:02:46.182401 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Jan 23 01:02:46.182410 kernel: ACPI: PM-Timer IO Port: 0x408 Jan 23 01:02:46.182419 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Jan 23 01:02:46.182430 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Jan 23 01:02:46.182439 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Jan 23 01:02:46.182448 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Jan 23 01:02:46.182457 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Jan 23 01:02:46.182466 kernel: CPU topo: Max. logical packages: 1 Jan 23 01:02:46.182474 kernel: CPU topo: Max. logical dies: 1 Jan 23 01:02:46.182483 kernel: CPU topo: Max. dies per package: 1 Jan 23 01:02:46.182492 kernel: CPU topo: Max. threads per core: 1 Jan 23 01:02:46.182500 kernel: CPU topo: Num. cores per package: 6 Jan 23 01:02:46.182512 kernel: CPU topo: Num. threads per package: 6 Jan 23 01:02:46.182521 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs Jan 23 01:02:46.182530 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Jan 23 01:02:46.182538 kernel: Booting paravirtualized kernel on Hyper-V Jan 23 01:02:46.182547 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Jan 23 01:02:46.182556 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Jan 23 01:02:46.182565 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 Jan 23 01:02:46.182574 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 Jan 23 01:02:46.182582 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Jan 23 01:02:46.182594 kernel: Hyper-V: PV spinlocks enabled Jan 23 01:02:46.182603 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Jan 23 01:02:46.182612 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=e8d7116310bea9a494780b8becdce41e7cc03ed509d8e2363e08981a47b3edc6 Jan 23 01:02:46.182622 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Jan 23 01:02:46.182630 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Jan 23 01:02:46.182639 kernel: Fallback order for Node 0: 0 Jan 23 01:02:46.182648 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 Jan 23 01:02:46.182656 kernel: Policy zone: Normal Jan 23 01:02:46.182669 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jan 23 01:02:46.182685 kernel: software IO TLB: area num 8. Jan 23 01:02:46.182695 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Jan 23 01:02:46.182707 kernel: Kernel/User page tables isolation: enabled Jan 23 01:02:46.182716 kernel: ftrace: allocating 40097 entries in 157 pages Jan 23 01:02:46.182725 kernel: ftrace: allocated 157 pages with 5 groups Jan 23 01:02:46.182734 kernel: Dynamic Preempt: voluntary Jan 23 01:02:46.182750 kernel: rcu: Preemptible hierarchical RCU implementation. Jan 23 01:02:46.182767 kernel: rcu: RCU event tracing is enabled. Jan 23 01:02:46.182782 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Jan 23 01:02:46.182796 kernel: Trampoline variant of Tasks RCU enabled. Jan 23 01:02:46.182809 kernel: Rude variant of Tasks RCU enabled. Jan 23 01:02:46.182818 kernel: Tracing variant of Tasks RCU enabled. Jan 23 01:02:46.182828 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Jan 23 01:02:46.182837 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Jan 23 01:02:46.182846 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jan 23 01:02:46.182856 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jan 23 01:02:46.182868 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jan 23 01:02:46.182877 kernel: Using NULL legacy PIC Jan 23 01:02:46.182887 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Jan 23 01:02:46.182896 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Jan 23 01:02:46.182905 kernel: Console: colour dummy device 80x25 Jan 23 01:02:46.182915 kernel: printk: legacy console [tty1] enabled Jan 23 01:02:46.182924 kernel: printk: legacy console [ttyS0] enabled Jan 23 01:02:46.182934 kernel: printk: legacy bootconsole [earlyser0] disabled Jan 23 01:02:46.182943 kernel: ACPI: Core revision 20240827 Jan 23 01:02:46.182962 kernel: Failed to register legacy timer interrupt Jan 23 01:02:46.182978 kernel: APIC: Switch to symmetric I/O mode setup Jan 23 01:02:46.182987 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Jan 23 01:02:46.182996 kernel: Hyper-V: enabling crash_kexec_post_notifiers Jan 23 01:02:46.183005 kernel: Hyper-V: Using IPI hypercalls Jan 23 01:02:46.183014 kernel: APIC: send_IPI() replaced with hv_send_ipi() Jan 23 01:02:46.183023 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Jan 23 01:02:46.183032 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Jan 23 01:02:46.183041 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Jan 23 01:02:46.183054 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Jan 23 01:02:46.183063 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Jan 23 01:02:46.183072 kernel: Hyper-V: Using enlightened APIC (xapic mode) Jan 23 01:02:46.183081 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Jan 23 01:02:46.183090 kernel: APIC: read() replaced with hv_apic_read() Jan 23 01:02:46.183100 kernel: APIC: write() replaced with hv_apic_write() Jan 23 01:02:46.183109 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Jan 23 01:02:46.183118 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Jan 23 01:02:46.183127 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593992) Jan 23 01:02:46.183140 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Jan 23 01:02:46.183149 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Jan 23 01:02:46.183158 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Jan 23 01:02:46.183167 kernel: Spectre V2 : Mitigation: Retpolines Jan 23 01:02:46.183176 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Jan 23 01:02:46.183185 kernel: Speculative Store Bypass: Vulnerable Jan 23 01:02:46.183194 kernel: MDS: Mitigation: Clear CPU buffers Jan 23 01:02:46.183203 kernel: TAA: Mitigation: Clear CPU buffers Jan 23 01:02:46.183212 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Jan 23 01:02:46.183220 kernel: active return thunk: its_return_thunk Jan 23 01:02:46.183256 kernel: ITS: Mitigation: Aligned branch/return thunks Jan 23 01:02:46.183271 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Jan 23 01:02:46.183280 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Jan 23 01:02:46.183289 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Jan 23 01:02:46.183298 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Jan 23 01:02:46.183307 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Jan 23 01:02:46.183317 kernel: Freeing SMP alternatives memory: 32K Jan 23 01:02:46.183326 kernel: pid_max: default: 32768 minimum: 301 Jan 23 01:02:46.183335 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Jan 23 01:02:46.183344 kernel: landlock: Up and running. Jan 23 01:02:46.183353 kernel: SELinux: Initializing. Jan 23 01:02:46.183362 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Jan 23 01:02:46.183374 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Jan 23 01:02:46.183383 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Jan 23 01:02:46.183393 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Jan 23 01:02:46.183402 kernel: signal: max sigframe size: 1776 Jan 23 01:02:46.183411 kernel: rcu: Hierarchical SRCU implementation. Jan 23 01:02:46.183421 kernel: rcu: Max phase no-delay instances is 400. Jan 23 01:02:46.183430 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Jan 23 01:02:46.183439 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Jan 23 01:02:46.183449 kernel: smp: Bringing up secondary CPUs ... Jan 23 01:02:46.183461 kernel: smpboot: x86: Booting SMP configuration: Jan 23 01:02:46.183470 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Jan 23 01:02:46.183479 kernel: smp: Brought up 1 node, 6 CPUs Jan 23 01:02:46.183489 kernel: smpboot: Total of 6 processors activated (31127.90 BogoMIPS) Jan 23 01:02:46.183499 kernel: Memory: 115330896K/117439364K available (14336K kernel code, 2445K rwdata, 26064K rodata, 46196K init, 2564K bss, 2098192K reserved, 0K cma-reserved) Jan 23 01:02:46.183508 kernel: devtmpfs: initialized Jan 23 01:02:46.183517 kernel: x86/mm: Memory block size: 1024MB Jan 23 01:02:46.183526 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Jan 23 01:02:46.183536 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Jan 23 01:02:46.183548 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Jan 23 01:02:46.183557 kernel: pinctrl core: initialized pinctrl subsystem Jan 23 01:02:46.183567 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jan 23 01:02:46.183576 kernel: audit: initializing netlink subsys (disabled) Jan 23 01:02:46.183585 kernel: audit: type=2000 audit(1769130162.033:1): state=initialized audit_enabled=0 res=1 Jan 23 01:02:46.183594 kernel: thermal_sys: Registered thermal governor 'step_wise' Jan 23 01:02:46.183604 kernel: thermal_sys: Registered thermal governor 'user_space' Jan 23 01:02:46.183613 kernel: cpuidle: using governor menu Jan 23 01:02:46.183622 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Jan 23 01:02:46.183634 kernel: dca service started, version 1.12.1 Jan 23 01:02:46.183643 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Jan 23 01:02:46.183684 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Jan 23 01:02:46.183694 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Jan 23 01:02:46.183703 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Jan 23 01:02:46.183713 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Jan 23 01:02:46.183722 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Jan 23 01:02:46.183731 kernel: ACPI: Added _OSI(Module Device) Jan 23 01:02:46.183740 kernel: ACPI: Added _OSI(Processor Device) Jan 23 01:02:46.183754 kernel: ACPI: Added _OSI(Processor Aggregator Device) Jan 23 01:02:46.183764 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Jan 23 01:02:46.183773 kernel: ACPI: Interpreter enabled Jan 23 01:02:46.183782 kernel: ACPI: PM: (supports S0 S5) Jan 23 01:02:46.183791 kernel: ACPI: Using IOAPIC for interrupt routing Jan 23 01:02:46.183800 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Jan 23 01:02:46.183810 kernel: PCI: Ignoring E820 reservations for host bridge windows Jan 23 01:02:46.183819 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Jan 23 01:02:46.183828 kernel: iommu: Default domain type: Translated Jan 23 01:02:46.183840 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Jan 23 01:02:46.183850 kernel: efivars: Registered efivars operations Jan 23 01:02:46.183859 kernel: PCI: Using ACPI for IRQ routing Jan 23 01:02:46.183868 kernel: PCI: System does not support PCI Jan 23 01:02:46.183877 kernel: vgaarb: loaded Jan 23 01:02:46.183886 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Jan 23 01:02:46.183895 kernel: VFS: Disk quotas dquot_6.6.0 Jan 23 01:02:46.183904 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jan 23 01:02:46.183913 kernel: pnp: PnP ACPI init Jan 23 01:02:46.183925 kernel: pnp: PnP ACPI: found 3 devices Jan 23 01:02:46.183935 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Jan 23 01:02:46.183944 kernel: NET: Registered PF_INET protocol family Jan 23 01:02:46.183953 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Jan 23 01:02:46.183962 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Jan 23 01:02:46.183972 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jan 23 01:02:46.183981 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Jan 23 01:02:46.183990 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jan 23 01:02:46.183999 kernel: TCP: Hash tables configured (established 524288 bind 65536) Jan 23 01:02:46.184011 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jan 23 01:02:46.184020 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jan 23 01:02:46.184029 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jan 23 01:02:46.184039 kernel: NET: Registered PF_XDP protocol family Jan 23 01:02:46.184057 kernel: PCI: CLS 0 bytes, default 64 Jan 23 01:02:46.184067 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Jan 23 01:02:46.184076 kernel: software IO TLB: mapped [mem 0x000000003b5bf000-0x000000003f5bf000] (64MB) Jan 23 01:02:46.184085 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Jan 23 01:02:46.184094 kernel: Initialise system trusted keyrings Jan 23 01:02:46.184108 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Jan 23 01:02:46.184117 kernel: Key type asymmetric registered Jan 23 01:02:46.184126 kernel: Asymmetric key parser 'x509' registered Jan 23 01:02:46.184135 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Jan 23 01:02:46.184144 kernel: io scheduler mq-deadline registered Jan 23 01:02:46.184153 kernel: io scheduler kyber registered Jan 23 01:02:46.184162 kernel: io scheduler bfq registered Jan 23 01:02:46.184171 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Jan 23 01:02:46.184180 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Jan 23 01:02:46.184192 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Jan 23 01:02:46.184202 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Jan 23 01:02:46.184211 kernel: i8042: PNP: No PS/2 controller found. Jan 23 01:02:46.184396 kernel: rtc_cmos 00:02: registered as rtc0 Jan 23 01:02:46.184513 kernel: rtc_cmos 00:02: setting system clock to 2026-01-23T01:02:45 UTC (1769130165) Jan 23 01:02:46.184621 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Jan 23 01:02:46.184635 kernel: intel_pstate: CPU model not supported Jan 23 01:02:46.184650 kernel: efifb: probing for efifb Jan 23 01:02:46.184659 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Jan 23 01:02:46.184669 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Jan 23 01:02:46.184678 kernel: efifb: scrolling: redraw Jan 23 01:02:46.184687 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Jan 23 01:02:46.184697 kernel: Console: switching to colour frame buffer device 128x48 Jan 23 01:02:46.184706 kernel: fb0: EFI VGA frame buffer device Jan 23 01:02:46.184715 kernel: pstore: Using crash dump compression: deflate Jan 23 01:02:46.184725 kernel: pstore: Registered efi_pstore as persistent store backend Jan 23 01:02:46.184736 kernel: NET: Registered PF_INET6 protocol family Jan 23 01:02:46.184746 kernel: Segment Routing with IPv6 Jan 23 01:02:46.184755 kernel: In-situ OAM (IOAM) with IPv6 Jan 23 01:02:46.184764 kernel: NET: Registered PF_PACKET protocol family Jan 23 01:02:46.184774 kernel: Key type dns_resolver registered Jan 23 01:02:46.184783 kernel: IPI shorthand broadcast: enabled Jan 23 01:02:46.184792 kernel: sched_clock: Marking stable (3737005100, 128418100)->(4246342800, -380919600) Jan 23 01:02:46.184801 kernel: registered taskstats version 1 Jan 23 01:02:46.184810 kernel: Loading compiled-in X.509 certificates Jan 23 01:02:46.184820 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.66-flatcar: ed54f39d0282729985c39b8ffa9938cacff38d8a' Jan 23 01:02:46.184832 kernel: Demotion targets for Node 0: null Jan 23 01:02:46.184841 kernel: Key type .fscrypt registered Jan 23 01:02:46.184850 kernel: Key type fscrypt-provisioning registered Jan 23 01:02:46.184859 kernel: ima: No TPM chip found, activating TPM-bypass! Jan 23 01:02:46.184868 kernel: ima: Allocated hash algorithm: sha1 Jan 23 01:02:46.184877 kernel: ima: No architecture policies found Jan 23 01:02:46.184886 kernel: clk: Disabling unused clocks Jan 23 01:02:46.184896 kernel: Warning: unable to open an initial console. Jan 23 01:02:46.184905 kernel: Freeing unused kernel image (initmem) memory: 46196K Jan 23 01:02:46.184917 kernel: Write protecting the kernel read-only data: 40960k Jan 23 01:02:46.184926 kernel: Freeing unused kernel image (rodata/data gap) memory: 560K Jan 23 01:02:46.184936 kernel: Run /init as init process Jan 23 01:02:46.184945 kernel: with arguments: Jan 23 01:02:46.184954 kernel: /init Jan 23 01:02:46.184963 kernel: with environment: Jan 23 01:02:46.184972 kernel: HOME=/ Jan 23 01:02:46.184981 kernel: TERM=linux Jan 23 01:02:46.184992 systemd[1]: Successfully made /usr/ read-only. Jan 23 01:02:46.185007 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) Jan 23 01:02:46.185017 systemd[1]: Detected virtualization microsoft. Jan 23 01:02:46.185027 systemd[1]: Detected architecture x86-64. Jan 23 01:02:46.185036 systemd[1]: Running in initrd. Jan 23 01:02:46.185046 systemd[1]: No hostname configured, using default hostname. Jan 23 01:02:46.185056 systemd[1]: Hostname set to . Jan 23 01:02:46.185065 systemd[1]: Initializing machine ID from random generator. Jan 23 01:02:46.185078 systemd[1]: Queued start job for default target initrd.target. Jan 23 01:02:46.185088 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jan 23 01:02:46.185098 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jan 23 01:02:46.185108 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Jan 23 01:02:46.185118 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jan 23 01:02:46.185128 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Jan 23 01:02:46.185138 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Jan 23 01:02:46.185152 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Jan 23 01:02:46.185162 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Jan 23 01:02:46.185172 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jan 23 01:02:46.185182 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jan 23 01:02:46.185191 systemd[1]: Reached target paths.target - Path Units. Jan 23 01:02:46.185201 systemd[1]: Reached target slices.target - Slice Units. Jan 23 01:02:46.185211 systemd[1]: Reached target swap.target - Swaps. Jan 23 01:02:46.185221 systemd[1]: Reached target timers.target - Timer Units. Jan 23 01:02:46.185257 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Jan 23 01:02:46.185267 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jan 23 01:02:46.185277 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Jan 23 01:02:46.185287 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Jan 23 01:02:46.185297 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jan 23 01:02:46.185307 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jan 23 01:02:46.185317 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jan 23 01:02:46.185327 systemd[1]: Reached target sockets.target - Socket Units. Jan 23 01:02:46.185337 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Jan 23 01:02:46.185351 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jan 23 01:02:46.185361 systemd[1]: Finished network-cleanup.service - Network Cleanup. Jan 23 01:02:46.185371 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). Jan 23 01:02:46.185381 systemd[1]: Starting systemd-fsck-usr.service... Jan 23 01:02:46.185391 systemd[1]: Starting systemd-journald.service - Journal Service... Jan 23 01:02:46.185401 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jan 23 01:02:46.185410 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jan 23 01:02:46.185444 systemd-journald[213]: Collecting audit messages is disabled. Jan 23 01:02:46.185471 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Jan 23 01:02:46.185486 systemd-journald[213]: Journal started Jan 23 01:02:46.185506 systemd-journald[213]: Runtime Journal (/run/log/journal/4945501307534b7ebecf83f5562c31f1) is 8M, max 2.2G, 2.1G free. Jan 23 01:02:46.182992 systemd-modules-load[215]: Inserted module 'overlay' Jan 23 01:02:46.193956 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jan 23 01:02:46.194410 systemd[1]: Started systemd-journald.service - Journal Service. Jan 23 01:02:46.194562 systemd[1]: Finished systemd-fsck-usr.service. Jan 23 01:02:46.196741 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jan 23 01:02:46.197888 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jan 23 01:02:46.241945 systemd-tmpfiles[225]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Jan 23 01:02:46.244846 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jan 23 01:02:46.250272 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jan 23 01:02:46.268618 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jan 23 01:02:46.271614 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jan 23 01:02:46.288838 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jan 23 01:02:46.309068 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Jan 23 01:02:46.312018 systemd-modules-load[215]: Inserted module 'br_netfilter' Jan 23 01:02:46.315660 kernel: Bridge firewalling registered Jan 23 01:02:46.325442 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jan 23 01:02:46.327092 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jan 23 01:02:46.340953 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jan 23 01:02:46.351364 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Jan 23 01:02:46.364509 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jan 23 01:02:46.366135 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jan 23 01:02:46.377818 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jan 23 01:02:46.398046 dracut-cmdline[253]: 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=e8d7116310bea9a494780b8becdce41e7cc03ed509d8e2363e08981a47b3edc6 Jan 23 01:02:46.445653 systemd-resolved[260]: Positive Trust Anchors: Jan 23 01:02:46.445667 systemd-resolved[260]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jan 23 01:02:46.445720 systemd-resolved[260]: 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 Jan 23 01:02:46.448177 systemd-resolved[260]: Defaulting to hostname 'linux'. Jan 23 01:02:46.449193 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jan 23 01:02:46.456404 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jan 23 01:02:46.551249 kernel: SCSI subsystem initialized Jan 23 01:02:46.562253 kernel: Loading iSCSI transport class v2.0-870. Jan 23 01:02:46.574253 kernel: iscsi: registered transport (tcp) Jan 23 01:02:46.598716 kernel: iscsi: registered transport (qla4xxx) Jan 23 01:02:46.598750 kernel: QLogic iSCSI HBA Driver Jan 23 01:02:46.618287 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jan 23 01:02:46.653705 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jan 23 01:02:46.656192 systemd[1]: Reached target network-pre.target - Preparation for Network. Jan 23 01:02:46.714035 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Jan 23 01:02:46.716723 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Jan 23 01:02:46.784249 kernel: raid6: avx2x4 gen() 30670 MB/s Jan 23 01:02:46.803246 kernel: raid6: avx2x2 gen() 31018 MB/s Jan 23 01:02:46.824634 kernel: raid6: avx2x1 gen() 26925 MB/s Jan 23 01:02:46.824661 kernel: raid6: using algorithm avx2x2 gen() 31018 MB/s Jan 23 01:02:46.847469 kernel: raid6: .... xor() 18339 MB/s, rmw enabled Jan 23 01:02:46.847507 kernel: raid6: using avx2x2 recovery algorithm Jan 23 01:02:46.870248 kernel: xor: automatically using best checksumming function avx Jan 23 01:02:47.033259 kernel: Btrfs loaded, zoned=no, fsverity=no Jan 23 01:02:47.040386 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Jan 23 01:02:47.043375 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jan 23 01:02:47.080521 systemd-udevd[467]: Using default interface naming scheme 'v255'. Jan 23 01:02:47.086883 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jan 23 01:02:47.088956 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Jan 23 01:02:47.129822 dracut-pre-trigger[473]: rd.md=0: removing MD RAID activation Jan 23 01:02:47.157997 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Jan 23 01:02:47.160719 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jan 23 01:02:47.234502 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jan 23 01:02:47.252344 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Jan 23 01:02:47.270251 kernel: cryptd: max_cpu_qlen set to 1000 Jan 23 01:02:47.291752 kernel: hv_vmbus: Vmbus version:4.0 Jan 23 01:02:47.291791 kernel: AES CTR mode by8 optimization enabled Jan 23 01:02:47.291524 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jan 23 01:02:47.314774 kernel: hv_vmbus: registering driver hyperv_keyboard Jan 23 01:02:47.314798 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 Jan 23 01:02:47.291697 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jan 23 01:02:47.310216 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Jan 23 01:02:47.315839 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jan 23 01:02:47.324422 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jan 23 01:02:47.342246 kernel: pps_core: LinuxPPS API ver. 1 registered Jan 23 01:02:47.342279 kernel: hid: raw HID events driver (C) Jiri Kosina Jan 23 01:02:47.342292 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Jan 23 01:02:47.346347 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jan 23 01:02:47.346459 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jan 23 01:02:47.354668 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jan 23 01:02:47.380284 kernel: hv_vmbus: registering driver hv_netvsc Jan 23 01:02:47.380327 kernel: PTP clock support registered Jan 23 01:02:47.383241 kernel: hv_vmbus: registering driver hv_storvsc Jan 23 01:02:47.386260 kernel: hv_vmbus: registering driver hv_pci Jan 23 01:02:47.389246 kernel: hv_utils: Registering HyperV Utility Driver Jan 23 01:02:47.389276 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Jan 23 01:02:47.395954 kernel: hv_vmbus: registering driver hv_utils Jan 23 01:02:47.400589 kernel: hv_vmbus: registering driver hid_hyperv Jan 23 01:02:47.400618 kernel: scsi host0: storvsc_host_t Jan 23 01:02:47.400670 kernel: hv_utils: Shutdown IC version 3.2 Jan 23 01:02:47.400684 kernel: scsi host1: storvsc_host_t Jan 23 01:02:47.400846 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Jan 23 01:02:47.400985 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Jan 23 01:02:47.401132 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Jan 23 01:02:47.401280 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Jan 23 01:02:47.401432 kernel: hv_utils: Heartbeat IC version 3.0 Jan 23 01:02:47.401446 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint Jan 23 01:02:47.401472 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] Jan 23 01:02:47.402259 kernel: hv_utils: TimeSync IC version 4.0 Jan 23 01:02:48.560748 systemd-resolved[260]: Clock change detected. Flushing caches. Jan 23 01:02:48.715879 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Jan 23 01:02:48.716134 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] Jan 23 01:02:48.716295 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Jan 23 01:02:48.716315 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Jan 23 01:02:48.716443 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Jan 23 01:02:48.716590 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] Jan 23 01:02:48.716721 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Jan 23 01:02:48.716860 kernel: pci 0001:00:00.0: Enabling HDA controller Jan 23 01:02:48.717009 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Jan 23 01:02:48.717149 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Jan 23 01:02:48.717282 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks Jan 23 01:02:48.717411 kernel: sd 0:0:0:1: [sdb] Write Protect is off Jan 23 01:02:48.717540 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Jan 23 01:02:48.717669 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Jan 23 01:02:48.717798 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#69 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jan 23 01:02:48.717950 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#76 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jan 23 01:02:48.718097 kernel: pci 0001:00:00.0: quirk_nvidia_hda+0x0/0x70 took 24742 usecs Jan 23 01:02:48.718233 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Jan 23 01:02:48.718363 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Jan 23 01:02:48.718489 kernel: sd 0:0:0:0: [sda] Write Protect is off Jan 23 01:02:48.718617 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned Jan 23 01:02:48.718746 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Jan 23 01:02:48.718876 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned Jan 23 01:02:48.719024 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Jan 23 01:02:48.719158 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned Jan 23 01:02:48.719286 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#455 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jan 23 01:02:48.719404 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#582 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jan 23 01:02:48.719520 kernel: sdb: sdb1 Jan 23 01:02:48.719534 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk Jan 23 01:02:48.708891 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jan 23 01:02:48.727620 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Jan 23 01:02:48.731977 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Jan 23 01:02:48.739667 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Jan 23 01:02:48.739843 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Jan 23 01:02:48.741989 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Jan 23 01:02:49.153538 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Jan 23 01:02:49.223997 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Jan 23 01:02:49.229390 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Jan 23 01:02:49.253184 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Jan 23 01:02:49.270002 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Jan 23 01:02:49.279313 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Jan 23 01:02:49.319449 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#632 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jan 23 01:02:49.333973 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Jan 23 01:02:49.342230 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#592 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jan 23 01:02:49.365977 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Jan 23 01:02:50.373402 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#122 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jan 23 01:02:50.391005 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Jan 23 01:02:50.391413 disk-uuid[706]: The operation has completed successfully. Jan 23 01:02:51.768004 systemd[1]: disk-uuid.service: Deactivated successfully. Jan 23 01:02:51.768120 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Jan 23 01:02:51.782085 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Jan 23 01:02:51.791750 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Jan 23 01:02:51.801966 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jan 23 01:02:51.811530 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jan 23 01:02:51.820790 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Jan 23 01:02:51.842573 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Jan 23 01:02:51.851290 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Jan 23 01:02:51.861717 sh[878]: Success Jan 23 01:02:51.899604 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Jan 23 01:02:51.899642 kernel: device-mapper: uevent: version 1.0.3 Jan 23 01:02:51.904505 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Jan 23 01:02:51.916009 kernel: device-mapper: verity: sha256 using shash "sha256-avx2" Jan 23 01:02:52.146332 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Jan 23 01:02:52.155600 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Jan 23 01:02:52.180474 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Jan 23 01:02:52.200974 kernel: BTRFS: device fsid f8eb2396-46b8-49a3-a8e7-cd8ad10a3ce4 devid 1 transid 37 /dev/mapper/usr (254:0) scanned by mount (896) Jan 23 01:02:52.210659 kernel: BTRFS info (device dm-0): first mount of filesystem f8eb2396-46b8-49a3-a8e7-cd8ad10a3ce4 Jan 23 01:02:52.210694 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Jan 23 01:02:52.501447 kernel: BTRFS info (device dm-0): disabling log replay at mount time Jan 23 01:02:52.501527 kernel: BTRFS info (device dm-0): enabling free space tree Jan 23 01:02:52.544775 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Jan 23 01:02:52.552358 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Jan 23 01:02:52.561615 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Jan 23 01:02:52.570335 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Jan 23 01:02:52.591518 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Jan 23 01:02:52.625010 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (926) Jan 23 01:02:52.634672 kernel: BTRFS info (device sda6): first mount of filesystem a3ccc207-e674-4ba2-b6d8-404b4581ba01 Jan 23 01:02:52.634707 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Jan 23 01:02:52.658240 kernel: BTRFS info (device sda6): turning on async discard Jan 23 01:02:52.658282 kernel: BTRFS info (device sda6): enabling free space tree Jan 23 01:02:52.667981 kernel: BTRFS info (device sda6): last unmount of filesystem a3ccc207-e674-4ba2-b6d8-404b4581ba01 Jan 23 01:02:52.668189 systemd[1]: Finished ignition-setup.service - Ignition (setup). Jan 23 01:02:52.669978 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Jan 23 01:02:52.705110 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jan 23 01:02:52.708384 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jan 23 01:02:52.758492 systemd-networkd[1065]: lo: Link UP Jan 23 01:02:52.758504 systemd-networkd[1065]: lo: Gained carrier Jan 23 01:02:52.759518 systemd-networkd[1065]: Enumeration completed Jan 23 01:02:52.759614 systemd[1]: Started systemd-networkd.service - Network Configuration. Jan 23 01:02:52.759883 systemd-networkd[1065]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jan 23 01:02:52.759888 systemd-networkd[1065]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jan 23 01:02:52.760681 systemd-networkd[1065]: eth0: Link UP Jan 23 01:02:52.760872 systemd-networkd[1065]: eth0: Gained carrier Jan 23 01:02:52.760882 systemd-networkd[1065]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jan 23 01:02:52.765679 systemd[1]: Reached target network.target - Network. Jan 23 01:02:52.820989 systemd-networkd[1065]: eth0: DHCPv4 address 10.200.8.20/24, gateway 10.200.8.1 acquired from 168.63.129.16 Jan 23 01:02:53.577663 ignition[1040]: Ignition 2.22.0 Jan 23 01:02:53.577677 ignition[1040]: Stage: fetch-offline Jan 23 01:02:53.585847 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Jan 23 01:02:53.577862 ignition[1040]: no configs at "/usr/lib/ignition/base.d" Jan 23 01:02:53.588419 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Jan 23 01:02:53.577887 ignition[1040]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jan 23 01:02:53.578007 ignition[1040]: parsed url from cmdline: "" Jan 23 01:02:53.578012 ignition[1040]: no config URL provided Jan 23 01:02:53.578018 ignition[1040]: reading system config file "/usr/lib/ignition/user.ign" Jan 23 01:02:53.578029 ignition[1040]: no config at "/usr/lib/ignition/user.ign" Jan 23 01:02:53.578034 ignition[1040]: failed to fetch config: resource requires networking Jan 23 01:02:53.578193 ignition[1040]: Ignition finished successfully Jan 23 01:02:53.637436 ignition[1079]: Ignition 2.22.0 Jan 23 01:02:53.637451 ignition[1079]: Stage: fetch Jan 23 01:02:53.637761 ignition[1079]: no configs at "/usr/lib/ignition/base.d" Jan 23 01:02:53.637776 ignition[1079]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jan 23 01:02:53.637915 ignition[1079]: parsed url from cmdline: "" Jan 23 01:02:53.637920 ignition[1079]: no config URL provided Jan 23 01:02:53.637927 ignition[1079]: reading system config file "/usr/lib/ignition/user.ign" Jan 23 01:02:53.637939 ignition[1079]: no config at "/usr/lib/ignition/user.ign" Jan 23 01:02:53.638003 ignition[1079]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Jan 23 01:02:53.732453 ignition[1079]: GET result: OK Jan 23 01:02:53.732498 ignition[1079]: config has been read from IMDS userdata Jan 23 01:02:53.732510 ignition[1079]: parsing config with SHA512: 38f17a769707b523eed305a9e344abde35a014bd8b2673b4fdd933bd9003768b32113508fd3f0669178f9fa3d8c71056cd073ac0fd39adc640df5077d893abbe Jan 23 01:02:53.737193 unknown[1079]: fetched base config from "system" Jan 23 01:02:53.737601 ignition[1079]: fetch: fetch complete Jan 23 01:02:53.737200 unknown[1079]: fetched base config from "system" Jan 23 01:02:53.737607 ignition[1079]: fetch: fetch passed Jan 23 01:02:53.737210 unknown[1079]: fetched user config from "azure" Jan 23 01:02:53.737659 ignition[1079]: Ignition finished successfully Jan 23 01:02:53.741036 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Jan 23 01:02:53.744317 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Jan 23 01:02:53.791241 ignition[1089]: Ignition 2.22.0 Jan 23 01:02:53.791254 ignition[1089]: Stage: kargs Jan 23 01:02:53.791494 ignition[1089]: no configs at "/usr/lib/ignition/base.d" Jan 23 01:02:53.791506 ignition[1089]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jan 23 01:02:53.791963 ignition[1089]: kargs: kargs passed Jan 23 01:02:53.800077 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Jan 23 01:02:53.792006 ignition[1089]: Ignition finished successfully Jan 23 01:02:53.808929 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Jan 23 01:02:53.856215 ignition[1099]: Ignition 2.22.0 Jan 23 01:02:53.856229 ignition[1099]: Stage: disks Jan 23 01:02:53.856467 ignition[1099]: no configs at "/usr/lib/ignition/base.d" Jan 23 01:02:53.856479 ignition[1099]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jan 23 01:02:53.856936 ignition[1099]: disks: disks passed Jan 23 01:02:53.865941 systemd[1]: Finished ignition-disks.service - Ignition (disks). Jan 23 01:02:53.857005 ignition[1099]: Ignition finished successfully Jan 23 01:02:53.873255 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Jan 23 01:02:53.880143 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Jan 23 01:02:53.881565 systemd[1]: Reached target local-fs.target - Local File Systems. Jan 23 01:02:53.893364 systemd[1]: Reached target sysinit.target - System Initialization. Jan 23 01:02:53.901855 systemd[1]: Reached target basic.target - Basic System. Jan 23 01:02:53.911779 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Jan 23 01:02:53.984115 systemd-fsck[1111]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks Jan 23 01:02:53.989540 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Jan 23 01:02:54.000042 systemd[1]: Mounting sysroot.mount - /sysroot... Jan 23 01:02:54.319972 kernel: EXT4-fs (sda9): mounted filesystem 2036722e-4586-420e-8dc7-a3b65e840c36 r/w with ordered data mode. Quota mode: none. Jan 23 01:02:54.320649 systemd[1]: Mounted sysroot.mount - /sysroot. Jan 23 01:02:54.322565 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Jan 23 01:02:54.341055 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jan 23 01:02:54.343184 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Jan 23 01:02:54.356608 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Jan 23 01:02:54.370837 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1125) Jan 23 01:02:54.358198 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Jan 23 01:02:54.398816 kernel: BTRFS info (device sda6): first mount of filesystem a3ccc207-e674-4ba2-b6d8-404b4581ba01 Jan 23 01:02:54.398837 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Jan 23 01:02:54.398851 kernel: BTRFS info (device sda6): turning on async discard Jan 23 01:02:54.398863 kernel: BTRFS info (device sda6): enabling free space tree Jan 23 01:02:54.358228 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Jan 23 01:02:54.377044 systemd-networkd[1065]: eth0: Gained IPv6LL Jan 23 01:02:54.378766 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Jan 23 01:02:54.401367 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jan 23 01:02:54.409971 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Jan 23 01:02:54.916599 coreos-metadata[1127]: Jan 23 01:02:54.916 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Jan 23 01:02:54.922629 coreos-metadata[1127]: Jan 23 01:02:54.922 INFO Fetch successful Jan 23 01:02:54.926529 coreos-metadata[1127]: Jan 23 01:02:54.922 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Jan 23 01:02:54.934897 coreos-metadata[1127]: Jan 23 01:02:54.934 INFO Fetch successful Jan 23 01:02:54.945989 coreos-metadata[1127]: Jan 23 01:02:54.945 INFO wrote hostname ci-4459.2.2-n-a6b7843ddb to /sysroot/etc/hostname Jan 23 01:02:54.953301 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Jan 23 01:02:55.131702 initrd-setup-root[1155]: cut: /sysroot/etc/passwd: No such file or directory Jan 23 01:02:55.175459 initrd-setup-root[1162]: cut: /sysroot/etc/group: No such file or directory Jan 23 01:02:55.205195 initrd-setup-root[1169]: cut: /sysroot/etc/shadow: No such file or directory Jan 23 01:02:55.212349 initrd-setup-root[1176]: cut: /sysroot/etc/gshadow: No such file or directory Jan 23 01:02:56.125421 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Jan 23 01:02:56.128280 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Jan 23 01:02:56.154503 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Jan 23 01:02:56.162445 systemd[1]: sysroot-oem.mount: Deactivated successfully. Jan 23 01:02:56.171221 kernel: BTRFS info (device sda6): last unmount of filesystem a3ccc207-e674-4ba2-b6d8-404b4581ba01 Jan 23 01:02:56.193541 ignition[1244]: INFO : Ignition 2.22.0 Jan 23 01:02:56.193541 ignition[1244]: INFO : Stage: mount Jan 23 01:02:56.203255 ignition[1244]: INFO : no configs at "/usr/lib/ignition/base.d" Jan 23 01:02:56.203255 ignition[1244]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jan 23 01:02:56.203255 ignition[1244]: INFO : mount: mount passed Jan 23 01:02:56.203255 ignition[1244]: INFO : Ignition finished successfully Jan 23 01:02:56.194528 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Jan 23 01:02:56.208623 systemd[1]: Finished ignition-mount.service - Ignition (mount). Jan 23 01:02:56.214976 systemd[1]: Starting ignition-files.service - Ignition (files)... Jan 23 01:02:56.249673 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jan 23 01:02:56.277548 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1260) Jan 23 01:02:56.277590 kernel: BTRFS info (device sda6): first mount of filesystem a3ccc207-e674-4ba2-b6d8-404b4581ba01 Jan 23 01:02:56.281802 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Jan 23 01:02:56.291832 kernel: BTRFS info (device sda6): turning on async discard Jan 23 01:02:56.291856 kernel: BTRFS info (device sda6): enabling free space tree Jan 23 01:02:56.294103 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jan 23 01:02:56.361964 ignition[1277]: INFO : Ignition 2.22.0 Jan 23 01:02:56.361964 ignition[1277]: INFO : Stage: files Jan 23 01:02:56.367729 ignition[1277]: INFO : no configs at "/usr/lib/ignition/base.d" Jan 23 01:02:56.367729 ignition[1277]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jan 23 01:02:56.367729 ignition[1277]: DEBUG : files: compiled without relabeling support, skipping Jan 23 01:02:56.382750 ignition[1277]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Jan 23 01:02:56.382750 ignition[1277]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Jan 23 01:02:56.445670 ignition[1277]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Jan 23 01:02:56.451180 ignition[1277]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Jan 23 01:02:56.451180 ignition[1277]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Jan 23 01:02:56.446430 unknown[1277]: wrote ssh authorized keys file for user: core Jan 23 01:02:56.484098 ignition[1277]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Jan 23 01:02:56.491481 ignition[1277]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Jan 23 01:02:56.491481 ignition[1277]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Jan 23 01:02:56.491481 ignition[1277]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Jan 23 01:02:56.491481 ignition[1277]: INFO : files: files passed Jan 23 01:02:56.491481 ignition[1277]: INFO : Ignition finished successfully Jan 23 01:02:56.486354 systemd[1]: Finished ignition-files.service - Ignition (files). Jan 23 01:02:56.493860 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Jan 23 01:02:56.524520 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Jan 23 01:02:56.531809 systemd[1]: ignition-quench.service: Deactivated successfully. Jan 23 01:02:56.531906 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Jan 23 01:02:56.562933 initrd-setup-root-after-ignition[1309]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jan 23 01:02:56.562933 initrd-setup-root-after-ignition[1309]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Jan 23 01:02:56.580606 initrd-setup-root-after-ignition[1313]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jan 23 01:02:56.564808 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Jan 23 01:02:56.570988 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Jan 23 01:02:56.582877 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Jan 23 01:02:56.640384 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Jan 23 01:02:56.640496 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Jan 23 01:02:56.649244 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Jan 23 01:02:56.651277 systemd[1]: Reached target initrd.target - Initrd Default Target. Jan 23 01:02:56.662939 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Jan 23 01:02:56.663822 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Jan 23 01:02:56.714458 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jan 23 01:02:56.725286 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Jan 23 01:02:56.759274 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Jan 23 01:02:56.760993 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Jan 23 01:02:56.770294 systemd[1]: Stopped target timers.target - Timer Units. Jan 23 01:02:56.779517 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Jan 23 01:02:56.779639 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jan 23 01:02:56.793834 systemd[1]: Stopped target initrd.target - Initrd Default Target. Jan 23 01:02:56.802591 systemd[1]: Stopped target basic.target - Basic System. Jan 23 01:02:56.804776 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Jan 23 01:02:56.822673 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Jan 23 01:02:56.824340 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Jan 23 01:02:56.862400 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Jan 23 01:02:56.863987 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Jan 23 01:02:56.872300 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Jan 23 01:02:56.882452 systemd[1]: Stopped target sysinit.target - System Initialization. Jan 23 01:02:56.892308 systemd[1]: Stopped target local-fs.target - Local File Systems. Jan 23 01:02:56.900739 systemd[1]: Stopped target swap.target - Swaps. Jan 23 01:02:56.954028 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Jan 23 01:02:56.954186 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Jan 23 01:02:56.967112 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Jan 23 01:02:56.977865 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jan 23 01:02:56.979299 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Jan 23 01:02:56.979529 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jan 23 01:02:56.992242 systemd[1]: dracut-initqueue.service: Deactivated successfully. Jan 23 01:02:56.992379 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Jan 23 01:02:56.999586 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Jan 23 01:02:56.999715 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Jan 23 01:02:57.001057 systemd[1]: ignition-files.service: Deactivated successfully. Jan 23 01:02:57.001177 systemd[1]: Stopped ignition-files.service - Ignition (files). Jan 23 01:02:57.011921 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Jan 23 01:02:57.012061 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Jan 23 01:02:57.022056 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Jan 23 01:02:57.029713 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Jan 23 01:02:57.087828 ignition[1333]: INFO : Ignition 2.22.0 Jan 23 01:02:57.087828 ignition[1333]: INFO : Stage: umount Jan 23 01:02:57.087828 ignition[1333]: INFO : no configs at "/usr/lib/ignition/base.d" Jan 23 01:02:57.087828 ignition[1333]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jan 23 01:02:57.087828 ignition[1333]: INFO : umount: umount passed Jan 23 01:02:57.087828 ignition[1333]: INFO : Ignition finished successfully Jan 23 01:02:57.029842 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Jan 23 01:02:57.059500 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Jan 23 01:02:57.063232 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Jan 23 01:02:57.063480 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Jan 23 01:02:57.076881 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Jan 23 01:02:57.077021 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Jan 23 01:02:57.091841 systemd[1]: ignition-mount.service: Deactivated successfully. Jan 23 01:02:57.091943 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Jan 23 01:02:57.095147 systemd[1]: initrd-cleanup.service: Deactivated successfully. Jan 23 01:02:57.095245 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Jan 23 01:02:57.098720 systemd[1]: ignition-disks.service: Deactivated successfully. Jan 23 01:02:57.098797 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Jan 23 01:02:57.106851 systemd[1]: ignition-kargs.service: Deactivated successfully. Jan 23 01:02:57.106903 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Jan 23 01:02:57.119645 systemd[1]: ignition-fetch.service: Deactivated successfully. Jan 23 01:02:57.119693 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Jan 23 01:02:57.129046 systemd[1]: Stopped target network.target - Network. Jan 23 01:02:57.130495 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Jan 23 01:02:57.130549 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Jan 23 01:02:57.142212 systemd[1]: Stopped target paths.target - Path Units. Jan 23 01:02:57.149919 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Jan 23 01:02:57.154028 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jan 23 01:02:57.158335 systemd[1]: Stopped target slices.target - Slice Units. Jan 23 01:02:57.167746 systemd[1]: Stopped target sockets.target - Socket Units. Jan 23 01:02:57.177994 systemd[1]: iscsid.socket: Deactivated successfully. Jan 23 01:02:57.178041 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Jan 23 01:02:57.179476 systemd[1]: iscsiuio.socket: Deactivated successfully. Jan 23 01:02:57.179512 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jan 23 01:02:57.191808 systemd[1]: ignition-setup.service: Deactivated successfully. Jan 23 01:02:57.191867 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Jan 23 01:02:57.198771 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Jan 23 01:02:57.198816 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Jan 23 01:02:57.209854 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Jan 23 01:02:57.218452 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Jan 23 01:02:57.227286 systemd[1]: sysroot-boot.mount: Deactivated successfully. Jan 23 01:02:57.227856 systemd[1]: systemd-resolved.service: Deactivated successfully. Jan 23 01:02:57.227971 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Jan 23 01:02:57.239847 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Jan 23 01:02:57.240146 systemd[1]: sysroot-boot.service: Deactivated successfully. Jan 23 01:02:57.240240 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Jan 23 01:02:57.243192 systemd[1]: systemd-networkd.service: Deactivated successfully. Jan 23 01:02:57.243291 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Jan 23 01:02:57.258409 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Jan 23 01:02:57.259730 systemd[1]: Stopped target network-pre.target - Preparation for Network. Jan 23 01:02:57.262310 systemd[1]: systemd-networkd.socket: Deactivated successfully. Jan 23 01:02:57.262369 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Jan 23 01:02:57.269922 systemd[1]: initrd-setup-root.service: Deactivated successfully. Jan 23 01:02:57.270029 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Jan 23 01:02:57.283590 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Jan 23 01:02:57.289901 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Jan 23 01:02:57.289963 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jan 23 01:02:57.299158 systemd[1]: systemd-sysctl.service: Deactivated successfully. Jan 23 01:02:57.299204 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Jan 23 01:02:57.310726 systemd[1]: systemd-modules-load.service: Deactivated successfully. Jan 23 01:02:57.310773 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Jan 23 01:02:57.312142 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Jan 23 01:02:57.312190 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Jan 23 01:02:57.327013 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Jan 23 01:02:57.329366 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Jan 23 01:02:57.329430 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Jan 23 01:02:57.351648 systemd[1]: network-cleanup.service: Deactivated successfully. Jan 23 01:02:57.351779 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Jan 23 01:02:57.371571 systemd[1]: systemd-udevd.service: Deactivated successfully. Jan 23 01:02:57.371761 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Jan 23 01:02:57.375176 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Jan 23 01:02:57.375237 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Jan 23 01:02:57.387104 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Jan 23 01:02:57.387139 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Jan 23 01:02:57.395053 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Jan 23 01:02:57.395103 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Jan 23 01:02:57.408862 systemd[1]: dracut-cmdline.service: Deactivated successfully. Jan 23 01:02:57.409048 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Jan 23 01:02:57.420035 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jan 23 01:02:57.420085 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jan 23 01:02:57.433625 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Jan 23 01:02:57.441887 systemd[1]: systemd-network-generator.service: Deactivated successfully. Jan 23 01:02:57.441944 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Jan 23 01:02:57.454935 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Jan 23 01:02:57.455021 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jan 23 01:02:57.469642 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jan 23 01:02:57.469692 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jan 23 01:02:57.646972 systemd-journald[213]: Received SIGTERM from PID 1 (systemd). Jan 23 01:02:57.482841 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Jan 23 01:02:57.482895 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Jan 23 01:02:57.482938 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jan 23 01:02:57.483322 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Jan 23 01:02:57.483408 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Jan 23 01:02:57.496742 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Jan 23 01:02:57.500186 systemd[1]: Starting initrd-switch-root.service - Switch Root... Jan 23 01:02:57.534105 systemd[1]: Switching root. Jan 23 01:02:57.658555 systemd-journald[213]: Journal stopped Jan 23 01:03:02.422706 kernel: SELinux: policy capability network_peer_controls=1 Jan 23 01:03:02.422739 kernel: SELinux: policy capability open_perms=1 Jan 23 01:03:02.422752 kernel: SELinux: policy capability extended_socket_class=1 Jan 23 01:03:02.422760 kernel: SELinux: policy capability always_check_network=0 Jan 23 01:03:02.422769 kernel: SELinux: policy capability cgroup_seclabel=1 Jan 23 01:03:02.422778 kernel: SELinux: policy capability nnp_nosuid_transition=1 Jan 23 01:03:02.422788 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Jan 23 01:03:02.422800 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Jan 23 01:03:02.422809 kernel: SELinux: policy capability userspace_initial_context=0 Jan 23 01:03:02.422818 kernel: audit: type=1403 audit(1769130178.369:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Jan 23 01:03:02.422829 systemd[1]: Successfully loaded SELinux policy in 205.989ms. Jan 23 01:03:02.422840 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 7.819ms. Jan 23 01:03:02.422851 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) Jan 23 01:03:02.422861 systemd[1]: Detected virtualization microsoft. Jan 23 01:03:02.422874 systemd[1]: Detected architecture x86-64. Jan 23 01:03:02.422885 systemd[1]: Detected first boot. Jan 23 01:03:02.422895 systemd[1]: Hostname set to . Jan 23 01:03:02.422905 systemd[1]: Initializing machine ID from random generator. Jan 23 01:03:02.422915 zram_generator::config[1380]: No configuration found. Jan 23 01:03:02.422928 kernel: Guest personality initialized and is inactive Jan 23 01:03:02.422938 kernel: VMCI host device registered (name=vmci, major=10, minor=259) Jan 23 01:03:02.422962 kernel: Initialized host personality Jan 23 01:03:02.422974 kernel: NET: Registered PF_VSOCK protocol family Jan 23 01:03:02.422985 systemd[1]: Populated /etc with preset unit settings. Jan 23 01:03:02.422996 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Jan 23 01:03:02.423006 systemd[1]: initrd-switch-root.service: Deactivated successfully. Jan 23 01:03:02.423020 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Jan 23 01:03:02.423031 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Jan 23 01:03:02.423041 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Jan 23 01:03:02.423052 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Jan 23 01:03:02.423062 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Jan 23 01:03:02.423073 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Jan 23 01:03:02.423083 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Jan 23 01:03:02.423093 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Jan 23 01:03:02.423106 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Jan 23 01:03:02.423117 systemd[1]: Created slice user.slice - User and Session Slice. Jan 23 01:03:02.423127 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jan 23 01:03:02.423137 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jan 23 01:03:02.423189 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Jan 23 01:03:02.423207 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Jan 23 01:03:02.423224 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Jan 23 01:03:02.423235 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jan 23 01:03:02.423253 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Jan 23 01:03:02.423264 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jan 23 01:03:02.423275 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jan 23 01:03:02.423285 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Jan 23 01:03:02.423296 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Jan 23 01:03:02.423307 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Jan 23 01:03:02.423317 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Jan 23 01:03:02.423330 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jan 23 01:03:02.423341 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jan 23 01:03:02.423352 systemd[1]: Reached target slices.target - Slice Units. Jan 23 01:03:02.423362 systemd[1]: Reached target swap.target - Swaps. Jan 23 01:03:02.423373 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Jan 23 01:03:02.423383 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Jan 23 01:03:02.423397 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Jan 23 01:03:02.423408 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jan 23 01:03:02.423418 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jan 23 01:03:02.423429 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jan 23 01:03:02.423440 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Jan 23 01:03:02.423450 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Jan 23 01:03:02.423461 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Jan 23 01:03:02.423474 systemd[1]: Mounting media.mount - External Media Directory... Jan 23 01:03:02.423489 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jan 23 01:03:02.423500 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Jan 23 01:03:02.423510 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Jan 23 01:03:02.423521 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Jan 23 01:03:02.423532 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). Jan 23 01:03:02.423543 systemd[1]: Reached target machines.target - Containers. Jan 23 01:03:02.423554 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Jan 23 01:03:02.423565 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jan 23 01:03:02.423579 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jan 23 01:03:02.423590 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Jan 23 01:03:02.423601 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jan 23 01:03:02.423612 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jan 23 01:03:02.423622 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jan 23 01:03:02.423633 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Jan 23 01:03:02.423644 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jan 23 01:03:02.423655 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Jan 23 01:03:02.423668 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Jan 23 01:03:02.423679 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Jan 23 01:03:02.423689 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Jan 23 01:03:02.423700 systemd[1]: Stopped systemd-fsck-usr.service. Jan 23 01:03:02.423711 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). Jan 23 01:03:02.423725 systemd[1]: Starting systemd-journald.service - Journal Service... Jan 23 01:03:02.423736 kernel: loop: module loaded Jan 23 01:03:02.423746 kernel: fuse: init (API version 7.41) Jan 23 01:03:02.423759 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jan 23 01:03:02.423770 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jan 23 01:03:02.423780 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Jan 23 01:03:02.423816 systemd-journald[1487]: Collecting audit messages is disabled. Jan 23 01:03:02.423845 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Jan 23 01:03:02.423857 systemd-journald[1487]: Journal started Jan 23 01:03:02.423879 systemd-journald[1487]: Runtime Journal (/run/log/journal/c46ba99c0c3e429a88718b51c61978cc) is 8M, max 2.2G, 2.1G free. Jan 23 01:03:01.670172 systemd[1]: Queued start job for default target multi-user.target. Jan 23 01:03:01.694396 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Jan 23 01:03:01.694904 systemd[1]: systemd-journald.service: Deactivated successfully. Jan 23 01:03:02.441416 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jan 23 01:03:02.451039 systemd[1]: verity-setup.service: Deactivated successfully. Jan 23 01:03:02.451083 systemd[1]: Stopped verity-setup.service. Jan 23 01:03:02.463982 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jan 23 01:03:02.466979 kernel: ACPI: bus type drm_connector registered Jan 23 01:03:02.467010 systemd[1]: Started systemd-journald.service - Journal Service. Jan 23 01:03:02.475676 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Jan 23 01:03:02.479987 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Jan 23 01:03:02.484457 systemd[1]: Mounted media.mount - External Media Directory. Jan 23 01:03:02.488946 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Jan 23 01:03:02.493650 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Jan 23 01:03:02.498196 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Jan 23 01:03:02.502230 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Jan 23 01:03:02.507198 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jan 23 01:03:02.512724 systemd[1]: modprobe@configfs.service: Deactivated successfully. Jan 23 01:03:02.512917 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Jan 23 01:03:02.517875 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jan 23 01:03:02.518092 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jan 23 01:03:02.522887 systemd[1]: modprobe@drm.service: Deactivated successfully. Jan 23 01:03:02.523086 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jan 23 01:03:02.527428 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jan 23 01:03:02.527622 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jan 23 01:03:02.532814 systemd[1]: modprobe@fuse.service: Deactivated successfully. Jan 23 01:03:02.533013 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Jan 23 01:03:02.537588 systemd[1]: modprobe@loop.service: Deactivated successfully. Jan 23 01:03:02.537772 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jan 23 01:03:02.542379 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jan 23 01:03:02.547131 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jan 23 01:03:02.552417 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Jan 23 01:03:02.557679 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Jan 23 01:03:02.572120 systemd[1]: Reached target network-pre.target - Preparation for Network. Jan 23 01:03:02.578119 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Jan 23 01:03:02.593763 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Jan 23 01:03:02.598263 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Jan 23 01:03:02.598300 systemd[1]: Reached target local-fs.target - Local File Systems. Jan 23 01:03:02.604157 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Jan 23 01:03:02.610140 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Jan 23 01:03:02.614156 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jan 23 01:03:02.615205 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Jan 23 01:03:02.620645 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Jan 23 01:03:02.625328 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jan 23 01:03:02.626265 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Jan 23 01:03:02.630572 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jan 23 01:03:02.631494 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jan 23 01:03:02.636516 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Jan 23 01:03:02.642041 systemd[1]: Starting systemd-sysusers.service - Create System Users... Jan 23 01:03:02.648785 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jan 23 01:03:02.653751 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Jan 23 01:03:02.655600 systemd-journald[1487]: Time spent on flushing to /var/log/journal/c46ba99c0c3e429a88718b51c61978cc is 15.730ms for 953 entries. Jan 23 01:03:02.655600 systemd-journald[1487]: System Journal (/var/log/journal/c46ba99c0c3e429a88718b51c61978cc) is 8M, max 2.6G, 2.6G free. Jan 23 01:03:02.725555 systemd-journald[1487]: Received client request to flush runtime journal. Jan 23 01:03:02.662689 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Jan 23 01:03:02.668720 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Jan 23 01:03:02.675560 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Jan 23 01:03:02.681348 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Jan 23 01:03:02.726931 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Jan 23 01:03:02.741314 kernel: loop0: detected capacity change from 0 to 110984 Jan 23 01:03:02.751289 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Jan 23 01:03:02.752081 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Jan 23 01:03:02.842608 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jan 23 01:03:02.864001 systemd[1]: Finished systemd-sysusers.service - Create System Users. Jan 23 01:03:02.869612 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jan 23 01:03:02.952680 systemd-tmpfiles[1538]: ACLs are not supported, ignoring. Jan 23 01:03:02.952701 systemd-tmpfiles[1538]: ACLs are not supported, ignoring. Jan 23 01:03:02.956854 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jan 23 01:03:03.266989 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jan 23 01:03:03.302975 kernel: loop1: detected capacity change from 0 to 27936 Jan 23 01:03:03.512041 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Jan 23 01:03:03.518692 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jan 23 01:03:03.564362 systemd-udevd[1545]: Using default interface naming scheme 'v255'. Jan 23 01:03:03.762989 kernel: loop2: detected capacity change from 0 to 128560 Jan 23 01:03:03.795964 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jan 23 01:03:03.805317 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jan 23 01:03:03.849054 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Jan 23 01:03:03.882299 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Jan 23 01:03:03.888282 kernel: mousedev: PS/2 mouse device common for all mice Jan 23 01:03:03.896386 kernel: hv_vmbus: registering driver hyperv_fb Jan 23 01:03:03.896455 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Jan 23 01:03:03.898298 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Jan 23 01:03:03.906333 kernel: Console: switching to colour dummy device 80x25 Jan 23 01:03:03.918973 kernel: hv_vmbus: registering driver hv_balloon Jan 23 01:03:03.919021 kernel: Console: switching to colour frame buffer device 128x48 Jan 23 01:03:03.927985 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Jan 23 01:03:03.950247 kernel: EDAC MC: Ver: 3.0.0 Jan 23 01:03:03.960661 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jan 23 01:03:03.987458 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jan 23 01:03:03.987655 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jan 23 01:03:03.993440 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jan 23 01:03:04.044411 systemd[1]: Started systemd-userdbd.service - User Database Manager. Jan 23 01:03:04.181075 kernel: loop3: detected capacity change from 0 to 110984 Jan 23 01:03:04.196978 kernel: loop4: detected capacity change from 0 to 27936 Jan 23 01:03:04.214971 kernel: loop5: detected capacity change from 0 to 128560 Jan 23 01:03:04.220398 systemd-networkd[1572]: lo: Link UP Jan 23 01:03:04.220409 systemd-networkd[1572]: lo: Gained carrier Jan 23 01:03:04.221846 systemd-networkd[1572]: Enumeration completed Jan 23 01:03:04.221971 systemd[1]: Started systemd-networkd.service - Network Configuration. Jan 23 01:03:04.222291 systemd-networkd[1572]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jan 23 01:03:04.222303 systemd-networkd[1572]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jan 23 01:03:04.222873 systemd-networkd[1572]: eth0: Link UP Jan 23 01:03:04.222885 systemd-networkd[1572]: eth0: Gained carrier Jan 23 01:03:04.222900 systemd-networkd[1572]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jan 23 01:03:04.224696 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Jan 23 01:03:04.225824 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Jan 23 01:03:04.249841 (sd-merge)[1630]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Jan 23 01:03:04.250434 (sd-merge)[1630]: Merged extensions into '/usr'. Jan 23 01:03:04.252340 systemd-networkd[1572]: eth0: DHCPv4 address 10.200.8.20/24, gateway 10.200.8.1 acquired from 168.63.129.16 Jan 23 01:03:04.255027 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Jan 23 01:03:04.259078 systemd[1]: Starting ensure-sysext.service... Jan 23 01:03:04.260409 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jan 23 01:03:04.289859 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Jan 23 01:03:04.300498 systemd[1]: Reload requested from client PID 1647 ('systemctl') (unit ensure-sysext.service)... Jan 23 01:03:04.300516 systemd[1]: Reloading... Jan 23 01:03:04.351978 zram_generator::config[1732]: No configuration found. Jan 23 01:03:04.372743 systemd-tmpfiles[1650]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Jan 23 01:03:04.384473 systemd-tmpfiles[1650]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Jan 23 01:03:04.384819 systemd-tmpfiles[1650]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Jan 23 01:03:04.385144 systemd-tmpfiles[1650]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Jan 23 01:03:04.386047 systemd-tmpfiles[1650]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Jan 23 01:03:04.386335 systemd-tmpfiles[1650]: ACLs are not supported, ignoring. Jan 23 01:03:04.386403 systemd-tmpfiles[1650]: ACLs are not supported, ignoring. Jan 23 01:03:04.392125 systemd-tmpfiles[1650]: Detected autofs mount point /boot during canonicalization of boot. Jan 23 01:03:04.392138 systemd-tmpfiles[1650]: Skipping /boot Jan 23 01:03:04.400602 systemd-tmpfiles[1650]: Detected autofs mount point /boot during canonicalization of boot. Jan 23 01:03:04.400617 systemd-tmpfiles[1650]: Skipping /boot Jan 23 01:03:04.631055 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Jan 23 01:03:04.636244 systemd[1]: Reloading finished in 335 ms. Jan 23 01:03:04.668983 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jan 23 01:03:04.717875 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jan 23 01:03:04.759527 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jan 23 01:03:04.760770 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jan 23 01:03:04.787848 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Jan 23 01:03:04.792836 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jan 23 01:03:04.794134 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jan 23 01:03:04.799385 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jan 23 01:03:04.805813 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jan 23 01:03:04.810052 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jan 23 01:03:04.811126 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Jan 23 01:03:04.815804 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). Jan 23 01:03:04.817221 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Jan 23 01:03:04.824149 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jan 23 01:03:04.827079 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Jan 23 01:03:04.827197 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jan 23 01:03:04.829310 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jan 23 01:03:04.829593 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jan 23 01:03:04.831451 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jan 23 01:03:04.831628 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jan 23 01:03:04.832055 systemd[1]: modprobe@loop.service: Deactivated successfully. Jan 23 01:03:04.832400 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jan 23 01:03:04.841129 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jan 23 01:03:04.841406 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jan 23 01:03:04.842663 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jan 23 01:03:04.855889 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jan 23 01:03:04.860819 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jan 23 01:03:04.872056 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jan 23 01:03:04.876743 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jan 23 01:03:04.876871 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). Jan 23 01:03:04.877070 systemd[1]: Reached target time-set.target - System Time Set. Jan 23 01:03:04.881780 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jan 23 01:03:04.885351 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Jan 23 01:03:04.890988 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jan 23 01:03:04.891220 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jan 23 01:03:04.896359 systemd[1]: modprobe@drm.service: Deactivated successfully. Jan 23 01:03:04.896548 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jan 23 01:03:04.901218 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jan 23 01:03:04.901406 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jan 23 01:03:04.907046 systemd[1]: modprobe@loop.service: Deactivated successfully. Jan 23 01:03:04.907231 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jan 23 01:03:04.914750 systemd[1]: Finished ensure-sysext.service. Jan 23 01:03:04.923889 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jan 23 01:03:04.924211 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jan 23 01:03:04.925772 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Jan 23 01:03:04.944835 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Jan 23 01:03:04.966814 systemd-resolved[1805]: Positive Trust Anchors: Jan 23 01:03:04.966830 systemd-resolved[1805]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jan 23 01:03:04.966862 systemd-resolved[1805]: 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 Jan 23 01:03:04.979915 augenrules[1841]: No rules Jan 23 01:03:04.981031 systemd[1]: audit-rules.service: Deactivated successfully. Jan 23 01:03:04.981273 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jan 23 01:03:04.989585 systemd-resolved[1805]: Using system hostname 'ci-4459.2.2-n-a6b7843ddb'. Jan 23 01:03:04.991809 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jan 23 01:03:04.996564 systemd[1]: Reached target network.target - Network. Jan 23 01:03:05.000174 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jan 23 01:03:05.575087 systemd-networkd[1572]: eth0: Gained IPv6LL Jan 23 01:03:05.577434 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Jan 23 01:03:05.582813 systemd[1]: Reached target network-online.target - Network is Online. Jan 23 01:03:05.833235 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Jan 23 01:03:05.838504 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). Jan 23 01:03:08.388377 ldconfig[1516]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jan 23 01:03:08.399258 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Jan 23 01:03:08.406083 systemd[1]: Starting systemd-update-done.service - Update is Completed... Jan 23 01:03:08.438370 systemd[1]: Finished systemd-update-done.service - Update is Completed. Jan 23 01:03:08.443103 systemd[1]: Reached target sysinit.target - System Initialization. Jan 23 01:03:08.447495 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Jan 23 01:03:08.452491 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Jan 23 01:03:08.457672 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Jan 23 01:03:08.462423 systemd[1]: Started logrotate.timer - Daily rotation of log files. Jan 23 01:03:08.467358 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Jan 23 01:03:08.472997 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Jan 23 01:03:08.478314 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Jan 23 01:03:08.478352 systemd[1]: Reached target paths.target - Path Units. Jan 23 01:03:08.482653 systemd[1]: Reached target timers.target - Timer Units. Jan 23 01:03:08.487465 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Jan 23 01:03:08.493305 systemd[1]: Starting docker.socket - Docker Socket for the API... Jan 23 01:03:08.499981 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Jan 23 01:03:08.505636 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Jan 23 01:03:08.510618 systemd[1]: Reached target ssh-access.target - SSH Access Available. Jan 23 01:03:08.517651 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Jan 23 01:03:08.522002 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Jan 23 01:03:08.527449 systemd[1]: Listening on docker.socket - Docker Socket for the API. Jan 23 01:03:08.532598 systemd[1]: Reached target sockets.target - Socket Units. Jan 23 01:03:08.536427 systemd[1]: Reached target basic.target - Basic System. Jan 23 01:03:08.540141 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Jan 23 01:03:08.540173 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Jan 23 01:03:08.554626 systemd[1]: Starting chronyd.service - NTP client/server... Jan 23 01:03:08.576707 systemd[1]: Starting containerd.service - containerd container runtime... Jan 23 01:03:08.581977 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Jan 23 01:03:08.602271 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Jan 23 01:03:08.606843 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Jan 23 01:03:08.612642 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Jan 23 01:03:08.617844 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Jan 23 01:03:08.621950 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). Jan 23 01:03:08.622926 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Jan 23 01:03:08.627051 jq[1862]: false Jan 23 01:03:08.627051 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). Jan 23 01:03:08.627983 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Jan 23 01:03:08.630488 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Jan 23 01:03:08.631472 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Jan 23 01:03:08.638846 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Jan 23 01:03:08.643648 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Jan 23 01:03:08.649650 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Jan 23 01:03:08.653993 KVP[1865]: KVP starting; pid is:1865 Jan 23 01:03:08.657626 extend-filesystems[1863]: Found /dev/sda6 Jan 23 01:03:08.661469 kernel: hv_utils: KVP IC version 4.0 Jan 23 01:03:08.656725 KVP[1865]: KVP LIC Version: 3.1 Jan 23 01:03:08.657821 systemd[1]: Starting systemd-logind.service - User Login Management... Jan 23 01:03:08.657542 chronyd[1854]: chronyd version 4.7 starting (+CMDMON +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +NTS +SECHASH +IPV6 -DEBUG) Jan 23 01:03:08.666757 extend-filesystems[1863]: Found /dev/sda9 Jan 23 01:03:08.667517 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Jan 23 01:03:08.667986 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Jan 23 01:03:08.668553 systemd[1]: Starting update-engine.service - Update Engine... Jan 23 01:03:08.676470 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Jan 23 01:03:08.677681 google_oslogin_nss_cache[1864]: oslogin_cache_refresh[1864]: Refreshing passwd entry cache Jan 23 01:03:08.677699 oslogin_cache_refresh[1864]: Refreshing passwd entry cache Jan 23 01:03:08.678071 extend-filesystems[1863]: Checking size of /dev/sda9 Jan 23 01:03:08.685086 oslogin_cache_refresh[1864]: Failure getting users, quitting Jan 23 01:03:08.683162 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Jan 23 01:03:08.689736 google_oslogin_nss_cache[1864]: oslogin_cache_refresh[1864]: Failure getting users, quitting Jan 23 01:03:08.689736 google_oslogin_nss_cache[1864]: oslogin_cache_refresh[1864]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Jan 23 01:03:08.689736 google_oslogin_nss_cache[1864]: oslogin_cache_refresh[1864]: Refreshing group entry cache Jan 23 01:03:08.689736 google_oslogin_nss_cache[1864]: oslogin_cache_refresh[1864]: Failure getting groups, quitting Jan 23 01:03:08.689736 google_oslogin_nss_cache[1864]: oslogin_cache_refresh[1864]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Jan 23 01:03:08.685102 oslogin_cache_refresh[1864]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Jan 23 01:03:08.684289 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Jan 23 01:03:08.690046 jq[1882]: true Jan 23 01:03:08.685144 oslogin_cache_refresh[1864]: Refreshing group entry cache Jan 23 01:03:08.684493 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Jan 23 01:03:08.689445 oslogin_cache_refresh[1864]: Failure getting groups, quitting Jan 23 01:03:08.684772 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Jan 23 01:03:08.689456 oslogin_cache_refresh[1864]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Jan 23 01:03:08.685157 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Jan 23 01:03:08.691618 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Jan 23 01:03:08.691834 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Jan 23 01:03:08.697889 extend-filesystems[1863]: Old size kept for /dev/sda9 Jan 23 01:03:08.700523 systemd[1]: extend-filesystems.service: Deactivated successfully. Jan 23 01:03:08.700731 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Jan 23 01:03:08.705123 chronyd[1854]: Timezone right/UTC failed leap second check, ignoring Jan 23 01:03:08.705308 chronyd[1854]: Loaded seccomp filter (level 2) Jan 23 01:03:08.708564 systemd[1]: Started chronyd.service - NTP client/server. Jan 23 01:03:08.723442 systemd[1]: motdgen.service: Deactivated successfully. Jan 23 01:03:08.726356 (ntainerd)[1899]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Jan 23 01:03:08.727012 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Jan 23 01:03:08.727790 update_engine[1879]: I20260123 01:03:08.727648 1879 main.cc:92] Flatcar Update Engine starting Jan 23 01:03:08.729453 jq[1895]: true Jan 23 01:03:08.798605 setup-nvidia[1870]: Downloading Flatcar Container Linux Developer Container for version: 4459.2.2+nightly-20260122-2100 Jan 23 01:03:08.812111 systemd-logind[1876]: New seat seat0. Jan 23 01:03:08.813575 systemd-logind[1876]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Jan 23 01:03:08.813856 systemd[1]: Started systemd-logind.service - User Login Management. Jan 23 01:03:08.827845 sshd_keygen[1885]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Jan 23 01:03:08.841530 bash[1954]: Updated "/home/core/.ssh/authorized_keys" Jan 23 01:03:08.843562 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Jan 23 01:03:08.849999 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Jan 23 01:03:08.856811 systemd[1]: Starting issuegen.service - Generate /run/issue... Jan 23 01:03:08.861361 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Jan 23 01:03:08.873798 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Jan 23 01:03:08.877669 dbus-daemon[1857]: [system] SELinux support is enabled Jan 23 01:03:08.878245 systemd[1]: Started dbus.service - D-Bus System Message Bus. Jan 23 01:03:08.880720 update_engine[1879]: I20260123 01:03:08.880666 1879 update_check_scheduler.cc:74] Next update check in 11m7s Jan 23 01:03:08.885191 systemd[1]: issuegen.service: Deactivated successfully. Jan 23 01:03:08.885390 systemd[1]: Finished issuegen.service - Generate /run/issue. Jan 23 01:03:08.891377 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). Jan 23 01:03:08.891425 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Jan 23 01:03:08.892116 dbus-daemon[1857]: [system] Successfully activated service 'org.freedesktop.systemd1' Jan 23 01:03:08.913225 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Jan 23 01:03:08.918002 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). Jan 23 01:03:08.918033 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Jan 23 01:03:08.924477 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Jan 23 01:03:08.929441 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Jan 23 01:03:08.935647 systemd[1]: Started update-engine.service - Update Engine. Jan 23 01:03:08.941906 systemd[1]: Started getty@tty1.service - Getty on tty1. Jan 23 01:03:08.942839 coreos-metadata[1856]: Jan 23 01:03:08.942 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Jan 23 01:03:08.945914 coreos-metadata[1856]: Jan 23 01:03:08.945 INFO Fetch successful Jan 23 01:03:08.946126 coreos-metadata[1856]: Jan 23 01:03:08.946 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Jan 23 01:03:08.946722 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Jan 23 01:03:08.951164 coreos-metadata[1856]: Jan 23 01:03:08.950 INFO Fetch successful Jan 23 01:03:08.951164 coreos-metadata[1856]: Jan 23 01:03:08.950 INFO Fetching http://168.63.129.16/machine/818768fb-097c-4a7b-b4aa-388105ea3247/0bda5300%2D07f1%2D428b%2D9ea4%2Dd15082044923.%5Fci%2D4459.2.2%2Dn%2Da6b7843ddb?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Jan 23 01:03:08.951269 systemd[1]: Reached target getty.target - Login Prompts. Jan 23 01:03:08.955044 coreos-metadata[1856]: Jan 23 01:03:08.951 INFO Fetch successful Jan 23 01:03:08.955044 coreos-metadata[1856]: Jan 23 01:03:08.952 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Jan 23 01:03:08.956189 systemd[1]: Started locksmithd.service - Cluster reboot manager. Jan 23 01:03:08.959201 coreos-metadata[1856]: Jan 23 01:03:08.959 INFO Fetch successful Jan 23 01:03:08.982618 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Jan 23 01:03:08.987376 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Jan 23 01:03:09.231125 locksmithd[2019]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Jan 23 01:03:09.817650 setup-nvidia[2034]: % Total % Received % Xferd Average Speed Time Time Time Current Jan 23 01:03:09.817981 setup-nvidia[2034]: Dload Upload Total Spent Left Speed Jan 23 01:03:09.846326 containerd[1899]: time="2026-01-23T01:03:09Z" 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 Jan 23 01:03:09.847156 containerd[1899]: time="2026-01-23T01:03:09.847110900Z" level=info msg="starting containerd" revision=4ac6c20c7bbf8177f29e46bbdc658fec02ffb8ad version=v2.0.7 Jan 23 01:03:09.854864 containerd[1899]: time="2026-01-23T01:03:09.854827000Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="8.7µs" Jan 23 01:03:09.854864 containerd[1899]: time="2026-01-23T01:03:09.854858200Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Jan 23 01:03:09.854965 containerd[1899]: time="2026-01-23T01:03:09.854877700Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Jan 23 01:03:09.855087 containerd[1899]: time="2026-01-23T01:03:09.855061900Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Jan 23 01:03:09.855122 containerd[1899]: time="2026-01-23T01:03:09.855087700Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Jan 23 01:03:09.855122 containerd[1899]: time="2026-01-23T01:03:09.855114900Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jan 23 01:03:09.855207 containerd[1899]: time="2026-01-23T01:03:09.855183300Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jan 23 01:03:09.855240 containerd[1899]: time="2026-01-23T01:03:09.855204800Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Jan 23 01:03:09.855444 containerd[1899]: time="2026-01-23T01:03:09.855419400Z" 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 Jan 23 01:03:09.855472 containerd[1899]: time="2026-01-23T01:03:09.855442300Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jan 23 01:03:09.855472 containerd[1899]: time="2026-01-23T01:03:09.855455700Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jan 23 01:03:09.855472 containerd[1899]: time="2026-01-23T01:03:09.855464700Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Jan 23 01:03:09.855589 containerd[1899]: time="2026-01-23T01:03:09.855565500Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Jan 23 01:03:09.856259 containerd[1899]: time="2026-01-23T01:03:09.856045100Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Jan 23 01:03:09.856299 containerd[1899]: time="2026-01-23T01:03:09.856282500Z" 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 Jan 23 01:03:09.856299 containerd[1899]: time="2026-01-23T01:03:09.856296600Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Jan 23 01:03:09.856368 containerd[1899]: time="2026-01-23T01:03:09.856346200Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Jan 23 01:03:09.856545 containerd[1899]: time="2026-01-23T01:03:09.856526500Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Jan 23 01:03:09.856622 containerd[1899]: time="2026-01-23T01:03:09.856599700Z" level=info msg="metadata content store policy set" policy=shared Jan 23 01:03:09.884894 containerd[1899]: time="2026-01-23T01:03:09.884857800Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Jan 23 01:03:09.884985 containerd[1899]: time="2026-01-23T01:03:09.884908000Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Jan 23 01:03:09.884985 containerd[1899]: time="2026-01-23T01:03:09.884927100Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Jan 23 01:03:09.885036 containerd[1899]: time="2026-01-23T01:03:09.884993900Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Jan 23 01:03:09.885036 containerd[1899]: time="2026-01-23T01:03:09.885012400Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Jan 23 01:03:09.885036 containerd[1899]: time="2026-01-23T01:03:09.885025400Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Jan 23 01:03:09.885113 containerd[1899]: time="2026-01-23T01:03:09.885042100Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Jan 23 01:03:09.885113 containerd[1899]: time="2026-01-23T01:03:09.885056600Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Jan 23 01:03:09.885113 containerd[1899]: time="2026-01-23T01:03:09.885081300Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Jan 23 01:03:09.885113 containerd[1899]: time="2026-01-23T01:03:09.885097500Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Jan 23 01:03:09.885113 containerd[1899]: time="2026-01-23T01:03:09.885108600Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Jan 23 01:03:09.885212 containerd[1899]: time="2026-01-23T01:03:09.885121300Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Jan 23 01:03:09.885279 containerd[1899]: time="2026-01-23T01:03:09.885251300Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Jan 23 01:03:09.885311 containerd[1899]: time="2026-01-23T01:03:09.885280900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Jan 23 01:03:09.885311 containerd[1899]: time="2026-01-23T01:03:09.885298100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Jan 23 01:03:09.885355 containerd[1899]: time="2026-01-23T01:03:09.885325100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Jan 23 01:03:09.885355 containerd[1899]: time="2026-01-23T01:03:09.885341100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Jan 23 01:03:09.885355 containerd[1899]: time="2026-01-23T01:03:09.885352000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Jan 23 01:03:09.885420 containerd[1899]: time="2026-01-23T01:03:09.885363500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Jan 23 01:03:09.885420 containerd[1899]: time="2026-01-23T01:03:09.885374600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Jan 23 01:03:09.885420 containerd[1899]: time="2026-01-23T01:03:09.885387100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Jan 23 01:03:09.885420 containerd[1899]: time="2026-01-23T01:03:09.885397900Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Jan 23 01:03:09.885420 containerd[1899]: time="2026-01-23T01:03:09.885408700Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Jan 23 01:03:09.885525 containerd[1899]: time="2026-01-23T01:03:09.885453400Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Jan 23 01:03:09.885525 containerd[1899]: time="2026-01-23T01:03:09.885469400Z" level=info msg="Start snapshots syncer" Jan 23 01:03:09.885525 containerd[1899]: time="2026-01-23T01:03:09.885497600Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Jan 23 01:03:09.885791 containerd[1899]: time="2026-01-23T01:03:09.885748300Z" 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\"}" Jan 23 01:03:09.885902 containerd[1899]: time="2026-01-23T01:03:09.885815500Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Jan 23 01:03:09.885902 containerd[1899]: time="2026-01-23T01:03:09.885871000Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Jan 23 01:03:09.886032 containerd[1899]: time="2026-01-23T01:03:09.886005600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Jan 23 01:03:09.886074 containerd[1899]: time="2026-01-23T01:03:09.886035800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Jan 23 01:03:09.886074 containerd[1899]: time="2026-01-23T01:03:09.886049800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Jan 23 01:03:09.886074 containerd[1899]: time="2026-01-23T01:03:09.886062300Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Jan 23 01:03:09.886137 containerd[1899]: time="2026-01-23T01:03:09.886077000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Jan 23 01:03:09.886137 containerd[1899]: time="2026-01-23T01:03:09.886089100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Jan 23 01:03:09.886137 containerd[1899]: time="2026-01-23T01:03:09.886100200Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Jan 23 01:03:09.886137 containerd[1899]: time="2026-01-23T01:03:09.886121200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Jan 23 01:03:09.886137 containerd[1899]: time="2026-01-23T01:03:09.886133800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Jan 23 01:03:09.886238 containerd[1899]: time="2026-01-23T01:03:09.886146000Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Jan 23 01:03:09.886238 containerd[1899]: time="2026-01-23T01:03:09.886184700Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Jan 23 01:03:09.886238 containerd[1899]: time="2026-01-23T01:03:09.886219100Z" 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 Jan 23 01:03:09.886238 containerd[1899]: time="2026-01-23T01:03:09.886230500Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jan 23 01:03:09.886321 containerd[1899]: time="2026-01-23T01:03:09.886240900Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jan 23 01:03:09.886321 containerd[1899]: time="2026-01-23T01:03:09.886249300Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Jan 23 01:03:09.886321 containerd[1899]: time="2026-01-23T01:03:09.886272400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Jan 23 01:03:09.886321 containerd[1899]: time="2026-01-23T01:03:09.886295100Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Jan 23 01:03:09.886321 containerd[1899]: time="2026-01-23T01:03:09.886312400Z" level=info msg="runtime interface created" Jan 23 01:03:09.886321 containerd[1899]: time="2026-01-23T01:03:09.886319100Z" level=info msg="created NRI interface" Jan 23 01:03:09.886444 containerd[1899]: time="2026-01-23T01:03:09.886327800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Jan 23 01:03:09.886444 containerd[1899]: time="2026-01-23T01:03:09.886340800Z" level=info msg="Connect containerd service" Jan 23 01:03:09.886444 containerd[1899]: time="2026-01-23T01:03:09.886376100Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Jan 23 01:03:09.887107 containerd[1899]: time="2026-01-23T01:03:09.887071800Z" 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" Jan 23 01:03:10.380333 containerd[1899]: time="2026-01-23T01:03:10.380260500Z" level=info msg="Start subscribing containerd event" Jan 23 01:03:10.380668 containerd[1899]: time="2026-01-23T01:03:10.380613700Z" level=info msg="Start recovering state" Jan 23 01:03:10.380759 containerd[1899]: time="2026-01-23T01:03:10.380732900Z" level=info msg="Start event monitor" Jan 23 01:03:10.380759 containerd[1899]: time="2026-01-23T01:03:10.380754100Z" level=info msg="Start cni network conf syncer for default" Jan 23 01:03:10.380759 containerd[1899]: time="2026-01-23T01:03:10.380764400Z" level=info msg="Start streaming server" Jan 23 01:03:10.380875 containerd[1899]: time="2026-01-23T01:03:10.380772900Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Jan 23 01:03:10.380875 containerd[1899]: time="2026-01-23T01:03:10.380781000Z" level=info msg="runtime interface starting up..." Jan 23 01:03:10.380875 containerd[1899]: time="2026-01-23T01:03:10.380786600Z" level=info msg="starting plugins..." Jan 23 01:03:10.380875 containerd[1899]: time="2026-01-23T01:03:10.380800400Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Jan 23 01:03:10.380875 containerd[1899]: time="2026-01-23T01:03:10.380842700Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jan 23 01:03:10.381016 containerd[1899]: time="2026-01-23T01:03:10.380901300Z" level=info msg=serving... address=/run/containerd/containerd.sock Jan 23 01:03:10.381016 containerd[1899]: time="2026-01-23T01:03:10.381005100Z" level=info msg="containerd successfully booted in 0.535121s" Jan 23 01:03:10.381092 systemd[1]: Started containerd.service - containerd container runtime. Jan 23 01:03:10.872771 waagent[2014]: 2026-01-23T01:03:10.872688Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Jan 23 01:03:10.877575 waagent[2014]: 2026-01-23T01:03:10.877526Z INFO Daemon Daemon OS: flatcar 4459.2.2 Jan 23 01:03:10.881042 waagent[2014]: 2026-01-23T01:03:10.880996Z INFO Daemon Daemon Python: 3.11.13 Jan 23 01:03:10.884315 waagent[2014]: 2026-01-23T01:03:10.884275Z INFO Daemon Daemon Run daemon Jan 23 01:03:10.887572 waagent[2014]: 2026-01-23T01:03:10.887529Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4459.2.2' Jan 23 01:03:10.894172 waagent[2014]: 2026-01-23T01:03:10.894125Z INFO Daemon Daemon Using waagent for provisioning Jan 23 01:03:10.898975 waagent[2014]: 2026-01-23T01:03:10.898916Z INFO Daemon Daemon Activate resource disk Jan 23 01:03:10.902786 waagent[2014]: 2026-01-23T01:03:10.902742Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Jan 23 01:03:10.910270 waagent[2014]: 2026-01-23T01:03:10.910222Z INFO Daemon Daemon Found device: sdb Jan 23 01:03:10.920076 waagent[2014]: 2026-01-23T01:03:10.920027Z INFO Daemon Daemon Examining partition table Jan 23 01:03:10.947430 waagent[2014]: 2026-01-23T01:03:10.947380Z INFO Daemon Daemon GPT not detected, determining filesystem Jan 23 01:03:10.974541 waagent[2014]: 2026-01-23T01:03:10.974477Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Jan 23 01:03:10.979044 waagent[2014]: 2026-01-23T01:03:10.978993Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Jan 23 01:03:11.672375 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#465 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jan 23 01:03:11.685849 kernel: sdb: sdb1 Jan 23 01:03:11.949633 waagent[2014]: 2026-01-23T01:03:11.949478Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Jan 23 01:03:11.968582 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#519 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jan 23 01:03:11.988974 kernel: sdb: sdb1 Jan 23 01:03:11.989304 waagent[2014]: 2026-01-23T01:03:11.989248Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Jan 23 01:03:15.943846 setup-nvidia[2034]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 529M 0 1760k 0 0 4141k 0 0:02:10 --:--:-- 0:02:10 4141k\u000d 10 529M 10 57.1M 0 0 39.9M 0 0:00:13 0:00:01 0:00:12 39.9M\u000d 30 529M 30 160M 0 0 66.2M 0 0:00:07 0:00:02 0:00:05 66.2M\u000d 52 529M 52 277M 0 0 81.3M 0 0:00:06 0:00:03 0:00:03 81.3M\u000d 71 529M 71 380M 0 0 86.1M 0 0:00:06 0:00:04 0:00:02 86.1M\u000d 83 529M 83 444M 0 0 82.1M 0 0:00:06 0:00:05 0:00:01 88.7M\u000d100 529M 100 529M 0 0 86.4M 0 0:00:06 0:00:06 --:--:-- 100M Jan 23 01:03:17.379439 login[2017]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Jan 23 01:03:17.381234 login[2018]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Jan 23 01:03:17.390543 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Jan 23 01:03:17.392252 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Jan 23 01:03:17.400439 systemd-logind[1876]: New session 2 of user core. Jan 23 01:03:17.487853 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Jan 23 01:03:17.492331 systemd[1]: Starting user@500.service - User Manager for UID 500... Jan 23 01:03:17.634816 (systemd)[2126]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Jan 23 01:03:17.637826 systemd-logind[1876]: New session c1 of user core. Jan 23 01:03:17.861253 waagent[2014]: 2026-01-23T01:03:17.861180Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Jan 23 01:03:18.061453 systemd[2126]: Queued start job for default target default.target. Jan 23 01:03:18.073235 kernel: EXT4-fs (sdb1): mounted filesystem 3037f2a9-6ed4-46af-a29f-0a4e7548d865 r/w with ordered data mode. Quota mode: none. Jan 23 01:03:18.075358 waagent[2014]: 2026-01-23T01:03:18.075291Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Jan 23 01:03:18.077001 systemd[2126]: Created slice app.slice - User Application Slice. Jan 23 01:03:18.077153 systemd[2126]: Reached target paths.target - Paths. Jan 23 01:03:18.077296 systemd[2126]: Reached target timers.target - Timers. Jan 23 01:03:18.078496 waagent[2014]: 2026-01-23T01:03:18.078174Z INFO Daemon Daemon Clean protocol and wireserver endpoint Jan 23 01:03:18.082581 waagent[2014]: 2026-01-23T01:03:18.079012Z INFO Daemon Daemon Running default provisioning handler Jan 23 01:03:18.081043 systemd[2126]: Starting dbus.socket - D-Bus User Message Bus Socket... Jan 23 01:03:18.095567 waagent[2014]: 2026-01-23T01:03:18.095504Z 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. Jan 23 01:03:18.098113 waagent[2014]: 2026-01-23T01:03:18.098073Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Jan 23 01:03:18.098748 waagent[2014]: 2026-01-23T01:03:18.098713Z INFO Daemon Daemon cloud-init is enabled: False Jan 23 01:03:18.101850 waagent[2014]: 2026-01-23T01:03:18.101809Z INFO Daemon Daemon Copying ovf-env.xml Jan 23 01:03:18.101921 systemd[2126]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jan 23 01:03:18.102074 systemd[2126]: Reached target sockets.target - Sockets. Jan 23 01:03:18.102128 systemd[2126]: Reached target basic.target - Basic System. Jan 23 01:03:18.102174 systemd[2126]: Reached target default.target - Main User Target. Jan 23 01:03:18.102208 systemd[2126]: Startup finished in 457ms. Jan 23 01:03:18.103969 systemd[1]: Started user@500.service - User Manager for UID 500. Jan 23 01:03:18.128371 systemd[1]: Started session-2.scope - Session 2 of User core. Jan 23 01:03:18.226397 waagent[2014]: 2026-01-23T01:03:18.225081Z INFO Daemon Daemon Successfully mounted dvd Jan 23 01:03:18.258613 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Jan 23 01:03:18.267220 waagent[2014]: 2026-01-23T01:03:18.264779Z INFO Daemon Daemon Detect protocol endpoint Jan 23 01:03:18.267220 waagent[2014]: 2026-01-23T01:03:18.266508Z INFO Daemon Daemon Clean protocol and wireserver endpoint Jan 23 01:03:18.269992 waagent[2014]: 2026-01-23T01:03:18.269938Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Jan 23 01:03:18.270659 waagent[2014]: 2026-01-23T01:03:18.270624Z INFO Daemon Daemon Test for route to 168.63.129.16 Jan 23 01:03:18.273690 waagent[2014]: 2026-01-23T01:03:18.273650Z INFO Daemon Daemon Route to 168.63.129.16 exists Jan 23 01:03:18.274886 waagent[2014]: 2026-01-23T01:03:18.274851Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Jan 23 01:03:18.286363 waagent[2014]: 2026-01-23T01:03:18.286324Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Jan 23 01:03:18.292229 waagent[2014]: 2026-01-23T01:03:18.290198Z INFO Daemon Daemon Wire protocol version:2012-11-30 Jan 23 01:03:18.292229 waagent[2014]: 2026-01-23T01:03:18.290785Z INFO Daemon Daemon Server preferred version:2015-04-05 Jan 23 01:03:18.379838 login[2017]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Jan 23 01:03:18.385128 systemd-logind[1876]: New session 1 of user core. Jan 23 01:03:18.389787 waagent[2014]: 2026-01-23T01:03:18.389710Z INFO Daemon Daemon Initializing goal state during protocol detection Jan 23 01:03:18.392088 waagent[2014]: 2026-01-23T01:03:18.391417Z INFO Daemon Daemon Forcing an update of the goal state. Jan 23 01:03:18.394109 systemd[1]: Started session-1.scope - Session 1 of User core. Jan 23 01:03:18.398056 waagent[2014]: 2026-01-23T01:03:18.395808Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Jan 23 01:03:18.430488 waagent[2014]: 2026-01-23T01:03:18.430444Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.177 Jan 23 01:03:18.432763 waagent[2014]: 2026-01-23T01:03:18.432722Z INFO Daemon Jan 23 01:03:18.433713 waagent[2014]: 2026-01-23T01:03:18.433677Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: a4244919-e5eb-4475-bc22-c56f366db2d4 eTag: 1880548378585015090 source: Fabric] Jan 23 01:03:18.435429 waagent[2014]: 2026-01-23T01:03:18.435363Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Jan 23 01:03:18.436134 waagent[2014]: 2026-01-23T01:03:18.436098Z INFO Daemon Jan 23 01:03:18.436569 waagent[2014]: 2026-01-23T01:03:18.436536Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Jan 23 01:03:18.464329 waagent[2014]: 2026-01-23T01:03:18.458525Z INFO Daemon Daemon Downloading artifacts profile blob Jan 23 01:03:18.582603 waagent[2014]: 2026-01-23T01:03:18.582542Z INFO Daemon Downloaded certificate {'thumbprint': '85E79F82F62EB61FF833534B1B41AFBD86D2B23B', 'hasPrivateKey': True} Jan 23 01:03:18.584732 waagent[2014]: 2026-01-23T01:03:18.584692Z INFO Daemon Fetch goal state completed Jan 23 01:03:18.615623 waagent[2014]: 2026-01-23T01:03:18.615572Z INFO Daemon Daemon Starting provisioning Jan 23 01:03:18.619673 waagent[2014]: 2026-01-23T01:03:18.619631Z INFO Daemon Daemon Handle ovf-env.xml. Jan 23 01:03:18.620460 waagent[2014]: 2026-01-23T01:03:18.620422Z INFO Daemon Daemon Set hostname [ci-4459.2.2-n-a6b7843ddb] Jan 23 01:03:18.657426 waagent[2014]: 2026-01-23T01:03:18.654315Z INFO Daemon Daemon Publish hostname [ci-4459.2.2-n-a6b7843ddb] Jan 23 01:03:18.657426 waagent[2014]: 2026-01-23T01:03:18.656171Z INFO Daemon Daemon Examine /proc/net/route for primary interface Jan 23 01:03:18.658363 waagent[2014]: 2026-01-23T01:03:18.658327Z INFO Daemon Daemon Primary interface is [eth0] Jan 23 01:03:18.669843 systemd-networkd[1572]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jan 23 01:03:18.670143 systemd-networkd[1572]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jan 23 01:03:18.670235 systemd-networkd[1572]: eth0: DHCP lease lost Jan 23 01:03:18.671220 waagent[2014]: 2026-01-23T01:03:18.671172Z INFO Daemon Daemon Create user account if not exists Jan 23 01:03:18.673715 waagent[2014]: 2026-01-23T01:03:18.673676Z INFO Daemon Daemon User core already exists, skip useradd Jan 23 01:03:18.676588 waagent[2014]: 2026-01-23T01:03:18.676550Z INFO Daemon Daemon Configure sudoer Jan 23 01:03:18.700699 waagent[2014]: 2026-01-23T01:03:18.700620Z INFO Daemon Daemon Configure sshd Jan 23 01:03:18.705827 waagent[2014]: 2026-01-23T01:03:18.705785Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Jan 23 01:03:18.707500 systemd-networkd[1572]: eth0: DHCPv4 address 10.200.8.20/24, gateway 10.200.8.1 acquired from 168.63.129.16 Jan 23 01:03:18.710039 waagent[2014]: 2026-01-23T01:03:18.709996Z INFO Daemon Daemon Deploy ssh public key. Jan 23 01:03:19.819904 waagent[2014]: 2026-01-23T01:03:19.819847Z INFO Daemon Daemon Provisioning complete Jan 23 01:03:19.829901 waagent[2014]: 2026-01-23T01:03:19.829843Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Jan 23 01:03:19.831687 waagent[2014]: 2026-01-23T01:03:19.831642Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Jan 23 01:03:19.833090 waagent[2014]: 2026-01-23T01:03:19.833050Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Jan 23 01:03:20.004718 waagent[2184]: 2026-01-23T01:03:20.004640Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Jan 23 01:03:20.005219 waagent[2184]: 2026-01-23T01:03:20.005181Z INFO ExtHandler ExtHandler OS: flatcar 4459.2.2 Jan 23 01:03:20.005370 waagent[2184]: 2026-01-23T01:03:20.005338Z INFO ExtHandler ExtHandler Python: 3.11.13 Jan 23 01:03:20.005520 waagent[2184]: 2026-01-23T01:03:20.005487Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Jan 23 01:03:20.064772 waagent[2184]: 2026-01-23T01:03:20.064694Z 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; Jan 23 01:03:20.065164 waagent[2184]: 2026-01-23T01:03:20.065126Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jan 23 01:03:20.065330 waagent[2184]: 2026-01-23T01:03:20.065296Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Jan 23 01:03:20.075655 waagent[2184]: 2026-01-23T01:03:20.075543Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Jan 23 01:03:20.082792 waagent[2184]: 2026-01-23T01:03:20.082747Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.177 Jan 23 01:03:20.084533 waagent[2184]: 2026-01-23T01:03:20.084493Z INFO ExtHandler Jan 23 01:03:20.084705 waagent[2184]: 2026-01-23T01:03:20.084671Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 2f933207-5264-4023-83af-4a3aaae7f11f eTag: 1880548378585015090 source: Fabric] Jan 23 01:03:20.085139 waagent[2184]: 2026-01-23T01:03:20.085100Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Jan 23 01:03:20.085851 waagent[2184]: 2026-01-23T01:03:20.085812Z INFO ExtHandler Jan 23 01:03:20.086032 waagent[2184]: 2026-01-23T01:03:20.085997Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Jan 23 01:03:20.090390 waagent[2184]: 2026-01-23T01:03:20.090355Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Jan 23 01:03:20.160978 waagent[2184]: 2026-01-23T01:03:20.160656Z INFO ExtHandler Downloaded certificate {'thumbprint': '85E79F82F62EB61FF833534B1B41AFBD86D2B23B', 'hasPrivateKey': True} Jan 23 01:03:20.161691 waagent[2184]: 2026-01-23T01:03:20.161646Z INFO ExtHandler Fetch goal state completed Jan 23 01:03:20.177797 waagent[2184]: 2026-01-23T01:03:20.177123Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.4.2 1 Jul 2025 (Library: OpenSSL 3.4.2 1 Jul 2025) Jan 23 01:03:20.184980 waagent[2184]: 2026-01-23T01:03:20.183295Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2184 Jan 23 01:03:20.184980 waagent[2184]: 2026-01-23T01:03:20.183453Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Jan 23 01:03:20.184980 waagent[2184]: 2026-01-23T01:03:20.183791Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Jan 23 01:03:20.187180 waagent[2184]: 2026-01-23T01:03:20.185634Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4459.2.2', '', 'Flatcar Container Linux by Kinvolk'] Jan 23 01:03:20.187180 waagent[2184]: 2026-01-23T01:03:20.186109Z 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 Jan 23 01:03:20.187180 waagent[2184]: 2026-01-23T01:03:20.186264Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Jan 23 01:03:20.187180 waagent[2184]: 2026-01-23T01:03:20.186851Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Jan 23 01:03:20.266792 waagent[2184]: 2026-01-23T01:03:20.266751Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Jan 23 01:03:20.267151 waagent[2184]: 2026-01-23T01:03:20.267116Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Jan 23 01:03:20.279774 waagent[2184]: 2026-01-23T01:03:20.279194Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Jan 23 01:03:20.289523 systemd[1]: Reload requested from client PID 2199 ('systemctl') (unit waagent.service)... Jan 23 01:03:20.289538 systemd[1]: Reloading... Jan 23 01:03:20.403991 zram_generator::config[2239]: No configuration found. Jan 23 01:03:20.750787 systemd[1]: Reloading finished in 460 ms. Jan 23 01:03:20.768044 waagent[2184]: 2026-01-23T01:03:20.767978Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Jan 23 01:03:20.768918 waagent[2184]: 2026-01-23T01:03:20.768268Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Jan 23 01:03:21.101719 waagent[2184]: 2026-01-23T01:03:21.100592Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Jan 23 01:03:21.101719 waagent[2184]: 2026-01-23T01:03:21.100935Z 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] Jan 23 01:03:21.102112 waagent[2184]: 2026-01-23T01:03:21.101932Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jan 23 01:03:21.102112 waagent[2184]: 2026-01-23T01:03:21.102067Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Jan 23 01:03:21.102342 waagent[2184]: 2026-01-23T01:03:21.102291Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Jan 23 01:03:21.102412 waagent[2184]: 2026-01-23T01:03:21.102351Z INFO ExtHandler ExtHandler Starting env monitor service. Jan 23 01:03:21.102862 waagent[2184]: 2026-01-23T01:03:21.102814Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Jan 23 01:03:21.103137 waagent[2184]: 2026-01-23T01:03:21.103088Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Jan 23 01:03:21.103137 waagent[2184]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Jan 23 01:03:21.103137 waagent[2184]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Jan 23 01:03:21.103137 waagent[2184]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Jan 23 01:03:21.103137 waagent[2184]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Jan 23 01:03:21.103137 waagent[2184]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jan 23 01:03:21.103137 waagent[2184]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jan 23 01:03:21.103318 waagent[2184]: 2026-01-23T01:03:21.103258Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jan 23 01:03:21.103707 waagent[2184]: 2026-01-23T01:03:21.103659Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Jan 23 01:03:21.104227 waagent[2184]: 2026-01-23T01:03:21.104129Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Jan 23 01:03:21.104306 waagent[2184]: 2026-01-23T01:03:21.104257Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Jan 23 01:03:21.104575 waagent[2184]: 2026-01-23T01:03:21.104526Z INFO EnvHandler ExtHandler Configure routes Jan 23 01:03:21.104906 waagent[2184]: 2026-01-23T01:03:21.104850Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Jan 23 01:03:21.105030 waagent[2184]: 2026-01-23T01:03:21.104989Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Jan 23 01:03:21.105184 waagent[2184]: 2026-01-23T01:03:21.105126Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Jan 23 01:03:21.105184 waagent[2184]: 2026-01-23T01:03:21.105079Z INFO EnvHandler ExtHandler Gateway:None Jan 23 01:03:21.105560 waagent[2184]: 2026-01-23T01:03:21.105519Z INFO EnvHandler ExtHandler Routes:None Jan 23 01:03:21.115503 waagent[2184]: 2026-01-23T01:03:21.115456Z INFO ExtHandler ExtHandler Jan 23 01:03:21.115594 waagent[2184]: 2026-01-23T01:03:21.115548Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 81de2b86-1c11-4356-ba41-d268baaa61fb correlation 3848b2a8-842c-4ad6-8633-06fa4a588b57 created: 2026-01-23T01:02:02.374680Z] Jan 23 01:03:21.115950 waagent[2184]: 2026-01-23T01:03:21.115908Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Jan 23 01:03:21.116567 waagent[2184]: 2026-01-23T01:03:21.116528Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 1 ms] Jan 23 01:03:21.145072 waagent[2184]: 2026-01-23T01:03:21.145019Z 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 Jan 23 01:03:21.145072 waagent[2184]: Try `iptables -h' or 'iptables --help' for more information.) Jan 23 01:03:21.146082 waagent[2184]: 2026-01-23T01:03:21.145976Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: F21D933E-C3AD-4B55-A459-3EE611CD2B58;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Jan 23 01:03:21.182654 waagent[2184]: 2026-01-23T01:03:21.182247Z INFO MonitorHandler ExtHandler Network interfaces: Jan 23 01:03:21.182654 waagent[2184]: Executing ['ip', '-a', '-o', 'link']: Jan 23 01:03:21.182654 waagent[2184]: 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 Jan 23 01:03:21.182654 waagent[2184]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:1e:52:20:08:46 brd ff:ff:ff:ff:ff:ff Jan 23 01:03:21.182654 waagent[2184]: Executing ['ip', '-4', '-a', '-o', 'address']: Jan 23 01:03:21.182654 waagent[2184]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Jan 23 01:03:21.182654 waagent[2184]: 2: eth0 inet 10.200.8.20/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Jan 23 01:03:21.182654 waagent[2184]: Executing ['ip', '-6', '-a', '-o', 'address']: Jan 23 01:03:21.182654 waagent[2184]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Jan 23 01:03:21.182654 waagent[2184]: 2: eth0 inet6 fe80::7e1e:52ff:fe20:846/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Jan 23 01:03:21.230813 waagent[2184]: 2026-01-23T01:03:21.230763Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Jan 23 01:03:21.230813 waagent[2184]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Jan 23 01:03:21.230813 waagent[2184]: pkts bytes target prot opt in out source destination Jan 23 01:03:21.230813 waagent[2184]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Jan 23 01:03:21.230813 waagent[2184]: pkts bytes target prot opt in out source destination Jan 23 01:03:21.230813 waagent[2184]: Chain OUTPUT (policy ACCEPT 1 packets, 52 bytes) Jan 23 01:03:21.230813 waagent[2184]: pkts bytes target prot opt in out source destination Jan 23 01:03:21.230813 waagent[2184]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Jan 23 01:03:21.230813 waagent[2184]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Jan 23 01:03:21.230813 waagent[2184]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Jan 23 01:03:21.234947 waagent[2184]: 2026-01-23T01:03:21.234909Z INFO EnvHandler ExtHandler Current Firewall rules: Jan 23 01:03:21.234947 waagent[2184]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Jan 23 01:03:21.234947 waagent[2184]: pkts bytes target prot opt in out source destination Jan 23 01:03:21.234947 waagent[2184]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Jan 23 01:03:21.234947 waagent[2184]: pkts bytes target prot opt in out source destination Jan 23 01:03:21.234947 waagent[2184]: Chain OUTPUT (policy ACCEPT 1 packets, 52 bytes) Jan 23 01:03:21.234947 waagent[2184]: pkts bytes target prot opt in out source destination Jan 23 01:03:21.234947 waagent[2184]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Jan 23 01:03:21.234947 waagent[2184]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Jan 23 01:03:21.234947 waagent[2184]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Jan 23 01:03:21.235471 waagent[2184]: 2026-01-23T01:03:21.235439Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Jan 23 01:03:21.235690 waagent[2184]: 2026-01-23T01:03:21.235660Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Jan 23 01:03:26.789821 setup-nvidia[1870]: Downloading NVIDIA 535.261.03 Driver Jan 23 01:03:26.794130 setup-nvidia[2331]: % Total % Received % Xferd Average Speed Time Time Time Current Jan 23 01:03:26.794354 setup-nvidia[2331]: Dload Upload Total Spent Left Speed Jan 23 01:03:32.484612 chronyd[1854]: Selected source PHC0 Jan 23 01:03:48.563818 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Jan 23 01:03:48.569380 systemd[1]: Started sshd@0-10.200.8.20:22-10.200.16.10:52572.service - OpenSSH per-connection server daemon (10.200.16.10:52572). Jan 23 01:03:50.802539 sshd[2333]: Accepted publickey for core from 10.200.16.10 port 52572 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:03:50.803724 sshd-session[2333]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:03:50.812240 systemd-logind[1876]: New session 3 of user core. Jan 23 01:03:50.816085 systemd[1]: Started session-3.scope - Session 3 of User core. Jan 23 01:03:51.395900 systemd[1]: Started sshd@1-10.200.8.20:22-10.200.16.10:36800.service - OpenSSH per-connection server daemon (10.200.16.10:36800). Jan 23 01:03:52.019967 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Jan 23 01:03:52.075713 sshd[2339]: Accepted publickey for core from 10.200.16.10 port 36800 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:03:52.077059 sshd-session[2339]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:03:52.082661 systemd-logind[1876]: New session 4 of user core. Jan 23 01:03:52.088086 systemd[1]: Started session-4.scope - Session 4 of User core. Jan 23 01:03:52.552970 sshd[2342]: Connection closed by 10.200.16.10 port 36800 Jan 23 01:03:52.553372 sshd-session[2339]: pam_unix(sshd:session): session closed for user core Jan 23 01:03:52.556998 systemd[1]: sshd@1-10.200.8.20:22-10.200.16.10:36800.service: Deactivated successfully. Jan 23 01:03:52.558815 systemd[1]: session-4.scope: Deactivated successfully. Jan 23 01:03:52.559688 systemd-logind[1876]: Session 4 logged out. Waiting for processes to exit. Jan 23 01:03:52.560582 systemd-logind[1876]: Removed session 4. Jan 23 01:03:52.676504 systemd[1]: Started sshd@2-10.200.8.20:22-10.200.16.10:36810.service - OpenSSH per-connection server daemon (10.200.16.10:36810). Jan 23 01:03:53.359526 sshd[2348]: Accepted publickey for core from 10.200.16.10 port 36810 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:03:53.360749 sshd-session[2348]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:03:53.365219 systemd-logind[1876]: New session 5 of user core. Jan 23 01:03:53.377090 systemd[1]: Started session-5.scope - Session 5 of User core. Jan 23 01:03:53.836186 sshd[2351]: Connection closed by 10.200.16.10 port 36810 Jan 23 01:03:53.836518 sshd-session[2348]: pam_unix(sshd:session): session closed for user core Jan 23 01:03:53.840186 systemd[1]: sshd@2-10.200.8.20:22-10.200.16.10:36810.service: Deactivated successfully. Jan 23 01:03:53.841746 systemd[1]: session-5.scope: Deactivated successfully. Jan 23 01:03:53.842463 systemd-logind[1876]: Session 5 logged out. Waiting for processes to exit. Jan 23 01:03:53.843385 systemd-logind[1876]: Removed session 5. Jan 23 01:03:54.001247 update_engine[1879]: I20260123 01:03:54.001160 1879 update_attempter.cc:509] Updating boot flags... Jan 23 01:04:03.962347 systemd[1]: Started sshd@3-10.200.8.20:22-10.200.16.10:42494.service - OpenSSH per-connection server daemon (10.200.16.10:42494). Jan 23 01:04:09.976553 sshd[2525]: Accepted publickey for core from 10.200.16.10 port 42494 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:04:09.976772 sshd-session[2525]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:04:09.981149 systemd-logind[1876]: New session 6 of user core. Jan 23 01:04:09.996075 systemd[1]: Started session-6.scope - Session 6 of User core. Jan 23 01:04:10.370872 sshd[2528]: Connection closed by 10.200.16.10 port 42494 Jan 23 01:04:10.371208 sshd-session[2525]: pam_unix(sshd:session): session closed for user core Jan 23 01:04:10.374831 systemd[1]: sshd@3-10.200.8.20:22-10.200.16.10:42494.service: Deactivated successfully. Jan 23 01:04:10.376635 systemd[1]: session-6.scope: Deactivated successfully. Jan 23 01:04:10.377424 systemd-logind[1876]: Session 6 logged out. Waiting for processes to exit. Jan 23 01:04:10.378360 systemd-logind[1876]: Removed session 6. Jan 23 01:04:10.495873 systemd[1]: Started sshd@4-10.200.8.20:22-10.200.16.10:52726.service - OpenSSH per-connection server daemon (10.200.16.10:52726). Jan 23 01:04:11.193925 sshd[2534]: Accepted publickey for core from 10.200.16.10 port 52726 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:04:11.195186 sshd-session[2534]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:04:11.199365 systemd-logind[1876]: New session 7 of user core. Jan 23 01:04:11.220091 systemd[1]: Started session-7.scope - Session 7 of User core. Jan 23 01:04:11.674966 sshd[2537]: Connection closed by 10.200.16.10 port 52726 Jan 23 01:04:11.675302 sshd-session[2534]: pam_unix(sshd:session): session closed for user core Jan 23 01:04:11.678927 systemd[1]: sshd@4-10.200.8.20:22-10.200.16.10:52726.service: Deactivated successfully. Jan 23 01:04:11.680574 systemd[1]: session-7.scope: Deactivated successfully. Jan 23 01:04:11.681757 systemd-logind[1876]: Session 7 logged out. Waiting for processes to exit. Jan 23 01:04:11.682741 systemd-logind[1876]: Removed session 7. Jan 23 01:04:21.799380 systemd[1]: Started sshd@5-10.200.8.20:22-10.200.16.10:44456.service - OpenSSH per-connection server daemon (10.200.16.10:44456). Jan 23 01:04:22.479756 sshd[2543]: Accepted publickey for core from 10.200.16.10 port 44456 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:04:22.481007 sshd-session[2543]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:04:22.485354 systemd-logind[1876]: New session 8 of user core. Jan 23 01:04:22.497087 systemd[1]: Started session-8.scope - Session 8 of User core. Jan 23 01:04:22.955320 sshd[2546]: Connection closed by 10.200.16.10 port 44456 Jan 23 01:04:22.955665 sshd-session[2543]: pam_unix(sshd:session): session closed for user core Jan 23 01:04:22.959234 systemd[1]: sshd@5-10.200.8.20:22-10.200.16.10:44456.service: Deactivated successfully. Jan 23 01:04:22.960907 systemd[1]: session-8.scope: Deactivated successfully. Jan 23 01:04:22.961648 systemd-logind[1876]: Session 8 logged out. Waiting for processes to exit. Jan 23 01:04:22.962582 systemd-logind[1876]: Removed session 8. Jan 23 01:04:23.076687 systemd[1]: Started sshd@6-10.200.8.20:22-10.200.16.10:44460.service - OpenSSH per-connection server daemon (10.200.16.10:44460). Jan 23 01:04:23.774565 sshd[2552]: Accepted publickey for core from 10.200.16.10 port 44460 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:04:23.775749 sshd-session[2552]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:04:23.780127 systemd-logind[1876]: New session 9 of user core. Jan 23 01:04:23.795073 systemd[1]: Started session-9.scope - Session 9 of User core. Jan 23 01:04:24.251583 sshd[2555]: Connection closed by 10.200.16.10 port 44460 Jan 23 01:04:24.251975 sshd-session[2552]: pam_unix(sshd:session): session closed for user core Jan 23 01:04:24.255544 systemd[1]: sshd@6-10.200.8.20:22-10.200.16.10:44460.service: Deactivated successfully. Jan 23 01:04:24.257166 systemd[1]: session-9.scope: Deactivated successfully. Jan 23 01:04:24.257846 systemd-logind[1876]: Session 9 logged out. Waiting for processes to exit. Jan 23 01:04:24.258940 systemd-logind[1876]: Removed session 9. Jan 23 01:04:29.518691 setup-nvidia[2331]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 325M 0 16144 0 0 18408 0 5:09:26 --:--:-- 5:09:26 18408\u000d 0 325M 0 2049k 0 0 1192k 0 0:04:39 0:00:01 0:04:38 1192k\u000d 1 325M 1 6145k 0 0 2329k 0 0:02:23 0:00:02 0:02:21 2329k\u000d 3 325M 3 10.0M 0 0 2918k 0 0:01:54 0:00:03 0:01:51 2918k\u000d 4 325M 4 16.0M 0 0 3412k 0 0:01:37 0:00:04 0:01:33 3412k\u000d 6 325M 6 20.0M 0 0 3604k 0 0:01:32 0:00:05 0:01:27 4257k\u000d 7 325M 7 24.0M 0 0 3746k 0 0:01:29 0:00:06 0:01:23 4651k\u000d 8 325M 8 28.0M 0 0 3848k 0 0:01:26 0:00:07 0:01:19 4679k\u000d 10 325M 10 34.1M 0 0 3982k 0 0:01:23 0:00:08 0:01:15 4697k\u000d 11 325M 11 38.0M 0 0 4038k 0 0:01:22 0:00:09 0:01:13 4658k\u000d 12 325M 12 42.0M 0 0 4117k 0 0:01:21 0:00:10 0:01:11 4730k\u000d 14 325M 14 48.0M 0 0 4252k 0 0:01:18 0:00:11 0:01:07 4915k\u000d 16 325M 16 54.0M 0 0 4377k 0 0:01:16 0:00:12 0:01:04 5135k\u000d 18 325M 18 60.0M 0 0 4469k 0 0:01:14 0:00:13 0:01:01 5324k\u000d 19 325M 19 64.0M 0 0 4528k 0 0:01:13 0:00:14 0:00:59 5507k\u000d 21 325M 21 70.1M 0 0 4611k 0 0:01:12 0:00:15 0:00:57 5620k\u000d 23 325M 23 76.1M 0 0 4678k 0 0:01:11 0:00:16 0:00:55 5649k\u000d 25 325M 25 82.1M 0 0 4739k 0 0:01:10 0:00:17 0:00:53 5641k\u000d 26 325M 26 86.0M 0 0 4774k 0 0:01:09 0:00:18 0:00:51 5666k\u000d 28 325M 28 92.1M 0 0 4828k 0 0:01:09 0:00:19 0:00:50 5684k\u000d 30 325M 30 98.0M 0 0 4867k 0 0:01:08 0:00:20 0:00:48 5655k\u000d 31 325M 31 104M 0 0 4909k 0 0:01:07 0:00:21 0:00:46 5674k\u000d 33 325M 33 108M 0 0 4773k 0 0:01:09 0:00:23 0:00:46 4884k\u000d 34 325M 34 112M 0 0 4929k 0 0:01:07 0:00:23 0:00:44 5502k\u000d 36 325M 36 118M 0 0 4932k 0 0:01:07 0:00:24 0:00:43 5342k\u000d 38 325M 38 124M 0 0 4965k 0 0:01:07 0:00:25 0:00:42 5370k\u000d 39 325M 39 130M 0 0 4993k 0 0:01:06 0:00:26 0:00:40 5354k\u000d 41 325M 41 135M 0 0 4897k 0 0:01:08 0:00:28 0:00:40 5451k\u000d 42 325M 42 140M 0 0 5035k 0 0:01:06 0:00:28 0:00:38 5530k\u000d 44 325M 44 146M 0 0 5065k 0 0:01:05 0:00:29 0:00:36 5711k\u000d 46 325M 46 152M 0 0 5087k 0 0:01:05 0:00:30 0:00:35 5710k\u000d 48 325M 48 158M 0 0 5105k 0 0:01:05 0:00:31 0:00:34 5705k\u000d 50 325M 50 164M 0 0 5127k 0 0:01:05 0:00:32 0:00:33 6626k\u000d 51 325M 51 168M 0 0 5133k 0 0:01:05 0:00:33 0:00:32 5688k\u000d 53 325M 53 174M 0 0 5143k 0 0:01:04 0:00:34 0:00:30 5598k\u000d 54 325M 54 178M 0 0 5135k 0 0:01:04 0:00:35 0:00:29 5432k\u000d 56 325M 56 184M 0 0 5140k 0 0:01:04 0:00:36 0:00:28 5359k\u000d 58 325M 58 190M 0 0 4505k 0 0:01:14 0:00:43 0:00:31 2554k\u000d 60 325M 60 198M 0 0 4669k 0 0:01:11 0:00:43 0:00:28 3104k\u000d 68 325M 68 224M 0 0 5162k 0 0:01:04 0:00:44 0:00:20 5225k\u000d 70 325M 70 230M 0 0 5164k 0 0:01:04 0:00:45 0:00:19 5268k\u000d 72 325M 72 236M 0 0 5174k 0 0:01:04 0:00:46 0:00:18 5297k\u000d 74 325M 74 241M 0 0 5212k 0 0:01:04 0:00:47 0:00:17 12.0M\u000d 75 325M 75 246M 0 0 5187k 0 0:01:04 0:00:48 0:00:16 9584k\u000d 77 325M 77 252M 0 0 5198k 0 0:01:04 0:00:49 0:00:15 5511k\u000d 79 325M 79 258M 0 0 5209k 0 0:01:04 0:00:50 0:00:14 5606k\u000d 80 325M 80 262M 0 0 5213k 0 0:01:04 0:00:51 0:00:13 5600k\u000d 82 325M 82 268M 0 0 5223k 0 0:01:03 0:00:52 0:00:11 5321k\u000d 84 325M 84 274M 0 0 5226k 0 0:01:03 0:00:53 0:00:10 5588k\u000d 85 325M 85 280M 0 0 5237k 0 0:01:03 0:00:54 0:00:09 5618k\u000d 87 325M 87 284M 0 0 5243k 0 0:01:03 0:00:55 0:00:08 5604k\u000d 88 325M 88 290M 0 0 5251k 0 0:01:03 0:00:56 0:00:07 5634k\u000d 90 325M 90 296M 0 0 5260k 0 0:01:03 0:00:57 0:00:06 5644k\u000d 92 325M 92 302M 0 0 5266k 0 0:01:03 0:00:58 0:00:05 5701k\u000d 94 325M 94 306M 0 0 5285k 0 0:01:03 0:00:59 0:00:04 5846k\u000d 95 325M 95 312M 0 0 5277k 0 0:01:03 0:01:00 0:00:03 5652k\u000d 97 325M 97 317M 0 0 5284k 0 0:01:03 0:01:01 0:00:02 5670k\u000d 99 325M 99 324M 0 0 5292k 0 0:01:03 0:01:02 0:00:01 5662k\u000d100 325M 100 325M 0 0 5320k 0 0:01:02 0:01:02 --:--:-- 6113k Jan 23 01:04:29.589908 setup-nvidia[1870]: Extract the NVIDIA Driver Installer 535.261.03 Jan 23 01:04:29.590131 setup-nvidia[1870]: /opt/nvidia/workdir/nvidia-workdir / Jan 23 01:04:29.608316 setup-nvidia[2562]: Creating directory NVIDIA-Linux-x86_64-535.261.03 Jan 23 01:04:29.867080 setup-nvidia[2562]: Verifying archive integrity... OK Jan 23 01:04:30.572787 setup-nvidia[2562]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.261.03 Jan 23 01:04:32.412592 setup-nvidia[2588]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Jan 23 01:04:32.455135 setup-nvidia[1870]: / Jan 23 01:04:32.455135 setup-nvidia[1870]: Spawn system-nspawn container to install the NVIDIA drivers Jan 23 01:04:32.644125 sudo[2595]: 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+nightly-20260122-2100.bin --overlay=/usr/lib/modules::/usr/lib/modules --bind=/opt/nvidia/workdir/nvidia-workdir:/nvidia --bind=/usr/lib/nvidia/bin:/app/bin/ /app/bin/install-nvidia NVIDIA-Linux-x86_64-535.261.03 Jan 23 01:04:32.645935 sudo[2595]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 23 01:04:32.651832 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Jan 23 01:04:32.654457 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Jan 23 01:04:32.679504 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Jan 23 01:04:32.683270 systemd[1]: Starting user@0.service - User Manager for UID 0... Jan 23 01:04:32.699868 (systemd)[2598]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Jan 23 01:04:32.702068 systemd-logind[1876]: New session c3 of user root. Jan 23 01:04:32.873121 systemd[2598]: Queued start job for default target default.target. Jan 23 01:04:32.896087 systemd[2598]: Created slice app.slice - User Application Slice. Jan 23 01:04:32.896117 systemd[2598]: Reached target paths.target - Paths. Jan 23 01:04:32.896164 systemd[2598]: Reached target timers.target - Timers. Jan 23 01:04:32.897276 systemd[2598]: Starting dbus.socket - D-Bus User Message Bus Socket... Jan 23 01:04:32.907921 systemd[2598]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jan 23 01:04:32.908107 systemd[2598]: Reached target sockets.target - Sockets. Jan 23 01:04:32.908162 systemd[2598]: Reached target basic.target - Basic System. Jan 23 01:04:32.908206 systemd[2598]: Reached target default.target - Main User Target. Jan 23 01:04:32.908237 systemd[2598]: Startup finished in 200ms. Jan 23 01:04:32.908264 systemd[1]: Started user@0.service - User Manager for UID 0. Jan 23 01:04:32.911195 systemd[1]: Started session-c2.scope - Session c2 of User root. Jan 23 01:04:32.956978 kernel: loop6: detected capacity change from 0 to 12644352 Jan 23 01:04:32.995974 kernel: loop6: p9 Jan 23 01:04:33.045909 setup-nvidia[2607]: ░ Spawning container flatcardevelopercontainer-4459.2.2nightly-20260122-2100.bin on /opt/nvidia/workdir/flatcar_developer_container-4459.2.2+nightly-20260122-2100.bin. Jan 23 01:04:33.165486 kernel: EXT4-fs (loop6p9): write access unavailable, skipping orphan cleanup Jan 23 01:04:33.165609 kernel: EXT4-fs (loop6p9): mounted filesystem fd657f23-885c-4ff0-b4c6-ecd1f54eb88a ro without journal. Quota mode: none. Jan 23 01:04:34.374340 systemd[1]: Started sshd@7-10.200.8.20:22-10.200.16.10:47340.service - OpenSSH per-connection server daemon (10.200.16.10:47340). Jan 23 01:04:35.078781 sshd[2674]: Accepted publickey for core from 10.200.16.10 port 47340 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:04:35.080009 sshd-session[2674]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:04:35.084258 systemd-logind[1876]: New session 10 of user core. Jan 23 01:04:35.098103 systemd[1]: Started session-10.scope - Session 10 of User core. Jan 23 01:04:35.553325 sshd[2818]: Connection closed by 10.200.16.10 port 47340 Jan 23 01:04:35.553662 sshd-session[2674]: pam_unix(sshd:session): session closed for user core Jan 23 01:04:35.557130 systemd[1]: sshd@7-10.200.8.20:22-10.200.16.10:47340.service: Deactivated successfully. Jan 23 01:04:35.558759 systemd[1]: session-10.scope: Deactivated successfully. Jan 23 01:04:35.559449 systemd-logind[1876]: Session 10 logged out. Waiting for processes to exit. Jan 23 01:04:35.560472 systemd-logind[1876]: Removed session 10. Jan 23 01:04:35.683155 systemd[1]: Started sshd@8-10.200.8.20:22-10.200.16.10:47356.service - OpenSSH per-connection server daemon (10.200.16.10:47356). Jan 23 01:04:36.363283 sshd[3025]: Accepted publickey for core from 10.200.16.10 port 47356 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:04:36.364485 sshd-session[3025]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:04:36.368757 systemd-logind[1876]: New session 11 of user core. Jan 23 01:04:36.377095 systemd[1]: Started session-11.scope - Session 11 of User core. Jan 23 01:04:36.841221 sshd[3404]: Connection closed by 10.200.16.10 port 47356 Jan 23 01:04:36.842153 sshd-session[3025]: pam_unix(sshd:session): session closed for user core Jan 23 01:04:36.845654 systemd[1]: sshd@8-10.200.8.20:22-10.200.16.10:47356.service: Deactivated successfully. Jan 23 01:04:36.847234 systemd[1]: session-11.scope: Deactivated successfully. Jan 23 01:04:36.847942 systemd-logind[1876]: Session 11 logged out. Waiting for processes to exit. Jan 23 01:04:36.849030 systemd-logind[1876]: Removed session 11. Jan 23 01:04:46.963927 systemd[1]: Started sshd@9-10.200.8.20:22-10.200.16.10:49238.service - OpenSSH per-connection server daemon (10.200.16.10:49238). Jan 23 01:04:47.653348 sshd[7478]: Accepted publickey for core from 10.200.16.10 port 49238 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:04:47.655076 sshd-session[7478]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:04:47.663025 systemd-logind[1876]: New session 12 of user core. Jan 23 01:04:47.672208 systemd[1]: Started session-12.scope - Session 12 of User core. Jan 23 01:04:48.130635 sshd[7716]: Connection closed by 10.200.16.10 port 49238 Jan 23 01:04:48.131213 sshd-session[7478]: pam_unix(sshd:session): session closed for user core Jan 23 01:04:48.134298 systemd[1]: sshd@9-10.200.8.20:22-10.200.16.10:49238.service: Deactivated successfully. Jan 23 01:04:48.136450 systemd[1]: session-12.scope: Deactivated successfully. Jan 23 01:04:48.137738 systemd-logind[1876]: Session 12 logged out. Waiting for processes to exit. Jan 23 01:04:48.141517 systemd-logind[1876]: Removed session 12. Jan 23 01:04:48.259217 systemd[1]: Started sshd@10-10.200.8.20:22-10.200.16.10:49252.service - OpenSSH per-connection server daemon (10.200.16.10:49252). Jan 23 01:04:49.436443 sshd[7870]: Accepted publickey for core from 10.200.16.10 port 49252 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:04:49.439466 sshd-session[7870]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:04:49.448793 systemd-logind[1876]: New session 13 of user core. Jan 23 01:04:49.458045 systemd[1]: Started session-13.scope - Session 13 of User core. Jan 23 01:04:49.917259 sshd[8344]: Connection closed by 10.200.16.10 port 49252 Jan 23 01:04:49.918020 sshd-session[7870]: pam_unix(sshd:session): session closed for user core Jan 23 01:04:49.923928 systemd-logind[1876]: Session 13 logged out. Waiting for processes to exit. Jan 23 01:04:49.925039 systemd[1]: sshd@10-10.200.8.20:22-10.200.16.10:49252.service: Deactivated successfully. Jan 23 01:04:49.928196 systemd[1]: session-13.scope: Deactivated successfully. Jan 23 01:04:49.930739 systemd-logind[1876]: Removed session 13. Jan 23 01:05:00.047441 systemd[1]: Started sshd@11-10.200.8.20:22-10.200.16.10:43800.service - OpenSSH per-connection server daemon (10.200.16.10:43800). Jan 23 01:05:00.760367 sshd[12539]: Accepted publickey for core from 10.200.16.10 port 43800 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:05:00.763571 sshd-session[12539]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:05:00.769864 systemd-logind[1876]: New session 14 of user core. Jan 23 01:05:00.773520 systemd[1]: Started session-14.scope - Session 14 of User core. Jan 23 01:05:01.251794 sshd[12752]: Connection closed by 10.200.16.10 port 43800 Jan 23 01:05:01.254124 sshd-session[12539]: pam_unix(sshd:session): session closed for user core Jan 23 01:05:01.257829 systemd-logind[1876]: Session 14 logged out. Waiting for processes to exit. Jan 23 01:05:01.260347 systemd[1]: sshd@11-10.200.8.20:22-10.200.16.10:43800.service: Deactivated successfully. Jan 23 01:05:01.262696 systemd[1]: session-14.scope: Deactivated successfully. Jan 23 01:05:01.266929 systemd-logind[1876]: Removed session 14. Jan 23 01:05:01.376172 systemd[1]: Started sshd@12-10.200.8.20:22-10.200.16.10:43802.service - OpenSSH per-connection server daemon (10.200.16.10:43802). Jan 23 01:05:02.072435 sshd[12943]: Accepted publickey for core from 10.200.16.10 port 43802 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:05:02.074212 sshd-session[12943]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:05:02.080186 systemd-logind[1876]: New session 15 of user core. Jan 23 01:05:02.089151 systemd[1]: Started session-15.scope - Session 15 of User core. Jan 23 01:05:02.768143 sshd[13269]: Connection closed by 10.200.16.10 port 43802 Jan 23 01:05:02.768678 sshd-session[12943]: pam_unix(sshd:session): session closed for user core Jan 23 01:05:02.774677 systemd[1]: sshd@12-10.200.8.20:22-10.200.16.10:43802.service: Deactivated successfully. Jan 23 01:05:02.775193 systemd-logind[1876]: Session 15 logged out. Waiting for processes to exit. Jan 23 01:05:02.777631 systemd[1]: session-15.scope: Deactivated successfully. Jan 23 01:05:02.779715 systemd-logind[1876]: Removed session 15. Jan 23 01:05:12.897086 systemd[1]: Started sshd@13-10.200.8.20:22-10.200.16.10:48818.service - OpenSSH per-connection server daemon (10.200.16.10:48818). Jan 23 01:05:13.615458 sshd[14416]: Accepted publickey for core from 10.200.16.10 port 48818 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:05:13.619571 sshd-session[14416]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:05:13.626729 systemd-logind[1876]: New session 16 of user core. Jan 23 01:05:13.634046 systemd[1]: Started session-16.scope - Session 16 of User core. Jan 23 01:05:14.105097 sshd[14431]: Connection closed by 10.200.16.10 port 48818 Jan 23 01:05:14.108106 sshd-session[14416]: pam_unix(sshd:session): session closed for user core Jan 23 01:05:14.111911 systemd[1]: sshd@13-10.200.8.20:22-10.200.16.10:48818.service: Deactivated successfully. Jan 23 01:05:14.114625 systemd-logind[1876]: Session 16 logged out. Waiting for processes to exit. Jan 23 01:05:14.115477 systemd[1]: session-16.scope: Deactivated successfully. Jan 23 01:05:14.118727 systemd-logind[1876]: Removed session 16. Jan 23 01:05:14.223760 systemd[1]: Started sshd@14-10.200.8.20:22-10.200.16.10:48820.service - OpenSSH per-connection server daemon (10.200.16.10:48820). Jan 23 01:05:14.924862 sshd[14455]: Accepted publickey for core from 10.200.16.10 port 48820 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:05:14.926252 sshd-session[14455]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:05:14.935015 systemd-logind[1876]: New session 17 of user core. Jan 23 01:05:14.945143 systemd[1]: Started session-17.scope - Session 17 of User core. Jan 23 01:05:15.404887 sshd[14470]: Connection closed by 10.200.16.10 port 48820 Jan 23 01:05:15.407127 sshd-session[14455]: pam_unix(sshd:session): session closed for user core Jan 23 01:05:15.410673 systemd[1]: sshd@14-10.200.8.20:22-10.200.16.10:48820.service: Deactivated successfully. Jan 23 01:05:15.413234 systemd[1]: session-17.scope: Deactivated successfully. Jan 23 01:05:15.414938 systemd-logind[1876]: Session 17 logged out. Waiting for processes to exit. Jan 23 01:05:15.417981 systemd-logind[1876]: Removed session 17. Jan 23 01:05:25.527932 systemd[1]: Started sshd@15-10.200.8.20:22-10.200.16.10:59086.service - OpenSSH per-connection server daemon (10.200.16.10:59086). Jan 23 01:05:26.219469 sshd[14812]: Accepted publickey for core from 10.200.16.10 port 59086 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:05:26.221232 sshd-session[14812]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:05:26.227916 systemd-logind[1876]: New session 18 of user core. Jan 23 01:05:26.233609 systemd[1]: Started session-18.scope - Session 18 of User core. Jan 23 01:05:26.699121 sshd[14843]: Connection closed by 10.200.16.10 port 59086 Jan 23 01:05:26.699944 sshd-session[14812]: pam_unix(sshd:session): session closed for user core Jan 23 01:05:26.705288 systemd[1]: sshd@15-10.200.8.20:22-10.200.16.10:59086.service: Deactivated successfully. Jan 23 01:05:26.707102 systemd[1]: session-18.scope: Deactivated successfully. Jan 23 01:05:26.708627 systemd-logind[1876]: Session 18 logged out. Waiting for processes to exit. Jan 23 01:05:26.711636 systemd-logind[1876]: Removed session 18. Jan 23 01:05:26.826177 systemd[1]: Started sshd@16-10.200.8.20:22-10.200.16.10:59102.service - OpenSSH per-connection server daemon (10.200.16.10:59102). Jan 23 01:05:27.526792 sshd[14863]: Accepted publickey for core from 10.200.16.10 port 59102 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:05:27.528506 sshd-session[14863]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:05:27.536864 systemd-logind[1876]: New session 19 of user core. Jan 23 01:05:27.544583 systemd[1]: Started session-19.scope - Session 19 of User core. Jan 23 01:05:28.009826 sshd[14878]: Connection closed by 10.200.16.10 port 59102 Jan 23 01:05:28.010609 sshd-session[14863]: pam_unix(sshd:session): session closed for user core Jan 23 01:05:28.014057 systemd-logind[1876]: Session 19 logged out. Waiting for processes to exit. Jan 23 01:05:28.015172 systemd[1]: sshd@16-10.200.8.20:22-10.200.16.10:59102.service: Deactivated successfully. Jan 23 01:05:28.017685 systemd[1]: session-19.scope: Deactivated successfully. Jan 23 01:05:28.021045 systemd-logind[1876]: Removed session 19. Jan 23 01:05:38.131453 systemd[1]: Started sshd@17-10.200.8.20:22-10.200.16.10:33982.service - OpenSSH per-connection server daemon (10.200.16.10:33982). Jan 23 01:05:38.837126 sshd[15235]: Accepted publickey for core from 10.200.16.10 port 33982 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:05:38.838396 sshd-session[15235]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:05:38.843094 systemd-logind[1876]: New session 20 of user core. Jan 23 01:05:38.858084 systemd[1]: Started session-20.scope - Session 20 of User core. Jan 23 01:05:39.316409 sshd[15238]: Connection closed by 10.200.16.10 port 33982 Jan 23 01:05:39.316803 sshd-session[15235]: pam_unix(sshd:session): session closed for user core Jan 23 01:05:39.320560 systemd[1]: sshd@17-10.200.8.20:22-10.200.16.10:33982.service: Deactivated successfully. Jan 23 01:05:39.322146 systemd[1]: session-20.scope: Deactivated successfully. Jan 23 01:05:39.322995 systemd-logind[1876]: Session 20 logged out. Waiting for processes to exit. Jan 23 01:05:39.323872 systemd-logind[1876]: Removed session 20. Jan 23 01:05:39.439313 systemd[1]: Started sshd@18-10.200.8.20:22-10.200.16.10:33994.service - OpenSSH per-connection server daemon (10.200.16.10:33994). Jan 23 01:05:40.117553 sshd[15244]: Accepted publickey for core from 10.200.16.10 port 33994 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:05:40.118805 sshd-session[15244]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:05:40.123037 systemd-logind[1876]: New session 21 of user core. Jan 23 01:05:40.135105 systemd[1]: Started session-21.scope - Session 21 of User core. Jan 23 01:05:40.593020 sshd[15247]: Connection closed by 10.200.16.10 port 33994 Jan 23 01:05:40.593391 sshd-session[15244]: pam_unix(sshd:session): session closed for user core Jan 23 01:05:40.597026 systemd[1]: sshd@18-10.200.8.20:22-10.200.16.10:33994.service: Deactivated successfully. Jan 23 01:05:40.598757 systemd[1]: session-21.scope: Deactivated successfully. Jan 23 01:05:40.599518 systemd-logind[1876]: Session 21 logged out. Waiting for processes to exit. Jan 23 01:05:40.600449 systemd-logind[1876]: Removed session 21. Jan 23 01:05:46.659542 setup-nvidia[2607]: \u000d Jan 23 01:05:46.659542 setup-nvidia[2607]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Jan 23 01:05:46.659542 setup-nvidia[2607]: frequently when this kernel module was built against the wrong or Jan 23 01:05:46.659542 setup-nvidia[2607]: improperly configured kernel sources, with a version of gcc that differs Jan 23 01:05:46.659542 setup-nvidia[2607]: from the one used to build the target kernel, or if another driver, such Jan 23 01:05:46.659542 setup-nvidia[2607]: as nouveau, is present and prevents the NVIDIA kernel module from Jan 23 01:05:46.659542 setup-nvidia[2607]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Jan 23 01:05:46.659542 setup-nvidia[2607]: installed in this system is supported by this NVIDIA Linux graphics Jan 23 01:05:46.659542 setup-nvidia[2607]: driver release. Jan 23 01:05:46.659542 setup-nvidia[2607]: \u000d Jan 23 01:05:46.659542 setup-nvidia[2607]: Please see the log entries 'Kernel module load error' and 'Kernel Jan 23 01:05:46.659542 setup-nvidia[2607]: messages' at the end of the file Jan 23 01:05:46.659542 setup-nvidia[2607]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for more Jan 23 01:05:46.659542 setup-nvidia[2607]: information. Jan 23 01:05:46.659542 setup-nvidia[2607]: \u000d Jan 23 01:05:46.674481 setup-nvidia[2607]: dmesg: read kernel buffer failed: Operation not permitted Jan 23 01:05:46.692736 setup-nvidia[2607]: \u000d Jan 23 01:05:46.692736 setup-nvidia[2607]: ERROR: Installation has failed. Please see the file Jan 23 01:05:46.692736 setup-nvidia[2607]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for Jan 23 01:05:46.692736 setup-nvidia[2607]: details. You may find suggestions on fixing installation problems in Jan 23 01:05:46.692736 setup-nvidia[2607]: the README available on the Linux driver download page at Jan 23 01:05:46.692736 setup-nvidia[2607]: www.nvidia.com. Jan 23 01:05:46.692736 setup-nvidia[2607]: \u000d Jan 23 01:05:46.693210 setup-nvidia[2607]: Last 50 lines of nvidia-installer.log: Jan 23 01:05:46.694025 setup-nvidia[2607]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039059rm+0x53: 'naked' return found in MITIGATION_RETHUNK build Jan 23 01:05:46.694025 setup-nvidia[2607]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039056rm+0x90: 'naked' return found in MITIGATION_RETHUNK build Jan 23 01:05:46.694025 setup-nvidia[2607]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039049rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build Jan 23 01:05:46.694025 setup-nvidia[2607]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039105rm+0x93: 'naked' return found in MITIGATION_RETHUNK build Jan 23 01:05:46.694025 setup-nvidia[2607]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039101rm+0x36: 'naked' return found in MITIGATION_RETHUNK build Jan 23 01:05:46.694025 setup-nvidia[2607]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039100rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build Jan 23 01:05:46.694025 setup-nvidia[2607]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039102rm+0x77: 'naked' return found in MITIGATION_RETHUNK build Jan 23 01:05:46.694025 setup-nvidia[2607]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039046rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build Jan 23 01:05:46.694025 setup-nvidia[2607]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030615rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build Jan 23 01:05:46.694025 setup-nvidia[2607]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030614rm+0x27: 'naked' return found in MITIGATION_RETHUNK build Jan 23 01:05:46.694340 setup-nvidia[2607]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030618rm+0x37: 'naked' return found in MITIGATION_RETHUNK build Jan 23 01:05:46.694340 setup-nvidia[2607]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030617rm+0x24: 'naked' return found in MITIGATION_RETHUNK build Jan 23 01:05:46.694340 setup-nvidia[2607]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030616rm+0xca: 'naked' return found in MITIGATION_RETHUNK build Jan 23 01:05:46.694340 setup-nvidia[2607]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030619rm+0x193: 'naked' return found in MITIGATION_RETHUNK build Jan 23 01:05:46.694340 setup-nvidia[2607]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030621rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build Jan 23 01:05:46.694340 setup-nvidia[2607]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv011864rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build Jan 23 01:05:46.694340 setup-nvidia[2607]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039413rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build Jan 23 01:05:46.694340 setup-nvidia[2607]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/Module.symvers Jan 23 01:05:46.694340 setup-nvidia[2607]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.mod.o Jan 23 01:05:46.694340 setup-nvidia[2607]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/.module-common.o Jan 23 01:05:46.694340 setup-nvidia[2607]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.mod.o Jan 23 01:05:46.694340 setup-nvidia[2607]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.mod.o Jan 23 01:05:46.694340 setup-nvidia[2607]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.mod.o Jan 23 01:05:46.694340 setup-nvidia[2607]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.mod.o Jan 23 01:05:46.694340 setup-nvidia[2607]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Jan 23 01:05:46.694340 setup-nvidia[2607]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Jan 23 01:05:46.694340 setup-nvidia[2607]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Jan 23 01:05:46.694340 setup-nvidia[2607]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Jan 23 01:05:46.694340 setup-nvidia[2607]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Jan 23 01:05:46.694340 setup-nvidia[2607]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko due to unavailability of vmlinux Jan 23 01:05:46.694340 setup-nvidia[2607]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Jan 23 01:05:46.694340 setup-nvidia[2607]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Jan 23 01:05:46.694340 setup-nvidia[2607]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko due to unavailability of vmlinux Jan 23 01:05:46.694340 setup-nvidia[2607]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Jan 23 01:05:46.694340 setup-nvidia[2607]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko due to unavailability of vmlinux Jan 23 01:05:46.694340 setup-nvidia[2607]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Jan 23 01:05:46.694340 setup-nvidia[2607]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko due to unavailability of vmlinux Jan 23 01:05:46.694340 setup-nvidia[2607]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Jan 23 01:05:46.694340 setup-nvidia[2607]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko due to unavailability of vmlinux Jan 23 01:05:46.694340 setup-nvidia[2607]: make[2]: Leaving directory '/usr/lib/modules/6.12.66-flatcar/build' Jan 23 01:05:46.694340 setup-nvidia[2607]: make[1]: Leaving directory '/usr/lib/modules/6.12.66-flatcar/source' Jan 23 01:05:46.694340 setup-nvidia[2607]: -> done. Jan 23 01:05:46.694340 setup-nvidia[2607]: -> Kernel module compilation complete. Jan 23 01:05:46.694340 setup-nvidia[2607]: -> Unable to determine if Secure Boot is enabled: No such file or directory Jan 23 01:05:46.694340 setup-nvidia[2607]: 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. Jan 23 01:05:46.694340 setup-nvidia[2607]: \u000d Jan 23 01:05:46.694340 setup-nvidia[2607]: 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. Jan 23 01:05:46.694340 setup-nvidia[2607]: -> Kernel module load error: Operation not permitted Jan 23 01:05:46.694340 setup-nvidia[2607]: -> Kernel messages: Jan 23 01:05:46.694340 setup-nvidia[2607]: 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. Jan 23 01:05:50.754213 systemd[1]: Started sshd@19-10.200.8.20:22-10.200.16.10:46942.service - OpenSSH per-connection server daemon (10.200.16.10:46942). Jan 23 01:05:51.619606 sshd[15433]: Accepted publickey for core from 10.200.16.10 port 46942 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:05:51.620801 sshd-session[15433]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:05:51.625126 systemd-logind[1876]: New session 22 of user core. Jan 23 01:05:51.638066 systemd[1]: Started session-22.scope - Session 22 of User core. Jan 23 01:05:52.168609 sshd[15436]: Connection closed by 10.200.16.10 port 46942 Jan 23 01:05:52.169040 sshd-session[15433]: pam_unix(sshd:session): session closed for user core Jan 23 01:05:52.172728 systemd[1]: sshd@19-10.200.8.20:22-10.200.16.10:46942.service: Deactivated successfully. Jan 23 01:05:52.174344 systemd[1]: session-22.scope: Deactivated successfully. Jan 23 01:05:52.175093 systemd-logind[1876]: Session 22 logged out. Waiting for processes to exit. Jan 23 01:05:52.176008 systemd-logind[1876]: Removed session 22. Jan 23 01:05:52.292562 systemd[1]: Started sshd@20-10.200.8.20:22-10.200.16.10:46952.service - OpenSSH per-connection server daemon (10.200.16.10:46952). Jan 23 01:05:52.968975 sshd[15442]: Accepted publickey for core from 10.200.16.10 port 46952 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:05:52.970225 sshd-session[15442]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:05:52.974580 systemd-logind[1876]: New session 23 of user core. Jan 23 01:05:52.988077 systemd[1]: Started session-23.scope - Session 23 of User core. Jan 23 01:05:53.443043 sshd[15445]: Connection closed by 10.200.16.10 port 46952 Jan 23 01:05:53.443410 sshd-session[15442]: pam_unix(sshd:session): session closed for user core Jan 23 01:05:53.446851 systemd[1]: sshd@20-10.200.8.20:22-10.200.16.10:46952.service: Deactivated successfully. Jan 23 01:05:53.448450 systemd[1]: session-23.scope: Deactivated successfully. Jan 23 01:05:53.449472 systemd-logind[1876]: Session 23 logged out. Waiting for processes to exit. Jan 23 01:05:53.450501 systemd-logind[1876]: Removed session 23. Jan 23 01:06:00.845980 kernel: EXT4-fs (loop6p9): unmounting filesystem fd657f23-885c-4ff0-b4c6-ecd1f54eb88a. Jan 23 01:06:00.846741 setup-nvidia[2607]: Container flatcardevelopercontainer-4459.2.2nightly-20260122-2100.bin exited successfully. Jan 23 01:06:00.849149 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4459.2.2nightly\x2d20260122\x2d2100.bin.mount: Deactivated successfully. Jan 23 01:06:00.912755 sudo[2595]: pam_unix(sudo:session): session closed for user root Jan 23 01:06:00.914763 systemd[1]: session-c2.scope: Deactivated successfully. Jan 23 01:06:00.915044 systemd[1]: session-c2.scope: Consumed 5min 23.833s CPU time, 889.6M memory peak. Jan 23 01:06:01.349844 setup-nvidia[1870]: /opt/nvidia / Jan 23 01:06:01.350775 setup-nvidia[1870]: / Jan 23 01:06:01.361973 kernel: IPMI message handler: version 39.2 Jan 23 01:06:01.364972 kernel: ipmi device interface Jan 23 01:06:01.377609 setup-nvidia[1870]: /opt/nvidia/current/usr/lib/modules/6.12.66-flatcar/video / Jan 23 01:06:01.431102 kernel: nvidia: loading out-of-tree module taints kernel. Jan 23 01:06:01.431243 kernel: nvidia: module license 'NVIDIA' taints kernel. Jan 23 01:06:01.431268 kernel: Disabling lock debugging due to kernel taint Jan 23 01:06:01.438475 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Jan 23 01:06:01.438528 kernel: nvidia: module license taints kernel. Jan 23 01:06:01.840988 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 Jan 23 01:06:02.250163 kernel: Jan 23 01:06:02.250299 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Jan 23 01:06:02.457976 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.261.03 Sat Jun 14 16:05:50 UTC 2025 Jan 23 01:06:02.481080 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.261.03 Sat Jun 14 15:39:16 UTC 2025 Jan 23 01:06:02.501990 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Jan 23 01:06:02.549984 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. Jan 23 01:06:02.550267 setup-nvidia[1870]: / Jan 23 01:06:02.565310 setup-nvidia[15485]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jan 23 01:06:03.567323 systemd[1]: Started sshd@21-10.200.8.20:22-10.200.16.10:38788.service - OpenSSH per-connection server daemon (10.200.16.10:38788). Jan 23 01:06:04.249784 sshd[15507]: Accepted publickey for core from 10.200.16.10 port 38788 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:06:04.251105 sshd-session[15507]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:06:04.256044 systemd-logind[1876]: New session 24 of user core. Jan 23 01:06:04.275099 systemd[1]: Started session-24.scope - Session 24 of User core. Jan 23 01:06:04.370496 setup-nvidia[15502]: Fri Jan 23 01:06:04 2026 Jan 23 01:06:04.370496 setup-nvidia[15502]: +---------------------------------------------------------------------------------------+ Jan 23 01:06:04.370496 setup-nvidia[15502]: | NVIDIA-SMI 535.261.03 Driver Version: 535.261.03 CUDA Version: 12.2 | Jan 23 01:06:04.370496 setup-nvidia[15502]: |-----------------------------------------+----------------------+----------------------+ Jan 23 01:06:04.370496 setup-nvidia[15502]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Jan 23 01:06:04.370496 setup-nvidia[15502]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Jan 23 01:06:04.370496 setup-nvidia[15502]: | | | MIG M. | Jan 23 01:06:04.370496 setup-nvidia[15502]: |=========================================+======================+======================| Jan 23 01:06:04.375394 setup-nvidia[15502]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Jan 23 01:06:04.375394 setup-nvidia[15502]: | N/A 25C P0 36W / 250W | 0MiB / 16384MiB | 0% Default | Jan 23 01:06:04.375394 setup-nvidia[15502]: | | | N/A | Jan 23 01:06:04.375394 setup-nvidia[15502]: +-----------------------------------------+----------------------+----------------------+ Jan 23 01:06:04.375513 setup-nvidia[15502]: Jan 23 01:06:04.375513 setup-nvidia[15502]: +---------------------------------------------------------------------------------------+ Jan 23 01:06:04.375513 setup-nvidia[15502]: | Processes: | Jan 23 01:06:04.375513 setup-nvidia[15502]: | GPU GI CI PID Type Process name GPU Memory | Jan 23 01:06:04.375513 setup-nvidia[15502]: | ID ID Usage | Jan 23 01:06:04.375513 setup-nvidia[15502]: |=======================================================================================| Jan 23 01:06:04.375640 setup-nvidia[15502]: | No running processes found | Jan 23 01:06:04.375640 setup-nvidia[15502]: +---------------------------------------------------------------------------------------+ Jan 23 01:06:04.727840 sshd[15510]: Connection closed by 10.200.16.10 port 38788 Jan 23 01:06:04.728201 sshd-session[15507]: pam_unix(sshd:session): session closed for user core Jan 23 01:06:04.731516 systemd[1]: sshd@21-10.200.8.20:22-10.200.16.10:38788.service: Deactivated successfully. Jan 23 01:06:04.733286 systemd[1]: session-24.scope: Deactivated successfully. Jan 23 01:06:04.734278 systemd-logind[1876]: Session 24 logged out. Waiting for processes to exit. Jan 23 01:06:04.735617 systemd-logind[1876]: Removed session 24. Jan 23 01:06:04.747374 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Jan 23 01:06:04.749325 systemd[1]: Reached target multi-user.target - Multi-User System. Jan 23 01:06:04.749895 systemd[1]: Startup finished in 1.027s (firmware) + 19.448s (loader) + 3.854s (kernel) + 11.309s (initrd) + 3min 6.585s (userspace) = 3min 42.225s. Jan 23 01:06:04.853538 systemd[1]: Started sshd@22-10.200.8.20:22-10.200.16.10:38798.service - OpenSSH per-connection server daemon (10.200.16.10:38798). Jan 23 01:06:04.980565 waagent[2184]: 2026-01-23T01:06:04.980456Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 2] Jan 23 01:06:04.989401 waagent[2184]: 2026-01-23T01:06:04.989361Z INFO ExtHandler Jan 23 01:06:04.989491 waagent[2184]: 2026-01-23T01:06:04.989457Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 478bd739-b8c7-4114-bef5-b4b6e0b4131b eTag: 12109680826356238847 source: Fabric] Jan 23 01:06:04.990061 waagent[2184]: 2026-01-23T01:06:04.990019Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Jan 23 01:06:04.990722 waagent[2184]: 2026-01-23T01:06:04.990681Z INFO ExtHandler Jan 23 01:06:04.990800 waagent[2184]: 2026-01-23T01:06:04.990768Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 2] Jan 23 01:06:05.043004 waagent[2184]: 2026-01-23T01:06:05.042945Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Jan 23 01:06:05.103365 waagent[2184]: 2026-01-23T01:06:05.103310Z INFO ExtHandler Downloaded certificate {'thumbprint': '85E79F82F62EB61FF833534B1B41AFBD86D2B23B', 'hasPrivateKey': True} Jan 23 01:06:05.103834 waagent[2184]: 2026-01-23T01:06:05.103795Z INFO ExtHandler Fetch goal state completed Jan 23 01:06:05.104237 waagent[2184]: 2026-01-23T01:06:05.104199Z INFO ExtHandler ExtHandler Jan 23 01:06:05.104318 waagent[2184]: 2026-01-23T01:06:05.104284Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_2 channel: WireServer source: Fabric activity: 3ab1fc87-bd63-49c7-be9a-cf76199f2917 correlation 3848b2a8-842c-4ad6-8633-06fa4a588b57 created: 2026-01-23T01:05:59.968575Z] Jan 23 01:06:05.104648 waagent[2184]: 2026-01-23T01:06:05.104601Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Jan 23 01:06:05.105187 waagent[2184]: 2026-01-23T01:06:05.105149Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_2 0 ms] Jan 23 01:06:05.532976 sshd[15518]: Accepted publickey for core from 10.200.16.10 port 38798 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:06:05.534142 sshd-session[15518]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:06:05.538237 systemd-logind[1876]: New session 25 of user core. Jan 23 01:06:05.554074 systemd[1]: Started session-25.scope - Session 25 of User core. Jan 23 01:06:06.010195 sshd[15526]: Connection closed by 10.200.16.10 port 38798 Jan 23 01:06:06.010566 sshd-session[15518]: pam_unix(sshd:session): session closed for user core Jan 23 01:06:06.014066 systemd[1]: sshd@22-10.200.8.20:22-10.200.16.10:38798.service: Deactivated successfully. Jan 23 01:06:06.015672 systemd[1]: session-25.scope: Deactivated successfully. Jan 23 01:06:06.016410 systemd-logind[1876]: Session 25 logged out. Waiting for processes to exit. Jan 23 01:06:06.017485 systemd-logind[1876]: Removed session 25. Jan 23 01:06:10.920974 systemd[1]: Stopping user@0.service - User Manager for UID 0... Jan 23 01:06:10.921750 systemd[2598]: Activating special unit exit.target... Jan 23 01:06:10.921838 systemd[2598]: Stopped target default.target - Main User Target. Jan 23 01:06:10.921880 systemd[2598]: Stopped target basic.target - Basic System. Jan 23 01:06:10.921909 systemd[2598]: Stopped target paths.target - Paths. Jan 23 01:06:10.921935 systemd[2598]: Stopped target sockets.target - Sockets. Jan 23 01:06:10.922140 systemd[2598]: Stopped target timers.target - Timers. Jan 23 01:06:10.922521 systemd[2598]: Closed dbus.socket - D-Bus User Message Bus Socket. Jan 23 01:06:10.922743 systemd[2598]: Removed slice app.slice - User Application Slice. Jan 23 01:06:10.922772 systemd[2598]: Reached target shutdown.target - Shutdown. Jan 23 01:06:10.922910 systemd[2598]: Finished systemd-exit.service - Exit the Session. Jan 23 01:06:10.922941 systemd[2598]: Reached target exit.target - Exit the Session. Jan 23 01:06:10.938520 (sd-pam)[2599]: pam_unix(systemd-user:session): session closed for user root Jan 23 01:06:10.939808 systemd[1]: user@0.service: Deactivated successfully. Jan 23 01:06:10.940181 systemd[1]: Stopped user@0.service - User Manager for UID 0. Jan 23 01:06:10.942480 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Jan 23 01:06:10.962678 systemd[1]: run-user-0.mount: Deactivated successfully. Jan 23 01:06:10.963603 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Jan 23 01:06:10.963800 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Jan 23 01:06:10.965317 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Jan 23 01:06:10.965408 systemd[1]: user-0.slice: Consumed 5min 24.061s CPU time, 895.8M memory peak. Jan 23 01:06:10.965809 systemd-logind[1876]: Removed session c3. Jan 23 01:06:16.133996 systemd[1]: Started sshd@23-10.200.8.20:22-10.200.16.10:41234.service - OpenSSH per-connection server daemon (10.200.16.10:41234). Jan 23 01:06:16.833073 sshd[15536]: Accepted publickey for core from 10.200.16.10 port 41234 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:06:16.834581 sshd-session[15536]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:06:16.838664 systemd-logind[1876]: New session 26 of user core. Jan 23 01:06:16.851079 systemd[1]: Started session-26.scope - Session 26 of User core. Jan 23 01:06:17.308041 sshd[15539]: Connection closed by 10.200.16.10 port 41234 Jan 23 01:06:17.308402 sshd-session[15536]: pam_unix(sshd:session): session closed for user core Jan 23 01:06:17.311851 systemd[1]: sshd@23-10.200.8.20:22-10.200.16.10:41234.service: Deactivated successfully. Jan 23 01:06:17.313685 systemd[1]: session-26.scope: Deactivated successfully. Jan 23 01:06:17.314477 systemd-logind[1876]: Session 26 logged out. Waiting for processes to exit. Jan 23 01:06:17.315385 systemd-logind[1876]: Removed session 26. Jan 23 01:06:17.431313 systemd[1]: Started sshd@24-10.200.8.20:22-10.200.16.10:41248.service - OpenSSH per-connection server daemon (10.200.16.10:41248). Jan 23 01:06:18.114092 sshd[15545]: Accepted publickey for core from 10.200.16.10 port 41248 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:06:18.115347 sshd-session[15545]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:06:18.119793 systemd-logind[1876]: New session 27 of user core. Jan 23 01:06:18.132080 systemd[1]: Started session-27.scope - Session 27 of User core. Jan 23 01:06:18.593415 sshd[15548]: Connection closed by 10.200.16.10 port 41248 Jan 23 01:06:18.593759 sshd-session[15545]: pam_unix(sshd:session): session closed for user core Jan 23 01:06:18.597529 systemd[1]: sshd@24-10.200.8.20:22-10.200.16.10:41248.service: Deactivated successfully. Jan 23 01:06:18.599155 systemd[1]: session-27.scope: Deactivated successfully. Jan 23 01:06:18.599928 systemd-logind[1876]: Session 27 logged out. Waiting for processes to exit. Jan 23 01:06:18.600866 systemd-logind[1876]: Removed session 27. Jan 23 01:06:18.719023 systemd[1]: Started sshd@25-10.200.8.20:22-10.200.16.10:41258.service - OpenSSH per-connection server daemon (10.200.16.10:41258). Jan 23 01:06:19.450038 sshd[15554]: Accepted publickey for core from 10.200.16.10 port 41258 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:06:19.451292 sshd-session[15554]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:06:19.455983 systemd-logind[1876]: New session 28 of user core. Jan 23 01:06:19.470073 systemd[1]: Started session-28.scope - Session 28 of User core. Jan 23 01:06:19.944743 sshd[15558]: Connection closed by 10.200.16.10 port 41258 Jan 23 01:06:19.945111 sshd-session[15554]: pam_unix(sshd:session): session closed for user core Jan 23 01:06:19.948563 systemd[1]: sshd@25-10.200.8.20:22-10.200.16.10:41258.service: Deactivated successfully. Jan 23 01:06:19.951661 systemd[1]: session-28.scope: Deactivated successfully. Jan 23 01:06:19.952390 systemd-logind[1876]: Session 28 logged out. Waiting for processes to exit. Jan 23 01:06:19.953512 systemd-logind[1876]: Removed session 28. Jan 23 01:06:20.066806 systemd[1]: Started sshd@26-10.200.8.20:22-10.200.16.10:57060.service - OpenSSH per-connection server daemon (10.200.16.10:57060). Jan 23 01:06:20.868552 sshd[15564]: Accepted publickey for core from 10.200.16.10 port 57060 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:06:20.869801 sshd-session[15564]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:06:20.874353 systemd-logind[1876]: New session 29 of user core. Jan 23 01:06:20.888082 systemd[1]: Started session-29.scope - Session 29 of User core. Jan 23 01:06:21.354811 sshd[15567]: Connection closed by 10.200.16.10 port 57060 Jan 23 01:06:21.355203 sshd-session[15564]: pam_unix(sshd:session): session closed for user core Jan 23 01:06:21.358721 systemd[1]: sshd@26-10.200.8.20:22-10.200.16.10:57060.service: Deactivated successfully. Jan 23 01:06:21.360370 systemd[1]: session-29.scope: Deactivated successfully. Jan 23 01:06:21.361137 systemd-logind[1876]: Session 29 logged out. Waiting for processes to exit. Jan 23 01:06:21.362184 systemd-logind[1876]: Removed session 29. Jan 23 01:06:21.479146 systemd[1]: Started sshd@27-10.200.8.20:22-10.200.16.10:57064.service - OpenSSH per-connection server daemon (10.200.16.10:57064). Jan 23 01:06:22.220258 sshd[15574]: Accepted publickey for core from 10.200.16.10 port 57064 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:06:22.221513 sshd-session[15574]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:06:22.225989 systemd-logind[1876]: New session 30 of user core. Jan 23 01:06:22.234077 systemd[1]: Started session-30.scope - Session 30 of User core. Jan 23 01:06:24.227604 sshd[15578]: Connection closed by 10.200.16.10 port 57064 Jan 23 01:06:24.228047 sshd-session[15574]: pam_unix(sshd:session): session closed for user core Jan 23 01:06:24.231786 systemd[1]: sshd@27-10.200.8.20:22-10.200.16.10:57064.service: Deactivated successfully. Jan 23 01:06:24.233415 systemd[1]: session-30.scope: Deactivated successfully. Jan 23 01:06:24.233611 systemd[1]: session-30.scope: Consumed 1.469s CPU time, 14M memory peak. Jan 23 01:06:24.234118 systemd-logind[1876]: Session 30 logged out. Waiting for processes to exit. Jan 23 01:06:24.235096 systemd-logind[1876]: Removed session 30.