Sep 11 00:25:15.187625 kernel: Linux version 6.12.46-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.44 p1) 2.44.0) #1 SMP PREEMPT_DYNAMIC Wed Sep 10 22:25:29 -00 2025 Sep 11 00:25:15.187655 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=24178014e7d1a618b6c727661dc98ca9324f7f5aeefcaa5f4996d4d839e6e63a Sep 11 00:25:15.187665 kernel: BIOS-provided physical RAM map: Sep 11 00:25:15.187672 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Sep 11 00:25:15.187678 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Sep 11 00:25:15.187685 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Sep 11 00:25:15.187693 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Sep 11 00:25:15.187702 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Sep 11 00:25:15.187709 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Sep 11 00:25:15.187716 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Sep 11 00:25:15.187723 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Sep 11 00:25:15.187730 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Sep 11 00:25:15.187736 kernel: printk: legacy bootconsole [earlyser0] enabled Sep 11 00:25:15.187743 kernel: NX (Execute Disable) protection: active Sep 11 00:25:15.187754 kernel: APIC: Static calls initialized Sep 11 00:25:15.187762 kernel: efi: EFI v2.7 by Microsoft Sep 11 00:25:15.187770 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Sep 11 00:25:15.187777 kernel: random: crng init done Sep 11 00:25:15.187785 kernel: secureboot: Secure boot disabled Sep 11 00:25:15.187792 kernel: SMBIOS 3.1.0 present. Sep 11 00:25:15.187800 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Sep 11 00:25:15.187808 kernel: DMI: Memory slots populated: 3/3 Sep 11 00:25:15.187815 kernel: Hypervisor detected: Microsoft Hyper-V Sep 11 00:25:15.187823 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Sep 11 00:25:15.187833 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Sep 11 00:25:15.187840 kernel: Hyper-V: Using hypercall for remote TLB flush Sep 11 00:25:15.187848 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Sep 11 00:25:15.187856 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Sep 11 00:25:15.187864 kernel: tsc: Marking TSC unstable due to running on Hyper-V Sep 11 00:25:15.187872 kernel: tsc: Detected 2593.992 MHz processor Sep 11 00:25:15.187879 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Sep 11 00:25:15.187887 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Sep 11 00:25:15.187895 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Sep 11 00:25:15.187905 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Sep 11 00:25:15.187913 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Sep 11 00:25:15.187921 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Sep 11 00:25:15.187928 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Sep 11 00:25:15.187936 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Sep 11 00:25:15.187943 kernel: Using GB pages for direct mapping Sep 11 00:25:15.187951 kernel: ACPI: Early table checksum verification disabled Sep 11 00:25:15.187963 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Sep 11 00:25:15.187973 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 11 00:25:15.187981 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 11 00:25:15.187989 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Sep 11 00:25:15.187997 kernel: ACPI: FACS 0x000000003FFFE000 000040 Sep 11 00:25:15.188005 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 11 00:25:15.188014 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 11 00:25:15.188024 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 11 00:25:15.188032 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 11 00:25:15.188040 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 11 00:25:15.188049 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 11 00:25:15.188057 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 11 00:25:15.188065 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Sep 11 00:25:15.188073 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Sep 11 00:25:15.188081 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Sep 11 00:25:15.188091 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Sep 11 00:25:15.188100 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Sep 11 00:25:15.188108 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Sep 11 00:25:15.188115 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Sep 11 00:25:15.188123 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Sep 11 00:25:15.188132 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Sep 11 00:25:15.188140 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Sep 11 00:25:15.188148 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Sep 11 00:25:15.188156 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Sep 11 00:25:15.188166 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Sep 11 00:25:15.188174 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Sep 11 00:25:15.188182 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Sep 11 00:25:15.188190 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Sep 11 00:25:15.188198 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Sep 11 00:25:15.188206 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Sep 11 00:25:15.188214 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] Sep 11 00:25:15.188223 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] Sep 11 00:25:15.188231 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] Sep 11 00:25:15.188241 kernel: Zone ranges: Sep 11 00:25:15.188249 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Sep 11 00:25:15.188257 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Sep 11 00:25:15.188265 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Sep 11 00:25:15.188273 kernel: Device empty Sep 11 00:25:15.188281 kernel: Movable zone start for each node Sep 11 00:25:15.188289 kernel: Early memory node ranges Sep 11 00:25:15.188297 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Sep 11 00:25:15.188305 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Sep 11 00:25:15.188316 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Sep 11 00:25:15.188324 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Sep 11 00:25:15.188332 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Sep 11 00:25:15.188340 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Sep 11 00:25:15.188348 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Sep 11 00:25:15.188356 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Sep 11 00:25:15.188364 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Sep 11 00:25:15.188372 kernel: ACPI: PM-Timer IO Port: 0x408 Sep 11 00:25:15.188380 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Sep 11 00:25:15.188390 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Sep 11 00:25:15.188398 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Sep 11 00:25:15.188406 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Sep 11 00:25:15.188415 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Sep 11 00:25:15.188423 kernel: CPU topo: Max. logical packages: 1 Sep 11 00:25:15.188431 kernel: CPU topo: Max. logical dies: 1 Sep 11 00:25:15.188439 kernel: CPU topo: Max. dies per package: 1 Sep 11 00:25:15.188447 kernel: CPU topo: Max. threads per core: 1 Sep 11 00:25:15.188455 kernel: CPU topo: Num. cores per package: 6 Sep 11 00:25:15.188465 kernel: CPU topo: Num. threads per package: 6 Sep 11 00:25:15.188473 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs Sep 11 00:25:15.188481 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Sep 11 00:25:15.188489 kernel: Booting paravirtualized kernel on Hyper-V Sep 11 00:25:15.188497 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Sep 11 00:25:15.188506 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Sep 11 00:25:15.188514 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 Sep 11 00:25:15.188522 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 Sep 11 00:25:15.188530 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Sep 11 00:25:15.188540 kernel: Hyper-V: PV spinlocks enabled Sep 11 00:25:15.188548 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Sep 11 00:25:15.188557 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=24178014e7d1a618b6c727661dc98ca9324f7f5aeefcaa5f4996d4d839e6e63a Sep 11 00:25:15.188566 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Sep 11 00:25:15.188584 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Sep 11 00:25:15.188593 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Sep 11 00:25:15.188601 kernel: Fallback order for Node 0: 0 Sep 11 00:25:15.188609 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 Sep 11 00:25:15.188620 kernel: Policy zone: Normal Sep 11 00:25:15.188636 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 11 00:25:15.188644 kernel: software IO TLB: area num 8. Sep 11 00:25:15.188655 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Sep 11 00:25:15.188664 kernel: Kernel/User page tables isolation: enabled Sep 11 00:25:15.188672 kernel: ftrace: allocating 40103 entries in 157 pages Sep 11 00:25:15.188681 kernel: ftrace: allocated 157 pages with 5 groups Sep 11 00:25:15.188689 kernel: Dynamic Preempt: voluntary Sep 11 00:25:15.188698 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 11 00:25:15.188707 kernel: rcu: RCU event tracing is enabled. Sep 11 00:25:15.188716 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Sep 11 00:25:15.188724 kernel: Trampoline variant of Tasks RCU enabled. Sep 11 00:25:15.188735 kernel: Rude variant of Tasks RCU enabled. Sep 11 00:25:15.188744 kernel: Tracing variant of Tasks RCU enabled. Sep 11 00:25:15.188766 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 11 00:25:15.188793 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Sep 11 00:25:15.188803 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Sep 11 00:25:15.188819 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Sep 11 00:25:15.188828 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Sep 11 00:25:15.188837 kernel: Using NULL legacy PIC Sep 11 00:25:15.188845 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Sep 11 00:25:15.188854 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 11 00:25:15.188863 kernel: Console: colour dummy device 80x25 Sep 11 00:25:15.188872 kernel: printk: legacy console [tty1] enabled Sep 11 00:25:15.188881 kernel: printk: legacy console [ttyS0] enabled Sep 11 00:25:15.188889 kernel: printk: legacy bootconsole [earlyser0] disabled Sep 11 00:25:15.188901 kernel: ACPI: Core revision 20240827 Sep 11 00:25:15.188909 kernel: Failed to register legacy timer interrupt Sep 11 00:25:15.188918 kernel: APIC: Switch to symmetric I/O mode setup Sep 11 00:25:15.188927 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Sep 11 00:25:15.188935 kernel: Hyper-V: enabling crash_kexec_post_notifiers Sep 11 00:25:15.188944 kernel: Hyper-V: Using IPI hypercalls Sep 11 00:25:15.188953 kernel: APIC: send_IPI() replaced with hv_send_ipi() Sep 11 00:25:15.188961 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Sep 11 00:25:15.188970 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Sep 11 00:25:15.188982 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Sep 11 00:25:15.188990 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Sep 11 00:25:15.188999 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Sep 11 00:25:15.189008 kernel: Hyper-V: Using enlightened APIC (xapic mode) Sep 11 00:25:15.189016 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Sep 11 00:25:15.189025 kernel: APIC: read() replaced with hv_apic_read() Sep 11 00:25:15.189034 kernel: APIC: write() replaced with hv_apic_write() Sep 11 00:25:15.189042 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Sep 11 00:25:15.189051 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Sep 11 00:25:15.189063 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593992) Sep 11 00:25:15.189072 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Sep 11 00:25:15.189080 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Sep 11 00:25:15.189089 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Sep 11 00:25:15.189097 kernel: Spectre V2 : Mitigation: Retpolines Sep 11 00:25:15.189106 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Sep 11 00:25:15.189114 kernel: Speculative Store Bypass: Vulnerable Sep 11 00:25:15.189123 kernel: MDS: Mitigation: Clear CPU buffers Sep 11 00:25:15.189131 kernel: TAA: Mitigation: Clear CPU buffers Sep 11 00:25:15.189139 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Sep 11 00:25:15.189150 kernel: active return thunk: its_return_thunk Sep 11 00:25:15.189159 kernel: ITS: Mitigation: Aligned branch/return thunks Sep 11 00:25:15.189167 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Sep 11 00:25:15.189176 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Sep 11 00:25:15.189184 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Sep 11 00:25:15.189193 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Sep 11 00:25:15.189201 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Sep 11 00:25:15.189210 kernel: Freeing SMP alternatives memory: 32K Sep 11 00:25:15.189218 kernel: pid_max: default: 32768 minimum: 301 Sep 11 00:25:15.189226 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Sep 11 00:25:15.189235 kernel: landlock: Up and running. Sep 11 00:25:15.189248 kernel: SELinux: Initializing. Sep 11 00:25:15.189257 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Sep 11 00:25:15.189265 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Sep 11 00:25:15.189274 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Sep 11 00:25:15.189283 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Sep 11 00:25:15.189292 kernel: signal: max sigframe size: 1776 Sep 11 00:25:15.189300 kernel: rcu: Hierarchical SRCU implementation. Sep 11 00:25:15.189310 kernel: rcu: Max phase no-delay instances is 400. Sep 11 00:25:15.189327 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Sep 11 00:25:15.189336 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Sep 11 00:25:15.189348 kernel: smp: Bringing up secondary CPUs ... Sep 11 00:25:15.189357 kernel: smpboot: x86: Booting SMP configuration: Sep 11 00:25:15.189365 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Sep 11 00:25:15.189374 kernel: smp: Brought up 1 node, 6 CPUs Sep 11 00:25:15.189382 kernel: smpboot: Total of 6 processors activated (31127.90 BogoMIPS) Sep 11 00:25:15.189392 kernel: Memory: 115341144K/117439364K available (14336K kernel code, 2429K rwdata, 9960K rodata, 53832K init, 1088K bss, 2087952K reserved, 0K cma-reserved) Sep 11 00:25:15.189401 kernel: devtmpfs: initialized Sep 11 00:25:15.189410 kernel: x86/mm: Memory block size: 1024MB Sep 11 00:25:15.189418 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Sep 11 00:25:15.189430 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 11 00:25:15.189439 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Sep 11 00:25:15.189447 kernel: pinctrl core: initialized pinctrl subsystem Sep 11 00:25:15.189456 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 11 00:25:15.189465 kernel: audit: initializing netlink subsys (disabled) Sep 11 00:25:15.189473 kernel: audit: type=2000 audit(1757550311.028:1): state=initialized audit_enabled=0 res=1 Sep 11 00:25:15.189482 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 11 00:25:15.189491 kernel: thermal_sys: Registered thermal governor 'user_space' Sep 11 00:25:15.189499 kernel: cpuidle: using governor menu Sep 11 00:25:15.189511 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 11 00:25:15.189525 kernel: dca service started, version 1.12.1 Sep 11 00:25:15.189539 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Sep 11 00:25:15.189548 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Sep 11 00:25:15.189557 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 11 00:25:15.189566 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Sep 11 00:25:15.189589 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 11 00:25:15.189599 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Sep 11 00:25:15.189608 kernel: ACPI: Added _OSI(Module Device) Sep 11 00:25:15.189621 kernel: ACPI: Added _OSI(Processor Device) Sep 11 00:25:15.189629 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 11 00:25:15.189638 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 11 00:25:15.189647 kernel: ACPI: Interpreter enabled Sep 11 00:25:15.189655 kernel: ACPI: PM: (supports S0 S5) Sep 11 00:25:15.189664 kernel: ACPI: Using IOAPIC for interrupt routing Sep 11 00:25:15.189673 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Sep 11 00:25:15.189681 kernel: PCI: Ignoring E820 reservations for host bridge windows Sep 11 00:25:15.189690 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Sep 11 00:25:15.189702 kernel: iommu: Default domain type: Translated Sep 11 00:25:15.189710 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Sep 11 00:25:15.189719 kernel: efivars: Registered efivars operations Sep 11 00:25:15.189728 kernel: PCI: Using ACPI for IRQ routing Sep 11 00:25:15.189737 kernel: PCI: System does not support PCI Sep 11 00:25:15.189746 kernel: vgaarb: loaded Sep 11 00:25:15.189754 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Sep 11 00:25:15.189763 kernel: VFS: Disk quotas dquot_6.6.0 Sep 11 00:25:15.189772 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 11 00:25:15.189783 kernel: pnp: PnP ACPI init Sep 11 00:25:15.189792 kernel: pnp: PnP ACPI: found 3 devices Sep 11 00:25:15.189800 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Sep 11 00:25:15.189809 kernel: NET: Registered PF_INET protocol family Sep 11 00:25:15.189818 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 11 00:25:15.189827 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Sep 11 00:25:15.189836 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 11 00:25:15.189844 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 11 00:25:15.189853 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Sep 11 00:25:15.189871 kernel: TCP: Hash tables configured (established 524288 bind 65536) Sep 11 00:25:15.189880 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Sep 11 00:25:15.189889 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Sep 11 00:25:15.189902 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 11 00:25:15.189911 kernel: NET: Registered PF_XDP protocol family Sep 11 00:25:15.189925 kernel: PCI: CLS 0 bytes, default 64 Sep 11 00:25:15.189938 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Sep 11 00:25:15.189947 kernel: software IO TLB: mapped [mem 0x000000003b5bf000-0x000000003f5bf000] (64MB) Sep 11 00:25:15.189956 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Sep 11 00:25:15.189968 kernel: Initialise system trusted keyrings Sep 11 00:25:15.189977 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Sep 11 00:25:15.189985 kernel: Key type asymmetric registered Sep 11 00:25:15.189994 kernel: Asymmetric key parser 'x509' registered Sep 11 00:25:15.190002 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Sep 11 00:25:15.190011 kernel: io scheduler mq-deadline registered Sep 11 00:25:15.190020 kernel: io scheduler kyber registered Sep 11 00:25:15.190028 kernel: io scheduler bfq registered Sep 11 00:25:15.190037 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Sep 11 00:25:15.190048 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 11 00:25:15.190057 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Sep 11 00:25:15.190066 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Sep 11 00:25:15.190075 kernel: i8042: PNP: No PS/2 controller found. Sep 11 00:25:15.190233 kernel: rtc_cmos 00:02: registered as rtc0 Sep 11 00:25:15.190336 kernel: rtc_cmos 00:02: setting system clock to 2025-09-11T00:25:14 UTC (1757550314) Sep 11 00:25:15.190431 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Sep 11 00:25:15.190447 kernel: intel_pstate: CPU model not supported Sep 11 00:25:15.190457 kernel: efifb: probing for efifb Sep 11 00:25:15.190465 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Sep 11 00:25:15.190474 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Sep 11 00:25:15.190483 kernel: efifb: scrolling: redraw Sep 11 00:25:15.190491 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Sep 11 00:25:15.190500 kernel: Console: switching to colour frame buffer device 128x48 Sep 11 00:25:15.190509 kernel: fb0: EFI VGA frame buffer device Sep 11 00:25:15.190518 kernel: pstore: Using crash dump compression: deflate Sep 11 00:25:15.190529 kernel: pstore: Registered efi_pstore as persistent store backend Sep 11 00:25:15.190538 kernel: NET: Registered PF_INET6 protocol family Sep 11 00:25:15.190546 kernel: Segment Routing with IPv6 Sep 11 00:25:15.190555 kernel: In-situ OAM (IOAM) with IPv6 Sep 11 00:25:15.190564 kernel: NET: Registered PF_PACKET protocol family Sep 11 00:25:15.190585 kernel: Key type dns_resolver registered Sep 11 00:25:15.190594 kernel: IPI shorthand broadcast: enabled Sep 11 00:25:15.190603 kernel: sched_clock: Marking stable (3654005400, 110097800)->(4117793800, -353690600) Sep 11 00:25:15.190615 kernel: registered taskstats version 1 Sep 11 00:25:15.190627 kernel: Loading compiled-in X.509 certificates Sep 11 00:25:15.190635 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.46-flatcar: 8138ce5002a1b572fd22b23ac238f29bab3f249f' Sep 11 00:25:15.190644 kernel: Demotion targets for Node 0: null Sep 11 00:25:15.190653 kernel: Key type .fscrypt registered Sep 11 00:25:15.190661 kernel: Key type fscrypt-provisioning registered Sep 11 00:25:15.190670 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 11 00:25:15.190679 kernel: ima: Allocated hash algorithm: sha1 Sep 11 00:25:15.190687 kernel: ima: No architecture policies found Sep 11 00:25:15.190696 kernel: clk: Disabling unused clocks Sep 11 00:25:15.190707 kernel: Warning: unable to open an initial console. Sep 11 00:25:15.190716 kernel: Freeing unused kernel image (initmem) memory: 53832K Sep 11 00:25:15.190724 kernel: Write protecting the kernel read-only data: 24576k Sep 11 00:25:15.190733 kernel: Freeing unused kernel image (rodata/data gap) memory: 280K Sep 11 00:25:15.190742 kernel: Run /init as init process Sep 11 00:25:15.190750 kernel: with arguments: Sep 11 00:25:15.190759 kernel: /init Sep 11 00:25:15.190767 kernel: with environment: Sep 11 00:25:15.190776 kernel: HOME=/ Sep 11 00:25:15.190786 kernel: TERM=linux Sep 11 00:25:15.190795 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 11 00:25:15.190805 systemd[1]: Successfully made /usr/ read-only. Sep 11 00:25:15.190817 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 11 00:25:15.190826 systemd[1]: Detected virtualization microsoft. Sep 11 00:25:15.190835 systemd[1]: Detected architecture x86-64. Sep 11 00:25:15.190844 systemd[1]: Running in initrd. Sep 11 00:25:15.190853 systemd[1]: No hostname configured, using default hostname. Sep 11 00:25:15.190865 systemd[1]: Hostname set to . Sep 11 00:25:15.190874 systemd[1]: Initializing machine ID from random generator. Sep 11 00:25:15.190883 systemd[1]: Queued start job for default target initrd.target. Sep 11 00:25:15.190892 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 11 00:25:15.190901 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 11 00:25:15.190911 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 11 00:25:15.190921 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 11 00:25:15.190930 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 11 00:25:15.190942 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 11 00:25:15.190952 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Sep 11 00:25:15.190962 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 11 00:25:15.190971 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 11 00:25:15.190980 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 11 00:25:15.190990 systemd[1]: Reached target paths.target - Path Units. Sep 11 00:25:15.191001 systemd[1]: Reached target slices.target - Slice Units. Sep 11 00:25:15.191010 systemd[1]: Reached target swap.target - Swaps. Sep 11 00:25:15.191020 systemd[1]: Reached target timers.target - Timer Units. Sep 11 00:25:15.191029 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 11 00:25:15.191038 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 11 00:25:15.191047 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 11 00:25:15.191057 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Sep 11 00:25:15.191066 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 11 00:25:15.191075 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 11 00:25:15.191087 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 11 00:25:15.191096 systemd[1]: Reached target sockets.target - Socket Units. Sep 11 00:25:15.191105 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 11 00:25:15.191114 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 11 00:25:15.191123 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 11 00:25:15.191133 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 11 00:25:15.191142 systemd[1]: Starting systemd-fsck-usr.service... Sep 11 00:25:15.191151 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 11 00:25:15.191163 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 11 00:25:15.191192 systemd-journald[233]: Collecting audit messages is disabled. Sep 11 00:25:15.191216 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 11 00:25:15.191226 systemd-journald[233]: Journal started Sep 11 00:25:15.191250 systemd-journald[233]: Runtime Journal (/run/log/journal/226c3e3203244eb68cb4035f610075bf) is 8M, max 2.2G, 2.1G free. Sep 11 00:25:15.194445 systemd-modules-load[235]: Inserted module 'overlay' Sep 11 00:25:15.205655 systemd[1]: Started systemd-journald.service - Journal Service. Sep 11 00:25:15.200867 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 11 00:25:15.214507 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 11 00:25:15.216174 systemd[1]: Finished systemd-fsck-usr.service. Sep 11 00:25:15.228095 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 11 00:25:15.229077 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 11 00:25:15.263631 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 11 00:25:15.266471 systemd-tmpfiles[244]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Sep 11 00:25:15.269810 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 11 00:25:15.283853 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 11 00:25:15.300566 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 11 00:25:15.301986 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 11 00:25:15.326108 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 11 00:25:15.330885 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 11 00:25:15.343638 systemd-modules-load[235]: Inserted module 'br_netfilter' Sep 11 00:25:15.345156 kernel: Bridge firewalling registered Sep 11 00:25:15.344522 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 11 00:25:15.346657 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 11 00:25:15.349002 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 11 00:25:15.351263 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 11 00:25:15.380819 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 11 00:25:15.386713 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 11 00:25:15.398080 dracut-cmdline[269]: 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=24178014e7d1a618b6c727661dc98ca9324f7f5aeefcaa5f4996d4d839e6e63a Sep 11 00:25:15.448507 systemd-resolved[285]: Positive Trust Anchors: Sep 11 00:25:15.448523 systemd-resolved[285]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 11 00:25:15.448553 systemd-resolved[285]: 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 11 00:25:15.451128 systemd-resolved[285]: Defaulting to hostname 'linux'. Sep 11 00:25:15.452110 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 11 00:25:15.461771 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 11 00:25:15.555601 kernel: SCSI subsystem initialized Sep 11 00:25:15.567593 kernel: Loading iSCSI transport class v2.0-870. Sep 11 00:25:15.579597 kernel: iscsi: registered transport (tcp) Sep 11 00:25:15.603860 kernel: iscsi: registered transport (qla4xxx) Sep 11 00:25:15.603887 kernel: QLogic iSCSI HBA Driver Sep 11 00:25:15.622258 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 11 00:25:15.647017 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 11 00:25:15.649494 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 11 00:25:15.712182 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 11 00:25:15.714822 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 11 00:25:15.788599 kernel: raid6: avx2x4 gen() 30946 MB/s Sep 11 00:25:15.807595 kernel: raid6: avx2x2 gen() 31120 MB/s Sep 11 00:25:15.828755 kernel: raid6: avx2x1 gen() 27014 MB/s Sep 11 00:25:15.828779 kernel: raid6: using algorithm avx2x2 gen() 31120 MB/s Sep 11 00:25:15.851804 kernel: raid6: .... xor() 18578 MB/s, rmw enabled Sep 11 00:25:15.851829 kernel: raid6: using avx2x2 recovery algorithm Sep 11 00:25:15.874599 kernel: xor: automatically using best checksumming function avx Sep 11 00:25:16.037601 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 11 00:25:16.044995 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 11 00:25:16.048002 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 11 00:25:16.082052 systemd-udevd[484]: Using default interface naming scheme 'v255'. Sep 11 00:25:16.087973 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 11 00:25:16.090184 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 11 00:25:16.128903 dracut-pre-trigger[492]: rd.md=0: removing MD RAID activation Sep 11 00:25:16.155924 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 11 00:25:16.158490 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 11 00:25:16.239380 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 11 00:25:16.249410 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 11 00:25:16.271592 kernel: cryptd: max_cpu_qlen set to 1000 Sep 11 00:25:16.281748 kernel: AES CTR mode by8 optimization enabled Sep 11 00:25:16.286604 kernel: hv_vmbus: Vmbus version:4.0 Sep 11 00:25:16.304519 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 11 00:25:16.304717 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 11 00:25:16.318245 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 11 00:25:16.334382 kernel: pps_core: LinuxPPS API ver. 1 registered Sep 11 00:25:16.334418 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Sep 11 00:25:16.331691 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 11 00:25:16.345330 kernel: hv_vmbus: registering driver hv_netvsc Sep 11 00:25:16.339992 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 11 00:25:16.341769 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 11 00:25:16.373769 kernel: hv_vmbus: registering driver hyperv_keyboard Sep 11 00:25:16.373791 kernel: input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/VMBUS:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input0 Sep 11 00:25:16.341862 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 11 00:25:16.351623 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 11 00:25:16.390594 kernel: PTP clock support registered Sep 11 00:25:16.390650 kernel: hid: raw HID events driver (C) Jiri Kosina Sep 11 00:25:16.390670 kernel: hv_vmbus: registering driver hv_pci Sep 11 00:25:16.397964 kernel: hv_utils: Registering HyperV Utility Driver Sep 11 00:25:16.397998 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Sep 11 00:25:16.398172 kernel: hv_vmbus: registering driver hv_utils Sep 11 00:25:16.398186 kernel: hv_utils: Heartbeat IC version 3.0 Sep 11 00:25:16.405650 kernel: hv_utils: Shutdown IC version 3.2 Sep 11 00:25:16.405677 kernel: hv_utils: TimeSync IC version 4.0 Sep 11 00:25:16.405690 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Sep 11 00:25:16.405835 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Sep 11 00:25:16.405989 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Sep 11 00:25:16.406128 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Sep 11 00:25:16.406243 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint Sep 11 00:25:16.261391 systemd-resolved[285]: Clock change detected. Flushing caches. Sep 11 00:25:16.308382 kernel: hv_vmbus: registering driver hv_storvsc Sep 11 00:25:16.308404 kernel: hv_vmbus: registering driver hid_hyperv Sep 11 00:25:16.308418 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] Sep 11 00:25:16.308442 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] Sep 11 00:25:16.308459 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] Sep 11 00:25:16.308475 systemd-journald[233]: Time jumped backwards, rotating. Sep 11 00:25:16.308522 kernel: pci 0001:00:00.0: Enabling HDA controller Sep 11 00:25:16.291651 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 11 00:25:16.334582 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Sep 11 00:25:16.334749 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned Sep 11 00:25:16.335346 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned Sep 11 00:25:16.345418 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned Sep 11 00:25:16.649363 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Sep 11 00:25:16.649421 kernel: scsi host1: storvsc_host_t Sep 11 00:25:16.649457 kernel: scsi host0: storvsc_host_t Sep 11 00:25:16.651385 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Sep 11 00:25:16.652377 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Sep 11 00:25:16.652412 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Sep 11 00:25:16.654947 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Sep 11 00:25:16.686845 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Sep 11 00:25:16.687044 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Sep 11 00:25:16.687200 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks Sep 11 00:25:16.687348 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Sep 11 00:25:16.687490 kernel: sd 0:0:0:0: [sda] Write Protect is off Sep 11 00:25:16.693343 kernel: sd 0:0:0:1: [sdb] Write Protect is off Sep 11 00:25:16.693497 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Sep 11 00:25:16.699629 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Sep 11 00:25:16.699785 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Sep 11 00:25:16.702350 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Sep 11 00:25:16.702504 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#69 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 11 00:25:16.724380 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#199 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 11 00:25:16.731341 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#76 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 11 00:25:16.731489 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#512 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 11 00:25:16.753344 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 11 00:25:16.753370 kernel: sdb: sdb1 Sep 11 00:25:16.757012 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Sep 11 00:25:16.757186 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk Sep 11 00:25:16.762349 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Sep 11 00:25:16.767051 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Sep 11 00:25:16.769351 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Sep 11 00:25:17.425572 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Sep 11 00:25:17.491700 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Sep 11 00:25:17.502962 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Sep 11 00:25:17.515398 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Sep 11 00:25:17.543174 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 11 00:25:17.557309 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Sep 11 00:25:17.591349 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#127 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 11 00:25:17.609344 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 11 00:25:18.621775 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#472 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 11 00:25:18.635851 disk-uuid[725]: The operation has completed successfully. Sep 11 00:25:18.639941 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 11 00:25:19.507555 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 11 00:25:19.517354 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 11 00:25:19.520871 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 11 00:25:19.532914 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 11 00:25:19.543045 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 11 00:25:19.552811 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 11 00:25:19.562211 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 11 00:25:19.567237 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 11 00:25:19.595750 sh[890]: Success Sep 11 00:25:19.602351 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 11 00:25:19.634628 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 11 00:25:19.634676 kernel: device-mapper: uevent: version 1.0.3 Sep 11 00:25:19.639407 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Sep 11 00:25:19.652352 kernel: device-mapper: verity: sha256 using shash "sha256-avx2" Sep 11 00:25:19.978468 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 11 00:25:19.988529 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 11 00:25:20.010978 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 11 00:25:20.031407 kernel: BTRFS: device fsid f1eb5eb7-34cc-49c0-9f2b-e603bd772d66 devid 1 transid 39 /dev/mapper/usr (254:0) scanned by mount (915) Sep 11 00:25:20.040346 kernel: BTRFS info (device dm-0): first mount of filesystem f1eb5eb7-34cc-49c0-9f2b-e603bd772d66 Sep 11 00:25:20.040374 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Sep 11 00:25:20.461682 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 11 00:25:20.461758 kernel: BTRFS info (device dm-0): enabling free space tree Sep 11 00:25:20.493228 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 11 00:25:20.495265 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Sep 11 00:25:20.501662 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 11 00:25:20.502755 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 11 00:25:20.541998 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 11 00:25:20.575356 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (948) Sep 11 00:25:20.580348 kernel: BTRFS info (device sda6): first mount of filesystem a5de7b5e-e14d-4c62-883d-af7ea22fae7e Sep 11 00:25:20.580376 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Sep 11 00:25:20.631809 kernel: BTRFS info (device sda6): turning on async discard Sep 11 00:25:20.631847 kernel: BTRFS info (device sda6): enabling free space tree Sep 11 00:25:20.634525 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 11 00:25:20.637572 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 11 00:25:20.657186 kernel: BTRFS info (device sda6): last unmount of filesystem a5de7b5e-e14d-4c62-883d-af7ea22fae7e Sep 11 00:25:20.665425 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 11 00:25:20.667824 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 11 00:25:20.705569 systemd-networkd[1082]: lo: Link UP Sep 11 00:25:20.705580 systemd-networkd[1082]: lo: Gained carrier Sep 11 00:25:20.706605 systemd-networkd[1082]: Enumeration completed Sep 11 00:25:20.706703 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 11 00:25:20.706968 systemd-networkd[1082]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 11 00:25:20.706972 systemd-networkd[1082]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 11 00:25:20.707692 systemd-networkd[1082]: eth0: Link UP Sep 11 00:25:20.707857 systemd-networkd[1082]: eth0: Gained carrier Sep 11 00:25:20.707867 systemd-networkd[1082]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 11 00:25:20.712577 systemd[1]: Reached target network.target - Network. Sep 11 00:25:20.785371 systemd-networkd[1082]: eth0: DHCPv4 address 10.200.8.36/24, gateway 10.200.8.1 acquired from 168.63.129.16 Sep 11 00:25:21.827609 ignition[1085]: Ignition 2.21.0 Sep 11 00:25:21.827625 ignition[1085]: Stage: fetch-offline Sep 11 00:25:21.827739 ignition[1085]: no configs at "/usr/lib/ignition/base.d" Sep 11 00:25:21.827749 ignition[1085]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 11 00:25:21.827849 ignition[1085]: parsed url from cmdline: "" Sep 11 00:25:21.827853 ignition[1085]: no config URL provided Sep 11 00:25:21.827858 ignition[1085]: reading system config file "/usr/lib/ignition/user.ign" Sep 11 00:25:21.841990 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 11 00:25:21.827867 ignition[1085]: no config at "/usr/lib/ignition/user.ign" Sep 11 00:25:21.849564 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Sep 11 00:25:21.827872 ignition[1085]: failed to fetch config: resource requires networking Sep 11 00:25:21.832826 ignition[1085]: Ignition finished successfully Sep 11 00:25:21.889544 ignition[1097]: Ignition 2.21.0 Sep 11 00:25:21.889558 ignition[1097]: Stage: fetch Sep 11 00:25:21.890230 ignition[1097]: no configs at "/usr/lib/ignition/base.d" Sep 11 00:25:21.890245 ignition[1097]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 11 00:25:21.890462 ignition[1097]: parsed url from cmdline: "" Sep 11 00:25:21.890467 ignition[1097]: no config URL provided Sep 11 00:25:21.890473 ignition[1097]: reading system config file "/usr/lib/ignition/user.ign" Sep 11 00:25:21.890487 ignition[1097]: no config at "/usr/lib/ignition/user.ign" Sep 11 00:25:21.890556 ignition[1097]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Sep 11 00:25:21.994768 ignition[1097]: GET result: OK Sep 11 00:25:21.994819 ignition[1097]: config has been read from IMDS userdata Sep 11 00:25:21.994830 ignition[1097]: parsing config with SHA512: a15ff897478d6089a303d18faed3d2a2ca9cb83031fb8c0f8699107a233bc2d19d968355eae34034ec5536273a5a18c8ff32e0cf46b5f0017249e3791f932d4f Sep 11 00:25:22.000629 unknown[1097]: fetched base config from "system" Sep 11 00:25:22.000640 unknown[1097]: fetched base config from "system" Sep 11 00:25:22.000805 ignition[1097]: fetch: fetch complete Sep 11 00:25:22.000645 unknown[1097]: fetched user config from "azure" Sep 11 00:25:22.000820 ignition[1097]: fetch: fetch passed Sep 11 00:25:22.011831 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Sep 11 00:25:22.000867 ignition[1097]: Ignition finished successfully Sep 11 00:25:22.018383 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 11 00:25:22.065246 ignition[1106]: Ignition 2.21.0 Sep 11 00:25:22.065261 ignition[1106]: Stage: kargs Sep 11 00:25:22.065508 ignition[1106]: no configs at "/usr/lib/ignition/base.d" Sep 11 00:25:22.065519 ignition[1106]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 11 00:25:22.066560 ignition[1106]: kargs: kargs passed Sep 11 00:25:22.073840 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 11 00:25:22.066668 ignition[1106]: Ignition finished successfully Sep 11 00:25:22.082656 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 11 00:25:22.121847 ignition[1116]: Ignition 2.21.0 Sep 11 00:25:22.121861 ignition[1116]: Stage: disks Sep 11 00:25:22.122116 ignition[1116]: no configs at "/usr/lib/ignition/base.d" Sep 11 00:25:22.122127 ignition[1116]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 11 00:25:22.130998 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 11 00:25:22.123195 ignition[1116]: disks: disks passed Sep 11 00:25:22.138812 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 11 00:25:22.123272 ignition[1116]: Ignition finished successfully Sep 11 00:25:22.146859 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 11 00:25:22.154140 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 11 00:25:22.155569 systemd[1]: Reached target sysinit.target - System Initialization. Sep 11 00:25:22.163856 systemd[1]: Reached target basic.target - Basic System. Sep 11 00:25:22.174463 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 11 00:25:22.217438 systemd-networkd[1082]: eth0: Gained IPv6LL Sep 11 00:25:22.269239 systemd-fsck[1128]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks Sep 11 00:25:22.274413 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 11 00:25:22.276082 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 11 00:25:24.172364 kernel: EXT4-fs (sda9): mounted filesystem 6a9ce0af-81d0-4628-9791-e47488ed2744 r/w with ordered data mode. Quota mode: none. Sep 11 00:25:24.173288 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 11 00:25:24.179919 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 11 00:25:24.206784 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 11 00:25:24.227844 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 11 00:25:24.230380 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Sep 11 00:25:24.248290 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1142) Sep 11 00:25:24.236049 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Sep 11 00:25:24.270004 kernel: BTRFS info (device sda6): first mount of filesystem a5de7b5e-e14d-4c62-883d-af7ea22fae7e Sep 11 00:25:24.270026 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Sep 11 00:25:24.236081 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 11 00:25:24.283806 kernel: BTRFS info (device sda6): turning on async discard Sep 11 00:25:24.283827 kernel: BTRFS info (device sda6): enabling free space tree Sep 11 00:25:24.250887 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 11 00:25:24.283963 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 11 00:25:24.292405 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 11 00:25:25.042526 coreos-metadata[1144]: Sep 11 00:25:25.042 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Sep 11 00:25:25.049901 coreos-metadata[1144]: Sep 11 00:25:25.045 INFO Fetch successful Sep 11 00:25:25.049901 coreos-metadata[1144]: Sep 11 00:25:25.045 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Sep 11 00:25:25.062292 coreos-metadata[1144]: Sep 11 00:25:25.052 INFO Fetch successful Sep 11 00:25:25.062292 coreos-metadata[1144]: Sep 11 00:25:25.052 INFO wrote hostname ci-4372.1.0-n-d13e05d80d to /sysroot/etc/hostname Sep 11 00:25:25.055759 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Sep 11 00:25:25.256728 initrd-setup-root[1174]: cut: /sysroot/etc/passwd: No such file or directory Sep 11 00:25:25.355092 initrd-setup-root[1181]: cut: /sysroot/etc/group: No such file or directory Sep 11 00:25:25.371645 initrd-setup-root[1188]: cut: /sysroot/etc/shadow: No such file or directory Sep 11 00:25:25.379033 initrd-setup-root[1195]: cut: /sysroot/etc/gshadow: No such file or directory Sep 11 00:25:26.750971 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 11 00:25:26.753701 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 11 00:25:26.775872 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 11 00:25:26.787125 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 11 00:25:26.798706 kernel: BTRFS info (device sda6): last unmount of filesystem a5de7b5e-e14d-4c62-883d-af7ea22fae7e Sep 11 00:25:26.824821 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 11 00:25:26.835406 ignition[1265]: INFO : Ignition 2.21.0 Sep 11 00:25:26.835406 ignition[1265]: INFO : Stage: mount Sep 11 00:25:26.840042 ignition[1265]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 11 00:25:26.840042 ignition[1265]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 11 00:25:26.840042 ignition[1265]: INFO : mount: mount passed Sep 11 00:25:26.840042 ignition[1265]: INFO : Ignition finished successfully Sep 11 00:25:26.839430 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 11 00:25:26.845943 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 11 00:25:26.882223 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 11 00:25:26.911800 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1279) Sep 11 00:25:26.911831 kernel: BTRFS info (device sda6): first mount of filesystem a5de7b5e-e14d-4c62-883d-af7ea22fae7e Sep 11 00:25:26.916071 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Sep 11 00:25:26.925679 kernel: BTRFS info (device sda6): turning on async discard Sep 11 00:25:26.925702 kernel: BTRFS info (device sda6): enabling free space tree Sep 11 00:25:26.927993 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 11 00:25:26.968380 ignition[1296]: INFO : Ignition 2.21.0 Sep 11 00:25:26.968380 ignition[1296]: INFO : Stage: files Sep 11 00:25:26.974117 ignition[1296]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 11 00:25:26.974117 ignition[1296]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 11 00:25:26.974117 ignition[1296]: DEBUG : files: compiled without relabeling support, skipping Sep 11 00:25:26.996480 ignition[1296]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 11 00:25:26.996480 ignition[1296]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 11 00:25:27.076577 ignition[1296]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 11 00:25:27.082866 ignition[1296]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 11 00:25:27.088488 ignition[1296]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 11 00:25:27.083392 unknown[1296]: wrote ssh authorized keys file for user: core Sep 11 00:25:27.151777 ignition[1296]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 11 00:25:27.159077 ignition[1296]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 11 00:25:27.159077 ignition[1296]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 11 00:25:27.159077 ignition[1296]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 11 00:25:27.159077 ignition[1296]: INFO : files: files passed Sep 11 00:25:27.159077 ignition[1296]: INFO : Ignition finished successfully Sep 11 00:25:27.188999 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 11 00:25:27.191472 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 11 00:25:27.213868 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 11 00:25:27.217083 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 11 00:25:27.217175 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 11 00:25:27.287241 initrd-setup-root-after-ignition[1329]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 11 00:25:27.287241 initrd-setup-root-after-ignition[1329]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 11 00:25:27.305147 initrd-setup-root-after-ignition[1333]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 11 00:25:27.289839 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 11 00:25:27.295185 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 11 00:25:27.315823 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 11 00:25:27.358530 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 11 00:25:27.358642 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 11 00:25:27.373887 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 11 00:25:27.383043 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 11 00:25:27.385086 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 11 00:25:27.391961 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 11 00:25:27.434587 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 11 00:25:27.445387 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 11 00:25:27.476175 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 11 00:25:27.477777 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 11 00:25:27.486229 systemd[1]: Stopped target timers.target - Timer Units. Sep 11 00:25:27.498324 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 11 00:25:27.498465 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 11 00:25:27.510285 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 11 00:25:27.518867 systemd[1]: Stopped target basic.target - Basic System. Sep 11 00:25:27.526440 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 11 00:25:27.534169 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 11 00:25:27.536442 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 11 00:25:27.548777 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Sep 11 00:25:27.557541 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 11 00:25:27.573013 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 11 00:25:27.578498 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 11 00:25:27.587227 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 11 00:25:27.595076 systemd[1]: Stopped target swap.target - Swaps. Sep 11 00:25:27.602004 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 11 00:25:27.602182 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 11 00:25:27.609258 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 11 00:25:27.617397 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 11 00:25:27.625973 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 11 00:25:27.630125 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 11 00:25:27.640502 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 11 00:25:27.640619 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 11 00:25:27.653866 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 11 00:25:27.653989 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 11 00:25:27.662543 systemd[1]: ignition-files.service: Deactivated successfully. Sep 11 00:25:27.662659 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 11 00:25:27.663937 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Sep 11 00:25:27.664045 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Sep 11 00:25:27.680412 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 11 00:25:27.691800 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 11 00:25:27.698019 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 11 00:25:27.736555 ignition[1353]: INFO : Ignition 2.21.0 Sep 11 00:25:27.736555 ignition[1353]: INFO : Stage: umount Sep 11 00:25:27.736555 ignition[1353]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 11 00:25:27.736555 ignition[1353]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 11 00:25:27.736555 ignition[1353]: INFO : umount: umount passed Sep 11 00:25:27.736555 ignition[1353]: INFO : Ignition finished successfully Sep 11 00:25:27.698148 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 11 00:25:27.706469 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 11 00:25:27.706584 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 11 00:25:27.723103 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 11 00:25:27.723197 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 11 00:25:27.737658 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 11 00:25:27.737775 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 11 00:25:27.744260 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 11 00:25:27.744381 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 11 00:25:27.750341 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 11 00:25:27.750396 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 11 00:25:27.757995 systemd[1]: ignition-fetch.service: Deactivated successfully. Sep 11 00:25:27.758048 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Sep 11 00:25:27.766620 systemd[1]: Stopped target network.target - Network. Sep 11 00:25:27.773073 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 11 00:25:27.773123 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 11 00:25:27.780972 systemd[1]: Stopped target paths.target - Path Units. Sep 11 00:25:27.788655 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 11 00:25:27.792372 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 11 00:25:27.801596 systemd[1]: Stopped target slices.target - Slice Units. Sep 11 00:25:27.803034 systemd[1]: Stopped target sockets.target - Socket Units. Sep 11 00:25:27.820570 systemd[1]: iscsid.socket: Deactivated successfully. Sep 11 00:25:27.820612 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 11 00:25:27.822032 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 11 00:25:27.822069 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 11 00:25:27.833013 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 11 00:25:27.833068 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 11 00:25:27.840732 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 11 00:25:27.840778 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 11 00:25:27.848410 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 11 00:25:27.856345 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 11 00:25:27.864188 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 11 00:25:27.864778 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 11 00:25:27.864866 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 11 00:25:27.871531 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 11 00:25:27.871629 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 11 00:25:27.891031 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Sep 11 00:25:27.891282 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 11 00:25:27.891399 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 11 00:25:27.902402 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Sep 11 00:25:27.903728 systemd[1]: Stopped target network-pre.target - Preparation for Network. Sep 11 00:25:27.908777 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 11 00:25:27.908841 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 11 00:25:27.916271 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 11 00:25:27.916349 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 11 00:25:27.933952 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 11 00:25:27.941174 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 11 00:25:27.941232 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 11 00:25:27.942646 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 11 00:25:27.942690 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 11 00:25:27.957742 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 11 00:25:27.957786 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 11 00:25:27.969630 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 11 00:25:27.973605 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 11 00:25:27.982765 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 11 00:25:27.988282 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Sep 11 00:25:27.988365 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Sep 11 00:25:28.012096 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 11 00:25:28.012282 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 11 00:25:28.019890 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 11 00:25:28.019935 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 11 00:25:28.029159 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 11 00:25:28.029197 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 11 00:25:28.038029 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 11 00:25:28.038083 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 11 00:25:28.054391 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 11 00:25:28.054453 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 11 00:25:28.070392 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 11 00:25:28.070450 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 11 00:25:28.084209 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 11 00:25:28.091744 systemd[1]: systemd-network-generator.service: Deactivated successfully. Sep 11 00:25:28.091797 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Sep 11 00:25:28.102519 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 11 00:25:28.102568 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 11 00:25:28.111109 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Sep 11 00:25:28.111154 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 11 00:25:28.120152 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 11 00:25:28.120197 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 11 00:25:28.128224 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 11 00:25:28.128268 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 11 00:25:28.138601 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Sep 11 00:25:28.138671 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev\x2dearly.service.mount: Deactivated successfully. Sep 11 00:25:28.138716 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Sep 11 00:25:28.138760 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 11 00:25:28.139131 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 11 00:25:28.139227 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 11 00:25:28.142532 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 11 00:25:28.142623 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 11 00:25:28.152879 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 11 00:25:28.172230 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 11 00:25:28.206778 systemd[1]: Switching root. Sep 11 00:25:28.335984 systemd-journald[233]: Received SIGTERM from PID 1 (systemd). Sep 11 00:25:28.336094 systemd-journald[233]: Journal stopped Sep 11 00:25:36.575835 kernel: SELinux: policy capability network_peer_controls=1 Sep 11 00:25:36.575866 kernel: SELinux: policy capability open_perms=1 Sep 11 00:25:36.575879 kernel: SELinux: policy capability extended_socket_class=1 Sep 11 00:25:36.575888 kernel: SELinux: policy capability always_check_network=0 Sep 11 00:25:36.575897 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 11 00:25:36.575906 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 11 00:25:36.575916 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 11 00:25:36.575929 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 11 00:25:36.575942 kernel: SELinux: policy capability userspace_initial_context=0 Sep 11 00:25:36.575951 kernel: audit: type=1403 audit(1757550329.287:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 11 00:25:36.575963 systemd[1]: Successfully loaded SELinux policy in 265.650ms. Sep 11 00:25:36.575975 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 17.656ms. Sep 11 00:25:36.575986 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 11 00:25:36.575997 systemd[1]: Detected virtualization microsoft. Sep 11 00:25:36.576011 systemd[1]: Detected architecture x86-64. Sep 11 00:25:36.576021 systemd[1]: Detected first boot. Sep 11 00:25:36.576032 systemd[1]: Hostname set to . Sep 11 00:25:36.576043 systemd[1]: Initializing machine ID from random generator. Sep 11 00:25:36.576054 zram_generator::config[1396]: No configuration found. Sep 11 00:25:36.576068 kernel: Guest personality initialized and is inactive Sep 11 00:25:36.576078 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Sep 11 00:25:36.576087 kernel: Initialized host personality Sep 11 00:25:36.576097 kernel: NET: Registered PF_VSOCK protocol family Sep 11 00:25:36.576107 systemd[1]: Populated /etc with preset unit settings. Sep 11 00:25:36.576119 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Sep 11 00:25:36.576130 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 11 00:25:36.576143 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 11 00:25:36.576154 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 11 00:25:36.576164 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 11 00:25:36.576178 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 11 00:25:36.576189 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 11 00:25:36.576200 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 11 00:25:36.576210 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 11 00:25:36.576221 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 11 00:25:36.576235 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 11 00:25:36.576246 systemd[1]: Created slice user.slice - User and Session Slice. Sep 11 00:25:36.576257 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 11 00:25:36.576268 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 11 00:25:36.576279 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 11 00:25:36.576290 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 11 00:25:36.576304 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 11 00:25:36.576316 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 11 00:25:36.576343 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Sep 11 00:25:36.576357 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 11 00:25:36.576369 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 11 00:25:36.576380 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 11 00:25:36.576391 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 11 00:25:36.576402 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 11 00:25:36.576413 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 11 00:25:36.576427 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 11 00:25:36.576442 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 11 00:25:36.576453 systemd[1]: Reached target slices.target - Slice Units. Sep 11 00:25:36.576464 systemd[1]: Reached target swap.target - Swaps. Sep 11 00:25:36.576475 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 11 00:25:36.576486 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 11 00:25:36.576501 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Sep 11 00:25:36.576512 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 11 00:25:36.576523 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 11 00:25:36.576534 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 11 00:25:36.576546 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 11 00:25:36.576557 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 11 00:25:36.576568 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 11 00:25:36.576582 systemd[1]: Mounting media.mount - External Media Directory... Sep 11 00:25:36.576594 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 11 00:25:36.576605 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 11 00:25:36.576616 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 11 00:25:36.576627 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 11 00:25:36.576639 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 11 00:25:36.576650 systemd[1]: Reached target machines.target - Containers. Sep 11 00:25:36.576662 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 11 00:25:36.576673 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 11 00:25:36.576690 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 11 00:25:36.576701 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 11 00:25:36.576713 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 11 00:25:36.576724 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 11 00:25:36.576735 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 11 00:25:36.576746 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 11 00:25:36.576757 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 11 00:25:36.576769 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 11 00:25:36.576783 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 11 00:25:36.576794 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 11 00:25:36.576805 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 11 00:25:36.576817 systemd[1]: Stopped systemd-fsck-usr.service. Sep 11 00:25:36.576828 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 11 00:25:36.576840 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 11 00:25:36.576851 kernel: loop: module loaded Sep 11 00:25:36.576861 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 11 00:25:36.576875 kernel: fuse: init (API version 7.41) Sep 11 00:25:36.576886 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 11 00:25:36.576897 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 11 00:25:36.576909 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Sep 11 00:25:36.576920 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 11 00:25:36.576934 systemd[1]: verity-setup.service: Deactivated successfully. Sep 11 00:25:36.576945 systemd[1]: Stopped verity-setup.service. Sep 11 00:25:36.576956 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 11 00:25:36.576967 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 11 00:25:36.576981 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 11 00:25:36.577016 systemd-journald[1503]: Collecting audit messages is disabled. Sep 11 00:25:36.577043 systemd[1]: Mounted media.mount - External Media Directory. Sep 11 00:25:36.577058 systemd-journald[1503]: Journal started Sep 11 00:25:36.577081 systemd-journald[1503]: Runtime Journal (/run/log/journal/6371c16538994d03ab70fbd952e04507) is 8M, max 2.2G, 2.1G free. Sep 11 00:25:35.668160 systemd[1]: Queued start job for default target multi-user.target. Sep 11 00:25:35.678643 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Sep 11 00:25:35.679135 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 11 00:25:36.588913 systemd[1]: Started systemd-journald.service - Journal Service. Sep 11 00:25:36.593059 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 11 00:25:36.597713 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 11 00:25:36.602235 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 11 00:25:36.606684 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 11 00:25:36.611687 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 11 00:25:36.616993 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 11 00:25:36.617180 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 11 00:25:36.622252 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 11 00:25:36.622483 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 11 00:25:36.627562 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 11 00:25:36.627751 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 11 00:25:36.632933 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 11 00:25:36.633117 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 11 00:25:36.637945 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 11 00:25:36.638125 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 11 00:25:36.644346 kernel: ACPI: bus type drm_connector registered Sep 11 00:25:36.645357 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 11 00:25:36.650106 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 11 00:25:36.650286 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 11 00:25:36.654972 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 11 00:25:36.660383 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 11 00:25:36.665854 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Sep 11 00:25:36.680532 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 11 00:25:36.686182 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 11 00:25:36.705611 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 11 00:25:36.710012 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 11 00:25:36.710048 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 11 00:25:36.715254 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Sep 11 00:25:36.721520 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 11 00:25:36.725788 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 11 00:25:36.726954 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 11 00:25:36.732176 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 11 00:25:36.736941 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 11 00:25:36.737878 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 11 00:25:36.743363 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 11 00:25:36.744265 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 11 00:25:36.749494 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 11 00:25:36.755265 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 11 00:25:36.761812 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 11 00:25:36.766839 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 11 00:25:36.772479 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 11 00:25:36.785962 systemd-journald[1503]: Time spent on flushing to /var/log/journal/6371c16538994d03ab70fbd952e04507 is 44.144ms for 957 entries. Sep 11 00:25:36.785962 systemd-journald[1503]: System Journal (/var/log/journal/6371c16538994d03ab70fbd952e04507) is 11.8M, max 2.6G, 2.6G free. Sep 11 00:25:36.984568 systemd-journald[1503]: Received client request to flush runtime journal. Sep 11 00:25:36.984624 systemd-journald[1503]: /var/log/journal/6371c16538994d03ab70fbd952e04507/system.journal: Realtime clock jumped backwards relative to last journal entry, rotating. Sep 11 00:25:36.984655 systemd-journald[1503]: Rotating system journal. Sep 11 00:25:36.984684 kernel: loop0: detected capacity change from 0 to 113872 Sep 11 00:25:36.803156 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 11 00:25:36.808123 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 11 00:25:36.814041 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Sep 11 00:25:36.923155 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 11 00:25:36.928840 systemd-tmpfiles[1540]: ACLs are not supported, ignoring. Sep 11 00:25:36.928855 systemd-tmpfiles[1540]: ACLs are not supported, ignoring. Sep 11 00:25:36.934088 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 11 00:25:36.940523 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 11 00:25:36.985749 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 11 00:25:36.991681 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 11 00:25:36.992362 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Sep 11 00:25:37.709798 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 11 00:25:37.716656 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 11 00:25:37.749459 systemd-tmpfiles[1557]: ACLs are not supported, ignoring. Sep 11 00:25:37.749481 systemd-tmpfiles[1557]: ACLs are not supported, ignoring. Sep 11 00:25:37.753964 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 11 00:25:37.915359 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 11 00:25:38.008360 kernel: loop1: detected capacity change from 0 to 146240 Sep 11 00:25:39.008226 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 11 00:25:39.014831 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 11 00:25:39.064510 systemd-udevd[1563]: Using default interface naming scheme 'v255'. Sep 11 00:25:39.347361 kernel: loop2: detected capacity change from 0 to 28504 Sep 11 00:25:39.826521 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 11 00:25:39.834361 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 11 00:25:39.862370 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Sep 11 00:25:39.880361 kernel: loop3: detected capacity change from 0 to 113872 Sep 11 00:25:39.911359 kernel: loop4: detected capacity change from 0 to 146240 Sep 11 00:25:39.935370 kernel: loop5: detected capacity change from 0 to 28504 Sep 11 00:25:39.958623 (sd-merge)[1607]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Sep 11 00:25:39.963472 (sd-merge)[1607]: Merged extensions into '/usr'. Sep 11 00:25:39.971787 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 11 00:25:39.978670 systemd[1]: Starting ensure-sysext.service... Sep 11 00:25:39.983386 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 11 00:25:39.991316 kernel: mousedev: PS/2 mouse device common for all mice Sep 11 00:25:40.003577 kernel: hv_vmbus: registering driver hv_balloon Sep 11 00:25:40.003643 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Sep 11 00:25:40.010347 kernel: hv_vmbus: registering driver hyperv_fb Sep 11 00:25:40.015350 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Sep 11 00:25:40.015402 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Sep 11 00:25:40.021422 systemd[1]: Reload requested from client PID 1632 ('systemctl') (unit ensure-sysext.service)... Sep 11 00:25:40.021440 systemd[1]: Reloading... Sep 11 00:25:40.022648 kernel: Console: switching to colour dummy device 80x25 Sep 11 00:25:40.024350 kernel: Console: switching to colour frame buffer device 128x48 Sep 11 00:25:40.031363 kernel: EDAC MC: Ver: 3.0.0 Sep 11 00:25:40.066360 zram_generator::config[1666]: No configuration found. Sep 11 00:25:40.098539 systemd-tmpfiles[1634]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Sep 11 00:25:40.098593 systemd-tmpfiles[1634]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Sep 11 00:25:40.098935 systemd-tmpfiles[1634]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 11 00:25:40.099223 systemd-tmpfiles[1634]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 11 00:25:40.100302 systemd-tmpfiles[1634]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 11 00:25:40.100615 systemd-tmpfiles[1634]: ACLs are not supported, ignoring. Sep 11 00:25:40.100783 systemd-tmpfiles[1634]: ACLs are not supported, ignoring. Sep 11 00:25:40.176133 systemd-tmpfiles[1634]: Detected autofs mount point /boot during canonicalization of boot. Sep 11 00:25:40.176149 systemd-tmpfiles[1634]: Skipping /boot Sep 11 00:25:40.188557 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Sep 11 00:25:40.191297 systemd-tmpfiles[1634]: Detected autofs mount point /boot during canonicalization of boot. Sep 11 00:25:40.191314 systemd-tmpfiles[1634]: Skipping /boot Sep 11 00:25:40.355627 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Sep 11 00:25:40.360936 systemd[1]: Reloading finished in 339 ms. Sep 11 00:25:40.421540 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 11 00:25:40.474701 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 11 00:25:40.476008 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 11 00:25:40.500203 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 11 00:25:40.505073 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 11 00:25:40.506282 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 11 00:25:40.512111 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 11 00:25:40.517201 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 11 00:25:40.523067 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 11 00:25:40.527636 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 11 00:25:40.528854 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 11 00:25:40.533873 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 11 00:25:40.536994 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 11 00:25:40.544019 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 11 00:25:40.548350 systemd[1]: Reached target time-set.target - System Time Set. Sep 11 00:25:40.553503 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 11 00:25:40.559704 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 11 00:25:40.564895 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 11 00:25:40.569247 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 11 00:25:40.572629 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 11 00:25:40.572834 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 11 00:25:40.578196 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 11 00:25:40.578398 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 11 00:25:40.584981 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 11 00:25:40.585175 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 11 00:25:40.590627 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 11 00:25:40.590818 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 11 00:25:40.598182 systemd[1]: Finished ensure-sysext.service. Sep 11 00:25:40.604854 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 11 00:25:40.604912 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 11 00:25:40.625190 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 11 00:25:40.634925 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 11 00:25:40.666724 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 11 00:25:40.770311 systemd-resolved[1801]: Positive Trust Anchors: Sep 11 00:25:40.770326 systemd-resolved[1801]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 11 00:25:40.770369 systemd-resolved[1801]: 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 11 00:25:40.824716 systemd-resolved[1801]: Using system hostname 'ci-4372.1.0-n-d13e05d80d'. Sep 11 00:25:40.826228 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 11 00:25:40.827836 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 11 00:25:40.851131 systemd-networkd[1589]: lo: Link UP Sep 11 00:25:40.851141 systemd-networkd[1589]: lo: Gained carrier Sep 11 00:25:40.852616 systemd-networkd[1589]: Enumeration completed Sep 11 00:25:40.852718 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 11 00:25:40.853039 systemd-networkd[1589]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 11 00:25:40.853050 systemd-networkd[1589]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 11 00:25:40.853677 systemd-networkd[1589]: eth0: Link UP Sep 11 00:25:40.853688 systemd-networkd[1589]: eth0: Gained carrier Sep 11 00:25:40.853704 systemd-networkd[1589]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 11 00:25:40.854267 systemd[1]: Reached target network.target - Network. Sep 11 00:25:40.856271 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Sep 11 00:25:40.857375 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 11 00:25:40.862524 augenrules[1843]: No rules Sep 11 00:25:40.863748 systemd[1]: audit-rules.service: Deactivated successfully. Sep 11 00:25:40.890563 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 11 00:25:40.920828 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 11 00:25:40.937384 systemd-networkd[1589]: eth0: DHCPv4 address 10.200.8.36/24, gateway 10.200.8.1 acquired from 168.63.129.16 Sep 11 00:25:40.945247 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Sep 11 00:25:41.992306 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 11 00:25:42.313478 systemd-networkd[1589]: eth0: Gained IPv6LL Sep 11 00:25:42.315850 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 11 00:25:42.321428 systemd[1]: Reached target network-online.target - Network is Online. Sep 11 00:25:42.858799 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 11 00:25:42.864106 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 11 00:25:47.583673 ldconfig[1533]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 11 00:25:47.596807 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 11 00:25:47.603878 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 11 00:25:47.643088 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 11 00:25:47.648219 systemd[1]: Reached target sysinit.target - System Initialization. Sep 11 00:25:47.653186 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 11 00:25:47.658735 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 11 00:25:47.665167 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Sep 11 00:25:47.670437 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 11 00:25:47.675400 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 11 00:25:47.704822 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 11 00:25:47.744561 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 11 00:25:47.744600 systemd[1]: Reached target paths.target - Path Units. Sep 11 00:25:47.748805 systemd[1]: Reached target timers.target - Timer Units. Sep 11 00:25:47.753735 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 11 00:25:47.759871 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 11 00:25:47.767028 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Sep 11 00:25:47.772448 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Sep 11 00:25:47.777721 systemd[1]: Reached target ssh-access.target - SSH Access Available. Sep 11 00:25:47.787992 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 11 00:25:47.792571 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Sep 11 00:25:47.798047 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 11 00:25:47.803218 systemd[1]: Reached target sockets.target - Socket Units. Sep 11 00:25:47.807139 systemd[1]: Reached target basic.target - Basic System. Sep 11 00:25:47.810903 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 11 00:25:47.810933 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 11 00:25:47.825976 systemd[1]: Starting chronyd.service - NTP client/server... Sep 11 00:25:47.852058 systemd[1]: Starting containerd.service - containerd container runtime... Sep 11 00:25:47.857448 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Sep 11 00:25:47.862404 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 11 00:25:47.864855 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 11 00:25:47.868438 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 11 00:25:47.873297 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 11 00:25:47.878940 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 11 00:25:47.879983 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Sep 11 00:25:47.884553 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 11 00:25:47.885672 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Sep 11 00:25:47.890210 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Sep 11 00:25:47.891291 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 11 00:25:47.892767 jq[1866]: false Sep 11 00:25:47.896220 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 11 00:25:47.901491 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 11 00:25:47.908650 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 11 00:25:47.911983 (chronyd)[1861]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Sep 11 00:25:47.916048 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 11 00:25:47.921400 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 11 00:25:47.921827 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 11 00:25:47.925033 systemd[1]: Starting update-engine.service - Update Engine... Sep 11 00:25:47.928926 google_oslogin_nss_cache[1871]: oslogin_cache_refresh[1871]: Refreshing passwd entry cache Sep 11 00:25:47.928942 oslogin_cache_refresh[1871]: Refreshing passwd entry cache Sep 11 00:25:47.929622 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 11 00:25:47.937642 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 11 00:25:47.938693 jq[1889]: true Sep 11 00:25:47.938978 KVP[1872]: KVP starting; pid is:1872 Sep 11 00:25:47.940693 chronyd[1892]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) Sep 11 00:25:47.943473 KVP[1872]: KVP LIC Version: 3.1 Sep 11 00:25:47.944349 kernel: hv_utils: KVP IC version 4.0 Sep 11 00:25:47.945069 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 11 00:25:47.945289 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 11 00:25:47.945600 systemd[1]: motdgen.service: Deactivated successfully. Sep 11 00:25:47.945812 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 11 00:25:47.947087 google_oslogin_nss_cache[1871]: oslogin_cache_refresh[1871]: Failure getting users, quitting Sep 11 00:25:47.947081 oslogin_cache_refresh[1871]: Failure getting users, quitting Sep 11 00:25:47.947177 google_oslogin_nss_cache[1871]: oslogin_cache_refresh[1871]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Sep 11 00:25:47.947177 google_oslogin_nss_cache[1871]: oslogin_cache_refresh[1871]: Refreshing group entry cache Sep 11 00:25:47.947099 oslogin_cache_refresh[1871]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Sep 11 00:25:47.947137 oslogin_cache_refresh[1871]: Refreshing group entry cache Sep 11 00:25:47.951000 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 11 00:25:47.951212 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 11 00:25:47.961786 google_oslogin_nss_cache[1871]: oslogin_cache_refresh[1871]: Failure getting groups, quitting Sep 11 00:25:47.961786 google_oslogin_nss_cache[1871]: oslogin_cache_refresh[1871]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Sep 11 00:25:47.961774 oslogin_cache_refresh[1871]: Failure getting groups, quitting Sep 11 00:25:47.961787 oslogin_cache_refresh[1871]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Sep 11 00:25:47.966694 (ntainerd)[1895]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 11 00:25:47.966773 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Sep 11 00:25:47.966998 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Sep 11 00:25:47.969540 jq[1894]: true Sep 11 00:25:47.971974 extend-filesystems[1870]: Found /dev/sda6 Sep 11 00:25:47.986348 update_engine[1888]: I20250911 00:25:47.986172 1888 main.cc:92] Flatcar Update Engine starting Sep 11 00:25:48.016424 systemd-logind[1883]: New seat seat0. Sep 11 00:25:48.018307 systemd-logind[1883]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Sep 11 00:25:48.018509 systemd[1]: Started systemd-logind.service - User Login Management. Sep 11 00:25:48.021419 extend-filesystems[1870]: Found /dev/sda9 Sep 11 00:25:48.025597 extend-filesystems[1870]: Checking size of /dev/sda9 Sep 11 00:25:48.025411 systemd[1]: Started chronyd.service - NTP client/server. Sep 11 00:25:48.023883 chronyd[1892]: Timezone right/UTC failed leap second check, ignoring Sep 11 00:25:48.024064 chronyd[1892]: Loaded seccomp filter (level 2) Sep 11 00:25:48.073608 bash[1925]: Updated "/home/core/.ssh/authorized_keys" Sep 11 00:25:48.075064 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 11 00:25:48.081029 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Sep 11 00:25:48.092893 extend-filesystems[1870]: Old size kept for /dev/sda9 Sep 11 00:25:48.095671 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 11 00:25:48.095910 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 11 00:25:48.161739 setup-nvidia[1874]: Downloading Flatcar Container Linux Developer Container for version: 4372.1.0+nightly-20250910-2100 Sep 11 00:25:48.241255 sshd_keygen[1924]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 11 00:25:48.262861 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 11 00:25:48.269081 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 11 00:25:48.286162 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Sep 11 00:25:48.291377 systemd[1]: issuegen.service: Deactivated successfully. Sep 11 00:25:48.291598 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 11 00:25:48.298290 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 11 00:25:48.308803 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Sep 11 00:25:48.323643 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 11 00:25:48.326745 dbus-daemon[1864]: [system] SELinux support is enabled Sep 11 00:25:48.328607 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 11 00:25:48.332663 update_engine[1888]: I20250911 00:25:48.329633 1888 update_check_scheduler.cc:74] Next update check in 4m2s Sep 11 00:25:48.339552 dbus-daemon[1864]: [system] Successfully activated service 'org.freedesktop.systemd1' Sep 11 00:25:48.339874 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 11 00:25:48.344801 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Sep 11 00:25:48.349394 systemd[1]: Reached target getty.target - Login Prompts. Sep 11 00:25:48.351719 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 11 00:25:48.351748 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 11 00:25:48.358399 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 11 00:25:48.358424 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 11 00:25:48.363618 systemd[1]: Started update-engine.service - Update Engine. Sep 11 00:25:48.369321 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 11 00:25:48.408360 coreos-metadata[1863]: Sep 11 00:25:48.408 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Sep 11 00:25:48.410805 coreos-metadata[1863]: Sep 11 00:25:48.410 INFO Fetch successful Sep 11 00:25:48.410946 coreos-metadata[1863]: Sep 11 00:25:48.410 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Sep 11 00:25:48.415221 coreos-metadata[1863]: Sep 11 00:25:48.415 INFO Fetch successful Sep 11 00:25:48.415469 coreos-metadata[1863]: Sep 11 00:25:48.415 INFO Fetching http://168.63.129.16/machine/50b99840-57ed-4db3-a760-9a71b5cddcbd/43838ea3%2D3d15%2D4bf8%2Da630%2D836fc4fb6ea5.%5Fci%2D4372.1.0%2Dn%2Dd13e05d80d?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Sep 11 00:25:48.417193 coreos-metadata[1863]: Sep 11 00:25:48.417 INFO Fetch successful Sep 11 00:25:48.417338 coreos-metadata[1863]: Sep 11 00:25:48.417 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Sep 11 00:25:48.424838 coreos-metadata[1863]: Sep 11 00:25:48.424 INFO Fetch successful Sep 11 00:25:48.455718 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Sep 11 00:25:48.460799 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 11 00:25:48.565345 locksmithd[2026]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 11 00:25:48.947972 setup-nvidia[2041]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 00:25:48.948296 setup-nvidia[2041]: Dload Upload Total Spent Left Speed Sep 11 00:25:49.410027 containerd[1895]: time="2025-09-11T00:25:49Z" 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 11 00:25:49.410675 containerd[1895]: time="2025-09-11T00:25:49.410643700Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 Sep 11 00:25:49.418119 containerd[1895]: time="2025-09-11T00:25:49.418082100Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="7.9µs" Sep 11 00:25:49.418119 containerd[1895]: time="2025-09-11T00:25:49.418109800Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Sep 11 00:25:49.418205 containerd[1895]: time="2025-09-11T00:25:49.418126900Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Sep 11 00:25:49.418288 containerd[1895]: time="2025-09-11T00:25:49.418265000Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Sep 11 00:25:49.418317 containerd[1895]: time="2025-09-11T00:25:49.418287300Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Sep 11 00:25:49.418350 containerd[1895]: time="2025-09-11T00:25:49.418315900Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 11 00:25:49.418412 containerd[1895]: time="2025-09-11T00:25:49.418388500Z" 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 11 00:25:49.418412 containerd[1895]: time="2025-09-11T00:25:49.418407500Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 11 00:25:49.418622 containerd[1895]: time="2025-09-11T00:25:49.418598300Z" 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 11 00:25:49.418622 containerd[1895]: time="2025-09-11T00:25:49.418618500Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 11 00:25:49.418673 containerd[1895]: time="2025-09-11T00:25:49.418629700Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 11 00:25:49.418673 containerd[1895]: time="2025-09-11T00:25:49.418637500Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Sep 11 00:25:49.418722 containerd[1895]: time="2025-09-11T00:25:49.418712500Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Sep 11 00:25:49.418930 containerd[1895]: time="2025-09-11T00:25:49.418908200Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 11 00:25:49.418958 containerd[1895]: time="2025-09-11T00:25:49.418942500Z" 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 11 00:25:49.418958 containerd[1895]: time="2025-09-11T00:25:49.418953600Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Sep 11 00:25:49.419007 containerd[1895]: time="2025-09-11T00:25:49.418995900Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Sep 11 00:25:49.419788 containerd[1895]: time="2025-09-11T00:25:49.419751600Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Sep 11 00:25:49.419869 containerd[1895]: time="2025-09-11T00:25:49.419846600Z" level=info msg="metadata content store policy set" policy=shared Sep 11 00:25:49.438662 containerd[1895]: time="2025-09-11T00:25:49.438634400Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Sep 11 00:25:49.438733 containerd[1895]: time="2025-09-11T00:25:49.438689600Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Sep 11 00:25:49.438733 containerd[1895]: time="2025-09-11T00:25:49.438709500Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Sep 11 00:25:49.438733 containerd[1895]: time="2025-09-11T00:25:49.438722300Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Sep 11 00:25:49.438815 containerd[1895]: time="2025-09-11T00:25:49.438734000Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Sep 11 00:25:49.438815 containerd[1895]: time="2025-09-11T00:25:49.438744800Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Sep 11 00:25:49.438815 containerd[1895]: time="2025-09-11T00:25:49.438756300Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Sep 11 00:25:49.438815 containerd[1895]: time="2025-09-11T00:25:49.438767300Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Sep 11 00:25:49.438815 containerd[1895]: time="2025-09-11T00:25:49.438778300Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Sep 11 00:25:49.438815 containerd[1895]: time="2025-09-11T00:25:49.438787900Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Sep 11 00:25:49.438815 containerd[1895]: time="2025-09-11T00:25:49.438796600Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Sep 11 00:25:49.438815 containerd[1895]: time="2025-09-11T00:25:49.438807600Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Sep 11 00:25:49.438974 containerd[1895]: time="2025-09-11T00:25:49.438913800Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Sep 11 00:25:49.438974 containerd[1895]: time="2025-09-11T00:25:49.438934900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Sep 11 00:25:49.438974 containerd[1895]: time="2025-09-11T00:25:49.438947900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Sep 11 00:25:49.438974 containerd[1895]: time="2025-09-11T00:25:49.438958300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Sep 11 00:25:49.438974 containerd[1895]: time="2025-09-11T00:25:49.438967200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Sep 11 00:25:49.439077 containerd[1895]: time="2025-09-11T00:25:49.438975800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Sep 11 00:25:49.439077 containerd[1895]: time="2025-09-11T00:25:49.438986300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Sep 11 00:25:49.439077 containerd[1895]: time="2025-09-11T00:25:49.439000600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Sep 11 00:25:49.439077 containerd[1895]: time="2025-09-11T00:25:49.439012700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Sep 11 00:25:49.439077 containerd[1895]: time="2025-09-11T00:25:49.439022000Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Sep 11 00:25:49.439077 containerd[1895]: time="2025-09-11T00:25:49.439030900Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Sep 11 00:25:49.439208 containerd[1895]: time="2025-09-11T00:25:49.439083300Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Sep 11 00:25:49.439208 containerd[1895]: time="2025-09-11T00:25:49.439097700Z" level=info msg="Start snapshots syncer" Sep 11 00:25:49.439208 containerd[1895]: time="2025-09-11T00:25:49.439122300Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Sep 11 00:25:49.439386 containerd[1895]: time="2025-09-11T00:25:49.439324200Z" 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 11 00:25:49.439494 containerd[1895]: time="2025-09-11T00:25:49.439407300Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Sep 11 00:25:49.439494 containerd[1895]: time="2025-09-11T00:25:49.439480300Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Sep 11 00:25:49.439600 containerd[1895]: time="2025-09-11T00:25:49.439577700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Sep 11 00:25:49.439630 containerd[1895]: time="2025-09-11T00:25:49.439605900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Sep 11 00:25:49.439630 containerd[1895]: time="2025-09-11T00:25:49.439617400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Sep 11 00:25:49.439630 containerd[1895]: time="2025-09-11T00:25:49.439627400Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Sep 11 00:25:49.439693 containerd[1895]: time="2025-09-11T00:25:49.439639500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Sep 11 00:25:49.439693 containerd[1895]: time="2025-09-11T00:25:49.439650000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Sep 11 00:25:49.439693 containerd[1895]: time="2025-09-11T00:25:49.439659900Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Sep 11 00:25:49.439693 containerd[1895]: time="2025-09-11T00:25:49.439680200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Sep 11 00:25:49.439693 containerd[1895]: time="2025-09-11T00:25:49.439692500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Sep 11 00:25:49.439838 containerd[1895]: time="2025-09-11T00:25:49.439702600Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Sep 11 00:25:49.439838 containerd[1895]: time="2025-09-11T00:25:49.439727700Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 11 00:25:49.439838 containerd[1895]: time="2025-09-11T00:25:49.439741100Z" 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 11 00:25:49.439838 containerd[1895]: time="2025-09-11T00:25:49.439750100Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 11 00:25:49.439838 containerd[1895]: time="2025-09-11T00:25:49.439759800Z" 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 11 00:25:49.439838 containerd[1895]: time="2025-09-11T00:25:49.439767900Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Sep 11 00:25:49.439838 containerd[1895]: time="2025-09-11T00:25:49.439777300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Sep 11 00:25:49.439838 containerd[1895]: time="2025-09-11T00:25:49.439791400Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Sep 11 00:25:49.439838 containerd[1895]: time="2025-09-11T00:25:49.439806400Z" level=info msg="runtime interface created" Sep 11 00:25:49.439838 containerd[1895]: time="2025-09-11T00:25:49.439813100Z" level=info msg="created NRI interface" Sep 11 00:25:49.439838 containerd[1895]: time="2025-09-11T00:25:49.439821500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Sep 11 00:25:49.439838 containerd[1895]: time="2025-09-11T00:25:49.439834200Z" level=info msg="Connect containerd service" Sep 11 00:25:49.440079 containerd[1895]: time="2025-09-11T00:25:49.439856700Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 11 00:25:49.440549 containerd[1895]: time="2025-09-11T00:25:49.440520500Z" 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 11 00:25:50.528601 containerd[1895]: time="2025-09-11T00:25:50.528551800Z" level=info msg="Start subscribing containerd event" Sep 11 00:25:50.528974 containerd[1895]: time="2025-09-11T00:25:50.528612300Z" level=info msg="Start recovering state" Sep 11 00:25:50.528974 containerd[1895]: time="2025-09-11T00:25:50.528715600Z" level=info msg="Start event monitor" Sep 11 00:25:50.528974 containerd[1895]: time="2025-09-11T00:25:50.528731500Z" level=info msg="Start cni network conf syncer for default" Sep 11 00:25:50.528974 containerd[1895]: time="2025-09-11T00:25:50.528738800Z" level=info msg="Start streaming server" Sep 11 00:25:50.528974 containerd[1895]: time="2025-09-11T00:25:50.528767300Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Sep 11 00:25:50.528974 containerd[1895]: time="2025-09-11T00:25:50.528778800Z" level=info msg="runtime interface starting up..." Sep 11 00:25:50.528974 containerd[1895]: time="2025-09-11T00:25:50.528784900Z" level=info msg="starting plugins..." Sep 11 00:25:50.528974 containerd[1895]: time="2025-09-11T00:25:50.528799400Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Sep 11 00:25:50.528974 containerd[1895]: time="2025-09-11T00:25:50.528772500Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 11 00:25:50.528974 containerd[1895]: time="2025-09-11T00:25:50.528935600Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 11 00:25:50.529193 containerd[1895]: time="2025-09-11T00:25:50.529014700Z" level=info msg="containerd successfully booted in 1.119468s" Sep 11 00:25:50.529114 systemd[1]: Started containerd.service - containerd container runtime. Sep 11 00:25:51.158351 waagent[2020]: 2025-09-11T00:25:51.158271Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Sep 11 00:25:51.163470 waagent[2020]: 2025-09-11T00:25:51.163405Z INFO Daemon Daemon OS: flatcar 4372.1.0 Sep 11 00:25:51.166829 waagent[2020]: 2025-09-11T00:25:51.166783Z INFO Daemon Daemon Python: 3.11.12 Sep 11 00:25:51.170208 waagent[2020]: 2025-09-11T00:25:51.170158Z INFO Daemon Daemon Run daemon Sep 11 00:25:51.173265 waagent[2020]: 2025-09-11T00:25:51.173223Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4372.1.0' Sep 11 00:25:51.179895 waagent[2020]: 2025-09-11T00:25:51.179848Z INFO Daemon Daemon Using waagent for provisioning Sep 11 00:25:51.183844 waagent[2020]: 2025-09-11T00:25:51.183803Z INFO Daemon Daemon Activate resource disk Sep 11 00:25:51.187509 waagent[2020]: 2025-09-11T00:25:51.187460Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Sep 11 00:25:51.194914 waagent[2020]: 2025-09-11T00:25:51.194872Z INFO Daemon Daemon Found device: sdb Sep 11 00:25:51.204016 waagent[2020]: 2025-09-11T00:25:51.203967Z INFO Daemon Daemon Examining partition table Sep 11 00:25:51.268889 waagent[2020]: 2025-09-11T00:25:51.268805Z INFO Daemon Daemon GPT not detected, determining filesystem Sep 11 00:25:51.321904 waagent[2020]: 2025-09-11T00:25:51.321838Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Sep 11 00:25:51.326614 waagent[2020]: 2025-09-11T00:25:51.326561Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Sep 11 00:25:51.873221 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#107 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 11 00:25:51.888367 kernel: sdb: sdb1 Sep 11 00:25:51.913054 waagent[2020]: 2025-09-11T00:25:51.912976Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Sep 11 00:25:51.931357 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#394 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 11 00:25:51.944346 kernel: sdb: sdb1 Sep 11 00:25:51.944388 waagent[2020]: 2025-09-11T00:25:51.944322Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Sep 11 00:25:56.616989 login[2025]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Sep 11 00:25:56.718707 login[2024]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Sep 11 00:25:56.725455 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 11 00:25:56.726597 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 11 00:25:56.733166 systemd-logind[1883]: New session 2 of user core. Sep 11 00:25:56.827598 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 11 00:25:56.830260 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 11 00:25:56.960380 (systemd)[2129]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 11 00:25:56.962977 systemd-logind[1883]: New session c1 of user core. Sep 11 00:25:57.609479 waagent[2020]: 2025-09-11T00:25:57.609392Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Sep 11 00:25:57.618790 login[2025]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Sep 11 00:25:57.623241 systemd-logind[1883]: New session 1 of user core. Sep 11 00:25:57.716984 waagent[2020]: 2025-09-11T00:25:57.716899Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Sep 11 00:25:57.717354 kernel: EXT4-fs (sdb1): mounted filesystem 72398f70-d55e-4e6d-8b42-c585dd39bd26 r/w with ordered data mode. Quota mode: none. Sep 11 00:25:57.719159 waagent[2020]: 2025-09-11T00:25:57.719115Z INFO Daemon Daemon Clean protocol and wireserver endpoint Sep 11 00:25:57.720192 waagent[2020]: 2025-09-11T00:25:57.720151Z INFO Daemon Daemon Running default provisioning handler Sep 11 00:25:57.728288 waagent[2020]: 2025-09-11T00:25:57.728244Z 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 11 00:25:57.729318 waagent[2020]: 2025-09-11T00:25:57.729275Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Sep 11 00:25:57.729705 waagent[2020]: 2025-09-11T00:25:57.729667Z INFO Daemon Daemon cloud-init is enabled: False Sep 11 00:25:57.730426 waagent[2020]: 2025-09-11T00:25:57.730393Z INFO Daemon Daemon Copying ovf-env.xml Sep 11 00:25:57.830165 systemd[2129]: Queued start job for default target default.target. Sep 11 00:25:57.840088 waagent[2020]: 2025-09-11T00:25:57.840016Z INFO Daemon Daemon Successfully mounted dvd Sep 11 00:25:57.848812 systemd[2129]: Created slice app.slice - User Application Slice. Sep 11 00:25:57.848842 systemd[2129]: Reached target paths.target - Paths. Sep 11 00:25:57.848889 systemd[2129]: Reached target timers.target - Timers. Sep 11 00:25:57.850106 systemd[2129]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 11 00:25:57.861320 systemd[2129]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 11 00:25:57.861529 systemd[2129]: Reached target sockets.target - Sockets. Sep 11 00:25:57.861587 systemd[2129]: Reached target basic.target - Basic System. Sep 11 00:25:57.861631 systemd[2129]: Reached target default.target - Main User Target. Sep 11 00:25:57.861664 systemd[2129]: Startup finished in 892ms. Sep 11 00:25:57.861802 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 11 00:25:57.863480 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 11 00:25:57.864216 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 11 00:25:57.865419 waagent[2020]: 2025-09-11T00:25:57.865361Z INFO Daemon Daemon Detect protocol endpoint Sep 11 00:25:57.866327 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Sep 11 00:25:57.869311 waagent[2020]: 2025-09-11T00:25:57.869259Z INFO Daemon Daemon Clean protocol and wireserver endpoint Sep 11 00:25:57.878842 waagent[2020]: 2025-09-11T00:25:57.878799Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Sep 11 00:25:57.884010 waagent[2020]: 2025-09-11T00:25:57.883970Z INFO Daemon Daemon Test for route to 168.63.129.16 Sep 11 00:25:57.888242 waagent[2020]: 2025-09-11T00:25:57.888204Z INFO Daemon Daemon Route to 168.63.129.16 exists Sep 11 00:25:57.892171 waagent[2020]: 2025-09-11T00:25:57.892133Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Sep 11 00:25:57.921096 waagent[2020]: 2025-09-11T00:25:57.921051Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Sep 11 00:25:57.922818 waagent[2020]: 2025-09-11T00:25:57.922788Z INFO Daemon Daemon Wire protocol version:2012-11-30 Sep 11 00:25:57.924106 waagent[2020]: 2025-09-11T00:25:57.924073Z INFO Daemon Daemon Server preferred version:2015-04-05 Sep 11 00:25:58.005267 waagent[2020]: 2025-09-11T00:25:58.005179Z INFO Daemon Daemon Initializing goal state during protocol detection Sep 11 00:25:58.007043 waagent[2020]: 2025-09-11T00:25:58.006999Z INFO Daemon Daemon Forcing an update of the goal state. Sep 11 00:25:58.011818 waagent[2020]: 2025-09-11T00:25:58.011770Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Sep 11 00:25:58.030344 waagent[2020]: 2025-09-11T00:25:58.030285Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.175 Sep 11 00:25:58.032430 waagent[2020]: 2025-09-11T00:25:58.032389Z INFO Daemon Sep 11 00:25:58.032738 waagent[2020]: 2025-09-11T00:25:58.032705Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: f4175f7f-b910-44ab-bdf8-c217d0c0cdbb eTag: 14463419529055379368 source: Fabric] Sep 11 00:25:58.034490 waagent[2020]: 2025-09-11T00:25:58.034450Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Sep 11 00:25:58.035206 waagent[2020]: 2025-09-11T00:25:58.035170Z INFO Daemon Sep 11 00:25:58.036487 waagent[2020]: 2025-09-11T00:25:58.036446Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Sep 11 00:25:58.040721 waagent[2020]: 2025-09-11T00:25:58.040687Z INFO Daemon Daemon Downloading artifacts profile blob Sep 11 00:25:58.183507 waagent[2020]: 2025-09-11T00:25:58.183393Z INFO Daemon Downloaded certificate {'thumbprint': '76624C6551543C714A1E7EE03184ABC0B57FD831', 'hasPrivateKey': True} Sep 11 00:25:58.185738 waagent[2020]: 2025-09-11T00:25:58.185690Z INFO Daemon Fetch goal state completed Sep 11 00:25:58.230031 waagent[2020]: 2025-09-11T00:25:58.229981Z INFO Daemon Daemon Starting provisioning Sep 11 00:25:58.232676 waagent[2020]: 2025-09-11T00:25:58.232616Z INFO Daemon Daemon Handle ovf-env.xml. Sep 11 00:25:58.235381 waagent[2020]: 2025-09-11T00:25:58.235308Z INFO Daemon Daemon Set hostname [ci-4372.1.0-n-d13e05d80d] Sep 11 00:25:58.253584 waagent[2020]: 2025-09-11T00:25:58.253533Z INFO Daemon Daemon Publish hostname [ci-4372.1.0-n-d13e05d80d] Sep 11 00:25:58.255508 waagent[2020]: 2025-09-11T00:25:58.255459Z INFO Daemon Daemon Examine /proc/net/route for primary interface Sep 11 00:25:58.256200 waagent[2020]: 2025-09-11T00:25:58.256162Z INFO Daemon Daemon Primary interface is [eth0] Sep 11 00:25:58.265840 systemd-networkd[1589]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 11 00:25:58.265852 systemd-networkd[1589]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 11 00:25:58.265880 systemd-networkd[1589]: eth0: DHCP lease lost Sep 11 00:25:58.266274 waagent[2020]: 2025-09-11T00:25:58.266226Z INFO Daemon Daemon Create user account if not exists Sep 11 00:25:58.268031 waagent[2020]: 2025-09-11T00:25:58.267989Z INFO Daemon Daemon User core already exists, skip useradd Sep 11 00:25:58.269380 waagent[2020]: 2025-09-11T00:25:58.269326Z INFO Daemon Daemon Configure sudoer Sep 11 00:25:58.282452 waagent[2020]: 2025-09-11T00:25:58.282403Z INFO Daemon Daemon Configure sshd Sep 11 00:25:58.289625 waagent[2020]: 2025-09-11T00:25:58.289578Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Sep 11 00:25:58.291233 waagent[2020]: 2025-09-11T00:25:58.291183Z INFO Daemon Daemon Deploy ssh public key. Sep 11 00:25:58.358399 systemd-networkd[1589]: eth0: DHCPv4 address 10.200.8.36/24, gateway 10.200.8.1 acquired from 168.63.129.16 Sep 11 00:25:59.455712 waagent[2020]: 2025-09-11T00:25:59.455652Z INFO Daemon Daemon Provisioning complete Sep 11 00:25:59.467139 waagent[2020]: 2025-09-11T00:25:59.467094Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Sep 11 00:25:59.469116 waagent[2020]: 2025-09-11T00:25:59.469053Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Sep 11 00:25:59.470568 waagent[2020]: 2025-09-11T00:25:59.470520Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Sep 11 00:25:59.623573 waagent[2188]: 2025-09-11T00:25:59.623491Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Sep 11 00:25:59.623895 waagent[2188]: 2025-09-11T00:25:59.623623Z INFO ExtHandler ExtHandler OS: flatcar 4372.1.0 Sep 11 00:25:59.623895 waagent[2188]: 2025-09-11T00:25:59.623690Z INFO ExtHandler ExtHandler Python: 3.11.12 Sep 11 00:25:59.623895 waagent[2188]: 2025-09-11T00:25:59.623755Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Sep 11 00:25:59.776218 waagent[2188]: 2025-09-11T00:25:59.776151Z INFO ExtHandler ExtHandler Distro: flatcar-4372.1.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.12; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Sep 11 00:25:59.776461 waagent[2188]: 2025-09-11T00:25:59.776416Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 11 00:25:59.776537 waagent[2188]: 2025-09-11T00:25:59.776504Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 11 00:25:59.783270 waagent[2188]: 2025-09-11T00:25:59.783221Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Sep 11 00:25:59.788646 waagent[2188]: 2025-09-11T00:25:59.788568Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.175 Sep 11 00:25:59.789136 waagent[2188]: 2025-09-11T00:25:59.789096Z INFO ExtHandler Sep 11 00:25:59.789212 waagent[2188]: 2025-09-11T00:25:59.789181Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: c91279ba-9dd4-49eb-874c-1a227380c735 eTag: 14463419529055379368 source: Fabric] Sep 11 00:25:59.789647 waagent[2188]: 2025-09-11T00:25:59.789602Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Sep 11 00:25:59.790290 waagent[2188]: 2025-09-11T00:25:59.790250Z INFO ExtHandler Sep 11 00:25:59.790474 waagent[2188]: 2025-09-11T00:25:59.790437Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Sep 11 00:25:59.793968 waagent[2188]: 2025-09-11T00:25:59.793934Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Sep 11 00:25:59.853088 waagent[2188]: 2025-09-11T00:25:59.853031Z INFO ExtHandler Downloaded certificate {'thumbprint': '76624C6551543C714A1E7EE03184ABC0B57FD831', 'hasPrivateKey': True} Sep 11 00:25:59.853560 waagent[2188]: 2025-09-11T00:25:59.853518Z INFO ExtHandler Fetch goal state completed Sep 11 00:25:59.864708 waagent[2188]: 2025-09-11T00:25:59.864661Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.3.3 11 Feb 2025 (Library: OpenSSL 3.3.3 11 Feb 2025) Sep 11 00:25:59.869637 waagent[2188]: 2025-09-11T00:25:59.869596Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2188 Sep 11 00:25:59.869774 waagent[2188]: 2025-09-11T00:25:59.869740Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Sep 11 00:25:59.870150 waagent[2188]: 2025-09-11T00:25:59.870107Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Sep 11 00:25:59.872285 waagent[2188]: 2025-09-11T00:25:59.872240Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4372.1.0', '', 'Flatcar Container Linux by Kinvolk'] Sep 11 00:25:59.872836 waagent[2188]: 2025-09-11T00:25:59.872789Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4372.1.0', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Sep 11 00:25:59.872987 waagent[2188]: 2025-09-11T00:25:59.872951Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Sep 11 00:25:59.873598 waagent[2188]: 2025-09-11T00:25:59.873560Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Sep 11 00:26:00.065623 waagent[2188]: 2025-09-11T00:26:00.065529Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Sep 11 00:26:00.065783 waagent[2188]: 2025-09-11T00:26:00.065739Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Sep 11 00:26:00.072806 waagent[2188]: 2025-09-11T00:26:00.072774Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Sep 11 00:26:00.080040 systemd[1]: Reload requested from client PID 2203 ('systemctl') (unit waagent.service)... Sep 11 00:26:00.080057 systemd[1]: Reloading... Sep 11 00:26:00.147396 zram_generator::config[2243]: No configuration found. Sep 11 00:26:00.264670 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Sep 11 00:26:00.427761 systemd[1]: Reloading finished in 347 ms. Sep 11 00:26:00.452136 waagent[2188]: 2025-09-11T00:26:00.452066Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Sep 11 00:26:00.452248 waagent[2188]: 2025-09-11T00:26:00.452212Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Sep 11 00:26:01.160666 waagent[2188]: 2025-09-11T00:26:01.160584Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Sep 11 00:26:01.161045 waagent[2188]: 2025-09-11T00:26:01.160910Z 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 11 00:26:01.161716 waagent[2188]: 2025-09-11T00:26:01.161669Z INFO ExtHandler ExtHandler Starting env monitor service. Sep 11 00:26:01.161967 waagent[2188]: 2025-09-11T00:26:01.161919Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 11 00:26:01.162311 waagent[2188]: 2025-09-11T00:26:01.162266Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Sep 11 00:26:01.162386 waagent[2188]: 2025-09-11T00:26:01.162316Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 11 00:26:01.162770 waagent[2188]: 2025-09-11T00:26:01.162722Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Sep 11 00:26:01.163161 waagent[2188]: 2025-09-11T00:26:01.163118Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 11 00:26:01.163259 waagent[2188]: 2025-09-11T00:26:01.163202Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Sep 11 00:26:01.163327 waagent[2188]: 2025-09-11T00:26:01.163275Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Sep 11 00:26:01.163654 waagent[2188]: 2025-09-11T00:26:01.163606Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 11 00:26:01.164227 waagent[2188]: 2025-09-11T00:26:01.164179Z INFO EnvHandler ExtHandler Configure routes Sep 11 00:26:01.164367 waagent[2188]: 2025-09-11T00:26:01.164298Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Sep 11 00:26:01.164455 waagent[2188]: 2025-09-11T00:26:01.164411Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Sep 11 00:26:01.164622 waagent[2188]: 2025-09-11T00:26:01.164584Z INFO EnvHandler ExtHandler Gateway:None Sep 11 00:26:01.164809 waagent[2188]: 2025-09-11T00:26:01.164769Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Sep 11 00:26:01.164893 waagent[2188]: 2025-09-11T00:26:01.164847Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Sep 11 00:26:01.164893 waagent[2188]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Sep 11 00:26:01.164893 waagent[2188]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Sep 11 00:26:01.164893 waagent[2188]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Sep 11 00:26:01.164893 waagent[2188]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Sep 11 00:26:01.164893 waagent[2188]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Sep 11 00:26:01.164893 waagent[2188]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Sep 11 00:26:01.165307 waagent[2188]: 2025-09-11T00:26:01.165272Z INFO EnvHandler ExtHandler Routes:None Sep 11 00:26:01.171666 waagent[2188]: 2025-09-11T00:26:01.171618Z INFO ExtHandler ExtHandler Sep 11 00:26:01.172048 waagent[2188]: 2025-09-11T00:26:01.171998Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: d8490141-297b-4bf1-867f-7094e31a8367 correlation dc34c46f-937b-4e52-9e28-bcd8c85d7d4a created: 2025-09-11T00:24:15.595940Z] Sep 11 00:26:01.173463 waagent[2188]: 2025-09-11T00:26:01.173411Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Sep 11 00:26:01.175686 waagent[2188]: 2025-09-11T00:26:01.175639Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 4 ms] Sep 11 00:26:01.276305 waagent[2188]: 2025-09-11T00:26:01.276240Z 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 11 00:26:01.276305 waagent[2188]: Try `iptables -h' or 'iptables --help' for more information.) Sep 11 00:26:01.276749 waagent[2188]: 2025-09-11T00:26:01.276710Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: D7606DE8-5C5C-4B23-BFFF-65E0A7F59586;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Sep 11 00:26:01.417081 waagent[2188]: 2025-09-11T00:26:01.416960Z INFO MonitorHandler ExtHandler Network interfaces: Sep 11 00:26:01.417081 waagent[2188]: Executing ['ip', '-a', '-o', 'link']: Sep 11 00:26:01.417081 waagent[2188]: 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 11 00:26:01.417081 waagent[2188]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:1e:52:fb:56:fe brd ff:ff:ff:ff:ff:ff Sep 11 00:26:01.417081 waagent[2188]: Executing ['ip', '-4', '-a', '-o', 'address']: Sep 11 00:26:01.417081 waagent[2188]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Sep 11 00:26:01.417081 waagent[2188]: 2: eth0 inet 10.200.8.36/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Sep 11 00:26:01.417081 waagent[2188]: Executing ['ip', '-6', '-a', '-o', 'address']: Sep 11 00:26:01.417081 waagent[2188]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Sep 11 00:26:01.417081 waagent[2188]: 2: eth0 inet6 fe80::7e1e:52ff:fefb:56fe/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Sep 11 00:26:01.641734 waagent[2188]: 2025-09-11T00:26:01.641666Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Sep 11 00:26:01.641734 waagent[2188]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Sep 11 00:26:01.641734 waagent[2188]: pkts bytes target prot opt in out source destination Sep 11 00:26:01.641734 waagent[2188]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Sep 11 00:26:01.641734 waagent[2188]: pkts bytes target prot opt in out source destination Sep 11 00:26:01.641734 waagent[2188]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Sep 11 00:26:01.641734 waagent[2188]: pkts bytes target prot opt in out source destination Sep 11 00:26:01.641734 waagent[2188]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Sep 11 00:26:01.641734 waagent[2188]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Sep 11 00:26:01.641734 waagent[2188]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Sep 11 00:26:01.645050 waagent[2188]: 2025-09-11T00:26:01.645009Z INFO EnvHandler ExtHandler Current Firewall rules: Sep 11 00:26:01.645050 waagent[2188]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Sep 11 00:26:01.645050 waagent[2188]: pkts bytes target prot opt in out source destination Sep 11 00:26:01.645050 waagent[2188]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Sep 11 00:26:01.645050 waagent[2188]: pkts bytes target prot opt in out source destination Sep 11 00:26:01.645050 waagent[2188]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Sep 11 00:26:01.645050 waagent[2188]: pkts bytes target prot opt in out source destination Sep 11 00:26:01.645050 waagent[2188]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Sep 11 00:26:01.645050 waagent[2188]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Sep 11 00:26:01.645050 waagent[2188]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Sep 11 00:26:01.645433 waagent[2188]: 2025-09-11T00:26:01.645397Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Sep 11 00:26:01.645637 waagent[2188]: 2025-09-11T00:26:01.645603Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Sep 11 00:26:11.827877 chronyd[1892]: Selected source PHC0 Sep 11 00:26:12.058745 setup-nvidia[2041]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 544M 0 208k 0 0 207k 0 0:44:50 0:00:01 0:44:49 207k\u000d 3 544M 3 16.6M 0 0 8500k 0 0:01:05 0:00:02 0:01:03 8503k\u000d 7 544M 7 41.9M 0 0 13.4M 0 0:00:40 0:00:03 0:00:37 13.4M\u000d 12 544M 12 67.3M 0 0 16.4M 0 0:00:33 0:00:04 0:00:29 16.4M\u000d 16 544M 16 92.4M 0 0 18.1M 0 0:00:29 0:00:05 0:00:24 18.1M\u000d 21 544M 21 117M 0 0 19.3M 0 0:00:28 0:00:06 0:00:22 23.1M\u000d 26 544M 26 141M 0 0 20.0M 0 0:00:27 0:00:07 0:00:20 24.7M\u000d 30 544M 30 167M 0 0 20.4M 0 0:00:26 0:00:08 0:00:18 24.7M\u000d 34 544M 34 188M 0 0 20.9M 0 0:00:26 0:00:08 0:00:18 24.6M\u000d 39 544M 39 213M 0 0 21.3M 0 0:00:25 0:00:09 0:00:16 24.7M\u000d 44 544M 44 240M 0 0 21.6M 0 0:00:25 0:00:11 0:00:14 24.3M\u000d 48 544M 48 264M 0 0 21.8M 0 0:00:24 0:00:12 0:00:12 24.3M\u000d 53 544M 53 289M 0 0 22.1M 0 0:00:24 0:00:13 0:00:11 24.8M\u000d 57 544M 57 315M 0 0 22.3M 0 0:00:24 0:00:14 0:00:10 25.0M\u000d 62 544M 62 338M 0 0 22.4M 0 0:00:24 0:00:15 0:00:09 24.6M\u000d 66 544M 66 363M 0 0 22.6M 0 0:00:24 0:00:16 0:00:08 24.9M\u000d 71 544M 71 389M 0 0 22.8M 0 0:00:23 0:00:17 0:00:06 25.2M\u000d 76 544M 76 414M 0 0 22.9M 0 0:00:23 0:00:18 0:00:05 25.2M\u000d 80 544M 80 439M 0 0 23.1M 0 0:00:23 0:00:19 0:00:04 25.2M\u000d 85 544M 85 464M 0 0 23.2M 0 0:00:23 0:00:20 0:00:03 25.5M\u000d 90 544M 90 490M 0 0 23.3M 0 0:00:23 0:00:20 0:00:03 25.6M\u000d 95 544M 95 518M 0 0 23.4M 0 0:00:23 0:00:22 0:00:01 25.3M\u000d 99 544M 99 542M 0 0 23.4M 0 0:00:23 0:00:23 --:--:-- 25.2M\u000d100 544M 100 544M 0 0 23.5M 0 0:00:23 0:00:23 --:--:-- 25.4M Sep 11 00:26:28.121478 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Sep 11 00:26:28.366164 setup-nvidia[1874]: Downloading NVIDIA 535.230.02 Driver Sep 11 00:26:28.370495 setup-nvidia[2345]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 00:26:28.370743 setup-nvidia[2345]: Dload Upload Total Spent Left Speed Sep 11 00:26:30.906799 setup-nvidia[2345]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 326M 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 38 326M 38 124M 0 0 79.0M 0 0:00:04 0:00:01 0:00:03 79.0M\u000d100 326M 100 326M 0 0 128M 0 0:00:02 0:00:02 --:--:-- 128M Sep 11 00:26:30.953461 setup-nvidia[1874]: Extract the NVIDIA Driver Installer 535.230.02 Sep 11 00:26:30.953730 setup-nvidia[1874]: /opt/nvidia/workdir/nvidia-workdir / Sep 11 00:26:30.995940 setup-nvidia[2348]: Creating directory NVIDIA-Linux-x86_64-535.230.02 Sep 11 00:26:31.326939 setup-nvidia[2348]: Verifying archive integrity... OK Sep 11 00:26:32.237355 setup-nvidia[2348]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.230.02 Sep 11 00:26:32.488812 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 11 00:26:32.491669 systemd[1]: Started sshd@0-10.200.8.36:22-10.200.16.10:51532.service - OpenSSH per-connection server daemon (10.200.16.10:51532). Sep 11 00:26:33.407762 sshd[2379]: Accepted publickey for core from 10.200.16.10 port 51532 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:26:33.409211 sshd-session[2379]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:26:33.413756 systemd-logind[1883]: New session 3 of user core. Sep 11 00:26:33.423537 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 11 00:26:33.778413 update_engine[1888]: I20250911 00:26:33.778358 1888 update_attempter.cc:509] Updating boot flags... Sep 11 00:26:33.981967 systemd[1]: Started sshd@1-10.200.8.36:22-10.200.16.10:51548.service - OpenSSH per-connection server daemon (10.200.16.10:51548). Sep 11 00:26:34.097388 setup-nvidia[2374]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Sep 11 00:26:34.173392 setup-nvidia[1874]: / Sep 11 00:26:34.173626 setup-nvidia[1874]: Spawn system-nspawn container to install the NVIDIA drivers Sep 11 00:26:34.634832 sshd[2456]: Accepted publickey for core from 10.200.16.10 port 51548 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:26:34.636142 sshd-session[2456]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:26:34.640390 systemd-logind[1883]: New session 4 of user core. Sep 11 00:26:34.658459 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 11 00:26:34.791253 sudo[2505]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4372.1.0+nightly-20250910-2100.bin --overlay=/usr/lib/modules::/usr/lib/modules --bind=/opt/nvidia/workdir/nvidia-workdir:/nvidia --bind=/usr/lib/nvidia/bin:/app/bin/ /app/bin/install-nvidia NVIDIA-Linux-x86_64-535.230.02 Sep 11 00:26:34.792972 sudo[2505]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 11 00:26:34.798095 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Sep 11 00:26:34.800670 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Sep 11 00:26:34.825898 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Sep 11 00:26:34.829805 systemd[1]: Starting user@0.service - User Manager for UID 0... Sep 11 00:26:34.854248 (systemd)[2509]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Sep 11 00:26:34.856486 systemd-logind[1883]: New session c3 of user root. Sep 11 00:26:35.041467 systemd[2509]: Queued start job for default target default.target. Sep 11 00:26:35.063471 systemd[2509]: Created slice app.slice - User Application Slice. Sep 11 00:26:35.063501 systemd[2509]: Reached target paths.target - Paths. Sep 11 00:26:35.063548 systemd[2509]: Reached target timers.target - Timers. Sep 11 00:26:35.064713 systemd[2509]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 11 00:26:35.075640 systemd[2509]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 11 00:26:35.075757 systemd[2509]: Reached target sockets.target - Sockets. Sep 11 00:26:35.075813 systemd[2509]: Reached target basic.target - Basic System. Sep 11 00:26:35.075864 systemd[2509]: Reached target default.target - Main User Target. Sep 11 00:26:35.075894 systemd[2509]: Startup finished in 213ms. Sep 11 00:26:35.075969 systemd[1]: Started user@0.service - User Manager for UID 0. Sep 11 00:26:35.082196 systemd[1]: Started session-c2.scope - Session c2 of User root. Sep 11 00:26:35.086853 sshd[2506]: Connection closed by 10.200.16.10 port 51548 Sep 11 00:26:35.087315 sshd-session[2456]: pam_unix(sshd:session): session closed for user core Sep 11 00:26:35.091197 systemd[1]: sshd@1-10.200.8.36:22-10.200.16.10:51548.service: Deactivated successfully. Sep 11 00:26:35.092892 systemd[1]: session-4.scope: Deactivated successfully. Sep 11 00:26:35.093632 systemd-logind[1883]: Session 4 logged out. Waiting for processes to exit. Sep 11 00:26:35.094582 systemd-logind[1883]: Removed session 4. Sep 11 00:26:35.190354 kernel: loop6: detected capacity change from 0 to 12644352 Sep 11 00:26:35.199348 kernel: loop6: p9 Sep 11 00:26:35.203989 systemd[1]: Started sshd@2-10.200.8.36:22-10.200.16.10:51558.service - OpenSSH per-connection server daemon (10.200.16.10:51558). Sep 11 00:26:35.210078 setup-nvidia[2519]: ░ Spawning container flatcardevelopercontainer-4372.1.0nightly-20250910-2100.bin on /opt/nvidia/workdir/flatcar_developer_container-4372.1.0+nightly-20250910-2100.bin. Sep 11 00:26:35.760766 kernel: EXT4-fs (loop6p9): write access unavailable, skipping orphan cleanup Sep 11 00:26:35.760881 kernel: EXT4-fs (loop6p9): mounted filesystem f1e27df3-5e40-42fc-9547-7151e2f73697 ro without journal. Quota mode: none. Sep 11 00:26:35.842533 sshd[2524]: Accepted publickey for core from 10.200.16.10 port 51558 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:26:35.843783 sshd-session[2524]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:26:35.848056 systemd-logind[1883]: New session 5 of user core. Sep 11 00:26:35.859457 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 11 00:26:36.294875 sshd[2530]: Connection closed by 10.200.16.10 port 51558 Sep 11 00:26:36.295210 sshd-session[2524]: pam_unix(sshd:session): session closed for user core Sep 11 00:26:36.298605 systemd[1]: sshd@2-10.200.8.36:22-10.200.16.10:51558.service: Deactivated successfully. Sep 11 00:26:36.300220 systemd[1]: session-5.scope: Deactivated successfully. Sep 11 00:26:36.300902 systemd-logind[1883]: Session 5 logged out. Waiting for processes to exit. Sep 11 00:26:36.301890 systemd-logind[1883]: Removed session 5. Sep 11 00:26:46.417711 systemd[1]: Started sshd@3-10.200.8.36:22-10.200.16.10:39458.service - OpenSSH per-connection server daemon (10.200.16.10:39458). Sep 11 00:26:47.073129 sshd[2923]: Accepted publickey for core from 10.200.16.10 port 39458 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:26:47.074456 sshd-session[2923]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:26:47.081230 systemd-logind[1883]: New session 6 of user core. Sep 11 00:26:47.093477 systemd[1]: Started session-6.scope - Session 6 of User core. Sep 11 00:26:47.529036 sshd[3057]: Connection closed by 10.200.16.10 port 39458 Sep 11 00:26:47.529397 sshd-session[2923]: pam_unix(sshd:session): session closed for user core Sep 11 00:26:47.532862 systemd[1]: sshd@3-10.200.8.36:22-10.200.16.10:39458.service: Deactivated successfully. Sep 11 00:26:47.534594 systemd[1]: session-6.scope: Deactivated successfully. Sep 11 00:26:47.535315 systemd-logind[1883]: Session 6 logged out. Waiting for processes to exit. Sep 11 00:26:47.536258 systemd-logind[1883]: Removed session 6. Sep 11 00:26:47.652017 systemd[1]: Started sshd@4-10.200.8.36:22-10.200.16.10:39464.service - OpenSSH per-connection server daemon (10.200.16.10:39464). Sep 11 00:26:48.297805 sshd[3254]: Accepted publickey for core from 10.200.16.10 port 39464 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:26:48.299091 sshd-session[3254]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:26:48.303300 systemd-logind[1883]: New session 7 of user core. Sep 11 00:26:48.311476 systemd[1]: Started session-7.scope - Session 7 of User core. Sep 11 00:26:48.749847 sshd[3264]: Connection closed by 10.200.16.10 port 39464 Sep 11 00:26:48.750185 sshd-session[3254]: pam_unix(sshd:session): session closed for user core Sep 11 00:26:48.753480 systemd[1]: sshd@4-10.200.8.36:22-10.200.16.10:39464.service: Deactivated successfully. Sep 11 00:26:48.755097 systemd[1]: session-7.scope: Deactivated successfully. Sep 11 00:26:48.755860 systemd-logind[1883]: Session 7 logged out. Waiting for processes to exit. Sep 11 00:26:48.756836 systemd-logind[1883]: Removed session 7. Sep 11 00:26:58.867644 systemd[1]: Started sshd@5-10.200.8.36:22-10.200.16.10:40920.service - OpenSSH per-connection server daemon (10.200.16.10:40920). Sep 11 00:26:59.513298 sshd[3411]: Accepted publickey for core from 10.200.16.10 port 40920 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:26:59.514650 sshd-session[3411]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:26:59.518920 systemd-logind[1883]: New session 8 of user core. Sep 11 00:26:59.529469 systemd[1]: Started session-8.scope - Session 8 of User core. Sep 11 00:26:59.966583 sshd[3413]: Connection closed by 10.200.16.10 port 40920 Sep 11 00:26:59.966871 sshd-session[3411]: pam_unix(sshd:session): session closed for user core Sep 11 00:26:59.970698 systemd[1]: sshd@5-10.200.8.36:22-10.200.16.10:40920.service: Deactivated successfully. Sep 11 00:26:59.972366 systemd[1]: session-8.scope: Deactivated successfully. Sep 11 00:26:59.973068 systemd-logind[1883]: Session 8 logged out. Waiting for processes to exit. Sep 11 00:26:59.974794 systemd-logind[1883]: Removed session 8. Sep 11 00:27:00.083547 systemd[1]: Started sshd@6-10.200.8.36:22-10.200.16.10:45282.service - OpenSSH per-connection server daemon (10.200.16.10:45282). Sep 11 00:27:00.744198 sshd[3419]: Accepted publickey for core from 10.200.16.10 port 45282 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:27:00.745532 sshd-session[3419]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:27:00.749922 systemd-logind[1883]: New session 9 of user core. Sep 11 00:27:00.758501 systemd[1]: Started session-9.scope - Session 9 of User core. Sep 11 00:27:01.195778 sshd[3446]: Connection closed by 10.200.16.10 port 45282 Sep 11 00:27:01.196122 sshd-session[3419]: pam_unix(sshd:session): session closed for user core Sep 11 00:27:01.199544 systemd[1]: sshd@6-10.200.8.36:22-10.200.16.10:45282.service: Deactivated successfully. Sep 11 00:27:01.201462 systemd[1]: session-9.scope: Deactivated successfully. Sep 11 00:27:01.203683 systemd-logind[1883]: Session 9 logged out. Waiting for processes to exit. Sep 11 00:27:01.204994 systemd-logind[1883]: Removed session 9. Sep 11 00:27:11.314986 systemd[1]: Started sshd@7-10.200.8.36:22-10.200.16.10:33320.service - OpenSSH per-connection server daemon (10.200.16.10:33320). Sep 11 00:27:11.982469 sshd[6477]: Accepted publickey for core from 10.200.16.10 port 33320 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:27:11.984166 sshd-session[6477]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:27:11.991554 systemd-logind[1883]: New session 10 of user core. Sep 11 00:27:12.001050 systemd[1]: Started session-10.scope - Session 10 of User core. Sep 11 00:27:12.438233 sshd[6606]: Connection closed by 10.200.16.10 port 33320 Sep 11 00:27:12.440099 sshd-session[6477]: pam_unix(sshd:session): session closed for user core Sep 11 00:27:12.443995 systemd-logind[1883]: Session 10 logged out. Waiting for processes to exit. Sep 11 00:27:12.444320 systemd[1]: sshd@7-10.200.8.36:22-10.200.16.10:33320.service: Deactivated successfully. Sep 11 00:27:12.446107 systemd[1]: session-10.scope: Deactivated successfully. Sep 11 00:27:12.447695 systemd-logind[1883]: Removed session 10. Sep 11 00:27:12.555869 systemd[1]: Started sshd@8-10.200.8.36:22-10.200.16.10:33324.service - OpenSSH per-connection server daemon (10.200.16.10:33324). Sep 11 00:27:13.212837 sshd[6708]: Accepted publickey for core from 10.200.16.10 port 33324 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:27:13.214142 sshd-session[6708]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:27:13.218711 systemd-logind[1883]: New session 11 of user core. Sep 11 00:27:13.229479 systemd[1]: Started session-11.scope - Session 11 of User core. Sep 11 00:27:13.664814 sshd[6916]: Connection closed by 10.200.16.10 port 33324 Sep 11 00:27:13.665152 sshd-session[6708]: pam_unix(sshd:session): session closed for user core Sep 11 00:27:13.668427 systemd[1]: sshd@8-10.200.8.36:22-10.200.16.10:33324.service: Deactivated successfully. Sep 11 00:27:13.669953 systemd[1]: session-11.scope: Deactivated successfully. Sep 11 00:27:13.670638 systemd-logind[1883]: Session 11 logged out. Waiting for processes to exit. Sep 11 00:27:13.671734 systemd-logind[1883]: Removed session 11. Sep 11 00:27:23.783939 systemd[1]: Started sshd@9-10.200.8.36:22-10.200.16.10:58244.service - OpenSSH per-connection server daemon (10.200.16.10:58244). Sep 11 00:27:24.458725 sshd[9386]: Accepted publickey for core from 10.200.16.10 port 58244 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:27:24.459367 sshd-session[9386]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:27:24.467660 systemd-logind[1883]: New session 12 of user core. Sep 11 00:27:24.473914 systemd[1]: Started session-12.scope - Session 12 of User core. Sep 11 00:27:24.919121 sshd[9616]: Connection closed by 10.200.16.10 port 58244 Sep 11 00:27:24.919484 sshd-session[9386]: pam_unix(sshd:session): session closed for user core Sep 11 00:27:24.924519 systemd[1]: sshd@9-10.200.8.36:22-10.200.16.10:58244.service: Deactivated successfully. Sep 11 00:27:24.927103 systemd[1]: session-12.scope: Deactivated successfully. Sep 11 00:27:24.928238 systemd-logind[1883]: Session 12 logged out. Waiting for processes to exit. Sep 11 00:27:24.929700 systemd-logind[1883]: Removed session 12. Sep 11 00:27:25.032995 systemd[1]: Started sshd@10-10.200.8.36:22-10.200.16.10:58248.service - OpenSSH per-connection server daemon (10.200.16.10:58248). Sep 11 00:27:25.688710 sshd[9788]: Accepted publickey for core from 10.200.16.10 port 58248 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:27:25.690102 sshd-session[9788]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:27:25.696785 systemd-logind[1883]: New session 13 of user core. Sep 11 00:27:25.704732 systemd[1]: Started session-13.scope - Session 13 of User core. Sep 11 00:27:26.145846 sshd[10067]: Connection closed by 10.200.16.10 port 58248 Sep 11 00:27:26.147432 sshd-session[9788]: pam_unix(sshd:session): session closed for user core Sep 11 00:27:26.150727 systemd-logind[1883]: Session 13 logged out. Waiting for processes to exit. Sep 11 00:27:26.153461 systemd[1]: sshd@10-10.200.8.36:22-10.200.16.10:58248.service: Deactivated successfully. Sep 11 00:27:26.157066 systemd[1]: session-13.scope: Deactivated successfully. Sep 11 00:27:26.159588 systemd-logind[1883]: Removed session 13. Sep 11 00:27:36.264287 systemd[1]: Started sshd@11-10.200.8.36:22-10.200.16.10:54730.service - OpenSSH per-connection server daemon (10.200.16.10:54730). Sep 11 00:27:36.924700 sshd[11627]: Accepted publickey for core from 10.200.16.10 port 54730 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:27:36.926718 sshd-session[11627]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:27:36.935556 systemd-logind[1883]: New session 14 of user core. Sep 11 00:27:36.941001 systemd[1]: Started session-14.scope - Session 14 of User core. Sep 11 00:27:37.383168 sshd[11652]: Connection closed by 10.200.16.10 port 54730 Sep 11 00:27:37.385198 sshd-session[11627]: pam_unix(sshd:session): session closed for user core Sep 11 00:27:37.388516 systemd-logind[1883]: Session 14 logged out. Waiting for processes to exit. Sep 11 00:27:37.390002 systemd[1]: sshd@11-10.200.8.36:22-10.200.16.10:54730.service: Deactivated successfully. Sep 11 00:27:37.391539 systemd[1]: session-14.scope: Deactivated successfully. Sep 11 00:27:37.394559 systemd-logind[1883]: Removed session 14. Sep 11 00:27:37.503902 systemd[1]: Started sshd@12-10.200.8.36:22-10.200.16.10:54738.service - OpenSSH per-connection server daemon (10.200.16.10:54738). Sep 11 00:27:38.160327 sshd[11699]: Accepted publickey for core from 10.200.16.10 port 54738 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:27:38.160892 sshd-session[11699]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:27:38.167350 systemd-logind[1883]: New session 15 of user core. Sep 11 00:27:38.176502 systemd[1]: Started session-15.scope - Session 15 of User core. Sep 11 00:27:38.613032 sshd[11719]: Connection closed by 10.200.16.10 port 54738 Sep 11 00:27:38.614513 sshd-session[11699]: pam_unix(sshd:session): session closed for user core Sep 11 00:27:38.620467 systemd[1]: sshd@12-10.200.8.36:22-10.200.16.10:54738.service: Deactivated successfully. Sep 11 00:27:38.622086 systemd[1]: session-15.scope: Deactivated successfully. Sep 11 00:27:38.622886 systemd-logind[1883]: Session 15 logged out. Waiting for processes to exit. Sep 11 00:27:38.625407 systemd-logind[1883]: Removed session 15. Sep 11 00:27:48.730573 systemd[1]: Started sshd@13-10.200.8.36:22-10.200.16.10:37116.service - OpenSSH per-connection server daemon (10.200.16.10:37116). Sep 11 00:27:49.389760 sshd[12038]: Accepted publickey for core from 10.200.16.10 port 37116 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:27:49.391199 sshd-session[12038]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:27:49.397276 systemd-logind[1883]: New session 16 of user core. Sep 11 00:27:49.406537 systemd[1]: Started session-16.scope - Session 16 of User core. Sep 11 00:27:49.850111 sshd[12063]: Connection closed by 10.200.16.10 port 37116 Sep 11 00:27:49.850500 sshd-session[12038]: pam_unix(sshd:session): session closed for user core Sep 11 00:27:49.853948 systemd[1]: sshd@13-10.200.8.36:22-10.200.16.10:37116.service: Deactivated successfully. Sep 11 00:27:49.856349 systemd[1]: session-16.scope: Deactivated successfully. Sep 11 00:27:49.859545 systemd-logind[1883]: Session 16 logged out. Waiting for processes to exit. Sep 11 00:27:49.860886 systemd-logind[1883]: Removed session 16. Sep 11 00:27:49.966692 systemd[1]: Started sshd@14-10.200.8.36:22-10.200.16.10:51022.service - OpenSSH per-connection server daemon (10.200.16.10:51022). Sep 11 00:27:50.629828 sshd[12099]: Accepted publickey for core from 10.200.16.10 port 51022 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:27:50.631029 sshd-session[12099]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:27:50.637318 systemd-logind[1883]: New session 17 of user core. Sep 11 00:27:50.649520 systemd[1]: Started session-17.scope - Session 17 of User core. Sep 11 00:27:51.088438 sshd[12125]: Connection closed by 10.200.16.10 port 51022 Sep 11 00:27:51.091512 sshd-session[12099]: pam_unix(sshd:session): session closed for user core Sep 11 00:27:51.094923 systemd[1]: sshd@14-10.200.8.36:22-10.200.16.10:51022.service: Deactivated successfully. Sep 11 00:27:51.097143 systemd[1]: session-17.scope: Deactivated successfully. Sep 11 00:27:51.100050 systemd-logind[1883]: Session 17 logged out. Waiting for processes to exit. Sep 11 00:27:51.101627 systemd-logind[1883]: Removed session 17. Sep 11 00:28:01.203689 systemd[1]: Started sshd@15-10.200.8.36:22-10.200.16.10:40130.service - OpenSSH per-connection server daemon (10.200.16.10:40130). Sep 11 00:28:01.871485 sshd[12455]: Accepted publickey for core from 10.200.16.10 port 40130 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:28:01.872724 sshd-session[12455]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:28:01.877791 systemd-logind[1883]: New session 18 of user core. Sep 11 00:28:01.888601 systemd[1]: Started session-18.scope - Session 18 of User core. Sep 11 00:28:02.323782 sshd[12495]: Connection closed by 10.200.16.10 port 40130 Sep 11 00:28:02.324126 sshd-session[12455]: pam_unix(sshd:session): session closed for user core Sep 11 00:28:02.327748 systemd[1]: sshd@15-10.200.8.36:22-10.200.16.10:40130.service: Deactivated successfully. Sep 11 00:28:02.329300 systemd[1]: session-18.scope: Deactivated successfully. Sep 11 00:28:02.329997 systemd-logind[1883]: Session 18 logged out. Waiting for processes to exit. Sep 11 00:28:02.330939 systemd-logind[1883]: Removed session 18. Sep 11 00:28:02.441918 systemd[1]: Started sshd@16-10.200.8.36:22-10.200.16.10:40138.service - OpenSSH per-connection server daemon (10.200.16.10:40138). Sep 11 00:28:03.082024 sshd[12526]: Accepted publickey for core from 10.200.16.10 port 40138 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:28:03.083360 sshd-session[12526]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:28:03.087669 systemd-logind[1883]: New session 19 of user core. Sep 11 00:28:03.098459 systemd[1]: Started session-19.scope - Session 19 of User core. Sep 11 00:28:03.542528 sshd[12528]: Connection closed by 10.200.16.10 port 40138 Sep 11 00:28:03.542875 sshd-session[12526]: pam_unix(sshd:session): session closed for user core Sep 11 00:28:03.546234 systemd[1]: sshd@16-10.200.8.36:22-10.200.16.10:40138.service: Deactivated successfully. Sep 11 00:28:03.547798 systemd[1]: session-19.scope: Deactivated successfully. Sep 11 00:28:03.548490 systemd-logind[1883]: Session 19 logged out. Waiting for processes to exit. Sep 11 00:28:03.549429 systemd-logind[1883]: Removed session 19. Sep 11 00:28:11.227629 setup-nvidia[2519]: \u000d Sep 11 00:28:11.227629 setup-nvidia[2519]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Sep 11 00:28:11.227629 setup-nvidia[2519]: frequently when this kernel module was built against the wrong or Sep 11 00:28:11.227629 setup-nvidia[2519]: improperly configured kernel sources, with a version of gcc that differs Sep 11 00:28:11.227629 setup-nvidia[2519]: from the one used to build the target kernel, or if another driver, such Sep 11 00:28:11.227629 setup-nvidia[2519]: as nouveau, is present and prevents the NVIDIA kernel module from Sep 11 00:28:11.227629 setup-nvidia[2519]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Sep 11 00:28:11.227629 setup-nvidia[2519]: installed in this system is supported by this NVIDIA Linux graphics Sep 11 00:28:11.227629 setup-nvidia[2519]: driver release. Sep 11 00:28:11.227629 setup-nvidia[2519]: \u000d Sep 11 00:28:11.227629 setup-nvidia[2519]: Please see the log entries 'Kernel module load error' and 'Kernel Sep 11 00:28:11.227629 setup-nvidia[2519]: messages' at the end of the file Sep 11 00:28:11.227629 setup-nvidia[2519]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more Sep 11 00:28:11.227629 setup-nvidia[2519]: information. Sep 11 00:28:11.227629 setup-nvidia[2519]: \u000d Sep 11 00:28:11.242876 setup-nvidia[2519]: dmesg: read kernel buffer failed: Operation not permitted Sep 11 00:28:11.262280 setup-nvidia[2519]: \u000d Sep 11 00:28:11.262280 setup-nvidia[2519]: ERROR: Installation has failed. Please see the file Sep 11 00:28:11.262280 setup-nvidia[2519]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for Sep 11 00:28:11.262280 setup-nvidia[2519]: details. You may find suggestions on fixing installation problems in Sep 11 00:28:11.262280 setup-nvidia[2519]: the README available on the Linux driver download page at Sep 11 00:28:11.262280 setup-nvidia[2519]: www.nvidia.com. Sep 11 00:28:11.262280 setup-nvidia[2519]: \u000d Sep 11 00:28:11.262799 setup-nvidia[2519]: Last 50 lines of nvidia-installer.log: Sep 11 00:28:11.263603 setup-nvidia[2519]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039000rm+0x53: 'naked' return found in MITIGATION_RETHUNK build Sep 11 00:28:11.263603 setup-nvidia[2519]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038997rm+0x90: 'naked' return found in MITIGATION_RETHUNK build Sep 11 00:28:11.263603 setup-nvidia[2519]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038990rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build Sep 11 00:28:11.263603 setup-nvidia[2519]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039046rm+0x93: 'naked' return found in MITIGATION_RETHUNK build Sep 11 00:28:11.263603 setup-nvidia[2519]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039042rm+0x36: 'naked' return found in MITIGATION_RETHUNK build Sep 11 00:28:11.263603 setup-nvidia[2519]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039041rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build Sep 11 00:28:11.263603 setup-nvidia[2519]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039043rm+0x77: 'naked' return found in MITIGATION_RETHUNK build Sep 11 00:28:11.263603 setup-nvidia[2519]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038987rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build Sep 11 00:28:11.263603 setup-nvidia[2519]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030558rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build Sep 11 00:28:11.263603 setup-nvidia[2519]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030557rm+0x27: 'naked' return found in MITIGATION_RETHUNK build Sep 11 00:28:11.263603 setup-nvidia[2519]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030561rm+0x37: 'naked' return found in MITIGATION_RETHUNK build Sep 11 00:28:11.263603 setup-nvidia[2519]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030560rm+0x24: 'naked' return found in MITIGATION_RETHUNK build Sep 11 00:28:11.263603 setup-nvidia[2519]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030559rm+0xca: 'naked' return found in MITIGATION_RETHUNK build Sep 11 00:28:11.263603 setup-nvidia[2519]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030562rm+0x193: 'naked' return found in MITIGATION_RETHUNK build Sep 11 00:28:11.263603 setup-nvidia[2519]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030564rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build Sep 11 00:28:11.263603 setup-nvidia[2519]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv011860rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build Sep 11 00:28:11.263603 setup-nvidia[2519]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039354rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build Sep 11 00:28:11.263603 setup-nvidia[2519]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/Module.symvers Sep 11 00:28:11.263603 setup-nvidia[2519]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.mod.o Sep 11 00:28:11.263603 setup-nvidia[2519]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/.module-common.o Sep 11 00:28:11.263603 setup-nvidia[2519]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.mod.o Sep 11 00:28:11.263603 setup-nvidia[2519]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.mod.o Sep 11 00:28:11.263603 setup-nvidia[2519]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.mod.o Sep 11 00:28:11.263603 setup-nvidia[2519]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.mod.o Sep 11 00:28:11.263603 setup-nvidia[2519]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Sep 11 00:28:11.263603 setup-nvidia[2519]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Sep 11 00:28:11.263603 setup-nvidia[2519]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Sep 11 00:28:11.263603 setup-nvidia[2519]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Sep 11 00:28:11.263603 setup-nvidia[2519]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Sep 11 00:28:11.263603 setup-nvidia[2519]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Sep 11 00:28:11.263603 setup-nvidia[2519]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko due to unavailability of vmlinux Sep 11 00:28:11.263603 setup-nvidia[2519]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Sep 11 00:28:11.263603 setup-nvidia[2519]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko due to unavailability of vmlinux Sep 11 00:28:11.263603 setup-nvidia[2519]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Sep 11 00:28:11.263603 setup-nvidia[2519]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko due to unavailability of vmlinux Sep 11 00:28:11.263603 setup-nvidia[2519]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Sep 11 00:28:11.263603 setup-nvidia[2519]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko due to unavailability of vmlinux Sep 11 00:28:11.263603 setup-nvidia[2519]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Sep 11 00:28:11.263603 setup-nvidia[2519]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko due to unavailability of vmlinux Sep 11 00:28:11.263603 setup-nvidia[2519]: make[2]: Leaving directory '/usr/lib/modules/6.12.46-flatcar/build' Sep 11 00:28:11.264663 setup-nvidia[2519]: make[1]: Leaving directory '/usr/lib/modules/6.12.46-flatcar/source' Sep 11 00:28:11.264663 setup-nvidia[2519]: -> done. Sep 11 00:28:11.264663 setup-nvidia[2519]: -> Kernel module compilation complete. Sep 11 00:28:11.264663 setup-nvidia[2519]: -> Unable to determine if Secure Boot is enabled: No such file or directory Sep 11 00:28:11.264663 setup-nvidia[2519]: 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 11 00:28:11.264663 setup-nvidia[2519]: \u000d Sep 11 00:28:11.264663 setup-nvidia[2519]: Please see the log entries 'Kernel module load error' and 'Kernel messages' at the end of the file '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more information. Sep 11 00:28:11.264663 setup-nvidia[2519]: -> Kernel module load error: Operation not permitted Sep 11 00:28:11.264663 setup-nvidia[2519]: -> Kernel messages: Sep 11 00:28:11.264663 setup-nvidia[2519]: ERROR: Installation has failed. Please see the file '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for details. You may find suggestions on fixing installation problems in the README available on the Linux driver download page at www.nvidia.com. Sep 11 00:28:13.659697 systemd[1]: Started sshd@17-10.200.8.36:22-10.200.16.10:50998.service - OpenSSH per-connection server daemon (10.200.16.10:50998). Sep 11 00:28:14.300054 sshd[12714]: Accepted publickey for core from 10.200.16.10 port 50998 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:28:14.301357 sshd-session[12714]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:28:14.305589 systemd-logind[1883]: New session 20 of user core. Sep 11 00:28:14.316467 systemd[1]: Started session-20.scope - Session 20 of User core. Sep 11 00:28:14.751020 sshd[12716]: Connection closed by 10.200.16.10 port 50998 Sep 11 00:28:14.751359 sshd-session[12714]: pam_unix(sshd:session): session closed for user core Sep 11 00:28:14.754692 systemd[1]: sshd@17-10.200.8.36:22-10.200.16.10:50998.service: Deactivated successfully. Sep 11 00:28:14.756404 systemd[1]: session-20.scope: Deactivated successfully. Sep 11 00:28:14.757126 systemd-logind[1883]: Session 20 logged out. Waiting for processes to exit. Sep 11 00:28:14.758074 systemd-logind[1883]: Removed session 20. Sep 11 00:28:14.867582 systemd[1]: Started sshd@18-10.200.8.36:22-10.200.16.10:51012.service - OpenSSH per-connection server daemon (10.200.16.10:51012). Sep 11 00:28:15.507010 sshd[12722]: Accepted publickey for core from 10.200.16.10 port 51012 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:28:15.508303 sshd-session[12722]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:28:15.512711 systemd-logind[1883]: New session 21 of user core. Sep 11 00:28:15.532477 systemd[1]: Started session-21.scope - Session 21 of User core. Sep 11 00:28:15.960415 sshd[12724]: Connection closed by 10.200.16.10 port 51012 Sep 11 00:28:15.960704 sshd-session[12722]: pam_unix(sshd:session): session closed for user core Sep 11 00:28:15.963960 systemd[1]: sshd@18-10.200.8.36:22-10.200.16.10:51012.service: Deactivated successfully. Sep 11 00:28:15.965577 systemd[1]: session-21.scope: Deactivated successfully. Sep 11 00:28:15.966252 systemd-logind[1883]: Session 21 logged out. Waiting for processes to exit. Sep 11 00:28:15.967179 systemd-logind[1883]: Removed session 21. Sep 11 00:28:26.077746 systemd[1]: Started sshd@19-10.200.8.36:22-10.200.16.10:50586.service - OpenSSH per-connection server daemon (10.200.16.10:50586). Sep 11 00:28:26.720842 sshd[12730]: Accepted publickey for core from 10.200.16.10 port 50586 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:28:26.722147 sshd-session[12730]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:28:26.726323 systemd-logind[1883]: New session 22 of user core. Sep 11 00:28:26.739455 systemd[1]: Started session-22.scope - Session 22 of User core. Sep 11 00:28:27.171401 sshd[12732]: Connection closed by 10.200.16.10 port 50586 Sep 11 00:28:27.171749 sshd-session[12730]: pam_unix(sshd:session): session closed for user core Sep 11 00:28:27.175174 systemd[1]: sshd@19-10.200.8.36:22-10.200.16.10:50586.service: Deactivated successfully. Sep 11 00:28:27.176690 systemd[1]: session-22.scope: Deactivated successfully. Sep 11 00:28:27.177358 systemd-logind[1883]: Session 22 logged out. Waiting for processes to exit. Sep 11 00:28:27.178279 systemd-logind[1883]: Removed session 22. Sep 11 00:28:27.288769 systemd[1]: Started sshd@20-10.200.8.36:22-10.200.16.10:50588.service - OpenSSH per-connection server daemon (10.200.16.10:50588). Sep 11 00:28:27.931710 sshd[12738]: Accepted publickey for core from 10.200.16.10 port 50588 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:28:27.932999 sshd-session[12738]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:28:27.937376 systemd-logind[1883]: New session 23 of user core. Sep 11 00:28:27.949460 systemd[1]: Started session-23.scope - Session 23 of User core. Sep 11 00:28:28.384812 sshd[12740]: Connection closed by 10.200.16.10 port 50588 Sep 11 00:28:28.385161 sshd-session[12738]: pam_unix(sshd:session): session closed for user core Sep 11 00:28:28.388464 systemd[1]: sshd@20-10.200.8.36:22-10.200.16.10:50588.service: Deactivated successfully. Sep 11 00:28:28.389994 systemd[1]: session-23.scope: Deactivated successfully. Sep 11 00:28:28.390655 systemd-logind[1883]: Session 23 logged out. Waiting for processes to exit. Sep 11 00:28:28.391588 systemd-logind[1883]: Removed session 23. Sep 11 00:28:38.502380 systemd[1]: Started sshd@21-10.200.8.36:22-10.200.16.10:54096.service - OpenSSH per-connection server daemon (10.200.16.10:54096). Sep 11 00:28:39.156670 sshd[12747]: Accepted publickey for core from 10.200.16.10 port 54096 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:28:39.157986 sshd-session[12747]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:28:39.162539 systemd-logind[1883]: New session 24 of user core. Sep 11 00:28:39.182465 systemd[1]: Started session-24.scope - Session 24 of User core. Sep 11 00:28:39.611612 sshd[12749]: Connection closed by 10.200.16.10 port 54096 Sep 11 00:28:39.611966 sshd-session[12747]: pam_unix(sshd:session): session closed for user core Sep 11 00:28:39.615945 systemd[1]: sshd@21-10.200.8.36:22-10.200.16.10:54096.service: Deactivated successfully. Sep 11 00:28:39.617754 systemd[1]: session-24.scope: Deactivated successfully. Sep 11 00:28:39.619056 systemd-logind[1883]: Session 24 logged out. Waiting for processes to exit. Sep 11 00:28:39.620025 systemd-logind[1883]: Removed session 24. Sep 11 00:28:39.735900 systemd[1]: Started sshd@22-10.200.8.36:22-10.200.16.10:54098.service - OpenSSH per-connection server daemon (10.200.16.10:54098). Sep 11 00:28:40.375120 sshd[12755]: Accepted publickey for core from 10.200.16.10 port 54098 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:28:40.376499 sshd-session[12755]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:28:40.380983 systemd-logind[1883]: New session 25 of user core. Sep 11 00:28:40.391460 systemd[1]: Started session-25.scope - Session 25 of User core. Sep 11 00:28:40.826250 sshd[12757]: Connection closed by 10.200.16.10 port 54098 Sep 11 00:28:40.826620 sshd-session[12755]: pam_unix(sshd:session): session closed for user core Sep 11 00:28:40.829914 systemd[1]: sshd@22-10.200.8.36:22-10.200.16.10:54098.service: Deactivated successfully. Sep 11 00:28:40.831456 systemd[1]: session-25.scope: Deactivated successfully. Sep 11 00:28:40.832162 systemd-logind[1883]: Session 25 logged out. Waiting for processes to exit. Sep 11 00:28:40.833136 systemd-logind[1883]: Removed session 25. Sep 11 00:28:40.979366 kernel: EXT4-fs (loop6p9): unmounting filesystem f1e27df3-5e40-42fc-9547-7151e2f73697. Sep 11 00:28:40.979826 setup-nvidia[2519]: Container flatcardevelopercontainer-4372.1.0nightly-20250910-2100.bin exited successfully. Sep 11 00:28:40.982159 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4372.1.0nightly\x2d20250910\x2d2100.bin.mount: Deactivated successfully. Sep 11 00:28:41.022251 sudo[2505]: pam_unix(sudo:session): session closed for user root Sep 11 00:28:41.024144 systemd[1]: session-c2.scope: Deactivated successfully. Sep 11 00:28:41.024433 systemd[1]: session-c2.scope: Consumed 5min 10.381s CPU time, 893.6M memory peak. Sep 11 00:28:41.440526 setup-nvidia[1874]: /opt/nvidia / Sep 11 00:28:41.441579 setup-nvidia[1874]: / Sep 11 00:28:41.451348 kernel: IPMI message handler: version 39.2 Sep 11 00:28:41.454348 kernel: ipmi device interface Sep 11 00:28:41.466476 setup-nvidia[1874]: /opt/nvidia/current/usr/lib/modules/6.12.46-flatcar/video / Sep 11 00:28:41.518402 kernel: nvidia: loading out-of-tree module taints kernel. Sep 11 00:28:41.518521 kernel: nvidia: module license 'NVIDIA' taints kernel. Sep 11 00:28:41.518546 kernel: Disabling lock debugging due to kernel taint Sep 11 00:28:41.525747 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Sep 11 00:28:41.525788 kernel: nvidia: module license taints kernel. Sep 11 00:28:41.934359 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 Sep 11 00:28:42.338727 kernel: Sep 11 00:28:42.338872 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Sep 11 00:28:42.549355 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.230.02 Fri Dec 20 21:42:05 UTC 2024 Sep 11 00:28:42.571355 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.230.02 Fri Dec 20 21:52:34 UTC 2024 Sep 11 00:28:42.589370 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Sep 11 00:28:42.634795 setup-nvidia[1874]: / Sep 11 00:28:42.635349 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. Sep 11 00:28:42.650342 setup-nvidia[12796]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 11 00:28:44.478652 setup-nvidia[12813]: Thu Sep 11 00:28:44 2025 Sep 11 00:28:44.478652 setup-nvidia[12813]: +---------------------------------------------------------------------------------------+ Sep 11 00:28:44.478652 setup-nvidia[12813]: | NVIDIA-SMI 535.230.02 Driver Version: 535.230.02 CUDA Version: 12.2 | Sep 11 00:28:44.478652 setup-nvidia[12813]: |-----------------------------------------+----------------------+----------------------+ Sep 11 00:28:44.478652 setup-nvidia[12813]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Sep 11 00:28:44.478652 setup-nvidia[12813]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Sep 11 00:28:44.478652 setup-nvidia[12813]: | | | MIG M. | Sep 11 00:28:44.478652 setup-nvidia[12813]: |=========================================+======================+======================| Sep 11 00:28:44.483848 setup-nvidia[12813]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Sep 11 00:28:44.483848 setup-nvidia[12813]: | N/A 25C P0 37W / 250W | 0MiB / 16384MiB | 0% Default | Sep 11 00:28:44.483848 setup-nvidia[12813]: | | | N/A | Sep 11 00:28:44.483848 setup-nvidia[12813]: +-----------------------------------------+----------------------+----------------------+ Sep 11 00:28:44.483966 setup-nvidia[12813]: Sep 11 00:28:44.483966 setup-nvidia[12813]: +---------------------------------------------------------------------------------------+ Sep 11 00:28:44.483966 setup-nvidia[12813]: | Processes: | Sep 11 00:28:44.483966 setup-nvidia[12813]: | GPU GI CI PID Type Process name GPU Memory | Sep 11 00:28:44.483966 setup-nvidia[12813]: | ID ID Usage | Sep 11 00:28:44.483966 setup-nvidia[12813]: |=======================================================================================| Sep 11 00:28:44.484101 setup-nvidia[12813]: | No running processes found | Sep 11 00:28:44.484101 setup-nvidia[12813]: +---------------------------------------------------------------------------------------+ Sep 11 00:28:44.856627 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 11 00:28:44.858657 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 11 00:28:44.858933 systemd[1]: Startup finished in 1.292s (firmware) + 32.825s (loader) + 3.784s (kernel) + 14.460s (initrd) + 3min 15.835s (userspace) = 4min 8.198s. Sep 11 00:28:50.951306 systemd[1]: Started sshd@23-10.200.8.36:22-10.200.16.10:39214.service - OpenSSH per-connection server daemon (10.200.16.10:39214). Sep 11 00:28:51.249130 systemd[1]: Stopping user@0.service - User Manager for UID 0... Sep 11 00:28:51.249905 systemd[2509]: Activating special unit exit.target... Sep 11 00:28:51.249986 systemd[2509]: Stopped target default.target - Main User Target. Sep 11 00:28:51.250023 systemd[2509]: Stopped target basic.target - Basic System. Sep 11 00:28:51.250051 systemd[2509]: Stopped target paths.target - Paths. Sep 11 00:28:51.250077 systemd[2509]: Stopped target sockets.target - Sockets. Sep 11 00:28:51.250102 systemd[2509]: Stopped target timers.target - Timers. Sep 11 00:28:51.250456 systemd[2509]: Closed dbus.socket - D-Bus User Message Bus Socket. Sep 11 00:28:51.250659 systemd[2509]: Removed slice app.slice - User Application Slice. Sep 11 00:28:51.250693 systemd[2509]: Reached target shutdown.target - Shutdown. Sep 11 00:28:51.250831 systemd[2509]: Finished systemd-exit.service - Exit the Session. Sep 11 00:28:51.250868 systemd[2509]: Reached target exit.target - Exit the Session. Sep 11 00:28:51.268870 (sd-pam)[2510]: pam_unix(systemd-user:session): session closed for user root Sep 11 00:28:51.270326 systemd[1]: user@0.service: Deactivated successfully. Sep 11 00:28:51.270749 systemd[1]: Stopped user@0.service - User Manager for UID 0. Sep 11 00:28:51.273094 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Sep 11 00:28:51.297208 systemd[1]: run-user-0.mount: Deactivated successfully. Sep 11 00:28:51.298104 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Sep 11 00:28:51.298337 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Sep 11 00:28:51.300011 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Sep 11 00:28:51.300107 systemd[1]: user-0.slice: Consumed 5min 10.610s CPU time, 903M memory peak. Sep 11 00:28:51.300545 systemd-logind[1883]: Removed session c3. Sep 11 00:28:51.590480 sshd[12820]: Accepted publickey for core from 10.200.16.10 port 39214 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:28:51.591816 sshd-session[12820]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:28:51.596210 systemd-logind[1883]: New session 26 of user core. Sep 11 00:28:51.610521 systemd[1]: Started session-26.scope - Session 26 of User core. Sep 11 00:28:52.042904 sshd[12826]: Connection closed by 10.200.16.10 port 39214 Sep 11 00:28:52.043276 sshd-session[12820]: pam_unix(sshd:session): session closed for user core Sep 11 00:28:52.046696 systemd[1]: sshd@23-10.200.8.36:22-10.200.16.10:39214.service: Deactivated successfully. Sep 11 00:28:52.048459 systemd[1]: session-26.scope: Deactivated successfully. Sep 11 00:28:52.049204 systemd-logind[1883]: Session 26 logged out. Waiting for processes to exit. Sep 11 00:28:52.050146 systemd-logind[1883]: Removed session 26. Sep 11 00:28:52.160005 systemd[1]: Started sshd@24-10.200.8.36:22-10.200.16.10:39222.service - OpenSSH per-connection server daemon (10.200.16.10:39222). Sep 11 00:28:52.820000 sshd[12832]: Accepted publickey for core from 10.200.16.10 port 39222 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:28:52.821347 sshd-session[12832]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:28:52.825790 systemd-logind[1883]: New session 27 of user core. Sep 11 00:28:52.835466 systemd[1]: Started session-27.scope - Session 27 of User core. Sep 11 00:28:54.149109 sshd[12834]: Connection closed by 10.200.16.10 port 39222 Sep 11 00:28:53.386897 systemd[1]: Started sshd@25-10.200.8.36:22-10.200.16.10:39226.service - OpenSSH per-connection server daemon (10.200.16.10:39226). Sep 11 00:28:54.149205 sshd-session[12832]: pam_unix(sshd:session): session closed for user core Sep 11 00:28:54.149929 sshd[12837]: Accepted publickey for core from 10.200.16.10 port 39226 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:28:54.149441 sshd-session[12837]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:28:54.154103 systemd-logind[1883]: New session 28 of user core. Sep 11 00:28:54.154696 systemd[1]: sshd@24-10.200.8.36:22-10.200.16.10:39222.service: Deactivated successfully. Sep 11 00:28:54.156396 systemd[1]: session-27.scope: Deactivated successfully. Sep 11 00:28:54.158669 systemd-logind[1883]: Session 27 logged out. Waiting for processes to exit. Sep 11 00:28:54.173502 systemd[1]: Started session-28.scope - Session 28 of User core. Sep 11 00:28:54.174475 systemd-logind[1883]: Removed session 27. Sep 11 00:28:54.533511 sshd[12842]: Connection closed by 10.200.16.10 port 39226 Sep 11 00:28:54.533864 sshd-session[12837]: pam_unix(sshd:session): session closed for user core Sep 11 00:28:54.536772 systemd[1]: sshd@25-10.200.8.36:22-10.200.16.10:39226.service: Deactivated successfully. Sep 11 00:28:54.538419 systemd[1]: session-28.scope: Deactivated successfully. Sep 11 00:28:54.539093 systemd-logind[1883]: Session 28 logged out. Waiting for processes to exit. Sep 11 00:28:54.540479 systemd-logind[1883]: Removed session 28. Sep 11 00:28:54.650675 systemd[1]: Started sshd@26-10.200.8.36:22-10.200.16.10:39242.service - OpenSSH per-connection server daemon (10.200.16.10:39242). Sep 11 00:28:55.289580 sshd[12848]: Accepted publickey for core from 10.200.16.10 port 39242 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:28:55.290872 sshd-session[12848]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:28:55.295023 systemd-logind[1883]: New session 29 of user core. Sep 11 00:28:55.305444 systemd[1]: Started session-29.scope - Session 29 of User core. Sep 11 00:28:55.660167 sudo[12851]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Sep 11 00:28:55.660492 sudo[12851]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 11 00:28:55.725556 sudo[12851]: pam_unix(sudo:session): session closed for user root Sep 11 00:28:55.829376 sshd[12850]: Connection closed by 10.200.16.10 port 39242 Sep 11 00:28:55.829737 sshd-session[12848]: pam_unix(sshd:session): session closed for user core Sep 11 00:28:55.833216 systemd[1]: sshd@26-10.200.8.36:22-10.200.16.10:39242.service: Deactivated successfully. Sep 11 00:28:55.834796 systemd[1]: session-29.scope: Deactivated successfully. Sep 11 00:28:55.835513 systemd-logind[1883]: Session 29 logged out. Waiting for processes to exit. Sep 11 00:28:55.836502 systemd-logind[1883]: Removed session 29. Sep 11 00:28:55.947540 systemd[1]: Started sshd@27-10.200.8.36:22-10.200.16.10:39252.service - OpenSSH per-connection server daemon (10.200.16.10:39252). Sep 11 00:28:56.600728 sshd[12857]: Accepted publickey for core from 10.200.16.10 port 39252 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:28:56.602044 sshd-session[12857]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:28:56.606269 systemd-logind[1883]: New session 30 of user core. Sep 11 00:28:56.615466 systemd[1]: Started session-30.scope - Session 30 of User core. Sep 11 00:28:56.951319 sudo[12861]: 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 11 00:28:56.951670 sudo[12861]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 11 00:28:56.959376 sudo[12861]: pam_unix(sudo:session): session closed for user root Sep 11 00:28:56.965259 sudo[12860]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Sep 11 00:28:56.965582 sudo[12860]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 11 00:28:56.974244 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 11 00:28:57.011513 augenrules[12883]: No rules Sep 11 00:28:57.012999 systemd[1]: audit-rules.service: Deactivated successfully. Sep 11 00:28:57.013226 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 11 00:28:57.013966 sudo[12860]: pam_unix(sudo:session): session closed for user root Sep 11 00:28:57.127440 sshd[12859]: Connection closed by 10.200.16.10 port 39252 Sep 11 00:28:57.127775 sshd-session[12857]: pam_unix(sshd:session): session closed for user core Sep 11 00:28:57.131173 systemd[1]: sshd@27-10.200.8.36:22-10.200.16.10:39252.service: Deactivated successfully. Sep 11 00:28:57.132805 systemd[1]: session-30.scope: Deactivated successfully. Sep 11 00:28:57.133488 systemd-logind[1883]: Session 30 logged out. Waiting for processes to exit. Sep 11 00:28:57.134420 systemd-logind[1883]: Removed session 30. Sep 11 00:28:57.251503 systemd[1]: Started sshd@28-10.200.8.36:22-10.200.16.10:39258.service - OpenSSH per-connection server daemon (10.200.16.10:39258). Sep 11 00:28:57.889951 sshd[12892]: Accepted publickey for core from 10.200.16.10 port 39258 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:28:57.891246 sshd-session[12892]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:28:57.895448 systemd-logind[1883]: New session 31 of user core. Sep 11 00:28:57.909459 systemd[1]: Started session-31.scope - Session 31 of User core. Sep 11 00:28:58.240711 sudo[12895]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/mkdir -p . Sep 11 00:28:58.241006 sudo[12895]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 11 00:28:58.244217 sudo[12895]: pam_unix(sudo:session): session closed for user root Sep 11 00:28:58.345814 sshd[12894]: Connection closed by 10.200.16.10 port 39258 Sep 11 00:28:58.346164 sshd-session[12892]: pam_unix(sshd:session): session closed for user core Sep 11 00:28:58.349494 systemd[1]: sshd@28-10.200.8.36:22-10.200.16.10:39258.service: Deactivated successfully. Sep 11 00:28:58.351089 systemd[1]: session-31.scope: Deactivated successfully. Sep 11 00:28:58.351824 systemd-logind[1883]: Session 31 logged out. Waiting for processes to exit. Sep 11 00:28:58.352825 systemd-logind[1883]: Removed session 31. Sep 11 00:28:58.461538 systemd[1]: Started sshd@29-10.200.8.36:22-10.200.16.10:39270.service - OpenSSH per-connection server daemon (10.200.16.10:39270). Sep 11 00:28:59.116707 sshd[12901]: Accepted publickey for core from 10.200.16.10 port 39270 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:28:59.117998 sshd-session[12901]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:28:59.122208 systemd-logind[1883]: New session 32 of user core. Sep 11 00:28:59.133457 systemd[1]: Started session-32.scope - Session 32 of User core. Sep 11 00:28:59.466634 sudo[12904]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/install -m 0755 /dev/stdin kolet Sep 11 00:28:59.466931 sudo[12904]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 11 00:29:02.926213 sudo[12904]: pam_unix(sudo:session): session closed for user root Sep 11 00:29:03.029664 sshd[12903]: Connection closed by 10.200.16.10 port 39270 Sep 11 00:29:03.029934 sshd-session[12901]: pam_unix(sshd:session): session closed for user core Sep 11 00:29:03.033357 systemd[1]: sshd@29-10.200.8.36:22-10.200.16.10:39270.service: Deactivated successfully. Sep 11 00:29:03.035013 systemd[1]: session-32.scope: Deactivated successfully. Sep 11 00:29:03.035800 systemd-logind[1883]: Session 32 logged out. Waiting for processes to exit. Sep 11 00:29:03.036750 systemd-logind[1883]: Removed session 32. Sep 11 00:29:03.146863 systemd[1]: Started sshd@30-10.200.8.36:22-10.200.16.10:51138.service - OpenSSH per-connection server daemon (10.200.16.10:51138). Sep 11 00:29:03.799103 sshd[12910]: Accepted publickey for core from 10.200.16.10 port 51138 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:29:03.800393 sshd-session[12910]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:29:03.804914 systemd-logind[1883]: New session 33 of user core. Sep 11 00:29:03.821452 systemd[1]: Started session-33.scope - Session 33 of User core. Sep 11 00:29:04.250238 ntpdate[12922]: ntpdate 4.2.8p17@1.4004-o Wed Sep 10 21:39:41 UTC 2025 (1) Sep 11 00:29:12.634428 ntpdate[12922]: adjust time server 188.125.64.6 offset -0.001746 sec Sep 11 00:29:12.738896 sshd[12912]: Connection closed by 10.200.16.10 port 51138 Sep 11 00:29:12.739247 sshd-session[12910]: pam_unix(sshd:session): session closed for user core Sep 11 00:29:12.742731 systemd[1]: sshd@30-10.200.8.36:22-10.200.16.10:51138.service: Deactivated successfully. Sep 11 00:29:12.744299 systemd[1]: session-33.scope: Deactivated successfully. Sep 11 00:29:12.745030 systemd-logind[1883]: Session 33 logged out. Waiting for processes to exit. Sep 11 00:29:12.746026 systemd-logind[1883]: Removed session 33. Sep 11 00:29:12.855543 systemd[1]: Started sshd@31-10.200.8.36:22-10.200.16.10:42904.service - OpenSSH per-connection server daemon (10.200.16.10:42904). Sep 11 00:29:13.515592 sshd[12927]: Accepted publickey for core from 10.200.16.10 port 42904 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:29:13.516900 sshd-session[12927]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:29:13.521541 systemd-logind[1883]: New session 34 of user core. Sep 11 00:29:13.532463 systemd[1]: Started session-34.scope - Session 34 of User core. Sep 11 00:29:14.065291 sshd[12929]: Connection closed by 10.200.16.10 port 42904 Sep 11 00:29:14.065666 sshd-session[12927]: pam_unix(sshd:session): session closed for user core Sep 11 00:29:14.069431 systemd[1]: sshd@31-10.200.8.36:22-10.200.16.10:42904.service: Deactivated successfully. Sep 11 00:29:14.071017 systemd[1]: session-34.scope: Deactivated successfully. Sep 11 00:29:14.071701 systemd-logind[1883]: Session 34 logged out. Waiting for processes to exit. Sep 11 00:29:14.073153 systemd-logind[1883]: Removed session 34. Sep 11 00:29:14.189434 systemd[1]: Started sshd@32-10.200.8.36:22-10.200.16.10:42908.service - OpenSSH per-connection server daemon (10.200.16.10:42908). Sep 11 00:29:14.850553 sshd[12944]: Accepted publickey for core from 10.200.16.10 port 42908 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:29:14.851893 sshd-session[12944]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:29:14.856115 systemd-logind[1883]: New session 35 of user core. Sep 11 00:29:14.866460 systemd[1]: Started session-35.scope - Session 35 of User core. Sep 11 00:29:16.025645 systemd[1]: Starting docker.service - Docker Application Container Engine... Sep 11 00:29:16.044678 (dockerd)[12966]: 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 11 00:29:17.122215 dockerd[12966]: time="2025-09-11T00:29:17.122155545Z" level=info msg="Starting up" Sep 11 00:29:17.122857 dockerd[12966]: time="2025-09-11T00:29:17.122834013Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Sep 11 00:29:17.740931 dockerd[12966]: time="2025-09-11T00:29:17.740874956Z" level=info msg="Loading containers: start." Sep 11 00:29:17.755355 kernel: Initializing XFRM netlink socket Sep 11 00:29:18.062675 systemd-networkd[1589]: docker0: Link UP Sep 11 00:29:18.081082 dockerd[12966]: time="2025-09-11T00:29:18.081040243Z" level=info msg="Loading containers: done." Sep 11 00:29:18.102948 dockerd[12966]: time="2025-09-11T00:29:18.102906801Z" 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 11 00:29:18.103063 dockerd[12966]: time="2025-09-11T00:29:18.102977798Z" level=info msg="Docker daemon" commit=bbd0a17ccc67e48d4a69393287b7fcc4f0578683 containerd-snapshotter=false storage-driver=overlay2 version=28.0.1 Sep 11 00:29:18.103100 dockerd[12966]: time="2025-09-11T00:29:18.103073093Z" level=info msg="Initializing buildkit" Sep 11 00:29:18.159888 dockerd[12966]: time="2025-09-11T00:29:18.159857687Z" level=info msg="Completed buildkit initialization" Sep 11 00:29:18.162871 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck3999963150-merged.mount: Deactivated successfully. Sep 11 00:29:18.164930 dockerd[12966]: time="2025-09-11T00:29:18.164902446Z" level=info msg="Daemon has completed initialization" Sep 11 00:29:18.165036 dockerd[12966]: time="2025-09-11T00:29:18.164951444Z" level=info msg="API listen on /run/docker.sock" Sep 11 00:29:18.165053 systemd[1]: Started docker.service - Docker Application Container Engine. Sep 11 00:29:19.239745 systemd[1]: var-lib-docker-overlay2-4ff7da96d55e98819a4bf9260fdf403878a67e22b96f1887b50ec52d80d2d152\x2dinit-merged.mount: Deactivated successfully. Sep 11 00:29:19.332213 containerd[1895]: time="2025-09-11T00:29:19.332167613Z" level=info msg="connecting to shim 95d20a1b3e79fbc513a117d67a39a16ec2932648d90e559e49af8dee69a49bf7" address="unix:///run/containerd/s/ae47b94a219d249da7918a8da87be4419ee1959ee7a3686751b8c73c937292b4" namespace=moby protocol=ttrpc version=3 Sep 11 00:29:19.365468 systemd[1]: Started docker-95d20a1b3e79fbc513a117d67a39a16ec2932648d90e559e49af8dee69a49bf7.scope - libcontainer container 95d20a1b3e79fbc513a117d67a39a16ec2932648d90e559e49af8dee69a49bf7. Sep 11 00:29:19.413387 kernel: docker0: port 1(veth5e8f8f5) entered blocking state Sep 11 00:29:19.413473 kernel: docker0: port 1(veth5e8f8f5) entered disabled state Sep 11 00:29:19.413495 kernel: veth5e8f8f5: entered allmulticast mode Sep 11 00:29:19.418681 kernel: veth5e8f8f5: entered promiscuous mode Sep 11 00:29:19.419196 systemd-networkd[1589]: veth5e8f8f5: Link UP Sep 11 00:29:19.447700 kernel: eth0: renamed from veth2a7b9c5 Sep 11 00:29:19.447755 kernel: docker0: port 1(veth5e8f8f5) entered blocking state Sep 11 00:29:19.453782 kernel: docker0: port 1(veth5e8f8f5) entered forwarding state Sep 11 00:29:19.453870 systemd-networkd[1589]: veth5e8f8f5: Gained carrier Sep 11 00:29:19.454128 systemd-networkd[1589]: docker0: Gained carrier Sep 11 00:29:20.809488 systemd-networkd[1589]: docker0: Gained IPv6LL Sep 11 00:29:21.385482 systemd-networkd[1589]: veth5e8f8f5: Gained IPv6LL Sep 11 00:29:22.741998 systemd[1]: docker-95d20a1b3e79fbc513a117d67a39a16ec2932648d90e559e49af8dee69a49bf7.scope: Deactivated successfully. Sep 11 00:29:22.754584 containerd[1895]: time="2025-09-11T00:29:22.754537998Z" level=info msg="shim disconnected" id=95d20a1b3e79fbc513a117d67a39a16ec2932648d90e559e49af8dee69a49bf7 namespace=moby Sep 11 00:29:22.754584 containerd[1895]: time="2025-09-11T00:29:22.754570696Z" level=warning msg="cleaning up after shim disconnected" id=95d20a1b3e79fbc513a117d67a39a16ec2932648d90e559e49af8dee69a49bf7 namespace=moby Sep 11 00:29:22.754888 containerd[1895]: time="2025-09-11T00:29:22.754579296Z" level=info msg="cleaning up dead shim" namespace=moby Sep 11 00:29:22.754922 dockerd[12966]: time="2025-09-11T00:29:22.754632393Z" level=info msg="ignoring event" container=95d20a1b3e79fbc513a117d67a39a16ec2932648d90e559e49af8dee69a49bf7 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Sep 11 00:29:22.783839 systemd-networkd[1589]: veth5e8f8f5: Lost carrier Sep 11 00:29:22.784348 kernel: docker0: port 1(veth5e8f8f5) entered disabled state Sep 11 00:29:22.784395 kernel: veth2a7b9c5: renamed from eth0 Sep 11 00:29:22.804530 systemd-networkd[1589]: veth5e8f8f5: Link DOWN Sep 11 00:29:22.808349 kernel: docker0: port 1(veth5e8f8f5) entered disabled state Sep 11 00:29:22.808395 kernel: veth5e8f8f5 (unregistering): left allmulticast mode Sep 11 00:29:22.813997 kernel: veth5e8f8f5 (unregistering): left promiscuous mode Sep 11 00:29:22.817478 kernel: docker0: port 1(veth5e8f8f5) entered disabled state Sep 11 00:29:22.839684 systemd[1]: run-docker-netns-34fd144d7e25.mount: Deactivated successfully. Sep 11 00:29:22.842982 systemd[1]: var-lib-docker-overlay2-4ff7da96d55e98819a4bf9260fdf403878a67e22b96f1887b50ec52d80d2d152-merged.mount: Deactivated successfully. Sep 11 00:29:22.981243 sshd[12946]: Connection closed by 10.200.16.10 port 42908 Sep 11 00:29:22.981656 sshd-session[12944]: pam_unix(sshd:session): session closed for user core Sep 11 00:29:22.985145 systemd[1]: sshd@32-10.200.8.36:22-10.200.16.10:42908.service: Deactivated successfully. Sep 11 00:29:22.986746 systemd[1]: session-35.scope: Deactivated successfully. Sep 11 00:29:22.987415 systemd-logind[1883]: Session 35 logged out. Waiting for processes to exit. Sep 11 00:29:22.988354 systemd-logind[1883]: Removed session 35. Sep 11 00:29:23.093621 systemd[1]: Started sshd@33-10.200.8.36:22-10.200.16.10:52674.service - OpenSSH per-connection server daemon (10.200.16.10:52674). Sep 11 00:29:23.732948 sshd[13249]: Accepted publickey for core from 10.200.16.10 port 52674 ssh2: RSA SHA256:ohJ5BCVWplQQBltnwCsxa59ESpsWA75v8KLUz/jsOpg Sep 11 00:29:23.734433 sshd-session[13249]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:29:23.738640 systemd-logind[1883]: New session 36 of user core. Sep 11 00:29:23.749459 systemd[1]: Started session-36.scope - Session 36 of User core. Sep 11 00:29:23.817634 systemd-networkd[1589]: docker0: Lost carrier Sep 11 00:29:24.137476 systemd[1]: var-lib-docker-overlay2-94ceb74d8da0f67044fe60b2ae31d9f12334d0cf573c3e203bf259a97eac3efb\x2dinit-merged.mount: Deactivated successfully. Sep 11 00:29:24.219517 containerd[1895]: time="2025-09-11T00:29:24.219471433Z" level=info msg="connecting to shim 4de031ec8e3d8f532484a78f8086faf11f6665eaf0cf2d83148a4b73fc91bb94" address="unix:///run/containerd/s/93599199f201c7176e37cbb638c46f7ecaf9367bc78b74fbe8d58902bcd177ea" namespace=moby protocol=ttrpc version=3 Sep 11 00:29:24.245460 systemd[1]: Started docker-4de031ec8e3d8f532484a78f8086faf11f6665eaf0cf2d83148a4b73fc91bb94.scope - libcontainer container 4de031ec8e3d8f532484a78f8086faf11f6665eaf0cf2d83148a4b73fc91bb94. Sep 11 00:29:24.289307 kernel: docker0: port 1(veth021aa7d) entered blocking state Sep 11 00:29:24.289400 kernel: docker0: port 1(veth021aa7d) entered disabled state Sep 11 00:29:24.292132 kernel: veth021aa7d: entered allmulticast mode Sep 11 00:29:24.294968 kernel: veth021aa7d: entered promiscuous mode Sep 11 00:29:24.295748 systemd-networkd[1589]: veth021aa7d: Link UP Sep 11 00:29:24.316353 kernel: eth0: renamed from veth3a83cfd Sep 11 00:29:24.316404 kernel: docker0: port 1(veth021aa7d) entered blocking state Sep 11 00:29:24.322437 kernel: docker0: port 1(veth021aa7d) entered forwarding state Sep 11 00:29:24.322816 systemd-networkd[1589]: veth021aa7d: Gained carrier Sep 11 00:29:24.323058 systemd-networkd[1589]: docker0: Gained carrier Sep 11 00:29:24.359357 systemd[1]: docker-4de031ec8e3d8f532484a78f8086faf11f6665eaf0cf2d83148a4b73fc91bb94.scope: Deactivated successfully. Sep 11 00:29:24.386718 containerd[1895]: time="2025-09-11T00:29:24.386680214Z" level=info msg="shim disconnected" id=4de031ec8e3d8f532484a78f8086faf11f6665eaf0cf2d83148a4b73fc91bb94 namespace=moby Sep 11 00:29:24.386718 containerd[1895]: time="2025-09-11T00:29:24.386712312Z" level=warning msg="cleaning up after shim disconnected" id=4de031ec8e3d8f532484a78f8086faf11f6665eaf0cf2d83148a4b73fc91bb94 namespace=moby Sep 11 00:29:24.386718 containerd[1895]: time="2025-09-11T00:29:24.386721012Z" level=info msg="cleaning up dead shim" namespace=moby Sep 11 00:29:24.386930 dockerd[12966]: time="2025-09-11T00:29:24.386746811Z" level=info msg="ignoring event" container=4de031ec8e3d8f532484a78f8086faf11f6665eaf0cf2d83148a4b73fc91bb94 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Sep 11 00:29:24.419600 systemd-networkd[1589]: veth021aa7d: Lost carrier Sep 11 00:29:24.420349 kernel: docker0: port 1(veth021aa7d) entered disabled state Sep 11 00:29:24.420396 kernel: veth3a83cfd: renamed from eth0 Sep 11 00:29:24.440721 systemd-networkd[1589]: veth021aa7d: Link DOWN Sep 11 00:29:24.444345 kernel: docker0: port 1(veth021aa7d) entered disabled state Sep 11 00:29:24.450945 kernel: veth021aa7d (unregistering): left allmulticast mode Sep 11 00:29:24.450996 kernel: veth021aa7d (unregistering): left promiscuous mode Sep 11 00:29:24.454209 kernel: docker0: port 1(veth021aa7d) entered disabled state Sep 11 00:29:24.600169 sshd[13251]: Connection closed by 10.200.16.10 port 52674 Sep 11 00:29:24.600545 sshd-session[13249]: pam_unix(sshd:session): session closed for user core Sep 11 00:29:24.603358 systemd[1]: sshd@33-10.200.8.36:22-10.200.16.10:52674.service: Deactivated successfully. Sep 11 00:29:24.605001 systemd[1]: session-36.scope: Deactivated successfully. Sep 11 00:29:24.606316 systemd-logind[1883]: Session 36 logged out. Waiting for processes to exit. Sep 11 00:29:24.607355 systemd-logind[1883]: Removed session 36. Sep 11 00:29:24.825549 systemd-networkd[1589]: docker0: Lost carrier Sep 11 00:29:25.134678 systemd[1]: var-lib-docker-overlay2-94ceb74d8da0f67044fe60b2ae31d9f12334d0cf573c3e203bf259a97eac3efb-merged.mount: Deactivated successfully.