Sep 4 23:46:36.218169 kernel: Linux version 6.6.103-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 Thu Sep 4 22:03:18 -00 2025 Sep 4 23:46:36.218198 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=564344e0ae537bb1f195be96fecdd60e9e7ec1fe4e3ba9f8a7a8da5d9135455e Sep 4 23:46:36.218219 kernel: BIOS-provided physical RAM map: Sep 4 23:46:36.218227 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Sep 4 23:46:36.218233 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Sep 4 23:46:36.218240 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Sep 4 23:46:36.218248 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Sep 4 23:46:36.218259 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Sep 4 23:46:36.218266 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Sep 4 23:46:36.218273 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Sep 4 23:46:36.218280 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Sep 4 23:46:36.218287 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Sep 4 23:46:36.218293 kernel: printk: bootconsole [earlyser0] enabled Sep 4 23:46:36.218301 kernel: NX (Execute Disable) protection: active Sep 4 23:46:36.218312 kernel: APIC: Static calls initialized Sep 4 23:46:36.218320 kernel: efi: EFI v2.7 by Microsoft Sep 4 23:46:36.218328 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Sep 4 23:46:36.218335 kernel: random: crng init done Sep 4 23:46:36.218343 kernel: secureboot: Secure boot disabled Sep 4 23:46:36.218391 kernel: SMBIOS 3.1.0 present. Sep 4 23:46:36.218405 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Sep 4 23:46:36.218413 kernel: Hypervisor detected: Microsoft Hyper-V Sep 4 23:46:36.218420 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Sep 4 23:46:36.218428 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Sep 4 23:46:36.218440 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Sep 4 23:46:36.218448 kernel: Hyper-V: Using hypercall for remote TLB flush Sep 4 23:46:36.218456 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Sep 4 23:46:36.218464 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Sep 4 23:46:36.218472 kernel: tsc: Marking TSC unstable due to running on Hyper-V Sep 4 23:46:36.218480 kernel: tsc: Detected 2593.991 MHz processor Sep 4 23:46:36.218488 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Sep 4 23:46:36.218496 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Sep 4 23:46:36.218504 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Sep 4 23:46:36.218514 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Sep 4 23:46:36.218522 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Sep 4 23:46:36.218530 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Sep 4 23:46:36.218537 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Sep 4 23:46:36.218545 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Sep 4 23:46:36.218553 kernel: Using GB pages for direct mapping Sep 4 23:46:36.218561 kernel: ACPI: Early table checksum verification disabled Sep 4 23:46:36.218573 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Sep 4 23:46:36.218584 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 23:46:36.218592 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 23:46:36.218600 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Sep 4 23:46:36.218608 kernel: ACPI: FACS 0x000000003FFFE000 000040 Sep 4 23:46:36.218616 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 23:46:36.218624 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 23:46:36.218635 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 23:46:36.218643 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 23:46:36.218652 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 23:46:36.218660 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 23:46:36.218668 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 23:46:36.218676 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Sep 4 23:46:36.218684 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Sep 4 23:46:36.218693 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Sep 4 23:46:36.218703 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Sep 4 23:46:36.218712 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Sep 4 23:46:36.218720 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Sep 4 23:46:36.218728 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Sep 4 23:46:36.218736 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Sep 4 23:46:36.218744 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Sep 4 23:46:36.218752 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Sep 4 23:46:36.218760 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0 Sep 4 23:46:36.218768 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0 Sep 4 23:46:36.218779 kernel: SRAT: PXM 0 -> APIC 0x02 -> Node 0 Sep 4 23:46:36.218787 kernel: SRAT: PXM 0 -> APIC 0x03 -> Node 0 Sep 4 23:46:36.218795 kernel: SRAT: PXM 0 -> APIC 0x04 -> Node 0 Sep 4 23:46:36.218803 kernel: SRAT: PXM 0 -> APIC 0x05 -> Node 0 Sep 4 23:46:36.218811 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Sep 4 23:46:36.218819 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Sep 4 23:46:36.218827 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Sep 4 23:46:36.218836 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Sep 4 23:46:36.218844 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Sep 4 23:46:36.218855 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Sep 4 23:46:36.218863 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Sep 4 23:46:36.218871 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Sep 4 23:46:36.218879 kernel: NUMA: Node 0 [mem 0x00000000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00000000-0xfdfffffff] Sep 4 23:46:36.218888 kernel: NUMA: Node 0 [mem 0x00000000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00000000-0x5cbfffffff] Sep 4 23:46:36.218896 kernel: NODE_DATA(0) allocated [mem 0x5cbfff9000-0x5cbfffefff] Sep 4 23:46:36.218907 kernel: Zone ranges: Sep 4 23:46:36.218918 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Sep 4 23:46:36.218927 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Sep 4 23:46:36.218938 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Sep 4 23:46:36.218946 kernel: Movable zone start for each node Sep 4 23:46:36.218954 kernel: Early memory node ranges Sep 4 23:46:36.218963 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Sep 4 23:46:36.218971 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Sep 4 23:46:36.218979 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Sep 4 23:46:36.218987 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Sep 4 23:46:36.218995 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Sep 4 23:46:36.219003 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Sep 4 23:46:36.219014 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Sep 4 23:46:36.219023 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Sep 4 23:46:36.219031 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Sep 4 23:46:36.219039 kernel: ACPI: PM-Timer IO Port: 0x408 Sep 4 23:46:36.219047 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Sep 4 23:46:36.219055 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Sep 4 23:46:36.219063 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Sep 4 23:46:36.219071 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Sep 4 23:46:36.219080 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Sep 4 23:46:36.219090 kernel: smpboot: Allowing 6 CPUs, 0 hotplug CPUs Sep 4 23:46:36.219099 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Sep 4 23:46:36.219107 kernel: Booting paravirtualized kernel on Hyper-V Sep 4 23:46:36.219115 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Sep 4 23:46:36.219123 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Sep 4 23:46:36.219132 kernel: percpu: Embedded 58 pages/cpu s197160 r8192 d32216 u262144 Sep 4 23:46:36.219140 kernel: pcpu-alloc: s197160 r8192 d32216 u262144 alloc=1*2097152 Sep 4 23:46:36.219148 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Sep 4 23:46:36.219156 kernel: Hyper-V: PV spinlocks enabled Sep 4 23:46:36.219166 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Sep 4 23:46:36.219176 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=564344e0ae537bb1f195be96fecdd60e9e7ec1fe4e3ba9f8a7a8da5d9135455e Sep 4 23:46:36.219185 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Sep 4 23:46:36.219193 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Sep 4 23:46:36.219201 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Sep 4 23:46:36.219221 kernel: Fallback order for Node 0: 0 Sep 4 23:46:36.219234 kernel: Built 1 zonelists, mobility grouping on. Total pages: 28899610 Sep 4 23:46:36.219247 kernel: Policy zone: Normal Sep 4 23:46:36.219263 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 4 23:46:36.219286 kernel: software IO TLB: area num 8. Sep 4 23:46:36.219295 kernel: Memory: 115328448K/117439364K available (14336K kernel code, 2293K rwdata, 22868K rodata, 43508K init, 1568K bss, 2110660K reserved, 0K cma-reserved) Sep 4 23:46:36.219306 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Sep 4 23:46:36.219315 kernel: Kernel/User page tables isolation: enabled Sep 4 23:46:36.219326 kernel: ftrace: allocating 37943 entries in 149 pages Sep 4 23:46:36.219334 kernel: ftrace: allocated 149 pages with 4 groups Sep 4 23:46:36.219343 kernel: Dynamic Preempt: voluntary Sep 4 23:46:36.219351 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 4 23:46:36.219361 kernel: rcu: RCU event tracing is enabled. Sep 4 23:46:36.219369 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Sep 4 23:46:36.219378 kernel: Trampoline variant of Tasks RCU enabled. Sep 4 23:46:36.219390 kernel: Rude variant of Tasks RCU enabled. Sep 4 23:46:36.219398 kernel: Tracing variant of Tasks RCU enabled. Sep 4 23:46:36.219407 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 4 23:46:36.219416 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Sep 4 23:46:36.219427 kernel: Using NULL legacy PIC Sep 4 23:46:36.219435 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Sep 4 23:46:36.219444 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 4 23:46:36.219453 kernel: Console: colour dummy device 80x25 Sep 4 23:46:36.219461 kernel: printk: console [tty1] enabled Sep 4 23:46:36.219470 kernel: printk: console [ttyS0] enabled Sep 4 23:46:36.219478 kernel: printk: bootconsole [earlyser0] disabled Sep 4 23:46:36.219487 kernel: ACPI: Core revision 20230628 Sep 4 23:46:36.219495 kernel: Failed to register legacy timer interrupt Sep 4 23:46:36.219504 kernel: APIC: Switch to symmetric I/O mode setup Sep 4 23:46:36.219515 kernel: Hyper-V: enabling crash_kexec_post_notifiers Sep 4 23:46:36.219524 kernel: Hyper-V: Using IPI hypercalls Sep 4 23:46:36.219532 kernel: APIC: send_IPI() replaced with hv_send_ipi() Sep 4 23:46:36.219541 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Sep 4 23:46:36.219549 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Sep 4 23:46:36.219558 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Sep 4 23:46:36.219566 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Sep 4 23:46:36.219575 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Sep 4 23:46:36.219583 kernel: Hyper-V: Using enlightened APIC (xapic mode) Sep 4 23:46:36.219595 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Sep 4 23:46:36.219603 kernel: APIC: read() replaced with hv_apic_read() Sep 4 23:46:36.219612 kernel: APIC: write() replaced with hv_apic_write() Sep 4 23:46:36.219620 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Sep 4 23:46:36.219629 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Sep 4 23:46:36.219638 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593991) Sep 4 23:46:36.219646 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Sep 4 23:46:36.219655 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Sep 4 23:46:36.219663 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Sep 4 23:46:36.219674 kernel: Spectre V2 : Mitigation: Retpolines Sep 4 23:46:36.219683 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Sep 4 23:46:36.219691 kernel: Speculative Store Bypass: Vulnerable Sep 4 23:46:36.219699 kernel: MDS: Mitigation: Clear CPU buffers Sep 4 23:46:36.219708 kernel: TAA: Mitigation: Clear CPU buffers Sep 4 23:46:36.219716 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Sep 4 23:46:36.219725 kernel: active return thunk: its_return_thunk Sep 4 23:46:36.219733 kernel: ITS: Mitigation: Aligned branch/return thunks Sep 4 23:46:36.219741 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Sep 4 23:46:36.219749 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Sep 4 23:46:36.219758 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Sep 4 23:46:36.219769 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Sep 4 23:46:36.219777 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Sep 4 23:46:36.219786 kernel: Freeing SMP alternatives memory: 32K Sep 4 23:46:36.219794 kernel: pid_max: default: 32768 minimum: 301 Sep 4 23:46:36.219803 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Sep 4 23:46:36.219811 kernel: landlock: Up and running. Sep 4 23:46:36.219820 kernel: SELinux: Initializing. Sep 4 23:46:36.219828 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Sep 4 23:46:36.219837 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Sep 4 23:46:36.219845 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Sep 4 23:46:36.219854 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Sep 4 23:46:36.219865 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Sep 4 23:46:36.219874 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Sep 4 23:46:36.219883 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Sep 4 23:46:36.219892 kernel: signal: max sigframe size: 1776 Sep 4 23:46:36.219900 kernel: rcu: Hierarchical SRCU implementation. Sep 4 23:46:36.219909 kernel: rcu: Max phase no-delay instances is 400. Sep 4 23:46:36.219918 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Sep 4 23:46:36.219926 kernel: smp: Bringing up secondary CPUs ... Sep 4 23:46:36.219935 kernel: smpboot: x86: Booting SMP configuration: Sep 4 23:46:36.219946 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Sep 4 23:46:36.219954 kernel: smp: Brought up 1 node, 6 CPUs Sep 4 23:46:36.219963 kernel: smpboot: Max logical packages: 1 Sep 4 23:46:36.219971 kernel: smpboot: Total of 6 processors activated (31127.89 BogoMIPS) Sep 4 23:46:36.219980 kernel: devtmpfs: initialized Sep 4 23:46:36.219989 kernel: x86/mm: Memory block size: 1024MB Sep 4 23:46:36.219997 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Sep 4 23:46:36.220006 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 4 23:46:36.220015 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Sep 4 23:46:36.220026 kernel: pinctrl core: initialized pinctrl subsystem Sep 4 23:46:36.220034 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 4 23:46:36.220043 kernel: audit: initializing netlink subsys (disabled) Sep 4 23:46:36.220051 kernel: audit: type=2000 audit(1757029595.029:1): state=initialized audit_enabled=0 res=1 Sep 4 23:46:36.220060 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 4 23:46:36.220068 kernel: thermal_sys: Registered thermal governor 'user_space' Sep 4 23:46:36.220077 kernel: cpuidle: using governor menu Sep 4 23:46:36.220086 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 4 23:46:36.220094 kernel: dca service started, version 1.12.1 Sep 4 23:46:36.220105 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Sep 4 23:46:36.220114 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Sep 4 23:46:36.220123 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 4 23:46:36.220131 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Sep 4 23:46:36.220140 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 4 23:46:36.220148 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Sep 4 23:46:36.220157 kernel: ACPI: Added _OSI(Module Device) Sep 4 23:46:36.220165 kernel: ACPI: Added _OSI(Processor Device) Sep 4 23:46:36.220174 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 4 23:46:36.220185 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 4 23:46:36.220194 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Sep 4 23:46:36.220202 kernel: ACPI: Interpreter enabled Sep 4 23:46:36.220218 kernel: ACPI: PM: (supports S0 S5) Sep 4 23:46:36.220227 kernel: ACPI: Using IOAPIC for interrupt routing Sep 4 23:46:36.220236 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Sep 4 23:46:36.220244 kernel: PCI: Ignoring E820 reservations for host bridge windows Sep 4 23:46:36.220253 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Sep 4 23:46:36.220261 kernel: iommu: Default domain type: Translated Sep 4 23:46:36.220273 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Sep 4 23:46:36.220282 kernel: efivars: Registered efivars operations Sep 4 23:46:36.220290 kernel: PCI: Using ACPI for IRQ routing Sep 4 23:46:36.220299 kernel: PCI: System does not support PCI Sep 4 23:46:36.220308 kernel: vgaarb: loaded Sep 4 23:46:36.220316 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Sep 4 23:46:36.220325 kernel: VFS: Disk quotas dquot_6.6.0 Sep 4 23:46:36.220333 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 4 23:46:36.220342 kernel: pnp: PnP ACPI init Sep 4 23:46:36.220353 kernel: pnp: PnP ACPI: found 3 devices Sep 4 23:46:36.220362 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Sep 4 23:46:36.220370 kernel: NET: Registered PF_INET protocol family Sep 4 23:46:36.220379 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 4 23:46:36.220387 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Sep 4 23:46:36.220396 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 4 23:46:36.220405 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 4 23:46:36.220414 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Sep 4 23:46:36.220422 kernel: TCP: Hash tables configured (established 524288 bind 65536) Sep 4 23:46:36.220433 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Sep 4 23:46:36.220442 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Sep 4 23:46:36.220451 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 4 23:46:36.220459 kernel: NET: Registered PF_XDP protocol family Sep 4 23:46:36.220468 kernel: PCI: CLS 0 bytes, default 64 Sep 4 23:46:36.220476 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Sep 4 23:46:36.220485 kernel: software IO TLB: mapped [mem 0x000000003ae61000-0x000000003ee61000] (64MB) Sep 4 23:46:36.220494 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Sep 4 23:46:36.220502 kernel: Initialise system trusted keyrings Sep 4 23:46:36.220513 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Sep 4 23:46:36.220522 kernel: Key type asymmetric registered Sep 4 23:46:36.220530 kernel: Asymmetric key parser 'x509' registered Sep 4 23:46:36.220538 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Sep 4 23:46:36.220547 kernel: io scheduler mq-deadline registered Sep 4 23:46:36.220555 kernel: io scheduler kyber registered Sep 4 23:46:36.220564 kernel: io scheduler bfq registered Sep 4 23:46:36.220572 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Sep 4 23:46:36.220581 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 4 23:46:36.220592 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Sep 4 23:46:36.220601 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Sep 4 23:46:36.220610 kernel: i8042: PNP: No PS/2 controller found. Sep 4 23:46:36.220765 kernel: rtc_cmos 00:02: registered as rtc0 Sep 4 23:46:36.220873 kernel: rtc_cmos 00:02: setting system clock to 2025-09-04T23:46:35 UTC (1757029595) Sep 4 23:46:36.220984 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Sep 4 23:46:36.220997 kernel: intel_pstate: CPU model not supported Sep 4 23:46:36.221010 kernel: efifb: probing for efifb Sep 4 23:46:36.221019 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Sep 4 23:46:36.221028 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Sep 4 23:46:36.221036 kernel: efifb: scrolling: redraw Sep 4 23:46:36.221045 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Sep 4 23:46:36.221053 kernel: Console: switching to colour frame buffer device 128x48 Sep 4 23:46:36.221062 kernel: fb0: EFI VGA frame buffer device Sep 4 23:46:36.221071 kernel: pstore: Using crash dump compression: deflate Sep 4 23:46:36.221079 kernel: pstore: Registered efi_pstore as persistent store backend Sep 4 23:46:36.221090 kernel: NET: Registered PF_INET6 protocol family Sep 4 23:46:36.221099 kernel: Segment Routing with IPv6 Sep 4 23:46:36.221107 kernel: In-situ OAM (IOAM) with IPv6 Sep 4 23:46:36.221116 kernel: NET: Registered PF_PACKET protocol family Sep 4 23:46:36.221125 kernel: Key type dns_resolver registered Sep 4 23:46:36.221133 kernel: IPI shorthand broadcast: enabled Sep 4 23:46:36.221142 kernel: sched_clock: Marking stable (1612006100, 100884000)->(2045008900, -332118800) Sep 4 23:46:36.221150 kernel: registered taskstats version 1 Sep 4 23:46:36.221159 kernel: Loading compiled-in X.509 certificates Sep 4 23:46:36.221167 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.103-flatcar: f395d469db1520f53594f6c4948c5f8002e6cc8b' Sep 4 23:46:36.221178 kernel: Key type .fscrypt registered Sep 4 23:46:36.221187 kernel: Key type fscrypt-provisioning registered Sep 4 23:46:36.221327 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 4 23:46:36.221337 kernel: ima: Allocated hash algorithm: sha1 Sep 4 23:46:36.221346 kernel: ima: No architecture policies found Sep 4 23:46:36.221354 kernel: clk: Disabling unused clocks Sep 4 23:46:36.221363 kernel: Freeing unused kernel image (initmem) memory: 43508K Sep 4 23:46:36.221371 kernel: Write protecting the kernel read-only data: 38912k Sep 4 23:46:36.221385 kernel: Freeing unused kernel image (rodata/data gap) memory: 1708K Sep 4 23:46:36.221394 kernel: Run /init as init process Sep 4 23:46:36.221403 kernel: with arguments: Sep 4 23:46:36.221411 kernel: /init Sep 4 23:46:36.221420 kernel: with environment: Sep 4 23:46:36.221428 kernel: HOME=/ Sep 4 23:46:36.221436 kernel: TERM=linux Sep 4 23:46:36.221445 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 4 23:46:36.221455 systemd[1]: Successfully made /usr/ read-only. Sep 4 23:46:36.221469 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) Sep 4 23:46:36.221479 systemd[1]: Detected virtualization microsoft. Sep 4 23:46:36.221488 systemd[1]: Detected architecture x86-64. Sep 4 23:46:36.221497 systemd[1]: Running in initrd. Sep 4 23:46:36.221506 systemd[1]: No hostname configured, using default hostname. Sep 4 23:46:36.221515 systemd[1]: Hostname set to . Sep 4 23:46:36.221524 systemd[1]: Initializing machine ID from random generator. Sep 4 23:46:36.221536 systemd[1]: Queued start job for default target initrd.target. Sep 4 23:46:36.221545 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 4 23:46:36.221554 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 4 23:46:36.221564 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 4 23:46:36.221574 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 4 23:46:36.221583 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 4 23:46:36.221593 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 4 23:46:36.221606 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Sep 4 23:46:36.221615 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 4 23:46:36.221625 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 4 23:46:36.221634 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 4 23:46:36.221643 systemd[1]: Reached target paths.target - Path Units. Sep 4 23:46:36.221652 systemd[1]: Reached target slices.target - Slice Units. Sep 4 23:46:36.221661 systemd[1]: Reached target swap.target - Swaps. Sep 4 23:46:36.221671 systemd[1]: Reached target timers.target - Timer Units. Sep 4 23:46:36.221680 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 4 23:46:36.221692 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 4 23:46:36.221701 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 4 23:46:36.221710 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Sep 4 23:46:36.221720 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 4 23:46:36.221729 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 4 23:46:36.221738 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 4 23:46:36.221747 systemd[1]: Reached target sockets.target - Socket Units. Sep 4 23:46:36.221757 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 4 23:46:36.221769 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 4 23:46:36.221778 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 4 23:46:36.221787 systemd[1]: Starting systemd-fsck-usr.service... Sep 4 23:46:36.221796 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 4 23:46:36.221805 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 4 23:46:36.221814 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 23:46:36.221847 systemd-journald[205]: Collecting audit messages is disabled. Sep 4 23:46:36.221873 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 4 23:46:36.221884 systemd-journald[205]: Journal started Sep 4 23:46:36.221907 systemd-journald[205]: Runtime Journal (/run/log/journal/c3ba8d7432a24a5295a311129fed1749) is 8M, max 2.2G, 2.1G free. Sep 4 23:46:36.227229 systemd-modules-load[206]: Inserted module 'overlay' Sep 4 23:46:36.227966 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 4 23:46:36.228353 systemd[1]: Started systemd-journald.service - Journal Service. Sep 4 23:46:36.228505 systemd[1]: Finished systemd-fsck-usr.service. Sep 4 23:46:36.250248 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 23:46:36.268331 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 4 23:46:36.277892 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 4 23:46:36.279361 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 4 23:46:36.284730 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 4 23:46:36.290932 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 4 23:46:36.311597 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 4 23:46:36.329979 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Sep 4 23:46:36.330006 kernel: Bridge firewalling registered Sep 4 23:46:36.330039 dracut-cmdline[232]: dracut-dracut-053 Sep 4 23:46:36.330039 dracut-cmdline[232]: 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=564344e0ae537bb1f195be96fecdd60e9e7ec1fe4e3ba9f8a7a8da5d9135455e Sep 4 23:46:36.317768 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 4 23:46:36.319999 systemd-modules-load[206]: Inserted module 'br_netfilter' Sep 4 23:46:36.325250 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 4 23:46:36.335635 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 4 23:46:36.364412 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 4 23:46:36.384250 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 4 23:46:36.391417 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 4 23:46:36.412353 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 4 23:46:36.447229 kernel: SCSI subsystem initialized Sep 4 23:46:36.448885 systemd-resolved[300]: Positive Trust Anchors: Sep 4 23:46:36.448901 systemd-resolved[300]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 4 23:46:36.448931 systemd-resolved[300]: 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 Sep 4 23:46:36.451507 systemd-resolved[300]: Defaulting to hostname 'linux'. Sep 4 23:46:36.452589 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 4 23:46:36.502347 kernel: Loading iSCSI transport class v2.0-870. Sep 4 23:46:36.457928 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 4 23:46:36.520229 kernel: iscsi: registered transport (tcp) Sep 4 23:46:36.543429 kernel: iscsi: registered transport (qla4xxx) Sep 4 23:46:36.543469 kernel: QLogic iSCSI HBA Driver Sep 4 23:46:36.583532 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 4 23:46:36.602343 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 4 23:46:36.636324 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 4 23:46:36.636366 kernel: device-mapper: uevent: version 1.0.3 Sep 4 23:46:36.640895 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Sep 4 23:46:36.684233 kernel: raid6: avx2x4 gen() 24534 MB/s Sep 4 23:46:36.703227 kernel: raid6: avx2x2 gen() 31003 MB/s Sep 4 23:46:36.724335 kernel: raid6: avx2x1 gen() 28069 MB/s Sep 4 23:46:36.724361 kernel: raid6: using algorithm avx2x2 gen() 31003 MB/s Sep 4 23:46:36.747373 kernel: raid6: .... xor() 18529 MB/s, rmw enabled Sep 4 23:46:36.747415 kernel: raid6: using avx2x2 recovery algorithm Sep 4 23:46:36.772233 kernel: xor: automatically using best checksumming function avx Sep 4 23:46:36.934242 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 4 23:46:36.945228 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 4 23:46:36.968341 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 4 23:46:36.990745 systemd-udevd[429]: Using default interface naming scheme 'v255'. Sep 4 23:46:36.996388 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 4 23:46:37.001536 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 4 23:46:37.026935 dracut-pre-trigger[436]: rd.md=0: removing MD RAID activation Sep 4 23:46:37.057988 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 4 23:46:37.072362 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 4 23:46:37.143305 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 4 23:46:37.160343 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 4 23:46:37.174839 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 4 23:46:37.190541 kernel: cryptd: max_cpu_qlen set to 1000 Sep 4 23:46:37.181057 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 4 23:46:37.186412 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 4 23:46:37.195586 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 4 23:46:37.222923 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 4 23:46:37.237161 kernel: AVX2 version of gcm_enc/dec engaged. Sep 4 23:46:37.237187 kernel: AES CTR mode by8 optimization enabled Sep 4 23:46:37.237200 kernel: hv_vmbus: Vmbus version:4.0 Sep 4 23:46:37.232130 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 4 23:46:37.244236 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 4 23:46:37.244402 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 4 23:46:37.257014 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 4 23:46:37.281097 kernel: hv_vmbus: registering driver hyperv_keyboard Sep 4 23:46:37.281124 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 Sep 4 23:46:37.266555 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 4 23:46:37.266798 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 23:46:37.289656 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 23:46:37.314085 kernel: pps_core: LinuxPPS API ver. 1 registered Sep 4 23:46:37.314111 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Sep 4 23:46:37.314123 kernel: PTP clock support registered Sep 4 23:46:37.316521 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 23:46:37.317057 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 4 23:46:37.334291 kernel: hv_utils: Registering HyperV Utility Driver Sep 4 23:46:37.334330 kernel: hv_vmbus: registering driver hv_netvsc Sep 4 23:46:37.334348 kernel: hv_vmbus: registering driver hv_pci Sep 4 23:46:37.335243 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Sep 4 23:46:37.335575 kernel: hv_vmbus: registering driver hv_utils Sep 4 23:46:37.340528 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Sep 4 23:46:37.340692 kernel: hid: raw HID events driver (C) Jiri Kosina Sep 4 23:46:37.340707 kernel: hv_utils: Heartbeat IC version 3.0 Sep 4 23:46:37.342225 kernel: hv_utils: Shutdown IC version 3.2 Sep 4 23:46:37.342263 kernel: hv_utils: TimeSync IC version 4.0 Sep 4 23:46:38.573056 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Sep 4 23:46:38.570438 systemd-resolved[300]: Clock change detected. Flushing caches. Sep 4 23:46:38.649767 kernel: hv_vmbus: registering driver hv_storvsc Sep 4 23:46:38.650406 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Sep 4 23:46:38.650608 kernel: scsi host0: storvsc_host_t Sep 4 23:46:38.650777 kernel: scsi host1: storvsc_host_t Sep 4 23:46:38.650932 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Sep 4 23:46:38.651061 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Sep 4 23:46:38.651209 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 Sep 4 23:46:38.651238 kernel: hv_vmbus: registering driver hid_hyperv Sep 4 23:46:38.651256 kernel: pci 0001:00:00.0: reg 0x10: [mem 0x41000000-0x41ffffff] Sep 4 23:46:38.651279 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Sep 4 23:46:38.651292 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Sep 4 23:46:38.651466 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Sep 4 23:46:38.651617 kernel: pci 0001:00:00.0: reg 0x14: [mem 0x1000000000-0x13ffffffff 64bit pref] Sep 4 23:46:38.651640 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Sep 4 23:46:38.651760 kernel: pci 0001:00:00.0: reg 0x1c: [mem 0x1400000000-0x1401ffffff 64bit pref] Sep 4 23:46:38.651782 kernel: pci 0001:00:00.0: Enabling HDA controller Sep 4 23:46:38.651816 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Sep 4 23:46:38.650690 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 4 23:46:38.664081 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Sep 4 23:46:38.664107 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Sep 4 23:46:38.650843 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 23:46:38.675707 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Sep 4 23:46:38.675894 kernel: pci 0001:00:00.0: BAR 1: assigned [mem 0x1000000000-0x13ffffffff 64bit pref] Sep 4 23:46:38.676101 kernel: pci 0001:00:00.0: BAR 3: assigned [mem 0x1400000000-0x1401ffffff 64bit pref] Sep 4 23:46:38.666018 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 4 23:46:38.688449 kernel: pci 0001:00:00.0: BAR 0: assigned [mem 0x41000000-0x41ffffff] Sep 4 23:46:38.690976 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 23:46:38.715810 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Sep 4 23:46:38.716018 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Sep 4 23:46:38.716179 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Sep 4 23:46:38.716325 kernel: sd 0:0:0:0: [sda] Write Protect is off Sep 4 23:46:38.716466 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks Sep 4 23:46:38.721270 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Sep 4 23:46:38.721470 kernel: sd 0:0:0:1: [sdb] Write Protect is off Sep 4 23:46:38.725166 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Sep 4 23:46:38.725357 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Sep 4 23:46:38.725508 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Sep 4 23:46:38.731808 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 4 23:46:38.738849 kernel: sdb: sdb1 Sep 4 23:46:38.738880 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Sep 4 23:46:38.739057 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk Sep 4 23:46:38.749208 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 23:46:38.762967 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 4 23:46:38.780137 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 4 23:46:39.312490 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Sep 4 23:46:39.323063 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 scanned by (udev-worker) (487) Sep 4 23:46:39.336895 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Sep 4 23:46:39.353330 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Sep 4 23:46:39.388816 kernel: BTRFS: device fsid 185ffa67-4184-4488-b7c8-7c0711a63b2d devid 1 transid 38 /dev/sda3 scanned by (udev-worker) (492) Sep 4 23:46:39.408952 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Sep 4 23:46:39.409094 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Sep 4 23:46:39.437917 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 4 23:46:39.455805 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 4 23:46:39.466808 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 4 23:46:40.476400 disk-uuid[640]: The operation has completed successfully. Sep 4 23:46:40.480158 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 4 23:46:42.452684 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 4 23:46:42.452816 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 4 23:46:42.480928 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 4 23:46:42.491079 sh[727]: Success Sep 4 23:46:42.512812 kernel: device-mapper: verity: sha256 using implementation "sha256-avx2" Sep 4 23:46:42.869187 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 4 23:46:42.884210 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 4 23:46:42.886566 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 4 23:46:42.916220 kernel: BTRFS info (device dm-0): first mount of filesystem 185ffa67-4184-4488-b7c8-7c0711a63b2d Sep 4 23:46:42.916284 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Sep 4 23:46:42.921111 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Sep 4 23:46:42.924730 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 4 23:46:42.927812 kernel: BTRFS info (device dm-0): using free space tree Sep 4 23:46:43.238726 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 4 23:46:43.239451 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 4 23:46:43.260905 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 4 23:46:43.261681 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 4 23:46:43.312204 kernel: BTRFS info (device sda6): first mount of filesystem 66b85247-a711-4bbf-a14c-62367abde12c Sep 4 23:46:43.312260 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Sep 4 23:46:43.315255 kernel: BTRFS info (device sda6): using free space tree Sep 4 23:46:43.355881 kernel: BTRFS info (device sda6): auto enabling async discard Sep 4 23:46:43.364805 kernel: BTRFS info (device sda6): last unmount of filesystem 66b85247-a711-4bbf-a14c-62367abde12c Sep 4 23:46:43.371326 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 4 23:46:43.384014 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 4 23:46:43.396608 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 4 23:46:43.398311 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 4 23:46:43.436062 systemd-networkd[908]: lo: Link UP Sep 4 23:46:43.436072 systemd-networkd[908]: lo: Gained carrier Sep 4 23:46:43.437255 systemd-networkd[908]: Enumeration completed Sep 4 23:46:43.437464 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 4 23:46:43.437622 systemd-networkd[908]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 23:46:43.437627 systemd-networkd[908]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 4 23:46:43.438371 systemd-networkd[908]: eth0: Link UP Sep 4 23:46:43.438541 systemd-networkd[908]: eth0: Gained carrier Sep 4 23:46:43.438549 systemd-networkd[908]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 23:46:43.438905 systemd[1]: Reached target network.target - Network. Sep 4 23:46:43.496822 systemd-networkd[908]: eth0: DHCPv4 address 10.200.8.22/24, gateway 10.200.8.1 acquired from 168.63.129.16 Sep 4 23:46:44.473919 systemd-networkd[908]: eth0: Gained IPv6LL Sep 4 23:46:44.528332 ignition[897]: Ignition 2.20.0 Sep 4 23:46:44.528345 ignition[897]: Stage: fetch-offline Sep 4 23:46:44.528398 ignition[897]: no configs at "/usr/lib/ignition/base.d" Sep 4 23:46:44.528411 ignition[897]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 4 23:46:44.528520 ignition[897]: parsed url from cmdline: "" Sep 4 23:46:44.528525 ignition[897]: no config URL provided Sep 4 23:46:44.528531 ignition[897]: reading system config file "/usr/lib/ignition/user.ign" Sep 4 23:46:44.528541 ignition[897]: no config at "/usr/lib/ignition/user.ign" Sep 4 23:46:44.528547 ignition[897]: failed to fetch config: resource requires networking Sep 4 23:46:44.528717 ignition[897]: Ignition finished successfully Sep 4 23:46:44.556315 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 4 23:46:44.574920 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Sep 4 23:46:44.586934 ignition[923]: Ignition 2.20.0 Sep 4 23:46:44.586946 ignition[923]: Stage: fetch Sep 4 23:46:44.587119 ignition[923]: no configs at "/usr/lib/ignition/base.d" Sep 4 23:46:44.587132 ignition[923]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 4 23:46:44.587234 ignition[923]: parsed url from cmdline: "" Sep 4 23:46:44.587239 ignition[923]: no config URL provided Sep 4 23:46:44.587245 ignition[923]: reading system config file "/usr/lib/ignition/user.ign" Sep 4 23:46:44.587256 ignition[923]: no config at "/usr/lib/ignition/user.ign" Sep 4 23:46:44.587281 ignition[923]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Sep 4 23:46:44.702157 ignition[923]: GET result: OK Sep 4 23:46:44.702204 ignition[923]: config has been read from IMDS userdata Sep 4 23:46:44.702216 ignition[923]: parsing config with SHA512: 97254d619c318bdb902d2a513dc36db22acda84bfdc19c23220dbff20458b050c885d4975e6bd308fc9a285819c5514d29f1491d9c38104e0ebf17b2fe7e1bde Sep 4 23:46:44.706174 unknown[923]: fetched base config from "system" Sep 4 23:46:44.707595 ignition[923]: fetch: fetch complete Sep 4 23:46:44.706184 unknown[923]: fetched base config from "system" Sep 4 23:46:44.707601 ignition[923]: fetch: fetch passed Sep 4 23:46:44.706194 unknown[923]: fetched user config from "azure" Sep 4 23:46:44.707654 ignition[923]: Ignition finished successfully Sep 4 23:46:44.715437 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Sep 4 23:46:44.737922 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 4 23:46:44.758421 ignition[933]: Ignition 2.20.0 Sep 4 23:46:44.758434 ignition[933]: Stage: kargs Sep 4 23:46:44.758601 ignition[933]: no configs at "/usr/lib/ignition/base.d" Sep 4 23:46:44.758614 ignition[933]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 4 23:46:44.759460 ignition[933]: kargs: kargs passed Sep 4 23:46:44.759512 ignition[933]: Ignition finished successfully Sep 4 23:46:44.774019 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 4 23:46:44.795951 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 4 23:46:44.807353 ignition[943]: Ignition 2.20.0 Sep 4 23:46:44.807365 ignition[943]: Stage: disks Sep 4 23:46:44.807514 ignition[943]: no configs at "/usr/lib/ignition/base.d" Sep 4 23:46:44.810981 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 4 23:46:44.807526 ignition[943]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 4 23:46:44.812229 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 4 23:46:44.808085 ignition[943]: disks: disks passed Sep 4 23:46:44.819134 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 4 23:46:44.808128 ignition[943]: Ignition finished successfully Sep 4 23:46:44.831760 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 4 23:46:44.839764 systemd[1]: Reached target sysinit.target - System Initialization. Sep 4 23:46:44.847897 systemd[1]: Reached target basic.target - Basic System. Sep 4 23:46:44.875926 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 4 23:46:44.956353 systemd-fsck[954]: ROOT: clean, 14/7326000 files, 477710/7359488 blocks Sep 4 23:46:44.963836 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 4 23:46:44.984864 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 4 23:46:45.084801 kernel: EXT4-fs (sda9): mounted filesystem 86dd2c20-900e-43ec-8fda-e9f0f484a013 r/w with ordered data mode. Quota mode: none. Sep 4 23:46:45.085366 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 4 23:46:45.086065 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 4 23:46:45.124860 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 4 23:46:45.137140 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 4 23:46:45.142830 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/sda6 scanned by mount (965) Sep 4 23:46:45.143206 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Sep 4 23:46:45.158228 kernel: BTRFS info (device sda6): first mount of filesystem 66b85247-a711-4bbf-a14c-62367abde12c Sep 4 23:46:45.158257 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Sep 4 23:46:45.161667 kernel: BTRFS info (device sda6): using free space tree Sep 4 23:46:45.168854 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Sep 4 23:46:45.168894 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 4 23:46:45.186410 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 4 23:46:45.196880 kernel: BTRFS info (device sda6): auto enabling async discard Sep 4 23:46:45.210916 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 4 23:46:45.212336 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 4 23:46:45.925704 coreos-metadata[967]: Sep 04 23:46:45.925 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Sep 4 23:46:45.931519 coreos-metadata[967]: Sep 04 23:46:45.931 INFO Fetch successful Sep 4 23:46:45.935234 coreos-metadata[967]: Sep 04 23:46:45.931 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Sep 4 23:46:45.943319 coreos-metadata[967]: Sep 04 23:46:45.936 INFO Fetch successful Sep 4 23:46:45.947850 coreos-metadata[967]: Sep 04 23:46:45.947 INFO wrote hostname ci-4230.2.2-n-ccd817eef4 to /sysroot/etc/hostname Sep 4 23:46:45.954912 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Sep 4 23:46:46.025181 initrd-setup-root[995]: cut: /sysroot/etc/passwd: No such file or directory Sep 4 23:46:46.070936 initrd-setup-root[1002]: cut: /sysroot/etc/group: No such file or directory Sep 4 23:46:46.109856 initrd-setup-root[1009]: cut: /sysroot/etc/shadow: No such file or directory Sep 4 23:46:46.127919 initrd-setup-root[1016]: cut: /sysroot/etc/gshadow: No such file or directory Sep 4 23:46:47.122924 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 4 23:46:47.138862 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 4 23:46:47.140504 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 4 23:46:47.159225 kernel: BTRFS info (device sda6): last unmount of filesystem 66b85247-a711-4bbf-a14c-62367abde12c Sep 4 23:46:47.149672 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 4 23:46:47.179906 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 4 23:46:47.187101 ignition[1085]: INFO : Ignition 2.20.0 Sep 4 23:46:47.187101 ignition[1085]: INFO : Stage: mount Sep 4 23:46:47.187101 ignition[1085]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 4 23:46:47.187101 ignition[1085]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 4 23:46:47.187101 ignition[1085]: INFO : mount: mount passed Sep 4 23:46:47.187101 ignition[1085]: INFO : Ignition finished successfully Sep 4 23:46:47.208425 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 4 23:46:47.225902 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 4 23:46:47.239189 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 4 23:46:47.262803 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/sda6 scanned by mount (1099) Sep 4 23:46:47.271935 kernel: BTRFS info (device sda6): first mount of filesystem 66b85247-a711-4bbf-a14c-62367abde12c Sep 4 23:46:47.271966 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Sep 4 23:46:47.274719 kernel: BTRFS info (device sda6): using free space tree Sep 4 23:46:47.282824 kernel: BTRFS info (device sda6): auto enabling async discard Sep 4 23:46:47.284822 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 4 23:46:47.314320 ignition[1116]: INFO : Ignition 2.20.0 Sep 4 23:46:47.314320 ignition[1116]: INFO : Stage: files Sep 4 23:46:47.320218 ignition[1116]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 4 23:46:47.320218 ignition[1116]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 4 23:46:47.320218 ignition[1116]: DEBUG : files: compiled without relabeling support, skipping Sep 4 23:46:47.333081 ignition[1116]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 4 23:46:47.333081 ignition[1116]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 4 23:46:47.484167 ignition[1116]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 4 23:46:47.489714 ignition[1116]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 4 23:46:47.489714 ignition[1116]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 4 23:46:47.485257 unknown[1116]: wrote ssh authorized keys file for user: core Sep 4 23:46:47.520717 ignition[1116]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 4 23:46:47.527996 ignition[1116]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 4 23:46:47.535128 ignition[1116]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 4 23:46:47.535128 ignition[1116]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 4 23:46:47.535128 ignition[1116]: INFO : files: files passed Sep 4 23:46:47.535128 ignition[1116]: INFO : Ignition finished successfully Sep 4 23:46:47.555957 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 4 23:46:47.575927 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 4 23:46:47.580893 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 4 23:46:47.584901 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 4 23:46:47.584992 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 4 23:46:47.621867 initrd-setup-root-after-ignition[1148]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 4 23:46:47.628212 initrd-setup-root-after-ignition[1148]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 4 23:46:47.623859 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 4 23:46:47.648194 initrd-setup-root-after-ignition[1152]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 4 23:46:47.633206 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 4 23:46:47.658936 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 4 23:46:47.680944 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 4 23:46:47.681064 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 4 23:46:47.684896 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 4 23:46:47.694221 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 4 23:46:47.719541 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 4 23:46:47.720439 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 4 23:46:47.749495 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 4 23:46:47.758850 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 4 23:46:47.772519 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 4 23:46:47.772717 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 4 23:46:47.781447 systemd[1]: Stopped target timers.target - Timer Units. Sep 4 23:46:47.790193 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 4 23:46:47.790310 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 4 23:46:47.805060 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 4 23:46:47.809524 systemd[1]: Stopped target basic.target - Basic System. Sep 4 23:46:47.817125 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 4 23:46:47.823966 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 4 23:46:47.832176 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 4 23:46:47.840915 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 4 23:46:47.849180 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 4 23:46:47.856885 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 4 23:46:47.866274 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 4 23:46:47.874018 systemd[1]: Stopped target swap.target - Swaps. Sep 4 23:46:47.881575 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 4 23:46:47.881705 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 4 23:46:47.895105 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 4 23:46:47.899149 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 4 23:46:47.907162 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 4 23:46:47.907321 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 4 23:46:47.916196 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 4 23:46:47.916313 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 4 23:46:47.933805 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 4 23:46:47.933932 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 4 23:46:47.937428 systemd[1]: ignition-files.service: Deactivated successfully. Sep 4 23:46:47.937541 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 4 23:46:47.946724 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Sep 4 23:46:47.946861 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Sep 4 23:46:47.975938 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 4 23:46:47.980127 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 4 23:46:47.995848 ignition[1173]: INFO : Ignition 2.20.0 Sep 4 23:46:47.995848 ignition[1173]: INFO : Stage: umount Sep 4 23:46:47.980263 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 4 23:46:48.017971 ignition[1173]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 4 23:46:48.017971 ignition[1173]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 4 23:46:48.017971 ignition[1173]: INFO : umount: umount passed Sep 4 23:46:48.017971 ignition[1173]: INFO : Ignition finished successfully Sep 4 23:46:47.993513 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 4 23:46:47.999305 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 4 23:46:47.999449 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 4 23:46:48.002248 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 4 23:46:48.002370 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 4 23:46:48.019890 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 4 23:46:48.019994 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 4 23:46:48.025085 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 4 23:46:48.025184 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 4 23:46:48.039287 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 4 23:46:48.039368 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 4 23:46:48.042250 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 4 23:46:48.042298 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 4 23:46:48.053379 systemd[1]: ignition-fetch.service: Deactivated successfully. Sep 4 23:46:48.053425 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Sep 4 23:46:48.058241 systemd[1]: Stopped target network.target - Network. Sep 4 23:46:48.066526 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 4 23:46:48.066589 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 4 23:46:48.078643 systemd[1]: Stopped target paths.target - Path Units. Sep 4 23:46:48.085922 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 4 23:46:48.089835 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 4 23:46:48.093896 systemd[1]: Stopped target slices.target - Slice Units. Sep 4 23:46:48.102175 systemd[1]: Stopped target sockets.target - Socket Units. Sep 4 23:46:48.110513 systemd[1]: iscsid.socket: Deactivated successfully. Sep 4 23:46:48.110568 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 4 23:46:48.117557 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 4 23:46:48.117609 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 4 23:46:48.128857 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 4 23:46:48.128910 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 4 23:46:48.132359 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 4 23:46:48.132406 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 4 23:46:48.143959 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 4 23:46:48.147429 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 4 23:46:48.155892 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 4 23:46:48.156516 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 4 23:46:48.156614 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 4 23:46:48.162551 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 4 23:46:48.162690 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 4 23:46:48.180331 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Sep 4 23:46:48.180933 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 4 23:46:48.181055 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 4 23:46:48.195851 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Sep 4 23:46:48.197637 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 4 23:46:48.197686 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 4 23:46:48.198298 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 4 23:46:48.198349 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 4 23:46:48.226858 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 4 23:46:48.227235 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 4 23:46:48.227289 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 4 23:46:48.239306 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 4 23:46:48.239359 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 4 23:46:48.247354 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 4 23:46:48.247403 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 4 23:46:48.255484 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 4 23:46:48.255532 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 4 23:46:48.269296 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 4 23:46:48.278166 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Sep 4 23:46:48.278237 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Sep 4 23:46:48.300769 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 4 23:46:48.300894 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 4 23:46:48.308812 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 4 23:46:48.308959 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 4 23:46:48.319133 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 4 23:46:48.319203 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 4 23:46:48.321988 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 4 23:46:48.322034 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 4 23:46:48.330342 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 4 23:46:48.330397 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 4 23:46:48.350008 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 4 23:46:48.350061 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 4 23:46:48.363124 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 4 23:46:48.363182 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 4 23:46:48.394961 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 4 23:46:48.397980 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 4 23:46:48.398039 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 4 23:46:48.416766 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 4 23:46:48.416835 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 23:46:48.425989 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Sep 4 23:46:48.426054 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 4 23:46:48.426527 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 4 23:46:48.426625 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 4 23:46:48.429031 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 4 23:46:48.437618 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 4 23:46:48.465337 systemd[1]: Switching root. Sep 4 23:46:48.568326 systemd-journald[205]: Journal stopped Sep 4 23:46:57.060161 systemd-journald[205]: Received SIGTERM from PID 1 (systemd). Sep 4 23:46:57.060197 kernel: SELinux: policy capability network_peer_controls=1 Sep 4 23:46:57.060210 kernel: SELinux: policy capability open_perms=1 Sep 4 23:46:57.060220 kernel: SELinux: policy capability extended_socket_class=1 Sep 4 23:46:57.060229 kernel: SELinux: policy capability always_check_network=0 Sep 4 23:46:57.060238 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 4 23:46:57.060248 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 4 23:46:57.060261 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 4 23:46:57.060270 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 4 23:46:57.060280 kernel: audit: type=1403 audit(1757029609.449:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 4 23:46:57.060290 systemd[1]: Successfully loaded SELinux policy in 187.219ms. Sep 4 23:46:57.060302 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 15.338ms. Sep 4 23:46:57.060313 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) Sep 4 23:46:57.060323 systemd[1]: Detected virtualization microsoft. Sep 4 23:46:57.060337 systemd[1]: Detected architecture x86-64. Sep 4 23:46:57.060348 systemd[1]: Detected first boot. Sep 4 23:46:57.060358 systemd[1]: Hostname set to . Sep 4 23:46:57.060369 systemd[1]: Initializing machine ID from random generator. Sep 4 23:46:57.060379 zram_generator::config[1221]: No configuration found. Sep 4 23:46:57.060398 kernel: Guest personality initialized and is inactive Sep 4 23:46:57.060407 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Sep 4 23:46:57.060417 kernel: Initialized host personality Sep 4 23:46:57.060427 kernel: NET: Registered PF_VSOCK protocol family Sep 4 23:46:57.060437 systemd[1]: Populated /etc with preset unit settings. Sep 4 23:46:57.060448 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Sep 4 23:46:57.060459 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 4 23:46:57.060469 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 4 23:46:57.060482 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 4 23:46:57.060493 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 4 23:46:57.060504 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 4 23:46:57.060515 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 4 23:46:57.060526 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 4 23:46:57.060536 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 4 23:46:57.060547 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 4 23:46:57.060561 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 4 23:46:57.060572 systemd[1]: Created slice user.slice - User and Session Slice. Sep 4 23:46:57.060582 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 4 23:46:57.060593 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 4 23:46:57.060604 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 4 23:46:57.060614 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 4 23:46:57.060633 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 4 23:46:57.060644 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 4 23:46:57.060658 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Sep 4 23:46:57.060669 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 4 23:46:57.060680 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 4 23:46:57.060691 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 4 23:46:57.060702 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 4 23:46:57.060713 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 4 23:46:57.060724 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 4 23:46:57.060735 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 4 23:46:57.060749 systemd[1]: Reached target slices.target - Slice Units. Sep 4 23:46:57.060761 systemd[1]: Reached target swap.target - Swaps. Sep 4 23:46:57.060771 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 4 23:46:57.060782 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 4 23:46:57.060806 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Sep 4 23:46:57.060822 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 4 23:46:57.060833 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 4 23:46:57.060844 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 4 23:46:57.060855 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 4 23:46:57.060866 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 4 23:46:57.060880 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 4 23:46:57.060895 systemd[1]: Mounting media.mount - External Media Directory... Sep 4 23:46:57.060906 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 4 23:46:57.060921 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 4 23:46:57.060932 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 4 23:46:57.060943 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 4 23:46:57.060955 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). Sep 4 23:46:57.060966 systemd[1]: Reached target machines.target - Containers. Sep 4 23:46:57.060978 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 4 23:46:57.060989 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 4 23:46:57.061000 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 4 23:46:57.061014 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 4 23:46:57.061026 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 4 23:46:57.061037 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 4 23:46:57.061048 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 4 23:46:57.061059 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 4 23:46:57.061070 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 4 23:46:57.061081 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 4 23:46:57.061092 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 4 23:46:57.061106 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 4 23:46:57.061117 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 4 23:46:57.061128 systemd[1]: Stopped systemd-fsck-usr.service. Sep 4 23:46:57.061143 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). Sep 4 23:46:57.061155 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 4 23:46:57.061166 kernel: loop: module loaded Sep 4 23:46:57.061176 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 4 23:46:57.061188 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 4 23:46:57.061202 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 4 23:46:57.061213 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Sep 4 23:46:57.061224 kernel: fuse: init (API version 7.39) Sep 4 23:46:57.061234 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 4 23:46:57.061271 systemd-journald[1328]: Collecting audit messages is disabled. Sep 4 23:46:57.061299 systemd-journald[1328]: Journal started Sep 4 23:46:57.061321 systemd-journald[1328]: Runtime Journal (/run/log/journal/c58277173b1a48dd80a2281f98111655) is 8M, max 2.2G, 2.1G free. Sep 4 23:46:56.217780 systemd[1]: Queued start job for default target multi-user.target. Sep 4 23:46:56.231220 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Sep 4 23:46:56.231682 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 4 23:46:57.080247 systemd[1]: verity-setup.service: Deactivated successfully. Sep 4 23:46:57.080296 systemd[1]: Stopped verity-setup.service. Sep 4 23:46:57.092807 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 4 23:46:57.098814 systemd[1]: Started systemd-journald.service - Journal Service. Sep 4 23:46:57.103335 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 4 23:46:57.108808 kernel: ACPI: bus type drm_connector registered Sep 4 23:46:57.109949 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 4 23:46:57.114528 systemd[1]: Mounted media.mount - External Media Directory. Sep 4 23:46:57.118570 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 4 23:46:57.123177 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 4 23:46:57.127746 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 4 23:46:57.131958 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 4 23:46:57.137039 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 4 23:46:57.142499 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 4 23:46:57.142691 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 4 23:46:57.147777 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 4 23:46:57.147998 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 4 23:46:57.153048 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 4 23:46:57.153233 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 4 23:46:57.158048 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 4 23:46:57.158242 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 4 23:46:57.163580 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 4 23:46:57.163779 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 4 23:46:57.168324 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 4 23:46:57.168518 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 4 23:46:57.173215 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 4 23:46:57.178041 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 4 23:46:57.183351 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 4 23:46:57.189183 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Sep 4 23:46:57.203508 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 4 23:46:57.221889 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 4 23:46:57.228911 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 4 23:46:57.233324 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 4 23:46:57.233360 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 4 23:46:57.238505 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Sep 4 23:46:57.245463 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 4 23:46:57.250973 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 4 23:46:57.257252 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 4 23:46:57.271414 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 4 23:46:57.276566 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 4 23:46:57.281070 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 4 23:46:57.282097 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 4 23:46:57.286348 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 4 23:46:57.287376 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 4 23:46:57.292385 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 4 23:46:57.298333 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 4 23:46:57.304269 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 4 23:46:57.309324 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 4 23:46:57.315198 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 4 23:46:57.320980 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 4 23:46:57.324338 systemd-journald[1328]: Time spent on flushing to /var/log/journal/c58277173b1a48dd80a2281f98111655 is 14.674ms for 943 entries. Sep 4 23:46:57.324338 systemd-journald[1328]: System Journal (/var/log/journal/c58277173b1a48dd80a2281f98111655) is 8M, max 2.6G, 2.6G free. Sep 4 23:46:57.357313 systemd-journald[1328]: Received client request to flush runtime journal. Sep 4 23:46:57.347003 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Sep 4 23:46:57.352354 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 4 23:46:57.360191 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 4 23:46:57.365995 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 4 23:46:57.380813 kernel: loop0: detected capacity change from 0 to 28272 Sep 4 23:46:57.386031 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Sep 4 23:46:57.391189 udevadm[1372]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Sep 4 23:46:57.424795 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 4 23:46:57.425595 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Sep 4 23:46:57.452135 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 4 23:46:57.884044 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 4 23:46:57.900993 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 4 23:46:57.906821 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 4 23:46:58.040821 kernel: loop1: detected capacity change from 0 to 147912 Sep 4 23:46:58.054357 systemd-tmpfiles[1385]: ACLs are not supported, ignoring. Sep 4 23:46:58.054378 systemd-tmpfiles[1385]: ACLs are not supported, ignoring. Sep 4 23:46:58.059913 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 4 23:46:58.732815 kernel: loop2: detected capacity change from 0 to 138176 Sep 4 23:46:59.186452 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 4 23:46:59.205988 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 4 23:46:59.234823 systemd-udevd[1391]: Using default interface naming scheme 'v255'. Sep 4 23:46:59.266811 kernel: loop3: detected capacity change from 0 to 28272 Sep 4 23:46:59.281805 kernel: loop4: detected capacity change from 0 to 147912 Sep 4 23:46:59.311807 kernel: loop5: detected capacity change from 0 to 138176 Sep 4 23:46:59.329807 (sd-merge)[1393]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Sep 4 23:46:59.330289 (sd-merge)[1393]: Merged extensions into '/usr'. Sep 4 23:46:59.333486 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 4 23:46:59.348935 systemd[1]: Starting ensure-sysext.service... Sep 4 23:46:59.352557 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 4 23:46:59.392233 systemd[1]: Reload requested from client PID 1395 ('systemctl') (unit ensure-sysext.service)... Sep 4 23:46:59.392250 systemd[1]: Reloading... Sep 4 23:46:59.420752 systemd-tmpfiles[1396]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 4 23:46:59.421089 systemd-tmpfiles[1396]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 4 23:46:59.422162 systemd-tmpfiles[1396]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 4 23:46:59.422569 systemd-tmpfiles[1396]: ACLs are not supported, ignoring. Sep 4 23:46:59.422645 systemd-tmpfiles[1396]: ACLs are not supported, ignoring. Sep 4 23:46:59.436858 zram_generator::config[1428]: No configuration found. Sep 4 23:46:59.458624 systemd-tmpfiles[1396]: Detected autofs mount point /boot during canonicalization of boot. Sep 4 23:46:59.458638 systemd-tmpfiles[1396]: Skipping /boot Sep 4 23:46:59.470562 systemd-tmpfiles[1396]: Detected autofs mount point /boot during canonicalization of boot. Sep 4 23:46:59.470576 systemd-tmpfiles[1396]: Skipping /boot Sep 4 23:46:59.565940 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. Sep 4 23:46:59.666120 systemd[1]: Reloading finished in 273 ms. Sep 4 23:46:59.711489 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 4 23:46:59.732065 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 4 23:46:59.748801 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 4 23:46:59.754560 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 4 23:46:59.761065 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 4 23:46:59.766352 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 4 23:46:59.774147 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 4 23:46:59.774348 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 4 23:46:59.775479 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 4 23:46:59.780672 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 4 23:46:59.786466 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 4 23:46:59.790549 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 4 23:46:59.790674 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). Sep 4 23:46:59.790802 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 4 23:46:59.791944 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 4 23:46:59.792124 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 4 23:46:59.797351 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 4 23:46:59.797546 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 4 23:46:59.803174 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 4 23:46:59.803348 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 4 23:46:59.809833 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 4 23:46:59.824081 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 4 23:46:59.844306 systemd[1]: Finished ensure-sysext.service. Sep 4 23:46:59.849876 systemd[1]: Expecting device dev-ptp_hyperv.device - /dev/ptp_hyperv... Sep 4 23:46:59.854074 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 4 23:46:59.854307 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 4 23:46:59.860940 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 4 23:46:59.866800 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 4 23:46:59.871700 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 4 23:46:59.877255 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 4 23:46:59.881466 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 4 23:46:59.881522 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). Sep 4 23:46:59.884763 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 4 23:46:59.888969 systemd[1]: Reached target time-set.target - System Time Set. Sep 4 23:46:59.894713 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 4 23:46:59.899104 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 4 23:46:59.899825 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 4 23:46:59.900021 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 4 23:46:59.905263 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 4 23:46:59.905454 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 4 23:46:59.910341 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 4 23:46:59.910524 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 4 23:46:59.915989 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 4 23:46:59.916177 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 4 23:46:59.923866 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Sep 4 23:46:59.924895 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 4 23:46:59.924960 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 4 23:46:59.937806 kernel: mousedev: PS/2 mouse device common for all mice Sep 4 23:46:59.941995 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 23:46:59.946868 systemd[1]: Condition check resulted in dev-ptp_hyperv.device - /dev/ptp_hyperv being skipped. Sep 4 23:46:59.965632 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 4 23:46:59.966117 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 23:46:59.971991 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 4 23:46:59.972715 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 4 23:46:59.973823 kernel: hv_vmbus: registering driver hyperv_fb Sep 4 23:46:59.982835 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Sep 4 23:46:59.982892 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Sep 4 23:46:59.989405 kernel: Console: switching to colour dummy device 80x25 Sep 4 23:46:59.993559 kernel: Console: switching to colour frame buffer device 128x48 Sep 4 23:46:59.997809 kernel: hv_vmbus: registering driver hv_balloon Sep 4 23:46:59.997863 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Sep 4 23:46:59.999300 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 23:47:00.011412 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 4 23:47:00.011632 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 23:47:00.027916 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 23:47:00.049804 kernel: EDAC MC: Ver: 3.0.0 Sep 4 23:47:00.094768 augenrules[1593]: No rules Sep 4 23:47:00.095476 systemd[1]: audit-rules.service: Deactivated successfully. Sep 4 23:47:00.095726 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 4 23:47:00.118465 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 38 scanned by (udev-worker) (1520) Sep 4 23:47:00.144543 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 4 23:47:00.164651 systemd-networkd[1547]: lo: Link UP Sep 4 23:47:00.164663 systemd-networkd[1547]: lo: Gained carrier Sep 4 23:47:00.166110 systemd-networkd[1547]: Enumeration completed Sep 4 23:47:00.166494 systemd-networkd[1547]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 23:47:00.166505 systemd-networkd[1547]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 4 23:47:00.167064 systemd-networkd[1547]: eth0: Link UP Sep 4 23:47:00.167073 systemd-networkd[1547]: eth0: Gained carrier Sep 4 23:47:00.167087 systemd-networkd[1547]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 23:47:00.169386 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Sep 4 23:47:00.169601 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 4 23:47:00.183261 systemd-resolved[1490]: Positive Trust Anchors: Sep 4 23:47:00.183280 systemd-resolved[1490]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 4 23:47:00.183311 systemd-resolved[1490]: 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 Sep 4 23:47:00.188952 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 4 23:47:00.193796 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Sep 4 23:47:00.194974 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 4 23:47:00.195724 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Sep 4 23:47:00.198315 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Sep 4 23:47:00.222909 systemd-networkd[1547]: eth0: DHCPv4 address 10.200.8.22/24, gateway 10.200.8.1 acquired from 168.63.129.16 Sep 4 23:47:00.236945 systemd-resolved[1490]: Using system hostname 'ci-4230.2.2-n-ccd817eef4'. Sep 4 23:47:00.238774 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 4 23:47:00.239002 systemd[1]: Reached target network.target - Network. Sep 4 23:47:00.240336 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 4 23:47:00.267242 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Sep 4 23:47:00.318179 lvm[1666]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Sep 4 23:47:00.352651 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 4 23:47:00.371393 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Sep 4 23:47:00.371734 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 4 23:47:00.388944 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Sep 4 23:47:00.393678 lvm[1671]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Sep 4 23:47:00.427503 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Sep 4 23:47:01.188636 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 23:47:01.817924 systemd-networkd[1547]: eth0: Gained IPv6LL Sep 4 23:47:01.820243 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 4 23:47:01.825478 systemd[1]: Reached target network-online.target - Network is Online. Sep 4 23:47:02.044155 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 4 23:47:02.049551 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). Sep 4 23:47:06.508911 ldconfig[1359]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 4 23:47:06.520491 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 4 23:47:06.538944 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 4 23:47:06.559302 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 4 23:47:06.563984 systemd[1]: Reached target sysinit.target - System Initialization. Sep 4 23:47:06.568233 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 4 23:47:06.573197 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Sep 4 23:47:06.578382 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 4 23:47:06.582709 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 4 23:47:06.587700 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 4 23:47:06.592727 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 4 23:47:06.592752 systemd[1]: Reached target paths.target - Path Units. Sep 4 23:47:06.597292 systemd[1]: Reached target timers.target - Timer Units. Sep 4 23:47:06.625191 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 4 23:47:06.630856 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 4 23:47:06.638078 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Sep 4 23:47:06.643217 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Sep 4 23:47:06.648203 systemd[1]: Reached target ssh-access.target - SSH Access Available. Sep 4 23:47:06.654294 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 4 23:47:06.658732 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Sep 4 23:47:06.664001 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 4 23:47:06.668408 systemd[1]: Reached target sockets.target - Socket Units. Sep 4 23:47:06.672215 systemd[1]: Reached target basic.target - Basic System. Sep 4 23:47:06.675772 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 4 23:47:06.675832 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 4 23:47:06.696867 systemd[1]: Starting chronyd.service - NTP client/server... Sep 4 23:47:06.701839 systemd[1]: Starting containerd.service - containerd container runtime... Sep 4 23:47:06.707174 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Sep 4 23:47:06.712290 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 4 23:47:06.716850 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 4 23:47:06.721896 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 4 23:47:06.725858 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). Sep 4 23:47:06.725905 systemd[1]: hv_fcopy_daemon.service - Hyper-V FCOPY daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_fcopy). Sep 4 23:47:06.726916 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Sep 4 23:47:06.731022 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Sep 4 23:47:06.732073 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 4 23:47:06.737026 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 4 23:47:06.745054 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 4 23:47:06.745804 (chronyd)[1684]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Sep 4 23:47:06.746248 KVP[1693]: KVP starting; pid is:1693 Sep 4 23:47:06.748825 KVP[1693]: KVP LIC Version: 3.1 Sep 4 23:47:06.750256 jq[1691]: false Sep 4 23:47:06.750805 kernel: hv_utils: KVP IC version 4.0 Sep 4 23:47:06.751550 chronyd[1701]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) Sep 4 23:47:06.752887 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 4 23:47:06.759474 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 4 23:47:06.764574 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 4 23:47:06.765060 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 4 23:47:06.765643 systemd[1]: Starting update-engine.service - Update Engine... Sep 4 23:47:06.769922 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 4 23:47:06.776392 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 4 23:47:06.776604 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 4 23:47:06.780100 jq[1707]: true Sep 4 23:47:06.784396 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 4 23:47:06.784641 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 4 23:47:06.790447 (ntainerd)[1711]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 4 23:47:06.793299 jq[1710]: true Sep 4 23:47:06.797472 extend-filesystems[1692]: Found loop3 Sep 4 23:47:06.800257 extend-filesystems[1692]: Found loop4 Sep 4 23:47:06.800257 extend-filesystems[1692]: Found loop5 Sep 4 23:47:06.800257 extend-filesystems[1692]: Found sda Sep 4 23:47:06.800257 extend-filesystems[1692]: Found sda1 Sep 4 23:47:06.800257 extend-filesystems[1692]: Found sda2 Sep 4 23:47:06.800257 extend-filesystems[1692]: Found sda3 Sep 4 23:47:06.800257 extend-filesystems[1692]: Found usr Sep 4 23:47:06.800257 extend-filesystems[1692]: Found sda4 Sep 4 23:47:06.800257 extend-filesystems[1692]: Found sda6 Sep 4 23:47:06.800257 extend-filesystems[1692]: Found sda7 Sep 4 23:47:06.800257 extend-filesystems[1692]: Found sda9 Sep 4 23:47:06.800257 extend-filesystems[1692]: Checking size of /dev/sda9 Sep 4 23:47:06.797896 chronyd[1701]: Timezone right/UTC failed leap second check, ignoring Sep 4 23:47:06.798858 systemd[1]: Started chronyd.service - NTP client/server. Sep 4 23:47:06.798061 chronyd[1701]: Loaded seccomp filter (level 2) Sep 4 23:47:06.809891 systemd[1]: motdgen.service: Deactivated successfully. Sep 4 23:47:06.810117 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 4 23:47:06.861178 extend-filesystems[1692]: Old size kept for /dev/sda9 Sep 4 23:47:06.864674 extend-filesystems[1692]: Found sdb Sep 4 23:47:06.864674 extend-filesystems[1692]: Found sdb1 Sep 4 23:47:06.864674 extend-filesystems[1692]: Found sr0 Sep 4 23:47:06.861820 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 4 23:47:06.876526 update_engine[1705]: I20250904 23:47:06.876359 1705 main.cc:92] Flatcar Update Engine starting Sep 4 23:47:06.862055 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 4 23:47:06.906821 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 38 scanned by (udev-worker) (1758) Sep 4 23:47:06.929203 systemd-logind[1703]: New seat seat0. Sep 4 23:47:06.933325 systemd-logind[1703]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Sep 4 23:47:06.937655 systemd[1]: Started systemd-logind.service - User Login Management. Sep 4 23:47:06.952873 bash[1742]: Updated "/home/core/.ssh/authorized_keys" Sep 4 23:47:06.955012 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 4 23:47:06.961600 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Sep 4 23:47:06.987271 setup-nvidia[1695]: Downloading Flatcar Container Linux Developer Container for version: 4230.2.2+nightly-20250904-2100 Sep 4 23:47:06.995714 sshd_keygen[1741]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 4 23:47:07.017049 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 4 23:47:07.031060 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 4 23:47:07.035596 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Sep 4 23:47:07.039955 systemd[1]: issuegen.service: Deactivated successfully. Sep 4 23:47:07.040160 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 4 23:47:07.046087 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 4 23:47:07.057184 dbus-daemon[1687]: [system] SELinux support is enabled Sep 4 23:47:07.057358 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 4 23:47:07.059870 update_engine[1705]: I20250904 23:47:07.059813 1705 update_check_scheduler.cc:74] Next update check in 9m39s Sep 4 23:47:07.064887 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Sep 4 23:47:07.070257 systemd[1]: Started update-engine.service - Update Engine. Sep 4 23:47:07.070570 dbus-daemon[1687]: [system] Successfully activated service 'org.freedesktop.systemd1' Sep 4 23:47:07.074746 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 4 23:47:07.081985 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 4 23:47:07.087042 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Sep 4 23:47:07.091649 systemd[1]: Reached target getty.target - Login Prompts. Sep 4 23:47:07.095599 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). Sep 4 23:47:07.095742 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 4 23:47:07.100924 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). Sep 4 23:47:07.101060 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 4 23:47:07.116102 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 4 23:47:07.116919 coreos-metadata[1686]: Sep 04 23:47:07.116 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Sep 4 23:47:07.119358 coreos-metadata[1686]: Sep 04 23:47:07.119 INFO Fetch successful Sep 4 23:47:07.119517 coreos-metadata[1686]: Sep 04 23:47:07.119 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Sep 4 23:47:07.124344 coreos-metadata[1686]: Sep 04 23:47:07.124 INFO Fetch successful Sep 4 23:47:07.124643 coreos-metadata[1686]: Sep 04 23:47:07.124 INFO Fetching http://168.63.129.16/machine/a6975a21-3b2e-4d09-9b01-defd98820b3d/860dfe4d%2D628f%2D4cd0%2Da16c%2Da5568ae495b4.%5Fci%2D4230.2.2%2Dn%2Dccd817eef4?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Sep 4 23:47:07.126251 coreos-metadata[1686]: Sep 04 23:47:07.126 INFO Fetch successful Sep 4 23:47:07.126392 coreos-metadata[1686]: Sep 04 23:47:07.126 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Sep 4 23:47:07.134133 coreos-metadata[1686]: Sep 04 23:47:07.134 INFO Fetch successful Sep 4 23:47:07.164647 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Sep 4 23:47:07.169542 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 4 23:47:07.309686 locksmithd[1841]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 4 23:47:07.928360 containerd[1711]: time="2025-09-04T23:47:07.928272200Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Sep 4 23:47:07.946905 containerd[1711]: time="2025-09-04T23:47:07.946871400Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Sep 4 23:47:07.948422 containerd[1711]: time="2025-09-04T23:47:07.948390100Z" 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.103-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Sep 4 23:47:07.948459 containerd[1711]: time="2025-09-04T23:47:07.948420800Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Sep 4 23:47:07.948459 containerd[1711]: time="2025-09-04T23:47:07.948436500Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Sep 4 23:47:07.948607 containerd[1711]: time="2025-09-04T23:47:07.948586600Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Sep 4 23:47:07.948644 containerd[1711]: time="2025-09-04T23:47:07.948610100Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Sep 4 23:47:07.948695 containerd[1711]: time="2025-09-04T23:47:07.948675100Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Sep 4 23:47:07.948727 containerd[1711]: time="2025-09-04T23:47:07.948693600Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Sep 4 23:47:07.948912 containerd[1711]: time="2025-09-04T23:47:07.948889600Z" 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 Sep 4 23:47:07.948941 containerd[1711]: time="2025-09-04T23:47:07.948911400Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Sep 4 23:47:07.948941 containerd[1711]: time="2025-09-04T23:47:07.948927000Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Sep 4 23:47:07.948941 containerd[1711]: time="2025-09-04T23:47:07.948937600Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Sep 4 23:47:07.949046 containerd[1711]: time="2025-09-04T23:47:07.949026600Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Sep 4 23:47:07.949270 containerd[1711]: time="2025-09-04T23:47:07.949250600Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Sep 4 23:47:07.949398 containerd[1711]: time="2025-09-04T23:47:07.949377600Z" 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 Sep 4 23:47:07.949430 containerd[1711]: time="2025-09-04T23:47:07.949398100Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Sep 4 23:47:07.949498 containerd[1711]: time="2025-09-04T23:47:07.949480300Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Sep 4 23:47:07.949555 containerd[1711]: time="2025-09-04T23:47:07.949537500Z" level=info msg="metadata content store policy set" policy=shared Sep 4 23:47:07.964418 containerd[1711]: time="2025-09-04T23:47:07.964384700Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Sep 4 23:47:07.964471 containerd[1711]: time="2025-09-04T23:47:07.964426900Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Sep 4 23:47:07.964471 containerd[1711]: time="2025-09-04T23:47:07.964446800Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Sep 4 23:47:07.964521 containerd[1711]: time="2025-09-04T23:47:07.964501700Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Sep 4 23:47:07.964580 containerd[1711]: time="2025-09-04T23:47:07.964556300Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Sep 4 23:47:07.964853 containerd[1711]: time="2025-09-04T23:47:07.964761300Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Sep 4 23:47:07.965092 containerd[1711]: time="2025-09-04T23:47:07.965064300Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Sep 4 23:47:07.965376 containerd[1711]: time="2025-09-04T23:47:07.965201200Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Sep 4 23:47:07.965376 containerd[1711]: time="2025-09-04T23:47:07.965221200Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Sep 4 23:47:07.965376 containerd[1711]: time="2025-09-04T23:47:07.965235400Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Sep 4 23:47:07.965376 containerd[1711]: time="2025-09-04T23:47:07.965267000Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Sep 4 23:47:07.965376 containerd[1711]: time="2025-09-04T23:47:07.965285300Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Sep 4 23:47:07.965376 containerd[1711]: time="2025-09-04T23:47:07.965298100Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Sep 4 23:47:07.965376 containerd[1711]: time="2025-09-04T23:47:07.965311900Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Sep 4 23:47:07.965376 containerd[1711]: time="2025-09-04T23:47:07.965326700Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Sep 4 23:47:07.965376 containerd[1711]: time="2025-09-04T23:47:07.965338900Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Sep 4 23:47:07.965376 containerd[1711]: time="2025-09-04T23:47:07.965350500Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Sep 4 23:47:07.965376 containerd[1711]: time="2025-09-04T23:47:07.965360900Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Sep 4 23:47:07.965376 containerd[1711]: time="2025-09-04T23:47:07.965379200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Sep 4 23:47:07.965696 containerd[1711]: time="2025-09-04T23:47:07.965392300Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Sep 4 23:47:07.965696 containerd[1711]: time="2025-09-04T23:47:07.965409200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Sep 4 23:47:07.965696 containerd[1711]: time="2025-09-04T23:47:07.965422800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Sep 4 23:47:07.965696 containerd[1711]: time="2025-09-04T23:47:07.965433900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Sep 4 23:47:07.965696 containerd[1711]: time="2025-09-04T23:47:07.965445800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Sep 4 23:47:07.965696 containerd[1711]: time="2025-09-04T23:47:07.965456500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Sep 4 23:47:07.965696 containerd[1711]: time="2025-09-04T23:47:07.965468100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Sep 4 23:47:07.965696 containerd[1711]: time="2025-09-04T23:47:07.965480000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Sep 4 23:47:07.965696 containerd[1711]: time="2025-09-04T23:47:07.965494000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Sep 4 23:47:07.965696 containerd[1711]: time="2025-09-04T23:47:07.965504200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Sep 4 23:47:07.965696 containerd[1711]: time="2025-09-04T23:47:07.965514900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Sep 4 23:47:07.965696 containerd[1711]: time="2025-09-04T23:47:07.965526700Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Sep 4 23:47:07.965696 containerd[1711]: time="2025-09-04T23:47:07.965539300Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Sep 4 23:47:07.965696 containerd[1711]: time="2025-09-04T23:47:07.965557900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Sep 4 23:47:07.965696 containerd[1711]: time="2025-09-04T23:47:07.965571000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Sep 4 23:47:07.966158 containerd[1711]: time="2025-09-04T23:47:07.965580700Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Sep 4 23:47:07.966158 containerd[1711]: time="2025-09-04T23:47:07.965628500Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Sep 4 23:47:07.966158 containerd[1711]: time="2025-09-04T23:47:07.965647500Z" 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 Sep 4 23:47:07.966158 containerd[1711]: time="2025-09-04T23:47:07.965659800Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Sep 4 23:47:07.966158 containerd[1711]: time="2025-09-04T23:47:07.965670600Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Sep 4 23:47:07.966158 containerd[1711]: time="2025-09-04T23:47:07.965679700Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Sep 4 23:47:07.966158 containerd[1711]: time="2025-09-04T23:47:07.965691000Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Sep 4 23:47:07.966158 containerd[1711]: time="2025-09-04T23:47:07.965701500Z" level=info msg="NRI interface is disabled by configuration." Sep 4 23:47:07.966158 containerd[1711]: time="2025-09-04T23:47:07.965711300Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Sep 4 23:47:07.966425 containerd[1711]: time="2025-09-04T23:47:07.965973800Z" 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}" Sep 4 23:47:07.966425 containerd[1711]: time="2025-09-04T23:47:07.966018900Z" level=info msg="Connect containerd service" Sep 4 23:47:07.966425 containerd[1711]: time="2025-09-04T23:47:07.966059500Z" level=info msg="using legacy CRI server" Sep 4 23:47:07.966425 containerd[1711]: time="2025-09-04T23:47:07.966071300Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 4 23:47:07.966425 containerd[1711]: time="2025-09-04T23:47:07.966382200Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Sep 4 23:47:07.967327 containerd[1711]: time="2025-09-04T23:47:07.967288500Z" 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" Sep 4 23:47:07.967453 containerd[1711]: time="2025-09-04T23:47:07.967416600Z" level=info msg="Start subscribing containerd event" Sep 4 23:47:07.967483 containerd[1711]: time="2025-09-04T23:47:07.967466400Z" level=info msg="Start recovering state" Sep 4 23:47:07.967542 containerd[1711]: time="2025-09-04T23:47:07.967524800Z" level=info msg="Start event monitor" Sep 4 23:47:07.967582 containerd[1711]: time="2025-09-04T23:47:07.967541500Z" level=info msg="Start snapshots syncer" Sep 4 23:47:07.967582 containerd[1711]: time="2025-09-04T23:47:07.967550300Z" level=info msg="Start cni network conf syncer for default" Sep 4 23:47:07.967582 containerd[1711]: time="2025-09-04T23:47:07.967557000Z" level=info msg="Start streaming server" Sep 4 23:47:07.967647 containerd[1711]: time="2025-09-04T23:47:07.967602000Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 4 23:47:07.967682 containerd[1711]: time="2025-09-04T23:47:07.967667000Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 4 23:47:07.967766 containerd[1711]: time="2025-09-04T23:47:07.967733800Z" level=info msg="containerd successfully booted in 0.040390s" Sep 4 23:47:07.967853 systemd[1]: Started containerd.service - containerd container runtime. Sep 4 23:47:08.145852 setup-nvidia[1862]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 4 23:47:08.146157 setup-nvidia[1862]: Dload Upload Total Spent Left Speed Sep 4 23:47:09.460054 waagent[1836]: 2025-09-04T23:47:09.459956Z INFO Daemon Daemon Azure Linux Agent Version: 2.9.1.1 Sep 4 23:47:09.464307 waagent[1836]: 2025-09-04T23:47:09.464222Z INFO Daemon Daemon OS: flatcar 4230.2.2 Sep 4 23:47:09.467642 waagent[1836]: 2025-09-04T23:47:09.467588Z INFO Daemon Daemon Python: 3.11.11 Sep 4 23:47:09.470924 waagent[1836]: 2025-09-04T23:47:09.470871Z INFO Daemon Daemon Run daemon Sep 4 23:47:09.474040 waagent[1836]: 2025-09-04T23:47:09.473991Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4230.2.2' Sep 4 23:47:09.480444 waagent[1836]: 2025-09-04T23:47:09.480390Z INFO Daemon Daemon Using waagent for provisioning Sep 4 23:47:09.484306 waagent[1836]: 2025-09-04T23:47:09.484255Z INFO Daemon Daemon Activate resource disk Sep 4 23:47:09.487894 waagent[1836]: 2025-09-04T23:47:09.487774Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Sep 4 23:47:09.495504 waagent[1836]: 2025-09-04T23:47:09.495444Z INFO Daemon Daemon Found device: sdb Sep 4 23:47:09.504507 waagent[1836]: 2025-09-04T23:47:09.504450Z INFO Daemon Daemon Examining partition table Sep 4 23:47:09.570990 waagent[1836]: 2025-09-04T23:47:09.570933Z INFO Daemon Daemon GPT not detected, determining filesystem Sep 4 23:47:09.606274 waagent[1836]: 2025-09-04T23:47:09.606200Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Sep 4 23:47:09.610840 waagent[1836]: 2025-09-04T23:47:09.610778Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Sep 4 23:47:09.963815 kernel: sdb: sdb1 Sep 4 23:47:09.968803 kernel: sdb: sdb1 Sep 4 23:47:09.980656 waagent[1836]: 2025-09-04T23:47:09.980568Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Sep 4 23:47:09.994805 kernel: sdb: sdb1 Sep 4 23:47:09.994992 waagent[1836]: 2025-09-04T23:47:09.994936Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Sep 4 23:47:14.759705 login[1840]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Sep 4 23:47:14.857104 login[1839]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Sep 4 23:47:14.868223 systemd-logind[1703]: New session 2 of user core. Sep 4 23:47:14.869315 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 4 23:47:14.879989 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 4 23:47:14.964617 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 4 23:47:14.980032 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 4 23:47:15.064196 (systemd)[1940]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 4 23:47:15.066733 systemd-logind[1703]: New session c1 of user core. Sep 4 23:47:15.761793 login[1840]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Sep 4 23:47:15.767398 systemd-logind[1703]: New session 1 of user core. Sep 4 23:47:15.786828 waagent[1836]: 2025-09-04T23:47:15.786727Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Sep 4 23:47:15.804200 waagent[1836]: 2025-09-04T23:47:15.804136Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Sep 4 23:47:15.804806 kernel: EXT4-fs (sdb1): mounted filesystem c40e29a6-318a-4e61-bb36-fab5752a5771 r/w with ordered data mode. Quota mode: none. Sep 4 23:47:15.804914 waagent[1836]: 2025-09-04T23:47:15.804864Z INFO Daemon Daemon Clean protocol and wireserver endpoint Sep 4 23:47:15.805209 waagent[1836]: 2025-09-04T23:47:15.805167Z INFO Daemon Daemon Running default provisioning handler Sep 4 23:47:15.818017 waagent[1836]: 2025-09-04T23:47:15.816849Z 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. Sep 4 23:47:15.818885 waagent[1836]: 2025-09-04T23:47:15.818834Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Sep 4 23:47:15.819780 waagent[1836]: 2025-09-04T23:47:15.819731Z INFO Daemon Daemon cloud-init is enabled: False Sep 4 23:47:15.821206 waagent[1836]: 2025-09-04T23:47:15.821159Z INFO Daemon Daemon Copying ovf-env.xml Sep 4 23:47:15.943775 waagent[1836]: 2025-09-04T23:47:15.943684Z INFO Daemon Daemon Successfully mounted dvd Sep 4 23:47:15.970679 waagent[1836]: 2025-09-04T23:47:15.970614Z INFO Daemon Daemon Detect protocol endpoint Sep 4 23:47:15.970678 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Sep 4 23:47:15.974605 waagent[1836]: 2025-09-04T23:47:15.974551Z INFO Daemon Daemon Clean protocol and wireserver endpoint Sep 4 23:47:15.980274 waagent[1836]: 2025-09-04T23:47:15.980215Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Sep 4 23:47:15.985272 waagent[1836]: 2025-09-04T23:47:15.985226Z INFO Daemon Daemon Test for route to 168.63.129.16 Sep 4 23:47:15.989522 waagent[1836]: 2025-09-04T23:47:15.989476Z INFO Daemon Daemon Route to 168.63.129.16 exists Sep 4 23:47:15.993605 waagent[1836]: 2025-09-04T23:47:15.993556Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Sep 4 23:47:16.014297 systemd[1940]: Queued start job for default target default.target. Sep 4 23:47:16.036332 systemd[1940]: Created slice app.slice - User Application Slice. Sep 4 23:47:16.045079 waagent[1836]: 2025-09-04T23:47:16.032560Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Sep 4 23:47:16.045079 waagent[1836]: 2025-09-04T23:47:16.032978Z INFO Daemon Daemon Wire protocol version:2012-11-30 Sep 4 23:47:16.045079 waagent[1836]: 2025-09-04T23:47:16.033505Z INFO Daemon Daemon Server preferred version:2015-04-05 Sep 4 23:47:16.036358 systemd[1940]: Reached target paths.target - Paths. Sep 4 23:47:16.036409 systemd[1940]: Reached target timers.target - Timers. Sep 4 23:47:16.037906 systemd[1940]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 4 23:47:16.049830 systemd[1940]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 4 23:47:16.049958 systemd[1940]: Reached target sockets.target - Sockets. Sep 4 23:47:16.050016 systemd[1940]: Reached target basic.target - Basic System. Sep 4 23:47:16.050066 systemd[1940]: Reached target default.target - Main User Target. Sep 4 23:47:16.050106 systemd[1940]: Startup finished in 976ms. Sep 4 23:47:16.050242 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 4 23:47:16.051927 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 4 23:47:16.052646 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 4 23:47:16.140177 waagent[1836]: 2025-09-04T23:47:16.140078Z INFO Daemon Daemon Initializing goal state during protocol detection Sep 4 23:47:16.140449 waagent[1836]: 2025-09-04T23:47:16.140399Z INFO Daemon Daemon Forcing an update of the goal state. Sep 4 23:47:16.144989 waagent[1836]: 2025-09-04T23:47:16.144935Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Sep 4 23:47:16.173468 waagent[1836]: 2025-09-04T23:47:16.173414Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.175 Sep 4 23:47:16.174065 waagent[1836]: 2025-09-04T23:47:16.174016Z INFO Daemon Sep 4 23:47:16.174405 waagent[1836]: 2025-09-04T23:47:16.174366Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 8e6bc322-541d-4232-8869-51850022c98d eTag: 8599378597017558775 source: Fabric] Sep 4 23:47:16.176096 waagent[1836]: 2025-09-04T23:47:16.176049Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Sep 4 23:47:16.177157 waagent[1836]: 2025-09-04T23:47:16.177055Z INFO Daemon Sep 4 23:47:16.177281 waagent[1836]: 2025-09-04T23:47:16.177239Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Sep 4 23:47:16.182407 waagent[1836]: 2025-09-04T23:47:16.182368Z INFO Daemon Daemon Downloading artifacts profile blob Sep 4 23:47:16.304386 waagent[1836]: 2025-09-04T23:47:16.304329Z INFO Daemon Downloaded certificate {'thumbprint': '9E3C7EFCD43EB15E63B8A25D35DFADF2E9FBC3AF', 'hasPrivateKey': True} Sep 4 23:47:16.305053 waagent[1836]: 2025-09-04T23:47:16.305007Z INFO Daemon Fetch goal state completed Sep 4 23:47:16.341390 waagent[1836]: 2025-09-04T23:47:16.341322Z INFO Daemon Daemon Starting provisioning Sep 4 23:47:16.341635 waagent[1836]: 2025-09-04T23:47:16.341557Z INFO Daemon Daemon Handle ovf-env.xml. Sep 4 23:47:16.343044 waagent[1836]: 2025-09-04T23:47:16.342987Z INFO Daemon Daemon Set hostname [ci-4230.2.2-n-ccd817eef4] Sep 4 23:47:16.377316 waagent[1836]: 2025-09-04T23:47:16.377259Z INFO Daemon Daemon Publish hostname [ci-4230.2.2-n-ccd817eef4] Sep 4 23:47:16.377673 waagent[1836]: 2025-09-04T23:47:16.377626Z INFO Daemon Daemon Examine /proc/net/route for primary interface Sep 4 23:47:16.378352 waagent[1836]: 2025-09-04T23:47:16.378301Z INFO Daemon Daemon Primary interface is [eth0] Sep 4 23:47:16.400147 systemd-networkd[1547]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 23:47:16.400157 systemd-networkd[1547]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 4 23:47:16.400182 systemd-networkd[1547]: eth0: DHCP lease lost Sep 4 23:47:16.400574 waagent[1836]: 2025-09-04T23:47:16.400518Z INFO Daemon Daemon Create user account if not exists Sep 4 23:47:16.401654 waagent[1836]: 2025-09-04T23:47:16.401607Z INFO Daemon Daemon User core already exists, skip useradd Sep 4 23:47:16.403030 waagent[1836]: 2025-09-04T23:47:16.402988Z INFO Daemon Daemon Configure sudoer Sep 4 23:47:16.404029 waagent[1836]: 2025-09-04T23:47:16.403983Z INFO Daemon Daemon Configure sshd Sep 4 23:47:16.404671 waagent[1836]: 2025-09-04T23:47:16.404620Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Sep 4 23:47:16.405887 waagent[1836]: 2025-09-04T23:47:16.405845Z INFO Daemon Daemon Deploy ssh public key. Sep 4 23:47:16.479833 systemd-networkd[1547]: eth0: DHCPv4 address 10.200.8.22/24, gateway 10.200.8.1 acquired from 168.63.129.16 Sep 4 23:47:17.558492 waagent[1836]: 2025-09-04T23:47:17.558417Z INFO Daemon Daemon Provisioning complete Sep 4 23:47:17.570669 waagent[1836]: 2025-09-04T23:47:17.570611Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Sep 4 23:47:17.575839 waagent[1836]: 2025-09-04T23:47:17.575770Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Sep 4 23:47:17.583107 waagent[1836]: 2025-09-04T23:47:17.583060Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.9.1.1 is the most current agent Sep 4 23:47:17.737636 waagent[2000]: 2025-09-04T23:47:17.737549Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.9.1.1) Sep 4 23:47:17.737985 waagent[2000]: 2025-09-04T23:47:17.737701Z INFO ExtHandler ExtHandler OS: flatcar 4230.2.2 Sep 4 23:47:17.737985 waagent[2000]: 2025-09-04T23:47:17.737797Z INFO ExtHandler ExtHandler Python: 3.11.11 Sep 4 23:47:17.823068 waagent[2000]: 2025-09-04T23:47:17.822928Z INFO ExtHandler ExtHandler Distro: flatcar-4230.2.2; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.11; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; Sep 4 23:47:17.823237 waagent[2000]: 2025-09-04T23:47:17.823191Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 4 23:47:17.823323 waagent[2000]: 2025-09-04T23:47:17.823284Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 4 23:47:17.831068 waagent[2000]: 2025-09-04T23:47:17.831013Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Sep 4 23:47:17.835772 waagent[2000]: 2025-09-04T23:47:17.835727Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.175 Sep 4 23:47:17.836238 waagent[2000]: 2025-09-04T23:47:17.836192Z INFO ExtHandler Sep 4 23:47:17.836322 waagent[2000]: 2025-09-04T23:47:17.836284Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 693c9753-7595-47f9-8090-7f152949f41b eTag: 8599378597017558775 source: Fabric] Sep 4 23:47:17.836646 waagent[2000]: 2025-09-04T23:47:17.836602Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Sep 4 23:47:17.837304 waagent[2000]: 2025-09-04T23:47:17.837256Z INFO ExtHandler Sep 4 23:47:17.837534 waagent[2000]: 2025-09-04T23:47:17.837349Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Sep 4 23:47:17.840636 waagent[2000]: 2025-09-04T23:47:17.840595Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Sep 4 23:47:17.914797 waagent[2000]: 2025-09-04T23:47:17.914711Z INFO ExtHandler Downloaded certificate {'thumbprint': '9E3C7EFCD43EB15E63B8A25D35DFADF2E9FBC3AF', 'hasPrivateKey': True} Sep 4 23:47:17.915329 waagent[2000]: 2025-09-04T23:47:17.915281Z INFO ExtHandler Fetch goal state completed Sep 4 23:47:17.927181 waagent[2000]: 2025-09-04T23:47:17.927122Z INFO ExtHandler ExtHandler WALinuxAgent-2.9.1.1 running as process 2000 Sep 4 23:47:17.927327 waagent[2000]: 2025-09-04T23:47:17.927284Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Sep 4 23:47:17.929193 waagent[2000]: 2025-09-04T23:47:17.929137Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '4230.2.2', '', 'Flatcar Container Linux by Kinvolk'] Sep 4 23:47:17.929562 waagent[2000]: 2025-09-04T23:47:17.929515Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Sep 4 23:47:17.981200 waagent[2000]: 2025-09-04T23:47:17.981158Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Sep 4 23:47:17.981385 waagent[2000]: 2025-09-04T23:47:17.981342Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Sep 4 23:47:17.987957 waagent[2000]: 2025-09-04T23:47:17.987919Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Sep 4 23:47:17.995236 systemd[1]: Reload requested from client PID 2013 ('systemctl') (unit waagent.service)... Sep 4 23:47:17.995252 systemd[1]: Reloading... Sep 4 23:47:18.056823 zram_generator::config[2056]: No configuration found. Sep 4 23:47:18.194388 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. Sep 4 23:47:18.340962 systemd[1]: Reloading finished in 345 ms. Sep 4 23:47:18.358436 waagent[2000]: 2025-09-04T23:47:18.358345Z INFO ExtHandler ExtHandler Executing systemctl daemon-reload for setting up waagent-network-setup.service Sep 4 23:47:18.364127 systemd[1]: Reload requested from client PID 2110 ('systemctl') (unit waagent.service)... Sep 4 23:47:18.364144 systemd[1]: Reloading... Sep 4 23:47:18.423819 zram_generator::config[2151]: No configuration found. Sep 4 23:47:18.558355 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. Sep 4 23:47:18.710004 systemd[1]: Reloading finished in 345 ms. Sep 4 23:47:18.722431 waagent[2000]: 2025-09-04T23:47:18.722343Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Sep 4 23:47:18.722542 waagent[2000]: 2025-09-04T23:47:18.722502Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Sep 4 23:47:19.182254 waagent[2000]: 2025-09-04T23:47:19.182158Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Sep 4 23:47:19.182827 waagent[2000]: 2025-09-04T23:47:19.182750Z 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] Sep 4 23:47:19.183607 waagent[2000]: 2025-09-04T23:47:19.183554Z INFO ExtHandler ExtHandler Starting env monitor service. Sep 4 23:47:19.184126 waagent[2000]: 2025-09-04T23:47:19.184073Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 4 23:47:19.184165 waagent[2000]: 2025-09-04T23:47:19.184124Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Sep 4 23:47:19.184484 waagent[2000]: 2025-09-04T23:47:19.184440Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 4 23:47:19.184570 waagent[2000]: 2025-09-04T23:47:19.184520Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 4 23:47:19.185117 waagent[2000]: 2025-09-04T23:47:19.185063Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Sep 4 23:47:19.185223 waagent[2000]: 2025-09-04T23:47:19.185160Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Sep 4 23:47:19.185291 waagent[2000]: 2025-09-04T23:47:19.185243Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Sep 4 23:47:19.185359 waagent[2000]: 2025-09-04T23:47:19.185314Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 4 23:47:19.185927 waagent[2000]: 2025-09-04T23:47:19.185876Z INFO EnvHandler ExtHandler Configure routes Sep 4 23:47:19.186016 waagent[2000]: 2025-09-04T23:47:19.185967Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Sep 4 23:47:19.186016 waagent[2000]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Sep 4 23:47:19.186016 waagent[2000]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Sep 4 23:47:19.186016 waagent[2000]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Sep 4 23:47:19.186016 waagent[2000]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Sep 4 23:47:19.186016 waagent[2000]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Sep 4 23:47:19.186016 waagent[2000]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Sep 4 23:47:19.186439 waagent[2000]: 2025-09-04T23:47:19.186369Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Sep 4 23:47:19.186515 waagent[2000]: 2025-09-04T23:47:19.186476Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Sep 4 23:47:19.186673 waagent[2000]: 2025-09-04T23:47:19.186629Z INFO EnvHandler ExtHandler Gateway:None Sep 4 23:47:19.187037 waagent[2000]: 2025-09-04T23:47:19.186994Z INFO EnvHandler ExtHandler Routes:None Sep 4 23:47:19.187509 waagent[2000]: 2025-09-04T23:47:19.187458Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Sep 4 23:47:19.192617 waagent[2000]: 2025-09-04T23:47:19.192544Z INFO ExtHandler ExtHandler Sep 4 23:47:19.192890 waagent[2000]: 2025-09-04T23:47:19.192841Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: aaa083e6-6714-450e-a4d4-f0283c74e24a correlation 859baa90-32c7-498a-8abb-2c6c8e1973d1 created: 2025-09-04T23:45:45.480400Z] Sep 4 23:47:19.194177 waagent[2000]: 2025-09-04T23:47:19.194125Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Sep 4 23:47:19.195707 waagent[2000]: 2025-09-04T23:47:19.195662Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Sep 4 23:47:19.262043 waagent[2000]: 2025-09-04T23:47:19.261979Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.9.1.1 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 6AF59C30-69B0-4C53-AF25-13695AA64ED8;DroppedPackets: 0;UpdateGSErrors: 0;AutoUpdate: 0] Sep 4 23:47:19.293673 waagent[2000]: 2025-09-04T23:47:19.293607Z INFO MonitorHandler ExtHandler Network interfaces: Sep 4 23:47:19.293673 waagent[2000]: Executing ['ip', '-a', '-o', 'link']: Sep 4 23:47:19.293673 waagent[2000]: 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 Sep 4 23:47:19.293673 waagent[2000]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:ed:8d:78:07:a7 brd ff:ff:ff:ff:ff:ff Sep 4 23:47:19.293673 waagent[2000]: Executing ['ip', '-4', '-a', '-o', 'address']: Sep 4 23:47:19.293673 waagent[2000]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Sep 4 23:47:19.293673 waagent[2000]: 2: eth0 inet 10.200.8.22/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Sep 4 23:47:19.293673 waagent[2000]: Executing ['ip', '-6', '-a', '-o', 'address']: Sep 4 23:47:19.293673 waagent[2000]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Sep 4 23:47:19.293673 waagent[2000]: 2: eth0 inet6 fe80::7eed:8dff:fe78:7a7/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Sep 4 23:47:19.395247 waagent[2000]: 2025-09-04T23:47:19.395162Z INFO EnvHandler ExtHandler Successfully added Azure fabric firewall rules. Current Firewall rules: Sep 4 23:47:19.395247 waagent[2000]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Sep 4 23:47:19.395247 waagent[2000]: pkts bytes target prot opt in out source destination Sep 4 23:47:19.395247 waagent[2000]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Sep 4 23:47:19.395247 waagent[2000]: pkts bytes target prot opt in out source destination Sep 4 23:47:19.395247 waagent[2000]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Sep 4 23:47:19.395247 waagent[2000]: pkts bytes target prot opt in out source destination Sep 4 23:47:19.395247 waagent[2000]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Sep 4 23:47:19.395247 waagent[2000]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Sep 4 23:47:19.395247 waagent[2000]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Sep 4 23:47:19.399085 waagent[2000]: 2025-09-04T23:47:19.399026Z INFO EnvHandler ExtHandler Current Firewall rules: Sep 4 23:47:19.399085 waagent[2000]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Sep 4 23:47:19.399085 waagent[2000]: pkts bytes target prot opt in out source destination Sep 4 23:47:19.399085 waagent[2000]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Sep 4 23:47:19.399085 waagent[2000]: pkts bytes target prot opt in out source destination Sep 4 23:47:19.399085 waagent[2000]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Sep 4 23:47:19.399085 waagent[2000]: pkts bytes target prot opt in out source destination Sep 4 23:47:19.399085 waagent[2000]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Sep 4 23:47:19.399085 waagent[2000]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Sep 4 23:47:19.399085 waagent[2000]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Sep 4 23:47:19.399350 waagent[2000]: 2025-09-04T23:47:19.399308Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Sep 4 23:47:19.399513 waagent[2000]: 2025-09-04T23:47:19.399475Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Sep 4 23:47:30.576550 chronyd[1701]: Selected source PHC0 Sep 4 23:47:32.574638 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 4 23:47:32.584075 systemd[1]: Started sshd@0-10.200.8.22:22-10.200.16.10:40612.service - OpenSSH per-connection server daemon (10.200.16.10:40612). Sep 4 23:47:33.497494 sshd[2241]: Accepted publickey for core from 10.200.16.10 port 40612 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:47:33.498834 sshd-session[2241]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:47:33.503059 systemd-logind[1703]: New session 3 of user core. Sep 4 23:47:33.513917 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 4 23:47:34.045472 systemd[1]: Started sshd@1-10.200.8.22:22-10.200.16.10:40622.service - OpenSSH per-connection server daemon (10.200.16.10:40622). Sep 4 23:47:34.675145 sshd[2246]: Accepted publickey for core from 10.200.16.10 port 40622 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:47:34.676439 sshd-session[2246]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:47:34.680617 systemd-logind[1703]: New session 4 of user core. Sep 4 23:47:34.702918 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 4 23:47:34.936884 setup-nvidia[1862]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 540M 0 432k 0 0 353k 0 0:26:06 0:00:01 0:26:05 353k\u000d 2 540M 2 15.5M 0 0 7213k 0 0:01:16 0:00:02 0:01:14 7212k\u000d 7 540M 7 39.1M 0 0 12.1M 0 0:00:44 0:00:03 0:00:41 12.1M\u000d 11 540M 11 62.6M 0 0 14.8M 0 0:00:36 0:00:04 0:00:32 14.8M\u000d 15 540M 15 84.3M 0 0 16.2M 0 0:00:33 0:00:05 0:00:28 17.0M\u000d 19 540M 19 104M 0 0 16.9M 0 0:00:31 0:00:06 0:00:25 20.9M\u000d 23 540M 23 126M 0 0 17.4M 0 0:00:30 0:00:07 0:00:23 22.0M\u000d 27 540M 27 148M 0 0 17.9M 0 0:00:30 0:00:08 0:00:22 21.7M\u000d 31 540M 31 169M 0 0 18.4M 0 0:00:29 0:00:09 0:00:20 21.3M\u000d 35 540M 35 190M 0 0 18.6M 0 0:00:28 0:00:10 0:00:18 21.2M\u000d 39 540M 39 214M 0 0 19.1M 0 0:00:28 0:00:11 0:00:17 21.9M\u000d 42 540M 42 230M 0 0 18.7M 0 0:00:28 0:00:12 0:00:16 20.6M\u000d 46 540M 46 252M 0 0 19.0M 0 0:00:28 0:00:13 0:00:15 20.7M\u000d 50 540M 50 273M 0 0 19.1M 0 0:00:28 0:00:14 0:00:14 20.6M\u000d 54 540M 54 294M 0 0 19.2M 0 0:00:28 0:00:15 0:00:13 20.4M\u000d 58 540M 58 316M 0 0 19.4M 0 0:00:27 0:00:16 0:00:11 20.1M\u000d 62 540M 62 337M 0 0 19.5M 0 0:00:27 0:00:17 0:00:10 21.4M\u000d 66 540M 66 360M 0 0 19.7M 0 0:00:27 0:00:18 0:00:09 21.6M\u000d 70 540M 70 382M 0 0 19.8M 0 0:00:27 0:00:19 0:00:08 21.8M\u000d 74 540M 74 403M 0 0 19.9M 0 0:00:27 0:00:20 0:00:07 22.0M\u000d 78 540M 78 425M 0 0 20.0M 0 0:00:26 0:00:21 0:00:05 22.0M\u000d 82 540M 82 446M 0 0 20.0M 0 0:00:26 0:00:22 0:00:04 21.9M\u000d 86 540M 86 466M 0 0 20.0M 0 0:00:26 0:00:23 0:00:03 21.3M\u000d 90 540M 90 487M 0 0 20.1M 0 0:00:26 0:00:24 0:00:02 21.0M\u000d 94 540M 94 511M 0 0 20.2M 0 0:00:26 0:00:25 0:00:01 21.5M\u000d 98 540M 98 533M 0 0 20.3M 0 0:00:26 0:00:26 --:--:-- 21.6M\u000d100 540M 100 540M 0 0 20.1M 0 0:00:26 0:00:26 --:--:-- 20.6M Sep 4 23:47:35.121909 sshd[2248]: Connection closed by 10.200.16.10 port 40622 Sep 4 23:47:35.122324 sshd-session[2246]: pam_unix(sshd:session): session closed for user core Sep 4 23:47:35.128254 systemd[1]: sshd@1-10.200.8.22:22-10.200.16.10:40622.service: Deactivated successfully. Sep 4 23:47:35.130016 systemd[1]: session-4.scope: Deactivated successfully. Sep 4 23:47:35.131244 systemd-logind[1703]: Session 4 logged out. Waiting for processes to exit. Sep 4 23:47:35.133592 systemd-logind[1703]: Removed session 4. Sep 4 23:47:35.243066 systemd[1]: Started sshd@2-10.200.8.22:22-10.200.16.10:40628.service - OpenSSH per-connection server daemon (10.200.16.10:40628). Sep 4 23:47:35.877571 sshd[2264]: Accepted publickey for core from 10.200.16.10 port 40628 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:47:35.878204 sshd-session[2264]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:47:35.884867 systemd-logind[1703]: New session 5 of user core. Sep 4 23:47:35.889956 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 4 23:47:36.320928 sshd[2266]: Connection closed by 10.200.16.10 port 40628 Sep 4 23:47:36.322563 sshd-session[2264]: pam_unix(sshd:session): session closed for user core Sep 4 23:47:36.328867 systemd[1]: sshd@2-10.200.8.22:22-10.200.16.10:40628.service: Deactivated successfully. Sep 4 23:47:36.332752 systemd[1]: session-5.scope: Deactivated successfully. Sep 4 23:47:36.333591 systemd-logind[1703]: Session 5 logged out. Waiting for processes to exit. Sep 4 23:47:36.334746 systemd-logind[1703]: Removed session 5. Sep 4 23:47:45.857716 setup-nvidia[1695]: Downloading NVIDIA 535.230.02 Driver Sep 4 23:47:45.862188 setup-nvidia[2272]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 4 23:47:45.862430 setup-nvidia[2272]: Dload Upload Total Spent Left Speed Sep 4 23:47:46.433682 systemd[1]: Started sshd@3-10.200.8.22:22-10.200.16.10:42654.service - OpenSSH per-connection server daemon (10.200.16.10:42654). Sep 4 23:47:47.068109 sshd[2274]: Accepted publickey for core from 10.200.16.10 port 42654 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:47:47.069602 sshd-session[2274]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:47:47.074762 systemd-logind[1703]: New session 6 of user core. Sep 4 23:47:47.086957 systemd[1]: Started session-6.scope - Session 6 of User core. Sep 4 23:47:47.384197 setup-nvidia[2272]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 16 326M 16 53.0M 0 0 117M 0 0:00:02 --:--:-- 0:00:02 117M\u000d 93 326M 93 303M 0 0 209M 0 0:00:01 0:00:01 --:--:-- 209M\u000d100 326M 100 326M 0 0 214M 0 0:00:01 0:00:01 --:--:-- 214M Sep 4 23:47:47.483726 setup-nvidia[1695]: Extract the NVIDIA Driver Installer 535.230.02 Sep 4 23:47:47.484011 setup-nvidia[1695]: /opt/nvidia/workdir/nvidia-workdir / Sep 4 23:47:47.516589 setup-nvidia[2280]: Creating directory NVIDIA-Linux-x86_64-535.230.02 Sep 4 23:47:47.519169 sshd[2276]: Connection closed by 10.200.16.10 port 42654 Sep 4 23:47:47.519526 sshd-session[2274]: pam_unix(sshd:session): session closed for user core Sep 4 23:47:47.522551 systemd[1]: sshd@3-10.200.8.22:22-10.200.16.10:42654.service: Deactivated successfully. Sep 4 23:47:47.524165 systemd[1]: session-6.scope: Deactivated successfully. Sep 4 23:47:47.524807 systemd-logind[1703]: Session 6 logged out. Waiting for processes to exit. Sep 4 23:47:47.525572 systemd-logind[1703]: Removed session 6. Sep 4 23:47:47.630436 systemd[1]: Started sshd@4-10.200.8.22:22-10.200.16.10:42666.service - OpenSSH per-connection server daemon (10.200.16.10:42666). Sep 4 23:47:47.798668 setup-nvidia[2280]: Verifying archive integrity... OK Sep 4 23:47:48.146453 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Sep 4 23:47:48.262135 sshd[2303]: Accepted publickey for core from 10.200.16.10 port 42666 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:47:48.263455 sshd-session[2303]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:47:48.267694 systemd-logind[1703]: New session 7 of user core. Sep 4 23:47:48.278937 systemd[1]: Started session-7.scope - Session 7 of User core. Sep 4 23:47:48.625436 setup-nvidia[2280]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.230.02 Sep 4 23:47:48.705959 sshd[2309]: Connection closed by 10.200.16.10 port 42666 Sep 4 23:47:48.706367 sshd-session[2303]: pam_unix(sshd:session): session closed for user core Sep 4 23:47:48.709574 systemd-logind[1703]: Session 7 logged out. Waiting for processes to exit. Sep 4 23:47:48.709929 systemd[1]: sshd@4-10.200.8.22:22-10.200.16.10:42666.service: Deactivated successfully. Sep 4 23:47:48.711775 systemd[1]: session-7.scope: Deactivated successfully. Sep 4 23:47:48.712982 systemd-logind[1703]: Removed session 7. Sep 4 23:47:50.454028 setup-nvidia[2313]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Sep 4 23:47:50.502813 setup-nvidia[1695]: / Sep 4 23:47:50.503072 setup-nvidia[1695]: Spawn system-nspawn container to install the NVIDIA drivers Sep 4 23:47:50.732546 sudo[2324]: 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.2+nightly-20250904-2100.bin --overlay=/usr/lib/modules::/usr/lib/modules --bind=/opt/nvidia/workdir/nvidia-workdir:/nvidia --bind=/usr/lib/nvidia/bin:/app/bin/ /app/bin/install-nvidia NVIDIA-Linux-x86_64-535.230.02 Sep 4 23:47:50.734291 sudo[2324]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 4 23:47:50.742124 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Sep 4 23:47:50.757037 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Sep 4 23:47:50.767130 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Sep 4 23:47:50.770077 systemd[1]: Starting user@0.service - User Manager for UID 0... Sep 4 23:47:50.776450 (systemd)[2327]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Sep 4 23:47:50.778569 systemd-logind[1703]: New session c3 of user root. Sep 4 23:47:50.946963 systemd[2327]: Queued start job for default target default.target. Sep 4 23:47:50.958929 systemd[2327]: Created slice app.slice - User Application Slice. Sep 4 23:47:50.958961 systemd[2327]: Reached target paths.target - Paths. Sep 4 23:47:50.959011 systemd[2327]: Reached target timers.target - Timers. Sep 4 23:47:50.960244 systemd[2327]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 4 23:47:50.970682 systemd[2327]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 4 23:47:50.970741 systemd[2327]: Reached target sockets.target - Sockets. Sep 4 23:47:50.970803 systemd[2327]: Reached target basic.target - Basic System. Sep 4 23:47:50.970856 systemd[2327]: Reached target default.target - Main User Target. Sep 4 23:47:50.970890 systemd[2327]: Startup finished in 186ms. Sep 4 23:47:50.971023 systemd[1]: Started user@0.service - User Manager for UID 0. Sep 4 23:47:50.976874 systemd[1]: Started session-c2.scope - Session c2 of User root. Sep 4 23:47:51.064814 kernel: loop6: detected capacity change from 0 to 12644352 Sep 4 23:47:51.073811 kernel: loop6: p9 Sep 4 23:47:51.195589 setup-nvidia[2336]: ░ Spawning container flatcardevelopercontainer-4230.2.2nightly-20250904-2100.bin on /opt/nvidia/workdir/flatcar_developer_container-4230.2.2+nightly-20250904-2100.bin. Sep 4 23:47:51.263811 kernel: EXT4-fs (loop6p9): mounted filesystem 31317368-05db-4c20-ad22-912e33323410 ro without journal. Quota mode: none. Sep 4 23:47:52.195182 update_engine[1705]: I20250904 23:47:52.195097 1705 update_attempter.cc:509] Updating boot flags... Sep 4 23:47:52.371815 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 38 scanned by (udev-worker) (2356) Sep 4 23:47:58.823298 systemd[1]: Started sshd@5-10.200.8.22:22-10.200.16.10:38792.service - OpenSSH per-connection server daemon (10.200.16.10:38792). Sep 4 23:47:59.450284 sshd[2464]: Accepted publickey for core from 10.200.16.10 port 38792 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:47:59.451620 sshd-session[2464]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:47:59.455908 systemd-logind[1703]: New session 8 of user core. Sep 4 23:47:59.468902 systemd[1]: Started session-8.scope - Session 8 of User core. Sep 4 23:47:59.905618 sshd[2501]: Connection closed by 10.200.16.10 port 38792 Sep 4 23:47:59.905983 sshd-session[2464]: pam_unix(sshd:session): session closed for user core Sep 4 23:47:59.909078 systemd[1]: sshd@5-10.200.8.22:22-10.200.16.10:38792.service: Deactivated successfully. Sep 4 23:47:59.910665 systemd[1]: session-8.scope: Deactivated successfully. Sep 4 23:47:59.911325 systemd-logind[1703]: Session 8 logged out. Waiting for processes to exit. Sep 4 23:47:59.912105 systemd-logind[1703]: Removed session 8. Sep 4 23:48:00.016118 systemd[1]: Started sshd@6-10.200.8.22:22-10.200.16.10:54806.service - OpenSSH per-connection server daemon (10.200.16.10:54806). Sep 4 23:48:00.646298 sshd[2522]: Accepted publickey for core from 10.200.16.10 port 54806 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:48:00.647490 sshd-session[2522]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:48:00.651704 systemd-logind[1703]: New session 9 of user core. Sep 4 23:48:00.659908 systemd[1]: Started session-9.scope - Session 9 of User core. Sep 4 23:48:01.097429 sshd[2543]: Connection closed by 10.200.16.10 port 54806 Sep 4 23:48:01.097807 sshd-session[2522]: pam_unix(sshd:session): session closed for user core Sep 4 23:48:01.100981 systemd[1]: sshd@6-10.200.8.22:22-10.200.16.10:54806.service: Deactivated successfully. Sep 4 23:48:01.102944 systemd[1]: session-9.scope: Deactivated successfully. Sep 4 23:48:01.103594 systemd-logind[1703]: Session 9 logged out. Waiting for processes to exit. Sep 4 23:48:01.104402 systemd-logind[1703]: Removed session 9. Sep 4 23:48:11.210813 systemd[1]: Started sshd@7-10.200.8.22:22-10.200.16.10:43734.service - OpenSSH per-connection server daemon (10.200.16.10:43734). Sep 4 23:48:11.838739 sshd[3482]: Accepted publickey for core from 10.200.16.10 port 43734 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:48:11.840143 sshd-session[3482]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:48:11.844417 systemd-logind[1703]: New session 10 of user core. Sep 4 23:48:11.851916 systemd[1]: Started session-10.scope - Session 10 of User core. Sep 4 23:48:12.282648 sshd[3484]: Connection closed by 10.200.16.10 port 43734 Sep 4 23:48:12.282996 sshd-session[3482]: pam_unix(sshd:session): session closed for user core Sep 4 23:48:12.286210 systemd[1]: sshd@7-10.200.8.22:22-10.200.16.10:43734.service: Deactivated successfully. Sep 4 23:48:12.287846 systemd[1]: session-10.scope: Deactivated successfully. Sep 4 23:48:12.288495 systemd-logind[1703]: Session 10 logged out. Waiting for processes to exit. Sep 4 23:48:12.289273 systemd-logind[1703]: Removed session 10. Sep 4 23:48:12.398239 systemd[1]: Started sshd@8-10.200.8.22:22-10.200.16.10:43748.service - OpenSSH per-connection server daemon (10.200.16.10:43748). Sep 4 23:48:13.027643 sshd[3490]: Accepted publickey for core from 10.200.16.10 port 43748 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:48:13.029074 sshd-session[3490]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:48:13.033382 systemd-logind[1703]: New session 11 of user core. Sep 4 23:48:13.048229 systemd[1]: Started session-11.scope - Session 11 of User core. Sep 4 23:48:13.472505 sshd[3492]: Connection closed by 10.200.16.10 port 43748 Sep 4 23:48:13.472926 sshd-session[3490]: pam_unix(sshd:session): session closed for user core Sep 4 23:48:13.476183 systemd[1]: sshd@8-10.200.8.22:22-10.200.16.10:43748.service: Deactivated successfully. Sep 4 23:48:13.477801 systemd[1]: session-11.scope: Deactivated successfully. Sep 4 23:48:13.478420 systemd-logind[1703]: Session 11 logged out. Waiting for processes to exit. Sep 4 23:48:13.479238 systemd-logind[1703]: Removed session 11. Sep 4 23:48:23.584368 systemd[1]: Started sshd@9-10.200.8.22:22-10.200.16.10:34412.service - OpenSSH per-connection server daemon (10.200.16.10:34412). Sep 4 23:48:24.212028 sshd[3595]: Accepted publickey for core from 10.200.16.10 port 34412 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:48:24.213394 sshd-session[3595]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:48:24.217551 systemd-logind[1703]: New session 12 of user core. Sep 4 23:48:24.228904 systemd[1]: Started session-12.scope - Session 12 of User core. Sep 4 23:48:24.666048 sshd[3597]: Connection closed by 10.200.16.10 port 34412 Sep 4 23:48:24.666428 sshd-session[3595]: pam_unix(sshd:session): session closed for user core Sep 4 23:48:24.669597 systemd[1]: sshd@9-10.200.8.22:22-10.200.16.10:34412.service: Deactivated successfully. Sep 4 23:48:24.671270 systemd[1]: session-12.scope: Deactivated successfully. Sep 4 23:48:24.671923 systemd-logind[1703]: Session 12 logged out. Waiting for processes to exit. Sep 4 23:48:24.672839 systemd-logind[1703]: Removed session 12. Sep 4 23:48:24.778165 systemd[1]: Started sshd@10-10.200.8.22:22-10.200.16.10:34426.service - OpenSSH per-connection server daemon (10.200.16.10:34426). Sep 4 23:48:25.407285 sshd[3603]: Accepted publickey for core from 10.200.16.10 port 34426 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:48:25.408643 sshd-session[3603]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:48:25.412972 systemd-logind[1703]: New session 13 of user core. Sep 4 23:48:25.430908 systemd[1]: Started session-13.scope - Session 13 of User core. Sep 4 23:48:25.862557 sshd[3605]: Connection closed by 10.200.16.10 port 34426 Sep 4 23:48:25.862977 sshd-session[3603]: pam_unix(sshd:session): session closed for user core Sep 4 23:48:25.866279 systemd[1]: sshd@10-10.200.8.22:22-10.200.16.10:34426.service: Deactivated successfully. Sep 4 23:48:25.867906 systemd[1]: session-13.scope: Deactivated successfully. Sep 4 23:48:25.868538 systemd-logind[1703]: Session 13 logged out. Waiting for processes to exit. Sep 4 23:48:25.869370 systemd-logind[1703]: Removed session 13. Sep 4 23:48:35.976359 systemd[1]: Started sshd@11-10.200.8.22:22-10.200.16.10:53386.service - OpenSSH per-connection server daemon (10.200.16.10:53386). Sep 4 23:48:36.603388 sshd[5484]: Accepted publickey for core from 10.200.16.10 port 53386 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:48:36.604682 sshd-session[5484]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:48:36.608799 systemd-logind[1703]: New session 14 of user core. Sep 4 23:48:36.622910 systemd[1]: Started session-14.scope - Session 14 of User core. Sep 4 23:48:37.057884 sshd[5492]: Connection closed by 10.200.16.10 port 53386 Sep 4 23:48:37.058345 sshd-session[5484]: pam_unix(sshd:session): session closed for user core Sep 4 23:48:37.061862 systemd[1]: sshd@11-10.200.8.22:22-10.200.16.10:53386.service: Deactivated successfully. Sep 4 23:48:37.063778 systemd[1]: session-14.scope: Deactivated successfully. Sep 4 23:48:37.066813 systemd-logind[1703]: Session 14 logged out. Waiting for processes to exit. Sep 4 23:48:37.067699 systemd-logind[1703]: Removed session 14. Sep 4 23:48:37.185057 systemd[1]: Started sshd@12-10.200.8.22:22-10.200.16.10:53392.service - OpenSSH per-connection server daemon (10.200.16.10:53392). Sep 4 23:48:37.821658 sshd[5672]: Accepted publickey for core from 10.200.16.10 port 53392 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:48:45.392875 sshd-session[5672]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:48:45.399289 systemd-logind[1703]: New session 15 of user core. Sep 4 23:48:45.415015 systemd[1]: Started session-15.scope - Session 15 of User core. Sep 4 23:48:45.776287 sshd[5939]: Connection closed by 10.200.16.10 port 53392 Sep 4 23:48:45.775521 sshd-session[5672]: pam_unix(sshd:session): session closed for user core Sep 4 23:48:45.780172 systemd[1]: sshd@12-10.200.8.22:22-10.200.16.10:53392.service: Deactivated successfully. Sep 4 23:48:45.781940 systemd[1]: session-15.scope: Deactivated successfully. Sep 4 23:48:45.782755 systemd-logind[1703]: Session 15 logged out. Waiting for processes to exit. Sep 4 23:48:45.784103 systemd-logind[1703]: Removed session 15. Sep 4 23:48:55.890056 systemd[1]: Started sshd@13-10.200.8.22:22-10.200.16.10:45252.service - OpenSSH per-connection server daemon (10.200.16.10:45252). Sep 4 23:48:56.555959 sshd[9554]: Accepted publickey for core from 10.200.16.10 port 45252 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:48:56.557824 sshd-session[9554]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:48:56.566517 systemd-logind[1703]: New session 16 of user core. Sep 4 23:48:56.576536 systemd[1]: Started session-16.scope - Session 16 of User core. Sep 4 23:48:57.013042 sshd[9765]: Connection closed by 10.200.16.10 port 45252 Sep 4 23:48:57.014005 sshd-session[9554]: pam_unix(sshd:session): session closed for user core Sep 4 23:48:57.017386 systemd-logind[1703]: Session 16 logged out. Waiting for processes to exit. Sep 4 23:48:57.018042 systemd[1]: sshd@13-10.200.8.22:22-10.200.16.10:45252.service: Deactivated successfully. Sep 4 23:48:57.020465 systemd[1]: session-16.scope: Deactivated successfully. Sep 4 23:48:57.028978 systemd-logind[1703]: Removed session 16. Sep 4 23:48:57.147074 systemd[1]: Started sshd@14-10.200.8.22:22-10.200.16.10:45254.service - OpenSSH per-connection server daemon (10.200.16.10:45254). Sep 4 23:48:57.957599 sshd[9908]: Accepted publickey for core from 10.200.16.10 port 45254 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:48:57.959126 sshd-session[9908]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:48:57.965367 systemd-logind[1703]: New session 17 of user core. Sep 4 23:48:57.976509 systemd[1]: Started session-17.scope - Session 17 of User core. Sep 4 23:48:58.412915 sshd[10274]: Connection closed by 10.200.16.10 port 45254 Sep 4 23:48:58.413696 sshd-session[9908]: pam_unix(sshd:session): session closed for user core Sep 4 23:48:58.418263 systemd-logind[1703]: Session 17 logged out. Waiting for processes to exit. Sep 4 23:48:58.419987 systemd[1]: sshd@14-10.200.8.22:22-10.200.16.10:45254.service: Deactivated successfully. Sep 4 23:48:58.422252 systemd[1]: session-17.scope: Deactivated successfully. Sep 4 23:48:58.424136 systemd-logind[1703]: Removed session 17. Sep 4 23:49:08.535172 systemd[1]: Started sshd@15-10.200.8.22:22-10.200.16.10:48094.service - OpenSSH per-connection server daemon (10.200.16.10:48094). Sep 4 23:49:09.165728 sshd[11806]: Accepted publickey for core from 10.200.16.10 port 48094 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:49:09.167217 sshd-session[11806]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:49:09.174874 systemd-logind[1703]: New session 18 of user core. Sep 4 23:49:09.183962 systemd[1]: Started session-18.scope - Session 18 of User core. Sep 4 23:49:09.611988 sshd[11832]: Connection closed by 10.200.16.10 port 48094 Sep 4 23:49:09.613995 sshd-session[11806]: pam_unix(sshd:session): session closed for user core Sep 4 23:49:09.616766 systemd[1]: sshd@15-10.200.8.22:22-10.200.16.10:48094.service: Deactivated successfully. Sep 4 23:49:09.618533 systemd[1]: session-18.scope: Deactivated successfully. Sep 4 23:49:09.621391 systemd-logind[1703]: Session 18 logged out. Waiting for processes to exit. Sep 4 23:49:09.622699 systemd-logind[1703]: Removed session 18. Sep 4 23:49:09.736199 systemd[1]: Started sshd@16-10.200.8.22:22-10.200.16.10:48098.service - OpenSSH per-connection server daemon (10.200.16.10:48098). Sep 4 23:49:10.375653 sshd[11862]: Accepted publickey for core from 10.200.16.10 port 48098 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:49:10.376253 sshd-session[11862]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:49:10.382238 systemd-logind[1703]: New session 19 of user core. Sep 4 23:49:10.387973 systemd[1]: Started session-19.scope - Session 19 of User core. Sep 4 23:49:10.824822 sshd[11894]: Connection closed by 10.200.16.10 port 48098 Sep 4 23:49:10.825243 sshd-session[11862]: pam_unix(sshd:session): session closed for user core Sep 4 23:49:10.828979 systemd-logind[1703]: Session 19 logged out. Waiting for processes to exit. Sep 4 23:49:10.830221 systemd[1]: sshd@16-10.200.8.22:22-10.200.16.10:48098.service: Deactivated successfully. Sep 4 23:49:10.833508 systemd[1]: session-19.scope: Deactivated successfully. Sep 4 23:49:10.835024 systemd-logind[1703]: Removed session 19. Sep 4 23:49:20.942854 systemd[1]: Started sshd@17-10.200.8.22:22-10.200.16.10:33446.service - OpenSSH per-connection server daemon (10.200.16.10:33446). Sep 4 23:49:21.579332 sshd[12182]: Accepted publickey for core from 10.200.16.10 port 33446 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:49:21.581285 sshd-session[12182]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:49:21.588271 systemd-logind[1703]: New session 20 of user core. Sep 4 23:49:21.596984 systemd[1]: Started session-20.scope - Session 20 of User core. Sep 4 23:49:22.029692 sshd[12208]: Connection closed by 10.200.16.10 port 33446 Sep 4 23:49:22.031687 sshd-session[12182]: pam_unix(sshd:session): session closed for user core Sep 4 23:49:22.036112 systemd[1]: sshd@17-10.200.8.22:22-10.200.16.10:33446.service: Deactivated successfully. Sep 4 23:49:22.038371 systemd-logind[1703]: Session 20 logged out. Waiting for processes to exit. Sep 4 23:49:22.040777 systemd[1]: session-20.scope: Deactivated successfully. Sep 4 23:49:22.042203 systemd-logind[1703]: Removed session 20. Sep 4 23:49:22.147093 systemd[1]: Started sshd@18-10.200.8.22:22-10.200.16.10:33462.service - OpenSSH per-connection server daemon (10.200.16.10:33462). Sep 4 23:49:22.786700 sshd[12238]: Accepted publickey for core from 10.200.16.10 port 33462 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:49:22.788614 sshd-session[12238]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:49:22.795087 systemd-logind[1703]: New session 21 of user core. Sep 4 23:49:22.804043 systemd[1]: Started session-21.scope - Session 21 of User core. Sep 4 23:49:23.241371 sshd[12252]: Connection closed by 10.200.16.10 port 33462 Sep 4 23:49:23.243331 sshd-session[12238]: pam_unix(sshd:session): session closed for user core Sep 4 23:49:23.247130 systemd[1]: sshd@18-10.200.8.22:22-10.200.16.10:33462.service: Deactivated successfully. Sep 4 23:49:23.248755 systemd[1]: session-21.scope: Deactivated successfully. Sep 4 23:49:23.248967 systemd-logind[1703]: Session 21 logged out. Waiting for processes to exit. Sep 4 23:49:23.250965 systemd-logind[1703]: Removed session 21. Sep 4 23:49:33.360901 systemd[1]: Started sshd@19-10.200.8.22:22-10.200.16.10:48890.service - OpenSSH per-connection server daemon (10.200.16.10:48890). Sep 4 23:49:33.998357 sshd[12552]: Accepted publickey for core from 10.200.16.10 port 48890 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:49:34.000261 sshd-session[12552]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:49:34.006431 systemd-logind[1703]: New session 22 of user core. Sep 4 23:49:34.014561 systemd[1]: Started session-22.scope - Session 22 of User core. Sep 4 23:49:34.455137 sshd[12573]: Connection closed by 10.200.16.10 port 48890 Sep 4 23:49:34.456001 sshd-session[12552]: pam_unix(sshd:session): session closed for user core Sep 4 23:49:34.460264 systemd-logind[1703]: Session 22 logged out. Waiting for processes to exit. Sep 4 23:49:34.461872 systemd[1]: sshd@19-10.200.8.22:22-10.200.16.10:48890.service: Deactivated successfully. Sep 4 23:49:34.463884 systemd[1]: session-22.scope: Deactivated successfully. Sep 4 23:49:34.465311 systemd-logind[1703]: Removed session 22. Sep 4 23:49:34.578111 systemd[1]: Started sshd@20-10.200.8.22:22-10.200.16.10:48896.service - OpenSSH per-connection server daemon (10.200.16.10:48896). Sep 4 23:49:35.216365 sshd[12608]: Accepted publickey for core from 10.200.16.10 port 48896 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:49:35.219376 sshd-session[12608]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:49:35.224523 systemd-logind[1703]: New session 23 of user core. Sep 4 23:49:35.238503 systemd[1]: Started session-23.scope - Session 23 of User core. Sep 4 23:49:35.672085 sshd[12646]: Connection closed by 10.200.16.10 port 48896 Sep 4 23:49:35.672934 sshd-session[12608]: pam_unix(sshd:session): session closed for user core Sep 4 23:49:35.679318 systemd[1]: sshd@20-10.200.8.22:22-10.200.16.10:48896.service: Deactivated successfully. Sep 4 23:49:35.682124 systemd[1]: session-23.scope: Deactivated successfully. Sep 4 23:49:35.682937 systemd-logind[1703]: Session 23 logged out. Waiting for processes to exit. Sep 4 23:49:35.684037 systemd-logind[1703]: Removed session 23. Sep 4 23:49:44.499949 setup-nvidia[2336]: \u000d Sep 4 23:49:44.499949 setup-nvidia[2336]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Sep 4 23:49:44.499949 setup-nvidia[2336]: frequently when this kernel module was built against the wrong or Sep 4 23:49:44.499949 setup-nvidia[2336]: improperly configured kernel sources, with a version of gcc that differs Sep 4 23:49:44.499949 setup-nvidia[2336]: from the one used to build the target kernel, or if another driver, such Sep 4 23:49:44.499949 setup-nvidia[2336]: as nouveau, is present and prevents the NVIDIA kernel module from Sep 4 23:49:44.499949 setup-nvidia[2336]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Sep 4 23:49:44.499949 setup-nvidia[2336]: installed in this system is supported by this NVIDIA Linux graphics Sep 4 23:49:44.499949 setup-nvidia[2336]: driver release. Sep 4 23:49:44.499949 setup-nvidia[2336]: \u000d Sep 4 23:49:44.499949 setup-nvidia[2336]: Please see the log entries 'Kernel module load error' and 'Kernel Sep 4 23:49:44.499949 setup-nvidia[2336]: messages' at the end of the file Sep 4 23:49:44.499949 setup-nvidia[2336]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more Sep 4 23:49:44.499949 setup-nvidia[2336]: information. Sep 4 23:49:44.499949 setup-nvidia[2336]: \u000d Sep 4 23:49:44.513761 setup-nvidia[2336]: dmesg: read kernel buffer failed: Operation not permitted Sep 4 23:49:44.535100 setup-nvidia[2336]: \u000d Sep 4 23:49:44.535100 setup-nvidia[2336]: ERROR: Installation has failed. Please see the file Sep 4 23:49:44.535100 setup-nvidia[2336]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for Sep 4 23:49:44.535100 setup-nvidia[2336]: details. You may find suggestions on fixing installation problems in Sep 4 23:49:44.535100 setup-nvidia[2336]: the README available on the Linux driver download page at Sep 4 23:49:44.535100 setup-nvidia[2336]: www.nvidia.com. Sep 4 23:49:44.535100 setup-nvidia[2336]: \u000d Sep 4 23:49:44.535489 setup-nvidia[2336]: Last 50 lines of nvidia-installer.log: Sep 4 23:49:44.536319 setup-nvidia[2336]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038989rm+0x32: 'naked' return found in RETHUNK build Sep 4 23:49:44.536319 setup-nvidia[2336]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039000rm+0x53: 'naked' return found in RETHUNK build Sep 4 23:49:44.536319 setup-nvidia[2336]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038997rm+0x90: 'naked' return found in RETHUNK build Sep 4 23:49:44.536319 setup-nvidia[2336]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038990rm+0x2b: 'naked' return found in RETHUNK build Sep 4 23:49:44.536319 setup-nvidia[2336]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039046rm+0x93: 'naked' return found in RETHUNK build Sep 4 23:49:44.536319 setup-nvidia[2336]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039042rm+0x36: 'naked' return found in RETHUNK build Sep 4 23:49:44.536319 setup-nvidia[2336]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039041rm+0x9d: 'naked' return found in RETHUNK build Sep 4 23:49:44.536319 setup-nvidia[2336]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039043rm+0x77: 'naked' return found in RETHUNK build Sep 4 23:49:44.536319 setup-nvidia[2336]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038987rm+0x7f: 'naked' return found in RETHUNK build Sep 4 23:49:44.536319 setup-nvidia[2336]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030558rm+0x3e: 'naked' return found in RETHUNK build Sep 4 23:49:44.536319 setup-nvidia[2336]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030557rm+0x27: 'naked' return found in RETHUNK build Sep 4 23:49:44.536319 setup-nvidia[2336]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030561rm+0x37: 'naked' return found in RETHUNK build Sep 4 23:49:44.536319 setup-nvidia[2336]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030560rm+0x24: 'naked' return found in RETHUNK build Sep 4 23:49:44.536319 setup-nvidia[2336]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030559rm+0xca: 'naked' return found in RETHUNK build Sep 4 23:49:44.536319 setup-nvidia[2336]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030562rm+0x193: 'naked' return found in RETHUNK build Sep 4 23:49:44.536319 setup-nvidia[2336]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030564rm+0x17f: 'naked' return found in RETHUNK build Sep 4 23:49:44.536319 setup-nvidia[2336]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv011860rm+0x5d: 'naked' return found in RETHUNK build Sep 4 23:49:44.536319 setup-nvidia[2336]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039354rm+0x12f: 'naked' return found in RETHUNK build Sep 4 23:49:44.536319 setup-nvidia[2336]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/Module.symvers Sep 4 23:49:44.536319 setup-nvidia[2336]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.mod.o Sep 4 23:49:44.536319 setup-nvidia[2336]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.mod.o Sep 4 23:49:44.536319 setup-nvidia[2336]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.mod.o Sep 4 23:49:44.536319 setup-nvidia[2336]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.mod.o Sep 4 23:49:44.536319 setup-nvidia[2336]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.mod.o Sep 4 23:49:44.536319 setup-nvidia[2336]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Sep 4 23:49:44.536319 setup-nvidia[2336]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Sep 4 23:49:44.536319 setup-nvidia[2336]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Sep 4 23:49:44.536319 setup-nvidia[2336]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Sep 4 23:49:44.536319 setup-nvidia[2336]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Sep 4 23:49:44.536319 setup-nvidia[2336]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Sep 4 23:49:44.536319 setup-nvidia[2336]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko due to unavailability of vmlinux Sep 4 23:49:44.536319 setup-nvidia[2336]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Sep 4 23:49:44.536319 setup-nvidia[2336]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko due to unavailability of vmlinux Sep 4 23:49:44.536319 setup-nvidia[2336]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Sep 4 23:49:44.536319 setup-nvidia[2336]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko due to unavailability of vmlinux Sep 4 23:49:44.536319 setup-nvidia[2336]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Sep 4 23:49:44.536319 setup-nvidia[2336]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko due to unavailability of vmlinux Sep 4 23:49:44.536319 setup-nvidia[2336]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Sep 4 23:49:44.536319 setup-nvidia[2336]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko due to unavailability of vmlinux Sep 4 23:49:44.536319 setup-nvidia[2336]: make[2]: Leaving directory '/usr/lib/modules/6.6.103-flatcar/build' Sep 4 23:49:44.536319 setup-nvidia[2336]: make[1]: Leaving directory '/usr/lib/modules/6.6.103-flatcar/source' Sep 4 23:49:44.536319 setup-nvidia[2336]: -> done. Sep 4 23:49:44.536319 setup-nvidia[2336]: -> Kernel module compilation complete. Sep 4 23:49:44.536319 setup-nvidia[2336]: -> Unable to determine if Secure Boot is enabled: No such file or directory Sep 4 23:49:44.536319 setup-nvidia[2336]: 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. Sep 4 23:49:44.536319 setup-nvidia[2336]: \u000d Sep 4 23:49:44.537422 setup-nvidia[2336]: 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. Sep 4 23:49:44.537422 setup-nvidia[2336]: -> Kernel module load error: Operation not permitted Sep 4 23:49:44.537422 setup-nvidia[2336]: -> Kernel messages: Sep 4 23:49:44.537422 setup-nvidia[2336]: 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. Sep 4 23:49:45.784428 systemd[1]: Started sshd@21-10.200.8.22:22-10.200.16.10:38408.service - OpenSSH per-connection server daemon (10.200.16.10:38408). Sep 4 23:49:46.414291 sshd[12924]: Accepted publickey for core from 10.200.16.10 port 38408 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:49:46.415847 sshd-session[12924]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:49:46.420080 systemd-logind[1703]: New session 24 of user core. Sep 4 23:49:46.434915 systemd[1]: Started session-24.scope - Session 24 of User core. Sep 4 23:49:46.865063 sshd[12926]: Connection closed by 10.200.16.10 port 38408 Sep 4 23:49:46.865488 sshd-session[12924]: pam_unix(sshd:session): session closed for user core Sep 4 23:49:46.868890 systemd[1]: sshd@21-10.200.8.22:22-10.200.16.10:38408.service: Deactivated successfully. Sep 4 23:49:46.870546 systemd[1]: session-24.scope: Deactivated successfully. Sep 4 23:49:46.871234 systemd-logind[1703]: Session 24 logged out. Waiting for processes to exit. Sep 4 23:49:46.872094 systemd-logind[1703]: Removed session 24. Sep 4 23:49:46.976478 systemd[1]: Started sshd@22-10.200.8.22:22-10.200.16.10:38422.service - OpenSSH per-connection server daemon (10.200.16.10:38422). Sep 4 23:49:47.604404 sshd[12932]: Accepted publickey for core from 10.200.16.10 port 38422 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:49:47.605734 sshd-session[12932]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:49:47.609898 systemd-logind[1703]: New session 25 of user core. Sep 4 23:49:47.621914 systemd[1]: Started session-25.scope - Session 25 of User core. Sep 4 23:49:48.050359 sshd[12934]: Connection closed by 10.200.16.10 port 38422 Sep 4 23:49:48.050880 sshd-session[12932]: pam_unix(sshd:session): session closed for user core Sep 4 23:49:48.054108 systemd[1]: sshd@22-10.200.8.22:22-10.200.16.10:38422.service: Deactivated successfully. Sep 4 23:49:48.055749 systemd[1]: session-25.scope: Deactivated successfully. Sep 4 23:49:48.056606 systemd-logind[1703]: Session 25 logged out. Waiting for processes to exit. Sep 4 23:49:48.057607 systemd-logind[1703]: Removed session 25. Sep 4 23:49:58.162311 systemd[1]: Started sshd@23-10.200.8.22:22-10.200.16.10:51798.service - OpenSSH per-connection server daemon (10.200.16.10:51798). Sep 4 23:49:58.789108 sshd[12940]: Accepted publickey for core from 10.200.16.10 port 51798 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:49:58.790404 sshd-session[12940]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:49:58.794545 systemd-logind[1703]: New session 26 of user core. Sep 4 23:49:58.806903 systemd[1]: Started session-26.scope - Session 26 of User core. Sep 4 23:49:59.234116 sshd[12942]: Connection closed by 10.200.16.10 port 51798 Sep 4 23:49:59.234385 sshd-session[12940]: pam_unix(sshd:session): session closed for user core Sep 4 23:49:59.237765 systemd[1]: sshd@23-10.200.8.22:22-10.200.16.10:51798.service: Deactivated successfully. Sep 4 23:49:59.239396 systemd[1]: session-26.scope: Deactivated successfully. Sep 4 23:49:59.240042 systemd-logind[1703]: Session 26 logged out. Waiting for processes to exit. Sep 4 23:49:59.240874 systemd-logind[1703]: Removed session 26. Sep 4 23:49:59.350154 systemd[1]: Started sshd@24-10.200.8.22:22-10.200.16.10:51808.service - OpenSSH per-connection server daemon (10.200.16.10:51808). Sep 4 23:49:59.980004 sshd[12948]: Accepted publickey for core from 10.200.16.10 port 51808 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:49:59.981277 sshd-session[12948]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:49:59.985380 systemd-logind[1703]: New session 27 of user core. Sep 4 23:49:59.997901 systemd[1]: Started session-27.scope - Session 27 of User core. Sep 4 23:50:00.424950 sshd[12950]: Connection closed by 10.200.16.10 port 51808 Sep 4 23:50:00.425341 sshd-session[12948]: pam_unix(sshd:session): session closed for user core Sep 4 23:50:00.428496 systemd[1]: sshd@24-10.200.8.22:22-10.200.16.10:51808.service: Deactivated successfully. Sep 4 23:50:00.430171 systemd[1]: session-27.scope: Deactivated successfully. Sep 4 23:50:00.430815 systemd-logind[1703]: Session 27 logged out. Waiting for processes to exit. Sep 4 23:50:00.431572 systemd-logind[1703]: Removed session 27. Sep 4 23:50:07.154814 kernel: EXT4-fs (loop6p9): unmounting filesystem 31317368-05db-4c20-ad22-912e33323410. Sep 4 23:50:07.155321 setup-nvidia[2336]: Container flatcardevelopercontainer-4230.2.2nightly-20250904-2100.bin exited successfully. Sep 4 23:50:07.157830 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4230.2.2nightly\x2d20250904\x2d2100.bin.mount: Deactivated successfully. Sep 4 23:50:07.215557 sudo[2324]: pam_unix(sudo:session): session closed for user root Sep 4 23:50:07.217279 systemd[1]: session-c2.scope: Deactivated successfully. Sep 4 23:50:07.217547 systemd[1]: session-c2.scope: Consumed 5min 17.331s CPU time, 882.2M memory peak. Sep 4 23:50:07.629626 setup-nvidia[1695]: /opt/nvidia / Sep 4 23:50:07.630624 setup-nvidia[1695]: / Sep 4 23:50:07.646474 setup-nvidia[12973]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 4 23:50:07.668814 kernel: IPMI message handler: version 39.2 Sep 4 23:50:07.671811 kernel: ipmi device interface Sep 4 23:50:07.673253 setup-nvidia[1695]: /opt/nvidia/current/usr/lib/modules/6.6.103-flatcar/video / Sep 4 23:50:07.726027 kernel: nvidia: loading out-of-tree module taints kernel. Sep 4 23:50:07.726309 kernel: nvidia: module license 'NVIDIA' taints kernel. Sep 4 23:50:07.726460 kernel: Disabling lock debugging due to kernel taint Sep 4 23:50:07.733215 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Sep 4 23:50:07.733258 kernel: nvidia: module license taints kernel. Sep 4 23:50:08.149817 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 245 Sep 4 23:50:08.563660 kernel: Sep 4 23:50:08.563772 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Sep 4 23:50:08.769808 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.230.02 Fri Dec 20 21:42:05 UTC 2024 Sep 4 23:50:08.790807 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.230.02 Fri Dec 20 21:52:34 UTC 2024 Sep 4 23:50:08.810826 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Sep 4 23:50:08.855493 setup-nvidia[1695]: / Sep 4 23:50:08.855935 kernel: nvidia-uvm: Loaded the UVM driver, major device number 243. Sep 4 23:50:10.537565 systemd[1]: Started sshd@25-10.200.8.22:22-10.200.16.10:42298.service - OpenSSH per-connection server daemon (10.200.16.10:42298). Sep 4 23:50:10.573227 setup-nvidia[13003]: Thu Sep 4 23:50:10 2025 Sep 4 23:50:10.573227 setup-nvidia[13003]: +---------------------------------------------------------------------------------------+ Sep 4 23:50:10.573227 setup-nvidia[13003]: | NVIDIA-SMI 535.230.02 Driver Version: 535.230.02 CUDA Version: 12.2 | Sep 4 23:50:10.573227 setup-nvidia[13003]: |-----------------------------------------+----------------------+----------------------+ Sep 4 23:50:10.573227 setup-nvidia[13003]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Sep 4 23:50:10.573227 setup-nvidia[13003]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Sep 4 23:50:10.573227 setup-nvidia[13003]: | | | MIG M. | Sep 4 23:50:10.573227 setup-nvidia[13003]: |=========================================+======================+======================| Sep 4 23:50:10.577163 setup-nvidia[13003]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Sep 4 23:50:10.577163 setup-nvidia[13003]: | N/A 25C P0 36W / 250W | 0MiB / 16384MiB | 0% Default | Sep 4 23:50:10.577163 setup-nvidia[13003]: | | | N/A | Sep 4 23:50:10.577163 setup-nvidia[13003]: +-----------------------------------------+----------------------+----------------------+ Sep 4 23:50:10.577274 setup-nvidia[13003]: Sep 4 23:50:10.577274 setup-nvidia[13003]: +---------------------------------------------------------------------------------------+ Sep 4 23:50:10.577274 setup-nvidia[13003]: | Processes: | Sep 4 23:50:10.577274 setup-nvidia[13003]: | GPU GI CI PID Type Process name GPU Memory | Sep 4 23:50:10.577274 setup-nvidia[13003]: | ID ID Usage | Sep 4 23:50:10.577274 setup-nvidia[13003]: |=======================================================================================| Sep 4 23:50:10.577413 setup-nvidia[13003]: | No running processes found | Sep 4 23:50:10.577413 setup-nvidia[13003]: +---------------------------------------------------------------------------------------+ Sep 4 23:50:10.948945 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 4 23:50:10.949602 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 4 23:50:10.949954 systemd[1]: Startup finished in 1.415s (firmware) + 25.327s (loader) + 1.792s (kernel) + 12.276s (initrd) + 3min 21.686s (userspace) = 4min 2.498s. Sep 4 23:50:11.166440 sshd[13008]: Accepted publickey for core from 10.200.16.10 port 42298 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:50:11.167820 sshd-session[13008]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:50:11.172125 systemd-logind[1703]: New session 28 of user core. Sep 4 23:50:11.182916 systemd[1]: Started session-28.scope - Session 28 of User core. Sep 4 23:50:11.611604 sshd[13012]: Connection closed by 10.200.16.10 port 42298 Sep 4 23:50:11.612015 sshd-session[13008]: pam_unix(sshd:session): session closed for user core Sep 4 23:50:11.615203 systemd[1]: sshd@25-10.200.8.22:22-10.200.16.10:42298.service: Deactivated successfully. Sep 4 23:50:11.616850 systemd[1]: session-28.scope: Deactivated successfully. Sep 4 23:50:11.617485 systemd-logind[1703]: Session 28 logged out. Waiting for processes to exit. Sep 4 23:50:11.618342 systemd-logind[1703]: Removed session 28. Sep 4 23:50:11.726126 systemd[1]: Started sshd@26-10.200.8.22:22-10.200.16.10:42300.service - OpenSSH per-connection server daemon (10.200.16.10:42300). Sep 4 23:50:12.353964 sshd[13018]: Accepted publickey for core from 10.200.16.10 port 42300 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:50:12.355439 sshd-session[13018]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:50:12.360131 systemd-logind[1703]: New session 29 of user core. Sep 4 23:50:12.371900 systemd[1]: Started session-29.scope - Session 29 of User core. Sep 4 23:50:12.797699 sshd[13020]: Connection closed by 10.200.16.10 port 42300 Sep 4 23:50:12.798073 sshd-session[13018]: pam_unix(sshd:session): session closed for user core Sep 4 23:50:12.801306 systemd[1]: sshd@26-10.200.8.22:22-10.200.16.10:42300.service: Deactivated successfully. Sep 4 23:50:12.802946 systemd[1]: session-29.scope: Deactivated successfully. Sep 4 23:50:12.803577 systemd-logind[1703]: Session 29 logged out. Waiting for processes to exit. Sep 4 23:50:12.804344 systemd-logind[1703]: Removed session 29. Sep 4 23:50:12.908428 systemd[1]: Started sshd@27-10.200.8.22:22-10.200.16.10:42312.service - OpenSSH per-connection server daemon (10.200.16.10:42312). Sep 4 23:50:13.535732 sshd[13026]: Accepted publickey for core from 10.200.16.10 port 42312 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:50:13.537171 sshd-session[13026]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:50:13.541383 systemd-logind[1703]: New session 30 of user core. Sep 4 23:50:13.556911 systemd[1]: Started session-30.scope - Session 30 of User core. Sep 4 23:50:13.980323 sshd[13028]: Connection closed by 10.200.16.10 port 42312 Sep 4 23:50:13.980712 sshd-session[13026]: pam_unix(sshd:session): session closed for user core Sep 4 23:50:13.983856 systemd[1]: sshd@27-10.200.8.22:22-10.200.16.10:42312.service: Deactivated successfully. Sep 4 23:50:13.985445 systemd[1]: session-30.scope: Deactivated successfully. Sep 4 23:50:13.986094 systemd-logind[1703]: Session 30 logged out. Waiting for processes to exit. Sep 4 23:50:13.986845 systemd-logind[1703]: Removed session 30. Sep 4 23:50:14.091400 systemd[1]: Started sshd@28-10.200.8.22:22-10.200.16.10:42320.service - OpenSSH per-connection server daemon (10.200.16.10:42320). Sep 4 23:50:14.721337 sshd[13034]: Accepted publickey for core from 10.200.16.10 port 42320 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:50:14.722826 sshd-session[13034]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:50:14.727093 systemd-logind[1703]: New session 31 of user core. Sep 4 23:50:14.740906 systemd[1]: Started session-31.scope - Session 31 of User core. Sep 4 23:50:15.169975 sshd[13036]: Connection closed by 10.200.16.10 port 42320 Sep 4 23:50:15.170354 sshd-session[13034]: pam_unix(sshd:session): session closed for user core Sep 4 23:50:15.173546 systemd[1]: sshd@28-10.200.8.22:22-10.200.16.10:42320.service: Deactivated successfully. Sep 4 23:50:15.175192 systemd[1]: session-31.scope: Deactivated successfully. Sep 4 23:50:15.175819 systemd-logind[1703]: Session 31 logged out. Waiting for processes to exit. Sep 4 23:50:15.176636 systemd-logind[1703]: Removed session 31. Sep 4 23:50:15.281341 systemd[1]: Started sshd@29-10.200.8.22:22-10.200.16.10:42328.service - OpenSSH per-connection server daemon (10.200.16.10:42328). Sep 4 23:50:15.907747 sshd[13042]: Accepted publickey for core from 10.200.16.10 port 42328 ssh2: RSA SHA256:PKJ30pVvLwhpqxquio0cPcGWuwoyX9VWl5pfkm7sdzs Sep 4 23:50:15.909158 sshd-session[13042]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:50:15.913420 systemd-logind[1703]: New session 32 of user core. Sep 4 23:50:15.924908 systemd[1]: Started session-32.scope - Session 32 of User core. Sep 4 23:50:17.287713 systemd[1]: Stopping user@0.service - User Manager for UID 0... Sep 4 23:50:17.288522 systemd[2327]: Activating special unit exit.target... Sep 4 23:50:17.288614 systemd[2327]: Stopped target default.target - Main User Target. Sep 4 23:50:17.288657 systemd[2327]: Stopped target basic.target - Basic System. Sep 4 23:50:17.288689 systemd[2327]: Stopped target paths.target - Paths. Sep 4 23:50:17.288718 systemd[2327]: Stopped target sockets.target - Sockets. Sep 4 23:50:17.288746 systemd[2327]: Stopped target timers.target - Timers. Sep 4 23:50:17.289128 systemd[2327]: Closed dbus.socket - D-Bus User Message Bus Socket. Sep 4 23:50:17.289346 systemd[2327]: Removed slice app.slice - User Application Slice. Sep 4 23:50:17.289380 systemd[2327]: Reached target shutdown.target - Shutdown. Sep 4 23:50:17.289539 systemd[2327]: Finished systemd-exit.service - Exit the Session. Sep 4 23:50:17.289579 systemd[2327]: Reached target exit.target - Exit the Session. Sep 4 23:50:17.297775 (sd-pam)[2328]: pam_unix(systemd-user:session): session closed for user root Sep 4 23:50:17.299215 systemd[1]: user@0.service: Deactivated successfully. Sep 4 23:50:17.299581 systemd[1]: Stopped user@0.service - User Manager for UID 0. Sep 4 23:50:17.310958 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Sep 4 23:50:17.320377 systemd[1]: run-user-0.mount: Deactivated successfully. Sep 4 23:50:17.321272 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Sep 4 23:50:17.321470 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Sep 4 23:50:17.323472 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Sep 4 23:50:17.323561 systemd[1]: user-0.slice: Consumed 5min 17.577s CPU time, 891.1M memory peak. Sep 4 23:50:17.323990 systemd-logind[1703]: Removed session c3. Sep 4 23:50:17.867065 sshd[13044]: Connection closed by 10.200.16.10 port 42328 Sep 4 23:50:18.142077 sshd-session[13042]: pam_unix(sshd:session): session closed for user core Sep 4 23:50:18.145658 systemd[1]: sshd@29-10.200.8.22:22-10.200.16.10:42328.service: Deactivated successfully. Sep 4 23:50:18.147449 systemd[1]: session-32.scope: Deactivated successfully. Sep 4 23:50:18.147664 systemd[1]: session-32.scope: Consumed 1.458s CPU time, 14M memory peak. Sep 4 23:50:18.148149 systemd-logind[1703]: Session 32 logged out. Waiting for processes to exit. Sep 4 23:50:18.149085 systemd-logind[1703]: Removed session 32.