Nov 5 15:50:07.137245 kernel: Linux version 6.12.54-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.1_p20250801 p4) 14.3.1 20250801, GNU ld (Gentoo 2.45 p3) 2.45.0) #1 SMP PREEMPT_DYNAMIC Wed Nov 5 13:45:21 -00 2025 Nov 5 15:50:07.137345 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=c2a05564bcb92d35bbb2f0ae32fe5ddfa8424368122998dedda8bd375a237cb4 Nov 5 15:50:07.137358 kernel: BIOS-provided physical RAM map: Nov 5 15:50:07.137365 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Nov 5 15:50:07.137372 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Nov 5 15:50:07.137466 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Nov 5 15:50:07.137483 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Nov 5 15:50:07.137490 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Nov 5 15:50:07.137498 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Nov 5 15:50:07.137505 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Nov 5 15:50:07.137512 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Nov 5 15:50:07.137519 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Nov 5 15:50:07.137526 kernel: printk: legacy bootconsole [earlyser0] enabled Nov 5 15:50:07.137534 kernel: NX (Execute Disable) protection: active Nov 5 15:50:07.137545 kernel: APIC: Static calls initialized Nov 5 15:50:07.137553 kernel: efi: EFI v2.7 by Microsoft Nov 5 15:50:07.137562 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3ee60a98 RNG=0x3ffd1018 Nov 5 15:50:07.137570 kernel: random: crng init done Nov 5 15:50:07.137578 kernel: secureboot: Secure boot disabled Nov 5 15:50:07.137586 kernel: SMBIOS 3.1.0 present. Nov 5 15:50:07.137597 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Nov 5 15:50:07.137607 kernel: DMI: Memory slots populated: 3/3 Nov 5 15:50:07.137615 kernel: Hypervisor detected: Microsoft Hyper-V Nov 5 15:50:07.137623 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Nov 5 15:50:07.137631 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Nov 5 15:50:07.137639 kernel: Hyper-V: Using hypercall for remote TLB flush Nov 5 15:50:07.137647 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Nov 5 15:50:07.137655 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Nov 5 15:50:07.137664 kernel: tsc: Marking TSC unstable due to running on Hyper-V Nov 5 15:50:07.137683 kernel: tsc: Detected 2593.993 MHz processor Nov 5 15:50:07.137695 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Nov 5 15:50:07.137704 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Nov 5 15:50:07.137713 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Nov 5 15:50:07.137721 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Nov 5 15:50:07.137729 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Nov 5 15:50:07.137737 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Nov 5 15:50:07.137745 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Nov 5 15:50:07.137756 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Nov 5 15:50:07.137764 kernel: Using GB pages for direct mapping Nov 5 15:50:07.137773 kernel: ACPI: Early table checksum verification disabled Nov 5 15:50:07.137785 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Nov 5 15:50:07.137794 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 5 15:50:07.137803 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 5 15:50:07.137814 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Nov 5 15:50:07.137822 kernel: ACPI: FACS 0x000000003FFFE000 000040 Nov 5 15:50:07.137831 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 5 15:50:07.137840 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 5 15:50:07.137849 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 5 15:50:07.137857 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 5 15:50:07.137869 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 5 15:50:07.137877 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 5 15:50:07.137886 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 5 15:50:07.137895 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Nov 5 15:50:07.137904 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Nov 5 15:50:07.137912 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Nov 5 15:50:07.137923 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Nov 5 15:50:07.137932 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Nov 5 15:50:07.137941 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Nov 5 15:50:07.137949 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Nov 5 15:50:07.137958 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Nov 5 15:50:07.137967 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Nov 5 15:50:07.137983 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Nov 5 15:50:07.137994 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Nov 5 15:50:07.138003 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Nov 5 15:50:07.138012 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Nov 5 15:50:07.138020 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Nov 5 15:50:07.138029 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Nov 5 15:50:07.138038 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Nov 5 15:50:07.138047 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Nov 5 15:50:07.138058 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Nov 5 15:50:07.138067 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] Nov 5 15:50:07.138076 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] Nov 5 15:50:07.138084 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] Nov 5 15:50:07.138093 kernel: Zone ranges: Nov 5 15:50:07.138102 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Nov 5 15:50:07.138111 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Nov 5 15:50:07.138122 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Nov 5 15:50:07.138131 kernel: Device empty Nov 5 15:50:07.138140 kernel: Movable zone start for each node Nov 5 15:50:07.138148 kernel: Early memory node ranges Nov 5 15:50:07.138157 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Nov 5 15:50:07.138165 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Nov 5 15:50:07.138174 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Nov 5 15:50:07.138183 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Nov 5 15:50:07.138194 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Nov 5 15:50:07.138203 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Nov 5 15:50:07.138212 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Nov 5 15:50:07.138220 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Nov 5 15:50:07.138229 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Nov 5 15:50:07.138237 kernel: ACPI: PM-Timer IO Port: 0x408 Nov 5 15:50:07.138246 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Nov 5 15:50:07.138257 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Nov 5 15:50:07.138266 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Nov 5 15:50:07.138280 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Nov 5 15:50:07.138289 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Nov 5 15:50:07.138297 kernel: CPU topo: Max. logical packages: 1 Nov 5 15:50:07.138306 kernel: CPU topo: Max. logical dies: 1 Nov 5 15:50:07.138315 kernel: CPU topo: Max. dies per package: 1 Nov 5 15:50:07.138326 kernel: CPU topo: Max. threads per core: 1 Nov 5 15:50:07.138335 kernel: CPU topo: Num. cores per package: 6 Nov 5 15:50:07.138343 kernel: CPU topo: Num. threads per package: 6 Nov 5 15:50:07.138352 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs Nov 5 15:50:07.138361 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Nov 5 15:50:07.138369 kernel: Booting paravirtualized kernel on Hyper-V Nov 5 15:50:07.138378 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Nov 5 15:50:07.138389 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Nov 5 15:50:07.138398 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 Nov 5 15:50:07.138407 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 Nov 5 15:50:07.138415 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Nov 5 15:50:07.138424 kernel: Hyper-V: PV spinlocks enabled Nov 5 15:50:07.138433 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Nov 5 15:50:07.138443 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=c2a05564bcb92d35bbb2f0ae32fe5ddfa8424368122998dedda8bd375a237cb4 Nov 5 15:50:07.138455 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Nov 5 15:50:07.138463 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Nov 5 15:50:07.138472 kernel: Fallback order for Node 0: 0 Nov 5 15:50:07.138481 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 Nov 5 15:50:07.138489 kernel: Policy zone: Normal Nov 5 15:50:07.138498 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Nov 5 15:50:07.138517 kernel: software IO TLB: area num 8. Nov 5 15:50:07.138526 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Nov 5 15:50:07.138535 kernel: Kernel/User page tables isolation: enabled Nov 5 15:50:07.138547 kernel: ftrace: allocating 40092 entries in 157 pages Nov 5 15:50:07.138556 kernel: ftrace: allocated 157 pages with 5 groups Nov 5 15:50:07.138565 kernel: Dynamic Preempt: voluntary Nov 5 15:50:07.138574 kernel: rcu: Preemptible hierarchical RCU implementation. Nov 5 15:50:07.138584 kernel: rcu: RCU event tracing is enabled. Nov 5 15:50:07.138593 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Nov 5 15:50:07.138603 kernel: Trampoline variant of Tasks RCU enabled. Nov 5 15:50:07.138614 kernel: Rude variant of Tasks RCU enabled. Nov 5 15:50:07.138624 kernel: Tracing variant of Tasks RCU enabled. Nov 5 15:50:07.138633 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Nov 5 15:50:07.138642 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Nov 5 15:50:07.138654 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Nov 5 15:50:07.138663 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Nov 5 15:50:07.138680 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Nov 5 15:50:07.138689 kernel: Using NULL legacy PIC Nov 5 15:50:07.138698 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Nov 5 15:50:07.138708 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Nov 5 15:50:07.138717 kernel: Console: colour dummy device 80x25 Nov 5 15:50:07.138729 kernel: printk: legacy console [tty1] enabled Nov 5 15:50:07.138738 kernel: printk: legacy console [ttyS0] enabled Nov 5 15:50:07.138747 kernel: printk: legacy bootconsole [earlyser0] disabled Nov 5 15:50:07.138756 kernel: ACPI: Core revision 20240827 Nov 5 15:50:07.138765 kernel: Failed to register legacy timer interrupt Nov 5 15:50:07.138775 kernel: APIC: Switch to symmetric I/O mode setup Nov 5 15:50:07.138784 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Nov 5 15:50:07.138796 kernel: Hyper-V: enabling crash_kexec_post_notifiers Nov 5 15:50:07.138805 kernel: Hyper-V: Using IPI hypercalls Nov 5 15:50:07.138814 kernel: APIC: send_IPI() replaced with hv_send_ipi() Nov 5 15:50:07.138823 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Nov 5 15:50:07.138832 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Nov 5 15:50:07.138842 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Nov 5 15:50:07.138851 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Nov 5 15:50:07.138862 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Nov 5 15:50:07.138871 kernel: Hyper-V: Using enlightened APIC (xapic mode) Nov 5 15:50:07.138881 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Nov 5 15:50:07.138890 kernel: APIC: read() replaced with hv_apic_read() Nov 5 15:50:07.138899 kernel: APIC: write() replaced with hv_apic_write() Nov 5 15:50:07.138908 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Nov 5 15:50:07.138918 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Nov 5 15:50:07.138929 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593993) Nov 5 15:50:07.138939 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Nov 5 15:50:07.138948 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Nov 5 15:50:07.138957 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Nov 5 15:50:07.138965 kernel: Spectre V2 : Mitigation: Retpolines Nov 5 15:50:07.138974 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Nov 5 15:50:07.138983 kernel: Speculative Store Bypass: Vulnerable Nov 5 15:50:07.138992 kernel: MDS: Mitigation: Clear CPU buffers Nov 5 15:50:07.139000 kernel: TAA: Mitigation: Clear CPU buffers Nov 5 15:50:07.139011 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Nov 5 15:50:07.139020 kernel: active return thunk: its_return_thunk Nov 5 15:50:07.139029 kernel: ITS: Mitigation: Aligned branch/return thunks Nov 5 15:50:07.139038 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Nov 5 15:50:07.139046 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Nov 5 15:50:07.139055 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Nov 5 15:50:07.139064 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Nov 5 15:50:07.139073 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Nov 5 15:50:07.139082 kernel: Freeing SMP alternatives memory: 32K Nov 5 15:50:07.139090 kernel: pid_max: default: 32768 minimum: 301 Nov 5 15:50:07.139101 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Nov 5 15:50:07.139110 kernel: landlock: Up and running. Nov 5 15:50:07.139119 kernel: SELinux: Initializing. Nov 5 15:50:07.139128 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Nov 5 15:50:07.139137 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Nov 5 15:50:07.139146 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Nov 5 15:50:07.139155 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Nov 5 15:50:07.139164 kernel: signal: max sigframe size: 1776 Nov 5 15:50:07.139173 kernel: rcu: Hierarchical SRCU implementation. Nov 5 15:50:07.139185 kernel: rcu: Max phase no-delay instances is 400. Nov 5 15:50:07.139194 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Nov 5 15:50:07.139203 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Nov 5 15:50:07.139212 kernel: smp: Bringing up secondary CPUs ... Nov 5 15:50:07.139221 kernel: smpboot: x86: Booting SMP configuration: Nov 5 15:50:07.139230 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Nov 5 15:50:07.139239 kernel: smp: Brought up 1 node, 6 CPUs Nov 5 15:50:07.139249 kernel: smpboot: Total of 6 processors activated (31127.91 BogoMIPS) Nov 5 15:50:07.139261 kernel: Memory: 115361616K/117439364K available (14336K kernel code, 2443K rwdata, 26064K rodata, 15964K init, 2080K bss, 2067472K reserved, 0K cma-reserved) Nov 5 15:50:07.139270 kernel: devtmpfs: initialized Nov 5 15:50:07.139279 kernel: x86/mm: Memory block size: 1024MB Nov 5 15:50:07.139288 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Nov 5 15:50:07.139297 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Nov 5 15:50:07.139306 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Nov 5 15:50:07.139318 kernel: pinctrl core: initialized pinctrl subsystem Nov 5 15:50:07.139327 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Nov 5 15:50:07.139336 kernel: audit: initializing netlink subsys (disabled) Nov 5 15:50:07.139346 kernel: audit: type=2000 audit(1762357800.028:1): state=initialized audit_enabled=0 res=1 Nov 5 15:50:07.139355 kernel: thermal_sys: Registered thermal governor 'step_wise' Nov 5 15:50:07.139364 kernel: thermal_sys: Registered thermal governor 'user_space' Nov 5 15:50:07.139373 kernel: cpuidle: using governor menu Nov 5 15:50:07.139382 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Nov 5 15:50:07.139393 kernel: dca service started, version 1.12.1 Nov 5 15:50:07.139403 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Nov 5 15:50:07.139412 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Nov 5 15:50:07.139421 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Nov 5 15:50:07.139430 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Nov 5 15:50:07.139439 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Nov 5 15:50:07.139448 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Nov 5 15:50:07.139460 kernel: ACPI: Added _OSI(Module Device) Nov 5 15:50:07.139469 kernel: ACPI: Added _OSI(Processor Device) Nov 5 15:50:07.139478 kernel: ACPI: Added _OSI(Processor Aggregator Device) Nov 5 15:50:07.139487 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Nov 5 15:50:07.139496 kernel: ACPI: Interpreter enabled Nov 5 15:50:07.139505 kernel: ACPI: PM: (supports S0 S5) Nov 5 15:50:07.139514 kernel: ACPI: Using IOAPIC for interrupt routing Nov 5 15:50:07.139526 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Nov 5 15:50:07.139535 kernel: PCI: Ignoring E820 reservations for host bridge windows Nov 5 15:50:07.139544 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Nov 5 15:50:07.139553 kernel: iommu: Default domain type: Translated Nov 5 15:50:07.139562 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Nov 5 15:50:07.139571 kernel: efivars: Registered efivars operations Nov 5 15:50:07.139580 kernel: PCI: Using ACPI for IRQ routing Nov 5 15:50:07.139592 kernel: PCI: System does not support PCI Nov 5 15:50:07.139601 kernel: vgaarb: loaded Nov 5 15:50:07.139611 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Nov 5 15:50:07.139620 kernel: VFS: Disk quotas dquot_6.6.0 Nov 5 15:50:07.139629 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Nov 5 15:50:07.139638 kernel: pnp: PnP ACPI init Nov 5 15:50:07.139647 kernel: pnp: PnP ACPI: found 3 devices Nov 5 15:50:07.139659 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Nov 5 15:50:07.139668 kernel: NET: Registered PF_INET protocol family Nov 5 15:50:07.139684 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Nov 5 15:50:07.139693 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Nov 5 15:50:07.139703 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Nov 5 15:50:07.139712 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Nov 5 15:50:07.139721 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Nov 5 15:50:07.139733 kernel: TCP: Hash tables configured (established 524288 bind 65536) Nov 5 15:50:07.139743 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Nov 5 15:50:07.139752 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Nov 5 15:50:07.139761 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Nov 5 15:50:07.139770 kernel: NET: Registered PF_XDP protocol family Nov 5 15:50:07.139779 kernel: PCI: CLS 0 bytes, default 64 Nov 5 15:50:07.139788 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Nov 5 15:50:07.139800 kernel: software IO TLB: mapped [mem 0x000000003ae60000-0x000000003ee60000] (64MB) Nov 5 15:50:07.139809 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Nov 5 15:50:07.139818 kernel: Initialise system trusted keyrings Nov 5 15:50:07.139827 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Nov 5 15:50:07.139836 kernel: Key type asymmetric registered Nov 5 15:50:07.139845 kernel: Asymmetric key parser 'x509' registered Nov 5 15:50:07.139854 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Nov 5 15:50:07.139866 kernel: io scheduler mq-deadline registered Nov 5 15:50:07.139875 kernel: io scheduler kyber registered Nov 5 15:50:07.139884 kernel: io scheduler bfq registered Nov 5 15:50:07.139893 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Nov 5 15:50:07.139902 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Nov 5 15:50:07.139911 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Nov 5 15:50:07.139921 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Nov 5 15:50:07.139932 kernel: i8042: PNP: No PS/2 controller found. Nov 5 15:50:07.140126 kernel: rtc_cmos 00:02: registered as rtc0 Nov 5 15:50:07.140264 kernel: rtc_cmos 00:02: setting system clock to 2025-11-05T15:50:01 UTC (1762357801) Nov 5 15:50:07.140395 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Nov 5 15:50:07.140408 kernel: intel_pstate: CPU model not supported Nov 5 15:50:07.140418 kernel: efifb: probing for efifb Nov 5 15:50:07.140431 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Nov 5 15:50:07.140441 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Nov 5 15:50:07.140450 kernel: efifb: scrolling: redraw Nov 5 15:50:07.140459 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Nov 5 15:50:07.140468 kernel: Console: switching to colour frame buffer device 128x48 Nov 5 15:50:07.140477 kernel: fb0: EFI VGA frame buffer device Nov 5 15:50:07.140487 kernel: pstore: Using crash dump compression: deflate Nov 5 15:50:07.140496 kernel: pstore: Registered efi_pstore as persistent store backend Nov 5 15:50:07.140508 kernel: NET: Registered PF_INET6 protocol family Nov 5 15:50:07.140517 kernel: Segment Routing with IPv6 Nov 5 15:50:07.140526 kernel: In-situ OAM (IOAM) with IPv6 Nov 5 15:50:07.140535 kernel: NET: Registered PF_PACKET protocol family Nov 5 15:50:07.140544 kernel: Key type dns_resolver registered Nov 5 15:50:07.140553 kernel: IPI shorthand broadcast: enabled Nov 5 15:50:07.140562 kernel: sched_clock: Marking stable (1978005700, 108200600)->(2419739100, -333532800) Nov 5 15:50:07.140574 kernel: registered taskstats version 1 Nov 5 15:50:07.140583 kernel: Loading compiled-in X.509 certificates Nov 5 15:50:07.140593 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.54-flatcar: 9f02cc8d588ce542f03b0da66dde47a90a145382' Nov 5 15:50:07.140602 kernel: Demotion targets for Node 0: null Nov 5 15:50:07.140611 kernel: Key type .fscrypt registered Nov 5 15:50:07.140620 kernel: Key type fscrypt-provisioning registered Nov 5 15:50:07.140629 kernel: ima: No TPM chip found, activating TPM-bypass! Nov 5 15:50:07.140640 kernel: ima: Allocated hash algorithm: sha1 Nov 5 15:50:07.140650 kernel: ima: No architecture policies found Nov 5 15:50:07.140659 kernel: clk: Disabling unused clocks Nov 5 15:50:07.140668 kernel: Freeing unused kernel image (initmem) memory: 15964K Nov 5 15:50:07.140696 kernel: Write protecting the kernel read-only data: 40960k Nov 5 15:50:07.140706 kernel: Freeing unused kernel image (rodata/data gap) memory: 560K Nov 5 15:50:07.140715 kernel: Run /init as init process Nov 5 15:50:07.140728 kernel: with arguments: Nov 5 15:50:07.140737 kernel: /init Nov 5 15:50:07.140746 kernel: with environment: Nov 5 15:50:07.140755 kernel: HOME=/ Nov 5 15:50:07.140763 kernel: TERM=linux Nov 5 15:50:07.140772 kernel: hv_vmbus: Vmbus version:4.0 Nov 5 15:50:07.140782 kernel: hid: raw HID events driver (C) Jiri Kosina Nov 5 15:50:07.140791 kernel: hv_vmbus: registering driver hid_hyperv Nov 5 15:50:07.140802 kernel: SCSI subsystem initialized Nov 5 15:50:07.140812 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input0 Nov 5 15:50:07.140972 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Nov 5 15:50:07.140988 kernel: hv_vmbus: registering driver hyperv_keyboard Nov 5 15:50:07.140997 kernel: input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/VMBUS:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input1 Nov 5 15:50:07.141007 kernel: pps_core: LinuxPPS API ver. 1 registered Nov 5 15:50:07.141020 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Nov 5 15:50:07.141029 kernel: PTP clock support registered Nov 5 15:50:07.141038 kernel: hv_utils: Registering HyperV Utility Driver Nov 5 15:50:07.141048 kernel: hv_vmbus: registering driver hv_utils Nov 5 15:50:07.141057 kernel: hv_utils: Heartbeat IC version 3.0 Nov 5 15:50:07.141066 kernel: hv_utils: Shutdown IC version 3.2 Nov 5 15:50:07.141075 kernel: hv_utils: TimeSync IC version 4.0 Nov 5 15:50:07.141087 kernel: hv_vmbus: registering driver hv_storvsc Nov 5 15:50:07.141259 kernel: scsi host1: storvsc_host_t Nov 5 15:50:07.141419 kernel: scsi host0: storvsc_host_t Nov 5 15:50:07.141589 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Nov 5 15:50:07.141771 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Nov 5 15:50:07.141950 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Nov 5 15:50:07.142107 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Nov 5 15:50:07.142262 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Nov 5 15:50:07.142416 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Nov 5 15:50:07.142568 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks Nov 5 15:50:07.142742 kernel: sd 0:0:0:0: [sda] Write Protect is off Nov 5 15:50:07.142909 kernel: sd 0:0:0:1: [sdb] Write Protect is off Nov 5 15:50:07.143064 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Nov 5 15:50:07.143218 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Nov 5 15:50:07.143372 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Nov 5 15:50:07.143585 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Nov 5 15:50:07.143774 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#325 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 5 15:50:07.143921 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#197 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 5 15:50:07.144063 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#332 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 5 15:50:07.144201 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#204 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 5 15:50:07.144214 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Nov 5 15:50:07.144365 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Nov 5 15:50:07.144383 kernel: sdb: sdb1 Nov 5 15:50:07.144536 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk Nov 5 15:50:07.144549 kernel: hv_vmbus: registering driver hv_pci Nov 5 15:50:07.144720 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Nov 5 15:50:07.144866 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Nov 5 15:50:07.145026 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Nov 5 15:50:07.145176 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Nov 5 15:50:07.145323 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Nov 5 15:50:07.145513 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint Nov 5 15:50:07.145692 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] Nov 5 15:50:07.145854 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] Nov 5 15:50:07.146014 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] Nov 5 15:50:07.146028 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Nov 5 15:50:07.146177 kernel: pci 0001:00:00.0: Enabling HDA controller Nov 5 15:50:07.146190 kernel: device-mapper: uevent: version 1.0.3 Nov 5 15:50:07.146200 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Nov 5 15:50:07.146342 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Nov 5 15:50:07.146500 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned Nov 5 15:50:07.146654 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned Nov 5 15:50:07.146821 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned Nov 5 15:50:07.146836 kernel: device-mapper: verity: sha256 using shash "sha256-generic" Nov 5 15:50:07.146990 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Nov 5 15:50:07.147005 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Nov 5 15:50:07.147162 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Nov 5 15:50:07.147176 kernel: raid6: avx2x4 gen() 30953 MB/s Nov 5 15:50:07.147186 kernel: raid6: avx2x2 gen() 31236 MB/s Nov 5 15:50:07.147195 kernel: raid6: avx2x1 gen() 26939 MB/s Nov 5 15:50:07.147205 kernel: raid6: using algorithm avx2x2 gen() 31236 MB/s Nov 5 15:50:07.147215 kernel: raid6: .... xor() 18482 MB/s, rmw enabled Nov 5 15:50:07.147224 kernel: raid6: using avx2x2 recovery algorithm Nov 5 15:50:07.147238 kernel: xor: automatically using best checksumming function avx Nov 5 15:50:07.147250 kernel: Btrfs loaded, zoned=no, fsverity=no Nov 5 15:50:07.147260 kernel: BTRFS: device fsid a4c7be9c-39f6-471d-8a4c-d50144c6bf01 devid 1 transid 37 /dev/mapper/usr (254:0) scanned by mount (423) Nov 5 15:50:07.147270 kernel: BTRFS info (device dm-0): first mount of filesystem a4c7be9c-39f6-471d-8a4c-d50144c6bf01 Nov 5 15:50:07.147280 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Nov 5 15:50:07.147289 kernel: BTRFS info (device dm-0): disabling log replay at mount time Nov 5 15:50:07.147299 kernel: BTRFS info (device dm-0): enabling free space tree Nov 5 15:50:07.147311 kernel: loop: module loaded Nov 5 15:50:07.147320 kernel: loop0: detected capacity change from 0 to 100120 Nov 5 15:50:07.147371 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Nov 5 15:50:07.148306 systemd[1]: Successfully made /usr/ read-only. Nov 5 15:50:07.148320 systemd[1]: systemd 257.7 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +IPE +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 -BTF -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Nov 5 15:50:07.148331 systemd[1]: Detected virtualization microsoft. Nov 5 15:50:07.148347 systemd[1]: Detected architecture x86-64. Nov 5 15:50:07.148357 systemd[1]: Running in initrd. Nov 5 15:50:07.148367 systemd[1]: No hostname configured, using default hostname. Nov 5 15:50:07.148377 systemd[1]: Hostname set to . Nov 5 15:50:07.148387 systemd[1]: Initializing machine ID from random generator. Nov 5 15:50:07.148397 systemd[1]: Queued start job for default target initrd.target. Nov 5 15:50:07.148407 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Nov 5 15:50:07.148420 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 5 15:50:07.148430 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 5 15:50:07.148441 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Nov 5 15:50:07.148451 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 5 15:50:07.148462 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Nov 5 15:50:07.148475 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Nov 5 15:50:07.148489 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 5 15:50:07.148502 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 5 15:50:07.148512 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Nov 5 15:50:07.148525 systemd[1]: Reached target paths.target - Path Units. Nov 5 15:50:07.148536 systemd[1]: Reached target slices.target - Slice Units. Nov 5 15:50:07.148546 systemd[1]: Reached target swap.target - Swaps. Nov 5 15:50:07.148556 systemd[1]: Reached target timers.target - Timer Units. Nov 5 15:50:07.148566 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Nov 5 15:50:07.148577 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 5 15:50:07.148587 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Nov 5 15:50:07.148600 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Nov 5 15:50:07.148611 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 5 15:50:07.148621 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 5 15:50:07.148631 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 5 15:50:07.148641 systemd[1]: Reached target sockets.target - Socket Units. Nov 5 15:50:07.148652 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Nov 5 15:50:07.148662 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Nov 5 15:50:07.148682 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 5 15:50:07.148693 systemd[1]: Finished network-cleanup.service - Network Cleanup. Nov 5 15:50:07.148703 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). Nov 5 15:50:07.148714 systemd[1]: Starting systemd-fsck-usr.service... Nov 5 15:50:07.148724 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 5 15:50:07.148734 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 5 15:50:07.148747 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 5 15:50:07.148785 systemd-journald[559]: Collecting audit messages is disabled. Nov 5 15:50:07.148813 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Nov 5 15:50:07.148824 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 5 15:50:07.148836 systemd-journald[559]: Journal started Nov 5 15:50:07.148856 systemd-journald[559]: Runtime Journal (/run/log/journal/d165532b0bed4a928fe6feff10c3d1fc) is 8M, max 2.2G, 2.1G free. Nov 5 15:50:07.162019 systemd[1]: Started systemd-journald.service - Journal Service. Nov 5 15:50:07.165814 systemd[1]: Finished systemd-fsck-usr.service. Nov 5 15:50:07.169338 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 5 15:50:07.197864 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 5 15:50:07.341707 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Nov 5 15:50:07.347201 systemd-modules-load[562]: Inserted module 'br_netfilter' Nov 5 15:50:07.348935 kernel: Bridge firewalling registered Nov 5 15:50:07.348267 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 5 15:50:07.363127 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 5 15:50:07.419616 systemd-tmpfiles[570]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Nov 5 15:50:07.425946 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 5 15:50:07.428283 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 5 15:50:07.456909 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 5 15:50:07.459144 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 5 15:50:07.473634 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 5 15:50:07.482588 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 5 15:50:07.485510 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 5 15:50:07.502219 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 5 15:50:07.520561 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 5 15:50:07.524457 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Nov 5 15:50:07.630274 dracut-cmdline[603]: 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=c2a05564bcb92d35bbb2f0ae32fe5ddfa8424368122998dedda8bd375a237cb4 Nov 5 15:50:07.656400 systemd-resolved[587]: Positive Trust Anchors: Nov 5 15:50:07.656413 systemd-resolved[587]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 5 15:50:07.656417 systemd-resolved[587]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Nov 5 15:50:07.656448 systemd-resolved[587]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Nov 5 15:50:07.672787 systemd-resolved[587]: Defaulting to hostname 'linux'. Nov 5 15:50:07.673852 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 5 15:50:07.701900 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 5 15:50:07.869759 kernel: Loading iSCSI transport class v2.0-870. Nov 5 15:50:07.965697 kernel: iscsi: registered transport (tcp) Nov 5 15:50:08.040743 kernel: iscsi: registered transport (qla4xxx) Nov 5 15:50:08.040789 kernel: QLogic iSCSI HBA Driver Nov 5 15:50:08.133375 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 5 15:50:08.161249 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 5 15:50:08.164377 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 5 15:50:08.220352 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Nov 5 15:50:08.223071 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Nov 5 15:50:08.237303 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Nov 5 15:50:08.278524 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Nov 5 15:50:08.283270 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 5 15:50:08.323308 systemd-udevd[838]: Using default interface naming scheme 'v257'. Nov 5 15:50:08.334899 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 5 15:50:08.337273 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Nov 5 15:50:08.366982 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 5 15:50:08.370144 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 5 15:50:08.389974 dracut-pre-trigger[901]: rd.md=0: removing MD RAID activation Nov 5 15:50:08.413749 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Nov 5 15:50:08.415784 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 5 15:50:08.419820 systemd-networkd[941]: lo: Link UP Nov 5 15:50:08.419824 systemd-networkd[941]: lo: Gained carrier Nov 5 15:50:08.423961 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 5 15:50:08.437491 systemd[1]: Reached target network.target - Network. Nov 5 15:50:08.510408 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 5 15:50:08.520325 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Nov 5 15:50:08.579311 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 5 15:50:08.579904 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 5 15:50:08.587876 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Nov 5 15:50:08.590152 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 5 15:50:08.628693 kernel: hv_vmbus: registering driver hv_netvsc Nov 5 15:50:08.655525 kernel: cryptd: max_cpu_qlen set to 1000 Nov 5 15:50:08.664112 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 5 15:50:08.704688 kernel: AES CTR mode by8 optimization enabled Nov 5 15:50:08.961010 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Nov 5 15:50:08.972103 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Nov 5 15:50:09.076449 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Nov 5 15:50:09.105249 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Nov 5 15:50:09.153339 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Nov 5 15:50:10.365868 disk-uuid[1125]: Warning: The kernel is still using the old partition table. Nov 5 15:50:10.365868 disk-uuid[1125]: The new table will be used at the next reboot or after you Nov 5 15:50:10.365868 disk-uuid[1125]: run partprobe(8) or kpartx(8) Nov 5 15:50:10.365868 disk-uuid[1125]: The operation has completed successfully. Nov 5 15:50:11.880426 systemd-networkd[941]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 5 15:50:11.880440 systemd-networkd[941]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 5 15:50:11.881350 systemd-networkd[941]: eth0: Link UP Nov 5 15:50:11.881519 systemd-networkd[941]: eth0: Gained carrier Nov 5 15:50:11.881531 systemd-networkd[941]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 5 15:50:11.910421 systemd[1]: disk-uuid.service: Deactivated successfully. Nov 5 15:50:11.910535 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Nov 5 15:50:11.924887 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Nov 5 15:50:11.927851 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Nov 5 15:50:11.933727 systemd-networkd[941]: eth0: DHCPv4 address 10.200.8.31/24, gateway 10.200.8.1 acquired from 168.63.129.16 Nov 5 15:50:11.935690 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 5 15:50:11.949984 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 5 15:50:11.959686 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Nov 5 15:50:11.967917 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Nov 5 15:50:12.016783 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Nov 5 15:50:12.035723 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1278) Nov 5 15:50:12.044789 kernel: BTRFS info (device sda6): first mount of filesystem fa887730-d07b-4714-9f34-65e9489ec2e4 Nov 5 15:50:12.044823 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Nov 5 15:50:12.068487 kernel: BTRFS info (device sda6): turning on async discard Nov 5 15:50:12.068520 kernel: BTRFS info (device sda6): enabling free space tree Nov 5 15:50:12.078685 kernel: BTRFS info (device sda6): last unmount of filesystem fa887730-d07b-4714-9f34-65e9489ec2e4 Nov 5 15:50:12.079105 systemd[1]: Finished ignition-setup.service - Ignition (setup). Nov 5 15:50:12.082500 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Nov 5 15:50:12.946816 systemd-networkd[941]: eth0: Gained IPv6LL Nov 5 15:50:13.210930 ignition[1298]: Ignition 2.22.0 Nov 5 15:50:13.210945 ignition[1298]: Stage: fetch-offline Nov 5 15:50:13.211201 ignition[1298]: no configs at "/usr/lib/ignition/base.d" Nov 5 15:50:13.211228 ignition[1298]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 5 15:50:13.211332 ignition[1298]: parsed url from cmdline: "" Nov 5 15:50:13.211337 ignition[1298]: no config URL provided Nov 5 15:50:13.211342 ignition[1298]: reading system config file "/usr/lib/ignition/user.ign" Nov 5 15:50:13.211354 ignition[1298]: no config at "/usr/lib/ignition/user.ign" Nov 5 15:50:13.211359 ignition[1298]: failed to fetch config: resource requires networking Nov 5 15:50:13.211521 ignition[1298]: Ignition finished successfully Nov 5 15:50:13.242856 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Nov 5 15:50:13.252329 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Nov 5 15:50:13.298300 ignition[1308]: Ignition 2.22.0 Nov 5 15:50:13.298312 ignition[1308]: Stage: fetch Nov 5 15:50:13.298550 ignition[1308]: no configs at "/usr/lib/ignition/base.d" Nov 5 15:50:13.298560 ignition[1308]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 5 15:50:13.298666 ignition[1308]: parsed url from cmdline: "" Nov 5 15:50:13.298683 ignition[1308]: no config URL provided Nov 5 15:50:13.298690 ignition[1308]: reading system config file "/usr/lib/ignition/user.ign" Nov 5 15:50:13.298699 ignition[1308]: no config at "/usr/lib/ignition/user.ign" Nov 5 15:50:13.298718 ignition[1308]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Nov 5 15:50:13.407771 ignition[1308]: GET result: OK Nov 5 15:50:13.407859 ignition[1308]: config has been read from IMDS userdata Nov 5 15:50:13.407873 ignition[1308]: parsing config with SHA512: 4cb74b98e51627e45ac906bbcc32fbb125383a31d8bb2904c5992a0a125092aa8cc36de765ef3499567e49b96bdf9febce3cbd81439fdb83da94cf9fa018f22a Nov 5 15:50:13.417003 unknown[1308]: fetched base config from "system" Nov 5 15:50:13.417013 unknown[1308]: fetched base config from "system" Nov 5 15:50:13.417019 unknown[1308]: fetched user config from "azure" Nov 5 15:50:13.420299 ignition[1308]: fetch: fetch complete Nov 5 15:50:13.420304 ignition[1308]: fetch: fetch passed Nov 5 15:50:13.423436 ignition[1308]: Ignition finished successfully Nov 5 15:50:13.436761 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Nov 5 15:50:13.444808 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Nov 5 15:50:13.487738 ignition[1318]: Ignition 2.22.0 Nov 5 15:50:13.487749 ignition[1318]: Stage: kargs Nov 5 15:50:13.488136 ignition[1318]: no configs at "/usr/lib/ignition/base.d" Nov 5 15:50:13.488185 ignition[1318]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 5 15:50:13.489718 ignition[1318]: kargs: kargs passed Nov 5 15:50:13.496909 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Nov 5 15:50:13.489865 ignition[1318]: Ignition finished successfully Nov 5 15:50:13.507214 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Nov 5 15:50:13.553838 ignition[1328]: Ignition 2.22.0 Nov 5 15:50:13.553851 ignition[1328]: Stage: disks Nov 5 15:50:13.554113 ignition[1328]: no configs at "/usr/lib/ignition/base.d" Nov 5 15:50:13.554124 ignition[1328]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 5 15:50:13.554878 ignition[1328]: disks: disks passed Nov 5 15:50:13.563373 systemd[1]: Finished ignition-disks.service - Ignition (disks). Nov 5 15:50:13.554922 ignition[1328]: Ignition finished successfully Nov 5 15:50:13.568665 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Nov 5 15:50:13.574898 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Nov 5 15:50:13.582718 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 5 15:50:13.594140 systemd[1]: Reached target sysinit.target - System Initialization. Nov 5 15:50:13.602619 systemd[1]: Reached target basic.target - Basic System. Nov 5 15:50:13.611978 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Nov 5 15:50:13.762665 systemd-fsck[1340]: ROOT: clean, 15/6361680 files, 408771/6359552 blocks Nov 5 15:50:13.770097 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Nov 5 15:50:13.772582 systemd[1]: Mounting sysroot.mount - /sysroot... Nov 5 15:50:14.192694 kernel: EXT4-fs (sda9): mounted filesystem f3db699e-c9e0-4f6b-8c2b-aa40a78cd116 r/w with ordered data mode. Quota mode: none. Nov 5 15:50:14.193814 systemd[1]: Mounted sysroot.mount - /sysroot. Nov 5 15:50:14.195884 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Nov 5 15:50:14.222628 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 5 15:50:14.229634 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Nov 5 15:50:14.248279 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Nov 5 15:50:14.249843 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Nov 5 15:50:14.269716 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1354) Nov 5 15:50:14.269741 kernel: BTRFS info (device sda6): first mount of filesystem fa887730-d07b-4714-9f34-65e9489ec2e4 Nov 5 15:50:14.269755 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Nov 5 15:50:14.249877 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Nov 5 15:50:14.298201 kernel: BTRFS info (device sda6): turning on async discard Nov 5 15:50:14.298220 kernel: BTRFS info (device sda6): enabling free space tree Nov 5 15:50:14.265616 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Nov 5 15:50:14.275649 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Nov 5 15:50:14.300836 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 5 15:50:14.976830 coreos-metadata[1356]: Nov 05 15:50:14.976 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Nov 5 15:50:14.982925 coreos-metadata[1356]: Nov 05 15:50:14.979 INFO Fetch successful Nov 5 15:50:14.982925 coreos-metadata[1356]: Nov 05 15:50:14.979 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Nov 5 15:50:14.994869 coreos-metadata[1356]: Nov 05 15:50:14.986 INFO Fetch successful Nov 5 15:50:15.000512 coreos-metadata[1356]: Nov 05 15:50:15.000 INFO wrote hostname ci-4487.0.1-a-f76c371356 to /sysroot/etc/hostname Nov 5 15:50:15.007659 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Nov 5 15:50:15.328715 initrd-setup-root[1384]: cut: /sysroot/etc/passwd: No such file or directory Nov 5 15:50:15.367203 initrd-setup-root[1391]: cut: /sysroot/etc/group: No such file or directory Nov 5 15:50:15.399695 initrd-setup-root[1398]: cut: /sysroot/etc/shadow: No such file or directory Nov 5 15:50:15.420068 initrd-setup-root[1405]: cut: /sysroot/etc/gshadow: No such file or directory Nov 5 15:50:16.440823 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Nov 5 15:50:16.450666 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Nov 5 15:50:16.458785 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Nov 5 15:50:16.514444 systemd[1]: sysroot-oem.mount: Deactivated successfully. Nov 5 15:50:16.523385 kernel: BTRFS info (device sda6): last unmount of filesystem fa887730-d07b-4714-9f34-65e9489ec2e4 Nov 5 15:50:16.543784 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Nov 5 15:50:16.562921 ignition[1474]: INFO : Ignition 2.22.0 Nov 5 15:50:16.562921 ignition[1474]: INFO : Stage: mount Nov 5 15:50:16.568802 ignition[1474]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 5 15:50:16.568802 ignition[1474]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 5 15:50:16.568802 ignition[1474]: INFO : mount: mount passed Nov 5 15:50:16.568802 ignition[1474]: INFO : Ignition finished successfully Nov 5 15:50:16.570047 systemd[1]: Finished ignition-mount.service - Ignition (mount). Nov 5 15:50:16.579280 systemd[1]: Starting ignition-files.service - Ignition (files)... Nov 5 15:50:16.620241 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 5 15:50:16.648049 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1488) Nov 5 15:50:16.648084 kernel: BTRFS info (device sda6): first mount of filesystem fa887730-d07b-4714-9f34-65e9489ec2e4 Nov 5 15:50:16.652233 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Nov 5 15:50:16.665118 kernel: BTRFS info (device sda6): turning on async discard Nov 5 15:50:16.665154 kernel: BTRFS info (device sda6): enabling free space tree Nov 5 15:50:16.667328 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 5 15:50:16.709376 ignition[1505]: INFO : Ignition 2.22.0 Nov 5 15:50:16.709376 ignition[1505]: INFO : Stage: files Nov 5 15:50:16.715729 ignition[1505]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 5 15:50:16.715729 ignition[1505]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 5 15:50:16.715729 ignition[1505]: DEBUG : files: compiled without relabeling support, skipping Nov 5 15:50:16.730759 ignition[1505]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Nov 5 15:50:16.730759 ignition[1505]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Nov 5 15:50:16.816500 ignition[1505]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Nov 5 15:50:16.822261 ignition[1505]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Nov 5 15:50:16.827459 ignition[1505]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Nov 5 15:50:16.824151 unknown[1505]: wrote ssh authorized keys file for user: core Nov 5 15:50:16.863597 ignition[1505]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Nov 5 15:50:16.870936 ignition[1505]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Nov 5 15:50:16.870936 ignition[1505]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Nov 5 15:50:16.870936 ignition[1505]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Nov 5 15:50:16.870936 ignition[1505]: INFO : files: files passed Nov 5 15:50:16.870936 ignition[1505]: INFO : Ignition finished successfully Nov 5 15:50:16.886842 systemd[1]: Finished ignition-files.service - Ignition (files). Nov 5 15:50:16.894827 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Nov 5 15:50:16.921266 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Nov 5 15:50:16.924538 systemd[1]: ignition-quench.service: Deactivated successfully. Nov 5 15:50:16.924645 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Nov 5 15:50:16.969054 initrd-setup-root-after-ignition[1540]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 5 15:50:16.969054 initrd-setup-root-after-ignition[1540]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Nov 5 15:50:16.977107 initrd-setup-root-after-ignition[1544]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 5 15:50:16.976641 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 5 15:50:16.987312 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Nov 5 15:50:16.990688 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Nov 5 15:50:17.045952 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Nov 5 15:50:17.046068 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Nov 5 15:50:17.047628 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Nov 5 15:50:17.055583 systemd[1]: Reached target initrd.target - Initrd Default Target. Nov 5 15:50:17.070191 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Nov 5 15:50:17.071096 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Nov 5 15:50:17.097658 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 5 15:50:17.108320 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Nov 5 15:50:17.142571 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Nov 5 15:50:17.142737 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Nov 5 15:50:17.152026 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 5 15:50:17.161705 systemd[1]: Stopped target timers.target - Timer Units. Nov 5 15:50:17.169925 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Nov 5 15:50:17.170066 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 5 15:50:17.181682 systemd[1]: Stopped target initrd.target - Initrd Default Target. Nov 5 15:50:17.190246 systemd[1]: Stopped target basic.target - Basic System. Nov 5 15:50:17.197579 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Nov 5 15:50:17.205184 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Nov 5 15:50:17.213951 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Nov 5 15:50:17.222735 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Nov 5 15:50:17.231427 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Nov 5 15:50:17.239764 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Nov 5 15:50:17.248522 systemd[1]: Stopped target sysinit.target - System Initialization. Nov 5 15:50:17.257397 systemd[1]: Stopped target local-fs.target - Local File Systems. Nov 5 15:50:17.265186 systemd[1]: Stopped target swap.target - Swaps. Nov 5 15:50:17.272129 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Nov 5 15:50:17.272343 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Nov 5 15:50:17.283101 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Nov 5 15:50:17.291312 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 5 15:50:17.300131 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Nov 5 15:50:17.300265 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 5 15:50:17.302297 systemd[1]: dracut-initqueue.service: Deactivated successfully. Nov 5 15:50:17.302419 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Nov 5 15:50:17.322743 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Nov 5 15:50:17.322874 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 5 15:50:17.331277 systemd[1]: ignition-files.service: Deactivated successfully. Nov 5 15:50:17.331385 systemd[1]: Stopped ignition-files.service - Ignition (files). Nov 5 15:50:17.339212 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Nov 5 15:50:17.339339 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Nov 5 15:50:17.349347 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Nov 5 15:50:17.399398 ignition[1564]: INFO : Ignition 2.22.0 Nov 5 15:50:17.399398 ignition[1564]: INFO : Stage: umount Nov 5 15:50:17.399398 ignition[1564]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 5 15:50:17.399398 ignition[1564]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 5 15:50:17.399398 ignition[1564]: INFO : umount: umount passed Nov 5 15:50:17.399398 ignition[1564]: INFO : Ignition finished successfully Nov 5 15:50:17.361001 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Nov 5 15:50:17.367114 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Nov 5 15:50:17.367253 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 5 15:50:17.380489 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Nov 5 15:50:17.380620 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Nov 5 15:50:17.389005 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Nov 5 15:50:17.389122 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Nov 5 15:50:17.403330 systemd[1]: ignition-mount.service: Deactivated successfully. Nov 5 15:50:17.403431 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Nov 5 15:50:17.407447 systemd[1]: ignition-disks.service: Deactivated successfully. Nov 5 15:50:17.407565 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Nov 5 15:50:17.415512 systemd[1]: ignition-kargs.service: Deactivated successfully. Nov 5 15:50:17.415561 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Nov 5 15:50:17.423409 systemd[1]: ignition-fetch.service: Deactivated successfully. Nov 5 15:50:17.423457 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Nov 5 15:50:17.432268 systemd[1]: Stopped target network.target - Network. Nov 5 15:50:17.438863 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Nov 5 15:50:17.438921 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Nov 5 15:50:17.447090 systemd[1]: Stopped target paths.target - Path Units. Nov 5 15:50:17.452514 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Nov 5 15:50:17.455734 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 5 15:50:17.459591 systemd[1]: Stopped target slices.target - Slice Units. Nov 5 15:50:17.469598 systemd[1]: Stopped target sockets.target - Socket Units. Nov 5 15:50:17.477175 systemd[1]: iscsid.socket: Deactivated successfully. Nov 5 15:50:17.477226 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Nov 5 15:50:17.484621 systemd[1]: iscsiuio.socket: Deactivated successfully. Nov 5 15:50:17.484681 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 5 15:50:17.495728 systemd[1]: ignition-setup.service: Deactivated successfully. Nov 5 15:50:17.495841 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Nov 5 15:50:17.503726 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Nov 5 15:50:17.503775 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Nov 5 15:50:17.511688 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Nov 5 15:50:17.519188 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Nov 5 15:50:17.527872 systemd[1]: sysroot-boot.mount: Deactivated successfully. Nov 5 15:50:17.528668 systemd[1]: initrd-cleanup.service: Deactivated successfully. Nov 5 15:50:17.528780 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Nov 5 15:50:17.532107 systemd[1]: sysroot-boot.service: Deactivated successfully. Nov 5 15:50:17.532202 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Nov 5 15:50:17.539966 systemd[1]: systemd-resolved.service: Deactivated successfully. Nov 5 15:50:17.540065 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Nov 5 15:50:17.554763 systemd[1]: systemd-networkd.service: Deactivated successfully. Nov 5 15:50:17.554870 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Nov 5 15:50:17.567601 systemd[1]: Stopped target network-pre.target - Preparation for Network. Nov 5 15:50:17.578354 systemd[1]: systemd-networkd.socket: Deactivated successfully. Nov 5 15:50:17.578414 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Nov 5 15:50:17.586049 systemd[1]: initrd-setup-root.service: Deactivated successfully. Nov 5 15:50:17.586106 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Nov 5 15:50:17.595287 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Nov 5 15:50:17.603791 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Nov 5 15:50:17.603848 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 5 15:50:17.606019 systemd[1]: systemd-sysctl.service: Deactivated successfully. Nov 5 15:50:17.606071 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Nov 5 15:50:17.616859 systemd[1]: systemd-modules-load.service: Deactivated successfully. Nov 5 15:50:17.616909 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Nov 5 15:50:17.623610 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 5 15:50:17.672431 systemd[1]: systemd-udevd.service: Deactivated successfully. Nov 5 15:50:17.672573 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 5 15:50:17.676370 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Nov 5 15:50:17.676467 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Nov 5 15:50:17.687599 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Nov 5 15:50:17.687640 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Nov 5 15:50:17.698822 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Nov 5 15:50:17.698880 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Nov 5 15:50:17.709175 systemd[1]: dracut-cmdline.service: Deactivated successfully. Nov 5 15:50:17.709231 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Nov 5 15:50:17.722577 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 5 15:50:17.722629 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 5 15:50:17.736772 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Nov 5 15:50:17.743746 systemd[1]: systemd-network-generator.service: Deactivated successfully. Nov 5 15:50:17.743802 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Nov 5 15:50:17.753001 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Nov 5 15:50:17.753054 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 5 15:50:17.761559 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Nov 5 15:50:17.761605 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 5 15:50:17.770586 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Nov 5 15:50:17.770633 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Nov 5 15:50:17.779236 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 5 15:50:17.779284 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 5 15:50:17.788877 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Nov 5 15:50:17.788969 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Nov 5 15:50:17.829367 systemd[1]: network-cleanup.service: Deactivated successfully. Nov 5 15:50:17.829799 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Nov 5 15:50:17.833036 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Nov 5 15:50:17.841416 systemd[1]: Starting initrd-switch-root.service - Switch Root... Nov 5 15:50:17.881457 systemd[1]: Switching root. Nov 5 15:50:18.016002 systemd-journald[559]: Journal stopped Nov 5 15:50:23.007834 systemd-journald[559]: Received SIGTERM from PID 1 (systemd). Nov 5 15:50:23.007870 kernel: SELinux: policy capability network_peer_controls=1 Nov 5 15:50:23.007884 kernel: SELinux: policy capability open_perms=1 Nov 5 15:50:23.007893 kernel: SELinux: policy capability extended_socket_class=1 Nov 5 15:50:23.007907 kernel: SELinux: policy capability always_check_network=0 Nov 5 15:50:23.007917 kernel: SELinux: policy capability cgroup_seclabel=1 Nov 5 15:50:23.007930 kernel: SELinux: policy capability nnp_nosuid_transition=1 Nov 5 15:50:23.007941 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Nov 5 15:50:23.007950 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Nov 5 15:50:23.007960 kernel: SELinux: policy capability userspace_initial_context=0 Nov 5 15:50:23.007971 kernel: audit: type=1403 audit(1762357818.916:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Nov 5 15:50:23.007982 systemd[1]: Successfully loaded SELinux policy in 241.170ms. Nov 5 15:50:23.007997 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 7.887ms. Nov 5 15:50:23.008009 systemd[1]: systemd 257.7 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +IPE +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 -BTF -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Nov 5 15:50:23.008021 systemd[1]: Detected virtualization microsoft. Nov 5 15:50:23.008032 systemd[1]: Detected architecture x86-64. Nov 5 15:50:23.008045 systemd[1]: Detected first boot. Nov 5 15:50:23.008057 systemd[1]: Hostname set to . Nov 5 15:50:23.008068 systemd[1]: Initializing machine ID from random generator. Nov 5 15:50:23.008079 zram_generator::config[1611]: No configuration found. Nov 5 15:50:23.008090 kernel: Guest personality initialized and is inactive Nov 5 15:50:23.008103 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Nov 5 15:50:23.008114 kernel: Initialized host personality Nov 5 15:50:23.008124 kernel: NET: Registered PF_VSOCK protocol family Nov 5 15:50:23.008135 systemd[1]: Populated /etc with preset unit settings. Nov 5 15:50:23.008150 systemd[1]: initrd-switch-root.service: Deactivated successfully. Nov 5 15:50:23.008161 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Nov 5 15:50:23.008172 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Nov 5 15:50:23.008186 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Nov 5 15:50:23.008197 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Nov 5 15:50:23.008209 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Nov 5 15:50:23.008220 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Nov 5 15:50:23.008231 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Nov 5 15:50:23.008245 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Nov 5 15:50:23.008257 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Nov 5 15:50:23.008268 systemd[1]: Created slice user.slice - User and Session Slice. Nov 5 15:50:23.008280 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 5 15:50:23.008291 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 5 15:50:23.008302 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Nov 5 15:50:23.008314 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Nov 5 15:50:23.008329 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Nov 5 15:50:23.008344 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 5 15:50:23.008356 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Nov 5 15:50:23.008367 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 5 15:50:23.008379 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 5 15:50:23.008390 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Nov 5 15:50:23.008407 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Nov 5 15:50:23.008419 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Nov 5 15:50:23.008431 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Nov 5 15:50:23.008442 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 5 15:50:23.008453 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 5 15:50:23.008465 systemd[1]: Reached target slices.target - Slice Units. Nov 5 15:50:23.008476 systemd[1]: Reached target swap.target - Swaps. Nov 5 15:50:23.008821 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Nov 5 15:50:23.008841 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Nov 5 15:50:23.008854 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Nov 5 15:50:23.008866 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 5 15:50:23.008883 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 5 15:50:23.008895 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 5 15:50:23.008907 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Nov 5 15:50:23.008918 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Nov 5 15:50:23.008930 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Nov 5 15:50:23.008942 systemd[1]: Mounting media.mount - External Media Directory... Nov 5 15:50:23.008957 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 5 15:50:23.008969 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Nov 5 15:50:23.008980 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Nov 5 15:50:23.008992 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Nov 5 15:50:23.009008 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Nov 5 15:50:23.009020 systemd[1]: Reached target machines.target - Containers. Nov 5 15:50:23.009032 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Nov 5 15:50:23.009047 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 5 15:50:23.009059 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 5 15:50:23.009071 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Nov 5 15:50:23.009083 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 5 15:50:23.009095 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 5 15:50:23.009107 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 5 15:50:23.009121 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Nov 5 15:50:23.009133 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 5 15:50:23.009145 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Nov 5 15:50:23.009157 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Nov 5 15:50:23.009169 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Nov 5 15:50:23.009181 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Nov 5 15:50:23.009193 systemd[1]: Stopped systemd-fsck-usr.service. Nov 5 15:50:23.009207 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Nov 5 15:50:23.009219 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 5 15:50:23.009231 kernel: fuse: init (API version 7.41) Nov 5 15:50:23.009242 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 5 15:50:23.009254 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 5 15:50:23.009270 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Nov 5 15:50:23.009282 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Nov 5 15:50:23.009297 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 5 15:50:23.009309 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 5 15:50:23.009346 systemd-journald[1708]: Collecting audit messages is disabled. Nov 5 15:50:23.009375 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Nov 5 15:50:23.009389 systemd-journald[1708]: Journal started Nov 5 15:50:23.009411 systemd-journald[1708]: Runtime Journal (/run/log/journal/208c6e1d7e2d44c68fd90c19c4136acf) is 8M, max 2.2G, 2.1G free. Nov 5 15:50:22.256808 systemd[1]: Queued start job for default target multi-user.target. Nov 5 15:50:22.281021 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Nov 5 15:50:22.281520 systemd[1]: systemd-journald.service: Deactivated successfully. Nov 5 15:50:23.016903 systemd[1]: Started systemd-journald.service - Journal Service. Nov 5 15:50:23.021392 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Nov 5 15:50:23.026046 systemd[1]: Mounted media.mount - External Media Directory. Nov 5 15:50:23.030026 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Nov 5 15:50:23.034422 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Nov 5 15:50:23.038911 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Nov 5 15:50:23.043308 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Nov 5 15:50:23.049712 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 5 15:50:23.055031 systemd[1]: modprobe@configfs.service: Deactivated successfully. Nov 5 15:50:23.055221 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Nov 5 15:50:23.062212 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 5 15:50:23.062404 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 5 15:50:23.067435 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 5 15:50:23.067624 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 5 15:50:23.073245 systemd[1]: modprobe@fuse.service: Deactivated successfully. Nov 5 15:50:23.073446 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Nov 5 15:50:23.078139 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 5 15:50:23.078326 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 5 15:50:23.083049 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 5 15:50:23.087993 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 5 15:50:23.094212 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Nov 5 15:50:23.099679 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Nov 5 15:50:23.113936 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 5 15:50:23.119050 systemd[1]: Listening on systemd-importd.socket - Disk Image Download Service Socket. Nov 5 15:50:23.125099 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Nov 5 15:50:23.158003 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Nov 5 15:50:23.162476 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Nov 5 15:50:23.162517 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 5 15:50:23.167758 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Nov 5 15:50:23.172919 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 5 15:50:23.189491 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Nov 5 15:50:23.209687 kernel: ACPI: bus type drm_connector registered Nov 5 15:50:23.213930 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Nov 5 15:50:23.218421 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 5 15:50:23.219421 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Nov 5 15:50:23.226197 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 5 15:50:23.227164 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 5 15:50:23.232248 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Nov 5 15:50:23.238372 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 5 15:50:23.240503 systemd-journald[1708]: Time spent on flushing to /var/log/journal/208c6e1d7e2d44c68fd90c19c4136acf is 13.861ms for 925 entries. Nov 5 15:50:23.240503 systemd-journald[1708]: System Journal (/var/log/journal/208c6e1d7e2d44c68fd90c19c4136acf) is 8M, max 2.2G, 2.2G free. Nov 5 15:50:23.282315 systemd-journald[1708]: Received client request to flush runtime journal. Nov 5 15:50:23.248857 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 5 15:50:23.249052 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 5 15:50:23.253682 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 5 15:50:23.259275 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Nov 5 15:50:23.264865 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Nov 5 15:50:23.272443 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Nov 5 15:50:23.277396 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Nov 5 15:50:23.283255 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Nov 5 15:50:23.290692 kernel: loop1: detected capacity change from 0 to 27752 Nov 5 15:50:23.290906 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Nov 5 15:50:23.347149 systemd-tmpfiles[1754]: ACLs are not supported, ignoring. Nov 5 15:50:23.347172 systemd-tmpfiles[1754]: ACLs are not supported, ignoring. Nov 5 15:50:23.351375 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 5 15:50:23.359221 systemd[1]: Starting systemd-sysusers.service - Create System Users... Nov 5 15:50:23.379034 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Nov 5 15:50:23.380420 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Nov 5 15:50:23.455163 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 5 15:50:23.541080 systemd[1]: Finished systemd-sysusers.service - Create System Users. Nov 5 15:50:23.547330 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 5 15:50:23.552331 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 5 15:50:23.585260 systemd-tmpfiles[1772]: ACLs are not supported, ignoring. Nov 5 15:50:23.585282 systemd-tmpfiles[1772]: ACLs are not supported, ignoring. Nov 5 15:50:23.588729 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 5 15:50:23.627206 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Nov 5 15:50:23.693641 systemd[1]: Started systemd-userdbd.service - User Database Manager. Nov 5 15:50:23.847697 kernel: loop2: detected capacity change from 0 to 128048 Nov 5 15:50:23.871928 systemd-resolved[1771]: Positive Trust Anchors: Nov 5 15:50:23.871945 systemd-resolved[1771]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 5 15:50:23.871950 systemd-resolved[1771]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Nov 5 15:50:23.871985 systemd-resolved[1771]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Nov 5 15:50:24.029160 systemd-resolved[1771]: Using system hostname 'ci-4487.0.1-a-f76c371356'. Nov 5 15:50:24.030601 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 5 15:50:24.035133 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 5 15:50:24.127247 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Nov 5 15:50:24.134627 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 5 15:50:24.172277 systemd-udevd[1784]: Using default interface naming scheme 'v257'. Nov 5 15:50:24.443698 kernel: loop3: detected capacity change from 0 to 110984 Nov 5 15:50:24.514111 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 5 15:50:24.522050 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 5 15:50:24.539181 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Nov 5 15:50:24.604699 kernel: hv_vmbus: registering driver hv_balloon Nov 5 15:50:24.604787 kernel: hv_vmbus: registering driver hyperv_fb Nov 5 15:50:24.604928 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Nov 5 15:50:24.610736 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Nov 5 15:50:24.621389 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Nov 5 15:50:24.621437 kernel: Console: switching to colour dummy device 80x25 Nov 5 15:50:24.626368 kernel: Console: switching to colour frame buffer device 128x48 Nov 5 15:50:24.682686 kernel: mousedev: PS/2 mouse device common for all mice Nov 5 15:50:24.685689 kernel: EDAC MC: Ver: 3.0.0 Nov 5 15:50:24.699377 systemd-networkd[1799]: lo: Link UP Nov 5 15:50:24.699390 systemd-networkd[1799]: lo: Gained carrier Nov 5 15:50:24.699876 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 5 15:50:24.701170 systemd-networkd[1799]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 5 15:50:24.701181 systemd-networkd[1799]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 5 15:50:24.702003 systemd-networkd[1799]: eth0: Link UP Nov 5 15:50:24.702106 systemd-networkd[1799]: eth0: Gained carrier Nov 5 15:50:24.702175 systemd-networkd[1799]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 5 15:50:24.704540 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 5 15:50:24.712654 systemd[1]: Reached target network.target - Network. Nov 5 15:50:24.717426 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Nov 5 15:50:24.723381 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Nov 5 15:50:24.731539 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 5 15:50:24.740179 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 5 15:50:24.749354 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 5 15:50:24.768732 systemd-networkd[1799]: eth0: DHCPv4 address 10.200.8.31/24, gateway 10.200.8.1 acquired from 168.63.129.16 Nov 5 15:50:24.818094 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Nov 5 15:50:24.833694 kernel: loop4: detected capacity change from 0 to 27752 Nov 5 15:50:24.847693 kernel: loop5: detected capacity change from 0 to 128048 Nov 5 15:50:24.864690 kernel: loop6: detected capacity change from 0 to 110984 Nov 5 15:50:24.877119 (sd-merge)[1864]: Using extensions 'containerd-flatcar.raw', 'docker-flatcar.raw', 'oem-azure.raw'. Nov 5 15:50:24.880572 (sd-merge)[1864]: Merged extensions into '/usr'. Nov 5 15:50:24.884445 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Nov 5 15:50:24.891317 systemd[1]: Starting ensure-sysext.service... Nov 5 15:50:24.895296 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 5 15:50:24.915654 systemd[1]: Reload requested from client PID 1866 ('systemctl') (unit ensure-sysext.service)... Nov 5 15:50:24.915694 systemd[1]: Reloading... Nov 5 15:50:24.937549 systemd-tmpfiles[1867]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Nov 5 15:50:24.937598 systemd-tmpfiles[1867]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Nov 5 15:50:24.937956 systemd-tmpfiles[1867]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Nov 5 15:50:24.938245 systemd-tmpfiles[1867]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Nov 5 15:50:24.939159 systemd-tmpfiles[1867]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Nov 5 15:50:24.939428 systemd-tmpfiles[1867]: ACLs are not supported, ignoring. Nov 5 15:50:24.939531 systemd-tmpfiles[1867]: ACLs are not supported, ignoring. Nov 5 15:50:24.948157 systemd-tmpfiles[1867]: Detected autofs mount point /boot during canonicalization of boot. Nov 5 15:50:24.948172 systemd-tmpfiles[1867]: Skipping /boot Nov 5 15:50:24.962730 zram_generator::config[1914]: No configuration found. Nov 5 15:50:24.963096 systemd-tmpfiles[1867]: Detected autofs mount point /boot during canonicalization of boot. Nov 5 15:50:24.963228 systemd-tmpfiles[1867]: Skipping /boot Nov 5 15:50:25.242302 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Nov 5 15:50:25.247931 systemd[1]: Reloading finished in 331 ms. Nov 5 15:50:25.276212 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 5 15:50:25.313348 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 5 15:50:25.363700 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 5 15:50:25.365337 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 5 15:50:25.384036 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Nov 5 15:50:25.388801 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 5 15:50:25.390060 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Nov 5 15:50:25.395319 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 5 15:50:25.400462 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 5 15:50:25.405597 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 5 15:50:25.411113 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 5 15:50:25.415176 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 5 15:50:25.416287 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Nov 5 15:50:25.420855 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Nov 5 15:50:25.422023 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Nov 5 15:50:25.426585 systemd[1]: Reached target time-set.target - System Time Set. Nov 5 15:50:25.431837 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Nov 5 15:50:25.436544 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 5 15:50:25.440247 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 5 15:50:25.440454 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 5 15:50:25.445603 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 5 15:50:25.445810 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 5 15:50:25.448300 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 5 15:50:25.448760 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 5 15:50:25.450353 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 5 15:50:25.450925 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 5 15:50:25.461096 systemd[1]: Finished ensure-sysext.service. Nov 5 15:50:25.464666 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Nov 5 15:50:25.472641 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 5 15:50:25.472787 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 5 15:50:25.473800 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Nov 5 15:50:25.532414 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Nov 5 15:50:25.904532 augenrules[2066]: No rules Nov 5 15:50:25.905708 systemd[1]: audit-rules.service: Deactivated successfully. Nov 5 15:50:25.905957 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 5 15:50:26.130811 systemd-networkd[1799]: eth0: Gained IPv6LL Nov 5 15:50:26.133064 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Nov 5 15:50:26.138246 systemd[1]: Reached target network-online.target - Network is Online. Nov 5 15:50:26.408601 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Nov 5 15:50:26.413996 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Nov 5 15:50:36.587974 ldconfig[2029]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Nov 5 15:50:36.838289 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Nov 5 15:50:36.844946 systemd[1]: Starting systemd-update-done.service - Update is Completed... Nov 5 15:50:36.874826 systemd[1]: Finished systemd-update-done.service - Update is Completed. Nov 5 15:50:36.879595 systemd[1]: Reached target sysinit.target - System Initialization. Nov 5 15:50:36.883966 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Nov 5 15:50:36.889096 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Nov 5 15:50:36.894092 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Nov 5 15:50:36.898985 systemd[1]: Started logrotate.timer - Daily rotation of log files. Nov 5 15:50:36.903375 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Nov 5 15:50:36.908441 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Nov 5 15:50:36.913465 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Nov 5 15:50:36.913508 systemd[1]: Reached target paths.target - Path Units. Nov 5 15:50:36.917171 systemd[1]: Reached target timers.target - Timer Units. Nov 5 15:50:36.936145 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Nov 5 15:50:36.941652 systemd[1]: Starting docker.socket - Docker Socket for the API... Nov 5 15:50:36.947974 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Nov 5 15:50:36.953549 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Nov 5 15:50:36.958658 systemd[1]: Reached target ssh-access.target - SSH Access Available. Nov 5 15:50:36.964766 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Nov 5 15:50:36.969111 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Nov 5 15:50:36.974399 systemd[1]: Listening on docker.socket - Docker Socket for the API. Nov 5 15:50:36.979693 systemd[1]: Reached target sockets.target - Socket Units. Nov 5 15:50:36.983852 systemd[1]: Reached target basic.target - Basic System. Nov 5 15:50:36.987644 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Nov 5 15:50:36.987689 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Nov 5 15:50:36.990188 systemd[1]: Starting chronyd.service - NTP client/server... Nov 5 15:50:36.994581 systemd[1]: Starting containerd.service - containerd container runtime... Nov 5 15:50:37.008519 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Nov 5 15:50:37.013525 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Nov 5 15:50:37.020573 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Nov 5 15:50:37.047910 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Nov 5 15:50:37.052884 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Nov 5 15:50:37.063727 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Nov 5 15:50:37.064814 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Nov 5 15:50:37.068830 systemd[1]: hv_fcopy_uio_daemon.service - Hyper-V FCOPY UIO daemon was skipped because of an unmet condition check (ConditionPathExists=/sys/bus/vmbus/devices/eb765408-105f-49b6-b4aa-c123b64d17d4/uio). Nov 5 15:50:37.070014 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Nov 5 15:50:37.074329 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Nov 5 15:50:37.075430 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Nov 5 15:50:37.082815 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Nov 5 15:50:37.087990 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Nov 5 15:50:37.093769 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Nov 5 15:50:37.100564 systemd[1]: Starting systemd-logind.service - User Login Management... Nov 5 15:50:37.104485 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Nov 5 15:50:37.104913 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Nov 5 15:50:37.105536 systemd[1]: Starting update-engine.service - Update Engine... Nov 5 15:50:37.110590 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Nov 5 15:50:37.174864 KVP[2093]: KVP starting; pid is:2093 Nov 5 15:50:37.177442 chronyd[2079]: chronyd version 4.7 starting (+CMDMON +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +NTS +SECHASH +IPV6 -DEBUG) Nov 5 15:50:37.177651 KVP[2093]: KVP LIC Version: 3.1 Nov 5 15:50:37.178691 kernel: hv_utils: KVP IC version 4.0 Nov 5 15:50:37.233880 chronyd[2079]: Timezone right/UTC failed leap second check, ignoring Nov 5 15:50:37.234052 chronyd[2079]: Loaded seccomp filter (level 2) Nov 5 15:50:37.234194 systemd[1]: Started chronyd.service - NTP client/server. Nov 5 15:50:37.238370 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Nov 5 15:50:37.238612 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Nov 5 15:50:37.251179 jq[2106]: true Nov 5 15:50:37.251437 jq[2087]: false Nov 5 15:50:37.252071 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Nov 5 15:50:37.252366 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Nov 5 15:50:37.269830 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Nov 5 15:50:37.272688 jq[2115]: true Nov 5 15:50:37.281382 extend-filesystems[2088]: Found /dev/sda6 Nov 5 15:50:37.284290 systemd-logind[2100]: New seat seat0. Nov 5 15:50:37.285508 systemd-logind[2100]: Watching system buttons on /dev/input/event1 (AT Translated Set 2 keyboard) Nov 5 15:50:37.285746 systemd[1]: Started systemd-logind.service - User Login Management. Nov 5 15:50:37.299845 systemd[1]: motdgen.service: Deactivated successfully. Nov 5 15:50:37.300084 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Nov 5 15:50:37.308973 (ntainerd)[2142]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Nov 5 15:50:37.320544 google_oslogin_nss_cache[2089]: oslogin_cache_refresh[2089]: Refreshing passwd entry cache Nov 5 15:50:37.320553 oslogin_cache_refresh[2089]: Refreshing passwd entry cache Nov 5 15:50:37.331095 google_oslogin_nss_cache[2089]: oslogin_cache_refresh[2089]: Failure getting users, quitting Nov 5 15:50:37.331095 google_oslogin_nss_cache[2089]: oslogin_cache_refresh[2089]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Nov 5 15:50:37.331087 oslogin_cache_refresh[2089]: Failure getting users, quitting Nov 5 15:50:37.331214 google_oslogin_nss_cache[2089]: oslogin_cache_refresh[2089]: Refreshing group entry cache Nov 5 15:50:37.331104 oslogin_cache_refresh[2089]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Nov 5 15:50:37.331145 oslogin_cache_refresh[2089]: Refreshing group entry cache Nov 5 15:50:37.349237 google_oslogin_nss_cache[2089]: oslogin_cache_refresh[2089]: Failure getting groups, quitting Nov 5 15:50:37.349237 google_oslogin_nss_cache[2089]: oslogin_cache_refresh[2089]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Nov 5 15:50:37.349196 oslogin_cache_refresh[2089]: Failure getting groups, quitting Nov 5 15:50:37.349207 oslogin_cache_refresh[2089]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Nov 5 15:50:37.350508 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Nov 5 15:50:37.350760 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Nov 5 15:50:37.612576 update_engine[2102]: I20251105 15:50:37.612443 2102 main.cc:92] Flatcar Update Engine starting Nov 5 15:50:37.618455 extend-filesystems[2088]: Found /dev/sda9 Nov 5 15:50:37.744481 extend-filesystems[2088]: Checking size of /dev/sda9 Nov 5 15:50:38.143883 extend-filesystems[2088]: Resized partition /dev/sda9 Nov 5 15:50:38.189426 extend-filesystems[2162]: resize2fs 1.47.3 (8-Jul-2025) Nov 5 15:50:38.166839 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Nov 5 15:50:38.193471 sshd_keygen[2113]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Nov 5 15:50:38.172580 systemd[1]: Starting issuegen.service - Generate /run/issue... Nov 5 15:50:38.196292 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Nov 5 15:50:38.200662 systemd[1]: issuegen.service: Deactivated successfully. Nov 5 15:50:38.200878 systemd[1]: Finished issuegen.service - Generate /run/issue. Nov 5 15:50:38.208730 kernel: EXT4-fs (sda9): resizing filesystem from 6359552 to 6376955 blocks Nov 5 15:50:38.208856 kernel: EXT4-fs (sda9): resized filesystem to 6376955 Nov 5 15:50:38.215897 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Nov 5 15:50:38.216053 dbus-daemon[2082]: [system] SELinux support is enabled Nov 5 15:50:38.220470 systemd[1]: Started dbus.service - D-Bus System Message Bus. Nov 5 15:50:38.261195 update_engine[2102]: I20251105 15:50:38.222143 2102 update_check_scheduler.cc:74] Next update check in 7m21s Nov 5 15:50:38.232337 dbus-daemon[2082]: [system] Successfully activated service 'org.freedesktop.systemd1' Nov 5 15:50:38.231366 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Nov 5 15:50:38.231391 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Nov 5 15:50:38.236653 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Nov 5 15:50:38.236679 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Nov 5 15:50:38.242546 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Nov 5 15:50:38.247188 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Nov 5 15:50:38.252152 systemd[1]: Started update-engine.service - Update Engine. Nov 5 15:50:38.257846 systemd[1]: Started getty@tty1.service - Getty on tty1. Nov 5 15:50:38.262205 setup-nvidia[2095]: Downloading Flatcar Container Linux Developer Container for version: 4487.0.1 Nov 5 15:50:38.263010 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Nov 5 15:50:38.267607 systemd[1]: Reached target getty.target - Login Prompts. Nov 5 15:50:38.272704 systemd[1]: Started locksmithd.service - Cluster reboot manager. Nov 5 15:50:38.684350 bash[2139]: Updated "/home/core/.ssh/authorized_keys" Nov 5 15:50:38.686042 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Nov 5 15:50:38.691616 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Nov 5 15:50:39.068875 coreos-metadata[2081]: Nov 05 15:50:39.068 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Nov 5 15:50:39.071158 coreos-metadata[2081]: Nov 05 15:50:39.071 INFO Fetch successful Nov 5 15:50:39.071343 coreos-metadata[2081]: Nov 05 15:50:39.071 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Nov 5 15:50:39.075211 coreos-metadata[2081]: Nov 05 15:50:39.075 INFO Fetch successful Nov 5 15:50:39.075518 coreos-metadata[2081]: Nov 05 15:50:39.075 INFO Fetching http://168.63.129.16/machine/e6640e63-a468-40bd-83b6-76e070f56560/7b9d83a9%2D85fb%2D4df4%2Dbb56%2D22c0ddc102fa.%5Fci%2D4487.0.1%2Da%2Df76c371356?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Nov 5 15:50:39.108122 coreos-metadata[2081]: Nov 05 15:50:39.108 INFO Fetch successful Nov 5 15:50:39.108276 coreos-metadata[2081]: Nov 05 15:50:39.108 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Nov 5 15:50:39.115628 coreos-metadata[2081]: Nov 05 15:50:39.115 INFO Fetch successful Nov 5 15:50:39.148215 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Nov 5 15:50:39.153029 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Nov 5 15:50:39.592111 extend-filesystems[2162]: Filesystem at /dev/sda9 is mounted on /; on-line resizing required Nov 5 15:50:39.592111 extend-filesystems[2162]: old_desc_blocks = 4, new_desc_blocks = 4 Nov 5 15:50:39.592111 extend-filesystems[2162]: The filesystem on /dev/sda9 is now 6376955 (4k) blocks long. Nov 5 15:50:39.610746 extend-filesystems[2088]: Resized filesystem in /dev/sda9 Nov 5 15:50:39.593657 systemd[1]: extend-filesystems.service: Deactivated successfully. Nov 5 15:50:39.593938 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Nov 5 15:50:39.788447 setup-nvidia[2254]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 5 15:50:39.788755 setup-nvidia[2254]: Dload Upload Total Spent Left Speed Nov 5 15:50:40.038192 locksmithd[2244]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Nov 5 15:50:40.865089 containerd[2142]: time="2025-11-05T15:50:40Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Nov 5 15:50:40.865738 containerd[2142]: time="2025-11-05T15:50:40.865705200Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Nov 5 15:50:40.875084 containerd[2142]: time="2025-11-05T15:50:40.875050800Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="8.6µs" Nov 5 15:50:40.875084 containerd[2142]: time="2025-11-05T15:50:40.875079600Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Nov 5 15:50:40.875152 containerd[2142]: time="2025-11-05T15:50:40.875097500Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Nov 5 15:50:40.875388 containerd[2142]: time="2025-11-05T15:50:40.875344800Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Nov 5 15:50:40.875388 containerd[2142]: time="2025-11-05T15:50:40.875383500Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Nov 5 15:50:40.875481 containerd[2142]: time="2025-11-05T15:50:40.875413700Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Nov 5 15:50:40.875643 containerd[2142]: time="2025-11-05T15:50:40.875607400Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Nov 5 15:50:40.875643 containerd[2142]: time="2025-11-05T15:50:40.875635800Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Nov 5 15:50:40.875961 containerd[2142]: time="2025-11-05T15:50:40.875927300Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Nov 5 15:50:40.875961 containerd[2142]: time="2025-11-05T15:50:40.875954000Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Nov 5 15:50:40.876094 containerd[2142]: time="2025-11-05T15:50:40.875968000Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Nov 5 15:50:40.876094 containerd[2142]: time="2025-11-05T15:50:40.875976900Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Nov 5 15:50:40.876148 containerd[2142]: time="2025-11-05T15:50:40.876106200Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Nov 5 15:50:40.876355 containerd[2142]: time="2025-11-05T15:50:40.876327800Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Nov 5 15:50:40.876385 containerd[2142]: time="2025-11-05T15:50:40.876364700Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Nov 5 15:50:40.876385 containerd[2142]: time="2025-11-05T15:50:40.876376900Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Nov 5 15:50:40.876426 containerd[2142]: time="2025-11-05T15:50:40.876407800Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Nov 5 15:50:40.876599 containerd[2142]: time="2025-11-05T15:50:40.876576200Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Nov 5 15:50:40.876664 containerd[2142]: time="2025-11-05T15:50:40.876643300Z" level=info msg="metadata content store policy set" policy=shared Nov 5 15:50:41.250113 containerd[2142]: time="2025-11-05T15:50:41.250059500Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Nov 5 15:50:41.250248 containerd[2142]: time="2025-11-05T15:50:41.250132800Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Nov 5 15:50:41.250248 containerd[2142]: time="2025-11-05T15:50:41.250151500Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Nov 5 15:50:41.250248 containerd[2142]: time="2025-11-05T15:50:41.250164500Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Nov 5 15:50:41.250248 containerd[2142]: time="2025-11-05T15:50:41.250178700Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Nov 5 15:50:41.250248 containerd[2142]: time="2025-11-05T15:50:41.250190400Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Nov 5 15:50:41.250248 containerd[2142]: time="2025-11-05T15:50:41.250202300Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Nov 5 15:50:41.250248 containerd[2142]: time="2025-11-05T15:50:41.250215400Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Nov 5 15:50:41.250248 containerd[2142]: time="2025-11-05T15:50:41.250227900Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Nov 5 15:50:41.250421 containerd[2142]: time="2025-11-05T15:50:41.250259300Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Nov 5 15:50:41.250421 containerd[2142]: time="2025-11-05T15:50:41.250273300Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Nov 5 15:50:41.250421 containerd[2142]: time="2025-11-05T15:50:41.250286900Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Nov 5 15:50:41.250486 containerd[2142]: time="2025-11-05T15:50:41.250452400Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Nov 5 15:50:41.250509 containerd[2142]: time="2025-11-05T15:50:41.250491400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Nov 5 15:50:41.250530 containerd[2142]: time="2025-11-05T15:50:41.250509000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Nov 5 15:50:41.250530 containerd[2142]: time="2025-11-05T15:50:41.250520500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Nov 5 15:50:41.250573 containerd[2142]: time="2025-11-05T15:50:41.250530700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Nov 5 15:50:41.250573 containerd[2142]: time="2025-11-05T15:50:41.250541700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Nov 5 15:50:41.250573 containerd[2142]: time="2025-11-05T15:50:41.250565800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Nov 5 15:50:41.250643 containerd[2142]: time="2025-11-05T15:50:41.250578700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Nov 5 15:50:41.250643 containerd[2142]: time="2025-11-05T15:50:41.250591300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Nov 5 15:50:41.250643 containerd[2142]: time="2025-11-05T15:50:41.250611300Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Nov 5 15:50:41.250643 containerd[2142]: time="2025-11-05T15:50:41.250625400Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Nov 5 15:50:41.250750 containerd[2142]: time="2025-11-05T15:50:41.250727300Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Nov 5 15:50:41.250750 containerd[2142]: time="2025-11-05T15:50:41.250745800Z" level=info msg="Start snapshots syncer" Nov 5 15:50:41.250801 containerd[2142]: time="2025-11-05T15:50:41.250767200Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Nov 5 15:50:41.251073 containerd[2142]: time="2025-11-05T15:50:41.251021800Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Nov 5 15:50:41.251184 containerd[2142]: time="2025-11-05T15:50:41.251090000Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Nov 5 15:50:41.251184 containerd[2142]: time="2025-11-05T15:50:41.251175000Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Nov 5 15:50:41.251301 containerd[2142]: time="2025-11-05T15:50:41.251277100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Nov 5 15:50:41.251339 containerd[2142]: time="2025-11-05T15:50:41.251320100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Nov 5 15:50:41.251339 containerd[2142]: time="2025-11-05T15:50:41.251334400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Nov 5 15:50:41.251386 containerd[2142]: time="2025-11-05T15:50:41.251344900Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Nov 5 15:50:41.251386 containerd[2142]: time="2025-11-05T15:50:41.251357200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Nov 5 15:50:41.251386 containerd[2142]: time="2025-11-05T15:50:41.251367900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Nov 5 15:50:41.251448 containerd[2142]: time="2025-11-05T15:50:41.251391400Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Nov 5 15:50:41.251448 containerd[2142]: time="2025-11-05T15:50:41.251421700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Nov 5 15:50:41.251448 containerd[2142]: time="2025-11-05T15:50:41.251436000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Nov 5 15:50:41.251513 containerd[2142]: time="2025-11-05T15:50:41.251458600Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Nov 5 15:50:41.251513 containerd[2142]: time="2025-11-05T15:50:41.251491800Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Nov 5 15:50:41.251513 containerd[2142]: time="2025-11-05T15:50:41.251508900Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Nov 5 15:50:41.251586 containerd[2142]: time="2025-11-05T15:50:41.251518400Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Nov 5 15:50:41.251586 containerd[2142]: time="2025-11-05T15:50:41.251527900Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Nov 5 15:50:41.251586 containerd[2142]: time="2025-11-05T15:50:41.251535700Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Nov 5 15:50:41.251586 containerd[2142]: time="2025-11-05T15:50:41.251571800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Nov 5 15:50:41.251586 containerd[2142]: time="2025-11-05T15:50:41.251585200Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Nov 5 15:50:41.251705 containerd[2142]: time="2025-11-05T15:50:41.251601000Z" level=info msg="runtime interface created" Nov 5 15:50:41.251705 containerd[2142]: time="2025-11-05T15:50:41.251606700Z" level=info msg="created NRI interface" Nov 5 15:50:41.251705 containerd[2142]: time="2025-11-05T15:50:41.251614800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Nov 5 15:50:41.251705 containerd[2142]: time="2025-11-05T15:50:41.251625200Z" level=info msg="Connect containerd service" Nov 5 15:50:41.251705 containerd[2142]: time="2025-11-05T15:50:41.251648000Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Nov 5 15:50:41.252460 containerd[2142]: time="2025-11-05T15:50:41.252430300Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Nov 5 15:50:42.718753 containerd[2142]: time="2025-11-05T15:50:42.718705500Z" level=info msg="Start subscribing containerd event" Nov 5 15:50:42.719115 containerd[2142]: time="2025-11-05T15:50:42.718766600Z" level=info msg="Start recovering state" Nov 5 15:50:42.719115 containerd[2142]: time="2025-11-05T15:50:42.718884300Z" level=info msg="Start event monitor" Nov 5 15:50:42.719115 containerd[2142]: time="2025-11-05T15:50:42.718902400Z" level=info msg="Start cni network conf syncer for default" Nov 5 15:50:42.719115 containerd[2142]: time="2025-11-05T15:50:42.718910000Z" level=info msg="Start streaming server" Nov 5 15:50:42.719115 containerd[2142]: time="2025-11-05T15:50:42.718918500Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Nov 5 15:50:42.719115 containerd[2142]: time="2025-11-05T15:50:42.718926000Z" level=info msg="runtime interface starting up..." Nov 5 15:50:42.719115 containerd[2142]: time="2025-11-05T15:50:42.718932100Z" level=info msg="starting plugins..." Nov 5 15:50:42.719115 containerd[2142]: time="2025-11-05T15:50:42.718946000Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Nov 5 15:50:42.719115 containerd[2142]: time="2025-11-05T15:50:42.718971000Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Nov 5 15:50:42.719115 containerd[2142]: time="2025-11-05T15:50:42.719038300Z" level=info msg=serving... address=/run/containerd/containerd.sock Nov 5 15:50:42.719332 containerd[2142]: time="2025-11-05T15:50:42.719136200Z" level=info msg="containerd successfully booted in 1.854521s" Nov 5 15:50:42.719263 systemd[1]: Started containerd.service - containerd container runtime. Nov 5 15:50:46.589850 login[2239]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Nov 5 15:50:46.591619 login[2242]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Nov 5 15:50:46.598501 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Nov 5 15:50:46.599706 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Nov 5 15:50:46.605665 systemd-logind[2100]: New session 2 of user core. Nov 5 15:50:46.622853 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Nov 5 15:50:46.625254 systemd[1]: Starting user@500.service - User Manager for UID 500... Nov 5 15:50:46.802026 (systemd)[2287]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Nov 5 15:50:46.804474 systemd-logind[2100]: New session c1 of user core. Nov 5 15:50:47.047016 systemd[2287]: Queued start job for default target default.target. Nov 5 15:50:47.066699 systemd[2287]: Created slice app.slice - User Application Slice. Nov 5 15:50:47.066728 systemd[2287]: Reached target paths.target - Paths. Nov 5 15:50:47.066777 systemd[2287]: Reached target timers.target - Timers. Nov 5 15:50:47.068088 systemd[2287]: Starting dbus.socket - D-Bus User Message Bus Socket... Nov 5 15:50:47.079090 systemd[2287]: Listening on dbus.socket - D-Bus User Message Bus Socket. Nov 5 15:50:47.079212 systemd[2287]: Reached target sockets.target - Sockets. Nov 5 15:50:47.079265 systemd[2287]: Reached target basic.target - Basic System. Nov 5 15:50:47.079312 systemd[2287]: Reached target default.target - Main User Target. Nov 5 15:50:47.079344 systemd[2287]: Startup finished in 268ms. Nov 5 15:50:47.079435 systemd[1]: Started user@500.service - User Manager for UID 500. Nov 5 15:50:47.080793 systemd[1]: Started session-2.scope - Session 2 of User core. Nov 5 15:50:47.590258 login[2239]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Nov 5 15:50:47.595077 systemd-logind[2100]: New session 1 of user core. Nov 5 15:50:47.607801 systemd[1]: Started session-1.scope - Session 1 of User core. Nov 5 15:50:47.702564 waagent[2238]: 2025-11-05T15:50:47.702471Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Nov 5 15:50:47.704363 waagent[2238]: 2025-11-05T15:50:47.704315Z INFO Daemon Daemon OS: flatcar 4487.0.1 Nov 5 15:50:47.706027 waagent[2238]: 2025-11-05T15:50:47.705975Z INFO Daemon Daemon Python: 3.11.13 Nov 5 15:50:47.707705 waagent[2238]: 2025-11-05T15:50:47.707639Z INFO Daemon Daemon Run daemon Nov 5 15:50:47.708306 waagent[2238]: 2025-11-05T15:50:47.708257Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4487.0.1' Nov 5 15:50:47.709663 waagent[2238]: 2025-11-05T15:50:47.709622Z INFO Daemon Daemon Using waagent for provisioning Nov 5 15:50:47.710541 waagent[2238]: 2025-11-05T15:50:47.710499Z INFO Daemon Daemon Activate resource disk Nov 5 15:50:47.711918 waagent[2238]: 2025-11-05T15:50:47.711882Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Nov 5 15:50:47.713998 waagent[2238]: 2025-11-05T15:50:47.713957Z INFO Daemon Daemon Found device: sdb Nov 5 15:50:47.719757 waagent[2238]: 2025-11-05T15:50:47.719714Z INFO Daemon Daemon Examining partition table Nov 5 15:50:48.016019 waagent[2238]: 2025-11-05T15:50:48.015933Z INFO Daemon Daemon GPT not detected, determining filesystem Nov 5 15:50:48.042659 waagent[2238]: 2025-11-05T15:50:48.042591Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Nov 5 15:50:48.052788 waagent[2238]: 2025-11-05T15:50:48.044339Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Nov 5 15:50:49.307387 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#235 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 5 15:50:49.318751 kernel: sdb: sdb1 Nov 5 15:50:49.450409 waagent[2238]: 2025-11-05T15:50:49.450323Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Nov 5 15:50:49.465920 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#598 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 5 15:50:49.479757 kernel: sdb: sdb1 Nov 5 15:50:49.479849 waagent[2238]: 2025-11-05T15:50:49.479778Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Nov 5 15:50:55.259947 waagent[2238]: 2025-11-05T15:50:55.259859Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Nov 5 15:50:55.364518 waagent[2238]: 2025-11-05T15:50:55.364439Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Nov 5 15:50:55.365362 kernel: EXT4-fs (sdb1): mounted filesystem 02f46840-f715-4282-bf44-09115a45a0e1 r/w with ordered data mode. Quota mode: none. Nov 5 15:50:55.366546 waagent[2238]: 2025-11-05T15:50:55.365967Z INFO Daemon Daemon Clean protocol and wireserver endpoint Nov 5 15:50:55.366600 waagent[2238]: 2025-11-05T15:50:55.366569Z INFO Daemon Daemon Running default provisioning handler Nov 5 15:50:55.385426 waagent[2238]: 2025-11-05T15:50:55.385372Z INFO Daemon Daemon Unable to get cloud-init enabled status from systemctl: Command '['systemctl', 'is-enabled', 'cloud-init-local.service']' returned non-zero exit status 4. Nov 5 15:50:55.392106 waagent[2238]: 2025-11-05T15:50:55.387339Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Nov 5 15:50:55.392106 waagent[2238]: 2025-11-05T15:50:55.388426Z INFO Daemon Daemon cloud-init is enabled: False Nov 5 15:50:55.392106 waagent[2238]: 2025-11-05T15:50:55.389937Z INFO Daemon Daemon Copying ovf-env.xml Nov 5 15:50:55.724525 waagent[2238]: 2025-11-05T15:50:55.724439Z INFO Daemon Daemon Successfully mounted dvd Nov 5 15:50:55.953116 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Nov 5 15:50:55.953450 waagent[2238]: 2025-11-05T15:50:55.953262Z INFO Daemon Daemon Detect protocol endpoint Nov 5 15:50:55.955023 waagent[2238]: 2025-11-05T15:50:55.954978Z INFO Daemon Daemon Clean protocol and wireserver endpoint Nov 5 15:50:55.956515 waagent[2238]: 2025-11-05T15:50:55.956478Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Nov 5 15:50:55.957972 waagent[2238]: 2025-11-05T15:50:55.957939Z INFO Daemon Daemon Test for route to 168.63.129.16 Nov 5 15:50:55.959597 waagent[2238]: 2025-11-05T15:50:55.959561Z INFO Daemon Daemon Route to 168.63.129.16 exists Nov 5 15:50:55.960958 waagent[2238]: 2025-11-05T15:50:55.960925Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Nov 5 15:50:55.985108 waagent[2238]: 2025-11-05T15:50:55.985017Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Nov 5 15:50:55.986849 waagent[2238]: 2025-11-05T15:50:55.986820Z INFO Daemon Daemon Wire protocol version:2012-11-30 Nov 5 15:50:55.987463 waagent[2238]: 2025-11-05T15:50:55.987431Z INFO Daemon Daemon Server preferred version:2015-04-05 Nov 5 15:50:56.131251 waagent[2238]: 2025-11-05T15:50:56.131159Z INFO Daemon Daemon Initializing goal state during protocol detection Nov 5 15:50:56.132930 waagent[2238]: 2025-11-05T15:50:56.132885Z INFO Daemon Daemon Forcing an update of the goal state. Nov 5 15:50:56.136956 waagent[2238]: 2025-11-05T15:50:56.136903Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Nov 5 15:50:56.167012 waagent[2238]: 2025-11-05T15:50:56.166967Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.177 Nov 5 15:50:56.169386 waagent[2238]: 2025-11-05T15:50:56.169291Z INFO Daemon Nov 5 15:50:56.172139 waagent[2238]: 2025-11-05T15:50:56.172091Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 93526bcb-a708-4b66-a1bc-75d78d68dfce eTag: 16831760555351326498 source: Fabric] Nov 5 15:50:56.174687 waagent[2238]: 2025-11-05T15:50:56.174603Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Nov 5 15:50:56.176934 waagent[2238]: 2025-11-05T15:50:56.176846Z INFO Daemon Nov 5 15:50:56.178484 waagent[2238]: 2025-11-05T15:50:56.178444Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Nov 5 15:50:56.185413 waagent[2238]: 2025-11-05T15:50:56.185334Z INFO Daemon Daemon Downloading artifacts profile blob Nov 5 15:50:56.324248 waagent[2238]: 2025-11-05T15:50:56.324141Z INFO Daemon Downloaded certificate {'thumbprint': 'F0BCC6E6DBE9C76D2E9328393FBBE711BDDE3574', 'hasPrivateKey': True} Nov 5 15:50:56.326180 waagent[2238]: 2025-11-05T15:50:56.326137Z INFO Daemon Fetch goal state completed Nov 5 15:50:56.369915 waagent[2238]: 2025-11-05T15:50:56.369864Z INFO Daemon Daemon Starting provisioning Nov 5 15:50:56.371578 waagent[2238]: 2025-11-05T15:50:56.371523Z INFO Daemon Daemon Handle ovf-env.xml. Nov 5 15:50:56.375649 waagent[2238]: 2025-11-05T15:50:56.375594Z INFO Daemon Daemon Set hostname [ci-4487.0.1-a-f76c371356] Nov 5 15:50:56.404376 waagent[2238]: 2025-11-05T15:50:56.404323Z INFO Daemon Daemon Publish hostname [ci-4487.0.1-a-f76c371356] Nov 5 15:50:56.407727 waagent[2238]: 2025-11-05T15:50:56.407660Z INFO Daemon Daemon Examine /proc/net/route for primary interface Nov 5 15:50:56.412446 waagent[2238]: 2025-11-05T15:50:56.412396Z INFO Daemon Daemon Primary interface is [eth0] Nov 5 15:50:56.424596 systemd-networkd[1799]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 5 15:50:56.424607 systemd-networkd[1799]: eth0: Reconfiguring with /usr/lib/systemd/network/zz-default.network. Nov 5 15:50:56.424681 systemd-networkd[1799]: eth0: DHCP lease lost Nov 5 15:50:56.451454 waagent[2238]: 2025-11-05T15:50:56.451394Z INFO Daemon Daemon Create user account if not exists Nov 5 15:50:56.453135 waagent[2238]: 2025-11-05T15:50:56.453085Z INFO Daemon Daemon User core already exists, skip useradd Nov 5 15:50:56.454546 waagent[2238]: 2025-11-05T15:50:56.454503Z INFO Daemon Daemon Configure sudoer Nov 5 15:50:56.483708 systemd-networkd[1799]: eth0: DHCPv4 address 10.200.8.31/24, gateway 10.200.8.1 acquired from 168.63.129.16 Nov 5 15:50:56.592633 waagent[2238]: 2025-11-05T15:50:56.592514Z INFO Daemon Daemon Configure sshd Nov 5 15:50:56.598851 waagent[2238]: 2025-11-05T15:50:56.598803Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Nov 5 15:50:56.600489 waagent[2238]: 2025-11-05T15:50:56.600440Z INFO Daemon Daemon Deploy ssh public key. Nov 5 15:50:57.717707 waagent[2238]: 2025-11-05T15:50:57.717641Z INFO Daemon Daemon Provisioning complete Nov 5 15:50:57.728214 waagent[2238]: 2025-11-05T15:50:57.727926Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Nov 5 15:50:57.729956 waagent[2238]: 2025-11-05T15:50:57.729881Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Nov 5 15:50:57.736482 waagent[2238]: 2025-11-05T15:50:57.736384Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Nov 5 15:50:57.890695 waagent[2409]: 2025-11-05T15:50:57.890597Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Nov 5 15:50:57.891041 waagent[2409]: 2025-11-05T15:50:57.890751Z INFO ExtHandler ExtHandler OS: flatcar 4487.0.1 Nov 5 15:50:57.891041 waagent[2409]: 2025-11-05T15:50:57.890822Z INFO ExtHandler ExtHandler Python: 3.11.13 Nov 5 15:50:57.891041 waagent[2409]: 2025-11-05T15:50:57.890890Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Nov 5 15:50:58.005457 waagent[2409]: 2025-11-05T15:50:58.005348Z INFO ExtHandler ExtHandler Distro: flatcar-4487.0.1; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.13; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Nov 5 15:50:58.005603 waagent[2409]: 2025-11-05T15:50:58.005562Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Nov 5 15:50:58.005680 waagent[2409]: 2025-11-05T15:50:58.005646Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Nov 5 15:50:58.012423 waagent[2409]: 2025-11-05T15:50:58.012366Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Nov 5 15:50:58.017368 waagent[2409]: 2025-11-05T15:50:58.017323Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.177 Nov 5 15:50:58.017830 waagent[2409]: 2025-11-05T15:50:58.017789Z INFO ExtHandler Nov 5 15:50:58.017911 waagent[2409]: 2025-11-05T15:50:58.017879Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 681abe74-180a-4d2e-996e-0ee564369249 eTag: 16831760555351326498 source: Fabric] Nov 5 15:50:58.018239 waagent[2409]: 2025-11-05T15:50:58.018201Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Nov 5 15:50:58.018863 waagent[2409]: 2025-11-05T15:50:58.018824Z INFO ExtHandler Nov 5 15:50:58.018979 waagent[2409]: 2025-11-05T15:50:58.018908Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Nov 5 15:50:58.022535 waagent[2409]: 2025-11-05T15:50:58.022496Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Nov 5 15:50:58.088169 waagent[2409]: 2025-11-05T15:50:58.088094Z INFO ExtHandler Downloaded certificate {'thumbprint': 'F0BCC6E6DBE9C76D2E9328393FBBE711BDDE3574', 'hasPrivateKey': True} Nov 5 15:50:58.088683 waagent[2409]: 2025-11-05T15:50:58.088639Z INFO ExtHandler Fetch goal state completed Nov 5 15:50:58.100456 waagent[2409]: 2025-11-05T15:50:58.100407Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.4.2 1 Jul 2025 (Library: OpenSSL 3.4.2 1 Jul 2025) Nov 5 15:50:58.105567 waagent[2409]: 2025-11-05T15:50:58.105521Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2409 Nov 5 15:50:58.105720 waagent[2409]: 2025-11-05T15:50:58.105664Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Nov 5 15:50:58.106049 waagent[2409]: 2025-11-05T15:50:58.106011Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Nov 5 15:50:58.107611 waagent[2409]: 2025-11-05T15:50:58.107570Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4487.0.1', '', 'Flatcar Container Linux by Kinvolk'] Nov 5 15:50:58.108061 waagent[2409]: 2025-11-05T15:50:58.108023Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4487.0.1', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Nov 5 15:50:58.108212 waagent[2409]: 2025-11-05T15:50:58.108176Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Nov 5 15:50:58.108819 waagent[2409]: 2025-11-05T15:50:58.108780Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Nov 5 15:50:58.297931 waagent[2409]: 2025-11-05T15:50:58.297832Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Nov 5 15:50:58.298096 waagent[2409]: 2025-11-05T15:50:58.298058Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Nov 5 15:50:58.304751 waagent[2409]: 2025-11-05T15:50:58.304711Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Nov 5 15:50:58.312391 systemd[1]: Reload requested from client PID 2424 ('systemctl') (unit waagent.service)... Nov 5 15:50:58.312409 systemd[1]: Reloading... Nov 5 15:50:58.379712 zram_generator::config[2465]: No configuration found. Nov 5 15:50:58.668913 systemd[1]: Reloading finished in 356 ms. Nov 5 15:50:58.694331 waagent[2409]: 2025-11-05T15:50:58.694254Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Nov 5 15:50:58.694443 waagent[2409]: 2025-11-05T15:50:58.694406Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Nov 5 15:51:00.245308 waagent[2409]: 2025-11-05T15:51:00.245222Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Nov 5 15:51:00.245702 waagent[2409]: 2025-11-05T15:51:00.245564Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: 1. configuration enabled [True], 2. cgroups v1 enabled [False] OR cgroups v2 is in use and v2 resource limiting configuration enabled [False], 3. python supported: [True] Nov 5 15:51:00.246373 waagent[2409]: 2025-11-05T15:51:00.246318Z INFO ExtHandler ExtHandler Starting env monitor service. Nov 5 15:51:00.246745 waagent[2409]: 2025-11-05T15:51:00.246695Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Nov 5 15:51:00.246939 waagent[2409]: 2025-11-05T15:51:00.246893Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Nov 5 15:51:00.247109 waagent[2409]: 2025-11-05T15:51:00.247070Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Nov 5 15:51:00.247550 waagent[2409]: 2025-11-05T15:51:00.247500Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Nov 5 15:51:00.247783 waagent[2409]: 2025-11-05T15:51:00.247737Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Nov 5 15:51:00.247882 waagent[2409]: 2025-11-05T15:51:00.247825Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Nov 5 15:51:00.247965 waagent[2409]: 2025-11-05T15:51:00.247922Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Nov 5 15:51:00.248466 waagent[2409]: 2025-11-05T15:51:00.248425Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Nov 5 15:51:00.248622 waagent[2409]: 2025-11-05T15:51:00.248567Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Nov 5 15:51:00.248694 waagent[2409]: 2025-11-05T15:51:00.248643Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Nov 5 15:51:00.248938 waagent[2409]: 2025-11-05T15:51:00.248891Z INFO EnvHandler ExtHandler Configure routes Nov 5 15:51:00.249088 waagent[2409]: 2025-11-05T15:51:00.249047Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Nov 5 15:51:00.249490 waagent[2409]: 2025-11-05T15:51:00.249446Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Nov 5 15:51:00.249490 waagent[2409]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Nov 5 15:51:00.249490 waagent[2409]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Nov 5 15:51:00.249490 waagent[2409]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Nov 5 15:51:00.249490 waagent[2409]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Nov 5 15:51:00.249490 waagent[2409]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Nov 5 15:51:00.249490 waagent[2409]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Nov 5 15:51:00.249662 waagent[2409]: 2025-11-05T15:51:00.249516Z INFO EnvHandler ExtHandler Gateway:None Nov 5 15:51:00.249946 waagent[2409]: 2025-11-05T15:51:00.249907Z INFO EnvHandler ExtHandler Routes:None Nov 5 15:51:00.255690 waagent[2409]: 2025-11-05T15:51:00.255639Z INFO ExtHandler ExtHandler Nov 5 15:51:00.255988 waagent[2409]: 2025-11-05T15:51:00.255947Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: d5711be9-ed87-4d0a-96ca-adbfb7809283 correlation aed575e3-5fc2-4d74-a47a-16e70023404b created: 2025-11-05T15:48:56.577995Z] Nov 5 15:51:00.257077 waagent[2409]: 2025-11-05T15:51:00.257033Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Nov 5 15:51:00.259092 waagent[2409]: 2025-11-05T15:51:00.259048Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Nov 5 15:51:00.282360 waagent[2409]: 2025-11-05T15:51:00.282310Z WARNING ExtHandler ExtHandler Failed to get firewall packets: 'iptables -w -t security -L OUTPUT --zero OUTPUT -nxv' failed: 2 (iptables v1.8.11 (nf_tables): Illegal option `--numeric' with this command Nov 5 15:51:00.282360 waagent[2409]: Try `iptables -h' or 'iptables --help' for more information.) Nov 5 15:51:00.282734 waagent[2409]: 2025-11-05T15:51:00.282697Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 4A405224-5267-4AC3-8F0E-A913D440C19B;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Nov 5 15:51:00.337150 waagent[2409]: 2025-11-05T15:51:00.337095Z INFO MonitorHandler ExtHandler Network interfaces: Nov 5 15:51:00.337150 waagent[2409]: Executing ['ip', '-a', '-o', 'link']: Nov 5 15:51:00.337150 waagent[2409]: 1: lo: mtu 65536 qdisc noqueue state UNKNOWN mode DEFAULT group default qlen 1000\ link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00 Nov 5 15:51:00.337150 waagent[2409]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:ed:8d:48:e8:a4 brd ff:ff:ff:ff:ff:ff\ altname enx7ced8d48e8a4 Nov 5 15:51:00.337150 waagent[2409]: Executing ['ip', '-4', '-a', '-o', 'address']: Nov 5 15:51:00.337150 waagent[2409]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Nov 5 15:51:00.337150 waagent[2409]: 2: eth0 inet 10.200.8.31/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Nov 5 15:51:00.337150 waagent[2409]: Executing ['ip', '-6', '-a', '-o', 'address']: Nov 5 15:51:00.337150 waagent[2409]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Nov 5 15:51:00.337150 waagent[2409]: 2: eth0 inet6 fe80::7eed:8dff:fe48:e8a4/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Nov 5 15:51:00.362894 waagent[2409]: 2025-11-05T15:51:00.362841Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Nov 5 15:51:00.362894 waagent[2409]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Nov 5 15:51:00.362894 waagent[2409]: pkts bytes target prot opt in out source destination Nov 5 15:51:00.362894 waagent[2409]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Nov 5 15:51:00.362894 waagent[2409]: pkts bytes target prot opt in out source destination Nov 5 15:51:00.362894 waagent[2409]: Chain OUTPUT (policy ACCEPT 3 packets, 364 bytes) Nov 5 15:51:00.362894 waagent[2409]: pkts bytes target prot opt in out source destination Nov 5 15:51:00.362894 waagent[2409]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Nov 5 15:51:00.362894 waagent[2409]: 10 1104 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Nov 5 15:51:00.362894 waagent[2409]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Nov 5 15:51:00.367202 waagent[2409]: 2025-11-05T15:51:00.367155Z INFO EnvHandler ExtHandler Current Firewall rules: Nov 5 15:51:00.367202 waagent[2409]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Nov 5 15:51:00.367202 waagent[2409]: pkts bytes target prot opt in out source destination Nov 5 15:51:00.367202 waagent[2409]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Nov 5 15:51:00.367202 waagent[2409]: pkts bytes target prot opt in out source destination Nov 5 15:51:00.367202 waagent[2409]: Chain OUTPUT (policy ACCEPT 3 packets, 364 bytes) Nov 5 15:51:00.367202 waagent[2409]: pkts bytes target prot opt in out source destination Nov 5 15:51:00.367202 waagent[2409]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Nov 5 15:51:00.367202 waagent[2409]: 20 1972 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Nov 5 15:51:00.367202 waagent[2409]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Nov 5 15:51:00.367459 waagent[2409]: 2025-11-05T15:51:00.367424Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Nov 5 15:51:00.367615 waagent[2409]: 2025-11-05T15:51:00.367583Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Nov 5 15:51:01.014184 chronyd[2079]: Selected source PHC0 Nov 5 15:51:01.915293 setup-nvidia[2254]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 494M 0 160k 0 0 159k 0 0:53:03 0:00:01 0:53:02 159k\u000d 2 494M 2 12.9M 0 0 6645k 0 0:01:16 0:00:01 0:01:15 6644k\u000d 7 494M 7 38.2M 0 0 12.7M 0 0:00:38 0:00:02 0:00:36 12.7M\u000d 12 494M 12 63.5M 0 0 15.8M 0 0:00:31 0:00:04 0:00:27 15.8M\u000d 17 494M 17 89.0M 0 0 17.7M 0 0:00:27 0:00:05 0:00:22 17.7M\u000d 23 494M 23 114M 0 0 19.0M 0 0:00:26 0:00:06 0:00:20 22.8M\u000d 28 494M 28 139M 0 0 19.9M 0 0:00:24 0:00:07 0:00:17 25.2M\u000d 33 494M 33 165M 0 0 20.5M 0 0:00:24 0:00:08 0:00:16 25.1M\u000d 38 494M 38 190M 0 0 21.0M 0 0:00:23 0:00:09 0:00:14 25.2M\u000d 42 494M 42 212M 0 0 21.3M 0 0:00:23 0:00:09 0:00:14 25.0M\u000d 47 494M 47 237M 0 0 21.7M 0 0:00:22 0:00:10 0:00:12 24.9M\u000d 52 494M 52 261M 0 0 21.9M 0 0:00:22 0:00:11 0:00:11 24.8M\u000d 58 494M 58 287M 0 0 22.2M 0 0:00:22 0:00:12 0:00:10 24.8M\u000d 63 494M 63 312M 0 0 22.4M 0 0:00:22 0:00:13 0:00:09 24.8M\u000d 67 494M 67 335M 0 0 22.2M 0 0:00:22 0:00:15 0:00:07 24.0M\u000d 72 494M 72 358M 0 0 22.3M 0 0:00:22 0:00:16 0:00:06 23.7M\u000d 76 494M 76 380M 0 0 22.4M 0 0:00:22 0:00:16 0:00:06 23.6M\u000d 81 494M 81 404M 0 0 22.5M 0 0:00:21 0:00:17 0:00:04 23.4M\u000d 86 494M 86 428M 0 0 22.6M 0 0:00:21 0:00:18 0:00:03 23.1M\u000d 91 494M 91 452M 0 0 22.7M 0 0:00:21 0:00:19 0:00:02 23.9M\u000d 95 494M 95 470M 0 0 22.4M 0 0:00:22 0:00:20 0:00:02 22.6M\u000d 99 494M 99 490M 0 0 22.3M 0 0:00:22 0:00:21 0:00:01 21.9M\u000d100 494M 100 494M 0 0 22.3M 0 0:00:22 0:00:22 --:--:-- 21.4M Nov 5 15:51:12.446803 setup-nvidia[2095]: Downloading NVIDIA 535.261.03 Driver Nov 5 15:51:12.451622 setup-nvidia[2568]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 5 15:51:12.451876 setup-nvidia[2568]: Dload Upload Total Spent Left Speed Nov 5 15:51:12.704467 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Nov 5 15:51:13.089116 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Nov 5 15:51:13.091951 systemd[1]: Started sshd@0-10.200.8.31:22-10.200.16.10:35758.service - OpenSSH per-connection server daemon (10.200.16.10:35758). Nov 5 15:51:13.984400 sshd[2570]: Accepted publickey for core from 10.200.16.10 port 35758 ssh2: RSA SHA256:wOn5dyIm5WplZhFa/bL50fgwDeFFpayVU2x0GGhbAwc Nov 5 15:51:13.985727 sshd-session[2570]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:51:13.990465 systemd-logind[2100]: New session 3 of user core. Nov 5 15:51:14.002800 systemd[1]: Started session-3.scope - Session 3 of User core. Nov 5 15:51:14.604014 systemd[1]: Started sshd@1-10.200.8.31:22-10.200.16.10:35766.service - OpenSSH per-connection server daemon (10.200.16.10:35766). Nov 5 15:51:15.308986 sshd[2576]: Accepted publickey for core from 10.200.16.10 port 35766 ssh2: RSA SHA256:wOn5dyIm5WplZhFa/bL50fgwDeFFpayVU2x0GGhbAwc Nov 5 15:51:15.310211 sshd-session[2576]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:51:15.314508 systemd-logind[2100]: New session 4 of user core. Nov 5 15:51:15.335785 systemd[1]: Started session-4.scope - Session 4 of User core. Nov 5 15:51:15.805691 sshd[2579]: Connection closed by 10.200.16.10 port 35766 Nov 5 15:51:15.806022 sshd-session[2576]: pam_unix(sshd:session): session closed for user core Nov 5 15:51:15.809776 systemd[1]: sshd@1-10.200.8.31:22-10.200.16.10:35766.service: Deactivated successfully. Nov 5 15:51:15.811473 systemd[1]: session-4.scope: Deactivated successfully. Nov 5 15:51:15.816983 systemd-logind[2100]: Session 4 logged out. Waiting for processes to exit. Nov 5 15:51:15.817994 systemd-logind[2100]: Removed session 4. Nov 5 15:51:15.935355 systemd[1]: Started sshd@2-10.200.8.31:22-10.200.16.10:35780.service - OpenSSH per-connection server daemon (10.200.16.10:35780). Nov 5 15:51:16.064159 setup-nvidia[2568]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 2 325M 2 9.7M 0 0 37.2M 0 0:00:08 --:--:-- 0:00:08 37.1M\u000d 29 325M 29 96.2M 0 0 76.2M 0 0:00:04 0:00:01 0:00:03 76.2M\u000d 57 325M 57 188M 0 0 83.3M 0 0:00:03 0:00:02 0:00:01 83.2M\u000d 88 325M 88 286M 0 0 87.9M 0 0:00:03 0:00:03 --:--:-- 87.9M\u000d100 325M 100 325M 0 0 90.2M 0 0:00:03 0:00:03 --:--:-- 90.2M Nov 5 15:51:16.096993 setup-nvidia[2095]: Extract the NVIDIA Driver Installer 535.261.03 Nov 5 15:51:16.097203 setup-nvidia[2095]: /opt/nvidia/workdir/nvidia-workdir / Nov 5 15:51:16.131117 setup-nvidia[2590]: Creating directory NVIDIA-Linux-x86_64-535.261.03 Nov 5 15:51:16.421794 setup-nvidia[2590]: Verifying archive integrity... OK Nov 5 15:51:16.663491 sshd[2585]: Accepted publickey for core from 10.200.16.10 port 35780 ssh2: RSA SHA256:wOn5dyIm5WplZhFa/bL50fgwDeFFpayVU2x0GGhbAwc Nov 5 15:51:16.664777 sshd-session[2585]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:51:16.669071 systemd-logind[2100]: New session 5 of user core. Nov 5 15:51:16.688836 systemd[1]: Started session-5.scope - Session 5 of User core. Nov 5 15:51:17.156008 setup-nvidia[2590]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.261.03 Nov 5 15:51:17.163076 sshd[2613]: Connection closed by 10.200.16.10 port 35780 Nov 5 15:51:17.163400 sshd-session[2585]: pam_unix(sshd:session): session closed for user core Nov 5 15:51:17.167046 systemd[1]: sshd@2-10.200.8.31:22-10.200.16.10:35780.service: Deactivated successfully. Nov 5 15:51:17.168659 systemd[1]: session-5.scope: Deactivated successfully. Nov 5 15:51:17.169413 systemd-logind[2100]: Session 5 logged out. Waiting for processes to exit. Nov 5 15:51:17.170356 systemd-logind[2100]: Removed session 5. Nov 5 15:51:18.928548 setup-nvidia[2618]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Nov 5 15:51:19.005614 setup-nvidia[2095]: / Nov 5 15:51:19.005904 setup-nvidia[2095]: Spawn system-nspawn container to install the NVIDIA drivers Nov 5 15:51:19.240597 sudo[2628]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4487.0.1.bin --overlay=/usr/lib/modules::/usr/lib/modules --bind=/opt/nvidia/workdir/nvidia-workdir:/nvidia --bind=/usr/lib/nvidia/bin:/app/bin/ /app/bin/install-nvidia NVIDIA-Linux-x86_64-535.261.03 Nov 5 15:51:19.242330 sudo[2628]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Nov 5 15:51:19.248275 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Nov 5 15:51:19.251003 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Nov 5 15:51:19.286785 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Nov 5 15:51:19.290580 systemd[1]: Starting user@0.service - User Manager for UID 0... Nov 5 15:51:19.305520 (systemd)[2631]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Nov 5 15:51:19.307624 systemd-logind[2100]: New session c3 of user root. Nov 5 15:51:19.468099 systemd[2631]: Queued start job for default target default.target. Nov 5 15:51:19.485779 systemd[2631]: Created slice app.slice - User Application Slice. Nov 5 15:51:19.485808 systemd[2631]: Reached target paths.target - Paths. Nov 5 15:51:19.485854 systemd[2631]: Reached target timers.target - Timers. Nov 5 15:51:19.486974 systemd[2631]: Starting dbus.socket - D-Bus User Message Bus Socket... Nov 5 15:51:19.497368 systemd[2631]: Listening on dbus.socket - D-Bus User Message Bus Socket. Nov 5 15:51:19.497428 systemd[2631]: Reached target sockets.target - Sockets. Nov 5 15:51:19.497480 systemd[2631]: Reached target basic.target - Basic System. Nov 5 15:51:19.497558 systemd[2631]: Reached target default.target - Main User Target. Nov 5 15:51:19.497597 systemd[2631]: Startup finished in 184ms. Nov 5 15:51:19.497638 systemd[1]: Started user@0.service - User Manager for UID 0. Nov 5 15:51:19.500610 systemd[1]: Started session-c2.scope - Session c2 of User root. Nov 5 15:51:19.557333 systemd-udevd[1784]: Received message with invalid length, ignoring Nov 5 15:51:19.561694 kernel: loop7: detected capacity change from 0 to 12644352 Nov 5 15:51:19.632700 kernel: loop7: p9 Nov 5 15:51:19.888744 setup-nvidia[2640]: ░ Spawning container flatcardevelopercontainer-4487.0.1.bin on /opt/nvidia/workdir/flatcar_developer_container-4487.0.1.bin. Nov 5 15:51:22.241132 kernel: EXT4-fs (loop7p9): write access unavailable, skipping orphan cleanup Nov 5 15:51:22.241255 kernel: EXT4-fs (loop7p9): mounted filesystem 6ebeca74-615c-4ade-93c7-dfb442225868 ro without journal. Quota mode: none. Nov 5 15:51:23.578410 update_engine[2102]: I20251105 15:51:23.578321 2102 update_attempter.cc:509] Updating boot flags... Nov 5 15:51:27.291127 systemd[1]: Started sshd@3-10.200.8.31:22-10.200.16.10:50314.service - OpenSSH per-connection server daemon (10.200.16.10:50314). Nov 5 15:51:28.012481 sshd[2722]: Accepted publickey for core from 10.200.16.10 port 50314 ssh2: RSA SHA256:wOn5dyIm5WplZhFa/bL50fgwDeFFpayVU2x0GGhbAwc Nov 5 15:51:28.013697 sshd-session[2722]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:51:28.017882 systemd-logind[2100]: New session 6 of user core. Nov 5 15:51:28.038007 systemd[1]: Started session-6.scope - Session 6 of User core. Nov 5 15:51:28.509559 sshd[2765]: Connection closed by 10.200.16.10 port 50314 Nov 5 15:51:28.509893 sshd-session[2722]: pam_unix(sshd:session): session closed for user core Nov 5 15:51:28.513475 systemd[1]: sshd@3-10.200.8.31:22-10.200.16.10:50314.service: Deactivated successfully. Nov 5 15:51:28.515061 systemd[1]: session-6.scope: Deactivated successfully. Nov 5 15:51:28.515759 systemd-logind[2100]: Session 6 logged out. Waiting for processes to exit. Nov 5 15:51:28.516588 systemd-logind[2100]: Removed session 6. Nov 5 15:51:28.637970 systemd[1]: Started sshd@4-10.200.8.31:22-10.200.16.10:50316.service - OpenSSH per-connection server daemon (10.200.16.10:50316). Nov 5 15:51:29.345068 sshd[2775]: Accepted publickey for core from 10.200.16.10 port 50316 ssh2: RSA SHA256:wOn5dyIm5WplZhFa/bL50fgwDeFFpayVU2x0GGhbAwc Nov 5 15:51:29.346346 sshd-session[2775]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:51:29.350783 systemd-logind[2100]: New session 7 of user core. Nov 5 15:51:29.366793 systemd[1]: Started session-7.scope - Session 7 of User core. Nov 5 15:51:29.842113 sshd[2779]: Connection closed by 10.200.16.10 port 50316 Nov 5 15:51:29.842464 sshd-session[2775]: pam_unix(sshd:session): session closed for user core Nov 5 15:51:29.846189 systemd[1]: sshd@4-10.200.8.31:22-10.200.16.10:50316.service: Deactivated successfully. Nov 5 15:51:29.847832 systemd[1]: session-7.scope: Deactivated successfully. Nov 5 15:51:29.848535 systemd-logind[2100]: Session 7 logged out. Waiting for processes to exit. Nov 5 15:51:29.849384 systemd-logind[2100]: Removed session 7. Nov 5 15:51:39.972789 systemd[1]: Started sshd@5-10.200.8.31:22-10.200.16.10:41138.service - OpenSSH per-connection server daemon (10.200.16.10:41138). Nov 5 15:51:40.682096 sshd[2785]: Accepted publickey for core from 10.200.16.10 port 41138 ssh2: RSA SHA256:wOn5dyIm5WplZhFa/bL50fgwDeFFpayVU2x0GGhbAwc Nov 5 15:51:40.683281 sshd-session[2785]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:51:40.687461 systemd-logind[2100]: New session 8 of user core. Nov 5 15:51:40.705802 systemd[1]: Started session-8.scope - Session 8 of User core. Nov 5 15:51:41.181135 sshd[2788]: Connection closed by 10.200.16.10 port 41138 Nov 5 15:51:41.181475 sshd-session[2785]: pam_unix(sshd:session): session closed for user core Nov 5 15:51:41.185031 systemd[1]: sshd@5-10.200.8.31:22-10.200.16.10:41138.service: Deactivated successfully. Nov 5 15:51:41.186751 systemd[1]: session-8.scope: Deactivated successfully. Nov 5 15:51:41.187642 systemd-logind[2100]: Session 8 logged out. Waiting for processes to exit. Nov 5 15:51:41.188583 systemd-logind[2100]: Removed session 8. Nov 5 15:51:41.316113 systemd[1]: Started sshd@6-10.200.8.31:22-10.200.16.10:41144.service - OpenSSH per-connection server daemon (10.200.16.10:41144). Nov 5 15:51:42.022399 sshd[2794]: Accepted publickey for core from 10.200.16.10 port 41144 ssh2: RSA SHA256:wOn5dyIm5WplZhFa/bL50fgwDeFFpayVU2x0GGhbAwc Nov 5 15:51:42.023649 sshd-session[2794]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:51:42.027978 systemd-logind[2100]: New session 9 of user core. Nov 5 15:51:42.037795 systemd[1]: Started session-9.scope - Session 9 of User core. Nov 5 15:51:42.520012 sshd[2797]: Connection closed by 10.200.16.10 port 41144 Nov 5 15:51:42.520350 sshd-session[2794]: pam_unix(sshd:session): session closed for user core Nov 5 15:51:42.524040 systemd[1]: sshd@6-10.200.8.31:22-10.200.16.10:41144.service: Deactivated successfully. Nov 5 15:51:42.525590 systemd[1]: session-9.scope: Deactivated successfully. Nov 5 15:51:42.526287 systemd-logind[2100]: Session 9 logged out. Waiting for processes to exit. Nov 5 15:51:42.527141 systemd-logind[2100]: Removed session 9. Nov 5 15:51:52.644221 systemd[1]: Started sshd@7-10.200.8.31:22-10.200.16.10:33262.service - OpenSSH per-connection server daemon (10.200.16.10:33262). Nov 5 15:51:53.889136 sshd[2804]: Accepted publickey for core from 10.200.16.10 port 33262 ssh2: RSA SHA256:wOn5dyIm5WplZhFa/bL50fgwDeFFpayVU2x0GGhbAwc Nov 5 15:51:53.937582 sshd-session[2804]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:51:53.941957 systemd-logind[2100]: New session 10 of user core. Nov 5 15:51:53.956024 systemd[1]: Started session-10.scope - Session 10 of User core. Nov 5 15:51:54.349496 sshd[2807]: Connection closed by 10.200.16.10 port 33262 Nov 5 15:51:54.349836 sshd-session[2804]: pam_unix(sshd:session): session closed for user core Nov 5 15:51:54.353344 systemd[1]: sshd@7-10.200.8.31:22-10.200.16.10:33262.service: Deactivated successfully. Nov 5 15:51:54.354912 systemd[1]: session-10.scope: Deactivated successfully. Nov 5 15:51:54.355604 systemd-logind[2100]: Session 10 logged out. Waiting for processes to exit. Nov 5 15:51:54.356442 systemd-logind[2100]: Removed session 10. Nov 5 15:51:54.473754 systemd[1]: Started sshd@8-10.200.8.31:22-10.200.16.10:33274.service - OpenSSH per-connection server daemon (10.200.16.10:33274). Nov 5 15:51:55.183662 sshd[2813]: Accepted publickey for core from 10.200.16.10 port 33274 ssh2: RSA SHA256:wOn5dyIm5WplZhFa/bL50fgwDeFFpayVU2x0GGhbAwc Nov 5 15:51:55.184963 sshd-session[2813]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:51:55.189217 systemd-logind[2100]: New session 11 of user core. Nov 5 15:51:55.210793 systemd[1]: Started session-11.scope - Session 11 of User core. Nov 5 15:51:55.683608 sshd[2816]: Connection closed by 10.200.16.10 port 33274 Nov 5 15:51:55.683931 sshd-session[2813]: pam_unix(sshd:session): session closed for user core Nov 5 15:51:55.687333 systemd[1]: sshd@8-10.200.8.31:22-10.200.16.10:33274.service: Deactivated successfully. Nov 5 15:51:55.688926 systemd[1]: session-11.scope: Deactivated successfully. Nov 5 15:51:55.689684 systemd-logind[2100]: Session 11 logged out. Waiting for processes to exit. Nov 5 15:51:55.690534 systemd-logind[2100]: Removed session 11. Nov 5 15:52:05.812741 systemd[1]: Started sshd@9-10.200.8.31:22-10.200.16.10:39840.service - OpenSSH per-connection server daemon (10.200.16.10:39840). Nov 5 15:52:07.677838 sshd[2822]: Accepted publickey for core from 10.200.16.10 port 39840 ssh2: RSA SHA256:wOn5dyIm5WplZhFa/bL50fgwDeFFpayVU2x0GGhbAwc Nov 5 15:52:07.677949 sshd-session[2822]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:52:07.682446 systemd-logind[2100]: New session 12 of user core. Nov 5 15:52:07.699791 systemd[1]: Started session-12.scope - Session 12 of User core. Nov 5 15:52:08.886390 sshd[2825]: Connection closed by 10.200.16.10 port 39840 Nov 5 15:52:08.215782 systemd[1]: Started sshd@10-10.200.8.31:22-10.200.16.10:39850.service - OpenSSH per-connection server daemon (10.200.16.10:39850). Nov 5 15:52:08.886468 sshd-session[2822]: pam_unix(sshd:session): session closed for user core Nov 5 15:52:08.890353 systemd[1]: sshd@9-10.200.8.31:22-10.200.16.10:39840.service: Deactivated successfully. Nov 5 15:52:08.892166 systemd[1]: session-12.scope: Deactivated successfully. Nov 5 15:52:08.892953 systemd-logind[2100]: Session 12 logged out. Waiting for processes to exit. Nov 5 15:52:08.893867 systemd-logind[2100]: Removed session 12. Nov 5 15:52:08.922368 sshd[2828]: Accepted publickey for core from 10.200.16.10 port 39850 ssh2: RSA SHA256:wOn5dyIm5WplZhFa/bL50fgwDeFFpayVU2x0GGhbAwc Nov 5 15:52:08.923508 sshd-session[2828]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:52:08.927785 systemd-logind[2100]: New session 13 of user core. Nov 5 15:52:08.938799 systemd[1]: Started session-13.scope - Session 13 of User core. Nov 5 15:52:09.420201 sshd[2834]: Connection closed by 10.200.16.10 port 39850 Nov 5 15:52:09.420572 sshd-session[2828]: pam_unix(sshd:session): session closed for user core Nov 5 15:52:09.424221 systemd[1]: sshd@10-10.200.8.31:22-10.200.16.10:39850.service: Deactivated successfully. Nov 5 15:52:09.425984 systemd[1]: session-13.scope: Deactivated successfully. Nov 5 15:52:09.426734 systemd-logind[2100]: Session 13 logged out. Waiting for processes to exit. Nov 5 15:52:09.427588 systemd-logind[2100]: Removed session 13. Nov 5 15:52:19.556188 systemd[1]: Started sshd@11-10.200.8.31:22-10.200.16.10:37260.service - OpenSSH per-connection server daemon (10.200.16.10:37260). Nov 5 15:52:20.261775 sshd[3560]: Accepted publickey for core from 10.200.16.10 port 37260 ssh2: RSA SHA256:wOn5dyIm5WplZhFa/bL50fgwDeFFpayVU2x0GGhbAwc Nov 5 15:52:20.262987 sshd-session[3560]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:52:20.267231 systemd-logind[2100]: New session 14 of user core. Nov 5 15:52:20.289792 systemd[1]: Started session-14.scope - Session 14 of User core. Nov 5 15:52:20.761952 sshd[3563]: Connection closed by 10.200.16.10 port 37260 Nov 5 15:52:20.762278 sshd-session[3560]: pam_unix(sshd:session): session closed for user core Nov 5 15:52:20.765823 systemd[1]: sshd@11-10.200.8.31:22-10.200.16.10:37260.service: Deactivated successfully. Nov 5 15:52:20.767389 systemd[1]: session-14.scope: Deactivated successfully. Nov 5 15:52:20.768078 systemd-logind[2100]: Session 14 logged out. Waiting for processes to exit. Nov 5 15:52:20.768954 systemd-logind[2100]: Removed session 14. Nov 5 15:52:20.889910 systemd[1]: Started sshd@12-10.200.8.31:22-10.200.16.10:47690.service - OpenSSH per-connection server daemon (10.200.16.10:47690). Nov 5 15:52:21.596893 sshd[3569]: Accepted publickey for core from 10.200.16.10 port 47690 ssh2: RSA SHA256:wOn5dyIm5WplZhFa/bL50fgwDeFFpayVU2x0GGhbAwc Nov 5 15:52:21.598112 sshd-session[3569]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:52:21.602315 systemd-logind[2100]: New session 15 of user core. Nov 5 15:52:21.618794 systemd[1]: Started session-15.scope - Session 15 of User core. Nov 5 15:52:22.093695 sshd[3572]: Connection closed by 10.200.16.10 port 47690 Nov 5 15:52:22.094037 sshd-session[3569]: pam_unix(sshd:session): session closed for user core Nov 5 15:52:22.097564 systemd[1]: sshd@12-10.200.8.31:22-10.200.16.10:47690.service: Deactivated successfully. Nov 5 15:52:22.099161 systemd[1]: session-15.scope: Deactivated successfully. Nov 5 15:52:22.099872 systemd-logind[2100]: Session 15 logged out. Waiting for processes to exit. Nov 5 15:52:22.100723 systemd-logind[2100]: Removed session 15. Nov 5 15:52:32.217922 systemd[1]: Started sshd@13-10.200.8.31:22-10.200.16.10:57496.service - OpenSSH per-connection server daemon (10.200.16.10:57496). Nov 5 15:52:32.924975 sshd[3578]: Accepted publickey for core from 10.200.16.10 port 57496 ssh2: RSA SHA256:wOn5dyIm5WplZhFa/bL50fgwDeFFpayVU2x0GGhbAwc Nov 5 15:52:32.926196 sshd-session[3578]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:52:32.930416 systemd-logind[2100]: New session 16 of user core. Nov 5 15:52:32.939802 systemd[1]: Started session-16.scope - Session 16 of User core. Nov 5 15:52:33.424407 sshd[3637]: Connection closed by 10.200.16.10 port 57496 Nov 5 15:52:33.424736 sshd-session[3578]: pam_unix(sshd:session): session closed for user core Nov 5 15:52:33.428138 systemd[1]: sshd@13-10.200.8.31:22-10.200.16.10:57496.service: Deactivated successfully. Nov 5 15:52:33.429803 systemd[1]: session-16.scope: Deactivated successfully. Nov 5 15:52:33.430846 systemd-logind[2100]: Session 16 logged out. Waiting for processes to exit. Nov 5 15:52:33.431743 systemd-logind[2100]: Removed session 16. Nov 5 15:52:33.549085 systemd[1]: Started sshd@14-10.200.8.31:22-10.200.16.10:57500.service - OpenSSH per-connection server daemon (10.200.16.10:57500). Nov 5 15:52:34.260878 sshd[3643]: Accepted publickey for core from 10.200.16.10 port 57500 ssh2: RSA SHA256:wOn5dyIm5WplZhFa/bL50fgwDeFFpayVU2x0GGhbAwc Nov 5 15:52:34.262074 sshd-session[3643]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:52:34.266269 systemd-logind[2100]: New session 17 of user core. Nov 5 15:52:34.277813 systemd[1]: Started session-17.scope - Session 17 of User core. Nov 5 15:52:34.759718 sshd[3767]: Connection closed by 10.200.16.10 port 57500 Nov 5 15:52:34.759623 sshd-session[3643]: pam_unix(sshd:session): session closed for user core Nov 5 15:52:34.764802 systemd[1]: sshd@14-10.200.8.31:22-10.200.16.10:57500.service: Deactivated successfully. Nov 5 15:52:34.767580 systemd[1]: session-17.scope: Deactivated successfully. Nov 5 15:52:34.769381 systemd-logind[2100]: Session 17 logged out. Waiting for processes to exit. Nov 5 15:52:34.771642 systemd-logind[2100]: Removed session 17. Nov 5 15:52:44.889924 systemd[1]: Started sshd@15-10.200.8.31:22-10.200.16.10:46772.service - OpenSSH per-connection server daemon (10.200.16.10:46772). Nov 5 15:52:45.614716 sshd[8454]: Accepted publickey for core from 10.200.16.10 port 46772 ssh2: RSA SHA256:wOn5dyIm5WplZhFa/bL50fgwDeFFpayVU2x0GGhbAwc Nov 5 15:52:45.615345 sshd-session[8454]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:52:45.622461 systemd-logind[2100]: New session 18 of user core. Nov 5 15:52:45.632763 systemd[1]: Started session-18.scope - Session 18 of User core. Nov 5 15:52:46.112059 sshd[8687]: Connection closed by 10.200.16.10 port 46772 Nov 5 15:52:46.112603 sshd-session[8454]: pam_unix(sshd:session): session closed for user core Nov 5 15:52:46.116358 systemd[1]: sshd@15-10.200.8.31:22-10.200.16.10:46772.service: Deactivated successfully. Nov 5 15:52:46.120558 systemd[1]: session-18.scope: Deactivated successfully. Nov 5 15:52:46.121663 systemd-logind[2100]: Session 18 logged out. Waiting for processes to exit. Nov 5 15:52:46.123966 systemd-logind[2100]: Removed session 18. Nov 5 15:52:46.240133 systemd[1]: Started sshd@16-10.200.8.31:22-10.200.16.10:46774.service - OpenSSH per-connection server daemon (10.200.16.10:46774). Nov 5 15:52:46.961215 sshd[8975]: Accepted publickey for core from 10.200.16.10 port 46774 ssh2: RSA SHA256:wOn5dyIm5WplZhFa/bL50fgwDeFFpayVU2x0GGhbAwc Nov 5 15:52:46.962885 sshd-session[8975]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:52:46.971296 systemd-logind[2100]: New session 19 of user core. Nov 5 15:52:46.983809 systemd[1]: Started session-19.scope - Session 19 of User core. Nov 5 15:52:47.458437 sshd[9297]: Connection closed by 10.200.16.10 port 46774 Nov 5 15:52:47.458778 sshd-session[8975]: pam_unix(sshd:session): session closed for user core Nov 5 15:52:47.462466 systemd-logind[2100]: Session 19 logged out. Waiting for processes to exit. Nov 5 15:52:47.464207 systemd[1]: sshd@16-10.200.8.31:22-10.200.16.10:46774.service: Deactivated successfully. Nov 5 15:52:47.467253 systemd[1]: session-19.scope: Deactivated successfully. Nov 5 15:52:47.471923 systemd-logind[2100]: Removed session 19. Nov 5 15:52:57.593918 systemd[1]: Started sshd@17-10.200.8.31:22-10.200.16.10:54222.service - OpenSSH per-connection server daemon (10.200.16.10:54222). Nov 5 15:52:58.347038 sshd[13417]: Accepted publickey for core from 10.200.16.10 port 54222 ssh2: RSA SHA256:wOn5dyIm5WplZhFa/bL50fgwDeFFpayVU2x0GGhbAwc Nov 5 15:52:58.348316 sshd-session[13417]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:52:58.355517 systemd-logind[2100]: New session 20 of user core. Nov 5 15:52:58.367873 systemd[1]: Started session-20.scope - Session 20 of User core. Nov 5 15:52:58.914956 sshd[13711]: Connection closed by 10.200.16.10 port 54222 Nov 5 15:52:58.913867 sshd-session[13417]: pam_unix(sshd:session): session closed for user core Nov 5 15:52:58.919497 systemd-logind[2100]: Session 20 logged out. Waiting for processes to exit. Nov 5 15:52:58.921385 systemd[1]: sshd@17-10.200.8.31:22-10.200.16.10:54222.service: Deactivated successfully. Nov 5 15:52:58.923406 systemd[1]: session-20.scope: Deactivated successfully. Nov 5 15:52:58.926682 systemd-logind[2100]: Removed session 20. Nov 5 15:52:59.040404 systemd[1]: Started sshd@18-10.200.8.31:22-10.200.16.10:54234.service - OpenSSH per-connection server daemon (10.200.16.10:54234). Nov 5 15:52:59.999696 sshd[14063]: Accepted publickey for core from 10.200.16.10 port 54234 ssh2: RSA SHA256:wOn5dyIm5WplZhFa/bL50fgwDeFFpayVU2x0GGhbAwc Nov 5 15:52:59.999544 sshd-session[14063]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:53:00.009084 systemd-logind[2100]: New session 21 of user core. Nov 5 15:53:00.018837 systemd[1]: Started session-21.scope - Session 21 of User core. Nov 5 15:53:00.507206 sshd[14254]: Connection closed by 10.200.16.10 port 54234 Nov 5 15:53:00.507549 sshd-session[14063]: pam_unix(sshd:session): session closed for user core Nov 5 15:53:00.511267 systemd[1]: sshd@18-10.200.8.31:22-10.200.16.10:54234.service: Deactivated successfully. Nov 5 15:53:00.512846 systemd[1]: session-21.scope: Deactivated successfully. Nov 5 15:53:00.513531 systemd-logind[2100]: Session 21 logged out. Waiting for processes to exit. Nov 5 15:53:00.514461 systemd-logind[2100]: Removed session 21. Nov 5 15:53:10.638941 systemd[1]: Started sshd@19-10.200.8.31:22-10.200.16.10:43786.service - OpenSSH per-connection server daemon (10.200.16.10:43786). Nov 5 15:53:11.364767 sshd[14692]: Accepted publickey for core from 10.200.16.10 port 43786 ssh2: RSA SHA256:wOn5dyIm5WplZhFa/bL50fgwDeFFpayVU2x0GGhbAwc Nov 5 15:53:11.366060 sshd-session[14692]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:53:11.372229 systemd-logind[2100]: New session 22 of user core. Nov 5 15:53:11.380831 systemd[1]: Started session-22.scope - Session 22 of User core. Nov 5 15:53:11.867199 sshd[14713]: Connection closed by 10.200.16.10 port 43786 Nov 5 15:53:11.871864 sshd-session[14692]: pam_unix(sshd:session): session closed for user core Nov 5 15:53:11.875483 systemd[1]: sshd@19-10.200.8.31:22-10.200.16.10:43786.service: Deactivated successfully. Nov 5 15:53:11.878041 systemd[1]: session-22.scope: Deactivated successfully. Nov 5 15:53:11.879210 systemd-logind[2100]: Session 22 logged out. Waiting for processes to exit. Nov 5 15:53:11.882107 systemd-logind[2100]: Removed session 22. Nov 5 15:53:11.995233 systemd[1]: Started sshd@20-10.200.8.31:22-10.200.16.10:43796.service - OpenSSH per-connection server daemon (10.200.16.10:43796). Nov 5 15:53:12.729603 sshd[14727]: Accepted publickey for core from 10.200.16.10 port 43796 ssh2: RSA SHA256:wOn5dyIm5WplZhFa/bL50fgwDeFFpayVU2x0GGhbAwc Nov 5 15:53:12.731409 sshd-session[14727]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:53:12.738738 systemd-logind[2100]: New session 23 of user core. Nov 5 15:53:12.744430 systemd[1]: Started session-23.scope - Session 23 of User core. Nov 5 15:53:13.232244 sshd[14753]: Connection closed by 10.200.16.10 port 43796 Nov 5 15:53:13.233050 sshd-session[14727]: pam_unix(sshd:session): session closed for user core Nov 5 15:53:13.236836 systemd-logind[2100]: Session 23 logged out. Waiting for processes to exit. Nov 5 15:53:13.238274 systemd[1]: sshd@20-10.200.8.31:22-10.200.16.10:43796.service: Deactivated successfully. Nov 5 15:53:13.239998 systemd[1]: session-23.scope: Deactivated successfully. Nov 5 15:53:13.242246 systemd-logind[2100]: Removed session 23. Nov 5 15:53:23.370762 systemd[1]: Started sshd@21-10.200.8.31:22-10.200.16.10:37820.service - OpenSSH per-connection server daemon (10.200.16.10:37820). Nov 5 15:53:24.098409 sshd[15119]: Accepted publickey for core from 10.200.16.10 port 37820 ssh2: RSA SHA256:wOn5dyIm5WplZhFa/bL50fgwDeFFpayVU2x0GGhbAwc Nov 5 15:53:24.099788 sshd-session[15119]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:53:24.107797 systemd-logind[2100]: New session 24 of user core. Nov 5 15:53:24.116875 systemd[1]: Started session-24.scope - Session 24 of User core. Nov 5 15:53:24.598830 sshd[15134]: Connection closed by 10.200.16.10 port 37820 Nov 5 15:53:24.599438 sshd-session[15119]: pam_unix(sshd:session): session closed for user core Nov 5 15:53:24.603745 systemd-logind[2100]: Session 24 logged out. Waiting for processes to exit. Nov 5 15:53:24.604244 systemd[1]: sshd@21-10.200.8.31:22-10.200.16.10:37820.service: Deactivated successfully. Nov 5 15:53:24.605887 systemd[1]: session-24.scope: Deactivated successfully. Nov 5 15:53:24.608073 systemd-logind[2100]: Removed session 24. Nov 5 15:53:24.726542 systemd[1]: Started sshd@22-10.200.8.31:22-10.200.16.10:37832.service - OpenSSH per-connection server daemon (10.200.16.10:37832). Nov 5 15:53:25.460588 sshd[15158]: Accepted publickey for core from 10.200.16.10 port 37832 ssh2: RSA SHA256:wOn5dyIm5WplZhFa/bL50fgwDeFFpayVU2x0GGhbAwc Nov 5 15:53:25.462375 sshd-session[15158]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:53:25.473360 systemd-logind[2100]: New session 25 of user core. Nov 5 15:53:25.479775 systemd[1]: Started session-25.scope - Session 25 of User core. Nov 5 15:53:25.961799 sshd[15174]: Connection closed by 10.200.16.10 port 37832 Nov 5 15:53:25.964073 sshd-session[15158]: pam_unix(sshd:session): session closed for user core Nov 5 15:53:25.972012 systemd[1]: sshd@22-10.200.8.31:22-10.200.16.10:37832.service: Deactivated successfully. Nov 5 15:53:25.974241 systemd[1]: session-25.scope: Deactivated successfully. Nov 5 15:53:25.975987 systemd-logind[2100]: Session 25 logged out. Waiting for processes to exit. Nov 5 15:53:25.978112 systemd-logind[2100]: Removed session 25. Nov 5 15:53:36.094449 systemd[1]: Started sshd@23-10.200.8.31:22-10.200.16.10:50856.service - OpenSSH per-connection server daemon (10.200.16.10:50856). Nov 5 15:53:36.803442 sshd[15422]: Accepted publickey for core from 10.200.16.10 port 50856 ssh2: RSA SHA256:wOn5dyIm5WplZhFa/bL50fgwDeFFpayVU2x0GGhbAwc Nov 5 15:53:36.804731 sshd-session[15422]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:53:36.809778 systemd-logind[2100]: New session 26 of user core. Nov 5 15:53:36.819814 systemd[1]: Started session-26.scope - Session 26 of User core. Nov 5 15:53:37.302145 sshd[15425]: Connection closed by 10.200.16.10 port 50856 Nov 5 15:53:37.302511 sshd-session[15422]: pam_unix(sshd:session): session closed for user core Nov 5 15:53:37.306962 systemd[1]: sshd@23-10.200.8.31:22-10.200.16.10:50856.service: Deactivated successfully. Nov 5 15:53:37.308562 systemd[1]: session-26.scope: Deactivated successfully. Nov 5 15:53:37.309285 systemd-logind[2100]: Session 26 logged out. Waiting for processes to exit. Nov 5 15:53:37.310161 systemd-logind[2100]: Removed session 26. Nov 5 15:53:37.438274 systemd[1]: Started sshd@24-10.200.8.31:22-10.200.16.10:50866.service - OpenSSH per-connection server daemon (10.200.16.10:50866). Nov 5 15:53:38.161907 sshd[15431]: Accepted publickey for core from 10.200.16.10 port 50866 ssh2: RSA SHA256:wOn5dyIm5WplZhFa/bL50fgwDeFFpayVU2x0GGhbAwc Nov 5 15:53:38.163282 sshd-session[15431]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:53:38.167441 systemd-logind[2100]: New session 27 of user core. Nov 5 15:53:38.175793 systemd[1]: Started session-27.scope - Session 27 of User core. Nov 5 15:53:38.660738 sshd[15466]: Connection closed by 10.200.16.10 port 50866 Nov 5 15:53:38.661094 sshd-session[15431]: pam_unix(sshd:session): session closed for user core Nov 5 15:53:38.664741 systemd[1]: sshd@24-10.200.8.31:22-10.200.16.10:50866.service: Deactivated successfully. Nov 5 15:53:38.666462 systemd[1]: session-27.scope: Deactivated successfully. Nov 5 15:53:38.667300 systemd-logind[2100]: Session 27 logged out. Waiting for processes to exit. Nov 5 15:53:38.668213 systemd-logind[2100]: Removed session 27. Nov 5 15:53:39.974144 setup-nvidia[2640]: \u000d Nov 5 15:53:39.974144 setup-nvidia[2640]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Nov 5 15:53:39.974144 setup-nvidia[2640]: frequently when this kernel module was built against the wrong or Nov 5 15:53:39.974144 setup-nvidia[2640]: improperly configured kernel sources, with a version of gcc that differs Nov 5 15:53:39.974144 setup-nvidia[2640]: from the one used to build the target kernel, or if another driver, such Nov 5 15:53:39.974144 setup-nvidia[2640]: as nouveau, is present and prevents the NVIDIA kernel module from Nov 5 15:53:39.974144 setup-nvidia[2640]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Nov 5 15:53:39.974144 setup-nvidia[2640]: installed in this system is supported by this NVIDIA Linux graphics Nov 5 15:53:39.974144 setup-nvidia[2640]: driver release. Nov 5 15:53:39.974144 setup-nvidia[2640]: \u000d Nov 5 15:53:39.974144 setup-nvidia[2640]: Please see the log entries 'Kernel module load error' and 'Kernel Nov 5 15:53:39.974144 setup-nvidia[2640]: messages' at the end of the file Nov 5 15:53:39.974144 setup-nvidia[2640]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for more Nov 5 15:53:39.974144 setup-nvidia[2640]: information. Nov 5 15:53:39.974144 setup-nvidia[2640]: \u000d Nov 5 15:53:39.988586 setup-nvidia[2640]: dmesg: read kernel buffer failed: Operation not permitted Nov 5 15:53:40.007063 setup-nvidia[2640]: \u000d Nov 5 15:53:40.007063 setup-nvidia[2640]: ERROR: Installation has failed. Please see the file Nov 5 15:53:40.007063 setup-nvidia[2640]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for Nov 5 15:53:40.007063 setup-nvidia[2640]: details. You may find suggestions on fixing installation problems in Nov 5 15:53:40.007063 setup-nvidia[2640]: the README available on the Linux driver download page at Nov 5 15:53:40.007063 setup-nvidia[2640]: www.nvidia.com. Nov 5 15:53:40.007063 setup-nvidia[2640]: \u000d Nov 5 15:53:40.007546 setup-nvidia[2640]: Last 50 lines of nvidia-installer.log: Nov 5 15:53:40.008422 setup-nvidia[2640]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039059rm+0x53: 'naked' return found in MITIGATION_RETHUNK build Nov 5 15:53:40.008422 setup-nvidia[2640]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039056rm+0x90: 'naked' return found in MITIGATION_RETHUNK build Nov 5 15:53:40.008422 setup-nvidia[2640]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039049rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build Nov 5 15:53:40.008422 setup-nvidia[2640]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039105rm+0x93: 'naked' return found in MITIGATION_RETHUNK build Nov 5 15:53:40.008422 setup-nvidia[2640]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039101rm+0x36: 'naked' return found in MITIGATION_RETHUNK build Nov 5 15:53:40.008422 setup-nvidia[2640]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039100rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build Nov 5 15:53:40.008422 setup-nvidia[2640]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039102rm+0x77: 'naked' return found in MITIGATION_RETHUNK build Nov 5 15:53:40.008422 setup-nvidia[2640]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039046rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build Nov 5 15:53:40.008422 setup-nvidia[2640]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030615rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build Nov 5 15:53:40.008422 setup-nvidia[2640]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030614rm+0x27: 'naked' return found in MITIGATION_RETHUNK build Nov 5 15:53:40.008422 setup-nvidia[2640]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030618rm+0x37: 'naked' return found in MITIGATION_RETHUNK build Nov 5 15:53:40.008422 setup-nvidia[2640]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030617rm+0x24: 'naked' return found in MITIGATION_RETHUNK build Nov 5 15:53:40.008422 setup-nvidia[2640]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030616rm+0xca: 'naked' return found in MITIGATION_RETHUNK build Nov 5 15:53:40.008422 setup-nvidia[2640]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030619rm+0x193: 'naked' return found in MITIGATION_RETHUNK build Nov 5 15:53:40.008422 setup-nvidia[2640]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030621rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build Nov 5 15:53:40.008422 setup-nvidia[2640]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv011864rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build Nov 5 15:53:40.008422 setup-nvidia[2640]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039413rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build Nov 5 15:53:40.008422 setup-nvidia[2640]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/Module.symvers Nov 5 15:53:40.008422 setup-nvidia[2640]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.mod.o Nov 5 15:53:40.008422 setup-nvidia[2640]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/.module-common.o Nov 5 15:53:40.008422 setup-nvidia[2640]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.mod.o Nov 5 15:53:40.008422 setup-nvidia[2640]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.mod.o Nov 5 15:53:40.008422 setup-nvidia[2640]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.mod.o Nov 5 15:53:40.008422 setup-nvidia[2640]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.mod.o Nov 5 15:53:40.008422 setup-nvidia[2640]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Nov 5 15:53:40.008422 setup-nvidia[2640]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Nov 5 15:53:40.008422 setup-nvidia[2640]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Nov 5 15:53:40.008422 setup-nvidia[2640]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Nov 5 15:53:40.008422 setup-nvidia[2640]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Nov 5 15:53:40.008422 setup-nvidia[2640]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Nov 5 15:53:40.008422 setup-nvidia[2640]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko due to unavailability of vmlinux Nov 5 15:53:40.008422 setup-nvidia[2640]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Nov 5 15:53:40.008422 setup-nvidia[2640]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko due to unavailability of vmlinux Nov 5 15:53:40.008422 setup-nvidia[2640]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Nov 5 15:53:40.008422 setup-nvidia[2640]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko due to unavailability of vmlinux Nov 5 15:53:40.008422 setup-nvidia[2640]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Nov 5 15:53:40.008422 setup-nvidia[2640]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko due to unavailability of vmlinux Nov 5 15:53:40.008422 setup-nvidia[2640]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Nov 5 15:53:40.008422 setup-nvidia[2640]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko due to unavailability of vmlinux Nov 5 15:53:40.008422 setup-nvidia[2640]: make[2]: Leaving directory '/usr/lib/modules/6.12.54-flatcar/build' Nov 5 15:53:40.008422 setup-nvidia[2640]: make[1]: Leaving directory '/usr/lib/modules/6.12.54-flatcar/source' Nov 5 15:53:40.008422 setup-nvidia[2640]: -> done. Nov 5 15:53:40.008422 setup-nvidia[2640]: -> Kernel module compilation complete. Nov 5 15:53:40.008422 setup-nvidia[2640]: -> Unable to determine if Secure Boot is enabled: No such file or directory Nov 5 15:53:40.008422 setup-nvidia[2640]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most frequently when this kernel module was built against the wrong or improperly configured kernel sources, with a version of gcc that differs from the one used to build the target kernel, or if another driver, such as nouveau, is present and prevents the NVIDIA kernel module from obtaining ownership of the NVIDIA device(s), or no NVIDIA device installed in this system is supported by this NVIDIA Linux graphics driver release. Nov 5 15:53:40.009761 setup-nvidia[2640]: \u000d Nov 5 15:53:40.009761 setup-nvidia[2640]: Please see the log entries 'Kernel module load error' and 'Kernel messages' at the end of the file '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for more information. Nov 5 15:53:40.009761 setup-nvidia[2640]: -> Kernel module load error: Operation not permitted Nov 5 15:53:40.009761 setup-nvidia[2640]: -> Kernel messages: Nov 5 15:53:40.009761 setup-nvidia[2640]: ERROR: Installation has failed. Please see the file '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for details. You may find suggestions on fixing installation problems in the README available on the Linux driver download page at www.nvidia.com. Nov 5 15:53:48.791785 systemd[1]: Started sshd@25-10.200.8.31:22-10.200.16.10:38268.service - OpenSSH per-connection server daemon (10.200.16.10:38268). Nov 5 15:53:49.517220 sshd[15620]: Accepted publickey for core from 10.200.16.10 port 38268 ssh2: RSA SHA256:wOn5dyIm5WplZhFa/bL50fgwDeFFpayVU2x0GGhbAwc Nov 5 15:53:49.518514 sshd-session[15620]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:53:49.523019 systemd-logind[2100]: New session 28 of user core. Nov 5 15:53:49.533811 systemd[1]: Started session-28.scope - Session 28 of User core. Nov 5 15:53:50.013653 sshd[15623]: Connection closed by 10.200.16.10 port 38268 Nov 5 15:53:50.013982 sshd-session[15620]: pam_unix(sshd:session): session closed for user core Nov 5 15:53:50.017523 systemd[1]: sshd@25-10.200.8.31:22-10.200.16.10:38268.service: Deactivated successfully. Nov 5 15:53:50.019065 systemd[1]: session-28.scope: Deactivated successfully. Nov 5 15:53:50.019742 systemd-logind[2100]: Session 28 logged out. Waiting for processes to exit. Nov 5 15:53:50.020579 systemd-logind[2100]: Removed session 28. Nov 5 15:53:50.140756 systemd[1]: Started sshd@26-10.200.8.31:22-10.200.16.10:50708.service - OpenSSH per-connection server daemon (10.200.16.10:50708). Nov 5 15:53:50.850227 sshd[15629]: Accepted publickey for core from 10.200.16.10 port 50708 ssh2: RSA SHA256:wOn5dyIm5WplZhFa/bL50fgwDeFFpayVU2x0GGhbAwc Nov 5 15:53:50.851464 sshd-session[15629]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:53:50.855739 systemd-logind[2100]: New session 29 of user core. Nov 5 15:53:50.864788 systemd[1]: Started session-29.scope - Session 29 of User core. Nov 5 15:53:51.347332 sshd[15634]: Connection closed by 10.200.16.10 port 50708 Nov 5 15:53:51.347684 sshd-session[15629]: pam_unix(sshd:session): session closed for user core Nov 5 15:53:51.351134 systemd[1]: sshd@26-10.200.8.31:22-10.200.16.10:50708.service: Deactivated successfully. Nov 5 15:53:51.352613 systemd[1]: session-29.scope: Deactivated successfully. Nov 5 15:53:51.353313 systemd-logind[2100]: Session 29 logged out. Waiting for processes to exit. Nov 5 15:53:51.354191 systemd-logind[2100]: Removed session 29. Nov 5 15:53:55.453704 kernel: EXT4-fs (loop7p9): unmounting filesystem 6ebeca74-615c-4ade-93c7-dfb442225868. Nov 5 15:53:55.454056 setup-nvidia[2640]: Container flatcardevelopercontainer-4487.0.1.bin exited successfully. Nov 5 15:53:55.456269 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4487.0.1.bin.mount: Deactivated successfully. Nov 5 15:53:55.506521 sudo[2628]: pam_unix(sudo:session): session closed for user root Nov 5 15:53:55.508590 systemd[1]: session-c2.scope: Deactivated successfully. Nov 5 15:53:55.508875 systemd[1]: session-c2.scope: Consumed 5min 15.953s CPU time, 891.4M memory peak. Nov 5 15:53:55.939131 setup-nvidia[2095]: /opt/nvidia / Nov 5 15:53:55.940139 setup-nvidia[2095]: / Nov 5 15:53:55.949696 kernel: IPMI message handler: version 39.2 Nov 5 15:53:55.952690 kernel: ipmi device interface Nov 5 15:53:55.965703 setup-nvidia[2095]: /opt/nvidia/current/usr/lib/modules/6.12.54-flatcar/video / Nov 5 15:53:56.020651 kernel: nvidia: loading out-of-tree module taints kernel. Nov 5 15:53:56.020805 kernel: nvidia: module license 'NVIDIA' taints kernel. Nov 5 15:53:56.020831 kernel: Disabling lock debugging due to kernel taint Nov 5 15:53:56.027996 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Nov 5 15:53:56.028041 kernel: nvidia: module license taints kernel. Nov 5 15:53:56.425695 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 Nov 5 15:53:56.835367 kernel: Nov 5 15:53:56.835508 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Nov 5 15:53:57.041696 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.261.03 Sat Jun 14 16:05:50 UTC 2025 Nov 5 15:53:57.064697 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.261.03 Sat Jun 14 15:39:16 UTC 2025 Nov 5 15:53:57.083714 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Nov 5 15:53:57.132543 setup-nvidia[2095]: / Nov 5 15:53:57.132982 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. Nov 5 15:53:57.141955 setup-nvidia[15674]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Nov 5 15:53:59.027249 setup-nvidia[15691]: Wed Nov 5 15:53:59 2025 Nov 5 15:53:59.027249 setup-nvidia[15691]: +---------------------------------------------------------------------------------------+ Nov 5 15:53:59.027249 setup-nvidia[15691]: | NVIDIA-SMI 535.261.03 Driver Version: 535.261.03 CUDA Version: 12.2 | Nov 5 15:53:59.027249 setup-nvidia[15691]: |-----------------------------------------+----------------------+----------------------+ Nov 5 15:53:59.027249 setup-nvidia[15691]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Nov 5 15:53:59.027249 setup-nvidia[15691]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Nov 5 15:53:59.027249 setup-nvidia[15691]: | | | MIG M. | Nov 5 15:53:59.027249 setup-nvidia[15691]: |=========================================+======================+======================| Nov 5 15:53:59.030773 setup-nvidia[15691]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Nov 5 15:53:59.030773 setup-nvidia[15691]: | N/A 27C P0 39W / 250W | 0MiB / 16384MiB | 0% Default | Nov 5 15:53:59.030773 setup-nvidia[15691]: | | | N/A | Nov 5 15:53:59.030773 setup-nvidia[15691]: +-----------------------------------------+----------------------+----------------------+ Nov 5 15:53:59.030887 setup-nvidia[15691]: Nov 5 15:53:59.030887 setup-nvidia[15691]: +---------------------------------------------------------------------------------------+ Nov 5 15:53:59.030887 setup-nvidia[15691]: | Processes: | Nov 5 15:53:59.030887 setup-nvidia[15691]: | GPU GI CI PID Type Process name GPU Memory | Nov 5 15:53:59.030887 setup-nvidia[15691]: | ID ID Usage | Nov 5 15:53:59.030887 setup-nvidia[15691]: |=======================================================================================| Nov 5 15:53:59.031481 setup-nvidia[15691]: | No running processes found | Nov 5 15:53:59.031481 setup-nvidia[15691]: +---------------------------------------------------------------------------------------+ Nov 5 15:53:59.461407 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Nov 5 15:53:59.463488 systemd[1]: Reached target multi-user.target - Multi-User System. Nov 5 15:53:59.463795 systemd[1]: Startup finished in 1.118s (firmware) + 11.324s (loader) + 5.236s (kernel) + 12.861s (initrd) + 3min 40.787s (userspace) = 4min 11.328s. Nov 5 15:54:01.478546 systemd[1]: Started sshd@27-10.200.8.31:22-10.200.16.10:41576.service - OpenSSH per-connection server daemon (10.200.16.10:41576). Nov 5 15:54:02.183267 sshd[15698]: Accepted publickey for core from 10.200.16.10 port 41576 ssh2: RSA SHA256:wOn5dyIm5WplZhFa/bL50fgwDeFFpayVU2x0GGhbAwc Nov 5 15:54:02.187049 sshd-session[15698]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:54:02.191710 systemd-logind[2100]: New session 30 of user core. Nov 5 15:54:02.201800 systemd[1]: Started session-30.scope - Session 30 of User core. Nov 5 15:54:02.680519 sshd[15702]: Connection closed by 10.200.16.10 port 41576 Nov 5 15:54:02.680924 sshd-session[15698]: pam_unix(sshd:session): session closed for user core Nov 5 15:54:02.684710 systemd[1]: sshd@27-10.200.8.31:22-10.200.16.10:41576.service: Deactivated successfully. Nov 5 15:54:02.686341 systemd[1]: session-30.scope: Deactivated successfully. Nov 5 15:54:02.687221 systemd-logind[2100]: Session 30 logged out. Waiting for processes to exit. Nov 5 15:54:02.688213 systemd-logind[2100]: Removed session 30. Nov 5 15:54:02.809895 systemd[1]: Started sshd@28-10.200.8.31:22-10.200.16.10:41578.service - OpenSSH per-connection server daemon (10.200.16.10:41578). Nov 5 15:54:03.541623 sshd[15708]: Accepted publickey for core from 10.200.16.10 port 41578 ssh2: RSA SHA256:wOn5dyIm5WplZhFa/bL50fgwDeFFpayVU2x0GGhbAwc Nov 5 15:54:03.543702 sshd-session[15708]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:54:03.548061 systemd-logind[2100]: New session 31 of user core. Nov 5 15:54:03.558795 systemd[1]: Started session-31.scope - Session 31 of User core. Nov 5 15:54:04.037397 sshd[15711]: Connection closed by 10.200.16.10 port 41578 Nov 5 15:54:04.037789 sshd-session[15708]: pam_unix(sshd:session): session closed for user core Nov 5 15:54:04.041497 systemd[1]: sshd@28-10.200.8.31:22-10.200.16.10:41578.service: Deactivated successfully. Nov 5 15:54:04.043177 systemd[1]: session-31.scope: Deactivated successfully. Nov 5 15:54:04.043902 systemd-logind[2100]: Session 31 logged out. Waiting for processes to exit. Nov 5 15:54:04.044809 systemd-logind[2100]: Removed session 31. Nov 5 15:54:04.166217 systemd[1]: Started sshd@29-10.200.8.31:22-10.200.16.10:41588.service - OpenSSH per-connection server daemon (10.200.16.10:41588). Nov 5 15:54:04.877590 sshd[15717]: Accepted publickey for core from 10.200.16.10 port 41588 ssh2: RSA SHA256:wOn5dyIm5WplZhFa/bL50fgwDeFFpayVU2x0GGhbAwc Nov 5 15:54:04.878895 sshd-session[15717]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:54:04.883294 systemd-logind[2100]: New session 32 of user core. Nov 5 15:54:04.893904 systemd[1]: Started session-32.scope - Session 32 of User core. Nov 5 15:54:05.397199 sshd[15720]: Connection closed by 10.200.16.10 port 41588 Nov 5 15:54:05.397560 sshd-session[15717]: pam_unix(sshd:session): session closed for user core Nov 5 15:54:05.401419 systemd[1]: sshd@29-10.200.8.31:22-10.200.16.10:41588.service: Deactivated successfully. Nov 5 15:54:05.403093 systemd[1]: session-32.scope: Deactivated successfully. Nov 5 15:54:05.403864 systemd-logind[2100]: Session 32 logged out. Waiting for processes to exit. Nov 5 15:54:05.404779 systemd-logind[2100]: Removed session 32. Nov 5 15:54:05.527272 systemd[1]: Started sshd@30-10.200.8.31:22-10.200.16.10:41596.service - OpenSSH per-connection server daemon (10.200.16.10:41596). Nov 5 15:54:05.528422 systemd[1]: Stopping user@0.service - User Manager for UID 0... Nov 5 15:54:05.529199 systemd[2631]: Activating special unit exit.target... Nov 5 15:54:05.529278 systemd[2631]: Stopped target default.target - Main User Target. Nov 5 15:54:05.529320 systemd[2631]: Stopped target basic.target - Basic System. Nov 5 15:54:05.529349 systemd[2631]: Stopped target paths.target - Paths. Nov 5 15:54:05.529374 systemd[2631]: Stopped target sockets.target - Sockets. Nov 5 15:54:05.529399 systemd[2631]: Stopped target timers.target - Timers. Nov 5 15:54:05.529743 systemd[2631]: Closed dbus.socket - D-Bus User Message Bus Socket. Nov 5 15:54:05.529957 systemd[2631]: Removed slice app.slice - User Application Slice. Nov 5 15:54:05.529989 systemd[2631]: Reached target shutdown.target - Shutdown. Nov 5 15:54:05.530127 systemd[2631]: Finished systemd-exit.service - Exit the Session. Nov 5 15:54:05.530164 systemd[2631]: Reached target exit.target - Exit the Session. Nov 5 15:54:05.549075 systemd-logind[2100]: Removed session c3. Nov 5 15:54:05.549088 (sd-pam)[2632]: pam_unix(systemd-user:session): session closed for user root Nov 5 15:54:05.550362 systemd[1]: user@0.service: Deactivated successfully. Nov 5 15:54:05.550740 systemd[1]: Stopped user@0.service - User Manager for UID 0. Nov 5 15:54:05.553008 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Nov 5 15:54:05.571981 systemd[1]: run-user-0.mount: Deactivated successfully. Nov 5 15:54:05.572921 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Nov 5 15:54:05.573125 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Nov 5 15:54:05.574834 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Nov 5 15:54:05.574923 systemd[1]: user-0.slice: Consumed 5min 16.191s CPU time, 897.6M memory peak. Nov 5 15:54:06.252105 sshd[15726]: Accepted publickey for core from 10.200.16.10 port 41596 ssh2: RSA SHA256:wOn5dyIm5WplZhFa/bL50fgwDeFFpayVU2x0GGhbAwc Nov 5 15:54:06.253421 sshd-session[15726]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:54:06.257954 systemd-logind[2100]: New session 33 of user core. Nov 5 15:54:06.269814 systemd[1]: Started session-33.scope - Session 33 of User core. Nov 5 15:54:07.689331 sudo[15734]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Nov 5 15:54:07.689637 sudo[15734]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 5 15:54:07.811401 sudo[15734]: pam_unix(sudo:session): session closed for user root Nov 5 15:54:07.927273 sshd[15733]: Connection closed by 10.200.16.10 port 41596 Nov 5 15:54:07.927662 sshd-session[15726]: pam_unix(sshd:session): session closed for user core Nov 5 15:54:07.931774 systemd[1]: sshd@30-10.200.8.31:22-10.200.16.10:41596.service: Deactivated successfully. Nov 5 15:54:07.933481 systemd[1]: session-33.scope: Deactivated successfully. Nov 5 15:54:07.934210 systemd-logind[2100]: Session 33 logged out. Waiting for processes to exit. Nov 5 15:54:07.935169 systemd-logind[2100]: Removed session 33. Nov 5 15:54:08.063121 systemd[1]: Started sshd@31-10.200.8.31:22-10.200.16.10:41612.service - OpenSSH per-connection server daemon (10.200.16.10:41612). Nov 5 15:54:08.775947 sshd[15740]: Accepted publickey for core from 10.200.16.10 port 41612 ssh2: RSA SHA256:wOn5dyIm5WplZhFa/bL50fgwDeFFpayVU2x0GGhbAwc Nov 5 15:54:08.777248 sshd-session[15740]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:54:08.781645 systemd-logind[2100]: New session 34 of user core. Nov 5 15:54:08.793832 systemd[1]: Started session-34.scope - Session 34 of User core. Nov 5 15:54:09.162495 sudo[15745]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Nov 5 15:54:09.162805 sudo[15745]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 5 15:54:09.170190 sudo[15745]: pam_unix(sudo:session): session closed for user root Nov 5 15:54:09.176805 sudo[15744]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Nov 5 15:54:09.177074 sudo[15744]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 5 15:54:09.185955 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 5 15:54:09.236097 augenrules[15767]: No rules Nov 5 15:54:09.237339 systemd[1]: audit-rules.service: Deactivated successfully. Nov 5 15:54:09.237621 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 5 15:54:09.238520 sudo[15744]: pam_unix(sudo:session): session closed for user root Nov 5 15:54:09.354212 sshd[15743]: Connection closed by 10.200.16.10 port 41612 Nov 5 15:54:09.354530 sshd-session[15740]: pam_unix(sshd:session): session closed for user core Nov 5 15:54:09.358168 systemd[1]: sshd@31-10.200.8.31:22-10.200.16.10:41612.service: Deactivated successfully. Nov 5 15:54:09.359764 systemd[1]: session-34.scope: Deactivated successfully. Nov 5 15:54:09.360478 systemd-logind[2100]: Session 34 logged out. Waiting for processes to exit. Nov 5 15:54:09.361322 systemd-logind[2100]: Removed session 34. Nov 5 15:54:09.478942 systemd[1]: Started sshd@32-10.200.8.31:22-10.200.16.10:41616.service - OpenSSH per-connection server daemon (10.200.16.10:41616). Nov 5 15:54:10.187162 sshd[15776]: Accepted publickey for core from 10.200.16.10 port 41616 ssh2: RSA SHA256:wOn5dyIm5WplZhFa/bL50fgwDeFFpayVU2x0GGhbAwc Nov 5 15:54:10.188387 sshd-session[15776]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:54:10.192661 systemd-logind[2100]: New session 35 of user core. Nov 5 15:54:10.201792 systemd[1]: Started session-35.scope - Session 35 of User core. Nov 5 15:54:10.573532 sudo[15780]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/mkdir -p . Nov 5 15:54:10.573837 sudo[15780]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 5 15:54:10.577727 sudo[15780]: pam_unix(sudo:session): session closed for user root Nov 5 15:54:10.691995 sshd[15779]: Connection closed by 10.200.16.10 port 41616 Nov 5 15:54:10.692326 sshd-session[15776]: pam_unix(sshd:session): session closed for user core Nov 5 15:54:10.696336 systemd[1]: sshd@32-10.200.8.31:22-10.200.16.10:41616.service: Deactivated successfully. Nov 5 15:54:10.697906 systemd[1]: session-35.scope: Deactivated successfully. Nov 5 15:54:10.699085 systemd-logind[2100]: Session 35 logged out. Waiting for processes to exit. Nov 5 15:54:10.699895 systemd-logind[2100]: Removed session 35. Nov 5 15:54:10.819973 systemd[1]: Started sshd@33-10.200.8.31:22-10.200.16.10:51964.service - OpenSSH per-connection server daemon (10.200.16.10:51964). Nov 5 15:54:11.527801 sshd[15786]: Accepted publickey for core from 10.200.16.10 port 51964 ssh2: RSA SHA256:wOn5dyIm5WplZhFa/bL50fgwDeFFpayVU2x0GGhbAwc Nov 5 15:54:11.529049 sshd-session[15786]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:54:11.533273 systemd-logind[2100]: New session 36 of user core. Nov 5 15:54:11.554792 systemd[1]: Started session-36.scope - Session 36 of User core. Nov 5 15:54:11.914547 sudo[15790]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/install -m 0755 /dev/stdin kolet Nov 5 15:54:11.914880 sudo[15790]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 5 15:54:15.944479 sudo[15790]: pam_unix(sudo:session): session closed for user root Nov 5 15:54:16.059871 sshd[15789]: Connection closed by 10.200.16.10 port 51964 Nov 5 15:54:16.060162 sshd-session[15786]: pam_unix(sshd:session): session closed for user core Nov 5 15:54:16.063925 systemd[1]: sshd@33-10.200.8.31:22-10.200.16.10:51964.service: Deactivated successfully. Nov 5 15:54:16.065472 systemd[1]: session-36.scope: Deactivated successfully. Nov 5 15:54:16.066164 systemd-logind[2100]: Session 36 logged out. Waiting for processes to exit. Nov 5 15:54:16.067109 systemd-logind[2100]: Removed session 36. Nov 5 15:54:16.187911 systemd[1]: Started sshd@34-10.200.8.31:22-10.200.16.10:51980.service - OpenSSH per-connection server daemon (10.200.16.10:51980). Nov 5 15:54:16.896132 sshd[15796]: Accepted publickey for core from 10.200.16.10 port 51980 ssh2: RSA SHA256:wOn5dyIm5WplZhFa/bL50fgwDeFFpayVU2x0GGhbAwc Nov 5 15:54:16.897449 sshd-session[15796]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:54:16.901865 systemd-logind[2100]: New session 37 of user core. Nov 5 15:54:16.918804 systemd[1]: Started session-37.scope - Session 37 of User core. Nov 5 15:54:18.394742 systemd[1]: Starting docker.service - Docker Application Container Engine... Nov 5 15:54:18.415960 (dockerd)[15819]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPTS, DOCKER_OPT_BIP, DOCKER_OPT_IPMASQ, DOCKER_OPT_MTU Nov 5 15:54:19.582525 dockerd[15819]: time="2025-11-05T15:54:19.582341070Z" level=info msg="Starting up" Nov 5 15:54:19.584887 dockerd[15819]: time="2025-11-05T15:54:19.583846829Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Nov 5 15:54:19.595843 dockerd[15819]: time="2025-11-05T15:54:19.595806204Z" level=info msg="Creating a containerd client" address=/var/run/docker/libcontainerd/docker-containerd.sock timeout=1m0s Nov 5 15:54:21.478446 dockerd[15819]: time="2025-11-05T15:54:21.478360825Z" level=info msg="Loading containers: start." Nov 5 15:54:21.499708 kernel: Initializing XFRM netlink socket Nov 5 15:54:21.790491 systemd-networkd[1799]: docker0: Link UP Nov 5 15:54:21.815640 dockerd[15819]: time="2025-11-05T15:54:21.815604641Z" level=info msg="Loading containers: done." Nov 5 15:54:21.844161 dockerd[15819]: time="2025-11-05T15:54:21.844114011Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2 Nov 5 15:54:21.844298 dockerd[15819]: time="2025-11-05T15:54:21.844249592Z" level=info msg="Docker daemon" commit=6430e49a55babd9b8f4d08e70ecb2b68900770fe containerd-snapshotter=false storage-driver=overlay2 version=28.0.4 Nov 5 15:54:21.844380 dockerd[15819]: time="2025-11-05T15:54:21.844356078Z" level=info msg="Initializing buildkit" Nov 5 15:54:21.913868 dockerd[15819]: time="2025-11-05T15:54:21.913832001Z" level=info msg="Completed buildkit initialization" Nov 5 15:54:21.918648 dockerd[15819]: time="2025-11-05T15:54:21.918614342Z" level=info msg="Daemon has completed initialization" Nov 5 15:54:21.918711 dockerd[15819]: time="2025-11-05T15:54:21.918663835Z" level=info msg="API listen on /run/docker.sock" Nov 5 15:54:21.918888 systemd[1]: Started docker.service - Docker Application Container Engine. Nov 5 15:54:23.077530 systemd[1]: var-lib-docker-overlay2-38a3a504be80e7b1a4029dd7b153b541f10dc368a87debdf4651196a10b18f17\x2dinit-merged.mount: Deactivated successfully. Nov 5 15:54:23.206995 containerd[2142]: time="2025-11-05T15:54:23.206939264Z" level=info msg="connecting to shim 7d9e611375747b154aa71018220d50c088661083075218bb9f60c23399a4c3bc" address="unix:///run/containerd/s/95d5b09f223fb3272cc4f7d42fd253d9dd9b1dcea363e3738107a95a38b309e0" namespace=moby protocol=ttrpc version=3 Nov 5 15:54:23.239836 systemd[1]: Started docker-7d9e611375747b154aa71018220d50c088661083075218bb9f60c23399a4c3bc.scope - libcontainer container 7d9e611375747b154aa71018220d50c088661083075218bb9f60c23399a4c3bc. Nov 5 15:54:23.289789 kernel: docker0: port 1(veth8b3fb2a) entered blocking state Nov 5 15:54:23.289885 kernel: docker0: port 1(veth8b3fb2a) entered disabled state Nov 5 15:54:23.292699 kernel: veth8b3fb2a: entered allmulticast mode Nov 5 15:54:23.295791 kernel: veth8b3fb2a: entered promiscuous mode Nov 5 15:54:23.297313 systemd-networkd[1799]: veth8b3fb2a: Link UP Nov 5 15:54:23.325703 kernel: eth0: renamed from vethca0b544 Nov 5 15:54:23.332916 kernel: docker0: port 1(veth8b3fb2a) entered blocking state Nov 5 15:54:23.332997 kernel: docker0: port 1(veth8b3fb2a) entered forwarding state Nov 5 15:54:23.333374 systemd-networkd[1799]: veth8b3fb2a: Gained carrier Nov 5 15:54:23.333809 systemd-networkd[1799]: docker0: Gained carrier Nov 5 15:54:24.722823 systemd-networkd[1799]: docker0: Gained IPv6LL Nov 5 15:54:25.234825 systemd-networkd[1799]: veth8b3fb2a: Gained IPv6LL Nov 5 15:54:26.405485 systemd[1]: docker-7d9e611375747b154aa71018220d50c088661083075218bb9f60c23399a4c3bc.scope: Deactivated successfully. Nov 5 15:54:26.418062 dockerd[15819]: time="2025-11-05T15:54:26.418006574Z" level=info msg="ignoring event" container=7d9e611375747b154aa71018220d50c088661083075218bb9f60c23399a4c3bc module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Nov 5 15:54:26.418481 containerd[2142]: time="2025-11-05T15:54:26.418305861Z" level=info msg="shim disconnected" id=7d9e611375747b154aa71018220d50c088661083075218bb9f60c23399a4c3bc namespace=moby Nov 5 15:54:26.418481 containerd[2142]: time="2025-11-05T15:54:26.418338360Z" level=warning msg="cleaning up after shim disconnected" id=7d9e611375747b154aa71018220d50c088661083075218bb9f60c23399a4c3bc namespace=moby Nov 5 15:54:26.418481 containerd[2142]: time="2025-11-05T15:54:26.418346559Z" level=info msg="cleaning up dead shim" namespace=moby Nov 5 15:54:26.449069 systemd-networkd[1799]: veth8b3fb2a: Lost carrier Nov 5 15:54:26.449690 kernel: docker0: port 1(veth8b3fb2a) entered disabled state Nov 5 15:54:26.449728 kernel: vethca0b544: renamed from eth0 Nov 5 15:54:26.480571 systemd-networkd[1799]: veth8b3fb2a: Link DOWN Nov 5 15:54:26.485692 kernel: docker0: port 1(veth8b3fb2a) entered disabled state Nov 5 15:54:26.485757 kernel: veth8b3fb2a (unregistering): left allmulticast mode Nov 5 15:54:26.491981 kernel: veth8b3fb2a (unregistering): left promiscuous mode Nov 5 15:54:26.495278 kernel: docker0: port 1(veth8b3fb2a) entered disabled state Nov 5 15:54:26.519855 systemd[1]: run-docker-netns-71e5ed622b5a.mount: Deactivated successfully. Nov 5 15:54:26.523844 systemd[1]: var-lib-docker-overlay2-38a3a504be80e7b1a4029dd7b153b541f10dc368a87debdf4651196a10b18f17-merged.mount: Deactivated successfully. Nov 5 15:54:26.681137 sshd[15799]: Connection closed by 10.200.16.10 port 51980 Nov 5 15:54:26.681422 sshd-session[15796]: pam_unix(sshd:session): session closed for user core Nov 5 15:54:26.684542 systemd[1]: sshd@34-10.200.8.31:22-10.200.16.10:51980.service: Deactivated successfully. Nov 5 15:54:26.687131 systemd[1]: session-37.scope: Deactivated successfully. Nov 5 15:54:26.688405 systemd-logind[2100]: Session 37 logged out. Waiting for processes to exit. Nov 5 15:54:26.689384 systemd-logind[2100]: Removed session 37. Nov 5 15:54:26.817050 systemd[1]: Started sshd@35-10.200.8.31:22-10.200.16.10:42426.service - OpenSSH per-connection server daemon (10.200.16.10:42426). Nov 5 15:54:27.474957 systemd-networkd[1799]: docker0: Lost carrier Nov 5 15:54:27.526658 sshd[16108]: Accepted publickey for core from 10.200.16.10 port 42426 ssh2: RSA SHA256:wOn5dyIm5WplZhFa/bL50fgwDeFFpayVU2x0GGhbAwc Nov 5 15:54:27.528005 sshd-session[16108]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:54:27.532207 systemd-logind[2100]: New session 38 of user core. Nov 5 15:54:27.552797 systemd[1]: Started session-38.scope - Session 38 of User core. Nov 5 15:54:27.968329 systemd[1]: var-lib-docker-overlay2-b5f71e1d2f04a46f250939fa1d4ac8737f65a3104f117aa223a78e108e3c00fb\x2dinit-merged.mount: Deactivated successfully. Nov 5 15:54:28.087298 containerd[2142]: time="2025-11-05T15:54:28.087240754Z" level=info msg="connecting to shim d199d67150d0c59479a635906b44061c6fe97d1a9e51e112a2c138d1c04e9ed4" address="unix:///run/containerd/s/f611e3f4196015eef7ac721681f1acaa43339adc1705ca30512404118fc62963" namespace=moby protocol=ttrpc version=3 Nov 5 15:54:28.110809 systemd[1]: Started docker-d199d67150d0c59479a635906b44061c6fe97d1a9e51e112a2c138d1c04e9ed4.scope - libcontainer container d199d67150d0c59479a635906b44061c6fe97d1a9e51e112a2c138d1c04e9ed4. Nov 5 15:54:28.154237 kernel: docker0: port 1(veth25dccb9) entered blocking state Nov 5 15:54:28.154328 kernel: docker0: port 1(veth25dccb9) entered disabled state Nov 5 15:54:28.157153 kernel: veth25dccb9: entered allmulticast mode Nov 5 15:54:28.160125 kernel: veth25dccb9: entered promiscuous mode Nov 5 15:54:28.160762 systemd-networkd[1799]: veth25dccb9: Link UP Nov 5 15:54:28.181702 kernel: eth0: renamed from vethb12637a Nov 5 15:54:28.188855 kernel: docker0: port 1(veth25dccb9) entered blocking state Nov 5 15:54:28.188909 kernel: docker0: port 1(veth25dccb9) entered forwarding state Nov 5 15:54:28.189253 systemd-networkd[1799]: veth25dccb9: Gained carrier Nov 5 15:54:28.189616 systemd-networkd[1799]: docker0: Gained carrier Nov 5 15:54:28.225749 systemd[1]: docker-d199d67150d0c59479a635906b44061c6fe97d1a9e51e112a2c138d1c04e9ed4.scope: Deactivated successfully. Nov 5 15:54:28.246455 dockerd[15819]: time="2025-11-05T15:54:28.246406886Z" level=info msg="ignoring event" container=d199d67150d0c59479a635906b44061c6fe97d1a9e51e112a2c138d1c04e9ed4 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Nov 5 15:54:28.246900 containerd[2142]: time="2025-11-05T15:54:28.246855566Z" level=info msg="shim disconnected" id=d199d67150d0c59479a635906b44061c6fe97d1a9e51e112a2c138d1c04e9ed4 namespace=moby Nov 5 15:54:28.246900 containerd[2142]: time="2025-11-05T15:54:28.246893964Z" level=warning msg="cleaning up after shim disconnected" id=d199d67150d0c59479a635906b44061c6fe97d1a9e51e112a2c138d1c04e9ed4 namespace=moby Nov 5 15:54:28.247001 containerd[2142]: time="2025-11-05T15:54:28.246904764Z" level=info msg="cleaning up dead shim" namespace=moby Nov 5 15:54:28.277699 kernel: docker0: port 1(veth25dccb9) entered disabled state Nov 5 15:54:28.277783 kernel: vethb12637a: renamed from eth0 Nov 5 15:54:28.277717 systemd-networkd[1799]: veth25dccb9: Lost carrier Nov 5 15:54:28.307550 systemd-networkd[1799]: veth25dccb9: Link DOWN Nov 5 15:54:28.316044 kernel: docker0: port 1(veth25dccb9) entered disabled state Nov 5 15:54:28.316104 kernel: veth25dccb9 (unregistering): left allmulticast mode Nov 5 15:54:28.321265 kernel: veth25dccb9 (unregistering): left promiscuous mode Nov 5 15:54:28.324659 kernel: docker0: port 1(veth25dccb9) entered disabled state Nov 5 15:54:28.482967 systemd-networkd[1799]: docker0: Lost carrier Nov 5 15:54:28.492761 sshd[16111]: Connection closed by 10.200.16.10 port 42426 Nov 5 15:54:28.493101 sshd-session[16108]: pam_unix(sshd:session): session closed for user core Nov 5 15:54:28.496906 systemd[1]: sshd@35-10.200.8.31:22-10.200.16.10:42426.service: Deactivated successfully. Nov 5 15:54:28.498465 systemd[1]: session-38.scope: Deactivated successfully. Nov 5 15:54:28.499152 systemd-logind[2100]: Session 38 logged out. Waiting for processes to exit. Nov 5 15:54:28.500014 systemd-logind[2100]: Removed session 38. Nov 5 15:54:28.621128 systemd[1]: Started sshd@36-10.200.8.31:22-10.200.16.10:42438.service - OpenSSH per-connection server daemon (10.200.16.10:42438). Nov 5 15:54:28.965012 systemd[1]: var-lib-docker-overlay2-b5f71e1d2f04a46f250939fa1d4ac8737f65a3104f117aa223a78e108e3c00fb-merged.mount: Deactivated successfully. Nov 5 15:54:29.344369 sshd[16216]: Accepted publickey for core from 10.200.16.10 port 42438 ssh2: RSA SHA256:wOn5dyIm5WplZhFa/bL50fgwDeFFpayVU2x0GGhbAwc Nov 5 15:54:29.345592 sshd-session[16216]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:54:29.350444 systemd-logind[2100]: New session 39 of user core. Nov 5 15:54:29.359820 systemd[1]: Started session-39.scope - Session 39 of User core. Nov 5 15:54:29.845766 ntpdate[16230]: ntpdate 4.2.8p18@1.4062-o Wed Nov 5 13:12:26 UTC 2025 (1) Nov 5 15:54:36.572974 ntpdate[16230]: adjust time server 193.1.8.106 offset -0.012194 sec Nov 5 15:54:36.689170 sshd[16219]: Connection closed by 10.200.16.10 port 42438 Nov 5 15:54:36.689538 sshd-session[16216]: pam_unix(sshd:session): session closed for user core Nov 5 15:54:36.693510 systemd[1]: sshd@36-10.200.8.31:22-10.200.16.10:42438.service: Deactivated successfully. Nov 5 15:54:36.695075 systemd[1]: session-39.scope: Deactivated successfully. Nov 5 15:54:36.695762 systemd-logind[2100]: Session 39 logged out. Waiting for processes to exit. Nov 5 15:54:36.697231 systemd-logind[2100]: Removed session 39. Nov 5 15:54:36.818371 systemd[1]: Started sshd@37-10.200.8.31:22-10.200.16.10:48906.service - OpenSSH per-connection server daemon (10.200.16.10:48906). Nov 5 15:54:37.552250 sshd[16235]: Accepted publickey for core from 10.200.16.10 port 48906 ssh2: RSA SHA256:wOn5dyIm5WplZhFa/bL50fgwDeFFpayVU2x0GGhbAwc Nov 5 15:54:37.553514 sshd-session[16235]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:54:37.557788 systemd-logind[2100]: New session 40 of user core. Nov 5 15:54:37.568794 systemd[1]: Started session-40.scope - Session 40 of User core. Nov 5 15:54:38.111009 sshd[16238]: Connection closed by 10.200.16.10 port 48906 Nov 5 15:54:38.111373 sshd-session[16235]: pam_unix(sshd:session): session closed for user core Nov 5 15:54:38.115086 systemd[1]: sshd@37-10.200.8.31:22-10.200.16.10:48906.service: Deactivated successfully. Nov 5 15:54:38.116819 systemd[1]: session-40.scope: Deactivated successfully. Nov 5 15:54:38.117525 systemd-logind[2100]: Session 40 logged out. Waiting for processes to exit. Nov 5 15:54:38.118381 systemd-logind[2100]: Removed session 40.