Jan 23 18:53:48.206271 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 Fri Jan 23 16:02:29 -00 2026 Jan 23 18:53:48.206300 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=e498a861432c458392bc8ae0919597d8f4554cdcc46b00c7f3d7a634c3492c81 Jan 23 18:53:48.206309 kernel: BIOS-provided physical RAM map: Jan 23 18:53:48.206316 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Jan 23 18:53:48.206323 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Jan 23 18:53:48.206329 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Jan 23 18:53:48.206337 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Jan 23 18:53:48.206347 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Jan 23 18:53:48.206353 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Jan 23 18:53:48.206360 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Jan 23 18:53:48.206367 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Jan 23 18:53:48.206373 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Jan 23 18:53:48.206380 kernel: printk: legacy bootconsole [earlyser0] enabled Jan 23 18:53:48.206387 kernel: NX (Execute Disable) protection: active Jan 23 18:53:48.206398 kernel: APIC: Static calls initialized Jan 23 18:53:48.206405 kernel: efi: EFI v2.7 by Microsoft Jan 23 18:53:48.206413 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Jan 23 18:53:48.206421 kernel: random: crng init done Jan 23 18:53:48.206428 kernel: secureboot: Secure boot disabled Jan 23 18:53:48.206436 kernel: SMBIOS 3.1.0 present. Jan 23 18:53:48.206443 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Jan 23 18:53:48.206451 kernel: DMI: Memory slots populated: 3/3 Jan 23 18:53:48.206458 kernel: Hypervisor detected: Microsoft Hyper-V Jan 23 18:53:48.206465 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Jan 23 18:53:48.206475 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Jan 23 18:53:48.206483 kernel: Hyper-V: Using hypercall for remote TLB flush Jan 23 18:53:48.206490 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Jan 23 18:53:48.206498 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Jan 23 18:53:48.206506 kernel: tsc: Marking TSC unstable due to running on Hyper-V Jan 23 18:53:48.206514 kernel: tsc: Detected 2593.993 MHz processor Jan 23 18:53:48.206521 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Jan 23 18:53:48.206529 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Jan 23 18:53:48.206537 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Jan 23 18:53:48.206547 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Jan 23 18:53:48.206555 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Jan 23 18:53:48.206562 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Jan 23 18:53:48.206570 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Jan 23 18:53:48.206577 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Jan 23 18:53:48.206584 kernel: Using GB pages for direct mapping Jan 23 18:53:48.206592 kernel: ACPI: Early table checksum verification disabled Jan 23 18:53:48.206603 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Jan 23 18:53:48.206614 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 23 18:53:48.206622 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 23 18:53:48.206630 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Jan 23 18:53:48.206638 kernel: ACPI: FACS 0x000000003FFFE000 000040 Jan 23 18:53:48.206646 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 23 18:53:48.206654 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 23 18:53:48.206664 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 23 18:53:48.206672 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 23 18:53:48.206680 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 23 18:53:48.206688 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 23 18:53:48.206696 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 23 18:53:48.206704 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Jan 23 18:53:48.206712 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Jan 23 18:53:48.206720 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Jan 23 18:53:48.206728 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Jan 23 18:53:48.206738 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Jan 23 18:53:48.206746 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Jan 23 18:53:48.206754 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Jan 23 18:53:48.206762 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Jan 23 18:53:48.206770 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Jan 23 18:53:48.206778 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Jan 23 18:53:48.206786 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Jan 23 18:53:48.206794 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Jan 23 18:53:48.206804 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Jan 23 18:53:48.206812 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Jan 23 18:53:48.206820 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Jan 23 18:53:48.206828 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Jan 23 18:53:48.206836 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Jan 23 18:53:48.206844 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Jan 23 18:53:48.206852 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] Jan 23 18:53:48.206860 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] Jan 23 18:53:48.206868 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] Jan 23 18:53:48.206878 kernel: Zone ranges: Jan 23 18:53:48.206886 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Jan 23 18:53:48.206894 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Jan 23 18:53:48.206902 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Jan 23 18:53:48.206910 kernel: Device empty Jan 23 18:53:48.206918 kernel: Movable zone start for each node Jan 23 18:53:48.206926 kernel: Early memory node ranges Jan 23 18:53:48.206934 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Jan 23 18:53:48.206942 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Jan 23 18:53:48.206952 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Jan 23 18:53:48.206960 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Jan 23 18:53:48.206968 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Jan 23 18:53:48.206976 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Jan 23 18:53:48.206984 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Jan 23 18:53:48.206996 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Jan 23 18:53:48.207004 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Jan 23 18:53:48.207012 kernel: ACPI: PM-Timer IO Port: 0x408 Jan 23 18:53:48.207019 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Jan 23 18:53:48.207030 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Jan 23 18:53:48.207038 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Jan 23 18:53:48.207046 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Jan 23 18:53:48.207054 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Jan 23 18:53:48.207062 kernel: CPU topo: Max. logical packages: 1 Jan 23 18:53:48.207070 kernel: CPU topo: Max. logical dies: 1 Jan 23 18:53:48.207078 kernel: CPU topo: Max. dies per package: 1 Jan 23 18:53:48.207086 kernel: CPU topo: Max. threads per core: 1 Jan 23 18:53:48.207094 kernel: CPU topo: Num. cores per package: 6 Jan 23 18:53:48.207104 kernel: CPU topo: Num. threads per package: 6 Jan 23 18:53:48.207112 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs Jan 23 18:53:48.207120 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Jan 23 18:53:48.207128 kernel: Booting paravirtualized kernel on Hyper-V Jan 23 18:53:48.207146 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Jan 23 18:53:48.207154 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Jan 23 18:53:48.207162 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 Jan 23 18:53:48.207170 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 Jan 23 18:53:48.207178 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Jan 23 18:53:48.207189 kernel: Hyper-V: PV spinlocks enabled Jan 23 18:53:48.207197 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Jan 23 18:53:48.207206 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=e498a861432c458392bc8ae0919597d8f4554cdcc46b00c7f3d7a634c3492c81 Jan 23 18:53:48.207214 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Jan 23 18:53:48.207222 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Jan 23 18:53:48.207230 kernel: Fallback order for Node 0: 0 Jan 23 18:53:48.207238 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 Jan 23 18:53:48.207246 kernel: Policy zone: Normal Jan 23 18:53:48.207257 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jan 23 18:53:48.207273 kernel: software IO TLB: area num 8. Jan 23 18:53:48.207281 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Jan 23 18:53:48.207292 kernel: Kernel/User page tables isolation: enabled Jan 23 18:53:48.207301 kernel: ftrace: allocating 40097 entries in 157 pages Jan 23 18:53:48.207309 kernel: ftrace: allocated 157 pages with 5 groups Jan 23 18:53:48.207317 kernel: Dynamic Preempt: voluntary Jan 23 18:53:48.207326 kernel: rcu: Preemptible hierarchical RCU implementation. Jan 23 18:53:48.207335 kernel: rcu: RCU event tracing is enabled. Jan 23 18:53:48.207343 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Jan 23 18:53:48.207352 kernel: Trampoline variant of Tasks RCU enabled. Jan 23 18:53:48.207363 kernel: Rude variant of Tasks RCU enabled. Jan 23 18:53:48.207371 kernel: Tracing variant of Tasks RCU enabled. Jan 23 18:53:48.207380 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Jan 23 18:53:48.207388 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Jan 23 18:53:48.207397 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jan 23 18:53:48.207405 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jan 23 18:53:48.207416 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jan 23 18:53:48.207425 kernel: Using NULL legacy PIC Jan 23 18:53:48.207433 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Jan 23 18:53:48.207441 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Jan 23 18:53:48.207450 kernel: Console: colour dummy device 80x25 Jan 23 18:53:48.207458 kernel: printk: legacy console [tty1] enabled Jan 23 18:53:48.207467 kernel: printk: legacy console [ttyS0] enabled Jan 23 18:53:48.207475 kernel: printk: legacy bootconsole [earlyser0] disabled Jan 23 18:53:48.207483 kernel: ACPI: Core revision 20240827 Jan 23 18:53:48.207494 kernel: Failed to register legacy timer interrupt Jan 23 18:53:48.207503 kernel: APIC: Switch to symmetric I/O mode setup Jan 23 18:53:48.207511 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Jan 23 18:53:48.207519 kernel: Hyper-V: enabling crash_kexec_post_notifiers Jan 23 18:53:48.207528 kernel: Hyper-V: Using IPI hypercalls Jan 23 18:53:48.207536 kernel: APIC: send_IPI() replaced with hv_send_ipi() Jan 23 18:53:48.207544 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Jan 23 18:53:48.207553 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Jan 23 18:53:48.207561 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Jan 23 18:53:48.207572 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Jan 23 18:53:48.207580 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Jan 23 18:53:48.207589 kernel: Hyper-V: Using enlightened APIC (xapic mode) Jan 23 18:53:48.207597 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Jan 23 18:53:48.207606 kernel: APIC: read() replaced with hv_apic_read() Jan 23 18:53:48.207614 kernel: APIC: write() replaced with hv_apic_write() Jan 23 18:53:48.207622 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Jan 23 18:53:48.207631 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Jan 23 18:53:48.207639 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593993) Jan 23 18:53:48.207650 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Jan 23 18:53:48.207659 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Jan 23 18:53:48.207667 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Jan 23 18:53:48.207675 kernel: Spectre V2 : Mitigation: Retpolines Jan 23 18:53:48.207683 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Jan 23 18:53:48.207691 kernel: Speculative Store Bypass: Vulnerable Jan 23 18:53:48.207700 kernel: MDS: Mitigation: Clear CPU buffers Jan 23 18:53:48.207708 kernel: TAA: Mitigation: Clear CPU buffers Jan 23 18:53:48.207716 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Jan 23 18:53:48.207724 kernel: active return thunk: its_return_thunk Jan 23 18:53:48.207732 kernel: ITS: Mitigation: Aligned branch/return thunks Jan 23 18:53:48.207743 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Jan 23 18:53:48.207751 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Jan 23 18:53:48.207759 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Jan 23 18:53:48.207768 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Jan 23 18:53:48.207776 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Jan 23 18:53:48.207784 kernel: Freeing SMP alternatives memory: 32K Jan 23 18:53:48.207792 kernel: pid_max: default: 32768 minimum: 301 Jan 23 18:53:48.207801 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Jan 23 18:53:48.207809 kernel: landlock: Up and running. Jan 23 18:53:48.207817 kernel: SELinux: Initializing. Jan 23 18:53:48.207825 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Jan 23 18:53:48.207836 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Jan 23 18:53:48.207844 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Jan 23 18:53:48.207853 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Jan 23 18:53:48.207861 kernel: signal: max sigframe size: 1776 Jan 23 18:53:48.207870 kernel: rcu: Hierarchical SRCU implementation. Jan 23 18:53:48.207878 kernel: rcu: Max phase no-delay instances is 400. Jan 23 18:53:48.207887 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Jan 23 18:53:48.207895 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Jan 23 18:53:48.207903 kernel: smp: Bringing up secondary CPUs ... Jan 23 18:53:48.207914 kernel: smpboot: x86: Booting SMP configuration: Jan 23 18:53:48.207923 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Jan 23 18:53:48.207931 kernel: smp: Brought up 1 node, 6 CPUs Jan 23 18:53:48.207939 kernel: smpboot: Total of 6 processors activated (31127.91 BogoMIPS) Jan 23 18:53:48.207948 kernel: Memory: 115330900K/117439364K available (14336K kernel code, 2445K rwdata, 26064K rodata, 46200K init, 2560K bss, 2098188K reserved, 0K cma-reserved) Jan 23 18:53:48.207957 kernel: devtmpfs: initialized Jan 23 18:53:48.207965 kernel: x86/mm: Memory block size: 1024MB Jan 23 18:53:48.207974 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Jan 23 18:53:48.207982 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Jan 23 18:53:48.207993 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Jan 23 18:53:48.208001 kernel: pinctrl core: initialized pinctrl subsystem Jan 23 18:53:48.208010 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jan 23 18:53:48.208018 kernel: audit: initializing netlink subsys (disabled) Jan 23 18:53:48.208026 kernel: audit: type=2000 audit(1769194424.028:1): state=initialized audit_enabled=0 res=1 Jan 23 18:53:48.208035 kernel: thermal_sys: Registered thermal governor 'step_wise' Jan 23 18:53:48.208043 kernel: thermal_sys: Registered thermal governor 'user_space' Jan 23 18:53:48.208051 kernel: cpuidle: using governor menu Jan 23 18:53:48.208060 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Jan 23 18:53:48.208071 kernel: dca service started, version 1.12.1 Jan 23 18:53:48.208079 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Jan 23 18:53:48.208087 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Jan 23 18:53:48.208096 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Jan 23 18:53:48.208104 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Jan 23 18:53:48.208112 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Jan 23 18:53:48.208121 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Jan 23 18:53:48.208129 kernel: ACPI: Added _OSI(Module Device) Jan 23 18:53:48.208146 kernel: ACPI: Added _OSI(Processor Device) Jan 23 18:53:48.208157 kernel: ACPI: Added _OSI(Processor Aggregator Device) Jan 23 18:53:48.208165 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Jan 23 18:53:48.208174 kernel: ACPI: Interpreter enabled Jan 23 18:53:48.208182 kernel: ACPI: PM: (supports S0 S5) Jan 23 18:53:48.208191 kernel: ACPI: Using IOAPIC for interrupt routing Jan 23 18:53:48.208199 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Jan 23 18:53:48.208208 kernel: PCI: Ignoring E820 reservations for host bridge windows Jan 23 18:53:48.208216 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Jan 23 18:53:48.208224 kernel: iommu: Default domain type: Translated Jan 23 18:53:48.208235 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Jan 23 18:53:48.208244 kernel: efivars: Registered efivars operations Jan 23 18:53:48.208252 kernel: PCI: Using ACPI for IRQ routing Jan 23 18:53:48.208260 kernel: PCI: System does not support PCI Jan 23 18:53:48.208269 kernel: vgaarb: loaded Jan 23 18:53:48.208277 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Jan 23 18:53:48.208286 kernel: VFS: Disk quotas dquot_6.6.0 Jan 23 18:53:48.208294 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jan 23 18:53:48.208302 kernel: pnp: PnP ACPI init Jan 23 18:53:48.208313 kernel: pnp: PnP ACPI: found 3 devices Jan 23 18:53:48.208322 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Jan 23 18:53:48.208330 kernel: NET: Registered PF_INET protocol family Jan 23 18:53:48.208339 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Jan 23 18:53:48.208347 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Jan 23 18:53:48.208356 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jan 23 18:53:48.208364 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Jan 23 18:53:48.208373 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jan 23 18:53:48.208381 kernel: TCP: Hash tables configured (established 524288 bind 65536) Jan 23 18:53:48.208392 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jan 23 18:53:48.208401 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jan 23 18:53:48.208409 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jan 23 18:53:48.208417 kernel: NET: Registered PF_XDP protocol family Jan 23 18:53:48.208426 kernel: PCI: CLS 0 bytes, default 64 Jan 23 18:53:48.208434 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Jan 23 18:53:48.208443 kernel: software IO TLB: mapped [mem 0x000000003b5bf000-0x000000003f5bf000] (64MB) Jan 23 18:53:48.208451 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Jan 23 18:53:48.208460 kernel: Initialise system trusted keyrings Jan 23 18:53:48.208470 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Jan 23 18:53:48.208479 kernel: Key type asymmetric registered Jan 23 18:53:48.208487 kernel: Asymmetric key parser 'x509' registered Jan 23 18:53:48.208495 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Jan 23 18:53:48.208504 kernel: io scheduler mq-deadline registered Jan 23 18:53:48.208512 kernel: io scheduler kyber registered Jan 23 18:53:48.208520 kernel: io scheduler bfq registered Jan 23 18:53:48.208529 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Jan 23 18:53:48.208537 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Jan 23 18:53:48.208548 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Jan 23 18:53:48.208556 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Jan 23 18:53:48.208565 kernel: i8042: PNP: No PS/2 controller found. Jan 23 18:53:48.208718 kernel: rtc_cmos 00:02: registered as rtc0 Jan 23 18:53:48.208822 kernel: rtc_cmos 00:02: setting system clock to 2026-01-23T18:53:47 UTC (1769194427) Jan 23 18:53:48.208924 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Jan 23 18:53:48.208936 kernel: intel_pstate: CPU model not supported Jan 23 18:53:48.208949 kernel: efifb: probing for efifb Jan 23 18:53:48.208958 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Jan 23 18:53:48.208966 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Jan 23 18:53:48.208975 kernel: efifb: scrolling: redraw Jan 23 18:53:48.208983 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Jan 23 18:53:48.208991 kernel: Console: switching to colour frame buffer device 128x48 Jan 23 18:53:48.209000 kernel: fb0: EFI VGA frame buffer device Jan 23 18:53:48.209008 kernel: pstore: Using crash dump compression: deflate Jan 23 18:53:48.209017 kernel: pstore: Registered efi_pstore as persistent store backend Jan 23 18:53:48.209028 kernel: NET: Registered PF_INET6 protocol family Jan 23 18:53:48.209036 kernel: Segment Routing with IPv6 Jan 23 18:53:48.209045 kernel: In-situ OAM (IOAM) with IPv6 Jan 23 18:53:48.209053 kernel: NET: Registered PF_PACKET protocol family Jan 23 18:53:48.209062 kernel: Key type dns_resolver registered Jan 23 18:53:48.209070 kernel: IPI shorthand broadcast: enabled Jan 23 18:53:48.209078 kernel: sched_clock: Marking stable (3635005700, 111862000)->(4093738700, -346871000) Jan 23 18:53:48.209087 kernel: registered taskstats version 1 Jan 23 18:53:48.209095 kernel: Loading compiled-in X.509 certificates Jan 23 18:53:48.209104 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.66-flatcar: 2aec04a968f0111235eb989789145bc2b989f0c6' Jan 23 18:53:48.209115 kernel: Demotion targets for Node 0: null Jan 23 18:53:48.209123 kernel: Key type .fscrypt registered Jan 23 18:53:48.209142 kernel: Key type fscrypt-provisioning registered Jan 23 18:53:48.209152 kernel: ima: No TPM chip found, activating TPM-bypass! Jan 23 18:53:48.209160 kernel: ima: Allocated hash algorithm: sha1 Jan 23 18:53:48.209169 kernel: ima: No architecture policies found Jan 23 18:53:48.209177 kernel: clk: Disabling unused clocks Jan 23 18:53:48.209185 kernel: Warning: unable to open an initial console. Jan 23 18:53:48.209194 kernel: Freeing unused kernel image (initmem) memory: 46200K Jan 23 18:53:48.209205 kernel: Write protecting the kernel read-only data: 40960k Jan 23 18:53:48.209213 kernel: Freeing unused kernel image (rodata/data gap) memory: 560K Jan 23 18:53:48.209222 kernel: Run /init as init process Jan 23 18:53:48.209231 kernel: with arguments: Jan 23 18:53:48.209239 kernel: /init Jan 23 18:53:48.209247 kernel: with environment: Jan 23 18:53:48.209255 kernel: HOME=/ Jan 23 18:53:48.209264 kernel: TERM=linux Jan 23 18:53:48.209273 systemd[1]: Successfully made /usr/ read-only. Jan 23 18:53:48.209287 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 18:53:48.209297 systemd[1]: Detected virtualization microsoft. Jan 23 18:53:48.209305 systemd[1]: Detected architecture x86-64. Jan 23 18:53:48.209314 systemd[1]: Running in initrd. Jan 23 18:53:48.209323 systemd[1]: No hostname configured, using default hostname. Jan 23 18:53:48.209332 systemd[1]: Hostname set to . Jan 23 18:53:48.209341 systemd[1]: Initializing machine ID from random generator. Jan 23 18:53:48.209353 systemd[1]: Queued start job for default target initrd.target. Jan 23 18:53:48.209362 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jan 23 18:53:48.209371 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jan 23 18:53:48.209380 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Jan 23 18:53:48.209389 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jan 23 18:53:48.209398 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Jan 23 18:53:48.209408 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Jan 23 18:53:48.209420 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 18:53:48.209430 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Jan 23 18:53:48.209439 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jan 23 18:53:48.209448 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jan 23 18:53:48.209457 systemd[1]: Reached target paths.target - Path Units. Jan 23 18:53:48.209466 systemd[1]: Reached target slices.target - Slice Units. Jan 23 18:53:48.209474 systemd[1]: Reached target swap.target - Swaps. Jan 23 18:53:48.209483 systemd[1]: Reached target timers.target - Timer Units. Jan 23 18:53:48.209495 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Jan 23 18:53:48.209504 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jan 23 18:53:48.209513 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Jan 23 18:53:48.209522 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Jan 23 18:53:48.209531 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jan 23 18:53:48.209540 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jan 23 18:53:48.209549 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jan 23 18:53:48.209558 systemd[1]: Reached target sockets.target - Socket Units. Jan 23 18:53:48.209567 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Jan 23 18:53:48.209578 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jan 23 18:53:48.209587 systemd[1]: Finished network-cleanup.service - Network Cleanup. Jan 23 18:53:48.209597 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 18:53:48.209606 systemd[1]: Starting systemd-fsck-usr.service... Jan 23 18:53:48.209615 systemd[1]: Starting systemd-journald.service - Journal Service... Jan 23 18:53:48.209624 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jan 23 18:53:48.209652 systemd-journald[213]: Collecting audit messages is disabled. Jan 23 18:53:48.209677 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jan 23 18:53:48.209686 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Jan 23 18:53:48.209699 systemd-journald[213]: Journal started Jan 23 18:53:48.209719 systemd-journald[213]: Runtime Journal (/run/log/journal/38674141768d4ce9bfca4200dc350853) is 8M, max 2.2G, 2.1G free. Jan 23 18:53:48.220153 systemd[1]: Started systemd-journald.service - Journal Service. Jan 23 18:53:48.220488 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jan 23 18:53:48.220996 systemd[1]: Finished systemd-fsck-usr.service. Jan 23 18:53:48.223343 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jan 23 18:53:48.224390 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jan 23 18:53:48.266927 systemd-modules-load[215]: Inserted module 'overlay' Jan 23 18:53:48.270201 systemd-tmpfiles[221]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Jan 23 18:53:48.271084 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jan 23 18:53:48.276711 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jan 23 18:53:48.284621 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jan 23 18:53:48.320153 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 18:53:48.324213 systemd-modules-load[215]: Inserted module 'br_netfilter' Jan 23 18:53:48.328228 kernel: Bridge firewalling registered Jan 23 18:53:48.329287 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jan 23 18:53:48.329677 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jan 23 18:53:48.339937 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jan 23 18:53:48.348325 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jan 23 18:53:48.358231 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jan 23 18:53:48.382493 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jan 23 18:53:48.383897 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jan 23 18:53:48.392701 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jan 23 18:53:48.403351 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Jan 23 18:53:48.423867 dracut-cmdline[257]: Using kernel command line parameters: rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1 rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=e498a861432c458392bc8ae0919597d8f4554cdcc46b00c7f3d7a634c3492c81 Jan 23 18:53:48.436413 systemd-resolved[246]: Positive Trust Anchors: Jan 23 18:53:48.436423 systemd-resolved[246]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jan 23 18:53:48.436457 systemd-resolved[246]: 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 18:53:48.438912 systemd-resolved[246]: Defaulting to hostname 'linux'. Jan 23 18:53:48.439903 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jan 23 18:53:48.453743 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jan 23 18:53:48.580158 kernel: SCSI subsystem initialized Jan 23 18:53:48.591156 kernel: Loading iSCSI transport class v2.0-870. Jan 23 18:53:48.603160 kernel: iscsi: registered transport (tcp) Jan 23 18:53:48.626156 kernel: iscsi: registered transport (qla4xxx) Jan 23 18:53:48.626185 kernel: QLogic iSCSI HBA Driver Jan 23 18:53:48.644971 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jan 23 18:53:48.669589 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jan 23 18:53:48.670702 systemd[1]: Reached target network-pre.target - Preparation for Network. Jan 23 18:53:48.727067 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Jan 23 18:53:48.728446 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Jan 23 18:53:48.810161 kernel: raid6: avx2x4 gen() 31120 MB/s Jan 23 18:53:48.829148 kernel: raid6: avx2x2 gen() 31107 MB/s Jan 23 18:53:48.851448 kernel: raid6: avx2x1 gen() 27561 MB/s Jan 23 18:53:48.851477 kernel: raid6: using algorithm avx2x4 gen() 31120 MB/s Jan 23 18:53:48.873487 kernel: raid6: .... xor() 9915 MB/s, rmw enabled Jan 23 18:53:48.873524 kernel: raid6: using avx2x2 recovery algorithm Jan 23 18:53:48.897157 kernel: xor: automatically using best checksumming function avx Jan 23 18:53:49.082168 kernel: Btrfs loaded, zoned=no, fsverity=no Jan 23 18:53:49.089672 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Jan 23 18:53:49.091223 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jan 23 18:53:49.133287 systemd-udevd[467]: Using default interface naming scheme 'v255'. Jan 23 18:53:49.140522 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jan 23 18:53:49.141445 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Jan 23 18:53:49.193838 dracut-pre-trigger[471]: rd.md=0: removing MD RAID activation Jan 23 18:53:49.222042 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Jan 23 18:53:49.228370 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jan 23 18:53:49.303307 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jan 23 18:53:49.309600 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Jan 23 18:53:49.332150 kernel: cryptd: max_cpu_qlen set to 1000 Jan 23 18:53:49.344154 kernel: AES CTR mode by8 optimization enabled Jan 23 18:53:49.344192 kernel: hv_vmbus: Vmbus version:4.0 Jan 23 18:53:49.365420 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jan 23 18:53:49.380979 kernel: pps_core: LinuxPPS API ver. 1 registered Jan 23 18:53:49.381018 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Jan 23 18:53:49.381031 kernel: hv_vmbus: registering driver hyperv_keyboard Jan 23 18:53:49.365591 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jan 23 18:53:49.407309 kernel: PTP clock support registered Jan 23 18:53:49.407333 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 18:53:49.407347 kernel: hv_vmbus: registering driver hv_pci Jan 23 18:53:49.407359 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Jan 23 18:53:49.383975 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Jan 23 18:53:49.417018 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Jan 23 18:53:49.417279 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Jan 23 18:53:49.426084 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Jan 23 18:53:49.426257 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Jan 23 18:53:49.436392 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint Jan 23 18:53:49.436436 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] Jan 23 18:53:49.440025 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jan 23 18:53:49.585842 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] Jan 23 18:53:49.586050 kernel: hv_vmbus: registering driver hv_storvsc Jan 23 18:53:49.586066 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] Jan 23 18:53:49.586232 kernel: hid: raw HID events driver (C) Jiri Kosina Jan 23 18:53:49.586247 kernel: scsi host0: storvsc_host_t Jan 23 18:53:49.586392 kernel: pci 0001:00:00.0: Enabling HDA controller Jan 23 18:53:49.586531 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Jan 23 18:53:49.586673 kernel: hv_utils: Registering HyperV Utility Driver Jan 23 18:53:49.586687 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Jan 23 18:53:49.586837 kernel: hv_vmbus: registering driver hv_utils Jan 23 18:53:49.586850 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Jan 23 18:53:49.586978 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned Jan 23 18:53:49.587128 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned Jan 23 18:53:49.587273 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned Jan 23 18:53:49.587409 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Jan 23 18:53:49.587548 kernel: hv_utils: Heartbeat IC version 3.0 Jan 23 18:53:49.587562 kernel: scsi host1: storvsc_host_t Jan 23 18:53:49.587694 kernel: hv_utils: TimeSync IC version 4.0 Jan 23 18:53:49.587708 kernel: hv_utils: Shutdown IC version 3.2 Jan 23 18:53:49.559426 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jan 23 18:53:50.720242 systemd-resolved[246]: Clock change detected. Flushing caches. Jan 23 18:53:50.723267 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jan 23 18:53:50.723378 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jan 23 18:53:50.734960 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jan 23 18:53:50.737772 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jan 23 18:53:50.765717 kernel: hv_vmbus: registering driver hid_hyperv Jan 23 18:53:50.765738 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Jan 23 18:53:50.773602 kernel: hv_vmbus: registering driver hv_netvsc Jan 23 18:53:50.773637 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Jan 23 18:53:50.773798 kernel: sd 0:0:0:1: [sda] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Jan 23 18:53:50.773952 kernel: sd 0:0:0:0: [sdb] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Jan 23 18:53:50.781320 kernel: sd 0:0:0:1: [sda] 4096-byte physical blocks Jan 23 18:53:50.781492 kernel: sd 0:0:0:1: [sda] Write Protect is off Jan 23 18:53:50.781629 kernel: sd 0:0:0:0: [sdb] 4096-byte physical blocks Jan 23 18:53:50.786787 kernel: sd 0:0:0:0: [sdb] Write Protect is off Jan 23 18:53:50.787027 kernel: sd 0:0:0:1: [sda] Mode Sense: 0f 00 10 00 Jan 23 18:53:50.787196 kernel: sd 0:0:0:0: [sdb] Mode Sense: 0f 00 10 00 Jan 23 18:53:50.792059 kernel: sd 0:0:0:1: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Jan 23 18:53:50.807119 kernel: sd 0:0:0:0: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Jan 23 18:53:50.807301 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#260 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jan 23 18:53:50.807441 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#197 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jan 23 18:53:50.823092 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#387 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jan 23 18:53:50.823255 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#270 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jan 23 18:53:50.833298 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jan 23 18:53:50.847707 kernel: sda: sda1 Jan 23 18:53:50.847736 kernel: sd 0:0:0:1: [sda] Attached SCSI disk Jan 23 18:53:50.847898 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Jan 23 18:53:50.854122 kernel: sd 0:0:0:0: [sdb] Attached SCSI disk Jan 23 18:53:50.862843 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Jan 23 18:53:50.863020 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Jan 23 18:53:50.865094 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Jan 23 18:53:51.333854 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Jan 23 18:53:51.345540 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Jan 23 18:53:51.364458 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Jan 23 18:53:51.473923 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Jan 23 18:53:51.474116 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Jan 23 18:53:51.492112 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Jan 23 18:53:51.532408 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#243 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jan 23 18:53:51.550094 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Jan 23 18:53:52.563530 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#247 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jan 23 18:53:52.579025 disk-uuid[706]: The operation has completed successfully. Jan 23 18:53:52.583206 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Jan 23 18:53:52.639745 systemd[1]: disk-uuid.service: Deactivated successfully. Jan 23 18:53:52.639862 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Jan 23 18:53:52.694674 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Jan 23 18:53:52.723418 sh[869]: Success Jan 23 18:53:52.807267 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Jan 23 18:53:52.807318 kernel: device-mapper: uevent: version 1.0.3 Jan 23 18:53:52.812164 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Jan 23 18:53:52.824089 kernel: device-mapper: verity: sha256 using shash "sha256-avx2" Jan 23 18:53:52.892138 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Jan 23 18:53:52.892786 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Jan 23 18:53:52.901292 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jan 23 18:53:52.911736 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jan 23 18:53:52.922121 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Jan 23 18:53:52.966579 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Jan 23 18:53:53.060120 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Jan 23 18:53:53.062104 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Jan 23 18:53:53.086692 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Jan 23 18:53:53.109721 kernel: BTRFS: device fsid 4711e7dc-9586-49d4-8dcc-466f082e7841 devid 1 transid 37 /dev/mapper/usr (254:0) scanned by mount (900) Jan 23 18:53:53.109755 kernel: BTRFS info (device dm-0): first mount of filesystem 4711e7dc-9586-49d4-8dcc-466f082e7841 Jan 23 18:53:53.114142 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Jan 23 18:53:53.668270 kernel: BTRFS info (device dm-0): disabling log replay at mount time Jan 23 18:53:53.668357 kernel: BTRFS info (device dm-0): enabling free space tree Jan 23 18:53:53.716440 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Jan 23 18:53:53.720723 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Jan 23 18:53:53.723729 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Jan 23 18:53:53.724539 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Jan 23 18:53:53.757348 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Jan 23 18:53:53.793092 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sdb6 (8:22) scanned by mount (931) Jan 23 18:53:53.799104 kernel: BTRFS info (device sdb6): first mount of filesystem a15cc984-6718-480b-8520-c0d724ebf6fe Jan 23 18:53:53.799135 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Jan 23 18:53:53.827215 kernel: BTRFS info (device sdb6): turning on async discard Jan 23 18:53:53.827246 kernel: BTRFS info (device sdb6): enabling free space tree Jan 23 18:53:53.836086 kernel: BTRFS info (device sdb6): last unmount of filesystem a15cc984-6718-480b-8520-c0d724ebf6fe Jan 23 18:53:53.836352 systemd[1]: Finished ignition-setup.service - Ignition (setup). Jan 23 18:53:53.837414 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Jan 23 18:53:53.864117 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jan 23 18:53:53.865738 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jan 23 18:53:53.918023 systemd-networkd[1069]: lo: Link UP Jan 23 18:53:53.918033 systemd-networkd[1069]: lo: Gained carrier Jan 23 18:53:53.919122 systemd-networkd[1069]: Enumeration completed Jan 23 18:53:53.919208 systemd[1]: Started systemd-networkd.service - Network Configuration. Jan 23 18:53:53.919485 systemd-networkd[1069]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jan 23 18:53:53.919490 systemd-networkd[1069]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jan 23 18:53:53.920260 systemd-networkd[1069]: eth0: Link UP Jan 23 18:53:53.920460 systemd-networkd[1069]: eth0: Gained carrier Jan 23 18:53:53.920470 systemd-networkd[1069]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jan 23 18:53:53.925665 systemd[1]: Reached target network.target - Network. Jan 23 18:53:53.981110 systemd-networkd[1069]: eth0: DHCPv4 address 10.200.4.28/24, gateway 10.200.4.1 acquired from 168.63.129.16 Jan 23 18:53:55.616236 ignition[1050]: Ignition 2.22.0 Jan 23 18:53:55.617031 ignition[1050]: Stage: fetch-offline Jan 23 18:53:55.619175 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Jan 23 18:53:55.617223 ignition[1050]: no configs at "/usr/lib/ignition/base.d" Jan 23 18:53:55.625271 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Jan 23 18:53:55.617239 ignition[1050]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jan 23 18:53:55.617347 ignition[1050]: parsed url from cmdline: "" Jan 23 18:53:55.617353 ignition[1050]: no config URL provided Jan 23 18:53:55.617361 ignition[1050]: reading system config file "/usr/lib/ignition/user.ign" Jan 23 18:53:55.617374 ignition[1050]: no config at "/usr/lib/ignition/user.ign" Jan 23 18:53:55.617383 ignition[1050]: failed to fetch config: resource requires networking Jan 23 18:53:55.617544 ignition[1050]: Ignition finished successfully Jan 23 18:53:55.668140 ignition[1083]: Ignition 2.22.0 Jan 23 18:53:55.668147 ignition[1083]: Stage: fetch Jan 23 18:53:55.668405 ignition[1083]: no configs at "/usr/lib/ignition/base.d" Jan 23 18:53:55.668416 ignition[1083]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jan 23 18:53:55.668524 ignition[1083]: parsed url from cmdline: "" Jan 23 18:53:55.668528 ignition[1083]: no config URL provided Jan 23 18:53:55.668534 ignition[1083]: reading system config file "/usr/lib/ignition/user.ign" Jan 23 18:53:55.668543 ignition[1083]: no config at "/usr/lib/ignition/user.ign" Jan 23 18:53:55.668561 ignition[1083]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Jan 23 18:53:55.703104 systemd-networkd[1069]: eth0: Gained IPv6LL Jan 23 18:53:55.804794 ignition[1083]: GET result: OK Jan 23 18:53:55.804883 ignition[1083]: config has been read from IMDS userdata Jan 23 18:53:55.804895 ignition[1083]: parsing config with SHA512: 2b5143f974f673baff8af9cfdc4e5d680f94d9efdeb07ccb1c5abce7868cb055f1df11955913bee365a5438f3f094b454794c68dc5f017a087ee7308f2133ae1 Jan 23 18:53:55.810434 unknown[1083]: fetched base config from "system" Jan 23 18:53:55.810595 ignition[1083]: fetch: fetch complete Jan 23 18:53:55.810441 unknown[1083]: fetched base config from "system" Jan 23 18:53:55.810600 ignition[1083]: fetch: fetch passed Jan 23 18:53:55.810446 unknown[1083]: fetched user config from "azure" Jan 23 18:53:55.810642 ignition[1083]: Ignition finished successfully Jan 23 18:53:55.817453 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Jan 23 18:53:55.826216 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Jan 23 18:53:55.870733 ignition[1093]: Ignition 2.22.0 Jan 23 18:53:55.870746 ignition[1093]: Stage: kargs Jan 23 18:53:55.870972 ignition[1093]: no configs at "/usr/lib/ignition/base.d" Jan 23 18:53:55.870984 ignition[1093]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jan 23 18:53:55.876407 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Jan 23 18:53:55.871604 ignition[1093]: kargs: kargs passed Jan 23 18:53:55.880451 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Jan 23 18:53:55.871647 ignition[1093]: Ignition finished successfully Jan 23 18:53:55.927286 ignition[1102]: Ignition 2.22.0 Jan 23 18:53:55.927299 ignition[1102]: Stage: disks Jan 23 18:53:55.927523 ignition[1102]: no configs at "/usr/lib/ignition/base.d" Jan 23 18:53:55.931854 systemd[1]: Finished ignition-disks.service - Ignition (disks). Jan 23 18:53:55.927534 ignition[1102]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jan 23 18:53:55.933140 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Jan 23 18:53:55.927958 ignition[1102]: disks: disks passed Jan 23 18:53:55.940670 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Jan 23 18:53:55.928000 ignition[1102]: Ignition finished successfully Jan 23 18:53:55.954439 systemd[1]: Reached target local-fs.target - Local File Systems. Jan 23 18:53:55.958780 systemd[1]: Reached target sysinit.target - System Initialization. Jan 23 18:53:55.958884 systemd[1]: Reached target basic.target - Basic System. Jan 23 18:53:55.968556 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Jan 23 18:53:56.066898 systemd-fsck[1114]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks Jan 23 18:53:56.074265 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Jan 23 18:53:56.075386 systemd[1]: Mounting sysroot.mount - /sysroot... Jan 23 18:53:56.352101 kernel: EXT4-fs (sdb9): mounted filesystem dcb97a38-a4f5-43e7-bcb0-85a5c1e2a446 r/w with ordered data mode. Quota mode: none. Jan 23 18:53:56.353019 systemd[1]: Mounted sysroot.mount - /sysroot. Jan 23 18:53:56.353674 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Jan 23 18:53:56.372391 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jan 23 18:53:56.373494 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Jan 23 18:53:56.397577 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Jan 23 18:53:56.397716 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Jan 23 18:53:56.433741 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sdb6 (8:22) scanned by mount (1128) Jan 23 18:53:56.433776 kernel: BTRFS info (device sdb6): first mount of filesystem a15cc984-6718-480b-8520-c0d724ebf6fe Jan 23 18:53:56.433789 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Jan 23 18:53:56.397745 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Jan 23 18:53:56.448310 kernel: BTRFS info (device sdb6): turning on async discard Jan 23 18:53:56.448331 kernel: BTRFS info (device sdb6): enabling free space tree Jan 23 18:53:56.408538 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Jan 23 18:53:56.438953 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Jan 23 18:53:56.454017 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jan 23 18:53:57.457922 coreos-metadata[1130]: Jan 23 18:53:57.457 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Jan 23 18:53:57.465859 coreos-metadata[1130]: Jan 23 18:53:57.460 INFO Fetch successful Jan 23 18:53:57.465859 coreos-metadata[1130]: Jan 23 18:53:57.460 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Jan 23 18:53:57.480142 coreos-metadata[1130]: Jan 23 18:53:57.477 INFO Fetch successful Jan 23 18:53:57.480142 coreos-metadata[1130]: Jan 23 18:53:57.477 INFO wrote hostname ci-4459.2.3-a-cafc3b76e8 to /sysroot/etc/hostname Jan 23 18:53:57.491802 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Jan 23 18:53:57.744669 initrd-setup-root[1159]: cut: /sysroot/etc/passwd: No such file or directory Jan 23 18:53:57.808000 initrd-setup-root[1166]: cut: /sysroot/etc/group: No such file or directory Jan 23 18:53:57.816303 initrd-setup-root[1173]: cut: /sysroot/etc/shadow: No such file or directory Jan 23 18:53:57.824100 initrd-setup-root[1180]: cut: /sysroot/etc/gshadow: No such file or directory Jan 23 18:53:59.364046 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Jan 23 18:53:59.373492 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Jan 23 18:53:59.374232 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Jan 23 18:53:59.403920 systemd[1]: sysroot-oem.mount: Deactivated successfully. Jan 23 18:53:59.413096 kernel: BTRFS info (device sdb6): last unmount of filesystem a15cc984-6718-480b-8520-c0d724ebf6fe Jan 23 18:53:59.430449 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Jan 23 18:53:59.442441 ignition[1249]: INFO : Ignition 2.22.0 Jan 23 18:53:59.442441 ignition[1249]: INFO : Stage: mount Jan 23 18:53:59.448393 ignition[1249]: INFO : no configs at "/usr/lib/ignition/base.d" Jan 23 18:53:59.448393 ignition[1249]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jan 23 18:53:59.448393 ignition[1249]: INFO : mount: mount passed Jan 23 18:53:59.448393 ignition[1249]: INFO : Ignition finished successfully Jan 23 18:53:59.449685 systemd[1]: Finished ignition-mount.service - Ignition (mount). Jan 23 18:53:59.461607 systemd[1]: Starting ignition-files.service - Ignition (files)... Jan 23 18:53:59.497034 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jan 23 18:53:59.524094 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sdb6 (8:22) scanned by mount (1262) Jan 23 18:53:59.533644 kernel: BTRFS info (device sdb6): first mount of filesystem a15cc984-6718-480b-8520-c0d724ebf6fe Jan 23 18:53:59.533673 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Jan 23 18:53:59.544604 kernel: BTRFS info (device sdb6): turning on async discard Jan 23 18:53:59.544636 kernel: BTRFS info (device sdb6): enabling free space tree Jan 23 18:53:59.548815 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jan 23 18:53:59.589812 ignition[1279]: INFO : Ignition 2.22.0 Jan 23 18:53:59.589812 ignition[1279]: INFO : Stage: files Jan 23 18:53:59.596105 ignition[1279]: INFO : no configs at "/usr/lib/ignition/base.d" Jan 23 18:53:59.596105 ignition[1279]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jan 23 18:53:59.596105 ignition[1279]: DEBUG : files: compiled without relabeling support, skipping Jan 23 18:53:59.613282 ignition[1279]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Jan 23 18:53:59.613282 ignition[1279]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Jan 23 18:53:59.648352 ignition[1279]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Jan 23 18:53:59.654994 ignition[1279]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Jan 23 18:53:59.654994 ignition[1279]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Jan 23 18:53:59.649122 unknown[1279]: wrote ssh authorized keys file for user: core Jan 23 18:53:59.700607 ignition[1279]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Jan 23 18:53:59.708483 ignition[1279]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Jan 23 18:53:59.708483 ignition[1279]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Jan 23 18:53:59.708483 ignition[1279]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Jan 23 18:53:59.708483 ignition[1279]: INFO : files: files passed Jan 23 18:53:59.708483 ignition[1279]: INFO : Ignition finished successfully Jan 23 18:53:59.703238 systemd[1]: Finished ignition-files.service - Ignition (files). Jan 23 18:53:59.709519 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Jan 23 18:53:59.751828 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Jan 23 18:53:59.756138 systemd[1]: ignition-quench.service: Deactivated successfully. Jan 23 18:53:59.756248 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Jan 23 18:53:59.818134 initrd-setup-root-after-ignition[1312]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jan 23 18:53:59.818134 initrd-setup-root-after-ignition[1312]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Jan 23 18:53:59.831361 initrd-setup-root-after-ignition[1316]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jan 23 18:53:59.832468 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Jan 23 18:53:59.838176 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Jan 23 18:53:59.849270 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Jan 23 18:53:59.912124 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Jan 23 18:53:59.912233 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Jan 23 18:53:59.921521 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Jan 23 18:53:59.926637 systemd[1]: Reached target initrd.target - Initrd Default Target. Jan 23 18:53:59.935157 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Jan 23 18:53:59.935915 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Jan 23 18:53:59.991341 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jan 23 18:53:59.992611 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Jan 23 18:54:00.026294 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Jan 23 18:54:00.026504 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Jan 23 18:54:00.036165 systemd[1]: Stopped target timers.target - Timer Units. Jan 23 18:54:00.045840 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Jan 23 18:54:00.045962 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jan 23 18:54:00.062225 systemd[1]: Stopped target initrd.target - Initrd Default Target. Jan 23 18:54:00.067099 systemd[1]: Stopped target basic.target - Basic System. Jan 23 18:54:00.075167 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Jan 23 18:54:00.082305 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Jan 23 18:54:00.090941 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Jan 23 18:54:00.100334 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Jan 23 18:54:00.109338 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Jan 23 18:54:00.118304 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Jan 23 18:54:00.126503 systemd[1]: Stopped target sysinit.target - System Initialization. Jan 23 18:54:00.136516 systemd[1]: Stopped target local-fs.target - Local File Systems. Jan 23 18:54:00.144713 systemd[1]: Stopped target swap.target - Swaps. Jan 23 18:54:00.152762 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Jan 23 18:54:00.152902 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Jan 23 18:54:00.167401 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Jan 23 18:54:00.171604 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jan 23 18:54:00.180244 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Jan 23 18:54:00.180353 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jan 23 18:54:00.189973 systemd[1]: dracut-initqueue.service: Deactivated successfully. Jan 23 18:54:00.190136 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Jan 23 18:54:00.208730 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Jan 23 18:54:00.208863 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Jan 23 18:54:00.219681 systemd[1]: ignition-files.service: Deactivated successfully. Jan 23 18:54:00.219804 systemd[1]: Stopped ignition-files.service - Ignition (files). Jan 23 18:54:00.223943 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Jan 23 18:54:00.224055 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Jan 23 18:54:00.233350 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Jan 23 18:54:00.242476 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Jan 23 18:54:00.242604 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Jan 23 18:54:00.260613 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Jan 23 18:54:00.303630 ignition[1336]: INFO : Ignition 2.22.0 Jan 23 18:54:00.303630 ignition[1336]: INFO : Stage: umount Jan 23 18:54:00.303630 ignition[1336]: INFO : no configs at "/usr/lib/ignition/base.d" Jan 23 18:54:00.303630 ignition[1336]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jan 23 18:54:00.303630 ignition[1336]: INFO : umount: umount passed Jan 23 18:54:00.303630 ignition[1336]: INFO : Ignition finished successfully Jan 23 18:54:00.263218 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Jan 23 18:54:00.263352 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Jan 23 18:54:00.276904 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Jan 23 18:54:00.277023 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Jan 23 18:54:00.289116 systemd[1]: initrd-cleanup.service: Deactivated successfully. Jan 23 18:54:00.289212 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Jan 23 18:54:00.299500 systemd[1]: sysroot-boot.mount: Deactivated successfully. Jan 23 18:54:00.300154 systemd[1]: ignition-mount.service: Deactivated successfully. Jan 23 18:54:00.300270 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Jan 23 18:54:00.308186 systemd[1]: ignition-disks.service: Deactivated successfully. Jan 23 18:54:00.308286 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Jan 23 18:54:00.310824 systemd[1]: ignition-kargs.service: Deactivated successfully. Jan 23 18:54:00.310875 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Jan 23 18:54:00.318057 systemd[1]: ignition-fetch.service: Deactivated successfully. Jan 23 18:54:00.318118 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Jan 23 18:54:00.330104 systemd[1]: Stopped target network.target - Network. Jan 23 18:54:00.335014 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Jan 23 18:54:00.335064 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Jan 23 18:54:00.341800 systemd[1]: Stopped target paths.target - Path Units. Jan 23 18:54:00.350154 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Jan 23 18:54:00.354119 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jan 23 18:54:00.363041 systemd[1]: Stopped target slices.target - Slice Units. Jan 23 18:54:00.371340 systemd[1]: Stopped target sockets.target - Socket Units. Jan 23 18:54:00.379463 systemd[1]: iscsid.socket: Deactivated successfully. Jan 23 18:54:00.379512 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Jan 23 18:54:00.383933 systemd[1]: iscsiuio.socket: Deactivated successfully. Jan 23 18:54:00.383972 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jan 23 18:54:00.391820 systemd[1]: ignition-setup.service: Deactivated successfully. Jan 23 18:54:00.391875 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Jan 23 18:54:00.400820 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Jan 23 18:54:00.400867 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Jan 23 18:54:00.408552 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Jan 23 18:54:00.416660 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Jan 23 18:54:00.424607 systemd[1]: systemd-resolved.service: Deactivated successfully. Jan 23 18:54:00.424715 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Jan 23 18:54:00.441681 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Jan 23 18:54:00.441963 systemd[1]: systemd-networkd.service: Deactivated successfully. Jan 23 18:54:00.442090 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Jan 23 18:54:00.453637 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Jan 23 18:54:00.453874 systemd[1]: sysroot-boot.service: Deactivated successfully. Jan 23 18:54:00.453971 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Jan 23 18:54:00.458152 systemd[1]: Stopped target network-pre.target - Preparation for Network. Jan 23 18:54:00.464199 systemd[1]: systemd-networkd.socket: Deactivated successfully. Jan 23 18:54:00.464239 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Jan 23 18:54:00.471999 systemd[1]: initrd-setup-root.service: Deactivated successfully. Jan 23 18:54:00.472053 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Jan 23 18:54:00.482004 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Jan 23 18:54:00.490388 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Jan 23 18:54:00.490441 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jan 23 18:54:00.497644 systemd[1]: systemd-sysctl.service: Deactivated successfully. Jan 23 18:54:00.497692 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Jan 23 18:54:00.517556 systemd[1]: systemd-modules-load.service: Deactivated successfully. Jan 23 18:54:00.517606 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Jan 23 18:54:00.529704 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Jan 23 18:54:00.529759 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Jan 23 18:54:00.542007 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Jan 23 18:54:00.555466 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Jan 23 18:54:00.555541 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Jan 23 18:54:00.595830 systemd[1]: systemd-udevd.service: Deactivated successfully. Jan 23 18:54:00.606236 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Jan 23 18:54:00.606822 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Jan 23 18:54:00.606869 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Jan 23 18:54:00.620183 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Jan 23 18:54:00.620226 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Jan 23 18:54:00.628281 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Jan 23 18:54:00.628336 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Jan 23 18:54:00.641035 systemd[1]: dracut-cmdline.service: Deactivated successfully. Jan 23 18:54:00.641105 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Jan 23 18:54:00.653867 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jan 23 18:54:00.653924 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jan 23 18:54:00.668448 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Jan 23 18:54:00.671759 systemd[1]: systemd-network-generator.service: Deactivated successfully. Jan 23 18:54:00.671817 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Jan 23 18:54:00.690554 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Jan 23 18:54:00.690604 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jan 23 18:54:00.704376 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jan 23 18:54:00.704426 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jan 23 18:54:00.719535 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Jan 23 18:54:00.719592 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Jan 23 18:54:00.719635 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jan 23 18:54:00.720036 systemd[1]: network-cleanup.service: Deactivated successfully. Jan 23 18:54:00.720150 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Jan 23 18:54:00.723852 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Jan 23 18:54:00.723939 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Jan 23 18:54:00.734090 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Jan 23 18:54:00.743764 systemd[1]: Starting initrd-switch-root.service - Switch Root... Jan 23 18:54:00.784866 systemd[1]: Switching root. Jan 23 18:54:00.988527 systemd-journald[213]: Journal stopped Jan 23 18:54:07.185369 systemd-journald[213]: Received SIGTERM from PID 1 (systemd). Jan 23 18:54:07.185402 kernel: SELinux: policy capability network_peer_controls=1 Jan 23 18:54:07.185415 kernel: SELinux: policy capability open_perms=1 Jan 23 18:54:07.185425 kernel: SELinux: policy capability extended_socket_class=1 Jan 23 18:54:07.185434 kernel: SELinux: policy capability always_check_network=0 Jan 23 18:54:07.185443 kernel: SELinux: policy capability cgroup_seclabel=1 Jan 23 18:54:07.185453 kernel: SELinux: policy capability nnp_nosuid_transition=1 Jan 23 18:54:07.185466 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Jan 23 18:54:07.185475 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Jan 23 18:54:07.185485 kernel: SELinux: policy capability userspace_initial_context=0 Jan 23 18:54:07.185494 kernel: audit: type=1403 audit(1769194442.417:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Jan 23 18:54:07.185505 systemd[1]: Successfully loaded SELinux policy in 278.028ms. Jan 23 18:54:07.185517 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 7.762ms. Jan 23 18:54:07.185528 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 18:54:07.185543 systemd[1]: Detected virtualization microsoft. Jan 23 18:54:07.185554 systemd[1]: Detected architecture x86-64. Jan 23 18:54:07.185565 systemd[1]: Detected first boot. Jan 23 18:54:07.185580 systemd[1]: Hostname set to . Jan 23 18:54:07.185591 systemd[1]: Initializing machine ID from random generator. Jan 23 18:54:07.185604 zram_generator::config[1384]: No configuration found. Jan 23 18:54:07.185616 kernel: Guest personality initialized and is inactive Jan 23 18:54:07.185625 kernel: VMCI host device registered (name=vmci, major=10, minor=259) Jan 23 18:54:07.185635 kernel: Initialized host personality Jan 23 18:54:07.185645 kernel: NET: Registered PF_VSOCK protocol family Jan 23 18:54:07.185656 systemd[1]: Populated /etc with preset unit settings. Jan 23 18:54:07.185668 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Jan 23 18:54:07.185681 systemd[1]: initrd-switch-root.service: Deactivated successfully. Jan 23 18:54:07.185692 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Jan 23 18:54:07.185703 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Jan 23 18:54:07.185713 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Jan 23 18:54:07.185725 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Jan 23 18:54:07.185736 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Jan 23 18:54:07.185747 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Jan 23 18:54:07.185761 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Jan 23 18:54:07.185773 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Jan 23 18:54:07.185784 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Jan 23 18:54:07.185795 systemd[1]: Created slice user.slice - User and Session Slice. Jan 23 18:54:07.185806 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jan 23 18:54:07.185821 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jan 23 18:54:07.185833 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Jan 23 18:54:07.185847 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Jan 23 18:54:07.185859 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Jan 23 18:54:07.185873 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jan 23 18:54:07.185885 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Jan 23 18:54:07.185897 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jan 23 18:54:07.185908 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jan 23 18:54:07.185919 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Jan 23 18:54:07.185931 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Jan 23 18:54:07.185942 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Jan 23 18:54:07.185956 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Jan 23 18:54:07.185968 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jan 23 18:54:07.185979 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jan 23 18:54:07.185990 systemd[1]: Reached target slices.target - Slice Units. Jan 23 18:54:07.186002 systemd[1]: Reached target swap.target - Swaps. Jan 23 18:54:07.186013 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Jan 23 18:54:07.186024 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Jan 23 18:54:07.186039 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Jan 23 18:54:07.186050 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jan 23 18:54:07.186062 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jan 23 18:54:07.186141 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jan 23 18:54:07.186154 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Jan 23 18:54:07.186166 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Jan 23 18:54:07.186181 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Jan 23 18:54:07.186192 systemd[1]: Mounting media.mount - External Media Directory... Jan 23 18:54:07.186204 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jan 23 18:54:07.186216 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Jan 23 18:54:07.186227 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Jan 23 18:54:07.186238 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Jan 23 18:54:07.186250 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 18:54:07.186262 systemd[1]: Reached target machines.target - Containers. Jan 23 18:54:07.186276 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Jan 23 18:54:07.186288 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jan 23 18:54:07.186300 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jan 23 18:54:07.186311 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Jan 23 18:54:07.186323 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jan 23 18:54:07.186334 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jan 23 18:54:07.186345 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jan 23 18:54:07.186357 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Jan 23 18:54:07.186371 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jan 23 18:54:07.186383 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Jan 23 18:54:07.186395 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Jan 23 18:54:07.186406 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Jan 23 18:54:07.186417 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Jan 23 18:54:07.186429 systemd[1]: Stopped systemd-fsck-usr.service. Jan 23 18:54:07.186440 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 18:54:07.186452 kernel: fuse: init (API version 7.41) Jan 23 18:54:07.186463 systemd[1]: Starting systemd-journald.service - Journal Service... Jan 23 18:54:07.186477 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jan 23 18:54:07.186488 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jan 23 18:54:07.186500 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Jan 23 18:54:07.186511 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Jan 23 18:54:07.186546 systemd-journald[1491]: Collecting audit messages is disabled. Jan 23 18:54:07.186580 kernel: loop: module loaded Jan 23 18:54:07.186593 systemd-journald[1491]: Journal started Jan 23 18:54:07.186615 systemd-journald[1491]: Runtime Journal (/run/log/journal/ba9dd563aba443cf881e2694b1592f9e) is 8M, max 2.2G, 2.1G free. Jan 23 18:54:06.443772 systemd[1]: Queued start job for default target multi-user.target. Jan 23 18:54:06.458548 systemd[1]: Unnecessary job was removed for dev-sdb6.device - /dev/sdb6. Jan 23 18:54:06.459109 systemd[1]: systemd-journald.service: Deactivated successfully. Jan 23 18:54:07.198213 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jan 23 18:54:07.205045 systemd[1]: verity-setup.service: Deactivated successfully. Jan 23 18:54:07.205096 systemd[1]: Stopped verity-setup.service. Jan 23 18:54:07.219091 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 18:54:07.225119 systemd[1]: Started systemd-journald.service - Journal Service. Jan 23 18:54:07.230151 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Jan 23 18:54:07.234662 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Jan 23 18:54:07.239405 systemd[1]: Mounted media.mount - External Media Directory. Jan 23 18:54:07.243623 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Jan 23 18:54:07.249125 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Jan 23 18:54:07.253934 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Jan 23 18:54:07.258951 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Jan 23 18:54:07.266115 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jan 23 18:54:07.272055 systemd[1]: modprobe@configfs.service: Deactivated successfully. Jan 23 18:54:07.272279 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Jan 23 18:54:07.278142 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jan 23 18:54:07.278344 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jan 23 18:54:07.284094 kernel: ACPI: bus type drm_connector registered Jan 23 18:54:07.286138 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jan 23 18:54:07.286324 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jan 23 18:54:07.291888 systemd[1]: modprobe@drm.service: Deactivated successfully. Jan 23 18:54:07.292060 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jan 23 18:54:07.297020 systemd[1]: modprobe@fuse.service: Deactivated successfully. Jan 23 18:54:07.297232 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Jan 23 18:54:07.302228 systemd[1]: modprobe@loop.service: Deactivated successfully. Jan 23 18:54:07.302411 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jan 23 18:54:07.307399 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jan 23 18:54:07.312461 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jan 23 18:54:07.318212 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Jan 23 18:54:07.332635 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Jan 23 18:54:07.344253 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jan 23 18:54:07.353971 systemd[1]: Reached target network-pre.target - Preparation for Network. Jan 23 18:54:07.359853 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Jan 23 18:54:07.365415 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Jan 23 18:54:07.371100 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Jan 23 18:54:07.371148 systemd[1]: Reached target local-fs.target - Local File Systems. Jan 23 18:54:07.376512 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Jan 23 18:54:07.383921 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Jan 23 18:54:07.388631 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jan 23 18:54:07.389727 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Jan 23 18:54:07.395176 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Jan 23 18:54:07.399952 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jan 23 18:54:07.400840 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Jan 23 18:54:07.405286 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jan 23 18:54:07.406161 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jan 23 18:54:07.411385 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Jan 23 18:54:07.417218 systemd[1]: Starting systemd-sysusers.service - Create System Users... Jan 23 18:54:07.423161 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Jan 23 18:54:07.428307 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Jan 23 18:54:07.432519 systemd-journald[1491]: Time spent on flushing to /var/log/journal/ba9dd563aba443cf881e2694b1592f9e is 13.934ms for 952 entries. Jan 23 18:54:07.432519 systemd-journald[1491]: System Journal (/var/log/journal/ba9dd563aba443cf881e2694b1592f9e) is 8M, max 2.6G, 2.6G free. Jan 23 18:54:07.523811 systemd-journald[1491]: Received client request to flush runtime journal. Jan 23 18:54:07.523874 kernel: loop0: detected capacity change from 0 to 27936 Jan 23 18:54:07.437845 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Jan 23 18:54:07.446273 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Jan 23 18:54:07.452509 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Jan 23 18:54:07.529020 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Jan 23 18:54:07.541198 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jan 23 18:54:07.561180 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Jan 23 18:54:07.561870 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Jan 23 18:54:07.723146 systemd[1]: Finished systemd-sysusers.service - Create System Users. Jan 23 18:54:07.729218 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jan 23 18:54:07.815159 systemd-tmpfiles[1541]: ACLs are not supported, ignoring. Jan 23 18:54:07.815181 systemd-tmpfiles[1541]: ACLs are not supported, ignoring. Jan 23 18:54:07.819473 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jan 23 18:54:08.294825 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Jan 23 18:54:08.301866 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jan 23 18:54:08.314095 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jan 23 18:54:08.341821 systemd-udevd[1548]: Using default interface naming scheme 'v255'. Jan 23 18:54:08.435095 kernel: loop1: detected capacity change from 0 to 110984 Jan 23 18:54:08.659123 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jan 23 18:54:08.666287 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jan 23 18:54:08.706831 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Jan 23 18:54:08.768098 kernel: loop2: detected capacity change from 0 to 128560 Jan 23 18:54:08.786106 kernel: hv_vmbus: registering driver hyperv_fb Jan 23 18:54:08.796437 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Jan 23 18:54:08.802157 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Jan 23 18:54:08.802220 kernel: Console: switching to colour dummy device 80x25 Jan 23 18:54:08.808092 kernel: hv_vmbus: registering driver hv_balloon Jan 23 18:54:08.808144 kernel: Console: switching to colour frame buffer device 128x48 Jan 23 18:54:08.808167 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Jan 23 18:54:08.811293 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Jan 23 18:54:08.824096 kernel: mousedev: PS/2 mouse device common for all mice Jan 23 18:54:08.866236 kernel: EDAC MC: Ver: 3.0.0 Jan 23 18:54:08.877889 systemd[1]: Started systemd-userdbd.service - User Database Manager. Jan 23 18:54:08.892094 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jan 23 18:54:08.906168 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jan 23 18:54:08.906387 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jan 23 18:54:08.912310 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jan 23 18:54:08.915154 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jan 23 18:54:09.073882 systemd-networkd[1556]: lo: Link UP Jan 23 18:54:09.073893 systemd-networkd[1556]: lo: Gained carrier Jan 23 18:54:09.075248 systemd-networkd[1556]: Enumeration completed Jan 23 18:54:09.075368 systemd[1]: Started systemd-networkd.service - Network Configuration. Jan 23 18:54:09.075632 systemd-networkd[1556]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jan 23 18:54:09.075637 systemd-networkd[1556]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jan 23 18:54:09.076178 systemd-networkd[1556]: eth0: Link UP Jan 23 18:54:09.076187 systemd-networkd[1556]: eth0: Gained carrier Jan 23 18:54:09.076202 systemd-networkd[1556]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jan 23 18:54:09.080301 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Jan 23 18:54:09.081343 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Jan 23 18:54:09.133203 systemd-networkd[1556]: eth0: DHCPv4 address 10.200.4.28/24, gateway 10.200.4.1 acquired from 168.63.129.16 Jan 23 18:54:09.183708 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Jan 23 18:54:09.184265 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Jan 23 18:54:09.186287 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Jan 23 18:54:09.230887 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Jan 23 18:54:09.410566 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jan 23 18:54:09.580093 kernel: loop3: detected capacity change from 0 to 27936 Jan 23 18:54:09.598109 kernel: loop4: detected capacity change from 0 to 110984 Jan 23 18:54:09.623091 kernel: loop5: detected capacity change from 0 to 128560 Jan 23 18:54:09.641436 (sd-merge)[1710]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Jan 23 18:54:09.641924 (sd-merge)[1710]: Merged extensions into '/usr'. Jan 23 18:54:09.645567 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Jan 23 18:54:09.652741 systemd[1]: Starting ensure-sysext.service... Jan 23 18:54:09.657119 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jan 23 18:54:09.676858 systemd[1]: Reload requested from client PID 1712 ('systemctl') (unit ensure-sysext.service)... Jan 23 18:54:09.676874 systemd[1]: Reloading... Jan 23 18:54:09.695814 systemd-tmpfiles[1713]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Jan 23 18:54:09.708180 systemd-tmpfiles[1713]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Jan 23 18:54:09.708514 systemd-tmpfiles[1713]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Jan 23 18:54:09.708821 systemd-tmpfiles[1713]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Jan 23 18:54:09.709723 systemd-tmpfiles[1713]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Jan 23 18:54:09.710081 systemd-tmpfiles[1713]: ACLs are not supported, ignoring. Jan 23 18:54:09.710155 systemd-tmpfiles[1713]: ACLs are not supported, ignoring. Jan 23 18:54:09.714493 systemd-tmpfiles[1713]: Detected autofs mount point /boot during canonicalization of boot. Jan 23 18:54:09.714506 systemd-tmpfiles[1713]: Skipping /boot Jan 23 18:54:09.724100 zram_generator::config[1743]: No configuration found. Jan 23 18:54:09.725175 systemd-tmpfiles[1713]: Detected autofs mount point /boot during canonicalization of boot. Jan 23 18:54:09.725189 systemd-tmpfiles[1713]: Skipping /boot Jan 23 18:54:09.992036 systemd[1]: Reloading finished in 314 ms. Jan 23 18:54:10.043856 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jan 23 18:54:10.061515 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jan 23 18:54:10.062829 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jan 23 18:54:10.068206 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Jan 23 18:54:10.073203 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jan 23 18:54:10.083799 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jan 23 18:54:10.089662 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jan 23 18:54:10.095525 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jan 23 18:54:10.099949 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jan 23 18:54:10.100138 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 18:54:10.101295 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Jan 23 18:54:10.108065 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jan 23 18:54:10.113403 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Jan 23 18:54:10.118571 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 18:54:10.120963 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jan 23 18:54:10.121184 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jan 23 18:54:10.127173 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jan 23 18:54:10.127364 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jan 23 18:54:10.133041 systemd[1]: modprobe@loop.service: Deactivated successfully. Jan 23 18:54:10.133245 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jan 23 18:54:10.146681 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jan 23 18:54:10.146940 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jan 23 18:54:10.154532 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jan 23 18:54:10.160290 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jan 23 18:54:10.175548 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jan 23 18:54:10.180209 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jan 23 18:54:10.180401 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 18:54:10.180565 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 18:54:10.185186 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jan 23 18:54:10.185395 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jan 23 18:54:10.190854 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jan 23 18:54:10.191036 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jan 23 18:54:10.196611 systemd[1]: modprobe@loop.service: Deactivated successfully. Jan 23 18:54:10.196797 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jan 23 18:54:10.204182 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Jan 23 18:54:10.216402 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jan 23 18:54:10.216673 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jan 23 18:54:10.217942 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jan 23 18:54:10.224417 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jan 23 18:54:10.240062 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jan 23 18:54:10.247370 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jan 23 18:54:10.251738 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jan 23 18:54:10.251872 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 18:54:10.252190 systemd[1]: Reached target time-set.target - System Time Set. Jan 23 18:54:10.256420 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 18:54:10.258958 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jan 23 18:54:10.259173 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jan 23 18:54:10.264837 systemd[1]: modprobe@drm.service: Deactivated successfully. Jan 23 18:54:10.265039 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jan 23 18:54:10.270557 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jan 23 18:54:10.270743 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jan 23 18:54:10.276439 systemd[1]: modprobe@loop.service: Deactivated successfully. Jan 23 18:54:10.276630 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jan 23 18:54:10.284295 systemd[1]: Finished ensure-sysext.service. Jan 23 18:54:10.291376 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jan 23 18:54:10.291437 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jan 23 18:54:10.321653 systemd-resolved[1811]: Positive Trust Anchors: Jan 23 18:54:10.321668 systemd-resolved[1811]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jan 23 18:54:10.321699 systemd-resolved[1811]: 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 18:54:10.325986 systemd-resolved[1811]: Using system hostname 'ci-4459.2.3-a-cafc3b76e8'. Jan 23 18:54:10.327586 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jan 23 18:54:10.332431 systemd[1]: Reached target network.target - Network. Jan 23 18:54:10.336181 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jan 23 18:54:10.342481 augenrules[1851]: No rules Jan 23 18:54:10.343708 systemd[1]: audit-rules.service: Deactivated successfully. Jan 23 18:54:10.343960 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jan 23 18:54:10.397984 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Jan 23 18:54:10.546210 systemd-networkd[1556]: eth0: Gained IPv6LL Jan 23 18:54:10.548539 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Jan 23 18:54:10.559242 systemd[1]: Reached target network-online.target - Network is Online. Jan 23 18:54:10.918453 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Jan 23 18:54:10.924037 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 18:54:16.475089 ldconfig[1521]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jan 23 18:54:16.487986 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Jan 23 18:54:16.494703 systemd[1]: Starting systemd-update-done.service - Update is Completed... Jan 23 18:54:16.548179 systemd[1]: Finished systemd-update-done.service - Update is Completed. Jan 23 18:54:16.553403 systemd[1]: Reached target sysinit.target - System Initialization. Jan 23 18:54:16.558254 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Jan 23 18:54:16.563575 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 18:54:16.569034 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Jan 23 18:54:16.574129 systemd[1]: Started logrotate.timer - Daily rotation of log files. Jan 23 18:54:16.578885 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Jan 23 18:54:16.584322 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Jan 23 18:54:16.589780 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Jan 23 18:54:16.589816 systemd[1]: Reached target paths.target - Path Units. Jan 23 18:54:16.593749 systemd[1]: Reached target timers.target - Timer Units. Jan 23 18:54:16.610719 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Jan 23 18:54:16.616660 systemd[1]: Starting docker.socket - Docker Socket for the API... Jan 23 18:54:16.622840 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Jan 23 18:54:16.628354 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Jan 23 18:54:16.633746 systemd[1]: Reached target ssh-access.target - SSH Access Available. Jan 23 18:54:16.642725 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Jan 23 18:54:16.647392 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Jan 23 18:54:16.653054 systemd[1]: Listening on docker.socket - Docker Socket for the API. Jan 23 18:54:16.658487 systemd[1]: Reached target sockets.target - Socket Units. Jan 23 18:54:16.662675 systemd[1]: Reached target basic.target - Basic System. Jan 23 18:54:16.666499 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Jan 23 18:54:16.666532 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Jan 23 18:54:16.669238 systemd[1]: Starting chronyd.service - NTP client/server... Jan 23 18:54:16.673803 systemd[1]: Starting containerd.service - containerd container runtime... Jan 23 18:54:16.681348 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Jan 23 18:54:16.686522 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Jan 23 18:54:16.691328 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Jan 23 18:54:16.696999 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Jan 23 18:54:16.702816 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Jan 23 18:54:16.707112 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 18:54:16.708066 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Jan 23 18:54:16.713138 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 18:54:16.714119 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Jan 23 18:54:16.718783 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 18:54:16.719752 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Jan 23 18:54:16.724806 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Jan 23 18:54:16.729052 jq[1873]: false Jan 23 18:54:16.729954 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Jan 23 18:54:16.736446 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Jan 23 18:54:16.743572 systemd[1]: Starting systemd-logind.service - User Login Management... Jan 23 18:54:16.748669 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Jan 23 18:54:16.749108 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Jan 23 18:54:16.751309 systemd[1]: Starting update-engine.service - Update Engine... Jan 23 18:54:16.755912 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Jan 23 18:54:16.763031 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Jan 23 18:54:16.764208 jq[1890]: true Jan 23 18:54:16.768549 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Jan 23 18:54:16.768756 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Jan 23 18:54:16.769042 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Jan 23 18:54:16.769253 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Jan 23 18:54:16.774200 chronyd[1865]: chronyd version 4.7 starting (+CMDMON +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +NTS +SECHASH +IPV6 -DEBUG) Jan 23 18:54:16.776716 KVP[1879]: KVP starting; pid is:1879 Jan 23 18:54:16.777417 extend-filesystems[1874]: Found /dev/sdb6 Jan 23 18:54:16.784929 jq[1893]: true Jan 23 18:54:16.785409 KVP[1879]: KVP LIC Version: 3.1 Jan 23 18:54:16.786092 kernel: hv_utils: KVP IC version 4.0 Jan 23 18:54:16.787127 google_oslogin_nss_cache[1875]: oslogin_cache_refresh[1875]: Refreshing passwd entry cache Jan 23 18:54:16.787137 oslogin_cache_refresh[1875]: Refreshing passwd entry cache Jan 23 18:54:16.793694 systemd[1]: motdgen.service: Deactivated successfully. Jan 23 18:54:16.793996 google_oslogin_nss_cache[1875]: oslogin_cache_refresh[1875]: Failure getting users, quitting Jan 23 18:54:16.793990 oslogin_cache_refresh[1875]: Failure getting users, quitting Jan 23 18:54:16.794189 google_oslogin_nss_cache[1875]: oslogin_cache_refresh[1875]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Jan 23 18:54:16.794189 google_oslogin_nss_cache[1875]: oslogin_cache_refresh[1875]: Refreshing group entry cache Jan 23 18:54:16.794009 oslogin_cache_refresh[1875]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Jan 23 18:54:16.794055 oslogin_cache_refresh[1875]: Refreshing group entry cache Jan 23 18:54:16.796242 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Jan 23 18:54:16.796419 (ntainerd)[1904]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Jan 23 18:54:16.802553 google_oslogin_nss_cache[1875]: oslogin_cache_refresh[1875]: Failure getting groups, quitting Jan 23 18:54:16.802553 google_oslogin_nss_cache[1875]: oslogin_cache_refresh[1875]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Jan 23 18:54:16.802537 oslogin_cache_refresh[1875]: Failure getting groups, quitting Jan 23 18:54:16.802549 oslogin_cache_refresh[1875]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Jan 23 18:54:16.803942 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Jan 23 18:54:16.804185 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Jan 23 18:54:16.805036 chronyd[1865]: Timezone right/UTC failed leap second check, ignoring Jan 23 18:54:16.805220 chronyd[1865]: Loaded seccomp filter (level 2) Jan 23 18:54:16.806500 extend-filesystems[1874]: Found /dev/sdb9 Jan 23 18:54:16.812045 extend-filesystems[1874]: Checking size of /dev/sdb9 Jan 23 18:54:16.809141 systemd[1]: Started chronyd.service - NTP client/server. Jan 23 18:54:16.828140 extend-filesystems[1874]: Old size kept for /dev/sdb9 Jan 23 18:54:16.832122 update_engine[1887]: I20260123 18:54:16.827914 1887 main.cc:92] Flatcar Update Engine starting Jan 23 18:54:16.828954 systemd[1]: extend-filesystems.service: Deactivated successfully. Jan 23 18:54:16.829208 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Jan 23 18:54:16.856602 systemd-logind[1885]: New seat seat0. Jan 23 18:54:16.857754 systemd-logind[1885]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Jan 23 18:54:16.857953 systemd[1]: Started systemd-logind.service - User Login Management. Jan 23 18:54:16.938792 bash[1924]: Updated "/home/core/.ssh/authorized_keys" Jan 23 18:54:16.939570 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Jan 23 18:54:16.946352 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Jan 23 18:54:17.037528 dbus-daemon[1868]: [system] SELinux support is enabled Jan 23 18:54:17.037732 systemd[1]: Started dbus.service - D-Bus System Message Bus. Jan 23 18:54:17.042306 update_engine[1887]: I20260123 18:54:17.040468 1887 update_check_scheduler.cc:74] Next update check in 4m1s Jan 23 18:54:17.044686 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 18:54:17.044719 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Jan 23 18:54:17.045536 dbus-daemon[1868]: [system] Successfully activated service 'org.freedesktop.systemd1' Jan 23 18:54:17.050238 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 18:54:17.050258 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Jan 23 18:54:17.055524 systemd[1]: Started update-engine.service - Update Engine. Jan 23 18:54:17.061723 systemd[1]: Started locksmithd.service - Cluster reboot manager. Jan 23 18:54:17.076432 setup-nvidia[1881]: Downloading Flatcar Container Linux Developer Container for version: 4459.2.3 Jan 23 18:54:17.086810 coreos-metadata[1867]: Jan 23 18:54:17.086 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Jan 23 18:54:17.088785 coreos-metadata[1867]: Jan 23 18:54:17.088 INFO Fetch successful Jan 23 18:54:17.088937 coreos-metadata[1867]: Jan 23 18:54:17.088 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Jan 23 18:54:17.092405 coreos-metadata[1867]: Jan 23 18:54:17.092 INFO Fetch successful Jan 23 18:54:17.092646 coreos-metadata[1867]: Jan 23 18:54:17.092 INFO Fetching http://168.63.129.16/machine/9d56efa1-946d-45bd-a442-8fc6cb96b34c/6545eb8c%2D80c2%2D4e4b%2D987a%2D3a17c4e1cdee.%5Fci%2D4459.2.3%2Da%2Dcafc3b76e8?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Jan 23 18:54:17.124785 coreos-metadata[1867]: Jan 23 18:54:17.124 INFO Fetch successful Jan 23 18:54:17.124942 coreos-metadata[1867]: Jan 23 18:54:17.124 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Jan 23 18:54:17.130908 coreos-metadata[1867]: Jan 23 18:54:17.130 INFO Fetch successful Jan 23 18:54:17.175415 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Jan 23 18:54:17.180668 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Jan 23 18:54:17.271505 sshd_keygen[1891]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Jan 23 18:54:17.292748 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Jan 23 18:54:17.298906 systemd[1]: Starting issuegen.service - Generate /run/issue... Jan 23 18:54:17.320354 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Jan 23 18:54:17.326351 systemd[1]: issuegen.service: Deactivated successfully. Jan 23 18:54:17.326568 systemd[1]: Finished issuegen.service - Generate /run/issue. Jan 23 18:54:17.333669 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Jan 23 18:54:17.353633 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Jan 23 18:54:17.359502 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Jan 23 18:54:17.374166 systemd[1]: Started getty@tty1.service - Getty on tty1. Jan 23 18:54:17.379338 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Jan 23 18:54:17.384296 systemd[1]: Reached target getty.target - Login Prompts. Jan 23 18:54:17.447084 locksmithd[1998]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Jan 23 18:54:17.671990 setup-nvidia[2037]: % Total % Received % Xferd Average Speed Time Time Time Current Jan 23 18:54:17.672374 setup-nvidia[2037]: Dload Upload Total Spent Left Speed Jan 23 18:54:18.461181 containerd[1904]: time="2026-01-23T18:54:18Z" 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 18:54:18.461888 containerd[1904]: time="2026-01-23T18:54:18.461851600Z" level=info msg="starting containerd" revision=4ac6c20c7bbf8177f29e46bbdc658fec02ffb8ad version=v2.0.7 Jan 23 18:54:18.471058 containerd[1904]: time="2026-01-23T18:54:18.471021000Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="8.4µs" Jan 23 18:54:18.471058 containerd[1904]: time="2026-01-23T18:54:18.471048800Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Jan 23 18:54:18.471153 containerd[1904]: time="2026-01-23T18:54:18.471066900Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Jan 23 18:54:18.471295 containerd[1904]: time="2026-01-23T18:54:18.471217200Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Jan 23 18:54:18.471295 containerd[1904]: time="2026-01-23T18:54:18.471275200Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Jan 23 18:54:18.471353 containerd[1904]: time="2026-01-23T18:54:18.471307300Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jan 23 18:54:18.471394 containerd[1904]: time="2026-01-23T18:54:18.471370900Z" 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 18:54:18.471428 containerd[1904]: time="2026-01-23T18:54:18.471398100Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Jan 23 18:54:18.471666 containerd[1904]: time="2026-01-23T18:54:18.471641000Z" 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 18:54:18.471666 containerd[1904]: time="2026-01-23T18:54:18.471662100Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jan 23 18:54:18.471716 containerd[1904]: time="2026-01-23T18:54:18.471674400Z" 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 18:54:18.471716 containerd[1904]: time="2026-01-23T18:54:18.471682300Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Jan 23 18:54:18.471780 containerd[1904]: time="2026-01-23T18:54:18.471761100Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Jan 23 18:54:18.472040 containerd[1904]: time="2026-01-23T18:54:18.472018200Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Jan 23 18:54:18.472078 containerd[1904]: time="2026-01-23T18:54:18.472058000Z" 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 18:54:18.472104 containerd[1904]: time="2026-01-23T18:54:18.472081600Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Jan 23 18:54:18.472136 containerd[1904]: time="2026-01-23T18:54:18.472112400Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Jan 23 18:54:18.472578 containerd[1904]: time="2026-01-23T18:54:18.472552900Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Jan 23 18:54:18.472643 containerd[1904]: time="2026-01-23T18:54:18.472624100Z" level=info msg="metadata content store policy set" policy=shared Jan 23 18:54:18.492801 containerd[1904]: time="2026-01-23T18:54:18.492765000Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Jan 23 18:54:18.492879 containerd[1904]: time="2026-01-23T18:54:18.492831200Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Jan 23 18:54:18.492879 containerd[1904]: time="2026-01-23T18:54:18.492849800Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Jan 23 18:54:18.492879 containerd[1904]: time="2026-01-23T18:54:18.492861800Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Jan 23 18:54:18.492879 containerd[1904]: time="2026-01-23T18:54:18.492873900Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Jan 23 18:54:18.492975 containerd[1904]: time="2026-01-23T18:54:18.492885200Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Jan 23 18:54:18.492975 containerd[1904]: time="2026-01-23T18:54:18.492896100Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Jan 23 18:54:18.492975 containerd[1904]: time="2026-01-23T18:54:18.492907000Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Jan 23 18:54:18.492975 containerd[1904]: time="2026-01-23T18:54:18.492918700Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Jan 23 18:54:18.492975 containerd[1904]: time="2026-01-23T18:54:18.492929200Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Jan 23 18:54:18.492975 containerd[1904]: time="2026-01-23T18:54:18.492938900Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Jan 23 18:54:18.492975 containerd[1904]: time="2026-01-23T18:54:18.492950500Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Jan 23 18:54:18.493134 containerd[1904]: time="2026-01-23T18:54:18.493064000Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Jan 23 18:54:18.493134 containerd[1904]: time="2026-01-23T18:54:18.493106600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Jan 23 18:54:18.493134 containerd[1904]: time="2026-01-23T18:54:18.493121600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Jan 23 18:54:18.493134 containerd[1904]: time="2026-01-23T18:54:18.493131700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Jan 23 18:54:18.493213 containerd[1904]: time="2026-01-23T18:54:18.493141800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Jan 23 18:54:18.493213 containerd[1904]: time="2026-01-23T18:54:18.493151500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Jan 23 18:54:18.493213 containerd[1904]: time="2026-01-23T18:54:18.493161900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Jan 23 18:54:18.493213 containerd[1904]: time="2026-01-23T18:54:18.493172600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Jan 23 18:54:18.493213 containerd[1904]: time="2026-01-23T18:54:18.493184800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Jan 23 18:54:18.493213 containerd[1904]: time="2026-01-23T18:54:18.493208200Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Jan 23 18:54:18.493344 containerd[1904]: time="2026-01-23T18:54:18.493222400Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Jan 23 18:54:18.493344 containerd[1904]: time="2026-01-23T18:54:18.493287100Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Jan 23 18:54:18.493344 containerd[1904]: time="2026-01-23T18:54:18.493302600Z" level=info msg="Start snapshots syncer" Jan 23 18:54:18.493344 containerd[1904]: time="2026-01-23T18:54:18.493331000Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Jan 23 18:54:18.493674 containerd[1904]: time="2026-01-23T18:54:18.493623400Z" 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 18:54:18.493790 containerd[1904]: time="2026-01-23T18:54:18.493685300Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Jan 23 18:54:18.493790 containerd[1904]: time="2026-01-23T18:54:18.493723700Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Jan 23 18:54:18.493844 containerd[1904]: time="2026-01-23T18:54:18.493825800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Jan 23 18:54:18.493869 containerd[1904]: time="2026-01-23T18:54:18.493846600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Jan 23 18:54:18.493869 containerd[1904]: time="2026-01-23T18:54:18.493857200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Jan 23 18:54:18.493910 containerd[1904]: time="2026-01-23T18:54:18.493887100Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Jan 23 18:54:18.493910 containerd[1904]: time="2026-01-23T18:54:18.493902000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Jan 23 18:54:18.493950 containerd[1904]: time="2026-01-23T18:54:18.493922700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Jan 23 18:54:18.493950 containerd[1904]: time="2026-01-23T18:54:18.493935600Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Jan 23 18:54:18.493998 containerd[1904]: time="2026-01-23T18:54:18.493956900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Jan 23 18:54:18.493998 containerd[1904]: time="2026-01-23T18:54:18.493967800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Jan 23 18:54:18.493998 containerd[1904]: time="2026-01-23T18:54:18.493978300Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Jan 23 18:54:18.494060 containerd[1904]: time="2026-01-23T18:54:18.494021800Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Jan 23 18:54:18.494060 containerd[1904]: time="2026-01-23T18:54:18.494051600Z" 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 18:54:18.494122 containerd[1904]: time="2026-01-23T18:54:18.494061800Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jan 23 18:54:18.494122 containerd[1904]: time="2026-01-23T18:54:18.494084800Z" 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 18:54:18.494122 containerd[1904]: time="2026-01-23T18:54:18.494093600Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Jan 23 18:54:18.494122 containerd[1904]: time="2026-01-23T18:54:18.494116700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Jan 23 18:54:18.494204 containerd[1904]: time="2026-01-23T18:54:18.494136400Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Jan 23 18:54:18.494204 containerd[1904]: time="2026-01-23T18:54:18.494153200Z" level=info msg="runtime interface created" Jan 23 18:54:18.494204 containerd[1904]: time="2026-01-23T18:54:18.494158700Z" level=info msg="created NRI interface" Jan 23 18:54:18.494204 containerd[1904]: time="2026-01-23T18:54:18.494166200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Jan 23 18:54:18.494204 containerd[1904]: time="2026-01-23T18:54:18.494176500Z" level=info msg="Connect containerd service" Jan 23 18:54:18.494204 containerd[1904]: time="2026-01-23T18:54:18.494195200Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Jan 23 18:54:18.495032 containerd[1904]: time="2026-01-23T18:54:18.495004500Z" 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 18:54:19.343920 containerd[1904]: time="2026-01-23T18:54:19.343867300Z" level=info msg="Start subscribing containerd event" Jan 23 18:54:19.344054 containerd[1904]: time="2026-01-23T18:54:19.343920500Z" level=info msg="Start recovering state" Jan 23 18:54:19.344149 containerd[1904]: time="2026-01-23T18:54:19.344085200Z" level=info msg="Start event monitor" Jan 23 18:54:19.344149 containerd[1904]: time="2026-01-23T18:54:19.344108400Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jan 23 18:54:19.344198 containerd[1904]: time="2026-01-23T18:54:19.344168100Z" level=info msg=serving... address=/run/containerd/containerd.sock Jan 23 18:54:19.344198 containerd[1904]: time="2026-01-23T18:54:19.344108600Z" level=info msg="Start cni network conf syncer for default" Jan 23 18:54:19.344242 containerd[1904]: time="2026-01-23T18:54:19.344204900Z" level=info msg="Start streaming server" Jan 23 18:54:19.344242 containerd[1904]: time="2026-01-23T18:54:19.344214000Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Jan 23 18:54:19.344242 containerd[1904]: time="2026-01-23T18:54:19.344221500Z" level=info msg="runtime interface starting up..." Jan 23 18:54:19.344242 containerd[1904]: time="2026-01-23T18:54:19.344227000Z" level=info msg="starting plugins..." Jan 23 18:54:19.344325 containerd[1904]: time="2026-01-23T18:54:19.344244600Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Jan 23 18:54:19.344426 containerd[1904]: time="2026-01-23T18:54:19.344400000Z" level=info msg="containerd successfully booted in 0.883664s" Jan 23 18:54:19.344508 systemd[1]: Started containerd.service - containerd container runtime. Jan 23 18:54:19.956505 waagent[2029]: 2026-01-23T18:54:19.956425Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Jan 23 18:54:19.960967 waagent[2029]: 2026-01-23T18:54:19.960917Z INFO Daemon Daemon OS: flatcar 4459.2.3 Jan 23 18:54:19.964543 waagent[2029]: 2026-01-23T18:54:19.964495Z INFO Daemon Daemon Python: 3.11.13 Jan 23 18:54:19.968096 waagent[2029]: 2026-01-23T18:54:19.968012Z INFO Daemon Daemon Run daemon Jan 23 18:54:19.971284 waagent[2029]: 2026-01-23T18:54:19.971234Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4459.2.3' Jan 23 18:54:19.979914 waagent[2029]: 2026-01-23T18:54:19.979861Z INFO Daemon Daemon Using waagent for provisioning Jan 23 18:54:19.986341 waagent[2029]: 2026-01-23T18:54:19.986290Z INFO Daemon Daemon Activate resource disk Jan 23 18:54:19.990160 waagent[2029]: 2026-01-23T18:54:19.990111Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Jan 23 18:54:19.997862 waagent[2029]: 2026-01-23T18:54:19.997809Z INFO Daemon Daemon Found device: sda Jan 23 18:54:20.007289 waagent[2029]: 2026-01-23T18:54:20.007246Z INFO Daemon Daemon Examining partition table Jan 23 18:54:20.040113 waagent[2029]: 2026-01-23T18:54:20.040046Z INFO Daemon Daemon GPT not detected, determining filesystem Jan 23 18:54:20.069103 waagent[2029]: 2026-01-23T18:54:20.069040Z INFO Daemon Daemon sfdisk --part-type -f /dev/sda 1 -n succeeded Jan 23 18:54:20.074175 waagent[2029]: 2026-01-23T18:54:20.074085Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Jan 23 18:54:20.946793 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#492 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jan 23 18:54:20.961556 kernel: sda: sda1 Jan 23 18:54:21.181720 waagent[2029]: 2026-01-23T18:54:21.181618Z INFO Daemon Daemon sfdisk --part-type /dev/sda 1 83 succeeded Jan 23 18:54:21.203055 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#307 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jan 23 18:54:21.219105 kernel: sda: sda1 Jan 23 18:54:21.219322 waagent[2029]: 2026-01-23T18:54:21.219270Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sda1] Jan 23 18:54:25.809773 login[2032]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Jan 23 18:54:25.907465 login[2033]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Jan 23 18:54:25.913886 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Jan 23 18:54:25.914932 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Jan 23 18:54:25.921054 systemd-logind[1885]: New session 1 of user core. Jan 23 18:54:26.040581 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Jan 23 18:54:26.043259 systemd[1]: Starting user@500.service - User Manager for UID 500... Jan 23 18:54:26.079521 (systemd)[2131]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Jan 23 18:54:26.081668 systemd-logind[1885]: New session c1 of user core. Jan 23 18:54:26.799944 waagent[2029]: 2026-01-23T18:54:26.799860Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sda1 /mnt/resource] Jan 23 18:54:26.810799 login[2032]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Jan 23 18:54:26.820304 systemd-logind[1885]: New session 2 of user core. Jan 23 18:54:26.865316 systemd[2131]: Queued start job for default target default.target. Jan 23 18:54:26.887269 systemd[2131]: Created slice app.slice - User Application Slice. Jan 23 18:54:26.887310 systemd[2131]: Reached target paths.target - Paths. Jan 23 18:54:26.887356 systemd[2131]: Reached target timers.target - Timers. Jan 23 18:54:26.888748 systemd[2131]: Starting dbus.socket - D-Bus User Message Bus Socket... Jan 23 18:54:26.900610 systemd[2131]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jan 23 18:54:26.900729 systemd[2131]: Reached target sockets.target - Sockets. Jan 23 18:54:26.900782 systemd[2131]: Reached target basic.target - Basic System. Jan 23 18:54:26.900825 systemd[2131]: Reached target default.target - Main User Target. Jan 23 18:54:26.900857 systemd[2131]: Startup finished in 812ms. Jan 23 18:54:26.900896 systemd[1]: Started user@500.service - User Manager for UID 500. Jan 23 18:54:26.902215 systemd[1]: Started session-1.scope - Session 1 of User core. Jan 23 18:54:26.902941 systemd[1]: Started session-2.scope - Session 2 of User core. Jan 23 18:54:26.911617 waagent[2029]: 2026-01-23T18:54:26.911546Z INFO Daemon Daemon Resource disk /dev/sda is mounted at /mnt/resource with ext4 Jan 23 18:54:26.912090 kernel: EXT4-fs (sda1): mounted filesystem 6e442995-1853-4a4a-afe8-f2d586780efd r/w with ordered data mode. Quota mode: none. Jan 23 18:54:26.912381 waagent[2029]: 2026-01-23T18:54:26.912340Z INFO Daemon Daemon Clean protocol and wireserver endpoint Jan 23 18:54:26.912646 waagent[2029]: 2026-01-23T18:54:26.912612Z INFO Daemon Daemon Running default provisioning handler Jan 23 18:54:26.935467 waagent[2029]: 2026-01-23T18:54:26.935412Z 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 18:54:26.936037 waagent[2029]: 2026-01-23T18:54:26.935996Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Jan 23 18:54:26.936412 waagent[2029]: 2026-01-23T18:54:26.936378Z INFO Daemon Daemon cloud-init is enabled: False Jan 23 18:54:26.937863 waagent[2029]: 2026-01-23T18:54:26.937828Z INFO Daemon Daemon Copying ovf-env.xml Jan 23 18:54:27.049103 waagent[2029]: 2026-01-23T18:54:27.046032Z INFO Daemon Daemon Successfully mounted dvd Jan 23 18:54:27.091163 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Jan 23 18:54:27.091681 waagent[2029]: 2026-01-23T18:54:27.091626Z INFO Daemon Daemon Detect protocol endpoint Jan 23 18:54:27.091903 waagent[2029]: 2026-01-23T18:54:27.091862Z INFO Daemon Daemon Clean protocol and wireserver endpoint Jan 23 18:54:27.093445 waagent[2029]: 2026-01-23T18:54:27.093410Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Jan 23 18:54:27.094184 waagent[2029]: 2026-01-23T18:54:27.094153Z INFO Daemon Daemon Test for route to 168.63.129.16 Jan 23 18:54:27.095061 waagent[2029]: 2026-01-23T18:54:27.095026Z INFO Daemon Daemon Route to 168.63.129.16 exists Jan 23 18:54:27.095688 waagent[2029]: 2026-01-23T18:54:27.095656Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Jan 23 18:54:27.118316 waagent[2029]: 2026-01-23T18:54:27.118275Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Jan 23 18:54:27.118639 waagent[2029]: 2026-01-23T18:54:27.118613Z INFO Daemon Daemon Wire protocol version:2012-11-30 Jan 23 18:54:27.120039 waagent[2029]: 2026-01-23T18:54:27.120008Z INFO Daemon Daemon Server preferred version:2015-04-05 Jan 23 18:54:27.237088 waagent[2029]: 2026-01-23T18:54:27.236987Z INFO Daemon Daemon Initializing goal state during protocol detection Jan 23 18:54:27.237354 waagent[2029]: 2026-01-23T18:54:27.237311Z INFO Daemon Daemon Forcing an update of the goal state. Jan 23 18:54:27.241563 waagent[2029]: 2026-01-23T18:54:27.241522Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Jan 23 18:54:27.264998 waagent[2029]: 2026-01-23T18:54:27.264955Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.179 Jan 23 18:54:27.265606 waagent[2029]: 2026-01-23T18:54:27.265567Z INFO Daemon Jan 23 18:54:27.265990 waagent[2029]: 2026-01-23T18:54:27.265956Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 68bc5e2f-00f6-41b4-ba19-d057528bd135 eTag: 10969999894023915741 source: Fabric] Jan 23 18:54:27.267815 waagent[2029]: 2026-01-23T18:54:27.267767Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Jan 23 18:54:27.269344 waagent[2029]: 2026-01-23T18:54:27.269304Z INFO Daemon Jan 23 18:54:27.269839 waagent[2029]: 2026-01-23T18:54:27.269807Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Jan 23 18:54:27.274227 waagent[2029]: 2026-01-23T18:54:27.274189Z INFO Daemon Daemon Downloading artifacts profile blob Jan 23 18:54:27.409761 waagent[2029]: 2026-01-23T18:54:27.409711Z INFO Daemon Downloaded certificate {'thumbprint': 'FBAF7726AF4D9267AD9B460C6A81C7FE7EE0A159', 'hasPrivateKey': True} Jan 23 18:54:27.410351 waagent[2029]: 2026-01-23T18:54:27.410309Z INFO Daemon Fetch goal state completed Jan 23 18:54:27.447405 waagent[2029]: 2026-01-23T18:54:27.447354Z INFO Daemon Daemon Starting provisioning Jan 23 18:54:27.447592 waagent[2029]: 2026-01-23T18:54:27.447549Z INFO Daemon Daemon Handle ovf-env.xml. Jan 23 18:54:27.449155 waagent[2029]: 2026-01-23T18:54:27.449045Z INFO Daemon Daemon Set hostname [ci-4459.2.3-a-cafc3b76e8] Jan 23 18:54:27.459617 waagent[2029]: 2026-01-23T18:54:27.459562Z INFO Daemon Daemon Publish hostname [ci-4459.2.3-a-cafc3b76e8] Jan 23 18:54:27.459973 waagent[2029]: 2026-01-23T18:54:27.459931Z INFO Daemon Daemon Examine /proc/net/route for primary interface Jan 23 18:54:27.461546 waagent[2029]: 2026-01-23T18:54:27.461501Z INFO Daemon Daemon Primary interface is [eth0] Jan 23 18:54:27.471140 systemd-networkd[1556]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jan 23 18:54:27.471151 systemd-networkd[1556]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jan 23 18:54:27.471183 systemd-networkd[1556]: eth0: DHCP lease lost Jan 23 18:54:27.471644 waagent[2029]: 2026-01-23T18:54:27.471595Z INFO Daemon Daemon Create user account if not exists Jan 23 18:54:27.472633 waagent[2029]: 2026-01-23T18:54:27.472588Z INFO Daemon Daemon User core already exists, skip useradd Jan 23 18:54:27.474167 waagent[2029]: 2026-01-23T18:54:27.474123Z INFO Daemon Daemon Configure sudoer Jan 23 18:54:27.478570 waagent[2029]: 2026-01-23T18:54:27.478527Z INFO Daemon Daemon Configure sshd Jan 23 18:54:27.481290 waagent[2029]: 2026-01-23T18:54:27.481253Z 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 18:54:27.481885 waagent[2029]: 2026-01-23T18:54:27.481852Z INFO Daemon Daemon Deploy ssh public key. Jan 23 18:54:27.544108 systemd-networkd[1556]: eth0: DHCPv4 address 10.200.4.28/24, gateway 10.200.4.1 acquired from 168.63.129.16 Jan 23 18:54:28.107375 setup-nvidia[2037]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 2 470M 2 13.9M 0 0 23.7M 0 0:00:19 --:--:-- 0:00:19 23.7M\u000d 10 470M 10 50.8M 0 0 31.8M 0 0:00:14 0:00:01 0:00:13 31.8M\u000d 18 470M 18 87.8M 0 0 33.7M 0 0:00:13 0:00:02 0:00:11 33.7M\u000d 28 470M 28 134M 0 0 37.3M 0 0:00:12 0:00:03 0:00:09 37.3M\u000d 34 470M 34 163M 0 0 35.5M 0 0:00:13 0:00:04 0:00:09 35.5M\u000d 43 470M 43 203M 0 0 36.2M 0 0:00:12 0:00:05 0:00:07 37.7M\u000d 51 470M 51 242M 0 0 36.8M 0 0:00:12 0:00:06 0:00:06 38.3M\u000d 59 470M 59 279M 0 0 36.8M 0 0:00:12 0:00:07 0:00:05 38.3M\u000d 64 470M 64 302M 0 0 34.5M 0 0:00:13 0:00:08 0:00:05 32.5M\u000d 91 470M 91 431M 0 0 44.8M 0 0:00:10 0:00:09 0:00:01 53.3M\u000d100 470M 100 470M 0 0 45.1M 0 0:00:10 0:00:10 --:--:-- 55.4M Jan 23 18:54:39.027407 setup-nvidia[1881]: Downloading NVIDIA 535.261.03 Driver Jan 23 18:54:39.031580 setup-nvidia[2200]: % Total % Received % Xferd Average Speed Time Time Time Current Jan 23 18:54:39.031808 setup-nvidia[2200]: Dload Upload Total Spent Left Speed Jan 23 18:54:40.586333 chronyd[1865]: Selected source PHC0 Jan 23 18:54:43.807361 setup-nvidia[2200]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 8 325M 8 28.8M 0 0 126M 0 0:00:02 --:--:-- 0:00:02 126M\u000d 32 325M 32 105M 0 0 85.9M 0 0:00:03 0:00:01 0:00:02 85.9M\u000d 50 325M 50 164M 0 0 73.7M 0 0:00:04 0:00:02 0:00:02 73.7M\u000d 68 325M 68 224M 0 0 69.2M 0 0:00:04 0:00:03 0:00:01 69.2M\u000d 88 325M 88 288M 0 0 68.0M 0 0:00:04 0:00:04 --:--:-- 68.0M\u000d100 325M 100 325M 0 0 68.2M 0 0:00:04 0:00:04 --:--:-- 65.3M Jan 23 18:54:43.870414 setup-nvidia[1881]: Extract the NVIDIA Driver Installer 535.261.03 Jan 23 18:54:43.870652 setup-nvidia[1881]: /opt/nvidia/workdir/nvidia-workdir / Jan 23 18:54:43.888521 setup-nvidia[2203]: Creating directory NVIDIA-Linux-x86_64-535.261.03 Jan 23 18:54:44.158186 setup-nvidia[2203]: Verifying archive integrity... OK Jan 23 18:54:44.881178 setup-nvidia[2203]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.261.03 Jan 23 18:54:46.736800 setup-nvidia[2229]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Jan 23 18:54:46.833932 setup-nvidia[1881]: / Jan 23 18:54:46.834225 setup-nvidia[1881]: Spawn system-nspawn container to install the NVIDIA drivers Jan 23 18:54:47.107479 sudo[2236]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4459.2.3.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 18:54:47.109227 sudo[2236]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 23 18:54:47.114820 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Jan 23 18:54:47.115790 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Jan 23 18:54:47.136924 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Jan 23 18:54:47.139047 systemd[1]: Starting user@0.service - User Manager for UID 0... Jan 23 18:54:47.166017 (systemd)[2239]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Jan 23 18:54:47.168133 systemd-logind[1885]: New session c3 of user root. Jan 23 18:54:47.354003 systemd[2239]: Queued start job for default target default.target. Jan 23 18:54:47.372249 systemd[2239]: Created slice app.slice - User Application Slice. Jan 23 18:54:47.372278 systemd[2239]: Reached target paths.target - Paths. Jan 23 18:54:47.372325 systemd[2239]: Reached target timers.target - Timers. Jan 23 18:54:47.373509 systemd[2239]: Starting dbus.socket - D-Bus User Message Bus Socket... Jan 23 18:54:47.384358 systemd[2239]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jan 23 18:54:47.384474 systemd[2239]: Reached target sockets.target - Sockets. Jan 23 18:54:47.384529 systemd[2239]: Reached target basic.target - Basic System. Jan 23 18:54:47.384575 systemd[2239]: Reached target default.target - Main User Target. Jan 23 18:54:47.384613 systemd[2239]: Startup finished in 210ms. Jan 23 18:54:47.384630 systemd[1]: Started user@0.service - User Manager for UID 0. Jan 23 18:54:47.385954 systemd[1]: Started session-c2.scope - Session c2 of User root. Jan 23 18:54:47.511815 kernel: loop6: detected capacity change from 0 to 12644352 Jan 23 18:54:47.634096 kernel: loop6: p9 Jan 23 18:54:48.017633 setup-nvidia[2248]: ░ Spawning container flatcardevelopercontainer-4459.2.3.bin on /opt/nvidia/workdir/flatcar_developer_container-4459.2.3.bin. Jan 23 18:54:49.785371 kernel: EXT4-fs (loop6p9): write access unavailable, skipping orphan cleanup Jan 23 18:54:49.785502 kernel: EXT4-fs (loop6p9): mounted filesystem 94bea6f2-4f04-4451-9ae2-f9d206317999 ro without journal. Quota mode: none. Jan 23 18:54:56.921007 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Jan 23 18:54:57.571647 waagent[2029]: 2026-01-23T18:54:57.571577Z INFO Daemon Daemon Provisioning complete Jan 23 18:54:57.579235 waagent[2029]: 2026-01-23T18:54:57.579192Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Jan 23 18:54:57.579481 waagent[2029]: 2026-01-23T18:54:57.579442Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Jan 23 18:54:57.580995 waagent[2029]: 2026-01-23T18:54:57.580960Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Jan 23 18:54:57.761798 waagent[2311]: 2026-01-23T18:54:57.761710Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Jan 23 18:54:57.762179 waagent[2311]: 2026-01-23T18:54:57.761857Z INFO ExtHandler ExtHandler OS: flatcar 4459.2.3 Jan 23 18:54:57.762179 waagent[2311]: 2026-01-23T18:54:57.761931Z INFO ExtHandler ExtHandler Python: 3.11.13 Jan 23 18:54:57.762179 waagent[2311]: 2026-01-23T18:54:57.762003Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Jan 23 18:54:57.865144 waagent[2311]: 2026-01-23T18:54:57.864995Z INFO ExtHandler ExtHandler Distro: flatcar-4459.2.3; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.13; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Jan 23 18:54:57.865319 waagent[2311]: 2026-01-23T18:54:57.865278Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jan 23 18:54:57.865406 waagent[2311]: 2026-01-23T18:54:57.865370Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Jan 23 18:54:57.871947 waagent[2311]: 2026-01-23T18:54:57.871898Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Jan 23 18:54:57.880750 waagent[2311]: 2026-01-23T18:54:57.880703Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.179 Jan 23 18:54:57.881206 waagent[2311]: 2026-01-23T18:54:57.881167Z INFO ExtHandler Jan 23 18:54:57.881288 waagent[2311]: 2026-01-23T18:54:57.881256Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: b5e4746b-02d0-40a9-9aa5-ea94457c327e eTag: 10969999894023915741 source: Fabric] Jan 23 18:54:57.881603 waagent[2311]: 2026-01-23T18:54:57.881566Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Jan 23 18:54:57.882225 waagent[2311]: 2026-01-23T18:54:57.882187Z INFO ExtHandler Jan 23 18:54:57.882299 waagent[2311]: 2026-01-23T18:54:57.882269Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Jan 23 18:54:57.885031 waagent[2311]: 2026-01-23T18:54:57.884999Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Jan 23 18:54:57.962438 waagent[2311]: 2026-01-23T18:54:57.962365Z INFO ExtHandler Downloaded certificate {'thumbprint': 'FBAF7726AF4D9267AD9B460C6A81C7FE7EE0A159', 'hasPrivateKey': True} Jan 23 18:54:57.962958 waagent[2311]: 2026-01-23T18:54:57.962913Z INFO ExtHandler Fetch goal state completed Jan 23 18:54:57.973098 waagent[2311]: 2026-01-23T18:54:57.973045Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.4.4-dev (Library: OpenSSL 3.4.4-dev ) Jan 23 18:54:57.978353 waagent[2311]: 2026-01-23T18:54:57.978310Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2311 Jan 23 18:54:57.978489 waagent[2311]: 2026-01-23T18:54:57.978455Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Jan 23 18:54:57.978821 waagent[2311]: 2026-01-23T18:54:57.978785Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Jan 23 18:54:57.980419 waagent[2311]: 2026-01-23T18:54:57.980378Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4459.2.3', '', 'Flatcar Container Linux by Kinvolk'] Jan 23 18:54:57.980846 waagent[2311]: 2026-01-23T18:54:57.980807Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4459.2.3', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Jan 23 18:54:57.980990 waagent[2311]: 2026-01-23T18:54:57.980955Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Jan 23 18:54:57.981616 waagent[2311]: 2026-01-23T18:54:57.981578Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Jan 23 18:54:58.070672 waagent[2311]: 2026-01-23T18:54:58.070618Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Jan 23 18:54:58.070850 waagent[2311]: 2026-01-23T18:54:58.070813Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Jan 23 18:54:58.077286 waagent[2311]: 2026-01-23T18:54:58.077258Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Jan 23 18:54:58.083669 systemd[1]: Reload requested from client PID 2326 ('systemctl') (unit waagent.service)... Jan 23 18:54:58.083686 systemd[1]: Reloading... Jan 23 18:54:58.148105 zram_generator::config[2368]: No configuration found. Jan 23 18:54:58.431581 systemd[1]: Reloading finished in 347 ms. Jan 23 18:54:58.454716 waagent[2311]: 2026-01-23T18:54:58.454653Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Jan 23 18:54:58.454827 waagent[2311]: 2026-01-23T18:54:58.454793Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Jan 23 18:54:59.054631 waagent[2311]: 2026-01-23T18:54:59.054555Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Jan 23 18:54:59.054980 waagent[2311]: 2026-01-23T18:54:59.054904Z 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 18:54:59.055735 waagent[2311]: 2026-01-23T18:54:59.055687Z INFO ExtHandler ExtHandler Starting env monitor service. Jan 23 18:54:59.056123 waagent[2311]: 2026-01-23T18:54:59.056056Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jan 23 18:54:59.056301 waagent[2311]: 2026-01-23T18:54:59.056259Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Jan 23 18:54:59.056553 waagent[2311]: 2026-01-23T18:54:59.056515Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Jan 23 18:54:59.056796 waagent[2311]: 2026-01-23T18:54:59.056737Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Jan 23 18:54:59.056881 waagent[2311]: 2026-01-23T18:54:59.056840Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Jan 23 18:54:59.056986 waagent[2311]: 2026-01-23T18:54:59.056943Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jan 23 18:54:59.057415 waagent[2311]: 2026-01-23T18:54:59.057370Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Jan 23 18:54:59.057664 waagent[2311]: 2026-01-23T18:54:59.057625Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Jan 23 18:54:59.057976 waagent[2311]: 2026-01-23T18:54:59.057925Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Jan 23 18:54:59.058245 waagent[2311]: 2026-01-23T18:54:59.058203Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Jan 23 18:54:59.058613 waagent[2311]: 2026-01-23T18:54:59.058561Z INFO EnvHandler ExtHandler Configure routes Jan 23 18:54:59.058653 waagent[2311]: 2026-01-23T18:54:59.058614Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Jan 23 18:54:59.058653 waagent[2311]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Jan 23 18:54:59.058653 waagent[2311]: eth0 00000000 0104C80A 0003 0 0 1024 00000000 0 0 0 Jan 23 18:54:59.058653 waagent[2311]: eth0 0004C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Jan 23 18:54:59.058653 waagent[2311]: eth0 0104C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Jan 23 18:54:59.058653 waagent[2311]: eth0 10813FA8 0104C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jan 23 18:54:59.058653 waagent[2311]: eth0 FEA9FEA9 0104C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jan 23 18:54:59.058808 waagent[2311]: 2026-01-23T18:54:59.058758Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Jan 23 18:54:59.059649 waagent[2311]: 2026-01-23T18:54:59.059606Z INFO EnvHandler ExtHandler Gateway:None Jan 23 18:54:59.060373 waagent[2311]: 2026-01-23T18:54:59.060283Z INFO EnvHandler ExtHandler Routes:None Jan 23 18:54:59.064611 waagent[2311]: 2026-01-23T18:54:59.064562Z INFO ExtHandler ExtHandler Jan 23 18:54:59.064882 waagent[2311]: 2026-01-23T18:54:59.064846Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: edeadf20-34de-4acf-903c-a45fdef7bd2b correlation 0f34e0bf-f12a-4742-aea5-f5f0ad6553d0 created: 2026-01-23T18:52:54.341156Z] Jan 23 18:54:59.065817 waagent[2311]: 2026-01-23T18:54:59.065773Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Jan 23 18:54:59.067913 waagent[2311]: 2026-01-23T18:54:59.067867Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Jan 23 18:54:59.089647 waagent[2311]: 2026-01-23T18:54:59.089600Z 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 18:54:59.089647 waagent[2311]: Try `iptables -h' or 'iptables --help' for more information.) Jan 23 18:54:59.090000 waagent[2311]: 2026-01-23T18:54:59.089961Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 6A25D5CF-EC86-4E6F-8C71-1C8C28821B68;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Jan 23 18:54:59.220125 waagent[2311]: 2026-01-23T18:54:59.220036Z INFO MonitorHandler ExtHandler Network interfaces: Jan 23 18:54:59.220125 waagent[2311]: Executing ['ip', '-a', '-o', 'link']: Jan 23 18:54:59.220125 waagent[2311]: 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 18:54:59.220125 waagent[2311]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 60:45:bd:12:d5:35 brd ff:ff:ff:ff:ff:ff Jan 23 18:54:59.220125 waagent[2311]: Executing ['ip', '-4', '-a', '-o', 'address']: Jan 23 18:54:59.220125 waagent[2311]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Jan 23 18:54:59.220125 waagent[2311]: 2: eth0 inet 10.200.4.28/24 metric 1024 brd 10.200.4.255 scope global eth0\ valid_lft forever preferred_lft forever Jan 23 18:54:59.220125 waagent[2311]: Executing ['ip', '-6', '-a', '-o', 'address']: Jan 23 18:54:59.220125 waagent[2311]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Jan 23 18:54:59.220125 waagent[2311]: 2: eth0 inet6 fe80::6245:bdff:fe12:d535/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Jan 23 18:54:59.285862 waagent[2311]: 2026-01-23T18:54:59.285802Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Jan 23 18:54:59.285862 waagent[2311]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Jan 23 18:54:59.285862 waagent[2311]: pkts bytes target prot opt in out source destination Jan 23 18:54:59.285862 waagent[2311]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Jan 23 18:54:59.285862 waagent[2311]: pkts bytes target prot opt in out source destination Jan 23 18:54:59.285862 waagent[2311]: Chain OUTPUT (policy ACCEPT 8 packets, 817 bytes) Jan 23 18:54:59.285862 waagent[2311]: pkts bytes target prot opt in out source destination Jan 23 18:54:59.285862 waagent[2311]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Jan 23 18:54:59.285862 waagent[2311]: 9 995 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Jan 23 18:54:59.285862 waagent[2311]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Jan 23 18:54:59.289268 waagent[2311]: 2026-01-23T18:54:59.289227Z INFO EnvHandler ExtHandler Current Firewall rules: Jan 23 18:54:59.289268 waagent[2311]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Jan 23 18:54:59.289268 waagent[2311]: pkts bytes target prot opt in out source destination Jan 23 18:54:59.289268 waagent[2311]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Jan 23 18:54:59.289268 waagent[2311]: pkts bytes target prot opt in out source destination Jan 23 18:54:59.289268 waagent[2311]: Chain OUTPUT (policy ACCEPT 8 packets, 817 bytes) Jan 23 18:54:59.289268 waagent[2311]: pkts bytes target prot opt in out source destination Jan 23 18:54:59.289268 waagent[2311]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Jan 23 18:54:59.289268 waagent[2311]: 9 995 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Jan 23 18:54:59.289268 waagent[2311]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Jan 23 18:54:59.289521 waagent[2311]: 2026-01-23T18:54:59.289488Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Jan 23 18:54:59.289673 waagent[2311]: 2026-01-23T18:54:59.289643Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Jan 23 18:55:02.090230 update_engine[1887]: I20260123 18:55:02.090140 1887 update_attempter.cc:509] Updating boot flags... Jan 23 18:55:11.290305 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Jan 23 18:55:11.296301 systemd[1]: Started sshd@0-10.200.4.28:22-10.200.16.10:41710.service - OpenSSH per-connection server daemon (10.200.16.10:41710). Jan 23 18:55:12.617410 sshd[2634]: Accepted publickey for core from 10.200.16.10 port 41710 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:55:12.618645 sshd-session[2634]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:55:12.622900 systemd-logind[1885]: New session 3 of user core. Jan 23 18:55:12.632213 systemd[1]: Started session-3.scope - Session 3 of User core. Jan 23 18:55:13.167359 systemd[1]: Started sshd@1-10.200.4.28:22-10.200.16.10:41716.service - OpenSSH per-connection server daemon (10.200.16.10:41716). Jan 23 18:55:13.809744 sshd[2682]: Accepted publickey for core from 10.200.16.10 port 41716 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:55:13.810999 sshd-session[2682]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:55:13.815149 systemd-logind[1885]: New session 4 of user core. Jan 23 18:55:13.836210 systemd[1]: Started session-4.scope - Session 4 of User core. Jan 23 18:55:14.256768 sshd[2692]: Connection closed by 10.200.16.10 port 41716 Jan 23 18:55:14.257139 sshd-session[2682]: pam_unix(sshd:session): session closed for user core Jan 23 18:55:14.260668 systemd[1]: sshd@1-10.200.4.28:22-10.200.16.10:41716.service: Deactivated successfully. Jan 23 18:55:14.262378 systemd[1]: session-4.scope: Deactivated successfully. Jan 23 18:55:14.263143 systemd-logind[1885]: Session 4 logged out. Waiting for processes to exit. Jan 23 18:55:14.264165 systemd-logind[1885]: Removed session 4. Jan 23 18:55:14.375640 systemd[1]: Started sshd@2-10.200.4.28:22-10.200.16.10:41728.service - OpenSSH per-connection server daemon (10.200.16.10:41728). Jan 23 18:55:14.991826 sshd[2710]: Accepted publickey for core from 10.200.16.10 port 41728 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:55:14.993045 sshd-session[2710]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:55:14.997576 systemd-logind[1885]: New session 5 of user core. Jan 23 18:55:15.015236 systemd[1]: Started session-5.scope - Session 5 of User core. Jan 23 18:55:15.427038 sshd[2823]: Connection closed by 10.200.16.10 port 41728 Jan 23 18:55:15.427394 sshd-session[2710]: pam_unix(sshd:session): session closed for user core Jan 23 18:55:15.430787 systemd[1]: sshd@2-10.200.4.28:22-10.200.16.10:41728.service: Deactivated successfully. Jan 23 18:55:15.432363 systemd[1]: session-5.scope: Deactivated successfully. Jan 23 18:55:15.433026 systemd-logind[1885]: Session 5 logged out. Waiting for processes to exit. Jan 23 18:55:15.433911 systemd-logind[1885]: Removed session 5. Jan 23 18:55:25.547290 systemd[1]: Started sshd@3-10.200.4.28:22-10.200.16.10:56508.service - OpenSSH per-connection server daemon (10.200.16.10:56508). Jan 23 18:55:26.160952 sshd[3553]: Accepted publickey for core from 10.200.16.10 port 56508 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:55:26.162798 sshd-session[3553]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:55:26.167499 systemd-logind[1885]: New session 6 of user core. Jan 23 18:55:26.186201 systemd[1]: Started session-6.scope - Session 6 of User core. Jan 23 18:55:26.597171 sshd[3556]: Connection closed by 10.200.16.10 port 56508 Jan 23 18:55:26.597492 sshd-session[3553]: pam_unix(sshd:session): session closed for user core Jan 23 18:55:26.600909 systemd[1]: sshd@3-10.200.4.28:22-10.200.16.10:56508.service: Deactivated successfully. Jan 23 18:55:26.602605 systemd[1]: session-6.scope: Deactivated successfully. Jan 23 18:55:26.603358 systemd-logind[1885]: Session 6 logged out. Waiting for processes to exit. Jan 23 18:55:26.604308 systemd-logind[1885]: Removed session 6. Jan 23 18:55:26.724577 systemd[1]: Started sshd@4-10.200.4.28:22-10.200.16.10:56516.service - OpenSSH per-connection server daemon (10.200.16.10:56516). Jan 23 18:55:27.365649 sshd[3562]: Accepted publickey for core from 10.200.16.10 port 56516 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:55:27.366865 sshd-session[3562]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:55:27.371108 systemd-logind[1885]: New session 7 of user core. Jan 23 18:55:27.387210 systemd[1]: Started session-7.scope - Session 7 of User core. Jan 23 18:55:27.802534 sshd[3565]: Connection closed by 10.200.16.10 port 56516 Jan 23 18:55:27.802894 sshd-session[3562]: pam_unix(sshd:session): session closed for user core Jan 23 18:55:27.806289 systemd[1]: sshd@4-10.200.4.28:22-10.200.16.10:56516.service: Deactivated successfully. Jan 23 18:55:27.807822 systemd[1]: session-7.scope: Deactivated successfully. Jan 23 18:55:27.808506 systemd-logind[1885]: Session 7 logged out. Waiting for processes to exit. Jan 23 18:55:27.809555 systemd-logind[1885]: Removed session 7. Jan 23 18:55:37.921317 systemd[1]: Started sshd@5-10.200.4.28:22-10.200.16.10:58694.service - OpenSSH per-connection server daemon (10.200.16.10:58694). Jan 23 18:55:38.557589 sshd[7845]: Accepted publickey for core from 10.200.16.10 port 58694 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:55:38.558853 sshd-session[7845]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:55:38.564339 systemd-logind[1885]: New session 8 of user core. Jan 23 18:55:38.569193 systemd[1]: Started session-8.scope - Session 8 of User core. Jan 23 18:55:38.994746 sshd[8115]: Connection closed by 10.200.16.10 port 58694 Jan 23 18:55:38.995358 sshd-session[7845]: pam_unix(sshd:session): session closed for user core Jan 23 18:55:39.000345 systemd[1]: sshd@5-10.200.4.28:22-10.200.16.10:58694.service: Deactivated successfully. Jan 23 18:55:39.003781 systemd[1]: session-8.scope: Deactivated successfully. Jan 23 18:55:39.003823 systemd-logind[1885]: Session 8 logged out. Waiting for processes to exit. Jan 23 18:55:39.008613 systemd-logind[1885]: Removed session 8. Jan 23 18:55:39.107427 systemd[1]: Started sshd@6-10.200.4.28:22-10.200.16.10:58698.service - OpenSSH per-connection server daemon (10.200.16.10:58698). Jan 23 18:55:39.742235 sshd[8276]: Accepted publickey for core from 10.200.16.10 port 58698 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:55:39.744137 sshd-session[8276]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:55:39.749363 systemd-logind[1885]: New session 9 of user core. Jan 23 18:55:39.758225 systemd[1]: Started session-9.scope - Session 9 of User core. Jan 23 18:55:40.181530 sshd[8537]: Connection closed by 10.200.16.10 port 58698 Jan 23 18:55:40.181874 sshd-session[8276]: pam_unix(sshd:session): session closed for user core Jan 23 18:55:40.186406 systemd-logind[1885]: Session 9 logged out. Waiting for processes to exit. Jan 23 18:55:40.188778 systemd[1]: sshd@6-10.200.4.28:22-10.200.16.10:58698.service: Deactivated successfully. Jan 23 18:55:40.192883 systemd[1]: session-9.scope: Deactivated successfully. Jan 23 18:55:40.196614 systemd-logind[1885]: Removed session 9. Jan 23 18:55:50.296313 systemd[1]: Started sshd@7-10.200.4.28:22-10.200.16.10:44724.service - OpenSSH per-connection server daemon (10.200.16.10:44724). Jan 23 18:55:50.929594 sshd[12660]: Accepted publickey for core from 10.200.16.10 port 44724 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:55:50.930937 sshd-session[12660]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:55:50.936957 systemd-logind[1885]: New session 10 of user core. Jan 23 18:55:50.948260 systemd[1]: Started session-10.scope - Session 10 of User core. Jan 23 18:55:51.369633 sshd[12895]: Connection closed by 10.200.16.10 port 44724 Jan 23 18:55:51.373245 sshd-session[12660]: pam_unix(sshd:session): session closed for user core Jan 23 18:55:51.377404 systemd-logind[1885]: Session 10 logged out. Waiting for processes to exit. Jan 23 18:55:51.378306 systemd[1]: sshd@7-10.200.4.28:22-10.200.16.10:44724.service: Deactivated successfully. Jan 23 18:55:51.381870 systemd[1]: session-10.scope: Deactivated successfully. Jan 23 18:55:51.383916 systemd-logind[1885]: Removed session 10. Jan 23 18:55:51.488287 systemd[1]: Started sshd@8-10.200.4.28:22-10.200.16.10:44728.service - OpenSSH per-connection server daemon (10.200.16.10:44728). Jan 23 18:55:52.121597 sshd[13080]: Accepted publickey for core from 10.200.16.10 port 44728 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:55:52.122909 sshd-session[13080]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:55:52.128949 systemd-logind[1885]: New session 11 of user core. Jan 23 18:55:52.136109 systemd[1]: Started session-11.scope - Session 11 of User core. Jan 23 18:55:52.562210 sshd[13355]: Connection closed by 10.200.16.10 port 44728 Jan 23 18:55:52.562576 sshd-session[13080]: pam_unix(sshd:session): session closed for user core Jan 23 18:55:52.566941 systemd-logind[1885]: Session 11 logged out. Waiting for processes to exit. Jan 23 18:55:52.567052 systemd[1]: sshd@8-10.200.4.28:22-10.200.16.10:44728.service: Deactivated successfully. Jan 23 18:55:52.568701 systemd[1]: session-11.scope: Deactivated successfully. Jan 23 18:55:52.570985 systemd-logind[1885]: Removed session 11. Jan 23 18:56:02.669430 systemd[1]: Started sshd@9-10.200.4.28:22-10.200.16.10:52348.service - OpenSSH per-connection server daemon (10.200.16.10:52348). Jan 23 18:56:03.312096 sshd[14413]: Accepted publickey for core from 10.200.16.10 port 52348 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:56:03.314273 sshd-session[14413]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:56:03.322752 systemd-logind[1885]: New session 12 of user core. Jan 23 18:56:03.335167 systemd[1]: Started session-12.scope - Session 12 of User core. Jan 23 18:56:03.751997 sshd[14439]: Connection closed by 10.200.16.10 port 52348 Jan 23 18:56:03.752598 sshd-session[14413]: pam_unix(sshd:session): session closed for user core Jan 23 18:56:03.756969 systemd-logind[1885]: Session 12 logged out. Waiting for processes to exit. Jan 23 18:56:03.758346 systemd[1]: sshd@9-10.200.4.28:22-10.200.16.10:52348.service: Deactivated successfully. Jan 23 18:56:03.759899 systemd[1]: session-12.scope: Deactivated successfully. Jan 23 18:56:03.762547 systemd-logind[1885]: Removed session 12. Jan 23 18:56:03.866404 systemd[1]: Started sshd@10-10.200.4.28:22-10.200.16.10:52364.service - OpenSSH per-connection server daemon (10.200.16.10:52364). Jan 23 18:56:04.502580 sshd[14454]: Accepted publickey for core from 10.200.16.10 port 52364 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:56:04.504269 sshd-session[14454]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:56:04.510314 systemd-logind[1885]: New session 13 of user core. Jan 23 18:56:04.519243 systemd[1]: Started session-13.scope - Session 13 of User core. Jan 23 18:56:04.942268 sshd[14490]: Connection closed by 10.200.16.10 port 52364 Jan 23 18:56:04.943041 sshd-session[14454]: pam_unix(sshd:session): session closed for user core Jan 23 18:56:04.947973 systemd-logind[1885]: Session 13 logged out. Waiting for processes to exit. Jan 23 18:56:04.949429 systemd[1]: sshd@10-10.200.4.28:22-10.200.16.10:52364.service: Deactivated successfully. Jan 23 18:56:04.951751 systemd[1]: session-13.scope: Deactivated successfully. Jan 23 18:56:04.953992 systemd-logind[1885]: Removed session 13. Jan 23 18:56:15.073358 systemd[1]: Started sshd@11-10.200.4.28:22-10.200.16.10:50796.service - OpenSSH per-connection server daemon (10.200.16.10:50796). Jan 23 18:56:15.715820 sshd[14832]: Accepted publickey for core from 10.200.16.10 port 50796 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:56:15.718687 sshd-session[14832]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:56:15.727124 systemd-logind[1885]: New session 14 of user core. Jan 23 18:56:15.735289 systemd[1]: Started session-14.scope - Session 14 of User core. Jan 23 18:56:16.158397 sshd[14842]: Connection closed by 10.200.16.10 port 50796 Jan 23 18:56:16.158682 sshd-session[14832]: pam_unix(sshd:session): session closed for user core Jan 23 18:56:16.163662 systemd-logind[1885]: Session 14 logged out. Waiting for processes to exit. Jan 23 18:56:16.165021 systemd[1]: sshd@11-10.200.4.28:22-10.200.16.10:50796.service: Deactivated successfully. Jan 23 18:56:16.166613 systemd[1]: session-14.scope: Deactivated successfully. Jan 23 18:56:16.169085 systemd-logind[1885]: Removed session 14. Jan 23 18:56:16.267103 systemd[1]: Started sshd@12-10.200.4.28:22-10.200.16.10:50800.service - OpenSSH per-connection server daemon (10.200.16.10:50800). Jan 23 18:56:16.899549 sshd[14859]: Accepted publickey for core from 10.200.16.10 port 50800 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:56:16.900914 sshd-session[14859]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:56:16.908027 systemd-logind[1885]: New session 15 of user core. Jan 23 18:56:16.914730 systemd[1]: Started session-15.scope - Session 15 of User core. Jan 23 18:56:17.336800 sshd[14898]: Connection closed by 10.200.16.10 port 50800 Jan 23 18:56:17.337193 sshd-session[14859]: pam_unix(sshd:session): session closed for user core Jan 23 18:56:17.341998 systemd[1]: sshd@12-10.200.4.28:22-10.200.16.10:50800.service: Deactivated successfully. Jan 23 18:56:17.342372 systemd-logind[1885]: Session 15 logged out. Waiting for processes to exit. Jan 23 18:56:17.344714 systemd[1]: session-15.scope: Deactivated successfully. Jan 23 18:56:17.348928 systemd-logind[1885]: Removed session 15. Jan 23 18:56:27.457493 systemd[1]: Started sshd@13-10.200.4.28:22-10.200.16.10:59942.service - OpenSSH per-connection server daemon (10.200.16.10:59942). Jan 23 18:56:28.077103 sshd[15219]: Accepted publickey for core from 10.200.16.10 port 59942 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:56:28.078371 sshd-session[15219]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:56:28.082730 systemd-logind[1885]: New session 16 of user core. Jan 23 18:56:28.093200 systemd[1]: Started session-16.scope - Session 16 of User core. Jan 23 18:56:28.515199 sshd[15222]: Connection closed by 10.200.16.10 port 59942 Jan 23 18:56:28.515555 sshd-session[15219]: pam_unix(sshd:session): session closed for user core Jan 23 18:56:28.519107 systemd[1]: sshd@13-10.200.4.28:22-10.200.16.10:59942.service: Deactivated successfully. Jan 23 18:56:28.520640 systemd[1]: session-16.scope: Deactivated successfully. Jan 23 18:56:28.521317 systemd-logind[1885]: Session 16 logged out. Waiting for processes to exit. Jan 23 18:56:28.522330 systemd-logind[1885]: Removed session 16. Jan 23 18:56:28.635428 systemd[1]: Started sshd@14-10.200.4.28:22-10.200.16.10:59944.service - OpenSSH per-connection server daemon (10.200.16.10:59944). Jan 23 18:56:29.249910 sshd[15228]: Accepted publickey for core from 10.200.16.10 port 59944 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:56:29.252349 sshd-session[15228]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:56:29.256607 systemd-logind[1885]: New session 17 of user core. Jan 23 18:56:29.266286 systemd[1]: Started session-17.scope - Session 17 of User core. Jan 23 18:56:29.684781 sshd[15231]: Connection closed by 10.200.16.10 port 59944 Jan 23 18:56:29.685187 sshd-session[15228]: pam_unix(sshd:session): session closed for user core Jan 23 18:56:29.688670 systemd[1]: sshd@14-10.200.4.28:22-10.200.16.10:59944.service: Deactivated successfully. Jan 23 18:56:29.690320 systemd[1]: session-17.scope: Deactivated successfully. Jan 23 18:56:29.691006 systemd-logind[1885]: Session 17 logged out. Waiting for processes to exit. Jan 23 18:56:29.692030 systemd-logind[1885]: Removed session 17. Jan 23 18:56:35.218041 setup-nvidia[2248]: \u000d Jan 23 18:56:35.218041 setup-nvidia[2248]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Jan 23 18:56:35.218041 setup-nvidia[2248]: frequently when this kernel module was built against the wrong or Jan 23 18:56:35.218041 setup-nvidia[2248]: improperly configured kernel sources, with a version of gcc that differs Jan 23 18:56:35.218041 setup-nvidia[2248]: from the one used to build the target kernel, or if another driver, such Jan 23 18:56:35.218041 setup-nvidia[2248]: as nouveau, is present and prevents the NVIDIA kernel module from Jan 23 18:56:35.218041 setup-nvidia[2248]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Jan 23 18:56:35.218041 setup-nvidia[2248]: installed in this system is supported by this NVIDIA Linux graphics Jan 23 18:56:35.218041 setup-nvidia[2248]: driver release. Jan 23 18:56:35.218041 setup-nvidia[2248]: \u000d Jan 23 18:56:35.218041 setup-nvidia[2248]: Please see the log entries 'Kernel module load error' and 'Kernel Jan 23 18:56:35.218041 setup-nvidia[2248]: messages' at the end of the file Jan 23 18:56:35.218041 setup-nvidia[2248]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for more Jan 23 18:56:35.218041 setup-nvidia[2248]: information. Jan 23 18:56:35.218041 setup-nvidia[2248]: \u000d Jan 23 18:56:35.251089 setup-nvidia[2248]: dmesg: read kernel buffer failed: Operation not permitted Jan 23 18:56:35.271736 setup-nvidia[2248]: \u000d Jan 23 18:56:35.271736 setup-nvidia[2248]: ERROR: Installation has failed. Please see the file Jan 23 18:56:35.271736 setup-nvidia[2248]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for Jan 23 18:56:35.271736 setup-nvidia[2248]: details. You may find suggestions on fixing installation problems in Jan 23 18:56:35.271736 setup-nvidia[2248]: the README available on the Linux driver download page at Jan 23 18:56:35.271736 setup-nvidia[2248]: www.nvidia.com. Jan 23 18:56:35.271736 setup-nvidia[2248]: \u000d Jan 23 18:56:35.272605 setup-nvidia[2248]: Last 50 lines of nvidia-installer.log: Jan 23 18:56:35.273184 setup-nvidia[2248]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039059rm+0x53: 'naked' return found in MITIGATION_RETHUNK build Jan 23 18:56:35.273184 setup-nvidia[2248]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039056rm+0x90: 'naked' return found in MITIGATION_RETHUNK build Jan 23 18:56:35.273184 setup-nvidia[2248]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039049rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build Jan 23 18:56:35.273184 setup-nvidia[2248]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039105rm+0x93: 'naked' return found in MITIGATION_RETHUNK build Jan 23 18:56:35.273184 setup-nvidia[2248]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039101rm+0x36: 'naked' return found in MITIGATION_RETHUNK build Jan 23 18:56:35.273184 setup-nvidia[2248]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039100rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build Jan 23 18:56:35.273184 setup-nvidia[2248]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039102rm+0x77: 'naked' return found in MITIGATION_RETHUNK build Jan 23 18:56:35.273184 setup-nvidia[2248]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039046rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build Jan 23 18:56:35.273184 setup-nvidia[2248]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030615rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build Jan 23 18:56:35.273184 setup-nvidia[2248]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030614rm+0x27: 'naked' return found in MITIGATION_RETHUNK build Jan 23 18:56:35.273184 setup-nvidia[2248]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030618rm+0x37: 'naked' return found in MITIGATION_RETHUNK build Jan 23 18:56:35.273184 setup-nvidia[2248]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030617rm+0x24: 'naked' return found in MITIGATION_RETHUNK build Jan 23 18:56:35.273184 setup-nvidia[2248]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030616rm+0xca: 'naked' return found in MITIGATION_RETHUNK build Jan 23 18:56:35.273184 setup-nvidia[2248]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030619rm+0x193: 'naked' return found in MITIGATION_RETHUNK build Jan 23 18:56:35.273535 setup-nvidia[2248]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030621rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build Jan 23 18:56:35.273535 setup-nvidia[2248]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv011864rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build Jan 23 18:56:35.273535 setup-nvidia[2248]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039413rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build Jan 23 18:56:35.273535 setup-nvidia[2248]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/Module.symvers Jan 23 18:56:35.273535 setup-nvidia[2248]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.mod.o Jan 23 18:56:35.273535 setup-nvidia[2248]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/.module-common.o Jan 23 18:56:35.273535 setup-nvidia[2248]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.mod.o Jan 23 18:56:35.273535 setup-nvidia[2248]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.mod.o Jan 23 18:56:35.273535 setup-nvidia[2248]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.mod.o Jan 23 18:56:35.273535 setup-nvidia[2248]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.mod.o Jan 23 18:56:35.273535 setup-nvidia[2248]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Jan 23 18:56:35.273535 setup-nvidia[2248]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Jan 23 18:56:35.273535 setup-nvidia[2248]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Jan 23 18:56:35.273535 setup-nvidia[2248]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Jan 23 18:56:35.273535 setup-nvidia[2248]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Jan 23 18:56:35.273535 setup-nvidia[2248]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Jan 23 18:56:35.273535 setup-nvidia[2248]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko due to unavailability of vmlinux Jan 23 18:56:35.273535 setup-nvidia[2248]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Jan 23 18:56:35.273535 setup-nvidia[2248]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko due to unavailability of vmlinux Jan 23 18:56:35.273535 setup-nvidia[2248]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Jan 23 18:56:35.273535 setup-nvidia[2248]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko due to unavailability of vmlinux Jan 23 18:56:35.273535 setup-nvidia[2248]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Jan 23 18:56:35.274065 setup-nvidia[2248]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko due to unavailability of vmlinux Jan 23 18:56:35.274065 setup-nvidia[2248]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Jan 23 18:56:35.274065 setup-nvidia[2248]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko due to unavailability of vmlinux Jan 23 18:56:35.274065 setup-nvidia[2248]: make[2]: Leaving directory '/usr/lib/modules/6.12.66-flatcar/build' Jan 23 18:56:35.274065 setup-nvidia[2248]: make[1]: Leaving directory '/usr/lib/modules/6.12.66-flatcar/source' Jan 23 18:56:35.274065 setup-nvidia[2248]: -> done. Jan 23 18:56:35.274065 setup-nvidia[2248]: -> Kernel module compilation complete. Jan 23 18:56:35.274065 setup-nvidia[2248]: -> Unable to determine if Secure Boot is enabled: No such file or directory Jan 23 18:56:35.274065 setup-nvidia[2248]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most frequently when this kernel module was built against the wrong or improperly configured kernel sources, with a version of gcc that differs from the one used to build the target kernel, or if another driver, such as nouveau, is present and prevents the NVIDIA kernel module from obtaining ownership of the NVIDIA device(s), or no NVIDIA device installed in this system is supported by this NVIDIA Linux graphics driver release. Jan 23 18:56:35.274065 setup-nvidia[2248]: \u000d Jan 23 18:56:35.274065 setup-nvidia[2248]: Please see the log entries 'Kernel module load error' and 'Kernel messages' at the end of the file '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for more information. Jan 23 18:56:35.274065 setup-nvidia[2248]: -> Kernel module load error: Operation not permitted Jan 23 18:56:35.274065 setup-nvidia[2248]: -> Kernel messages: Jan 23 18:56:35.274065 setup-nvidia[2248]: ERROR: Installation has failed. Please see the file '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for details. You may find suggestions on fixing installation problems in the README available on the Linux driver download page at www.nvidia.com. Jan 23 18:56:39.799028 systemd[1]: Started sshd@15-10.200.4.28:22-10.200.16.10:47810.service - OpenSSH per-connection server daemon (10.200.16.10:47810). Jan 23 18:56:40.418920 sshd[15417]: Accepted publickey for core from 10.200.16.10 port 47810 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:56:40.420184 sshd-session[15417]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:56:40.424659 systemd-logind[1885]: New session 18 of user core. Jan 23 18:56:40.440199 systemd[1]: Started session-18.scope - Session 18 of User core. Jan 23 18:56:40.857708 sshd[15420]: Connection closed by 10.200.16.10 port 47810 Jan 23 18:56:40.858012 sshd-session[15417]: pam_unix(sshd:session): session closed for user core Jan 23 18:56:40.861635 systemd[1]: sshd@15-10.200.4.28:22-10.200.16.10:47810.service: Deactivated successfully. Jan 23 18:56:40.863535 systemd[1]: session-18.scope: Deactivated successfully. Jan 23 18:56:40.864342 systemd-logind[1885]: Session 18 logged out. Waiting for processes to exit. Jan 23 18:56:40.865254 systemd-logind[1885]: Removed session 18. Jan 23 18:56:40.970887 systemd[1]: Started sshd@16-10.200.4.28:22-10.200.16.10:47824.service - OpenSSH per-connection server daemon (10.200.16.10:47824). Jan 23 18:56:41.586985 sshd[15426]: Accepted publickey for core from 10.200.16.10 port 47824 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:56:41.588366 sshd-session[15426]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:56:41.592759 systemd-logind[1885]: New session 19 of user core. Jan 23 18:56:41.602202 systemd[1]: Started session-19.scope - Session 19 of User core. Jan 23 18:56:42.022223 sshd[15429]: Connection closed by 10.200.16.10 port 47824 Jan 23 18:56:42.022585 sshd-session[15426]: pam_unix(sshd:session): session closed for user core Jan 23 18:56:42.026171 systemd[1]: sshd@16-10.200.4.28:22-10.200.16.10:47824.service: Deactivated successfully. Jan 23 18:56:42.027738 systemd[1]: session-19.scope: Deactivated successfully. Jan 23 18:56:42.028425 systemd-logind[1885]: Session 19 logged out. Waiting for processes to exit. Jan 23 18:56:42.029455 systemd-logind[1885]: Removed session 19. Jan 23 18:56:51.336102 kernel: EXT4-fs (loop6p9): unmounting filesystem 94bea6f2-4f04-4451-9ae2-f9d206317999. Jan 23 18:56:51.336545 setup-nvidia[2248]: Container flatcardevelopercontainer-4459.2.3.bin exited successfully. Jan 23 18:56:51.339002 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4459.2.3.bin.mount: Deactivated successfully. Jan 23 18:56:51.390990 sudo[2236]: pam_unix(sudo:session): session closed for user root Jan 23 18:56:51.392952 systemd[1]: session-c2.scope: Deactivated successfully. Jan 23 18:56:51.393229 systemd[1]: session-c2.scope: Consumed 5min 21.918s CPU time, 889.7M memory peak. Jan 23 18:56:51.827272 setup-nvidia[1881]: /opt/nvidia / Jan 23 18:56:51.828310 setup-nvidia[1881]: / Jan 23 18:56:51.838091 kernel: IPMI message handler: version 39.2 Jan 23 18:56:51.841096 kernel: ipmi device interface Jan 23 18:56:51.853776 setup-nvidia[1881]: /opt/nvidia/current/usr/lib/modules/6.12.66-flatcar/video / Jan 23 18:56:51.906309 kernel: nvidia: loading out-of-tree module taints kernel. Jan 23 18:56:51.906432 kernel: nvidia: module license 'NVIDIA' taints kernel. Jan 23 18:56:51.906454 kernel: Disabling lock debugging due to kernel taint Jan 23 18:56:51.913921 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Jan 23 18:56:51.913951 kernel: nvidia: module license taints kernel. Jan 23 18:56:52.137436 systemd[1]: Started sshd@17-10.200.4.28:22-10.200.16.10:47946.service - OpenSSH per-connection server daemon (10.200.16.10:47946). Jan 23 18:56:52.315101 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 Jan 23 18:56:52.710936 kernel: Jan 23 18:56:52.711082 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Jan 23 18:56:52.765092 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.261.03 Sat Jun 14 16:05:50 UTC 2025 Jan 23 18:56:52.786093 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 18:56:52.807105 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Jan 23 18:56:52.858485 setup-nvidia[1881]: / Jan 23 18:56:52.859089 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. Jan 23 18:56:52.872662 setup-nvidia[15473]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jan 23 18:56:53.118217 sshd[15461]: Accepted publickey for core from 10.200.16.10 port 47946 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:56:53.119393 sshd-session[15461]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:56:53.123625 systemd-logind[1885]: New session 20 of user core. Jan 23 18:56:53.134200 systemd[1]: Started session-20.scope - Session 20 of User core. Jan 23 18:56:53.561834 sshd[15494]: Connection closed by 10.200.16.10 port 47946 Jan 23 18:56:53.562242 sshd-session[15461]: pam_unix(sshd:session): session closed for user core Jan 23 18:56:53.565767 systemd[1]: sshd@17-10.200.4.28:22-10.200.16.10:47946.service: Deactivated successfully. Jan 23 18:56:53.567389 systemd[1]: session-20.scope: Deactivated successfully. Jan 23 18:56:53.568081 systemd-logind[1885]: Session 20 logged out. Waiting for processes to exit. Jan 23 18:56:53.569108 systemd-logind[1885]: Removed session 20. Jan 23 18:56:53.675666 systemd[1]: Started sshd@18-10.200.4.28:22-10.200.16.10:47958.service - OpenSSH per-connection server daemon (10.200.16.10:47958). Jan 23 18:56:54.291201 sshd[15500]: Accepted publickey for core from 10.200.16.10 port 47958 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:56:54.292430 sshd-session[15500]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:56:54.296665 systemd-logind[1885]: New session 21 of user core. Jan 23 18:56:54.310202 systemd[1]: Started session-21.scope - Session 21 of User core. Jan 23 18:56:54.470068 setup-nvidia[15490]: Fri Jan 23 18:56:54 2026 Jan 23 18:56:54.470068 setup-nvidia[15490]: +---------------------------------------------------------------------------------------+ Jan 23 18:56:54.470068 setup-nvidia[15490]: | NVIDIA-SMI 535.261.03 Driver Version: 535.261.03 CUDA Version: 12.2 | Jan 23 18:56:54.470068 setup-nvidia[15490]: |-----------------------------------------+----------------------+----------------------+ Jan 23 18:56:54.470068 setup-nvidia[15490]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Jan 23 18:56:54.470068 setup-nvidia[15490]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Jan 23 18:56:54.470068 setup-nvidia[15490]: | | | MIG M. | Jan 23 18:56:54.470068 setup-nvidia[15490]: |=========================================+======================+======================| Jan 23 18:56:54.473695 setup-nvidia[15490]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Jan 23 18:56:54.473695 setup-nvidia[15490]: | N/A 30C P0 34W / 250W | 0MiB / 16384MiB | 2% Default | Jan 23 18:56:54.473695 setup-nvidia[15490]: | | | N/A | Jan 23 18:56:54.473695 setup-nvidia[15490]: +-----------------------------------------+----------------------+----------------------+ Jan 23 18:56:54.473695 setup-nvidia[15490]: Jan 23 18:56:54.473695 setup-nvidia[15490]: +---------------------------------------------------------------------------------------+ Jan 23 18:56:54.473695 setup-nvidia[15490]: | Processes: | Jan 23 18:56:54.473695 setup-nvidia[15490]: | GPU GI CI PID Type Process name GPU Memory | Jan 23 18:56:54.473695 setup-nvidia[15490]: | ID ID Usage | Jan 23 18:56:54.473695 setup-nvidia[15490]: |=======================================================================================| Jan 23 18:56:54.473941 setup-nvidia[15490]: | No running processes found | Jan 23 18:56:54.473941 setup-nvidia[15490]: +---------------------------------------------------------------------------------------+ Jan 23 18:56:54.726867 sshd[15503]: Connection closed by 10.200.16.10 port 47958 Jan 23 18:56:54.727278 sshd-session[15500]: pam_unix(sshd:session): session closed for user core Jan 23 18:56:54.731195 systemd[1]: sshd@18-10.200.4.28:22-10.200.16.10:47958.service: Deactivated successfully. Jan 23 18:56:54.732760 systemd[1]: session-21.scope: Deactivated successfully. Jan 23 18:56:54.733490 systemd-logind[1885]: Session 21 logged out. Waiting for processes to exit. Jan 23 18:56:54.734440 systemd-logind[1885]: Removed session 21. Jan 23 18:56:54.844767 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Jan 23 18:56:54.849844 systemd[1]: Reached target multi-user.target - Multi-User System. Jan 23 18:56:54.854793 systemd[1]: Startup finished in 911ms (firmware) + 27.655s (loader) + 3.753s (kernel) + 13.308s (initrd) + 2min 52.714s (userspace) = 3min 38.343s. Jan 23 18:57:01.410528 systemd[1]: Stopping user@0.service - User Manager for UID 0... Jan 23 18:57:01.411335 systemd[2239]: Activating special unit exit.target... Jan 23 18:57:01.411431 systemd[2239]: Stopped target default.target - Main User Target. Jan 23 18:57:01.411470 systemd[2239]: Stopped target basic.target - Basic System. Jan 23 18:57:01.411499 systemd[2239]: Stopped target paths.target - Paths. Jan 23 18:57:01.411525 systemd[2239]: Stopped target sockets.target - Sockets. Jan 23 18:57:01.411550 systemd[2239]: Stopped target timers.target - Timers. Jan 23 18:57:01.411887 systemd[2239]: Closed dbus.socket - D-Bus User Message Bus Socket. Jan 23 18:57:01.412140 systemd[2239]: Removed slice app.slice - User Application Slice. Jan 23 18:57:01.412173 systemd[2239]: Reached target shutdown.target - Shutdown. Jan 23 18:57:01.412307 systemd[2239]: Finished systemd-exit.service - Exit the Session. Jan 23 18:57:01.412343 systemd[2239]: Reached target exit.target - Exit the Session. Jan 23 18:57:01.438048 (sd-pam)[2240]: pam_unix(systemd-user:session): session closed for user root Jan 23 18:57:01.439314 systemd[1]: user@0.service: Deactivated successfully. Jan 23 18:57:01.439660 systemd[1]: Stopped user@0.service - User Manager for UID 0. Jan 23 18:57:01.441957 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Jan 23 18:57:01.468266 systemd[1]: run-user-0.mount: Deactivated successfully. Jan 23 18:57:01.469157 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Jan 23 18:57:01.469347 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Jan 23 18:57:01.471060 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Jan 23 18:57:01.471214 systemd[1]: user-0.slice: Consumed 5min 22.219s CPU time, 895.8M memory peak. Jan 23 18:57:01.471694 systemd-logind[1885]: Removed session c3. Jan 23 18:57:05.044049 systemd[1]: Started sshd@19-10.200.4.28:22-10.200.16.10:42988.service - OpenSSH per-connection server daemon (10.200.16.10:42988). Jan 23 18:57:05.674345 sshd[15515]: Accepted publickey for core from 10.200.16.10 port 42988 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:57:05.675559 sshd-session[15515]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:57:05.679607 systemd-logind[1885]: New session 22 of user core. Jan 23 18:57:05.702197 systemd[1]: Started session-22.scope - Session 22 of User core. Jan 23 18:57:06.111726 sshd[15518]: Connection closed by 10.200.16.10 port 42988 Jan 23 18:57:06.111986 sshd-session[15515]: pam_unix(sshd:session): session closed for user core Jan 23 18:57:06.115546 systemd[1]: sshd@19-10.200.4.28:22-10.200.16.10:42988.service: Deactivated successfully. Jan 23 18:57:06.117455 systemd[1]: session-22.scope: Deactivated successfully. Jan 23 18:57:06.118253 systemd-logind[1885]: Session 22 logged out. Waiting for processes to exit. Jan 23 18:57:06.119152 systemd-logind[1885]: Removed session 22. Jan 23 18:57:06.219892 systemd[1]: Started sshd@20-10.200.4.28:22-10.200.16.10:42994.service - OpenSSH per-connection server daemon (10.200.16.10:42994). Jan 23 18:57:06.848975 sshd[15524]: Accepted publickey for core from 10.200.16.10 port 42994 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:57:06.850231 sshd-session[15524]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:57:06.854933 systemd-logind[1885]: New session 23 of user core. Jan 23 18:57:06.876212 systemd[1]: Started session-23.scope - Session 23 of User core. Jan 23 18:57:07.281253 sshd[15527]: Connection closed by 10.200.16.10 port 42994 Jan 23 18:57:07.281583 sshd-session[15524]: pam_unix(sshd:session): session closed for user core Jan 23 18:57:07.285061 systemd[1]: sshd@20-10.200.4.28:22-10.200.16.10:42994.service: Deactivated successfully. Jan 23 18:57:07.286692 systemd[1]: session-23.scope: Deactivated successfully. Jan 23 18:57:07.287395 systemd-logind[1885]: Session 23 logged out. Waiting for processes to exit. Jan 23 18:57:07.288307 systemd-logind[1885]: Removed session 23. Jan 23 18:57:07.394500 systemd[1]: Started sshd@21-10.200.4.28:22-10.200.16.10:42998.service - OpenSSH per-connection server daemon (10.200.16.10:42998). Jan 23 18:57:08.035680 sshd[15533]: Accepted publickey for core from 10.200.16.10 port 42998 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:57:08.036920 sshd-session[15533]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:57:08.041200 systemd-logind[1885]: New session 24 of user core. Jan 23 18:57:08.055227 systemd[1]: Started session-24.scope - Session 24 of User core. Jan 23 18:57:08.473567 sshd[15536]: Connection closed by 10.200.16.10 port 42998 Jan 23 18:57:08.473958 sshd-session[15533]: pam_unix(sshd:session): session closed for user core Jan 23 18:57:08.477557 systemd[1]: sshd@21-10.200.4.28:22-10.200.16.10:42998.service: Deactivated successfully. Jan 23 18:57:08.479206 systemd[1]: session-24.scope: Deactivated successfully. Jan 23 18:57:08.480008 systemd-logind[1885]: Session 24 logged out. Waiting for processes to exit. Jan 23 18:57:08.481145 systemd-logind[1885]: Removed session 24. Jan 23 18:57:08.586415 systemd[1]: Started sshd@22-10.200.4.28:22-10.200.16.10:43000.service - OpenSSH per-connection server daemon (10.200.16.10:43000). Jan 23 18:57:09.221856 sshd[15542]: Accepted publickey for core from 10.200.16.10 port 43000 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:57:09.223060 sshd-session[15542]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:57:09.227186 systemd-logind[1885]: New session 25 of user core. Jan 23 18:57:09.243247 systemd[1]: Started session-25.scope - Session 25 of User core. Jan 23 18:57:09.660167 sshd[15545]: Connection closed by 10.200.16.10 port 43000 Jan 23 18:57:09.660546 sshd-session[15542]: pam_unix(sshd:session): session closed for user core Jan 23 18:57:09.664183 systemd[1]: sshd@22-10.200.4.28:22-10.200.16.10:43000.service: Deactivated successfully. Jan 23 18:57:09.665812 systemd[1]: session-25.scope: Deactivated successfully. Jan 23 18:57:09.666537 systemd-logind[1885]: Session 25 logged out. Waiting for processes to exit. Jan 23 18:57:09.667556 systemd-logind[1885]: Removed session 25. Jan 23 18:57:09.773365 systemd[1]: Started sshd@23-10.200.4.28:22-10.200.16.10:33500.service - OpenSSH per-connection server daemon (10.200.16.10:33500). Jan 23 18:57:10.409255 sshd[15551]: Accepted publickey for core from 10.200.16.10 port 33500 ssh2: RSA SHA256:/8Kg2RGXBnlGRIXsvo+LmzccnHh2ex0Z4J1FiHCatWA Jan 23 18:57:10.410506 sshd-session[15551]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 18:57:10.414675 systemd-logind[1885]: New session 26 of user core. Jan 23 18:57:10.424193 systemd[1]: Started session-26.scope - Session 26 of User core. Jan 23 18:57:12.361824 sshd[15554]: Connection closed by 10.200.16.10 port 33500 Jan 23 18:57:12.362241 sshd-session[15551]: pam_unix(sshd:session): session closed for user core Jan 23 18:57:12.365708 systemd[1]: sshd@23-10.200.4.28:22-10.200.16.10:33500.service: Deactivated successfully. Jan 23 18:57:12.367337 systemd[1]: session-26.scope: Deactivated successfully. Jan 23 18:57:12.367534 systemd[1]: session-26.scope: Consumed 1.455s CPU time, 14M memory peak. Jan 23 18:57:12.368002 systemd-logind[1885]: Session 26 logged out. Waiting for processes to exit. Jan 23 18:57:12.368897 systemd-logind[1885]: Removed session 26. Jan 23 18:57:12.534520 waagent[2311]: 2026-01-23T18:57:12.534444Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 2] Jan 23 18:57:12.542664 waagent[2311]: 2026-01-23T18:57:12.542623Z INFO ExtHandler Jan 23 18:57:12.542755 waagent[2311]: 2026-01-23T18:57:12.542720Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: d98d315c-c50e-4c2a-b004-82b21f43ebc1 eTag: 4881865255504251717 source: Fabric] Jan 23 18:57:12.543074 waagent[2311]: 2026-01-23T18:57:12.543034Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Jan 23 18:57:12.543818 waagent[2311]: 2026-01-23T18:57:12.543769Z INFO ExtHandler Jan 23 18:57:12.543882 waagent[2311]: 2026-01-23T18:57:12.543855Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 2] Jan 23 18:57:12.633926 waagent[2311]: 2026-01-23T18:57:12.633798Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Jan 23 18:57:12.697956 waagent[2311]: 2026-01-23T18:57:12.697891Z INFO ExtHandler Downloaded certificate {'thumbprint': 'FBAF7726AF4D9267AD9B460C6A81C7FE7EE0A159', 'hasPrivateKey': True} Jan 23 18:57:12.698491 waagent[2311]: 2026-01-23T18:57:12.698449Z INFO ExtHandler Fetch goal state completed Jan 23 18:57:12.698869 waagent[2311]: 2026-01-23T18:57:12.698833Z INFO ExtHandler ExtHandler Jan 23 18:57:12.698943 waagent[2311]: 2026-01-23T18:57:12.698911Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_2 channel: WireServer source: Fabric activity: 4ad20ddd-31a1-4b45-b56f-97261f63e600 correlation 0f34e0bf-f12a-4742-aea5-f5f0ad6553d0 created: 2026-01-23T18:57:04.664307Z] Jan 23 18:57:12.699289 waagent[2311]: 2026-01-23T18:57:12.699251Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Jan 23 18:57:12.699797 waagent[2311]: 2026-01-23T18:57:12.699762Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_2 0 ms]