Oct 13 00:06:51.221550 kernel: Linux version 6.6.110-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.43 p3) 2.43.1) #1 SMP PREEMPT_DYNAMIC Sun Oct 12 22:36:11 -00 2025 Oct 13 00:06:51.221580 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=f22c322725201bc05beb6be7a3cc1733cdde87d870355f876093fa075b62debf Oct 13 00:06:51.221590 kernel: BIOS-provided physical RAM map: Oct 13 00:06:51.221597 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Oct 13 00:06:51.221603 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Oct 13 00:06:51.221610 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Oct 13 00:06:51.221618 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Oct 13 00:06:51.221627 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Oct 13 00:06:51.221634 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Oct 13 00:06:51.221641 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Oct 13 00:06:51.221648 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Oct 13 00:06:51.221655 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Oct 13 00:06:51.221661 kernel: printk: bootconsole [earlyser0] enabled Oct 13 00:06:51.221668 kernel: NX (Execute Disable) protection: active Oct 13 00:06:51.221679 kernel: APIC: Static calls initialized Oct 13 00:06:51.221687 kernel: efi: EFI v2.7 by Microsoft Oct 13 00:06:51.221695 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Oct 13 00:06:51.221702 kernel: random: crng init done Oct 13 00:06:51.221710 kernel: secureboot: Secure boot disabled Oct 13 00:06:51.221717 kernel: SMBIOS 3.1.0 present. Oct 13 00:06:51.221724 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Oct 13 00:06:51.221732 kernel: Hypervisor detected: Microsoft Hyper-V Oct 13 00:06:51.221739 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Oct 13 00:06:51.221746 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Oct 13 00:06:51.221756 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Oct 13 00:06:51.221763 kernel: Hyper-V: Using hypercall for remote TLB flush Oct 13 00:06:51.221770 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Oct 13 00:06:51.221778 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Oct 13 00:06:51.221786 kernel: tsc: Marking TSC unstable due to running on Hyper-V Oct 13 00:06:51.221794 kernel: tsc: Detected 2593.992 MHz processor Oct 13 00:06:51.221802 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Oct 13 00:06:51.221809 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Oct 13 00:06:51.221817 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Oct 13 00:06:51.221827 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Oct 13 00:06:51.221835 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Oct 13 00:06:51.221842 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Oct 13 00:06:51.221850 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Oct 13 00:06:51.221857 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Oct 13 00:06:51.221865 kernel: Using GB pages for direct mapping Oct 13 00:06:51.221872 kernel: ACPI: Early table checksum verification disabled Oct 13 00:06:51.221884 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Oct 13 00:06:51.221894 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 00:06:51.221902 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 00:06:51.221910 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Oct 13 00:06:51.221918 kernel: ACPI: FACS 0x000000003FFFE000 000040 Oct 13 00:06:51.221926 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 00:06:51.221934 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 00:06:51.221945 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 00:06:51.221953 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 00:06:51.221961 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 00:06:51.221969 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 00:06:51.221977 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 13 00:06:51.221985 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Oct 13 00:06:51.221993 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Oct 13 00:06:51.222001 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Oct 13 00:06:51.222012 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Oct 13 00:06:51.222020 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Oct 13 00:06:51.222028 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Oct 13 00:06:51.222036 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Oct 13 00:06:51.222044 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Oct 13 00:06:51.222052 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Oct 13 00:06:51.222060 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Oct 13 00:06:51.222068 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0 Oct 13 00:06:51.222075 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0 Oct 13 00:06:51.222086 kernel: SRAT: PXM 0 -> APIC 0x02 -> Node 0 Oct 13 00:06:51.222094 kernel: SRAT: PXM 0 -> APIC 0x03 -> Node 0 Oct 13 00:06:51.222102 kernel: SRAT: PXM 0 -> APIC 0x04 -> Node 0 Oct 13 00:06:51.222134 kernel: SRAT: PXM 0 -> APIC 0x05 -> Node 0 Oct 13 00:06:51.222166 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Oct 13 00:06:51.222175 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Oct 13 00:06:51.222183 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Oct 13 00:06:51.222191 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Oct 13 00:06:51.222199 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Oct 13 00:06:51.222212 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Oct 13 00:06:51.222220 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Oct 13 00:06:51.222228 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Oct 13 00:06:51.222237 kernel: NUMA: Node 0 [mem 0x00000000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00000000-0xfdfffffff] Oct 13 00:06:51.222245 kernel: NUMA: Node 0 [mem 0x00000000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00000000-0x5cbfffffff] Oct 13 00:06:51.222253 kernel: NODE_DATA(0) allocated [mem 0x5cbfff9000-0x5cbfffefff] Oct 13 00:06:51.222261 kernel: Zone ranges: Oct 13 00:06:51.222269 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Oct 13 00:06:51.222277 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Oct 13 00:06:51.222288 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Oct 13 00:06:51.222296 kernel: Movable zone start for each node Oct 13 00:06:51.222304 kernel: Early memory node ranges Oct 13 00:06:51.222324 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Oct 13 00:06:51.222332 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Oct 13 00:06:51.222341 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Oct 13 00:06:51.222349 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Oct 13 00:06:51.222357 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Oct 13 00:06:51.222365 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Oct 13 00:06:51.222376 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Oct 13 00:06:51.222384 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Oct 13 00:06:51.222392 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Oct 13 00:06:51.222400 kernel: ACPI: PM-Timer IO Port: 0x408 Oct 13 00:06:51.222408 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Oct 13 00:06:51.222416 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Oct 13 00:06:51.222424 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Oct 13 00:06:51.222433 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Oct 13 00:06:51.222441 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Oct 13 00:06:51.222451 kernel: smpboot: Allowing 6 CPUs, 0 hotplug CPUs Oct 13 00:06:51.222460 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Oct 13 00:06:51.222468 kernel: Booting paravirtualized kernel on Hyper-V Oct 13 00:06:51.222476 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Oct 13 00:06:51.222484 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Oct 13 00:06:51.222492 kernel: percpu: Embedded 58 pages/cpu s196712 r8192 d32664 u262144 Oct 13 00:06:51.222500 kernel: pcpu-alloc: s196712 r8192 d32664 u262144 alloc=1*2097152 Oct 13 00:06:51.222508 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Oct 13 00:06:51.222516 kernel: Hyper-V: PV spinlocks enabled Oct 13 00:06:51.222527 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Oct 13 00:06:51.222536 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=f22c322725201bc05beb6be7a3cc1733cdde87d870355f876093fa075b62debf Oct 13 00:06:51.222544 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Oct 13 00:06:51.222552 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Oct 13 00:06:51.222560 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Oct 13 00:06:51.222568 kernel: Fallback order for Node 0: 0 Oct 13 00:06:51.222576 kernel: Built 1 zonelists, mobility grouping on. Total pages: 28899610 Oct 13 00:06:51.222584 kernel: Policy zone: Normal Oct 13 00:06:51.222595 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 13 00:06:51.222611 kernel: software IO TLB: area num 8. Oct 13 00:06:51.222620 kernel: Memory: 115328448K/117439364K available (14336K kernel code, 2288K rwdata, 22872K rodata, 43512K init, 1568K bss, 2110660K reserved, 0K cma-reserved) Oct 13 00:06:51.222631 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Oct 13 00:06:51.222639 kernel: Kernel/User page tables isolation: enabled Oct 13 00:06:51.222648 kernel: ftrace: allocating 37951 entries in 149 pages Oct 13 00:06:51.222656 kernel: ftrace: allocated 149 pages with 4 groups Oct 13 00:06:51.222664 kernel: Dynamic Preempt: voluntary Oct 13 00:06:51.222673 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 13 00:06:51.222682 kernel: rcu: RCU event tracing is enabled. Oct 13 00:06:51.222690 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Oct 13 00:06:51.222699 kernel: Trampoline variant of Tasks RCU enabled. Oct 13 00:06:51.222710 kernel: Rude variant of Tasks RCU enabled. Oct 13 00:06:51.222718 kernel: Tracing variant of Tasks RCU enabled. Oct 13 00:06:51.222727 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 13 00:06:51.222735 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Oct 13 00:06:51.222746 kernel: Using NULL legacy PIC Oct 13 00:06:51.222755 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Oct 13 00:06:51.222763 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Oct 13 00:06:51.222771 kernel: Console: colour dummy device 80x25 Oct 13 00:06:51.222780 kernel: printk: console [tty1] enabled Oct 13 00:06:51.222788 kernel: printk: console [ttyS0] enabled Oct 13 00:06:51.222797 kernel: printk: bootconsole [earlyser0] disabled Oct 13 00:06:51.222805 kernel: ACPI: Core revision 20230628 Oct 13 00:06:51.222813 kernel: Failed to register legacy timer interrupt Oct 13 00:06:51.222822 kernel: APIC: Switch to symmetric I/O mode setup Oct 13 00:06:51.222832 kernel: Hyper-V: enabling crash_kexec_post_notifiers Oct 13 00:06:51.222841 kernel: Hyper-V: Using IPI hypercalls Oct 13 00:06:51.222849 kernel: APIC: send_IPI() replaced with hv_send_ipi() Oct 13 00:06:51.222858 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Oct 13 00:06:51.222866 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Oct 13 00:06:51.222875 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Oct 13 00:06:51.222883 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Oct 13 00:06:51.222891 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Oct 13 00:06:51.222900 kernel: Hyper-V: Using enlightened APIC (xapic mode) Oct 13 00:06:51.222911 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Oct 13 00:06:51.222919 kernel: APIC: read() replaced with hv_apic_read() Oct 13 00:06:51.222928 kernel: APIC: write() replaced with hv_apic_write() Oct 13 00:06:51.222936 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Oct 13 00:06:51.222944 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Oct 13 00:06:51.222953 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593992) Oct 13 00:06:51.222961 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Oct 13 00:06:51.222970 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Oct 13 00:06:51.222978 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Oct 13 00:06:51.222989 kernel: Spectre V2 : Mitigation: Retpolines Oct 13 00:06:51.222997 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Oct 13 00:06:51.223006 kernel: Speculative Store Bypass: Vulnerable Oct 13 00:06:51.223014 kernel: MDS: Mitigation: Clear CPU buffers Oct 13 00:06:51.223022 kernel: TAA: Mitigation: Clear CPU buffers Oct 13 00:06:51.223030 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Oct 13 00:06:51.223038 kernel: active return thunk: its_return_thunk Oct 13 00:06:51.223046 kernel: ITS: Mitigation: Aligned branch/return thunks Oct 13 00:06:51.223055 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Oct 13 00:06:51.223063 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Oct 13 00:06:51.223071 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Oct 13 00:06:51.223082 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Oct 13 00:06:51.223090 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Oct 13 00:06:51.223099 kernel: Freeing SMP alternatives memory: 32K Oct 13 00:06:51.223107 kernel: pid_max: default: 32768 minimum: 301 Oct 13 00:06:51.223115 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Oct 13 00:06:51.223124 kernel: landlock: Up and running. Oct 13 00:06:51.223132 kernel: SELinux: Initializing. Oct 13 00:06:51.223140 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Oct 13 00:06:51.223148 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Oct 13 00:06:51.223157 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Oct 13 00:06:51.223165 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Oct 13 00:06:51.223176 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Oct 13 00:06:51.223185 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Oct 13 00:06:51.223194 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Oct 13 00:06:51.223202 kernel: signal: max sigframe size: 1776 Oct 13 00:06:51.223210 kernel: rcu: Hierarchical SRCU implementation. Oct 13 00:06:51.223219 kernel: rcu: Max phase no-delay instances is 400. Oct 13 00:06:51.223227 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Oct 13 00:06:51.223236 kernel: smp: Bringing up secondary CPUs ... Oct 13 00:06:51.223244 kernel: smpboot: x86: Booting SMP configuration: Oct 13 00:06:51.223255 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Oct 13 00:06:51.223263 kernel: smp: Brought up 1 node, 6 CPUs Oct 13 00:06:51.223272 kernel: smpboot: Max logical packages: 1 Oct 13 00:06:51.223280 kernel: smpboot: Total of 6 processors activated (31127.90 BogoMIPS) Oct 13 00:06:51.223288 kernel: devtmpfs: initialized Oct 13 00:06:51.223297 kernel: x86/mm: Memory block size: 1024MB Oct 13 00:06:51.223312 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Oct 13 00:06:51.223322 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 13 00:06:51.223330 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Oct 13 00:06:51.223342 kernel: pinctrl core: initialized pinctrl subsystem Oct 13 00:06:51.223351 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Oct 13 00:06:51.223359 kernel: audit: initializing netlink subsys (disabled) Oct 13 00:06:51.223368 kernel: audit: type=2000 audit(1760314009.029:1): state=initialized audit_enabled=0 res=1 Oct 13 00:06:51.223376 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 13 00:06:51.223384 kernel: thermal_sys: Registered thermal governor 'user_space' Oct 13 00:06:51.223393 kernel: cpuidle: using governor menu Oct 13 00:06:51.223401 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 13 00:06:51.223409 kernel: dca service started, version 1.12.1 Oct 13 00:06:51.223420 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Oct 13 00:06:51.223429 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Oct 13 00:06:51.223437 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Oct 13 00:06:51.223446 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Oct 13 00:06:51.223454 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Oct 13 00:06:51.223462 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Oct 13 00:06:51.223471 kernel: ACPI: Added _OSI(Module Device) Oct 13 00:06:51.223479 kernel: ACPI: Added _OSI(Processor Device) Oct 13 00:06:51.223488 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 13 00:06:51.223499 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 13 00:06:51.223507 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Oct 13 00:06:51.223516 kernel: ACPI: Interpreter enabled Oct 13 00:06:51.223524 kernel: ACPI: PM: (supports S0 S5) Oct 13 00:06:51.223532 kernel: ACPI: Using IOAPIC for interrupt routing Oct 13 00:06:51.223541 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Oct 13 00:06:51.223549 kernel: PCI: Ignoring E820 reservations for host bridge windows Oct 13 00:06:51.223558 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Oct 13 00:06:51.223566 kernel: iommu: Default domain type: Translated Oct 13 00:06:51.223577 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Oct 13 00:06:51.223586 kernel: efivars: Registered efivars operations Oct 13 00:06:51.223594 kernel: PCI: Using ACPI for IRQ routing Oct 13 00:06:51.223602 kernel: PCI: System does not support PCI Oct 13 00:06:51.223611 kernel: vgaarb: loaded Oct 13 00:06:51.223619 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Oct 13 00:06:51.223628 kernel: VFS: Disk quotas dquot_6.6.0 Oct 13 00:06:51.223636 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 13 00:06:51.223645 kernel: pnp: PnP ACPI init Oct 13 00:06:51.223656 kernel: pnp: PnP ACPI: found 3 devices Oct 13 00:06:51.223664 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Oct 13 00:06:51.223673 kernel: NET: Registered PF_INET protocol family Oct 13 00:06:51.223681 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 13 00:06:51.223690 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Oct 13 00:06:51.223698 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Oct 13 00:06:51.223707 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 13 00:06:51.223715 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Oct 13 00:06:51.223724 kernel: TCP: Hash tables configured (established 524288 bind 65536) Oct 13 00:06:51.223735 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Oct 13 00:06:51.223743 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Oct 13 00:06:51.223751 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Oct 13 00:06:51.223760 kernel: NET: Registered PF_XDP protocol family Oct 13 00:06:51.223768 kernel: PCI: CLS 0 bytes, default 64 Oct 13 00:06:51.223776 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Oct 13 00:06:51.223785 kernel: software IO TLB: mapped [mem 0x000000003ae61000-0x000000003ee61000] (64MB) Oct 13 00:06:51.223793 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Oct 13 00:06:51.223802 kernel: Initialise system trusted keyrings Oct 13 00:06:51.223812 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Oct 13 00:06:51.223821 kernel: Key type asymmetric registered Oct 13 00:06:51.223829 kernel: Asymmetric key parser 'x509' registered Oct 13 00:06:51.223837 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Oct 13 00:06:51.223846 kernel: io scheduler mq-deadline registered Oct 13 00:06:51.223854 kernel: io scheduler kyber registered Oct 13 00:06:51.223862 kernel: io scheduler bfq registered Oct 13 00:06:51.223870 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Oct 13 00:06:51.223879 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 13 00:06:51.223890 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Oct 13 00:06:51.223898 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Oct 13 00:06:51.223906 kernel: i8042: PNP: No PS/2 controller found. Oct 13 00:06:51.224066 kernel: rtc_cmos 00:02: registered as rtc0 Oct 13 00:06:51.224180 kernel: rtc_cmos 00:02: setting system clock to 2025-10-13T00:06:50 UTC (1760314010) Oct 13 00:06:51.224284 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Oct 13 00:06:51.224297 kernel: intel_pstate: CPU model not supported Oct 13 00:06:51.224322 kernel: efifb: probing for efifb Oct 13 00:06:51.224331 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Oct 13 00:06:51.224339 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Oct 13 00:06:51.224348 kernel: efifb: scrolling: redraw Oct 13 00:06:51.224356 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Oct 13 00:06:51.224365 kernel: Console: switching to colour frame buffer device 128x48 Oct 13 00:06:51.224373 kernel: fb0: EFI VGA frame buffer device Oct 13 00:06:51.224382 kernel: pstore: Using crash dump compression: deflate Oct 13 00:06:51.224390 kernel: pstore: Registered efi_pstore as persistent store backend Oct 13 00:06:51.224402 kernel: NET: Registered PF_INET6 protocol family Oct 13 00:06:51.224410 kernel: Segment Routing with IPv6 Oct 13 00:06:51.224419 kernel: In-situ OAM (IOAM) with IPv6 Oct 13 00:06:51.224427 kernel: NET: Registered PF_PACKET protocol family Oct 13 00:06:51.224436 kernel: Key type dns_resolver registered Oct 13 00:06:51.224444 kernel: IPI shorthand broadcast: enabled Oct 13 00:06:51.224452 kernel: sched_clock: Marking stable (1696005700, 101685200)->(2132474900, -334784000) Oct 13 00:06:51.224461 kernel: registered taskstats version 1 Oct 13 00:06:51.224469 kernel: Loading compiled-in X.509 certificates Oct 13 00:06:51.224478 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.110-flatcar: 50d5efdc867bacb346c7c22eb5069c0bfc15416d' Oct 13 00:06:51.224489 kernel: Key type .fscrypt registered Oct 13 00:06:51.224497 kernel: Key type fscrypt-provisioning registered Oct 13 00:06:51.224505 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 13 00:06:51.224514 kernel: ima: Allocated hash algorithm: sha1 Oct 13 00:06:51.224522 kernel: ima: No architecture policies found Oct 13 00:06:51.224531 kernel: clk: Disabling unused clocks Oct 13 00:06:51.224539 kernel: Freeing unused kernel image (initmem) memory: 43512K Oct 13 00:06:51.224548 kernel: Write protecting the kernel read-only data: 38912k Oct 13 00:06:51.224559 kernel: Freeing unused kernel image (rodata/data gap) memory: 1704K Oct 13 00:06:51.224567 kernel: Run /init as init process Oct 13 00:06:51.224576 kernel: with arguments: Oct 13 00:06:51.224584 kernel: /init Oct 13 00:06:51.224593 kernel: with environment: Oct 13 00:06:51.224601 kernel: HOME=/ Oct 13 00:06:51.224609 kernel: TERM=linux Oct 13 00:06:51.224617 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 13 00:06:51.224627 systemd[1]: Successfully made /usr/ read-only. Oct 13 00:06:51.224641 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Oct 13 00:06:51.224651 systemd[1]: Detected virtualization microsoft. Oct 13 00:06:51.224660 systemd[1]: Detected architecture x86-64. Oct 13 00:06:51.224669 systemd[1]: Running in initrd. Oct 13 00:06:51.224678 systemd[1]: No hostname configured, using default hostname. Oct 13 00:06:51.224687 systemd[1]: Hostname set to . Oct 13 00:06:51.224696 systemd[1]: Initializing machine ID from random generator. Oct 13 00:06:51.224707 systemd[1]: Queued start job for default target initrd.target. Oct 13 00:06:51.224716 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 13 00:06:51.224725 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 13 00:06:51.224735 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Oct 13 00:06:51.224744 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 13 00:06:51.224753 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Oct 13 00:06:51.224763 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Oct 13 00:06:51.224776 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Oct 13 00:06:51.224785 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Oct 13 00:06:51.224794 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 13 00:06:51.224803 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 13 00:06:51.224812 systemd[1]: Reached target paths.target - Path Units. Oct 13 00:06:51.224821 systemd[1]: Reached target slices.target - Slice Units. Oct 13 00:06:51.224830 systemd[1]: Reached target swap.target - Swaps. Oct 13 00:06:51.224839 systemd[1]: Reached target timers.target - Timer Units. Oct 13 00:06:51.224848 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Oct 13 00:06:51.224860 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 13 00:06:51.224869 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Oct 13 00:06:51.224878 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Oct 13 00:06:51.224887 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 13 00:06:51.224896 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 13 00:06:51.224905 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 13 00:06:51.224914 systemd[1]: Reached target sockets.target - Socket Units. Oct 13 00:06:51.224923 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Oct 13 00:06:51.224935 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 13 00:06:51.224944 systemd[1]: Finished network-cleanup.service - Network Cleanup. Oct 13 00:06:51.224954 systemd[1]: Starting systemd-fsck-usr.service... Oct 13 00:06:51.224963 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 13 00:06:51.224972 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 13 00:06:51.225001 systemd-journald[204]: Collecting audit messages is disabled. Oct 13 00:06:51.225027 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 13 00:06:51.225037 systemd-journald[204]: Journal started Oct 13 00:06:51.225057 systemd-journald[204]: Runtime Journal (/run/log/journal/709265c62817457e9a92381234fa1ccf) is 8M, max 2.2G, 2.1G free. Oct 13 00:06:51.231489 systemd[1]: Started systemd-journald.service - Journal Service. Oct 13 00:06:51.232635 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Oct 13 00:06:51.233406 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 13 00:06:51.234003 systemd[1]: Finished systemd-fsck-usr.service. Oct 13 00:06:51.237782 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 13 00:06:51.239234 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 13 00:06:51.245645 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 13 00:06:51.246714 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 13 00:06:51.299019 systemd-modules-load[206]: Inserted module 'overlay' Oct 13 00:06:51.304394 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 13 00:06:51.306820 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 13 00:06:51.316145 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 13 00:06:51.325327 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 13 00:06:51.355328 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Oct 13 00:06:51.359475 systemd-modules-load[206]: Inserted module 'br_netfilter' Oct 13 00:06:51.362220 kernel: Bridge firewalling registered Oct 13 00:06:51.360572 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 13 00:06:51.363599 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 13 00:06:51.388023 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 13 00:06:51.390335 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Oct 13 00:06:51.399668 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 13 00:06:51.408869 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 13 00:06:51.431926 dracut-cmdline[239]: dracut-dracut-053 Oct 13 00:06:51.437228 dracut-cmdline[239]: Using kernel command line parameters: rd.driver.pre=btrfs 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=f22c322725201bc05beb6be7a3cc1733cdde87d870355f876093fa075b62debf Oct 13 00:06:51.452710 systemd-resolved[241]: Positive Trust Anchors: Oct 13 00:06:51.452720 systemd-resolved[241]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 13 00:06:51.452751 systemd-resolved[241]: 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 Oct 13 00:06:51.455411 systemd-resolved[241]: Defaulting to hostname 'linux'. Oct 13 00:06:51.456546 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 13 00:06:51.461984 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 13 00:06:51.585333 kernel: SCSI subsystem initialized Oct 13 00:06:51.597333 kernel: Loading iSCSI transport class v2.0-870. Oct 13 00:06:51.610329 kernel: iscsi: registered transport (tcp) Oct 13 00:06:51.634872 kernel: iscsi: registered transport (qla4xxx) Oct 13 00:06:51.635031 kernel: QLogic iSCSI HBA Driver Oct 13 00:06:51.676690 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Oct 13 00:06:51.698425 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Oct 13 00:06:51.730104 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Oct 13 00:06:51.730151 kernel: device-mapper: uevent: version 1.0.3 Oct 13 00:06:51.734965 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Oct 13 00:06:51.777328 kernel: raid6: avx2x4 gen() 30887 MB/s Oct 13 00:06:51.796338 kernel: raid6: avx2x2 gen() 31174 MB/s Oct 13 00:06:51.817437 kernel: raid6: avx2x1 gen() 27886 MB/s Oct 13 00:06:51.817468 kernel: raid6: using algorithm avx2x2 gen() 31174 MB/s Oct 13 00:06:51.840440 kernel: raid6: .... xor() 18000 MB/s, rmw enabled Oct 13 00:06:51.840487 kernel: raid6: using avx2x2 recovery algorithm Oct 13 00:06:51.864325 kernel: xor: automatically using best checksumming function avx Oct 13 00:06:52.036338 kernel: Btrfs loaded, zoned=no, fsverity=no Oct 13 00:06:52.046964 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Oct 13 00:06:52.062515 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 13 00:06:52.086363 systemd-udevd[426]: Using default interface naming scheme 'v255'. Oct 13 00:06:52.092840 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 13 00:06:52.105444 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Oct 13 00:06:52.118310 dracut-pre-trigger[434]: rd.md=0: removing MD RAID activation Oct 13 00:06:52.150116 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Oct 13 00:06:52.170619 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 13 00:06:52.254484 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 13 00:06:52.275444 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Oct 13 00:06:52.287432 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Oct 13 00:06:52.293402 kernel: cryptd: max_cpu_qlen set to 1000 Oct 13 00:06:52.298765 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Oct 13 00:06:52.303988 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 13 00:06:52.325883 kernel: hv_vmbus: Vmbus version:4.0 Oct 13 00:06:52.314991 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 13 00:06:52.335469 kernel: AVX2 version of gcm_enc/dec engaged. Oct 13 00:06:52.335516 kernel: hv_vmbus: registering driver hyperv_keyboard Oct 13 00:06:52.345608 kernel: AES CTR mode by8 optimization enabled Oct 13 00:06:52.345642 kernel: input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/VMBUS:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input0 Oct 13 00:06:52.348415 kernel: pps_core: LinuxPPS API ver. 1 registered Oct 13 00:06:52.349333 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Oct 13 00:06:52.353388 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Oct 13 00:06:52.384974 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 13 00:06:52.385007 kernel: hv_vmbus: registering driver hv_netvsc Oct 13 00:06:52.385020 kernel: PTP clock support registered Oct 13 00:06:52.385031 kernel: hv_vmbus: registering driver hid_hyperv Oct 13 00:06:52.385042 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Oct 13 00:06:52.385053 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Oct 13 00:06:52.392288 kernel: hv_utils: Registering HyperV Utility Driver Oct 13 00:06:52.392327 kernel: hv_vmbus: registering driver hv_utils Oct 13 00:06:52.395249 kernel: hv_utils: Heartbeat IC version 3.0 Oct 13 00:06:52.395290 kernel: hv_vmbus: registering driver hv_pci Oct 13 00:06:52.395319 kernel: hv_utils: Shutdown IC version 3.2 Oct 13 00:06:52.403409 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 13 00:06:52.550431 kernel: hv_utils: TimeSync IC version 4.0 Oct 13 00:06:52.550454 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Oct 13 00:06:52.403548 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 13 00:06:52.621935 kernel: hv_vmbus: registering driver hv_storvsc Oct 13 00:06:52.621965 kernel: scsi host1: storvsc_host_t Oct 13 00:06:52.622152 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Oct 13 00:06:52.622297 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Oct 13 00:06:52.622453 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Oct 13 00:06:52.622605 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Oct 13 00:06:52.622737 kernel: scsi host0: storvsc_host_t Oct 13 00:06:52.622895 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 Oct 13 00:06:52.622924 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Oct 13 00:06:52.623076 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Oct 13 00:06:52.623223 kernel: pci 0001:00:00.0: reg 0x10: [mem 0x41000000-0x41ffffff] Oct 13 00:06:52.623246 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Oct 13 00:06:52.623395 kernel: pci 0001:00:00.0: reg 0x14: [mem 0x1000000000-0x13ffffffff 64bit pref] Oct 13 00:06:52.623417 kernel: pci 0001:00:00.0: reg 0x1c: [mem 0x1400000000-0x1401ffffff 64bit pref] Oct 13 00:06:52.623435 kernel: pci 0001:00:00.0: Enabling HDA controller Oct 13 00:06:52.543761 systemd-resolved[241]: Clock change detected. Flushing caches. Oct 13 00:06:52.560300 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 13 00:06:52.612151 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 13 00:06:52.657751 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Oct 13 00:06:52.658022 kernel: pci 0001:00:00.0: BAR 1: assigned [mem 0x1000000000-0x13ffffffff 64bit pref] Oct 13 00:06:52.658186 kernel: pci 0001:00:00.0: BAR 3: assigned [mem 0x1400000000-0x1401ffffff 64bit pref] Oct 13 00:06:52.658330 kernel: pci 0001:00:00.0: BAR 0: assigned [mem 0x41000000-0x41ffffff] Oct 13 00:06:52.612296 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 13 00:06:52.667584 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Oct 13 00:06:52.667792 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Oct 13 00:06:52.667812 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Oct 13 00:06:52.627190 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Oct 13 00:06:52.675325 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 13 00:06:52.676309 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Oct 13 00:06:52.676661 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Oct 13 00:06:52.711536 kernel: sd 0:0:0:1: [sda] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Oct 13 00:06:52.712099 kernel: sd 0:0:0:0: [sdb] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Oct 13 00:06:52.712265 kernel: sd 0:0:0:1: [sda] 4096-byte physical blocks Oct 13 00:06:52.712437 kernel: sd 0:0:0:0: [sdb] 4096-byte physical blocks Oct 13 00:06:52.712584 kernel: sd 0:0:0:0: [sdb] Write Protect is off Oct 13 00:06:52.712744 kernel: sd 0:0:0:0: [sdb] Mode Sense: 0f 00 10 00 Oct 13 00:06:52.712925 kernel: sd 0:0:0:0: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Oct 13 00:06:52.715937 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Oct 13 00:06:52.720688 kernel: sd 0:0:0:1: [sda] Write Protect is off Oct 13 00:06:52.720961 kernel: sd 0:0:0:0: [sdb] Attached SCSI disk Oct 13 00:06:52.721117 kernel: sd 0:0:0:1: [sda] Mode Sense: 0f 00 10 00 Oct 13 00:06:52.734861 kernel: sd 0:0:0:1: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Oct 13 00:06:52.741818 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 13 00:06:52.752911 kernel: sda: sda1 Oct 13 00:06:52.752931 kernel: sd 0:0:0:1: [sda] Attached SCSI disk Oct 13 00:06:52.764100 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 13 00:06:52.786380 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 13 00:06:53.396058 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Oct 13 00:06:53.426864 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sdb6 scanned by (udev-worker) (475) Oct 13 00:06:53.447343 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Oct 13 00:06:53.460152 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Oct 13 00:06:53.507870 kernel: BTRFS: device fsid 1b3281fd-66ec-42df-bcbd-268fe4ae17be devid 1 transid 38 /dev/sdb3 scanned by (udev-worker) (500) Oct 13 00:06:53.527339 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Oct 13 00:06:53.528889 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Oct 13 00:06:53.556972 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Oct 13 00:06:53.573863 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Oct 13 00:06:53.583859 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Oct 13 00:06:54.584862 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Oct 13 00:06:54.584995 disk-uuid[636]: The operation has completed successfully. Oct 13 00:06:55.089762 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 13 00:06:55.089891 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Oct 13 00:06:55.114016 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Oct 13 00:06:55.123207 sh[724]: Success Oct 13 00:06:55.175862 kernel: device-mapper: verity: sha256 using implementation "sha256-avx2" Oct 13 00:06:55.611835 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Oct 13 00:06:55.631274 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Oct 13 00:06:55.633301 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Oct 13 00:06:55.664081 kernel: BTRFS info (device dm-0): first mount of filesystem 1b3281fd-66ec-42df-bcbd-268fe4ae17be Oct 13 00:06:55.664124 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Oct 13 00:06:55.669150 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Oct 13 00:06:55.672823 kernel: BTRFS info (device dm-0): disabling log replay at mount time Oct 13 00:06:55.676115 kernel: BTRFS info (device dm-0): using free space tree Oct 13 00:06:56.284577 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Oct 13 00:06:56.286837 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Oct 13 00:06:56.304992 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Oct 13 00:06:56.306714 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Oct 13 00:06:56.349064 kernel: BTRFS info (device sdb6): first mount of filesystem 2fdf6342-252c-4019-93cf-d9e28c1a91b4 Oct 13 00:06:56.349103 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Oct 13 00:06:56.352160 kernel: BTRFS info (device sdb6): using free space tree Oct 13 00:06:56.403861 kernel: BTRFS info (device sdb6): auto enabling async discard Oct 13 00:06:56.411864 kernel: BTRFS info (device sdb6): last unmount of filesystem 2fdf6342-252c-4019-93cf-d9e28c1a91b4 Oct 13 00:06:56.413682 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 13 00:06:56.432024 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 13 00:06:56.433743 systemd[1]: Finished ignition-setup.service - Ignition (setup). Oct 13 00:06:56.443654 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Oct 13 00:06:56.476810 systemd-networkd[903]: lo: Link UP Oct 13 00:06:56.476821 systemd-networkd[903]: lo: Gained carrier Oct 13 00:06:56.477955 systemd-networkd[903]: Enumeration completed Oct 13 00:06:56.478038 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 13 00:06:56.478318 systemd-networkd[903]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 13 00:06:56.478323 systemd-networkd[903]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 13 00:06:56.479080 systemd-networkd[903]: eth0: Link UP Oct 13 00:06:56.479247 systemd-networkd[903]: eth0: Gained carrier Oct 13 00:06:56.479256 systemd-networkd[903]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 13 00:06:56.484001 systemd[1]: Reached target network.target - Network. Oct 13 00:06:56.536889 systemd-networkd[903]: eth0: DHCPv4 address 10.200.8.11/24, gateway 10.200.8.1 acquired from 168.63.129.16 Oct 13 00:06:57.591980 systemd-networkd[903]: eth0: Gained IPv6LL Oct 13 00:06:58.119630 ignition[906]: Ignition 2.20.0 Oct 13 00:06:58.119643 ignition[906]: Stage: fetch-offline Oct 13 00:06:58.119683 ignition[906]: no configs at "/usr/lib/ignition/base.d" Oct 13 00:06:58.119694 ignition[906]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 13 00:06:58.119801 ignition[906]: parsed url from cmdline: "" Oct 13 00:06:58.119805 ignition[906]: no config URL provided Oct 13 00:06:58.119811 ignition[906]: reading system config file "/usr/lib/ignition/user.ign" Oct 13 00:06:58.119820 ignition[906]: no config at "/usr/lib/ignition/user.ign" Oct 13 00:06:58.119826 ignition[906]: failed to fetch config: resource requires networking Oct 13 00:06:58.144040 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Oct 13 00:06:58.120021 ignition[906]: Ignition finished successfully Oct 13 00:06:58.165024 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Oct 13 00:06:58.176711 ignition[919]: Ignition 2.20.0 Oct 13 00:06:58.176723 ignition[919]: Stage: fetch Oct 13 00:06:58.176889 ignition[919]: no configs at "/usr/lib/ignition/base.d" Oct 13 00:06:58.176900 ignition[919]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 13 00:06:58.177001 ignition[919]: parsed url from cmdline: "" Oct 13 00:06:58.177006 ignition[919]: no config URL provided Oct 13 00:06:58.177011 ignition[919]: reading system config file "/usr/lib/ignition/user.ign" Oct 13 00:06:58.177022 ignition[919]: no config at "/usr/lib/ignition/user.ign" Oct 13 00:06:58.177045 ignition[919]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Oct 13 00:06:58.249949 ignition[919]: GET result: OK Oct 13 00:06:58.249992 ignition[919]: config has been read from IMDS userdata Oct 13 00:06:58.250004 ignition[919]: parsing config with SHA512: e043ea29aae31e0509cf4831371925f87170ee8bb893d32b4228d7a6847f838de5baf5a257862e81b1292a2d782b1b57c7ee80fe4795a5cd951d3e346ba8a4db Oct 13 00:06:58.255809 unknown[919]: fetched base config from "system" Oct 13 00:06:58.255824 unknown[919]: fetched base config from "system" Oct 13 00:06:58.256681 ignition[919]: fetch: fetch complete Oct 13 00:06:58.255831 unknown[919]: fetched user config from "azure" Oct 13 00:06:58.256688 ignition[919]: fetch: fetch passed Oct 13 00:06:58.264983 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Oct 13 00:06:58.256755 ignition[919]: Ignition finished successfully Oct 13 00:06:58.286013 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Oct 13 00:06:58.307339 ignition[929]: Ignition 2.20.0 Oct 13 00:06:58.307350 ignition[929]: Stage: kargs Oct 13 00:06:58.307517 ignition[929]: no configs at "/usr/lib/ignition/base.d" Oct 13 00:06:58.307530 ignition[929]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 13 00:06:58.308194 ignition[929]: kargs: kargs passed Oct 13 00:06:58.308240 ignition[929]: Ignition finished successfully Oct 13 00:06:58.325134 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Oct 13 00:06:58.336010 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Oct 13 00:06:58.357220 ignition[939]: Ignition 2.20.0 Oct 13 00:06:58.357232 ignition[939]: Stage: disks Oct 13 00:06:58.357390 ignition[939]: no configs at "/usr/lib/ignition/base.d" Oct 13 00:06:58.361051 systemd[1]: Finished ignition-disks.service - Ignition (disks). Oct 13 00:06:58.357402 ignition[939]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 13 00:06:58.366120 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Oct 13 00:06:58.357999 ignition[939]: disks: disks passed Oct 13 00:06:58.373564 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Oct 13 00:06:58.358048 ignition[939]: Ignition finished successfully Oct 13 00:06:58.381984 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 13 00:06:58.383369 systemd[1]: Reached target sysinit.target - System Initialization. Oct 13 00:06:58.394765 systemd[1]: Reached target basic.target - Basic System. Oct 13 00:06:58.424986 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Oct 13 00:06:58.494328 systemd-fsck[951]: ROOT: clean, 14/7326000 files, 477710/7359488 blocks Oct 13 00:06:58.502216 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Oct 13 00:06:58.522938 systemd[1]: Mounting sysroot.mount - /sysroot... Oct 13 00:06:58.622857 kernel: EXT4-fs (sdb9): mounted filesystem 02b6903b-203a-4032-98c5-29ee940136f6 r/w with ordered data mode. Quota mode: none. Oct 13 00:06:58.623195 systemd[1]: Mounted sysroot.mount - /sysroot. Oct 13 00:06:58.625225 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Oct 13 00:06:58.662919 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 13 00:06:58.675870 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Oct 13 00:06:58.713790 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/sdb6 scanned by mount (963) Oct 13 00:06:58.713819 kernel: BTRFS info (device sdb6): first mount of filesystem 2fdf6342-252c-4019-93cf-d9e28c1a91b4 Oct 13 00:06:58.713832 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Oct 13 00:06:58.713912 kernel: BTRFS info (device sdb6): using free space tree Oct 13 00:06:58.680887 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Oct 13 00:06:58.681261 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Oct 13 00:06:58.731692 kernel: BTRFS info (device sdb6): auto enabling async discard Oct 13 00:06:58.681292 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Oct 13 00:06:58.683158 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Oct 13 00:06:58.684283 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Oct 13 00:06:58.733127 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 13 00:06:59.890672 coreos-metadata[965]: Oct 13 00:06:59.890 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Oct 13 00:06:59.899776 coreos-metadata[965]: Oct 13 00:06:59.892 INFO Fetch successful Oct 13 00:06:59.899776 coreos-metadata[965]: Oct 13 00:06:59.893 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Oct 13 00:06:59.911448 coreos-metadata[965]: Oct 13 00:06:59.900 INFO Fetch successful Oct 13 00:06:59.915918 coreos-metadata[965]: Oct 13 00:06:59.911 INFO wrote hostname ci-4230.2.4-a-f3817b53fd to /sysroot/etc/hostname Oct 13 00:06:59.923292 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Oct 13 00:07:00.079638 initrd-setup-root[993]: cut: /sysroot/etc/passwd: No such file or directory Oct 13 00:07:00.120151 initrd-setup-root[1000]: cut: /sysroot/etc/group: No such file or directory Oct 13 00:07:00.143807 initrd-setup-root[1007]: cut: /sysroot/etc/shadow: No such file or directory Oct 13 00:07:00.163336 initrd-setup-root[1014]: cut: /sysroot/etc/gshadow: No such file or directory Oct 13 00:07:01.699026 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Oct 13 00:07:01.720953 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Oct 13 00:07:01.722565 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Oct 13 00:07:01.732633 systemd[1]: sysroot-oem.mount: Deactivated successfully. Oct 13 00:07:01.748293 kernel: BTRFS info (device sdb6): last unmount of filesystem 2fdf6342-252c-4019-93cf-d9e28c1a91b4 Oct 13 00:07:01.767586 ignition[1081]: INFO : Ignition 2.20.0 Oct 13 00:07:01.767586 ignition[1081]: INFO : Stage: mount Oct 13 00:07:01.777470 ignition[1081]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 13 00:07:01.777470 ignition[1081]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 13 00:07:01.777470 ignition[1081]: INFO : mount: mount passed Oct 13 00:07:01.777470 ignition[1081]: INFO : Ignition finished successfully Oct 13 00:07:01.769595 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Oct 13 00:07:01.772678 systemd[1]: Finished ignition-mount.service - Ignition (mount). Oct 13 00:07:01.801943 systemd[1]: Starting ignition-files.service - Ignition (files)... Oct 13 00:07:01.811961 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 13 00:07:01.840210 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/sdb6 scanned by mount (1097) Oct 13 00:07:01.840245 kernel: BTRFS info (device sdb6): first mount of filesystem 2fdf6342-252c-4019-93cf-d9e28c1a91b4 Oct 13 00:07:01.844460 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Oct 13 00:07:01.847651 kernel: BTRFS info (device sdb6): using free space tree Oct 13 00:07:01.854862 kernel: BTRFS info (device sdb6): auto enabling async discard Oct 13 00:07:01.856004 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 13 00:07:01.884054 ignition[1114]: INFO : Ignition 2.20.0 Oct 13 00:07:01.884054 ignition[1114]: INFO : Stage: files Oct 13 00:07:01.889625 ignition[1114]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 13 00:07:01.889625 ignition[1114]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 13 00:07:01.889625 ignition[1114]: DEBUG : files: compiled without relabeling support, skipping Oct 13 00:07:01.923380 ignition[1114]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Oct 13 00:07:01.923380 ignition[1114]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Oct 13 00:07:02.309881 ignition[1114]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Oct 13 00:07:02.315629 ignition[1114]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Oct 13 00:07:02.320612 ignition[1114]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Oct 13 00:07:02.315952 unknown[1114]: wrote ssh authorized keys file for user: core Oct 13 00:07:02.366052 ignition[1114]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Oct 13 00:07:02.373338 ignition[1114]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Oct 13 00:07:02.373338 ignition[1114]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Oct 13 00:07:02.373338 ignition[1114]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Oct 13 00:07:02.373338 ignition[1114]: INFO : files: files passed Oct 13 00:07:02.373338 ignition[1114]: INFO : Ignition finished successfully Oct 13 00:07:02.374744 systemd[1]: Finished ignition-files.service - Ignition (files). Oct 13 00:07:02.408992 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Oct 13 00:07:02.411322 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Oct 13 00:07:02.419393 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 13 00:07:02.419484 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Oct 13 00:07:02.472919 initrd-setup-root-after-ignition[1146]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 13 00:07:02.483911 initrd-setup-root-after-ignition[1146]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Oct 13 00:07:02.474972 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 13 00:07:02.499565 initrd-setup-root-after-ignition[1150]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 13 00:07:02.484086 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Oct 13 00:07:02.502976 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Oct 13 00:07:02.527987 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 13 00:07:02.528100 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Oct 13 00:07:02.536761 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Oct 13 00:07:02.545547 systemd[1]: Reached target initrd.target - Initrd Default Target. Oct 13 00:07:02.553986 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Oct 13 00:07:02.571014 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Oct 13 00:07:02.587708 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 13 00:07:02.590484 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Oct 13 00:07:02.608746 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Oct 13 00:07:02.613649 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 13 00:07:02.622927 systemd[1]: Stopped target timers.target - Timer Units. Oct 13 00:07:02.624409 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 13 00:07:02.624533 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 13 00:07:02.643477 systemd[1]: Stopped target initrd.target - Initrd Default Target. Oct 13 00:07:02.651169 systemd[1]: Stopped target basic.target - Basic System. Oct 13 00:07:02.660796 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Oct 13 00:07:02.668316 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Oct 13 00:07:02.676902 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Oct 13 00:07:02.685740 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Oct 13 00:07:02.694340 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Oct 13 00:07:02.703306 systemd[1]: Stopped target sysinit.target - System Initialization. Oct 13 00:07:02.712486 systemd[1]: Stopped target local-fs.target - Local File Systems. Oct 13 00:07:02.720448 systemd[1]: Stopped target swap.target - Swaps. Oct 13 00:07:02.727630 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 13 00:07:02.727764 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Oct 13 00:07:02.738209 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Oct 13 00:07:02.746434 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 13 00:07:02.749306 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Oct 13 00:07:02.749414 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 13 00:07:02.760961 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 13 00:07:02.761100 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Oct 13 00:07:02.777487 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Oct 13 00:07:02.777620 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 13 00:07:02.785962 systemd[1]: ignition-files.service: Deactivated successfully. Oct 13 00:07:02.786075 systemd[1]: Stopped ignition-files.service - Ignition (files). Oct 13 00:07:02.793729 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Oct 13 00:07:02.793871 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Oct 13 00:07:02.821981 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Oct 13 00:07:02.828259 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 13 00:07:02.842444 ignition[1171]: INFO : Ignition 2.20.0 Oct 13 00:07:02.842444 ignition[1171]: INFO : Stage: umount Oct 13 00:07:02.842444 ignition[1171]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 13 00:07:02.842444 ignition[1171]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 13 00:07:02.842444 ignition[1171]: INFO : umount: umount passed Oct 13 00:07:02.842444 ignition[1171]: INFO : Ignition finished successfully Oct 13 00:07:02.828389 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Oct 13 00:07:02.835560 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Oct 13 00:07:02.843755 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 13 00:07:02.843901 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Oct 13 00:07:02.856822 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 13 00:07:02.856958 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Oct 13 00:07:02.870916 systemd[1]: ignition-mount.service: Deactivated successfully. Oct 13 00:07:02.871015 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Oct 13 00:07:02.874887 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 13 00:07:02.874998 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Oct 13 00:07:02.880956 systemd[1]: ignition-kargs.service: Deactivated successfully. Oct 13 00:07:02.881010 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Oct 13 00:07:02.889368 systemd[1]: ignition-fetch.service: Deactivated successfully. Oct 13 00:07:02.889417 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Oct 13 00:07:02.896780 systemd[1]: Stopped target network.target - Network. Oct 13 00:07:02.903689 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Oct 13 00:07:02.903746 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Oct 13 00:07:02.915125 systemd[1]: Stopped target paths.target - Path Units. Oct 13 00:07:02.921977 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 13 00:07:02.925898 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 13 00:07:02.926756 systemd[1]: Stopped target slices.target - Slice Units. Oct 13 00:07:02.937806 systemd[1]: Stopped target sockets.target - Socket Units. Oct 13 00:07:02.944991 systemd[1]: iscsid.socket: Deactivated successfully. Oct 13 00:07:02.945045 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Oct 13 00:07:02.952233 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 13 00:07:02.952301 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 13 00:07:02.959921 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 13 00:07:02.959976 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Oct 13 00:07:02.967530 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Oct 13 00:07:02.967584 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Oct 13 00:07:02.975493 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Oct 13 00:07:02.983086 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Oct 13 00:07:02.991799 systemd[1]: sysroot-boot.mount: Deactivated successfully. Oct 13 00:07:02.992622 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 13 00:07:02.992724 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Oct 13 00:07:03.004140 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Oct 13 00:07:03.004439 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 13 00:07:03.004542 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Oct 13 00:07:03.015977 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Oct 13 00:07:03.016253 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 13 00:07:03.016346 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Oct 13 00:07:03.024228 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 13 00:07:03.024324 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Oct 13 00:07:03.036881 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 13 00:07:03.036953 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Oct 13 00:07:03.038376 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 13 00:07:03.038431 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Oct 13 00:07:03.065001 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Oct 13 00:07:03.068688 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Oct 13 00:07:03.068748 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 13 00:07:03.075775 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 13 00:07:03.075828 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Oct 13 00:07:03.090645 systemd[1]: systemd-modules-load.service: Deactivated successfully. Oct 13 00:07:03.090694 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Oct 13 00:07:03.098772 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 13 00:07:03.098820 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 13 00:07:03.108762 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 13 00:07:03.117762 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Oct 13 00:07:03.117832 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Oct 13 00:07:03.137178 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 13 00:07:03.137331 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 13 00:07:03.141433 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 13 00:07:03.141507 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Oct 13 00:07:03.149707 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 13 00:07:03.149759 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Oct 13 00:07:03.160013 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 13 00:07:03.160067 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Oct 13 00:07:03.175143 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 13 00:07:03.175194 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Oct 13 00:07:03.186061 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 13 00:07:03.186202 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 13 00:07:03.222303 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Oct 13 00:07:03.223852 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 13 00:07:03.223916 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 13 00:07:03.239231 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 13 00:07:03.239281 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 13 00:07:03.249379 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Oct 13 00:07:03.249444 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Oct 13 00:07:03.249840 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 13 00:07:03.249969 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Oct 13 00:07:03.254263 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 13 00:07:03.254354 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Oct 13 00:07:03.262501 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Oct 13 00:07:03.289984 systemd[1]: Starting initrd-switch-root.service - Switch Root... Oct 13 00:07:03.409923 systemd-journald[204]: Received SIGTERM from PID 1 (systemd). Oct 13 00:07:03.296566 systemd[1]: Switching root. Oct 13 00:07:03.412684 systemd-journald[204]: Journal stopped Oct 13 00:07:11.362768 kernel: SELinux: policy capability network_peer_controls=1 Oct 13 00:07:11.362800 kernel: SELinux: policy capability open_perms=1 Oct 13 00:07:11.362812 kernel: SELinux: policy capability extended_socket_class=1 Oct 13 00:07:11.362821 kernel: SELinux: policy capability always_check_network=0 Oct 13 00:07:11.362830 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 13 00:07:11.362840 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 13 00:07:11.362862 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 13 00:07:11.362875 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Oct 13 00:07:11.362885 kernel: audit: type=1403 audit(1760314024.490:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 13 00:07:11.362897 systemd[1]: Successfully loaded SELinux policy in 261.720ms. Oct 13 00:07:11.362909 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 15.268ms. Oct 13 00:07:11.362920 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Oct 13 00:07:11.362931 systemd[1]: Detected virtualization microsoft. Oct 13 00:07:11.362941 systemd[1]: Detected architecture x86-64. Oct 13 00:07:11.362955 systemd[1]: Detected first boot. Oct 13 00:07:11.362967 systemd[1]: Hostname set to . Oct 13 00:07:11.362982 systemd[1]: Initializing machine ID from random generator. Oct 13 00:07:11.362993 zram_generator::config[1218]: No configuration found. Oct 13 00:07:11.363004 kernel: Guest personality initialized and is inactive Oct 13 00:07:11.363017 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Oct 13 00:07:11.363027 kernel: Initialized host personality Oct 13 00:07:11.363037 kernel: NET: Registered PF_VSOCK protocol family Oct 13 00:07:11.363047 systemd[1]: Populated /etc with preset unit settings. Oct 13 00:07:11.363058 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Oct 13 00:07:11.363069 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 13 00:07:11.363079 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Oct 13 00:07:11.363090 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 13 00:07:11.363103 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Oct 13 00:07:11.363115 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Oct 13 00:07:11.363125 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Oct 13 00:07:11.363136 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Oct 13 00:07:11.363147 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Oct 13 00:07:11.363159 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Oct 13 00:07:11.363170 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Oct 13 00:07:11.363183 systemd[1]: Created slice user.slice - User and Session Slice. Oct 13 00:07:11.363195 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 13 00:07:11.363206 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 13 00:07:11.363220 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Oct 13 00:07:11.363231 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Oct 13 00:07:11.363242 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Oct 13 00:07:11.363257 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 13 00:07:11.363269 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Oct 13 00:07:11.363280 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 13 00:07:11.363294 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Oct 13 00:07:11.363305 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Oct 13 00:07:11.363317 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Oct 13 00:07:11.363328 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Oct 13 00:07:11.363340 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 13 00:07:11.363351 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 13 00:07:11.363362 systemd[1]: Reached target slices.target - Slice Units. Oct 13 00:07:11.363376 systemd[1]: Reached target swap.target - Swaps. Oct 13 00:07:11.363387 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Oct 13 00:07:11.363398 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Oct 13 00:07:11.363409 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Oct 13 00:07:11.363421 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 13 00:07:11.363435 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 13 00:07:11.363447 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 13 00:07:11.363458 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Oct 13 00:07:11.363472 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Oct 13 00:07:11.363483 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Oct 13 00:07:11.363495 systemd[1]: Mounting media.mount - External Media Directory... Oct 13 00:07:11.363506 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 13 00:07:11.363518 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Oct 13 00:07:11.363532 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Oct 13 00:07:11.363544 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Oct 13 00:07:11.363555 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). Oct 13 00:07:11.363567 systemd[1]: Reached target machines.target - Containers. Oct 13 00:07:11.363579 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Oct 13 00:07:11.363590 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 13 00:07:11.363602 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 13 00:07:11.363613 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Oct 13 00:07:11.363627 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 13 00:07:11.363639 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 13 00:07:11.363650 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 13 00:07:11.363661 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Oct 13 00:07:11.363673 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 13 00:07:11.363684 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Oct 13 00:07:11.363696 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 13 00:07:11.363707 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Oct 13 00:07:11.363722 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 13 00:07:11.363736 systemd[1]: Stopped systemd-fsck-usr.service. Oct 13 00:07:11.363748 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). Oct 13 00:07:11.363759 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 13 00:07:11.363770 kernel: loop: module loaded Oct 13 00:07:11.363781 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 13 00:07:11.363792 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 13 00:07:11.363804 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Oct 13 00:07:11.363817 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Oct 13 00:07:11.363829 kernel: fuse: init (API version 7.39) Oct 13 00:07:11.363840 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 13 00:07:11.363860 systemd[1]: verity-setup.service: Deactivated successfully. Oct 13 00:07:11.363871 systemd[1]: Stopped verity-setup.service. Oct 13 00:07:11.363883 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 13 00:07:11.363894 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Oct 13 00:07:11.363905 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Oct 13 00:07:11.363916 systemd[1]: Mounted media.mount - External Media Directory. Oct 13 00:07:11.363952 systemd-journald[1320]: Collecting audit messages is disabled. Oct 13 00:07:11.363981 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Oct 13 00:07:11.363994 systemd-journald[1320]: Journal started Oct 13 00:07:11.364020 systemd-journald[1320]: Runtime Journal (/run/log/journal/c58a3f938beb4675b4167ff37c11071c) is 8M, max 2.2G, 2.1G free. Oct 13 00:07:10.460800 systemd[1]: Queued start job for default target multi-user.target. Oct 13 00:07:10.471918 systemd[1]: Unnecessary job was removed for dev-sdb6.device - /dev/sdb6. Oct 13 00:07:10.472416 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 13 00:07:11.372520 systemd[1]: Started systemd-journald.service - Journal Service. Oct 13 00:07:11.376717 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Oct 13 00:07:11.379860 kernel: ACPI: bus type drm_connector registered Oct 13 00:07:11.384060 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Oct 13 00:07:11.388157 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Oct 13 00:07:11.393117 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 13 00:07:11.398300 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 13 00:07:11.398490 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Oct 13 00:07:11.404806 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 13 00:07:11.405015 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 13 00:07:11.409781 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 13 00:07:11.409997 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 13 00:07:11.414472 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 13 00:07:11.414662 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 13 00:07:11.419826 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 13 00:07:11.420028 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Oct 13 00:07:11.424584 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 13 00:07:11.424775 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 13 00:07:11.429415 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 13 00:07:11.434279 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 13 00:07:11.439578 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Oct 13 00:07:11.444950 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Oct 13 00:07:11.460494 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 13 00:07:11.472927 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Oct 13 00:07:11.478275 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Oct 13 00:07:11.482699 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Oct 13 00:07:11.482738 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 13 00:07:11.485235 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Oct 13 00:07:11.492888 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Oct 13 00:07:11.498401 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Oct 13 00:07:11.502651 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 13 00:07:11.534481 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Oct 13 00:07:11.539651 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Oct 13 00:07:11.544108 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 13 00:07:11.545064 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Oct 13 00:07:11.549304 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 13 00:07:11.551067 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 13 00:07:11.555758 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Oct 13 00:07:11.561386 systemd[1]: Starting systemd-sysusers.service - Create System Users... Oct 13 00:07:11.567530 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 13 00:07:11.572649 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Oct 13 00:07:11.578434 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Oct 13 00:07:11.584091 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Oct 13 00:07:11.592722 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Oct 13 00:07:11.601866 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Oct 13 00:07:11.609008 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Oct 13 00:07:11.615205 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Oct 13 00:07:11.620063 udevadm[1368]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Oct 13 00:07:11.623172 systemd-journald[1320]: Time spent on flushing to /var/log/journal/c58a3f938beb4675b4167ff37c11071c is 13.875ms for 944 entries. Oct 13 00:07:11.623172 systemd-journald[1320]: System Journal (/var/log/journal/c58a3f938beb4675b4167ff37c11071c) is 8M, max 2.6G, 2.6G free. Oct 13 00:07:11.677921 systemd-journald[1320]: Received client request to flush runtime journal. Oct 13 00:07:11.678085 kernel: loop0: detected capacity change from 0 to 28272 Oct 13 00:07:11.680740 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Oct 13 00:07:11.688873 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 13 00:07:11.707069 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 13 00:07:11.707750 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Oct 13 00:07:12.314718 systemd[1]: Finished systemd-sysusers.service - Create System Users. Oct 13 00:07:12.334139 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 13 00:07:12.523873 systemd-tmpfiles[1377]: ACLs are not supported, ignoring. Oct 13 00:07:12.523895 systemd-tmpfiles[1377]: ACLs are not supported, ignoring. Oct 13 00:07:12.529795 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 13 00:07:12.690712 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Oct 13 00:07:12.706057 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 13 00:07:12.730403 systemd-udevd[1383]: Using default interface naming scheme 'v255'. Oct 13 00:07:13.336875 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Oct 13 00:07:13.509871 kernel: loop1: detected capacity change from 0 to 138176 Oct 13 00:07:13.760117 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 13 00:07:13.781107 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 13 00:07:13.786766 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Oct 13 00:07:13.877868 kernel: mousedev: PS/2 mouse device common for all mice Oct 13 00:07:13.894873 kernel: hv_vmbus: registering driver hv_balloon Oct 13 00:07:13.895012 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Oct 13 00:07:13.898865 kernel: hv_vmbus: registering driver hyperv_fb Oct 13 00:07:13.901869 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Oct 13 00:07:13.904796 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 13 00:07:13.909639 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Oct 13 00:07:13.914208 kernel: Console: switching to colour dummy device 80x25 Oct 13 00:07:13.914864 kernel: Console: switching to colour frame buffer device 128x48 Oct 13 00:07:13.930059 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 13 00:07:13.930283 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 13 00:07:13.935960 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Oct 13 00:07:13.948983 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 13 00:07:13.955681 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Oct 13 00:07:13.962860 kernel: EDAC MC: Ver: 3.0.0 Oct 13 00:07:14.069641 systemd[1]: Started systemd-userdbd.service - User Database Manager. Oct 13 00:07:14.227015 kernel: BTRFS warning: duplicate device /dev/sdb3 devid 1 generation 38 scanned by (udev-worker) (1390) Oct 13 00:07:14.269424 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Oct 13 00:07:14.283977 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Oct 13 00:07:14.348235 systemd-networkd[1400]: lo: Link UP Oct 13 00:07:14.348244 systemd-networkd[1400]: lo: Gained carrier Oct 13 00:07:14.349658 systemd-networkd[1400]: Enumeration completed Oct 13 00:07:14.349767 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 13 00:07:14.350060 systemd-networkd[1400]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 13 00:07:14.350070 systemd-networkd[1400]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 13 00:07:14.350611 systemd-networkd[1400]: eth0: Link UP Oct 13 00:07:14.350621 systemd-networkd[1400]: eth0: Gained carrier Oct 13 00:07:14.350635 systemd-networkd[1400]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 13 00:07:14.371977 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Oct 13 00:07:14.376925 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Oct 13 00:07:14.378760 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Oct 13 00:07:14.408897 systemd-networkd[1400]: eth0: DHCPv4 address 10.200.8.11/24, gateway 10.200.8.1 acquired from 168.63.129.16 Oct 13 00:07:14.426451 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Oct 13 00:07:14.565018 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Oct 13 00:07:14.579060 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Oct 13 00:07:14.723986 lvm[1518]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 13 00:07:14.737867 kernel: loop2: detected capacity change from 0 to 147912 Oct 13 00:07:14.839738 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Oct 13 00:07:14.841511 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 13 00:07:14.858079 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Oct 13 00:07:14.862121 lvm[1520]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 13 00:07:14.895391 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Oct 13 00:07:15.238866 kernel: loop3: detected capacity change from 0 to 28272 Oct 13 00:07:15.253865 kernel: loop4: detected capacity change from 0 to 138176 Oct 13 00:07:15.273862 kernel: loop5: detected capacity change from 0 to 147912 Oct 13 00:07:15.292376 (sd-merge)[1523]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Oct 13 00:07:15.292891 (sd-merge)[1523]: Merged extensions into '/usr'. Oct 13 00:07:15.296451 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Oct 13 00:07:15.306071 systemd[1]: Starting ensure-sysext.service... Oct 13 00:07:15.308768 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 13 00:07:15.313233 systemd[1]: Reload requested from client PID 1525 ('systemctl') (unit ensure-sysext.service)... Oct 13 00:07:15.313244 systemd[1]: Reloading... Oct 13 00:07:15.342647 systemd-tmpfiles[1526]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Oct 13 00:07:15.342978 systemd-tmpfiles[1526]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Oct 13 00:07:15.343922 systemd-tmpfiles[1526]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Oct 13 00:07:15.344213 systemd-tmpfiles[1526]: ACLs are not supported, ignoring. Oct 13 00:07:15.344288 systemd-tmpfiles[1526]: ACLs are not supported, ignoring. Oct 13 00:07:15.360617 systemd-tmpfiles[1526]: Detected autofs mount point /boot during canonicalization of boot. Oct 13 00:07:15.360633 systemd-tmpfiles[1526]: Skipping /boot Oct 13 00:07:15.362903 zram_generator::config[1561]: No configuration found. Oct 13 00:07:15.372591 systemd-tmpfiles[1526]: Detected autofs mount point /boot during canonicalization of boot. Oct 13 00:07:15.372607 systemd-tmpfiles[1526]: Skipping /boot Oct 13 00:07:15.511963 systemd-networkd[1400]: eth0: Gained IPv6LL Oct 13 00:07:15.515016 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Oct 13 00:07:15.653214 systemd[1]: Reloading finished in 339 ms. Oct 13 00:07:15.675083 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 13 00:07:15.680306 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Oct 13 00:07:15.701752 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 13 00:07:15.718577 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 13 00:07:15.719925 systemd[1]: Starting audit-rules.service - Load Audit Rules... Oct 13 00:07:15.764067 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Oct 13 00:07:15.768735 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 13 00:07:15.769991 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 13 00:07:15.775020 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 13 00:07:15.781756 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 13 00:07:15.786901 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 13 00:07:15.787031 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). Oct 13 00:07:15.788294 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Oct 13 00:07:15.794752 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 13 00:07:15.800016 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Oct 13 00:07:15.804702 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 13 00:07:15.807037 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 13 00:07:15.807232 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 13 00:07:15.812371 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 13 00:07:15.812552 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 13 00:07:15.817835 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 13 00:07:15.818034 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 13 00:07:15.827591 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 13 00:07:15.827800 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 13 00:07:15.834990 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 13 00:07:15.840753 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 13 00:07:15.846601 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 13 00:07:15.850780 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 13 00:07:15.850938 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). Oct 13 00:07:15.851056 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 13 00:07:15.854327 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 13 00:07:15.854518 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 13 00:07:15.859864 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 13 00:07:15.860093 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 13 00:07:15.865594 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 13 00:07:15.865778 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 13 00:07:15.877430 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 13 00:07:15.877776 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 13 00:07:15.890103 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 13 00:07:15.895486 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 13 00:07:15.900735 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 13 00:07:15.906564 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 13 00:07:15.910882 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 13 00:07:15.911063 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). Oct 13 00:07:15.911332 systemd[1]: Reached target time-set.target - System Time Set. Oct 13 00:07:15.916145 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 13 00:07:15.919104 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 13 00:07:15.919304 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 13 00:07:15.924753 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 13 00:07:15.924945 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 13 00:07:15.930012 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 13 00:07:15.930186 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 13 00:07:15.935861 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 13 00:07:15.936070 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 13 00:07:15.943569 systemd[1]: Finished ensure-sysext.service. Oct 13 00:07:15.947282 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Oct 13 00:07:15.957048 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 13 00:07:15.957108 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 13 00:07:15.996712 systemd-resolved[1627]: Positive Trust Anchors: Oct 13 00:07:15.996728 systemd-resolved[1627]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 13 00:07:15.996760 systemd-resolved[1627]: 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 Oct 13 00:07:16.025705 systemd-resolved[1627]: Using system hostname 'ci-4230.2.4-a-f3817b53fd'. Oct 13 00:07:16.027621 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 13 00:07:16.032310 systemd[1]: Reached target network.target - Network. Oct 13 00:07:16.036062 systemd[1]: Reached target network-online.target - Network is Online. Oct 13 00:07:16.040621 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 13 00:07:16.117210 augenrules[1669]: No rules Oct 13 00:07:16.118810 systemd[1]: audit-rules.service: Deactivated successfully. Oct 13 00:07:16.119062 systemd[1]: Finished audit-rules.service - Load Audit Rules. Oct 13 00:07:16.136223 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Oct 13 00:07:19.765187 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Oct 13 00:07:19.771448 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). Oct 13 00:07:23.289389 ldconfig[1355]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 13 00:07:23.301709 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Oct 13 00:07:23.322051 systemd[1]: Starting systemd-update-done.service - Update is Completed... Oct 13 00:07:23.343469 systemd[1]: Finished systemd-update-done.service - Update is Completed. Oct 13 00:07:23.348413 systemd[1]: Reached target sysinit.target - System Initialization. Oct 13 00:07:23.352955 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Oct 13 00:07:23.358127 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 13 00:07:23.364216 systemd[1]: Started logrotate.timer - Daily rotation of log files. Oct 13 00:07:23.368769 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Oct 13 00:07:23.373955 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Oct 13 00:07:23.379056 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Oct 13 00:07:23.379158 systemd[1]: Reached target paths.target - Path Units. Oct 13 00:07:23.382815 systemd[1]: Reached target timers.target - Timer Units. Oct 13 00:07:23.387086 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Oct 13 00:07:23.393098 systemd[1]: Starting docker.socket - Docker Socket for the API... Oct 13 00:07:23.399751 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Oct 13 00:07:23.405160 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Oct 13 00:07:23.410226 systemd[1]: Reached target ssh-access.target - SSH Access Available. Oct 13 00:07:23.416740 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Oct 13 00:07:23.434337 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Oct 13 00:07:23.441950 systemd[1]: Listening on docker.socket - Docker Socket for the API. Oct 13 00:07:23.446384 systemd[1]: Reached target sockets.target - Socket Units. Oct 13 00:07:23.454637 systemd[1]: Reached target basic.target - Basic System. Oct 13 00:07:23.459071 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Oct 13 00:07:23.459134 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Oct 13 00:07:23.479924 systemd[1]: Starting chronyd.service - NTP client/server... Oct 13 00:07:23.484180 systemd[1]: Starting containerd.service - containerd container runtime... Oct 13 00:07:23.489978 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Oct 13 00:07:23.495057 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Oct 13 00:07:23.499500 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Oct 13 00:07:23.505988 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Oct 13 00:07:23.509996 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). Oct 13 00:07:23.510042 systemd[1]: hv_fcopy_daemon.service - Hyper-V FCOPY daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_fcopy). Oct 13 00:07:23.511055 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Oct 13 00:07:23.515221 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Oct 13 00:07:23.516197 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Oct 13 00:07:23.520723 jq[1690]: false Oct 13 00:07:23.522373 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Oct 13 00:07:23.527195 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Oct 13 00:07:23.532758 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Oct 13 00:07:23.533465 KVP[1692]: KVP starting; pid is:1692 Oct 13 00:07:23.533990 (chronyd)[1683]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Oct 13 00:07:23.537725 KVP[1692]: KVP LIC Version: 3.1 Oct 13 00:07:23.537909 kernel: hv_utils: KVP IC version 4.0 Oct 13 00:07:23.541327 systemd[1]: Starting systemd-logind.service - User Login Management... Oct 13 00:07:23.546250 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Oct 13 00:07:23.546689 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 13 00:07:23.547312 systemd[1]: Starting update-engine.service - Update Engine... Oct 13 00:07:23.553517 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Oct 13 00:07:23.556663 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Oct 13 00:07:23.556907 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Oct 13 00:07:23.557224 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 13 00:07:23.557442 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Oct 13 00:07:23.564400 chronyd[1705]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) Oct 13 00:07:23.568100 jq[1702]: true Oct 13 00:07:23.576707 jq[1713]: true Oct 13 00:07:23.593706 (ntainerd)[1726]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Oct 13 00:07:23.593855 systemd[1]: motdgen.service: Deactivated successfully. Oct 13 00:07:23.594097 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Oct 13 00:07:23.613517 extend-filesystems[1691]: Found loop3 Oct 13 00:07:23.616531 extend-filesystems[1691]: Found loop4 Oct 13 00:07:23.616531 extend-filesystems[1691]: Found loop5 Oct 13 00:07:23.616531 extend-filesystems[1691]: Found sda Oct 13 00:07:23.616531 extend-filesystems[1691]: Found sda1 Oct 13 00:07:23.616531 extend-filesystems[1691]: Found sdb Oct 13 00:07:23.616531 extend-filesystems[1691]: Found sdb1 Oct 13 00:07:23.616531 extend-filesystems[1691]: Found sdb2 Oct 13 00:07:23.616531 extend-filesystems[1691]: Found sdb3 Oct 13 00:07:23.616531 extend-filesystems[1691]: Found usr Oct 13 00:07:23.616531 extend-filesystems[1691]: Found sdb4 Oct 13 00:07:23.616531 extend-filesystems[1691]: Found sdb6 Oct 13 00:07:23.616531 extend-filesystems[1691]: Found sdb7 Oct 13 00:07:23.616531 extend-filesystems[1691]: Found sdb9 Oct 13 00:07:23.616531 extend-filesystems[1691]: Checking size of /dev/sdb9 Oct 13 00:07:23.633111 systemd-logind[1698]: New seat seat0. Oct 13 00:07:23.659059 update_engine[1701]: I20251013 00:07:23.647112 1701 main.cc:92] Flatcar Update Engine starting Oct 13 00:07:23.634505 systemd-logind[1698]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Oct 13 00:07:23.634708 systemd[1]: Started systemd-logind.service - User Login Management. Oct 13 00:07:23.669621 extend-filesystems[1691]: Old size kept for /dev/sdb9 Oct 13 00:07:23.671126 extend-filesystems[1691]: Found sr0 Oct 13 00:07:23.670226 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 13 00:07:23.670464 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Oct 13 00:07:23.691746 chronyd[1705]: Timezone right/UTC failed leap second check, ignoring Oct 13 00:07:23.691945 chronyd[1705]: Loaded seccomp filter (level 2) Oct 13 00:07:23.694609 systemd[1]: Started chronyd.service - NTP client/server. Oct 13 00:07:23.702864 kernel: BTRFS warning: duplicate device /dev/sdb3 devid 1 generation 38 scanned by (udev-worker) (1746) Oct 13 00:07:23.777694 bash[1738]: Updated "/home/core/.ssh/authorized_keys" Oct 13 00:07:23.778504 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Oct 13 00:07:23.784817 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Oct 13 00:07:23.793099 sshd_keygen[1739]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Oct 13 00:07:23.811240 setup-nvidia[1694]: Downloading Flatcar Container Linux Developer Container for version: 4230.2.4 Oct 13 00:07:23.816161 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Oct 13 00:07:23.834057 systemd[1]: Starting issuegen.service - Generate /run/issue... Oct 13 00:07:23.841052 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Oct 13 00:07:23.845344 systemd[1]: issuegen.service: Deactivated successfully. Oct 13 00:07:23.845550 systemd[1]: Finished issuegen.service - Generate /run/issue. Oct 13 00:07:23.852595 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Oct 13 00:07:23.862597 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Oct 13 00:07:23.877741 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Oct 13 00:07:23.883965 systemd[1]: Started getty@tty1.service - Getty on tty1. Oct 13 00:07:23.888831 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Oct 13 00:07:23.893266 systemd[1]: Reached target getty.target - Login Prompts. Oct 13 00:07:23.938749 dbus-daemon[1686]: [system] SELinux support is enabled Oct 13 00:07:23.938994 systemd[1]: Started dbus.service - D-Bus System Message Bus. Oct 13 00:07:23.942105 update_engine[1701]: I20251013 00:07:23.942046 1701 update_check_scheduler.cc:74] Next update check in 6m42s Oct 13 00:07:23.945480 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). Oct 13 00:07:23.945516 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Oct 13 00:07:23.950657 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). Oct 13 00:07:23.950688 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Oct 13 00:07:23.957191 systemd[1]: Started update-engine.service - Update Engine. Oct 13 00:07:23.957329 dbus-daemon[1686]: [system] Successfully activated service 'org.freedesktop.systemd1' Oct 13 00:07:23.993263 coreos-metadata[1685]: Oct 13 00:07:23.993 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Oct 13 00:07:23.994196 systemd[1]: Started locksmithd.service - Cluster reboot manager. Oct 13 00:07:23.998539 coreos-metadata[1685]: Oct 13 00:07:23.996 INFO Fetch successful Oct 13 00:07:23.998539 coreos-metadata[1685]: Oct 13 00:07:23.996 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Oct 13 00:07:24.000933 coreos-metadata[1685]: Oct 13 00:07:24.000 INFO Fetch successful Oct 13 00:07:24.001242 coreos-metadata[1685]: Oct 13 00:07:24.001 INFO Fetching http://168.63.129.16/machine/130ea25f-05f8-41bd-9b33-2455993ee69a/ee1fb924%2D7191%2D45a0%2Db6dc%2De07e746de329.%5Fci%2D4230.2.4%2Da%2Df3817b53fd?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Oct 13 00:07:24.004164 coreos-metadata[1685]: Oct 13 00:07:24.004 INFO Fetch successful Oct 13 00:07:24.004311 coreos-metadata[1685]: Oct 13 00:07:24.004 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Oct 13 00:07:24.011493 coreos-metadata[1685]: Oct 13 00:07:24.011 INFO Fetch successful Oct 13 00:07:24.043114 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Oct 13 00:07:24.048096 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Oct 13 00:07:24.185923 locksmithd[1838]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 13 00:07:24.457386 setup-nvidia[1853]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 13 00:07:24.457758 setup-nvidia[1853]: Dload Upload Total Spent Left Speed Oct 13 00:07:24.840471 containerd[1726]: time="2025-10-13T00:07:24.840383100Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Oct 13 00:07:24.859105 containerd[1726]: time="2025-10-13T00:07:24.859059700Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Oct 13 00:07:24.860621 containerd[1726]: time="2025-10-13T00:07:24.860588600Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.110-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Oct 13 00:07:24.860676 containerd[1726]: time="2025-10-13T00:07:24.860618300Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Oct 13 00:07:24.860676 containerd[1726]: time="2025-10-13T00:07:24.860634400Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Oct 13 00:07:24.860800 containerd[1726]: time="2025-10-13T00:07:24.860777800Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Oct 13 00:07:24.860835 containerd[1726]: time="2025-10-13T00:07:24.860800700Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Oct 13 00:07:24.860897 containerd[1726]: time="2025-10-13T00:07:24.860875700Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Oct 13 00:07:24.860897 containerd[1726]: time="2025-10-13T00:07:24.860894300Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Oct 13 00:07:24.861100 containerd[1726]: time="2025-10-13T00:07:24.861074700Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." 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" type=io.containerd.snapshotter.v1 Oct 13 00:07:24.861100 containerd[1726]: time="2025-10-13T00:07:24.861096800Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Oct 13 00:07:24.861148 containerd[1726]: time="2025-10-13T00:07:24.861110000Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Oct 13 00:07:24.861148 containerd[1726]: time="2025-10-13T00:07:24.861119600Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Oct 13 00:07:24.861225 containerd[1726]: time="2025-10-13T00:07:24.861205100Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Oct 13 00:07:24.861483 containerd[1726]: time="2025-10-13T00:07:24.861460800Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Oct 13 00:07:24.861613 containerd[1726]: time="2025-10-13T00:07:24.861591800Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Oct 13 00:07:24.861642 containerd[1726]: time="2025-10-13T00:07:24.861611200Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Oct 13 00:07:24.861710 containerd[1726]: time="2025-10-13T00:07:24.861691300Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Oct 13 00:07:24.861765 containerd[1726]: time="2025-10-13T00:07:24.861746800Z" level=info msg="metadata content store policy set" policy=shared Oct 13 00:07:24.875583 containerd[1726]: time="2025-10-13T00:07:24.875550100Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Oct 13 00:07:24.875637 containerd[1726]: time="2025-10-13T00:07:24.875595600Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Oct 13 00:07:24.875637 containerd[1726]: time="2025-10-13T00:07:24.875611600Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Oct 13 00:07:24.875637 containerd[1726]: time="2025-10-13T00:07:24.875626100Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Oct 13 00:07:24.875705 containerd[1726]: time="2025-10-13T00:07:24.875639000Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Oct 13 00:07:24.875774 containerd[1726]: time="2025-10-13T00:07:24.875753100Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Oct 13 00:07:24.875970 containerd[1726]: time="2025-10-13T00:07:24.875950200Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Oct 13 00:07:24.876092 containerd[1726]: time="2025-10-13T00:07:24.876071400Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Oct 13 00:07:24.876120 containerd[1726]: time="2025-10-13T00:07:24.876098500Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Oct 13 00:07:24.876120 containerd[1726]: time="2025-10-13T00:07:24.876114600Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Oct 13 00:07:24.876161 containerd[1726]: time="2025-10-13T00:07:24.876127300Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Oct 13 00:07:24.876161 containerd[1726]: time="2025-10-13T00:07:24.876140600Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Oct 13 00:07:24.876161 containerd[1726]: time="2025-10-13T00:07:24.876152700Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Oct 13 00:07:24.876232 containerd[1726]: time="2025-10-13T00:07:24.876164400Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Oct 13 00:07:24.876232 containerd[1726]: time="2025-10-13T00:07:24.876176900Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Oct 13 00:07:24.876360 containerd[1726]: time="2025-10-13T00:07:24.876275500Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Oct 13 00:07:24.876590 containerd[1726]: time="2025-10-13T00:07:24.876375800Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Oct 13 00:07:24.876590 containerd[1726]: time="2025-10-13T00:07:24.876403000Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Oct 13 00:07:24.876590 containerd[1726]: time="2025-10-13T00:07:24.876431400Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Oct 13 00:07:24.876590 containerd[1726]: time="2025-10-13T00:07:24.876452500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Oct 13 00:07:24.876590 containerd[1726]: time="2025-10-13T00:07:24.876470900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Oct 13 00:07:24.876590 containerd[1726]: time="2025-10-13T00:07:24.876489000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Oct 13 00:07:24.876590 containerd[1726]: time="2025-10-13T00:07:24.876505900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Oct 13 00:07:24.876590 containerd[1726]: time="2025-10-13T00:07:24.876523100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Oct 13 00:07:24.876590 containerd[1726]: time="2025-10-13T00:07:24.876561600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Oct 13 00:07:24.876795 containerd[1726]: time="2025-10-13T00:07:24.876617100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Oct 13 00:07:24.876795 containerd[1726]: time="2025-10-13T00:07:24.876642800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Oct 13 00:07:24.876795 containerd[1726]: time="2025-10-13T00:07:24.876773300Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Oct 13 00:07:24.876876 containerd[1726]: time="2025-10-13T00:07:24.876810400Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Oct 13 00:07:24.876876 containerd[1726]: time="2025-10-13T00:07:24.876827500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Oct 13 00:07:24.876876 containerd[1726]: time="2025-10-13T00:07:24.876839100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Oct 13 00:07:24.876876 containerd[1726]: time="2025-10-13T00:07:24.876867700Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Oct 13 00:07:24.876970 containerd[1726]: time="2025-10-13T00:07:24.876891900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Oct 13 00:07:24.876970 containerd[1726]: time="2025-10-13T00:07:24.876905300Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Oct 13 00:07:24.876970 containerd[1726]: time="2025-10-13T00:07:24.876915200Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Oct 13 00:07:24.876970 containerd[1726]: time="2025-10-13T00:07:24.876949400Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Oct 13 00:07:24.876970 containerd[1726]: time="2025-10-13T00:07:24.876965200Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Oct 13 00:07:24.877079 containerd[1726]: time="2025-10-13T00:07:24.876976200Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Oct 13 00:07:24.877079 containerd[1726]: time="2025-10-13T00:07:24.876987100Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Oct 13 00:07:24.877079 containerd[1726]: time="2025-10-13T00:07:24.876996100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Oct 13 00:07:24.877079 containerd[1726]: time="2025-10-13T00:07:24.877007000Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Oct 13 00:07:24.877079 containerd[1726]: time="2025-10-13T00:07:24.877016100Z" level=info msg="NRI interface is disabled by configuration." Oct 13 00:07:24.877079 containerd[1726]: time="2025-10-13T00:07:24.877025100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Oct 13 00:07:24.877316 containerd[1726]: time="2025-10-13T00:07:24.877265800Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} 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}" Oct 13 00:07:24.877316 containerd[1726]: time="2025-10-13T00:07:24.877317700Z" level=info msg="Connect containerd service" Oct 13 00:07:24.877478 containerd[1726]: time="2025-10-13T00:07:24.877354800Z" level=info msg="using legacy CRI server" Oct 13 00:07:24.877478 containerd[1726]: time="2025-10-13T00:07:24.877365200Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Oct 13 00:07:24.877478 containerd[1726]: time="2025-10-13T00:07:24.877454900Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Oct 13 00:07:24.878030 containerd[1726]: time="2025-10-13T00:07:24.878004300Z" 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" Oct 13 00:07:24.878182 containerd[1726]: time="2025-10-13T00:07:24.878143500Z" level=info msg="Start subscribing containerd event" Oct 13 00:07:24.878221 containerd[1726]: time="2025-10-13T00:07:24.878192700Z" level=info msg="Start recovering state" Oct 13 00:07:24.878289 containerd[1726]: time="2025-10-13T00:07:24.878270100Z" level=info msg="Start event monitor" Oct 13 00:07:24.878319 containerd[1726]: time="2025-10-13T00:07:24.878289300Z" level=info msg="Start snapshots syncer" Oct 13 00:07:24.878319 containerd[1726]: time="2025-10-13T00:07:24.878298900Z" level=info msg="Start cni network conf syncer for default" Oct 13 00:07:24.878319 containerd[1726]: time="2025-10-13T00:07:24.878306500Z" level=info msg="Start streaming server" Oct 13 00:07:24.878444 containerd[1726]: time="2025-10-13T00:07:24.878358200Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Oct 13 00:07:24.878444 containerd[1726]: time="2025-10-13T00:07:24.878418900Z" level=info msg=serving... address=/run/containerd/containerd.sock Oct 13 00:07:24.878493 containerd[1726]: time="2025-10-13T00:07:24.878484000Z" level=info msg="containerd successfully booted in 0.038969s" Oct 13 00:07:24.878565 systemd[1]: Started containerd.service - containerd container runtime. Oct 13 00:07:26.452989 waagent[1832]: 2025-10-13T00:07:26.452883Z INFO Daemon Daemon Azure Linux Agent Version: 2.9.1.1 Oct 13 00:07:26.458018 waagent[1832]: 2025-10-13T00:07:26.457941Z INFO Daemon Daemon OS: flatcar 4230.2.4 Oct 13 00:07:26.461637 waagent[1832]: 2025-10-13T00:07:26.461575Z INFO Daemon Daemon Python: 3.11.11 Oct 13 00:07:26.465155 waagent[1832]: 2025-10-13T00:07:26.465093Z INFO Daemon Daemon Run daemon Oct 13 00:07:26.468346 waagent[1832]: 2025-10-13T00:07:26.468291Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4230.2.4' Oct 13 00:07:26.475301 waagent[1832]: 2025-10-13T00:07:26.475242Z INFO Daemon Daemon Using waagent for provisioning Oct 13 00:07:26.479144 waagent[1832]: 2025-10-13T00:07:26.479098Z INFO Daemon Daemon Activate resource disk Oct 13 00:07:26.482684 waagent[1832]: 2025-10-13T00:07:26.482621Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Oct 13 00:07:26.490028 waagent[1832]: 2025-10-13T00:07:26.489966Z INFO Daemon Daemon Found device: sda Oct 13 00:07:26.498949 waagent[1832]: 2025-10-13T00:07:26.498896Z INFO Daemon Daemon Examining partition table Oct 13 00:07:26.540244 waagent[1832]: 2025-10-13T00:07:26.540149Z INFO Daemon Daemon GPT not detected, determining filesystem Oct 13 00:07:26.603249 waagent[1832]: 2025-10-13T00:07:26.603151Z INFO Daemon Daemon sfdisk --part-type -f /dev/sda 1 -n succeeded Oct 13 00:07:26.607816 waagent[1832]: 2025-10-13T00:07:26.607758Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Oct 13 00:07:27.075866 kernel: sda: sda1 Oct 13 00:07:27.082866 kernel: sda: sda1 Oct 13 00:07:27.121959 waagent[1832]: 2025-10-13T00:07:27.121417Z INFO Daemon Daemon sfdisk --part-type /dev/sda 1 83 succeeded Oct 13 00:07:27.135729 waagent[1832]: 2025-10-13T00:07:27.135668Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sda1] Oct 13 00:07:27.135858 kernel: sda: sda1 Oct 13 00:07:32.277393 login[1834]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Oct 13 00:07:32.665380 login[1835]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Oct 13 00:07:32.672686 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Oct 13 00:07:32.686047 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Oct 13 00:07:32.691483 systemd-logind[1698]: New session 2 of user core. Oct 13 00:07:32.780868 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Oct 13 00:07:32.796148 systemd[1]: Starting user@500.service - User Manager for UID 500... Oct 13 00:07:32.816483 waagent[1832]: 2025-10-13T00:07:32.816401Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sda1 /mnt/resource] Oct 13 00:07:32.827690 (systemd)[1938]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 13 00:07:32.830157 systemd-logind[1698]: New session c1 of user core. Oct 13 00:07:32.837797 waagent[1832]: 2025-10-13T00:07:32.837723Z INFO Daemon Daemon Resource disk /dev/sda is mounted at /mnt/resource with ext4 Oct 13 00:07:32.837916 kernel: EXT4-fs (sda1): mounted filesystem 95483aa3-b8c1-4a88-907e-3e71091d34a3 r/w with ordered data mode. Quota mode: none. Oct 13 00:07:32.840098 waagent[1832]: 2025-10-13T00:07:32.840038Z INFO Daemon Daemon Clean protocol and wireserver endpoint Oct 13 00:07:32.841070 waagent[1832]: 2025-10-13T00:07:32.841017Z INFO Daemon Daemon Running default provisioning handler Oct 13 00:07:32.861493 waagent[1832]: 2025-10-13T00:07:32.860409Z 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. Oct 13 00:07:32.864051 waagent[1832]: 2025-10-13T00:07:32.863962Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Oct 13 00:07:32.869667 waagent[1832]: 2025-10-13T00:07:32.869608Z INFO Daemon Daemon cloud-init is enabled: False Oct 13 00:07:32.871315 waagent[1832]: 2025-10-13T00:07:32.871264Z INFO Daemon Daemon Copying ovf-env.xml Oct 13 00:07:32.961533 waagent[1832]: 2025-10-13T00:07:32.961401Z INFO Daemon Daemon Successfully mounted dvd Oct 13 00:07:32.985975 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Oct 13 00:07:32.986107 waagent[1832]: 2025-10-13T00:07:32.985942Z INFO Daemon Daemon Detect protocol endpoint Oct 13 00:07:32.987640 waagent[1832]: 2025-10-13T00:07:32.987587Z INFO Daemon Daemon Clean protocol and wireserver endpoint Oct 13 00:07:32.988416 waagent[1832]: 2025-10-13T00:07:32.988374Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Oct 13 00:07:32.989891 waagent[1832]: 2025-10-13T00:07:32.989839Z INFO Daemon Daemon Test for route to 168.63.129.16 Oct 13 00:07:32.990763 waagent[1832]: 2025-10-13T00:07:32.990719Z INFO Daemon Daemon Route to 168.63.129.16 exists Oct 13 00:07:32.991415 waagent[1832]: 2025-10-13T00:07:32.991375Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Oct 13 00:07:33.053679 waagent[1832]: 2025-10-13T00:07:33.053621Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Oct 13 00:07:33.055352 waagent[1832]: 2025-10-13T00:07:33.055315Z INFO Daemon Daemon Wire protocol version:2012-11-30 Oct 13 00:07:33.055895 waagent[1832]: 2025-10-13T00:07:33.055854Z INFO Daemon Daemon Server preferred version:2015-04-05 Oct 13 00:07:33.200478 waagent[1832]: 2025-10-13T00:07:33.200384Z INFO Daemon Daemon Initializing goal state during protocol detection Oct 13 00:07:33.202099 waagent[1832]: 2025-10-13T00:07:33.202040Z INFO Daemon Daemon Forcing an update of the goal state. Oct 13 00:07:33.205840 waagent[1832]: 2025-10-13T00:07:33.205785Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Oct 13 00:07:33.222472 waagent[1832]: 2025-10-13T00:07:33.222390Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.177 Oct 13 00:07:33.224383 waagent[1832]: 2025-10-13T00:07:33.224335Z INFO Daemon Oct 13 00:07:33.224761 waagent[1832]: 2025-10-13T00:07:33.224720Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: db4b633d-46fd-4a78-b855-66e4443aac37 eTag: 3107231048417377643 source: Fabric] Oct 13 00:07:33.226497 waagent[1832]: 2025-10-13T00:07:33.226446Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Oct 13 00:07:33.228250 waagent[1832]: 2025-10-13T00:07:33.228202Z INFO Daemon Oct 13 00:07:33.228457 waagent[1832]: 2025-10-13T00:07:33.228418Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Oct 13 00:07:33.233283 waagent[1832]: 2025-10-13T00:07:33.233230Z INFO Daemon Daemon Downloading artifacts profile blob Oct 13 00:07:33.239142 systemd[1938]: Queued start job for default target default.target. Oct 13 00:07:33.267481 systemd[1938]: Created slice app.slice - User Application Slice. Oct 13 00:07:33.267513 systemd[1938]: Reached target paths.target - Paths. Oct 13 00:07:33.267561 systemd[1938]: Reached target timers.target - Timers. Oct 13 00:07:33.268882 systemd[1938]: Starting dbus.socket - D-Bus User Message Bus Socket... Oct 13 00:07:33.277758 login[1834]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Oct 13 00:07:33.279721 systemd[1938]: Listening on dbus.socket - D-Bus User Message Bus Socket. Oct 13 00:07:33.279864 systemd[1938]: Reached target sockets.target - Sockets. Oct 13 00:07:33.279920 systemd[1938]: Reached target basic.target - Basic System. Oct 13 00:07:33.279967 systemd[1938]: Reached target default.target - Main User Target. Oct 13 00:07:33.280001 systemd[1938]: Startup finished in 443ms. Oct 13 00:07:33.280093 systemd[1]: Started user@500.service - User Manager for UID 500. Oct 13 00:07:33.281623 systemd[1]: Started session-2.scope - Session 2 of User core. Oct 13 00:07:33.283815 systemd-logind[1698]: New session 1 of user core. Oct 13 00:07:33.284808 systemd[1]: Started session-1.scope - Session 1 of User core. Oct 13 00:07:33.294016 waagent[1832]: 2025-10-13T00:07:33.293954Z INFO Daemon Downloaded certificate {'thumbprint': '4338E0E11B5B9A0F6FB3F1E2E27644641B5BD6E3', 'hasPrivateKey': True} Oct 13 00:07:33.296119 waagent[1832]: 2025-10-13T00:07:33.296068Z INFO Daemon Fetch goal state completed Oct 13 00:07:33.309250 waagent[1832]: 2025-10-13T00:07:33.309204Z INFO Daemon Daemon Starting provisioning Oct 13 00:07:33.310777 waagent[1832]: 2025-10-13T00:07:33.310728Z INFO Daemon Daemon Handle ovf-env.xml. Oct 13 00:07:33.312252 waagent[1832]: 2025-10-13T00:07:33.312212Z INFO Daemon Daemon Set hostname [ci-4230.2.4-a-f3817b53fd] Oct 13 00:07:33.374712 waagent[1832]: 2025-10-13T00:07:33.374638Z INFO Daemon Daemon Publish hostname [ci-4230.2.4-a-f3817b53fd] Oct 13 00:07:33.376635 waagent[1832]: 2025-10-13T00:07:33.376575Z INFO Daemon Daemon Examine /proc/net/route for primary interface Oct 13 00:07:33.377316 waagent[1832]: 2025-10-13T00:07:33.377260Z INFO Daemon Daemon Primary interface is [eth0] Oct 13 00:07:33.386202 systemd-networkd[1400]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 13 00:07:33.386212 systemd-networkd[1400]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 13 00:07:33.386284 systemd-networkd[1400]: eth0: DHCP lease lost Oct 13 00:07:33.386634 waagent[1832]: 2025-10-13T00:07:33.386582Z INFO Daemon Daemon Create user account if not exists Oct 13 00:07:33.388959 waagent[1832]: 2025-10-13T00:07:33.388912Z INFO Daemon Daemon User core already exists, skip useradd Oct 13 00:07:33.389669 waagent[1832]: 2025-10-13T00:07:33.389630Z INFO Daemon Daemon Configure sudoer Oct 13 00:07:33.391441 waagent[1832]: 2025-10-13T00:07:33.391394Z INFO Daemon Daemon Configure sshd Oct 13 00:07:33.392933 waagent[1832]: 2025-10-13T00:07:33.392884Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Oct 13 00:07:33.393485 waagent[1832]: 2025-10-13T00:07:33.393445Z INFO Daemon Daemon Deploy ssh public key. Oct 13 00:07:33.461880 systemd-networkd[1400]: eth0: DHCPv4 address 10.200.8.11/24, gateway 10.200.8.1 acquired from 168.63.129.16 Oct 13 00:07:34.595449 waagent[1832]: 2025-10-13T00:07:34.595384Z INFO Daemon Daemon Provisioning complete Oct 13 00:07:34.606063 waagent[1832]: 2025-10-13T00:07:34.606016Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Oct 13 00:07:34.607712 waagent[1832]: 2025-10-13T00:07:34.607650Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Oct 13 00:07:34.609020 waagent[1832]: 2025-10-13T00:07:34.608975Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.9.1.1 is the most current agent Oct 13 00:07:34.760073 waagent[1995]: 2025-10-13T00:07:34.759977Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.9.1.1) Oct 13 00:07:34.760418 waagent[1995]: 2025-10-13T00:07:34.760136Z INFO ExtHandler ExtHandler OS: flatcar 4230.2.4 Oct 13 00:07:34.760418 waagent[1995]: 2025-10-13T00:07:34.760217Z INFO ExtHandler ExtHandler Python: 3.11.11 Oct 13 00:07:34.888912 waagent[1995]: 2025-10-13T00:07:34.888750Z INFO ExtHandler ExtHandler Distro: flatcar-4230.2.4; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.11; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; Oct 13 00:07:34.889091 waagent[1995]: 2025-10-13T00:07:34.889044Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Oct 13 00:07:34.889178 waagent[1995]: 2025-10-13T00:07:34.889138Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Oct 13 00:07:34.897683 waagent[1995]: 2025-10-13T00:07:34.897612Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Oct 13 00:07:34.902560 waagent[1995]: 2025-10-13T00:07:34.902512Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.177 Oct 13 00:07:34.903045 waagent[1995]: 2025-10-13T00:07:34.902997Z INFO ExtHandler Oct 13 00:07:34.903137 waagent[1995]: 2025-10-13T00:07:34.903098Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 65333250-2bf8-4e72-ba8f-7c9377581645 eTag: 3107231048417377643 source: Fabric] Oct 13 00:07:34.903480 waagent[1995]: 2025-10-13T00:07:34.903434Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Oct 13 00:07:34.904176 waagent[1995]: 2025-10-13T00:07:34.904126Z INFO ExtHandler Oct 13 00:07:34.904261 waagent[1995]: 2025-10-13T00:07:34.904223Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Oct 13 00:07:34.907758 waagent[1995]: 2025-10-13T00:07:34.907712Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Oct 13 00:07:34.970939 waagent[1995]: 2025-10-13T00:07:34.970833Z INFO ExtHandler Downloaded certificate {'thumbprint': '4338E0E11B5B9A0F6FB3F1E2E27644641B5BD6E3', 'hasPrivateKey': True} Oct 13 00:07:34.971507 waagent[1995]: 2025-10-13T00:07:34.971451Z INFO ExtHandler Fetch goal state completed Oct 13 00:07:34.982882 waagent[1995]: 2025-10-13T00:07:34.982810Z INFO ExtHandler ExtHandler WALinuxAgent-2.9.1.1 running as process 1995 Oct 13 00:07:34.983041 waagent[1995]: 2025-10-13T00:07:34.982997Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Oct 13 00:07:34.985580 waagent[1995]: 2025-10-13T00:07:34.985532Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '4230.2.4', '', 'Flatcar Container Linux by Kinvolk'] Oct 13 00:07:34.985984 waagent[1995]: 2025-10-13T00:07:34.985938Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Oct 13 00:07:35.157990 waagent[1995]: 2025-10-13T00:07:35.157873Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Oct 13 00:07:35.158151 waagent[1995]: 2025-10-13T00:07:35.158104Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Oct 13 00:07:35.164676 waagent[1995]: 2025-10-13T00:07:35.164624Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Oct 13 00:07:35.171950 systemd[1]: Reload requested from client PID 2008 ('systemctl') (unit waagent.service)... Oct 13 00:07:35.171967 systemd[1]: Reloading... Oct 13 00:07:35.245874 zram_generator::config[2048]: No configuration found. Oct 13 00:07:35.382467 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Oct 13 00:07:35.552413 systemd[1]: Reloading finished in 380 ms. Oct 13 00:07:35.573962 waagent[1995]: 2025-10-13T00:07:35.573876Z INFO ExtHandler ExtHandler Executing systemctl daemon-reload for setting up waagent-network-setup.service Oct 13 00:07:35.583348 systemd[1]: Reload requested from client PID 2105 ('systemctl') (unit waagent.service)... Oct 13 00:07:35.583365 systemd[1]: Reloading... Oct 13 00:07:35.663008 zram_generator::config[2145]: No configuration found. Oct 13 00:07:35.813681 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Oct 13 00:07:35.976099 systemd[1]: Reloading finished in 392 ms. Oct 13 00:07:35.995748 waagent[1995]: 2025-10-13T00:07:35.995662Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Oct 13 00:07:35.996095 waagent[1995]: 2025-10-13T00:07:35.995831Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Oct 13 00:07:37.027139 waagent[1995]: 2025-10-13T00:07:37.027042Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Oct 13 00:07:37.027785 waagent[1995]: 2025-10-13T00:07:37.027722Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: configuration enabled [True], cgroups enabled [False], python supported: [True] Oct 13 00:07:37.028725 waagent[1995]: 2025-10-13T00:07:37.028662Z INFO ExtHandler ExtHandler Starting env monitor service. Oct 13 00:07:37.029110 waagent[1995]: 2025-10-13T00:07:37.029055Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Oct 13 00:07:37.029297 waagent[1995]: 2025-10-13T00:07:37.029247Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Oct 13 00:07:37.029473 waagent[1995]: 2025-10-13T00:07:37.029429Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Oct 13 00:07:37.029951 waagent[1995]: 2025-10-13T00:07:37.029897Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Oct 13 00:07:37.030223 waagent[1995]: 2025-10-13T00:07:37.030151Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Oct 13 00:07:37.030360 waagent[1995]: 2025-10-13T00:07:37.030294Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Oct 13 00:07:37.030860 waagent[1995]: 2025-10-13T00:07:37.030790Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Oct 13 00:07:37.030975 waagent[1995]: 2025-10-13T00:07:37.030912Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Oct 13 00:07:37.031094 waagent[1995]: 2025-10-13T00:07:37.031037Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Oct 13 00:07:37.031460 waagent[1995]: 2025-10-13T00:07:37.031415Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Oct 13 00:07:37.031719 waagent[1995]: 2025-10-13T00:07:37.031674Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Oct 13 00:07:37.031966 waagent[1995]: 2025-10-13T00:07:37.031912Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Oct 13 00:07:37.031966 waagent[1995]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Oct 13 00:07:37.031966 waagent[1995]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Oct 13 00:07:37.031966 waagent[1995]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Oct 13 00:07:37.031966 waagent[1995]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Oct 13 00:07:37.031966 waagent[1995]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Oct 13 00:07:37.031966 waagent[1995]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Oct 13 00:07:37.032318 waagent[1995]: 2025-10-13T00:07:37.032266Z INFO EnvHandler ExtHandler Configure routes Oct 13 00:07:37.033508 waagent[1995]: 2025-10-13T00:07:37.033448Z INFO EnvHandler ExtHandler Gateway:None Oct 13 00:07:37.034000 waagent[1995]: 2025-10-13T00:07:37.033666Z INFO EnvHandler ExtHandler Routes:None Oct 13 00:07:37.037913 waagent[1995]: 2025-10-13T00:07:37.037827Z INFO ExtHandler ExtHandler Oct 13 00:07:37.038225 waagent[1995]: 2025-10-13T00:07:37.038177Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 0f18d55d-0d44-494d-8e7e-31d62df03711 correlation 5b600765-c92a-4be3-94d8-f713ec842748 created: 2025-10-13T00:05:55.091072Z] Oct 13 00:07:37.039287 waagent[1995]: 2025-10-13T00:07:37.039234Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Oct 13 00:07:37.041398 waagent[1995]: 2025-10-13T00:07:37.041229Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Oct 13 00:07:37.153114 waagent[1995]: 2025-10-13T00:07:37.153050Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.9.1.1 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 6D5A95A0-5EBA-4220-8DC1-108E9F9667F1;DroppedPackets: 0;UpdateGSErrors: 0;AutoUpdate: 0] Oct 13 00:07:37.236157 waagent[1995]: 2025-10-13T00:07:37.236076Z INFO MonitorHandler ExtHandler Network interfaces: Oct 13 00:07:37.236157 waagent[1995]: Executing ['ip', '-a', '-o', 'link']: Oct 13 00:07:37.236157 waagent[1995]: 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 Oct 13 00:07:37.236157 waagent[1995]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:ed:8d:44:f9:9b brd ff:ff:ff:ff:ff:ff Oct 13 00:07:37.236157 waagent[1995]: Executing ['ip', '-4', '-a', '-o', 'address']: Oct 13 00:07:37.236157 waagent[1995]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Oct 13 00:07:37.236157 waagent[1995]: 2: eth0 inet 10.200.8.11/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Oct 13 00:07:37.236157 waagent[1995]: Executing ['ip', '-6', '-a', '-o', 'address']: Oct 13 00:07:37.236157 waagent[1995]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Oct 13 00:07:37.236157 waagent[1995]: 2: eth0 inet6 fe80::7eed:8dff:fe44:f99b/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Oct 13 00:07:37.405394 waagent[1995]: 2025-10-13T00:07:37.405263Z INFO EnvHandler ExtHandler Successfully added Azure fabric firewall rules. Current Firewall rules: Oct 13 00:07:37.405394 waagent[1995]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Oct 13 00:07:37.405394 waagent[1995]: pkts bytes target prot opt in out source destination Oct 13 00:07:37.405394 waagent[1995]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Oct 13 00:07:37.405394 waagent[1995]: pkts bytes target prot opt in out source destination Oct 13 00:07:37.405394 waagent[1995]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Oct 13 00:07:37.405394 waagent[1995]: pkts bytes target prot opt in out source destination Oct 13 00:07:37.405394 waagent[1995]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Oct 13 00:07:37.405394 waagent[1995]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Oct 13 00:07:37.405394 waagent[1995]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Oct 13 00:07:37.408938 waagent[1995]: 2025-10-13T00:07:37.408882Z INFO EnvHandler ExtHandler Current Firewall rules: Oct 13 00:07:37.408938 waagent[1995]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Oct 13 00:07:37.408938 waagent[1995]: pkts bytes target prot opt in out source destination Oct 13 00:07:37.408938 waagent[1995]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Oct 13 00:07:37.408938 waagent[1995]: pkts bytes target prot opt in out source destination Oct 13 00:07:37.408938 waagent[1995]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Oct 13 00:07:37.408938 waagent[1995]: pkts bytes target prot opt in out source destination Oct 13 00:07:37.408938 waagent[1995]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Oct 13 00:07:37.408938 waagent[1995]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Oct 13 00:07:37.408938 waagent[1995]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Oct 13 00:07:37.409200 waagent[1995]: 2025-10-13T00:07:37.409160Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Oct 13 00:07:37.409361 waagent[1995]: 2025-10-13T00:07:37.409325Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Oct 13 00:07:41.528016 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Oct 13 00:07:41.541090 systemd[1]: Started sshd@0-10.200.8.11:22-10.200.16.10:37052.service - OpenSSH per-connection server daemon (10.200.16.10:37052). Oct 13 00:07:42.693429 sshd[2236]: Accepted publickey for core from 10.200.16.10 port 37052 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:07:42.694869 sshd-session[2236]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:07:42.699083 systemd-logind[1698]: New session 3 of user core. Oct 13 00:07:42.708993 systemd[1]: Started session-3.scope - Session 3 of User core. Oct 13 00:07:43.254306 systemd[1]: Started sshd@1-10.200.8.11:22-10.200.16.10:37054.service - OpenSSH per-connection server daemon (10.200.16.10:37054). Oct 13 00:07:43.907832 sshd[2241]: Accepted publickey for core from 10.200.16.10 port 37054 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:07:43.909179 sshd-session[2241]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:07:43.913530 systemd-logind[1698]: New session 4 of user core. Oct 13 00:07:43.924966 systemd[1]: Started session-4.scope - Session 4 of User core. Oct 13 00:07:44.371979 sshd[2243]: Connection closed by 10.200.16.10 port 37054 Oct 13 00:07:44.372393 sshd-session[2241]: pam_unix(sshd:session): session closed for user core Oct 13 00:07:44.375617 systemd[1]: sshd@1-10.200.8.11:22-10.200.16.10:37054.service: Deactivated successfully. Oct 13 00:07:44.377247 systemd[1]: session-4.scope: Deactivated successfully. Oct 13 00:07:44.377917 systemd-logind[1698]: Session 4 logged out. Waiting for processes to exit. Oct 13 00:07:44.378690 systemd-logind[1698]: Removed session 4. Oct 13 00:07:44.485414 systemd[1]: Started sshd@2-10.200.8.11:22-10.200.16.10:37068.service - OpenSSH per-connection server daemon (10.200.16.10:37068). Oct 13 00:07:45.147304 sshd[2249]: Accepted publickey for core from 10.200.16.10 port 37068 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:07:45.148669 sshd-session[2249]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:07:45.152934 systemd-logind[1698]: New session 5 of user core. Oct 13 00:07:45.161965 systemd[1]: Started session-5.scope - Session 5 of User core. Oct 13 00:07:45.362297 setup-nvidia[1853]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 480M 0 98304 0 0 110k 0 1:14:14 --:--:-- 1:14:14 110k\u000d 2 480M 2 10.3M 0 0 5734k 0 0:01:25 0:00:01 0:01:24 5732k\u000d 7 480M 7 34.1M 0 0 11.9M 0 0:00:40 0:00:02 0:00:38 11.9M\u000d 12 480M 12 58.2M 0 0 15.5M 0 0:00:30 0:00:03 0:00:27 15.5M\u000d 17 480M 17 82.0M 0 0 17.2M 0 0:00:27 0:00:04 0:00:23 17.2M\u000d 22 480M 22 106M 0 0 18.5M 0 0:00:25 0:00:05 0:00:20 21.8M\u000d 27 480M 27 131M 0 0 19.4M 0 0:00:24 0:00:06 0:00:18 24.7M\u000d 32 480M 32 157M 0 0 20.2M 0 0:00:23 0:00:07 0:00:16 25.0M\u000d 37 480M 37 178M 0 0 20.1M 0 0:00:23 0:00:08 0:00:15 23.5M\u000d 42 480M 42 204M 0 0 20.9M 0 0:00:22 0:00:09 0:00:13 24.4M\u000d 47 480M 47 226M 0 0 21.0M 0 0:00:22 0:00:10 0:00:12 23.9M\u000d 52 480M 52 251M 0 0 21.3M 0 0:00:22 0:00:11 0:00:11 23.9M\u000d 57 480M 57 276M 0 0 21.6M 0 0:00:22 0:00:12 0:00:10 23.9M\u000d 62 480M 62 301M 0 0 21.9M 0 0:00:21 0:00:13 0:00:08 25.0M\u000d 68 480M 68 327M 0 0 22.1M 0 0:00:21 0:00:14 0:00:07 24.3M\u000d 73 480M 73 352M 0 0 22.3M 0 0:00:21 0:00:15 0:00:06 24.9M\u000d 78 480M 78 378M 0 0 22.4M 0 0:00:21 0:00:16 0:00:05 25.0M\u000d 83 480M 83 403M 0 0 22.6M 0 0:00:21 0:00:17 0:00:04 24.9M\u000d 88 480M 88 427M 0 0 22.6M 0 0:00:21 0:00:18 0:00:03 24.6M\u000d 93 480M 93 451M 0 0 22.8M 0 0:00:21 0:00:19 0:00:02 25.1M\u000d 98 480M 98 474M 0 0 22.8M 0 0:00:21 0:00:20 0:00:01 24.6M\u000d100 480M 100 480M 0 0 22.9M 0 0:00:20 0:00:20 --:--:-- 25.1M Oct 13 00:07:45.607862 sshd[2251]: Connection closed by 10.200.16.10 port 37068 Oct 13 00:07:45.605307 sshd-session[2249]: pam_unix(sshd:session): session closed for user core Oct 13 00:07:45.613941 systemd[1]: sshd@2-10.200.8.11:22-10.200.16.10:37068.service: Deactivated successfully. Oct 13 00:07:45.616605 systemd[1]: session-5.scope: Deactivated successfully. Oct 13 00:07:45.619163 systemd-logind[1698]: Session 5 logged out. Waiting for processes to exit. Oct 13 00:07:45.620099 systemd-logind[1698]: Removed session 5. Oct 13 00:07:47.474892 chronyd[1705]: Selected source PHC0 Oct 13 00:07:55.725772 systemd[1]: Started sshd@3-10.200.8.11:22-10.200.16.10:57490.service - OpenSSH per-connection server daemon (10.200.16.10:57490). Oct 13 00:07:55.787567 setup-nvidia[1694]: Downloading NVIDIA 535.230.02 Driver Oct 13 00:07:55.792346 setup-nvidia[2270]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 13 00:07:55.792602 setup-nvidia[2270]: Dload Upload Total Spent Left Speed Oct 13 00:07:56.370276 sshd[2268]: Accepted publickey for core from 10.200.16.10 port 57490 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:07:56.371666 sshd-session[2268]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:07:56.375989 systemd-logind[1698]: New session 6 of user core. Oct 13 00:07:56.386967 systemd[1]: Started session-6.scope - Session 6 of User core. Oct 13 00:07:56.828492 sshd[2271]: Connection closed by 10.200.16.10 port 57490 Oct 13 00:07:56.828934 sshd-session[2268]: pam_unix(sshd:session): session closed for user core Oct 13 00:07:56.832546 systemd[1]: sshd@3-10.200.8.11:22-10.200.16.10:57490.service: Deactivated successfully. Oct 13 00:07:56.834146 systemd[1]: session-6.scope: Deactivated successfully. Oct 13 00:07:56.834764 systemd-logind[1698]: Session 6 logged out. Waiting for processes to exit. Oct 13 00:07:56.835537 systemd-logind[1698]: Removed session 6. Oct 13 00:07:56.946753 systemd[1]: Started sshd@4-10.200.8.11:22-10.200.16.10:57498.service - OpenSSH per-connection server daemon (10.200.16.10:57498). Oct 13 00:07:57.596385 sshd[2277]: Accepted publickey for core from 10.200.16.10 port 57498 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:07:57.597726 sshd-session[2277]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:07:57.601903 systemd-logind[1698]: New session 7 of user core. Oct 13 00:07:57.611993 systemd[1]: Started session-7.scope - Session 7 of User core. Oct 13 00:07:58.052870 sshd[2279]: Connection closed by 10.200.16.10 port 57498 Oct 13 00:07:58.053295 sshd-session[2277]: pam_unix(sshd:session): session closed for user core Oct 13 00:07:58.056944 systemd[1]: sshd@4-10.200.8.11:22-10.200.16.10:57498.service: Deactivated successfully. Oct 13 00:07:58.058530 systemd[1]: session-7.scope: Deactivated successfully. Oct 13 00:07:58.059194 systemd-logind[1698]: Session 7 logged out. Waiting for processes to exit. Oct 13 00:07:58.059976 systemd-logind[1698]: Removed session 7. Oct 13 00:08:02.032592 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Oct 13 00:08:04.108628 setup-nvidia[2270]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 326M 0 15657 0 0 24862 0 3:49:12 --:--:-- 3:49:12 24852\u000d 4 326M 4 14.5M 0 0 10.3M 0 0:00:31 0:00:01 0:00:30 10.3M\u000d 12 326M 12 42.0M 0 0 17.2M 0 0:00:18 0:00:02 0:00:16 17.2M\u000d 25 326M 25 84.0M 0 0 24.4M 0 0:00:13 0:00:03 0:00:10 24.4M\u000d 39 326M 39 130M 0 0 29.4M 0 0:00:11 0:00:04 0:00:07 29.4M\u000d 55 326M 55 180M 0 0 33.2M 0 0:00:09 0:00:05 0:00:04 37.6M\u000d 70 326M 70 230M 0 0 35.9M 0 0:00:09 0:00:06 0:00:03 43.1M\u000d 85 326M 85 278M 0 0 37.5M 0 0:00:08 0:00:07 0:00:01 47.5M\u000d100 326M 100 326M 0 0 39.2M 0 0:00:08 0:00:08 --:--:-- 49.5M Oct 13 00:08:04.149796 setup-nvidia[1694]: Extract the NVIDIA Driver Installer 535.230.02 Oct 13 00:08:04.149796 setup-nvidia[1694]: /opt/nvidia/workdir/nvidia-workdir / Oct 13 00:08:04.197451 setup-nvidia[2286]: Creating directory NVIDIA-Linux-x86_64-535.230.02 Oct 13 00:08:04.521426 setup-nvidia[2286]: Verifying archive integrity... OK Oct 13 00:08:05.299309 setup-nvidia[2286]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.230.02 Oct 13 00:08:07.121138 setup-nvidia[2312]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Oct 13 00:08:07.184865 setup-nvidia[1694]: / Oct 13 00:08:07.185155 setup-nvidia[1694]: Spawn system-nspawn container to install the NVIDIA drivers Oct 13 00:08:07.549062 sudo[2319]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4230.2.4.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.230.02 Oct 13 00:08:07.550838 sudo[2319]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 13 00:08:07.558478 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Oct 13 00:08:07.572067 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Oct 13 00:08:07.582131 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Oct 13 00:08:07.585896 systemd[1]: Starting user@0.service - User Manager for UID 0... Oct 13 00:08:07.591963 (systemd)[2322]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Oct 13 00:08:07.594103 systemd-logind[1698]: New session c3 of user root. Oct 13 00:08:07.772803 systemd[2322]: Queued start job for default target default.target. Oct 13 00:08:07.782023 systemd[2322]: Created slice app.slice - User Application Slice. Oct 13 00:08:07.782055 systemd[2322]: Reached target paths.target - Paths. Oct 13 00:08:07.782105 systemd[2322]: Reached target timers.target - Timers. Oct 13 00:08:07.783354 systemd[2322]: Starting dbus.socket - D-Bus User Message Bus Socket... Oct 13 00:08:07.794526 systemd[2322]: Listening on dbus.socket - D-Bus User Message Bus Socket. Oct 13 00:08:07.794654 systemd[2322]: Reached target sockets.target - Sockets. Oct 13 00:08:07.794712 systemd[2322]: Reached target basic.target - Basic System. Oct 13 00:08:07.794759 systemd[2322]: Reached target default.target - Main User Target. Oct 13 00:08:07.794796 systemd[2322]: Startup finished in 194ms. Oct 13 00:08:07.794876 systemd[1]: Started user@0.service - User Manager for UID 0. Oct 13 00:08:07.798793 systemd[1]: Started session-c2.scope - Session c2 of User root. Oct 13 00:08:07.884865 kernel: loop6: detected capacity change from 0 to 12644352 Oct 13 00:08:07.908868 kernel: loop6: p9 Oct 13 00:08:08.051988 setup-nvidia[2331]: ░ Spawning container flatcardevelopercontainer-4230.2.4.bin on /opt/nvidia/workdir/flatcar_developer_container-4230.2.4.bin. Oct 13 00:08:08.102876 kernel: EXT4-fs (loop6p9): mounted filesystem aea75de1-cc22-441d-8bc9-4b30365730be ro without journal. Quota mode: none. Oct 13 00:08:08.171327 systemd[1]: Started sshd@5-10.200.8.11:22-10.200.16.10:51752.service - OpenSSH per-connection server daemon (10.200.16.10:51752). Oct 13 00:08:08.814208 sshd[2336]: Accepted publickey for core from 10.200.16.10 port 51752 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:08:08.815550 sshd-session[2336]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:08:08.819799 systemd-logind[1698]: New session 8 of user core. Oct 13 00:08:08.825972 systemd[1]: Started session-8.scope - Session 8 of User core. Oct 13 00:08:09.034160 update_engine[1701]: I20251013 00:08:09.034080 1701 update_attempter.cc:509] Updating boot flags... Oct 13 00:08:09.139870 kernel: BTRFS warning: duplicate device /dev/sdb3 devid 1 generation 38 scanned by (udev-worker) (2355) Oct 13 00:08:09.242930 kernel: BTRFS warning: duplicate device /dev/sdb3 devid 1 generation 38 scanned by (udev-worker) (2356) Oct 13 00:08:09.281774 sshd[2340]: Connection closed by 10.200.16.10 port 51752 Oct 13 00:08:09.282160 sshd-session[2336]: pam_unix(sshd:session): session closed for user core Oct 13 00:08:09.287158 systemd[1]: sshd@5-10.200.8.11:22-10.200.16.10:51752.service: Deactivated successfully. Oct 13 00:08:09.288741 systemd[1]: session-8.scope: Deactivated successfully. Oct 13 00:08:09.289371 systemd-logind[1698]: Session 8 logged out. Waiting for processes to exit. Oct 13 00:08:09.290105 systemd-logind[1698]: Removed session 8. Oct 13 00:08:09.413207 systemd[1]: Started sshd@6-10.200.8.11:22-10.200.16.10:51766.service - OpenSSH per-connection server daemon (10.200.16.10:51766). Oct 13 00:08:10.052221 sshd[2459]: Accepted publickey for core from 10.200.16.10 port 51766 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:08:10.053813 sshd-session[2459]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:08:10.058035 systemd-logind[1698]: New session 9 of user core. Oct 13 00:08:10.065968 systemd[1]: Started session-9.scope - Session 9 of User core. Oct 13 00:08:10.520306 sshd[2462]: Connection closed by 10.200.16.10 port 51766 Oct 13 00:08:10.520722 sshd-session[2459]: pam_unix(sshd:session): session closed for user core Oct 13 00:08:10.524087 systemd[1]: sshd@6-10.200.8.11:22-10.200.16.10:51766.service: Deactivated successfully. Oct 13 00:08:10.525764 systemd[1]: session-9.scope: Deactivated successfully. Oct 13 00:08:10.526476 systemd-logind[1698]: Session 9 logged out. Waiting for processes to exit. Oct 13 00:08:10.527282 systemd-logind[1698]: Removed session 9. Oct 13 00:08:20.642378 systemd[1]: Started sshd@7-10.200.8.11:22-10.200.16.10:36716.service - OpenSSH per-connection server daemon (10.200.16.10:36716). Oct 13 00:08:21.297669 sshd[2613]: Accepted publickey for core from 10.200.16.10 port 36716 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:08:21.299312 sshd-session[2613]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:08:21.303606 systemd-logind[1698]: New session 10 of user core. Oct 13 00:08:21.310008 systemd[1]: Started session-10.scope - Session 10 of User core. Oct 13 00:08:21.766902 sshd[2648]: Connection closed by 10.200.16.10 port 36716 Oct 13 00:08:21.767250 sshd-session[2613]: pam_unix(sshd:session): session closed for user core Oct 13 00:08:21.770640 systemd[1]: sshd@7-10.200.8.11:22-10.200.16.10:36716.service: Deactivated successfully. Oct 13 00:08:21.772275 systemd[1]: session-10.scope: Deactivated successfully. Oct 13 00:08:21.772918 systemd-logind[1698]: Session 10 logged out. Waiting for processes to exit. Oct 13 00:08:21.773796 systemd-logind[1698]: Removed session 10. Oct 13 00:08:21.885981 systemd[1]: Started sshd@8-10.200.8.11:22-10.200.16.10:36726.service - OpenSSH per-connection server daemon (10.200.16.10:36726). Oct 13 00:08:22.533268 sshd[2706]: Accepted publickey for core from 10.200.16.10 port 36726 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:08:22.534656 sshd-session[2706]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:08:22.539158 systemd-logind[1698]: New session 11 of user core. Oct 13 00:08:22.554075 systemd[1]: Started session-11.scope - Session 11 of User core. Oct 13 00:08:23.656886 sshd[2905]: Connection closed by 10.200.16.10 port 36726 Oct 13 00:08:23.657004 sshd-session[2706]: pam_unix(sshd:session): session closed for user core Oct 13 00:08:23.660840 systemd[1]: sshd@8-10.200.8.11:22-10.200.16.10:36726.service: Deactivated successfully. Oct 13 00:08:23.662482 systemd[1]: session-11.scope: Deactivated successfully. Oct 13 00:08:23.663153 systemd-logind[1698]: Session 11 logged out. Waiting for processes to exit. Oct 13 00:08:23.664073 systemd-logind[1698]: Removed session 11. Oct 13 00:08:33.107406 systemd[1]: Started sshd@9-10.200.8.11:22-10.200.16.10:35158.service - OpenSSH per-connection server daemon (10.200.16.10:35158). Oct 13 00:08:33.750710 sshd[3500]: Accepted publickey for core from 10.200.16.10 port 35158 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:08:33.752064 sshd-session[3500]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:08:33.756203 systemd-logind[1698]: New session 12 of user core. Oct 13 00:08:33.768990 systemd[1]: Started session-12.scope - Session 12 of User core. Oct 13 00:08:34.223389 sshd[3502]: Connection closed by 10.200.16.10 port 35158 Oct 13 00:08:34.223742 sshd-session[3500]: pam_unix(sshd:session): session closed for user core Oct 13 00:08:34.226823 systemd[1]: sshd@9-10.200.8.11:22-10.200.16.10:35158.service: Deactivated successfully. Oct 13 00:08:34.228481 systemd[1]: session-12.scope: Deactivated successfully. Oct 13 00:08:34.229116 systemd-logind[1698]: Session 12 logged out. Waiting for processes to exit. Oct 13 00:08:34.229898 systemd-logind[1698]: Removed session 12. Oct 13 00:08:34.338462 systemd[1]: Started sshd@10-10.200.8.11:22-10.200.16.10:35172.service - OpenSSH per-connection server daemon (10.200.16.10:35172). Oct 13 00:08:34.981228 sshd[3508]: Accepted publickey for core from 10.200.16.10 port 35172 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:08:34.982556 sshd-session[3508]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:08:34.986576 systemd-logind[1698]: New session 13 of user core. Oct 13 00:08:34.996966 systemd[1]: Started session-13.scope - Session 13 of User core. Oct 13 00:08:35.436945 sshd[3510]: Connection closed by 10.200.16.10 port 35172 Oct 13 00:08:35.437321 sshd-session[3508]: pam_unix(sshd:session): session closed for user core Oct 13 00:08:35.440428 systemd[1]: sshd@10-10.200.8.11:22-10.200.16.10:35172.service: Deactivated successfully. Oct 13 00:08:35.442067 systemd[1]: session-13.scope: Deactivated successfully. Oct 13 00:08:35.442779 systemd-logind[1698]: Session 13 logged out. Waiting for processes to exit. Oct 13 00:08:35.443553 systemd-logind[1698]: Removed session 13. Oct 13 00:08:45.554721 systemd[1]: Started sshd@11-10.200.8.11:22-10.200.16.10:43812.service - OpenSSH per-connection server daemon (10.200.16.10:43812). Oct 13 00:08:46.203205 sshd[3560]: Accepted publickey for core from 10.200.16.10 port 43812 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:08:46.204630 sshd-session[3560]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:08:46.208835 systemd-logind[1698]: New session 14 of user core. Oct 13 00:08:46.221972 systemd[1]: Started session-14.scope - Session 14 of User core. Oct 13 00:08:46.680980 sshd[3562]: Connection closed by 10.200.16.10 port 43812 Oct 13 00:08:46.681357 sshd-session[3560]: pam_unix(sshd:session): session closed for user core Oct 13 00:08:46.684730 systemd[1]: sshd@11-10.200.8.11:22-10.200.16.10:43812.service: Deactivated successfully. Oct 13 00:08:46.686327 systemd[1]: session-14.scope: Deactivated successfully. Oct 13 00:08:46.686971 systemd-logind[1698]: Session 14 logged out. Waiting for processes to exit. Oct 13 00:08:46.687909 systemd-logind[1698]: Removed session 14. Oct 13 00:08:46.798186 systemd[1]: Started sshd@12-10.200.8.11:22-10.200.16.10:43814.service - OpenSSH per-connection server daemon (10.200.16.10:43814). Oct 13 00:08:47.439435 sshd[3615]: Accepted publickey for core from 10.200.16.10 port 43814 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:08:47.440745 sshd-session[3615]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:08:47.445352 systemd-logind[1698]: New session 15 of user core. Oct 13 00:08:47.452996 systemd[1]: Started session-15.scope - Session 15 of User core. Oct 13 00:08:47.891603 sshd[3667]: Connection closed by 10.200.16.10 port 43814 Oct 13 00:08:47.892036 sshd-session[3615]: pam_unix(sshd:session): session closed for user core Oct 13 00:08:47.895304 systemd[1]: sshd@12-10.200.8.11:22-10.200.16.10:43814.service: Deactivated successfully. Oct 13 00:08:47.896985 systemd[1]: session-15.scope: Deactivated successfully. Oct 13 00:08:47.897617 systemd-logind[1698]: Session 15 logged out. Waiting for processes to exit. Oct 13 00:08:47.898390 systemd-logind[1698]: Removed session 15. Oct 13 00:08:58.028147 systemd[1]: Started sshd@13-10.200.8.11:22-10.200.16.10:44808.service - OpenSSH per-connection server daemon (10.200.16.10:44808). Oct 13 00:08:58.671620 sshd[7477]: Accepted publickey for core from 10.200.16.10 port 44808 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:08:58.673520 sshd-session[7477]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:08:58.681323 systemd-logind[1698]: New session 16 of user core. Oct 13 00:08:58.686505 systemd[1]: Started session-16.scope - Session 16 of User core. Oct 13 00:08:59.138044 sshd[7746]: Connection closed by 10.200.16.10 port 44808 Oct 13 00:08:59.140064 sshd-session[7477]: pam_unix(sshd:session): session closed for user core Oct 13 00:08:59.145949 systemd[1]: sshd@13-10.200.8.11:22-10.200.16.10:44808.service: Deactivated successfully. Oct 13 00:08:59.147530 systemd-logind[1698]: Session 16 logged out. Waiting for processes to exit. Oct 13 00:08:59.151575 systemd[1]: session-16.scope: Deactivated successfully. Oct 13 00:08:59.153628 systemd-logind[1698]: Removed session 16. Oct 13 00:08:59.272160 systemd[1]: Started sshd@14-10.200.8.11:22-10.200.16.10:44816.service - OpenSSH per-connection server daemon (10.200.16.10:44816). Oct 13 00:08:59.916866 sshd[7951]: Accepted publickey for core from 10.200.16.10 port 44816 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:08:59.919348 sshd-session[7951]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:08:59.928794 systemd-logind[1698]: New session 17 of user core. Oct 13 00:08:59.938576 systemd[1]: Started session-17.scope - Session 17 of User core. Oct 13 00:09:00.373134 sshd[8058]: Connection closed by 10.200.16.10 port 44816 Oct 13 00:09:00.374692 sshd-session[7951]: pam_unix(sshd:session): session closed for user core Oct 13 00:09:00.377577 systemd-logind[1698]: Session 17 logged out. Waiting for processes to exit. Oct 13 00:09:00.379242 systemd[1]: sshd@14-10.200.8.11:22-10.200.16.10:44816.service: Deactivated successfully. Oct 13 00:09:00.381817 systemd[1]: session-17.scope: Deactivated successfully. Oct 13 00:09:00.383159 systemd-logind[1698]: Removed session 17. Oct 13 00:09:10.497957 systemd[1]: Started sshd@15-10.200.8.11:22-10.200.16.10:56628.service - OpenSSH per-connection server daemon (10.200.16.10:56628). Oct 13 00:09:11.142342 sshd[11588]: Accepted publickey for core from 10.200.16.10 port 56628 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:09:11.144224 sshd-session[11588]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:09:11.152809 systemd-logind[1698]: New session 18 of user core. Oct 13 00:09:11.164366 systemd[1]: Started session-18.scope - Session 18 of User core. Oct 13 00:09:11.603303 sshd[11616]: Connection closed by 10.200.16.10 port 56628 Oct 13 00:09:11.604867 sshd-session[11588]: pam_unix(sshd:session): session closed for user core Oct 13 00:09:11.609055 systemd[1]: sshd@15-10.200.8.11:22-10.200.16.10:56628.service: Deactivated successfully. Oct 13 00:09:11.610775 systemd[1]: session-18.scope: Deactivated successfully. Oct 13 00:09:11.612629 systemd-logind[1698]: Session 18 logged out. Waiting for processes to exit. Oct 13 00:09:11.613964 systemd-logind[1698]: Removed session 18. Oct 13 00:09:11.734107 systemd[1]: Started sshd@16-10.200.8.11:22-10.200.16.10:56636.service - OpenSSH per-connection server daemon (10.200.16.10:56636). Oct 13 00:09:12.384815 sshd[11628]: Accepted publickey for core from 10.200.16.10 port 56636 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:09:12.386628 sshd-session[11628]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:09:12.392787 systemd-logind[1698]: New session 19 of user core. Oct 13 00:09:12.402027 systemd[1]: Started session-19.scope - Session 19 of User core. Oct 13 00:09:12.873325 sshd[11664]: Connection closed by 10.200.16.10 port 56636 Oct 13 00:09:12.873934 sshd-session[11628]: pam_unix(sshd:session): session closed for user core Oct 13 00:09:12.877928 systemd[1]: sshd@16-10.200.8.11:22-10.200.16.10:56636.service: Deactivated successfully. Oct 13 00:09:12.879654 systemd[1]: session-19.scope: Deactivated successfully. Oct 13 00:09:12.880010 systemd-logind[1698]: Session 19 logged out. Waiting for processes to exit. Oct 13 00:09:12.883545 systemd-logind[1698]: Removed session 19. Oct 13 00:09:23.003140 systemd[1]: Started sshd@17-10.200.8.11:22-10.200.16.10:37870.service - OpenSSH per-connection server daemon (10.200.16.10:37870). Oct 13 00:09:23.650185 sshd[12084]: Accepted publickey for core from 10.200.16.10 port 37870 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:09:23.652145 sshd-session[12084]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:09:23.660200 systemd-logind[1698]: New session 20 of user core. Oct 13 00:09:23.668524 systemd[1]: Started session-20.scope - Session 20 of User core. Oct 13 00:09:24.124432 sshd[12104]: Connection closed by 10.200.16.10 port 37870 Oct 13 00:09:24.129037 sshd-session[12084]: pam_unix(sshd:session): session closed for user core Oct 13 00:09:24.132567 systemd[1]: sshd@17-10.200.8.11:22-10.200.16.10:37870.service: Deactivated successfully. Oct 13 00:09:24.134615 systemd[1]: session-20.scope: Deactivated successfully. Oct 13 00:09:24.135578 systemd-logind[1698]: Session 20 logged out. Waiting for processes to exit. Oct 13 00:09:24.136735 systemd-logind[1698]: Removed session 20. Oct 13 00:09:24.240082 systemd[1]: Started sshd@18-10.200.8.11:22-10.200.16.10:37874.service - OpenSSH per-connection server daemon (10.200.16.10:37874). Oct 13 00:09:24.887422 sshd[12118]: Accepted publickey for core from 10.200.16.10 port 37874 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:09:24.890496 sshd-session[12118]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:09:24.898424 systemd-logind[1698]: New session 21 of user core. Oct 13 00:09:24.910889 systemd[1]: Started session-21.scope - Session 21 of User core. Oct 13 00:09:25.342272 sshd[12142]: Connection closed by 10.200.16.10 port 37874 Oct 13 00:09:25.342667 sshd-session[12118]: pam_unix(sshd:session): session closed for user core Oct 13 00:09:25.345546 systemd-logind[1698]: Session 21 logged out. Waiting for processes to exit. Oct 13 00:09:25.347713 systemd[1]: sshd@18-10.200.8.11:22-10.200.16.10:37874.service: Deactivated successfully. Oct 13 00:09:25.349383 systemd[1]: session-21.scope: Deactivated successfully. Oct 13 00:09:25.352080 systemd-logind[1698]: Removed session 21. Oct 13 00:09:35.460144 systemd[1]: Started sshd@19-10.200.8.11:22-10.200.16.10:34300.service - OpenSSH per-connection server daemon (10.200.16.10:34300). Oct 13 00:09:36.116976 sshd[12490]: Accepted publickey for core from 10.200.16.10 port 34300 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:09:36.121632 sshd-session[12490]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:09:36.128559 systemd-logind[1698]: New session 22 of user core. Oct 13 00:09:36.141880 systemd[1]: Started session-22.scope - Session 22 of User core. Oct 13 00:09:36.581870 sshd[12510]: Connection closed by 10.200.16.10 port 34300 Oct 13 00:09:36.586037 sshd-session[12490]: pam_unix(sshd:session): session closed for user core Oct 13 00:09:36.589601 systemd[1]: sshd@19-10.200.8.11:22-10.200.16.10:34300.service: Deactivated successfully. Oct 13 00:09:36.591493 systemd[1]: session-22.scope: Deactivated successfully. Oct 13 00:09:36.592465 systemd-logind[1698]: Session 22 logged out. Waiting for processes to exit. Oct 13 00:09:36.593599 systemd-logind[1698]: Removed session 22. Oct 13 00:09:36.704163 systemd[1]: Started sshd@20-10.200.8.11:22-10.200.16.10:34314.service - OpenSSH per-connection server daemon (10.200.16.10:34314). Oct 13 00:09:37.357648 sshd[12522]: Accepted publickey for core from 10.200.16.10 port 34314 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:09:37.359446 sshd-session[12522]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:09:37.367398 systemd-logind[1698]: New session 23 of user core. Oct 13 00:09:37.375517 systemd[1]: Started session-23.scope - Session 23 of User core. Oct 13 00:09:37.819074 sshd[12538]: Connection closed by 10.200.16.10 port 34314 Oct 13 00:09:37.819472 sshd-session[12522]: pam_unix(sshd:session): session closed for user core Oct 13 00:09:37.822679 systemd[1]: sshd@20-10.200.8.11:22-10.200.16.10:34314.service: Deactivated successfully. Oct 13 00:09:37.823110 systemd-logind[1698]: Session 23 logged out. Waiting for processes to exit. Oct 13 00:09:37.825562 systemd[1]: session-23.scope: Deactivated successfully. Oct 13 00:09:37.827733 systemd-logind[1698]: Removed session 23. Oct 13 00:09:47.940456 systemd[1]: Started sshd@21-10.200.8.11:22-10.200.16.10:51902.service - OpenSSH per-connection server daemon (10.200.16.10:51902). Oct 13 00:09:48.589559 sshd[12761]: Accepted publickey for core from 10.200.16.10 port 51902 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:09:48.590991 sshd-session[12761]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:09:48.595397 systemd-logind[1698]: New session 24 of user core. Oct 13 00:09:48.603989 systemd[1]: Started session-24.scope - Session 24 of User core. Oct 13 00:09:49.041945 sshd[12809]: Connection closed by 10.200.16.10 port 51902 Oct 13 00:09:49.042359 sshd-session[12761]: pam_unix(sshd:session): session closed for user core Oct 13 00:09:49.045864 systemd[1]: sshd@21-10.200.8.11:22-10.200.16.10:51902.service: Deactivated successfully. Oct 13 00:09:49.047589 systemd[1]: session-24.scope: Deactivated successfully. Oct 13 00:09:49.048260 systemd-logind[1698]: Session 24 logged out. Waiting for processes to exit. Oct 13 00:09:49.049067 systemd-logind[1698]: Removed session 24. Oct 13 00:09:49.160148 systemd[1]: Started sshd@22-10.200.8.11:22-10.200.16.10:51918.service - OpenSSH per-connection server daemon (10.200.16.10:51918). Oct 13 00:09:49.809771 sshd[12815]: Accepted publickey for core from 10.200.16.10 port 51918 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:09:49.811200 sshd-session[12815]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:09:49.817461 systemd-logind[1698]: New session 25 of user core. Oct 13 00:09:49.828301 systemd[1]: Started session-25.scope - Session 25 of User core. Oct 13 00:09:50.285159 sshd[12849]: Connection closed by 10.200.16.10 port 51918 Oct 13 00:09:50.285634 sshd-session[12815]: pam_unix(sshd:session): session closed for user core Oct 13 00:09:50.288908 systemd[1]: sshd@22-10.200.8.11:22-10.200.16.10:51918.service: Deactivated successfully. Oct 13 00:09:50.290606 systemd[1]: session-25.scope: Deactivated successfully. Oct 13 00:09:50.292226 systemd-logind[1698]: Session 25 logged out. Waiting for processes to exit. Oct 13 00:09:50.294038 systemd-logind[1698]: Removed session 25. Oct 13 00:09:50.559611 setup-nvidia[2331]: \u000d Oct 13 00:09:50.559611 setup-nvidia[2331]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Oct 13 00:09:50.559611 setup-nvidia[2331]: frequently when this kernel module was built against the wrong or Oct 13 00:09:50.559611 setup-nvidia[2331]: improperly configured kernel sources, with a version of gcc that differs Oct 13 00:09:50.559611 setup-nvidia[2331]: from the one used to build the target kernel, or if another driver, such Oct 13 00:09:50.559611 setup-nvidia[2331]: as nouveau, is present and prevents the NVIDIA kernel module from Oct 13 00:09:50.559611 setup-nvidia[2331]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Oct 13 00:09:50.559611 setup-nvidia[2331]: installed in this system is supported by this NVIDIA Linux graphics Oct 13 00:09:50.559611 setup-nvidia[2331]: driver release. Oct 13 00:09:50.559611 setup-nvidia[2331]: \u000d Oct 13 00:09:50.559611 setup-nvidia[2331]: Please see the log entries 'Kernel module load error' and 'Kernel Oct 13 00:09:50.559611 setup-nvidia[2331]: messages' at the end of the file Oct 13 00:09:50.559611 setup-nvidia[2331]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more Oct 13 00:09:50.559611 setup-nvidia[2331]: information. Oct 13 00:09:50.559611 setup-nvidia[2331]: \u000d Oct 13 00:09:50.574878 setup-nvidia[2331]: dmesg: read kernel buffer failed: Operation not permitted Oct 13 00:09:50.596815 setup-nvidia[2331]: \u000d Oct 13 00:09:50.596815 setup-nvidia[2331]: ERROR: Installation has failed. Please see the file Oct 13 00:09:50.596815 setup-nvidia[2331]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for Oct 13 00:09:50.596815 setup-nvidia[2331]: details. You may find suggestions on fixing installation problems in Oct 13 00:09:50.596815 setup-nvidia[2331]: the README available on the Linux driver download page at Oct 13 00:09:50.596815 setup-nvidia[2331]: www.nvidia.com. Oct 13 00:09:50.596815 setup-nvidia[2331]: \u000d Oct 13 00:09:50.597145 setup-nvidia[2331]: Last 50 lines of nvidia-installer.log: Oct 13 00:09:50.598035 setup-nvidia[2331]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038989rm+0x32: 'naked' return found in RETHUNK build Oct 13 00:09:50.598035 setup-nvidia[2331]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039000rm+0x53: 'naked' return found in RETHUNK build Oct 13 00:09:50.598035 setup-nvidia[2331]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038997rm+0x90: 'naked' return found in RETHUNK build Oct 13 00:09:50.598035 setup-nvidia[2331]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038990rm+0x2b: 'naked' return found in RETHUNK build Oct 13 00:09:50.598035 setup-nvidia[2331]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039046rm+0x93: 'naked' return found in RETHUNK build Oct 13 00:09:50.598035 setup-nvidia[2331]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039042rm+0x36: 'naked' return found in RETHUNK build Oct 13 00:09:50.598035 setup-nvidia[2331]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039041rm+0x9d: 'naked' return found in RETHUNK build Oct 13 00:09:50.598035 setup-nvidia[2331]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039043rm+0x77: 'naked' return found in RETHUNK build Oct 13 00:09:50.598035 setup-nvidia[2331]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038987rm+0x7f: 'naked' return found in RETHUNK build Oct 13 00:09:50.598035 setup-nvidia[2331]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030558rm+0x3e: 'naked' return found in RETHUNK build Oct 13 00:09:50.598035 setup-nvidia[2331]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030557rm+0x27: 'naked' return found in RETHUNK build Oct 13 00:09:50.598035 setup-nvidia[2331]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030561rm+0x37: 'naked' return found in RETHUNK build Oct 13 00:09:50.598035 setup-nvidia[2331]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030560rm+0x24: 'naked' return found in RETHUNK build Oct 13 00:09:50.598035 setup-nvidia[2331]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030559rm+0xca: 'naked' return found in RETHUNK build Oct 13 00:09:50.598035 setup-nvidia[2331]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030562rm+0x193: 'naked' return found in RETHUNK build Oct 13 00:09:50.598035 setup-nvidia[2331]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030564rm+0x17f: 'naked' return found in RETHUNK build Oct 13 00:09:50.598035 setup-nvidia[2331]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv011860rm+0x5d: 'naked' return found in RETHUNK build Oct 13 00:09:50.598035 setup-nvidia[2331]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039354rm+0x12f: 'naked' return found in RETHUNK build Oct 13 00:09:50.598035 setup-nvidia[2331]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/Module.symvers Oct 13 00:09:50.598035 setup-nvidia[2331]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.mod.o Oct 13 00:09:50.598035 setup-nvidia[2331]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.mod.o Oct 13 00:09:50.598035 setup-nvidia[2331]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.mod.o Oct 13 00:09:50.598035 setup-nvidia[2331]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.mod.o Oct 13 00:09:50.598035 setup-nvidia[2331]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.mod.o Oct 13 00:09:50.598035 setup-nvidia[2331]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Oct 13 00:09:50.598035 setup-nvidia[2331]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Oct 13 00:09:50.598035 setup-nvidia[2331]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Oct 13 00:09:50.598035 setup-nvidia[2331]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Oct 13 00:09:50.598035 setup-nvidia[2331]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Oct 13 00:09:50.598035 setup-nvidia[2331]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Oct 13 00:09:50.598035 setup-nvidia[2331]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko due to unavailability of vmlinux Oct 13 00:09:50.598035 setup-nvidia[2331]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Oct 13 00:09:50.598035 setup-nvidia[2331]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko due to unavailability of vmlinux Oct 13 00:09:50.598035 setup-nvidia[2331]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Oct 13 00:09:50.598035 setup-nvidia[2331]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko due to unavailability of vmlinux Oct 13 00:09:50.598035 setup-nvidia[2331]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Oct 13 00:09:50.598035 setup-nvidia[2331]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko due to unavailability of vmlinux Oct 13 00:09:50.598035 setup-nvidia[2331]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Oct 13 00:09:50.598035 setup-nvidia[2331]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko due to unavailability of vmlinux Oct 13 00:09:50.598035 setup-nvidia[2331]: make[2]: Leaving directory '/usr/lib/modules/6.6.110-flatcar/build' Oct 13 00:09:50.598035 setup-nvidia[2331]: make[1]: Leaving directory '/usr/lib/modules/6.6.110-flatcar/source' Oct 13 00:09:50.598035 setup-nvidia[2331]: -> done. Oct 13 00:09:50.598035 setup-nvidia[2331]: -> Kernel module compilation complete. Oct 13 00:09:50.598035 setup-nvidia[2331]: -> Unable to determine if Secure Boot is enabled: No such file or directory Oct 13 00:09:50.598035 setup-nvidia[2331]: 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. Oct 13 00:09:50.598035 setup-nvidia[2331]: \u000d Oct 13 00:09:50.599220 setup-nvidia[2331]: Please see the log entries 'Kernel module load error' and 'Kernel messages' at the end of the file '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more information. Oct 13 00:09:50.599220 setup-nvidia[2331]: -> Kernel module load error: Operation not permitted Oct 13 00:09:50.599220 setup-nvidia[2331]: -> Kernel messages: Oct 13 00:09:50.599220 setup-nvidia[2331]: ERROR: Installation has failed. Please see the file '/nvidia/NVIDIA-Linux-x86_64-535.230.02/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. Oct 13 00:10:00.403387 systemd[1]: Started sshd@23-10.200.8.11:22-10.200.16.10:44588.service - OpenSSH per-connection server daemon (10.200.16.10:44588). Oct 13 00:10:01.046356 sshd[12986]: Accepted publickey for core from 10.200.16.10 port 44588 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:10:01.047762 sshd-session[12986]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:10:01.052550 systemd-logind[1698]: New session 26 of user core. Oct 13 00:10:01.068976 systemd[1]: Started session-26.scope - Session 26 of User core. Oct 13 00:10:01.537388 sshd[12988]: Connection closed by 10.200.16.10 port 44588 Oct 13 00:10:01.537804 sshd-session[12986]: pam_unix(sshd:session): session closed for user core Oct 13 00:10:01.541013 systemd[1]: sshd@23-10.200.8.11:22-10.200.16.10:44588.service: Deactivated successfully. Oct 13 00:10:01.542591 systemd[1]: session-26.scope: Deactivated successfully. Oct 13 00:10:01.543286 systemd-logind[1698]: Session 26 logged out. Waiting for processes to exit. Oct 13 00:10:01.544169 systemd-logind[1698]: Removed session 26. Oct 13 00:10:01.651507 systemd[1]: Started sshd@24-10.200.8.11:22-10.200.16.10:44594.service - OpenSSH per-connection server daemon (10.200.16.10:44594). Oct 13 00:10:02.294900 sshd[12994]: Accepted publickey for core from 10.200.16.10 port 44594 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:10:02.296304 sshd-session[12994]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:10:02.302382 systemd-logind[1698]: New session 27 of user core. Oct 13 00:10:02.310149 systemd[1]: Started session-27.scope - Session 27 of User core. Oct 13 00:10:02.748219 sshd[12996]: Connection closed by 10.200.16.10 port 44594 Oct 13 00:10:02.748557 sshd-session[12994]: pam_unix(sshd:session): session closed for user core Oct 13 00:10:02.751821 systemd[1]: sshd@24-10.200.8.11:22-10.200.16.10:44594.service: Deactivated successfully. Oct 13 00:10:02.753488 systemd[1]: session-27.scope: Deactivated successfully. Oct 13 00:10:02.754141 systemd-logind[1698]: Session 27 logged out. Waiting for processes to exit. Oct 13 00:10:02.754916 systemd-logind[1698]: Removed session 27. Oct 13 00:10:12.868092 systemd[1]: Started sshd@25-10.200.8.11:22-10.200.16.10:39152.service - OpenSSH per-connection server daemon (10.200.16.10:39152). Oct 13 00:10:13.514408 sshd[13003]: Accepted publickey for core from 10.200.16.10 port 39152 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:10:13.515818 sshd-session[13003]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:10:13.520317 systemd-logind[1698]: New session 28 of user core. Oct 13 00:10:13.534983 systemd[1]: Started session-28.scope - Session 28 of User core. Oct 13 00:10:13.969481 sshd[13005]: Connection closed by 10.200.16.10 port 39152 Oct 13 00:10:13.969901 sshd-session[13003]: pam_unix(sshd:session): session closed for user core Oct 13 00:10:13.973142 systemd[1]: sshd@25-10.200.8.11:22-10.200.16.10:39152.service: Deactivated successfully. Oct 13 00:10:13.974981 systemd[1]: session-28.scope: Deactivated successfully. Oct 13 00:10:13.975697 systemd-logind[1698]: Session 28 logged out. Waiting for processes to exit. Oct 13 00:10:13.976488 systemd-logind[1698]: Removed session 28. Oct 13 00:10:14.083904 systemd[1]: Started sshd@26-10.200.8.11:22-10.200.16.10:39154.service - OpenSSH per-connection server daemon (10.200.16.10:39154). Oct 13 00:10:14.467867 kernel: EXT4-fs (loop6p9): unmounting filesystem aea75de1-cc22-441d-8bc9-4b30365730be. Oct 13 00:10:14.468076 setup-nvidia[2331]: Container flatcardevelopercontainer-4230.2.4.bin exited successfully. Oct 13 00:10:14.470408 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4230.2.4.bin.mount: Deactivated successfully. Oct 13 00:10:14.542627 sudo[2319]: pam_unix(sudo:session): session closed for user root Oct 13 00:10:14.544360 systemd[1]: session-c2.scope: Deactivated successfully. Oct 13 00:10:14.544620 systemd[1]: session-c2.scope: Consumed 5min 7.313s CPU time, 883.5M memory peak. Oct 13 00:10:14.728030 sshd[13011]: Accepted publickey for core from 10.200.16.10 port 39154 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:10:14.729323 sshd-session[13011]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:10:14.733708 systemd-logind[1698]: New session 29 of user core. Oct 13 00:10:14.748039 systemd[1]: Started session-29.scope - Session 29 of User core. Oct 13 00:10:14.964888 setup-nvidia[1694]: /opt/nvidia / Oct 13 00:10:14.966024 setup-nvidia[1694]: / Oct 13 00:10:14.982197 setup-nvidia[13032]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 13 00:10:15.004887 kernel: IPMI message handler: version 39.2 Oct 13 00:10:15.007868 kernel: ipmi device interface Oct 13 00:10:15.009320 setup-nvidia[1694]: /opt/nvidia/current/usr/lib/modules/6.6.110-flatcar/video / Oct 13 00:10:15.063985 kernel: nvidia: loading out-of-tree module taints kernel. Oct 13 00:10:15.064273 kernel: nvidia: module license 'NVIDIA' taints kernel. Oct 13 00:10:15.064353 kernel: Disabling lock debugging due to kernel taint Oct 13 00:10:15.071362 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Oct 13 00:10:15.071407 kernel: nvidia: module license taints kernel. Oct 13 00:10:15.183313 sshd[13018]: Connection closed by 10.200.16.10 port 39154 Oct 13 00:10:15.183742 sshd-session[13011]: pam_unix(sshd:session): session closed for user core Oct 13 00:10:15.186983 systemd[1]: sshd@26-10.200.8.11:22-10.200.16.10:39154.service: Deactivated successfully. Oct 13 00:10:15.188591 systemd[1]: session-29.scope: Deactivated successfully. Oct 13 00:10:15.189315 systemd-logind[1698]: Session 29 logged out. Waiting for processes to exit. Oct 13 00:10:15.190126 systemd-logind[1698]: Removed session 29. Oct 13 00:10:15.476867 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 245 Oct 13 00:10:15.880419 kernel: Oct 13 00:10:15.880551 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Oct 13 00:10:16.086873 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.230.02 Fri Dec 20 21:42:05 UTC 2024 Oct 13 00:10:16.108869 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.230.02 Fri Dec 20 21:52:34 UTC 2024 Oct 13 00:10:16.128882 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Oct 13 00:10:16.179872 kernel: nvidia-uvm: Loaded the UVM driver, major device number 243. Oct 13 00:10:16.180042 setup-nvidia[1694]: / Oct 13 00:10:17.997351 setup-nvidia[13066]: Mon Oct 13 00:10:17 2025 Oct 13 00:10:17.997351 setup-nvidia[13066]: +---------------------------------------------------------------------------------------+ Oct 13 00:10:17.997351 setup-nvidia[13066]: | NVIDIA-SMI 535.230.02 Driver Version: 535.230.02 CUDA Version: 12.2 | Oct 13 00:10:17.997351 setup-nvidia[13066]: |-----------------------------------------+----------------------+----------------------+ Oct 13 00:10:17.997351 setup-nvidia[13066]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Oct 13 00:10:17.997351 setup-nvidia[13066]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Oct 13 00:10:17.997351 setup-nvidia[13066]: | | | MIG M. | Oct 13 00:10:17.997351 setup-nvidia[13066]: |=========================================+======================+======================| Oct 13 00:10:18.002900 setup-nvidia[13066]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Oct 13 00:10:18.002900 setup-nvidia[13066]: | N/A 25C P0 36W / 250W | 0MiB / 16384MiB | 0% Default | Oct 13 00:10:18.002900 setup-nvidia[13066]: | | | N/A | Oct 13 00:10:18.002900 setup-nvidia[13066]: +-----------------------------------------+----------------------+----------------------+ Oct 13 00:10:18.003018 setup-nvidia[13066]: Oct 13 00:10:18.003018 setup-nvidia[13066]: +---------------------------------------------------------------------------------------+ Oct 13 00:10:18.003018 setup-nvidia[13066]: | Processes: | Oct 13 00:10:18.003018 setup-nvidia[13066]: | GPU GI CI PID Type Process name GPU Memory | Oct 13 00:10:18.003018 setup-nvidia[13066]: | ID ID Usage | Oct 13 00:10:18.003018 setup-nvidia[13066]: |=======================================================================================| Oct 13 00:10:18.003153 setup-nvidia[13066]: | No running processes found | Oct 13 00:10:18.003153 setup-nvidia[13066]: +---------------------------------------------------------------------------------------+ Oct 13 00:10:18.372716 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Oct 13 00:10:18.375480 systemd[1]: Reached target multi-user.target - Multi-User System. Oct 13 00:10:18.375920 systemd[1]: Startup finished in 1.370s (firmware) + 31.649s (loader) + 1.881s (kernel) + 13.316s (initrd) + 3min 14.145s (userspace) = 4min 2.363s. Oct 13 00:10:24.761017 systemd[1]: Stopping user@0.service - User Manager for UID 0... Oct 13 00:10:24.761803 systemd[2322]: Activating special unit exit.target... Oct 13 00:10:24.761908 systemd[2322]: Stopped target default.target - Main User Target. Oct 13 00:10:24.761951 systemd[2322]: Stopped target basic.target - Basic System. Oct 13 00:10:24.761983 systemd[2322]: Stopped target paths.target - Paths. Oct 13 00:10:24.762011 systemd[2322]: Stopped target sockets.target - Sockets. Oct 13 00:10:24.762044 systemd[2322]: Stopped target timers.target - Timers. Oct 13 00:10:24.762401 systemd[2322]: Closed dbus.socket - D-Bus User Message Bus Socket. Oct 13 00:10:24.762627 systemd[2322]: Removed slice app.slice - User Application Slice. Oct 13 00:10:24.762660 systemd[2322]: Reached target shutdown.target - Shutdown. Oct 13 00:10:24.762816 systemd[2322]: Finished systemd-exit.service - Exit the Session. Oct 13 00:10:24.762870 systemd[2322]: Reached target exit.target - Exit the Session. Oct 13 00:10:24.778181 (sd-pam)[2323]: pam_unix(systemd-user:session): session closed for user root Oct 13 00:10:24.779684 systemd[1]: user@0.service: Deactivated successfully. Oct 13 00:10:24.780056 systemd[1]: Stopped user@0.service - User Manager for UID 0. Oct 13 00:10:24.792188 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Oct 13 00:10:24.801322 systemd[1]: run-user-0.mount: Deactivated successfully. Oct 13 00:10:24.802218 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Oct 13 00:10:24.802416 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Oct 13 00:10:24.804205 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Oct 13 00:10:24.804296 systemd[1]: user-0.slice: Consumed 5min 7.589s CPU time, 893M memory peak. Oct 13 00:10:24.804674 systemd-logind[1698]: Removed session c3. Oct 13 00:10:25.300030 systemd[1]: Started sshd@27-10.200.8.11:22-10.200.16.10:60302.service - OpenSSH per-connection server daemon (10.200.16.10:60302). Oct 13 00:10:25.944713 sshd[13077]: Accepted publickey for core from 10.200.16.10 port 60302 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:10:25.946246 sshd-session[13077]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:10:25.951262 systemd-logind[1698]: New session 30 of user core. Oct 13 00:10:25.968977 systemd[1]: Started session-30.scope - Session 30 of User core. Oct 13 00:10:27.301695 sshd[13081]: Connection closed by 10.200.16.10 port 60302 Oct 13 00:10:26.515394 systemd[1]: Started sshd@28-10.200.8.11:22-10.200.16.10:60314.service - OpenSSH per-connection server daemon (10.200.16.10:60314). Oct 13 00:10:27.301802 sshd-session[13077]: pam_unix(sshd:session): session closed for user core Oct 13 00:10:27.302506 sshd[13084]: Accepted publickey for core from 10.200.16.10 port 60314 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:10:27.302053 sshd-session[13084]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:10:27.305419 systemd[1]: sshd@27-10.200.8.11:22-10.200.16.10:60302.service: Deactivated successfully. Oct 13 00:10:27.307091 systemd[1]: session-30.scope: Deactivated successfully. Oct 13 00:10:27.309287 systemd-logind[1698]: New session 31 of user core. Oct 13 00:10:27.326037 systemd[1]: Started session-31.scope - Session 31 of User core. Oct 13 00:10:27.326676 systemd-logind[1698]: Session 30 logged out. Waiting for processes to exit. Oct 13 00:10:27.327510 systemd-logind[1698]: Removed session 30. Oct 13 00:10:27.680276 sshd[13089]: Connection closed by 10.200.16.10 port 60314 Oct 13 00:10:27.680614 sshd-session[13084]: pam_unix(sshd:session): session closed for user core Oct 13 00:10:27.683922 systemd[1]: sshd@28-10.200.8.11:22-10.200.16.10:60314.service: Deactivated successfully. Oct 13 00:10:27.685606 systemd[1]: session-31.scope: Deactivated successfully. Oct 13 00:10:27.686257 systemd-logind[1698]: Session 31 logged out. Waiting for processes to exit. Oct 13 00:10:27.687022 systemd-logind[1698]: Removed session 31. Oct 13 00:10:27.793420 systemd[1]: Started sshd@29-10.200.8.11:22-10.200.16.10:60330.service - OpenSSH per-connection server daemon (10.200.16.10:60330). Oct 13 00:10:28.440211 sshd[13095]: Accepted publickey for core from 10.200.16.10 port 60330 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:10:28.441613 sshd-session[13095]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:10:28.445862 systemd-logind[1698]: New session 32 of user core. Oct 13 00:10:28.468022 systemd[1]: Started session-32.scope - Session 32 of User core. Oct 13 00:10:28.899839 sshd[13097]: Connection closed by 10.200.16.10 port 60330 Oct 13 00:10:28.900267 sshd-session[13095]: pam_unix(sshd:session): session closed for user core Oct 13 00:10:28.903534 systemd[1]: sshd@29-10.200.8.11:22-10.200.16.10:60330.service: Deactivated successfully. Oct 13 00:10:28.905169 systemd[1]: session-32.scope: Deactivated successfully. Oct 13 00:10:28.905800 systemd-logind[1698]: Session 32 logged out. Waiting for processes to exit. Oct 13 00:10:28.906618 systemd-logind[1698]: Removed session 32. Oct 13 00:10:29.018525 systemd[1]: Started sshd@30-10.200.8.11:22-10.200.16.10:60338.service - OpenSSH per-connection server daemon (10.200.16.10:60338). Oct 13 00:10:29.662490 sshd[13103]: Accepted publickey for core from 10.200.16.10 port 60338 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:10:29.663932 sshd-session[13103]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:10:29.668079 systemd-logind[1698]: New session 33 of user core. Oct 13 00:10:29.684978 systemd[1]: Started session-33.scope - Session 33 of User core. Oct 13 00:10:30.030109 sudo[13106]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Oct 13 00:10:30.030428 sudo[13106]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 13 00:10:30.073167 sudo[13106]: pam_unix(sudo:session): session closed for user root Oct 13 00:10:30.196782 sshd[13105]: Connection closed by 10.200.16.10 port 60338 Oct 13 00:10:30.197267 sshd-session[13103]: pam_unix(sshd:session): session closed for user core Oct 13 00:10:30.200834 systemd[1]: sshd@30-10.200.8.11:22-10.200.16.10:60338.service: Deactivated successfully. Oct 13 00:10:30.202474 systemd[1]: session-33.scope: Deactivated successfully. Oct 13 00:10:30.203145 systemd-logind[1698]: Session 33 logged out. Waiting for processes to exit. Oct 13 00:10:30.203977 systemd-logind[1698]: Removed session 33. Oct 13 00:10:30.310344 systemd[1]: Started sshd@31-10.200.8.11:22-10.200.16.10:59968.service - OpenSSH per-connection server daemon (10.200.16.10:59968). Oct 13 00:10:30.953810 sshd[13112]: Accepted publickey for core from 10.200.16.10 port 59968 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:10:30.955193 sshd-session[13112]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:10:30.959242 systemd-logind[1698]: New session 34 of user core. Oct 13 00:10:30.970969 systemd[1]: Started session-34.scope - Session 34 of User core. Oct 13 00:10:31.307446 sudo[13116]: 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 Oct 13 00:10:31.307757 sudo[13116]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 13 00:10:31.311042 sudo[13116]: pam_unix(sudo:session): session closed for user root Oct 13 00:10:31.316768 sudo[13115]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Oct 13 00:10:31.317085 sudo[13115]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 13 00:10:31.338193 systemd[1]: Starting audit-rules.service - Load Audit Rules... Oct 13 00:10:31.363681 augenrules[13138]: No rules Oct 13 00:10:31.365098 systemd[1]: audit-rules.service: Deactivated successfully. Oct 13 00:10:31.365356 systemd[1]: Finished audit-rules.service - Load Audit Rules. Oct 13 00:10:31.366215 sudo[13115]: pam_unix(sudo:session): session closed for user root Oct 13 00:10:31.470940 sshd[13114]: Connection closed by 10.200.16.10 port 59968 Oct 13 00:10:31.471326 sshd-session[13112]: pam_unix(sshd:session): session closed for user core Oct 13 00:10:31.474755 systemd[1]: sshd@31-10.200.8.11:22-10.200.16.10:59968.service: Deactivated successfully. Oct 13 00:10:31.476414 systemd[1]: session-34.scope: Deactivated successfully. Oct 13 00:10:31.477059 systemd-logind[1698]: Session 34 logged out. Waiting for processes to exit. Oct 13 00:10:31.477811 systemd-logind[1698]: Removed session 34. Oct 13 00:10:31.586027 systemd[1]: Started sshd@32-10.200.8.11:22-10.200.16.10:59976.service - OpenSSH per-connection server daemon (10.200.16.10:59976). Oct 13 00:10:32.229873 sshd[13147]: Accepted publickey for core from 10.200.16.10 port 59976 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:10:32.231271 sshd-session[13147]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:10:32.235354 systemd-logind[1698]: New session 35 of user core. Oct 13 00:10:32.251974 systemd[1]: Started session-35.scope - Session 35 of User core. Oct 13 00:10:32.583043 sudo[13150]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/mkdir -p . Oct 13 00:10:32.583362 sudo[13150]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 13 00:10:32.586574 sudo[13150]: pam_unix(sudo:session): session closed for user root Oct 13 00:10:32.689198 sshd[13149]: Connection closed by 10.200.16.10 port 59976 Oct 13 00:10:32.689583 sshd-session[13147]: pam_unix(sshd:session): session closed for user core Oct 13 00:10:32.692894 systemd[1]: sshd@32-10.200.8.11:22-10.200.16.10:59976.service: Deactivated successfully. Oct 13 00:10:32.694551 systemd[1]: session-35.scope: Deactivated successfully. Oct 13 00:10:32.695615 systemd-logind[1698]: Session 35 logged out. Waiting for processes to exit. Oct 13 00:10:32.696392 systemd-logind[1698]: Removed session 35. Oct 13 00:10:32.802516 systemd[1]: Started sshd@33-10.200.8.11:22-10.200.16.10:59980.service - OpenSSH per-connection server daemon (10.200.16.10:59980). Oct 13 00:10:33.445743 sshd[13156]: Accepted publickey for core from 10.200.16.10 port 59980 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:10:33.447121 sshd-session[13156]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:10:33.451332 systemd-logind[1698]: New session 36 of user core. Oct 13 00:10:33.462977 systemd[1]: Started session-36.scope - Session 36 of User core. Oct 13 00:10:33.797278 sudo[13159]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/install -m 0755 /dev/stdin kolet Oct 13 00:10:33.797582 sudo[13159]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 13 00:10:37.363890 sudo[13159]: pam_unix(sudo:session): session closed for user root Oct 13 00:10:37.472038 sshd[13158]: Connection closed by 10.200.16.10 port 59980 Oct 13 00:10:37.472369 sshd-session[13156]: pam_unix(sshd:session): session closed for user core Oct 13 00:10:37.475767 systemd[1]: sshd@33-10.200.8.11:22-10.200.16.10:59980.service: Deactivated successfully. Oct 13 00:10:37.477529 systemd[1]: session-36.scope: Deactivated successfully. Oct 13 00:10:37.478153 systemd-logind[1698]: Session 36 logged out. Waiting for processes to exit. Oct 13 00:10:37.478908 systemd-logind[1698]: Removed session 36. Oct 13 00:10:37.589380 systemd[1]: Started sshd@34-10.200.8.11:22-10.200.16.10:59994.service - OpenSSH per-connection server daemon (10.200.16.10:59994). Oct 13 00:10:38.232821 sshd[13165]: Accepted publickey for core from 10.200.16.10 port 59994 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:10:38.234261 sshd-session[13165]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:10:38.238366 systemd-logind[1698]: New session 37 of user core. Oct 13 00:10:38.254977 systemd[1]: Started session-37.scope - Session 37 of User core. Oct 13 00:10:38.790735 sshd[13167]: Connection closed by 10.200.16.10 port 59994 Oct 13 00:10:38.791168 sshd-session[13165]: pam_unix(sshd:session): session closed for user core Oct 13 00:10:38.794437 systemd[1]: sshd@34-10.200.8.11:22-10.200.16.10:59994.service: Deactivated successfully. Oct 13 00:10:38.796055 systemd[1]: session-37.scope: Deactivated successfully. Oct 13 00:10:38.796676 systemd-logind[1698]: Session 37 logged out. Waiting for processes to exit. Oct 13 00:10:38.797431 systemd-logind[1698]: Removed session 37. Oct 13 00:10:38.905370 systemd[1]: Started sshd@35-10.200.8.11:22-10.200.16.10:59998.service - OpenSSH per-connection server daemon (10.200.16.10:59998). Oct 13 00:10:39.549253 sshd[13182]: Accepted publickey for core from 10.200.16.10 port 59998 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:10:39.550645 sshd-session[13182]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:10:39.554772 systemd-logind[1698]: New session 38 of user core. Oct 13 00:10:39.566967 systemd[1]: Started session-38.scope - Session 38 of User core. Oct 13 00:10:40.494064 systemd[1]: Starting docker.service - Docker Application Container Engine... Oct 13 00:10:40.494244 (dockerd)[13203]: 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 Oct 13 00:10:44.315270 dockerd[13203]: time="2025-10-13T00:10:44.315187566Z" level=info msg="Starting up" Oct 13 00:10:45.189695 dockerd[13203]: time="2025-10-13T00:10:45.189643029Z" level=info msg="Loading containers: start." Oct 13 00:10:45.428866 kernel: Initializing XFRM netlink socket Oct 13 00:10:45.543862 systemd-networkd[1400]: docker0: Link UP Oct 13 00:10:45.588084 dockerd[13203]: time="2025-10-13T00:10:45.588050420Z" level=info msg="Loading containers: done." Oct 13 00:10:45.601709 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck255354301-merged.mount: Deactivated successfully. Oct 13 00:10:45.608952 dockerd[13203]: time="2025-10-13T00:10:45.608860684Z" 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 Oct 13 00:10:45.609024 dockerd[13203]: time="2025-10-13T00:10:45.608999378Z" level=info msg="Docker daemon" commit=41ca978a0a5400cc24b274137efa9f25517fcc0b containerd-snapshotter=false storage-driver=overlay2 version=27.3.1 Oct 13 00:10:45.609164 dockerd[13203]: time="2025-10-13T00:10:45.609141873Z" level=info msg="Daemon has completed initialization" Oct 13 00:10:45.666992 dockerd[13203]: time="2025-10-13T00:10:45.666946550Z" level=info msg="API listen on /run/docker.sock" Oct 13 00:10:45.667124 systemd[1]: Started docker.service - Docker Application Container Engine. Oct 13 00:10:46.801050 systemd[1]: var-lib-docker-overlay2-060ac9563ccaec2bfedab91c9e3fd49274fc12c1b49814a89292048baa2d3734\x2dinit-merged.mount: Deactivated successfully. Oct 13 00:10:46.873366 kernel: docker0: port 1(veth8dcc06b) entered blocking state Oct 13 00:10:46.873608 kernel: docker0: port 1(veth8dcc06b) entered disabled state Oct 13 00:10:46.873693 kernel: veth8dcc06b: entered allmulticast mode Oct 13 00:10:46.878622 kernel: veth8dcc06b: entered promiscuous mode Oct 13 00:10:46.879306 systemd-networkd[1400]: veth8dcc06b: Link UP Oct 13 00:10:47.812614 containerd[1726]: time="2025-10-13T00:10:47.812513637Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 13 00:10:47.812614 containerd[1726]: time="2025-10-13T00:10:47.812578835Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 13 00:10:47.812614 containerd[1726]: time="2025-10-13T00:10:47.812593334Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 13 00:10:47.813094 containerd[1726]: time="2025-10-13T00:10:47.812674731Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 13 00:10:47.848980 systemd[1]: Started docker-4a9d4b9012212267ac6563efa6ef6119e1e2ad1661abad0e1ad5499570c072a4.scope - libcontainer container 4a9d4b9012212267ac6563efa6ef6119e1e2ad1661abad0e1ad5499570c072a4. Oct 13 00:10:47.904868 kernel: eth0: renamed from vethb26bc00 Oct 13 00:10:47.918441 kernel: docker0: port 1(veth8dcc06b) entered blocking state Oct 13 00:10:47.918504 kernel: docker0: port 1(veth8dcc06b) entered forwarding state Oct 13 00:10:47.918537 systemd-networkd[1400]: veth8dcc06b: Gained carrier Oct 13 00:10:47.918811 systemd-networkd[1400]: docker0: Gained carrier Oct 13 00:10:49.208030 systemd-networkd[1400]: docker0: Gained IPv6LL Oct 13 00:10:49.911991 systemd-networkd[1400]: veth8dcc06b: Gained IPv6LL Oct 13 00:10:50.982609 systemd[1]: docker-4a9d4b9012212267ac6563efa6ef6119e1e2ad1661abad0e1ad5499570c072a4.scope: Deactivated successfully. Oct 13 00:10:50.995706 dockerd[13203]: time="2025-10-13T00:10:50.995650033Z" level=info msg="ignoring event" container=4a9d4b9012212267ac6563efa6ef6119e1e2ad1661abad0e1ad5499570c072a4 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Oct 13 00:10:50.996069 containerd[1726]: time="2025-10-13T00:10:50.995655833Z" level=info msg="shim disconnected" id=4a9d4b9012212267ac6563efa6ef6119e1e2ad1661abad0e1ad5499570c072a4 namespace=moby Oct 13 00:10:50.996069 containerd[1726]: time="2025-10-13T00:10:50.995708831Z" level=warning msg="cleaning up after shim disconnected" id=4a9d4b9012212267ac6563efa6ef6119e1e2ad1661abad0e1ad5499570c072a4 namespace=moby Oct 13 00:10:50.996069 containerd[1726]: time="2025-10-13T00:10:50.995718331Z" level=info msg="cleaning up dead shim" namespace=moby Oct 13 00:10:51.025431 systemd-networkd[1400]: veth8dcc06b: Lost carrier Oct 13 00:10:51.025865 kernel: docker0: port 1(veth8dcc06b) entered disabled state Oct 13 00:10:51.025906 kernel: vethb26bc00: renamed from eth0 Oct 13 00:10:51.062016 systemd-networkd[1400]: veth8dcc06b: Link DOWN Oct 13 00:10:51.065871 kernel: docker0: port 1(veth8dcc06b) entered disabled state Oct 13 00:10:51.073278 kernel: veth8dcc06b (unregistering): left allmulticast mode Oct 13 00:10:51.073320 kernel: veth8dcc06b (unregistering): left promiscuous mode Oct 13 00:10:51.077194 kernel: docker0: port 1(veth8dcc06b) entered disabled state Oct 13 00:10:51.101290 systemd[1]: run-docker-netns-faf90078267c.mount: Deactivated successfully. Oct 13 00:10:51.104337 systemd[1]: var-lib-docker-overlay2-060ac9563ccaec2bfedab91c9e3fd49274fc12c1b49814a89292048baa2d3734-merged.mount: Deactivated successfully. Oct 13 00:10:51.235911 sshd[13184]: Connection closed by 10.200.16.10 port 59998 Oct 13 00:10:51.236246 sshd-session[13182]: pam_unix(sshd:session): session closed for user core Oct 13 00:10:51.239621 systemd[1]: sshd@35-10.200.8.11:22-10.200.16.10:59998.service: Deactivated successfully. Oct 13 00:10:51.241288 systemd[1]: session-38.scope: Deactivated successfully. Oct 13 00:10:51.241919 systemd-logind[1698]: Session 38 logged out. Waiting for processes to exit. Oct 13 00:10:51.242678 systemd-logind[1698]: Removed session 38. Oct 13 00:10:51.349541 systemd[1]: Started sshd@36-10.200.8.11:22-10.200.16.10:52512.service - OpenSSH per-connection server daemon (10.200.16.10:52512). Oct 13 00:10:51.996574 sshd[13479]: Accepted publickey for core from 10.200.16.10 port 52512 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:10:51.997968 sshd-session[13479]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:10:52.002270 systemd-logind[1698]: New session 39 of user core. Oct 13 00:10:52.014969 systemd[1]: Started session-39.scope - Session 39 of User core. Oct 13 00:10:52.024047 systemd-networkd[1400]: docker0: Lost carrier Oct 13 00:10:52.403083 systemd[1]: var-lib-docker-overlay2-426a006a05260ff476c3392bcc72bcf33c34a74c6b1a5f3121269f799e0f04df\x2dinit-merged.mount: Deactivated successfully. Oct 13 00:10:52.468692 kernel: docker0: port 1(veth2cdb012) entered blocking state Oct 13 00:10:52.468810 kernel: docker0: port 1(veth2cdb012) entered disabled state Oct 13 00:10:52.472067 kernel: veth2cdb012: entered allmulticast mode Oct 13 00:10:52.475165 kernel: veth2cdb012: entered promiscuous mode Oct 13 00:10:52.476007 kernel: docker0: port 1(veth2cdb012) entered blocking state Oct 13 00:10:52.477370 systemd-networkd[1400]: veth2cdb012: Link UP Oct 13 00:10:52.482000 kernel: docker0: port 1(veth2cdb012) entered forwarding state Oct 13 00:10:52.482977 kernel: docker0: port 1(veth2cdb012) entered disabled state Oct 13 00:10:52.554627 containerd[1726]: time="2025-10-13T00:10:52.554519381Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 13 00:10:52.554627 containerd[1726]: time="2025-10-13T00:10:52.554587478Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 13 00:10:52.554627 containerd[1726]: time="2025-10-13T00:10:52.554601678Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 13 00:10:52.555153 containerd[1726]: time="2025-10-13T00:10:52.554686674Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 13 00:10:52.579979 systemd[1]: Started docker-ea8e675b862affe48e75f4291f027272ef64119645c2ec6852a48ece49680ec4.scope - libcontainer container ea8e675b862affe48e75f4291f027272ef64119645c2ec6852a48ece49680ec4. Oct 13 00:10:52.632873 kernel: eth0: renamed from veth62c1516 Oct 13 00:10:52.647737 kernel: docker0: port 1(veth2cdb012) entered blocking state Oct 13 00:10:52.647789 kernel: docker0: port 1(veth2cdb012) entered forwarding state Oct 13 00:10:52.648137 systemd-networkd[1400]: veth2cdb012: Gained carrier Oct 13 00:10:52.648395 systemd-networkd[1400]: docker0: Gained carrier Oct 13 00:10:52.685576 systemd[1]: docker-ea8e675b862affe48e75f4291f027272ef64119645c2ec6852a48ece49680ec4.scope: Deactivated successfully. Oct 13 00:10:52.705298 containerd[1726]: time="2025-10-13T00:10:52.705103700Z" level=info msg="shim disconnected" id=ea8e675b862affe48e75f4291f027272ef64119645c2ec6852a48ece49680ec4 namespace=moby Oct 13 00:10:52.705298 containerd[1726]: time="2025-10-13T00:10:52.705157097Z" level=warning msg="cleaning up after shim disconnected" id=ea8e675b862affe48e75f4291f027272ef64119645c2ec6852a48ece49680ec4 namespace=moby Oct 13 00:10:52.705298 containerd[1726]: time="2025-10-13T00:10:52.705166897Z" level=info msg="cleaning up dead shim" namespace=moby Oct 13 00:10:52.705446 dockerd[13203]: time="2025-10-13T00:10:52.705120599Z" level=info msg="ignoring event" container=ea8e675b862affe48e75f4291f027272ef64119645c2ec6852a48ece49680ec4 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Oct 13 00:10:52.734209 systemd-networkd[1400]: veth2cdb012: Lost carrier Oct 13 00:10:52.734861 kernel: docker0: port 1(veth2cdb012) entered disabled state Oct 13 00:10:52.734901 kernel: veth62c1516: renamed from eth0 Oct 13 00:10:52.767592 kernel: docker0: port 1(veth2cdb012) entered disabled state Oct 13 00:10:52.767651 kernel: veth2cdb012 (unregistering): left allmulticast mode Oct 13 00:10:52.770818 kernel: veth2cdb012 (unregistering): left promiscuous mode Oct 13 00:10:52.774064 kernel: docker0: port 1(veth2cdb012) entered disabled state Oct 13 00:10:52.780075 systemd-networkd[1400]: veth2cdb012: Link DOWN Oct 13 00:10:52.926066 sshd[13481]: Connection closed by 10.200.16.10 port 52512 Oct 13 00:10:52.926507 sshd-session[13479]: pam_unix(sshd:session): session closed for user core Oct 13 00:10:52.929923 systemd[1]: sshd@36-10.200.8.11:22-10.200.16.10:52512.service: Deactivated successfully. Oct 13 00:10:52.931556 systemd[1]: session-39.scope: Deactivated successfully. Oct 13 00:10:52.932216 systemd-logind[1698]: Session 39 logged out. Waiting for processes to exit. Oct 13 00:10:52.933041 systemd-logind[1698]: Removed session 39. Oct 13 00:10:53.032060 systemd-networkd[1400]: docker0: Lost carrier Oct 13 00:10:53.039286 systemd[1]: Started sshd@37-10.200.8.11:22-10.200.16.10:52526.service - OpenSSH per-connection server daemon (10.200.16.10:52526). Oct 13 00:10:53.400180 systemd[1]: var-lib-docker-overlay2-426a006a05260ff476c3392bcc72bcf33c34a74c6b1a5f3121269f799e0f04df-merged.mount: Deactivated successfully. Oct 13 00:10:53.683451 sshd[13588]: Accepted publickey for core from 10.200.16.10 port 52526 ssh2: RSA SHA256:yx6Nx/Foqo4FtZuPxo0GwByGVgmGy7zeOmRgH8g9FZQ Oct 13 00:10:53.684760 sshd-session[13588]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 13 00:10:53.689018 systemd-logind[1698]: New session 40 of user core. Oct 13 00:10:53.706045 systemd[1]: Started session-40.scope - Session 40 of User core. Oct 13 00:10:54.096765 ntpdate[13600]: ntpdate 4.2.8p17@1.4004-o Sun Oct 12 22:04:53 UTC 2025 (1) Oct 13 00:11:00.840013 ntpdate[13600]: adjust time server 85.91.1.180 offset -0.003026 sec Oct 13 00:11:00.945525 sshd[13590]: Connection closed by 10.200.16.10 port 52526 Oct 13 00:11:00.945980 sshd-session[13588]: pam_unix(sshd:session): session closed for user core Oct 13 00:11:00.949235 systemd[1]: sshd@37-10.200.8.11:22-10.200.16.10:52526.service: Deactivated successfully. Oct 13 00:11:00.951132 systemd[1]: session-40.scope: Deactivated successfully. Oct 13 00:11:00.951907 systemd-logind[1698]: Session 40 logged out. Waiting for processes to exit. Oct 13 00:11:00.952738 systemd-logind[1698]: Removed session 40.