Sep 9 05:35:43.218560 kernel: Linux version 6.12.45-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 9 03:39:34 -00 2025 Sep 9 05:35:43.218589 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=107bc9be805328e5e30844239fa87d36579f371e3de2c34fec43f6ff6d17b104 Sep 9 05:35:43.218598 kernel: BIOS-provided physical RAM map: Sep 9 05:35:43.218605 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Sep 9 05:35:43.218611 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Sep 9 05:35:43.218618 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Sep 9 05:35:43.218638 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Sep 9 05:35:43.218648 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Sep 9 05:35:43.218655 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Sep 9 05:35:43.218661 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Sep 9 05:35:43.218668 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Sep 9 05:35:43.218674 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Sep 9 05:35:43.218681 kernel: printk: legacy bootconsole [earlyser0] enabled Sep 9 05:35:43.218688 kernel: NX (Execute Disable) protection: active Sep 9 05:35:43.218698 kernel: APIC: Static calls initialized Sep 9 05:35:43.218706 kernel: efi: EFI v2.7 by Microsoft Sep 9 05:35:43.218713 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Sep 9 05:35:43.218721 kernel: random: crng init done Sep 9 05:35:43.218728 kernel: secureboot: Secure boot disabled Sep 9 05:35:43.218736 kernel: SMBIOS 3.1.0 present. Sep 9 05:35:43.218743 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Sep 9 05:35:43.218751 kernel: DMI: Memory slots populated: 3/3 Sep 9 05:35:43.218758 kernel: Hypervisor detected: Microsoft Hyper-V Sep 9 05:35:43.218765 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Sep 9 05:35:43.218775 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Sep 9 05:35:43.218782 kernel: Hyper-V: Using hypercall for remote TLB flush Sep 9 05:35:43.218789 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Sep 9 05:35:43.218797 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Sep 9 05:35:43.218805 kernel: tsc: Marking TSC unstable due to running on Hyper-V Sep 9 05:35:43.218813 kernel: tsc: Detected 2593.992 MHz processor Sep 9 05:35:43.218820 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Sep 9 05:35:43.218828 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Sep 9 05:35:43.218835 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Sep 9 05:35:43.218845 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Sep 9 05:35:43.218853 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Sep 9 05:35:43.218861 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Sep 9 05:35:43.218868 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Sep 9 05:35:43.218875 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Sep 9 05:35:43.218882 kernel: Using GB pages for direct mapping Sep 9 05:35:43.218890 kernel: ACPI: Early table checksum verification disabled Sep 9 05:35:43.218901 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Sep 9 05:35:43.218912 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 9 05:35:43.218920 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 9 05:35:43.218928 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Sep 9 05:35:43.218935 kernel: ACPI: FACS 0x000000003FFFE000 000040 Sep 9 05:35:43.218943 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 9 05:35:43.218951 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 9 05:35:43.218961 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 9 05:35:43.218969 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 9 05:35:43.218977 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 9 05:35:43.218985 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 9 05:35:43.218993 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 9 05:35:43.219001 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Sep 9 05:35:43.219009 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Sep 9 05:35:43.219017 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Sep 9 05:35:43.219025 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Sep 9 05:35:43.219035 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Sep 9 05:35:43.219043 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Sep 9 05:35:43.219050 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Sep 9 05:35:43.219058 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Sep 9 05:35:43.219066 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Sep 9 05:35:43.219074 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Sep 9 05:35:43.219082 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Sep 9 05:35:43.219090 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Sep 9 05:35:43.219100 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Sep 9 05:35:43.219108 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Sep 9 05:35:43.219116 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Sep 9 05:35:43.219124 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Sep 9 05:35:43.219131 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Sep 9 05:35:43.219139 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Sep 9 05:35:43.219147 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] Sep 9 05:35:43.219155 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] Sep 9 05:35:43.219163 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] Sep 9 05:35:43.219174 kernel: Zone ranges: Sep 9 05:35:43.219182 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Sep 9 05:35:43.219190 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Sep 9 05:35:43.219197 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Sep 9 05:35:43.219213 kernel: Device empty Sep 9 05:35:43.219221 kernel: Movable zone start for each node Sep 9 05:35:43.219229 kernel: Early memory node ranges Sep 9 05:35:43.219237 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Sep 9 05:35:43.219250 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Sep 9 05:35:43.219269 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Sep 9 05:35:43.219279 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Sep 9 05:35:43.219287 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Sep 9 05:35:43.219294 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Sep 9 05:35:43.219302 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Sep 9 05:35:43.219310 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Sep 9 05:35:43.219318 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Sep 9 05:35:43.219326 kernel: ACPI: PM-Timer IO Port: 0x408 Sep 9 05:35:43.219334 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Sep 9 05:35:43.219345 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Sep 9 05:35:43.219353 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Sep 9 05:35:43.219361 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Sep 9 05:35:43.219369 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Sep 9 05:35:43.219377 kernel: CPU topo: Max. logical packages: 1 Sep 9 05:35:43.219385 kernel: CPU topo: Max. logical dies: 1 Sep 9 05:35:43.219393 kernel: CPU topo: Max. dies per package: 1 Sep 9 05:35:43.219400 kernel: CPU topo: Max. threads per core: 1 Sep 9 05:35:43.219408 kernel: CPU topo: Num. cores per package: 6 Sep 9 05:35:43.219419 kernel: CPU topo: Num. threads per package: 6 Sep 9 05:35:43.219427 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs Sep 9 05:35:43.219435 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Sep 9 05:35:43.219443 kernel: Booting paravirtualized kernel on Hyper-V Sep 9 05:35:43.219451 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Sep 9 05:35:43.219459 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Sep 9 05:35:43.219467 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 Sep 9 05:35:43.219475 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 Sep 9 05:35:43.219482 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Sep 9 05:35:43.219493 kernel: Hyper-V: PV spinlocks enabled Sep 9 05:35:43.219501 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Sep 9 05:35:43.219510 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=107bc9be805328e5e30844239fa87d36579f371e3de2c34fec43f6ff6d17b104 Sep 9 05:35:43.219518 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Sep 9 05:35:43.219526 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Sep 9 05:35:43.219534 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Sep 9 05:35:43.219542 kernel: Fallback order for Node 0: 0 Sep 9 05:35:43.219550 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 Sep 9 05:35:43.219560 kernel: Policy zone: Normal Sep 9 05:35:43.219576 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 9 05:35:43.219584 kernel: software IO TLB: area num 8. Sep 9 05:35:43.219595 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Sep 9 05:35:43.219604 kernel: Kernel/User page tables isolation: enabled Sep 9 05:35:43.219612 kernel: ftrace: allocating 40102 entries in 157 pages Sep 9 05:35:43.219628 kernel: ftrace: allocated 157 pages with 5 groups Sep 9 05:35:43.219638 kernel: Dynamic Preempt: voluntary Sep 9 05:35:43.219646 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 9 05:35:43.219655 kernel: rcu: RCU event tracing is enabled. Sep 9 05:35:43.219663 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Sep 9 05:35:43.219672 kernel: Trampoline variant of Tasks RCU enabled. Sep 9 05:35:43.219687 kernel: Rude variant of Tasks RCU enabled. Sep 9 05:35:43.219696 kernel: Tracing variant of Tasks RCU enabled. Sep 9 05:35:43.219704 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 9 05:35:43.219713 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Sep 9 05:35:43.219721 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Sep 9 05:35:43.219732 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Sep 9 05:35:43.219741 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Sep 9 05:35:43.219749 kernel: Using NULL legacy PIC Sep 9 05:35:43.219757 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Sep 9 05:35:43.219766 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 9 05:35:43.219774 kernel: Console: colour dummy device 80x25 Sep 9 05:35:43.219783 kernel: printk: legacy console [tty1] enabled Sep 9 05:35:43.219791 kernel: printk: legacy console [ttyS0] enabled Sep 9 05:35:43.219799 kernel: printk: legacy bootconsole [earlyser0] disabled Sep 9 05:35:43.219810 kernel: ACPI: Core revision 20240827 Sep 9 05:35:43.219818 kernel: Failed to register legacy timer interrupt Sep 9 05:35:43.219827 kernel: APIC: Switch to symmetric I/O mode setup Sep 9 05:35:43.219835 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Sep 9 05:35:43.219843 kernel: Hyper-V: enabling crash_kexec_post_notifiers Sep 9 05:35:43.219852 kernel: Hyper-V: Using IPI hypercalls Sep 9 05:35:43.219860 kernel: APIC: send_IPI() replaced with hv_send_ipi() Sep 9 05:35:43.219868 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Sep 9 05:35:43.219877 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Sep 9 05:35:43.219888 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Sep 9 05:35:43.219896 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Sep 9 05:35:43.219905 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Sep 9 05:35:43.219913 kernel: Hyper-V: Using enlightened APIC (xapic mode) Sep 9 05:35:43.219922 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Sep 9 05:35:43.219930 kernel: APIC: read() replaced with hv_apic_read() Sep 9 05:35:43.219938 kernel: APIC: write() replaced with hv_apic_write() Sep 9 05:35:43.219947 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Sep 9 05:35:43.219960 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Sep 9 05:35:43.219976 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593992) Sep 9 05:35:43.219990 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Sep 9 05:35:43.220002 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Sep 9 05:35:43.220014 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Sep 9 05:35:43.220022 kernel: Spectre V2 : Mitigation: Retpolines Sep 9 05:35:43.220030 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Sep 9 05:35:43.220038 kernel: Speculative Store Bypass: Vulnerable Sep 9 05:35:43.220046 kernel: MDS: Mitigation: Clear CPU buffers Sep 9 05:35:43.220054 kernel: TAA: Mitigation: Clear CPU buffers Sep 9 05:35:43.220063 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Sep 9 05:35:43.220073 kernel: active return thunk: its_return_thunk Sep 9 05:35:43.220081 kernel: ITS: Mitigation: Aligned branch/return thunks Sep 9 05:35:43.220090 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Sep 9 05:35:43.220098 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Sep 9 05:35:43.220106 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Sep 9 05:35:43.220114 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Sep 9 05:35:43.220123 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Sep 9 05:35:43.220131 kernel: Freeing SMP alternatives memory: 32K Sep 9 05:35:43.220139 kernel: pid_max: default: 32768 minimum: 301 Sep 9 05:35:43.220147 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Sep 9 05:35:43.220156 kernel: landlock: Up and running. Sep 9 05:35:43.220166 kernel: SELinux: Initializing. Sep 9 05:35:43.220175 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Sep 9 05:35:43.220183 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Sep 9 05:35:43.220191 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Sep 9 05:35:43.220200 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Sep 9 05:35:43.220208 kernel: signal: max sigframe size: 1776 Sep 9 05:35:43.220221 kernel: rcu: Hierarchical SRCU implementation. Sep 9 05:35:43.220232 kernel: rcu: Max phase no-delay instances is 400. Sep 9 05:35:43.220245 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Sep 9 05:35:43.220254 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Sep 9 05:35:43.220268 kernel: smp: Bringing up secondary CPUs ... Sep 9 05:35:43.220277 kernel: smpboot: x86: Booting SMP configuration: Sep 9 05:35:43.220285 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Sep 9 05:35:43.220293 kernel: smp: Brought up 1 node, 6 CPUs Sep 9 05:35:43.220302 kernel: smpboot: Total of 6 processors activated (31127.90 BogoMIPS) Sep 9 05:35:43.220311 kernel: Memory: 115339096K/117439364K available (14336K kernel code, 2428K rwdata, 9988K rodata, 54076K init, 2892K bss, 2090000K reserved, 0K cma-reserved) Sep 9 05:35:43.220319 kernel: devtmpfs: initialized Sep 9 05:35:43.220328 kernel: x86/mm: Memory block size: 1024MB Sep 9 05:35:43.220336 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Sep 9 05:35:43.220347 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 9 05:35:43.220356 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Sep 9 05:35:43.220364 kernel: pinctrl core: initialized pinctrl subsystem Sep 9 05:35:43.220372 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 9 05:35:43.220381 kernel: audit: initializing netlink subsys (disabled) Sep 9 05:35:43.220389 kernel: audit: type=2000 audit(1757396139.028:1): state=initialized audit_enabled=0 res=1 Sep 9 05:35:43.220397 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 9 05:35:43.220406 kernel: thermal_sys: Registered thermal governor 'user_space' Sep 9 05:35:43.220414 kernel: cpuidle: using governor menu Sep 9 05:35:43.220425 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 9 05:35:43.220433 kernel: dca service started, version 1.12.1 Sep 9 05:35:43.220442 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Sep 9 05:35:43.220450 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Sep 9 05:35:43.220458 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 9 05:35:43.220467 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Sep 9 05:35:43.220475 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 9 05:35:43.220483 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Sep 9 05:35:43.220492 kernel: ACPI: Added _OSI(Module Device) Sep 9 05:35:43.220502 kernel: ACPI: Added _OSI(Processor Device) Sep 9 05:35:43.220511 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 9 05:35:43.220519 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 9 05:35:43.220527 kernel: ACPI: Interpreter enabled Sep 9 05:35:43.220536 kernel: ACPI: PM: (supports S0 S5) Sep 9 05:35:43.220544 kernel: ACPI: Using IOAPIC for interrupt routing Sep 9 05:35:43.220552 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Sep 9 05:35:43.220561 kernel: PCI: Ignoring E820 reservations for host bridge windows Sep 9 05:35:43.220569 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Sep 9 05:35:43.220580 kernel: iommu: Default domain type: Translated Sep 9 05:35:43.220588 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Sep 9 05:35:43.220597 kernel: efivars: Registered efivars operations Sep 9 05:35:43.220605 kernel: PCI: Using ACPI for IRQ routing Sep 9 05:35:43.220613 kernel: PCI: System does not support PCI Sep 9 05:35:43.220630 kernel: vgaarb: loaded Sep 9 05:35:43.220639 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Sep 9 05:35:43.220647 kernel: VFS: Disk quotas dquot_6.6.0 Sep 9 05:35:43.220656 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 9 05:35:43.220667 kernel: pnp: PnP ACPI init Sep 9 05:35:43.220676 kernel: pnp: PnP ACPI: found 3 devices Sep 9 05:35:43.220684 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Sep 9 05:35:43.220693 kernel: NET: Registered PF_INET protocol family Sep 9 05:35:43.220701 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 9 05:35:43.220709 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Sep 9 05:35:43.220718 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 9 05:35:43.220744 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 9 05:35:43.220753 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Sep 9 05:35:43.220764 kernel: TCP: Hash tables configured (established 524288 bind 65536) Sep 9 05:35:43.220773 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Sep 9 05:35:43.220781 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Sep 9 05:35:43.220789 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 9 05:35:43.220798 kernel: NET: Registered PF_XDP protocol family Sep 9 05:35:43.220806 kernel: PCI: CLS 0 bytes, default 64 Sep 9 05:35:43.220814 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Sep 9 05:35:43.220823 kernel: software IO TLB: mapped [mem 0x000000003ae61000-0x000000003ee61000] (64MB) Sep 9 05:35:43.220831 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Sep 9 05:35:43.220842 kernel: Initialise system trusted keyrings Sep 9 05:35:43.220850 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Sep 9 05:35:43.220859 kernel: Key type asymmetric registered Sep 9 05:35:43.220867 kernel: Asymmetric key parser 'x509' registered Sep 9 05:35:43.220875 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Sep 9 05:35:43.220883 kernel: io scheduler mq-deadline registered Sep 9 05:35:43.220892 kernel: io scheduler kyber registered Sep 9 05:35:43.220900 kernel: io scheduler bfq registered Sep 9 05:35:43.220908 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Sep 9 05:35:43.220919 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 9 05:35:43.220927 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Sep 9 05:35:43.220936 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Sep 9 05:35:43.220944 kernel: i8042: PNP: No PS/2 controller found. Sep 9 05:35:43.221102 kernel: rtc_cmos 00:02: registered as rtc0 Sep 9 05:35:43.221207 kernel: rtc_cmos 00:02: setting system clock to 2025-09-09T05:35:42 UTC (1757396142) Sep 9 05:35:43.221304 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Sep 9 05:35:43.221327 kernel: intel_pstate: CPU model not supported Sep 9 05:35:43.221337 kernel: efifb: probing for efifb Sep 9 05:35:43.221346 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Sep 9 05:35:43.221354 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Sep 9 05:35:43.221363 kernel: efifb: scrolling: redraw Sep 9 05:35:43.221371 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Sep 9 05:35:43.221380 kernel: Console: switching to colour frame buffer device 128x48 Sep 9 05:35:43.221388 kernel: fb0: EFI VGA frame buffer device Sep 9 05:35:43.221396 kernel: pstore: Using crash dump compression: deflate Sep 9 05:35:43.221408 kernel: pstore: Registered efi_pstore as persistent store backend Sep 9 05:35:43.221417 kernel: NET: Registered PF_INET6 protocol family Sep 9 05:35:43.221425 kernel: Segment Routing with IPv6 Sep 9 05:35:43.221433 kernel: In-situ OAM (IOAM) with IPv6 Sep 9 05:35:43.221442 kernel: NET: Registered PF_PACKET protocol family Sep 9 05:35:43.221450 kernel: Key type dns_resolver registered Sep 9 05:35:43.221459 kernel: IPI shorthand broadcast: enabled Sep 9 05:35:43.221467 kernel: sched_clock: Marking stable (3665004400, 107014600)->(4100293500, -328274500) Sep 9 05:35:43.221475 kernel: registered taskstats version 1 Sep 9 05:35:43.221484 kernel: Loading compiled-in X.509 certificates Sep 9 05:35:43.221495 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.45-flatcar: 884b9ad6a330f59ae6e6488b20a5491e41ff24a3' Sep 9 05:35:43.221503 kernel: Demotion targets for Node 0: null Sep 9 05:35:43.221512 kernel: Key type .fscrypt registered Sep 9 05:35:43.221520 kernel: Key type fscrypt-provisioning registered Sep 9 05:35:43.221528 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 9 05:35:43.221537 kernel: ima: Allocated hash algorithm: sha1 Sep 9 05:35:43.221545 kernel: ima: No architecture policies found Sep 9 05:35:43.221553 kernel: clk: Disabling unused clocks Sep 9 05:35:43.221564 kernel: Warning: unable to open an initial console. Sep 9 05:35:43.221572 kernel: Freeing unused kernel image (initmem) memory: 54076K Sep 9 05:35:43.221581 kernel: Write protecting the kernel read-only data: 24576k Sep 9 05:35:43.221589 kernel: Freeing unused kernel image (rodata/data gap) memory: 252K Sep 9 05:35:43.221597 kernel: Run /init as init process Sep 9 05:35:43.221606 kernel: with arguments: Sep 9 05:35:43.221614 kernel: /init Sep 9 05:35:43.221656 kernel: with environment: Sep 9 05:35:43.221665 kernel: HOME=/ Sep 9 05:35:43.221677 kernel: TERM=linux Sep 9 05:35:43.221686 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 9 05:35:43.221695 systemd[1]: Successfully made /usr/ read-only. Sep 9 05:35:43.221707 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 9 05:35:43.221716 systemd[1]: Detected virtualization microsoft. Sep 9 05:35:43.221725 systemd[1]: Detected architecture x86-64. Sep 9 05:35:43.221733 systemd[1]: Running in initrd. Sep 9 05:35:43.221742 systemd[1]: No hostname configured, using default hostname. Sep 9 05:35:43.221753 systemd[1]: Hostname set to . Sep 9 05:35:43.221762 systemd[1]: Initializing machine ID from random generator. Sep 9 05:35:43.221771 systemd[1]: Queued start job for default target initrd.target. Sep 9 05:35:43.221780 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 9 05:35:43.221789 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 9 05:35:43.221798 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 9 05:35:43.221807 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 9 05:35:43.221816 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 9 05:35:43.221828 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 9 05:35:43.221838 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Sep 9 05:35:43.221847 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 9 05:35:43.221856 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 9 05:35:43.221865 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 9 05:35:43.221874 systemd[1]: Reached target paths.target - Path Units. Sep 9 05:35:43.221885 systemd[1]: Reached target slices.target - Slice Units. Sep 9 05:35:43.221894 systemd[1]: Reached target swap.target - Swaps. Sep 9 05:35:43.221902 systemd[1]: Reached target timers.target - Timer Units. Sep 9 05:35:43.221911 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 9 05:35:43.221920 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 9 05:35:43.221929 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 9 05:35:43.221938 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Sep 9 05:35:43.221947 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 9 05:35:43.221956 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 9 05:35:43.221967 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 9 05:35:43.221976 systemd[1]: Reached target sockets.target - Socket Units. Sep 9 05:35:43.221985 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 9 05:35:43.221994 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 9 05:35:43.222002 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 9 05:35:43.222012 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 9 05:35:43.222020 systemd[1]: Starting systemd-fsck-usr.service... Sep 9 05:35:43.222029 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 9 05:35:43.222041 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 9 05:35:43.222050 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 9 05:35:43.222081 systemd-journald[232]: Collecting audit messages is disabled. Sep 9 05:35:43.222103 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 9 05:35:43.222117 systemd-journald[232]: Journal started Sep 9 05:35:43.222140 systemd-journald[232]: Runtime Journal (/run/log/journal/f0cbe45c436648fbac43a89783413dc4) is 8M, max 2.2G, 2.1G free. Sep 9 05:35:43.220260 systemd-modules-load[234]: Inserted module 'overlay' Sep 9 05:35:43.236336 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 9 05:35:43.236753 systemd[1]: Started systemd-journald.service - Journal Service. Sep 9 05:35:43.236881 systemd[1]: Finished systemd-fsck-usr.service. Sep 9 05:35:43.239068 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 9 05:35:43.240138 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 9 05:35:43.287639 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 9 05:35:43.293261 systemd-modules-load[234]: Inserted module 'br_netfilter' Sep 9 05:35:43.296881 kernel: Bridge firewalling registered Sep 9 05:35:43.295802 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 05:35:43.302002 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 9 05:35:43.311681 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 9 05:35:43.320837 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 9 05:35:43.324048 systemd-tmpfiles[246]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Sep 9 05:35:43.326549 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 9 05:35:43.327883 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 9 05:35:43.329794 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 9 05:35:43.346238 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 9 05:35:43.352104 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 9 05:35:43.361497 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 9 05:35:43.389727 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 9 05:35:43.410781 dracut-cmdline[270]: 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=107bc9be805328e5e30844239fa87d36579f371e3de2c34fec43f6ff6d17b104 Sep 9 05:35:43.393316 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 9 05:35:43.461101 systemd-resolved[277]: Positive Trust Anchors: Sep 9 05:35:43.461116 systemd-resolved[277]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 9 05:35:43.461148 systemd-resolved[277]: 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 9 05:35:43.463845 systemd-resolved[277]: Defaulting to hostname 'linux'. Sep 9 05:35:43.464914 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 9 05:35:43.471803 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 9 05:35:43.573649 kernel: SCSI subsystem initialized Sep 9 05:35:43.583649 kernel: Loading iSCSI transport class v2.0-870. Sep 9 05:35:43.596646 kernel: iscsi: registered transport (tcp) Sep 9 05:35:43.618981 kernel: iscsi: registered transport (qla4xxx) Sep 9 05:35:43.619009 kernel: QLogic iSCSI HBA Driver Sep 9 05:35:43.637461 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 9 05:35:43.664948 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 9 05:35:43.666090 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 9 05:35:43.724264 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 9 05:35:43.725567 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 9 05:35:43.800640 kernel: raid6: avx2x4 gen() 30782 MB/s Sep 9 05:35:43.819640 kernel: raid6: avx2x2 gen() 31063 MB/s Sep 9 05:35:43.841680 kernel: raid6: avx2x1 gen() 27581 MB/s Sep 9 05:35:43.841707 kernel: raid6: using algorithm avx2x2 gen() 31063 MB/s Sep 9 05:35:43.864519 kernel: raid6: .... xor() 18435 MB/s, rmw enabled Sep 9 05:35:43.864551 kernel: raid6: using avx2x2 recovery algorithm Sep 9 05:35:43.888648 kernel: xor: automatically using best checksumming function avx Sep 9 05:35:44.049654 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 9 05:35:44.056691 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 9 05:35:44.058216 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 9 05:35:44.098094 systemd-udevd[486]: Using default interface naming scheme 'v255'. Sep 9 05:35:44.104201 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 9 05:35:44.105077 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 9 05:35:44.146331 dracut-pre-trigger[490]: rd.md=0: removing MD RAID activation Sep 9 05:35:44.174058 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 9 05:35:44.184118 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 9 05:35:44.272977 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 9 05:35:44.279267 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 9 05:35:44.301658 kernel: cryptd: max_cpu_qlen set to 1000 Sep 9 05:35:44.313650 kernel: AES CTR mode by8 optimization enabled Sep 9 05:35:44.319646 kernel: hv_vmbus: Vmbus version:4.0 Sep 9 05:35:44.329716 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 9 05:35:44.329993 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 05:35:44.343290 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 9 05:35:44.354345 kernel: hid: raw HID events driver (C) Jiri Kosina Sep 9 05:35:44.365012 kernel: pps_core: LinuxPPS API ver. 1 registered Sep 9 05:35:44.365043 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Sep 9 05:35:44.365062 kernel: hv_vmbus: registering driver hid_hyperv Sep 9 05:35:44.367596 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 9 05:35:44.385646 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input0 Sep 9 05:35:44.385673 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Sep 9 05:35:44.388827 kernel: hv_vmbus: registering driver hv_storvsc Sep 9 05:35:44.388844 kernel: hv_vmbus: registering driver hyperv_keyboard Sep 9 05:35:44.368128 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 9 05:35:44.426320 kernel: scsi host0: storvsc_host_t Sep 9 05:35:44.426603 kernel: scsi host1: storvsc_host_t Sep 9 05:35:44.429289 kernel: input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/VMBUS:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input1 Sep 9 05:35:44.429307 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Sep 9 05:35:44.429461 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Sep 9 05:35:44.429602 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Sep 9 05:35:44.429758 kernel: PTP clock support registered Sep 9 05:35:44.429772 kernel: hv_vmbus: registering driver hv_netvsc Sep 9 05:35:44.388915 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 9 05:35:44.450503 kernel: hv_vmbus: registering driver hv_pci Sep 9 05:35:44.450527 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Sep 9 05:35:44.450722 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Sep 9 05:35:44.389019 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 05:35:44.476618 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Sep 9 05:35:44.476855 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Sep 9 05:35:44.477002 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Sep 9 05:35:44.477142 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint Sep 9 05:35:44.477175 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] Sep 9 05:35:44.477193 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] Sep 9 05:35:44.414017 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 9 05:35:44.485454 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] Sep 9 05:35:44.490710 kernel: hv_utils: Registering HyperV Utility Driver Sep 9 05:35:44.490751 kernel: pci 0001:00:00.0: Enabling HDA controller Sep 9 05:35:44.490778 kernel: hv_vmbus: registering driver hv_utils Sep 9 05:35:44.502168 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Sep 9 05:35:44.502361 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Sep 9 05:35:44.502509 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks Sep 9 05:35:44.502689 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Sep 9 05:35:44.507645 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Sep 9 05:35:44.507873 kernel: sd 0:0:0:1: [sdb] Write Protect is off Sep 9 05:35:44.512911 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned Sep 9 05:35:44.513339 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Sep 9 05:35:44.513486 kernel: sd 0:0:0:0: [sda] Write Protect is off Sep 9 05:35:44.517731 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Sep 9 05:35:44.517899 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned Sep 9 05:35:44.518639 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned Sep 9 05:35:44.518797 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Sep 9 05:35:44.521639 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#190 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 9 05:35:44.528967 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Sep 9 05:35:44.532651 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#137 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 9 05:35:44.547152 kernel: hv_utils: Heartbeat IC version 3.0 Sep 9 05:35:44.547181 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#515 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 9 05:35:44.547340 kernel: hv_utils: Shutdown IC version 3.2 Sep 9 05:35:44.547355 kernel: hv_utils: TimeSync IC version 4.0 Sep 9 05:35:44.554647 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#522 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 9 05:35:45.132535 systemd-resolved[277]: Clock change detected. Flushing caches. Sep 9 05:35:45.154045 kernel: sdb: sdb1 Sep 9 05:35:45.154084 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk Sep 9 05:35:45.164125 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 9 05:35:45.164158 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Sep 9 05:35:45.166613 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 05:35:45.180793 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Sep 9 05:35:45.180999 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Sep 9 05:35:45.182073 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Sep 9 05:35:45.728144 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Sep 9 05:35:45.809279 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Sep 9 05:35:45.814368 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Sep 9 05:35:45.826832 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 9 05:35:45.856315 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Sep 9 05:35:45.882765 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Sep 9 05:35:46.863920 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#411 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 9 05:35:46.881802 disk-uuid[714]: The operation has completed successfully. Sep 9 05:35:46.885641 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 9 05:35:46.958548 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 9 05:35:46.967510 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 9 05:35:46.970958 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 9 05:35:47.016483 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 9 05:35:47.026334 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 9 05:35:47.035617 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 9 05:35:47.044760 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 9 05:35:47.069548 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 9 05:35:47.078391 sh[893]: Success Sep 9 05:35:47.082486 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 9 05:35:47.167346 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 9 05:35:47.167397 kernel: device-mapper: uevent: version 1.0.3 Sep 9 05:35:47.172649 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Sep 9 05:35:47.184073 kernel: device-mapper: verity: sha256 using shash "sha256-avx2" Sep 9 05:35:47.608113 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 9 05:35:47.610033 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 9 05:35:47.629732 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 9 05:35:47.648072 kernel: BTRFS: device fsid 9ca60a92-6b53-4529-adc0-1f4392d2ad56 devid 1 transid 37 /dev/mapper/usr (254:0) scanned by mount (916) Sep 9 05:35:47.657232 kernel: BTRFS info (device dm-0): first mount of filesystem 9ca60a92-6b53-4529-adc0-1f4392d2ad56 Sep 9 05:35:47.657257 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Sep 9 05:35:48.158286 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 9 05:35:48.158359 kernel: BTRFS info (device dm-0): enabling free space tree Sep 9 05:35:48.218812 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 9 05:35:48.223119 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Sep 9 05:35:48.231103 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 9 05:35:48.231988 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 9 05:35:48.236273 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 9 05:35:48.293092 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (948) Sep 9 05:35:48.299081 kernel: BTRFS info (device sda6): first mount of filesystem d4e5a7a8-c50a-463e-827d-ca249a0b8b8b Sep 9 05:35:48.299112 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Sep 9 05:35:48.365165 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 9 05:35:48.366881 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 9 05:35:48.412703 systemd-networkd[1079]: lo: Link UP Sep 9 05:35:48.416914 kernel: BTRFS info (device sda6): turning on async discard Sep 9 05:35:48.416945 kernel: BTRFS info (device sda6): enabling free space tree Sep 9 05:35:48.412714 systemd-networkd[1079]: lo: Gained carrier Sep 9 05:35:48.413752 systemd-networkd[1079]: Enumeration completed Sep 9 05:35:48.413849 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 9 05:35:48.414127 systemd-networkd[1079]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 9 05:35:48.414132 systemd-networkd[1079]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 9 05:35:48.414861 systemd-networkd[1079]: eth0: Link UP Sep 9 05:35:48.415026 systemd-networkd[1079]: eth0: Gained carrier Sep 9 05:35:48.415036 systemd-networkd[1079]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 9 05:35:48.423676 systemd[1]: Reached target network.target - Network. Sep 9 05:35:48.474810 kernel: BTRFS info (device sda6): last unmount of filesystem d4e5a7a8-c50a-463e-827d-ca249a0b8b8b Sep 9 05:35:48.475900 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 9 05:35:48.483755 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 9 05:35:48.587091 systemd-networkd[1079]: eth0: DHCPv4 address 10.200.8.10/24, gateway 10.200.8.1 acquired from 168.63.129.16 Sep 9 05:35:49.962182 systemd-networkd[1079]: eth0: Gained IPv6LL Sep 9 05:35:50.196287 ignition[1087]: Ignition 2.22.0 Sep 9 05:35:50.196306 ignition[1087]: Stage: fetch-offline Sep 9 05:35:50.196486 ignition[1087]: no configs at "/usr/lib/ignition/base.d" Sep 9 05:35:50.196500 ignition[1087]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 9 05:35:50.196604 ignition[1087]: parsed url from cmdline: "" Sep 9 05:35:50.196609 ignition[1087]: no config URL provided Sep 9 05:35:50.196615 ignition[1087]: reading system config file "/usr/lib/ignition/user.ign" Sep 9 05:35:50.196625 ignition[1087]: no config at "/usr/lib/ignition/user.ign" Sep 9 05:35:50.196631 ignition[1087]: failed to fetch config: resource requires networking Sep 9 05:35:50.196793 ignition[1087]: Ignition finished successfully Sep 9 05:35:50.227903 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 9 05:35:50.233709 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Sep 9 05:35:50.270163 ignition[1098]: Ignition 2.22.0 Sep 9 05:35:50.270177 ignition[1098]: Stage: fetch Sep 9 05:35:50.270448 ignition[1098]: no configs at "/usr/lib/ignition/base.d" Sep 9 05:35:50.270460 ignition[1098]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 9 05:35:50.270565 ignition[1098]: parsed url from cmdline: "" Sep 9 05:35:50.270569 ignition[1098]: no config URL provided Sep 9 05:35:50.270576 ignition[1098]: reading system config file "/usr/lib/ignition/user.ign" Sep 9 05:35:50.270585 ignition[1098]: no config at "/usr/lib/ignition/user.ign" Sep 9 05:35:50.270604 ignition[1098]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Sep 9 05:35:50.383556 ignition[1098]: GET result: OK Sep 9 05:35:50.383638 ignition[1098]: config has been read from IMDS userdata Sep 9 05:35:50.383658 ignition[1098]: parsing config with SHA512: 0f5a66d2b15dba92b0e07e697cb7dfec191bfcce9524a14536bc092285658b77478925d2f69f572a6aaad3de5cbde0042177c2d76b5d7042cc4002317c924129 Sep 9 05:35:50.391562 unknown[1098]: fetched base config from "system" Sep 9 05:35:50.391573 unknown[1098]: fetched base config from "system" Sep 9 05:35:50.391721 ignition[1098]: fetch: fetch complete Sep 9 05:35:50.391579 unknown[1098]: fetched user config from "azure" Sep 9 05:35:50.391726 ignition[1098]: fetch: fetch passed Sep 9 05:35:50.400260 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Sep 9 05:35:50.391770 ignition[1098]: Ignition finished successfully Sep 9 05:35:50.401994 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 9 05:35:50.449656 ignition[1108]: Ignition 2.22.0 Sep 9 05:35:50.449670 ignition[1108]: Stage: kargs Sep 9 05:35:50.449886 ignition[1108]: no configs at "/usr/lib/ignition/base.d" Sep 9 05:35:50.449898 ignition[1108]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 9 05:35:50.450524 ignition[1108]: kargs: kargs passed Sep 9 05:35:50.450568 ignition[1108]: Ignition finished successfully Sep 9 05:35:50.462682 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 9 05:35:50.465703 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 9 05:35:50.511204 ignition[1117]: Ignition 2.22.0 Sep 9 05:35:50.511216 ignition[1117]: Stage: disks Sep 9 05:35:50.511458 ignition[1117]: no configs at "/usr/lib/ignition/base.d" Sep 9 05:35:50.511469 ignition[1117]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 9 05:35:50.520471 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 9 05:35:50.512106 ignition[1117]: disks: disks passed Sep 9 05:35:50.524567 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 9 05:35:50.512150 ignition[1117]: Ignition finished successfully Sep 9 05:35:50.531977 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 9 05:35:50.538874 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 9 05:35:50.547915 systemd[1]: Reached target sysinit.target - System Initialization. Sep 9 05:35:50.554790 systemd[1]: Reached target basic.target - Basic System. Sep 9 05:35:50.564298 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 9 05:35:50.685784 systemd-fsck[1129]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks Sep 9 05:35:50.691247 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 9 05:35:50.692352 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 9 05:35:52.646094 kernel: EXT4-fs (sda9): mounted filesystem d2d7815e-fa16-4396-ab9d-ac540c1d8856 r/w with ordered data mode. Quota mode: none. Sep 9 05:35:52.646871 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 9 05:35:52.650811 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 9 05:35:52.683159 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 9 05:35:52.709566 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 9 05:35:52.710699 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Sep 9 05:35:52.716265 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Sep 9 05:35:52.730500 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1143) Sep 9 05:35:52.716303 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 9 05:35:52.756894 kernel: BTRFS info (device sda6): first mount of filesystem d4e5a7a8-c50a-463e-827d-ca249a0b8b8b Sep 9 05:35:52.756917 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Sep 9 05:35:52.753589 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 9 05:35:52.770241 kernel: BTRFS info (device sda6): turning on async discard Sep 9 05:35:52.770260 kernel: BTRFS info (device sda6): enabling free space tree Sep 9 05:35:52.768052 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 9 05:35:52.775588 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 9 05:35:54.174801 coreos-metadata[1145]: Sep 09 05:35:54.174 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Sep 9 05:35:54.180834 coreos-metadata[1145]: Sep 09 05:35:54.176 INFO Fetch successful Sep 9 05:35:54.180834 coreos-metadata[1145]: Sep 09 05:35:54.177 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Sep 9 05:35:54.192875 coreos-metadata[1145]: Sep 09 05:35:54.184 INFO Fetch successful Sep 9 05:35:54.197542 coreos-metadata[1145]: Sep 09 05:35:54.197 INFO wrote hostname ci-4452.0.0-n-b1cf16fc31 to /sysroot/etc/hostname Sep 9 05:35:54.199826 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Sep 9 05:35:54.893242 initrd-setup-root[1174]: cut: /sysroot/etc/passwd: No such file or directory Sep 9 05:35:55.014143 initrd-setup-root[1181]: cut: /sysroot/etc/group: No such file or directory Sep 9 05:35:55.060752 initrd-setup-root[1188]: cut: /sysroot/etc/shadow: No such file or directory Sep 9 05:35:55.068342 initrd-setup-root[1195]: cut: /sysroot/etc/gshadow: No such file or directory Sep 9 05:35:56.632719 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 9 05:35:56.638950 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 9 05:35:56.660580 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 9 05:35:56.666299 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 9 05:35:56.677400 kernel: BTRFS info (device sda6): last unmount of filesystem d4e5a7a8-c50a-463e-827d-ca249a0b8b8b Sep 9 05:35:56.697282 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 9 05:35:56.715858 ignition[1263]: INFO : Ignition 2.22.0 Sep 9 05:35:56.715858 ignition[1263]: INFO : Stage: mount Sep 9 05:35:56.721913 ignition[1263]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 9 05:35:56.721913 ignition[1263]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 9 05:35:56.721913 ignition[1263]: INFO : mount: mount passed Sep 9 05:35:56.721913 ignition[1263]: INFO : Ignition finished successfully Sep 9 05:35:56.723395 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 9 05:35:56.727027 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 9 05:35:56.761677 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 9 05:35:56.785075 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1279) Sep 9 05:35:56.793954 kernel: BTRFS info (device sda6): first mount of filesystem d4e5a7a8-c50a-463e-827d-ca249a0b8b8b Sep 9 05:35:56.793989 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Sep 9 05:35:56.803331 kernel: BTRFS info (device sda6): turning on async discard Sep 9 05:35:56.803357 kernel: BTRFS info (device sda6): enabling free space tree Sep 9 05:35:56.805481 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 9 05:35:56.854171 ignition[1296]: INFO : Ignition 2.22.0 Sep 9 05:35:56.854171 ignition[1296]: INFO : Stage: files Sep 9 05:35:56.860161 ignition[1296]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 9 05:35:56.860161 ignition[1296]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 9 05:35:56.860161 ignition[1296]: DEBUG : files: compiled without relabeling support, skipping Sep 9 05:35:56.890232 ignition[1296]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 9 05:35:56.890232 ignition[1296]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 9 05:35:56.953172 ignition[1296]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 9 05:35:56.958871 ignition[1296]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 9 05:35:56.964923 unknown[1296]: wrote ssh authorized keys file for user: core Sep 9 05:35:56.968824 ignition[1296]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 9 05:35:57.014380 ignition[1296]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 9 05:35:57.021709 ignition[1296]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 9 05:35:57.021709 ignition[1296]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 9 05:35:57.021709 ignition[1296]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 9 05:35:57.021709 ignition[1296]: INFO : files: files passed Sep 9 05:35:57.021709 ignition[1296]: INFO : Ignition finished successfully Sep 9 05:35:57.037699 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 9 05:35:57.044209 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 9 05:35:57.072377 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 9 05:35:57.082009 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 9 05:35:57.082125 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 9 05:35:57.116998 initrd-setup-root-after-ignition[1329]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 9 05:35:57.123209 initrd-setup-root-after-ignition[1329]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 9 05:35:57.129466 initrd-setup-root-after-ignition[1333]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 9 05:35:57.124111 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 9 05:35:57.134617 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 9 05:35:57.141217 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 9 05:35:57.202715 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 9 05:35:57.202831 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 9 05:35:57.206754 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 9 05:35:57.216232 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 9 05:35:57.224083 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 9 05:35:57.224823 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 9 05:35:57.278823 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 9 05:35:57.289508 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 9 05:35:57.323752 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 9 05:35:57.332937 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 9 05:35:57.333205 systemd[1]: Stopped target timers.target - Timer Units. Sep 9 05:35:57.345975 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 9 05:35:57.346153 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 9 05:35:57.357979 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 9 05:35:57.362625 systemd[1]: Stopped target basic.target - Basic System. Sep 9 05:35:57.370437 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 9 05:35:57.377341 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 9 05:35:57.385651 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 9 05:35:57.394547 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Sep 9 05:35:57.403285 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 9 05:35:57.415826 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 9 05:35:57.426267 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 9 05:35:57.435381 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 9 05:35:57.443462 systemd[1]: Stopped target swap.target - Swaps. Sep 9 05:35:57.450291 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 9 05:35:57.450643 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 9 05:35:57.466568 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 9 05:35:57.470690 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 9 05:35:57.478980 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 9 05:35:57.479104 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 9 05:35:57.488080 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 9 05:35:57.488216 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 9 05:35:57.506158 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 9 05:35:57.506281 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 9 05:35:57.509914 systemd[1]: ignition-files.service: Deactivated successfully. Sep 9 05:35:57.510039 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 9 05:35:57.518799 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Sep 9 05:35:57.518919 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Sep 9 05:35:57.527949 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 9 05:35:57.537461 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 9 05:35:57.543861 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 9 05:35:57.543992 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 9 05:35:57.590102 ignition[1353]: INFO : Ignition 2.22.0 Sep 9 05:35:57.590102 ignition[1353]: INFO : Stage: umount Sep 9 05:35:57.590102 ignition[1353]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 9 05:35:57.590102 ignition[1353]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 9 05:35:57.550894 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 9 05:35:57.621229 ignition[1353]: INFO : umount: umount passed Sep 9 05:35:57.621229 ignition[1353]: INFO : Ignition finished successfully Sep 9 05:35:57.551007 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 9 05:35:57.571473 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 9 05:35:57.572168 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 9 05:35:57.591548 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 9 05:35:57.591694 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 9 05:35:57.601263 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 9 05:35:57.601569 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 9 05:35:57.605193 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 9 05:35:57.605335 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 9 05:35:57.613743 systemd[1]: ignition-fetch.service: Deactivated successfully. Sep 9 05:35:57.613793 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Sep 9 05:35:57.621341 systemd[1]: Stopped target network.target - Network. Sep 9 05:35:57.628217 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 9 05:35:57.628274 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 9 05:35:57.639732 systemd[1]: Stopped target paths.target - Path Units. Sep 9 05:35:57.644003 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 9 05:35:57.644283 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 9 05:35:57.651031 systemd[1]: Stopped target slices.target - Slice Units. Sep 9 05:35:57.660705 systemd[1]: Stopped target sockets.target - Socket Units. Sep 9 05:35:57.667813 systemd[1]: iscsid.socket: Deactivated successfully. Sep 9 05:35:57.667866 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 9 05:35:57.675031 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 9 05:35:57.675127 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 9 05:35:57.686439 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 9 05:35:57.686497 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 9 05:35:57.690245 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 9 05:35:57.690290 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 9 05:35:57.697768 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 9 05:35:57.705463 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 9 05:35:57.713889 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 9 05:35:57.714508 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 9 05:35:57.714616 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 9 05:35:57.721219 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 9 05:35:57.721280 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 9 05:35:57.735931 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 9 05:35:57.736113 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 9 05:35:57.748323 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Sep 9 05:35:57.748524 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 9 05:35:57.748632 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 9 05:35:57.762049 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Sep 9 05:35:57.762591 systemd[1]: Stopped target network-pre.target - Preparation for Network. Sep 9 05:35:57.764656 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 9 05:35:57.764723 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 9 05:35:57.773290 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 9 05:35:57.779722 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 9 05:35:57.779772 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 9 05:35:57.786806 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 9 05:35:57.786851 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 9 05:35:57.802725 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 9 05:35:57.802768 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 9 05:35:57.806490 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 9 05:35:57.806541 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 9 05:35:57.819479 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 9 05:35:57.828723 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Sep 9 05:35:57.828784 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Sep 9 05:35:57.855754 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 9 05:35:57.863174 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 9 05:35:57.867012 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 9 05:35:57.867186 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 9 05:35:57.876640 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 9 05:35:57.876676 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 9 05:35:57.888571 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 9 05:35:57.888619 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 9 05:35:57.901670 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 9 05:35:57.901719 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 9 05:35:57.913348 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 9 05:35:57.913402 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 9 05:35:57.926941 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 9 05:35:57.930281 systemd[1]: systemd-network-generator.service: Deactivated successfully. Sep 9 05:35:57.930331 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Sep 9 05:35:57.947819 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 9 05:35:57.947867 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 9 05:35:57.960332 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Sep 9 05:35:57.960378 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 9 05:35:57.973158 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 9 05:35:57.973204 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 9 05:35:57.983768 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 9 05:35:57.983819 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 05:35:57.994600 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Sep 9 05:35:57.994655 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev\x2dearly.service.mount: Deactivated successfully. Sep 9 05:35:57.994692 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Sep 9 05:35:57.994731 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 9 05:35:57.995132 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 9 05:35:58.155441 systemd-journald[232]: Received SIGTERM from PID 1 (systemd). Sep 9 05:35:57.995227 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 9 05:35:57.998336 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 9 05:35:57.998424 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 9 05:35:58.012720 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 9 05:35:58.016369 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 9 05:35:58.066598 systemd[1]: Switching root. Sep 9 05:35:58.181029 systemd-journald[232]: Journal stopped Sep 9 05:36:07.771331 kernel: SELinux: policy capability network_peer_controls=1 Sep 9 05:36:07.771362 kernel: SELinux: policy capability open_perms=1 Sep 9 05:36:07.771374 kernel: SELinux: policy capability extended_socket_class=1 Sep 9 05:36:07.771383 kernel: SELinux: policy capability always_check_network=0 Sep 9 05:36:07.771392 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 9 05:36:07.771401 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 9 05:36:07.771411 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 9 05:36:07.771422 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 9 05:36:07.771432 kernel: SELinux: policy capability userspace_initial_context=0 Sep 9 05:36:07.771441 kernel: audit: type=1403 audit(1757396159.543:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 9 05:36:07.771452 systemd[1]: Successfully loaded SELinux policy in 258.714ms. Sep 9 05:36:07.771464 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 7.673ms. Sep 9 05:36:07.771475 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 9 05:36:07.771485 systemd[1]: Detected virtualization microsoft. Sep 9 05:36:07.771499 systemd[1]: Detected architecture x86-64. Sep 9 05:36:07.771509 systemd[1]: Detected first boot. Sep 9 05:36:07.771519 systemd[1]: Hostname set to . Sep 9 05:36:07.771530 systemd[1]: Initializing machine ID from random generator. Sep 9 05:36:07.771540 zram_generator::config[1399]: No configuration found. Sep 9 05:36:07.771557 kernel: Guest personality initialized and is inactive Sep 9 05:36:07.771567 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Sep 9 05:36:07.771576 kernel: Initialized host personality Sep 9 05:36:07.771586 kernel: NET: Registered PF_VSOCK protocol family Sep 9 05:36:07.771596 systemd[1]: Populated /etc with preset unit settings. Sep 9 05:36:07.771607 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Sep 9 05:36:07.771618 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 9 05:36:07.771630 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 9 05:36:07.771641 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 9 05:36:07.771651 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 9 05:36:07.771662 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 9 05:36:07.771673 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 9 05:36:07.771683 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 9 05:36:07.771694 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 9 05:36:07.771705 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 9 05:36:07.771718 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 9 05:36:07.771728 systemd[1]: Created slice user.slice - User and Session Slice. Sep 9 05:36:07.771738 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 9 05:36:07.771749 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 9 05:36:07.771759 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 9 05:36:07.771770 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 9 05:36:07.771788 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 9 05:36:07.771799 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 9 05:36:07.771812 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Sep 9 05:36:07.771823 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 9 05:36:07.771834 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 9 05:36:07.771845 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 9 05:36:07.771856 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 9 05:36:07.771867 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 9 05:36:07.771878 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 9 05:36:07.771891 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 9 05:36:07.771902 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 9 05:36:07.771912 systemd[1]: Reached target slices.target - Slice Units. Sep 9 05:36:07.771923 systemd[1]: Reached target swap.target - Swaps. Sep 9 05:36:07.771934 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 9 05:36:07.771944 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 9 05:36:07.771958 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Sep 9 05:36:07.771969 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 9 05:36:07.771980 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 9 05:36:07.771991 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 9 05:36:07.772001 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 9 05:36:07.772012 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 9 05:36:07.772026 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 9 05:36:07.772040 systemd[1]: Mounting media.mount - External Media Directory... Sep 9 05:36:07.772051 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 9 05:36:07.772073 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 9 05:36:07.772084 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 9 05:36:07.772095 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 9 05:36:07.772106 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 9 05:36:07.772118 systemd[1]: Reached target machines.target - Containers. Sep 9 05:36:07.772129 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 9 05:36:07.772140 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 9 05:36:07.772154 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 9 05:36:07.772165 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 9 05:36:07.772175 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 9 05:36:07.772186 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 9 05:36:07.772197 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 9 05:36:07.772208 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 9 05:36:07.772219 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 9 05:36:07.772230 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 9 05:36:07.772243 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 9 05:36:07.772254 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 9 05:36:07.772265 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 9 05:36:07.772279 systemd[1]: Stopped systemd-fsck-usr.service. Sep 9 05:36:07.772290 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 9 05:36:07.772301 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 9 05:36:07.772312 kernel: fuse: init (API version 7.41) Sep 9 05:36:07.772322 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 9 05:36:07.772357 systemd-journald[1506]: Collecting audit messages is disabled. Sep 9 05:36:07.772380 kernel: loop: module loaded Sep 9 05:36:07.772392 systemd-journald[1506]: Journal started Sep 9 05:36:07.772417 systemd-journald[1506]: Runtime Journal (/run/log/journal/a27e298c01c44be2b2d8e7492c77c549) is 8M, max 2.2G, 2.1G free. Sep 9 05:36:06.875015 systemd[1]: Queued start job for default target multi-user.target. Sep 9 05:36:06.896383 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Sep 9 05:36:06.896849 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 9 05:36:07.786100 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 9 05:36:07.797091 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 9 05:36:07.808074 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Sep 9 05:36:07.819080 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 9 05:36:07.832271 systemd[1]: verity-setup.service: Deactivated successfully. Sep 9 05:36:07.832472 systemd[1]: Stopped verity-setup.service. Sep 9 05:36:07.842074 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 9 05:36:07.848072 systemd[1]: Started systemd-journald.service - Journal Service. Sep 9 05:36:07.853475 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 9 05:36:07.858093 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 9 05:36:07.862642 systemd[1]: Mounted media.mount - External Media Directory. Sep 9 05:36:07.866758 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 9 05:36:07.871378 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 9 05:36:07.875980 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 9 05:36:07.880158 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 9 05:36:07.885314 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 9 05:36:07.891124 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 9 05:36:07.891330 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 9 05:36:07.896778 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 9 05:36:07.896962 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 9 05:36:07.902012 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 9 05:36:07.902218 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 9 05:36:07.907771 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 9 05:36:07.907960 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 9 05:36:07.912718 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 9 05:36:07.912897 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 9 05:36:07.917652 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 9 05:36:07.922372 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 9 05:36:07.927713 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 9 05:36:07.933051 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Sep 9 05:36:07.946608 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 9 05:36:07.952392 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 9 05:36:07.958071 kernel: ACPI: bus type drm_connector registered Sep 9 05:36:07.969288 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 9 05:36:07.973873 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 9 05:36:07.973912 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 9 05:36:07.979039 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Sep 9 05:36:07.985187 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 9 05:36:07.989252 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 9 05:36:07.990291 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 9 05:36:07.995558 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 9 05:36:08.000226 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 9 05:36:08.002202 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 9 05:36:08.006507 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 9 05:36:08.007413 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 9 05:36:08.012313 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 9 05:36:08.018039 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 9 05:36:08.024569 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 9 05:36:08.024767 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 9 05:36:08.029494 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 9 05:36:08.034954 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 9 05:36:08.040652 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 9 05:36:08.050052 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 9 05:36:08.055046 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 9 05:36:08.062536 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Sep 9 05:36:08.077358 systemd-journald[1506]: Time spent on flushing to /var/log/journal/a27e298c01c44be2b2d8e7492c77c549 is 14.072ms for 957 entries. Sep 9 05:36:08.077358 systemd-journald[1506]: System Journal (/var/log/journal/a27e298c01c44be2b2d8e7492c77c549) is 8M, max 2.6G, 2.6G free. Sep 9 05:36:08.143186 systemd-journald[1506]: Received client request to flush runtime journal. Sep 9 05:36:08.144660 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 9 05:36:08.164968 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 9 05:36:08.165661 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Sep 9 05:36:08.190195 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 9 05:36:08.220666 systemd-tmpfiles[1541]: ACLs are not supported, ignoring. Sep 9 05:36:08.220705 systemd-tmpfiles[1541]: ACLs are not supported, ignoring. Sep 9 05:36:08.224599 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 9 05:36:08.230983 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 9 05:36:08.243074 kernel: loop0: detected capacity change from 0 to 110984 Sep 9 05:36:09.170562 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 9 05:36:09.176189 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 9 05:36:09.208349 systemd-tmpfiles[1561]: ACLs are not supported, ignoring. Sep 9 05:36:09.208369 systemd-tmpfiles[1561]: ACLs are not supported, ignoring. Sep 9 05:36:09.211078 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 9 05:36:09.211716 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 9 05:36:09.413084 kernel: loop1: detected capacity change from 0 to 27936 Sep 9 05:36:10.062559 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 9 05:36:10.070279 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 9 05:36:10.110908 systemd-udevd[1565]: Using default interface naming scheme 'v255'. Sep 9 05:36:10.648089 kernel: loop2: detected capacity change from 0 to 128016 Sep 9 05:36:10.917612 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 9 05:36:10.927450 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 9 05:36:10.954034 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Sep 9 05:36:11.045079 kernel: mousedev: PS/2 mouse device common for all mice Sep 9 05:36:11.058105 kernel: hv_vmbus: registering driver hv_balloon Sep 9 05:36:11.058169 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Sep 9 05:36:11.071238 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 9 05:36:11.087074 kernel: hv_vmbus: registering driver hyperv_fb Sep 9 05:36:11.087140 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Sep 9 05:36:11.094160 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Sep 9 05:36:11.097407 kernel: Console: switching to colour dummy device 80x25 Sep 9 05:36:11.099081 kernel: Console: switching to colour frame buffer device 128x48 Sep 9 05:36:11.106247 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 9 05:36:11.106571 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 05:36:11.112275 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 9 05:36:11.114787 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 9 05:36:11.144372 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 9 05:36:11.205302 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 9 05:36:11.276165 kernel: EDAC MC: Ver: 3.0.0 Sep 9 05:36:11.397780 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Sep 9 05:36:11.402756 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 9 05:36:11.441605 systemd-networkd[1592]: lo: Link UP Sep 9 05:36:11.441614 systemd-networkd[1592]: lo: Gained carrier Sep 9 05:36:11.442947 systemd-networkd[1592]: Enumeration completed Sep 9 05:36:11.443038 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 9 05:36:11.443338 systemd-networkd[1592]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 9 05:36:11.443348 systemd-networkd[1592]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 9 05:36:11.443876 systemd-networkd[1592]: eth0: Link UP Sep 9 05:36:11.443885 systemd-networkd[1592]: eth0: Gained carrier Sep 9 05:36:11.443900 systemd-networkd[1592]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 9 05:36:11.444540 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Sep 9 05:36:11.446858 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 9 05:36:11.478078 kernel: loop3: detected capacity change from 0 to 110984 Sep 9 05:36:11.494072 kernel: loop4: detected capacity change from 0 to 27936 Sep 9 05:36:11.502099 systemd-networkd[1592]: eth0: DHCPv4 address 10.200.8.10/24, gateway 10.200.8.1 acquired from 168.63.129.16 Sep 9 05:36:11.505217 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Sep 9 05:36:11.507083 kernel: loop5: detected capacity change from 0 to 128016 Sep 9 05:36:11.512758 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 9 05:36:11.519998 (sd-merge)[1719]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Sep 9 05:36:11.520487 (sd-merge)[1719]: Merged extensions into '/usr'. Sep 9 05:36:11.523686 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 9 05:36:11.529220 systemd[1]: Starting ensure-sysext.service... Sep 9 05:36:11.530169 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 9 05:36:11.552642 systemd[1]: Reload requested from client PID 1724 ('systemctl') (unit ensure-sysext.service)... Sep 9 05:36:11.552658 systemd[1]: Reloading... Sep 9 05:36:11.606157 zram_generator::config[1760]: No configuration found. Sep 9 05:36:11.618111 systemd-tmpfiles[1725]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Sep 9 05:36:11.618160 systemd-tmpfiles[1725]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Sep 9 05:36:11.618485 systemd-tmpfiles[1725]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 9 05:36:11.618783 systemd-tmpfiles[1725]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 9 05:36:11.619783 systemd-tmpfiles[1725]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 9 05:36:11.620090 systemd-tmpfiles[1725]: ACLs are not supported, ignoring. Sep 9 05:36:11.620162 systemd-tmpfiles[1725]: ACLs are not supported, ignoring. Sep 9 05:36:11.700920 systemd-tmpfiles[1725]: Detected autofs mount point /boot during canonicalization of boot. Sep 9 05:36:11.700936 systemd-tmpfiles[1725]: Skipping /boot Sep 9 05:36:11.709399 systemd-tmpfiles[1725]: Detected autofs mount point /boot during canonicalization of boot. Sep 9 05:36:11.709412 systemd-tmpfiles[1725]: Skipping /boot Sep 9 05:36:11.867862 systemd[1]: Reloading finished in 314 ms. Sep 9 05:36:11.923093 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 9 05:36:11.934045 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 9 05:36:11.967831 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 9 05:36:11.969348 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 9 05:36:11.971073 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 9 05:36:11.990536 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 9 05:36:11.993199 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 9 05:36:11.994268 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 9 05:36:11.996213 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 9 05:36:11.997550 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 9 05:36:11.997896 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 9 05:36:11.998003 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 9 05:36:12.001065 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 9 05:36:12.001252 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 9 05:36:12.002698 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 9 05:36:12.002874 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 9 05:36:12.003263 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 9 05:36:12.004050 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 9 05:36:12.004245 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 9 05:36:12.004835 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 9 05:36:12.017203 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 9 05:36:12.028134 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 9 05:36:12.029417 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 9 05:36:12.030510 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 9 05:36:12.031896 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 9 05:36:12.032463 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 9 05:36:12.032578 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 9 05:36:12.036388 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 9 05:36:12.036564 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 9 05:36:12.037530 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 9 05:36:12.037714 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 9 05:36:12.041160 systemd[1]: Finished ensure-sysext.service. Sep 9 05:36:12.042561 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 9 05:36:12.043547 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 9 05:36:12.043835 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 9 05:36:12.043876 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 9 05:36:12.043919 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 9 05:36:12.043961 systemd[1]: Reached target time-set.target - System Time Set. Sep 9 05:36:12.049777 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 9 05:36:12.049964 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 9 05:36:12.071173 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 9 05:36:12.071384 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 9 05:36:12.071770 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 9 05:36:12.089479 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 9 05:36:12.089502 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 9 05:36:12.160611 systemd-resolved[1821]: Positive Trust Anchors: Sep 9 05:36:12.160625 systemd-resolved[1821]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 9 05:36:12.160657 systemd-resolved[1821]: 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 9 05:36:12.245408 systemd-resolved[1821]: Using system hostname 'ci-4452.0.0-n-b1cf16fc31'. Sep 9 05:36:12.257528 augenrules[1859]: No rules Sep 9 05:36:12.258992 systemd[1]: audit-rules.service: Deactivated successfully. Sep 9 05:36:12.259253 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 9 05:36:12.263670 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 9 05:36:12.263841 systemd[1]: Reached target network.target - Network. Sep 9 05:36:12.264532 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 9 05:36:12.317842 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 9 05:36:12.650636 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 05:36:13.066189 systemd-networkd[1592]: eth0: Gained IPv6LL Sep 9 05:36:13.068538 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 9 05:36:13.074239 systemd[1]: Reached target network-online.target - Network is Online. Sep 9 05:36:15.579083 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 9 05:36:15.584895 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 9 05:36:20.800176 ldconfig[1534]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 9 05:36:20.812202 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 9 05:36:20.819092 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 9 05:36:20.874604 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 9 05:36:20.879600 systemd[1]: Reached target sysinit.target - System Initialization. Sep 9 05:36:20.884274 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 9 05:36:20.889430 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 9 05:36:20.894622 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Sep 9 05:36:20.899472 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 9 05:36:20.903973 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 9 05:36:20.909167 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 9 05:36:20.914354 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 9 05:36:20.914403 systemd[1]: Reached target paths.target - Path Units. Sep 9 05:36:20.918151 systemd[1]: Reached target timers.target - Timer Units. Sep 9 05:36:20.935535 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 9 05:36:20.941233 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 9 05:36:20.947134 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Sep 9 05:36:20.952246 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Sep 9 05:36:20.957214 systemd[1]: Reached target ssh-access.target - SSH Access Available. Sep 9 05:36:20.963133 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 9 05:36:20.967545 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Sep 9 05:36:20.972876 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 9 05:36:20.977965 systemd[1]: Reached target sockets.target - Socket Units. Sep 9 05:36:20.981851 systemd[1]: Reached target basic.target - Basic System. Sep 9 05:36:20.985485 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 9 05:36:20.985514 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 9 05:36:20.999832 systemd[1]: Starting chronyd.service - NTP client/server... Sep 9 05:36:21.004117 systemd[1]: Starting containerd.service - containerd container runtime... Sep 9 05:36:21.025821 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Sep 9 05:36:21.031191 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 9 05:36:21.035848 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 9 05:36:21.041579 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 9 05:36:21.046697 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 9 05:36:21.050629 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 9 05:36:21.051662 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Sep 9 05:36:21.055720 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 9 05:36:21.056722 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Sep 9 05:36:21.061841 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Sep 9 05:36:21.062865 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 9 05:36:21.065368 jq[1884]: false Sep 9 05:36:21.067678 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 9 05:36:21.073638 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 9 05:36:21.079301 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 9 05:36:21.085661 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 9 05:36:21.090635 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 9 05:36:21.091396 oslogin_cache_refresh[1886]: Refreshing passwd entry cache Sep 9 05:36:21.091799 google_oslogin_nss_cache[1886]: oslogin_cache_refresh[1886]: Refreshing passwd entry cache Sep 9 05:36:21.091078 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 9 05:36:21.091613 systemd[1]: Starting update-engine.service - Update Engine... Sep 9 05:36:21.096031 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 9 05:36:21.103766 jq[1900]: true Sep 9 05:36:21.103980 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 9 05:36:21.110019 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 9 05:36:21.110251 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 9 05:36:21.110547 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 9 05:36:21.110754 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 9 05:36:21.114568 extend-filesystems[1885]: Found /dev/sda6 Sep 9 05:36:21.123660 jq[1903]: true Sep 9 05:36:21.125907 google_oslogin_nss_cache[1886]: oslogin_cache_refresh[1886]: Failure getting users, quitting Sep 9 05:36:21.125907 google_oslogin_nss_cache[1886]: oslogin_cache_refresh[1886]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Sep 9 05:36:21.125887 oslogin_cache_refresh[1886]: Failure getting users, quitting Sep 9 05:36:21.126206 google_oslogin_nss_cache[1886]: oslogin_cache_refresh[1886]: Refreshing group entry cache Sep 9 05:36:21.125905 oslogin_cache_refresh[1886]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Sep 9 05:36:21.125956 oslogin_cache_refresh[1886]: Refreshing group entry cache Sep 9 05:36:21.126486 KVP[1887]: KVP starting; pid is:1887 Sep 9 05:36:21.127081 systemd[1]: motdgen.service: Deactivated successfully. Sep 9 05:36:21.127433 chronyd[1876]: chronyd version 4.7 starting (+CMDMON +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +NTS +SECHASH +IPV6 -DEBUG) Sep 9 05:36:21.127922 (ntainerd)[1911]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 9 05:36:21.128097 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 9 05:36:21.130414 google_oslogin_nss_cache[1886]: oslogin_cache_refresh[1886]: Failure getting groups, quitting Sep 9 05:36:21.130414 google_oslogin_nss_cache[1886]: oslogin_cache_refresh[1886]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Sep 9 05:36:21.130356 oslogin_cache_refresh[1886]: Failure getting groups, quitting Sep 9 05:36:21.130368 oslogin_cache_refresh[1886]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Sep 9 05:36:21.133010 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Sep 9 05:36:21.133171 KVP[1887]: KVP LIC Version: 3.1 Sep 9 05:36:21.136083 kernel: hv_utils: KVP IC version 4.0 Sep 9 05:36:21.141212 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Sep 9 05:36:21.168861 extend-filesystems[1885]: Found /dev/sda9 Sep 9 05:36:21.172082 extend-filesystems[1885]: Checking size of /dev/sda9 Sep 9 05:36:21.187248 systemd-logind[1897]: New seat seat0. Sep 9 05:36:21.188536 systemd-logind[1897]: Watching system buttons on /dev/input/event1 (AT Translated Set 2 keyboard) Sep 9 05:36:21.188735 systemd[1]: Started systemd-logind.service - User Login Management. Sep 9 05:36:21.190203 chronyd[1876]: Timezone right/UTC failed leap second check, ignoring Sep 9 05:36:21.190361 chronyd[1876]: Loaded seccomp filter (level 2) Sep 9 05:36:21.193240 systemd[1]: Started chronyd.service - NTP client/server. Sep 9 05:36:21.219727 update_engine[1899]: I20250909 05:36:21.219641 1899 main.cc:92] Flatcar Update Engine starting Sep 9 05:36:21.221143 extend-filesystems[1885]: Old size kept for /dev/sda9 Sep 9 05:36:21.221541 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 9 05:36:21.221793 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 9 05:36:21.297402 bash[1936]: Updated "/home/core/.ssh/authorized_keys" Sep 9 05:36:21.298926 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 9 05:36:21.307876 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Sep 9 05:36:21.365918 sshd_keygen[1940]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 9 05:36:21.387185 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 9 05:36:21.393598 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 9 05:36:21.415931 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Sep 9 05:36:21.420657 systemd[1]: issuegen.service: Deactivated successfully. Sep 9 05:36:21.420854 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 9 05:36:21.425859 setup-nvidia[1889]: Downloading Flatcar Container Linux Developer Container for version: 4452.0.0+nightly-20250908-2100 Sep 9 05:36:21.426964 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 9 05:36:21.440980 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Sep 9 05:36:21.462270 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 9 05:36:21.468531 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 9 05:36:21.473429 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Sep 9 05:36:21.478203 systemd[1]: Reached target getty.target - Login Prompts. Sep 9 05:36:21.479815 dbus-daemon[1879]: [system] SELinux support is enabled Sep 9 05:36:21.482278 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 9 05:36:21.486451 update_engine[1899]: I20250909 05:36:21.484396 1899 update_check_scheduler.cc:74] Next update check in 10m40s Sep 9 05:36:21.488882 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 9 05:36:21.488912 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 9 05:36:21.490642 dbus-daemon[1879]: [system] Successfully activated service 'org.freedesktop.systemd1' Sep 9 05:36:21.494302 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 9 05:36:21.494326 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 9 05:36:21.499568 systemd[1]: Started update-engine.service - Update Engine. Sep 9 05:36:21.505376 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 9 05:36:21.599402 coreos-metadata[1878]: Sep 09 05:36:21.599 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Sep 9 05:36:21.601733 coreos-metadata[1878]: Sep 09 05:36:21.601 INFO Fetch successful Sep 9 05:36:21.601891 coreos-metadata[1878]: Sep 09 05:36:21.601 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Sep 9 05:36:21.605778 coreos-metadata[1878]: Sep 09 05:36:21.605 INFO Fetch successful Sep 9 05:36:21.606077 coreos-metadata[1878]: Sep 09 05:36:21.606 INFO Fetching http://168.63.129.16/machine/366d8348-b91e-4252-829f-9d1669c18321/6b3fda3d%2D0e5b%2D4773%2D9894%2D1dabbfa5b6b8.%5Fci%2D4452.0.0%2Dn%2Db1cf16fc31?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Sep 9 05:36:21.633342 coreos-metadata[1878]: Sep 09 05:36:21.633 INFO Fetch successful Sep 9 05:36:21.633477 coreos-metadata[1878]: Sep 09 05:36:21.633 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Sep 9 05:36:21.640958 coreos-metadata[1878]: Sep 09 05:36:21.640 INFO Fetch successful Sep 9 05:36:21.665531 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Sep 9 05:36:21.670470 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 9 05:36:21.922994 locksmithd[2036]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 9 05:36:22.486496 containerd[1911]: time="2025-09-09T05:36:22Z" 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 9 05:36:22.487098 containerd[1911]: time="2025-09-09T05:36:22.487065300Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Sep 9 05:36:22.495904 containerd[1911]: time="2025-09-09T05:36:22.495869200Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="7.6µs" Sep 9 05:36:22.495904 containerd[1911]: time="2025-09-09T05:36:22.495896100Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Sep 9 05:36:22.495973 containerd[1911]: time="2025-09-09T05:36:22.495913900Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Sep 9 05:36:22.496091 containerd[1911]: time="2025-09-09T05:36:22.496051900Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Sep 9 05:36:22.496124 containerd[1911]: time="2025-09-09T05:36:22.496089600Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Sep 9 05:36:22.496124 containerd[1911]: time="2025-09-09T05:36:22.496113800Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 9 05:36:22.496195 containerd[1911]: time="2025-09-09T05:36:22.496172400Z" 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 9 05:36:22.496195 containerd[1911]: time="2025-09-09T05:36:22.496190900Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 9 05:36:22.496416 containerd[1911]: time="2025-09-09T05:36:22.496390100Z" 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 9 05:36:22.496416 containerd[1911]: time="2025-09-09T05:36:22.496411800Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 9 05:36:22.496469 containerd[1911]: time="2025-09-09T05:36:22.496422900Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 9 05:36:22.496469 containerd[1911]: time="2025-09-09T05:36:22.496431400Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Sep 9 05:36:22.496545 containerd[1911]: time="2025-09-09T05:36:22.496521600Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Sep 9 05:36:22.496774 containerd[1911]: time="2025-09-09T05:36:22.496752100Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 9 05:36:22.496808 containerd[1911]: time="2025-09-09T05:36:22.496788900Z" 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 9 05:36:22.496808 containerd[1911]: time="2025-09-09T05:36:22.496799400Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Sep 9 05:36:22.496946 containerd[1911]: time="2025-09-09T05:36:22.496897800Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Sep 9 05:36:22.498017 containerd[1911]: time="2025-09-09T05:36:22.497895500Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Sep 9 05:36:22.498116 containerd[1911]: time="2025-09-09T05:36:22.498090800Z" level=info msg="metadata content store policy set" policy=shared Sep 9 05:36:22.513849 containerd[1911]: time="2025-09-09T05:36:22.513816100Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Sep 9 05:36:22.513891 containerd[1911]: time="2025-09-09T05:36:22.513860600Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Sep 9 05:36:22.513891 containerd[1911]: time="2025-09-09T05:36:22.513876100Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Sep 9 05:36:22.513891 containerd[1911]: time="2025-09-09T05:36:22.513888200Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Sep 9 05:36:22.513952 containerd[1911]: time="2025-09-09T05:36:22.513901100Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Sep 9 05:36:22.513952 containerd[1911]: time="2025-09-09T05:36:22.513911300Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Sep 9 05:36:22.513952 containerd[1911]: time="2025-09-09T05:36:22.513922300Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Sep 9 05:36:22.514028 containerd[1911]: time="2025-09-09T05:36:22.513949800Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Sep 9 05:36:22.514028 containerd[1911]: time="2025-09-09T05:36:22.513965700Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Sep 9 05:36:22.514028 containerd[1911]: time="2025-09-09T05:36:22.513975800Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Sep 9 05:36:22.514028 containerd[1911]: time="2025-09-09T05:36:22.513984200Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Sep 9 05:36:22.514028 containerd[1911]: time="2025-09-09T05:36:22.513995100Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Sep 9 05:36:22.514157 containerd[1911]: time="2025-09-09T05:36:22.514116700Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Sep 9 05:36:22.514157 containerd[1911]: time="2025-09-09T05:36:22.514137700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Sep 9 05:36:22.514157 containerd[1911]: time="2025-09-09T05:36:22.514151600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Sep 9 05:36:22.514220 containerd[1911]: time="2025-09-09T05:36:22.514166200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Sep 9 05:36:22.514220 containerd[1911]: time="2025-09-09T05:36:22.514176600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Sep 9 05:36:22.514220 containerd[1911]: time="2025-09-09T05:36:22.514186300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Sep 9 05:36:22.514220 containerd[1911]: time="2025-09-09T05:36:22.514195800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Sep 9 05:36:22.514220 containerd[1911]: time="2025-09-09T05:36:22.514206000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Sep 9 05:36:22.514325 containerd[1911]: time="2025-09-09T05:36:22.514230800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Sep 9 05:36:22.514325 containerd[1911]: time="2025-09-09T05:36:22.514245800Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Sep 9 05:36:22.514325 containerd[1911]: time="2025-09-09T05:36:22.514255900Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Sep 9 05:36:22.514325 containerd[1911]: time="2025-09-09T05:36:22.514307400Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Sep 9 05:36:22.514325 containerd[1911]: time="2025-09-09T05:36:22.514320900Z" level=info msg="Start snapshots syncer" Sep 9 05:36:22.514431 containerd[1911]: time="2025-09-09T05:36:22.514347100Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Sep 9 05:36:22.514583 containerd[1911]: time="2025-09-09T05:36:22.514542200Z" 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 9 05:36:22.514694 containerd[1911]: time="2025-09-09T05:36:22.514604900Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Sep 9 05:36:22.514694 containerd[1911]: time="2025-09-09T05:36:22.514665200Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Sep 9 05:36:22.514791 containerd[1911]: time="2025-09-09T05:36:22.514765500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Sep 9 05:36:22.514823 containerd[1911]: time="2025-09-09T05:36:22.514793300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Sep 9 05:36:22.514823 containerd[1911]: time="2025-09-09T05:36:22.514804400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Sep 9 05:36:22.514823 containerd[1911]: time="2025-09-09T05:36:22.514814600Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Sep 9 05:36:22.514886 containerd[1911]: time="2025-09-09T05:36:22.514825400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Sep 9 05:36:22.514886 containerd[1911]: time="2025-09-09T05:36:22.514835000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Sep 9 05:36:22.514886 containerd[1911]: time="2025-09-09T05:36:22.514844900Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Sep 9 05:36:22.514886 containerd[1911]: time="2025-09-09T05:36:22.514863400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Sep 9 05:36:22.514886 containerd[1911]: time="2025-09-09T05:36:22.514876600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Sep 9 05:36:22.514987 containerd[1911]: time="2025-09-09T05:36:22.514900500Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Sep 9 05:36:22.514987 containerd[1911]: time="2025-09-09T05:36:22.514930900Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 9 05:36:22.514987 containerd[1911]: time="2025-09-09T05:36:22.514943600Z" 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 9 05:36:22.514987 containerd[1911]: time="2025-09-09T05:36:22.514951600Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 9 05:36:22.514987 containerd[1911]: time="2025-09-09T05:36:22.514959800Z" 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 9 05:36:22.514987 containerd[1911]: time="2025-09-09T05:36:22.514967300Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Sep 9 05:36:22.514987 containerd[1911]: time="2025-09-09T05:36:22.514975700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Sep 9 05:36:22.514987 containerd[1911]: time="2025-09-09T05:36:22.514985300Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Sep 9 05:36:22.515166 containerd[1911]: time="2025-09-09T05:36:22.515001600Z" level=info msg="runtime interface created" Sep 9 05:36:22.515166 containerd[1911]: time="2025-09-09T05:36:22.515006900Z" level=info msg="created NRI interface" Sep 9 05:36:22.515166 containerd[1911]: time="2025-09-09T05:36:22.515015300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Sep 9 05:36:22.515166 containerd[1911]: time="2025-09-09T05:36:22.515040900Z" level=info msg="Connect containerd service" Sep 9 05:36:22.515166 containerd[1911]: time="2025-09-09T05:36:22.515101200Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 9 05:36:22.515782 containerd[1911]: time="2025-09-09T05:36:22.515752600Z" 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 9 05:36:23.510545 containerd[1911]: time="2025-09-09T05:36:23.510492800Z" level=info msg="Start subscribing containerd event" Sep 9 05:36:23.510885 containerd[1911]: time="2025-09-09T05:36:23.510547500Z" level=info msg="Start recovering state" Sep 9 05:36:23.510885 containerd[1911]: time="2025-09-09T05:36:23.510663500Z" level=info msg="Start event monitor" Sep 9 05:36:23.510885 containerd[1911]: time="2025-09-09T05:36:23.510686300Z" level=info msg="Start cni network conf syncer for default" Sep 9 05:36:23.510885 containerd[1911]: time="2025-09-09T05:36:23.510716500Z" level=info msg="Start streaming server" Sep 9 05:36:23.510885 containerd[1911]: time="2025-09-09T05:36:23.510728000Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Sep 9 05:36:23.510885 containerd[1911]: time="2025-09-09T05:36:23.510735500Z" level=info msg="runtime interface starting up..." Sep 9 05:36:23.510885 containerd[1911]: time="2025-09-09T05:36:23.510741600Z" level=info msg="starting plugins..." Sep 9 05:36:23.510885 containerd[1911]: time="2025-09-09T05:36:23.510756500Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Sep 9 05:36:23.510885 containerd[1911]: time="2025-09-09T05:36:23.510705500Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 9 05:36:23.510885 containerd[1911]: time="2025-09-09T05:36:23.510881200Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 9 05:36:23.511146 containerd[1911]: time="2025-09-09T05:36:23.510950300Z" level=info msg="containerd successfully booted in 1.024876s" Sep 9 05:36:23.511052 systemd[1]: Started containerd.service - containerd container runtime. Sep 9 05:36:25.339791 waagent[2030]: 2025-09-09T05:36:25.339666Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Sep 9 05:36:25.344580 waagent[2030]: 2025-09-09T05:36:25.344530Z INFO Daemon Daemon OS: flatcar 4452.0.0 Sep 9 05:36:25.348280 waagent[2030]: 2025-09-09T05:36:25.348232Z INFO Daemon Daemon Python: 3.11.13 Sep 9 05:36:25.351824 waagent[2030]: 2025-09-09T05:36:25.351774Z INFO Daemon Daemon Run daemon Sep 9 05:36:25.355198 waagent[2030]: 2025-09-09T05:36:25.355144Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4452.0.0' Sep 9 05:36:25.361947 waagent[2030]: 2025-09-09T05:36:25.361893Z INFO Daemon Daemon Using waagent for provisioning Sep 9 05:36:25.366226 waagent[2030]: 2025-09-09T05:36:25.366183Z INFO Daemon Daemon Activate resource disk Sep 9 05:36:25.370052 waagent[2030]: 2025-09-09T05:36:25.370005Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Sep 9 05:36:25.377610 waagent[2030]: 2025-09-09T05:36:25.377560Z INFO Daemon Daemon Found device: sdb Sep 9 05:36:25.386812 waagent[2030]: 2025-09-09T05:36:25.386768Z INFO Daemon Daemon Examining partition table Sep 9 05:36:25.451627 waagent[2030]: 2025-09-09T05:36:25.451560Z INFO Daemon Daemon GPT not detected, determining filesystem Sep 9 05:36:25.580954 waagent[2030]: 2025-09-09T05:36:25.580867Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Sep 9 05:36:25.585788 waagent[2030]: 2025-09-09T05:36:25.585740Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Sep 9 05:36:25.904866 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#226 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 9 05:36:25.916155 kernel: sdb: sdb1 Sep 9 05:36:25.923911 waagent[2030]: 2025-09-09T05:36:25.923841Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Sep 9 05:36:25.942084 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#139 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 9 05:36:25.951076 kernel: sdb: sdb1 Sep 9 05:36:25.951209 waagent[2030]: 2025-09-09T05:36:25.951149Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Sep 9 05:36:29.995031 login[2032]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Sep 9 05:36:30.094731 login[2033]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Sep 9 05:36:30.102320 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 9 05:36:30.103342 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 9 05:36:30.110547 systemd-logind[1897]: New session 1 of user core. Sep 9 05:36:30.194922 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 9 05:36:30.197632 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 9 05:36:30.279644 (systemd)[2139]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 9 05:36:30.281834 systemd-logind[1897]: New session c1 of user core. Sep 9 05:36:31.081779 login[2032]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Sep 9 05:36:31.087409 systemd-logind[1897]: New session 2 of user core. Sep 9 05:36:31.452895 waagent[2030]: 2025-09-09T05:36:31.452772Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Sep 9 05:36:31.578091 kernel: EXT4-fs (sdb1): mounted filesystem bfbc6f26-d833-4728-9be7-7afa71d71046 r/w with ordered data mode. Quota mode: none. Sep 9 05:36:31.578489 waagent[2030]: 2025-09-09T05:36:31.578415Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Sep 9 05:36:31.584514 waagent[2030]: 2025-09-09T05:36:31.584463Z INFO Daemon Daemon Clean protocol and wireserver endpoint Sep 9 05:36:31.589290 waagent[2030]: 2025-09-09T05:36:31.589233Z INFO Daemon Daemon Running default provisioning handler Sep 9 05:36:31.599894 waagent[2030]: 2025-09-09T05:36:31.599844Z 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 9 05:36:31.610016 waagent[2030]: 2025-09-09T05:36:31.609963Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Sep 9 05:36:31.610040 systemd[2139]: Queued start job for default target default.target. Sep 9 05:36:31.610377 waagent[2030]: 2025-09-09T05:36:31.610208Z INFO Daemon Daemon cloud-init is enabled: False Sep 9 05:36:31.610975 waagent[2030]: 2025-09-09T05:36:31.610938Z INFO Daemon Daemon Copying ovf-env.xml Sep 9 05:36:31.637133 systemd[2139]: Created slice app.slice - User Application Slice. Sep 9 05:36:31.637215 systemd[2139]: Reached target paths.target - Paths. Sep 9 05:36:31.637269 systemd[2139]: Reached target timers.target - Timers. Sep 9 05:36:31.638484 systemd[2139]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 9 05:36:31.648868 systemd[2139]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 9 05:36:31.648927 systemd[2139]: Reached target sockets.target - Sockets. Sep 9 05:36:31.648978 systemd[2139]: Reached target basic.target - Basic System. Sep 9 05:36:31.649030 systemd[2139]: Reached target default.target - Main User Target. Sep 9 05:36:31.649078 systemd[2139]: Startup finished in 1.361s. Sep 9 05:36:31.649113 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 9 05:36:31.650471 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 9 05:36:31.651235 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 9 05:36:31.810405 waagent[2030]: 2025-09-09T05:36:31.810321Z INFO Daemon Daemon Successfully mounted dvd Sep 9 05:36:31.835361 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Sep 9 05:36:31.871915 waagent[2030]: 2025-09-09T05:36:31.835834Z INFO Daemon Daemon Detect protocol endpoint Sep 9 05:36:31.871915 waagent[2030]: 2025-09-09T05:36:31.836830Z INFO Daemon Daemon Clean protocol and wireserver endpoint Sep 9 05:36:31.871915 waagent[2030]: 2025-09-09T05:36:31.838267Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Sep 9 05:36:31.871915 waagent[2030]: 2025-09-09T05:36:31.839766Z INFO Daemon Daemon Test for route to 168.63.129.16 Sep 9 05:36:31.871915 waagent[2030]: 2025-09-09T05:36:31.841312Z INFO Daemon Daemon Route to 168.63.129.16 exists Sep 9 05:36:31.871915 waagent[2030]: 2025-09-09T05:36:31.842718Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Sep 9 05:36:31.871915 waagent[2030]: 2025-09-09T05:36:31.854310Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Sep 9 05:36:31.871915 waagent[2030]: 2025-09-09T05:36:31.854587Z INFO Daemon Daemon Wire protocol version:2012-11-30 Sep 9 05:36:31.871915 waagent[2030]: 2025-09-09T05:36:31.855880Z INFO Daemon Daemon Server preferred version:2015-04-05 Sep 9 05:36:31.985284 waagent[2030]: 2025-09-09T05:36:31.985203Z INFO Daemon Daemon Initializing goal state during protocol detection Sep 9 05:36:32.000389 waagent[2030]: 2025-09-09T05:36:31.985472Z INFO Daemon Daemon Forcing an update of the goal state. Sep 9 05:36:32.000389 waagent[2030]: 2025-09-09T05:36:31.990549Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Sep 9 05:36:32.034424 waagent[2030]: 2025-09-09T05:36:32.034369Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.175 Sep 9 05:36:32.064431 waagent[2030]: 2025-09-09T05:36:32.035046Z INFO Daemon Sep 9 05:36:32.064431 waagent[2030]: 2025-09-09T05:36:32.035360Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: fd3bd7c8-325b-4b8b-b497-d65c468cf592 eTag: 18335401570290722642 source: Fabric] Sep 9 05:36:32.064431 waagent[2030]: 2025-09-09T05:36:32.037046Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Sep 9 05:36:32.064431 waagent[2030]: 2025-09-09T05:36:32.037769Z INFO Daemon Sep 9 05:36:32.064431 waagent[2030]: 2025-09-09T05:36:32.038916Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Sep 9 05:36:32.064431 waagent[2030]: 2025-09-09T05:36:32.043978Z INFO Daemon Daemon Downloading artifacts profile blob Sep 9 05:36:32.174659 waagent[2030]: 2025-09-09T05:36:32.174583Z INFO Daemon Downloaded certificate {'thumbprint': '26D186E029EF5C3E634DE26C686E1088387A138F', 'hasPrivateKey': True} Sep 9 05:36:32.184793 waagent[2030]: 2025-09-09T05:36:32.175210Z INFO Daemon Fetch goal state completed Sep 9 05:36:32.205412 waagent[2030]: 2025-09-09T05:36:32.205362Z INFO Daemon Daemon Starting provisioning Sep 9 05:36:32.216071 waagent[2030]: 2025-09-09T05:36:32.206369Z INFO Daemon Daemon Handle ovf-env.xml. Sep 9 05:36:32.216071 waagent[2030]: 2025-09-09T05:36:32.207861Z INFO Daemon Daemon Set hostname [ci-4452.0.0-n-b1cf16fc31] Sep 9 05:36:32.272800 waagent[2030]: 2025-09-09T05:36:32.272743Z INFO Daemon Daemon Publish hostname [ci-4452.0.0-n-b1cf16fc31] Sep 9 05:36:32.285587 waagent[2030]: 2025-09-09T05:36:32.273171Z INFO Daemon Daemon Examine /proc/net/route for primary interface Sep 9 05:36:32.285587 waagent[2030]: 2025-09-09T05:36:32.273883Z INFO Daemon Daemon Primary interface is [eth0] Sep 9 05:36:32.285170 systemd-networkd[1592]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 9 05:36:32.305547 waagent[2030]: 2025-09-09T05:36:32.285644Z INFO Daemon Daemon Create user account if not exists Sep 9 05:36:32.305547 waagent[2030]: 2025-09-09T05:36:32.285895Z INFO Daemon Daemon User core already exists, skip useradd Sep 9 05:36:32.305547 waagent[2030]: 2025-09-09T05:36:32.287304Z INFO Daemon Daemon Configure sudoer Sep 9 05:36:32.285175 systemd-networkd[1592]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 9 05:36:32.285205 systemd-networkd[1592]: eth0: DHCP lease lost Sep 9 05:36:32.307278 waagent[2030]: 2025-09-09T05:36:32.307218Z INFO Daemon Daemon Configure sshd Sep 9 05:36:32.314137 waagent[2030]: 2025-09-09T05:36:32.314083Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Sep 9 05:36:32.314342 waagent[2030]: 2025-09-09T05:36:32.314294Z INFO Daemon Daemon Deploy ssh public key. Sep 9 05:36:32.378097 systemd-networkd[1592]: eth0: DHCPv4 address 10.200.8.10/24, gateway 10.200.8.1 acquired from 168.63.129.16 Sep 9 05:36:33.493777 waagent[2030]: 2025-09-09T05:36:33.493705Z INFO Daemon Daemon Provisioning complete Sep 9 05:36:33.502392 waagent[2030]: 2025-09-09T05:36:33.502349Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Sep 9 05:36:33.502631 waagent[2030]: 2025-09-09T05:36:33.502591Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Sep 9 05:36:33.504075 waagent[2030]: 2025-09-09T05:36:33.504030Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Sep 9 05:36:33.671095 waagent[2201]: 2025-09-09T05:36:33.671006Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Sep 9 05:36:33.671414 waagent[2201]: 2025-09-09T05:36:33.671163Z INFO ExtHandler ExtHandler OS: flatcar 4452.0.0 Sep 9 05:36:33.671414 waagent[2201]: 2025-09-09T05:36:33.671232Z INFO ExtHandler ExtHandler Python: 3.11.13 Sep 9 05:36:33.671414 waagent[2201]: 2025-09-09T05:36:33.671299Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Sep 9 05:36:33.736453 waagent[2201]: 2025-09-09T05:36:33.736392Z INFO ExtHandler ExtHandler Distro: flatcar-4452.0.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.13; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Sep 9 05:36:33.736626 waagent[2201]: 2025-09-09T05:36:33.736588Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 9 05:36:33.736705 waagent[2201]: 2025-09-09T05:36:33.736670Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 9 05:36:33.743069 waagent[2201]: 2025-09-09T05:36:33.743013Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Sep 9 05:36:33.748788 waagent[2201]: 2025-09-09T05:36:33.748713Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.175 Sep 9 05:36:33.749213 waagent[2201]: 2025-09-09T05:36:33.749172Z INFO ExtHandler Sep 9 05:36:33.749293 waagent[2201]: 2025-09-09T05:36:33.749261Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: c0331381-4118-4615-8dfb-85e50ad9e244 eTag: 18335401570290722642 source: Fabric] Sep 9 05:36:33.749604 waagent[2201]: 2025-09-09T05:36:33.749567Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Sep 9 05:36:33.750219 waagent[2201]: 2025-09-09T05:36:33.750180Z INFO ExtHandler Sep 9 05:36:33.750291 waagent[2201]: 2025-09-09T05:36:33.750260Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Sep 9 05:36:33.753638 waagent[2201]: 2025-09-09T05:36:33.753599Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Sep 9 05:36:33.811340 waagent[2201]: 2025-09-09T05:36:33.811281Z INFO ExtHandler Downloaded certificate {'thumbprint': '26D186E029EF5C3E634DE26C686E1088387A138F', 'hasPrivateKey': True} Sep 9 05:36:33.811789 waagent[2201]: 2025-09-09T05:36:33.811747Z INFO ExtHandler Fetch goal state completed Sep 9 05:36:33.822347 waagent[2201]: 2025-09-09T05:36:33.822297Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.4.2 1 Jul 2025 (Library: OpenSSL 3.4.2 1 Jul 2025) Sep 9 05:36:33.827137 waagent[2201]: 2025-09-09T05:36:33.827089Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2201 Sep 9 05:36:33.827277 waagent[2201]: 2025-09-09T05:36:33.827240Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Sep 9 05:36:33.827618 waagent[2201]: 2025-09-09T05:36:33.827579Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Sep 9 05:36:33.829232 waagent[2201]: 2025-09-09T05:36:33.829191Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4452.0.0', '', 'Flatcar Container Linux by Kinvolk'] Sep 9 05:36:33.829653 waagent[2201]: 2025-09-09T05:36:33.829614Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4452.0.0', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Sep 9 05:36:33.829801 waagent[2201]: 2025-09-09T05:36:33.829766Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Sep 9 05:36:33.830418 waagent[2201]: 2025-09-09T05:36:33.830379Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Sep 9 05:36:33.950584 waagent[2201]: 2025-09-09T05:36:33.950531Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Sep 9 05:36:33.950783 waagent[2201]: 2025-09-09T05:36:33.950747Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Sep 9 05:36:33.957403 waagent[2201]: 2025-09-09T05:36:33.957367Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Sep 9 05:36:33.963912 systemd[1]: Reload requested from client PID 2216 ('systemctl') (unit waagent.service)... Sep 9 05:36:33.963928 systemd[1]: Reloading... Sep 9 05:36:34.024086 zram_generator::config[2256]: No configuration found. Sep 9 05:36:34.305163 systemd[1]: Reloading finished in 340 ms. Sep 9 05:36:34.326452 waagent[2201]: 2025-09-09T05:36:34.326390Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Sep 9 05:36:34.326570 waagent[2201]: 2025-09-09T05:36:34.326536Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Sep 9 05:36:35.031118 waagent[2201]: 2025-09-09T05:36:35.031014Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Sep 9 05:36:35.031479 waagent[2201]: 2025-09-09T05:36:35.031383Z 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 9 05:36:35.032192 waagent[2201]: 2025-09-09T05:36:35.032139Z INFO ExtHandler ExtHandler Starting env monitor service. Sep 9 05:36:35.032641 waagent[2201]: 2025-09-09T05:36:35.032598Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Sep 9 05:36:35.032712 waagent[2201]: 2025-09-09T05:36:35.032667Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 9 05:36:35.032943 waagent[2201]: 2025-09-09T05:36:35.032907Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 9 05:36:35.033248 waagent[2201]: 2025-09-09T05:36:35.033206Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 9 05:36:35.033475 waagent[2201]: 2025-09-09T05:36:35.033432Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Sep 9 05:36:35.033573 waagent[2201]: 2025-09-09T05:36:35.033518Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Sep 9 05:36:35.033686 waagent[2201]: 2025-09-09T05:36:35.033646Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Sep 9 05:36:35.034040 waagent[2201]: 2025-09-09T05:36:35.034003Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 9 05:36:35.034330 waagent[2201]: 2025-09-09T05:36:35.034275Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Sep 9 05:36:35.034393 waagent[2201]: 2025-09-09T05:36:35.034353Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Sep 9 05:36:35.034891 waagent[2201]: 2025-09-09T05:36:35.034846Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Sep 9 05:36:35.034935 waagent[2201]: 2025-09-09T05:36:35.034888Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Sep 9 05:36:35.034935 waagent[2201]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Sep 9 05:36:35.034935 waagent[2201]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Sep 9 05:36:35.034935 waagent[2201]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Sep 9 05:36:35.034935 waagent[2201]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Sep 9 05:36:35.034935 waagent[2201]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Sep 9 05:36:35.034935 waagent[2201]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Sep 9 05:36:35.035106 waagent[2201]: 2025-09-09T05:36:35.035063Z INFO EnvHandler ExtHandler Configure routes Sep 9 05:36:35.035570 waagent[2201]: 2025-09-09T05:36:35.035533Z INFO EnvHandler ExtHandler Gateway:None Sep 9 05:36:35.036098 waagent[2201]: 2025-09-09T05:36:35.036051Z INFO EnvHandler ExtHandler Routes:None Sep 9 05:36:35.040971 waagent[2201]: 2025-09-09T05:36:35.040925Z INFO ExtHandler ExtHandler Sep 9 05:36:35.041307 waagent[2201]: 2025-09-09T05:36:35.041268Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 658a8234-7c24-4cad-bc04-50182da6e632 correlation aeace05a-96b1-44c8-8169-2c9981de273e created: 2025-09-09T05:34:42.860901Z] Sep 9 05:36:35.042436 waagent[2201]: 2025-09-09T05:36:35.042389Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Sep 9 05:36:35.044437 waagent[2201]: 2025-09-09T05:36:35.044394Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Sep 9 05:36:35.104552 waagent[2201]: 2025-09-09T05:36:35.104507Z 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 9 05:36:35.104552 waagent[2201]: Try `iptables -h' or 'iptables --help' for more information.) Sep 9 05:36:35.104928 waagent[2201]: 2025-09-09T05:36:35.104889Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 7B32D48E-5A53-48E0-A4B5-CB81C4BA9DA8;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Sep 9 05:36:35.175936 waagent[2201]: 2025-09-09T05:36:35.175857Z INFO MonitorHandler ExtHandler Network interfaces: Sep 9 05:36:35.175936 waagent[2201]: Executing ['ip', '-a', '-o', 'link']: Sep 9 05:36:35.175936 waagent[2201]: 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 9 05:36:35.175936 waagent[2201]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:ed:8d:75:91:2a brd ff:ff:ff:ff:ff:ff Sep 9 05:36:35.175936 waagent[2201]: Executing ['ip', '-4', '-a', '-o', 'address']: Sep 9 05:36:35.175936 waagent[2201]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Sep 9 05:36:35.175936 waagent[2201]: 2: eth0 inet 10.200.8.10/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Sep 9 05:36:35.175936 waagent[2201]: Executing ['ip', '-6', '-a', '-o', 'address']: Sep 9 05:36:35.175936 waagent[2201]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Sep 9 05:36:35.175936 waagent[2201]: 2: eth0 inet6 fe80::7eed:8dff:fe75:912a/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Sep 9 05:36:35.308842 waagent[2201]: 2025-09-09T05:36:35.308747Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Sep 9 05:36:35.308842 waagent[2201]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Sep 9 05:36:35.308842 waagent[2201]: pkts bytes target prot opt in out source destination Sep 9 05:36:35.308842 waagent[2201]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Sep 9 05:36:35.308842 waagent[2201]: pkts bytes target prot opt in out source destination Sep 9 05:36:35.308842 waagent[2201]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Sep 9 05:36:35.308842 waagent[2201]: pkts bytes target prot opt in out source destination Sep 9 05:36:35.308842 waagent[2201]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Sep 9 05:36:35.308842 waagent[2201]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Sep 9 05:36:35.308842 waagent[2201]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Sep 9 05:36:35.312146 waagent[2201]: 2025-09-09T05:36:35.312106Z INFO EnvHandler ExtHandler Current Firewall rules: Sep 9 05:36:35.312146 waagent[2201]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Sep 9 05:36:35.312146 waagent[2201]: pkts bytes target prot opt in out source destination Sep 9 05:36:35.312146 waagent[2201]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Sep 9 05:36:35.312146 waagent[2201]: pkts bytes target prot opt in out source destination Sep 9 05:36:35.312146 waagent[2201]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Sep 9 05:36:35.312146 waagent[2201]: pkts bytes target prot opt in out source destination Sep 9 05:36:35.312146 waagent[2201]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Sep 9 05:36:35.312146 waagent[2201]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Sep 9 05:36:35.312146 waagent[2201]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Sep 9 05:36:35.312390 waagent[2201]: 2025-09-09T05:36:35.312358Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Sep 9 05:36:35.312538 waagent[2201]: 2025-09-09T05:36:35.312508Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Sep 9 05:36:44.970612 chronyd[1876]: Selected source PHC0 Sep 9 05:36:59.198794 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Sep 9 05:37:00.074327 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 9 05:37:00.075546 systemd[1]: Started sshd@0-10.200.8.10:22-10.200.16.10:36392.service - OpenSSH per-connection server daemon (10.200.16.10:36392). Sep 9 05:37:01.204351 sshd[2349]: Accepted publickey for core from 10.200.16.10 port 36392 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:37:01.205613 sshd-session[2349]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:37:01.209773 systemd-logind[1897]: New session 3 of user core. Sep 9 05:37:01.226169 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 9 05:37:01.942941 systemd[1]: Started sshd@1-10.200.8.10:22-10.200.16.10:36398.service - OpenSSH per-connection server daemon (10.200.16.10:36398). Sep 9 05:37:02.612535 sshd[2355]: Accepted publickey for core from 10.200.16.10 port 36398 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:37:02.613739 sshd-session[2355]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:37:02.617845 systemd-logind[1897]: New session 4 of user core. Sep 9 05:37:02.627186 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 9 05:37:03.115911 sshd[2358]: Connection closed by 10.200.16.10 port 36398 Sep 9 05:37:03.116299 sshd-session[2355]: pam_unix(sshd:session): session closed for user core Sep 9 05:37:03.119768 systemd[1]: sshd@1-10.200.8.10:22-10.200.16.10:36398.service: Deactivated successfully. Sep 9 05:37:03.121600 systemd[1]: session-4.scope: Deactivated successfully. Sep 9 05:37:03.122387 systemd-logind[1897]: Session 4 logged out. Waiting for processes to exit. Sep 9 05:37:03.123346 systemd-logind[1897]: Removed session 4. Sep 9 05:37:03.233001 systemd[1]: Started sshd@2-10.200.8.10:22-10.200.16.10:36412.service - OpenSSH per-connection server daemon (10.200.16.10:36412). Sep 9 05:37:04.227909 sshd[2364]: Accepted publickey for core from 10.200.16.10 port 36412 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:37:04.278543 sshd-session[2364]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:37:04.282780 systemd-logind[1897]: New session 5 of user core. Sep 9 05:37:04.295174 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 9 05:37:04.678505 sshd[2367]: Connection closed by 10.200.16.10 port 36412 Sep 9 05:37:04.678861 sshd-session[2364]: pam_unix(sshd:session): session closed for user core Sep 9 05:37:04.682376 systemd[1]: sshd@2-10.200.8.10:22-10.200.16.10:36412.service: Deactivated successfully. Sep 9 05:37:04.683922 systemd[1]: session-5.scope: Deactivated successfully. Sep 9 05:37:04.684614 systemd-logind[1897]: Session 5 logged out. Waiting for processes to exit. Sep 9 05:37:04.685557 systemd-logind[1897]: Removed session 5. Sep 9 05:37:06.498003 update_engine[1899]: I20250909 05:37:06.497910 1899 update_attempter.cc:509] Updating boot flags... Sep 9 05:37:14.806278 systemd[1]: Started sshd@3-10.200.8.10:22-10.200.16.10:40922.service - OpenSSH per-connection server daemon (10.200.16.10:40922). Sep 9 05:37:15.443817 sshd[2489]: Accepted publickey for core from 10.200.16.10 port 40922 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:37:15.445030 sshd-session[2489]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:37:15.449199 systemd-logind[1897]: New session 6 of user core. Sep 9 05:37:15.458189 systemd[1]: Started session-6.scope - Session 6 of User core. Sep 9 05:37:15.893881 sshd[2492]: Connection closed by 10.200.16.10 port 40922 Sep 9 05:37:15.894236 sshd-session[2489]: pam_unix(sshd:session): session closed for user core Sep 9 05:37:15.897670 systemd[1]: sshd@3-10.200.8.10:22-10.200.16.10:40922.service: Deactivated successfully. Sep 9 05:37:15.899258 systemd[1]: session-6.scope: Deactivated successfully. Sep 9 05:37:15.899901 systemd-logind[1897]: Session 6 logged out. Waiting for processes to exit. Sep 9 05:37:15.900794 systemd-logind[1897]: Removed session 6. Sep 9 05:37:16.010089 systemd[1]: Started sshd@4-10.200.8.10:22-10.200.16.10:40934.service - OpenSSH per-connection server daemon (10.200.16.10:40934). Sep 9 05:37:16.650282 sshd[2498]: Accepted publickey for core from 10.200.16.10 port 40934 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:37:16.651474 sshd-session[2498]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:37:16.655859 systemd-logind[1897]: New session 7 of user core. Sep 9 05:37:16.670180 systemd[1]: Started session-7.scope - Session 7 of User core. Sep 9 05:37:17.101729 sshd[2501]: Connection closed by 10.200.16.10 port 40934 Sep 9 05:37:17.102100 sshd-session[2498]: pam_unix(sshd:session): session closed for user core Sep 9 05:37:17.105492 systemd[1]: sshd@4-10.200.8.10:22-10.200.16.10:40934.service: Deactivated successfully. Sep 9 05:37:17.107156 systemd[1]: session-7.scope: Deactivated successfully. Sep 9 05:37:17.107834 systemd-logind[1897]: Session 7 logged out. Waiting for processes to exit. Sep 9 05:37:17.108736 systemd-logind[1897]: Removed session 7. Sep 9 05:37:21.785391 setup-nvidia[2506]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 9 05:37:21.785744 setup-nvidia[2506]: Dload Upload Total Spent Left Speed Sep 9 05:37:27.232958 systemd[1]: Started sshd@5-10.200.8.10:22-10.200.16.10:35714.service - OpenSSH per-connection server daemon (10.200.16.10:35714). Sep 9 05:37:27.881102 sshd[2508]: Accepted publickey for core from 10.200.16.10 port 35714 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:37:27.886359 sshd-session[2508]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:37:27.890577 systemd-logind[1897]: New session 8 of user core. Sep 9 05:37:27.904201 systemd[1]: Started session-8.scope - Session 8 of User core. Sep 9 05:37:28.454261 sshd[2511]: Connection closed by 10.200.16.10 port 35714 Sep 9 05:37:28.454645 sshd-session[2508]: pam_unix(sshd:session): session closed for user core Sep 9 05:37:28.458364 systemd[1]: sshd@5-10.200.8.10:22-10.200.16.10:35714.service: Deactivated successfully. Sep 9 05:37:28.460304 systemd[1]: session-8.scope: Deactivated successfully. Sep 9 05:37:28.461096 systemd-logind[1897]: Session 8 logged out. Waiting for processes to exit. Sep 9 05:37:28.461994 systemd-logind[1897]: Removed session 8. Sep 9 05:37:28.566522 systemd[1]: Started sshd@6-10.200.8.10:22-10.200.16.10:35728.service - OpenSSH per-connection server daemon (10.200.16.10:35728). Sep 9 05:37:29.206437 sshd[2517]: Accepted publickey for core from 10.200.16.10 port 35728 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:37:29.208329 sshd-session[2517]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:37:29.212734 systemd-logind[1897]: New session 9 of user core. Sep 9 05:37:29.229173 systemd[1]: Started session-9.scope - Session 9 of User core. Sep 9 05:37:29.657550 sshd[2520]: Connection closed by 10.200.16.10 port 35728 Sep 9 05:37:29.657893 sshd-session[2517]: pam_unix(sshd:session): session closed for user core Sep 9 05:37:29.661278 systemd[1]: sshd@6-10.200.8.10:22-10.200.16.10:35728.service: Deactivated successfully. Sep 9 05:37:29.662827 systemd[1]: session-9.scope: Deactivated successfully. Sep 9 05:37:29.663509 systemd-logind[1897]: Session 9 logged out. Waiting for processes to exit. Sep 9 05:37:29.664399 systemd-logind[1897]: Removed session 9. Sep 9 05:37:39.865956 systemd[1]: Started sshd@7-10.200.8.10:22-10.200.16.10:43266.service - OpenSSH per-connection server daemon (10.200.16.10:43266). Sep 9 05:37:40.507368 sshd[2526]: Accepted publickey for core from 10.200.16.10 port 43266 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:37:40.508607 sshd-session[2526]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:37:40.512737 systemd-logind[1897]: New session 10 of user core. Sep 9 05:37:40.522172 systemd[1]: Started session-10.scope - Session 10 of User core. Sep 9 05:37:40.958113 sshd[2529]: Connection closed by 10.200.16.10 port 43266 Sep 9 05:37:40.958393 sshd-session[2526]: pam_unix(sshd:session): session closed for user core Sep 9 05:37:40.961694 systemd[1]: sshd@7-10.200.8.10:22-10.200.16.10:43266.service: Deactivated successfully. Sep 9 05:37:40.963486 systemd[1]: session-10.scope: Deactivated successfully. Sep 9 05:37:40.964274 systemd-logind[1897]: Session 10 logged out. Waiting for processes to exit. Sep 9 05:37:40.965241 systemd-logind[1897]: Removed session 10. Sep 9 05:37:41.070634 systemd[1]: Started sshd@8-10.200.8.10:22-10.200.16.10:38786.service - OpenSSH per-connection server daemon (10.200.16.10:38786). Sep 9 05:37:41.709254 sshd[2535]: Accepted publickey for core from 10.200.16.10 port 38786 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:37:41.710450 sshd-session[2535]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:37:41.714708 systemd-logind[1897]: New session 11 of user core. Sep 9 05:37:41.723179 systemd[1]: Started session-11.scope - Session 11 of User core. Sep 9 05:37:42.160332 sshd[2538]: Connection closed by 10.200.16.10 port 38786 Sep 9 05:37:42.160679 sshd-session[2535]: pam_unix(sshd:session): session closed for user core Sep 9 05:37:42.164102 systemd[1]: sshd@8-10.200.8.10:22-10.200.16.10:38786.service: Deactivated successfully. Sep 9 05:37:42.165659 systemd[1]: session-11.scope: Deactivated successfully. Sep 9 05:37:42.166367 systemd-logind[1897]: Session 11 logged out. Waiting for processes to exit. Sep 9 05:37:42.167267 systemd-logind[1897]: Removed session 11. Sep 9 05:37:44.893090 setup-nvidia[2506]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 530M 0 240k 0 0 244k 0 0:36:58 --:--:-- 0:36:58 244k\u000d 2 530M 2 14.1M 0 0 7489k 0 0:01:12 0:00:01 0:01:11 7488k\u000d 7 530M 7 38.3M 0 0 13.1M 0 0:00:40 0:00:02 0:00:38 13.1M\u000d 11 530M 11 62.6M 0 0 16.1M 0 0:00:32 0:00:03 0:00:29 16.1M\u000d 16 530M 16 86.3M 0 0 17.4M 0 0:00:30 0:00:04 0:00:26 17.4M\u000d 20 530M 20 109M 0 0 18.4M 0 0:00:28 0:00:05 0:00:23 21.9M\u000d 25 530M 25 132M 0 0 19.1M 0 0:00:27 0:00:06 0:00:21 23.7M\u000d 29 530M 29 156M 0 0 19.7M 0 0:00:26 0:00:07 0:00:19 23.5M\u000d 34 530M 34 180M 0 0 20.3M 0 0:00:26 0:00:08 0:00:18 23.6M\u000d 38 530M 38 205M 0 0 20.8M 0 0:00:25 0:00:09 0:00:16 24.2M\u000d 43 530M 43 230M 0 0 21.2M 0 0:00:25 0:00:10 0:00:15 24.5M\u000d 47 530M 47 254M 0 0 21.2M 0 0:00:24 0:00:11 0:00:13 24.2M\u000d 52 530M 52 278M 0 0 21.4M 0 0:00:24 0:00:12 0:00:12 24.2M\u000d 57 530M 57 303M 0 0 21.8M 0 0:00:24 0:00:13 0:00:11 24.4M\u000d 61 530M 61 328M 0 0 22.0M 0 0:00:24 0:00:14 0:00:10 24.4M\u000d 66 530M 66 353M 0 0 22.2M 0 0:00:23 0:00:15 0:00:08 24.6M\u000d 71 530M 71 381M 0 0 22.4M 0 0:00:23 0:00:16 0:00:07 25.1M\u000d 76 530M 76 404M 0 0 22.5M 0 0:00:23 0:00:17 0:00:06 25.2M\u000d 80 530M 80 428M 0 0 22.6M 0 0:00:23 0:00:18 0:00:05 25.0M\u000d 85 530M 85 453M 0 0 22.7M 0 0:00:23 0:00:19 0:00:04 24.8M\u000d 89 530M 89 476M 0 0 22.8M 0 0:00:23 0:00:20 0:00:03 24.4M\u000d 94 530M 94 501M 0 0 22.9M 0 0:00:23 0:00:21 0:00:02 24.7M\u000d 99 530M 99 526M 0 0 22.9M 0 0:00:23 0:00:22 0:00:01 24.2M\u000d100 530M 100 530M 0 0 22.9M 0 0:00:23 0:00:23 --:--:-- 24.2M Sep 9 05:37:52.277499 systemd[1]: Started sshd@9-10.200.8.10:22-10.200.16.10:59980.service - OpenSSH per-connection server daemon (10.200.16.10:59980). Sep 9 05:37:52.940269 sshd[2554]: Accepted publickey for core from 10.200.16.10 port 59980 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:37:52.941521 sshd-session[2554]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:37:52.949305 systemd-logind[1897]: New session 12 of user core. Sep 9 05:37:52.954228 systemd[1]: Started session-12.scope - Session 12 of User core. Sep 9 05:37:53.397424 sshd[2557]: Connection closed by 10.200.16.10 port 59980 Sep 9 05:37:53.400236 sshd-session[2554]: pam_unix(sshd:session): session closed for user core Sep 9 05:37:53.406567 systemd[1]: sshd@9-10.200.8.10:22-10.200.16.10:59980.service: Deactivated successfully. Sep 9 05:37:53.407393 systemd-logind[1897]: Session 12 logged out. Waiting for processes to exit. Sep 9 05:37:53.409666 systemd[1]: session-12.scope: Deactivated successfully. Sep 9 05:37:53.413414 systemd-logind[1897]: Removed session 12. Sep 9 05:37:53.512743 systemd[1]: Started sshd@10-10.200.8.10:22-10.200.16.10:59988.service - OpenSSH per-connection server daemon (10.200.16.10:59988). Sep 9 05:37:54.169783 sshd[2563]: Accepted publickey for core from 10.200.16.10 port 59988 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:37:54.172646 sshd-session[2563]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:37:54.179467 systemd-logind[1897]: New session 13 of user core. Sep 9 05:37:54.184175 systemd[1]: Started session-13.scope - Session 13 of User core. Sep 9 05:37:54.626005 sshd[2566]: Connection closed by 10.200.16.10 port 59988 Sep 9 05:37:54.627234 sshd-session[2563]: pam_unix(sshd:session): session closed for user core Sep 9 05:37:54.630323 systemd-logind[1897]: Session 13 logged out. Waiting for processes to exit. Sep 9 05:37:54.631191 systemd[1]: sshd@10-10.200.8.10:22-10.200.16.10:59988.service: Deactivated successfully. Sep 9 05:37:54.632992 systemd[1]: session-13.scope: Deactivated successfully. Sep 9 05:37:54.636252 systemd-logind[1897]: Removed session 13. Sep 9 05:37:56.293739 setup-nvidia[1889]: Downloading NVIDIA 535.261.03 Driver Sep 9 05:37:56.298120 setup-nvidia[2573]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 9 05:37:56.298366 setup-nvidia[2573]: Dload Upload Total Spent Left Speed Sep 9 05:37:58.310774 setup-nvidia[2573]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 325M 0 16384 0 0 35501 0 2:40:27 --:--:-- 2:40:27 35463\u000d 59 325M 59 193M 0 0 143M 0 0:00:02 0:00:01 0:00:01 143M\u000d100 325M 100 325M 0 0 161M 0 0:00:02 0:00:02 --:--:-- 161M Sep 9 05:37:58.364194 setup-nvidia[1889]: Extract the NVIDIA Driver Installer 535.261.03 Sep 9 05:37:58.364435 setup-nvidia[1889]: /opt/nvidia/workdir/nvidia-workdir / Sep 9 05:37:58.399678 setup-nvidia[2576]: Creating directory NVIDIA-Linux-x86_64-535.261.03 Sep 9 05:37:58.644927 setup-nvidia[2576]: Verifying archive integrity... OK Sep 9 05:37:59.401295 setup-nvidia[2576]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.261.03 Sep 9 05:38:01.163580 setup-nvidia[2602]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Sep 9 05:38:01.215396 setup-nvidia[1889]: / Sep 9 05:38:01.215664 setup-nvidia[1889]: Spawn system-nspawn container to install the NVIDIA drivers Sep 9 05:38:01.351073 sudo[2609]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4452.0.0+nightly-20250908-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 9 05:38:01.352790 sudo[2609]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 9 05:38:01.358306 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Sep 9 05:38:01.359390 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Sep 9 05:38:01.393083 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Sep 9 05:38:01.398850 systemd[1]: Starting user@0.service - User Manager for UID 0... Sep 9 05:38:01.424305 (systemd)[2612]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Sep 9 05:38:01.427422 systemd-logind[1897]: New session c3 of user root. Sep 9 05:38:01.591825 systemd[2612]: Queued start job for default target default.target. Sep 9 05:38:01.617179 systemd[2612]: Created slice app.slice - User Application Slice. Sep 9 05:38:01.617208 systemd[2612]: Reached target paths.target - Paths. Sep 9 05:38:01.617255 systemd[2612]: Reached target timers.target - Timers. Sep 9 05:38:01.618478 systemd[2612]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 9 05:38:01.628947 systemd[2612]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 9 05:38:01.629006 systemd[2612]: Reached target sockets.target - Sockets. Sep 9 05:38:01.629076 systemd[2612]: Reached target basic.target - Basic System. Sep 9 05:38:01.629133 systemd[2612]: Reached target default.target - Main User Target. Sep 9 05:38:01.629166 systemd[2612]: Startup finished in 194ms. Sep 9 05:38:01.629221 systemd[1]: Started user@0.service - User Manager for UID 0. Sep 9 05:38:01.631764 systemd[1]: Started session-c2.scope - Session c2 of User root. Sep 9 05:38:01.675084 kernel: loop6: detected capacity change from 0 to 12644352 Sep 9 05:38:01.683076 kernel: loop6: p9 Sep 9 05:38:01.749483 setup-nvidia[2621]: ░ Spawning container flatcardevelopercontainer-4452.0.0nightly-20250908-2100.bin on /opt/nvidia/workdir/flatcar_developer_container-4452.0.0+nightly-20250908-2100.bin. Sep 9 05:38:02.555686 kernel: EXT4-fs (loop6p9): write access unavailable, skipping orphan cleanup Sep 9 05:38:02.555805 kernel: EXT4-fs (loop6p9): mounted filesystem 70a79864-9d90-4624-98c5-2d7a4a92e0f3 ro without journal. Quota mode: none. Sep 9 05:38:04.739411 systemd[1]: Started sshd@11-10.200.8.10:22-10.200.16.10:49118.service - OpenSSH per-connection server daemon (10.200.16.10:49118). Sep 9 05:38:05.382322 sshd[2687]: Accepted publickey for core from 10.200.16.10 port 49118 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:38:05.383567 sshd-session[2687]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:38:05.387970 systemd-logind[1897]: New session 14 of user core. Sep 9 05:38:05.409194 systemd[1]: Started session-14.scope - Session 14 of User core. Sep 9 05:38:05.833320 sshd[2732]: Connection closed by 10.200.16.10 port 49118 Sep 9 05:38:05.833642 sshd-session[2687]: pam_unix(sshd:session): session closed for user core Sep 9 05:38:05.836916 systemd[1]: sshd@11-10.200.8.10:22-10.200.16.10:49118.service: Deactivated successfully. Sep 9 05:38:05.838466 systemd[1]: session-14.scope: Deactivated successfully. Sep 9 05:38:05.839146 systemd-logind[1897]: Session 14 logged out. Waiting for processes to exit. Sep 9 05:38:05.840011 systemd-logind[1897]: Removed session 14. Sep 9 05:38:05.946199 systemd[1]: Started sshd@12-10.200.8.10:22-10.200.16.10:49134.service - OpenSSH per-connection server daemon (10.200.16.10:49134). Sep 9 05:38:06.583556 sshd[2780]: Accepted publickey for core from 10.200.16.10 port 49134 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:38:06.584709 sshd-session[2780]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:38:06.588943 systemd-logind[1897]: New session 15 of user core. Sep 9 05:38:06.598175 systemd[1]: Started session-15.scope - Session 15 of User core. Sep 9 05:38:07.033690 sshd[2978]: Connection closed by 10.200.16.10 port 49134 Sep 9 05:38:07.034095 sshd-session[2780]: pam_unix(sshd:session): session closed for user core Sep 9 05:38:07.037539 systemd[1]: sshd@12-10.200.8.10:22-10.200.16.10:49134.service: Deactivated successfully. Sep 9 05:38:07.039130 systemd[1]: session-15.scope: Deactivated successfully. Sep 9 05:38:07.039772 systemd-logind[1897]: Session 15 logged out. Waiting for processes to exit. Sep 9 05:38:07.040658 systemd-logind[1897]: Removed session 15. Sep 9 05:38:17.148401 systemd[1]: Started sshd@13-10.200.8.10:22-10.200.16.10:40822.service - OpenSSH per-connection server daemon (10.200.16.10:40822). Sep 9 05:38:17.789455 sshd[3627]: Accepted publickey for core from 10.200.16.10 port 40822 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:38:17.790695 sshd-session[3627]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:38:17.794936 systemd-logind[1897]: New session 16 of user core. Sep 9 05:38:17.815190 systemd[1]: Started session-16.scope - Session 16 of User core. Sep 9 05:38:18.241960 sshd[3634]: Connection closed by 10.200.16.10 port 40822 Sep 9 05:38:18.242264 sshd-session[3627]: pam_unix(sshd:session): session closed for user core Sep 9 05:38:18.245813 systemd[1]: sshd@13-10.200.8.10:22-10.200.16.10:40822.service: Deactivated successfully. Sep 9 05:38:18.247413 systemd[1]: session-16.scope: Deactivated successfully. Sep 9 05:38:18.248101 systemd-logind[1897]: Session 16 logged out. Waiting for processes to exit. Sep 9 05:38:18.248995 systemd-logind[1897]: Removed session 16. Sep 9 05:38:18.357491 systemd[1]: Started sshd@14-10.200.8.10:22-10.200.16.10:40832.service - OpenSSH per-connection server daemon (10.200.16.10:40832). Sep 9 05:38:18.997545 sshd[3640]: Accepted publickey for core from 10.200.16.10 port 40832 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:38:19.027621 sshd-session[3640]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:38:19.031849 systemd-logind[1897]: New session 17 of user core. Sep 9 05:38:19.043186 systemd[1]: Started session-17.scope - Session 17 of User core. Sep 9 05:38:19.450763 sshd[3643]: Connection closed by 10.200.16.10 port 40832 Sep 9 05:38:19.451129 sshd-session[3640]: pam_unix(sshd:session): session closed for user core Sep 9 05:38:19.454158 systemd[1]: sshd@14-10.200.8.10:22-10.200.16.10:40832.service: Deactivated successfully. Sep 9 05:38:19.455768 systemd[1]: session-17.scope: Deactivated successfully. Sep 9 05:38:19.456938 systemd-logind[1897]: Session 17 logged out. Waiting for processes to exit. Sep 9 05:38:19.458001 systemd-logind[1897]: Removed session 17. Sep 9 05:38:29.566842 systemd[1]: Started sshd@15-10.200.8.10:22-10.200.16.10:50990.service - OpenSSH per-connection server daemon (10.200.16.10:50990). Sep 9 05:38:30.226560 sshd[7321]: Accepted publickey for core from 10.200.16.10 port 50990 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:38:30.227852 sshd-session[7321]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:38:30.233195 systemd-logind[1897]: New session 18 of user core. Sep 9 05:38:30.247234 systemd[1]: Started session-18.scope - Session 18 of User core. Sep 9 05:38:30.682195 sshd[7536]: Connection closed by 10.200.16.10 port 50990 Sep 9 05:38:30.682772 sshd-session[7321]: pam_unix(sshd:session): session closed for user core Sep 9 05:38:30.687035 systemd-logind[1897]: Session 18 logged out. Waiting for processes to exit. Sep 9 05:38:30.689494 systemd[1]: sshd@15-10.200.8.10:22-10.200.16.10:50990.service: Deactivated successfully. Sep 9 05:38:30.691345 systemd[1]: session-18.scope: Deactivated successfully. Sep 9 05:38:30.693869 systemd-logind[1897]: Removed session 18. Sep 9 05:38:30.802287 systemd[1]: Started sshd@16-10.200.8.10:22-10.200.16.10:44210.service - OpenSSH per-connection server daemon (10.200.16.10:44210). Sep 9 05:38:31.449931 sshd[7740]: Accepted publickey for core from 10.200.16.10 port 44210 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:38:31.450164 sshd-session[7740]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:38:31.459337 systemd-logind[1897]: New session 19 of user core. Sep 9 05:38:31.467215 systemd[1]: Started session-19.scope - Session 19 of User core. Sep 9 05:38:31.901312 sshd[7898]: Connection closed by 10.200.16.10 port 44210 Sep 9 05:38:31.901655 sshd-session[7740]: pam_unix(sshd:session): session closed for user core Sep 9 05:38:31.905023 systemd[1]: sshd@16-10.200.8.10:22-10.200.16.10:44210.service: Deactivated successfully. Sep 9 05:38:31.906597 systemd[1]: session-19.scope: Deactivated successfully. Sep 9 05:38:31.907285 systemd-logind[1897]: Session 19 logged out. Waiting for processes to exit. Sep 9 05:38:31.908357 systemd-logind[1897]: Removed session 19. Sep 9 05:38:42.027010 systemd[1]: Started sshd@17-10.200.8.10:22-10.200.16.10:37986.service - OpenSSH per-connection server daemon (10.200.16.10:37986). Sep 9 05:38:44.914777 sshd[9694]: Accepted publickey for core from 10.200.16.10 port 37986 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:38:42.667586 sshd-session[9694]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:38:42.671867 systemd-logind[1897]: New session 20 of user core. Sep 9 05:38:44.915427 sshd[9697]: Connection closed by 10.200.16.10 port 37986 Sep 9 05:38:44.937919 sshd[9703]: Accepted publickey for core from 10.200.16.10 port 37988 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:38:43.118750 sshd-session[9694]: pam_unix(sshd:session): session closed for user core Sep 9 05:38:42.684170 systemd[1]: Started session-20.scope - Session 20 of User core. Sep 9 05:38:44.938517 sshd[9706]: Connection closed by 10.200.16.10 port 37988 Sep 9 05:38:43.871828 sshd-session[9703]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:38:43.122203 systemd[1]: sshd@17-10.200.8.10:22-10.200.16.10:37986.service: Deactivated successfully. Sep 9 05:38:44.324113 sshd-session[9703]: pam_unix(sshd:session): session closed for user core Sep 9 05:38:43.124014 systemd[1]: session-20.scope: Deactivated successfully. Sep 9 05:38:43.124808 systemd-logind[1897]: Session 20 logged out. Waiting for processes to exit. Sep 9 05:38:43.125704 systemd-logind[1897]: Removed session 20. Sep 9 05:38:43.231311 systemd[1]: Started sshd@18-10.200.8.10:22-10.200.16.10:37988.service - OpenSSH per-connection server daemon (10.200.16.10:37988). Sep 9 05:38:43.876149 systemd-logind[1897]: New session 21 of user core. Sep 9 05:38:43.885174 systemd[1]: Started session-21.scope - Session 21 of User core. Sep 9 05:38:44.326973 systemd[1]: sshd@18-10.200.8.10:22-10.200.16.10:37988.service: Deactivated successfully. Sep 9 05:38:44.328783 systemd[1]: session-21.scope: Deactivated successfully. Sep 9 05:38:44.329463 systemd-logind[1897]: Session 21 logged out. Waiting for processes to exit. Sep 9 05:38:44.330360 systemd-logind[1897]: Removed session 21. Sep 9 05:38:54.445286 systemd[1]: Started sshd@19-10.200.8.10:22-10.200.16.10:46688.service - OpenSSH per-connection server daemon (10.200.16.10:46688). Sep 9 05:38:55.101037 sshd[13276]: Accepted publickey for core from 10.200.16.10 port 46688 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:38:55.102297 sshd-session[13276]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:38:55.107883 systemd-logind[1897]: New session 22 of user core. Sep 9 05:38:55.114195 systemd[1]: Started session-22.scope - Session 22 of User core. Sep 9 05:38:55.556177 sshd[13516]: Connection closed by 10.200.16.10 port 46688 Sep 9 05:38:55.557244 sshd-session[13276]: pam_unix(sshd:session): session closed for user core Sep 9 05:38:55.561927 systemd[1]: sshd@19-10.200.8.10:22-10.200.16.10:46688.service: Deactivated successfully. Sep 9 05:38:55.563916 systemd[1]: session-22.scope: Deactivated successfully. Sep 9 05:38:55.565194 systemd-logind[1897]: Session 22 logged out. Waiting for processes to exit. Sep 9 05:38:55.567642 systemd-logind[1897]: Removed session 22. Sep 9 05:38:55.672334 systemd[1]: Started sshd@20-10.200.8.10:22-10.200.16.10:46700.service - OpenSSH per-connection server daemon (10.200.16.10:46700). Sep 9 05:38:56.315103 sshd[13810]: Accepted publickey for core from 10.200.16.10 port 46700 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:38:56.316295 sshd-session[13810]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:38:56.320620 systemd-logind[1897]: New session 23 of user core. Sep 9 05:38:56.332179 systemd[1]: Started session-23.scope - Session 23 of User core. Sep 9 05:38:56.766735 sshd[14087]: Connection closed by 10.200.16.10 port 46700 Sep 9 05:38:56.767158 sshd-session[13810]: pam_unix(sshd:session): session closed for user core Sep 9 05:38:56.770948 systemd[1]: sshd@20-10.200.8.10:22-10.200.16.10:46700.service: Deactivated successfully. Sep 9 05:38:56.772554 systemd[1]: session-23.scope: Deactivated successfully. Sep 9 05:38:56.773393 systemd-logind[1897]: Session 23 logged out. Waiting for processes to exit. Sep 9 05:38:56.774395 systemd-logind[1897]: Removed session 23. Sep 9 05:39:06.886321 systemd[1]: Started sshd@21-10.200.8.10:22-10.200.16.10:35742.service - OpenSSH per-connection server daemon (10.200.16.10:35742). Sep 9 05:39:07.542887 sshd[14532]: Accepted publickey for core from 10.200.16.10 port 35742 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:39:07.544663 sshd-session[14532]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:39:07.551998 systemd-logind[1897]: New session 24 of user core. Sep 9 05:39:07.560573 systemd[1]: Started session-24.scope - Session 24 of User core. Sep 9 05:39:07.996714 sshd[14552]: Connection closed by 10.200.16.10 port 35742 Sep 9 05:39:07.997468 sshd-session[14532]: pam_unix(sshd:session): session closed for user core Sep 9 05:39:08.001941 systemd-logind[1897]: Session 24 logged out. Waiting for processes to exit. Sep 9 05:39:08.003328 systemd[1]: sshd@21-10.200.8.10:22-10.200.16.10:35742.service: Deactivated successfully. Sep 9 05:39:08.005769 systemd[1]: session-24.scope: Deactivated successfully. Sep 9 05:39:08.010109 systemd-logind[1897]: Removed session 24. Sep 9 05:39:08.112910 systemd[1]: Started sshd@22-10.200.8.10:22-10.200.16.10:35746.service - OpenSSH per-connection server daemon (10.200.16.10:35746). Sep 9 05:39:08.777978 sshd[14576]: Accepted publickey for core from 10.200.16.10 port 35746 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:39:08.779354 sshd-session[14576]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:39:08.785693 systemd-logind[1897]: New session 25 of user core. Sep 9 05:39:08.796312 systemd[1]: Started session-25.scope - Session 25 of User core. Sep 9 05:39:09.233370 sshd[14591]: Connection closed by 10.200.16.10 port 35746 Sep 9 05:39:09.235081 sshd-session[14576]: pam_unix(sshd:session): session closed for user core Sep 9 05:39:09.238024 systemd[1]: sshd@22-10.200.8.10:22-10.200.16.10:35746.service: Deactivated successfully. Sep 9 05:39:09.239728 systemd[1]: session-25.scope: Deactivated successfully. Sep 9 05:39:09.243361 systemd-logind[1897]: Session 25 logged out. Waiting for processes to exit. Sep 9 05:39:09.244654 systemd-logind[1897]: Removed session 25. Sep 9 05:39:19.350326 systemd[1]: Started sshd@23-10.200.8.10:22-10.200.16.10:45788.service - OpenSSH per-connection server daemon (10.200.16.10:45788). Sep 9 05:39:20.021521 sshd[14951]: Accepted publickey for core from 10.200.16.10 port 45788 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:39:20.023344 sshd-session[14951]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:39:20.029378 systemd-logind[1897]: New session 26 of user core. Sep 9 05:39:20.035194 systemd[1]: Started session-26.scope - Session 26 of User core. Sep 9 05:39:20.474346 sshd[14967]: Connection closed by 10.200.16.10 port 45788 Sep 9 05:39:20.476599 sshd-session[14951]: pam_unix(sshd:session): session closed for user core Sep 9 05:39:20.482262 systemd-logind[1897]: Session 26 logged out. Waiting for processes to exit. Sep 9 05:39:20.484428 systemd[1]: sshd@23-10.200.8.10:22-10.200.16.10:45788.service: Deactivated successfully. Sep 9 05:39:20.486552 systemd[1]: session-26.scope: Deactivated successfully. Sep 9 05:39:20.490911 systemd-logind[1897]: Removed session 26. Sep 9 05:39:20.589943 systemd[1]: Started sshd@24-10.200.8.10:22-10.200.16.10:53672.service - OpenSSH per-connection server daemon (10.200.16.10:53672). Sep 9 05:39:21.248540 sshd[14990]: Accepted publickey for core from 10.200.16.10 port 53672 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:39:21.250684 sshd-session[14990]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:39:21.257631 systemd-logind[1897]: New session 27 of user core. Sep 9 05:39:21.265723 systemd[1]: Started session-27.scope - Session 27 of User core. Sep 9 05:39:21.702745 sshd[15011]: Connection closed by 10.200.16.10 port 53672 Sep 9 05:39:21.704756 sshd-session[14990]: pam_unix(sshd:session): session closed for user core Sep 9 05:39:21.708363 systemd-logind[1897]: Session 27 logged out. Waiting for processes to exit. Sep 9 05:39:21.709848 systemd[1]: sshd@24-10.200.8.10:22-10.200.16.10:53672.service: Deactivated successfully. Sep 9 05:39:21.711450 systemd[1]: session-27.scope: Deactivated successfully. Sep 9 05:39:21.713839 systemd-logind[1897]: Removed session 27. Sep 9 05:39:31.824388 systemd[1]: Started sshd@25-10.200.8.10:22-10.200.16.10:38480.service - OpenSSH per-connection server daemon (10.200.16.10:38480). Sep 9 05:39:32.464696 sshd[15284]: Accepted publickey for core from 10.200.16.10 port 38480 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:39:32.465911 sshd-session[15284]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:39:32.470199 systemd-logind[1897]: New session 28 of user core. Sep 9 05:39:32.480204 systemd[1]: Started session-28.scope - Session 28 of User core. Sep 9 05:39:32.914966 sshd[15287]: Connection closed by 10.200.16.10 port 38480 Sep 9 05:39:32.915312 sshd-session[15284]: pam_unix(sshd:session): session closed for user core Sep 9 05:39:32.918681 systemd[1]: sshd@25-10.200.8.10:22-10.200.16.10:38480.service: Deactivated successfully. Sep 9 05:39:32.920285 systemd[1]: session-28.scope: Deactivated successfully. Sep 9 05:39:32.920974 systemd-logind[1897]: Session 28 logged out. Waiting for processes to exit. Sep 9 05:39:32.921925 systemd-logind[1897]: Removed session 28. Sep 9 05:39:33.037045 systemd[1]: Started sshd@26-10.200.8.10:22-10.200.16.10:38490.service - OpenSSH per-connection server daemon (10.200.16.10:38490). Sep 9 05:39:33.677041 sshd[15294]: Accepted publickey for core from 10.200.16.10 port 38490 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:39:33.678316 sshd-session[15294]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:39:33.682728 systemd-logind[1897]: New session 29 of user core. Sep 9 05:39:33.693191 systemd[1]: Started session-29.scope - Session 29 of User core. Sep 9 05:39:34.129824 sshd[15298]: Connection closed by 10.200.16.10 port 38490 Sep 9 05:39:34.130210 sshd-session[15294]: pam_unix(sshd:session): session closed for user core Sep 9 05:39:34.133805 systemd[1]: sshd@26-10.200.8.10:22-10.200.16.10:38490.service: Deactivated successfully. Sep 9 05:39:34.135436 systemd[1]: session-29.scope: Deactivated successfully. Sep 9 05:39:34.136163 systemd-logind[1897]: Session 29 logged out. Waiting for processes to exit. Sep 9 05:39:34.137198 systemd-logind[1897]: Removed session 29. Sep 9 05:39:37.563903 setup-nvidia[2621]: \u000d Sep 9 05:39:37.563903 setup-nvidia[2621]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Sep 9 05:39:37.563903 setup-nvidia[2621]: frequently when this kernel module was built against the wrong or Sep 9 05:39:37.563903 setup-nvidia[2621]: improperly configured kernel sources, with a version of gcc that differs Sep 9 05:39:37.563903 setup-nvidia[2621]: from the one used to build the target kernel, or if another driver, such Sep 9 05:39:37.563903 setup-nvidia[2621]: as nouveau, is present and prevents the NVIDIA kernel module from Sep 9 05:39:37.563903 setup-nvidia[2621]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Sep 9 05:39:37.563903 setup-nvidia[2621]: installed in this system is supported by this NVIDIA Linux graphics Sep 9 05:39:37.563903 setup-nvidia[2621]: driver release. Sep 9 05:39:37.563903 setup-nvidia[2621]: \u000d Sep 9 05:39:37.563903 setup-nvidia[2621]: Please see the log entries 'Kernel module load error' and 'Kernel Sep 9 05:39:37.563903 setup-nvidia[2621]: messages' at the end of the file Sep 9 05:39:37.563903 setup-nvidia[2621]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for more Sep 9 05:39:37.563903 setup-nvidia[2621]: information. Sep 9 05:39:37.563903 setup-nvidia[2621]: \u000d Sep 9 05:39:37.578316 setup-nvidia[2621]: dmesg: read kernel buffer failed: Operation not permitted Sep 9 05:39:37.596538 setup-nvidia[2621]: \u000d Sep 9 05:39:37.596538 setup-nvidia[2621]: ERROR: Installation has failed. Please see the file Sep 9 05:39:37.596538 setup-nvidia[2621]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for Sep 9 05:39:37.596538 setup-nvidia[2621]: details. You may find suggestions on fixing installation problems in Sep 9 05:39:37.596538 setup-nvidia[2621]: the README available on the Linux driver download page at Sep 9 05:39:37.596538 setup-nvidia[2621]: www.nvidia.com. Sep 9 05:39:37.596538 setup-nvidia[2621]: \u000d Sep 9 05:39:37.596935 setup-nvidia[2621]: Last 50 lines of nvidia-installer.log: Sep 9 05:39:37.597720 setup-nvidia[2621]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039059rm+0x53: 'naked' return found in MITIGATION_RETHUNK build Sep 9 05:39:37.597720 setup-nvidia[2621]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039056rm+0x90: 'naked' return found in MITIGATION_RETHUNK build Sep 9 05:39:37.597720 setup-nvidia[2621]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039049rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build Sep 9 05:39:37.597720 setup-nvidia[2621]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039105rm+0x93: 'naked' return found in MITIGATION_RETHUNK build Sep 9 05:39:37.597720 setup-nvidia[2621]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039101rm+0x36: 'naked' return found in MITIGATION_RETHUNK build Sep 9 05:39:37.597720 setup-nvidia[2621]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039100rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build Sep 9 05:39:37.597720 setup-nvidia[2621]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039102rm+0x77: 'naked' return found in MITIGATION_RETHUNK build Sep 9 05:39:37.597720 setup-nvidia[2621]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039046rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build Sep 9 05:39:37.597720 setup-nvidia[2621]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030615rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build Sep 9 05:39:37.597720 setup-nvidia[2621]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030614rm+0x27: 'naked' return found in MITIGATION_RETHUNK build Sep 9 05:39:37.597720 setup-nvidia[2621]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030618rm+0x37: 'naked' return found in MITIGATION_RETHUNK build Sep 9 05:39:37.597720 setup-nvidia[2621]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030617rm+0x24: 'naked' return found in MITIGATION_RETHUNK build Sep 9 05:39:37.597720 setup-nvidia[2621]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030616rm+0xca: 'naked' return found in MITIGATION_RETHUNK build Sep 9 05:39:37.597720 setup-nvidia[2621]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030619rm+0x193: 'naked' return found in MITIGATION_RETHUNK build Sep 9 05:39:37.597720 setup-nvidia[2621]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030621rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build Sep 9 05:39:37.597720 setup-nvidia[2621]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv011864rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build Sep 9 05:39:37.597720 setup-nvidia[2621]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039413rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build Sep 9 05:39:37.597720 setup-nvidia[2621]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/Module.symvers Sep 9 05:39:37.597720 setup-nvidia[2621]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.mod.o Sep 9 05:39:37.597720 setup-nvidia[2621]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/.module-common.o Sep 9 05:39:37.597720 setup-nvidia[2621]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.mod.o Sep 9 05:39:37.597720 setup-nvidia[2621]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.mod.o Sep 9 05:39:37.597720 setup-nvidia[2621]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.mod.o Sep 9 05:39:37.597720 setup-nvidia[2621]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.mod.o Sep 9 05:39:37.597720 setup-nvidia[2621]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Sep 9 05:39:37.597720 setup-nvidia[2621]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Sep 9 05:39:37.597720 setup-nvidia[2621]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Sep 9 05:39:37.597720 setup-nvidia[2621]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Sep 9 05:39:37.597720 setup-nvidia[2621]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Sep 9 05:39:37.597720 setup-nvidia[2621]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Sep 9 05:39:37.597720 setup-nvidia[2621]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko due to unavailability of vmlinux Sep 9 05:39:37.597720 setup-nvidia[2621]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Sep 9 05:39:37.597720 setup-nvidia[2621]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko due to unavailability of vmlinux Sep 9 05:39:37.597720 setup-nvidia[2621]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Sep 9 05:39:37.597720 setup-nvidia[2621]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko due to unavailability of vmlinux Sep 9 05:39:37.597720 setup-nvidia[2621]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Sep 9 05:39:37.598617 setup-nvidia[2621]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko due to unavailability of vmlinux Sep 9 05:39:37.598617 setup-nvidia[2621]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Sep 9 05:39:37.598617 setup-nvidia[2621]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko due to unavailability of vmlinux Sep 9 05:39:37.598617 setup-nvidia[2621]: make[2]: Leaving directory '/usr/lib/modules/6.12.45-flatcar/build' Sep 9 05:39:37.598617 setup-nvidia[2621]: make[1]: Leaving directory '/usr/lib/modules/6.12.45-flatcar/source' Sep 9 05:39:37.598617 setup-nvidia[2621]: -> done. Sep 9 05:39:37.598617 setup-nvidia[2621]: -> Kernel module compilation complete. Sep 9 05:39:37.598617 setup-nvidia[2621]: -> Unable to determine if Secure Boot is enabled: No such file or directory Sep 9 05:39:37.598617 setup-nvidia[2621]: 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 9 05:39:37.598617 setup-nvidia[2621]: \u000d Sep 9 05:39:37.598617 setup-nvidia[2621]: 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 9 05:39:37.598617 setup-nvidia[2621]: -> Kernel module load error: Operation not permitted Sep 9 05:39:37.598617 setup-nvidia[2621]: -> Kernel messages: Sep 9 05:39:37.598617 setup-nvidia[2621]: 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 9 05:39:44.254012 systemd[1]: Started sshd@27-10.200.8.10:22-10.200.16.10:39942.service - OpenSSH per-connection server daemon (10.200.16.10:39942). Sep 9 05:39:44.892215 sshd[15484]: Accepted publickey for core from 10.200.16.10 port 39942 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:39:44.893405 sshd-session[15484]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:39:44.897795 systemd-logind[1897]: New session 30 of user core. Sep 9 05:39:44.910160 systemd[1]: Started session-30.scope - Session 30 of User core. Sep 9 05:39:45.342793 sshd[15488]: Connection closed by 10.200.16.10 port 39942 Sep 9 05:39:45.343170 sshd-session[15484]: pam_unix(sshd:session): session closed for user core Sep 9 05:39:45.346625 systemd[1]: sshd@27-10.200.8.10:22-10.200.16.10:39942.service: Deactivated successfully. Sep 9 05:39:45.348534 systemd[1]: session-30.scope: Deactivated successfully. Sep 9 05:39:45.349390 systemd-logind[1897]: Session 30 logged out. Waiting for processes to exit. Sep 9 05:39:45.350315 systemd-logind[1897]: Removed session 30. Sep 9 05:39:45.455374 systemd[1]: Started sshd@28-10.200.8.10:22-10.200.16.10:39948.service - OpenSSH per-connection server daemon (10.200.16.10:39948). Sep 9 05:39:46.115244 sshd[15494]: Accepted publickey for core from 10.200.16.10 port 39948 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:39:46.116478 sshd-session[15494]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:39:46.120719 systemd-logind[1897]: New session 31 of user core. Sep 9 05:39:46.130176 systemd[1]: Started session-31.scope - Session 31 of User core. Sep 9 05:39:46.567522 sshd[15497]: Connection closed by 10.200.16.10 port 39948 Sep 9 05:39:46.567929 sshd-session[15494]: pam_unix(sshd:session): session closed for user core Sep 9 05:39:46.571463 systemd[1]: sshd@28-10.200.8.10:22-10.200.16.10:39948.service: Deactivated successfully. Sep 9 05:39:46.573354 systemd[1]: session-31.scope: Deactivated successfully. Sep 9 05:39:46.574258 systemd-logind[1897]: Session 31 logged out. Waiting for processes to exit. Sep 9 05:39:46.575393 systemd-logind[1897]: Removed session 31. Sep 9 05:39:56.686314 systemd[1]: Started sshd@29-10.200.8.10:22-10.200.16.10:42334.service - OpenSSH per-connection server daemon (10.200.16.10:42334). Sep 9 05:39:57.328342 sshd[15503]: Accepted publickey for core from 10.200.16.10 port 42334 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:39:57.329642 sshd-session[15503]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:39:57.333911 systemd-logind[1897]: New session 32 of user core. Sep 9 05:39:57.349304 systemd[1]: Started session-32.scope - Session 32 of User core. Sep 9 05:39:57.779888 sshd[15506]: Connection closed by 10.200.16.10 port 42334 Sep 9 05:39:57.780247 sshd-session[15503]: pam_unix(sshd:session): session closed for user core Sep 9 05:39:57.783598 systemd[1]: sshd@29-10.200.8.10:22-10.200.16.10:42334.service: Deactivated successfully. Sep 9 05:39:57.785198 systemd[1]: session-32.scope: Deactivated successfully. Sep 9 05:39:57.785920 systemd-logind[1897]: Session 32 logged out. Waiting for processes to exit. Sep 9 05:39:57.786817 systemd-logind[1897]: Removed session 32. Sep 9 05:39:57.892038 systemd[1]: Started sshd@30-10.200.8.10:22-10.200.16.10:42350.service - OpenSSH per-connection server daemon (10.200.16.10:42350). Sep 9 05:39:58.530703 sshd[15512]: Accepted publickey for core from 10.200.16.10 port 42350 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:39:58.531907 sshd-session[15512]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:39:58.536012 systemd-logind[1897]: New session 33 of user core. Sep 9 05:39:58.549203 systemd[1]: Started session-33.scope - Session 33 of User core. Sep 9 05:39:58.982946 sshd[15515]: Connection closed by 10.200.16.10 port 42350 Sep 9 05:39:58.983239 sshd-session[15512]: pam_unix(sshd:session): session closed for user core Sep 9 05:39:58.986536 systemd[1]: sshd@30-10.200.8.10:22-10.200.16.10:42350.service: Deactivated successfully. Sep 9 05:39:58.988139 systemd[1]: session-33.scope: Deactivated successfully. Sep 9 05:39:58.988809 systemd-logind[1897]: Session 33 logged out. Waiting for processes to exit. Sep 9 05:39:58.989682 systemd-logind[1897]: Removed session 33. Sep 9 05:40:06.733083 kernel: EXT4-fs (loop6p9): unmounting filesystem 70a79864-9d90-4624-98c5-2d7a4a92e0f3. Sep 9 05:40:06.733231 setup-nvidia[2621]: Container flatcardevelopercontainer-4452.0.0nightly-20250908-2100.bin exited successfully. Sep 9 05:40:06.735558 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4452.0.0nightly\x2d20250908\x2d2100.bin.mount: Deactivated successfully. Sep 9 05:40:06.781856 sudo[2609]: pam_unix(sudo:session): session closed for user root Sep 9 05:40:06.783707 systemd[1]: session-c2.scope: Deactivated successfully. Sep 9 05:40:06.783981 systemd[1]: session-c2.scope: Consumed 5min 17.813s CPU time, 889M memory peak. Sep 9 05:40:07.194166 setup-nvidia[1889]: /opt/nvidia / Sep 9 05:40:07.195145 setup-nvidia[1889]: / Sep 9 05:40:07.204073 kernel: IPMI message handler: version 39.2 Sep 9 05:40:07.208076 kernel: ipmi device interface Sep 9 05:40:07.220338 setup-nvidia[1889]: /opt/nvidia/current/usr/lib/modules/6.12.45-flatcar/video / Sep 9 05:40:07.273902 kernel: nvidia: loading out-of-tree module taints kernel. Sep 9 05:40:07.274034 kernel: nvidia: module license 'NVIDIA' taints kernel. Sep 9 05:40:07.274086 kernel: Disabling lock debugging due to kernel taint Sep 9 05:40:07.280951 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Sep 9 05:40:07.280993 kernel: nvidia: module license taints kernel. Sep 9 05:40:07.675084 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 Sep 9 05:40:08.067533 kernel: Sep 9 05:40:08.067675 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Sep 9 05:40:08.275078 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.261.03 Sat Jun 14 16:05:50 UTC 2025 Sep 9 05:40:08.300079 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.261.03 Sat Jun 14 15:39:16 UTC 2025 Sep 9 05:40:08.320092 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Sep 9 05:40:08.367501 setup-nvidia[1889]: / Sep 9 05:40:08.368076 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. Sep 9 05:40:08.382016 setup-nvidia[15555]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 9 05:40:09.100435 systemd[1]: Started sshd@31-10.200.8.10:22-10.200.16.10:34862.service - OpenSSH per-connection server daemon (10.200.16.10:34862). Sep 9 05:40:09.739505 sshd[15577]: Accepted publickey for core from 10.200.16.10 port 34862 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:40:09.740712 sshd-session[15577]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:40:09.745168 systemd-logind[1897]: New session 34 of user core. Sep 9 05:40:09.758569 systemd[1]: Started session-34.scope - Session 34 of User core. Sep 9 05:40:10.189881 sshd[15580]: Connection closed by 10.200.16.10 port 34862 Sep 9 05:40:10.190219 sshd-session[15577]: pam_unix(sshd:session): session closed for user core Sep 9 05:40:10.193501 systemd[1]: sshd@31-10.200.8.10:22-10.200.16.10:34862.service: Deactivated successfully. Sep 9 05:40:10.195113 systemd[1]: session-34.scope: Deactivated successfully. Sep 9 05:40:10.195786 systemd-logind[1897]: Session 34 logged out. Waiting for processes to exit. Sep 9 05:40:10.196672 systemd-logind[1897]: Removed session 34. Sep 9 05:40:10.216483 setup-nvidia[15572]: Tue Sep 9 05:40:10 2025 Sep 9 05:40:10.216483 setup-nvidia[15572]: +---------------------------------------------------------------------------------------+ Sep 9 05:40:10.216483 setup-nvidia[15572]: | NVIDIA-SMI 535.261.03 Driver Version: 535.261.03 CUDA Version: 12.2 | Sep 9 05:40:10.216483 setup-nvidia[15572]: |-----------------------------------------+----------------------+----------------------+ Sep 9 05:40:10.216483 setup-nvidia[15572]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Sep 9 05:40:10.216483 setup-nvidia[15572]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Sep 9 05:40:10.216483 setup-nvidia[15572]: | | | MIG M. | Sep 9 05:40:10.216483 setup-nvidia[15572]: |=========================================+======================+======================| Sep 9 05:40:10.220468 setup-nvidia[15572]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Sep 9 05:40:10.220468 setup-nvidia[15572]: | N/A 26C P0 35W / 250W | 0MiB / 16384MiB | 0% Default | Sep 9 05:40:10.220468 setup-nvidia[15572]: | | | N/A | Sep 9 05:40:10.220468 setup-nvidia[15572]: +-----------------------------------------+----------------------+----------------------+ Sep 9 05:40:10.220468 setup-nvidia[15572]: Sep 9 05:40:10.220468 setup-nvidia[15572]: +---------------------------------------------------------------------------------------+ Sep 9 05:40:10.220468 setup-nvidia[15572]: | Processes: | Sep 9 05:40:10.220468 setup-nvidia[15572]: | GPU GI CI PID Type Process name GPU Memory | Sep 9 05:40:10.220468 setup-nvidia[15572]: | ID ID Usage | Sep 9 05:40:10.220468 setup-nvidia[15572]: |=======================================================================================| Sep 9 05:40:10.220716 setup-nvidia[15572]: | No running processes found | Sep 9 05:40:10.220716 setup-nvidia[15572]: +---------------------------------------------------------------------------------------+ Sep 9 05:40:10.306470 systemd[1]: Started sshd@32-10.200.8.10:22-10.200.16.10:37478.service - OpenSSH per-connection server daemon (10.200.16.10:37478). Sep 9 05:40:10.595916 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 9 05:40:10.596512 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 9 05:40:10.604160 systemd[1]: Startup finished in 1.423s (firmware) + 33.076s (loader) + 3.794s (kernel) + 15.994s (initrd) + 4min 11.318s (userspace) = 5min 5.608s. Sep 9 05:40:11.209234 sshd[15586]: Accepted publickey for core from 10.200.16.10 port 37478 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:40:11.210523 sshd-session[15586]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:40:11.214842 systemd-logind[1897]: New session 35 of user core. Sep 9 05:40:11.229184 systemd[1]: Started session-35.scope - Session 35 of User core. Sep 9 05:40:11.662609 sshd[15591]: Connection closed by 10.200.16.10 port 37478 Sep 9 05:40:11.662941 sshd-session[15586]: pam_unix(sshd:session): session closed for user core Sep 9 05:40:11.666280 systemd[1]: sshd@32-10.200.8.10:22-10.200.16.10:37478.service: Deactivated successfully. Sep 9 05:40:11.667839 systemd[1]: session-35.scope: Deactivated successfully. Sep 9 05:40:11.668518 systemd-logind[1897]: Session 35 logged out. Waiting for processes to exit. Sep 9 05:40:11.669400 systemd-logind[1897]: Removed session 35. Sep 9 05:40:17.003227 systemd[1]: Stopping user@0.service - User Manager for UID 0... Sep 9 05:40:17.004017 systemd[2612]: Activating special unit exit.target... Sep 9 05:40:17.004113 systemd[2612]: Stopped target default.target - Main User Target. Sep 9 05:40:17.004155 systemd[2612]: Stopped target basic.target - Basic System. Sep 9 05:40:17.004186 systemd[2612]: Stopped target paths.target - Paths. Sep 9 05:40:17.004213 systemd[2612]: Stopped target sockets.target - Sockets. Sep 9 05:40:17.004239 systemd[2612]: Stopped target timers.target - Timers. Sep 9 05:40:17.004586 systemd[2612]: Closed dbus.socket - D-Bus User Message Bus Socket. Sep 9 05:40:17.004798 systemd[2612]: Removed slice app.slice - User Application Slice. Sep 9 05:40:17.004830 systemd[2612]: Reached target shutdown.target - Shutdown. Sep 9 05:40:17.004972 systemd[2612]: Finished systemd-exit.service - Exit the Session. Sep 9 05:40:17.005008 systemd[2612]: Reached target exit.target - Exit the Session. Sep 9 05:40:17.020942 (sd-pam)[2613]: pam_unix(systemd-user:session): session closed for user root Sep 9 05:40:17.022253 systemd[1]: user@0.service: Deactivated successfully. Sep 9 05:40:17.022685 systemd[1]: Stopped user@0.service - User Manager for UID 0. Sep 9 05:40:17.025287 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Sep 9 05:40:17.052560 systemd[1]: run-user-0.mount: Deactivated successfully. Sep 9 05:40:17.053557 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Sep 9 05:40:17.053762 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Sep 9 05:40:17.055604 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Sep 9 05:40:17.055700 systemd[1]: user-0.slice: Consumed 5min 18.033s CPU time, 895M memory peak. Sep 9 05:40:17.056153 systemd-logind[1897]: Removed session c3. Sep 9 05:40:21.784636 systemd[1]: Started sshd@33-10.200.8.10:22-10.200.16.10:51896.service - OpenSSH per-connection server daemon (10.200.16.10:51896). Sep 9 05:40:22.434844 sshd[15601]: Accepted publickey for core from 10.200.16.10 port 51896 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:40:22.436115 sshd-session[15601]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:40:22.440333 systemd-logind[1897]: New session 36 of user core. Sep 9 05:40:22.453284 systemd[1]: Started session-36.scope - Session 36 of User core. Sep 9 05:40:22.880386 sshd[15604]: Connection closed by 10.200.16.10 port 51896 Sep 9 05:40:22.880713 sshd-session[15601]: pam_unix(sshd:session): session closed for user core Sep 9 05:40:22.883908 systemd[1]: sshd@33-10.200.8.10:22-10.200.16.10:51896.service: Deactivated successfully. Sep 9 05:40:22.885439 systemd[1]: session-36.scope: Deactivated successfully. Sep 9 05:40:22.886106 systemd-logind[1897]: Session 36 logged out. Waiting for processes to exit. Sep 9 05:40:22.886985 systemd-logind[1897]: Removed session 36. Sep 9 05:40:22.991018 systemd[1]: Started sshd@34-10.200.8.10:22-10.200.16.10:51912.service - OpenSSH per-connection server daemon (10.200.16.10:51912). Sep 9 05:40:23.629248 sshd[15610]: Accepted publickey for core from 10.200.16.10 port 51912 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:40:23.630482 sshd-session[15610]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:40:23.634658 systemd-logind[1897]: New session 37 of user core. Sep 9 05:40:23.647184 systemd[1]: Started session-37.scope - Session 37 of User core. Sep 9 05:40:24.071553 sshd[15613]: Connection closed by 10.200.16.10 port 51912 Sep 9 05:40:24.071948 sshd-session[15610]: pam_unix(sshd:session): session closed for user core Sep 9 05:40:24.075552 systemd[1]: sshd@34-10.200.8.10:22-10.200.16.10:51912.service: Deactivated successfully. Sep 9 05:40:24.077253 systemd[1]: session-37.scope: Deactivated successfully. Sep 9 05:40:24.077963 systemd-logind[1897]: Session 37 logged out. Waiting for processes to exit. Sep 9 05:40:24.078888 systemd-logind[1897]: Removed session 37. Sep 9 05:40:24.186518 systemd[1]: Started sshd@35-10.200.8.10:22-10.200.16.10:51928.service - OpenSSH per-connection server daemon (10.200.16.10:51928). Sep 9 05:40:24.838074 sshd[15620]: Accepted publickey for core from 10.200.16.10 port 51928 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:40:24.839317 sshd-session[15620]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:40:24.843483 systemd-logind[1897]: New session 38 of user core. Sep 9 05:40:24.853172 systemd[1]: Started session-38.scope - Session 38 of User core. Sep 9 05:40:25.282245 sshd[15623]: Connection closed by 10.200.16.10 port 51928 Sep 9 05:40:25.282610 sshd-session[15620]: pam_unix(sshd:session): session closed for user core Sep 9 05:40:25.286079 systemd[1]: sshd@35-10.200.8.10:22-10.200.16.10:51928.service: Deactivated successfully. Sep 9 05:40:25.287642 systemd[1]: session-38.scope: Deactivated successfully. Sep 9 05:40:25.288362 systemd-logind[1897]: Session 38 logged out. Waiting for processes to exit. Sep 9 05:40:25.289277 systemd-logind[1897]: Removed session 38. Sep 9 05:40:25.404321 systemd[1]: Started sshd@36-10.200.8.10:22-10.200.16.10:51936.service - OpenSSH per-connection server daemon (10.200.16.10:51936). Sep 9 05:40:26.044485 sshd[15629]: Accepted publickey for core from 10.200.16.10 port 51936 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:40:26.046222 sshd-session[15629]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:40:26.050596 systemd-logind[1897]: New session 39 of user core. Sep 9 05:40:26.066178 systemd[1]: Started session-39.scope - Session 39 of User core. Sep 9 05:40:26.407186 sudo[15633]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Sep 9 05:40:26.407510 sudo[15633]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 9 05:40:26.476739 sudo[15633]: pam_unix(sudo:session): session closed for user root Sep 9 05:40:26.579004 sshd[15632]: Connection closed by 10.200.16.10 port 51936 Sep 9 05:40:26.579406 sshd-session[15629]: pam_unix(sshd:session): session closed for user core Sep 9 05:40:26.582984 systemd[1]: sshd@36-10.200.8.10:22-10.200.16.10:51936.service: Deactivated successfully. Sep 9 05:40:26.584559 systemd[1]: session-39.scope: Deactivated successfully. Sep 9 05:40:26.585266 systemd-logind[1897]: Session 39 logged out. Waiting for processes to exit. Sep 9 05:40:26.586317 systemd-logind[1897]: Removed session 39. Sep 9 05:40:26.706407 systemd[1]: Started sshd@37-10.200.8.10:22-10.200.16.10:51938.service - OpenSSH per-connection server daemon (10.200.16.10:51938). Sep 9 05:40:27.351026 sshd[15639]: Accepted publickey for core from 10.200.16.10 port 51938 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:40:27.352294 sshd-session[15639]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:40:27.356450 systemd-logind[1897]: New session 40 of user core. Sep 9 05:40:27.371174 systemd[1]: Started session-40.scope - Session 40 of User core. Sep 9 05:40:27.696019 sudo[15644]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Sep 9 05:40:27.696397 sudo[15644]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 9 05:40:27.704355 sudo[15644]: pam_unix(sudo:session): session closed for user root Sep 9 05:40:27.709735 sudo[15643]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Sep 9 05:40:27.710013 sudo[15643]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 9 05:40:27.719238 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 9 05:40:27.765862 augenrules[15666]: No rules Sep 9 05:40:27.766968 systemd[1]: audit-rules.service: Deactivated successfully. Sep 9 05:40:27.767197 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 9 05:40:27.767865 sudo[15643]: pam_unix(sudo:session): session closed for user root Sep 9 05:40:27.868693 sshd[15642]: Connection closed by 10.200.16.10 port 51938 Sep 9 05:40:27.869013 sshd-session[15639]: pam_unix(sshd:session): session closed for user core Sep 9 05:40:27.872251 systemd[1]: sshd@37-10.200.8.10:22-10.200.16.10:51938.service: Deactivated successfully. Sep 9 05:40:27.873744 systemd[1]: session-40.scope: Deactivated successfully. Sep 9 05:40:27.874447 systemd-logind[1897]: Session 40 logged out. Waiting for processes to exit. Sep 9 05:40:27.875331 systemd-logind[1897]: Removed session 40. Sep 9 05:40:27.978028 systemd[1]: Started sshd@38-10.200.8.10:22-10.200.16.10:51952.service - OpenSSH per-connection server daemon (10.200.16.10:51952). Sep 9 05:40:28.618300 sshd[15675]: Accepted publickey for core from 10.200.16.10 port 51952 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:40:28.619561 sshd-session[15675]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:40:28.623806 systemd-logind[1897]: New session 41 of user core. Sep 9 05:40:28.635174 systemd[1]: Started session-41.scope - Session 41 of User core. Sep 9 05:40:28.962557 sudo[15679]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/mkdir -p . Sep 9 05:40:28.962853 sudo[15679]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 9 05:40:28.966204 sudo[15679]: pam_unix(sudo:session): session closed for user root Sep 9 05:40:29.067337 sshd[15678]: Connection closed by 10.200.16.10 port 51952 Sep 9 05:40:29.067737 sshd-session[15675]: pam_unix(sshd:session): session closed for user core Sep 9 05:40:29.071602 systemd[1]: sshd@38-10.200.8.10:22-10.200.16.10:51952.service: Deactivated successfully. Sep 9 05:40:29.073658 systemd[1]: session-41.scope: Deactivated successfully. Sep 9 05:40:29.074358 systemd-logind[1897]: Session 41 logged out. Waiting for processes to exit. Sep 9 05:40:29.076447 systemd-logind[1897]: Removed session 41. Sep 9 05:40:29.189484 systemd[1]: Started sshd@39-10.200.8.10:22-10.200.16.10:51960.service - OpenSSH per-connection server daemon (10.200.16.10:51960). Sep 9 05:40:29.831214 sshd[15685]: Accepted publickey for core from 10.200.16.10 port 51960 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:40:29.832442 sshd-session[15685]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:40:29.836593 systemd-logind[1897]: New session 42 of user core. Sep 9 05:40:29.850177 systemd[1]: Started session-42.scope - Session 42 of User core. Sep 9 05:40:30.175808 sudo[15689]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/install -m 0755 /dev/stdin kolet Sep 9 05:40:30.176121 sudo[15689]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 9 05:40:33.624692 sudo[15689]: pam_unix(sudo:session): session closed for user root Sep 9 05:40:33.726927 sshd[15688]: Connection closed by 10.200.16.10 port 51960 Sep 9 05:40:33.727188 sshd-session[15685]: pam_unix(sshd:session): session closed for user core Sep 9 05:40:33.730744 systemd[1]: sshd@39-10.200.8.10:22-10.200.16.10:51960.service: Deactivated successfully. Sep 9 05:40:33.732363 systemd[1]: session-42.scope: Deactivated successfully. Sep 9 05:40:33.733064 systemd-logind[1897]: Session 42 logged out. Waiting for processes to exit. Sep 9 05:40:33.733948 systemd-logind[1897]: Removed session 42. Sep 9 05:40:33.844504 systemd[1]: Started sshd@40-10.200.8.10:22-10.200.16.10:56936.service - OpenSSH per-connection server daemon (10.200.16.10:56936). Sep 9 05:40:34.475413 sshd[15695]: Accepted publickey for core from 10.200.16.10 port 56936 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:40:34.476607 sshd-session[15695]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:40:34.480763 systemd-logind[1897]: New session 43 of user core. Sep 9 05:40:34.491183 systemd[1]: Started session-43.scope - Session 43 of User core. Sep 9 05:40:35.005906 sshd[15698]: Connection closed by 10.200.16.10 port 56936 Sep 9 05:40:35.006292 sshd-session[15695]: pam_unix(sshd:session): session closed for user core Sep 9 05:40:35.009881 systemd[1]: sshd@40-10.200.8.10:22-10.200.16.10:56936.service: Deactivated successfully. Sep 9 05:40:35.011495 systemd[1]: session-43.scope: Deactivated successfully. Sep 9 05:40:35.012160 systemd-logind[1897]: Session 43 logged out. Waiting for processes to exit. Sep 9 05:40:35.013046 systemd-logind[1897]: Removed session 43. Sep 9 05:40:35.121359 systemd[1]: Started sshd@41-10.200.8.10:22-10.200.16.10:56948.service - OpenSSH per-connection server daemon (10.200.16.10:56948). Sep 9 05:40:35.749230 sshd[15714]: Accepted publickey for core from 10.200.16.10 port 56948 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:40:35.750479 sshd-session[15714]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:40:35.754770 systemd-logind[1897]: New session 44 of user core. Sep 9 05:40:35.764226 systemd[1]: Started session-44.scope - Session 44 of User core. Sep 9 05:40:37.333217 systemd[1]: Starting docker.service - Docker Application Container Engine... Sep 9 05:40:37.349397 (dockerd)[15737]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPTS, DOCKER_OPT_BIP, DOCKER_OPT_IPMASQ, DOCKER_OPT_MTU Sep 9 05:40:38.969192 dockerd[15737]: time="2025-09-09T05:40:38.969079760Z" level=info msg="Starting up" Sep 9 05:40:38.969848 dockerd[15737]: time="2025-09-09T05:40:38.969811627Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Sep 9 05:40:38.980244 dockerd[15737]: time="2025-09-09T05:40:38.980205659Z" level=info msg="Creating a containerd client" address=/var/run/docker/libcontainerd/docker-containerd.sock timeout=1m0s Sep 9 05:40:39.722572 dockerd[15737]: time="2025-09-09T05:40:39.722515097Z" level=info msg="Loading containers: start." Sep 9 05:40:39.736081 kernel: Initializing XFRM netlink socket Sep 9 05:40:40.081195 systemd-networkd[1592]: docker0: Link UP Sep 9 05:40:40.096686 dockerd[15737]: time="2025-09-09T05:40:40.096645132Z" level=info msg="Loading containers: done." Sep 9 05:40:40.109688 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck3433955967-merged.mount: Deactivated successfully. Sep 9 05:40:40.118268 dockerd[15737]: time="2025-09-09T05:40:40.118187161Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2 Sep 9 05:40:40.118894 dockerd[15737]: time="2025-09-09T05:40:40.118606242Z" level=info msg="Docker daemon" commit=6430e49a55babd9b8f4d08e70ecb2b68900770fe containerd-snapshotter=false storage-driver=overlay2 version=28.0.4 Sep 9 05:40:40.118894 dockerd[15737]: time="2025-09-09T05:40:40.118703938Z" level=info msg="Initializing buildkit" Sep 9 05:40:40.169356 dockerd[15737]: time="2025-09-09T05:40:40.169327056Z" level=info msg="Completed buildkit initialization" Sep 9 05:40:40.174952 dockerd[15737]: time="2025-09-09T05:40:40.174806209Z" level=info msg="Daemon has completed initialization" Sep 9 05:40:40.175005 systemd[1]: Started docker.service - Docker Application Container Engine. Sep 9 05:40:40.175137 dockerd[15737]: time="2025-09-09T05:40:40.175068597Z" level=info msg="API listen on /run/docker.sock" Sep 9 05:40:41.405596 systemd[1]: var-lib-docker-overlay2-ebdbcb3c1294d328b5fbbc86313c365dd6989edfe053540c3169768ddd8efdb5\x2dinit-merged.mount: Deactivated successfully. Sep 9 05:40:41.499804 containerd[1911]: time="2025-09-09T05:40:41.499759283Z" level=info msg="connecting to shim 97f51586da2f1b52855af1745f9383f4476ba12ee940e1072f9a9b305f3d7daf" address="unix:///run/containerd/s/a66016e13f390f4ef4c6ef8051ab71cf09746e53ad78c007fab85bacfda42497" namespace=moby protocol=ttrpc version=3 Sep 9 05:40:41.531190 systemd[1]: Started docker-97f51586da2f1b52855af1745f9383f4476ba12ee940e1072f9a9b305f3d7daf.scope - libcontainer container 97f51586da2f1b52855af1745f9383f4476ba12ee940e1072f9a9b305f3d7daf. Sep 9 05:40:41.579674 kernel: docker0: port 1(veth9433f30) entered blocking state Sep 9 05:40:41.579808 kernel: docker0: port 1(veth9433f30) entered disabled state Sep 9 05:40:41.579961 kernel: veth9433f30: entered allmulticast mode Sep 9 05:40:41.584594 kernel: veth9433f30: entered promiscuous mode Sep 9 05:40:41.585638 systemd-networkd[1592]: veth9433f30: Link UP Sep 9 05:40:41.610076 kernel: eth0: renamed from veth289460d Sep 9 05:40:41.616437 kernel: docker0: port 1(veth9433f30) entered blocking state Sep 9 05:40:41.616482 kernel: docker0: port 1(veth9433f30) entered forwarding state Sep 9 05:40:41.616826 systemd-networkd[1592]: veth9433f30: Gained carrier Sep 9 05:40:41.617110 systemd-networkd[1592]: docker0: Gained carrier Sep 9 05:40:42.826199 systemd-networkd[1592]: veth9433f30: Gained IPv6LL Sep 9 05:40:43.082183 systemd-networkd[1592]: docker0: Gained IPv6LL Sep 9 05:40:44.891882 systemd[1]: docker-97f51586da2f1b52855af1745f9383f4476ba12ee940e1072f9a9b305f3d7daf.scope: Deactivated successfully. Sep 9 05:40:44.904524 containerd[1911]: time="2025-09-09T05:40:44.904339912Z" level=info msg="shim disconnected" id=97f51586da2f1b52855af1745f9383f4476ba12ee940e1072f9a9b305f3d7daf namespace=moby Sep 9 05:40:44.904524 containerd[1911]: time="2025-09-09T05:40:44.904375610Z" level=warning msg="cleaning up after shim disconnected" id=97f51586da2f1b52855af1745f9383f4476ba12ee940e1072f9a9b305f3d7daf namespace=moby Sep 9 05:40:44.904524 containerd[1911]: time="2025-09-09T05:40:44.904384810Z" level=info msg="cleaning up dead shim" namespace=moby Sep 9 05:40:44.904877 dockerd[15737]: time="2025-09-09T05:40:44.904333112Z" level=info msg="ignoring event" container=97f51586da2f1b52855af1745f9383f4476ba12ee940e1072f9a9b305f3d7daf module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Sep 9 05:40:44.932930 systemd-networkd[1592]: veth9433f30: Lost carrier Sep 9 05:40:44.934138 kernel: docker0: port 1(veth9433f30) entered disabled state Sep 9 05:40:44.934276 kernel: veth289460d: renamed from eth0 Sep 9 05:40:44.948441 systemd-networkd[1592]: veth9433f30: Link DOWN Sep 9 05:40:44.955665 kernel: docker0: port 1(veth9433f30) entered disabled state Sep 9 05:40:44.955717 kernel: veth9433f30 (unregistering): left allmulticast mode Sep 9 05:40:44.959153 kernel: veth9433f30 (unregistering): left promiscuous mode Sep 9 05:40:44.962618 kernel: docker0: port 1(veth9433f30) entered disabled state Sep 9 05:40:44.982737 systemd[1]: run-docker-netns-e34ac025c5af.mount: Deactivated successfully. Sep 9 05:40:44.985690 systemd[1]: var-lib-docker-overlay2-ebdbcb3c1294d328b5fbbc86313c365dd6989edfe053540c3169768ddd8efdb5-merged.mount: Deactivated successfully. Sep 9 05:40:45.117932 sshd[15717]: Connection closed by 10.200.16.10 port 56948 Sep 9 05:40:45.118306 sshd-session[15714]: pam_unix(sshd:session): session closed for user core Sep 9 05:40:45.121768 systemd[1]: sshd@41-10.200.8.10:22-10.200.16.10:56948.service: Deactivated successfully. Sep 9 05:40:45.123497 systemd[1]: session-44.scope: Deactivated successfully. Sep 9 05:40:45.124275 systemd-logind[1897]: Session 44 logged out. Waiting for processes to exit. Sep 9 05:40:45.125254 systemd-logind[1897]: Removed session 44. Sep 9 05:40:45.232494 systemd[1]: Started sshd@42-10.200.8.10:22-10.200.16.10:33036.service - OpenSSH per-connection server daemon (10.200.16.10:33036). Sep 9 05:40:45.873756 sshd[16028]: Accepted publickey for core from 10.200.16.10 port 33036 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:40:45.874996 sshd-session[16028]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:40:45.879199 systemd-logind[1897]: New session 45 of user core. Sep 9 05:40:45.894181 systemd[1]: Started session-45.scope - Session 45 of User core. Sep 9 05:40:45.962292 systemd-networkd[1592]: docker0: Lost carrier Sep 9 05:40:46.272980 systemd[1]: var-lib-docker-overlay2-2fe102e2126091fad8bc86a47fdf627a6f2cf3efaf2171da232907170e742343\x2dinit-merged.mount: Deactivated successfully. Sep 9 05:40:46.366110 containerd[1911]: time="2025-09-09T05:40:46.366052349Z" level=info msg="connecting to shim 9b20169042ade6d2ddc744d1335ad5b165509fa8ead9471782040229b99ca6bc" address="unix:///run/containerd/s/72cceef203cf2e0de62fab6f638f03d4ffc8714c06508406b86da6938403ceb0" namespace=moby protocol=ttrpc version=3 Sep 9 05:40:46.390192 systemd[1]: Started docker-9b20169042ade6d2ddc744d1335ad5b165509fa8ead9471782040229b99ca6bc.scope - libcontainer container 9b20169042ade6d2ddc744d1335ad5b165509fa8ead9471782040229b99ca6bc. Sep 9 05:40:46.435066 kernel: docker0: port 1(veth4e94e93) entered blocking state Sep 9 05:40:46.435152 kernel: docker0: port 1(veth4e94e93) entered disabled state Sep 9 05:40:46.437869 kernel: veth4e94e93: entered allmulticast mode Sep 9 05:40:46.440696 kernel: veth4e94e93: entered promiscuous mode Sep 9 05:40:46.441542 systemd-networkd[1592]: veth4e94e93: Link UP Sep 9 05:40:46.459075 kernel: eth0: renamed from veth3e66b9d Sep 9 05:40:46.465813 kernel: docker0: port 1(veth4e94e93) entered blocking state Sep 9 05:40:46.465855 kernel: docker0: port 1(veth4e94e93) entered forwarding state Sep 9 05:40:46.466206 systemd-networkd[1592]: veth4e94e93: Gained carrier Sep 9 05:40:46.466450 systemd-networkd[1592]: docker0: Gained carrier Sep 9 05:40:46.497424 systemd[1]: docker-9b20169042ade6d2ddc744d1335ad5b165509fa8ead9471782040229b99ca6bc.scope: Deactivated successfully. Sep 9 05:40:46.516640 dockerd[15737]: time="2025-09-09T05:40:46.516599929Z" level=info msg="ignoring event" container=9b20169042ade6d2ddc744d1335ad5b165509fa8ead9471782040229b99ca6bc module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Sep 9 05:40:46.516930 containerd[1911]: time="2025-09-09T05:40:46.516683525Z" level=info msg="shim disconnected" id=9b20169042ade6d2ddc744d1335ad5b165509fa8ead9471782040229b99ca6bc namespace=moby Sep 9 05:40:46.516930 containerd[1911]: time="2025-09-09T05:40:46.516712524Z" level=warning msg="cleaning up after shim disconnected" id=9b20169042ade6d2ddc744d1335ad5b165509fa8ead9471782040229b99ca6bc namespace=moby Sep 9 05:40:46.516930 containerd[1911]: time="2025-09-09T05:40:46.516720124Z" level=info msg="cleaning up dead shim" namespace=moby Sep 9 05:40:46.545342 systemd-networkd[1592]: veth4e94e93: Lost carrier Sep 9 05:40:46.546077 kernel: docker0: port 1(veth4e94e93) entered disabled state Sep 9 05:40:46.546127 kernel: veth3e66b9d: renamed from eth0 Sep 9 05:40:46.571548 systemd-networkd[1592]: veth4e94e93: Link DOWN Sep 9 05:40:46.575072 kernel: docker0: port 1(veth4e94e93) entered disabled state Sep 9 05:40:46.581662 kernel: veth4e94e93 (unregistering): left allmulticast mode Sep 9 05:40:46.581714 kernel: veth4e94e93 (unregistering): left promiscuous mode Sep 9 05:40:46.585048 kernel: docker0: port 1(veth4e94e93) entered disabled state Sep 9 05:40:46.729250 sshd[16031]: Connection closed by 10.200.16.10 port 33036 Sep 9 05:40:46.729624 sshd-session[16028]: pam_unix(sshd:session): session closed for user core Sep 9 05:40:46.732553 systemd[1]: sshd@42-10.200.8.10:22-10.200.16.10:33036.service: Deactivated successfully. Sep 9 05:40:46.734232 systemd[1]: session-45.scope: Deactivated successfully. Sep 9 05:40:46.735439 systemd-logind[1897]: Session 45 logged out. Waiting for processes to exit. Sep 9 05:40:46.736402 systemd-logind[1897]: Removed session 45. Sep 9 05:40:46.851204 systemd[1]: Started sshd@43-10.200.8.10:22-10.200.16.10:33042.service - OpenSSH per-connection server daemon (10.200.16.10:33042). Sep 9 05:40:46.970257 systemd-networkd[1592]: docker0: Lost carrier Sep 9 05:40:47.268185 systemd[1]: var-lib-docker-overlay2-2fe102e2126091fad8bc86a47fdf627a6f2cf3efaf2171da232907170e742343-merged.mount: Deactivated successfully. Sep 9 05:40:47.499482 sshd[16131]: Accepted publickey for core from 10.200.16.10 port 33042 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:40:47.500671 sshd-session[16131]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:40:47.504635 systemd-logind[1897]: New session 46 of user core. Sep 9 05:40:47.515178 systemd[1]: Started session-46.scope - Session 46 of User core. Sep 9 05:40:47.956487 ntpdate[16145]: ntpdate 4.2.8p17@1.4004-o Tue Sep 9 03:09:58 UTC 2025 (1) Sep 9 05:40:54.738423 ntpdate[16145]: adjust time server 193.1.8.106 offset -0.003443 sec Sep 9 05:40:54.840148 sshd[16134]: Connection closed by 10.200.16.10 port 33042 Sep 9 05:40:54.840519 sshd-session[16131]: pam_unix(sshd:session): session closed for user core Sep 9 05:40:54.843432 systemd[1]: sshd@43-10.200.8.10:22-10.200.16.10:33042.service: Deactivated successfully. Sep 9 05:40:54.845121 systemd[1]: session-46.scope: Deactivated successfully. Sep 9 05:40:54.846310 systemd-logind[1897]: Session 46 logged out. Waiting for processes to exit. Sep 9 05:40:54.847369 systemd-logind[1897]: Removed session 46.