Sep 16 04:54:37.186576 kernel: Linux version 6.12.47-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.0 p8) 14.3.0, GNU ld (Gentoo 2.44 p4) 2.44.0) #1 SMP PREEMPT_DYNAMIC Tue Sep 16 03:05:42 -00 2025 Sep 16 04:54:37.186605 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=0b876f86a632750e9937176808a48c2452d5168964273bcfc3c72f2a26140c06 Sep 16 04:54:37.186614 kernel: BIOS-provided physical RAM map: Sep 16 04:54:37.186621 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Sep 16 04:54:37.186628 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Sep 16 04:54:37.186634 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Sep 16 04:54:37.186642 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Sep 16 04:54:37.186652 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Sep 16 04:54:37.186659 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Sep 16 04:54:37.186665 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Sep 16 04:54:37.186672 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Sep 16 04:54:37.186679 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Sep 16 04:54:37.186686 kernel: printk: legacy bootconsole [earlyser0] enabled Sep 16 04:54:37.186693 kernel: NX (Execute Disable) protection: active Sep 16 04:54:37.186703 kernel: APIC: Static calls initialized Sep 16 04:54:37.186711 kernel: efi: EFI v2.7 by Microsoft Sep 16 04:54:37.186719 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Sep 16 04:54:37.186726 kernel: random: crng init done Sep 16 04:54:37.186734 kernel: secureboot: Secure boot disabled Sep 16 04:54:37.186742 kernel: SMBIOS 3.1.0 present. Sep 16 04:54:37.186750 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Sep 16 04:54:37.186757 kernel: DMI: Memory slots populated: 3/3 Sep 16 04:54:37.186765 kernel: Hypervisor detected: Microsoft Hyper-V Sep 16 04:54:37.186772 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Sep 16 04:54:37.186782 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Sep 16 04:54:37.186790 kernel: Hyper-V: Using hypercall for remote TLB flush Sep 16 04:54:37.186798 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Sep 16 04:54:37.186805 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Sep 16 04:54:37.186828 kernel: tsc: Marking TSC unstable due to running on Hyper-V Sep 16 04:54:37.186837 kernel: tsc: Detected 2593.994 MHz processor Sep 16 04:54:37.186845 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Sep 16 04:54:37.186853 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Sep 16 04:54:37.186860 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Sep 16 04:54:37.186871 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Sep 16 04:54:37.186879 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Sep 16 04:54:37.186887 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Sep 16 04:54:37.186894 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Sep 16 04:54:37.186902 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Sep 16 04:54:37.186909 kernel: Using GB pages for direct mapping Sep 16 04:54:37.186917 kernel: ACPI: Early table checksum verification disabled Sep 16 04:54:37.186929 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Sep 16 04:54:37.186939 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 16 04:54:37.186948 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 16 04:54:37.186956 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Sep 16 04:54:37.186964 kernel: ACPI: FACS 0x000000003FFFE000 000040 Sep 16 04:54:37.186972 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 16 04:54:37.186980 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 16 04:54:37.186991 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 16 04:54:37.186999 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 16 04:54:37.187007 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 16 04:54:37.187015 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 16 04:54:37.187024 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 16 04:54:37.187032 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Sep 16 04:54:37.187040 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Sep 16 04:54:37.187048 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Sep 16 04:54:37.187056 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Sep 16 04:54:37.187066 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Sep 16 04:54:37.187074 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Sep 16 04:54:37.187083 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Sep 16 04:54:37.187091 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Sep 16 04:54:37.187099 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Sep 16 04:54:37.187107 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Sep 16 04:54:37.187115 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Sep 16 04:54:37.187123 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Sep 16 04:54:37.187133 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Sep 16 04:54:37.187142 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Sep 16 04:54:37.187150 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Sep 16 04:54:37.187158 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Sep 16 04:54:37.187166 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Sep 16 04:54:37.187174 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Sep 16 04:54:37.187182 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] Sep 16 04:54:37.187190 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] Sep 16 04:54:37.187198 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] Sep 16 04:54:37.187209 kernel: Zone ranges: Sep 16 04:54:37.187217 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Sep 16 04:54:37.187225 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Sep 16 04:54:37.187233 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Sep 16 04:54:37.187241 kernel: Device empty Sep 16 04:54:37.187249 kernel: Movable zone start for each node Sep 16 04:54:37.187257 kernel: Early memory node ranges Sep 16 04:54:37.187265 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Sep 16 04:54:37.187274 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Sep 16 04:54:37.187284 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Sep 16 04:54:37.187292 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Sep 16 04:54:37.187300 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Sep 16 04:54:37.187308 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Sep 16 04:54:37.187316 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Sep 16 04:54:37.187324 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Sep 16 04:54:37.187332 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Sep 16 04:54:37.187341 kernel: ACPI: PM-Timer IO Port: 0x408 Sep 16 04:54:37.187349 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Sep 16 04:54:37.187359 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Sep 16 04:54:37.187367 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Sep 16 04:54:37.187375 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Sep 16 04:54:37.187383 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Sep 16 04:54:37.187391 kernel: CPU topo: Max. logical packages: 1 Sep 16 04:54:37.187399 kernel: CPU topo: Max. logical dies: 1 Sep 16 04:54:37.187407 kernel: CPU topo: Max. dies per package: 1 Sep 16 04:54:37.187415 kernel: CPU topo: Max. threads per core: 1 Sep 16 04:54:37.187423 kernel: CPU topo: Num. cores per package: 6 Sep 16 04:54:37.187433 kernel: CPU topo: Num. threads per package: 6 Sep 16 04:54:37.187442 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs Sep 16 04:54:37.187450 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Sep 16 04:54:37.187458 kernel: Booting paravirtualized kernel on Hyper-V Sep 16 04:54:37.187466 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Sep 16 04:54:37.187474 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Sep 16 04:54:37.187482 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 Sep 16 04:54:37.187490 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 Sep 16 04:54:37.187498 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Sep 16 04:54:37.187508 kernel: Hyper-V: PV spinlocks enabled Sep 16 04:54:37.187517 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Sep 16 04:54:37.187526 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=0b876f86a632750e9937176808a48c2452d5168964273bcfc3c72f2a26140c06 Sep 16 04:54:37.187535 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Sep 16 04:54:37.187543 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Sep 16 04:54:37.187551 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Sep 16 04:54:37.187559 kernel: Fallback order for Node 0: 0 Sep 16 04:54:37.187567 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 Sep 16 04:54:37.187578 kernel: Policy zone: Normal Sep 16 04:54:37.187594 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 16 04:54:37.187602 kernel: software IO TLB: area num 8. Sep 16 04:54:37.187613 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Sep 16 04:54:37.187622 kernel: Kernel/User page tables isolation: enabled Sep 16 04:54:37.187631 kernel: ftrace: allocating 40125 entries in 157 pages Sep 16 04:54:37.187639 kernel: ftrace: allocated 157 pages with 5 groups Sep 16 04:54:37.187648 kernel: Dynamic Preempt: voluntary Sep 16 04:54:37.187656 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 16 04:54:37.187666 kernel: rcu: RCU event tracing is enabled. Sep 16 04:54:37.187674 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Sep 16 04:54:37.187683 kernel: Trampoline variant of Tasks RCU enabled. Sep 16 04:54:37.187694 kernel: Rude variant of Tasks RCU enabled. Sep 16 04:54:37.187703 kernel: Tracing variant of Tasks RCU enabled. Sep 16 04:54:37.187711 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 16 04:54:37.187720 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Sep 16 04:54:37.187728 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Sep 16 04:54:37.187739 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Sep 16 04:54:37.187748 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Sep 16 04:54:37.187757 kernel: Using NULL legacy PIC Sep 16 04:54:37.187765 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Sep 16 04:54:37.187774 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 16 04:54:37.187783 kernel: Console: colour dummy device 80x25 Sep 16 04:54:37.187791 kernel: printk: legacy console [tty1] enabled Sep 16 04:54:37.187800 kernel: printk: legacy console [ttyS0] enabled Sep 16 04:54:37.187808 kernel: printk: legacy bootconsole [earlyser0] disabled Sep 16 04:54:37.187836 kernel: ACPI: Core revision 20240827 Sep 16 04:54:37.187845 kernel: Failed to register legacy timer interrupt Sep 16 04:54:37.187853 kernel: APIC: Switch to symmetric I/O mode setup Sep 16 04:54:37.187862 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Sep 16 04:54:37.187870 kernel: Hyper-V: enabling crash_kexec_post_notifiers Sep 16 04:54:37.187879 kernel: Hyper-V: Using IPI hypercalls Sep 16 04:54:37.187887 kernel: APIC: send_IPI() replaced with hv_send_ipi() Sep 16 04:54:37.187895 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Sep 16 04:54:37.187904 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Sep 16 04:54:37.187915 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Sep 16 04:54:37.187924 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Sep 16 04:54:37.187932 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Sep 16 04:54:37.187941 kernel: Hyper-V: Using enlightened APIC (xapic mode) Sep 16 04:54:37.187949 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Sep 16 04:54:37.187958 kernel: APIC: read() replaced with hv_apic_read() Sep 16 04:54:37.187967 kernel: APIC: write() replaced with hv_apic_write() Sep 16 04:54:37.187975 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Sep 16 04:54:37.187984 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Sep 16 04:54:37.187995 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593994) Sep 16 04:54:37.188004 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Sep 16 04:54:37.188012 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Sep 16 04:54:37.188021 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Sep 16 04:54:37.188029 kernel: Spectre V2 : Mitigation: Retpolines Sep 16 04:54:37.188037 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Sep 16 04:54:37.188046 kernel: Speculative Store Bypass: Vulnerable Sep 16 04:54:37.188054 kernel: MDS: Mitigation: Clear CPU buffers Sep 16 04:54:37.188062 kernel: TAA: Mitigation: Clear CPU buffers Sep 16 04:54:37.188071 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Sep 16 04:54:37.188081 kernel: active return thunk: its_return_thunk Sep 16 04:54:37.188090 kernel: ITS: Mitigation: Aligned branch/return thunks Sep 16 04:54:37.188098 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Sep 16 04:54:37.188107 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Sep 16 04:54:37.188115 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Sep 16 04:54:37.188123 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Sep 16 04:54:37.188132 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Sep 16 04:54:37.188140 kernel: Freeing SMP alternatives memory: 32K Sep 16 04:54:37.188149 kernel: pid_max: default: 32768 minimum: 301 Sep 16 04:54:37.188157 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Sep 16 04:54:37.188166 kernel: landlock: Up and running. Sep 16 04:54:37.188176 kernel: SELinux: Initializing. Sep 16 04:54:37.188185 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Sep 16 04:54:37.188193 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Sep 16 04:54:37.188202 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Sep 16 04:54:37.188210 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Sep 16 04:54:37.188219 kernel: signal: max sigframe size: 1776 Sep 16 04:54:37.188228 kernel: rcu: Hierarchical SRCU implementation. Sep 16 04:54:37.188237 kernel: rcu: Max phase no-delay instances is 400. Sep 16 04:54:37.188245 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Sep 16 04:54:37.188254 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Sep 16 04:54:37.188265 kernel: smp: Bringing up secondary CPUs ... Sep 16 04:54:37.188274 kernel: smpboot: x86: Booting SMP configuration: Sep 16 04:54:37.188282 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Sep 16 04:54:37.188290 kernel: smp: Brought up 1 node, 6 CPUs Sep 16 04:54:37.188299 kernel: smpboot: Total of 6 processors activated (31127.92 BogoMIPS) Sep 16 04:54:37.188308 kernel: Memory: 115339100K/117439364K available (14336K kernel code, 2432K rwdata, 9992K rodata, 54096K init, 2868K bss, 2089996K reserved, 0K cma-reserved) Sep 16 04:54:37.188317 kernel: devtmpfs: initialized Sep 16 04:54:37.188325 kernel: x86/mm: Memory block size: 1024MB Sep 16 04:54:37.188334 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Sep 16 04:54:37.188345 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 16 04:54:37.188353 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Sep 16 04:54:37.188362 kernel: pinctrl core: initialized pinctrl subsystem Sep 16 04:54:37.188371 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 16 04:54:37.188379 kernel: audit: initializing netlink subsys (disabled) Sep 16 04:54:37.188388 kernel: audit: type=2000 audit(1757998473.028:1): state=initialized audit_enabled=0 res=1 Sep 16 04:54:37.188396 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 16 04:54:37.188405 kernel: thermal_sys: Registered thermal governor 'user_space' Sep 16 04:54:37.188413 kernel: cpuidle: using governor menu Sep 16 04:54:37.188424 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 16 04:54:37.188433 kernel: dca service started, version 1.12.1 Sep 16 04:54:37.188441 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Sep 16 04:54:37.188450 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Sep 16 04:54:37.188458 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 16 04:54:37.188467 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Sep 16 04:54:37.188475 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 16 04:54:37.188484 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Sep 16 04:54:37.188493 kernel: ACPI: Added _OSI(Module Device) Sep 16 04:54:37.188503 kernel: ACPI: Added _OSI(Processor Device) Sep 16 04:54:37.188512 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 16 04:54:37.188520 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 16 04:54:37.188529 kernel: ACPI: Interpreter enabled Sep 16 04:54:37.188537 kernel: ACPI: PM: (supports S0 S5) Sep 16 04:54:37.188546 kernel: ACPI: Using IOAPIC for interrupt routing Sep 16 04:54:37.188554 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Sep 16 04:54:37.188563 kernel: PCI: Ignoring E820 reservations for host bridge windows Sep 16 04:54:37.188572 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Sep 16 04:54:37.188583 kernel: iommu: Default domain type: Translated Sep 16 04:54:37.188591 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Sep 16 04:54:37.188600 kernel: efivars: Registered efivars operations Sep 16 04:54:37.188608 kernel: PCI: Using ACPI for IRQ routing Sep 16 04:54:37.188617 kernel: PCI: System does not support PCI Sep 16 04:54:37.188626 kernel: vgaarb: loaded Sep 16 04:54:37.188634 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Sep 16 04:54:37.188643 kernel: VFS: Disk quotas dquot_6.6.0 Sep 16 04:54:37.188651 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 16 04:54:37.188662 kernel: pnp: PnP ACPI init Sep 16 04:54:37.188671 kernel: pnp: PnP ACPI: found 3 devices Sep 16 04:54:37.188680 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Sep 16 04:54:37.188688 kernel: NET: Registered PF_INET protocol family Sep 16 04:54:37.188697 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 16 04:54:37.188706 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Sep 16 04:54:37.188714 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 16 04:54:37.188723 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 16 04:54:37.188731 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Sep 16 04:54:37.188742 kernel: TCP: Hash tables configured (established 524288 bind 65536) Sep 16 04:54:37.188751 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Sep 16 04:54:37.188760 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Sep 16 04:54:37.188768 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 16 04:54:37.188777 kernel: NET: Registered PF_XDP protocol family Sep 16 04:54:37.188785 kernel: PCI: CLS 0 bytes, default 64 Sep 16 04:54:37.188794 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Sep 16 04:54:37.188802 kernel: software IO TLB: mapped [mem 0x000000003ae61000-0x000000003ee61000] (64MB) Sep 16 04:54:37.188820 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Sep 16 04:54:37.188832 kernel: Initialise system trusted keyrings Sep 16 04:54:37.188840 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Sep 16 04:54:37.188849 kernel: Key type asymmetric registered Sep 16 04:54:37.188857 kernel: Asymmetric key parser 'x509' registered Sep 16 04:54:37.188866 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Sep 16 04:54:37.188874 kernel: io scheduler mq-deadline registered Sep 16 04:54:37.188883 kernel: io scheduler kyber registered Sep 16 04:54:37.188891 kernel: io scheduler bfq registered Sep 16 04:54:37.188900 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Sep 16 04:54:37.188911 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 16 04:54:37.188919 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Sep 16 04:54:37.188928 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Sep 16 04:54:37.188936 kernel: i8042: PNP: No PS/2 controller found. Sep 16 04:54:37.189092 kernel: rtc_cmos 00:02: registered as rtc0 Sep 16 04:54:37.189197 kernel: rtc_cmos 00:02: setting system clock to 2025-09-16T04:54:36 UTC (1757998476) Sep 16 04:54:37.189292 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Sep 16 04:54:37.189309 kernel: intel_pstate: CPU model not supported Sep 16 04:54:37.189318 kernel: efifb: probing for efifb Sep 16 04:54:37.189326 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Sep 16 04:54:37.189335 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Sep 16 04:54:37.189343 kernel: efifb: scrolling: redraw Sep 16 04:54:37.189356 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Sep 16 04:54:37.189364 kernel: Console: switching to colour frame buffer device 128x48 Sep 16 04:54:37.189373 kernel: fb0: EFI VGA frame buffer device Sep 16 04:54:37.189382 kernel: pstore: Using crash dump compression: deflate Sep 16 04:54:37.189393 kernel: pstore: Registered efi_pstore as persistent store backend Sep 16 04:54:37.189402 kernel: NET: Registered PF_INET6 protocol family Sep 16 04:54:37.189410 kernel: Segment Routing with IPv6 Sep 16 04:54:37.189419 kernel: In-situ OAM (IOAM) with IPv6 Sep 16 04:54:37.189427 kernel: NET: Registered PF_PACKET protocol family Sep 16 04:54:37.189436 kernel: Key type dns_resolver registered Sep 16 04:54:37.189444 kernel: IPI shorthand broadcast: enabled Sep 16 04:54:37.189453 kernel: sched_clock: Marking stable (3647004300, 107503300)->(4082410100, -327902500) Sep 16 04:54:37.189461 kernel: registered taskstats version 1 Sep 16 04:54:37.189470 kernel: Loading compiled-in X.509 certificates Sep 16 04:54:37.189481 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.47-flatcar: d1d5b0d56b9b23dabf19e645632ff93bf659b3bf' Sep 16 04:54:37.189489 kernel: Demotion targets for Node 0: null Sep 16 04:54:37.189498 kernel: Key type .fscrypt registered Sep 16 04:54:37.189506 kernel: Key type fscrypt-provisioning registered Sep 16 04:54:37.189515 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 16 04:54:37.189523 kernel: ima: Allocated hash algorithm: sha1 Sep 16 04:54:37.189532 kernel: ima: No architecture policies found Sep 16 04:54:37.189541 kernel: clk: Disabling unused clocks Sep 16 04:54:37.189552 kernel: Warning: unable to open an initial console. Sep 16 04:54:37.189561 kernel: Freeing unused kernel image (initmem) memory: 54096K Sep 16 04:54:37.189569 kernel: Write protecting the kernel read-only data: 24576k Sep 16 04:54:37.189578 kernel: Freeing unused kernel image (rodata/data gap) memory: 248K Sep 16 04:54:37.189586 kernel: Run /init as init process Sep 16 04:54:37.189595 kernel: with arguments: Sep 16 04:54:37.189603 kernel: /init Sep 16 04:54:37.189611 kernel: with environment: Sep 16 04:54:37.189620 kernel: HOME=/ Sep 16 04:54:37.189631 kernel: TERM=linux Sep 16 04:54:37.189639 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 16 04:54:37.189649 systemd[1]: Successfully made /usr/ read-only. Sep 16 04:54:37.189661 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Sep 16 04:54:37.189670 systemd[1]: Detected virtualization microsoft. Sep 16 04:54:37.189679 systemd[1]: Detected architecture x86-64. Sep 16 04:54:37.189688 systemd[1]: Running in initrd. Sep 16 04:54:37.189697 systemd[1]: No hostname configured, using default hostname. Sep 16 04:54:37.189709 systemd[1]: Hostname set to . Sep 16 04:54:37.189718 systemd[1]: Initializing machine ID from random generator. Sep 16 04:54:37.189727 systemd[1]: Queued start job for default target initrd.target. Sep 16 04:54:37.189736 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 16 04:54:37.189745 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 16 04:54:37.189755 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 16 04:54:37.189764 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 16 04:54:37.189773 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 16 04:54:37.189820 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 16 04:54:37.189837 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Sep 16 04:54:37.189847 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 16 04:54:37.189857 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 16 04:54:37.189866 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 16 04:54:37.189875 systemd[1]: Reached target paths.target - Path Units. Sep 16 04:54:37.189889 systemd[1]: Reached target slices.target - Slice Units. Sep 16 04:54:37.189898 systemd[1]: Reached target swap.target - Swaps. Sep 16 04:54:37.189908 systemd[1]: Reached target timers.target - Timer Units. Sep 16 04:54:37.189917 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 16 04:54:37.189926 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 16 04:54:37.189936 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 16 04:54:37.189945 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Sep 16 04:54:37.189954 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 16 04:54:37.189963 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 16 04:54:37.189975 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 16 04:54:37.189984 systemd[1]: Reached target sockets.target - Socket Units. Sep 16 04:54:37.189993 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 16 04:54:37.190011 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 16 04:54:37.190021 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 16 04:54:37.190030 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). Sep 16 04:54:37.190039 systemd[1]: Starting systemd-fsck-usr.service... Sep 16 04:54:37.190049 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 16 04:54:37.190061 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 16 04:54:37.190097 systemd-journald[231]: Collecting audit messages is disabled. Sep 16 04:54:37.190121 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 16 04:54:37.190131 systemd-journald[231]: Journal started Sep 16 04:54:37.190155 systemd-journald[231]: Runtime Journal (/run/log/journal/ff655bffb785430980f8634e7b68a5a3) is 8M, max 2.2G, 2.1G free. Sep 16 04:54:37.193749 systemd-modules-load[233]: Inserted module 'overlay' Sep 16 04:54:37.199206 systemd[1]: Started systemd-journald.service - Journal Service. Sep 16 04:54:37.199562 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 16 04:54:37.200007 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 16 04:54:37.201428 systemd[1]: Finished systemd-fsck-usr.service. Sep 16 04:54:37.203783 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 16 04:54:37.204867 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 16 04:54:37.251978 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 16 04:54:37.253941 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 16 04:54:37.255424 systemd-tmpfiles[243]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Sep 16 04:54:37.262319 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 16 04:54:37.285123 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 16 04:54:37.295069 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 16 04:54:37.296850 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 16 04:54:37.305175 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 16 04:54:37.325361 dracut-cmdline[260]: 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=0b876f86a632750e9937176808a48c2452d5168964273bcfc3c72f2a26140c06 Sep 16 04:54:37.355047 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Sep 16 04:54:37.357526 systemd-modules-load[233]: Inserted module 'br_netfilter' Sep 16 04:54:37.359031 kernel: Bridge firewalling registered Sep 16 04:54:37.358506 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 16 04:54:37.365480 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 16 04:54:37.375249 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 16 04:54:37.401122 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 16 04:54:37.404042 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 16 04:54:37.453299 systemd-resolved[327]: Positive Trust Anchors: Sep 16 04:54:37.453313 systemd-resolved[327]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 16 04:54:37.453343 systemd-resolved[327]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Sep 16 04:54:37.455990 systemd-resolved[327]: Defaulting to hostname 'linux'. Sep 16 04:54:37.457018 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 16 04:54:37.461789 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 16 04:54:37.515829 kernel: SCSI subsystem initialized Sep 16 04:54:37.525835 kernel: Loading iSCSI transport class v2.0-870. Sep 16 04:54:37.537833 kernel: iscsi: registered transport (tcp) Sep 16 04:54:37.560853 kernel: iscsi: registered transport (qla4xxx) Sep 16 04:54:37.560882 kernel: QLogic iSCSI HBA Driver Sep 16 04:54:37.579632 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 16 04:54:37.614265 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 16 04:54:37.616695 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 16 04:54:37.667105 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 16 04:54:37.675021 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 16 04:54:37.731837 kernel: raid6: avx2x4 gen() 30662 MB/s Sep 16 04:54:37.750832 kernel: raid6: avx2x2 gen() 27338 MB/s Sep 16 04:54:37.771908 kernel: raid6: avx2x1 gen() 27761 MB/s Sep 16 04:54:37.771939 kernel: raid6: using algorithm avx2x4 gen() 30662 MB/s Sep 16 04:54:37.794928 kernel: raid6: .... xor() 10185 MB/s, rmw enabled Sep 16 04:54:37.794959 kernel: raid6: using avx2x2 recovery algorithm Sep 16 04:54:37.817835 kernel: xor: automatically using best checksumming function avx Sep 16 04:54:37.980840 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 16 04:54:37.987539 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 16 04:54:37.990349 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 16 04:54:38.025367 systemd-udevd[483]: Using default interface naming scheme 'v255'. Sep 16 04:54:38.031623 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 16 04:54:38.033800 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 16 04:54:38.075125 dracut-pre-trigger[491]: rd.md=0: removing MD RAID activation Sep 16 04:54:38.099656 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 16 04:54:38.102383 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 16 04:54:38.176153 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 16 04:54:38.187932 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 16 04:54:38.211848 kernel: cryptd: max_cpu_qlen set to 1000 Sep 16 04:54:38.225847 kernel: AES CTR mode by8 optimization enabled Sep 16 04:54:38.228832 kernel: hv_vmbus: Vmbus version:4.0 Sep 16 04:54:38.246250 kernel: hv_vmbus: registering driver hyperv_keyboard Sep 16 04:54:38.246286 kernel: pps_core: LinuxPPS API ver. 1 registered Sep 16 04:54:38.246301 kernel: input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/VMBUS:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input0 Sep 16 04:54:38.246313 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Sep 16 04:54:38.251709 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 16 04:54:38.251853 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 16 04:54:38.278546 kernel: hid: raw HID events driver (C) Jiri Kosina Sep 16 04:54:38.269896 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 16 04:54:38.286414 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 16 04:54:38.965116 kernel: hv_vmbus: registering driver hid_hyperv Sep 16 04:54:38.965142 kernel: hv_vmbus: registering driver hv_storvsc Sep 16 04:54:38.965155 kernel: PTP clock support registered Sep 16 04:54:38.965194 kernel: hv_vmbus: registering driver hv_netvsc Sep 16 04:54:38.965214 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Sep 16 04:54:38.965227 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Sep 16 04:54:38.965401 kernel: scsi host0: storvsc_host_t Sep 16 04:54:38.965554 kernel: scsi host1: storvsc_host_t Sep 16 04:54:38.965694 kernel: hv_vmbus: registering driver hv_pci Sep 16 04:54:38.965708 kernel: scsi 1:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Sep 16 04:54:38.965735 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Sep 16 04:54:38.965882 kernel: hv_utils: Registering HyperV Utility Driver Sep 16 04:54:38.965901 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Sep 16 04:54:38.966023 kernel: hv_vmbus: registering driver hv_utils Sep 16 04:54:38.966038 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Sep 16 04:54:38.966168 kernel: hv_utils: Heartbeat IC version 3.0 Sep 16 04:54:38.966182 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Sep 16 04:54:38.966298 kernel: hv_utils: TimeSync IC version 4.0 Sep 16 04:54:38.966311 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Sep 16 04:54:38.966476 kernel: hv_utils: Shutdown IC version 3.2 Sep 16 04:54:38.966494 kernel: scsi 1:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Sep 16 04:54:38.966521 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint Sep 16 04:54:38.966546 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] Sep 16 04:54:38.966564 kernel: scsi 1:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Sep 16 04:54:38.966583 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] Sep 16 04:54:38.966600 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] Sep 16 04:54:38.966618 kernel: pci 0001:00:00.0: Enabling HDA controller Sep 16 04:54:38.945497 systemd-resolved[327]: Clock change detected. Flushing caches. Sep 16 04:54:38.974180 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 16 04:54:38.984236 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 16 04:54:38.984344 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 16 04:54:39.054956 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Sep 16 04:54:39.055191 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned Sep 16 04:54:39.055386 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned Sep 16 04:54:39.055536 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned Sep 16 04:54:39.055678 kernel: sd 1:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Sep 16 04:54:39.055841 kernel: sd 1:0:0:0: [sda] 4096-byte physical blocks Sep 16 04:54:39.055976 kernel: sd 1:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Sep 16 04:54:39.056110 kernel: sd 1:0:0:1: [sdb] 4096-byte physical blocks Sep 16 04:54:39.056242 kernel: sd 1:0:0:1: [sdb] Write Protect is off Sep 16 04:54:39.056370 kernel: sd 1:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Sep 16 04:54:39.056498 kernel: sd 1:0:0:0: [sda] Write Protect is off Sep 16 04:54:39.056624 kernel: sd 1:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Sep 16 04:54:39.056750 kernel: sd 1:0:0:0: [sda] Mode Sense: 0f 00 10 00 Sep 16 04:54:39.056900 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#320 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 16 04:54:39.057030 kernel: sd 1:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Sep 16 04:54:39.057161 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#327 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 16 04:54:39.057277 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#386 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 16 04:54:39.057390 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#393 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 16 04:54:39.013770 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 16 04:54:39.074803 kernel: sdb: sdb1 Sep 16 04:54:39.077806 kernel: sd 1:0:0:1: [sdb] Attached SCSI disk Sep 16 04:54:39.082825 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 16 04:54:39.082856 kernel: sd 1:0:0:0: [sda] Attached SCSI disk Sep 16 04:54:39.092671 kernel: sr 1:0:0:2: [sr0] scsi-1 drive Sep 16 04:54:39.092873 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Sep 16 04:54:39.095811 kernel: sr 1:0:0:2: Attached scsi CD-ROM sr0 Sep 16 04:54:39.098348 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 16 04:54:39.725667 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Sep 16 04:54:39.787065 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Sep 16 04:54:39.829181 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Sep 16 04:54:39.844160 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Sep 16 04:54:39.845681 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Sep 16 04:54:39.865073 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 16 04:54:39.908144 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#627 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 16 04:54:39.926810 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 16 04:54:39.935811 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#592 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 16 04:54:39.951808 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 16 04:54:40.959218 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#620 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 16 04:54:40.978820 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 16 04:54:40.982842 disk-uuid[722]: The operation has completed successfully. Sep 16 04:54:42.847862 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 16 04:54:42.847975 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 16 04:54:42.850357 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 16 04:54:42.874645 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 16 04:54:42.881952 sh[884]: Success Sep 16 04:54:42.879446 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 16 04:54:42.888770 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 16 04:54:42.891827 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 16 04:54:42.901838 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 16 04:54:42.931109 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 16 04:54:42.931141 kernel: device-mapper: uevent: version 1.0.3 Sep 16 04:54:42.936938 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Sep 16 04:54:42.948815 kernel: device-mapper: verity: sha256 using shash "sha256-avx2" Sep 16 04:54:42.953706 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 16 04:54:43.282446 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 16 04:54:43.291992 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 16 04:54:43.318544 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 16 04:54:43.337810 kernel: BTRFS: device fsid f1b91845-3914-4d21-a370-6d760ee45b2e devid 1 transid 36 /dev/mapper/usr (254:0) scanned by mount (912) Sep 16 04:54:43.346511 kernel: BTRFS info (device dm-0): first mount of filesystem f1b91845-3914-4d21-a370-6d760ee45b2e Sep 16 04:54:43.346531 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Sep 16 04:54:43.841327 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 16 04:54:43.841389 kernel: BTRFS info (device dm-0): enabling free space tree Sep 16 04:54:43.888213 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 16 04:54:43.890205 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Sep 16 04:54:43.896155 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 16 04:54:43.897065 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 16 04:54:43.934342 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 16 04:54:43.966820 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (943) Sep 16 04:54:43.975810 kernel: BTRFS info (device sda6): first mount of filesystem 8b047ef5-4757-404a-b211-2a505a425364 Sep 16 04:54:43.975843 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Sep 16 04:54:44.026346 kernel: BTRFS info (device sda6): turning on async discard Sep 16 04:54:44.026395 kernel: BTRFS info (device sda6): enabling free space tree Sep 16 04:54:44.029107 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 16 04:54:44.047537 kernel: BTRFS info (device sda6): last unmount of filesystem 8b047ef5-4757-404a-b211-2a505a425364 Sep 16 04:54:44.032109 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 16 04:54:44.055953 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 16 04:54:44.063719 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 16 04:54:44.098492 systemd-networkd[1079]: lo: Link UP Sep 16 04:54:44.098503 systemd-networkd[1079]: lo: Gained carrier Sep 16 04:54:44.099540 systemd-networkd[1079]: Enumeration completed Sep 16 04:54:44.099627 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 16 04:54:44.099919 systemd-networkd[1079]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 16 04:54:44.099923 systemd-networkd[1079]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 16 04:54:44.100663 systemd-networkd[1079]: eth0: Link UP Sep 16 04:54:44.100843 systemd-networkd[1079]: eth0: Gained carrier Sep 16 04:54:44.100853 systemd-networkd[1079]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 16 04:54:44.105421 systemd[1]: Reached target network.target - Network. Sep 16 04:54:44.157828 systemd-networkd[1079]: eth0: DHCPv4 address 10.200.8.11/24, gateway 10.200.8.1 acquired from 168.63.129.16 Sep 16 04:54:45.865685 ignition[1083]: Ignition 2.22.0 Sep 16 04:54:45.865700 ignition[1083]: Stage: fetch-offline Sep 16 04:54:45.865838 ignition[1083]: no configs at "/usr/lib/ignition/base.d" Sep 16 04:54:45.870521 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 16 04:54:45.865848 ignition[1083]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 16 04:54:45.873662 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Sep 16 04:54:45.865948 ignition[1083]: parsed url from cmdline: "" Sep 16 04:54:45.865952 ignition[1083]: no config URL provided Sep 16 04:54:45.865958 ignition[1083]: reading system config file "/usr/lib/ignition/user.ign" Sep 16 04:54:45.865966 ignition[1083]: no config at "/usr/lib/ignition/user.ign" Sep 16 04:54:45.865972 ignition[1083]: failed to fetch config: resource requires networking Sep 16 04:54:45.866361 ignition[1083]: Ignition finished successfully Sep 16 04:54:45.907737 ignition[1096]: Ignition 2.22.0 Sep 16 04:54:45.907747 ignition[1096]: Stage: fetch Sep 16 04:54:45.907971 ignition[1096]: no configs at "/usr/lib/ignition/base.d" Sep 16 04:54:45.907987 ignition[1096]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 16 04:54:45.908083 ignition[1096]: parsed url from cmdline: "" Sep 16 04:54:45.908087 ignition[1096]: no config URL provided Sep 16 04:54:45.908093 ignition[1096]: reading system config file "/usr/lib/ignition/user.ign" Sep 16 04:54:45.908101 ignition[1096]: no config at "/usr/lib/ignition/user.ign" Sep 16 04:54:45.908119 ignition[1096]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Sep 16 04:54:46.021347 ignition[1096]: GET result: OK Sep 16 04:54:46.021400 ignition[1096]: config has been read from IMDS userdata Sep 16 04:54:46.024589 unknown[1096]: fetched base config from "system" Sep 16 04:54:46.021412 ignition[1096]: parsing config with SHA512: 05c4fb21c85ba102757cf1d31de5bd9b2c43ce63fa36846653f72eb1324876b205c1d31e1009c86f05c432ddb79d079608f6d385d97b5c98a7aede3733be9972 Sep 16 04:54:46.024596 unknown[1096]: fetched base config from "system" Sep 16 04:54:46.024759 ignition[1096]: fetch: fetch complete Sep 16 04:54:46.024602 unknown[1096]: fetched user config from "azure" Sep 16 04:54:46.024763 ignition[1096]: fetch: fetch passed Sep 16 04:54:46.027107 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Sep 16 04:54:46.024825 ignition[1096]: Ignition finished successfully Sep 16 04:54:46.029542 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 16 04:54:46.085849 ignition[1105]: Ignition 2.22.0 Sep 16 04:54:46.085864 ignition[1105]: Stage: kargs Sep 16 04:54:46.086130 ignition[1105]: no configs at "/usr/lib/ignition/base.d" Sep 16 04:54:46.086141 ignition[1105]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 16 04:54:46.086845 ignition[1105]: kargs: kargs passed Sep 16 04:54:46.095453 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 16 04:54:46.086893 ignition[1105]: Ignition finished successfully Sep 16 04:54:46.098888 systemd-networkd[1079]: eth0: Gained IPv6LL Sep 16 04:54:46.103662 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 16 04:54:46.142700 ignition[1115]: Ignition 2.22.0 Sep 16 04:54:46.142713 ignition[1115]: Stage: disks Sep 16 04:54:46.142985 ignition[1115]: no configs at "/usr/lib/ignition/base.d" Sep 16 04:54:46.142997 ignition[1115]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 16 04:54:46.148433 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 16 04:54:46.143652 ignition[1115]: disks: disks passed Sep 16 04:54:46.154469 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 16 04:54:46.143695 ignition[1115]: Ignition finished successfully Sep 16 04:54:46.162776 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 16 04:54:46.171069 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 16 04:54:46.177305 systemd[1]: Reached target sysinit.target - System Initialization. Sep 16 04:54:46.185406 systemd[1]: Reached target basic.target - Basic System. Sep 16 04:54:46.192861 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 16 04:54:46.284285 systemd-fsck[1126]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks Sep 16 04:54:46.289487 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 16 04:54:46.300329 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 16 04:54:48.184809 kernel: EXT4-fs (sda9): mounted filesystem fb1cb44f-955b-4cd0-8849-33ce3640d547 r/w with ordered data mode. Quota mode: none. Sep 16 04:54:48.185396 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 16 04:54:48.188277 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 16 04:54:48.229094 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 16 04:54:48.254276 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 16 04:54:48.255909 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Sep 16 04:54:48.287598 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1140) Sep 16 04:54:48.287624 kernel: BTRFS info (device sda6): first mount of filesystem 8b047ef5-4757-404a-b211-2a505a425364 Sep 16 04:54:48.287637 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Sep 16 04:54:48.287649 kernel: BTRFS info (device sda6): turning on async discard Sep 16 04:54:48.264447 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Sep 16 04:54:48.304463 kernel: BTRFS info (device sda6): enabling free space tree Sep 16 04:54:48.264477 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 16 04:54:48.294184 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 16 04:54:48.305997 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 16 04:54:48.319748 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 16 04:54:49.320925 coreos-metadata[1142]: Sep 16 04:54:49.320 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Sep 16 04:54:49.326896 coreos-metadata[1142]: Sep 16 04:54:49.323 INFO Fetch successful Sep 16 04:54:49.326896 coreos-metadata[1142]: Sep 16 04:54:49.323 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Sep 16 04:54:49.338581 coreos-metadata[1142]: Sep 16 04:54:49.330 INFO Fetch successful Sep 16 04:54:49.338581 coreos-metadata[1142]: Sep 16 04:54:49.330 INFO wrote hostname ci-4459.0.0-n-ad459acd34 to /sysroot/etc/hostname Sep 16 04:54:49.348988 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Sep 16 04:54:49.885889 initrd-setup-root[1170]: cut: /sysroot/etc/passwd: No such file or directory Sep 16 04:54:49.964044 initrd-setup-root[1177]: cut: /sysroot/etc/group: No such file or directory Sep 16 04:54:49.984301 initrd-setup-root[1184]: cut: /sysroot/etc/shadow: No such file or directory Sep 16 04:54:50.005152 initrd-setup-root[1191]: cut: /sysroot/etc/gshadow: No such file or directory Sep 16 04:54:51.285531 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 16 04:54:51.288503 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 16 04:54:51.295963 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 16 04:54:51.325741 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 16 04:54:51.334368 kernel: BTRFS info (device sda6): last unmount of filesystem 8b047ef5-4757-404a-b211-2a505a425364 Sep 16 04:54:51.352933 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 16 04:54:51.371966 ignition[1260]: INFO : Ignition 2.22.0 Sep 16 04:54:51.371966 ignition[1260]: INFO : Stage: mount Sep 16 04:54:51.377638 ignition[1260]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 16 04:54:51.377638 ignition[1260]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 16 04:54:51.377638 ignition[1260]: INFO : mount: mount passed Sep 16 04:54:51.377638 ignition[1260]: INFO : Ignition finished successfully Sep 16 04:54:51.387667 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 16 04:54:51.390678 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 16 04:54:51.421329 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 16 04:54:51.458851 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1277) Sep 16 04:54:51.467681 kernel: BTRFS info (device sda6): first mount of filesystem 8b047ef5-4757-404a-b211-2a505a425364 Sep 16 04:54:51.467711 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Sep 16 04:54:51.477015 kernel: BTRFS info (device sda6): turning on async discard Sep 16 04:54:51.477057 kernel: BTRFS info (device sda6): enabling free space tree Sep 16 04:54:51.479161 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 16 04:54:51.519032 ignition[1294]: INFO : Ignition 2.22.0 Sep 16 04:54:51.519032 ignition[1294]: INFO : Stage: files Sep 16 04:54:51.524466 ignition[1294]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 16 04:54:51.524466 ignition[1294]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 16 04:54:51.524466 ignition[1294]: DEBUG : files: compiled without relabeling support, skipping Sep 16 04:54:51.537516 ignition[1294]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 16 04:54:51.537516 ignition[1294]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 16 04:54:51.611787 ignition[1294]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 16 04:54:51.616972 ignition[1294]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 16 04:54:51.616972 ignition[1294]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 16 04:54:51.612544 unknown[1294]: wrote ssh authorized keys file for user: core Sep 16 04:54:51.715413 ignition[1294]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 16 04:54:51.722335 ignition[1294]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 16 04:54:51.722335 ignition[1294]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 16 04:54:51.722335 ignition[1294]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 16 04:54:51.722335 ignition[1294]: INFO : files: files passed Sep 16 04:54:51.722335 ignition[1294]: INFO : Ignition finished successfully Sep 16 04:54:51.750396 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 16 04:54:51.759099 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 16 04:54:51.761757 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 16 04:54:51.785438 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 16 04:54:51.785538 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 16 04:54:51.850622 initrd-setup-root-after-ignition[1326]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 16 04:54:51.850622 initrd-setup-root-after-ignition[1326]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 16 04:54:51.862628 initrd-setup-root-after-ignition[1330]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 16 04:54:51.863511 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 16 04:54:51.873591 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 16 04:54:51.875942 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 16 04:54:51.933729 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 16 04:54:51.933847 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 16 04:54:51.942557 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 16 04:54:51.951363 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 16 04:54:51.959028 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 16 04:54:51.960025 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 16 04:54:51.999183 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 16 04:54:52.001765 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 16 04:54:52.031620 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 16 04:54:52.033163 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 16 04:54:52.041872 systemd[1]: Stopped target timers.target - Timer Units. Sep 16 04:54:52.050750 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 16 04:54:52.050881 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 16 04:54:52.070300 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 16 04:54:52.072483 systemd[1]: Stopped target basic.target - Basic System. Sep 16 04:54:52.083825 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 16 04:54:52.090852 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 16 04:54:52.099424 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 16 04:54:52.108036 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Sep 16 04:54:52.116035 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 16 04:54:52.130322 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 16 04:54:52.135344 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 16 04:54:52.137225 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 16 04:54:52.148205 systemd[1]: Stopped target swap.target - Swaps. Sep 16 04:54:52.155348 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 16 04:54:52.155494 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 16 04:54:52.167531 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 16 04:54:52.179261 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 16 04:54:52.180642 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 16 04:54:52.187824 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 16 04:54:52.196028 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 16 04:54:52.196161 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 16 04:54:52.208778 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 16 04:54:52.208928 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 16 04:54:52.217962 systemd[1]: ignition-files.service: Deactivated successfully. Sep 16 04:54:52.218085 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 16 04:54:52.225882 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Sep 16 04:54:52.226001 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Sep 16 04:54:52.236141 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 16 04:54:52.241880 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 16 04:54:52.242007 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 16 04:54:52.272456 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 16 04:54:52.280675 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 16 04:54:52.280849 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 16 04:54:52.291418 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 16 04:54:52.313301 ignition[1350]: INFO : Ignition 2.22.0 Sep 16 04:54:52.313301 ignition[1350]: INFO : Stage: umount Sep 16 04:54:52.313301 ignition[1350]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 16 04:54:52.313301 ignition[1350]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 16 04:54:52.313301 ignition[1350]: INFO : umount: umount passed Sep 16 04:54:52.313301 ignition[1350]: INFO : Ignition finished successfully Sep 16 04:54:52.291536 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 16 04:54:52.304422 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 16 04:54:52.313905 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 16 04:54:52.322278 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 16 04:54:52.322369 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 16 04:54:52.330648 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 16 04:54:52.330736 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 16 04:54:52.332157 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 16 04:54:52.332209 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 16 04:54:52.341919 systemd[1]: ignition-fetch.service: Deactivated successfully. Sep 16 04:54:52.341974 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Sep 16 04:54:52.349560 systemd[1]: Stopped target network.target - Network. Sep 16 04:54:52.357003 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 16 04:54:52.357059 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 16 04:54:52.363768 systemd[1]: Stopped target paths.target - Path Units. Sep 16 04:54:52.376306 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 16 04:54:52.377982 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 16 04:54:52.382137 systemd[1]: Stopped target slices.target - Slice Units. Sep 16 04:54:52.389974 systemd[1]: Stopped target sockets.target - Socket Units. Sep 16 04:54:52.396641 systemd[1]: iscsid.socket: Deactivated successfully. Sep 16 04:54:52.396695 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 16 04:54:52.403332 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 16 04:54:52.403376 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 16 04:54:52.410541 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 16 04:54:52.410601 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 16 04:54:52.417579 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 16 04:54:52.417624 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 16 04:54:52.424516 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 16 04:54:52.431204 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 16 04:54:52.439407 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 16 04:54:52.440039 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 16 04:54:52.440129 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 16 04:54:52.446281 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 16 04:54:52.446361 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 16 04:54:52.461361 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 16 04:54:52.461483 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 16 04:54:52.475000 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Sep 16 04:54:52.475216 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 16 04:54:52.475311 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 16 04:54:52.486260 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Sep 16 04:54:52.486838 systemd[1]: Stopped target network-pre.target - Preparation for Network. Sep 16 04:54:52.489724 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 16 04:54:52.489810 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 16 04:54:52.497930 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 16 04:54:52.504747 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 16 04:54:52.504809 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 16 04:54:52.511773 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 16 04:54:52.511834 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 16 04:54:52.526401 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 16 04:54:52.526447 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 16 04:54:52.534352 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 16 04:54:52.534405 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 16 04:54:52.543881 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 16 04:54:52.552818 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Sep 16 04:54:52.552888 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Sep 16 04:54:52.579173 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 16 04:54:52.579284 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 16 04:54:52.588465 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 16 04:54:52.588658 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 16 04:54:52.596144 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 16 04:54:52.596192 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 16 04:54:52.604254 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 16 04:54:52.604304 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 16 04:54:52.613123 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 16 04:54:52.613182 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 16 04:54:52.624712 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 16 04:54:52.624761 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 16 04:54:52.636339 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 16 04:54:52.636387 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 16 04:54:52.650913 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 16 04:54:52.658736 systemd[1]: systemd-network-generator.service: Deactivated successfully. Sep 16 04:54:52.658799 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Sep 16 04:54:52.674950 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 16 04:54:52.674999 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 16 04:54:52.683828 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 16 04:54:52.683876 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 16 04:54:52.697159 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Sep 16 04:54:52.697213 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Sep 16 04:54:52.697260 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 16 04:54:52.697611 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 16 04:54:52.697700 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 16 04:54:52.701889 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 16 04:54:52.714919 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 16 04:54:52.763533 systemd[1]: Switching root. Sep 16 04:54:52.906395 systemd-journald[231]: Journal stopped Sep 16 04:55:01.017608 systemd-journald[231]: Received SIGTERM from PID 1 (systemd). Sep 16 04:55:01.017650 kernel: SELinux: policy capability network_peer_controls=1 Sep 16 04:55:01.017664 kernel: SELinux: policy capability open_perms=1 Sep 16 04:55:01.017674 kernel: SELinux: policy capability extended_socket_class=1 Sep 16 04:55:01.017683 kernel: SELinux: policy capability always_check_network=0 Sep 16 04:55:01.017692 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 16 04:55:01.017703 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 16 04:55:01.017716 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 16 04:55:01.017725 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 16 04:55:01.017735 kernel: SELinux: policy capability userspace_initial_context=0 Sep 16 04:55:01.017745 kernel: audit: type=1403 audit(1757998494.269:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 16 04:55:01.017755 systemd[1]: Successfully loaded SELinux policy in 276.950ms. Sep 16 04:55:01.017767 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 8.266ms. Sep 16 04:55:01.017778 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Sep 16 04:55:01.017805 systemd[1]: Detected virtualization microsoft. Sep 16 04:55:01.017817 systemd[1]: Detected architecture x86-64. Sep 16 04:55:01.017827 systemd[1]: Detected first boot. Sep 16 04:55:01.017838 systemd[1]: Hostname set to . Sep 16 04:55:01.017849 systemd[1]: Initializing machine ID from random generator. Sep 16 04:55:01.017863 zram_generator::config[1398]: No configuration found. Sep 16 04:55:01.017875 kernel: Guest personality initialized and is inactive Sep 16 04:55:01.017889 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Sep 16 04:55:01.017899 kernel: Initialized host personality Sep 16 04:55:01.017909 kernel: NET: Registered PF_VSOCK protocol family Sep 16 04:55:01.017919 systemd[1]: Populated /etc with preset unit settings. Sep 16 04:55:01.017930 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Sep 16 04:55:01.017944 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 16 04:55:01.017955 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 16 04:55:01.017966 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 16 04:55:01.017976 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 16 04:55:01.017988 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 16 04:55:01.017999 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 16 04:55:01.018009 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 16 04:55:01.018023 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 16 04:55:01.018034 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 16 04:55:01.018045 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 16 04:55:01.018056 systemd[1]: Created slice user.slice - User and Session Slice. Sep 16 04:55:01.018067 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 16 04:55:01.018078 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 16 04:55:01.018089 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 16 04:55:01.018103 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 16 04:55:01.018115 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 16 04:55:01.018132 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 16 04:55:01.018144 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Sep 16 04:55:01.018155 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 16 04:55:01.018166 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 16 04:55:01.018178 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 16 04:55:01.018189 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 16 04:55:01.018200 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 16 04:55:01.018214 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 16 04:55:01.018226 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 16 04:55:01.018236 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 16 04:55:01.018248 systemd[1]: Reached target slices.target - Slice Units. Sep 16 04:55:01.018259 systemd[1]: Reached target swap.target - Swaps. Sep 16 04:55:01.018270 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 16 04:55:01.018281 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 16 04:55:01.018295 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Sep 16 04:55:01.018307 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 16 04:55:01.018318 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 16 04:55:01.018329 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 16 04:55:01.018340 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 16 04:55:01.018352 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 16 04:55:01.018366 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 16 04:55:01.018380 systemd[1]: Mounting media.mount - External Media Directory... Sep 16 04:55:01.018391 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 16 04:55:01.018402 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 16 04:55:01.018413 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 16 04:55:01.018425 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 16 04:55:01.018437 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Sep 16 04:55:01.018448 systemd[1]: Reached target machines.target - Containers. Sep 16 04:55:01.018462 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 16 04:55:01.018474 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 16 04:55:01.018485 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 16 04:55:01.018497 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 16 04:55:01.018508 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 16 04:55:01.018519 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 16 04:55:01.018530 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 16 04:55:01.018541 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 16 04:55:01.018555 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 16 04:55:01.018567 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 16 04:55:01.018578 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 16 04:55:01.018589 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 16 04:55:01.018601 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 16 04:55:01.018612 systemd[1]: Stopped systemd-fsck-usr.service. Sep 16 04:55:01.018627 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 16 04:55:01.018638 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 16 04:55:01.018649 kernel: fuse: init (API version 7.41) Sep 16 04:55:01.018663 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 16 04:55:01.018674 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 16 04:55:01.018686 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 16 04:55:01.018697 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Sep 16 04:55:01.018709 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 16 04:55:01.018744 systemd-journald[1481]: Collecting audit messages is disabled. Sep 16 04:55:01.018773 systemd[1]: verity-setup.service: Deactivated successfully. Sep 16 04:55:01.018786 systemd-journald[1481]: Journal started Sep 16 04:55:01.018818 systemd-journald[1481]: Runtime Journal (/run/log/journal/aa5082a6795d433aa8cba2c05d5adbda) is 8M, max 2.2G, 2.1G free. Sep 16 04:55:00.160407 systemd[1]: Queued start job for default target multi-user.target. Sep 16 04:55:00.185207 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Sep 16 04:55:00.185763 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 16 04:55:01.023223 systemd[1]: Stopped verity-setup.service. Sep 16 04:55:01.023264 kernel: loop: module loaded Sep 16 04:55:01.036948 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 16 04:55:01.044006 systemd[1]: Started systemd-journald.service - Journal Service. Sep 16 04:55:01.048180 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 16 04:55:01.052429 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 16 04:55:01.056761 systemd[1]: Mounted media.mount - External Media Directory. Sep 16 04:55:01.060654 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 16 04:55:01.065088 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 16 04:55:01.069532 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 16 04:55:01.073559 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 16 04:55:01.078452 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 16 04:55:01.083621 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 16 04:55:01.083817 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 16 04:55:01.088838 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 16 04:55:01.089029 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 16 04:55:01.093651 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 16 04:55:01.093852 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 16 04:55:01.098927 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 16 04:55:01.099108 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 16 04:55:01.103598 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 16 04:55:01.103784 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 16 04:55:01.108337 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 16 04:55:01.113040 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 16 04:55:01.118320 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 16 04:55:01.123576 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Sep 16 04:55:01.137257 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 16 04:55:01.142680 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 16 04:55:01.158025 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 16 04:55:01.162229 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 16 04:55:01.162264 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 16 04:55:01.167107 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Sep 16 04:55:01.172878 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 16 04:55:01.176746 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 16 04:55:01.192896 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 16 04:55:01.198011 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 16 04:55:01.202458 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 16 04:55:01.203529 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 16 04:55:01.207855 kernel: ACPI: bus type drm_connector registered Sep 16 04:55:01.211360 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 16 04:55:01.212258 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 16 04:55:01.217063 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 16 04:55:01.222389 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 16 04:55:01.228190 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 16 04:55:01.228388 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 16 04:55:01.232844 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 16 04:55:01.238472 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 16 04:55:01.243150 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 16 04:55:01.248079 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 16 04:55:01.255422 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 16 04:55:01.261052 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Sep 16 04:55:01.281073 systemd-journald[1481]: Time spent on flushing to /var/log/journal/aa5082a6795d433aa8cba2c05d5adbda is 13.949ms for 958 entries. Sep 16 04:55:01.281073 systemd-journald[1481]: System Journal (/var/log/journal/aa5082a6795d433aa8cba2c05d5adbda) is 8M, max 2.6G, 2.6G free. Sep 16 04:55:01.329810 systemd-journald[1481]: Received client request to flush runtime journal. Sep 16 04:55:01.332193 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 16 04:55:01.341823 kernel: loop0: detected capacity change from 0 to 128016 Sep 16 04:55:01.355764 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 16 04:55:01.356459 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Sep 16 04:55:01.386505 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 16 04:55:01.836042 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 16 04:55:01.841772 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 16 04:55:01.993973 systemd-tmpfiles[1556]: ACLs are not supported, ignoring. Sep 16 04:55:01.993993 systemd-tmpfiles[1556]: ACLs are not supported, ignoring. Sep 16 04:55:01.997866 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 16 04:55:02.122818 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 16 04:55:02.236819 kernel: loop1: detected capacity change from 0 to 110984 Sep 16 04:55:03.084820 kernel: loop2: detected capacity change from 0 to 27936 Sep 16 04:55:03.314738 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 16 04:55:03.321560 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 16 04:55:03.367359 systemd-udevd[1563]: Using default interface naming scheme 'v255'. Sep 16 04:55:03.656828 kernel: loop3: detected capacity change from 0 to 128016 Sep 16 04:55:03.672820 kernel: loop4: detected capacity change from 0 to 110984 Sep 16 04:55:03.689817 kernel: loop5: detected capacity change from 0 to 27936 Sep 16 04:55:03.699800 (sd-merge)[1565]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Sep 16 04:55:03.700275 (sd-merge)[1565]: Merged extensions into '/usr'. Sep 16 04:55:03.703716 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 16 04:55:03.710176 systemd[1]: Starting ensure-sysext.service... Sep 16 04:55:03.713845 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 16 04:55:03.775066 systemd[1]: Reload requested from client PID 1567 ('systemctl') (unit ensure-sysext.service)... Sep 16 04:55:03.775083 systemd[1]: Reloading... Sep 16 04:55:03.803226 systemd-tmpfiles[1568]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Sep 16 04:55:03.803267 systemd-tmpfiles[1568]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Sep 16 04:55:03.803593 systemd-tmpfiles[1568]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 16 04:55:03.803910 systemd-tmpfiles[1568]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 16 04:55:03.804777 systemd-tmpfiles[1568]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 16 04:55:03.805078 systemd-tmpfiles[1568]: ACLs are not supported, ignoring. Sep 16 04:55:03.805145 systemd-tmpfiles[1568]: ACLs are not supported, ignoring. Sep 16 04:55:03.820839 zram_generator::config[1596]: No configuration found. Sep 16 04:55:03.846078 systemd-tmpfiles[1568]: Detected autofs mount point /boot during canonicalization of boot. Sep 16 04:55:03.846095 systemd-tmpfiles[1568]: Skipping /boot Sep 16 04:55:03.854371 systemd-tmpfiles[1568]: Detected autofs mount point /boot during canonicalization of boot. Sep 16 04:55:03.854386 systemd-tmpfiles[1568]: Skipping /boot Sep 16 04:55:04.055118 systemd[1]: Reloading finished in 279 ms. Sep 16 04:55:04.126465 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 16 04:55:04.140488 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 16 04:55:04.167416 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 16 04:55:04.172927 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 16 04:55:04.195813 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 16 04:55:04.200882 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 16 04:55:04.207883 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 16 04:55:04.208150 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 16 04:55:04.209273 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 16 04:55:04.215021 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 16 04:55:04.220539 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 16 04:55:04.224550 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 16 04:55:04.224678 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 16 04:55:04.224787 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 16 04:55:04.226099 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 16 04:55:04.226282 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 16 04:55:04.231332 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 16 04:55:04.231504 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 16 04:55:04.236724 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 16 04:55:04.236916 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 16 04:55:04.247811 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 16 04:55:04.256630 systemd[1]: Expecting device dev-ptp_hyperv.device - /dev/ptp_hyperv... Sep 16 04:55:04.260677 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 16 04:55:04.261000 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 16 04:55:04.262278 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 16 04:55:04.279131 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 16 04:55:04.284290 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 16 04:55:04.290294 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 16 04:55:04.295333 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 16 04:55:04.295473 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 16 04:55:04.295655 systemd[1]: Reached target time-set.target - System Time Set. Sep 16 04:55:04.300867 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 16 04:55:04.302241 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 16 04:55:04.309499 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 16 04:55:04.309689 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 16 04:55:04.314731 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 16 04:55:04.314941 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 16 04:55:04.320317 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 16 04:55:04.320498 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 16 04:55:04.326186 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 16 04:55:04.326386 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 16 04:55:04.334227 systemd[1]: Finished ensure-sysext.service. Sep 16 04:55:04.349357 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 16 04:55:04.353533 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 16 04:55:04.353590 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 16 04:55:04.355904 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 16 04:55:04.374181 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Sep 16 04:55:04.408830 kernel: hv_vmbus: registering driver hyperv_fb Sep 16 04:55:04.417889 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Sep 16 04:55:04.417962 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Sep 16 04:55:04.414349 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 16 04:55:04.422060 kernel: Console: switching to colour dummy device 80x25 Sep 16 04:55:04.428568 kernel: Console: switching to colour frame buffer device 128x48 Sep 16 04:55:04.433835 kernel: hv_vmbus: registering driver hv_balloon Sep 16 04:55:04.440842 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Sep 16 04:55:04.445826 kernel: mousedev: PS/2 mouse device common for all mice Sep 16 04:55:04.463713 systemd[1]: Condition check resulted in dev-ptp_hyperv.device - /dev/ptp_hyperv being skipped. Sep 16 04:55:04.475099 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 16 04:55:04.486360 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 16 04:55:04.486574 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 16 04:55:04.492485 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 16 04:55:04.494809 kernel: EDAC MC: Ver: 3.0.0 Sep 16 04:55:04.514623 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 16 04:55:04.574479 systemd-resolved[1659]: Positive Trust Anchors: Sep 16 04:55:04.574495 systemd-resolved[1659]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 16 04:55:04.574528 systemd-resolved[1659]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Sep 16 04:55:04.578055 augenrules[1785]: No rules Sep 16 04:55:04.579228 systemd[1]: audit-rules.service: Deactivated successfully. Sep 16 04:55:04.579482 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 16 04:55:04.603553 systemd-resolved[1659]: Using system hostname 'ci-4459.0.0-n-ad459acd34'. Sep 16 04:55:04.632210 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 16 04:55:04.644732 systemd-networkd[1724]: lo: Link UP Sep 16 04:55:04.644743 systemd-networkd[1724]: lo: Gained carrier Sep 16 04:55:04.646070 systemd-networkd[1724]: Enumeration completed Sep 16 04:55:04.646440 systemd-networkd[1724]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 16 04:55:04.646451 systemd-networkd[1724]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 16 04:55:04.647006 systemd-networkd[1724]: eth0: Link UP Sep 16 04:55:04.647014 systemd-networkd[1724]: eth0: Gained carrier Sep 16 04:55:04.647028 systemd-networkd[1724]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 16 04:55:04.647823 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Sep 16 04:55:04.652571 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 16 04:55:04.656956 systemd[1]: Reached target network.target - Network. Sep 16 04:55:04.660602 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 16 04:55:04.666496 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 16 04:55:04.688943 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Sep 16 04:55:04.693510 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 16 04:55:04.718850 systemd-networkd[1724]: eth0: DHCPv4 address 10.200.8.11/24, gateway 10.200.8.1 acquired from 168.63.129.16 Sep 16 04:55:04.756764 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 16 04:55:04.768896 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Sep 16 04:55:05.816294 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 16 04:55:06.193934 systemd-networkd[1724]: eth0: Gained IPv6LL Sep 16 04:55:06.196168 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 16 04:55:06.201309 systemd[1]: Reached target network-online.target - Network is Online. Sep 16 04:55:06.596991 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 16 04:55:06.602201 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Sep 16 04:55:11.192139 ldconfig[1533]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 16 04:55:11.205966 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 16 04:55:11.212127 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 16 04:55:11.261476 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 16 04:55:11.266135 systemd[1]: Reached target sysinit.target - System Initialization. Sep 16 04:55:11.270480 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 16 04:55:11.275406 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Sep 16 04:55:11.280255 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Sep 16 04:55:11.285023 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 16 04:55:11.289333 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 16 04:55:11.294267 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 16 04:55:11.299319 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 16 04:55:11.299356 systemd[1]: Reached target paths.target - Path Units. Sep 16 04:55:11.302881 systemd[1]: Reached target timers.target - Timer Units. Sep 16 04:55:11.338218 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 16 04:55:11.343684 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 16 04:55:11.349605 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Sep 16 04:55:11.354617 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Sep 16 04:55:11.359694 systemd[1]: Reached target ssh-access.target - SSH Access Available. Sep 16 04:55:11.366318 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 16 04:55:11.370525 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Sep 16 04:55:11.375775 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 16 04:55:11.380707 systemd[1]: Reached target sockets.target - Socket Units. Sep 16 04:55:11.384482 systemd[1]: Reached target basic.target - Basic System. Sep 16 04:55:11.388154 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 16 04:55:11.388182 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 16 04:55:11.403613 systemd[1]: Starting chronyd.service - NTP client/server... Sep 16 04:55:11.408161 systemd[1]: Starting containerd.service - containerd container runtime... Sep 16 04:55:11.428592 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Sep 16 04:55:11.433575 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 16 04:55:11.438004 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 16 04:55:11.443384 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 16 04:55:11.448246 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 16 04:55:11.452104 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Sep 16 04:55:11.453069 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Sep 16 04:55:11.456990 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). Sep 16 04:55:11.457972 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Sep 16 04:55:11.462218 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Sep 16 04:55:11.463423 jq[1877]: false Sep 16 04:55:11.463717 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 16 04:55:11.468359 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 16 04:55:11.473133 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 16 04:55:11.479498 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 16 04:55:11.486139 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 16 04:55:11.490953 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 16 04:55:11.491518 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 16 04:55:11.493915 systemd[1]: Starting update-engine.service - Update Engine... Sep 16 04:55:11.497319 chronyd[1869]: chronyd version 4.7 starting (+CMDMON +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +NTS +SECHASH +IPV6 -DEBUG) Sep 16 04:55:11.498319 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 16 04:55:11.503673 KVP[1880]: KVP starting; pid is:1880 Sep 16 04:55:11.504971 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 16 04:55:11.506360 jq[1893]: true Sep 16 04:55:11.510062 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 16 04:55:11.510289 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 16 04:55:11.510590 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 16 04:55:11.510822 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 16 04:55:11.515097 google_oslogin_nss_cache[1879]: oslogin_cache_refresh[1879]: Refreshing passwd entry cache Sep 16 04:55:11.515107 oslogin_cache_refresh[1879]: Refreshing passwd entry cache Sep 16 04:55:11.522089 systemd[1]: motdgen.service: Deactivated successfully. Sep 16 04:55:11.522365 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 16 04:55:11.522595 (ntainerd)[1901]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 16 04:55:11.522768 KVP[1880]: KVP LIC Version: 3.1 Sep 16 04:55:11.523511 google_oslogin_nss_cache[1879]: oslogin_cache_refresh[1879]: Failure getting users, quitting Sep 16 04:55:11.523594 google_oslogin_nss_cache[1879]: oslogin_cache_refresh[1879]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Sep 16 04:55:11.523542 oslogin_cache_refresh[1879]: Failure getting users, quitting Sep 16 04:55:11.523594 oslogin_cache_refresh[1879]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Sep 16 04:55:11.523720 google_oslogin_nss_cache[1879]: oslogin_cache_refresh[1879]: Refreshing group entry cache Sep 16 04:55:11.523648 oslogin_cache_refresh[1879]: Refreshing group entry cache Sep 16 04:55:11.524858 kernel: hv_utils: KVP IC version 4.0 Sep 16 04:55:11.525953 jq[1897]: true Sep 16 04:55:11.528950 google_oslogin_nss_cache[1879]: oslogin_cache_refresh[1879]: Failure getting groups, quitting Sep 16 04:55:11.528950 google_oslogin_nss_cache[1879]: oslogin_cache_refresh[1879]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Sep 16 04:55:11.528942 oslogin_cache_refresh[1879]: Failure getting groups, quitting Sep 16 04:55:11.528953 oslogin_cache_refresh[1879]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Sep 16 04:55:11.551187 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Sep 16 04:55:11.551436 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Sep 16 04:55:11.559118 extend-filesystems[1878]: Found /dev/sda6 Sep 16 04:55:11.564001 update_engine[1892]: I20250916 04:55:11.563930 1892 main.cc:92] Flatcar Update Engine starting Sep 16 04:55:11.587372 extend-filesystems[1878]: Found /dev/sda9 Sep 16 04:55:11.590552 extend-filesystems[1878]: Checking size of /dev/sda9 Sep 16 04:55:11.608715 extend-filesystems[1878]: Old size kept for /dev/sda9 Sep 16 04:55:11.609754 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 16 04:55:11.610013 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 16 04:55:11.612741 systemd-logind[1889]: New seat seat0. Sep 16 04:55:11.618162 systemd-logind[1889]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Sep 16 04:55:11.618341 systemd[1]: Started systemd-logind.service - User Login Management. Sep 16 04:55:11.632772 chronyd[1869]: Timezone right/UTC failed leap second check, ignoring Sep 16 04:55:11.632971 chronyd[1869]: Loaded seccomp filter (level 2) Sep 16 04:55:11.633150 systemd[1]: Started chronyd.service - NTP client/server. Sep 16 04:55:11.694566 bash[1924]: Updated "/home/core/.ssh/authorized_keys" Sep 16 04:55:11.696146 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 16 04:55:11.702393 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Sep 16 04:55:11.710671 sshd_keygen[1925]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 16 04:55:11.731612 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 16 04:55:11.737141 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 16 04:55:11.743219 setup-nvidia[1882]: Downloading Flatcar Container Linux Developer Container for version: 4459.0.0+nightly-20250915-2100 Sep 16 04:55:11.753042 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Sep 16 04:55:11.759843 systemd[1]: issuegen.service: Deactivated successfully. Sep 16 04:55:11.760085 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 16 04:55:11.765164 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 16 04:55:11.778501 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Sep 16 04:55:11.790410 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 16 04:55:11.796292 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 16 04:55:11.801612 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Sep 16 04:55:11.806285 systemd[1]: Reached target getty.target - Login Prompts. Sep 16 04:55:11.872929 dbus-daemon[1872]: [system] SELinux support is enabled Sep 16 04:55:11.873142 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 16 04:55:11.876435 update_engine[1892]: I20250916 04:55:11.876375 1892 update_check_scheduler.cc:74] Next update check in 3m15s Sep 16 04:55:11.879572 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Sep 16 04:55:11.879605 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 16 04:55:11.880437 dbus-daemon[1872]: [system] Successfully activated service 'org.freedesktop.systemd1' Sep 16 04:55:11.884933 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Sep 16 04:55:11.884958 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 16 04:55:11.889946 systemd[1]: Started update-engine.service - Update Engine. Sep 16 04:55:11.895700 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 16 04:55:11.972578 coreos-metadata[1871]: Sep 16 04:55:11.972 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Sep 16 04:55:11.975067 coreos-metadata[1871]: Sep 16 04:55:11.975 INFO Fetch successful Sep 16 04:55:11.975218 coreos-metadata[1871]: Sep 16 04:55:11.975 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Sep 16 04:55:11.979383 coreos-metadata[1871]: Sep 16 04:55:11.979 INFO Fetch successful Sep 16 04:55:11.979661 coreos-metadata[1871]: Sep 16 04:55:11.979 INFO Fetching http://168.63.129.16/machine/d5162dc7-d3cf-41db-8022-e8993ca578a1/94ada6dd%2D92f4%2D4739%2D9b78%2Dd4e5a0df2b09.%5Fci%2D4459.0.0%2Dn%2Dad459acd34?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Sep 16 04:55:12.010969 coreos-metadata[1871]: Sep 16 04:55:12.010 INFO Fetch successful Sep 16 04:55:12.011115 coreos-metadata[1871]: Sep 16 04:55:12.011 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Sep 16 04:55:12.020545 coreos-metadata[1871]: Sep 16 04:55:12.020 INFO Fetch successful Sep 16 04:55:12.047359 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Sep 16 04:55:12.052492 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 16 04:55:12.152354 locksmithd[2031]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 16 04:55:12.539443 containerd[1901]: time="2025-09-16T04:55:12Z" 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 Sep 16 04:55:12.540067 containerd[1901]: time="2025-09-16T04:55:12.540029500Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Sep 16 04:55:12.548456 containerd[1901]: time="2025-09-16T04:55:12.548419700Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="8.4µs" Sep 16 04:55:12.548456 containerd[1901]: time="2025-09-16T04:55:12.548449700Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Sep 16 04:55:12.548523 containerd[1901]: time="2025-09-16T04:55:12.548468200Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Sep 16 04:55:12.548635 containerd[1901]: time="2025-09-16T04:55:12.548613000Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Sep 16 04:55:12.548665 containerd[1901]: time="2025-09-16T04:55:12.548635100Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Sep 16 04:55:12.548665 containerd[1901]: time="2025-09-16T04:55:12.548660300Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 16 04:55:12.548738 containerd[1901]: time="2025-09-16T04:55:12.548718200Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 16 04:55:12.548738 containerd[1901]: time="2025-09-16T04:55:12.548735600Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 16 04:55:12.549006 containerd[1901]: time="2025-09-16T04:55:12.548979700Z" 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 Sep 16 04:55:12.549006 containerd[1901]: time="2025-09-16T04:55:12.549003200Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 16 04:55:12.549063 containerd[1901]: time="2025-09-16T04:55:12.549015400Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 16 04:55:12.549063 containerd[1901]: time="2025-09-16T04:55:12.549023900Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Sep 16 04:55:12.549138 containerd[1901]: time="2025-09-16T04:55:12.549116500Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Sep 16 04:55:12.549360 containerd[1901]: time="2025-09-16T04:55:12.549339700Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 16 04:55:12.549391 containerd[1901]: time="2025-09-16T04:55:12.549375900Z" 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 Sep 16 04:55:12.549391 containerd[1901]: time="2025-09-16T04:55:12.549387200Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Sep 16 04:55:12.549439 containerd[1901]: time="2025-09-16T04:55:12.549423800Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Sep 16 04:55:12.549655 containerd[1901]: time="2025-09-16T04:55:12.549633500Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Sep 16 04:55:12.549734 containerd[1901]: time="2025-09-16T04:55:12.549702100Z" level=info msg="metadata content store policy set" policy=shared Sep 16 04:55:12.568807 containerd[1901]: time="2025-09-16T04:55:12.568755600Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Sep 16 04:55:12.568868 containerd[1901]: time="2025-09-16T04:55:12.568816100Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Sep 16 04:55:12.568868 containerd[1901]: time="2025-09-16T04:55:12.568833900Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Sep 16 04:55:12.568868 containerd[1901]: time="2025-09-16T04:55:12.568844800Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Sep 16 04:55:12.568868 containerd[1901]: time="2025-09-16T04:55:12.568855500Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Sep 16 04:55:12.568868 containerd[1901]: time="2025-09-16T04:55:12.568865200Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Sep 16 04:55:12.568983 containerd[1901]: time="2025-09-16T04:55:12.568876700Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Sep 16 04:55:12.568983 containerd[1901]: time="2025-09-16T04:55:12.568888400Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Sep 16 04:55:12.568983 containerd[1901]: time="2025-09-16T04:55:12.568899300Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Sep 16 04:55:12.568983 containerd[1901]: time="2025-09-16T04:55:12.568908700Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Sep 16 04:55:12.568983 containerd[1901]: time="2025-09-16T04:55:12.568917100Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Sep 16 04:55:12.568983 containerd[1901]: time="2025-09-16T04:55:12.568927900Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Sep 16 04:55:12.569108 containerd[1901]: time="2025-09-16T04:55:12.569030100Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Sep 16 04:55:12.569108 containerd[1901]: time="2025-09-16T04:55:12.569049300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Sep 16 04:55:12.569108 containerd[1901]: time="2025-09-16T04:55:12.569067200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Sep 16 04:55:12.569108 containerd[1901]: time="2025-09-16T04:55:12.569082100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Sep 16 04:55:12.569108 containerd[1901]: time="2025-09-16T04:55:12.569093000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Sep 16 04:55:12.569218 containerd[1901]: time="2025-09-16T04:55:12.569114500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Sep 16 04:55:12.569218 containerd[1901]: time="2025-09-16T04:55:12.569139600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Sep 16 04:55:12.569218 containerd[1901]: time="2025-09-16T04:55:12.569152600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Sep 16 04:55:12.569218 containerd[1901]: time="2025-09-16T04:55:12.569163800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Sep 16 04:55:12.569218 containerd[1901]: time="2025-09-16T04:55:12.569173000Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Sep 16 04:55:12.569218 containerd[1901]: time="2025-09-16T04:55:12.569182300Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Sep 16 04:55:12.569347 containerd[1901]: time="2025-09-16T04:55:12.569234700Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Sep 16 04:55:12.569347 containerd[1901]: time="2025-09-16T04:55:12.569248500Z" level=info msg="Start snapshots syncer" Sep 16 04:55:12.569347 containerd[1901]: time="2025-09-16T04:55:12.569276800Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Sep 16 04:55:12.569509 containerd[1901]: time="2025-09-16T04:55:12.569471400Z" 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\"}" Sep 16 04:55:12.569626 containerd[1901]: time="2025-09-16T04:55:12.569529100Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Sep 16 04:55:12.569626 containerd[1901]: time="2025-09-16T04:55:12.569589300Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Sep 16 04:55:12.569707 containerd[1901]: time="2025-09-16T04:55:12.569684700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Sep 16 04:55:12.569741 containerd[1901]: time="2025-09-16T04:55:12.569712900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Sep 16 04:55:12.569768 containerd[1901]: time="2025-09-16T04:55:12.569725200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Sep 16 04:55:12.569768 containerd[1901]: time="2025-09-16T04:55:12.569761300Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Sep 16 04:55:12.569830 containerd[1901]: time="2025-09-16T04:55:12.569819600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Sep 16 04:55:12.569865 containerd[1901]: time="2025-09-16T04:55:12.569838300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Sep 16 04:55:12.569865 containerd[1901]: time="2025-09-16T04:55:12.569849400Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Sep 16 04:55:12.569914 containerd[1901]: time="2025-09-16T04:55:12.569868700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Sep 16 04:55:12.569914 containerd[1901]: time="2025-09-16T04:55:12.569879600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Sep 16 04:55:12.569914 containerd[1901]: time="2025-09-16T04:55:12.569889300Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Sep 16 04:55:12.569987 containerd[1901]: time="2025-09-16T04:55:12.569926800Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 16 04:55:12.569987 containerd[1901]: time="2025-09-16T04:55:12.569941300Z" 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 Sep 16 04:55:12.569987 containerd[1901]: time="2025-09-16T04:55:12.569949200Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 16 04:55:12.569987 containerd[1901]: time="2025-09-16T04:55:12.569958800Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 16 04:55:12.569987 containerd[1901]: time="2025-09-16T04:55:12.569966200Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Sep 16 04:55:12.569987 containerd[1901]: time="2025-09-16T04:55:12.569977000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Sep 16 04:55:12.570109 containerd[1901]: time="2025-09-16T04:55:12.569989900Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Sep 16 04:55:12.570109 containerd[1901]: time="2025-09-16T04:55:12.570005200Z" level=info msg="runtime interface created" Sep 16 04:55:12.570109 containerd[1901]: time="2025-09-16T04:55:12.570010200Z" level=info msg="created NRI interface" Sep 16 04:55:12.570109 containerd[1901]: time="2025-09-16T04:55:12.570018100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Sep 16 04:55:12.570109 containerd[1901]: time="2025-09-16T04:55:12.570028300Z" level=info msg="Connect containerd service" Sep 16 04:55:12.570109 containerd[1901]: time="2025-09-16T04:55:12.570050100Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 16 04:55:12.570771 containerd[1901]: time="2025-09-16T04:55:12.570739400Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Sep 16 04:55:13.367452 containerd[1901]: time="2025-09-16T04:55:13.367403500Z" level=info msg="Start subscribing containerd event" Sep 16 04:55:13.367584 containerd[1901]: time="2025-09-16T04:55:13.367466700Z" level=info msg="Start recovering state" Sep 16 04:55:13.367584 containerd[1901]: time="2025-09-16T04:55:13.367564200Z" level=info msg="Start event monitor" Sep 16 04:55:13.367635 containerd[1901]: time="2025-09-16T04:55:13.367565500Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 16 04:55:13.367635 containerd[1901]: time="2025-09-16T04:55:13.367589800Z" level=info msg="Start cni network conf syncer for default" Sep 16 04:55:13.367678 containerd[1901]: time="2025-09-16T04:55:13.367641100Z" level=info msg="Start streaming server" Sep 16 04:55:13.367678 containerd[1901]: time="2025-09-16T04:55:13.367649600Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 16 04:55:13.367734 containerd[1901]: time="2025-09-16T04:55:13.367657300Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Sep 16 04:55:13.367734 containerd[1901]: time="2025-09-16T04:55:13.367702100Z" level=info msg="runtime interface starting up..." Sep 16 04:55:13.367779 containerd[1901]: time="2025-09-16T04:55:13.367732500Z" level=info msg="starting plugins..." Sep 16 04:55:13.367779 containerd[1901]: time="2025-09-16T04:55:13.367755800Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Sep 16 04:55:13.367939 containerd[1901]: time="2025-09-16T04:55:13.367920400Z" level=info msg="containerd successfully booted in 0.828835s" Sep 16 04:55:13.368025 systemd[1]: Started containerd.service - containerd container runtime. Sep 16 04:55:14.180583 waagent[2025]: 2025-09-16T04:55:14.180496Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Sep 16 04:55:14.184755 waagent[2025]: 2025-09-16T04:55:14.184666Z INFO Daemon Daemon OS: flatcar 4459.0.0 Sep 16 04:55:14.188072 waagent[2025]: 2025-09-16T04:55:14.188024Z INFO Daemon Daemon Python: 3.11.13 Sep 16 04:55:14.191426 waagent[2025]: 2025-09-16T04:55:14.191377Z INFO Daemon Daemon Run daemon Sep 16 04:55:14.194349 waagent[2025]: 2025-09-16T04:55:14.194305Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4459.0.0' Sep 16 04:55:14.200773 waagent[2025]: 2025-09-16T04:55:14.200717Z INFO Daemon Daemon Using waagent for provisioning Sep 16 04:55:14.204632 waagent[2025]: 2025-09-16T04:55:14.204587Z INFO Daemon Daemon Activate resource disk Sep 16 04:55:14.208068 waagent[2025]: 2025-09-16T04:55:14.208018Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Sep 16 04:55:14.218373 waagent[2025]: 2025-09-16T04:55:14.218322Z INFO Daemon Daemon Found device: sdb Sep 16 04:55:14.227179 waagent[2025]: 2025-09-16T04:55:14.227129Z INFO Daemon Daemon Examining partition table Sep 16 04:55:14.309908 waagent[2025]: 2025-09-16T04:55:14.309824Z INFO Daemon Daemon GPT not detected, determining filesystem Sep 16 04:55:14.363243 waagent[2025]: 2025-09-16T04:55:14.363182Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Sep 16 04:55:14.367674 waagent[2025]: 2025-09-16T04:55:14.367625Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Sep 16 04:55:14.707322 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#327 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 16 04:55:14.718812 kernel: sdb: sdb1 Sep 16 04:55:14.728809 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#589 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 16 04:55:14.731101 waagent[2025]: 2025-09-16T04:55:14.731032Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Sep 16 04:55:14.754816 kernel: sdb: sdb1 Sep 16 04:55:14.754868 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#391 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 16 04:55:14.777820 kernel: sdb: sdb1 Sep 16 04:55:14.777876 waagent[2025]: 2025-09-16T04:55:14.777802Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Sep 16 04:55:19.489053 login[2027]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Sep 16 04:55:19.492133 login[2028]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Sep 16 04:55:19.500668 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 16 04:55:19.501817 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 16 04:55:19.512068 systemd-logind[1889]: New session 1 of user core. Sep 16 04:55:19.595075 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 16 04:55:19.597540 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 16 04:55:19.681638 (systemd)[2142]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 16 04:55:19.684022 systemd-logind[1889]: New session c1 of user core. Sep 16 04:55:20.312296 waagent[2025]: 2025-09-16T04:55:20.312212Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Sep 16 04:55:20.429838 kernel: EXT4-fs (sdb1): mounted filesystem ae71ed64-ae69-42b0-9a9d-bfe54f0491a8 r/w with ordered data mode. Quota mode: none. Sep 16 04:55:20.430149 waagent[2025]: 2025-09-16T04:55:20.430075Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Sep 16 04:55:20.432319 waagent[2025]: 2025-09-16T04:55:20.432263Z INFO Daemon Daemon Clean protocol and wireserver endpoint Sep 16 04:55:20.432521 waagent[2025]: 2025-09-16T04:55:20.432481Z INFO Daemon Daemon Running default provisioning handler Sep 16 04:55:20.439371 waagent[2025]: 2025-09-16T04:55:20.439323Z INFO Daemon Daemon Unable to get cloud-init enabled status from systemctl: Command '['systemctl', 'is-enabled', 'cloud-init-local.service']' returned non-zero exit status 4. Sep 16 04:55:20.440012 waagent[2025]: 2025-09-16T04:55:20.439971Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Sep 16 04:55:20.441071 waagent[2025]: 2025-09-16T04:55:20.441030Z INFO Daemon Daemon cloud-init is enabled: False Sep 16 04:55:20.442490 waagent[2025]: 2025-09-16T04:55:20.442447Z INFO Daemon Daemon Copying ovf-env.xml Sep 16 04:55:20.489442 login[2027]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Sep 16 04:55:20.494329 systemd-logind[1889]: New session 2 of user core. Sep 16 04:55:20.564306 systemd[2142]: Queued start job for default target default.target. Sep 16 04:55:20.578653 systemd[2142]: Created slice app.slice - User Application Slice. Sep 16 04:55:20.578682 systemd[2142]: Reached target paths.target - Paths. Sep 16 04:55:20.578726 systemd[2142]: Reached target timers.target - Timers. Sep 16 04:55:20.579940 systemd[2142]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 16 04:55:20.590653 systemd[2142]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 16 04:55:20.590835 systemd[2142]: Reached target sockets.target - Sockets. Sep 16 04:55:20.590890 systemd[2142]: Reached target basic.target - Basic System. Sep 16 04:55:20.590938 systemd[2142]: Reached target default.target - Main User Target. Sep 16 04:55:20.590971 systemd[2142]: Startup finished in 900ms. Sep 16 04:55:20.590994 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 16 04:55:20.592354 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 16 04:55:20.593393 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 16 04:55:20.637041 waagent[2025]: 2025-09-16T04:55:20.636964Z INFO Daemon Daemon Successfully mounted dvd Sep 16 04:55:20.688032 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Sep 16 04:55:20.688370 waagent[2025]: 2025-09-16T04:55:20.688317Z INFO Daemon Daemon Detect protocol endpoint Sep 16 04:55:20.689884 waagent[2025]: 2025-09-16T04:55:20.689824Z INFO Daemon Daemon Clean protocol and wireserver endpoint Sep 16 04:55:20.691200 waagent[2025]: 2025-09-16T04:55:20.691156Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Sep 16 04:55:20.692571 waagent[2025]: 2025-09-16T04:55:20.692534Z INFO Daemon Daemon Test for route to 168.63.129.16 Sep 16 04:55:20.694172 waagent[2025]: 2025-09-16T04:55:20.694126Z INFO Daemon Daemon Route to 168.63.129.16 exists Sep 16 04:55:20.710720 waagent[2025]: 2025-09-16T04:55:20.694719Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Sep 16 04:55:20.713104 waagent[2025]: 2025-09-16T04:55:20.713067Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Sep 16 04:55:20.725872 waagent[2025]: 2025-09-16T04:55:20.714960Z INFO Daemon Daemon Wire protocol version:2012-11-30 Sep 16 04:55:20.725872 waagent[2025]: 2025-09-16T04:55:20.715531Z INFO Daemon Daemon Server preferred version:2015-04-05 Sep 16 04:55:20.822426 waagent[2025]: 2025-09-16T04:55:20.822286Z INFO Daemon Daemon Initializing goal state during protocol detection Sep 16 04:55:20.835814 waagent[2025]: 2025-09-16T04:55:20.823943Z INFO Daemon Daemon Forcing an update of the goal state. Sep 16 04:55:20.835814 waagent[2025]: 2025-09-16T04:55:20.828454Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Sep 16 04:55:20.874727 waagent[2025]: 2025-09-16T04:55:20.874673Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.175 Sep 16 04:55:20.899843 waagent[2025]: 2025-09-16T04:55:20.876913Z INFO Daemon Sep 16 04:55:20.899843 waagent[2025]: 2025-09-16T04:55:20.877624Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 1efb0aa7-b719-4277-acd8-bcc20e4cc330 eTag: 11928759338144578614 source: Fabric] Sep 16 04:55:20.899843 waagent[2025]: 2025-09-16T04:55:20.880143Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Sep 16 04:55:20.899843 waagent[2025]: 2025-09-16T04:55:20.880877Z INFO Daemon Sep 16 04:55:20.899843 waagent[2025]: 2025-09-16T04:55:20.881340Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Sep 16 04:55:20.903028 waagent[2025]: 2025-09-16T04:55:20.902781Z INFO Daemon Daemon Downloading artifacts profile blob Sep 16 04:55:21.028847 waagent[2025]: 2025-09-16T04:55:21.028615Z INFO Daemon Downloaded certificate {'thumbprint': 'D1378433B5DFF78774DED10BDBFBF32FC802F013', 'hasPrivateKey': True} Sep 16 04:55:21.038480 waagent[2025]: 2025-09-16T04:55:21.030654Z INFO Daemon Fetch goal state completed Sep 16 04:55:21.067691 waagent[2025]: 2025-09-16T04:55:21.067637Z INFO Daemon Daemon Starting provisioning Sep 16 04:55:21.080271 waagent[2025]: 2025-09-16T04:55:21.069335Z INFO Daemon Daemon Handle ovf-env.xml. Sep 16 04:55:21.080271 waagent[2025]: 2025-09-16T04:55:21.070808Z INFO Daemon Daemon Set hostname [ci-4459.0.0-n-ad459acd34] Sep 16 04:55:21.130692 waagent[2025]: 2025-09-16T04:55:21.130635Z INFO Daemon Daemon Publish hostname [ci-4459.0.0-n-ad459acd34] Sep 16 04:55:21.142497 waagent[2025]: 2025-09-16T04:55:21.132430Z INFO Daemon Daemon Examine /proc/net/route for primary interface Sep 16 04:55:21.142497 waagent[2025]: 2025-09-16T04:55:21.133784Z INFO Daemon Daemon Primary interface is [eth0] Sep 16 04:55:21.159925 systemd-networkd[1724]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 16 04:55:21.159935 systemd-networkd[1724]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 16 04:55:21.159967 systemd-networkd[1724]: eth0: DHCP lease lost Sep 16 04:55:21.161323 waagent[2025]: 2025-09-16T04:55:21.161264Z INFO Daemon Daemon Create user account if not exists Sep 16 04:55:21.163156 waagent[2025]: 2025-09-16T04:55:21.163113Z INFO Daemon Daemon User core already exists, skip useradd Sep 16 04:55:21.163826 waagent[2025]: 2025-09-16T04:55:21.163776Z INFO Daemon Daemon Configure sudoer Sep 16 04:55:21.176997 waagent[2025]: 2025-09-16T04:55:21.176952Z INFO Daemon Daemon Configure sshd Sep 16 04:55:21.182900 waagent[2025]: 2025-09-16T04:55:21.182856Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Sep 16 04:55:21.184456 waagent[2025]: 2025-09-16T04:55:21.184410Z INFO Daemon Daemon Deploy ssh public key. Sep 16 04:55:21.249832 systemd-networkd[1724]: eth0: DHCPv4 address 10.200.8.11/24, gateway 10.200.8.1 acquired from 168.63.129.16 Sep 16 04:55:22.322986 waagent[2025]: 2025-09-16T04:55:22.322886Z INFO Daemon Daemon Provisioning complete Sep 16 04:55:22.333094 waagent[2025]: 2025-09-16T04:55:22.333053Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Sep 16 04:55:22.334584 waagent[2025]: 2025-09-16T04:55:22.334532Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Sep 16 04:55:22.335983 waagent[2025]: 2025-09-16T04:55:22.335940Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Sep 16 04:55:22.496011 waagent[2200]: 2025-09-16T04:55:22.495923Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Sep 16 04:55:22.496352 waagent[2200]: 2025-09-16T04:55:22.496271Z INFO ExtHandler ExtHandler OS: flatcar 4459.0.0 Sep 16 04:55:22.496397 waagent[2200]: 2025-09-16T04:55:22.496355Z INFO ExtHandler ExtHandler Python: 3.11.13 Sep 16 04:55:22.496460 waagent[2200]: 2025-09-16T04:55:22.496426Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Sep 16 04:55:22.601517 waagent[2200]: 2025-09-16T04:55:22.601371Z INFO ExtHandler ExtHandler Distro: flatcar-4459.0.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.13; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Sep 16 04:55:22.601683 waagent[2200]: 2025-09-16T04:55:22.601643Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 16 04:55:22.601763 waagent[2200]: 2025-09-16T04:55:22.601730Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 16 04:55:22.608844 waagent[2200]: 2025-09-16T04:55:22.608769Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Sep 16 04:55:22.614296 waagent[2200]: 2025-09-16T04:55:22.614247Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.175 Sep 16 04:55:22.614752 waagent[2200]: 2025-09-16T04:55:22.614711Z INFO ExtHandler Sep 16 04:55:22.614847 waagent[2200]: 2025-09-16T04:55:22.614812Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 84cda784-df80-4ca9-afaf-fa5e00afeb11 eTag: 11928759338144578614 source: Fabric] Sep 16 04:55:22.615171 waagent[2200]: 2025-09-16T04:55:22.615133Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Sep 16 04:55:22.615776 waagent[2200]: 2025-09-16T04:55:22.615739Z INFO ExtHandler Sep 16 04:55:22.615867 waagent[2200]: 2025-09-16T04:55:22.615835Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Sep 16 04:55:22.619951 waagent[2200]: 2025-09-16T04:55:22.619910Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Sep 16 04:55:22.682544 waagent[2200]: 2025-09-16T04:55:22.682459Z INFO ExtHandler Downloaded certificate {'thumbprint': 'D1378433B5DFF78774DED10BDBFBF32FC802F013', 'hasPrivateKey': True} Sep 16 04:55:22.683183 waagent[2200]: 2025-09-16T04:55:22.683130Z INFO ExtHandler Fetch goal state completed Sep 16 04:55:22.696125 waagent[2200]: 2025-09-16T04:55:22.696067Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.4.2 1 Jul 2025 (Library: OpenSSL 3.4.2 1 Jul 2025) Sep 16 04:55:22.701349 waagent[2200]: 2025-09-16T04:55:22.701296Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2200 Sep 16 04:55:22.701481 waagent[2200]: 2025-09-16T04:55:22.701445Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Sep 16 04:55:22.701840 waagent[2200]: 2025-09-16T04:55:22.701784Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Sep 16 04:55:22.703511 waagent[2200]: 2025-09-16T04:55:22.703459Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4459.0.0', '', 'Flatcar Container Linux by Kinvolk'] Sep 16 04:55:22.703968 waagent[2200]: 2025-09-16T04:55:22.703921Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4459.0.0', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Sep 16 04:55:22.704114 waagent[2200]: 2025-09-16T04:55:22.704078Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Sep 16 04:55:22.704711 waagent[2200]: 2025-09-16T04:55:22.704669Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Sep 16 04:55:22.836300 waagent[2200]: 2025-09-16T04:55:22.836252Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Sep 16 04:55:22.836507 waagent[2200]: 2025-09-16T04:55:22.836469Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Sep 16 04:55:22.843133 waagent[2200]: 2025-09-16T04:55:22.843102Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Sep 16 04:55:22.849733 systemd[1]: Reload requested from client PID 2215 ('systemctl') (unit waagent.service)... Sep 16 04:55:22.849749 systemd[1]: Reloading... Sep 16 04:55:22.914825 zram_generator::config[2258]: No configuration found. Sep 16 04:55:23.190285 systemd[1]: Reloading finished in 340 ms. Sep 16 04:55:23.211264 waagent[2200]: 2025-09-16T04:55:23.211190Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Sep 16 04:55:23.211377 waagent[2200]: 2025-09-16T04:55:23.211341Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Sep 16 04:55:24.184369 waagent[2200]: 2025-09-16T04:55:24.184282Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Sep 16 04:55:24.184722 waagent[2200]: 2025-09-16T04:55:24.184625Z 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] Sep 16 04:55:24.185468 waagent[2200]: 2025-09-16T04:55:24.185416Z INFO ExtHandler ExtHandler Starting env monitor service. Sep 16 04:55:24.185860 waagent[2200]: 2025-09-16T04:55:24.185811Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 16 04:55:24.186037 waagent[2200]: 2025-09-16T04:55:24.185995Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Sep 16 04:55:24.186356 waagent[2200]: 2025-09-16T04:55:24.186318Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 16 04:55:24.186418 waagent[2200]: 2025-09-16T04:55:24.186376Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 16 04:55:24.186715 waagent[2200]: 2025-09-16T04:55:24.186662Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Sep 16 04:55:24.187030 waagent[2200]: 2025-09-16T04:55:24.186986Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Sep 16 04:55:24.187081 waagent[2200]: 2025-09-16T04:55:24.187035Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Sep 16 04:55:24.187146 waagent[2200]: 2025-09-16T04:55:24.187107Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 16 04:55:24.187564 waagent[2200]: 2025-09-16T04:55:24.187519Z INFO EnvHandler ExtHandler Configure routes Sep 16 04:55:24.188063 waagent[2200]: 2025-09-16T04:55:24.188002Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Sep 16 04:55:24.188138 waagent[2200]: 2025-09-16T04:55:24.188095Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Sep 16 04:55:24.188138 waagent[2200]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Sep 16 04:55:24.188138 waagent[2200]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Sep 16 04:55:24.188138 waagent[2200]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Sep 16 04:55:24.188138 waagent[2200]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Sep 16 04:55:24.188138 waagent[2200]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Sep 16 04:55:24.188138 waagent[2200]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Sep 16 04:55:24.188301 waagent[2200]: 2025-09-16T04:55:24.188170Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Sep 16 04:55:24.188301 waagent[2200]: 2025-09-16T04:55:24.188241Z INFO EnvHandler ExtHandler Gateway:None Sep 16 04:55:24.188546 waagent[2200]: 2025-09-16T04:55:24.188510Z INFO EnvHandler ExtHandler Routes:None Sep 16 04:55:24.188660 waagent[2200]: 2025-09-16T04:55:24.188623Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Sep 16 04:55:24.193835 waagent[2200]: 2025-09-16T04:55:24.193776Z INFO ExtHandler ExtHandler Sep 16 04:55:24.194415 waagent[2200]: 2025-09-16T04:55:24.194371Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 0dc69bb1-7d64-42ca-b4be-117d7055099f correlation 20e88050-4577-4212-b3ce-8ba109865ba2 created: 2025-09-16T04:53:40.999695Z] Sep 16 04:55:24.195709 waagent[2200]: 2025-09-16T04:55:24.195663Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Sep 16 04:55:24.197776 waagent[2200]: 2025-09-16T04:55:24.197734Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Sep 16 04:55:24.268942 waagent[2200]: 2025-09-16T04:55:24.268861Z 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 Sep 16 04:55:24.268942 waagent[2200]: Try `iptables -h' or 'iptables --help' for more information.) Sep 16 04:55:24.269398 waagent[2200]: 2025-09-16T04:55:24.269356Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: B2420CCF-B7FD-42D7-95DF-E812420793CC;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Sep 16 04:55:24.471489 waagent[2200]: 2025-09-16T04:55:24.471379Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Sep 16 04:55:24.471489 waagent[2200]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Sep 16 04:55:24.471489 waagent[2200]: pkts bytes target prot opt in out source destination Sep 16 04:55:24.471489 waagent[2200]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Sep 16 04:55:24.471489 waagent[2200]: pkts bytes target prot opt in out source destination Sep 16 04:55:24.471489 waagent[2200]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Sep 16 04:55:24.471489 waagent[2200]: pkts bytes target prot opt in out source destination Sep 16 04:55:24.471489 waagent[2200]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Sep 16 04:55:24.471489 waagent[2200]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Sep 16 04:55:24.471489 waagent[2200]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Sep 16 04:55:24.474864 waagent[2200]: 2025-09-16T04:55:24.474817Z INFO EnvHandler ExtHandler Current Firewall rules: Sep 16 04:55:24.474864 waagent[2200]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Sep 16 04:55:24.474864 waagent[2200]: pkts bytes target prot opt in out source destination Sep 16 04:55:24.474864 waagent[2200]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Sep 16 04:55:24.474864 waagent[2200]: pkts bytes target prot opt in out source destination Sep 16 04:55:24.474864 waagent[2200]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Sep 16 04:55:24.474864 waagent[2200]: pkts bytes target prot opt in out source destination Sep 16 04:55:24.474864 waagent[2200]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Sep 16 04:55:24.474864 waagent[2200]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Sep 16 04:55:24.474864 waagent[2200]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Sep 16 04:55:24.475098 waagent[2200]: 2025-09-16T04:55:24.475067Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Sep 16 04:55:24.475242 waagent[2200]: 2025-09-16T04:55:24.475210Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Sep 16 04:55:24.505223 waagent[2200]: 2025-09-16T04:55:24.505172Z INFO MonitorHandler ExtHandler Network interfaces: Sep 16 04:55:24.505223 waagent[2200]: Executing ['ip', '-a', '-o', 'link']: Sep 16 04:55:24.505223 waagent[2200]: 1: lo: mtu 65536 qdisc noqueue state UNKNOWN mode DEFAULT group default qlen 1000\ link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00 Sep 16 04:55:24.505223 waagent[2200]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:1e:52:36:e6:7c brd ff:ff:ff:ff:ff:ff Sep 16 04:55:24.505223 waagent[2200]: Executing ['ip', '-4', '-a', '-o', 'address']: Sep 16 04:55:24.505223 waagent[2200]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Sep 16 04:55:24.505223 waagent[2200]: 2: eth0 inet 10.200.8.11/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Sep 16 04:55:24.505223 waagent[2200]: Executing ['ip', '-6', '-a', '-o', 'address']: Sep 16 04:55:24.505223 waagent[2200]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Sep 16 04:55:24.505223 waagent[2200]: 2: eth0 inet6 fe80::7e1e:52ff:fe36:e67c/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Sep 16 04:55:28.287303 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 16 04:55:28.291857 systemd[1]: Started sshd@0-10.200.8.11:22-10.200.16.10:37552.service - OpenSSH per-connection server daemon (10.200.16.10:37552). Sep 16 04:55:29.238453 sshd[2348]: Accepted publickey for core from 10.200.16.10 port 37552 ssh2: RSA SHA256:h3fCh5TnNv3vJ3YKGDHm1APBHQN5CK8MTvNYIQHQseI Sep 16 04:55:29.239663 sshd-session[2348]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:55:29.243886 systemd-logind[1889]: New session 3 of user core. Sep 16 04:55:29.257908 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 16 04:55:29.791056 systemd[1]: Started sshd@1-10.200.8.11:22-10.200.16.10:37556.service - OpenSSH per-connection server daemon (10.200.16.10:37556). Sep 16 04:55:30.420221 sshd[2354]: Accepted publickey for core from 10.200.16.10 port 37556 ssh2: RSA SHA256:h3fCh5TnNv3vJ3YKGDHm1APBHQN5CK8MTvNYIQHQseI Sep 16 04:55:30.421471 sshd-session[2354]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:55:30.425573 systemd-logind[1889]: New session 4 of user core. Sep 16 04:55:30.437928 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 16 04:55:31.131910 sshd[2357]: Connection closed by 10.200.16.10 port 37556 Sep 16 04:55:31.132278 sshd-session[2354]: pam_unix(sshd:session): session closed for user core Sep 16 04:55:31.135853 systemd[1]: sshd@1-10.200.8.11:22-10.200.16.10:37556.service: Deactivated successfully. Sep 16 04:55:31.137424 systemd[1]: session-4.scope: Deactivated successfully. Sep 16 04:55:31.138170 systemd-logind[1889]: Session 4 logged out. Waiting for processes to exit. Sep 16 04:55:31.139102 systemd-logind[1889]: Removed session 4. Sep 16 04:55:31.247089 systemd[1]: Started sshd@2-10.200.8.11:22-10.200.16.10:42774.service - OpenSSH per-connection server daemon (10.200.16.10:42774). Sep 16 04:55:31.898978 sshd[2364]: Accepted publickey for core from 10.200.16.10 port 42774 ssh2: RSA SHA256:h3fCh5TnNv3vJ3YKGDHm1APBHQN5CK8MTvNYIQHQseI Sep 16 04:55:31.900188 sshd-session[2364]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:55:31.904335 systemd-logind[1889]: New session 5 of user core. Sep 16 04:55:31.913914 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 16 04:55:32.357097 sshd[2367]: Connection closed by 10.200.16.10 port 42774 Sep 16 04:55:32.357424 sshd-session[2364]: pam_unix(sshd:session): session closed for user core Sep 16 04:55:32.360686 systemd[1]: sshd@2-10.200.8.11:22-10.200.16.10:42774.service: Deactivated successfully. Sep 16 04:55:32.362257 systemd[1]: session-5.scope: Deactivated successfully. Sep 16 04:55:32.362933 systemd-logind[1889]: Session 5 logged out. Waiting for processes to exit. Sep 16 04:55:32.363829 systemd-logind[1889]: Removed session 5. Sep 16 04:55:35.413185 chronyd[1869]: Selected source PHC0 Sep 16 04:55:42.462945 systemd[1]: Started sshd@3-10.200.8.11:22-10.200.16.10:37362.service - OpenSSH per-connection server daemon (10.200.16.10:37362). Sep 16 04:55:43.101853 sshd[2373]: Accepted publickey for core from 10.200.16.10 port 37362 ssh2: RSA SHA256:h3fCh5TnNv3vJ3YKGDHm1APBHQN5CK8MTvNYIQHQseI Sep 16 04:55:43.103073 sshd-session[2373]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:55:43.107236 systemd-logind[1889]: New session 6 of user core. Sep 16 04:55:43.121970 systemd[1]: Started session-6.scope - Session 6 of User core. Sep 16 04:55:43.547240 sshd[2376]: Connection closed by 10.200.16.10 port 37362 Sep 16 04:55:43.547601 sshd-session[2373]: pam_unix(sshd:session): session closed for user core Sep 16 04:55:43.551011 systemd[1]: sshd@3-10.200.8.11:22-10.200.16.10:37362.service: Deactivated successfully. Sep 16 04:55:43.552771 systemd[1]: session-6.scope: Deactivated successfully. Sep 16 04:55:43.553523 systemd-logind[1889]: Session 6 logged out. Waiting for processes to exit. Sep 16 04:55:43.554490 systemd-logind[1889]: Removed session 6. Sep 16 04:55:43.666357 systemd[1]: Started sshd@4-10.200.8.11:22-10.200.16.10:37364.service - OpenSSH per-connection server daemon (10.200.16.10:37364). Sep 16 04:55:44.337270 sshd[2382]: Accepted publickey for core from 10.200.16.10 port 37364 ssh2: RSA SHA256:h3fCh5TnNv3vJ3YKGDHm1APBHQN5CK8MTvNYIQHQseI Sep 16 04:55:44.338521 sshd-session[2382]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:55:44.342735 systemd-logind[1889]: New session 7 of user core. Sep 16 04:55:44.360922 systemd[1]: Started session-7.scope - Session 7 of User core. Sep 16 04:55:44.791660 sshd[2385]: Connection closed by 10.200.16.10 port 37364 Sep 16 04:55:44.792048 sshd-session[2382]: pam_unix(sshd:session): session closed for user core Sep 16 04:55:44.795708 systemd[1]: sshd@4-10.200.8.11:22-10.200.16.10:37364.service: Deactivated successfully. Sep 16 04:55:44.797281 systemd[1]: session-7.scope: Deactivated successfully. Sep 16 04:55:44.797956 systemd-logind[1889]: Session 7 logged out. Waiting for processes to exit. Sep 16 04:55:44.798919 systemd-logind[1889]: Removed session 7. Sep 16 04:55:52.577085 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Sep 16 04:55:54.914130 systemd[1]: Started sshd@5-10.200.8.11:22-10.200.16.10:36970.service - OpenSSH per-connection server daemon (10.200.16.10:36970). Sep 16 04:55:55.559982 sshd[2391]: Accepted publickey for core from 10.200.16.10 port 36970 ssh2: RSA SHA256:h3fCh5TnNv3vJ3YKGDHm1APBHQN5CK8MTvNYIQHQseI Sep 16 04:55:55.561235 sshd-session[2391]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:55:55.565356 systemd-logind[1889]: New session 8 of user core. Sep 16 04:55:55.576907 systemd[1]: Started session-8.scope - Session 8 of User core. Sep 16 04:55:56.008875 sshd[2394]: Connection closed by 10.200.16.10 port 36970 Sep 16 04:55:56.009197 sshd-session[2391]: pam_unix(sshd:session): session closed for user core Sep 16 04:55:56.012555 systemd[1]: sshd@5-10.200.8.11:22-10.200.16.10:36970.service: Deactivated successfully. Sep 16 04:55:56.014156 systemd[1]: session-8.scope: Deactivated successfully. Sep 16 04:55:56.014823 systemd-logind[1889]: Session 8 logged out. Waiting for processes to exit. Sep 16 04:55:56.015701 systemd-logind[1889]: Removed session 8. Sep 16 04:55:56.124073 systemd[1]: Started sshd@6-10.200.8.11:22-10.200.16.10:36982.service - OpenSSH per-connection server daemon (10.200.16.10:36982). Sep 16 04:55:56.752414 sshd[2400]: Accepted publickey for core from 10.200.16.10 port 36982 ssh2: RSA SHA256:h3fCh5TnNv3vJ3YKGDHm1APBHQN5CK8MTvNYIQHQseI Sep 16 04:55:56.753607 sshd-session[2400]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:55:56.757935 systemd-logind[1889]: New session 9 of user core. Sep 16 04:55:56.770917 systemd[1]: Started session-9.scope - Session 9 of User core. Sep 16 04:55:57.196101 sshd[2403]: Connection closed by 10.200.16.10 port 36982 Sep 16 04:55:57.196452 sshd-session[2400]: pam_unix(sshd:session): session closed for user core Sep 16 04:55:57.199853 systemd[1]: sshd@6-10.200.8.11:22-10.200.16.10:36982.service: Deactivated successfully. Sep 16 04:55:57.201412 systemd[1]: session-9.scope: Deactivated successfully. Sep 16 04:55:57.202069 systemd-logind[1889]: Session 9 logged out. Waiting for processes to exit. Sep 16 04:55:57.202967 systemd-logind[1889]: Removed session 9. Sep 16 04:55:57.517999 update_engine[1892]: I20250916 04:55:57.517834 1892 update_attempter.cc:509] Updating boot flags... Sep 16 04:56:07.310775 systemd[1]: Started sshd@7-10.200.8.11:22-10.200.16.10:53208.service - OpenSSH per-connection server daemon (10.200.16.10:53208). Sep 16 04:56:07.941743 sshd[2473]: Accepted publickey for core from 10.200.16.10 port 53208 ssh2: RSA SHA256:h3fCh5TnNv3vJ3YKGDHm1APBHQN5CK8MTvNYIQHQseI Sep 16 04:56:07.942974 sshd-session[2473]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:56:07.947055 systemd-logind[1889]: New session 10 of user core. Sep 16 04:56:07.956913 systemd[1]: Started session-10.scope - Session 10 of User core. Sep 16 04:56:08.384371 sshd[2476]: Connection closed by 10.200.16.10 port 53208 Sep 16 04:56:08.384653 sshd-session[2473]: pam_unix(sshd:session): session closed for user core Sep 16 04:56:08.388306 systemd[1]: sshd@7-10.200.8.11:22-10.200.16.10:53208.service: Deactivated successfully. Sep 16 04:56:08.390113 systemd[1]: session-10.scope: Deactivated successfully. Sep 16 04:56:08.390887 systemd-logind[1889]: Session 10 logged out. Waiting for processes to exit. Sep 16 04:56:08.391819 systemd-logind[1889]: Removed session 10. Sep 16 04:56:08.500512 systemd[1]: Started sshd@8-10.200.8.11:22-10.200.16.10:53224.service - OpenSSH per-connection server daemon (10.200.16.10:53224). Sep 16 04:56:09.156814 sshd[2482]: Accepted publickey for core from 10.200.16.10 port 53224 ssh2: RSA SHA256:h3fCh5TnNv3vJ3YKGDHm1APBHQN5CK8MTvNYIQHQseI Sep 16 04:56:09.158027 sshd-session[2482]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:56:09.162194 systemd-logind[1889]: New session 11 of user core. Sep 16 04:56:09.175938 systemd[1]: Started session-11.scope - Session 11 of User core. Sep 16 04:56:09.604166 sshd[2485]: Connection closed by 10.200.16.10 port 53224 Sep 16 04:56:09.604447 sshd-session[2482]: pam_unix(sshd:session): session closed for user core Sep 16 04:56:09.607761 systemd[1]: sshd@8-10.200.8.11:22-10.200.16.10:53224.service: Deactivated successfully. Sep 16 04:56:09.609588 systemd[1]: session-11.scope: Deactivated successfully. Sep 16 04:56:09.610271 systemd-logind[1889]: Session 11 logged out. Waiting for processes to exit. Sep 16 04:56:09.611224 systemd-logind[1889]: Removed session 11. Sep 16 04:56:12.314132 setup-nvidia[2490]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 16 04:56:12.314462 setup-nvidia[2490]: Dload Upload Total Spent Left Speed Sep 16 04:56:19.727279 systemd[1]: Started sshd@9-10.200.8.11:22-10.200.16.10:39696.service - OpenSSH per-connection server daemon (10.200.16.10:39696). Sep 16 04:56:20.355755 sshd[2492]: Accepted publickey for core from 10.200.16.10 port 39696 ssh2: RSA SHA256:h3fCh5TnNv3vJ3YKGDHm1APBHQN5CK8MTvNYIQHQseI Sep 16 04:56:20.356951 sshd-session[2492]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:56:20.361087 systemd-logind[1889]: New session 12 of user core. Sep 16 04:56:20.371912 systemd[1]: Started session-12.scope - Session 12 of User core. Sep 16 04:56:20.800451 sshd[2495]: Connection closed by 10.200.16.10 port 39696 Sep 16 04:56:20.800818 sshd-session[2492]: pam_unix(sshd:session): session closed for user core Sep 16 04:56:20.804609 systemd[1]: sshd@9-10.200.8.11:22-10.200.16.10:39696.service: Deactivated successfully. Sep 16 04:56:20.806185 systemd[1]: session-12.scope: Deactivated successfully. Sep 16 04:56:20.806843 systemd-logind[1889]: Session 12 logged out. Waiting for processes to exit. Sep 16 04:56:20.807721 systemd-logind[1889]: Removed session 12. Sep 16 04:56:20.916013 systemd[1]: Started sshd@10-10.200.8.11:22-10.200.16.10:34390.service - OpenSSH per-connection server daemon (10.200.16.10:34390). Sep 16 04:56:21.548303 sshd[2501]: Accepted publickey for core from 10.200.16.10 port 34390 ssh2: RSA SHA256:h3fCh5TnNv3vJ3YKGDHm1APBHQN5CK8MTvNYIQHQseI Sep 16 04:56:21.549483 sshd-session[2501]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:56:21.553642 systemd-logind[1889]: New session 13 of user core. Sep 16 04:56:21.563917 systemd[1]: Started session-13.scope - Session 13 of User core. Sep 16 04:56:21.994896 sshd[2504]: Connection closed by 10.200.16.10 port 34390 Sep 16 04:56:21.995234 sshd-session[2501]: pam_unix(sshd:session): session closed for user core Sep 16 04:56:21.998589 systemd[1]: sshd@10-10.200.8.11:22-10.200.16.10:34390.service: Deactivated successfully. Sep 16 04:56:22.000225 systemd[1]: session-13.scope: Deactivated successfully. Sep 16 04:56:22.000917 systemd-logind[1889]: Session 13 logged out. Waiting for processes to exit. Sep 16 04:56:22.001800 systemd-logind[1889]: Removed session 13. Sep 16 04:56:32.111298 systemd[1]: Started sshd@11-10.200.8.11:22-10.200.16.10:57250.service - OpenSSH per-connection server daemon (10.200.16.10:57250). Sep 16 04:56:32.741208 sshd[2510]: Accepted publickey for core from 10.200.16.10 port 57250 ssh2: RSA SHA256:h3fCh5TnNv3vJ3YKGDHm1APBHQN5CK8MTvNYIQHQseI Sep 16 04:56:32.742381 sshd-session[2510]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:56:32.746414 systemd-logind[1889]: New session 14 of user core. Sep 16 04:56:32.756911 systemd[1]: Started session-14.scope - Session 14 of User core. Sep 16 04:56:33.186225 sshd[2513]: Connection closed by 10.200.16.10 port 57250 Sep 16 04:56:33.186614 sshd-session[2510]: pam_unix(sshd:session): session closed for user core Sep 16 04:56:33.190099 systemd[1]: sshd@11-10.200.8.11:22-10.200.16.10:57250.service: Deactivated successfully. Sep 16 04:56:33.191697 systemd[1]: session-14.scope: Deactivated successfully. Sep 16 04:56:33.192402 systemd-logind[1889]: Session 14 logged out. Waiting for processes to exit. Sep 16 04:56:33.193569 systemd-logind[1889]: Removed session 14. Sep 16 04:56:33.301478 systemd[1]: Started sshd@12-10.200.8.11:22-10.200.16.10:57266.service - OpenSSH per-connection server daemon (10.200.16.10:57266). Sep 16 04:56:33.955742 sshd[2519]: Accepted publickey for core from 10.200.16.10 port 57266 ssh2: RSA SHA256:h3fCh5TnNv3vJ3YKGDHm1APBHQN5CK8MTvNYIQHQseI Sep 16 04:56:33.956954 sshd-session[2519]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:56:33.961020 systemd-logind[1889]: New session 15 of user core. Sep 16 04:56:33.971904 systemd[1]: Started session-15.scope - Session 15 of User core. Sep 16 04:56:34.403567 sshd[2522]: Connection closed by 10.200.16.10 port 57266 Sep 16 04:56:34.403976 sshd-session[2519]: pam_unix(sshd:session): session closed for user core Sep 16 04:56:34.407055 systemd[1]: sshd@12-10.200.8.11:22-10.200.16.10:57266.service: Deactivated successfully. Sep 16 04:56:34.408922 systemd[1]: session-15.scope: Deactivated successfully. Sep 16 04:56:34.410183 systemd-logind[1889]: Session 15 logged out. Waiting for processes to exit. Sep 16 04:56:34.411270 systemd-logind[1889]: Removed session 15. Sep 16 04:56:34.863656 setup-nvidia[2490]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 532M 0 1744k 0 0 1231k 0 0:07:22 0:00:01 0:07:21 1231k\u000d 4 532M 4 23.7M 0 0 10.0M 0 0:00:53 0:00:02 0:00:51 10.0M\u000d 9 532M 9 50.6M 0 0 14.7M 0 0:00:36 0:00:03 0:00:33 14.7M\u000d 14 532M 14 76.0M 0 0 17.2M 0 0:00:30 0:00:04 0:00:26 17.2M\u000d 19 532M 19 101M 0 0 18.8M 0 0:00:28 0:00:05 0:00:23 20.2M\u000d 24 532M 24 128M 0 0 19.8M 0 0:00:26 0:00:06 0:00:20 25.1M\u000d 28 532M 28 151M 0 0 20.4M 0 0:00:26 0:00:07 0:00:19 25.2M\u000d 32 532M 32 174M 0 0 20.9M 0 0:00:25 0:00:08 0:00:17 25.2M\u000d 38 532M 38 203M 0 0 21.4M 0 0:00:24 0:00:09 0:00:15 25.2M\u000d 42 532M 42 226M 0 0 21.7M 0 0:00:24 0:00:10 0:00:14 24.8M\u000d 47 532M 47 251M 0 0 22.0M 0 0:00:24 0:00:11 0:00:13 24.9M\u000d 52 532M 52 279M 0 0 22.3M 0 0:00:23 0:00:12 0:00:11 25.2M\u000d 56 532M 56 302M 0 0 22.5M 0 0:00:23 0:00:13 0:00:10 25.1M\u000d 61 532M 61 326M 0 0 22.7M 0 0:00:23 0:00:14 0:00:09 25.0M\u000d 66 532M 66 353M 0 0 22.8M 0 0:00:23 0:00:15 0:00:08 25.1M\u000d 70 532M 70 377M 0 0 22.9M 0 0:00:23 0:00:16 0:00:07 25.0M\u000d 75 532M 75 400M 0 0 23.0M 0 0:00:23 0:00:17 0:00:06 24.7M\u000d 80 532M 80 428M 0 0 23.1M 0 0:00:22 0:00:18 0:00:04 24.9M\u000d 85 532M 85 452M 0 0 23.3M 0 0:00:22 0:00:19 0:00:03 25.0M\u000d 89 532M 89 475M 0 0 23.3M 0 0:00:22 0:00:20 0:00:02 24.8M\u000d 94 532M 94 503M 0 0 23.4M 0 0:00:22 0:00:21 0:00:01 25.0M\u000d 99 532M 99 527M 0 0 23.5M 0 0:00:22 0:00:22 --:--:-- 25.2M\u000d100 532M 100 532M 0 0 23.5M 0 0:00:22 0:00:22 --:--:-- 25.4M Sep 16 04:56:44.522077 systemd[1]: Started sshd@13-10.200.8.11:22-10.200.16.10:48982.service - OpenSSH per-connection server daemon (10.200.16.10:48982). Sep 16 04:56:45.168336 sshd[2538]: Accepted publickey for core from 10.200.16.10 port 48982 ssh2: RSA SHA256:h3fCh5TnNv3vJ3YKGDHm1APBHQN5CK8MTvNYIQHQseI Sep 16 04:56:45.169603 sshd-session[2538]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:56:45.179858 systemd-logind[1889]: New session 16 of user core. Sep 16 04:56:45.186942 systemd[1]: Started session-16.scope - Session 16 of User core. Sep 16 04:56:45.622898 sshd[2541]: Connection closed by 10.200.16.10 port 48982 Sep 16 04:56:45.623005 sshd-session[2538]: pam_unix(sshd:session): session closed for user core Sep 16 04:56:45.629032 systemd[1]: sshd@13-10.200.8.11:22-10.200.16.10:48982.service: Deactivated successfully. Sep 16 04:56:45.632523 systemd[1]: session-16.scope: Deactivated successfully. Sep 16 04:56:45.634672 systemd-logind[1889]: Session 16 logged out. Waiting for processes to exit. Sep 16 04:56:45.636562 systemd-logind[1889]: Removed session 16. Sep 16 04:56:45.736559 systemd[1]: Started sshd@14-10.200.8.11:22-10.200.16.10:48996.service - OpenSSH per-connection server daemon (10.200.16.10:48996). Sep 16 04:56:46.377847 sshd[2547]: Accepted publickey for core from 10.200.16.10 port 48996 ssh2: RSA SHA256:h3fCh5TnNv3vJ3YKGDHm1APBHQN5CK8MTvNYIQHQseI Sep 16 04:56:46.379547 sshd-session[2547]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:56:46.383754 systemd-logind[1889]: New session 17 of user core. Sep 16 04:56:46.402922 systemd[1]: Started session-17.scope - Session 17 of User core. Sep 16 04:56:46.825662 sshd[2551]: Connection closed by 10.200.16.10 port 48996 Sep 16 04:56:46.826138 sshd-session[2547]: pam_unix(sshd:session): session closed for user core Sep 16 04:56:46.829713 systemd[1]: sshd@14-10.200.8.11:22-10.200.16.10:48996.service: Deactivated successfully. Sep 16 04:56:46.831314 systemd[1]: session-17.scope: Deactivated successfully. Sep 16 04:56:46.832113 systemd-logind[1889]: Session 17 logged out. Waiting for processes to exit. Sep 16 04:56:46.833144 systemd-logind[1889]: Removed session 17. Sep 16 04:56:47.238287 setup-nvidia[1882]: Downloading NVIDIA 535.261.03 Driver Sep 16 04:56:47.242476 setup-nvidia[2556]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 16 04:56:47.242692 setup-nvidia[2556]: Dload Upload Total Spent Left Speed Sep 16 04:56:49.387387 setup-nvidia[2556]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 12 325M 12 42.3M 0 0 98.9M 0 0:00:03 --:--:-- 0:00:03 98.9M\u000d 63 325M 63 206M 0 0 144M 0 0:00:02 0:00:01 0:00:01 144M\u000d100 325M 100 325M 0 0 151M 0 0:00:02 0:00:02 --:--:-- 152M Sep 16 04:56:49.511744 setup-nvidia[1882]: Extract the NVIDIA Driver Installer 535.261.03 Sep 16 04:56:49.511744 setup-nvidia[1882]: /opt/nvidia/workdir/nvidia-workdir / Sep 16 04:56:49.546762 setup-nvidia[2559]: Creating directory NVIDIA-Linux-x86_64-535.261.03 Sep 16 04:56:49.783318 setup-nvidia[2559]: Verifying archive integrity... OK Sep 16 04:56:50.502530 setup-nvidia[2559]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.261.03 Sep 16 04:56:52.258010 setup-nvidia[2585]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Sep 16 04:56:52.355865 setup-nvidia[1882]: / Sep 16 04:56:52.356107 setup-nvidia[1882]: Spawn system-nspawn container to install the NVIDIA drivers Sep 16 04:56:52.555654 sudo[2592]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4459.0.0+nightly-20250915-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 Sep 16 04:56:52.557373 sudo[2592]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 16 04:56:52.563074 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Sep 16 04:56:52.566269 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Sep 16 04:56:52.600292 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Sep 16 04:56:52.604058 systemd[1]: Starting user@0.service - User Manager for UID 0... Sep 16 04:56:52.620856 (systemd)[2595]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Sep 16 04:56:52.623079 systemd-logind[1889]: New session c3 of user root. Sep 16 04:56:52.802779 systemd[2595]: Queued start job for default target default.target. Sep 16 04:56:52.820905 systemd[2595]: Created slice app.slice - User Application Slice. Sep 16 04:56:52.820934 systemd[2595]: Reached target paths.target - Paths. Sep 16 04:56:52.820980 systemd[2595]: Reached target timers.target - Timers. Sep 16 04:56:52.822094 systemd[2595]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 16 04:56:52.834188 systemd[2595]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 16 04:56:52.834305 systemd[2595]: Reached target sockets.target - Sockets. Sep 16 04:56:52.834359 systemd[2595]: Reached target basic.target - Basic System. Sep 16 04:56:52.834404 systemd[2595]: Reached target default.target - Main User Target. Sep 16 04:56:52.834436 systemd[2595]: Startup finished in 205ms. Sep 16 04:56:52.834471 systemd[1]: Started user@0.service - User Manager for UID 0. Sep 16 04:56:52.837345 systemd[1]: Started session-c2.scope - Session c2 of User root. Sep 16 04:56:52.914823 kernel: loop6: detected capacity change from 0 to 12644352 Sep 16 04:56:52.927814 kernel: loop6: p9 Sep 16 04:56:52.997403 setup-nvidia[2604]: ░ Spawning container flatcardevelopercontainer-4459.0.0nightly-20250915-2100.bin on /opt/nvidia/workdir/flatcar_developer_container-4459.0.0+nightly-20250915-2100.bin. Sep 16 04:56:53.966546 kernel: EXT4-fs (loop6p9): write access unavailable, skipping orphan cleanup Sep 16 04:56:53.966682 kernel: EXT4-fs (loop6p9): mounted filesystem d4012d09-7702-4715-a232-7247a60c6128 ro without journal. Quota mode: none. Sep 16 04:56:56.942096 systemd[1]: Started sshd@15-10.200.8.11:22-10.200.16.10:48854.service - OpenSSH per-connection server daemon (10.200.16.10:48854). Sep 16 04:56:57.573120 sshd[2719]: Accepted publickey for core from 10.200.16.10 port 48854 ssh2: RSA SHA256:h3fCh5TnNv3vJ3YKGDHm1APBHQN5CK8MTvNYIQHQseI Sep 16 04:56:57.574306 sshd-session[2719]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:56:57.578517 systemd-logind[1889]: New session 18 of user core. Sep 16 04:56:57.588917 systemd[1]: Started session-18.scope - Session 18 of User core. Sep 16 04:56:58.017380 sshd[2882]: Connection closed by 10.200.16.10 port 48854 Sep 16 04:56:58.017752 sshd-session[2719]: pam_unix(sshd:session): session closed for user core Sep 16 04:56:58.021349 systemd[1]: sshd@15-10.200.8.11:22-10.200.16.10:48854.service: Deactivated successfully. Sep 16 04:56:58.022977 systemd[1]: session-18.scope: Deactivated successfully. Sep 16 04:56:58.023645 systemd-logind[1889]: Session 18 logged out. Waiting for processes to exit. Sep 16 04:56:58.024732 systemd-logind[1889]: Removed session 18. Sep 16 04:56:58.132317 systemd[1]: Started sshd@16-10.200.8.11:22-10.200.16.10:48856.service - OpenSSH per-connection server daemon (10.200.16.10:48856). Sep 16 04:56:58.762602 sshd[3013]: Accepted publickey for core from 10.200.16.10 port 48856 ssh2: RSA SHA256:h3fCh5TnNv3vJ3YKGDHm1APBHQN5CK8MTvNYIQHQseI Sep 16 04:56:58.763842 sshd-session[3013]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:56:58.768328 systemd-logind[1889]: New session 19 of user core. Sep 16 04:56:58.779932 systemd[1]: Started session-19.scope - Session 19 of User core. Sep 16 04:56:59.209987 sshd[3262]: Connection closed by 10.200.16.10 port 48856 Sep 16 04:56:59.210327 sshd-session[3013]: pam_unix(sshd:session): session closed for user core Sep 16 04:56:59.214062 systemd[1]: sshd@16-10.200.8.11:22-10.200.16.10:48856.service: Deactivated successfully. Sep 16 04:56:59.215774 systemd[1]: session-19.scope: Deactivated successfully. Sep 16 04:56:59.216527 systemd-logind[1889]: Session 19 logged out. Waiting for processes to exit. Sep 16 04:56:59.217431 systemd-logind[1889]: Removed session 19. Sep 16 04:57:09.328033 systemd[1]: Started sshd@17-10.200.8.11:22-10.200.16.10:44990.service - OpenSSH per-connection server daemon (10.200.16.10:44990). Sep 16 04:57:09.965499 sshd[3655]: Accepted publickey for core from 10.200.16.10 port 44990 ssh2: RSA SHA256:h3fCh5TnNv3vJ3YKGDHm1APBHQN5CK8MTvNYIQHQseI Sep 16 04:57:09.966681 sshd-session[3655]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:57:09.970799 systemd-logind[1889]: New session 20 of user core. Sep 16 04:57:09.977940 systemd[1]: Started session-20.scope - Session 20 of User core. Sep 16 04:57:10.408607 sshd[3969]: Connection closed by 10.200.16.10 port 44990 Sep 16 04:57:10.408973 sshd-session[3655]: pam_unix(sshd:session): session closed for user core Sep 16 04:57:10.412447 systemd[1]: sshd@17-10.200.8.11:22-10.200.16.10:44990.service: Deactivated successfully. Sep 16 04:57:10.414231 systemd[1]: session-20.scope: Deactivated successfully. Sep 16 04:57:10.415020 systemd-logind[1889]: Session 20 logged out. Waiting for processes to exit. Sep 16 04:57:10.415954 systemd-logind[1889]: Removed session 20. Sep 16 04:57:10.522443 systemd[1]: Started sshd@18-10.200.8.11:22-10.200.16.10:43504.service - OpenSSH per-connection server daemon (10.200.16.10:43504). Sep 16 04:57:11.166917 sshd[4278]: Accepted publickey for core from 10.200.16.10 port 43504 ssh2: RSA SHA256:h3fCh5TnNv3vJ3YKGDHm1APBHQN5CK8MTvNYIQHQseI Sep 16 04:57:11.168160 sshd-session[4278]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:57:11.172404 systemd-logind[1889]: New session 21 of user core. Sep 16 04:57:11.180909 systemd[1]: Started session-21.scope - Session 21 of User core. Sep 16 04:57:11.610845 sshd[4616]: Connection closed by 10.200.16.10 port 43504 Sep 16 04:57:11.611113 sshd-session[4278]: pam_unix(sshd:session): session closed for user core Sep 16 04:57:11.613927 systemd[1]: sshd@18-10.200.8.11:22-10.200.16.10:43504.service: Deactivated successfully. Sep 16 04:57:11.615570 systemd[1]: session-21.scope: Deactivated successfully. Sep 16 04:57:11.616779 systemd-logind[1889]: Session 21 logged out. Waiting for processes to exit. Sep 16 04:57:11.617730 systemd-logind[1889]: Removed session 21. Sep 16 04:57:21.731047 systemd[1]: Started sshd@19-10.200.8.11:22-10.200.16.10:40330.service - OpenSSH per-connection server daemon (10.200.16.10:40330). Sep 16 04:57:24.016867 sshd[6208]: Accepted publickey for core from 10.200.16.10 port 40330 ssh2: RSA SHA256:h3fCh5TnNv3vJ3YKGDHm1APBHQN5CK8MTvNYIQHQseI Sep 16 04:57:24.017224 sshd-session[6208]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:57:24.021468 systemd-logind[1889]: New session 22 of user core. Sep 16 04:57:24.030920 systemd[1]: Started session-22.scope - Session 22 of User core. Sep 16 04:57:24.385439 sshd[6211]: Connection closed by 10.200.16.10 port 40330 Sep 16 04:57:24.385748 sshd-session[6208]: pam_unix(sshd:session): session closed for user core Sep 16 04:57:24.388747 systemd[1]: sshd@19-10.200.8.11:22-10.200.16.10:40330.service: Deactivated successfully. Sep 16 04:57:24.390385 systemd[1]: session-22.scope: Deactivated successfully. Sep 16 04:57:24.391560 systemd-logind[1889]: Session 22 logged out. Waiting for processes to exit. Sep 16 04:57:24.392499 systemd-logind[1889]: Removed session 22. Sep 16 04:57:24.503199 systemd[1]: Started sshd@20-10.200.8.11:22-10.200.16.10:40336.service - OpenSSH per-connection server daemon (10.200.16.10:40336). Sep 16 04:57:25.160933 sshd[6217]: Accepted publickey for core from 10.200.16.10 port 40336 ssh2: RSA SHA256:h3fCh5TnNv3vJ3YKGDHm1APBHQN5CK8MTvNYIQHQseI Sep 16 04:57:26.923578 sshd-session[6217]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:57:26.928582 systemd-logind[1889]: New session 23 of user core. Sep 16 04:57:26.942915 systemd[1]: Started session-23.scope - Session 23 of User core. Sep 16 04:57:27.295839 sshd[6220]: Connection closed by 10.200.16.10 port 40336 Sep 16 04:57:27.296115 sshd-session[6217]: pam_unix(sshd:session): session closed for user core Sep 16 04:57:27.299479 systemd[1]: sshd@20-10.200.8.11:22-10.200.16.10:40336.service: Deactivated successfully. Sep 16 04:57:27.301068 systemd[1]: session-23.scope: Deactivated successfully. Sep 16 04:57:27.301712 systemd-logind[1889]: Session 23 logged out. Waiting for processes to exit. Sep 16 04:57:27.302617 systemd-logind[1889]: Removed session 23. Sep 16 04:57:37.411251 systemd[1]: Started sshd@21-10.200.8.11:22-10.200.16.10:51944.service - OpenSSH per-connection server daemon (10.200.16.10:51944). Sep 16 04:57:38.050939 sshd[8397]: Accepted publickey for core from 10.200.16.10 port 51944 ssh2: RSA SHA256:h3fCh5TnNv3vJ3YKGDHm1APBHQN5CK8MTvNYIQHQseI Sep 16 04:57:38.052222 sshd-session[8397]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:57:38.064451 systemd-logind[1889]: New session 24 of user core. Sep 16 04:57:38.072993 systemd[1]: Started session-24.scope - Session 24 of User core. Sep 16 04:57:38.504932 sshd[8700]: Connection closed by 10.200.16.10 port 51944 Sep 16 04:57:38.505544 sshd-session[8397]: pam_unix(sshd:session): session closed for user core Sep 16 04:57:38.510031 systemd-logind[1889]: Session 24 logged out. Waiting for processes to exit. Sep 16 04:57:38.511663 systemd[1]: sshd@21-10.200.8.11:22-10.200.16.10:51944.service: Deactivated successfully. Sep 16 04:57:38.514261 systemd[1]: session-24.scope: Deactivated successfully. Sep 16 04:57:38.517099 systemd-logind[1889]: Removed session 24. Sep 16 04:57:38.625047 systemd[1]: Started sshd@22-10.200.8.11:22-10.200.16.10:51946.service - OpenSSH per-connection server daemon (10.200.16.10:51946). Sep 16 04:57:39.273393 sshd[8887]: Accepted publickey for core from 10.200.16.10 port 51946 ssh2: RSA SHA256:h3fCh5TnNv3vJ3YKGDHm1APBHQN5CK8MTvNYIQHQseI Sep 16 04:57:39.275126 sshd-session[8887]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:57:39.284991 systemd-logind[1889]: New session 25 of user core. Sep 16 04:57:39.291424 systemd[1]: Started session-25.scope - Session 25 of User core. Sep 16 04:57:39.722713 sshd[9143]: Connection closed by 10.200.16.10 port 51946 Sep 16 04:57:39.723104 sshd-session[8887]: pam_unix(sshd:session): session closed for user core Sep 16 04:57:39.728247 systemd-logind[1889]: Session 25 logged out. Waiting for processes to exit. Sep 16 04:57:39.729585 systemd[1]: sshd@22-10.200.8.11:22-10.200.16.10:51946.service: Deactivated successfully. Sep 16 04:57:39.732970 systemd[1]: session-25.scope: Deactivated successfully. Sep 16 04:57:39.735732 systemd-logind[1889]: Removed session 25. Sep 16 04:57:49.847565 systemd[1]: Started sshd@23-10.200.8.11:22-10.200.16.10:45154.service - OpenSSH per-connection server daemon (10.200.16.10:45154). Sep 16 04:57:50.491818 sshd[12976]: Accepted publickey for core from 10.200.16.10 port 45154 ssh2: RSA SHA256:h3fCh5TnNv3vJ3YKGDHm1APBHQN5CK8MTvNYIQHQseI Sep 16 04:57:50.491812 sshd-session[12976]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:57:50.496838 systemd-logind[1889]: New session 26 of user core. Sep 16 04:57:50.503070 systemd[1]: Started session-26.scope - Session 26 of User core. Sep 16 04:57:50.941452 sshd[13238]: Connection closed by 10.200.16.10 port 45154 Sep 16 04:57:50.944188 sshd-session[12976]: pam_unix(sshd:session): session closed for user core Sep 16 04:57:50.947836 systemd-logind[1889]: Session 26 logged out. Waiting for processes to exit. Sep 16 04:57:50.949457 systemd[1]: sshd@23-10.200.8.11:22-10.200.16.10:45154.service: Deactivated successfully. Sep 16 04:57:50.951947 systemd[1]: session-26.scope: Deactivated successfully. Sep 16 04:57:50.955374 systemd-logind[1889]: Removed session 26. Sep 16 04:57:51.064278 systemd[1]: Started sshd@24-10.200.8.11:22-10.200.16.10:56168.service - OpenSSH per-connection server daemon (10.200.16.10:56168). Sep 16 04:57:51.713117 sshd[13429]: Accepted publickey for core from 10.200.16.10 port 56168 ssh2: RSA SHA256:h3fCh5TnNv3vJ3YKGDHm1APBHQN5CK8MTvNYIQHQseI Sep 16 04:57:51.714904 sshd-session[13429]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:57:51.720522 systemd-logind[1889]: New session 27 of user core. Sep 16 04:57:51.729240 systemd[1]: Started session-27.scope - Session 27 of User core. Sep 16 04:57:52.160351 sshd[13726]: Connection closed by 10.200.16.10 port 56168 Sep 16 04:57:52.160924 sshd-session[13429]: pam_unix(sshd:session): session closed for user core Sep 16 04:57:52.166857 systemd-logind[1889]: Session 27 logged out. Waiting for processes to exit. Sep 16 04:57:52.167481 systemd[1]: sshd@24-10.200.8.11:22-10.200.16.10:56168.service: Deactivated successfully. Sep 16 04:57:52.169110 systemd[1]: session-27.scope: Deactivated successfully. Sep 16 04:57:52.175123 systemd-logind[1889]: Removed session 27. Sep 16 04:58:02.269071 systemd[1]: Started sshd@25-10.200.8.11:22-10.200.16.10:55750.service - OpenSSH per-connection server daemon (10.200.16.10:55750). Sep 16 04:58:02.918893 sshd[14484]: Accepted publickey for core from 10.200.16.10 port 55750 ssh2: RSA SHA256:h3fCh5TnNv3vJ3YKGDHm1APBHQN5CK8MTvNYIQHQseI Sep 16 04:58:02.920685 sshd-session[14484]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:58:02.929060 systemd-logind[1889]: New session 28 of user core. Sep 16 04:58:02.936461 systemd[1]: Started session-28.scope - Session 28 of User core. Sep 16 04:58:03.372477 sshd[14505]: Connection closed by 10.200.16.10 port 55750 Sep 16 04:58:03.375984 sshd-session[14484]: pam_unix(sshd:session): session closed for user core Sep 16 04:58:03.379576 systemd-logind[1889]: Session 28 logged out. Waiting for processes to exit. Sep 16 04:58:03.380981 systemd[1]: sshd@25-10.200.8.11:22-10.200.16.10:55750.service: Deactivated successfully. Sep 16 04:58:03.382705 systemd[1]: session-28.scope: Deactivated successfully. Sep 16 04:58:03.387323 systemd-logind[1889]: Removed session 28. Sep 16 04:58:03.486485 systemd[1]: Started sshd@26-10.200.8.11:22-10.200.16.10:55756.service - OpenSSH per-connection server daemon (10.200.16.10:55756). Sep 16 04:58:04.146788 sshd[14535]: Accepted publickey for core from 10.200.16.10 port 55756 ssh2: RSA SHA256:h3fCh5TnNv3vJ3YKGDHm1APBHQN5CK8MTvNYIQHQseI Sep 16 04:58:04.150230 sshd-session[14535]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:58:04.156447 systemd-logind[1889]: New session 29 of user core. Sep 16 04:58:04.167531 systemd[1]: Started session-29.scope - Session 29 of User core. Sep 16 04:58:04.599063 sshd[14562]: Connection closed by 10.200.16.10 port 55756 Sep 16 04:58:04.599618 sshd-session[14535]: pam_unix(sshd:session): session closed for user core Sep 16 04:58:04.603970 systemd-logind[1889]: Session 29 logged out. Waiting for processes to exit. Sep 16 04:58:04.604609 systemd[1]: sshd@26-10.200.8.11:22-10.200.16.10:55756.service: Deactivated successfully. Sep 16 04:58:04.606328 systemd[1]: session-29.scope: Deactivated successfully. Sep 16 04:58:04.611958 systemd-logind[1889]: Removed session 29. Sep 16 04:58:14.715046 systemd[1]: Started sshd@27-10.200.8.11:22-10.200.16.10:44006.service - OpenSSH per-connection server daemon (10.200.16.10:44006). Sep 16 04:58:15.363245 sshd[14898]: Accepted publickey for core from 10.200.16.10 port 44006 ssh2: RSA SHA256:h3fCh5TnNv3vJ3YKGDHm1APBHQN5CK8MTvNYIQHQseI Sep 16 04:58:15.366758 sshd-session[14898]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:58:15.375813 systemd-logind[1889]: New session 30 of user core. Sep 16 04:58:15.383236 systemd[1]: Started session-30.scope - Session 30 of User core. Sep 16 04:58:15.810526 sshd[14914]: Connection closed by 10.200.16.10 port 44006 Sep 16 04:58:15.810984 sshd-session[14898]: pam_unix(sshd:session): session closed for user core Sep 16 04:58:15.814939 systemd[1]: sshd@27-10.200.8.11:22-10.200.16.10:44006.service: Deactivated successfully. Sep 16 04:58:15.818660 systemd[1]: session-30.scope: Deactivated successfully. Sep 16 04:58:15.819723 systemd-logind[1889]: Session 30 logged out. Waiting for processes to exit. Sep 16 04:58:15.821970 systemd-logind[1889]: Removed session 30. Sep 16 04:58:15.927036 systemd[1]: Started sshd@28-10.200.8.11:22-10.200.16.10:44010.service - OpenSSH per-connection server daemon (10.200.16.10:44010). Sep 16 04:58:16.582026 sshd[14943]: Accepted publickey for core from 10.200.16.10 port 44010 ssh2: RSA SHA256:h3fCh5TnNv3vJ3YKGDHm1APBHQN5CK8MTvNYIQHQseI Sep 16 04:58:16.584718 sshd-session[14943]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:58:16.592877 systemd-logind[1889]: New session 31 of user core. Sep 16 04:58:16.600998 systemd[1]: Started session-31.scope - Session 31 of User core. Sep 16 04:58:17.030390 sshd[14970]: Connection closed by 10.200.16.10 port 44010 Sep 16 04:58:17.030784 sshd-session[14943]: pam_unix(sshd:session): session closed for user core Sep 16 04:58:17.035443 systemd[1]: sshd@28-10.200.8.11:22-10.200.16.10:44010.service: Deactivated successfully. Sep 16 04:58:17.037243 systemd-logind[1889]: Session 31 logged out. Waiting for processes to exit. Sep 16 04:58:17.039065 systemd[1]: session-31.scope: Deactivated successfully. Sep 16 04:58:17.041367 systemd-logind[1889]: Removed session 31. Sep 16 04:58:27.145143 systemd[1]: Started sshd@29-10.200.8.11:22-10.200.16.10:59754.service - OpenSSH per-connection server daemon (10.200.16.10:59754). Sep 16 04:58:27.528435 update_engine[1892]: I20250916 04:58:27.528369 1892 prefs.cc:52] certificate-report-to-send-update not present in /var/lib/update_engine/prefs Sep 16 04:58:27.528435 update_engine[1892]: I20250916 04:58:27.528419 1892 prefs.cc:52] certificate-report-to-send-download not present in /var/lib/update_engine/prefs Sep 16 04:58:27.528880 update_engine[1892]: I20250916 04:58:27.528586 1892 prefs.cc:52] aleph-version not present in /var/lib/update_engine/prefs Sep 16 04:58:27.529347 update_engine[1892]: I20250916 04:58:27.529320 1892 omaha_request_params.cc:62] Current group set to developer Sep 16 04:58:27.529459 update_engine[1892]: I20250916 04:58:27.529426 1892 update_attempter.cc:499] Already updated boot flags. Skipping. Sep 16 04:58:27.529459 update_engine[1892]: I20250916 04:58:27.529443 1892 update_attempter.cc:643] Scheduling an action processor start. Sep 16 04:58:27.529459 update_engine[1892]: I20250916 04:58:27.529460 1892 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Sep 16 04:58:27.529579 update_engine[1892]: I20250916 04:58:27.529491 1892 prefs.cc:52] previous-version not present in /var/lib/update_engine/prefs Sep 16 04:58:27.529579 update_engine[1892]: I20250916 04:58:27.529553 1892 omaha_request_action.cc:271] Posting an Omaha request to disabled Sep 16 04:58:27.529579 update_engine[1892]: I20250916 04:58:27.529564 1892 omaha_request_action.cc:272] Request: Sep 16 04:58:27.529579 update_engine[1892]: Sep 16 04:58:27.529579 update_engine[1892]: Sep 16 04:58:27.529579 update_engine[1892]: Sep 16 04:58:27.529579 update_engine[1892]: Sep 16 04:58:27.529579 update_engine[1892]: Sep 16 04:58:27.529579 update_engine[1892]: Sep 16 04:58:27.529579 update_engine[1892]: Sep 16 04:58:27.529579 update_engine[1892]: Sep 16 04:58:27.529579 update_engine[1892]: I20250916 04:58:27.529572 1892 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Sep 16 04:58:27.530069 locksmithd[2031]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_CHECKING_FOR_UPDATE" NewVersion=0.0.0 NewSize=0 Sep 16 04:58:27.530563 update_engine[1892]: I20250916 04:58:27.530534 1892 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Sep 16 04:58:27.531215 update_engine[1892]: I20250916 04:58:27.531186 1892 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Sep 16 04:58:27.551944 update_engine[1892]: E20250916 04:58:27.551904 1892 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Sep 16 04:58:27.552004 update_engine[1892]: I20250916 04:58:27.551970 1892 libcurl_http_fetcher.cc:283] No HTTP response, retry 1 Sep 16 04:58:27.778167 sshd[15267]: Accepted publickey for core from 10.200.16.10 port 59754 ssh2: RSA SHA256:h3fCh5TnNv3vJ3YKGDHm1APBHQN5CK8MTvNYIQHQseI Sep 16 04:58:27.779433 sshd-session[15267]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:58:27.783725 systemd-logind[1889]: New session 32 of user core. Sep 16 04:58:27.794911 systemd[1]: Started session-32.scope - Session 32 of User core. Sep 16 04:58:28.224668 sshd[15270]: Connection closed by 10.200.16.10 port 59754 Sep 16 04:58:28.225054 sshd-session[15267]: pam_unix(sshd:session): session closed for user core Sep 16 04:58:28.228624 systemd[1]: sshd@29-10.200.8.11:22-10.200.16.10:59754.service: Deactivated successfully. Sep 16 04:58:28.230270 systemd[1]: session-32.scope: Deactivated successfully. Sep 16 04:58:28.230948 systemd-logind[1889]: Session 32 logged out. Waiting for processes to exit. Sep 16 04:58:28.231858 systemd-logind[1889]: Removed session 32. Sep 16 04:58:28.339022 systemd[1]: Started sshd@30-10.200.8.11:22-10.200.16.10:59766.service - OpenSSH per-connection server daemon (10.200.16.10:59766). Sep 16 04:58:28.968331 sshd[15276]: Accepted publickey for core from 10.200.16.10 port 59766 ssh2: RSA SHA256:h3fCh5TnNv3vJ3YKGDHm1APBHQN5CK8MTvNYIQHQseI Sep 16 04:58:28.969564 sshd-session[15276]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:58:28.973694 systemd-logind[1889]: New session 33 of user core. Sep 16 04:58:28.983942 systemd[1]: Started session-33.scope - Session 33 of User core. Sep 16 04:58:29.419948 sshd[15279]: Connection closed by 10.200.16.10 port 59766 Sep 16 04:58:29.420330 sshd-session[15276]: pam_unix(sshd:session): session closed for user core Sep 16 04:58:29.423758 systemd[1]: sshd@30-10.200.8.11:22-10.200.16.10:59766.service: Deactivated successfully. Sep 16 04:58:29.425336 systemd[1]: session-33.scope: Deactivated successfully. Sep 16 04:58:29.426032 systemd-logind[1889]: Session 33 logged out. Waiting for processes to exit. Sep 16 04:58:29.426944 systemd-logind[1889]: Removed session 33. Sep 16 04:58:33.745918 setup-nvidia[2604]: \u000d Sep 16 04:58:33.745918 setup-nvidia[2604]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Sep 16 04:58:33.745918 setup-nvidia[2604]: frequently when this kernel module was built against the wrong or Sep 16 04:58:33.745918 setup-nvidia[2604]: improperly configured kernel sources, with a version of gcc that differs Sep 16 04:58:33.745918 setup-nvidia[2604]: from the one used to build the target kernel, or if another driver, such Sep 16 04:58:33.745918 setup-nvidia[2604]: as nouveau, is present and prevents the NVIDIA kernel module from Sep 16 04:58:33.745918 setup-nvidia[2604]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Sep 16 04:58:33.745918 setup-nvidia[2604]: installed in this system is supported by this NVIDIA Linux graphics Sep 16 04:58:33.745918 setup-nvidia[2604]: driver release. Sep 16 04:58:33.745918 setup-nvidia[2604]: \u000d Sep 16 04:58:33.745918 setup-nvidia[2604]: Please see the log entries 'Kernel module load error' and 'Kernel Sep 16 04:58:33.745918 setup-nvidia[2604]: messages' at the end of the file Sep 16 04:58:33.745918 setup-nvidia[2604]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for more Sep 16 04:58:33.745918 setup-nvidia[2604]: information. Sep 16 04:58:33.745918 setup-nvidia[2604]: \u000d Sep 16 04:58:33.760396 setup-nvidia[2604]: dmesg: read kernel buffer failed: Operation not permitted Sep 16 04:58:33.778515 setup-nvidia[2604]: \u000d Sep 16 04:58:33.778515 setup-nvidia[2604]: ERROR: Installation has failed. Please see the file Sep 16 04:58:33.778515 setup-nvidia[2604]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for Sep 16 04:58:33.778515 setup-nvidia[2604]: details. You may find suggestions on fixing installation problems in Sep 16 04:58:33.778515 setup-nvidia[2604]: the README available on the Linux driver download page at Sep 16 04:58:33.778515 setup-nvidia[2604]: www.nvidia.com. Sep 16 04:58:33.778515 setup-nvidia[2604]: \u000d Sep 16 04:58:33.779063 setup-nvidia[2604]: Last 50 lines of nvidia-installer.log: Sep 16 04:58:33.779878 setup-nvidia[2604]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039059rm+0x53: 'naked' return found in MITIGATION_RETHUNK build Sep 16 04:58:33.779878 setup-nvidia[2604]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039056rm+0x90: 'naked' return found in MITIGATION_RETHUNK build Sep 16 04:58:33.779878 setup-nvidia[2604]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039049rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build Sep 16 04:58:33.779878 setup-nvidia[2604]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039105rm+0x93: 'naked' return found in MITIGATION_RETHUNK build Sep 16 04:58:33.779878 setup-nvidia[2604]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039101rm+0x36: 'naked' return found in MITIGATION_RETHUNK build Sep 16 04:58:33.779878 setup-nvidia[2604]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039100rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build Sep 16 04:58:33.779878 setup-nvidia[2604]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039102rm+0x77: 'naked' return found in MITIGATION_RETHUNK build Sep 16 04:58:33.779878 setup-nvidia[2604]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039046rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build Sep 16 04:58:33.779878 setup-nvidia[2604]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030615rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build Sep 16 04:58:33.779878 setup-nvidia[2604]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030614rm+0x27: 'naked' return found in MITIGATION_RETHUNK build Sep 16 04:58:33.779878 setup-nvidia[2604]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030618rm+0x37: 'naked' return found in MITIGATION_RETHUNK build Sep 16 04:58:33.779878 setup-nvidia[2604]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030617rm+0x24: 'naked' return found in MITIGATION_RETHUNK build Sep 16 04:58:33.779878 setup-nvidia[2604]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030616rm+0xca: 'naked' return found in MITIGATION_RETHUNK build Sep 16 04:58:33.779878 setup-nvidia[2604]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030619rm+0x193: 'naked' return found in MITIGATION_RETHUNK build Sep 16 04:58:33.779878 setup-nvidia[2604]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030621rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build Sep 16 04:58:33.779878 setup-nvidia[2604]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv011864rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build Sep 16 04:58:33.779878 setup-nvidia[2604]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039413rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build Sep 16 04:58:33.779878 setup-nvidia[2604]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/Module.symvers Sep 16 04:58:33.779878 setup-nvidia[2604]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.mod.o Sep 16 04:58:33.779878 setup-nvidia[2604]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/.module-common.o Sep 16 04:58:33.779878 setup-nvidia[2604]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.mod.o Sep 16 04:58:33.779878 setup-nvidia[2604]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.mod.o Sep 16 04:58:33.779878 setup-nvidia[2604]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.mod.o Sep 16 04:58:33.779878 setup-nvidia[2604]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.mod.o Sep 16 04:58:33.779878 setup-nvidia[2604]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Sep 16 04:58:33.779878 setup-nvidia[2604]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Sep 16 04:58:33.779878 setup-nvidia[2604]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Sep 16 04:58:33.779878 setup-nvidia[2604]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Sep 16 04:58:33.779878 setup-nvidia[2604]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Sep 16 04:58:33.779878 setup-nvidia[2604]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko due to unavailability of vmlinux Sep 16 04:58:33.780713 setup-nvidia[2604]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Sep 16 04:58:33.780713 setup-nvidia[2604]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko due to unavailability of vmlinux Sep 16 04:58:33.780713 setup-nvidia[2604]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Sep 16 04:58:33.780713 setup-nvidia[2604]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Sep 16 04:58:33.780713 setup-nvidia[2604]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko due to unavailability of vmlinux Sep 16 04:58:33.780713 setup-nvidia[2604]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Sep 16 04:58:33.780713 setup-nvidia[2604]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko due to unavailability of vmlinux Sep 16 04:58:33.780713 setup-nvidia[2604]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Sep 16 04:58:33.780713 setup-nvidia[2604]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko due to unavailability of vmlinux Sep 16 04:58:33.780713 setup-nvidia[2604]: make[2]: Leaving directory '/usr/lib/modules/6.12.47-flatcar/build' Sep 16 04:58:33.780713 setup-nvidia[2604]: make[1]: Leaving directory '/usr/lib/modules/6.12.47-flatcar/source' Sep 16 04:58:33.780713 setup-nvidia[2604]: -> done. Sep 16 04:58:33.780713 setup-nvidia[2604]: -> Kernel module compilation complete. Sep 16 04:58:33.780713 setup-nvidia[2604]: -> Unable to determine if Secure Boot is enabled: No such file or directory Sep 16 04:58:33.780713 setup-nvidia[2604]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most frequently when this kernel module was built against the wrong or improperly configured kernel sources, with a version of gcc that differs from the one used to build the target kernel, or if another driver, such as nouveau, is present and prevents the NVIDIA kernel module from obtaining ownership of the NVIDIA device(s), or no NVIDIA device installed in this system is supported by this NVIDIA Linux graphics driver release. Sep 16 04:58:33.780713 setup-nvidia[2604]: \u000d Sep 16 04:58:33.780713 setup-nvidia[2604]: 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. Sep 16 04:58:33.780713 setup-nvidia[2604]: -> Kernel module load error: Operation not permitted Sep 16 04:58:33.780713 setup-nvidia[2604]: -> Kernel messages: Sep 16 04:58:33.780713 setup-nvidia[2604]: 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. Sep 16 04:58:37.521926 update_engine[1892]: I20250916 04:58:37.521839 1892 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Sep 16 04:58:37.522333 update_engine[1892]: I20250916 04:58:37.521941 1892 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Sep 16 04:58:37.522333 update_engine[1892]: I20250916 04:58:37.522270 1892 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Sep 16 04:58:37.543885 update_engine[1892]: E20250916 04:58:37.543846 1892 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Sep 16 04:58:37.543942 update_engine[1892]: I20250916 04:58:37.543922 1892 libcurl_http_fetcher.cc:283] No HTTP response, retry 2 Sep 16 04:58:39.531116 systemd[1]: Started sshd@31-10.200.8.11:22-10.200.16.10:32952.service - OpenSSH per-connection server daemon (10.200.16.10:32952). Sep 16 04:58:40.159912 sshd[15465]: Accepted publickey for core from 10.200.16.10 port 32952 ssh2: RSA SHA256:h3fCh5TnNv3vJ3YKGDHm1APBHQN5CK8MTvNYIQHQseI Sep 16 04:58:40.161122 sshd-session[15465]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:58:40.165947 systemd-logind[1889]: New session 34 of user core. Sep 16 04:58:40.189935 systemd[1]: Started session-34.scope - Session 34 of User core. Sep 16 04:58:40.603765 sshd[15468]: Connection closed by 10.200.16.10 port 32952 Sep 16 04:58:40.604080 sshd-session[15465]: pam_unix(sshd:session): session closed for user core Sep 16 04:58:40.607743 systemd[1]: sshd@31-10.200.8.11:22-10.200.16.10:32952.service: Deactivated successfully. Sep 16 04:58:40.609591 systemd[1]: session-34.scope: Deactivated successfully. Sep 16 04:58:40.610418 systemd-logind[1889]: Session 34 logged out. Waiting for processes to exit. Sep 16 04:58:40.611633 systemd-logind[1889]: Removed session 34. Sep 16 04:58:40.718522 systemd[1]: Started sshd@32-10.200.8.11:22-10.200.16.10:55656.service - OpenSSH per-connection server daemon (10.200.16.10:55656). Sep 16 04:58:41.354308 sshd[15474]: Accepted publickey for core from 10.200.16.10 port 55656 ssh2: RSA SHA256:h3fCh5TnNv3vJ3YKGDHm1APBHQN5CK8MTvNYIQHQseI Sep 16 04:58:41.355561 sshd-session[15474]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:58:41.359808 systemd-logind[1889]: New session 35 of user core. Sep 16 04:58:41.373929 systemd[1]: Started session-35.scope - Session 35 of User core. Sep 16 04:58:41.803543 sshd[15477]: Connection closed by 10.200.16.10 port 55656 Sep 16 04:58:41.803912 sshd-session[15474]: pam_unix(sshd:session): session closed for user core Sep 16 04:58:41.807250 systemd[1]: sshd@32-10.200.8.11:22-10.200.16.10:55656.service: Deactivated successfully. Sep 16 04:58:41.808919 systemd[1]: session-35.scope: Deactivated successfully. Sep 16 04:58:41.809572 systemd-logind[1889]: Session 35 logged out. Waiting for processes to exit. Sep 16 04:58:41.810456 systemd-logind[1889]: Removed session 35. Sep 16 04:58:47.523631 update_engine[1892]: I20250916 04:58:47.523546 1892 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Sep 16 04:58:47.524063 update_engine[1892]: I20250916 04:58:47.523643 1892 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Sep 16 04:58:47.524063 update_engine[1892]: I20250916 04:58:47.523990 1892 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Sep 16 04:58:47.539694 update_engine[1892]: E20250916 04:58:47.539654 1892 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Sep 16 04:58:47.539757 update_engine[1892]: I20250916 04:58:47.539708 1892 libcurl_http_fetcher.cc:283] No HTTP response, retry 3 Sep 16 04:58:51.919038 systemd[1]: Started sshd@33-10.200.8.11:22-10.200.16.10:40410.service - OpenSSH per-connection server daemon (10.200.16.10:40410). Sep 16 04:58:52.571109 sshd[15483]: Accepted publickey for core from 10.200.16.10 port 40410 ssh2: RSA SHA256:h3fCh5TnNv3vJ3YKGDHm1APBHQN5CK8MTvNYIQHQseI Sep 16 04:58:52.572366 sshd-session[15483]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:58:52.576805 systemd-logind[1889]: New session 36 of user core. Sep 16 04:58:52.585915 systemd[1]: Started session-36.scope - Session 36 of User core. Sep 16 04:58:53.019306 sshd[15486]: Connection closed by 10.200.16.10 port 40410 Sep 16 04:58:53.019701 sshd-session[15483]: pam_unix(sshd:session): session closed for user core Sep 16 04:58:53.023440 systemd[1]: sshd@33-10.200.8.11:22-10.200.16.10:40410.service: Deactivated successfully. Sep 16 04:58:53.025043 systemd[1]: session-36.scope: Deactivated successfully. Sep 16 04:58:53.025711 systemd-logind[1889]: Session 36 logged out. Waiting for processes to exit. Sep 16 04:58:53.026699 systemd-logind[1889]: Removed session 36. Sep 16 04:58:53.135085 systemd[1]: Started sshd@34-10.200.8.11:22-10.200.16.10:40416.service - OpenSSH per-connection server daemon (10.200.16.10:40416). Sep 16 04:58:53.785608 sshd[15492]: Accepted publickey for core from 10.200.16.10 port 40416 ssh2: RSA SHA256:h3fCh5TnNv3vJ3YKGDHm1APBHQN5CK8MTvNYIQHQseI Sep 16 04:58:53.786906 sshd-session[15492]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:58:53.791129 systemd-logind[1889]: New session 37 of user core. Sep 16 04:58:53.812932 systemd[1]: Started session-37.scope - Session 37 of User core. Sep 16 04:58:54.229773 sshd[15495]: Connection closed by 10.200.16.10 port 40416 Sep 16 04:58:54.230156 sshd-session[15492]: pam_unix(sshd:session): session closed for user core Sep 16 04:58:54.233543 systemd[1]: sshd@34-10.200.8.11:22-10.200.16.10:40416.service: Deactivated successfully. Sep 16 04:58:54.235340 systemd[1]: session-37.scope: Deactivated successfully. Sep 16 04:58:54.236031 systemd-logind[1889]: Session 37 logged out. Waiting for processes to exit. Sep 16 04:58:54.237001 systemd-logind[1889]: Removed session 37. Sep 16 04:58:57.521230 update_engine[1892]: I20250916 04:58:57.521143 1892 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Sep 16 04:58:57.521693 update_engine[1892]: I20250916 04:58:57.521246 1892 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Sep 16 04:58:57.521693 update_engine[1892]: I20250916 04:58:57.521579 1892 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Sep 16 04:58:57.538091 update_engine[1892]: E20250916 04:58:57.538056 1892 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Sep 16 04:58:57.538151 update_engine[1892]: I20250916 04:58:57.538107 1892 libcurl_http_fetcher.cc:297] Transfer resulted in an error (0), 0 bytes downloaded Sep 16 04:58:57.538151 update_engine[1892]: I20250916 04:58:57.538118 1892 omaha_request_action.cc:617] Omaha request response: Sep 16 04:58:57.538213 update_engine[1892]: E20250916 04:58:57.538187 1892 omaha_request_action.cc:636] Omaha request network transfer failed. Sep 16 04:58:57.538241 update_engine[1892]: I20250916 04:58:57.538209 1892 action_processor.cc:68] ActionProcessor::ActionComplete: OmahaRequestAction action failed. Aborting processing. Sep 16 04:58:57.538241 update_engine[1892]: I20250916 04:58:57.538215 1892 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Sep 16 04:58:57.538241 update_engine[1892]: I20250916 04:58:57.538221 1892 update_attempter.cc:306] Processing Done. Sep 16 04:58:57.538241 update_engine[1892]: E20250916 04:58:57.538235 1892 update_attempter.cc:619] Update failed. Sep 16 04:58:57.538343 update_engine[1892]: I20250916 04:58:57.538241 1892 utils.cc:600] Converting error code 2000 to kActionCodeOmahaErrorInHTTPResponse Sep 16 04:58:57.538343 update_engine[1892]: I20250916 04:58:57.538247 1892 payload_state.cc:97] Updating payload state for error code: 37 (kActionCodeOmahaErrorInHTTPResponse) Sep 16 04:58:57.538343 update_engine[1892]: I20250916 04:58:57.538254 1892 payload_state.cc:103] Ignoring failures until we get a valid Omaha response. Sep 16 04:58:57.538343 update_engine[1892]: I20250916 04:58:57.538319 1892 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Sep 16 04:58:57.538440 update_engine[1892]: I20250916 04:58:57.538344 1892 omaha_request_action.cc:271] Posting an Omaha request to disabled Sep 16 04:58:57.538440 update_engine[1892]: I20250916 04:58:57.538352 1892 omaha_request_action.cc:272] Request: Sep 16 04:58:57.538440 update_engine[1892]: Sep 16 04:58:57.538440 update_engine[1892]: Sep 16 04:58:57.538440 update_engine[1892]: Sep 16 04:58:57.538440 update_engine[1892]: Sep 16 04:58:57.538440 update_engine[1892]: Sep 16 04:58:57.538440 update_engine[1892]: Sep 16 04:58:57.538440 update_engine[1892]: I20250916 04:58:57.538358 1892 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Sep 16 04:58:57.538440 update_engine[1892]: I20250916 04:58:57.538376 1892 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Sep 16 04:58:57.538664 update_engine[1892]: I20250916 04:58:57.538589 1892 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Sep 16 04:58:57.538694 locksmithd[2031]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_REPORTING_ERROR_EVENT" NewVersion=0.0.0 NewSize=0 Sep 16 04:58:57.601903 update_engine[1892]: E20250916 04:58:57.601853 1892 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Sep 16 04:58:57.601987 update_engine[1892]: I20250916 04:58:57.601918 1892 libcurl_http_fetcher.cc:297] Transfer resulted in an error (0), 0 bytes downloaded Sep 16 04:58:57.601987 update_engine[1892]: I20250916 04:58:57.601929 1892 omaha_request_action.cc:617] Omaha request response: Sep 16 04:58:57.601987 update_engine[1892]: I20250916 04:58:57.601938 1892 action_processor.cc:65] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Sep 16 04:58:57.601987 update_engine[1892]: I20250916 04:58:57.601943 1892 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Sep 16 04:58:57.601987 update_engine[1892]: I20250916 04:58:57.601948 1892 update_attempter.cc:306] Processing Done. Sep 16 04:58:57.601987 update_engine[1892]: I20250916 04:58:57.601955 1892 update_attempter.cc:310] Error event sent. Sep 16 04:58:57.601987 update_engine[1892]: I20250916 04:58:57.601964 1892 update_check_scheduler.cc:74] Next update check in 42m48s Sep 16 04:58:57.602325 locksmithd[2031]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_IDLE" NewVersion=0.0.0 NewSize=0 Sep 16 04:59:00.284813 kernel: EXT4-fs (loop6p9): unmounting filesystem d4012d09-7702-4715-a232-7247a60c6128. Sep 16 04:59:00.285458 setup-nvidia[2604]: Container flatcardevelopercontainer-4459.0.0nightly-20250915-2100.bin exited successfully. Sep 16 04:59:00.287777 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4459.0.0nightly\x2d20250915\x2d2100.bin.mount: Deactivated successfully. Sep 16 04:59:00.337594 sudo[2592]: pam_unix(sudo:session): session closed for user root Sep 16 04:59:00.339483 systemd[1]: session-c2.scope: Deactivated successfully. Sep 16 04:59:00.339756 systemd[1]: session-c2.scope: Consumed 5min 18.748s CPU time, 889.2M memory peak. Sep 16 04:59:00.761397 setup-nvidia[1882]: /opt/nvidia / Sep 16 04:59:00.762403 setup-nvidia[1882]: / Sep 16 04:59:00.772814 kernel: IPMI message handler: version 39.2 Sep 16 04:59:00.776812 kernel: ipmi device interface Sep 16 04:59:00.789316 setup-nvidia[1882]: /opt/nvidia/current/usr/lib/modules/6.12.47-flatcar/video / Sep 16 04:59:00.842675 kernel: nvidia: loading out-of-tree module taints kernel. Sep 16 04:59:00.842924 kernel: nvidia: module license 'NVIDIA' taints kernel. Sep 16 04:59:00.843024 kernel: Disabling lock debugging due to kernel taint Sep 16 04:59:00.849797 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Sep 16 04:59:00.849843 kernel: nvidia: module license taints kernel. Sep 16 04:59:01.235821 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 Sep 16 04:59:01.650459 kernel: Sep 16 04:59:01.650617 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Sep 16 04:59:01.856815 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.261.03 Sat Jun 14 16:05:50 UTC 2025 Sep 16 04:59:01.877816 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.261.03 Sat Jun 14 15:39:16 UTC 2025 Sep 16 04:59:01.896829 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Sep 16 04:59:01.951814 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. Sep 16 04:59:01.952052 setup-nvidia[1882]: / Sep 16 04:59:01.966243 setup-nvidia[15535]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 16 04:59:03.767398 setup-nvidia[15552]: Tue Sep 16 04:59:03 2025 Sep 16 04:59:03.767398 setup-nvidia[15552]: +---------------------------------------------------------------------------------------+ Sep 16 04:59:03.767398 setup-nvidia[15552]: | NVIDIA-SMI 535.261.03 Driver Version: 535.261.03 CUDA Version: 12.2 | Sep 16 04:59:03.767398 setup-nvidia[15552]: |-----------------------------------------+----------------------+----------------------+ Sep 16 04:59:03.767398 setup-nvidia[15552]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Sep 16 04:59:03.767398 setup-nvidia[15552]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Sep 16 04:59:03.767398 setup-nvidia[15552]: | | | MIG M. | Sep 16 04:59:03.767398 setup-nvidia[15552]: |=========================================+======================+======================| Sep 16 04:59:03.773591 setup-nvidia[15552]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Sep 16 04:59:03.773591 setup-nvidia[15552]: | N/A 24C P0 35W / 250W | 0MiB / 16384MiB | 0% Default | Sep 16 04:59:03.773591 setup-nvidia[15552]: | | | N/A | Sep 16 04:59:03.773591 setup-nvidia[15552]: +-----------------------------------------+----------------------+----------------------+ Sep 16 04:59:03.773591 setup-nvidia[15552]: Sep 16 04:59:03.773591 setup-nvidia[15552]: +---------------------------------------------------------------------------------------+ Sep 16 04:59:03.773591 setup-nvidia[15552]: | Processes: | Sep 16 04:59:03.773591 setup-nvidia[15552]: | GPU GI CI PID Type Process name GPU Memory | Sep 16 04:59:03.773591 setup-nvidia[15552]: | ID ID Usage | Sep 16 04:59:03.773591 setup-nvidia[15552]: |=======================================================================================| Sep 16 04:59:03.773909 setup-nvidia[15552]: | No running processes found | Sep 16 04:59:03.773909 setup-nvidia[15552]: +---------------------------------------------------------------------------------------+ Sep 16 04:59:04.144712 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 16 04:59:04.149506 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 16 04:59:04.153909 systemd[1]: Startup finished in 1.460s (firmware) + 30.034s (loader) + 3.774s (kernel) + 16.701s (initrd) + 4min 10.160s (userspace) = 5min 2.130s. Sep 16 04:59:04.345097 systemd[1]: Started sshd@35-10.200.8.11:22-10.200.16.10:32890.service - OpenSSH per-connection server daemon (10.200.16.10:32890). Sep 16 04:59:04.996964 sshd[15559]: Accepted publickey for core from 10.200.16.10 port 32890 ssh2: RSA SHA256:h3fCh5TnNv3vJ3YKGDHm1APBHQN5CK8MTvNYIQHQseI Sep 16 04:59:04.998212 sshd-session[15559]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:59:05.002573 systemd-logind[1889]: New session 38 of user core. Sep 16 04:59:05.015920 systemd[1]: Started session-38.scope - Session 38 of User core. Sep 16 04:59:05.442199 sshd[15562]: Connection closed by 10.200.16.10 port 32890 Sep 16 04:59:05.442571 sshd-session[15559]: pam_unix(sshd:session): session closed for user core Sep 16 04:59:05.446338 systemd[1]: sshd@35-10.200.8.11:22-10.200.16.10:32890.service: Deactivated successfully. Sep 16 04:59:05.448121 systemd[1]: session-38.scope: Deactivated successfully. Sep 16 04:59:05.448785 systemd-logind[1889]: Session 38 logged out. Waiting for processes to exit. Sep 16 04:59:05.450023 systemd-logind[1889]: Removed session 38. Sep 16 04:59:05.554189 systemd[1]: Started sshd@36-10.200.8.11:22-10.200.16.10:32896.service - OpenSSH per-connection server daemon (10.200.16.10:32896). Sep 16 04:59:06.205452 sshd[15568]: Accepted publickey for core from 10.200.16.10 port 32896 ssh2: RSA SHA256:h3fCh5TnNv3vJ3YKGDHm1APBHQN5CK8MTvNYIQHQseI Sep 16 04:59:06.206721 sshd-session[15568]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:59:06.211165 systemd-logind[1889]: New session 39 of user core. Sep 16 04:59:06.222912 systemd[1]: Started session-39.scope - Session 39 of User core. Sep 16 04:59:06.648082 sshd[15571]: Connection closed by 10.200.16.10 port 32896 Sep 16 04:59:06.648375 sshd-session[15568]: pam_unix(sshd:session): session closed for user core Sep 16 04:59:06.652004 systemd[1]: sshd@36-10.200.8.11:22-10.200.16.10:32896.service: Deactivated successfully. Sep 16 04:59:06.653687 systemd[1]: session-39.scope: Deactivated successfully. Sep 16 04:59:06.654368 systemd-logind[1889]: Session 39 logged out. Waiting for processes to exit. Sep 16 04:59:06.655295 systemd-logind[1889]: Removed session 39. Sep 16 04:59:06.763096 systemd[1]: Started sshd@37-10.200.8.11:22-10.200.16.10:32898.service - OpenSSH per-connection server daemon (10.200.16.10:32898). Sep 16 04:59:07.400587 sshd[15577]: Accepted publickey for core from 10.200.16.10 port 32898 ssh2: RSA SHA256:h3fCh5TnNv3vJ3YKGDHm1APBHQN5CK8MTvNYIQHQseI Sep 16 04:59:10.412301 (sd-pam)[2596]: pam_unix(systemd-user:session): session closed for user root Sep 16 04:59:10.401902 systemd[1]: Stopping user@0.service - User Manager for UID 0... Sep 16 04:59:10.402687 systemd[2595]: Activating special unit exit.target... Sep 16 04:59:11.661731 sshd-session[15577]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:59:10.402771 systemd[2595]: Stopped target default.target - Main User Target. Sep 16 04:59:10.402832 systemd[2595]: Stopped target basic.target - Basic System. Sep 16 04:59:10.402863 systemd[2595]: Stopped target paths.target - Paths. Sep 16 04:59:10.402890 systemd[2595]: Stopped target sockets.target - Sockets. Sep 16 04:59:10.402916 systemd[2595]: Stopped target timers.target - Timers. Sep 16 04:59:10.403264 systemd[2595]: Closed dbus.socket - D-Bus User Message Bus Socket. Sep 16 04:59:10.403472 systemd[2595]: Removed slice app.slice - User Application Slice. Sep 16 04:59:10.403501 systemd[2595]: Reached target shutdown.target - Shutdown. Sep 16 04:59:10.403644 systemd[2595]: Finished systemd-exit.service - Exit the Session. Sep 16 04:59:10.403675 systemd[2595]: Reached target exit.target - Exit the Session. Sep 16 04:59:10.413579 systemd[1]: user@0.service: Deactivated successfully. Sep 16 04:59:10.414010 systemd[1]: Stopped user@0.service - User Manager for UID 0. Sep 16 04:59:10.416526 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Sep 16 04:59:10.442223 systemd[1]: run-user-0.mount: Deactivated successfully. Sep 16 04:59:10.443177 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Sep 16 04:59:10.443354 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Sep 16 04:59:10.444322 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Sep 16 04:59:10.444391 systemd[1]: user-0.slice: Consumed 5min 18.969s CPU time, 895.3M memory peak. Sep 16 04:59:10.444705 systemd-logind[1889]: Removed session c3. Sep 16 04:59:11.666832 systemd-logind[1889]: New session 40 of user core. Sep 16 04:59:11.679924 systemd[1]: Started session-40.scope - Session 40 of User core. Sep 16 04:59:12.028887 sshd[15585]: Connection closed by 10.200.16.10 port 32898 Sep 16 04:59:12.029186 sshd-session[15577]: pam_unix(sshd:session): session closed for user core Sep 16 04:59:12.032657 systemd[1]: sshd@37-10.200.8.11:22-10.200.16.10:32898.service: Deactivated successfully. Sep 16 04:59:12.034316 systemd[1]: session-40.scope: Deactivated successfully. Sep 16 04:59:12.034975 systemd-logind[1889]: Session 40 logged out. Waiting for processes to exit. Sep 16 04:59:12.035861 systemd-logind[1889]: Removed session 40. Sep 16 04:59:12.152103 systemd[1]: Started sshd@38-10.200.8.11:22-10.200.16.10:53682.service - OpenSSH per-connection server daemon (10.200.16.10:53682). Sep 16 04:59:13.814619 sshd[15592]: Accepted publickey for core from 10.200.16.10 port 53682 ssh2: RSA SHA256:h3fCh5TnNv3vJ3YKGDHm1APBHQN5CK8MTvNYIQHQseI Sep 16 04:59:13.815023 sshd-session[15592]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:59:13.819505 systemd-logind[1889]: New session 41 of user core. Sep 16 04:59:13.828909 systemd[1]: Started session-41.scope - Session 41 of User core. Sep 16 04:59:14.188885 sshd[15595]: Connection closed by 10.200.16.10 port 53682 Sep 16 04:59:14.189221 sshd-session[15592]: pam_unix(sshd:session): session closed for user core Sep 16 04:59:14.192562 systemd[1]: sshd@38-10.200.8.11:22-10.200.16.10:53682.service: Deactivated successfully. Sep 16 04:59:14.194185 systemd[1]: session-41.scope: Deactivated successfully. Sep 16 04:59:14.194909 systemd-logind[1889]: Session 41 logged out. Waiting for processes to exit. Sep 16 04:59:14.195856 systemd-logind[1889]: Removed session 41. Sep 16 04:59:14.311949 systemd[1]: Started sshd@39-10.200.8.11:22-10.200.16.10:53694.service - OpenSSH per-connection server daemon (10.200.16.10:53694). Sep 16 04:59:14.942090 sshd[15602]: Accepted publickey for core from 10.200.16.10 port 53694 ssh2: RSA SHA256:h3fCh5TnNv3vJ3YKGDHm1APBHQN5CK8MTvNYIQHQseI Sep 16 04:59:14.943351 sshd-session[15602]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:59:14.947756 systemd-logind[1889]: New session 42 of user core. Sep 16 04:59:14.955912 systemd[1]: Started session-42.scope - Session 42 of User core. Sep 16 04:59:16.878301 sshd[15605]: Connection closed by 10.200.16.10 port 53694 Sep 16 04:59:16.878705 sshd-session[15602]: pam_unix(sshd:session): session closed for user core Sep 16 04:59:16.882234 systemd[1]: sshd@39-10.200.8.11:22-10.200.16.10:53694.service: Deactivated successfully. Sep 16 04:59:16.883981 systemd[1]: session-42.scope: Deactivated successfully. Sep 16 04:59:16.884181 systemd[1]: session-42.scope: Consumed 1.427s CPU time, 14M memory peak. Sep 16 04:59:16.884664 systemd-logind[1889]: Session 42 logged out. Waiting for processes to exit. Sep 16 04:59:16.885602 systemd-logind[1889]: Removed session 42.