Oct 29 00:40:53.163633 kernel: Linux version 6.12.54-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.1_p20250801 p4) 14.3.1 20250801, GNU ld (Gentoo 2.45 p3) 2.45.0) #1 SMP PREEMPT_DYNAMIC Tue Oct 28 22:31:02 -00 2025 Oct 29 00:40:53.163662 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=54ef1c344b2a47697b32f3227bd37f41d37acb1889c1eaea33b22ce408b7b3ae Oct 29 00:40:53.163673 kernel: BIOS-provided physical RAM map: Oct 29 00:40:53.163680 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Oct 29 00:40:53.163687 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Oct 29 00:40:53.163694 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Oct 29 00:40:53.163705 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Oct 29 00:40:53.163713 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Oct 29 00:40:53.163720 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Oct 29 00:40:53.163727 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Oct 29 00:40:53.163734 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Oct 29 00:40:53.163742 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Oct 29 00:40:53.163749 kernel: printk: legacy bootconsole [earlyser0] enabled Oct 29 00:40:53.163756 kernel: NX (Execute Disable) protection: active Oct 29 00:40:53.163768 kernel: APIC: Static calls initialized Oct 29 00:40:53.163776 kernel: efi: EFI v2.7 by Microsoft Oct 29 00:40:53.163785 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3ee60a98 RNG=0x3ffd1018 Oct 29 00:40:53.163793 kernel: random: crng init done Oct 29 00:40:53.163801 kernel: secureboot: Secure boot disabled Oct 29 00:40:53.163809 kernel: SMBIOS 3.1.0 present. Oct 29 00:40:53.163817 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Oct 29 00:40:53.163828 kernel: DMI: Memory slots populated: 3/3 Oct 29 00:40:53.163835 kernel: Hypervisor detected: Microsoft Hyper-V Oct 29 00:40:53.163843 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Oct 29 00:40:53.163852 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Oct 29 00:40:53.163859 kernel: Hyper-V: Using hypercall for remote TLB flush Oct 29 00:40:53.163868 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Oct 29 00:40:53.163876 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Oct 29 00:40:53.163885 kernel: tsc: Marking TSC unstable due to running on Hyper-V Oct 29 00:40:53.163894 kernel: tsc: Detected 2593.993 MHz processor Oct 29 00:40:53.163905 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Oct 29 00:40:53.163914 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Oct 29 00:40:53.163922 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Oct 29 00:40:53.163930 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Oct 29 00:40:53.163939 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Oct 29 00:40:53.163947 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Oct 29 00:40:53.163955 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Oct 29 00:40:53.163966 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Oct 29 00:40:53.163974 kernel: Using GB pages for direct mapping Oct 29 00:40:53.163983 kernel: ACPI: Early table checksum verification disabled Oct 29 00:40:53.163995 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Oct 29 00:40:53.164004 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 29 00:40:53.164013 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 29 00:40:53.164024 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Oct 29 00:40:53.164033 kernel: ACPI: FACS 0x000000003FFFE000 000040 Oct 29 00:40:53.164042 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 29 00:40:53.164051 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 29 00:40:53.164060 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 29 00:40:53.164069 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 29 00:40:53.164080 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 29 00:40:53.164089 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 29 00:40:53.164098 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 29 00:40:53.164107 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Oct 29 00:40:53.164115 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Oct 29 00:40:53.164124 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Oct 29 00:40:53.164147 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Oct 29 00:40:53.164157 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Oct 29 00:40:53.164166 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Oct 29 00:40:53.164175 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Oct 29 00:40:53.164184 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Oct 29 00:40:53.164193 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Oct 29 00:40:53.164201 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Oct 29 00:40:53.164213 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Oct 29 00:40:53.164222 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Oct 29 00:40:53.164231 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Oct 29 00:40:53.164240 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Oct 29 00:40:53.164249 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Oct 29 00:40:53.164258 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Oct 29 00:40:53.164267 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Oct 29 00:40:53.164278 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Oct 29 00:40:53.164287 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] Oct 29 00:40:53.164296 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] Oct 29 00:40:53.164305 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] Oct 29 00:40:53.164314 kernel: Zone ranges: Oct 29 00:40:53.164323 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Oct 29 00:40:53.164332 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Oct 29 00:40:53.164343 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Oct 29 00:40:53.164352 kernel: Device empty Oct 29 00:40:53.164361 kernel: Movable zone start for each node Oct 29 00:40:53.164370 kernel: Early memory node ranges Oct 29 00:40:53.164378 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Oct 29 00:40:53.164387 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Oct 29 00:40:53.164396 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Oct 29 00:40:53.164411 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Oct 29 00:40:53.164426 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Oct 29 00:40:53.164435 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Oct 29 00:40:53.164444 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Oct 29 00:40:53.164453 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Oct 29 00:40:53.164462 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Oct 29 00:40:53.164470 kernel: ACPI: PM-Timer IO Port: 0x408 Oct 29 00:40:53.164479 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Oct 29 00:40:53.164496 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Oct 29 00:40:53.164505 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Oct 29 00:40:53.164514 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Oct 29 00:40:53.164523 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Oct 29 00:40:53.164532 kernel: CPU topo: Max. logical packages: 1 Oct 29 00:40:53.164541 kernel: CPU topo: Max. logical dies: 1 Oct 29 00:40:53.164550 kernel: CPU topo: Max. dies per package: 1 Oct 29 00:40:53.164561 kernel: CPU topo: Max. threads per core: 1 Oct 29 00:40:53.164570 kernel: CPU topo: Num. cores per package: 6 Oct 29 00:40:53.164579 kernel: CPU topo: Num. threads per package: 6 Oct 29 00:40:53.164588 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs Oct 29 00:40:53.164597 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Oct 29 00:40:53.164606 kernel: Booting paravirtualized kernel on Hyper-V Oct 29 00:40:53.164615 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Oct 29 00:40:53.164626 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Oct 29 00:40:53.164635 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 Oct 29 00:40:53.164644 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 Oct 29 00:40:53.164653 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Oct 29 00:40:53.164662 kernel: Hyper-V: PV spinlocks enabled Oct 29 00:40:53.164671 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Oct 29 00:40:53.164681 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=54ef1c344b2a47697b32f3227bd37f41d37acb1889c1eaea33b22ce408b7b3ae Oct 29 00:40:53.164692 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Oct 29 00:40:53.164702 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Oct 29 00:40:53.164711 kernel: Fallback order for Node 0: 0 Oct 29 00:40:53.164720 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 Oct 29 00:40:53.164728 kernel: Policy zone: Normal Oct 29 00:40:53.164738 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 29 00:40:53.164756 kernel: software IO TLB: area num 8. Oct 29 00:40:53.164765 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Oct 29 00:40:53.164775 kernel: Kernel/User page tables isolation: enabled Oct 29 00:40:53.164786 kernel: ftrace: allocating 40092 entries in 157 pages Oct 29 00:40:53.164795 kernel: ftrace: allocated 157 pages with 5 groups Oct 29 00:40:53.164805 kernel: Dynamic Preempt: voluntary Oct 29 00:40:53.164814 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 29 00:40:53.164824 kernel: rcu: RCU event tracing is enabled. Oct 29 00:40:53.164834 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Oct 29 00:40:53.164880 kernel: Trampoline variant of Tasks RCU enabled. Oct 29 00:40:53.165128 kernel: Rude variant of Tasks RCU enabled. Oct 29 00:40:53.165154 kernel: Tracing variant of Tasks RCU enabled. Oct 29 00:40:53.165165 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 29 00:40:53.165174 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Oct 29 00:40:53.165188 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Oct 29 00:40:53.165198 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Oct 29 00:40:53.165207 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Oct 29 00:40:53.165217 kernel: Using NULL legacy PIC Oct 29 00:40:53.165226 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Oct 29 00:40:53.165236 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Oct 29 00:40:53.165245 kernel: Console: colour dummy device 80x25 Oct 29 00:40:53.165257 kernel: printk: legacy console [tty1] enabled Oct 29 00:40:53.165266 kernel: printk: legacy console [ttyS0] enabled Oct 29 00:40:53.165276 kernel: printk: legacy bootconsole [earlyser0] disabled Oct 29 00:40:53.165285 kernel: ACPI: Core revision 20240827 Oct 29 00:40:53.165294 kernel: Failed to register legacy timer interrupt Oct 29 00:40:53.165304 kernel: APIC: Switch to symmetric I/O mode setup Oct 29 00:40:53.165313 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Oct 29 00:40:53.165325 kernel: Hyper-V: enabling crash_kexec_post_notifiers Oct 29 00:40:53.165334 kernel: Hyper-V: Using IPI hypercalls Oct 29 00:40:53.165343 kernel: APIC: send_IPI() replaced with hv_send_ipi() Oct 29 00:40:53.165353 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Oct 29 00:40:53.165362 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Oct 29 00:40:53.165372 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Oct 29 00:40:53.165381 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Oct 29 00:40:53.165393 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Oct 29 00:40:53.165402 kernel: Hyper-V: Using enlightened APIC (xapic mode) Oct 29 00:40:53.165411 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Oct 29 00:40:53.165421 kernel: APIC: read() replaced with hv_apic_read() Oct 29 00:40:53.165430 kernel: APIC: write() replaced with hv_apic_write() Oct 29 00:40:53.165439 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Oct 29 00:40:53.165449 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Oct 29 00:40:53.165461 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593993) Oct 29 00:40:53.165470 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Oct 29 00:40:53.165479 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Oct 29 00:40:53.165489 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Oct 29 00:40:53.165497 kernel: Spectre V2 : Mitigation: Retpolines Oct 29 00:40:53.165506 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Oct 29 00:40:53.165515 kernel: Speculative Store Bypass: Vulnerable Oct 29 00:40:53.165524 kernel: MDS: Mitigation: Clear CPU buffers Oct 29 00:40:53.165533 kernel: TAA: Mitigation: Clear CPU buffers Oct 29 00:40:53.165544 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Oct 29 00:40:53.165554 kernel: active return thunk: its_return_thunk Oct 29 00:40:53.165562 kernel: ITS: Mitigation: Aligned branch/return thunks Oct 29 00:40:53.165571 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Oct 29 00:40:53.165580 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Oct 29 00:40:53.165589 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Oct 29 00:40:53.165598 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Oct 29 00:40:53.165608 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Oct 29 00:40:53.165616 kernel: Freeing SMP alternatives memory: 32K Oct 29 00:40:53.165625 kernel: pid_max: default: 32768 minimum: 301 Oct 29 00:40:53.165637 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Oct 29 00:40:53.165646 kernel: landlock: Up and running. Oct 29 00:40:53.165655 kernel: SELinux: Initializing. Oct 29 00:40:53.165664 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Oct 29 00:40:53.165673 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Oct 29 00:40:53.165682 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Oct 29 00:40:53.165692 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Oct 29 00:40:53.165701 kernel: signal: max sigframe size: 1776 Oct 29 00:40:53.165710 kernel: rcu: Hierarchical SRCU implementation. Oct 29 00:40:53.165722 kernel: rcu: Max phase no-delay instances is 400. Oct 29 00:40:53.165732 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Oct 29 00:40:53.165741 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Oct 29 00:40:53.165751 kernel: smp: Bringing up secondary CPUs ... Oct 29 00:40:53.165760 kernel: smpboot: x86: Booting SMP configuration: Oct 29 00:40:53.165769 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Oct 29 00:40:53.165778 kernel: smp: Brought up 1 node, 6 CPUs Oct 29 00:40:53.165788 kernel: smpboot: Total of 6 processors activated (31127.91 BogoMIPS) Oct 29 00:40:53.165800 kernel: Memory: 115361620K/117439364K available (14336K kernel code, 2443K rwdata, 26064K rodata, 15964K init, 2080K bss, 2067468K reserved, 0K cma-reserved) Oct 29 00:40:53.165809 kernel: devtmpfs: initialized Oct 29 00:40:53.165819 kernel: x86/mm: Memory block size: 1024MB Oct 29 00:40:53.165828 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Oct 29 00:40:53.165838 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 29 00:40:53.165847 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Oct 29 00:40:53.165859 kernel: pinctrl core: initialized pinctrl subsystem Oct 29 00:40:53.165868 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Oct 29 00:40:53.165878 kernel: audit: initializing netlink subsys (disabled) Oct 29 00:40:53.165887 kernel: audit: type=2000 audit(1761698447.028:1): state=initialized audit_enabled=0 res=1 Oct 29 00:40:53.165896 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 29 00:40:53.165906 kernel: thermal_sys: Registered thermal governor 'user_space' Oct 29 00:40:53.165915 kernel: cpuidle: using governor menu Oct 29 00:40:53.165924 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 29 00:40:53.165936 kernel: dca service started, version 1.12.1 Oct 29 00:40:53.165946 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Oct 29 00:40:53.165955 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Oct 29 00:40:53.165964 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Oct 29 00:40:53.165973 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Oct 29 00:40:53.165983 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Oct 29 00:40:53.165992 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Oct 29 00:40:53.166004 kernel: ACPI: Added _OSI(Module Device) Oct 29 00:40:53.166013 kernel: ACPI: Added _OSI(Processor Device) Oct 29 00:40:53.166022 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 29 00:40:53.166032 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 29 00:40:53.166041 kernel: ACPI: Interpreter enabled Oct 29 00:40:53.166050 kernel: ACPI: PM: (supports S0 S5) Oct 29 00:40:53.166059 kernel: ACPI: Using IOAPIC for interrupt routing Oct 29 00:40:53.166071 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Oct 29 00:40:53.166081 kernel: PCI: Ignoring E820 reservations for host bridge windows Oct 29 00:40:53.166090 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Oct 29 00:40:53.166099 kernel: iommu: Default domain type: Translated Oct 29 00:40:53.166108 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Oct 29 00:40:53.166118 kernel: efivars: Registered efivars operations Oct 29 00:40:53.166127 kernel: PCI: Using ACPI for IRQ routing Oct 29 00:40:53.166148 kernel: PCI: System does not support PCI Oct 29 00:40:53.166158 kernel: vgaarb: loaded Oct 29 00:40:53.166167 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Oct 29 00:40:53.166176 kernel: VFS: Disk quotas dquot_6.6.0 Oct 29 00:40:53.166186 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 29 00:40:53.166195 kernel: pnp: PnP ACPI init Oct 29 00:40:53.166204 kernel: pnp: PnP ACPI: found 3 devices Oct 29 00:40:53.166216 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Oct 29 00:40:53.166226 kernel: NET: Registered PF_INET protocol family Oct 29 00:40:53.166235 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 29 00:40:53.166245 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Oct 29 00:40:53.166254 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Oct 29 00:40:53.166263 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 29 00:40:53.166273 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Oct 29 00:40:53.166285 kernel: TCP: Hash tables configured (established 524288 bind 65536) Oct 29 00:40:53.166294 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Oct 29 00:40:53.166304 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Oct 29 00:40:53.166313 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Oct 29 00:40:53.166322 kernel: NET: Registered PF_XDP protocol family Oct 29 00:40:53.166331 kernel: PCI: CLS 0 bytes, default 64 Oct 29 00:40:53.166341 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Oct 29 00:40:53.166352 kernel: software IO TLB: mapped [mem 0x000000003ae60000-0x000000003ee60000] (64MB) Oct 29 00:40:53.166362 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Oct 29 00:40:53.166371 kernel: Initialise system trusted keyrings Oct 29 00:40:53.166381 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Oct 29 00:40:53.166390 kernel: Key type asymmetric registered Oct 29 00:40:53.166399 kernel: Asymmetric key parser 'x509' registered Oct 29 00:40:53.166408 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Oct 29 00:40:53.166420 kernel: io scheduler mq-deadline registered Oct 29 00:40:53.166429 kernel: io scheduler kyber registered Oct 29 00:40:53.166439 kernel: io scheduler bfq registered Oct 29 00:40:53.166448 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Oct 29 00:40:53.166458 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 29 00:40:53.166467 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Oct 29 00:40:53.166476 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Oct 29 00:40:53.166488 kernel: i8042: PNP: No PS/2 controller found. Oct 29 00:40:53.166750 kernel: rtc_cmos 00:02: registered as rtc0 Oct 29 00:40:53.166892 kernel: rtc_cmos 00:02: setting system clock to 2025-10-29T00:40:48 UTC (1761698448) Oct 29 00:40:53.167027 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Oct 29 00:40:53.167041 kernel: intel_pstate: CPU model not supported Oct 29 00:40:53.167051 kernel: efifb: probing for efifb Oct 29 00:40:53.167065 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Oct 29 00:40:53.167074 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Oct 29 00:40:53.167083 kernel: efifb: scrolling: redraw Oct 29 00:40:53.167093 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Oct 29 00:40:53.167102 kernel: Console: switching to colour frame buffer device 128x48 Oct 29 00:40:53.167111 kernel: fb0: EFI VGA frame buffer device Oct 29 00:40:53.167121 kernel: pstore: Using crash dump compression: deflate Oct 29 00:40:53.167147 kernel: pstore: Registered efi_pstore as persistent store backend Oct 29 00:40:53.167162 kernel: NET: Registered PF_INET6 protocol family Oct 29 00:40:53.167171 kernel: Segment Routing with IPv6 Oct 29 00:40:53.167180 kernel: In-situ OAM (IOAM) with IPv6 Oct 29 00:40:53.167190 kernel: NET: Registered PF_PACKET protocol family Oct 29 00:40:53.167199 kernel: Key type dns_resolver registered Oct 29 00:40:53.167208 kernel: IPI shorthand broadcast: enabled Oct 29 00:40:53.167218 kernel: sched_clock: Marking stable (2016006200, 110278600)->(2467413700, -341128900) Oct 29 00:40:53.167229 kernel: registered taskstats version 1 Oct 29 00:40:53.167239 kernel: Loading compiled-in X.509 certificates Oct 29 00:40:53.167248 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.54-flatcar: 4eb70affb0e364bb9bcbea2a9416e57c31aed070' Oct 29 00:40:53.167257 kernel: Demotion targets for Node 0: null Oct 29 00:40:53.167267 kernel: Key type .fscrypt registered Oct 29 00:40:53.167276 kernel: Key type fscrypt-provisioning registered Oct 29 00:40:53.167285 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 29 00:40:53.167297 kernel: ima: Allocated hash algorithm: sha1 Oct 29 00:40:53.167306 kernel: ima: No architecture policies found Oct 29 00:40:53.167315 kernel: clk: Disabling unused clocks Oct 29 00:40:53.167324 kernel: Freeing unused kernel image (initmem) memory: 15964K Oct 29 00:40:53.167334 kernel: Write protecting the kernel read-only data: 40960k Oct 29 00:40:53.167343 kernel: Freeing unused kernel image (rodata/data gap) memory: 560K Oct 29 00:40:53.167352 kernel: Run /init as init process Oct 29 00:40:53.167364 kernel: with arguments: Oct 29 00:40:53.167373 kernel: /init Oct 29 00:40:53.167382 kernel: with environment: Oct 29 00:40:53.167392 kernel: HOME=/ Oct 29 00:40:53.167401 kernel: TERM=linux Oct 29 00:40:53.167410 kernel: hv_vmbus: Vmbus version:4.0 Oct 29 00:40:53.167420 kernel: Invalid ELF header magic: != \u007fELF Oct 29 00:40:53.167429 kernel: Invalid ELF header magic: != \u007fELF Oct 29 00:40:53.167441 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 29 00:40:53.167450 kernel: hv_vmbus: registering driver hid_hyperv Oct 29 00:40:53.167460 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input0 Oct 29 00:40:53.167622 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Oct 29 00:40:53.167638 kernel: SCSI subsystem initialized Oct 29 00:40:53.167648 kernel: Invalid ELF header magic: != \u007fELF Oct 29 00:40:53.167661 kernel: hv_vmbus: registering driver hyperv_keyboard Oct 29 00:40:53.167671 kernel: input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/VMBUS:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input1 Oct 29 00:40:53.167680 kernel: pps_core: LinuxPPS API ver. 1 registered Oct 29 00:40:53.167690 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Oct 29 00:40:53.167699 kernel: PTP clock support registered Oct 29 00:40:53.167708 kernel: hv_utils: Registering HyperV Utility Driver Oct 29 00:40:53.167718 kernel: hv_vmbus: registering driver hv_utils Oct 29 00:40:53.167730 kernel: hv_utils: Heartbeat IC version 3.0 Oct 29 00:40:53.167739 kernel: hv_utils: Shutdown IC version 3.2 Oct 29 00:40:53.167748 kernel: hv_utils: TimeSync IC version 4.0 Oct 29 00:40:53.167758 kernel: hv_vmbus: registering driver hv_storvsc Oct 29 00:40:53.167937 kernel: scsi host0: storvsc_host_t Oct 29 00:40:53.168100 kernel: scsi host1: storvsc_host_t Oct 29 00:40:53.168300 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Oct 29 00:40:53.168474 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Oct 29 00:40:53.168651 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Oct 29 00:40:53.168811 kernel: sd 0:0:0:0: [sda] 71737344 512-byte logical blocks: (36.7 GB/34.2 GiB) Oct 29 00:40:53.168970 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Oct 29 00:40:53.169148 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Oct 29 00:40:53.169314 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks Oct 29 00:40:53.169473 kernel: sd 0:0:0:1: [sdb] Write Protect is off Oct 29 00:40:53.169632 kernel: sd 0:0:0:0: [sda] Write Protect is off Oct 29 00:40:53.169790 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Oct 29 00:40:53.169947 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Oct 29 00:40:53.170105 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Oct 29 00:40:53.170304 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Oct 29 00:40:53.170473 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#389 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 29 00:40:53.170622 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#67 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 29 00:40:53.170768 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#259 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 29 00:40:53.170920 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#74 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 29 00:40:53.170934 kernel: sdb: sdb1 Oct 29 00:40:53.171089 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk Oct 29 00:40:53.171104 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Oct 29 00:40:53.171272 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Oct 29 00:40:53.171288 kernel: hv_vmbus: registering driver hv_pci Oct 29 00:40:53.171442 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Oct 29 00:40:53.171595 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Oct 29 00:40:53.171778 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Oct 29 00:40:53.171925 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Oct 29 00:40:53.172071 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Oct 29 00:40:53.172258 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint Oct 29 00:40:53.172426 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] Oct 29 00:40:53.172585 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] Oct 29 00:40:53.172741 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] Oct 29 00:40:53.172907 kernel: pci 0001:00:00.0: Enabling HDA controller Oct 29 00:40:53.172922 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Oct 29 00:40:53.172936 kernel: device-mapper: uevent: version 1.0.3 Oct 29 00:40:53.172947 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Oct 29 00:40:53.173094 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Oct 29 00:40:53.173266 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned Oct 29 00:40:53.173426 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned Oct 29 00:40:53.173582 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned Oct 29 00:40:53.173601 kernel: device-mapper: verity: sha256 using shash "sha256-generic" Oct 29 00:40:53.173612 kernel: Invalid ELF header magic: != \u007fELF Oct 29 00:40:53.173621 kernel: Invalid ELF header magic: != \u007fELF Oct 29 00:40:53.173780 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Oct 29 00:40:53.173795 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Oct 29 00:40:53.173954 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Oct 29 00:40:53.173969 kernel: raid6: avx2x4 gen() 30868 MB/s Oct 29 00:40:53.173979 kernel: raid6: avx2x2 gen() 30993 MB/s Oct 29 00:40:53.173992 kernel: raid6: avx2x1 gen() 26925 MB/s Oct 29 00:40:53.174002 kernel: raid6: using algorithm avx2x2 gen() 30993 MB/s Oct 29 00:40:53.174012 kernel: raid6: .... xor() 18353 MB/s, rmw enabled Oct 29 00:40:53.174022 kernel: raid6: using avx2x2 recovery algorithm Oct 29 00:40:53.174032 kernel: Invalid ELF header magic: != \u007fELF Oct 29 00:40:53.174042 kernel: Invalid ELF header magic: != \u007fELF Oct 29 00:40:53.174052 kernel: Invalid ELF header magic: != \u007fELF Oct 29 00:40:53.174064 kernel: xor: automatically using best checksumming function avx Oct 29 00:40:53.174074 kernel: Invalid ELF header magic: != \u007fELF Oct 29 00:40:53.174083 kernel: Btrfs loaded, zoned=no, fsverity=no Oct 29 00:40:53.174093 kernel: BTRFS: device fsid c0171910-1eb4-4fd7-b94c-9d6b11be282f devid 1 transid 39 /dev/mapper/usr (254:0) scanned by mount (434) Oct 29 00:40:53.174103 kernel: BTRFS info (device dm-0): first mount of filesystem c0171910-1eb4-4fd7-b94c-9d6b11be282f Oct 29 00:40:53.174113 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Oct 29 00:40:53.174123 kernel: BTRFS info (device dm-0): disabling log replay at mount time Oct 29 00:40:53.174147 kernel: BTRFS info (device dm-0): enabling free space tree Oct 29 00:40:53.174158 kernel: Invalid ELF header magic: != \u007fELF Oct 29 00:40:53.174167 kernel: loop: module loaded Oct 29 00:40:53.174177 kernel: loop0: detected capacity change from 0 to 100120 Oct 29 00:40:53.174187 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Oct 29 00:40:53.174198 systemd[1]: Successfully made /usr/ read-only. Oct 29 00:40:53.174211 systemd[1]: systemd 257.7 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +IPE +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Oct 29 00:40:53.174226 systemd[1]: Detected virtualization microsoft. Oct 29 00:40:53.174236 systemd[1]: Detected architecture x86-64. Oct 29 00:40:53.174246 systemd[1]: Running in initrd. Oct 29 00:40:53.174256 systemd[1]: No hostname configured, using default hostname. Oct 29 00:40:53.174266 systemd[1]: Hostname set to . Oct 29 00:40:53.174276 systemd[1]: Initializing machine ID from random generator. Oct 29 00:40:53.174289 systemd[1]: Queued start job for default target initrd.target. Oct 29 00:40:53.174299 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Oct 29 00:40:53.174310 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 29 00:40:53.174320 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 29 00:40:53.174330 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Oct 29 00:40:53.174341 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 29 00:40:53.174354 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Oct 29 00:40:53.174365 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Oct 29 00:40:53.174685 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 29 00:40:53.174702 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 29 00:40:53.174715 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Oct 29 00:40:53.174726 systemd[1]: Reached target paths.target - Path Units. Oct 29 00:40:53.174736 systemd[1]: Reached target slices.target - Slice Units. Oct 29 00:40:53.174746 systemd[1]: Reached target swap.target - Swaps. Oct 29 00:40:53.174757 systemd[1]: Reached target timers.target - Timer Units. Oct 29 00:40:53.174767 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Oct 29 00:40:53.174777 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 29 00:40:53.174790 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Oct 29 00:40:53.174801 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Oct 29 00:40:53.174811 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 29 00:40:53.174822 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 29 00:40:53.174832 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 29 00:40:53.174843 systemd[1]: Reached target sockets.target - Socket Units. Oct 29 00:40:53.174853 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Oct 29 00:40:53.174869 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Oct 29 00:40:53.174879 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 29 00:40:53.174889 systemd[1]: Finished network-cleanup.service - Network Cleanup. Oct 29 00:40:53.174943 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). Oct 29 00:40:53.174959 systemd[1]: Starting systemd-fsck-usr.service... Oct 29 00:40:53.174970 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 29 00:40:53.174989 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 29 00:40:53.175000 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 29 00:40:53.175011 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Oct 29 00:40:53.175022 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 29 00:40:53.175035 systemd[1]: Finished systemd-fsck-usr.service. Oct 29 00:40:53.175045 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 29 00:40:53.175081 systemd-journald[569]: Collecting audit messages is disabled. Oct 29 00:40:53.175108 systemd-journald[569]: Journal started Oct 29 00:40:53.175128 systemd-journald[569]: Runtime Journal (/run/log/journal/d201f3fdaa90415b9edbd9d2be828dfb) is 8M, max 2.2G, 2.1G free. Oct 29 00:40:53.181607 systemd[1]: Started systemd-journald.service - Journal Service. Oct 29 00:40:53.183695 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 29 00:40:53.320155 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Oct 29 00:40:53.328767 systemd-modules-load[573]: Inserted module 'br_netfilter' Oct 29 00:40:53.330469 kernel: Bridge firewalling registered Oct 29 00:40:53.330047 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 29 00:40:53.332097 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 29 00:40:53.347608 systemd-tmpfiles[585]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Oct 29 00:40:53.369387 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 29 00:40:53.380466 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 29 00:40:53.383751 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 29 00:40:53.400763 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 29 00:40:53.409932 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 29 00:40:53.420082 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 29 00:40:53.423019 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 29 00:40:53.430374 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 29 00:40:53.477261 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 29 00:40:53.481299 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Oct 29 00:40:53.548759 dracut-cmdline[616]: Using kernel command line parameters: rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1 rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=54ef1c344b2a47697b32f3227bd37f41d37acb1889c1eaea33b22ce408b7b3ae Oct 29 00:40:53.625461 systemd-resolved[601]: Positive Trust Anchors: Oct 29 00:40:53.625476 systemd-resolved[601]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 29 00:40:53.625481 systemd-resolved[601]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Oct 29 00:40:53.625512 systemd-resolved[601]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Oct 29 00:40:53.643351 systemd-resolved[601]: Defaulting to hostname 'linux'. Oct 29 00:40:53.644403 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 29 00:40:53.672816 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 29 00:40:53.813172 kernel: Loading iSCSI transport class v2.0-870. Oct 29 00:40:53.878155 kernel: iscsi: registered transport (tcp) Oct 29 00:40:53.945117 kernel: iscsi: registered transport (qla4xxx) Oct 29 00:40:53.945181 kernel: QLogic iSCSI HBA Driver Oct 29 00:40:54.030753 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 29 00:40:54.066924 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 29 00:40:54.069573 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 29 00:40:54.134029 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Oct 29 00:40:54.137070 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Oct 29 00:40:54.150558 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Oct 29 00:40:54.206447 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Oct 29 00:40:54.209629 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 29 00:40:54.252585 systemd-udevd[853]: Using default interface naming scheme 'v257'. Oct 29 00:40:54.264110 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 29 00:40:54.266583 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Oct 29 00:40:54.305323 dracut-pre-trigger[916]: rd.md=0: removing MD RAID activation Oct 29 00:40:54.312002 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 29 00:40:54.315087 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 29 00:40:54.333271 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Oct 29 00:40:54.335414 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 29 00:40:54.384447 systemd-networkd[973]: lo: Link UP Oct 29 00:40:54.384455 systemd-networkd[973]: lo: Gained carrier Oct 29 00:40:54.384976 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 29 00:40:54.391318 systemd[1]: Reached target network.target - Network. Oct 29 00:40:54.412225 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 29 00:40:54.447756 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Oct 29 00:40:54.504816 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 29 00:40:54.504882 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 29 00:40:54.513622 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Oct 29 00:40:54.522761 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 29 00:40:54.560073 kernel: cryptd: max_cpu_qlen set to 1000 Oct 29 00:40:54.614151 kernel: hv_vmbus: registering driver hv_netvsc Oct 29 00:40:54.636968 kernel: AES CTR mode by8 optimization enabled Oct 29 00:40:54.635657 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 29 00:40:54.932003 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Oct 29 00:40:54.942322 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Oct 29 00:40:55.041165 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Oct 29 00:40:55.076868 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Oct 29 00:40:55.180704 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Oct 29 00:40:56.310343 disk-uuid[1139]: Warning: The kernel is still using the old partition table. Oct 29 00:40:56.310343 disk-uuid[1139]: The new table will be used at the next reboot or after you Oct 29 00:40:56.310343 disk-uuid[1139]: run partprobe(8) or kpartx(8) Oct 29 00:40:56.310343 disk-uuid[1139]: The operation has completed successfully. Oct 29 00:40:57.324658 systemd-networkd[973]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Oct 29 00:40:57.324670 systemd-networkd[973]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 29 00:40:57.326333 systemd-networkd[973]: eth0: Link UP Oct 29 00:40:57.326499 systemd-networkd[973]: eth0: Gained carrier Oct 29 00:40:57.326510 systemd-networkd[973]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Oct 29 00:40:57.353859 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Oct 29 00:40:57.362856 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 29 00:40:57.362952 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Oct 29 00:40:57.373939 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Oct 29 00:40:57.374178 systemd-networkd[973]: eth0: DHCPv4 address 10.200.8.23/24, gateway 10.200.8.1 acquired from 168.63.129.16 Oct 29 00:40:57.377948 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 29 00:40:57.391757 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 29 00:40:57.406390 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Oct 29 00:40:57.415889 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Oct 29 00:40:57.463157 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Oct 29 00:40:57.483156 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1292) Oct 29 00:40:57.492691 kernel: BTRFS info (device sda6): first mount of filesystem ba5c42d5-4e97-4410-b3e4-abc54f9b4dae Oct 29 00:40:57.492728 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Oct 29 00:40:57.543166 kernel: BTRFS info (device sda6): turning on async discard Oct 29 00:40:57.543236 kernel: BTRFS info (device sda6): enabling free space tree Oct 29 00:40:57.556148 kernel: BTRFS info (device sda6): last unmount of filesystem ba5c42d5-4e97-4410-b3e4-abc54f9b4dae Oct 29 00:40:57.557220 systemd[1]: Finished ignition-setup.service - Ignition (setup). Oct 29 00:40:57.561939 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Oct 29 00:40:59.255305 systemd-networkd[973]: eth0: Gained IPv6LL Oct 29 00:40:59.511280 ignition[1312]: Ignition 2.22.0 Oct 29 00:40:59.511294 ignition[1312]: Stage: fetch-offline Oct 29 00:40:59.511528 ignition[1312]: no configs at "/usr/lib/ignition/base.d" Oct 29 00:40:59.516129 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Oct 29 00:40:59.511542 ignition[1312]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 29 00:40:59.522211 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Oct 29 00:40:59.512086 ignition[1312]: parsed url from cmdline: "" Oct 29 00:40:59.512090 ignition[1312]: no config URL provided Oct 29 00:40:59.512096 ignition[1312]: reading system config file "/usr/lib/ignition/user.ign" Oct 29 00:40:59.512107 ignition[1312]: no config at "/usr/lib/ignition/user.ign" Oct 29 00:40:59.512116 ignition[1312]: failed to fetch config: resource requires networking Oct 29 00:40:59.512284 ignition[1312]: Ignition finished successfully Oct 29 00:40:59.568300 ignition[1322]: Ignition 2.22.0 Oct 29 00:40:59.568313 ignition[1322]: Stage: fetch Oct 29 00:40:59.568576 ignition[1322]: no configs at "/usr/lib/ignition/base.d" Oct 29 00:40:59.568587 ignition[1322]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 29 00:40:59.568697 ignition[1322]: parsed url from cmdline: "" Oct 29 00:40:59.568702 ignition[1322]: no config URL provided Oct 29 00:40:59.568707 ignition[1322]: reading system config file "/usr/lib/ignition/user.ign" Oct 29 00:40:59.568716 ignition[1322]: no config at "/usr/lib/ignition/user.ign" Oct 29 00:40:59.568734 ignition[1322]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Oct 29 00:40:59.673316 ignition[1322]: GET result: OK Oct 29 00:40:59.673358 ignition[1322]: config has been read from IMDS userdata Oct 29 00:40:59.673368 ignition[1322]: parsing config with SHA512: 37a09a492e0d2a24668735ec8129041f0a1916558a1f468475dbb9232b63971c0c7db51c37ecdb5b9c4d18b203fec9006e603be25e2a5e67f1e067c485720fdb Oct 29 00:40:59.677525 unknown[1322]: fetched base config from "system" Oct 29 00:40:59.677689 ignition[1322]: fetch: fetch complete Oct 29 00:40:59.677532 unknown[1322]: fetched base config from "system" Oct 29 00:40:59.677694 ignition[1322]: fetch: fetch passed Oct 29 00:40:59.677537 unknown[1322]: fetched user config from "azure" Oct 29 00:40:59.677739 ignition[1322]: Ignition finished successfully Oct 29 00:40:59.680019 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Oct 29 00:40:59.687778 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Oct 29 00:40:59.747094 ignition[1332]: Ignition 2.22.0 Oct 29 00:40:59.747108 ignition[1332]: Stage: kargs Oct 29 00:40:59.747352 ignition[1332]: no configs at "/usr/lib/ignition/base.d" Oct 29 00:40:59.747363 ignition[1332]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 29 00:40:59.753373 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Oct 29 00:40:59.747814 ignition[1332]: kargs: kargs passed Oct 29 00:40:59.757152 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Oct 29 00:40:59.747858 ignition[1332]: Ignition finished successfully Oct 29 00:40:59.800333 ignition[1341]: Ignition 2.22.0 Oct 29 00:40:59.800345 ignition[1341]: Stage: disks Oct 29 00:40:59.800595 ignition[1341]: no configs at "/usr/lib/ignition/base.d" Oct 29 00:40:59.800607 ignition[1341]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 29 00:40:59.806266 systemd[1]: Finished ignition-disks.service - Ignition (disks). Oct 29 00:40:59.801286 ignition[1341]: disks: disks passed Oct 29 00:40:59.812723 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Oct 29 00:40:59.801334 ignition[1341]: Ignition finished successfully Oct 29 00:40:59.821415 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Oct 29 00:40:59.830701 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 29 00:40:59.837603 systemd[1]: Reached target sysinit.target - System Initialization. Oct 29 00:40:59.846589 systemd[1]: Reached target basic.target - Basic System. Oct 29 00:40:59.854206 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Oct 29 00:41:00.044166 systemd-fsck[1353]: ROOT: clean, 15/7340400 files, 470001/7359488 blocks Oct 29 00:41:00.049866 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Oct 29 00:41:00.054305 systemd[1]: Mounting sysroot.mount - /sysroot... Oct 29 00:41:01.722167 kernel: EXT4-fs (sda9): mounted filesystem ef53721c-fae5-4ad9-8976-8181c84bc175 r/w with ordered data mode. Quota mode: none. Oct 29 00:41:01.723005 systemd[1]: Mounted sysroot.mount - /sysroot. Oct 29 00:41:01.725141 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Oct 29 00:41:01.765960 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 29 00:41:01.789702 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Oct 29 00:41:01.792227 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Oct 29 00:41:01.796964 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Oct 29 00:41:01.821644 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1368) Oct 29 00:41:01.821670 kernel: BTRFS info (device sda6): first mount of filesystem ba5c42d5-4e97-4410-b3e4-abc54f9b4dae Oct 29 00:41:01.821684 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Oct 29 00:41:01.797000 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Oct 29 00:41:01.815939 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Oct 29 00:41:01.827226 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Oct 29 00:41:01.859237 kernel: BTRFS info (device sda6): turning on async discard Oct 29 00:41:01.859262 kernel: BTRFS info (device sda6): enabling free space tree Oct 29 00:41:01.860551 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 29 00:41:02.575536 coreos-metadata[1370]: Oct 29 00:41:02.575 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Oct 29 00:41:02.581664 coreos-metadata[1370]: Oct 29 00:41:02.577 INFO Fetch successful Oct 29 00:41:02.581664 coreos-metadata[1370]: Oct 29 00:41:02.578 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Oct 29 00:41:02.594254 coreos-metadata[1370]: Oct 29 00:41:02.586 INFO Fetch successful Oct 29 00:41:02.598683 coreos-metadata[1370]: Oct 29 00:41:02.598 INFO wrote hostname ci-4487.0.0-n-79e0bf2611 to /sysroot/etc/hostname Oct 29 00:41:02.601819 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Oct 29 00:41:02.943426 initrd-setup-root[1398]: cut: /sysroot/etc/passwd: No such file or directory Oct 29 00:41:03.010977 initrd-setup-root[1405]: cut: /sysroot/etc/group: No such file or directory Oct 29 00:41:03.079647 initrd-setup-root[1412]: cut: /sysroot/etc/shadow: No such file or directory Oct 29 00:41:03.100214 initrd-setup-root[1419]: cut: /sysroot/etc/gshadow: No such file or directory Oct 29 00:41:04.339256 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Oct 29 00:41:04.349326 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Oct 29 00:41:04.352444 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Oct 29 00:41:04.432368 systemd[1]: sysroot-oem.mount: Deactivated successfully. Oct 29 00:41:04.440537 kernel: BTRFS info (device sda6): last unmount of filesystem ba5c42d5-4e97-4410-b3e4-abc54f9b4dae Oct 29 00:41:04.455236 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Oct 29 00:41:04.478825 ignition[1488]: INFO : Ignition 2.22.0 Oct 29 00:41:04.478825 ignition[1488]: INFO : Stage: mount Oct 29 00:41:04.484770 ignition[1488]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 29 00:41:04.484770 ignition[1488]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 29 00:41:04.484770 ignition[1488]: INFO : mount: mount passed Oct 29 00:41:04.484770 ignition[1488]: INFO : Ignition finished successfully Oct 29 00:41:04.486119 systemd[1]: Finished ignition-mount.service - Ignition (mount). Oct 29 00:41:04.494020 systemd[1]: Starting ignition-files.service - Ignition (files)... Oct 29 00:41:04.523897 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 29 00:41:04.549152 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1502) Oct 29 00:41:04.549188 kernel: BTRFS info (device sda6): first mount of filesystem ba5c42d5-4e97-4410-b3e4-abc54f9b4dae Oct 29 00:41:04.558514 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Oct 29 00:41:04.568667 kernel: BTRFS info (device sda6): turning on async discard Oct 29 00:41:04.568694 kernel: BTRFS info (device sda6): enabling free space tree Oct 29 00:41:04.570774 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 29 00:41:04.617333 ignition[1519]: INFO : Ignition 2.22.0 Oct 29 00:41:04.617333 ignition[1519]: INFO : Stage: files Oct 29 00:41:04.623571 ignition[1519]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 29 00:41:04.623571 ignition[1519]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 29 00:41:04.623571 ignition[1519]: DEBUG : files: compiled without relabeling support, skipping Oct 29 00:41:04.637456 ignition[1519]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Oct 29 00:41:04.637456 ignition[1519]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Oct 29 00:41:04.700541 ignition[1519]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Oct 29 00:41:04.706174 ignition[1519]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Oct 29 00:41:04.706174 ignition[1519]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Oct 29 00:41:04.701253 unknown[1519]: wrote ssh authorized keys file for user: core Oct 29 00:41:04.756056 ignition[1519]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Oct 29 00:41:04.763501 ignition[1519]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Oct 29 00:41:04.763501 ignition[1519]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Oct 29 00:41:04.763501 ignition[1519]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Oct 29 00:41:04.763501 ignition[1519]: INFO : files: files passed Oct 29 00:41:04.763501 ignition[1519]: INFO : Ignition finished successfully Oct 29 00:41:04.780047 systemd[1]: Finished ignition-files.service - Ignition (files). Oct 29 00:41:04.788418 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Oct 29 00:41:04.814415 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Oct 29 00:41:04.819447 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 29 00:41:04.819626 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Oct 29 00:41:04.878289 initrd-setup-root-after-ignition[1552]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 29 00:41:04.878289 initrd-setup-root-after-ignition[1552]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Oct 29 00:41:04.890697 initrd-setup-root-after-ignition[1556]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 29 00:41:04.898312 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 29 00:41:04.899304 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Oct 29 00:41:04.913574 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Oct 29 00:41:04.989199 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 29 00:41:04.989310 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Oct 29 00:41:04.998699 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Oct 29 00:41:05.007420 systemd[1]: Reached target initrd.target - Initrd Default Target. Oct 29 00:41:05.009102 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Oct 29 00:41:05.009944 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Oct 29 00:41:05.061432 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 29 00:41:05.072678 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Oct 29 00:41:05.104223 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Oct 29 00:41:05.104469 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Oct 29 00:41:05.113978 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 29 00:41:05.123370 systemd[1]: Stopped target timers.target - Timer Units. Oct 29 00:41:05.131967 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 29 00:41:05.132102 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 29 00:41:05.142100 systemd[1]: Stopped target initrd.target - Initrd Default Target. Oct 29 00:41:05.142875 systemd[1]: Stopped target basic.target - Basic System. Oct 29 00:41:05.143664 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Oct 29 00:41:05.144398 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Oct 29 00:41:05.145185 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Oct 29 00:41:05.145946 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Oct 29 00:41:05.146733 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Oct 29 00:41:05.147513 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Oct 29 00:41:05.148303 systemd[1]: Stopped target sysinit.target - System Initialization. Oct 29 00:41:05.149872 systemd[1]: Stopped target local-fs.target - Local File Systems. Oct 29 00:41:05.150526 systemd[1]: Stopped target swap.target - Swaps. Oct 29 00:41:05.151260 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 29 00:41:05.151379 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Oct 29 00:41:05.309752 ignition[1576]: INFO : Ignition 2.22.0 Oct 29 00:41:05.309752 ignition[1576]: INFO : Stage: umount Oct 29 00:41:05.309752 ignition[1576]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 29 00:41:05.309752 ignition[1576]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 29 00:41:05.309752 ignition[1576]: INFO : umount: umount passed Oct 29 00:41:05.309752 ignition[1576]: INFO : Ignition finished successfully Oct 29 00:41:05.152851 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Oct 29 00:41:05.153651 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 29 00:41:05.154380 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Oct 29 00:41:05.154509 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 29 00:41:05.155170 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 29 00:41:05.155285 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Oct 29 00:41:05.156671 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Oct 29 00:41:05.156792 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 29 00:41:05.157428 systemd[1]: ignition-files.service: Deactivated successfully. Oct 29 00:41:05.157535 systemd[1]: Stopped ignition-files.service - Ignition (files). Oct 29 00:41:05.158147 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Oct 29 00:41:05.158265 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Oct 29 00:41:05.159716 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Oct 29 00:41:05.160931 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Oct 29 00:41:05.161665 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 29 00:41:05.161796 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 29 00:41:05.162465 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 29 00:41:05.162577 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Oct 29 00:41:05.163184 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 29 00:41:05.163294 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Oct 29 00:41:05.169059 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 29 00:41:05.275340 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Oct 29 00:41:05.307066 systemd[1]: ignition-mount.service: Deactivated successfully. Oct 29 00:41:05.307198 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Oct 29 00:41:05.311482 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 29 00:41:05.311533 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Oct 29 00:41:05.318072 systemd[1]: ignition-kargs.service: Deactivated successfully. Oct 29 00:41:05.318121 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Oct 29 00:41:05.323929 systemd[1]: ignition-fetch.service: Deactivated successfully. Oct 29 00:41:05.323974 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Oct 29 00:41:05.325410 systemd[1]: Stopped target network.target - Network. Oct 29 00:41:05.341485 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Oct 29 00:41:05.341643 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Oct 29 00:41:05.342973 systemd[1]: Stopped target paths.target - Path Units. Oct 29 00:41:05.361315 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 29 00:41:05.365176 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 29 00:41:05.367417 systemd[1]: Stopped target slices.target - Slice Units. Oct 29 00:41:05.385229 systemd[1]: Stopped target sockets.target - Socket Units. Oct 29 00:41:05.390509 systemd[1]: iscsid.socket: Deactivated successfully. Oct 29 00:41:05.390556 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Oct 29 00:41:05.398356 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 29 00:41:05.398401 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 29 00:41:05.406979 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 29 00:41:05.407037 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Oct 29 00:41:05.416338 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Oct 29 00:41:05.416387 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Oct 29 00:41:05.424459 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Oct 29 00:41:05.432423 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Oct 29 00:41:05.450176 systemd[1]: sysroot-boot.mount: Deactivated successfully. Oct 29 00:41:05.451906 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 29 00:41:05.452018 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Oct 29 00:41:05.472279 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 29 00:41:05.472432 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Oct 29 00:41:05.490214 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 29 00:41:05.490351 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Oct 29 00:41:05.500806 systemd[1]: Stopped target network-pre.target - Preparation for Network. Oct 29 00:41:05.501673 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 29 00:41:05.501736 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Oct 29 00:41:05.513372 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 29 00:41:05.513431 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Oct 29 00:41:05.521866 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Oct 29 00:41:05.528969 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Oct 29 00:41:05.529035 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 29 00:41:05.530487 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 29 00:41:05.530541 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Oct 29 00:41:05.545494 systemd[1]: systemd-modules-load.service: Deactivated successfully. Oct 29 00:41:05.545567 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Oct 29 00:41:05.553484 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 29 00:41:05.588251 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 29 00:41:05.588442 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 29 00:41:05.600812 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 29 00:41:05.600860 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Oct 29 00:41:05.608276 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 29 00:41:05.608317 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Oct 29 00:41:05.616153 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 29 00:41:05.616205 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Oct 29 00:41:05.627306 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 29 00:41:05.627361 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Oct 29 00:41:05.638581 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 29 00:41:05.638633 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 29 00:41:05.658969 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Oct 29 00:41:05.661068 systemd[1]: systemd-network-generator.service: Deactivated successfully. Oct 29 00:41:05.661183 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Oct 29 00:41:05.672949 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 29 00:41:05.673000 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 29 00:41:05.681445 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Oct 29 00:41:05.681492 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 29 00:41:05.689997 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 29 00:41:05.690046 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Oct 29 00:41:05.699026 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 29 00:41:05.699076 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 29 00:41:05.714964 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 29 00:41:05.715059 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Oct 29 00:41:05.760941 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 29 00:41:05.761124 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Oct 29 00:41:05.764995 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Oct 29 00:41:05.779154 systemd[1]: Starting initrd-switch-root.service - Switch Root... Oct 29 00:41:05.836411 systemd[1]: Switching root. Oct 29 00:41:05.935623 systemd-journald[569]: Journal stopped Oct 29 00:41:13.396815 systemd-journald[569]: Received SIGTERM from PID 1 (systemd). Oct 29 00:41:13.396849 kernel: SELinux: policy capability network_peer_controls=1 Oct 29 00:41:13.396863 kernel: SELinux: policy capability open_perms=1 Oct 29 00:41:13.396873 kernel: SELinux: policy capability extended_socket_class=1 Oct 29 00:41:13.396882 kernel: SELinux: policy capability always_check_network=0 Oct 29 00:41:13.396892 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 29 00:41:13.396905 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 29 00:41:13.396915 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 29 00:41:13.396925 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Oct 29 00:41:13.396935 kernel: SELinux: policy capability userspace_initial_context=0 Oct 29 00:41:13.396945 kernel: audit: type=1403 audit(1761698467.051:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 29 00:41:13.396956 systemd[1]: Successfully loaded SELinux policy in 277.450ms. Oct 29 00:41:13.396970 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 7.755ms. Oct 29 00:41:13.396986 systemd[1]: systemd 257.7 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +IPE +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Oct 29 00:41:13.396998 systemd[1]: Detected virtualization microsoft. Oct 29 00:41:13.397010 systemd[1]: Detected architecture x86-64. Oct 29 00:41:13.397023 systemd[1]: Detected first boot. Oct 29 00:41:13.397035 systemd[1]: Hostname set to . Oct 29 00:41:13.397046 systemd[1]: Initializing machine ID from random generator. Oct 29 00:41:13.397057 zram_generator::config[1621]: No configuration found. Oct 29 00:41:13.397068 kernel: Guest personality initialized and is inactive Oct 29 00:41:13.397081 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Oct 29 00:41:13.397092 kernel: Initialized host personality Oct 29 00:41:13.397102 kernel: NET: Registered PF_VSOCK protocol family Oct 29 00:41:13.397113 systemd[1]: Populated /etc with preset unit settings. Oct 29 00:41:13.397124 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 29 00:41:13.397147 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Oct 29 00:41:13.397159 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 29 00:41:13.397174 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Oct 29 00:41:13.397186 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Oct 29 00:41:13.397197 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Oct 29 00:41:13.397209 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Oct 29 00:41:13.397220 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Oct 29 00:41:13.397234 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Oct 29 00:41:13.397249 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Oct 29 00:41:13.397261 systemd[1]: Created slice user.slice - User and Session Slice. Oct 29 00:41:13.397272 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 29 00:41:13.397284 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 29 00:41:13.397295 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Oct 29 00:41:13.397307 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Oct 29 00:41:13.397322 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Oct 29 00:41:13.397337 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 29 00:41:13.397349 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Oct 29 00:41:13.397360 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 29 00:41:13.397372 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 29 00:41:13.397383 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Oct 29 00:41:13.397397 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Oct 29 00:41:13.397409 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Oct 29 00:41:13.397420 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Oct 29 00:41:13.397432 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 29 00:41:13.397443 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 29 00:41:13.397455 systemd[1]: Reached target slices.target - Slice Units. Oct 29 00:41:13.397466 systemd[1]: Reached target swap.target - Swaps. Oct 29 00:41:13.397480 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Oct 29 00:41:13.397492 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Oct 29 00:41:13.397507 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Oct 29 00:41:13.397519 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 29 00:41:13.397533 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 29 00:41:13.397545 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 29 00:41:13.397557 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Oct 29 00:41:13.397569 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Oct 29 00:41:13.397580 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Oct 29 00:41:13.397592 systemd[1]: Mounting media.mount - External Media Directory... Oct 29 00:41:13.397607 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 29 00:41:13.397619 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Oct 29 00:41:13.397630 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Oct 29 00:41:13.397642 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Oct 29 00:41:13.397654 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Oct 29 00:41:13.397666 systemd[1]: Reached target machines.target - Containers. Oct 29 00:41:13.397677 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Oct 29 00:41:13.397692 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 29 00:41:13.397704 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 29 00:41:13.397716 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Oct 29 00:41:13.397727 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 29 00:41:13.397739 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 29 00:41:13.397750 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 29 00:41:13.397768 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Oct 29 00:41:13.397780 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 29 00:41:13.397792 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Oct 29 00:41:13.397804 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 29 00:41:13.397816 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Oct 29 00:41:13.397828 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 29 00:41:13.397839 systemd[1]: Stopped systemd-fsck-usr.service. Oct 29 00:41:13.397854 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Oct 29 00:41:13.397866 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 29 00:41:13.397878 kernel: fuse: init (API version 7.41) Oct 29 00:41:13.397889 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 29 00:41:13.397901 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 29 00:41:13.397912 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Oct 29 00:41:13.397943 systemd-journald[1707]: Collecting audit messages is disabled. Oct 29 00:41:13.397975 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Oct 29 00:41:13.397989 systemd-journald[1707]: Journal started Oct 29 00:41:13.398013 systemd-journald[1707]: Runtime Journal (/run/log/journal/9f7de38f5881441b9131822af29580a7) is 8M, max 2.2G, 2.1G free. Oct 29 00:41:12.544351 systemd[1]: Queued start job for default target multi-user.target. Oct 29 00:41:12.573445 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Oct 29 00:41:12.574029 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 29 00:41:13.409855 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 29 00:41:13.423154 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 29 00:41:13.429497 systemd[1]: Started systemd-journald.service - Journal Service. Oct 29 00:41:13.434108 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Oct 29 00:41:13.438501 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Oct 29 00:41:13.443160 systemd[1]: Mounted media.mount - External Media Directory. Oct 29 00:41:13.447176 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Oct 29 00:41:13.451826 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Oct 29 00:41:13.456351 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Oct 29 00:41:13.460583 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Oct 29 00:41:13.465603 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 29 00:41:13.471015 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 29 00:41:13.471225 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Oct 29 00:41:13.476355 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 29 00:41:13.476539 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 29 00:41:13.481511 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 29 00:41:13.481699 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 29 00:41:13.486917 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 29 00:41:13.487110 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Oct 29 00:41:13.491645 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 29 00:41:13.491843 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 29 00:41:13.496523 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 29 00:41:13.501387 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 29 00:41:13.507477 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Oct 29 00:41:13.513062 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Oct 29 00:41:13.527993 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 29 00:41:13.555408 systemd[1]: Listening on systemd-importd.socket - Disk Image Download Service Socket. Oct 29 00:41:13.561450 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Oct 29 00:41:13.571149 kernel: ACPI: bus type drm_connector registered Oct 29 00:41:13.583386 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Oct 29 00:41:13.587891 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Oct 29 00:41:13.587927 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 29 00:41:13.593064 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Oct 29 00:41:13.608833 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 29 00:41:13.621697 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Oct 29 00:41:13.642825 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Oct 29 00:41:13.647754 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 29 00:41:13.648821 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Oct 29 00:41:13.653460 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 29 00:41:13.654452 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 29 00:41:13.659688 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Oct 29 00:41:13.665267 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 29 00:41:13.671973 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 29 00:41:13.672194 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 29 00:41:13.677150 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 29 00:41:13.683217 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Oct 29 00:41:13.688352 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Oct 29 00:41:13.694409 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Oct 29 00:41:13.698442 systemd-journald[1707]: Time spent on flushing to /var/log/journal/9f7de38f5881441b9131822af29580a7 is 13.897ms for 941 entries. Oct 29 00:41:13.698442 systemd-journald[1707]: System Journal (/var/log/journal/9f7de38f5881441b9131822af29580a7) is 8M, max 2.6G, 2.6G free. Oct 29 00:41:13.761637 systemd-journald[1707]: Received client request to flush runtime journal. Oct 29 00:41:13.761695 kernel: loop1: detected capacity change from 0 to 110976 Oct 29 00:41:13.705665 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Oct 29 00:41:13.712033 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Oct 29 00:41:13.763074 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Oct 29 00:41:13.814469 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 29 00:41:13.815234 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Oct 29 00:41:13.855896 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 29 00:41:13.888380 systemd-tmpfiles[1763]: ACLs are not supported, ignoring. Oct 29 00:41:13.888400 systemd-tmpfiles[1763]: ACLs are not supported, ignoring. Oct 29 00:41:13.892346 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 29 00:41:13.899455 systemd[1]: Starting systemd-sysusers.service - Create System Users... Oct 29 00:41:14.555388 systemd[1]: Finished systemd-sysusers.service - Create System Users. Oct 29 00:41:14.561681 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 29 00:41:14.566797 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 29 00:41:14.594850 systemd-tmpfiles[1783]: ACLs are not supported, ignoring. Oct 29 00:41:14.594872 systemd-tmpfiles[1783]: ACLs are not supported, ignoring. Oct 29 00:41:14.598325 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 29 00:41:14.610208 kernel: loop2: detected capacity change from 0 to 27752 Oct 29 00:41:14.658448 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Oct 29 00:41:14.721437 systemd[1]: Started systemd-userdbd.service - User Database Manager. Oct 29 00:41:14.835343 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Oct 29 00:41:14.842069 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 29 00:41:14.883613 systemd-udevd[1794]: Using default interface naming scheme 'v257'. Oct 29 00:41:14.911491 systemd-resolved[1782]: Positive Trust Anchors: Oct 29 00:41:14.911508 systemd-resolved[1782]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 29 00:41:14.911514 systemd-resolved[1782]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Oct 29 00:41:14.911545 systemd-resolved[1782]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Oct 29 00:41:15.132825 systemd-resolved[1782]: Using system hostname 'ci-4487.0.0-n-79e0bf2611'. Oct 29 00:41:15.134393 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 29 00:41:15.138180 kernel: loop3: detected capacity change from 0 to 128048 Oct 29 00:41:15.142119 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 29 00:41:15.436717 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 29 00:41:15.444144 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 29 00:41:15.478335 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Oct 29 00:41:15.538155 kernel: hv_vmbus: registering driver hyperv_fb Oct 29 00:41:15.542157 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Oct 29 00:41:15.542209 kernel: hv_vmbus: registering driver hv_balloon Oct 29 00:41:15.545153 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Oct 29 00:41:15.545204 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Oct 29 00:41:15.554151 kernel: Console: switching to colour dummy device 80x25 Oct 29 00:41:15.563058 kernel: Console: switching to colour frame buffer device 128x48 Oct 29 00:41:15.577856 kernel: mousedev: PS/2 mouse device common for all mice Oct 29 00:41:15.586152 systemd-networkd[1807]: lo: Link UP Oct 29 00:41:15.586177 systemd-networkd[1807]: lo: Gained carrier Oct 29 00:41:15.588078 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 29 00:41:15.592836 systemd[1]: Reached target network.target - Network. Oct 29 00:41:15.594383 systemd-networkd[1807]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Oct 29 00:41:15.594394 systemd-networkd[1807]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 29 00:41:15.596282 systemd-networkd[1807]: eth0: Link UP Oct 29 00:41:15.596292 systemd-networkd[1807]: eth0: Gained carrier Oct 29 00:41:15.596308 systemd-networkd[1807]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Oct 29 00:41:15.598116 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Oct 29 00:41:15.604619 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Oct 29 00:41:15.623198 systemd-networkd[1807]: eth0: DHCPv4 address 10.200.8.23/24, gateway 10.200.8.1 acquired from 168.63.129.16 Oct 29 00:41:15.646160 kernel: EDAC MC: Ver: 3.0.0 Oct 29 00:41:15.646236 kernel: loop4: detected capacity change from 0 to 110976 Oct 29 00:41:15.672189 kernel: loop5: detected capacity change from 0 to 27752 Oct 29 00:41:15.674872 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 29 00:41:15.679561 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Oct 29 00:41:15.689154 kernel: loop6: detected capacity change from 0 to 128048 Oct 29 00:41:15.689902 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 29 00:41:15.690145 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 29 00:41:15.695989 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 29 00:41:15.703229 (sd-merge)[1863]: Using extensions 'containerd-flatcar.raw', 'docker-flatcar.raw', 'oem-azure.raw'. Oct 29 00:41:15.706661 (sd-merge)[1863]: Merged extensions into '/usr'. Oct 29 00:41:15.713275 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Oct 29 00:41:15.719616 systemd[1]: Starting ensure-sysext.service... Oct 29 00:41:15.723459 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 29 00:41:15.746400 systemd[1]: Reload requested from client PID 1871 ('systemctl') (unit ensure-sysext.service)... Oct 29 00:41:15.746418 systemd[1]: Reloading... Oct 29 00:41:15.791172 zram_generator::config[1908]: No configuration found. Oct 29 00:41:15.803030 systemd-tmpfiles[1872]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Oct 29 00:41:15.803073 systemd-tmpfiles[1872]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Oct 29 00:41:15.803455 systemd-tmpfiles[1872]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Oct 29 00:41:15.803770 systemd-tmpfiles[1872]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Oct 29 00:41:15.804765 systemd-tmpfiles[1872]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Oct 29 00:41:15.805060 systemd-tmpfiles[1872]: ACLs are not supported, ignoring. Oct 29 00:41:15.805174 systemd-tmpfiles[1872]: ACLs are not supported, ignoring. Oct 29 00:41:15.915331 systemd-tmpfiles[1872]: Detected autofs mount point /boot during canonicalization of boot. Oct 29 00:41:15.915345 systemd-tmpfiles[1872]: Skipping /boot Oct 29 00:41:15.923969 systemd-tmpfiles[1872]: Detected autofs mount point /boot during canonicalization of boot. Oct 29 00:41:15.923983 systemd-tmpfiles[1872]: Skipping /boot Oct 29 00:41:16.068114 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Oct 29 00:41:16.070293 systemd[1]: Reloading finished in 323 ms. Oct 29 00:41:16.124595 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 29 00:41:16.170482 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 29 00:41:16.171730 systemd[1]: Starting audit-rules.service - Load Audit Rules... Oct 29 00:41:16.198065 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Oct 29 00:41:16.199634 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 29 00:41:16.200853 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Oct 29 00:41:16.202578 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 29 00:41:16.204013 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 29 00:41:16.205491 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 29 00:41:16.207000 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 29 00:41:16.207552 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 29 00:41:16.209170 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Oct 29 00:41:16.209544 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Oct 29 00:41:16.210756 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Oct 29 00:41:16.211145 systemd[1]: Reached target time-set.target - System Time Set. Oct 29 00:41:16.212909 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Oct 29 00:41:16.213219 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 29 00:41:16.215694 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 29 00:41:16.215866 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 29 00:41:16.216553 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 29 00:41:16.216717 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 29 00:41:16.217452 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 29 00:41:16.217666 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 29 00:41:16.218941 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 29 00:41:16.219106 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 29 00:41:16.221070 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 29 00:41:16.221194 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 29 00:41:16.222286 systemd[1]: Finished ensure-sysext.service. Oct 29 00:41:16.241733 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Oct 29 00:41:16.298518 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Oct 29 00:41:16.547840 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Oct 29 00:41:16.818398 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 29 00:41:16.826341 augenrules[2071]: No rules Oct 29 00:41:16.827382 systemd[1]: audit-rules.service: Deactivated successfully. Oct 29 00:41:16.827613 systemd[1]: Finished audit-rules.service - Load Audit Rules. Oct 29 00:41:17.047254 systemd-networkd[1807]: eth0: Gained IPv6LL Oct 29 00:41:17.049521 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Oct 29 00:41:17.054807 systemd[1]: Reached target network-online.target - Network is Online. Oct 29 00:41:18.951752 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Oct 29 00:41:18.957038 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Oct 29 00:41:26.134581 ldconfig[2032]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 29 00:41:26.147150 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Oct 29 00:41:26.154842 systemd[1]: Starting systemd-update-done.service - Update is Completed... Oct 29 00:41:26.199497 systemd[1]: Finished systemd-update-done.service - Update is Completed. Oct 29 00:41:26.204635 systemd[1]: Reached target sysinit.target - System Initialization. Oct 29 00:41:26.209605 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Oct 29 00:41:26.214984 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 29 00:41:26.220738 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Oct 29 00:41:26.226180 systemd[1]: Started logrotate.timer - Daily rotation of log files. Oct 29 00:41:26.231182 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Oct 29 00:41:26.236830 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Oct 29 00:41:26.242610 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Oct 29 00:41:26.242669 systemd[1]: Reached target paths.target - Path Units. Oct 29 00:41:26.246691 systemd[1]: Reached target timers.target - Timer Units. Oct 29 00:41:26.275443 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Oct 29 00:41:26.281127 systemd[1]: Starting docker.socket - Docker Socket for the API... Oct 29 00:41:26.287124 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Oct 29 00:41:26.292337 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Oct 29 00:41:26.297544 systemd[1]: Reached target ssh-access.target - SSH Access Available. Oct 29 00:41:26.303729 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Oct 29 00:41:26.308439 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Oct 29 00:41:26.314205 systemd[1]: Listening on docker.socket - Docker Socket for the API. Oct 29 00:41:26.319725 systemd[1]: Reached target sockets.target - Socket Units. Oct 29 00:41:26.323847 systemd[1]: Reached target basic.target - Basic System. Oct 29 00:41:26.327800 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Oct 29 00:41:26.327831 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Oct 29 00:41:26.341600 systemd[1]: Starting chronyd.service - NTP client/server... Oct 29 00:41:26.346367 systemd[1]: Starting containerd.service - containerd container runtime... Oct 29 00:41:26.367001 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Oct 29 00:41:26.372305 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Oct 29 00:41:26.377340 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Oct 29 00:41:26.383212 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Oct 29 00:41:26.388318 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Oct 29 00:41:26.392442 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Oct 29 00:41:26.393843 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Oct 29 00:41:26.397921 systemd[1]: hv_fcopy_uio_daemon.service - Hyper-V FCOPY UIO daemon was skipped because of an unmet condition check (ConditionPathExists=/sys/bus/vmbus/devices/eb765408-105f-49b6-b4aa-c123b64d17d4/uio). Oct 29 00:41:26.399029 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Oct 29 00:41:26.403251 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Oct 29 00:41:26.404373 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Oct 29 00:41:26.405776 jq[2092]: false Oct 29 00:41:26.409401 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Oct 29 00:41:26.414064 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Oct 29 00:41:26.419798 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Oct 29 00:41:26.426552 systemd[1]: Starting systemd-logind.service - User Login Management... Oct 29 00:41:26.430637 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Oct 29 00:41:26.431068 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 29 00:41:26.431737 systemd[1]: Starting update-engine.service - Update Engine... Oct 29 00:41:26.436248 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Oct 29 00:41:26.440524 extend-filesystems[2093]: Found /dev/sda6 Oct 29 00:41:26.444928 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Oct 29 00:41:26.446775 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Oct 29 00:41:26.447013 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Oct 29 00:41:26.447318 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 29 00:41:26.447522 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Oct 29 00:41:26.455758 systemd[1]: motdgen.service: Deactivated successfully. Oct 29 00:41:26.456266 extend-filesystems[2093]: Found /dev/sda9 Oct 29 00:41:26.457788 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Oct 29 00:41:26.460466 extend-filesystems[2093]: Checking size of /dev/sda9 Oct 29 00:41:26.466834 jq[2111]: true Oct 29 00:41:26.467860 oslogin_cache_refresh[2094]: Refreshing passwd entry cache Oct 29 00:41:26.467799 (ntainerd)[2125]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Oct 29 00:41:26.469030 google_oslogin_nss_cache[2094]: oslogin_cache_refresh[2094]: Refreshing passwd entry cache Oct 29 00:41:26.468769 KVP[2095]: KVP starting; pid is:2095 Oct 29 00:41:26.474160 kernel: hv_utils: KVP IC version 4.0 Oct 29 00:41:26.474251 jq[2129]: true Oct 29 00:41:26.472573 KVP[2095]: KVP LIC Version: 3.1 Oct 29 00:41:26.474363 chronyd[2084]: chronyd version 4.7 starting (+CMDMON +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +NTS +SECHASH +IPV6 -DEBUG) Oct 29 00:41:26.476607 google_oslogin_nss_cache[2094]: oslogin_cache_refresh[2094]: Failure getting users, quitting Oct 29 00:41:26.476607 google_oslogin_nss_cache[2094]: oslogin_cache_refresh[2094]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Oct 29 00:41:26.476596 oslogin_cache_refresh[2094]: Failure getting users, quitting Oct 29 00:41:26.476842 google_oslogin_nss_cache[2094]: oslogin_cache_refresh[2094]: Refreshing group entry cache Oct 29 00:41:26.476614 oslogin_cache_refresh[2094]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Oct 29 00:41:26.476658 oslogin_cache_refresh[2094]: Refreshing group entry cache Oct 29 00:41:26.481003 google_oslogin_nss_cache[2094]: oslogin_cache_refresh[2094]: Failure getting groups, quitting Oct 29 00:41:26.481003 google_oslogin_nss_cache[2094]: oslogin_cache_refresh[2094]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Oct 29 00:41:26.480989 oslogin_cache_refresh[2094]: Failure getting groups, quitting Oct 29 00:41:26.481001 oslogin_cache_refresh[2094]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Oct 29 00:41:26.482342 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Oct 29 00:41:26.482577 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Oct 29 00:41:26.504933 extend-filesystems[2093]: Resized partition /dev/sda9 Oct 29 00:41:26.527971 chronyd[2084]: Timezone right/UTC failed leap second check, ignoring Oct 29 00:41:26.528154 chronyd[2084]: Loaded seccomp filter (level 2) Oct 29 00:41:26.528291 systemd[1]: Started chronyd.service - NTP client/server. Oct 29 00:41:26.533154 extend-filesystems[2155]: resize2fs 1.47.3 (8-Jul-2025) Oct 29 00:41:26.549456 update_engine[2107]: I20251029 00:41:26.549378 2107 main.cc:92] Flatcar Update Engine starting Oct 29 00:41:26.579209 systemd-logind[2104]: New seat seat0. Oct 29 00:41:26.581605 systemd-logind[2104]: Watching system buttons on /dev/input/event1 (AT Translated Set 2 keyboard) Oct 29 00:41:26.581815 systemd[1]: Started systemd-logind.service - User Login Management. Oct 29 00:41:26.620352 bash[2150]: Updated "/home/core/.ssh/authorized_keys" Oct 29 00:41:26.621808 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Oct 29 00:41:26.627150 kernel: EXT4-fs (sda9): resizing filesystem from 7359488 to 7376891 blocks Oct 29 00:41:26.630008 sshd_keygen[2108]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Oct 29 00:41:26.638945 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Oct 29 00:41:26.641151 kernel: EXT4-fs (sda9): resized filesystem to 7376891 Oct 29 00:41:26.651160 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Oct 29 00:41:26.657183 systemd[1]: Starting issuegen.service - Generate /run/issue... Oct 29 00:41:26.661813 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Oct 29 00:41:26.682840 systemd[1]: issuegen.service: Deactivated successfully. Oct 29 00:41:26.683071 systemd[1]: Finished issuegen.service - Generate /run/issue. Oct 29 00:41:26.688955 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Oct 29 00:41:26.695298 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Oct 29 00:41:26.699548 extend-filesystems[2155]: Filesystem at /dev/sda9 is mounted on /; on-line resizing required Oct 29 00:41:26.699548 extend-filesystems[2155]: old_desc_blocks = 4, new_desc_blocks = 4 Oct 29 00:41:26.699548 extend-filesystems[2155]: The filesystem on /dev/sda9 is now 7376891 (4k) blocks long. Oct 29 00:41:26.717441 extend-filesystems[2093]: Resized filesystem in /dev/sda9 Oct 29 00:41:26.699811 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 29 00:41:26.700039 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Oct 29 00:41:26.722847 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Oct 29 00:41:26.729086 systemd[1]: Started getty@tty1.service - Getty on tty1. Oct 29 00:41:26.733889 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Oct 29 00:41:26.738434 systemd[1]: Reached target getty.target - Login Prompts. Oct 29 00:41:26.807492 setup-nvidia[2097]: Downloading Flatcar Container Linux Developer Container for version: 4487.0.0+nightly-20251028-2100 Oct 29 00:41:26.913813 dbus-daemon[2087]: [system] SELinux support is enabled Oct 29 00:41:26.914076 systemd[1]: Started dbus.service - D-Bus System Message Bus. Oct 29 00:41:26.916479 update_engine[2107]: I20251029 00:41:26.916416 2107 update_check_scheduler.cc:74] Next update check in 2m28s Oct 29 00:41:26.921119 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Oct 29 00:41:26.921172 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Oct 29 00:41:26.922046 dbus-daemon[2087]: [system] Successfully activated service 'org.freedesktop.systemd1' Oct 29 00:41:26.926451 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Oct 29 00:41:26.926474 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Oct 29 00:41:26.931797 systemd[1]: Started update-engine.service - Update Engine. Oct 29 00:41:26.937751 systemd[1]: Started locksmithd.service - Cluster reboot manager. Oct 29 00:41:26.952774 coreos-metadata[2086]: Oct 29 00:41:26.952 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Oct 29 00:41:26.955255 coreos-metadata[2086]: Oct 29 00:41:26.955 INFO Fetch successful Oct 29 00:41:26.955413 coreos-metadata[2086]: Oct 29 00:41:26.955 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Oct 29 00:41:26.959547 coreos-metadata[2086]: Oct 29 00:41:26.959 INFO Fetch successful Oct 29 00:41:26.959835 coreos-metadata[2086]: Oct 29 00:41:26.959 INFO Fetching http://168.63.129.16/machine/2aefd639-e65c-43ea-ae41-efe1f7cb0c36/96880647%2D5825%2D492b%2D9e97%2Db107300f8eed.%5Fci%2D4487.0.0%2Dn%2D79e0bf2611?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Oct 29 00:41:26.992989 coreos-metadata[2086]: Oct 29 00:41:26.992 INFO Fetch successful Oct 29 00:41:26.993150 coreos-metadata[2086]: Oct 29 00:41:26.993 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Oct 29 00:41:27.000508 coreos-metadata[2086]: Oct 29 00:41:27.000 INFO Fetch successful Oct 29 00:41:27.195148 locksmithd[2261]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 29 00:41:27.264997 setup-nvidia[2273]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 29 00:41:27.265292 setup-nvidia[2273]: Dload Upload Total Spent Left Speed Oct 29 00:41:27.378692 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Oct 29 00:41:27.384107 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Oct 29 00:41:27.506650 containerd[2125]: time="2025-10-29T00:41:27Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Oct 29 00:41:27.507234 containerd[2125]: time="2025-10-29T00:41:27.507204600Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Oct 29 00:41:27.516307 containerd[2125]: time="2025-10-29T00:41:27.516223500Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="8.3µs" Oct 29 00:41:27.516307 containerd[2125]: time="2025-10-29T00:41:27.516258700Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Oct 29 00:41:27.516307 containerd[2125]: time="2025-10-29T00:41:27.516278900Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Oct 29 00:41:27.516469 containerd[2125]: time="2025-10-29T00:41:27.516438100Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Oct 29 00:41:27.516511 containerd[2125]: time="2025-10-29T00:41:27.516469100Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Oct 29 00:41:27.516511 containerd[2125]: time="2025-10-29T00:41:27.516498900Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Oct 29 00:41:27.516595 containerd[2125]: time="2025-10-29T00:41:27.516568400Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Oct 29 00:41:27.516699 containerd[2125]: time="2025-10-29T00:41:27.516676800Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Oct 29 00:41:27.517031 containerd[2125]: time="2025-10-29T00:41:27.516992500Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Oct 29 00:41:27.517031 containerd[2125]: time="2025-10-29T00:41:27.517022100Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Oct 29 00:41:27.517117 containerd[2125]: time="2025-10-29T00:41:27.517039500Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Oct 29 00:41:27.517117 containerd[2125]: time="2025-10-29T00:41:27.517052000Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Oct 29 00:41:27.517198 containerd[2125]: time="2025-10-29T00:41:27.517146300Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Oct 29 00:41:27.517447 containerd[2125]: time="2025-10-29T00:41:27.517420700Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Oct 29 00:41:27.517494 containerd[2125]: time="2025-10-29T00:41:27.517470000Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Oct 29 00:41:27.517528 containerd[2125]: time="2025-10-29T00:41:27.517490400Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Oct 29 00:41:27.517528 containerd[2125]: time="2025-10-29T00:41:27.517514500Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Oct 29 00:41:27.517819 containerd[2125]: time="2025-10-29T00:41:27.517795200Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Oct 29 00:41:27.517898 containerd[2125]: time="2025-10-29T00:41:27.517877500Z" level=info msg="metadata content store policy set" policy=shared Oct 29 00:41:27.540886 containerd[2125]: time="2025-10-29T00:41:27.540852600Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Oct 29 00:41:27.540943 containerd[2125]: time="2025-10-29T00:41:27.540901900Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Oct 29 00:41:27.540943 containerd[2125]: time="2025-10-29T00:41:27.540918900Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Oct 29 00:41:27.540943 containerd[2125]: time="2025-10-29T00:41:27.540930800Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Oct 29 00:41:27.541019 containerd[2125]: time="2025-10-29T00:41:27.540943200Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Oct 29 00:41:27.541019 containerd[2125]: time="2025-10-29T00:41:27.540954800Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Oct 29 00:41:27.541019 containerd[2125]: time="2025-10-29T00:41:27.540966700Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Oct 29 00:41:27.541019 containerd[2125]: time="2025-10-29T00:41:27.540978300Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Oct 29 00:41:27.541019 containerd[2125]: time="2025-10-29T00:41:27.541002100Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Oct 29 00:41:27.541122 containerd[2125]: time="2025-10-29T00:41:27.541023700Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Oct 29 00:41:27.541122 containerd[2125]: time="2025-10-29T00:41:27.541035900Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Oct 29 00:41:27.541122 containerd[2125]: time="2025-10-29T00:41:27.541048100Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Oct 29 00:41:27.541218 containerd[2125]: time="2025-10-29T00:41:27.541172700Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Oct 29 00:41:27.541218 containerd[2125]: time="2025-10-29T00:41:27.541193300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Oct 29 00:41:27.541218 containerd[2125]: time="2025-10-29T00:41:27.541207600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Oct 29 00:41:27.541277 containerd[2125]: time="2025-10-29T00:41:27.541219100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Oct 29 00:41:27.541277 containerd[2125]: time="2025-10-29T00:41:27.541240500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Oct 29 00:41:27.541277 containerd[2125]: time="2025-10-29T00:41:27.541253700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Oct 29 00:41:27.541277 containerd[2125]: time="2025-10-29T00:41:27.541264700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Oct 29 00:41:27.541376 containerd[2125]: time="2025-10-29T00:41:27.541274200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Oct 29 00:41:27.541376 containerd[2125]: time="2025-10-29T00:41:27.541307900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Oct 29 00:41:27.541376 containerd[2125]: time="2025-10-29T00:41:27.541335800Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Oct 29 00:41:27.541376 containerd[2125]: time="2025-10-29T00:41:27.541347700Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Oct 29 00:41:27.541457 containerd[2125]: time="2025-10-29T00:41:27.541404400Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Oct 29 00:41:27.541457 containerd[2125]: time="2025-10-29T00:41:27.541418600Z" level=info msg="Start snapshots syncer" Oct 29 00:41:27.541457 containerd[2125]: time="2025-10-29T00:41:27.541443300Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Oct 29 00:41:27.541719 containerd[2125]: time="2025-10-29T00:41:27.541673800Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Oct 29 00:41:27.541839 containerd[2125]: time="2025-10-29T00:41:27.541739900Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Oct 29 00:41:27.541839 containerd[2125]: time="2025-10-29T00:41:27.541806800Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Oct 29 00:41:27.541936 containerd[2125]: time="2025-10-29T00:41:27.541915000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Oct 29 00:41:27.541969 containerd[2125]: time="2025-10-29T00:41:27.541944000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Oct 29 00:41:27.541998 containerd[2125]: time="2025-10-29T00:41:27.541967000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Oct 29 00:41:27.541998 containerd[2125]: time="2025-10-29T00:41:27.541981600Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Oct 29 00:41:27.541998 containerd[2125]: time="2025-10-29T00:41:27.541993300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Oct 29 00:41:27.542061 containerd[2125]: time="2025-10-29T00:41:27.542003500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Oct 29 00:41:27.542061 containerd[2125]: time="2025-10-29T00:41:27.542013800Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Oct 29 00:41:27.542061 containerd[2125]: time="2025-10-29T00:41:27.542033500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Oct 29 00:41:27.542061 containerd[2125]: time="2025-10-29T00:41:27.542044900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Oct 29 00:41:27.542061 containerd[2125]: time="2025-10-29T00:41:27.542055800Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Oct 29 00:41:27.542186 containerd[2125]: time="2025-10-29T00:41:27.542090400Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Oct 29 00:41:27.542186 containerd[2125]: time="2025-10-29T00:41:27.542105400Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Oct 29 00:41:27.542186 containerd[2125]: time="2025-10-29T00:41:27.542114300Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Oct 29 00:41:27.542186 containerd[2125]: time="2025-10-29T00:41:27.542125100Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Oct 29 00:41:27.542186 containerd[2125]: time="2025-10-29T00:41:27.542146500Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Oct 29 00:41:27.542186 containerd[2125]: time="2025-10-29T00:41:27.542157300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Oct 29 00:41:27.542186 containerd[2125]: time="2025-10-29T00:41:27.542167800Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Oct 29 00:41:27.542186 containerd[2125]: time="2025-10-29T00:41:27.542183900Z" level=info msg="runtime interface created" Oct 29 00:41:27.542186 containerd[2125]: time="2025-10-29T00:41:27.542189400Z" level=info msg="created NRI interface" Oct 29 00:41:27.542365 containerd[2125]: time="2025-10-29T00:41:27.542197600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Oct 29 00:41:27.542365 containerd[2125]: time="2025-10-29T00:41:27.542207700Z" level=info msg="Connect containerd service" Oct 29 00:41:27.542365 containerd[2125]: time="2025-10-29T00:41:27.542232300Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Oct 29 00:41:27.542946 containerd[2125]: time="2025-10-29T00:41:27.542919900Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Oct 29 00:41:28.148439 containerd[2125]: time="2025-10-29T00:41:28.148388700Z" level=info msg="Start subscribing containerd event" Oct 29 00:41:28.148587 containerd[2125]: time="2025-10-29T00:41:28.148451000Z" level=info msg="Start recovering state" Oct 29 00:41:28.148587 containerd[2125]: time="2025-10-29T00:41:28.148569000Z" level=info msg="Start event monitor" Oct 29 00:41:28.148671 containerd[2125]: time="2025-10-29T00:41:28.148590200Z" level=info msg="Start cni network conf syncer for default" Oct 29 00:41:28.148671 containerd[2125]: time="2025-10-29T00:41:28.148612300Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Oct 29 00:41:28.148721 containerd[2125]: time="2025-10-29T00:41:28.148683000Z" level=info msg=serving... address=/run/containerd/containerd.sock Oct 29 00:41:28.148721 containerd[2125]: time="2025-10-29T00:41:28.148619600Z" level=info msg="Start streaming server" Oct 29 00:41:28.148721 containerd[2125]: time="2025-10-29T00:41:28.148709600Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Oct 29 00:41:28.148721 containerd[2125]: time="2025-10-29T00:41:28.148717400Z" level=info msg="runtime interface starting up..." Oct 29 00:41:28.148800 containerd[2125]: time="2025-10-29T00:41:28.148724500Z" level=info msg="starting plugins..." Oct 29 00:41:28.148800 containerd[2125]: time="2025-10-29T00:41:28.148740200Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Oct 29 00:41:28.148892 containerd[2125]: time="2025-10-29T00:41:28.148870000Z" level=info msg="containerd successfully booted in 0.642762s" Oct 29 00:41:28.149018 systemd[1]: Started containerd.service - containerd container runtime. Oct 29 00:41:29.338178 waagent[2247]: 2025-10-29T00:41:29.338079Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Oct 29 00:41:29.343040 waagent[2247]: 2025-10-29T00:41:29.342996Z INFO Daemon Daemon OS: flatcar 4487.0.0 Oct 29 00:41:29.346767 waagent[2247]: 2025-10-29T00:41:29.346722Z INFO Daemon Daemon Python: 3.11.13 Oct 29 00:41:29.350540 waagent[2247]: 2025-10-29T00:41:29.350496Z INFO Daemon Daemon Run daemon Oct 29 00:41:29.353827 waagent[2247]: 2025-10-29T00:41:29.353788Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4487.0.0' Oct 29 00:41:29.361022 waagent[2247]: 2025-10-29T00:41:29.360975Z INFO Daemon Daemon Using waagent for provisioning Oct 29 00:41:29.365329 waagent[2247]: 2025-10-29T00:41:29.365288Z INFO Daemon Daemon Activate resource disk Oct 29 00:41:29.369198 waagent[2247]: 2025-10-29T00:41:29.369156Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Oct 29 00:41:29.376776 waagent[2247]: 2025-10-29T00:41:29.376722Z INFO Daemon Daemon Found device: sdb Oct 29 00:41:29.385788 waagent[2247]: 2025-10-29T00:41:29.385746Z INFO Daemon Daemon Examining partition table Oct 29 00:41:29.438594 waagent[2247]: 2025-10-29T00:41:29.438518Z INFO Daemon Daemon GPT not detected, determining filesystem Oct 29 00:41:29.514066 waagent[2247]: 2025-10-29T00:41:29.513999Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Oct 29 00:41:29.518982 waagent[2247]: 2025-10-29T00:41:29.518927Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Oct 29 00:41:30.030556 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#283 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 29 00:41:30.042153 kernel: sdb: sdb1 Oct 29 00:41:30.050152 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#513 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 29 00:41:30.069150 kernel: sdb: sdb1 Oct 29 00:41:30.069733 waagent[2247]: 2025-10-29T00:41:30.069659Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Oct 29 00:41:30.091153 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#261 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 29 00:41:30.103497 waagent[2247]: 2025-10-29T00:41:30.103437Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Oct 29 00:41:30.104168 kernel: sdb: sdb1 Oct 29 00:41:35.105831 login[2251]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Oct 29 00:41:35.454998 login[2250]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Oct 29 00:41:35.461612 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Oct 29 00:41:35.462652 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Oct 29 00:41:35.468535 systemd-logind[2104]: New session 2 of user core. Oct 29 00:41:35.577722 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Oct 29 00:41:35.580274 systemd[1]: Starting user@500.service - User Manager for UID 500... Oct 29 00:41:35.759064 (systemd)[2377]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 29 00:41:35.762602 systemd-logind[2104]: New session c1 of user core. Oct 29 00:41:35.807020 waagent[2247]: 2025-10-29T00:41:35.806946Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Oct 29 00:41:35.917154 kernel: EXT4-fs (sdb1): mounted filesystem 0c9e0572-3456-4a36-bd35-9e31e488dede r/w with ordered data mode. Quota mode: none. Oct 29 00:41:35.917557 waagent[2247]: 2025-10-29T00:41:35.917499Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Oct 29 00:41:35.925326 waagent[2247]: 2025-10-29T00:41:35.925270Z INFO Daemon Daemon Clean protocol and wireserver endpoint Oct 29 00:41:35.930015 waagent[2247]: 2025-10-29T00:41:35.929966Z INFO Daemon Daemon Running default provisioning handler Oct 29 00:41:35.940942 waagent[2247]: 2025-10-29T00:41:35.940888Z INFO Daemon Daemon Unable to get cloud-init enabled status from systemctl: Command '['systemctl', 'is-enabled', 'cloud-init-local.service']' returned non-zero exit status 4. Oct 29 00:41:35.951272 waagent[2247]: 2025-10-29T00:41:35.951226Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Oct 29 00:41:35.952926 waagent[2247]: 2025-10-29T00:41:35.952878Z INFO Daemon Daemon cloud-init is enabled: False Oct 29 00:41:35.954440 waagent[2247]: 2025-10-29T00:41:35.954395Z INFO Daemon Daemon Copying ovf-env.xml Oct 29 00:41:36.107624 login[2251]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Oct 29 00:41:36.112121 systemd-logind[2104]: New session 1 of user core. Oct 29 00:41:36.188561 waagent[2247]: 2025-10-29T00:41:36.188476Z INFO Daemon Daemon Successfully mounted dvd Oct 29 00:41:36.201091 systemd[2377]: Queued start job for default target default.target. Oct 29 00:41:36.218584 systemd[2377]: Created slice app.slice - User Application Slice. Oct 29 00:41:36.218616 systemd[2377]: Reached target paths.target - Paths. Oct 29 00:41:36.218661 systemd[2377]: Reached target timers.target - Timers. Oct 29 00:41:36.219854 systemd[2377]: Starting dbus.socket - D-Bus User Message Bus Socket... Oct 29 00:41:36.225495 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Oct 29 00:41:36.225823 waagent[2247]: 2025-10-29T00:41:36.225771Z INFO Daemon Daemon Detect protocol endpoint Oct 29 00:41:36.229742 waagent[2247]: 2025-10-29T00:41:36.229702Z INFO Daemon Daemon Clean protocol and wireserver endpoint Oct 29 00:41:36.230782 systemd[2377]: Listening on dbus.socket - D-Bus User Message Bus Socket. Oct 29 00:41:36.230898 systemd[2377]: Reached target sockets.target - Sockets. Oct 29 00:41:36.230946 systemd[2377]: Reached target basic.target - Basic System. Oct 29 00:41:36.230988 systemd[2377]: Reached target default.target - Main User Target. Oct 29 00:41:36.231018 systemd[2377]: Startup finished in 460ms. Oct 29 00:41:36.231266 systemd[1]: Started user@500.service - User Manager for UID 500. Oct 29 00:41:36.231359 waagent[2247]: 2025-10-29T00:41:36.231320Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Oct 29 00:41:36.231480 waagent[2247]: 2025-10-29T00:41:36.231448Z INFO Daemon Daemon Test for route to 168.63.129.16 Oct 29 00:41:36.242901 systemd[1]: Started session-1.scope - Session 1 of User core. Oct 29 00:41:36.243018 waagent[2247]: 2025-10-29T00:41:36.242972Z INFO Daemon Daemon Route to 168.63.129.16 exists Oct 29 00:41:36.243692 systemd[1]: Started session-2.scope - Session 2 of User core. Oct 29 00:41:36.252196 waagent[2247]: 2025-10-29T00:41:36.246928Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Oct 29 00:41:36.279586 waagent[2247]: 2025-10-29T00:41:36.279548Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Oct 29 00:41:36.281354 waagent[2247]: 2025-10-29T00:41:36.281331Z INFO Daemon Daemon Wire protocol version:2012-11-30 Oct 29 00:41:36.281982 waagent[2247]: 2025-10-29T00:41:36.281951Z INFO Daemon Daemon Server preferred version:2015-04-05 Oct 29 00:41:36.411070 waagent[2247]: 2025-10-29T00:41:36.410935Z INFO Daemon Daemon Initializing goal state during protocol detection Oct 29 00:41:36.416533 waagent[2247]: 2025-10-29T00:41:36.416481Z INFO Daemon Daemon Forcing an update of the goal state. Oct 29 00:41:36.424385 waagent[2247]: 2025-10-29T00:41:36.424341Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Oct 29 00:41:36.461191 waagent[2247]: 2025-10-29T00:41:36.461121Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.177 Oct 29 00:41:36.491205 waagent[2247]: 2025-10-29T00:41:36.463182Z INFO Daemon Oct 29 00:41:36.491205 waagent[2247]: 2025-10-29T00:41:36.464103Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: e6d2122c-f4bd-4883-8d51-230f4934380c eTag: 12831373536365317009 source: Fabric] Oct 29 00:41:36.491205 waagent[2247]: 2025-10-29T00:41:36.465938Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Oct 29 00:41:36.491205 waagent[2247]: 2025-10-29T00:41:36.467462Z INFO Daemon Oct 29 00:41:36.491205 waagent[2247]: 2025-10-29T00:41:36.467963Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Oct 29 00:41:36.491205 waagent[2247]: 2025-10-29T00:41:36.472962Z INFO Daemon Daemon Downloading artifacts profile blob Oct 29 00:41:36.603482 waagent[2247]: 2025-10-29T00:41:36.603429Z INFO Daemon Downloaded certificate {'thumbprint': '89D8F69A4BD01479B6495CB6F551701484B77CBA', 'hasPrivateKey': True} Oct 29 00:41:36.613919 waagent[2247]: 2025-10-29T00:41:36.605500Z INFO Daemon Fetch goal state completed Oct 29 00:41:36.648815 waagent[2247]: 2025-10-29T00:41:36.648765Z INFO Daemon Daemon Starting provisioning Oct 29 00:41:36.659944 waagent[2247]: 2025-10-29T00:41:36.650632Z INFO Daemon Daemon Handle ovf-env.xml. Oct 29 00:41:36.659944 waagent[2247]: 2025-10-29T00:41:36.652121Z INFO Daemon Daemon Set hostname [ci-4487.0.0-n-79e0bf2611] Oct 29 00:41:36.723939 waagent[2247]: 2025-10-29T00:41:36.723883Z INFO Daemon Daemon Publish hostname [ci-4487.0.0-n-79e0bf2611] Oct 29 00:41:36.736934 waagent[2247]: 2025-10-29T00:41:36.725630Z INFO Daemon Daemon Examine /proc/net/route for primary interface Oct 29 00:41:36.736934 waagent[2247]: 2025-10-29T00:41:36.727199Z INFO Daemon Daemon Primary interface is [eth0] Oct 29 00:41:36.735939 systemd-networkd[1807]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Oct 29 00:41:36.735945 systemd-networkd[1807]: eth0: Reconfiguring with /usr/lib/systemd/network/zz-default.network. Oct 29 00:41:36.736009 systemd-networkd[1807]: eth0: DHCP lease lost Oct 29 00:41:36.755887 waagent[2247]: 2025-10-29T00:41:36.755838Z INFO Daemon Daemon Create user account if not exists Oct 29 00:41:36.767019 waagent[2247]: 2025-10-29T00:41:36.759108Z INFO Daemon Daemon User core already exists, skip useradd Oct 29 00:41:36.767019 waagent[2247]: 2025-10-29T00:41:36.759730Z INFO Daemon Daemon Configure sudoer Oct 29 00:41:36.771470 waagent[2247]: 2025-10-29T00:41:36.771421Z INFO Daemon Daemon Configure sshd Oct 29 00:41:36.778495 waagent[2247]: 2025-10-29T00:41:36.778448Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Oct 29 00:41:36.779205 systemd-networkd[1807]: eth0: DHCPv4 address 10.200.8.23/24, gateway 10.200.8.1 acquired from 168.63.129.16 Oct 29 00:41:36.791881 waagent[2247]: 2025-10-29T00:41:36.780173Z INFO Daemon Daemon Deploy ssh public key. Oct 29 00:41:37.985154 waagent[2247]: 2025-10-29T00:41:37.985076Z INFO Daemon Daemon Provisioning complete Oct 29 00:41:37.995422 waagent[2247]: 2025-10-29T00:41:37.995383Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Oct 29 00:41:38.006645 waagent[2247]: 2025-10-29T00:41:37.997091Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Oct 29 00:41:38.006645 waagent[2247]: 2025-10-29T00:41:37.997792Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Oct 29 00:41:38.147354 waagent[2435]: 2025-10-29T00:41:38.147277Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Oct 29 00:41:38.147662 waagent[2435]: 2025-10-29T00:41:38.147413Z INFO ExtHandler ExtHandler OS: flatcar 4487.0.0 Oct 29 00:41:38.147662 waagent[2435]: 2025-10-29T00:41:38.147483Z INFO ExtHandler ExtHandler Python: 3.11.13 Oct 29 00:41:38.147662 waagent[2435]: 2025-10-29T00:41:38.147550Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Oct 29 00:41:38.222908 waagent[2435]: 2025-10-29T00:41:38.222819Z INFO ExtHandler ExtHandler Distro: flatcar-4487.0.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.13; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Oct 29 00:41:38.223109 waagent[2435]: 2025-10-29T00:41:38.223070Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Oct 29 00:41:38.223214 waagent[2435]: 2025-10-29T00:41:38.223178Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Oct 29 00:41:38.229914 waagent[2435]: 2025-10-29T00:41:38.229870Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Oct 29 00:41:38.234889 waagent[2435]: 2025-10-29T00:41:38.234850Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.177 Oct 29 00:41:38.235382 waagent[2435]: 2025-10-29T00:41:38.235314Z INFO ExtHandler Oct 29 00:41:38.235425 waagent[2435]: 2025-10-29T00:41:38.235404Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 69b1b5be-4be6-4970-95b9-32b4cfd399a8 eTag: 12831373536365317009 source: Fabric] Oct 29 00:41:38.235755 waagent[2435]: 2025-10-29T00:41:38.235715Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Oct 29 00:41:38.236380 waagent[2435]: 2025-10-29T00:41:38.236341Z INFO ExtHandler Oct 29 00:41:38.236452 waagent[2435]: 2025-10-29T00:41:38.236421Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Oct 29 00:41:38.239952 waagent[2435]: 2025-10-29T00:41:38.239916Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Oct 29 00:41:38.302433 waagent[2435]: 2025-10-29T00:41:38.302367Z INFO ExtHandler Downloaded certificate {'thumbprint': '89D8F69A4BD01479B6495CB6F551701484B77CBA', 'hasPrivateKey': True} Oct 29 00:41:38.302922 waagent[2435]: 2025-10-29T00:41:38.302881Z INFO ExtHandler Fetch goal state completed Oct 29 00:41:38.313875 waagent[2435]: 2025-10-29T00:41:38.313829Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.4.2 1 Jul 2025 (Library: OpenSSL 3.4.2 1 Jul 2025) Oct 29 00:41:38.318598 waagent[2435]: 2025-10-29T00:41:38.318556Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2435 Oct 29 00:41:38.318734 waagent[2435]: 2025-10-29T00:41:38.318697Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Oct 29 00:41:38.319065 waagent[2435]: 2025-10-29T00:41:38.319027Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Oct 29 00:41:38.321168 waagent[2435]: 2025-10-29T00:41:38.321108Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4487.0.0', '', 'Flatcar Container Linux by Kinvolk'] Oct 29 00:41:38.321596 waagent[2435]: 2025-10-29T00:41:38.321557Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4487.0.0', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Oct 29 00:41:38.321909 waagent[2435]: 2025-10-29T00:41:38.321869Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Oct 29 00:41:38.322773 waagent[2435]: 2025-10-29T00:41:38.322723Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Oct 29 00:41:38.378788 waagent[2435]: 2025-10-29T00:41:38.378742Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Oct 29 00:41:38.378966 waagent[2435]: 2025-10-29T00:41:38.378929Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Oct 29 00:41:38.385588 waagent[2435]: 2025-10-29T00:41:38.385560Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Oct 29 00:41:38.393014 systemd[1]: Reload requested from client PID 2450 ('systemctl') (unit waagent.service)... Oct 29 00:41:38.393032 systemd[1]: Reloading... Oct 29 00:41:38.450204 zram_generator::config[2491]: No configuration found. Oct 29 00:41:38.730290 systemd[1]: Reloading finished in 336 ms. Oct 29 00:41:38.756575 waagent[2435]: 2025-10-29T00:41:38.756504Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Oct 29 00:41:38.756686 waagent[2435]: 2025-10-29T00:41:38.756651Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Oct 29 00:41:39.196783 waagent[2435]: 2025-10-29T00:41:39.196652Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Oct 29 00:41:39.197114 waagent[2435]: 2025-10-29T00:41:39.196987Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: 1. configuration enabled [True], 2. cgroups v1 enabled [False] OR cgroups v2 is in use and v2 resource limiting configuration enabled [False], 3. python supported: [True] Oct 29 00:41:39.197771 waagent[2435]: 2025-10-29T00:41:39.197725Z INFO ExtHandler ExtHandler Starting env monitor service. Oct 29 00:41:39.198121 waagent[2435]: 2025-10-29T00:41:39.198075Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Oct 29 00:41:39.198301 waagent[2435]: 2025-10-29T00:41:39.198260Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Oct 29 00:41:39.198484 waagent[2435]: 2025-10-29T00:41:39.198447Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Oct 29 00:41:39.198891 waagent[2435]: 2025-10-29T00:41:39.198834Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Oct 29 00:41:39.199105 waagent[2435]: 2025-10-29T00:41:39.199060Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Oct 29 00:41:39.199197 waagent[2435]: 2025-10-29T00:41:39.199154Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Oct 29 00:41:39.199237 waagent[2435]: 2025-10-29T00:41:39.199199Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Oct 29 00:41:39.199798 waagent[2435]: 2025-10-29T00:41:39.199741Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Oct 29 00:41:39.199882 waagent[2435]: 2025-10-29T00:41:39.199842Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Oct 29 00:41:39.199926 waagent[2435]: 2025-10-29T00:41:39.199889Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Oct 29 00:41:39.200183 waagent[2435]: 2025-10-29T00:41:39.200144Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Oct 29 00:41:39.200400 waagent[2435]: 2025-10-29T00:41:39.200354Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Oct 29 00:41:39.200400 waagent[2435]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Oct 29 00:41:39.200400 waagent[2435]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Oct 29 00:41:39.200400 waagent[2435]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Oct 29 00:41:39.200400 waagent[2435]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Oct 29 00:41:39.200400 waagent[2435]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Oct 29 00:41:39.200400 waagent[2435]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Oct 29 00:41:39.200765 waagent[2435]: 2025-10-29T00:41:39.200720Z INFO EnvHandler ExtHandler Configure routes Oct 29 00:41:39.201473 waagent[2435]: 2025-10-29T00:41:39.201439Z INFO EnvHandler ExtHandler Gateway:None Oct 29 00:41:39.202057 waagent[2435]: 2025-10-29T00:41:39.202022Z INFO EnvHandler ExtHandler Routes:None Oct 29 00:41:39.207026 waagent[2435]: 2025-10-29T00:41:39.206981Z INFO ExtHandler ExtHandler Oct 29 00:41:39.207351 waagent[2435]: 2025-10-29T00:41:39.207313Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 83a7aa59-403f-4e36-9be1-3e11826c1c3a correlation 4b8f699f-8653-4727-86ab-c97b94e29684 created: 2025-10-29T00:40:06.248650Z] Oct 29 00:41:39.208184 waagent[2435]: 2025-10-29T00:41:39.208141Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Oct 29 00:41:39.210529 waagent[2435]: 2025-10-29T00:41:39.210487Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Oct 29 00:41:39.282260 waagent[2435]: 2025-10-29T00:41:39.282202Z WARNING ExtHandler ExtHandler Failed to get firewall packets: 'iptables -w -t security -L OUTPUT --zero OUTPUT -nxv' failed: 2 (iptables v1.8.11 (nf_tables): Illegal option `--numeric' with this command Oct 29 00:41:39.282260 waagent[2435]: Try `iptables -h' or 'iptables --help' for more information.) Oct 29 00:41:39.282668 waagent[2435]: 2025-10-29T00:41:39.282628Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 241BA404-8ECF-44BB-8253-DAB2C3D57428;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Oct 29 00:41:39.303090 waagent[2435]: 2025-10-29T00:41:39.303039Z INFO MonitorHandler ExtHandler Network interfaces: Oct 29 00:41:39.303090 waagent[2435]: Executing ['ip', '-a', '-o', 'link']: Oct 29 00:41:39.303090 waagent[2435]: 1: lo: mtu 65536 qdisc noqueue state UNKNOWN mode DEFAULT group default qlen 1000\ link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00 Oct 29 00:41:39.303090 waagent[2435]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 60:45:bd:dd:53:ad brd ff:ff:ff:ff:ff:ff\ altname enx6045bddd53ad Oct 29 00:41:39.303090 waagent[2435]: Executing ['ip', '-4', '-a', '-o', 'address']: Oct 29 00:41:39.303090 waagent[2435]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Oct 29 00:41:39.303090 waagent[2435]: 2: eth0 inet 10.200.8.23/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Oct 29 00:41:39.303090 waagent[2435]: Executing ['ip', '-6', '-a', '-o', 'address']: Oct 29 00:41:39.303090 waagent[2435]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Oct 29 00:41:39.303090 waagent[2435]: 2: eth0 inet6 fe80::6245:bdff:fedd:53ad/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Oct 29 00:41:39.484960 waagent[2435]: 2025-10-29T00:41:39.484851Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Oct 29 00:41:39.484960 waagent[2435]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Oct 29 00:41:39.484960 waagent[2435]: pkts bytes target prot opt in out source destination Oct 29 00:41:39.484960 waagent[2435]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Oct 29 00:41:39.484960 waagent[2435]: pkts bytes target prot opt in out source destination Oct 29 00:41:39.484960 waagent[2435]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Oct 29 00:41:39.484960 waagent[2435]: pkts bytes target prot opt in out source destination Oct 29 00:41:39.484960 waagent[2435]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Oct 29 00:41:39.484960 waagent[2435]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Oct 29 00:41:39.484960 waagent[2435]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Oct 29 00:41:39.488319 waagent[2435]: 2025-10-29T00:41:39.488274Z INFO EnvHandler ExtHandler Current Firewall rules: Oct 29 00:41:39.488319 waagent[2435]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Oct 29 00:41:39.488319 waagent[2435]: pkts bytes target prot opt in out source destination Oct 29 00:41:39.488319 waagent[2435]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Oct 29 00:41:39.488319 waagent[2435]: pkts bytes target prot opt in out source destination Oct 29 00:41:39.488319 waagent[2435]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Oct 29 00:41:39.488319 waagent[2435]: pkts bytes target prot opt in out source destination Oct 29 00:41:39.488319 waagent[2435]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Oct 29 00:41:39.488319 waagent[2435]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Oct 29 00:41:39.488319 waagent[2435]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Oct 29 00:41:39.488591 waagent[2435]: 2025-10-29T00:41:39.488526Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Oct 29 00:41:39.488705 waagent[2435]: 2025-10-29T00:41:39.488671Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Oct 29 00:41:50.308104 chronyd[2084]: Selected source PHC0 Oct 29 00:41:52.596000 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Oct 29 00:41:52.600938 systemd[1]: Started sshd@0-10.200.8.23:22-10.200.16.10:55608.service - OpenSSH per-connection server daemon (10.200.16.10:55608). Oct 29 00:41:53.440209 sshd[2584]: Accepted publickey for core from 10.200.16.10 port 55608 ssh2: RSA SHA256:+dA5Cjv73N58ZtO+QUEsqxSLadVLxJ02b9LKxPfkHbg Oct 29 00:41:53.441408 sshd-session[2584]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 00:41:53.445581 systemd-logind[2104]: New session 3 of user core. Oct 29 00:41:53.458261 systemd[1]: Started session-3.scope - Session 3 of User core. Oct 29 00:41:53.994324 systemd[1]: Started sshd@1-10.200.8.23:22-10.200.16.10:55622.service - OpenSSH per-connection server daemon (10.200.16.10:55622). Oct 29 00:41:54.621851 sshd[2590]: Accepted publickey for core from 10.200.16.10 port 55622 ssh2: RSA SHA256:+dA5Cjv73N58ZtO+QUEsqxSLadVLxJ02b9LKxPfkHbg Oct 29 00:41:54.623047 sshd-session[2590]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 00:41:54.627188 systemd-logind[2104]: New session 4 of user core. Oct 29 00:41:54.637260 systemd[1]: Started session-4.scope - Session 4 of User core. Oct 29 00:41:54.833812 setup-nvidia[2273]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 525M 0 32768 0 0 50478 0 3:01:58 --:--:-- 3:01:58 50489\u000d 0 525M 0 3168k 0 0 1970k 0 0:04:33 0:00:01 0:04:32 1970k\u000d 5 525M 5 27.2M 0 0 10.4M 0 0:00:50 0:00:02 0:00:48 10.4M\u000d 9 525M 9 51.6M 0 0 14.3M 0 0:00:36 0:00:03 0:00:33 14.3M\u000d 14 525M 14 76.1M 0 0 16.6M 0 0:00:31 0:00:04 0:00:27 16.6M\u000d 18 525M 18 99.0M 0 0 17.7M 0 0:00:29 0:00:05 0:00:24 20.1M\u000d 23 525M 23 125M 0 0 19.0M 0 0:00:27 0:00:06 0:00:21 24.6M\u000d 28 525M 28 149M 0 0 19.5M 0 0:00:26 0:00:07 0:00:19 24.1M\u000d 31 525M 31 168M 0 0 19.4M 0 0:00:27 0:00:08 0:00:19 23.0M\u000d 35 525M 35 187M 0 0 18.8M 0 0:00:27 0:00:09 0:00:18 20.8M\u000d 35 525M 35 187M 0 0 17.6M 0 0:00:29 0:00:10 0:00:19 17.5M\u000d 35 525M 35 187M 0 0 16.2M 0 0:00:32 0:00:11 0:00:21 12.4M\u000d 35 525M 35 189M 0 0 14.9M 0 0:00:35 0:00:12 0:00:23 8080k\u000d 38 525M 38 200M 0 0 14.7M 0 0:00:35 0:00:13 0:00:22 6658k\u000d 42 525M 42 223M 0 0 15.2M 0 0:00:34 0:00:14 0:00:20 7872k\u000d 47 525M 47 247M 0 0 15.8M 0 0:00:33 0:00:15 0:00:18 12.1M\u000d 51 525M 51 269M 0 0 16.2M 0 0:00:32 0:00:16 0:00:16 16.2M\u000d 55 525M 55 291M 0 0 16.5M 0 0:00:31 0:00:17 0:00:14 20.8M\u000d 60 525M 60 317M 0 0 16.9M 0 0:00:30 0:00:18 0:00:12 23.1M\u000d 64 525M 64 341M 0 0 17.3M 0 0:00:30 0:00:19 0:00:11 23.3M\u000d 69 525M 69 364M 0 0 17.6M 0 0:00:29 0:00:20 0:00:09 23.0M\u000d 73 525M 73 388M 0 0 17.9M 0 0:00:29 0:00:21 0:00:08 23.4M\u000d 78 525M 78 411M 0 0 18.1M 0 0:00:28 0:00:22 0:00:06 23.6M\u000d 82 525M 82 432M 0 0 18.3M 0 0:00:28 0:00:23 0:00:05 23.3M\u000d 86 525M 86 456M 0 0 18.5M 0 0:00:28 0:00:24 0:00:04 23.2M\u000d 91 525M 91 479M 0 0 18.7M 0 0:00:28 0:00:25 0:00:03 23.3M\u000d 95 525M 95 501M 0 0 18.8M 0 0:00:27 0:00:26 0:00:01 22.9M\u000d 99 525M 99 524M 0 0 19.0M 0 0:00:27 0:00:27 --:--:-- 23.0M\u000d100 525M 100 525M 0 0 19.0M 0 0:00:27 0:00:27 --:--:-- 23.4M Oct 29 00:41:55.072604 sshd[2593]: Connection closed by 10.200.16.10 port 55622 Oct 29 00:41:55.076364 sshd-session[2590]: pam_unix(sshd:session): session closed for user core Oct 29 00:41:55.083610 systemd[1]: sshd@1-10.200.8.23:22-10.200.16.10:55622.service: Deactivated successfully. Oct 29 00:41:55.084009 systemd-logind[2104]: Session 4 logged out. Waiting for processes to exit. Oct 29 00:41:55.086116 systemd[1]: session-4.scope: Deactivated successfully. Oct 29 00:41:55.091638 systemd-logind[2104]: Removed session 4. Oct 29 00:41:55.195472 systemd[1]: Started sshd@2-10.200.8.23:22-10.200.16.10:55624.service - OpenSSH per-connection server daemon (10.200.16.10:55624). Oct 29 00:41:55.838642 sshd[2609]: Accepted publickey for core from 10.200.16.10 port 55624 ssh2: RSA SHA256:+dA5Cjv73N58ZtO+QUEsqxSLadVLxJ02b9LKxPfkHbg Oct 29 00:41:55.839964 sshd-session[2609]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 00:41:55.850283 systemd-logind[2104]: New session 5 of user core. Oct 29 00:41:55.855307 systemd[1]: Started session-5.scope - Session 5 of User core. Oct 29 00:41:56.306877 sshd[2612]: Connection closed by 10.200.16.10 port 55624 Oct 29 00:41:56.305487 sshd-session[2609]: pam_unix(sshd:session): session closed for user core Oct 29 00:41:56.311603 systemd[1]: sshd@2-10.200.8.23:22-10.200.16.10:55624.service: Deactivated successfully. Oct 29 00:41:56.313118 systemd[1]: session-5.scope: Deactivated successfully. Oct 29 00:41:56.314868 systemd-logind[2104]: Session 5 logged out. Waiting for processes to exit. Oct 29 00:41:56.316001 systemd-logind[2104]: Removed session 5. Oct 29 00:42:03.680162 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Oct 29 00:42:05.378306 setup-nvidia[2097]: Downloading NVIDIA 535.261.03 Driver Oct 29 00:42:05.382558 setup-nvidia[2618]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 29 00:42:05.382794 setup-nvidia[2618]: Dload Upload Total Spent Left Speed Oct 29 00:42:06.428457 systemd[1]: Started sshd@3-10.200.8.23:22-10.200.16.10:55180.service - OpenSSH per-connection server daemon (10.200.16.10:55180). Oct 29 00:42:07.057095 sshd[2620]: Accepted publickey for core from 10.200.16.10 port 55180 ssh2: RSA SHA256:+dA5Cjv73N58ZtO+QUEsqxSLadVLxJ02b9LKxPfkHbg Oct 29 00:42:07.058323 sshd-session[2620]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 00:42:07.062505 systemd-logind[2104]: New session 6 of user core. Oct 29 00:42:07.072248 systemd[1]: Started session-6.scope - Session 6 of User core. Oct 29 00:42:07.509696 sshd[2623]: Connection closed by 10.200.16.10 port 55180 Oct 29 00:42:07.510012 sshd-session[2620]: pam_unix(sshd:session): session closed for user core Oct 29 00:42:07.513456 systemd[1]: sshd@3-10.200.8.23:22-10.200.16.10:55180.service: Deactivated successfully. Oct 29 00:42:07.514958 systemd[1]: session-6.scope: Deactivated successfully. Oct 29 00:42:07.515635 systemd-logind[2104]: Session 6 logged out. Waiting for processes to exit. Oct 29 00:42:07.516474 systemd-logind[2104]: Removed session 6. Oct 29 00:42:07.625503 systemd[1]: Started sshd@4-10.200.8.23:22-10.200.16.10:55186.service - OpenSSH per-connection server daemon (10.200.16.10:55186). Oct 29 00:42:08.254866 sshd[2630]: Accepted publickey for core from 10.200.16.10 port 55186 ssh2: RSA SHA256:+dA5Cjv73N58ZtO+QUEsqxSLadVLxJ02b9LKxPfkHbg Oct 29 00:42:08.256065 sshd-session[2630]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 00:42:08.260261 systemd-logind[2104]: New session 7 of user core. Oct 29 00:42:08.284248 systemd[1]: Started session-7.scope - Session 7 of User core. Oct 29 00:42:08.701385 sshd[2633]: Connection closed by 10.200.16.10 port 55186 Oct 29 00:42:08.701711 sshd-session[2630]: pam_unix(sshd:session): session closed for user core Oct 29 00:42:08.705226 systemd[1]: sshd@4-10.200.8.23:22-10.200.16.10:55186.service: Deactivated successfully. Oct 29 00:42:08.706789 systemd[1]: session-7.scope: Deactivated successfully. Oct 29 00:42:08.707475 systemd-logind[2104]: Session 7 logged out. Waiting for processes to exit. Oct 29 00:42:08.708918 systemd-logind[2104]: Removed session 7. Oct 29 00:42:12.659228 update_engine[2107]: I20251029 00:42:12.659124 2107 update_attempter.cc:509] Updating boot flags... Oct 29 00:42:18.818120 systemd[1]: Started sshd@5-10.200.8.23:22-10.200.16.10:37018.service - OpenSSH per-connection server daemon (10.200.16.10:37018). Oct 29 00:42:19.470484 sshd[2755]: Accepted publickey for core from 10.200.16.10 port 37018 ssh2: RSA SHA256:+dA5Cjv73N58ZtO+QUEsqxSLadVLxJ02b9LKxPfkHbg Oct 29 00:42:19.471645 sshd-session[2755]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 00:42:19.475749 systemd-logind[2104]: New session 8 of user core. Oct 29 00:42:19.485252 systemd[1]: Started session-8.scope - Session 8 of User core. Oct 29 00:42:19.931597 sshd[2758]: Connection closed by 10.200.16.10 port 37018 Oct 29 00:42:19.931941 sshd-session[2755]: pam_unix(sshd:session): session closed for user core Oct 29 00:42:19.935781 systemd[1]: sshd@5-10.200.8.23:22-10.200.16.10:37018.service: Deactivated successfully. Oct 29 00:42:19.937642 systemd[1]: session-8.scope: Deactivated successfully. Oct 29 00:42:19.938500 systemd-logind[2104]: Session 8 logged out. Waiting for processes to exit. Oct 29 00:42:19.940015 systemd-logind[2104]: Removed session 8. Oct 29 00:42:20.052719 systemd[1]: Started sshd@6-10.200.8.23:22-10.200.16.10:51740.service - OpenSSH per-connection server daemon (10.200.16.10:51740). Oct 29 00:42:20.710327 sshd[2764]: Accepted publickey for core from 10.200.16.10 port 51740 ssh2: RSA SHA256:+dA5Cjv73N58ZtO+QUEsqxSLadVLxJ02b9LKxPfkHbg Oct 29 00:42:20.711513 sshd-session[2764]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 00:42:20.715692 systemd-logind[2104]: New session 9 of user core. Oct 29 00:42:20.723248 systemd[1]: Started session-9.scope - Session 9 of User core. Oct 29 00:42:21.159068 sshd[2767]: Connection closed by 10.200.16.10 port 51740 Oct 29 00:42:21.159415 sshd-session[2764]: pam_unix(sshd:session): session closed for user core Oct 29 00:42:21.163010 systemd[1]: sshd@6-10.200.8.23:22-10.200.16.10:51740.service: Deactivated successfully. Oct 29 00:42:21.164561 systemd[1]: session-9.scope: Deactivated successfully. Oct 29 00:42:21.165251 systemd-logind[2104]: Session 9 logged out. Waiting for processes to exit. Oct 29 00:42:21.166242 systemd-logind[2104]: Removed session 9. Oct 29 00:42:31.275541 systemd[1]: Started sshd@7-10.200.8.23:22-10.200.16.10:44278.service - OpenSSH per-connection server daemon (10.200.16.10:44278). Oct 29 00:42:31.932325 sshd[2773]: Accepted publickey for core from 10.200.16.10 port 44278 ssh2: RSA SHA256:+dA5Cjv73N58ZtO+QUEsqxSLadVLxJ02b9LKxPfkHbg Oct 29 00:42:31.933535 sshd-session[2773]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 00:42:31.938011 systemd-logind[2104]: New session 10 of user core. Oct 29 00:42:31.949238 systemd[1]: Started session-10.scope - Session 10 of User core. Oct 29 00:42:32.380977 sshd[2776]: Connection closed by 10.200.16.10 port 44278 Oct 29 00:42:32.381357 sshd-session[2773]: pam_unix(sshd:session): session closed for user core Oct 29 00:42:32.385152 systemd[1]: sshd@7-10.200.8.23:22-10.200.16.10:44278.service: Deactivated successfully. Oct 29 00:42:32.386923 systemd[1]: session-10.scope: Deactivated successfully. Oct 29 00:42:32.387705 systemd-logind[2104]: Session 10 logged out. Waiting for processes to exit. Oct 29 00:42:32.388636 systemd-logind[2104]: Removed session 10. Oct 29 00:42:32.497868 systemd[1]: Started sshd@8-10.200.8.23:22-10.200.16.10:44288.service - OpenSSH per-connection server daemon (10.200.16.10:44288). Oct 29 00:42:33.153625 sshd[2783]: Accepted publickey for core from 10.200.16.10 port 44288 ssh2: RSA SHA256:+dA5Cjv73N58ZtO+QUEsqxSLadVLxJ02b9LKxPfkHbg Oct 29 00:42:33.949103 sshd-session[2783]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 00:42:33.953601 systemd-logind[2104]: New session 11 of user core. Oct 29 00:42:33.972270 systemd[1]: Started session-11.scope - Session 11 of User core. Oct 29 00:42:34.334361 sshd[2786]: Connection closed by 10.200.16.10 port 44288 Oct 29 00:42:34.334640 sshd-session[2783]: pam_unix(sshd:session): session closed for user core Oct 29 00:42:34.338251 systemd[1]: sshd@8-10.200.8.23:22-10.200.16.10:44288.service: Deactivated successfully. Oct 29 00:42:34.339847 systemd[1]: session-11.scope: Deactivated successfully. Oct 29 00:42:34.340530 systemd-logind[2104]: Session 11 logged out. Waiting for processes to exit. Oct 29 00:42:34.341413 systemd-logind[2104]: Removed session 11. Oct 29 00:42:44.446519 systemd[1]: Started sshd@9-10.200.8.23:22-10.200.16.10:50712.service - OpenSSH per-connection server daemon (10.200.16.10:50712). Oct 29 00:42:45.076505 sshd[2792]: Accepted publickey for core from 10.200.16.10 port 50712 ssh2: RSA SHA256:+dA5Cjv73N58ZtO+QUEsqxSLadVLxJ02b9LKxPfkHbg Oct 29 00:42:45.077723 sshd-session[2792]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 00:42:45.081942 systemd-logind[2104]: New session 12 of user core. Oct 29 00:42:45.091252 systemd[1]: Started session-12.scope - Session 12 of User core. Oct 29 00:42:45.524175 sshd[2795]: Connection closed by 10.200.16.10 port 50712 Oct 29 00:42:45.524513 sshd-session[2792]: pam_unix(sshd:session): session closed for user core Oct 29 00:42:45.528005 systemd[1]: sshd@9-10.200.8.23:22-10.200.16.10:50712.service: Deactivated successfully. Oct 29 00:42:45.529577 systemd[1]: session-12.scope: Deactivated successfully. Oct 29 00:42:45.530254 systemd-logind[2104]: Session 12 logged out. Waiting for processes to exit. Oct 29 00:42:45.531109 systemd-logind[2104]: Removed session 12. Oct 29 00:42:45.646321 systemd[1]: Started sshd@10-10.200.8.23:22-10.200.16.10:50718.service - OpenSSH per-connection server daemon (10.200.16.10:50718). Oct 29 00:42:46.273781 sshd[2801]: Accepted publickey for core from 10.200.16.10 port 50718 ssh2: RSA SHA256:+dA5Cjv73N58ZtO+QUEsqxSLadVLxJ02b9LKxPfkHbg Oct 29 00:42:46.274964 sshd-session[2801]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 00:42:46.279210 systemd-logind[2104]: New session 13 of user core. Oct 29 00:42:46.288252 systemd[1]: Started session-13.scope - Session 13 of User core. Oct 29 00:42:46.719954 sshd[2804]: Connection closed by 10.200.16.10 port 50718 Oct 29 00:42:46.720327 sshd-session[2801]: pam_unix(sshd:session): session closed for user core Oct 29 00:42:46.723954 systemd[1]: sshd@10-10.200.8.23:22-10.200.16.10:50718.service: Deactivated successfully. Oct 29 00:42:46.725524 systemd[1]: session-13.scope: Deactivated successfully. Oct 29 00:42:46.726213 systemd-logind[2104]: Session 13 logged out. Waiting for processes to exit. Oct 29 00:42:46.727067 systemd-logind[2104]: Removed session 13. Oct 29 00:42:56.836193 systemd[1]: Started sshd@11-10.200.8.23:22-10.200.16.10:60248.service - OpenSSH per-connection server daemon (10.200.16.10:60248). Oct 29 00:42:57.464841 sshd[2810]: Accepted publickey for core from 10.200.16.10 port 60248 ssh2: RSA SHA256:+dA5Cjv73N58ZtO+QUEsqxSLadVLxJ02b9LKxPfkHbg Oct 29 00:42:57.466090 sshd-session[2810]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 00:42:57.470234 systemd-logind[2104]: New session 14 of user core. Oct 29 00:42:57.484260 systemd[1]: Started session-14.scope - Session 14 of User core. Oct 29 00:42:57.911287 sshd[2813]: Connection closed by 10.200.16.10 port 60248 Oct 29 00:42:57.911633 sshd-session[2810]: pam_unix(sshd:session): session closed for user core Oct 29 00:42:57.915217 systemd[1]: sshd@11-10.200.8.23:22-10.200.16.10:60248.service: Deactivated successfully. Oct 29 00:42:57.916966 systemd[1]: session-14.scope: Deactivated successfully. Oct 29 00:42:57.917844 systemd-logind[2104]: Session 14 logged out. Waiting for processes to exit. Oct 29 00:42:57.919310 systemd-logind[2104]: Removed session 14. Oct 29 00:42:58.025540 systemd[1]: Started sshd@12-10.200.8.23:22-10.200.16.10:60258.service - OpenSSH per-connection server daemon (10.200.16.10:60258). Oct 29 00:42:58.656226 sshd[2819]: Accepted publickey for core from 10.200.16.10 port 60258 ssh2: RSA SHA256:+dA5Cjv73N58ZtO+QUEsqxSLadVLxJ02b9LKxPfkHbg Oct 29 00:42:58.658880 sshd-session[2819]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 00:42:58.663178 systemd-logind[2104]: New session 15 of user core. Oct 29 00:42:58.672253 systemd[1]: Started session-15.scope - Session 15 of User core. Oct 29 00:42:59.103453 sshd[2822]: Connection closed by 10.200.16.10 port 60258 Oct 29 00:42:59.103916 sshd-session[2819]: pam_unix(sshd:session): session closed for user core Oct 29 00:42:59.107625 systemd[1]: sshd@12-10.200.8.23:22-10.200.16.10:60258.service: Deactivated successfully. Oct 29 00:42:59.109189 systemd[1]: session-15.scope: Deactivated successfully. Oct 29 00:42:59.109881 systemd-logind[2104]: Session 15 logged out. Waiting for processes to exit. Oct 29 00:42:59.110788 systemd-logind[2104]: Removed session 15. Oct 29 00:43:09.220478 systemd[1]: Started sshd@13-10.200.8.23:22-10.200.16.10:38572.service - OpenSSH per-connection server daemon (10.200.16.10:38572). Oct 29 00:43:09.849419 sshd[2828]: Accepted publickey for core from 10.200.16.10 port 38572 ssh2: RSA SHA256:+dA5Cjv73N58ZtO+QUEsqxSLadVLxJ02b9LKxPfkHbg Oct 29 00:43:09.850758 sshd-session[2828]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 00:43:09.855006 systemd-logind[2104]: New session 16 of user core. Oct 29 00:43:09.876256 systemd[1]: Started session-16.scope - Session 16 of User core. Oct 29 00:43:10.341618 sshd[2831]: Connection closed by 10.200.16.10 port 38572 Oct 29 00:43:10.341975 sshd-session[2828]: pam_unix(sshd:session): session closed for user core Oct 29 00:43:10.345572 systemd[1]: sshd@13-10.200.8.23:22-10.200.16.10:38572.service: Deactivated successfully. Oct 29 00:43:10.347096 systemd[1]: session-16.scope: Deactivated successfully. Oct 29 00:43:10.347794 systemd-logind[2104]: Session 16 logged out. Waiting for processes to exit. Oct 29 00:43:10.348746 systemd-logind[2104]: Removed session 16. Oct 29 00:43:10.462477 setup-nvidia[2618]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 325M 0 2049k 0 0 3077k 0 0:01:48 --:--:-- 0:01:48 3077k\u000d 1 325M 1 6145k 0 0 3552k 0 0:01:33 0:00:01 0:01:32 3552k\u000d 3 325M 3 11.9M 0 0 4955k 0 0:01:07 0:00:02 0:01:05 4955k\u000d 5 325M 5 18.0M 0 0 5221k 0 0:01:03 0:00:03 0:01:00 5221k\u000d 7 325M 7 24.0M 0 0 5173k 0 0:01:04 0:00:04 0:01:00 5173k\u000d 8 325M 8 28.0M 0 0 4963k 0 0:01:07 0:00:05 0:01:02 5210k\u000d 9 325M 9 32.0M 0 0 4974k 0 0:01:07 0:00:06 0:01:01 5480k\u000d 11 325M 11 36.0M 0 0 4938k 0 0:01:07 0:00:07 0:01:00 4929k\u000d 12 325M 12 40.0M 0 0 4844k 0 0:01:08 0:00:08 0:01:00 4574k\u000d 14 325M 14 46.0M 0 0 4880k 0 0:01:08 0:00:09 0:00:59 4596k\u000d 15 325M 15 50.0M 0 0 4876k 0 0:01:08 0:00:10 0:00:58 4768k\u000d 17 325M 17 56.0M 0 0 4939k 0 0:01:07 0:00:11 0:00:56 4893k\u000d 18 325M 18 60.0M 0 0 4935k 0 0:01:07 0:00:12 0:00:55 4929k\u000d 20 325M 20 66.0M 0 0 4941k 0 0:01:07 0:00:13 0:00:54 5097k\u000d 21 325M 21 70.0M 0 0 4947k 0 0:01:07 0:00:14 0:00:53 5080k\u000d 23 325M 23 76.0M 0 0 4929k 0 0:01:07 0:00:15 0:00:52 5036k\u000d 24 325M 24 80.0M 0 0 4937k 0 0:01:07 0:00:16 0:00:51 4932k\u000d 25 325M 25 84.0M 0 0 4923k 0 0:01:07 0:00:17 0:00:50 4892k\u000d 27 325M 27 90.0M 0 0 4978k 0 0:01:07 0:00:18 0:00:49 5085k\u000d 29 325M 29 95.2M 0 0 5013k 0 0:01:06 0:00:19 0:00:47 5205k\u000d 30 325M 30 100M 0 0 4976k 0 0:01:07 0:00:20 0:00:47 5129k\u000d 31 325M 31 104M 0 0 4964k 0 0:01:07 0:00:21 0:00:46 5055k\u000d 33 325M 33 110M 0 0 5016k 0 0:01:06 0:00:22 0:00:44 5342k\u000d 35 325M 35 116M 0 0 5001k 0 0:01:06 0:00:23 0:00:43 5081k\u000d 37 325M 37 121M 0 0 5085k 0 0:01:05 0:00:24 0:00:41 5366k\u000d 39 325M 39 128M 0 0 5086k 0 0:01:05 0:00:25 0:00:40 5524k\u000d 40 325M 40 132M 0 0 5081k 0 0:01:05 0:00:26 0:00:39 5567k\u000d 41 325M 41 136M 0 0 5100k 0 0:01:05 0:00:27 0:00:38 5477k\u000d 42 325M 42 138M 0 0 4950k 0 0:01:07 0:00:28 0:00:39 4699k\u000d 44 325M 44 146M 0 0 5048k 0 0:01:06 0:00:29 0:00:37 4870k\u000d 46 325M 46 150M 0 0 5045k 0 0:01:06 0:00:30 0:00:36 4820k\u000d 47 325M 47 156M 0 0 5046k 0 0:01:06 0:00:31 0:00:35 4863k\u000d 49 325M 49 160M 0 0 5041k 0 0:01:06 0:00:32 0:00:34 4720k\u000d 50 325M 50 166M 0 0 5040k 0 0:01:06 0:00:33 0:00:33 5542k\u000d 52 325M 52 170M 0 0 5036k 0 0:01:06 0:00:34 0:00:32 4963k\u000d 53 325M 53 174M 0 0 5048k 0 0:01:06 0:00:35 0:00:31 5065k\u000d 55 325M 55 180M 0 0 5051k 0 0:01:06 0:00:36 0:00:30 5083k\u000d 57 325M 57 186M 0 0 5046k 0 0:01:06 0:00:37 0:00:29 5077k\u000d 58 325M 58 190M 0 0 5049k 0 0:01:06 0:00:38 0:00:28 5109k\u000d 60 325M 60 196M 0 0 5084k 0 0:01:05 0:00:39 0:00:26 5427k\u000d 62 325M 62 202M 0 0 5135k 0 0:01:04 0:00:40 0:00:24 5752k\u000d 64 325M 64 208M 0 0 5153k 0 0:01:04 0:00:41 0:00:23 5908k\u000d 65 325M 65 214M 0 0 5147k 0 0:01:04 0:00:42 0:00:22 5936k\u000d 67 325M 67 220M 0 0 5145k 0 0:01:04 0:00:43 0:00:21 5849k\u000d 68 325M 68 224M 0 0 5138k 0 0:01:04 0:00:44 0:00:20 5550k\u000d 69 325M 69 228M 0 0 5134k 0 0:01:04 0:00:45 0:00:19 5129k\u000d 71 325M 71 234M 0 0 5121k 0 0:01:05 0:00:46 0:00:19 4873k\u000d 73 325M 73 238M 0 0 5118k 0 0:01:05 0:00:47 0:00:18 4881k\u000d 74 325M 74 242M 0 0 5108k 0 0:01:05 0:00:48 0:00:17 4769k\u000d 76 325M 76 247M 0 0 5135k 0 0:01:04 0:00:49 0:00:15 5104k\u000d 77 325M 77 254M 0 0 5121k 0 0:01:05 0:00:50 0:00:15 5003k\u000d 79 325M 79 258M 0 0 5123k 0 0:01:05 0:00:51 0:00:14 5142k\u000d 80 325M 80 262M 0 0 5128k 0 0:01:05 0:00:52 0:00:13 5221k\u000d 82 325M 82 268M 0 0 5110k 0 0:01:05 0:00:53 0:00:12 5125k\u000d 83 325M 83 272M 0 0 5105k 0 0:01:05 0:00:54 0:00:11 4815k\u000d 85 325M 85 278M 0 0 5113k 0 0:01:05 0:00:55 0:00:10 5035k\u000d 86 325M 86 282M 0 0 5113k 0 0:01:05 0:00:56 0:00:09 5004k\u000d 88 325M 88 288M 0 0 5112k 0 0:01:05 0:00:57 0:00:08 4949k\u000d 89 325M 89 292M 0 0 5112k 0 0:01:05 0:00:58 0:00:07 5133k\u000d 91 325M 91 298M 0 0 5106k 0 0:01:05 0:00:59 0:00:06 5120k\u000d 92 325M 92 302M 0 0 5103k 0 0:01:05 0:01:00 0:00:05 4982k\u000d 93 325M 93 306M 0 0 5098k 0 0:01:05 0:01:01 0:00:04 4932k\u000d 95 325M 95 312M 0 0 5089k 0 0:01:05 0:01:02 0:00:03 4830k\u000d 96 325M 96 316M 0 0 5083k 0 0:01:05 0:01:03 0:00:02 4761k\u000d 98 325M 98 322M 0 0 5105k 0 0:01:05 0:01:04 0:00:01 5083k\u000d100 325M 100 325M 0 0 5128k 0 0:01:05 0:01:05 --:--:-- 5470k Oct 29 00:43:10.464546 systemd[1]: Started sshd@14-10.200.8.23:22-10.200.16.10:44378.service - OpenSSH per-connection server daemon (10.200.16.10:44378). Oct 29 00:43:10.672632 setup-nvidia[2097]: Extract the NVIDIA Driver Installer 535.261.03 Oct 29 00:43:10.672915 setup-nvidia[2097]: /opt/nvidia/workdir/nvidia-workdir / Oct 29 00:43:10.711382 setup-nvidia[2842]: Creating directory NVIDIA-Linux-x86_64-535.261.03 Oct 29 00:43:10.969982 setup-nvidia[2842]: Verifying archive integrity... OK Oct 29 00:43:11.095154 sshd[2837]: Accepted publickey for core from 10.200.16.10 port 44378 ssh2: RSA SHA256:+dA5Cjv73N58ZtO+QUEsqxSLadVLxJ02b9LKxPfkHbg Oct 29 00:43:11.096392 sshd-session[2837]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 00:43:11.100547 systemd-logind[2104]: New session 17 of user core. Oct 29 00:43:11.110265 systemd[1]: Started session-17.scope - Session 17 of User core. Oct 29 00:43:11.540803 sshd[2865]: Connection closed by 10.200.16.10 port 44378 Oct 29 00:43:11.541181 sshd-session[2837]: pam_unix(sshd:session): session closed for user core Oct 29 00:43:11.544791 systemd[1]: sshd@14-10.200.8.23:22-10.200.16.10:44378.service: Deactivated successfully. Oct 29 00:43:11.546432 systemd[1]: session-17.scope: Deactivated successfully. Oct 29 00:43:11.547204 systemd-logind[2104]: Session 17 logged out. Waiting for processes to exit. Oct 29 00:43:11.548042 systemd-logind[2104]: Removed session 17. Oct 29 00:43:11.650457 setup-nvidia[2842]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.261.03 Oct 29 00:43:13.459716 setup-nvidia[2873]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Oct 29 00:43:13.511195 setup-nvidia[2097]: / Oct 29 00:43:13.511422 setup-nvidia[2097]: Spawn system-nspawn container to install the NVIDIA drivers Oct 29 00:43:13.655083 sudo[2880]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4487.0.0+nightly-20251028-2100.bin --overlay=/usr/lib/modules::/usr/lib/modules --bind=/opt/nvidia/workdir/nvidia-workdir:/nvidia --bind=/usr/lib/nvidia/bin:/app/bin/ /app/bin/install-nvidia NVIDIA-Linux-x86_64-535.261.03 Oct 29 00:43:13.656794 sudo[2880]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 29 00:43:13.664009 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Oct 29 00:43:13.666796 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Oct 29 00:43:13.698255 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Oct 29 00:43:13.703696 systemd[1]: Starting user@0.service - User Manager for UID 0... Oct 29 00:43:13.728148 (systemd)[2883]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Oct 29 00:43:13.730335 systemd-logind[2104]: New session c3 of user root. Oct 29 00:43:13.906660 systemd[2883]: Queued start job for default target default.target. Oct 29 00:43:13.926240 systemd[2883]: Created slice app.slice - User Application Slice. Oct 29 00:43:13.926270 systemd[2883]: Reached target paths.target - Paths. Oct 29 00:43:13.926317 systemd[2883]: Reached target timers.target - Timers. Oct 29 00:43:13.927516 systemd[2883]: Starting dbus.socket - D-Bus User Message Bus Socket... Oct 29 00:43:13.938330 systemd[2883]: Listening on dbus.socket - D-Bus User Message Bus Socket. Oct 29 00:43:13.938447 systemd[2883]: Reached target sockets.target - Sockets. Oct 29 00:43:13.938502 systemd[2883]: Reached target basic.target - Basic System. Oct 29 00:43:13.938580 systemd[2883]: Reached target default.target - Main User Target. Oct 29 00:43:13.938619 systemd[2883]: Startup finished in 202ms. Oct 29 00:43:13.938657 systemd[1]: Started user@0.service - User Manager for UID 0. Oct 29 00:43:13.941445 systemd[1]: Started session-c2.scope - Session c2 of User root. Oct 29 00:43:13.972288 systemd-udevd[1794]: Received message with invalid length, ignoring Oct 29 00:43:13.976153 kernel: loop7: detected capacity change from 0 to 12644352 Oct 29 00:43:13.979149 kernel: loop7: p9 Oct 29 00:43:13.991694 setup-nvidia[2892]: ░ Spawning container flatcardevelopercontainer-4487.0.0nightly-20251028-2100.bin on /opt/nvidia/workdir/flatcar_developer_container-4487.0.0+nightly-20251028-2100.bin. Oct 29 00:43:14.107638 kernel: EXT4-fs (loop7p9): write access unavailable, skipping orphan cleanup Oct 29 00:43:14.107762 kernel: EXT4-fs (loop7p9): mounted filesystem 9b7416e2-a412-4c91-99e3-55e8e063e4ef ro without journal. Quota mode: none. Oct 29 00:43:21.652445 systemd[1]: Started sshd@15-10.200.8.23:22-10.200.16.10:50280.service - OpenSSH per-connection server daemon (10.200.16.10:50280). Oct 29 00:43:22.281295 sshd[3733]: Accepted publickey for core from 10.200.16.10 port 50280 ssh2: RSA SHA256:+dA5Cjv73N58ZtO+QUEsqxSLadVLxJ02b9LKxPfkHbg Oct 29 00:43:22.282557 sshd-session[3733]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 00:43:22.286836 systemd-logind[2104]: New session 18 of user core. Oct 29 00:43:22.305261 systemd[1]: Started session-18.scope - Session 18 of User core. Oct 29 00:43:22.728238 sshd[3795]: Connection closed by 10.200.16.10 port 50280 Oct 29 00:43:22.728570 sshd-session[3733]: pam_unix(sshd:session): session closed for user core Oct 29 00:43:22.732199 systemd[1]: sshd@15-10.200.8.23:22-10.200.16.10:50280.service: Deactivated successfully. Oct 29 00:43:22.733808 systemd[1]: session-18.scope: Deactivated successfully. Oct 29 00:43:22.734501 systemd-logind[2104]: Session 18 logged out. Waiting for processes to exit. Oct 29 00:43:22.735377 systemd-logind[2104]: Removed session 18. Oct 29 00:43:22.841504 systemd[1]: Started sshd@16-10.200.8.23:22-10.200.16.10:50292.service - OpenSSH per-connection server daemon (10.200.16.10:50292). Oct 29 00:43:23.484618 sshd[3863]: Accepted publickey for core from 10.200.16.10 port 50292 ssh2: RSA SHA256:+dA5Cjv73N58ZtO+QUEsqxSLadVLxJ02b9LKxPfkHbg Oct 29 00:43:23.485828 sshd-session[3863]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 00:43:23.490240 systemd-logind[2104]: New session 19 of user core. Oct 29 00:43:23.505302 systemd[1]: Started session-19.scope - Session 19 of User core. Oct 29 00:43:23.932716 sshd[3866]: Connection closed by 10.200.16.10 port 50292 Oct 29 00:43:23.933041 sshd-session[3863]: pam_unix(sshd:session): session closed for user core Oct 29 00:43:23.936692 systemd[1]: sshd@16-10.200.8.23:22-10.200.16.10:50292.service: Deactivated successfully. Oct 29 00:43:23.938307 systemd[1]: session-19.scope: Deactivated successfully. Oct 29 00:43:23.938991 systemd-logind[2104]: Session 19 logged out. Waiting for processes to exit. Oct 29 00:43:23.939892 systemd-logind[2104]: Removed session 19. Oct 29 00:43:34.050867 systemd[1]: Started sshd@17-10.200.8.23:22-10.200.16.10:59242.service - OpenSSH per-connection server daemon (10.200.16.10:59242). Oct 29 00:43:34.699906 sshd[8378]: Accepted publickey for core from 10.200.16.10 port 59242 ssh2: RSA SHA256:+dA5Cjv73N58ZtO+QUEsqxSLadVLxJ02b9LKxPfkHbg Oct 29 00:43:34.701713 sshd-session[8378]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 00:43:34.710323 systemd-logind[2104]: New session 20 of user core. Oct 29 00:43:34.720318 systemd[1]: Started session-20.scope - Session 20 of User core. Oct 29 00:43:35.147156 sshd[8599]: Connection closed by 10.200.16.10 port 59242 Oct 29 00:43:35.149446 sshd-session[8378]: pam_unix(sshd:session): session closed for user core Oct 29 00:43:35.156690 systemd[1]: sshd@17-10.200.8.23:22-10.200.16.10:59242.service: Deactivated successfully. Oct 29 00:43:35.157219 systemd-logind[2104]: Session 20 logged out. Waiting for processes to exit. Oct 29 00:43:35.159494 systemd[1]: session-20.scope: Deactivated successfully. Oct 29 00:43:35.163214 systemd-logind[2104]: Removed session 20. Oct 29 00:43:35.264379 systemd[1]: Started sshd@18-10.200.8.23:22-10.200.16.10:59248.service - OpenSSH per-connection server daemon (10.200.16.10:59248). Oct 29 00:43:35.911262 sshd[8819]: Accepted publickey for core from 10.200.16.10 port 59248 ssh2: RSA SHA256:+dA5Cjv73N58ZtO+QUEsqxSLadVLxJ02b9LKxPfkHbg Oct 29 00:43:35.912548 sshd-session[8819]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 00:43:35.922278 systemd-logind[2104]: New session 21 of user core. Oct 29 00:43:35.927747 systemd[1]: Started session-21.scope - Session 21 of User core. Oct 29 00:43:36.357929 sshd[9116]: Connection closed by 10.200.16.10 port 59248 Oct 29 00:43:36.358375 sshd-session[8819]: pam_unix(sshd:session): session closed for user core Oct 29 00:43:36.363276 systemd[1]: sshd@18-10.200.8.23:22-10.200.16.10:59248.service: Deactivated successfully. Oct 29 00:43:36.365073 systemd[1]: session-21.scope: Deactivated successfully. Oct 29 00:43:36.365880 systemd-logind[2104]: Session 21 logged out. Waiting for processes to exit. Oct 29 00:43:36.367170 systemd-logind[2104]: Removed session 21. Oct 29 00:43:46.474786 systemd[1]: Started sshd@19-10.200.8.23:22-10.200.16.10:58870.service - OpenSSH per-connection server daemon (10.200.16.10:58870). Oct 29 00:43:47.124996 sshd[13216]: Accepted publickey for core from 10.200.16.10 port 58870 ssh2: RSA SHA256:+dA5Cjv73N58ZtO+QUEsqxSLadVLxJ02b9LKxPfkHbg Oct 29 00:43:47.126721 sshd-session[13216]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 00:43:47.132587 systemd-logind[2104]: New session 22 of user core. Oct 29 00:43:47.141276 systemd[1]: Started session-22.scope - Session 22 of User core. Oct 29 00:43:47.574290 sshd[13484]: Connection closed by 10.200.16.10 port 58870 Oct 29 00:43:47.575305 sshd-session[13216]: pam_unix(sshd:session): session closed for user core Oct 29 00:43:47.580400 systemd-logind[2104]: Session 22 logged out. Waiting for processes to exit. Oct 29 00:43:47.582220 systemd[1]: sshd@19-10.200.8.23:22-10.200.16.10:58870.service: Deactivated successfully. Oct 29 00:43:47.584807 systemd[1]: session-22.scope: Deactivated successfully. Oct 29 00:43:47.587219 systemd-logind[2104]: Removed session 22. Oct 29 00:43:47.687377 systemd[1]: Started sshd@20-10.200.8.23:22-10.200.16.10:58872.service - OpenSSH per-connection server daemon (10.200.16.10:58872). Oct 29 00:43:48.336648 sshd[13677]: Accepted publickey for core from 10.200.16.10 port 58872 ssh2: RSA SHA256:+dA5Cjv73N58ZtO+QUEsqxSLadVLxJ02b9LKxPfkHbg Oct 29 00:43:48.337951 sshd-session[13677]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 00:43:48.345632 systemd-logind[2104]: New session 23 of user core. Oct 29 00:43:48.356341 systemd[1]: Started session-23.scope - Session 23 of User core. Oct 29 00:43:48.784367 sshd[13969]: Connection closed by 10.200.16.10 port 58872 Oct 29 00:43:48.786306 sshd-session[13677]: pam_unix(sshd:session): session closed for user core Oct 29 00:43:48.789759 systemd-logind[2104]: Session 23 logged out. Waiting for processes to exit. Oct 29 00:43:48.790059 systemd[1]: sshd@20-10.200.8.23:22-10.200.16.10:58872.service: Deactivated successfully. Oct 29 00:43:48.791616 systemd[1]: session-23.scope: Deactivated successfully. Oct 29 00:43:48.792963 systemd-logind[2104]: Removed session 23. Oct 29 00:43:55.664717 update_engine[2107]: I20251029 00:43:55.662215 2107 prefs.cc:52] certificate-report-to-send-update not present in /var/lib/update_engine/prefs Oct 29 00:43:55.664717 update_engine[2107]: I20251029 00:43:55.662268 2107 prefs.cc:52] certificate-report-to-send-download not present in /var/lib/update_engine/prefs Oct 29 00:43:55.664717 update_engine[2107]: I20251029 00:43:55.662408 2107 prefs.cc:52] aleph-version not present in /var/lib/update_engine/prefs Oct 29 00:43:55.664717 update_engine[2107]: I20251029 00:43:55.662822 2107 omaha_request_params.cc:62] Current group set to alpha Oct 29 00:43:55.664717 update_engine[2107]: I20251029 00:43:55.662932 2107 update_attempter.cc:499] Already updated boot flags. Skipping. Oct 29 00:43:55.664717 update_engine[2107]: I20251029 00:43:55.662943 2107 update_attempter.cc:643] Scheduling an action processor start. Oct 29 00:43:55.664717 update_engine[2107]: I20251029 00:43:55.662961 2107 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Oct 29 00:43:55.664717 update_engine[2107]: I20251029 00:43:55.662987 2107 prefs.cc:52] previous-version not present in /var/lib/update_engine/prefs Oct 29 00:43:55.664717 update_engine[2107]: I20251029 00:43:55.663045 2107 omaha_request_action.cc:271] Posting an Omaha request to disabled Oct 29 00:43:55.664717 update_engine[2107]: I20251029 00:43:55.663056 2107 omaha_request_action.cc:272] Request: Oct 29 00:43:55.664717 update_engine[2107]: Oct 29 00:43:55.664717 update_engine[2107]: Oct 29 00:43:55.664717 update_engine[2107]: Oct 29 00:43:55.664717 update_engine[2107]: Oct 29 00:43:55.664717 update_engine[2107]: Oct 29 00:43:55.664717 update_engine[2107]: Oct 29 00:43:55.664717 update_engine[2107]: Oct 29 00:43:55.664717 update_engine[2107]: Oct 29 00:43:55.664717 update_engine[2107]: I20251029 00:43:55.663064 2107 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Oct 29 00:43:55.664717 update_engine[2107]: I20251029 00:43:55.664047 2107 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Oct 29 00:43:55.665505 update_engine[2107]: I20251029 00:43:55.664594 2107 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Oct 29 00:43:55.667337 locksmithd[2261]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_CHECKING_FOR_UPDATE" NewVersion=0.0.0 NewSize=0 Oct 29 00:43:55.680608 update_engine[2107]: E20251029 00:43:55.680552 2107 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled (Domain name not found) Oct 29 00:43:55.680983 update_engine[2107]: I20251029 00:43:55.680655 2107 libcurl_http_fetcher.cc:283] No HTTP response, retry 1 Oct 29 00:43:58.902382 systemd[1]: Started sshd@21-10.200.8.23:22-10.200.16.10:41936.service - OpenSSH per-connection server daemon (10.200.16.10:41936). Oct 29 00:43:59.550481 sshd[14679]: Accepted publickey for core from 10.200.16.10 port 41936 ssh2: RSA SHA256:+dA5Cjv73N58ZtO+QUEsqxSLadVLxJ02b9LKxPfkHbg Oct 29 00:43:59.551030 sshd-session[14679]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 00:43:59.557922 systemd-logind[2104]: New session 24 of user core. Oct 29 00:43:59.565229 systemd[1]: Started session-24.scope - Session 24 of User core. Oct 29 00:43:59.998920 sshd[14704]: Connection closed by 10.200.16.10 port 41936 Oct 29 00:43:59.999278 sshd-session[14679]: pam_unix(sshd:session): session closed for user core Oct 29 00:44:00.004355 systemd-logind[2104]: Session 24 logged out. Waiting for processes to exit. Oct 29 00:44:00.005779 systemd[1]: sshd@21-10.200.8.23:22-10.200.16.10:41936.service: Deactivated successfully. Oct 29 00:44:00.009002 systemd[1]: session-24.scope: Deactivated successfully. Oct 29 00:44:00.010177 systemd-logind[2104]: Removed session 24. Oct 29 00:44:00.112551 systemd[1]: Started sshd@22-10.200.8.23:22-10.200.16.10:59586.service - OpenSSH per-connection server daemon (10.200.16.10:59586). Oct 29 00:44:00.764987 sshd[14722]: Accepted publickey for core from 10.200.16.10 port 59586 ssh2: RSA SHA256:+dA5Cjv73N58ZtO+QUEsqxSLadVLxJ02b9LKxPfkHbg Oct 29 00:44:00.766494 sshd-session[14722]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 00:44:00.775842 systemd-logind[2104]: New session 25 of user core. Oct 29 00:44:00.782778 systemd[1]: Started session-25.scope - Session 25 of User core. Oct 29 00:44:01.221226 sshd[14744]: Connection closed by 10.200.16.10 port 59586 Oct 29 00:44:01.223344 sshd-session[14722]: pam_unix(sshd:session): session closed for user core Oct 29 00:44:01.227798 systemd-logind[2104]: Session 25 logged out. Waiting for processes to exit. Oct 29 00:44:01.229638 systemd[1]: sshd@22-10.200.8.23:22-10.200.16.10:59586.service: Deactivated successfully. Oct 29 00:44:01.233190 systemd[1]: session-25.scope: Deactivated successfully. Oct 29 00:44:01.235454 systemd-logind[2104]: Removed session 25. Oct 29 00:44:05.659667 update_engine[2107]: I20251029 00:44:05.659180 2107 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Oct 29 00:44:05.659667 update_engine[2107]: I20251029 00:44:05.659284 2107 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Oct 29 00:44:05.659667 update_engine[2107]: I20251029 00:44:05.659619 2107 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Oct 29 00:44:05.674737 update_engine[2107]: E20251029 00:44:05.674582 2107 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled (Domain name not found) Oct 29 00:44:05.674737 update_engine[2107]: I20251029 00:44:05.674696 2107 libcurl_http_fetcher.cc:283] No HTTP response, retry 2 Oct 29 00:44:11.450193 systemd[1]: Started sshd@23-10.200.8.23:22-10.200.16.10:41690.service - OpenSSH per-connection server daemon (10.200.16.10:41690). Oct 29 00:44:12.109835 sshd[15091]: Accepted publickey for core from 10.200.16.10 port 41690 ssh2: RSA SHA256:+dA5Cjv73N58ZtO+QUEsqxSLadVLxJ02b9LKxPfkHbg Oct 29 00:44:12.111636 sshd-session[15091]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 00:44:12.118086 systemd-logind[2104]: New session 26 of user core. Oct 29 00:44:12.127330 systemd[1]: Started session-26.scope - Session 26 of User core. Oct 29 00:44:12.558474 sshd[15124]: Connection closed by 10.200.16.10 port 41690 Oct 29 00:44:12.560465 sshd-session[15091]: pam_unix(sshd:session): session closed for user core Oct 29 00:44:12.564664 systemd-logind[2104]: Session 26 logged out. Waiting for processes to exit. Oct 29 00:44:12.565489 systemd[1]: sshd@23-10.200.8.23:22-10.200.16.10:41690.service: Deactivated successfully. Oct 29 00:44:12.566954 systemd[1]: session-26.scope: Deactivated successfully. Oct 29 00:44:12.570642 systemd-logind[2104]: Removed session 26. Oct 29 00:44:12.675433 systemd[1]: Started sshd@24-10.200.8.23:22-10.200.16.10:41698.service - OpenSSH per-connection server daemon (10.200.16.10:41698). Oct 29 00:44:13.327406 sshd[15136]: Accepted publickey for core from 10.200.16.10 port 41698 ssh2: RSA SHA256:+dA5Cjv73N58ZtO+QUEsqxSLadVLxJ02b9LKxPfkHbg Oct 29 00:44:13.330775 sshd-session[15136]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 00:44:13.338451 systemd-logind[2104]: New session 27 of user core. Oct 29 00:44:13.345222 systemd[1]: Started session-27.scope - Session 27 of User core. Oct 29 00:44:13.787352 sshd[15156]: Connection closed by 10.200.16.10 port 41698 Oct 29 00:44:13.787703 sshd-session[15136]: pam_unix(sshd:session): session closed for user core Oct 29 00:44:13.794324 systemd-logind[2104]: Session 27 logged out. Waiting for processes to exit. Oct 29 00:44:13.795824 systemd[1]: sshd@24-10.200.8.23:22-10.200.16.10:41698.service: Deactivated successfully. Oct 29 00:44:13.797457 systemd[1]: session-27.scope: Deactivated successfully. Oct 29 00:44:13.801586 systemd-logind[2104]: Removed session 27. Oct 29 00:44:15.656675 update_engine[2107]: I20251029 00:44:15.656181 2107 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Oct 29 00:44:15.656675 update_engine[2107]: I20251029 00:44:15.656282 2107 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Oct 29 00:44:15.656675 update_engine[2107]: I20251029 00:44:15.656628 2107 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Oct 29 00:44:15.681757 update_engine[2107]: E20251029 00:44:15.681592 2107 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled (Domain name not found) Oct 29 00:44:15.681757 update_engine[2107]: I20251029 00:44:15.681710 2107 libcurl_http_fetcher.cc:283] No HTTP response, retry 3 Oct 29 00:44:23.903700 systemd[1]: Started sshd@25-10.200.8.23:22-10.200.16.10:42586.service - OpenSSH per-connection server daemon (10.200.16.10:42586). Oct 29 00:44:24.557812 sshd[15520]: Accepted publickey for core from 10.200.16.10 port 42586 ssh2: RSA SHA256:+dA5Cjv73N58ZtO+QUEsqxSLadVLxJ02b9LKxPfkHbg Oct 29 00:44:24.559061 sshd-session[15520]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 00:44:24.563354 systemd-logind[2104]: New session 28 of user core. Oct 29 00:44:24.573261 systemd[1]: Started session-28.scope - Session 28 of User core. Oct 29 00:44:25.006377 sshd[15523]: Connection closed by 10.200.16.10 port 42586 Oct 29 00:44:25.006727 sshd-session[15520]: pam_unix(sshd:session): session closed for user core Oct 29 00:44:25.010509 systemd[1]: sshd@25-10.200.8.23:22-10.200.16.10:42586.service: Deactivated successfully. Oct 29 00:44:25.012086 systemd[1]: session-28.scope: Deactivated successfully. Oct 29 00:44:25.012784 systemd-logind[2104]: Session 28 logged out. Waiting for processes to exit. Oct 29 00:44:25.013674 systemd-logind[2104]: Removed session 28. Oct 29 00:44:25.126603 systemd[1]: Started sshd@26-10.200.8.23:22-10.200.16.10:42590.service - OpenSSH per-connection server daemon (10.200.16.10:42590). Oct 29 00:44:25.655226 update_engine[2107]: I20251029 00:44:25.655167 2107 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Oct 29 00:44:25.655577 update_engine[2107]: I20251029 00:44:25.655243 2107 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Oct 29 00:44:25.655577 update_engine[2107]: I20251029 00:44:25.655543 2107 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Oct 29 00:44:25.677291 update_engine[2107]: E20251029 00:44:25.677248 2107 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled (Domain name not found) Oct 29 00:44:25.677367 update_engine[2107]: I20251029 00:44:25.677323 2107 libcurl_http_fetcher.cc:297] Transfer resulted in an error (0), 0 bytes downloaded Oct 29 00:44:25.677367 update_engine[2107]: I20251029 00:44:25.677338 2107 omaha_request_action.cc:617] Omaha request response: Oct 29 00:44:25.677423 update_engine[2107]: E20251029 00:44:25.677412 2107 omaha_request_action.cc:636] Omaha request network transfer failed. Oct 29 00:44:25.677450 update_engine[2107]: I20251029 00:44:25.677433 2107 action_processor.cc:68] ActionProcessor::ActionComplete: OmahaRequestAction action failed. Aborting processing. Oct 29 00:44:25.677450 update_engine[2107]: I20251029 00:44:25.677439 2107 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Oct 29 00:44:25.677450 update_engine[2107]: I20251029 00:44:25.677445 2107 update_attempter.cc:306] Processing Done. Oct 29 00:44:25.677530 update_engine[2107]: E20251029 00:44:25.677459 2107 update_attempter.cc:619] Update failed. Oct 29 00:44:25.677530 update_engine[2107]: I20251029 00:44:25.677465 2107 utils.cc:600] Converting error code 2000 to kActionCodeOmahaErrorInHTTPResponse Oct 29 00:44:25.677530 update_engine[2107]: I20251029 00:44:25.677470 2107 payload_state.cc:97] Updating payload state for error code: 37 (kActionCodeOmahaErrorInHTTPResponse) Oct 29 00:44:25.677530 update_engine[2107]: I20251029 00:44:25.677476 2107 payload_state.cc:103] Ignoring failures until we get a valid Omaha response. Oct 29 00:44:25.677627 update_engine[2107]: I20251029 00:44:25.677542 2107 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Oct 29 00:44:25.677627 update_engine[2107]: I20251029 00:44:25.677569 2107 omaha_request_action.cc:271] Posting an Omaha request to disabled Oct 29 00:44:25.677627 update_engine[2107]: I20251029 00:44:25.677577 2107 omaha_request_action.cc:272] Request: Oct 29 00:44:25.677627 update_engine[2107]: Oct 29 00:44:25.677627 update_engine[2107]: Oct 29 00:44:25.677627 update_engine[2107]: Oct 29 00:44:25.677627 update_engine[2107]: Oct 29 00:44:25.677627 update_engine[2107]: Oct 29 00:44:25.677627 update_engine[2107]: Oct 29 00:44:25.677627 update_engine[2107]: I20251029 00:44:25.677582 2107 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Oct 29 00:44:25.677627 update_engine[2107]: I20251029 00:44:25.677600 2107 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Oct 29 00:44:25.677851 update_engine[2107]: I20251029 00:44:25.677834 2107 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Oct 29 00:44:25.677876 locksmithd[2261]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_REPORTING_ERROR_EVENT" NewVersion=0.0.0 NewSize=0 Oct 29 00:44:25.690655 update_engine[2107]: E20251029 00:44:25.690614 2107 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled (Domain name not found) Oct 29 00:44:25.690733 update_engine[2107]: I20251029 00:44:25.690683 2107 libcurl_http_fetcher.cc:297] Transfer resulted in an error (0), 0 bytes downloaded Oct 29 00:44:25.690733 update_engine[2107]: I20251029 00:44:25.690697 2107 omaha_request_action.cc:617] Omaha request response: Oct 29 00:44:25.690733 update_engine[2107]: I20251029 00:44:25.690703 2107 action_processor.cc:65] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Oct 29 00:44:25.690733 update_engine[2107]: I20251029 00:44:25.690708 2107 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Oct 29 00:44:25.690733 update_engine[2107]: I20251029 00:44:25.690712 2107 update_attempter.cc:306] Processing Done. Oct 29 00:44:25.690733 update_engine[2107]: I20251029 00:44:25.690718 2107 update_attempter.cc:310] Error event sent. Oct 29 00:44:25.690733 update_engine[2107]: I20251029 00:44:25.690727 2107 update_check_scheduler.cc:74] Next update check in 45m12s Oct 29 00:44:25.691039 locksmithd[2261]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_IDLE" NewVersion=0.0.0 NewSize=0 Oct 29 00:44:25.757545 sshd[15529]: Accepted publickey for core from 10.200.16.10 port 42590 ssh2: RSA SHA256:+dA5Cjv73N58ZtO+QUEsqxSLadVLxJ02b9LKxPfkHbg Oct 29 00:44:25.758823 sshd-session[15529]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 00:44:25.763440 systemd-logind[2104]: New session 29 of user core. Oct 29 00:44:25.781264 systemd[1]: Started session-29.scope - Session 29 of User core. Oct 29 00:44:26.204934 sshd[15532]: Connection closed by 10.200.16.10 port 42590 Oct 29 00:44:26.205285 sshd-session[15529]: pam_unix(sshd:session): session closed for user core Oct 29 00:44:26.208968 systemd[1]: sshd@26-10.200.8.23:22-10.200.16.10:42590.service: Deactivated successfully. Oct 29 00:44:26.210568 systemd[1]: session-29.scope: Deactivated successfully. Oct 29 00:44:26.211269 systemd-logind[2104]: Session 29 logged out. Waiting for processes to exit. Oct 29 00:44:26.212106 systemd-logind[2104]: Removed session 29. Oct 29 00:44:32.780085 setup-nvidia[2892]: \u000d Oct 29 00:44:32.780085 setup-nvidia[2892]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Oct 29 00:44:32.780085 setup-nvidia[2892]: frequently when this kernel module was built against the wrong or Oct 29 00:44:32.780085 setup-nvidia[2892]: improperly configured kernel sources, with a version of gcc that differs Oct 29 00:44:32.780085 setup-nvidia[2892]: from the one used to build the target kernel, or if another driver, such Oct 29 00:44:32.780085 setup-nvidia[2892]: as nouveau, is present and prevents the NVIDIA kernel module from Oct 29 00:44:32.780085 setup-nvidia[2892]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Oct 29 00:44:32.780085 setup-nvidia[2892]: installed in this system is supported by this NVIDIA Linux graphics Oct 29 00:44:32.780085 setup-nvidia[2892]: driver release. Oct 29 00:44:32.780085 setup-nvidia[2892]: \u000d Oct 29 00:44:32.780085 setup-nvidia[2892]: Please see the log entries 'Kernel module load error' and 'Kernel Oct 29 00:44:32.780085 setup-nvidia[2892]: messages' at the end of the file Oct 29 00:44:32.780085 setup-nvidia[2892]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for more Oct 29 00:44:32.780085 setup-nvidia[2892]: information. Oct 29 00:44:32.780085 setup-nvidia[2892]: \u000d Oct 29 00:44:32.794992 setup-nvidia[2892]: dmesg: read kernel buffer failed: Operation not permitted Oct 29 00:44:32.815645 setup-nvidia[2892]: \u000d Oct 29 00:44:32.815645 setup-nvidia[2892]: ERROR: Installation has failed. Please see the file Oct 29 00:44:32.815645 setup-nvidia[2892]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for Oct 29 00:44:32.815645 setup-nvidia[2892]: details. You may find suggestions on fixing installation problems in Oct 29 00:44:32.815645 setup-nvidia[2892]: the README available on the Linux driver download page at Oct 29 00:44:32.815645 setup-nvidia[2892]: www.nvidia.com. Oct 29 00:44:32.815645 setup-nvidia[2892]: \u000d Oct 29 00:44:32.816063 setup-nvidia[2892]: Last 50 lines of nvidia-installer.log: Oct 29 00:44:32.816846 setup-nvidia[2892]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039059rm+0x53: 'naked' return found in MITIGATION_RETHUNK build Oct 29 00:44:32.816846 setup-nvidia[2892]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039056rm+0x90: 'naked' return found in MITIGATION_RETHUNK build Oct 29 00:44:32.816846 setup-nvidia[2892]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039049rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build Oct 29 00:44:32.816846 setup-nvidia[2892]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039105rm+0x93: 'naked' return found in MITIGATION_RETHUNK build Oct 29 00:44:32.816846 setup-nvidia[2892]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039101rm+0x36: 'naked' return found in MITIGATION_RETHUNK build Oct 29 00:44:32.816846 setup-nvidia[2892]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039100rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build Oct 29 00:44:32.816846 setup-nvidia[2892]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039102rm+0x77: 'naked' return found in MITIGATION_RETHUNK build Oct 29 00:44:32.816846 setup-nvidia[2892]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039046rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build Oct 29 00:44:32.816846 setup-nvidia[2892]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030615rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build Oct 29 00:44:32.816846 setup-nvidia[2892]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030614rm+0x27: 'naked' return found in MITIGATION_RETHUNK build Oct 29 00:44:32.816846 setup-nvidia[2892]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030618rm+0x37: 'naked' return found in MITIGATION_RETHUNK build Oct 29 00:44:32.816846 setup-nvidia[2892]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030617rm+0x24: 'naked' return found in MITIGATION_RETHUNK build Oct 29 00:44:32.816846 setup-nvidia[2892]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030616rm+0xca: 'naked' return found in MITIGATION_RETHUNK build Oct 29 00:44:32.816846 setup-nvidia[2892]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030619rm+0x193: 'naked' return found in MITIGATION_RETHUNK build Oct 29 00:44:32.816846 setup-nvidia[2892]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030621rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build Oct 29 00:44:32.816846 setup-nvidia[2892]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv011864rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build Oct 29 00:44:32.817278 setup-nvidia[2892]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039413rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build Oct 29 00:44:32.817278 setup-nvidia[2892]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/Module.symvers Oct 29 00:44:32.817278 setup-nvidia[2892]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.mod.o Oct 29 00:44:32.817278 setup-nvidia[2892]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/.module-common.o Oct 29 00:44:32.817278 setup-nvidia[2892]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.mod.o Oct 29 00:44:32.817278 setup-nvidia[2892]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.mod.o Oct 29 00:44:32.817278 setup-nvidia[2892]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.mod.o Oct 29 00:44:32.817278 setup-nvidia[2892]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.mod.o Oct 29 00:44:32.817278 setup-nvidia[2892]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Oct 29 00:44:32.817278 setup-nvidia[2892]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Oct 29 00:44:32.817278 setup-nvidia[2892]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Oct 29 00:44:32.817278 setup-nvidia[2892]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Oct 29 00:44:32.817278 setup-nvidia[2892]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Oct 29 00:44:32.817278 setup-nvidia[2892]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Oct 29 00:44:32.817278 setup-nvidia[2892]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko due to unavailability of vmlinux Oct 29 00:44:32.817278 setup-nvidia[2892]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Oct 29 00:44:32.817278 setup-nvidia[2892]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko due to unavailability of vmlinux Oct 29 00:44:32.817278 setup-nvidia[2892]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Oct 29 00:44:32.817278 setup-nvidia[2892]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko due to unavailability of vmlinux Oct 29 00:44:32.817278 setup-nvidia[2892]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Oct 29 00:44:32.817278 setup-nvidia[2892]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko due to unavailability of vmlinux Oct 29 00:44:32.817278 setup-nvidia[2892]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Oct 29 00:44:32.817278 setup-nvidia[2892]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko due to unavailability of vmlinux Oct 29 00:44:32.817278 setup-nvidia[2892]: make[2]: Leaving directory '/usr/lib/modules/6.12.54-flatcar/build' Oct 29 00:44:32.817278 setup-nvidia[2892]: make[1]: Leaving directory '/usr/lib/modules/6.12.54-flatcar/source' Oct 29 00:44:32.817278 setup-nvidia[2892]: -> done. Oct 29 00:44:32.817278 setup-nvidia[2892]: -> Kernel module compilation complete. Oct 29 00:44:32.817278 setup-nvidia[2892]: -> Unable to determine if Secure Boot is enabled: No such file or directory Oct 29 00:44:32.817278 setup-nvidia[2892]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most frequently when this kernel module was built against the wrong or improperly configured kernel sources, with a version of gcc that differs from the one used to build the target kernel, or if another driver, such as nouveau, is present and prevents the NVIDIA kernel module from obtaining ownership of the NVIDIA device(s), or no NVIDIA device installed in this system is supported by this NVIDIA Linux graphics driver release. Oct 29 00:44:32.817278 setup-nvidia[2892]: \u000d Oct 29 00:44:32.817278 setup-nvidia[2892]: Please see the log entries 'Kernel module load error' and 'Kernel messages' at the end of the file '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for more information. Oct 29 00:44:32.817278 setup-nvidia[2892]: -> Kernel module load error: Operation not permitted Oct 29 00:44:32.817278 setup-nvidia[2892]: -> Kernel messages: Oct 29 00:44:32.817278 setup-nvidia[2892]: ERROR: Installation has failed. Please see the file '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for details. You may find suggestions on fixing installation problems in the README available on the Linux driver download page at www.nvidia.com. Oct 29 00:44:36.320169 systemd[1]: Started sshd@27-10.200.8.23:22-10.200.16.10:37068.service - OpenSSH per-connection server daemon (10.200.16.10:37068). Oct 29 00:44:36.952576 sshd[15719]: Accepted publickey for core from 10.200.16.10 port 37068 ssh2: RSA SHA256:+dA5Cjv73N58ZtO+QUEsqxSLadVLxJ02b9LKxPfkHbg Oct 29 00:44:36.953742 sshd-session[15719]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 00:44:36.958172 systemd-logind[2104]: New session 30 of user core. Oct 29 00:44:36.973258 systemd[1]: Started session-30.scope - Session 30 of User core. Oct 29 00:44:37.404791 sshd[15724]: Connection closed by 10.200.16.10 port 37068 Oct 29 00:44:37.405122 sshd-session[15719]: pam_unix(sshd:session): session closed for user core Oct 29 00:44:37.408635 systemd[1]: sshd@27-10.200.8.23:22-10.200.16.10:37068.service: Deactivated successfully. Oct 29 00:44:37.410400 systemd[1]: session-30.scope: Deactivated successfully. Oct 29 00:44:37.411164 systemd-logind[2104]: Session 30 logged out. Waiting for processes to exit. Oct 29 00:44:37.412025 systemd-logind[2104]: Removed session 30. Oct 29 00:44:37.532826 systemd[1]: Started sshd@28-10.200.8.23:22-10.200.16.10:37080.service - OpenSSH per-connection server daemon (10.200.16.10:37080). Oct 29 00:44:38.168100 sshd[15730]: Accepted publickey for core from 10.200.16.10 port 37080 ssh2: RSA SHA256:+dA5Cjv73N58ZtO+QUEsqxSLadVLxJ02b9LKxPfkHbg Oct 29 00:44:38.169403 sshd-session[15730]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 00:44:38.173773 systemd-logind[2104]: New session 31 of user core. Oct 29 00:44:38.185248 systemd[1]: Started session-31.scope - Session 31 of User core. Oct 29 00:44:38.616714 sshd[15733]: Connection closed by 10.200.16.10 port 37080 Oct 29 00:44:38.617007 sshd-session[15730]: pam_unix(sshd:session): session closed for user core Oct 29 00:44:38.620745 systemd[1]: sshd@28-10.200.8.23:22-10.200.16.10:37080.service: Deactivated successfully. Oct 29 00:44:38.622346 systemd[1]: session-31.scope: Deactivated successfully. Oct 29 00:44:38.623020 systemd-logind[2104]: Session 31 logged out. Waiting for processes to exit. Oct 29 00:44:38.623900 systemd-logind[2104]: Removed session 31. Oct 29 00:44:48.728483 systemd[1]: Started sshd@29-10.200.8.23:22-10.200.16.10:34334.service - OpenSSH per-connection server daemon (10.200.16.10:34334). Oct 29 00:44:49.378480 sshd[15739]: Accepted publickey for core from 10.200.16.10 port 34334 ssh2: RSA SHA256:+dA5Cjv73N58ZtO+QUEsqxSLadVLxJ02b9LKxPfkHbg Oct 29 00:44:49.379707 sshd-session[15739]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 00:44:49.383888 systemd-logind[2104]: New session 32 of user core. Oct 29 00:44:49.398255 systemd[1]: Started session-32.scope - Session 32 of User core. Oct 29 00:44:49.824819 sshd[15742]: Connection closed by 10.200.16.10 port 34334 Oct 29 00:44:49.825091 sshd-session[15739]: pam_unix(sshd:session): session closed for user core Oct 29 00:44:49.828593 systemd[1]: sshd@29-10.200.8.23:22-10.200.16.10:34334.service: Deactivated successfully. Oct 29 00:44:49.830221 systemd[1]: session-32.scope: Deactivated successfully. Oct 29 00:44:49.830915 systemd-logind[2104]: Session 32 logged out. Waiting for processes to exit. Oct 29 00:44:49.831759 systemd-logind[2104]: Removed session 32. Oct 29 00:44:49.940255 systemd[1]: Started sshd@30-10.200.8.23:22-10.200.16.10:50422.service - OpenSSH per-connection server daemon (10.200.16.10:50422). Oct 29 00:44:50.598480 sshd[15748]: Accepted publickey for core from 10.200.16.10 port 50422 ssh2: RSA SHA256:+dA5Cjv73N58ZtO+QUEsqxSLadVLxJ02b9LKxPfkHbg Oct 29 00:44:50.599706 sshd-session[15748]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 00:44:50.603916 systemd-logind[2104]: New session 33 of user core. Oct 29 00:44:50.623260 systemd[1]: Started session-33.scope - Session 33 of User core. Oct 29 00:44:51.052725 sshd[15751]: Connection closed by 10.200.16.10 port 50422 Oct 29 00:44:51.053074 sshd-session[15748]: pam_unix(sshd:session): session closed for user core Oct 29 00:44:51.058069 systemd[1]: sshd@30-10.200.8.23:22-10.200.16.10:50422.service: Deactivated successfully. Oct 29 00:44:51.059728 systemd[1]: session-33.scope: Deactivated successfully. Oct 29 00:44:51.060901 systemd-logind[2104]: Session 33 logged out. Waiting for processes to exit. Oct 29 00:44:51.061878 systemd-logind[2104]: Removed session 33. Oct 29 00:44:57.559166 kernel: EXT4-fs (loop7p9): unmounting filesystem 9b7416e2-a412-4c91-99e3-55e8e063e4ef. Oct 29 00:44:57.559479 setup-nvidia[2892]: Container flatcardevelopercontainer-4487.0.0nightly-20251028-2100.bin exited successfully. Oct 29 00:44:57.561511 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4487.0.0nightly\x2d20251028\x2d2100.bin.mount: Deactivated successfully. Oct 29 00:44:57.619966 sudo[2880]: pam_unix(sudo:session): session closed for user root Oct 29 00:44:57.622507 systemd[1]: session-c2.scope: Deactivated successfully. Oct 29 00:44:57.622769 systemd[1]: session-c2.scope: Consumed 5min 18.580s CPU time, 891.6M memory peak. Oct 29 00:44:58.049205 setup-nvidia[2097]: /opt/nvidia / Oct 29 00:44:58.050188 setup-nvidia[2097]: / Oct 29 00:44:58.062153 kernel: IPMI message handler: version 39.2 Oct 29 00:44:58.065153 kernel: ipmi device interface Oct 29 00:44:58.080654 setup-nvidia[2097]: /opt/nvidia/current/usr/lib/modules/6.12.54-flatcar/video / Oct 29 00:44:58.133818 kernel: nvidia: loading out-of-tree module taints kernel. Oct 29 00:44:58.133946 kernel: nvidia: module license 'NVIDIA' taints kernel. Oct 29 00:44:58.133987 kernel: Disabling lock debugging due to kernel taint Oct 29 00:44:58.141404 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Oct 29 00:44:58.141438 kernel: nvidia: module license taints kernel. Oct 29 00:44:58.537161 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 Oct 29 00:44:58.930559 kernel: Oct 29 00:44:58.930707 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Oct 29 00:44:59.138161 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.261.03 Sat Jun 14 16:05:50 UTC 2025 Oct 29 00:44:59.159150 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.261.03 Sat Jun 14 15:39:16 UTC 2025 Oct 29 00:44:59.179162 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Oct 29 00:44:59.224804 setup-nvidia[2097]: / Oct 29 00:44:59.225182 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. Oct 29 00:44:59.234059 setup-nvidia[15791]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 29 00:45:00.986556 setup-nvidia[15808]: Wed Oct 29 00:45:00 2025 Oct 29 00:45:00.986556 setup-nvidia[15808]: +---------------------------------------------------------------------------------------+ Oct 29 00:45:00.986556 setup-nvidia[15808]: | NVIDIA-SMI 535.261.03 Driver Version: 535.261.03 CUDA Version: 12.2 | Oct 29 00:45:00.986556 setup-nvidia[15808]: |-----------------------------------------+----------------------+----------------------+ Oct 29 00:45:00.986556 setup-nvidia[15808]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Oct 29 00:45:00.986556 setup-nvidia[15808]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Oct 29 00:45:00.986556 setup-nvidia[15808]: | | | MIG M. | Oct 29 00:45:00.986556 setup-nvidia[15808]: |=========================================+======================+======================| Oct 29 00:45:00.990076 setup-nvidia[15808]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Oct 29 00:45:00.990076 setup-nvidia[15808]: | N/A 23C P0 37W / 250W | 0MiB / 16384MiB | 0% Default | Oct 29 00:45:00.990076 setup-nvidia[15808]: | | | N/A | Oct 29 00:45:00.990076 setup-nvidia[15808]: +-----------------------------------------+----------------------+----------------------+ Oct 29 00:45:00.990206 setup-nvidia[15808]: Oct 29 00:45:00.990206 setup-nvidia[15808]: +---------------------------------------------------------------------------------------+ Oct 29 00:45:00.990206 setup-nvidia[15808]: | Processes: | Oct 29 00:45:00.990206 setup-nvidia[15808]: | GPU GI CI PID Type Process name GPU Memory | Oct 29 00:45:00.990206 setup-nvidia[15808]: | ID ID Usage | Oct 29 00:45:00.990206 setup-nvidia[15808]: |=======================================================================================| Oct 29 00:45:00.990343 setup-nvidia[15808]: | No running processes found | Oct 29 00:45:00.990343 setup-nvidia[15808]: +---------------------------------------------------------------------------------------+ Oct 29 00:45:01.169033 systemd[1]: Started sshd@31-10.200.8.23:22-10.200.16.10:39412.service - OpenSSH per-connection server daemon (10.200.16.10:39412). Oct 29 00:45:01.375979 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Oct 29 00:45:01.377988 systemd[1]: Reached target multi-user.target - Multi-User System. Oct 29 00:45:01.384252 systemd[1]: Startup finished in 1.101s (firmware) + 15.656s (loader) + 5.159s (kernel) + 14.961s (initrd) + 3min 54.608s (userspace) = 4min 31.486s. Oct 29 00:45:01.991948 sshd[15813]: Accepted publickey for core from 10.200.16.10 port 39412 ssh2: RSA SHA256:+dA5Cjv73N58ZtO+QUEsqxSLadVLxJ02b9LKxPfkHbg Oct 29 00:45:01.993233 sshd-session[15813]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 00:45:01.997677 systemd-logind[2104]: New session 34 of user core. Oct 29 00:45:02.008256 systemd[1]: Started session-34.scope - Session 34 of User core. Oct 29 00:45:02.450219 sshd[15818]: Connection closed by 10.200.16.10 port 39412 Oct 29 00:45:02.450559 sshd-session[15813]: pam_unix(sshd:session): session closed for user core Oct 29 00:45:02.454091 systemd[1]: sshd@31-10.200.8.23:22-10.200.16.10:39412.service: Deactivated successfully. Oct 29 00:45:02.455789 systemd[1]: session-34.scope: Deactivated successfully. Oct 29 00:45:02.456533 systemd-logind[2104]: Session 34 logged out. Waiting for processes to exit. Oct 29 00:45:02.457386 systemd-logind[2104]: Removed session 34. Oct 29 00:45:02.561554 systemd[1]: Started sshd@32-10.200.8.23:22-10.200.16.10:39418.service - OpenSSH per-connection server daemon (10.200.16.10:39418). Oct 29 00:45:03.227101 sshd[15824]: Accepted publickey for core from 10.200.16.10 port 39418 ssh2: RSA SHA256:+dA5Cjv73N58ZtO+QUEsqxSLadVLxJ02b9LKxPfkHbg Oct 29 00:45:03.228353 sshd-session[15824]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 00:45:03.232593 systemd-logind[2104]: New session 35 of user core. Oct 29 00:45:03.241261 systemd[1]: Started session-35.scope - Session 35 of User core. Oct 29 00:45:03.676403 sshd[15827]: Connection closed by 10.200.16.10 port 39418 Oct 29 00:45:03.676725 sshd-session[15824]: pam_unix(sshd:session): session closed for user core Oct 29 00:45:03.680321 systemd[1]: sshd@32-10.200.8.23:22-10.200.16.10:39418.service: Deactivated successfully. Oct 29 00:45:03.681889 systemd[1]: session-35.scope: Deactivated successfully. Oct 29 00:45:03.682577 systemd-logind[2104]: Session 35 logged out. Waiting for processes to exit. Oct 29 00:45:03.683425 systemd-logind[2104]: Removed session 35. Oct 29 00:45:03.821303 systemd[1]: Started sshd@33-10.200.8.23:22-10.200.16.10:39426.service - OpenSSH per-connection server daemon (10.200.16.10:39426). Oct 29 00:45:04.471254 sshd[15833]: Accepted publickey for core from 10.200.16.10 port 39426 ssh2: RSA SHA256:+dA5Cjv73N58ZtO+QUEsqxSLadVLxJ02b9LKxPfkHbg Oct 29 00:45:04.474142 sshd-session[15833]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 00:45:04.478281 systemd-logind[2104]: New session 36 of user core. Oct 29 00:45:04.491252 systemd[1]: Started session-36.scope - Session 36 of User core. Oct 29 00:45:04.926697 sshd[15836]: Connection closed by 10.200.16.10 port 39426 Oct 29 00:45:04.927008 sshd-session[15833]: pam_unix(sshd:session): session closed for user core Oct 29 00:45:04.933579 systemd[1]: sshd@33-10.200.8.23:22-10.200.16.10:39426.service: Deactivated successfully. Oct 29 00:45:04.935244 systemd[1]: session-36.scope: Deactivated successfully. Oct 29 00:45:04.935936 systemd-logind[2104]: Session 36 logged out. Waiting for processes to exit. Oct 29 00:45:04.936798 systemd-logind[2104]: Removed session 36. Oct 29 00:45:05.042420 systemd[1]: Started sshd@34-10.200.8.23:22-10.200.16.10:39430.service - OpenSSH per-connection server daemon (10.200.16.10:39430). Oct 29 00:45:05.690626 sshd[15842]: Accepted publickey for core from 10.200.16.10 port 39430 ssh2: RSA SHA256:+dA5Cjv73N58ZtO+QUEsqxSLadVLxJ02b9LKxPfkHbg Oct 29 00:45:05.691846 sshd-session[15842]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 00:45:05.695991 systemd-logind[2104]: New session 37 of user core. Oct 29 00:45:05.707256 systemd[1]: Started session-37.scope - Session 37 of User core. Oct 29 00:45:06.139434 sshd[15845]: Connection closed by 10.200.16.10 port 39430 Oct 29 00:45:06.139774 sshd-session[15842]: pam_unix(sshd:session): session closed for user core Oct 29 00:45:06.142862 systemd[1]: sshd@34-10.200.8.23:22-10.200.16.10:39430.service: Deactivated successfully. Oct 29 00:45:06.144548 systemd[1]: session-37.scope: Deactivated successfully. Oct 29 00:45:06.145705 systemd-logind[2104]: Session 37 logged out. Waiting for processes to exit. Oct 29 00:45:06.146666 systemd-logind[2104]: Removed session 37. Oct 29 00:45:06.258922 systemd[1]: Started sshd@35-10.200.8.23:22-10.200.16.10:39438.service - OpenSSH per-connection server daemon (10.200.16.10:39438). Oct 29 00:45:06.906853 sshd[15851]: Accepted publickey for core from 10.200.16.10 port 39438 ssh2: RSA SHA256:+dA5Cjv73N58ZtO+QUEsqxSLadVLxJ02b9LKxPfkHbg Oct 29 00:45:06.908035 sshd-session[15851]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 00:45:06.912313 systemd-logind[2104]: New session 38 of user core. Oct 29 00:45:06.923243 systemd[1]: Started session-38.scope - Session 38 of User core. Oct 29 00:45:07.626799 systemd[1]: Stopping user@0.service - User Manager for UID 0... Oct 29 00:45:07.627575 systemd[2883]: Activating special unit exit.target... Oct 29 00:45:07.627660 systemd[2883]: Stopped target default.target - Main User Target. Oct 29 00:45:07.627702 systemd[2883]: Stopped target basic.target - Basic System. Oct 29 00:45:07.627732 systemd[2883]: Stopped target paths.target - Paths. Oct 29 00:45:07.627758 systemd[2883]: Stopped target sockets.target - Sockets. Oct 29 00:45:07.627784 systemd[2883]: Stopped target timers.target - Timers. Oct 29 00:45:07.628122 systemd[2883]: Closed dbus.socket - D-Bus User Message Bus Socket. Oct 29 00:45:07.628353 systemd[2883]: Removed slice app.slice - User Application Slice. Oct 29 00:45:07.628386 systemd[2883]: Reached target shutdown.target - Shutdown. Oct 29 00:45:07.628525 systemd[2883]: Finished systemd-exit.service - Exit the Session. Oct 29 00:45:07.628562 systemd[2883]: Reached target exit.target - Exit the Session. Oct 29 00:45:07.641142 systemd-logind[2104]: Removed session c3. Oct 29 00:45:07.641156 (sd-pam)[2884]: pam_unix(systemd-user:session): session closed for user root Oct 29 00:45:07.642407 systemd[1]: user@0.service: Deactivated successfully. Oct 29 00:45:07.642776 systemd[1]: Stopped user@0.service - User Manager for UID 0. Oct 29 00:45:07.645067 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Oct 29 00:45:07.669422 systemd[1]: run-user-0.mount: Deactivated successfully. Oct 29 00:45:07.670361 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Oct 29 00:45:07.670544 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Oct 29 00:45:07.672188 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Oct 29 00:45:07.672293 systemd[1]: user-0.slice: Consumed 5min 18.792s CPU time, 897.7M memory peak. Oct 29 00:45:08.813413 sshd[15854]: Connection closed by 10.200.16.10 port 39438 Oct 29 00:45:08.813768 sshd-session[15851]: pam_unix(sshd:session): session closed for user core Oct 29 00:45:08.817428 systemd[1]: sshd@35-10.200.8.23:22-10.200.16.10:39438.service: Deactivated successfully. Oct 29 00:45:08.819024 systemd[1]: session-38.scope: Deactivated successfully. Oct 29 00:45:08.819248 systemd[1]: session-38.scope: Consumed 1.402s CPU time, 14.1M memory peak. Oct 29 00:45:08.819738 systemd-logind[2104]: Session 38 logged out. Waiting for processes to exit. Oct 29 00:45:08.820664 systemd-logind[2104]: Removed session 38.