Aug 12 23:59:02.232696 kernel: Linux version 6.6.100-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.43 p3) 2.43.1) #1 SMP PREEMPT_DYNAMIC Tue Aug 12 21:47:31 -00 2025 Aug 12 23:59:02.232741 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=ca71ea747c3f0d1de8a5ffcd0cfb9d0a1a4c4755719a09093b0248fa3902b433 Aug 12 23:59:02.232760 kernel: BIOS-provided physical RAM map: Aug 12 23:59:02.232772 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Aug 12 23:59:02.232787 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Aug 12 23:59:02.232795 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Aug 12 23:59:02.232803 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Aug 12 23:59:02.232814 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Aug 12 23:59:02.232821 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Aug 12 23:59:02.232828 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Aug 12 23:59:02.232835 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Aug 12 23:59:02.232842 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Aug 12 23:59:02.232849 kernel: printk: bootconsole [earlyser0] enabled Aug 12 23:59:02.232856 kernel: NX (Execute Disable) protection: active Aug 12 23:59:02.232868 kernel: APIC: Static calls initialized Aug 12 23:59:02.232875 kernel: efi: EFI v2.7 by Microsoft Aug 12 23:59:02.232884 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3ee6da98 RNG=0x3ffd1018 Aug 12 23:59:02.232891 kernel: random: crng init done Aug 12 23:59:02.232899 kernel: secureboot: Secure boot disabled Aug 12 23:59:02.232906 kernel: SMBIOS 3.1.0 present. Aug 12 23:59:02.232914 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Aug 12 23:59:02.232921 kernel: Hypervisor detected: Microsoft Hyper-V Aug 12 23:59:02.232929 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Aug 12 23:59:02.232937 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Aug 12 23:59:02.232947 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Aug 12 23:59:02.232955 kernel: Hyper-V: Using hypercall for remote TLB flush Aug 12 23:59:02.232962 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Aug 12 23:59:02.232970 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Aug 12 23:59:02.232978 kernel: tsc: Marking TSC unstable due to running on Hyper-V Aug 12 23:59:02.232986 kernel: tsc: Detected 2593.993 MHz processor Aug 12 23:59:02.232994 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Aug 12 23:59:02.233003 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Aug 12 23:59:02.233010 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Aug 12 23:59:02.233021 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Aug 12 23:59:02.233029 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Aug 12 23:59:02.233037 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Aug 12 23:59:02.233044 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Aug 12 23:59:02.233052 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Aug 12 23:59:02.233059 kernel: Using GB pages for direct mapping Aug 12 23:59:02.233067 kernel: ACPI: Early table checksum verification disabled Aug 12 23:59:02.233079 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Aug 12 23:59:02.233127 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 12 23:59:02.233138 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 12 23:59:02.233146 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Aug 12 23:59:02.233154 kernel: ACPI: FACS 0x000000003FFFE000 000040 Aug 12 23:59:02.233163 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 12 23:59:02.233171 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 12 23:59:02.233183 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 12 23:59:02.233192 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 12 23:59:02.233200 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 12 23:59:02.233209 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 12 23:59:02.233217 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Aug 12 23:59:02.233225 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Aug 12 23:59:02.233233 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Aug 12 23:59:02.233242 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Aug 12 23:59:02.233253 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Aug 12 23:59:02.233261 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Aug 12 23:59:02.233270 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Aug 12 23:59:02.233278 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Aug 12 23:59:02.233286 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Aug 12 23:59:02.233294 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Aug 12 23:59:02.233302 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Aug 12 23:59:02.233310 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0 Aug 12 23:59:02.233318 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0 Aug 12 23:59:02.233329 kernel: SRAT: PXM 0 -> APIC 0x02 -> Node 0 Aug 12 23:59:02.233338 kernel: SRAT: PXM 0 -> APIC 0x03 -> Node 0 Aug 12 23:59:02.233346 kernel: SRAT: PXM 0 -> APIC 0x04 -> Node 0 Aug 12 23:59:02.233354 kernel: SRAT: PXM 0 -> APIC 0x05 -> Node 0 Aug 12 23:59:02.233362 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Aug 12 23:59:02.233370 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Aug 12 23:59:02.233378 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Aug 12 23:59:02.233386 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Aug 12 23:59:02.233395 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Aug 12 23:59:02.233406 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Aug 12 23:59:02.233414 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Aug 12 23:59:02.233422 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Aug 12 23:59:02.233431 kernel: NUMA: Node 0 [mem 0x00000000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00000000-0xfdfffffff] Aug 12 23:59:02.233439 kernel: NUMA: Node 0 [mem 0x00000000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00000000-0x5cbfffffff] Aug 12 23:59:02.233447 kernel: NODE_DATA(0) allocated [mem 0x5cbfff9000-0x5cbfffefff] Aug 12 23:59:02.233455 kernel: Zone ranges: Aug 12 23:59:02.233464 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Aug 12 23:59:02.233472 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Aug 12 23:59:02.233483 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Aug 12 23:59:02.233491 kernel: Movable zone start for each node Aug 12 23:59:02.233499 kernel: Early memory node ranges Aug 12 23:59:02.233507 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Aug 12 23:59:02.233515 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Aug 12 23:59:02.233523 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Aug 12 23:59:02.233531 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Aug 12 23:59:02.233540 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Aug 12 23:59:02.233548 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Aug 12 23:59:02.233559 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Aug 12 23:59:02.233567 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Aug 12 23:59:02.233575 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Aug 12 23:59:02.233583 kernel: ACPI: PM-Timer IO Port: 0x408 Aug 12 23:59:02.233591 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Aug 12 23:59:02.233599 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Aug 12 23:59:02.233607 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Aug 12 23:59:02.233616 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Aug 12 23:59:02.233624 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Aug 12 23:59:02.233635 kernel: smpboot: Allowing 6 CPUs, 0 hotplug CPUs Aug 12 23:59:02.233643 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Aug 12 23:59:02.233651 kernel: Booting paravirtualized kernel on Hyper-V Aug 12 23:59:02.233660 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Aug 12 23:59:02.233668 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Aug 12 23:59:02.233676 kernel: percpu: Embedded 58 pages/cpu s197096 r8192 d32280 u262144 Aug 12 23:59:02.233684 kernel: pcpu-alloc: s197096 r8192 d32280 u262144 alloc=1*2097152 Aug 12 23:59:02.233692 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Aug 12 23:59:02.233701 kernel: Hyper-V: PV spinlocks enabled Aug 12 23:59:02.233712 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Aug 12 23:59:02.233721 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=ca71ea747c3f0d1de8a5ffcd0cfb9d0a1a4c4755719a09093b0248fa3902b433 Aug 12 23:59:02.233730 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Aug 12 23:59:02.233738 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Aug 12 23:59:02.233746 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Aug 12 23:59:02.233754 kernel: Fallback order for Node 0: 0 Aug 12 23:59:02.233762 kernel: Built 1 zonelists, mobility grouping on. Total pages: 28899610 Aug 12 23:59:02.233829 kernel: Policy zone: Normal Aug 12 23:59:02.233844 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Aug 12 23:59:02.233870 kernel: software IO TLB: area num 8. Aug 12 23:59:02.233885 kernel: Memory: 115335932K/117439364K available (14336K kernel code, 2295K rwdata, 22872K rodata, 43504K init, 1572K bss, 2103176K reserved, 0K cma-reserved) Aug 12 23:59:02.233901 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Aug 12 23:59:02.233914 kernel: Kernel/User page tables isolation: enabled Aug 12 23:59:02.233927 kernel: ftrace: allocating 37942 entries in 149 pages Aug 12 23:59:02.233938 kernel: ftrace: allocated 149 pages with 4 groups Aug 12 23:59:02.233952 kernel: Dynamic Preempt: voluntary Aug 12 23:59:02.233965 kernel: rcu: Preemptible hierarchical RCU implementation. Aug 12 23:59:02.233977 kernel: rcu: RCU event tracing is enabled. Aug 12 23:59:02.233985 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Aug 12 23:59:02.233995 kernel: Trampoline variant of Tasks RCU enabled. Aug 12 23:59:02.234007 kernel: Rude variant of Tasks RCU enabled. Aug 12 23:59:02.234015 kernel: Tracing variant of Tasks RCU enabled. Aug 12 23:59:02.234024 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Aug 12 23:59:02.234033 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Aug 12 23:59:02.234044 kernel: Using NULL legacy PIC Aug 12 23:59:02.234053 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Aug 12 23:59:02.234062 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Aug 12 23:59:02.234070 kernel: Console: colour dummy device 80x25 Aug 12 23:59:02.234079 kernel: printk: console [tty1] enabled Aug 12 23:59:02.234087 kernel: printk: console [ttyS0] enabled Aug 12 23:59:02.234109 kernel: printk: bootconsole [earlyser0] disabled Aug 12 23:59:02.234118 kernel: ACPI: Core revision 20230628 Aug 12 23:59:02.234127 kernel: Failed to register legacy timer interrupt Aug 12 23:59:02.234135 kernel: APIC: Switch to symmetric I/O mode setup Aug 12 23:59:02.234148 kernel: Hyper-V: enabling crash_kexec_post_notifiers Aug 12 23:59:02.234156 kernel: Hyper-V: Using IPI hypercalls Aug 12 23:59:02.234172 kernel: APIC: send_IPI() replaced with hv_send_ipi() Aug 12 23:59:02.234186 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Aug 12 23:59:02.234195 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Aug 12 23:59:02.234204 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Aug 12 23:59:02.234213 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Aug 12 23:59:02.234224 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Aug 12 23:59:02.234233 kernel: Hyper-V: Using enlightened APIC (xapic mode) Aug 12 23:59:02.234246 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Aug 12 23:59:02.234254 kernel: APIC: read() replaced with hv_apic_read() Aug 12 23:59:02.234263 kernel: APIC: write() replaced with hv_apic_write() Aug 12 23:59:02.234272 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Aug 12 23:59:02.234280 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Aug 12 23:59:02.234289 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593993) Aug 12 23:59:02.234298 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Aug 12 23:59:02.234307 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Aug 12 23:59:02.234315 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Aug 12 23:59:02.234327 kernel: Spectre V2 : Mitigation: Retpolines Aug 12 23:59:02.234335 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Aug 12 23:59:02.234343 kernel: Speculative Store Bypass: Vulnerable Aug 12 23:59:02.234352 kernel: MDS: Mitigation: Clear CPU buffers Aug 12 23:59:02.234360 kernel: TAA: Mitigation: Clear CPU buffers Aug 12 23:59:02.234369 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Aug 12 23:59:02.234377 kernel: ITS: Mitigation: Aligned branch/return thunks Aug 12 23:59:02.234385 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Aug 12 23:59:02.234394 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Aug 12 23:59:02.234402 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Aug 12 23:59:02.234411 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Aug 12 23:59:02.234423 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Aug 12 23:59:02.234431 kernel: Freeing SMP alternatives memory: 32K Aug 12 23:59:02.234439 kernel: pid_max: default: 32768 minimum: 301 Aug 12 23:59:02.234448 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Aug 12 23:59:02.234457 kernel: landlock: Up and running. Aug 12 23:59:02.234465 kernel: SELinux: Initializing. Aug 12 23:59:02.234473 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Aug 12 23:59:02.234482 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Aug 12 23:59:02.234490 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Aug 12 23:59:02.234499 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Aug 12 23:59:02.234508 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Aug 12 23:59:02.234520 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Aug 12 23:59:02.234529 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Aug 12 23:59:02.234537 kernel: signal: max sigframe size: 1776 Aug 12 23:59:02.234546 kernel: rcu: Hierarchical SRCU implementation. Aug 12 23:59:02.234555 kernel: rcu: Max phase no-delay instances is 400. Aug 12 23:59:02.234563 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Aug 12 23:59:02.234572 kernel: smp: Bringing up secondary CPUs ... Aug 12 23:59:02.234580 kernel: smpboot: x86: Booting SMP configuration: Aug 12 23:59:02.234589 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Aug 12 23:59:02.234600 kernel: smp: Brought up 1 node, 6 CPUs Aug 12 23:59:02.234609 kernel: smpboot: Max logical packages: 1 Aug 12 23:59:02.234617 kernel: smpboot: Total of 6 processors activated (31127.91 BogoMIPS) Aug 12 23:59:02.234626 kernel: devtmpfs: initialized Aug 12 23:59:02.234634 kernel: x86/mm: Memory block size: 1024MB Aug 12 23:59:02.234643 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Aug 12 23:59:02.234652 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Aug 12 23:59:02.234660 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Aug 12 23:59:02.234669 kernel: pinctrl core: initialized pinctrl subsystem Aug 12 23:59:02.234680 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Aug 12 23:59:02.234689 kernel: audit: initializing netlink subsys (disabled) Aug 12 23:59:02.234698 kernel: audit: type=2000 audit(1755043140.031:1): state=initialized audit_enabled=0 res=1 Aug 12 23:59:02.234706 kernel: thermal_sys: Registered thermal governor 'step_wise' Aug 12 23:59:02.234715 kernel: thermal_sys: Registered thermal governor 'user_space' Aug 12 23:59:02.234723 kernel: cpuidle: using governor menu Aug 12 23:59:02.234732 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Aug 12 23:59:02.234740 kernel: dca service started, version 1.12.1 Aug 12 23:59:02.234749 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Aug 12 23:59:02.234760 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Aug 12 23:59:02.234769 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Aug 12 23:59:02.234778 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Aug 12 23:59:02.234787 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Aug 12 23:59:02.234795 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Aug 12 23:59:02.234804 kernel: ACPI: Added _OSI(Module Device) Aug 12 23:59:02.234812 kernel: ACPI: Added _OSI(Processor Device) Aug 12 23:59:02.234821 kernel: ACPI: Added _OSI(Processor Aggregator Device) Aug 12 23:59:02.234829 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Aug 12 23:59:02.234841 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Aug 12 23:59:02.234849 kernel: ACPI: Interpreter enabled Aug 12 23:59:02.234858 kernel: ACPI: PM: (supports S0 S5) Aug 12 23:59:02.234867 kernel: ACPI: Using IOAPIC for interrupt routing Aug 12 23:59:02.234875 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Aug 12 23:59:02.234884 kernel: PCI: Ignoring E820 reservations for host bridge windows Aug 12 23:59:02.234893 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Aug 12 23:59:02.234901 kernel: iommu: Default domain type: Translated Aug 12 23:59:02.234910 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Aug 12 23:59:02.234922 kernel: efivars: Registered efivars operations Aug 12 23:59:02.234930 kernel: PCI: Using ACPI for IRQ routing Aug 12 23:59:02.234939 kernel: PCI: System does not support PCI Aug 12 23:59:02.234947 kernel: vgaarb: loaded Aug 12 23:59:02.234956 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Aug 12 23:59:02.234965 kernel: VFS: Disk quotas dquot_6.6.0 Aug 12 23:59:02.234973 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Aug 12 23:59:02.234982 kernel: pnp: PnP ACPI init Aug 12 23:59:02.234990 kernel: pnp: PnP ACPI: found 3 devices Aug 12 23:59:02.235002 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Aug 12 23:59:02.235011 kernel: NET: Registered PF_INET protocol family Aug 12 23:59:02.235020 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Aug 12 23:59:02.235028 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Aug 12 23:59:02.235037 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Aug 12 23:59:02.235046 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Aug 12 23:59:02.235054 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Aug 12 23:59:02.235063 kernel: TCP: Hash tables configured (established 524288 bind 65536) Aug 12 23:59:02.235074 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Aug 12 23:59:02.235083 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Aug 12 23:59:02.235102 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Aug 12 23:59:02.235111 kernel: NET: Registered PF_XDP protocol family Aug 12 23:59:02.235120 kernel: PCI: CLS 0 bytes, default 64 Aug 12 23:59:02.235129 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Aug 12 23:59:02.235137 kernel: software IO TLB: mapped [mem 0x000000003ad78000-0x000000003ed78000] (64MB) Aug 12 23:59:02.235146 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Aug 12 23:59:02.235154 kernel: Initialise system trusted keyrings Aug 12 23:59:02.235167 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Aug 12 23:59:02.235175 kernel: Key type asymmetric registered Aug 12 23:59:02.235184 kernel: Asymmetric key parser 'x509' registered Aug 12 23:59:02.235193 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Aug 12 23:59:02.235201 kernel: io scheduler mq-deadline registered Aug 12 23:59:02.235210 kernel: io scheduler kyber registered Aug 12 23:59:02.235219 kernel: io scheduler bfq registered Aug 12 23:59:02.235227 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Aug 12 23:59:02.235236 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Aug 12 23:59:02.235244 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Aug 12 23:59:02.235256 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Aug 12 23:59:02.235265 kernel: i8042: PNP: No PS/2 controller found. Aug 12 23:59:02.235534 kernel: rtc_cmos 00:02: registered as rtc0 Aug 12 23:59:02.235660 kernel: rtc_cmos 00:02: setting system clock to 2025-08-12T23:59:01 UTC (1755043141) Aug 12 23:59:02.235766 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Aug 12 23:59:02.235781 kernel: intel_pstate: CPU model not supported Aug 12 23:59:02.235790 kernel: efifb: probing for efifb Aug 12 23:59:02.235805 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Aug 12 23:59:02.235814 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Aug 12 23:59:02.235823 kernel: efifb: scrolling: redraw Aug 12 23:59:02.235831 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Aug 12 23:59:02.235840 kernel: Console: switching to colour frame buffer device 128x48 Aug 12 23:59:02.235849 kernel: fb0: EFI VGA frame buffer device Aug 12 23:59:02.235858 kernel: pstore: Using crash dump compression: deflate Aug 12 23:59:02.235867 kernel: pstore: Registered efi_pstore as persistent store backend Aug 12 23:59:02.235875 kernel: NET: Registered PF_INET6 protocol family Aug 12 23:59:02.235887 kernel: Segment Routing with IPv6 Aug 12 23:59:02.235896 kernel: In-situ OAM (IOAM) with IPv6 Aug 12 23:59:02.235905 kernel: NET: Registered PF_PACKET protocol family Aug 12 23:59:02.235914 kernel: Key type dns_resolver registered Aug 12 23:59:02.235923 kernel: IPI shorthand broadcast: enabled Aug 12 23:59:02.235932 kernel: sched_clock: Marking stable (1841006400, 111805000)->(2307962800, -355151400) Aug 12 23:59:02.235941 kernel: registered taskstats version 1 Aug 12 23:59:02.235949 kernel: Loading compiled-in X.509 certificates Aug 12 23:59:02.235958 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.100-flatcar: dfd2b306eb54324ea79eea0261f8d493924aeeeb' Aug 12 23:59:02.235970 kernel: Key type .fscrypt registered Aug 12 23:59:02.235979 kernel: Key type fscrypt-provisioning registered Aug 12 23:59:02.235988 kernel: ima: No TPM chip found, activating TPM-bypass! Aug 12 23:59:02.235996 kernel: ima: Allocated hash algorithm: sha1 Aug 12 23:59:02.236005 kernel: ima: No architecture policies found Aug 12 23:59:02.236014 kernel: clk: Disabling unused clocks Aug 12 23:59:02.236022 kernel: Freeing unused kernel image (initmem) memory: 43504K Aug 12 23:59:02.236031 kernel: Write protecting the kernel read-only data: 38912k Aug 12 23:59:02.236040 kernel: Freeing unused kernel image (rodata/data gap) memory: 1704K Aug 12 23:59:02.236051 kernel: Run /init as init process Aug 12 23:59:02.236060 kernel: with arguments: Aug 12 23:59:02.236069 kernel: /init Aug 12 23:59:02.236078 kernel: with environment: Aug 12 23:59:02.236086 kernel: HOME=/ Aug 12 23:59:02.236111 kernel: TERM=linux Aug 12 23:59:02.236120 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Aug 12 23:59:02.236130 systemd[1]: Successfully made /usr/ read-only. Aug 12 23:59:02.236142 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) Aug 12 23:59:02.236157 systemd[1]: Detected virtualization microsoft. Aug 12 23:59:02.236166 systemd[1]: Detected architecture x86-64. Aug 12 23:59:02.236175 systemd[1]: Running in initrd. Aug 12 23:59:02.236184 systemd[1]: No hostname configured, using default hostname. Aug 12 23:59:02.236193 systemd[1]: Hostname set to . Aug 12 23:59:02.236202 systemd[1]: Initializing machine ID from random generator. Aug 12 23:59:02.236212 systemd[1]: Queued start job for default target initrd.target. Aug 12 23:59:02.236224 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 12 23:59:02.236233 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 12 23:59:02.236243 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Aug 12 23:59:02.236253 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Aug 12 23:59:02.236262 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Aug 12 23:59:02.236272 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Aug 12 23:59:02.236283 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Aug 12 23:59:02.236302 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Aug 12 23:59:02.236320 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 12 23:59:02.236331 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Aug 12 23:59:02.236340 systemd[1]: Reached target paths.target - Path Units. Aug 12 23:59:02.236349 systemd[1]: Reached target slices.target - Slice Units. Aug 12 23:59:02.236358 systemd[1]: Reached target swap.target - Swaps. Aug 12 23:59:02.236368 systemd[1]: Reached target timers.target - Timer Units. Aug 12 23:59:02.236382 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Aug 12 23:59:02.236395 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Aug 12 23:59:02.236405 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Aug 12 23:59:02.236414 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Aug 12 23:59:02.236423 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Aug 12 23:59:02.236433 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Aug 12 23:59:02.236442 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Aug 12 23:59:02.236451 systemd[1]: Reached target sockets.target - Socket Units. Aug 12 23:59:02.236461 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Aug 12 23:59:02.236470 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Aug 12 23:59:02.236482 systemd[1]: Finished network-cleanup.service - Network Cleanup. Aug 12 23:59:02.236491 systemd[1]: Starting systemd-fsck-usr.service... Aug 12 23:59:02.236501 systemd[1]: Starting systemd-journald.service - Journal Service... Aug 12 23:59:02.236510 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Aug 12 23:59:02.236519 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 12 23:59:02.236552 systemd-journald[205]: Collecting audit messages is disabled. Aug 12 23:59:02.236581 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Aug 12 23:59:02.236592 systemd-journald[205]: Journal started Aug 12 23:59:02.236613 systemd-journald[205]: Runtime Journal (/run/log/journal/c14f24bf608a47f4880f4400bc375143) is 8M, max 2.2G, 2.1G free. Aug 12 23:59:02.234210 systemd-modules-load[207]: Inserted module 'overlay' Aug 12 23:59:02.248186 systemd[1]: Started systemd-journald.service - Journal Service. Aug 12 23:59:02.248567 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Aug 12 23:59:02.249108 systemd[1]: Finished systemd-fsck-usr.service. Aug 12 23:59:02.251556 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Aug 12 23:59:02.252480 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Aug 12 23:59:02.298310 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Aug 12 23:59:02.316276 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Aug 12 23:59:02.307184 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 12 23:59:02.317969 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Aug 12 23:59:02.329463 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Aug 12 23:59:02.340380 kernel: Bridge firewalling registered Aug 12 23:59:02.337591 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Aug 12 23:59:02.357966 systemd-modules-load[207]: Inserted module 'br_netfilter' Aug 12 23:59:02.358934 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Aug 12 23:59:02.360268 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Aug 12 23:59:02.374967 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 12 23:59:02.386135 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Aug 12 23:59:02.391698 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Aug 12 23:59:02.395574 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 12 23:59:02.397789 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Aug 12 23:59:02.428890 dracut-cmdline[237]: dracut-dracut-053 Aug 12 23:59:02.431908 dracut-cmdline[237]: Using kernel command line parameters: rd.driver.pre=btrfs rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=ca71ea747c3f0d1de8a5ffcd0cfb9d0a1a4c4755719a09093b0248fa3902b433 Aug 12 23:59:02.456780 systemd-resolved[243]: Positive Trust Anchors: Aug 12 23:59:02.456799 systemd-resolved[243]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Aug 12 23:59:02.456830 systemd-resolved[243]: 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 Aug 12 23:59:02.459490 systemd-resolved[243]: Defaulting to hostname 'linux'. Aug 12 23:59:02.460628 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Aug 12 23:59:02.470034 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Aug 12 23:59:02.601126 kernel: SCSI subsystem initialized Aug 12 23:59:02.613124 kernel: Loading iSCSI transport class v2.0-870. Aug 12 23:59:02.626134 kernel: iscsi: registered transport (tcp) Aug 12 23:59:02.650385 kernel: iscsi: registered transport (qla4xxx) Aug 12 23:59:02.650464 kernel: QLogic iSCSI HBA Driver Aug 12 23:59:02.691303 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Aug 12 23:59:02.716261 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Aug 12 23:59:02.753077 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Aug 12 23:59:02.753160 kernel: device-mapper: uevent: version 1.0.3 Aug 12 23:59:02.757749 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Aug 12 23:59:02.810131 kernel: raid6: avx2x4 gen() 27345 MB/s Aug 12 23:59:02.829105 kernel: raid6: avx2x2 gen() 27193 MB/s Aug 12 23:59:02.850421 kernel: raid6: avx2x1 gen() 24612 MB/s Aug 12 23:59:02.850485 kernel: raid6: using algorithm avx2x4 gen() 27345 MB/s Aug 12 23:59:02.873374 kernel: raid6: .... xor() 7907 MB/s, rmw enabled Aug 12 23:59:02.873417 kernel: raid6: using avx2x2 recovery algorithm Aug 12 23:59:02.897123 kernel: xor: automatically using best checksumming function avx Aug 12 23:59:03.060128 kernel: Btrfs loaded, zoned=no, fsverity=no Aug 12 23:59:03.071867 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Aug 12 23:59:03.096277 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 12 23:59:03.118777 systemd-udevd[428]: Using default interface naming scheme 'v255'. Aug 12 23:59:03.131923 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 12 23:59:03.151240 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Aug 12 23:59:03.167604 dracut-pre-trigger[440]: rd.md=0: removing MD RAID activation Aug 12 23:59:03.196163 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Aug 12 23:59:03.220268 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Aug 12 23:59:03.279539 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Aug 12 23:59:03.295284 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Aug 12 23:59:03.310109 kernel: cryptd: max_cpu_qlen set to 1000 Aug 12 23:59:03.316035 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Aug 12 23:59:03.325572 kernel: AVX2 version of gcm_enc/dec engaged. Aug 12 23:59:03.325616 kernel: AES CTR mode by8 optimization enabled Aug 12 23:59:03.336621 kernel: hv_vmbus: Vmbus version:4.0 Aug 12 23:59:03.342125 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Aug 12 23:59:03.356318 kernel: pps_core: LinuxPPS API ver. 1 registered Aug 12 23:59:03.356342 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Aug 12 23:59:03.356355 kernel: hv_vmbus: registering driver hv_pci Aug 12 23:59:03.354932 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 12 23:59:03.371045 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Aug 12 23:59:03.369176 systemd[1]: Reached target remote-fs.target - Remote File Systems. Aug 12 23:59:03.422526 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Aug 12 23:59:03.422717 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Aug 12 23:59:03.422878 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Aug 12 23:59:03.423017 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Aug 12 23:59:03.423169 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 Aug 12 23:59:03.423201 kernel: pci 0001:00:00.0: reg 0x10: [mem 0x41000000-0x41ffffff] Aug 12 23:59:03.423221 kernel: pci 0001:00:00.0: reg 0x14: [mem 0x1000000000-0x13ffffffff 64bit pref] Aug 12 23:59:03.423240 kernel: pci 0001:00:00.0: reg 0x1c: [mem 0x1400000000-0x1401ffffff 64bit pref] Aug 12 23:59:03.423258 kernel: pci 0001:00:00.0: Enabling HDA controller Aug 12 23:59:03.423277 kernel: hid: raw HID events driver (C) Jiri Kosina Aug 12 23:59:03.406980 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Aug 12 23:59:03.407291 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 12 23:59:03.427727 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Aug 12 23:59:03.452795 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Aug 12 23:59:03.452971 kernel: pci 0001:00:00.0: BAR 1: assigned [mem 0x1000000000-0x13ffffffff 64bit pref] Aug 12 23:59:03.453154 kernel: pci 0001:00:00.0: BAR 3: assigned [mem 0x1400000000-0x1401ffffff 64bit pref] Aug 12 23:59:03.453300 kernel: pci 0001:00:00.0: BAR 0: assigned [mem 0x41000000-0x41ffffff] Aug 12 23:59:03.463111 kernel: hv_vmbus: registering driver hyperv_keyboard Aug 12 23:59:03.463141 kernel: hv_vmbus: registering driver hv_storvsc Aug 12 23:59:03.466145 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 Aug 12 23:59:03.466400 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Aug 12 23:59:03.490257 kernel: scsi host0: storvsc_host_t Aug 12 23:59:03.490642 kernel: scsi host1: storvsc_host_t Aug 12 23:59:03.490880 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Aug 12 23:59:03.490918 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Aug 12 23:59:03.490981 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Aug 12 23:59:03.489477 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Aug 12 23:59:03.510721 kernel: hv_vmbus: registering driver hid_hyperv Aug 12 23:59:03.489761 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Aug 12 23:59:03.507620 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Aug 12 23:59:03.532255 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Aug 12 23:59:03.532279 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Aug 12 23:59:03.523213 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 12 23:59:03.534481 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Aug 12 23:59:03.535085 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Aug 12 23:59:03.552896 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Aug 12 23:59:03.553055 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Aug 12 23:59:03.575106 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Aug 12 23:59:03.585140 kernel: PTP clock support registered Aug 12 23:59:03.585195 kernel: hv_vmbus: registering driver hv_netvsc Aug 12 23:59:03.586269 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 12 23:59:03.604289 kernel: hv_utils: Registering HyperV Utility Driver Aug 12 23:59:03.604340 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Aug 12 23:59:03.604590 kernel: hv_vmbus: registering driver hv_utils Aug 12 23:59:03.604606 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Aug 12 23:59:03.607109 kernel: hv_utils: Heartbeat IC version 3.0 Aug 12 23:59:03.612190 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Aug 12 23:59:03.612393 kernel: hv_utils: TimeSync IC version 4.0 Aug 12 23:59:03.617828 kernel: hv_utils: Shutdown IC version 3.2 Aug 12 23:59:03.674088 systemd-resolved[243]: Clock change detected. Flushing caches. Aug 12 23:59:03.692766 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 12 23:59:03.710167 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Aug 12 23:59:03.710426 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Aug 12 23:59:03.710592 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Aug 12 23:59:03.710737 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks Aug 12 23:59:03.710877 kernel: sd 0:0:0:1: [sdb] Write Protect is off Aug 12 23:59:03.715625 kernel: sd 0:0:0:0: [sda] Write Protect is off Aug 12 23:59:03.715797 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Aug 12 23:59:03.717204 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Aug 12 23:59:03.744088 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Aug 12 23:59:03.744273 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Aug 12 23:59:03.744423 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Aug 12 23:59:03.744563 kernel: sdb: sdb1 Aug 12 23:59:03.744578 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Aug 12 23:59:03.744590 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk Aug 12 23:59:03.744727 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Aug 12 23:59:03.774363 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 12 23:59:04.130214 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Aug 12 23:59:04.196043 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 scanned by (udev-worker) (474) Aug 12 23:59:04.218182 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Aug 12 23:59:04.239038 kernel: BTRFS: device fsid 88a9bed3-d26b-40c9-82ba-dbb7d44acae7 devid 1 transid 45 /dev/sda3 scanned by (udev-worker) (496) Aug 12 23:59:04.240561 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Aug 12 23:59:04.272138 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Aug 12 23:59:04.273891 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Aug 12 23:59:04.300150 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Aug 12 23:59:04.312213 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Aug 12 23:59:04.319036 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Aug 12 23:59:05.326488 disk-uuid[641]: The operation has completed successfully. Aug 12 23:59:05.330313 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Aug 12 23:59:06.867075 systemd[1]: disk-uuid.service: Deactivated successfully. Aug 12 23:59:06.867197 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Aug 12 23:59:06.889202 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Aug 12 23:59:06.898127 sh[728]: Success Aug 12 23:59:06.932043 kernel: device-mapper: verity: sha256 using implementation "sha256-avx2" Aug 12 23:59:07.103648 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Aug 12 23:59:07.121534 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Aug 12 23:59:07.123255 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Aug 12 23:59:07.147718 kernel: BTRFS info (device dm-0): first mount of filesystem 88a9bed3-d26b-40c9-82ba-dbb7d44acae7 Aug 12 23:59:07.147760 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Aug 12 23:59:07.165119 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Aug 12 23:59:07.168833 kernel: BTRFS info (device dm-0): disabling log replay at mount time Aug 12 23:59:07.171979 kernel: BTRFS info (device dm-0): using free space tree Aug 12 23:59:07.375001 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Aug 12 23:59:07.377292 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Aug 12 23:59:07.398188 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Aug 12 23:59:07.406557 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Aug 12 23:59:07.428519 kernel: BTRFS info (device sda6): first mount of filesystem fdf7217d-4a76-4a93-98b1-684d9c141517 Aug 12 23:59:07.428560 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Aug 12 23:59:07.431613 kernel: BTRFS info (device sda6): using free space tree Aug 12 23:59:07.450035 kernel: BTRFS info (device sda6): auto enabling async discard Aug 12 23:59:07.459043 kernel: BTRFS info (device sda6): last unmount of filesystem fdf7217d-4a76-4a93-98b1-684d9c141517 Aug 12 23:59:07.464200 systemd[1]: Finished ignition-setup.service - Ignition (setup). Aug 12 23:59:07.477203 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Aug 12 23:59:07.540843 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Aug 12 23:59:07.560221 systemd[1]: Starting systemd-networkd.service - Network Configuration... Aug 12 23:59:07.590217 systemd-networkd[910]: lo: Link UP Aug 12 23:59:07.590229 systemd-networkd[910]: lo: Gained carrier Aug 12 23:59:07.591385 systemd-networkd[910]: Enumeration completed Aug 12 23:59:07.591484 systemd[1]: Started systemd-networkd.service - Network Configuration. Aug 12 23:59:07.591760 systemd-networkd[910]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 12 23:59:07.591765 systemd-networkd[910]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Aug 12 23:59:07.592570 systemd-networkd[910]: eth0: Link UP Aug 12 23:59:07.592747 systemd-networkd[910]: eth0: Gained carrier Aug 12 23:59:07.592756 systemd-networkd[910]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 12 23:59:07.598371 systemd[1]: Reached target network.target - Network. Aug 12 23:59:07.660071 systemd-networkd[910]: eth0: DHCPv4 address 10.200.8.10/24, gateway 10.200.8.1 acquired from 168.63.129.16 Aug 12 23:59:08.396334 ignition[835]: Ignition 2.20.0 Aug 12 23:59:08.396347 ignition[835]: Stage: fetch-offline Aug 12 23:59:08.396403 ignition[835]: no configs at "/usr/lib/ignition/base.d" Aug 12 23:59:08.396416 ignition[835]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Aug 12 23:59:08.396525 ignition[835]: parsed url from cmdline: "" Aug 12 23:59:08.396529 ignition[835]: no config URL provided Aug 12 23:59:08.396536 ignition[835]: reading system config file "/usr/lib/ignition/user.ign" Aug 12 23:59:08.396546 ignition[835]: no config at "/usr/lib/ignition/user.ign" Aug 12 23:59:08.396552 ignition[835]: failed to fetch config: resource requires networking Aug 12 23:59:08.396742 ignition[835]: Ignition finished successfully Aug 12 23:59:08.427625 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Aug 12 23:59:08.448148 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Aug 12 23:59:08.460534 ignition[924]: Ignition 2.20.0 Aug 12 23:59:08.460550 ignition[924]: Stage: fetch Aug 12 23:59:08.460715 ignition[924]: no configs at "/usr/lib/ignition/base.d" Aug 12 23:59:08.460728 ignition[924]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Aug 12 23:59:08.460835 ignition[924]: parsed url from cmdline: "" Aug 12 23:59:08.460839 ignition[924]: no config URL provided Aug 12 23:59:08.460846 ignition[924]: reading system config file "/usr/lib/ignition/user.ign" Aug 12 23:59:08.460857 ignition[924]: no config at "/usr/lib/ignition/user.ign" Aug 12 23:59:08.460885 ignition[924]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Aug 12 23:59:08.556894 ignition[924]: GET result: OK Aug 12 23:59:08.556952 ignition[924]: config has been read from IMDS userdata Aug 12 23:59:08.560805 unknown[924]: fetched base config from "system" Aug 12 23:59:08.556966 ignition[924]: parsing config with SHA512: 30606cce05ea18e9287b760c6c21a341fb4ffd3f1461b72e6399a585bcfa402aea9297214dcdb4b131bb98eb9564778cc6fceb68c04207263b87b5252c0d13e1 Aug 12 23:59:08.560813 unknown[924]: fetched base config from "system" Aug 12 23:59:08.560994 ignition[924]: fetch: fetch complete Aug 12 23:59:08.560819 unknown[924]: fetched user config from "azure" Aug 12 23:59:08.561000 ignition[924]: fetch: fetch passed Aug 12 23:59:08.567862 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Aug 12 23:59:08.561065 ignition[924]: Ignition finished successfully Aug 12 23:59:08.596172 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Aug 12 23:59:08.617673 ignition[933]: Ignition 2.20.0 Aug 12 23:59:08.617685 ignition[933]: Stage: kargs Aug 12 23:59:08.617868 ignition[933]: no configs at "/usr/lib/ignition/base.d" Aug 12 23:59:08.617882 ignition[933]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Aug 12 23:59:08.626547 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Aug 12 23:59:08.618474 ignition[933]: kargs: kargs passed Aug 12 23:59:08.618524 ignition[933]: Ignition finished successfully Aug 12 23:59:08.650159 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Aug 12 23:59:08.662914 ignition[943]: Ignition 2.20.0 Aug 12 23:59:08.662929 ignition[943]: Stage: disks Aug 12 23:59:08.663135 ignition[943]: no configs at "/usr/lib/ignition/base.d" Aug 12 23:59:08.663153 ignition[943]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Aug 12 23:59:08.668819 systemd[1]: Finished ignition-disks.service - Ignition (disks). Aug 12 23:59:08.663728 ignition[943]: disks: disks passed Aug 12 23:59:08.675297 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Aug 12 23:59:08.663780 ignition[943]: Ignition finished successfully Aug 12 23:59:08.684391 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Aug 12 23:59:08.693178 systemd[1]: Reached target local-fs.target - Local File Systems. Aug 12 23:59:08.694706 systemd[1]: Reached target sysinit.target - System Initialization. Aug 12 23:59:08.702128 systemd[1]: Reached target basic.target - Basic System. Aug 12 23:59:08.732193 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Aug 12 23:59:08.785328 systemd-fsck[954]: ROOT: clean, 14/7326000 files, 477710/7359488 blocks Aug 12 23:59:08.790526 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Aug 12 23:59:08.806144 systemd[1]: Mounting sysroot.mount - /sysroot... Aug 12 23:59:08.905033 kernel: EXT4-fs (sda9): mounted filesystem 27db109b-2440-48a3-909e-fd8973275523 r/w with ordered data mode. Quota mode: none. Aug 12 23:59:08.905729 systemd[1]: Mounted sysroot.mount - /sysroot. Aug 12 23:59:08.907818 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Aug 12 23:59:08.951099 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Aug 12 23:59:08.952703 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Aug 12 23:59:08.979876 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/sda6 scanned by mount (965) Aug 12 23:59:08.979900 kernel: BTRFS info (device sda6): first mount of filesystem fdf7217d-4a76-4a93-98b1-684d9c141517 Aug 12 23:59:08.979915 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Aug 12 23:59:08.979936 kernel: BTRFS info (device sda6): using free space tree Aug 12 23:59:08.979950 kernel: BTRFS info (device sda6): auto enabling async discard Aug 12 23:59:08.969803 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Aug 12 23:59:08.980587 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Aug 12 23:59:08.980620 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Aug 12 23:59:09.007277 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Aug 12 23:59:09.012102 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Aug 12 23:59:09.019752 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Aug 12 23:59:09.041119 systemd-networkd[910]: eth0: Gained IPv6LL Aug 12 23:59:09.562478 coreos-metadata[967]: Aug 12 23:59:09.562 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Aug 12 23:59:09.568526 coreos-metadata[967]: Aug 12 23:59:09.564 INFO Fetch successful Aug 12 23:59:09.568526 coreos-metadata[967]: Aug 12 23:59:09.564 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Aug 12 23:59:09.580522 coreos-metadata[967]: Aug 12 23:59:09.580 INFO Fetch successful Aug 12 23:59:09.592405 coreos-metadata[967]: Aug 12 23:59:09.592 INFO wrote hostname ci-4230.2.2-a-2f6de76cbe to /sysroot/etc/hostname Aug 12 23:59:09.594319 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Aug 12 23:59:09.703667 initrd-setup-root[996]: cut: /sysroot/etc/passwd: No such file or directory Aug 12 23:59:09.719251 initrd-setup-root[1003]: cut: /sysroot/etc/group: No such file or directory Aug 12 23:59:09.726668 initrd-setup-root[1010]: cut: /sysroot/etc/shadow: No such file or directory Aug 12 23:59:09.731277 initrd-setup-root[1017]: cut: /sysroot/etc/gshadow: No such file or directory Aug 12 23:59:11.092122 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Aug 12 23:59:11.118150 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Aug 12 23:59:11.119677 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Aug 12 23:59:11.137485 systemd[1]: sysroot-oem.mount: Deactivated successfully. Aug 12 23:59:11.147831 kernel: BTRFS info (device sda6): last unmount of filesystem fdf7217d-4a76-4a93-98b1-684d9c141517 Aug 12 23:59:11.163808 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Aug 12 23:59:11.174973 ignition[1085]: INFO : Ignition 2.20.0 Aug 12 23:59:11.174973 ignition[1085]: INFO : Stage: mount Aug 12 23:59:11.174973 ignition[1085]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 12 23:59:11.174973 ignition[1085]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Aug 12 23:59:11.174973 ignition[1085]: INFO : mount: mount passed Aug 12 23:59:11.174973 ignition[1085]: INFO : Ignition finished successfully Aug 12 23:59:11.176043 systemd[1]: Finished ignition-mount.service - Ignition (mount). Aug 12 23:59:11.183243 systemd[1]: Starting ignition-files.service - Ignition (files)... Aug 12 23:59:11.197782 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Aug 12 23:59:11.226038 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/sda6 scanned by mount (1100) Aug 12 23:59:11.226074 kernel: BTRFS info (device sda6): first mount of filesystem fdf7217d-4a76-4a93-98b1-684d9c141517 Aug 12 23:59:11.235453 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Aug 12 23:59:11.238789 kernel: BTRFS info (device sda6): using free space tree Aug 12 23:59:11.245033 kernel: BTRFS info (device sda6): auto enabling async discard Aug 12 23:59:11.246100 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Aug 12 23:59:11.277972 ignition[1117]: INFO : Ignition 2.20.0 Aug 12 23:59:11.277972 ignition[1117]: INFO : Stage: files Aug 12 23:59:11.283963 ignition[1117]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 12 23:59:11.283963 ignition[1117]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Aug 12 23:59:11.283963 ignition[1117]: DEBUG : files: compiled without relabeling support, skipping Aug 12 23:59:11.297333 ignition[1117]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Aug 12 23:59:11.297333 ignition[1117]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Aug 12 23:59:11.383721 ignition[1117]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Aug 12 23:59:11.389239 ignition[1117]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Aug 12 23:59:11.389239 ignition[1117]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Aug 12 23:59:11.384453 unknown[1117]: wrote ssh authorized keys file for user: core Aug 12 23:59:11.466806 ignition[1117]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Aug 12 23:59:11.474071 ignition[1117]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Aug 12 23:59:11.474071 ignition[1117]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Aug 12 23:59:11.474071 ignition[1117]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Aug 12 23:59:11.474071 ignition[1117]: INFO : files: files passed Aug 12 23:59:11.474071 ignition[1117]: INFO : Ignition finished successfully Aug 12 23:59:11.475601 systemd[1]: Finished ignition-files.service - Ignition (files). Aug 12 23:59:11.506189 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Aug 12 23:59:11.514473 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Aug 12 23:59:11.522896 systemd[1]: ignition-quench.service: Deactivated successfully. Aug 12 23:59:11.523044 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Aug 12 23:59:11.547029 initrd-setup-root-after-ignition[1149]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Aug 12 23:59:11.553320 initrd-setup-root-after-ignition[1149]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Aug 12 23:59:11.559828 initrd-setup-root-after-ignition[1153]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Aug 12 23:59:11.567000 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Aug 12 23:59:11.577458 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Aug 12 23:59:11.593184 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Aug 12 23:59:11.616138 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Aug 12 23:59:11.616245 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Aug 12 23:59:11.618583 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Aug 12 23:59:11.631255 systemd[1]: Reached target initrd.target - Initrd Default Target. Aug 12 23:59:11.639387 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Aug 12 23:59:11.640325 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Aug 12 23:59:11.665605 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Aug 12 23:59:11.668277 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Aug 12 23:59:11.684333 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Aug 12 23:59:11.685929 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 12 23:59:11.695097 systemd[1]: Stopped target timers.target - Timer Units. Aug 12 23:59:11.704814 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Aug 12 23:59:11.704953 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Aug 12 23:59:11.719140 systemd[1]: Stopped target initrd.target - Initrd Default Target. Aug 12 23:59:11.727582 systemd[1]: Stopped target basic.target - Basic System. Aug 12 23:59:11.730570 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Aug 12 23:59:11.740180 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Aug 12 23:59:11.749012 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Aug 12 23:59:11.758172 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Aug 12 23:59:11.767089 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Aug 12 23:59:11.775215 systemd[1]: Stopped target sysinit.target - System Initialization. Aug 12 23:59:11.785118 systemd[1]: Stopped target local-fs.target - Local File Systems. Aug 12 23:59:11.793158 systemd[1]: Stopped target swap.target - Swaps. Aug 12 23:59:11.800833 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Aug 12 23:59:11.800983 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Aug 12 23:59:11.813696 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Aug 12 23:59:11.822088 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 12 23:59:11.831339 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Aug 12 23:59:11.835998 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 12 23:59:11.844725 systemd[1]: dracut-initqueue.service: Deactivated successfully. Aug 12 23:59:11.844846 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Aug 12 23:59:11.858084 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Aug 12 23:59:11.858218 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Aug 12 23:59:11.867312 systemd[1]: ignition-files.service: Deactivated successfully. Aug 12 23:59:11.867427 systemd[1]: Stopped ignition-files.service - Ignition (files). Aug 12 23:59:11.875813 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Aug 12 23:59:11.875931 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Aug 12 23:59:11.905180 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Aug 12 23:59:11.906040 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Aug 12 23:59:11.906232 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Aug 12 23:59:11.918624 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Aug 12 23:59:11.924933 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Aug 12 23:59:11.947108 ignition[1173]: INFO : Ignition 2.20.0 Aug 12 23:59:11.947108 ignition[1173]: INFO : Stage: umount Aug 12 23:59:11.947108 ignition[1173]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 12 23:59:11.947108 ignition[1173]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Aug 12 23:59:11.947108 ignition[1173]: INFO : umount: umount passed Aug 12 23:59:11.947108 ignition[1173]: INFO : Ignition finished successfully Aug 12 23:59:11.925088 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Aug 12 23:59:11.934303 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Aug 12 23:59:11.934500 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Aug 12 23:59:11.948849 systemd[1]: ignition-mount.service: Deactivated successfully. Aug 12 23:59:11.949171 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Aug 12 23:59:11.953678 systemd[1]: initrd-cleanup.service: Deactivated successfully. Aug 12 23:59:11.953775 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Aug 12 23:59:11.967092 systemd[1]: sysroot-boot.mount: Deactivated successfully. Aug 12 23:59:11.968138 systemd[1]: ignition-disks.service: Deactivated successfully. Aug 12 23:59:11.968206 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Aug 12 23:59:11.972196 systemd[1]: ignition-kargs.service: Deactivated successfully. Aug 12 23:59:11.972249 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Aug 12 23:59:11.978953 systemd[1]: ignition-fetch.service: Deactivated successfully. Aug 12 23:59:11.979003 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Aug 12 23:59:11.989638 systemd[1]: Stopped target network.target - Network. Aug 12 23:59:11.997440 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Aug 12 23:59:11.997501 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Aug 12 23:59:11.999673 systemd[1]: Stopped target paths.target - Path Units. Aug 12 23:59:12.017617 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Aug 12 23:59:12.023075 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 12 23:59:12.026459 systemd[1]: Stopped target slices.target - Slice Units. Aug 12 23:59:12.041910 systemd[1]: Stopped target sockets.target - Socket Units. Aug 12 23:59:12.049220 systemd[1]: iscsid.socket: Deactivated successfully. Aug 12 23:59:12.049267 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Aug 12 23:59:12.056831 systemd[1]: iscsiuio.socket: Deactivated successfully. Aug 12 23:59:12.056875 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Aug 12 23:59:12.066606 systemd[1]: ignition-setup.service: Deactivated successfully. Aug 12 23:59:12.066675 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Aug 12 23:59:12.074193 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Aug 12 23:59:12.074242 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Aug 12 23:59:12.093746 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Aug 12 23:59:12.101733 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Aug 12 23:59:12.109388 systemd[1]: systemd-resolved.service: Deactivated successfully. Aug 12 23:59:12.109505 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Aug 12 23:59:12.123576 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Aug 12 23:59:12.123860 systemd[1]: systemd-networkd.service: Deactivated successfully. Aug 12 23:59:12.123968 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Aug 12 23:59:12.135158 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Aug 12 23:59:12.135406 systemd[1]: sysroot-boot.service: Deactivated successfully. Aug 12 23:59:12.135505 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Aug 12 23:59:12.137239 systemd[1]: systemd-networkd.socket: Deactivated successfully. Aug 12 23:59:12.137327 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Aug 12 23:59:12.145551 systemd[1]: initrd-setup-root.service: Deactivated successfully. Aug 12 23:59:12.145612 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Aug 12 23:59:12.178141 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Aug 12 23:59:12.185272 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Aug 12 23:59:12.185334 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Aug 12 23:59:12.194066 systemd[1]: systemd-sysctl.service: Deactivated successfully. Aug 12 23:59:12.194114 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Aug 12 23:59:12.202779 systemd[1]: systemd-modules-load.service: Deactivated successfully. Aug 12 23:59:12.202827 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Aug 12 23:59:12.210662 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Aug 12 23:59:12.210712 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Aug 12 23:59:12.222112 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 12 23:59:12.223931 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Aug 12 23:59:12.224004 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Aug 12 23:59:12.252458 systemd[1]: network-cleanup.service: Deactivated successfully. Aug 12 23:59:12.252566 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Aug 12 23:59:12.276098 systemd[1]: systemd-udevd.service: Deactivated successfully. Aug 12 23:59:12.276243 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 12 23:59:12.284994 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Aug 12 23:59:12.285060 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Aug 12 23:59:12.292996 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Aug 12 23:59:12.293048 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Aug 12 23:59:12.301301 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Aug 12 23:59:12.301349 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Aug 12 23:59:12.314438 systemd[1]: dracut-cmdline.service: Deactivated successfully. Aug 12 23:59:12.314488 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Aug 12 23:59:12.326323 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Aug 12 23:59:12.326373 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 12 23:59:12.364181 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Aug 12 23:59:12.371299 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Aug 12 23:59:12.371357 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 12 23:59:12.384957 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Aug 12 23:59:12.385009 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Aug 12 23:59:12.395191 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Aug 12 23:59:12.395256 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Aug 12 23:59:12.395596 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Aug 12 23:59:12.395686 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Aug 12 23:59:12.399523 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Aug 12 23:59:12.419159 systemd[1]: Starting initrd-switch-root.service - Switch Root... Aug 12 23:59:12.429353 systemd[1]: Switching root. Aug 12 23:59:12.509426 systemd-journald[205]: Journal stopped Aug 12 23:59:17.697485 systemd-journald[205]: Received SIGTERM from PID 1 (systemd). Aug 12 23:59:17.697518 kernel: SELinux: policy capability network_peer_controls=1 Aug 12 23:59:17.697531 kernel: SELinux: policy capability open_perms=1 Aug 12 23:59:17.697541 kernel: SELinux: policy capability extended_socket_class=1 Aug 12 23:59:17.697554 kernel: SELinux: policy capability always_check_network=0 Aug 12 23:59:17.697563 kernel: SELinux: policy capability cgroup_seclabel=1 Aug 12 23:59:17.697573 kernel: SELinux: policy capability nnp_nosuid_transition=1 Aug 12 23:59:17.697586 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Aug 12 23:59:17.697595 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Aug 12 23:59:17.697605 kernel: audit: type=1403 audit(1755043153.507:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Aug 12 23:59:17.697615 systemd[1]: Successfully loaded SELinux policy in 205.396ms. Aug 12 23:59:17.697627 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 15.795ms. Aug 12 23:59:17.697638 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) Aug 12 23:59:17.697649 systemd[1]: Detected virtualization microsoft. Aug 12 23:59:17.697663 systemd[1]: Detected architecture x86-64. Aug 12 23:59:17.697673 systemd[1]: Detected first boot. Aug 12 23:59:17.697684 systemd[1]: Hostname set to . Aug 12 23:59:17.697695 systemd[1]: Initializing machine ID from random generator. Aug 12 23:59:17.697705 zram_generator::config[1221]: No configuration found. Aug 12 23:59:17.697719 kernel: Guest personality initialized and is inactive Aug 12 23:59:17.697729 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Aug 12 23:59:17.697738 kernel: Initialized host personality Aug 12 23:59:17.697748 kernel: NET: Registered PF_VSOCK protocol family Aug 12 23:59:17.697758 systemd[1]: Populated /etc with preset unit settings. Aug 12 23:59:17.697770 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Aug 12 23:59:17.697784 systemd[1]: initrd-switch-root.service: Deactivated successfully. Aug 12 23:59:17.697794 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Aug 12 23:59:17.697808 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Aug 12 23:59:17.697819 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Aug 12 23:59:17.697830 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Aug 12 23:59:17.697841 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Aug 12 23:59:17.697852 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Aug 12 23:59:17.697863 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Aug 12 23:59:17.697874 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Aug 12 23:59:17.697888 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Aug 12 23:59:17.697899 systemd[1]: Created slice user.slice - User and Session Slice. Aug 12 23:59:17.697910 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 12 23:59:17.697920 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 12 23:59:17.697931 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Aug 12 23:59:17.697942 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Aug 12 23:59:17.697957 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Aug 12 23:59:17.697968 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Aug 12 23:59:17.697982 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Aug 12 23:59:17.697994 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 12 23:59:17.698005 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Aug 12 23:59:17.698016 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Aug 12 23:59:17.698044 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Aug 12 23:59:17.698056 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Aug 12 23:59:17.698067 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 12 23:59:17.698078 systemd[1]: Reached target remote-fs.target - Remote File Systems. Aug 12 23:59:17.698093 systemd[1]: Reached target slices.target - Slice Units. Aug 12 23:59:17.698104 systemd[1]: Reached target swap.target - Swaps. Aug 12 23:59:17.698115 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Aug 12 23:59:17.698127 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Aug 12 23:59:17.698138 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Aug 12 23:59:17.698153 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Aug 12 23:59:17.698165 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Aug 12 23:59:17.698176 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Aug 12 23:59:17.698187 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Aug 12 23:59:17.698198 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Aug 12 23:59:17.698209 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Aug 12 23:59:17.698221 systemd[1]: Mounting media.mount - External Media Directory... Aug 12 23:59:17.698232 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 12 23:59:17.698246 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Aug 12 23:59:17.698258 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Aug 12 23:59:17.698269 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Aug 12 23:59:17.698281 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). Aug 12 23:59:17.698295 systemd[1]: Reached target machines.target - Containers. Aug 12 23:59:17.698307 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Aug 12 23:59:17.698318 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 12 23:59:17.698330 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Aug 12 23:59:17.698344 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Aug 12 23:59:17.698355 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 12 23:59:17.698367 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Aug 12 23:59:17.698378 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 12 23:59:17.698389 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Aug 12 23:59:17.698400 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 12 23:59:17.698412 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Aug 12 23:59:17.698423 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Aug 12 23:59:17.698437 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Aug 12 23:59:17.698448 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Aug 12 23:59:17.698459 systemd[1]: Stopped systemd-fsck-usr.service. Aug 12 23:59:17.698471 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). Aug 12 23:59:17.698482 kernel: fuse: init (API version 7.39) Aug 12 23:59:17.698493 kernel: loop: module loaded Aug 12 23:59:17.698504 systemd[1]: Starting systemd-journald.service - Journal Service... Aug 12 23:59:17.698515 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Aug 12 23:59:17.698529 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Aug 12 23:59:17.698544 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Aug 12 23:59:17.698555 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Aug 12 23:59:17.698566 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Aug 12 23:59:17.698577 systemd[1]: verity-setup.service: Deactivated successfully. Aug 12 23:59:17.698589 systemd[1]: Stopped verity-setup.service. Aug 12 23:59:17.698600 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 12 23:59:17.698611 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Aug 12 23:59:17.698625 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Aug 12 23:59:17.698637 systemd[1]: Mounted media.mount - External Media Directory. Aug 12 23:59:17.698648 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Aug 12 23:59:17.698679 systemd-journald[1328]: Collecting audit messages is disabled. Aug 12 23:59:17.698711 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Aug 12 23:59:17.698723 kernel: ACPI: bus type drm_connector registered Aug 12 23:59:17.698734 systemd-journald[1328]: Journal started Aug 12 23:59:17.698757 systemd-journald[1328]: Runtime Journal (/run/log/journal/ceea441e0dc24d41a5902261d41bf056) is 8M, max 2.2G, 2.1G free. Aug 12 23:59:16.854913 systemd[1]: Queued start job for default target multi-user.target. Aug 12 23:59:16.866523 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Aug 12 23:59:16.867034 systemd[1]: systemd-journald.service: Deactivated successfully. Aug 12 23:59:17.714226 systemd[1]: Started systemd-journald.service - Journal Service. Aug 12 23:59:17.718648 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Aug 12 23:59:17.722888 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Aug 12 23:59:17.728064 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Aug 12 23:59:17.733493 systemd[1]: modprobe@configfs.service: Deactivated successfully. Aug 12 23:59:17.733729 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Aug 12 23:59:17.739735 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 12 23:59:17.739919 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 12 23:59:17.744840 systemd[1]: modprobe@drm.service: Deactivated successfully. Aug 12 23:59:17.745110 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Aug 12 23:59:17.749920 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 12 23:59:17.750133 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 12 23:59:17.755666 systemd[1]: modprobe@fuse.service: Deactivated successfully. Aug 12 23:59:17.755857 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Aug 12 23:59:17.760825 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 12 23:59:17.761013 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 12 23:59:17.766287 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Aug 12 23:59:17.771083 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Aug 12 23:59:17.776628 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Aug 12 23:59:17.781972 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Aug 12 23:59:17.796806 systemd[1]: Reached target network-pre.target - Preparation for Network. Aug 12 23:59:17.816142 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Aug 12 23:59:17.821479 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Aug 12 23:59:17.825958 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Aug 12 23:59:17.825999 systemd[1]: Reached target local-fs.target - Local File Systems. Aug 12 23:59:17.831816 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Aug 12 23:59:17.838803 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Aug 12 23:59:17.846215 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Aug 12 23:59:17.850345 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 12 23:59:17.854160 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Aug 12 23:59:17.859448 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Aug 12 23:59:17.864285 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Aug 12 23:59:17.865317 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Aug 12 23:59:17.869767 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Aug 12 23:59:17.870707 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Aug 12 23:59:17.875802 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Aug 12 23:59:17.878361 systemd[1]: Starting systemd-sysusers.service - Create System Users... Aug 12 23:59:17.881159 systemd-journald[1328]: Time spent on flushing to /var/log/journal/ceea441e0dc24d41a5902261d41bf056 is 14.973ms for 938 entries. Aug 12 23:59:17.881159 systemd-journald[1328]: System Journal (/var/log/journal/ceea441e0dc24d41a5902261d41bf056) is 8M, max 2.6G, 2.6G free. Aug 12 23:59:17.932934 systemd-journald[1328]: Received client request to flush runtime journal. Aug 12 23:59:17.890332 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Aug 12 23:59:17.892131 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Aug 12 23:59:17.901320 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Aug 12 23:59:17.907492 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Aug 12 23:59:17.913119 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Aug 12 23:59:17.924255 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Aug 12 23:59:17.938179 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Aug 12 23:59:17.943927 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Aug 12 23:59:17.948979 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Aug 12 23:59:17.957136 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Aug 12 23:59:17.965044 kernel: loop0: detected capacity change from 0 to 28272 Aug 12 23:59:17.967005 udevadm[1375]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Aug 12 23:59:17.994073 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Aug 12 23:59:17.994786 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Aug 12 23:59:18.338318 systemd[1]: Finished systemd-sysusers.service - Create System Users. Aug 12 23:59:18.353171 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Aug 12 23:59:18.441538 systemd-tmpfiles[1380]: ACLs are not supported, ignoring. Aug 12 23:59:18.441562 systemd-tmpfiles[1380]: ACLs are not supported, ignoring. Aug 12 23:59:18.447520 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 12 23:59:18.488297 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Aug 12 23:59:18.627057 kernel: loop1: detected capacity change from 0 to 147912 Aug 12 23:59:18.999056 kernel: loop2: detected capacity change from 0 to 138176 Aug 12 23:59:19.319445 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Aug 12 23:59:19.347192 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 12 23:59:19.372667 systemd-udevd[1388]: Using default interface naming scheme 'v255'. Aug 12 23:59:19.578070 kernel: loop3: detected capacity change from 0 to 28272 Aug 12 23:59:19.588045 kernel: loop4: detected capacity change from 0 to 147912 Aug 12 23:59:19.602047 kernel: loop5: detected capacity change from 0 to 138176 Aug 12 23:59:19.665456 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 12 23:59:19.666473 (sd-merge)[1390]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Aug 12 23:59:19.666997 (sd-merge)[1390]: Merged extensions into '/usr'. Aug 12 23:59:19.681713 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Aug 12 23:59:19.699703 systemd[1]: Starting ensure-sysext.service... Aug 12 23:59:19.706900 systemd[1]: Starting systemd-networkd.service - Network Configuration... Aug 12 23:59:19.712804 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Aug 12 23:59:19.719951 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Aug 12 23:59:19.732581 systemd[1]: Reload requested from client PID 1411 ('systemctl') (unit ensure-sysext.service)... Aug 12 23:59:19.732602 systemd[1]: Reloading... Aug 12 23:59:19.787444 systemd-tmpfiles[1429]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Aug 12 23:59:19.787751 systemd-tmpfiles[1429]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Aug 12 23:59:19.788683 systemd-tmpfiles[1429]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Aug 12 23:59:19.788976 systemd-tmpfiles[1429]: ACLs are not supported, ignoring. Aug 12 23:59:19.789069 systemd-tmpfiles[1429]: ACLs are not supported, ignoring. Aug 12 23:59:19.791125 zram_generator::config[1465]: No configuration found. Aug 12 23:59:19.808049 kernel: hv_vmbus: registering driver hv_balloon Aug 12 23:59:19.808128 kernel: mousedev: PS/2 mouse device common for all mice Aug 12 23:59:19.808151 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Aug 12 23:59:19.817679 systemd-tmpfiles[1429]: Detected autofs mount point /boot during canonicalization of boot. Aug 12 23:59:19.817694 systemd-tmpfiles[1429]: Skipping /boot Aug 12 23:59:19.830294 kernel: hv_vmbus: registering driver hyperv_fb Aug 12 23:59:19.835036 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Aug 12 23:59:19.835094 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Aug 12 23:59:19.832554 systemd-tmpfiles[1429]: Detected autofs mount point /boot during canonicalization of boot. Aug 12 23:59:19.832570 systemd-tmpfiles[1429]: Skipping /boot Aug 12 23:59:19.840077 kernel: EDAC MC: Ver: 3.0.0 Aug 12 23:59:19.844065 kernel: Console: switching to colour dummy device 80x25 Aug 12 23:59:19.850294 kernel: Console: switching to colour frame buffer device 128x48 Aug 12 23:59:19.939046 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 45 scanned by (udev-worker) (1399) Aug 12 23:59:19.949510 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. Aug 12 23:59:20.060444 systemd[1]: Reloading finished in 327 ms. Aug 12 23:59:20.079784 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Aug 12 23:59:20.129674 systemd[1]: Finished ensure-sysext.service. Aug 12 23:59:20.147626 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Aug 12 23:59:20.177855 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Aug 12 23:59:20.182957 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 12 23:59:20.194208 systemd[1]: Starting audit-rules.service - Load Audit Rules... Aug 12 23:59:20.199101 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Aug 12 23:59:20.203745 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 12 23:59:20.204784 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Aug 12 23:59:20.210693 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 12 23:59:20.215905 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Aug 12 23:59:20.220951 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 12 23:59:20.226573 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 12 23:59:20.230766 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 12 23:59:20.231696 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Aug 12 23:59:20.236400 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). Aug 12 23:59:20.237486 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Aug 12 23:59:20.244136 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Aug 12 23:59:20.245793 systemd[1]: Reached target time-set.target - System Time Set. Aug 12 23:59:20.253775 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Aug 12 23:59:20.260182 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Aug 12 23:59:20.266074 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 12 23:59:20.271343 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 12 23:59:20.272634 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 12 23:59:20.273054 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 12 23:59:20.274890 systemd[1]: modprobe@drm.service: Deactivated successfully. Aug 12 23:59:20.275106 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Aug 12 23:59:20.275543 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 12 23:59:20.275730 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 12 23:59:20.276265 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 12 23:59:20.276453 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 12 23:59:20.279374 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Aug 12 23:59:20.279443 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Aug 12 23:59:20.304979 lvm[1599]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Aug 12 23:59:20.315327 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Aug 12 23:59:20.328877 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Aug 12 23:59:20.330555 systemd[1]: Started systemd-userdbd.service - User Database Manager. Aug 12 23:59:20.366011 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Aug 12 23:59:20.368078 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Aug 12 23:59:20.369009 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Aug 12 23:59:20.386319 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Aug 12 23:59:20.390489 lvm[1634]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Aug 12 23:59:20.479496 systemd-networkd[1419]: lo: Link UP Aug 12 23:59:20.479506 systemd-networkd[1419]: lo: Gained carrier Aug 12 23:59:20.481093 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Aug 12 23:59:20.481350 systemd-networkd[1419]: Enumeration completed Aug 12 23:59:20.481753 systemd-networkd[1419]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 12 23:59:20.481764 systemd-networkd[1419]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Aug 12 23:59:20.483028 systemd[1]: Started systemd-networkd.service - Network Configuration. Aug 12 23:59:20.483114 systemd-networkd[1419]: eth0: Link UP Aug 12 23:59:20.483118 systemd-networkd[1419]: eth0: Gained carrier Aug 12 23:59:20.483132 systemd-networkd[1419]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 12 23:59:20.499285 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Aug 12 23:59:20.503735 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Aug 12 23:59:20.518738 augenrules[1652]: No rules Aug 12 23:59:20.519133 systemd-networkd[1419]: eth0: DHCPv4 address 10.200.8.10/24, gateway 10.200.8.1 acquired from 168.63.129.16 Aug 12 23:59:20.520092 systemd[1]: audit-rules.service: Deactivated successfully. Aug 12 23:59:20.520356 systemd[1]: Finished audit-rules.service - Load Audit Rules. Aug 12 23:59:20.533070 systemd-resolved[1607]: Positive Trust Anchors: Aug 12 23:59:20.533082 systemd-resolved[1607]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Aug 12 23:59:20.533114 systemd-resolved[1607]: 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 Aug 12 23:59:20.537208 systemd-resolved[1607]: Using system hostname 'ci-4230.2.2-a-2f6de76cbe'. Aug 12 23:59:20.539062 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Aug 12 23:59:20.540739 systemd[1]: Reached target network.target - Network. Aug 12 23:59:20.541459 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Aug 12 23:59:20.556003 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Aug 12 23:59:21.143140 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 12 23:59:21.269269 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Aug 12 23:59:21.274365 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). Aug 12 23:59:22.353183 systemd-networkd[1419]: eth0: Gained IPv6LL Aug 12 23:59:22.355796 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Aug 12 23:59:22.362092 systemd[1]: Reached target network-online.target - Network is Online. Aug 12 23:59:24.923266 ldconfig[1358]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Aug 12 23:59:24.942170 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Aug 12 23:59:24.961208 systemd[1]: Starting systemd-update-done.service - Update is Completed... Aug 12 23:59:24.981210 systemd[1]: Finished systemd-update-done.service - Update is Completed. Aug 12 23:59:24.986276 systemd[1]: Reached target sysinit.target - System Initialization. Aug 12 23:59:24.990993 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Aug 12 23:59:24.996354 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Aug 12 23:59:25.002067 systemd[1]: Started logrotate.timer - Daily rotation of log files. Aug 12 23:59:25.006759 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Aug 12 23:59:25.012181 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Aug 12 23:59:25.017728 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Aug 12 23:59:25.017781 systemd[1]: Reached target paths.target - Path Units. Aug 12 23:59:25.021749 systemd[1]: Reached target timers.target - Timer Units. Aug 12 23:59:25.026826 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Aug 12 23:59:25.033112 systemd[1]: Starting docker.socket - Docker Socket for the API... Aug 12 23:59:25.040004 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Aug 12 23:59:25.045307 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Aug 12 23:59:25.050378 systemd[1]: Reached target ssh-access.target - SSH Access Available. Aug 12 23:59:25.064663 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Aug 12 23:59:25.069967 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Aug 12 23:59:25.076043 systemd[1]: Listening on docker.socket - Docker Socket for the API. Aug 12 23:59:25.080611 systemd[1]: Reached target sockets.target - Socket Units. Aug 12 23:59:25.084605 systemd[1]: Reached target basic.target - Basic System. Aug 12 23:59:25.088397 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Aug 12 23:59:25.088449 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Aug 12 23:59:25.102167 systemd[1]: Starting chronyd.service - NTP client/server... Aug 12 23:59:25.106995 systemd[1]: Starting containerd.service - containerd container runtime... Aug 12 23:59:25.112316 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Aug 12 23:59:25.117510 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Aug 12 23:59:25.122152 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Aug 12 23:59:25.128964 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Aug 12 23:59:25.133085 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). Aug 12 23:59:25.133138 systemd[1]: hv_fcopy_daemon.service - Hyper-V FCOPY daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_fcopy). Aug 12 23:59:25.134427 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Aug 12 23:59:25.138764 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Aug 12 23:59:25.139914 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Aug 12 23:59:25.143946 jq[1677]: false Aug 12 23:59:25.145847 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Aug 12 23:59:25.147305 KVP[1679]: KVP starting; pid is:1679 Aug 12 23:59:25.151059 KVP[1679]: KVP LIC Version: 3.1 Aug 12 23:59:25.151441 (chronyd)[1670]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Aug 12 23:59:25.152038 kernel: hv_utils: KVP IC version 4.0 Aug 12 23:59:25.153040 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Aug 12 23:59:25.157053 chronyd[1685]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) Aug 12 23:59:25.159170 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Aug 12 23:59:25.165840 systemd[1]: Starting systemd-logind.service - User Login Management... Aug 12 23:59:25.171174 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Aug 12 23:59:25.171695 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Aug 12 23:59:25.172418 systemd[1]: Starting update-engine.service - Update Engine... Aug 12 23:59:25.177932 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Aug 12 23:59:25.178215 chronyd[1685]: Timezone right/UTC failed leap second check, ignoring Aug 12 23:59:25.178451 chronyd[1685]: Loaded seccomp filter (level 2) Aug 12 23:59:25.184034 systemd[1]: Started chronyd.service - NTP client/server. Aug 12 23:59:25.189189 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Aug 12 23:59:25.191417 extend-filesystems[1678]: Found loop3 Aug 12 23:59:25.191417 extend-filesystems[1678]: Found loop4 Aug 12 23:59:25.191417 extend-filesystems[1678]: Found loop5 Aug 12 23:59:25.191417 extend-filesystems[1678]: Found sda Aug 12 23:59:25.191417 extend-filesystems[1678]: Found sda1 Aug 12 23:59:25.191417 extend-filesystems[1678]: Found sda2 Aug 12 23:59:25.191417 extend-filesystems[1678]: Found sda3 Aug 12 23:59:25.191417 extend-filesystems[1678]: Found usr Aug 12 23:59:25.191417 extend-filesystems[1678]: Found sda4 Aug 12 23:59:25.191417 extend-filesystems[1678]: Found sda6 Aug 12 23:59:25.191417 extend-filesystems[1678]: Found sda7 Aug 12 23:59:25.191417 extend-filesystems[1678]: Found sda9 Aug 12 23:59:25.191417 extend-filesystems[1678]: Checking size of /dev/sda9 Aug 12 23:59:25.189419 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Aug 12 23:59:25.245165 update_engine[1691]: I20250812 23:59:25.219237 1691 main.cc:92] Flatcar Update Engine starting Aug 12 23:59:25.245401 extend-filesystems[1678]: Old size kept for /dev/sda9 Aug 12 23:59:25.245401 extend-filesystems[1678]: Found sdb Aug 12 23:59:25.245401 extend-filesystems[1678]: Found sdb1 Aug 12 23:59:25.245401 extend-filesystems[1678]: Found sr0 Aug 12 23:59:25.189725 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Aug 12 23:59:25.258271 jq[1692]: true Aug 12 23:59:25.189931 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Aug 12 23:59:25.204886 (ntainerd)[1705]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Aug 12 23:59:25.258888 jq[1704]: true Aug 12 23:59:25.206998 systemd[1]: motdgen.service: Deactivated successfully. Aug 12 23:59:25.207320 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Aug 12 23:59:25.220180 systemd[1]: extend-filesystems.service: Deactivated successfully. Aug 12 23:59:25.220410 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Aug 12 23:59:25.270086 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 45 scanned by (udev-worker) (1740) Aug 12 23:59:25.272285 systemd-logind[1687]: New seat seat0. Aug 12 23:59:25.273952 systemd-logind[1687]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Aug 12 23:59:25.274161 systemd[1]: Started systemd-logind.service - User Login Management. Aug 12 23:59:25.306927 dbus-daemon[1673]: [system] SELinux support is enabled Aug 12 23:59:25.310134 update_engine[1691]: I20250812 23:59:25.310075 1691 update_check_scheduler.cc:74] Next update check in 5m45s Aug 12 23:59:25.311102 systemd[1]: Started dbus.service - D-Bus System Message Bus. Aug 12 23:59:25.321525 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). Aug 12 23:59:25.321565 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Aug 12 23:59:25.321996 dbus-daemon[1673]: [system] Successfully activated service 'org.freedesktop.systemd1' Aug 12 23:59:25.326675 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). Aug 12 23:59:25.326713 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Aug 12 23:59:25.331719 systemd[1]: Started update-engine.service - Update Engine. Aug 12 23:59:25.352320 systemd[1]: Started locksmithd.service - Cluster reboot manager. Aug 12 23:59:25.361895 coreos-metadata[1672]: Aug 12 23:59:25.361 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Aug 12 23:59:25.364401 coreos-metadata[1672]: Aug 12 23:59:25.364 INFO Fetch successful Aug 12 23:59:25.364931 coreos-metadata[1672]: Aug 12 23:59:25.364 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Aug 12 23:59:25.366999 bash[1748]: Updated "/home/core/.ssh/authorized_keys" Aug 12 23:59:25.368242 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Aug 12 23:59:25.368435 coreos-metadata[1672]: Aug 12 23:59:25.368 INFO Fetch successful Aug 12 23:59:25.368762 coreos-metadata[1672]: Aug 12 23:59:25.368 INFO Fetching http://168.63.129.16/machine/06a7bd89-4564-4603-88bf-b0f2a65b857d/94af148c%2D3335%2D4522%2Da85a%2Ddcb8e24152ec.%5Fci%2D4230.2.2%2Da%2D2f6de76cbe?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Aug 12 23:59:25.370614 coreos-metadata[1672]: Aug 12 23:59:25.370 INFO Fetch successful Aug 12 23:59:25.370761 coreos-metadata[1672]: Aug 12 23:59:25.370 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Aug 12 23:59:25.374826 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Aug 12 23:59:25.378844 coreos-metadata[1672]: Aug 12 23:59:25.378 INFO Fetch successful Aug 12 23:59:25.382994 setup-nvidia[1682]: Downloading Flatcar Container Linux Developer Container for version: 4230.2.2 Aug 12 23:59:25.405660 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Aug 12 23:59:25.410860 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Aug 12 23:59:25.527612 locksmithd[1804]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Aug 12 23:59:25.649840 sshd_keygen[1690]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Aug 12 23:59:25.672069 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Aug 12 23:59:25.684257 systemd[1]: Starting issuegen.service - Generate /run/issue... Aug 12 23:59:25.689039 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Aug 12 23:59:25.698105 systemd[1]: issuegen.service: Deactivated successfully. Aug 12 23:59:25.698317 systemd[1]: Finished issuegen.service - Generate /run/issue. Aug 12 23:59:25.707355 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Aug 12 23:59:25.718168 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Aug 12 23:59:25.732453 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Aug 12 23:59:25.739154 systemd[1]: Started getty@tty1.service - Getty on tty1. Aug 12 23:59:25.744401 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Aug 12 23:59:25.749912 systemd[1]: Reached target getty.target - Login Prompts. Aug 12 23:59:26.448189 containerd[1705]: time="2025-08-12T23:59:26.448092600Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Aug 12 23:59:26.470463 containerd[1705]: time="2025-08-12T23:59:26.470394600Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Aug 12 23:59:26.472627 containerd[1705]: time="2025-08-12T23:59:26.472580400Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.100-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Aug 12 23:59:26.472627 containerd[1705]: time="2025-08-12T23:59:26.472614100Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Aug 12 23:59:26.472727 containerd[1705]: time="2025-08-12T23:59:26.472632300Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Aug 12 23:59:26.472837 containerd[1705]: time="2025-08-12T23:59:26.472814700Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Aug 12 23:59:26.472877 containerd[1705]: time="2025-08-12T23:59:26.472838900Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Aug 12 23:59:26.472947 containerd[1705]: time="2025-08-12T23:59:26.472925300Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Aug 12 23:59:26.472976 containerd[1705]: time="2025-08-12T23:59:26.472945200Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Aug 12 23:59:26.473213 containerd[1705]: time="2025-08-12T23:59:26.473188600Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Aug 12 23:59:26.473246 containerd[1705]: time="2025-08-12T23:59:26.473212000Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Aug 12 23:59:26.473246 containerd[1705]: time="2025-08-12T23:59:26.473226800Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Aug 12 23:59:26.473246 containerd[1705]: time="2025-08-12T23:59:26.473236700Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Aug 12 23:59:26.473355 containerd[1705]: time="2025-08-12T23:59:26.473328400Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Aug 12 23:59:26.473580 containerd[1705]: time="2025-08-12T23:59:26.473559100Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Aug 12 23:59:26.473726 containerd[1705]: time="2025-08-12T23:59:26.473704400Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Aug 12 23:59:26.473757 containerd[1705]: time="2025-08-12T23:59:26.473724200Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Aug 12 23:59:26.473827 containerd[1705]: time="2025-08-12T23:59:26.473808400Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Aug 12 23:59:26.473885 containerd[1705]: time="2025-08-12T23:59:26.473867100Z" level=info msg="metadata content store policy set" policy=shared Aug 12 23:59:26.488882 containerd[1705]: time="2025-08-12T23:59:26.488851600Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Aug 12 23:59:26.488932 containerd[1705]: time="2025-08-12T23:59:26.488902600Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Aug 12 23:59:26.488932 containerd[1705]: time="2025-08-12T23:59:26.488921700Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Aug 12 23:59:26.488998 containerd[1705]: time="2025-08-12T23:59:26.488938000Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Aug 12 23:59:26.488998 containerd[1705]: time="2025-08-12T23:59:26.488953500Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Aug 12 23:59:26.489128 containerd[1705]: time="2025-08-12T23:59:26.489105600Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Aug 12 23:59:26.489385 containerd[1705]: time="2025-08-12T23:59:26.489359100Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Aug 12 23:59:26.489520 containerd[1705]: time="2025-08-12T23:59:26.489498300Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Aug 12 23:59:26.489558 containerd[1705]: time="2025-08-12T23:59:26.489524300Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Aug 12 23:59:26.489558 containerd[1705]: time="2025-08-12T23:59:26.489541000Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Aug 12 23:59:26.489605 containerd[1705]: time="2025-08-12T23:59:26.489557400Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Aug 12 23:59:26.489605 containerd[1705]: time="2025-08-12T23:59:26.489572500Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Aug 12 23:59:26.489605 containerd[1705]: time="2025-08-12T23:59:26.489585000Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Aug 12 23:59:26.489605 containerd[1705]: time="2025-08-12T23:59:26.489599100Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Aug 12 23:59:26.489678 containerd[1705]: time="2025-08-12T23:59:26.489614000Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Aug 12 23:59:26.489678 containerd[1705]: time="2025-08-12T23:59:26.489626800Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Aug 12 23:59:26.489678 containerd[1705]: time="2025-08-12T23:59:26.489638800Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Aug 12 23:59:26.489678 containerd[1705]: time="2025-08-12T23:59:26.489650100Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Aug 12 23:59:26.489678 containerd[1705]: time="2025-08-12T23:59:26.489670800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Aug 12 23:59:26.489784 containerd[1705]: time="2025-08-12T23:59:26.489685700Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Aug 12 23:59:26.489784 containerd[1705]: time="2025-08-12T23:59:26.489704300Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Aug 12 23:59:26.489784 containerd[1705]: time="2025-08-12T23:59:26.489718800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Aug 12 23:59:26.489784 containerd[1705]: time="2025-08-12T23:59:26.489731600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Aug 12 23:59:26.489784 containerd[1705]: time="2025-08-12T23:59:26.489745600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Aug 12 23:59:26.489784 containerd[1705]: time="2025-08-12T23:59:26.489757900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Aug 12 23:59:26.489784 containerd[1705]: time="2025-08-12T23:59:26.489771200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Aug 12 23:59:26.489784 containerd[1705]: time="2025-08-12T23:59:26.489784600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Aug 12 23:59:26.489948 containerd[1705]: time="2025-08-12T23:59:26.489799800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Aug 12 23:59:26.489948 containerd[1705]: time="2025-08-12T23:59:26.489811900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Aug 12 23:59:26.489948 containerd[1705]: time="2025-08-12T23:59:26.489823600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Aug 12 23:59:26.489948 containerd[1705]: time="2025-08-12T23:59:26.489835300Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Aug 12 23:59:26.489948 containerd[1705]: time="2025-08-12T23:59:26.489848900Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Aug 12 23:59:26.489948 containerd[1705]: time="2025-08-12T23:59:26.489869900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Aug 12 23:59:26.489948 containerd[1705]: time="2025-08-12T23:59:26.489883300Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Aug 12 23:59:26.489948 containerd[1705]: time="2025-08-12T23:59:26.489895400Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Aug 12 23:59:26.489948 containerd[1705]: time="2025-08-12T23:59:26.489944800Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Aug 12 23:59:26.490140 containerd[1705]: time="2025-08-12T23:59:26.489966400Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Aug 12 23:59:26.490140 containerd[1705]: time="2025-08-12T23:59:26.489978500Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Aug 12 23:59:26.490140 containerd[1705]: time="2025-08-12T23:59:26.490005300Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Aug 12 23:59:26.490140 containerd[1705]: time="2025-08-12T23:59:26.490034600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Aug 12 23:59:26.490140 containerd[1705]: time="2025-08-12T23:59:26.490062800Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Aug 12 23:59:26.490140 containerd[1705]: time="2025-08-12T23:59:26.490079500Z" level=info msg="NRI interface is disabled by configuration." Aug 12 23:59:26.490140 containerd[1705]: time="2025-08-12T23:59:26.490100500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Aug 12 23:59:26.490407 containerd[1705]: time="2025-08-12T23:59:26.490354600Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Aug 12 23:59:26.490542 containerd[1705]: time="2025-08-12T23:59:26.490410200Z" level=info msg="Connect containerd service" Aug 12 23:59:26.490542 containerd[1705]: time="2025-08-12T23:59:26.490448400Z" level=info msg="using legacy CRI server" Aug 12 23:59:26.490542 containerd[1705]: time="2025-08-12T23:59:26.490460800Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Aug 12 23:59:26.491194 containerd[1705]: time="2025-08-12T23:59:26.491162100Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Aug 12 23:59:26.491888 containerd[1705]: time="2025-08-12T23:59:26.491860300Z" 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" Aug 12 23:59:26.492060 containerd[1705]: time="2025-08-12T23:59:26.491999000Z" level=info msg="Start subscribing containerd event" Aug 12 23:59:26.492092 containerd[1705]: time="2025-08-12T23:59:26.492077900Z" level=info msg="Start recovering state" Aug 12 23:59:26.492190 containerd[1705]: time="2025-08-12T23:59:26.492165400Z" level=info msg="Start event monitor" Aug 12 23:59:26.492236 containerd[1705]: time="2025-08-12T23:59:26.492214300Z" level=info msg="Start snapshots syncer" Aug 12 23:59:26.492274 containerd[1705]: time="2025-08-12T23:59:26.492243400Z" level=info msg="Start cni network conf syncer for default" Aug 12 23:59:26.492274 containerd[1705]: time="2025-08-12T23:59:26.492260100Z" level=info msg="Start streaming server" Aug 12 23:59:26.492365 containerd[1705]: time="2025-08-12T23:59:26.492343500Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Aug 12 23:59:26.492425 containerd[1705]: time="2025-08-12T23:59:26.492407900Z" level=info msg=serving... address=/run/containerd/containerd.sock Aug 12 23:59:26.492505 containerd[1705]: time="2025-08-12T23:59:26.492479300Z" level=info msg="containerd successfully booted in 0.045414s" Aug 12 23:59:26.492590 systemd[1]: Started containerd.service - containerd container runtime. Aug 12 23:59:26.572198 setup-nvidia[1853]: % Total % Received % Xferd Average Speed Time Time Time Current Aug 12 23:59:26.572534 setup-nvidia[1853]: Dload Upload Total Spent Left Speed Aug 12 23:59:27.682659 waagent[1838]: 2025-08-12T23:59:27.682550Z INFO Daemon Daemon Azure Linux Agent Version: 2.9.1.1 Aug 12 23:59:27.686999 waagent[1838]: 2025-08-12T23:59:27.686892Z INFO Daemon Daemon OS: flatcar 4230.2.2 Aug 12 23:59:27.690363 waagent[1838]: 2025-08-12T23:59:27.690306Z INFO Daemon Daemon Python: 3.11.11 Aug 12 23:59:27.693668 waagent[1838]: 2025-08-12T23:59:27.693613Z INFO Daemon Daemon Run daemon Aug 12 23:59:27.696601 waagent[1838]: 2025-08-12T23:59:27.696545Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4230.2.2' Aug 12 23:59:27.703252 waagent[1838]: 2025-08-12T23:59:27.703194Z INFO Daemon Daemon Using waagent for provisioning Aug 12 23:59:27.707275 waagent[1838]: 2025-08-12T23:59:27.707223Z INFO Daemon Daemon Activate resource disk Aug 12 23:59:27.710745 waagent[1838]: 2025-08-12T23:59:27.710694Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Aug 12 23:59:27.718288 waagent[1838]: 2025-08-12T23:59:27.718236Z INFO Daemon Daemon Found device: sdb Aug 12 23:59:27.727610 waagent[1838]: 2025-08-12T23:59:27.727556Z INFO Daemon Daemon Examining partition table Aug 12 23:59:27.821611 waagent[1838]: 2025-08-12T23:59:27.821515Z INFO Daemon Daemon GPT not detected, determining filesystem Aug 12 23:59:27.883202 waagent[1838]: 2025-08-12T23:59:27.883112Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Aug 12 23:59:27.887861 waagent[1838]: 2025-08-12T23:59:27.887806Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Aug 12 23:59:28.249054 kernel: sdb: sdb1 Aug 12 23:59:28.251039 kernel: sdb: sdb1 Aug 12 23:59:28.275731 waagent[1838]: 2025-08-12T23:59:28.275625Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Aug 12 23:59:28.290622 waagent[1838]: 2025-08-12T23:59:28.290559Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Aug 12 23:59:28.294954 kernel: sdb: sdb1 Aug 12 23:59:33.653553 login[1840]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Aug 12 23:59:33.704503 login[1841]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Aug 12 23:59:33.715854 systemd-logind[1687]: New session 1 of user core. Aug 12 23:59:33.716988 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Aug 12 23:59:33.732241 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Aug 12 23:59:33.921528 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Aug 12 23:59:33.934258 systemd[1]: Starting user@500.service - User Manager for UID 500... Aug 12 23:59:34.027251 (systemd)[1931]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Aug 12 23:59:34.030068 systemd-logind[1687]: New session c1 of user core. Aug 12 23:59:34.074211 waagent[1838]: 2025-08-12T23:59:34.074109Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Aug 12 23:59:34.095050 kernel: EXT4-fs (sdb1): mounted filesystem 254b5054-0218-4253-8a79-71b3f4e78c8a r/w with ordered data mode. Quota mode: none. Aug 12 23:59:34.095142 waagent[1838]: 2025-08-12T23:59:34.095063Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Aug 12 23:59:34.097349 waagent[1838]: 2025-08-12T23:59:34.097288Z INFO Daemon Daemon Clean protocol and wireserver endpoint Aug 12 23:59:34.097648 waagent[1838]: 2025-08-12T23:59:34.097604Z INFO Daemon Daemon Running default provisioning handler Aug 12 23:59:34.106102 waagent[1838]: 2025-08-12T23:59:34.105004Z 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. Aug 12 23:59:34.106912 waagent[1838]: 2025-08-12T23:59:34.106864Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Aug 12 23:59:34.107955 waagent[1838]: 2025-08-12T23:59:34.107913Z INFO Daemon Daemon cloud-init is enabled: False Aug 12 23:59:34.108725 waagent[1838]: 2025-08-12T23:59:34.108686Z INFO Daemon Daemon Copying ovf-env.xml Aug 12 23:59:34.166304 waagent[1838]: 2025-08-12T23:59:34.166162Z INFO Daemon Daemon Successfully mounted dvd Aug 12 23:59:34.190850 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Aug 12 23:59:34.191078 waagent[1838]: 2025-08-12T23:59:34.190923Z INFO Daemon Daemon Detect protocol endpoint Aug 12 23:59:34.192668 waagent[1838]: 2025-08-12T23:59:34.192615Z INFO Daemon Daemon Clean protocol and wireserver endpoint Aug 12 23:59:34.194236 waagent[1838]: 2025-08-12T23:59:34.194186Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Aug 12 23:59:34.194886 waagent[1838]: 2025-08-12T23:59:34.194845Z INFO Daemon Daemon Test for route to 168.63.129.16 Aug 12 23:59:34.196531 waagent[1838]: 2025-08-12T23:59:34.196486Z INFO Daemon Daemon Route to 168.63.129.16 exists Aug 12 23:59:34.197914 waagent[1838]: 2025-08-12T23:59:34.197866Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Aug 12 23:59:34.230649 waagent[1838]: 2025-08-12T23:59:34.230600Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Aug 12 23:59:34.232465 waagent[1838]: 2025-08-12T23:59:34.232431Z INFO Daemon Daemon Wire protocol version:2012-11-30 Aug 12 23:59:34.233063 waagent[1838]: 2025-08-12T23:59:34.233011Z INFO Daemon Daemon Server preferred version:2015-04-05 Aug 12 23:59:34.239368 systemd[1931]: Queued start job for default target default.target. Aug 12 23:59:34.258921 systemd[1931]: Created slice app.slice - User Application Slice. Aug 12 23:59:34.258967 systemd[1931]: Reached target paths.target - Paths. Aug 12 23:59:34.259030 systemd[1931]: Reached target timers.target - Timers. Aug 12 23:59:34.260459 systemd[1931]: Starting dbus.socket - D-Bus User Message Bus Socket... Aug 12 23:59:34.272829 systemd[1931]: Listening on dbus.socket - D-Bus User Message Bus Socket. Aug 12 23:59:34.272982 systemd[1931]: Reached target sockets.target - Sockets. Aug 12 23:59:34.273052 systemd[1931]: Reached target basic.target - Basic System. Aug 12 23:59:34.273163 systemd[1931]: Reached target default.target - Main User Target. Aug 12 23:59:34.273215 systemd[1931]: Startup finished in 236ms. Aug 12 23:59:34.273227 systemd[1]: Started user@500.service - User Manager for UID 500. Aug 12 23:59:34.274753 systemd[1]: Started session-1.scope - Session 1 of User core. Aug 12 23:59:34.377285 waagent[1838]: 2025-08-12T23:59:34.377179Z INFO Daemon Daemon Initializing goal state during protocol detection Aug 12 23:59:34.387702 waagent[1838]: 2025-08-12T23:59:34.379050Z INFO Daemon Daemon Forcing an update of the goal state. Aug 12 23:59:34.390561 waagent[1838]: 2025-08-12T23:59:34.390502Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Aug 12 23:59:34.404150 waagent[1838]: 2025-08-12T23:59:34.403931Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.175 Aug 12 23:59:34.410418 waagent[1838]: 2025-08-12T23:59:34.406381Z INFO Daemon Aug 12 23:59:34.410492 waagent[1838]: 2025-08-12T23:59:34.410418Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 99ff898d-7c1f-4313-8218-b36ada6014ee eTag: 12727428035069668978 source: Fabric] Aug 12 23:59:34.433729 waagent[1838]: 2025-08-12T23:59:34.414105Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Aug 12 23:59:34.433729 waagent[1838]: 2025-08-12T23:59:34.418679Z INFO Daemon Aug 12 23:59:34.433729 waagent[1838]: 2025-08-12T23:59:34.420148Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Aug 12 23:59:34.433729 waagent[1838]: 2025-08-12T23:59:34.424603Z INFO Daemon Daemon Downloading artifacts profile blob Aug 12 23:59:34.541211 waagent[1838]: 2025-08-12T23:59:34.541139Z INFO Daemon Downloaded certificate {'thumbprint': '01EB0041BC92804CCCFE635D22898BA7E4605F1F', 'hasPrivateKey': True} Aug 12 23:59:34.551509 waagent[1838]: 2025-08-12T23:59:34.543207Z INFO Daemon Fetch goal state completed Aug 12 23:59:34.579894 waagent[1838]: 2025-08-12T23:59:34.579839Z INFO Daemon Daemon Starting provisioning Aug 12 23:59:34.591010 waagent[1838]: 2025-08-12T23:59:34.581647Z INFO Daemon Daemon Handle ovf-env.xml. Aug 12 23:59:34.591010 waagent[1838]: 2025-08-12T23:59:34.583159Z INFO Daemon Daemon Set hostname [ci-4230.2.2-a-2f6de76cbe] Aug 12 23:59:34.654061 login[1840]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Aug 12 23:59:34.658573 systemd-logind[1687]: New session 2 of user core. Aug 12 23:59:34.664282 waagent[1838]: 2025-08-12T23:59:34.664195Z INFO Daemon Daemon Publish hostname [ci-4230.2.2-a-2f6de76cbe] Aug 12 23:59:34.666250 waagent[1838]: 2025-08-12T23:59:34.666191Z INFO Daemon Daemon Examine /proc/net/route for primary interface Aug 12 23:59:34.666812 waagent[1838]: 2025-08-12T23:59:34.666763Z INFO Daemon Daemon Primary interface is [eth0] Aug 12 23:59:34.678998 systemd-networkd[1419]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 12 23:59:34.679009 systemd-networkd[1419]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Aug 12 23:59:34.706582 waagent[1838]: 2025-08-12T23:59:34.679461Z INFO Daemon Daemon Create user account if not exists Aug 12 23:59:34.706582 waagent[1838]: 2025-08-12T23:59:34.681098Z INFO Daemon Daemon User core already exists, skip useradd Aug 12 23:59:34.706582 waagent[1838]: 2025-08-12T23:59:34.681785Z INFO Daemon Daemon Configure sudoer Aug 12 23:59:34.706582 waagent[1838]: 2025-08-12T23:59:34.683661Z INFO Daemon Daemon Configure sshd Aug 12 23:59:34.706582 waagent[1838]: 2025-08-12T23:59:34.684978Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Aug 12 23:59:34.706582 waagent[1838]: 2025-08-12T23:59:34.685547Z INFO Daemon Daemon Deploy ssh public key. Aug 12 23:59:34.679070 systemd-networkd[1419]: eth0: DHCP lease lost Aug 12 23:59:34.706484 systemd[1]: Started session-2.scope - Session 2 of User core. Aug 12 23:59:34.748261 systemd-networkd[1419]: eth0: DHCPv4 address 10.200.8.10/24, gateway 10.200.8.1 acquired from 168.63.129.16 Aug 12 23:59:35.846954 waagent[1838]: 2025-08-12T23:59:35.846880Z INFO Daemon Daemon Provisioning complete Aug 12 23:59:35.857683 waagent[1838]: 2025-08-12T23:59:35.857565Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Aug 12 23:59:35.859333 waagent[1838]: 2025-08-12T23:59:35.859282Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Aug 12 23:59:35.860096 waagent[1838]: 2025-08-12T23:59:35.860051Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.9.1.1 is the most current agent Aug 12 23:59:36.010212 waagent[1988]: 2025-08-12T23:59:36.010118Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.9.1.1) Aug 12 23:59:36.010587 waagent[1988]: 2025-08-12T23:59:36.010286Z INFO ExtHandler ExtHandler OS: flatcar 4230.2.2 Aug 12 23:59:36.010587 waagent[1988]: 2025-08-12T23:59:36.010370Z INFO ExtHandler ExtHandler Python: 3.11.11 Aug 12 23:59:36.028848 waagent[1988]: 2025-08-12T23:59:36.028791Z INFO ExtHandler ExtHandler Distro: flatcar-4230.2.2; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.11; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; Aug 12 23:59:36.029039 waagent[1988]: 2025-08-12T23:59:36.028981Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Aug 12 23:59:36.029130 waagent[1988]: 2025-08-12T23:59:36.029088Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Aug 12 23:59:36.036377 waagent[1988]: 2025-08-12T23:59:36.036268Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Aug 12 23:59:36.040934 waagent[1988]: 2025-08-12T23:59:36.040885Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.175 Aug 12 23:59:36.041397 waagent[1988]: 2025-08-12T23:59:36.041351Z INFO ExtHandler Aug 12 23:59:36.041492 waagent[1988]: 2025-08-12T23:59:36.041453Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: bcf69b8a-dc7a-496f-9817-bab1020d36c7 eTag: 12727428035069668978 source: Fabric] Aug 12 23:59:36.041825 waagent[1988]: 2025-08-12T23:59:36.041779Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Aug 12 23:59:36.042520 waagent[1988]: 2025-08-12T23:59:36.042472Z INFO ExtHandler Aug 12 23:59:36.042685 waagent[1988]: 2025-08-12T23:59:36.042566Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Aug 12 23:59:36.045953 waagent[1988]: 2025-08-12T23:59:36.045908Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Aug 12 23:59:36.116528 waagent[1988]: 2025-08-12T23:59:36.116393Z INFO ExtHandler Downloaded certificate {'thumbprint': '01EB0041BC92804CCCFE635D22898BA7E4605F1F', 'hasPrivateKey': True} Aug 12 23:59:36.117363 waagent[1988]: 2025-08-12T23:59:36.117309Z INFO ExtHandler Fetch goal state completed Aug 12 23:59:36.128779 waagent[1988]: 2025-08-12T23:59:36.128722Z INFO ExtHandler ExtHandler WALinuxAgent-2.9.1.1 running as process 1988 Aug 12 23:59:36.128933 waagent[1988]: 2025-08-12T23:59:36.128889Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Aug 12 23:59:36.130760 waagent[1988]: 2025-08-12T23:59:36.130712Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '4230.2.2', '', 'Flatcar Container Linux by Kinvolk'] Aug 12 23:59:36.131173 waagent[1988]: 2025-08-12T23:59:36.131126Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Aug 12 23:59:36.171710 waagent[1988]: 2025-08-12T23:59:36.171666Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Aug 12 23:59:36.171882 waagent[1988]: 2025-08-12T23:59:36.171838Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Aug 12 23:59:36.178508 waagent[1988]: 2025-08-12T23:59:36.178469Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Aug 12 23:59:36.187469 systemd[1]: Reload requested from client PID 2001 ('systemctl') (unit waagent.service)... Aug 12 23:59:36.187486 systemd[1]: Reloading... Aug 12 23:59:36.255113 zram_generator::config[2041]: No configuration found. Aug 12 23:59:36.401529 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. Aug 12 23:59:36.551257 systemd[1]: Reloading finished in 363 ms. Aug 12 23:59:36.564199 waagent[1988]: 2025-08-12T23:59:36.564061Z INFO ExtHandler ExtHandler Executing systemctl daemon-reload for setting up waagent-network-setup.service Aug 12 23:59:36.570169 systemd[1]: Reload requested from client PID 2098 ('systemctl') (unit waagent.service)... Aug 12 23:59:36.570184 systemd[1]: Reloading... Aug 12 23:59:36.634073 zram_generator::config[2139]: No configuration found. Aug 12 23:59:36.774700 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. Aug 12 23:59:36.922546 systemd[1]: Reloading finished in 351 ms. Aug 12 23:59:36.938632 waagent[1988]: 2025-08-12T23:59:36.938540Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Aug 12 23:59:36.938764 waagent[1988]: 2025-08-12T23:59:36.938719Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Aug 12 23:59:37.563669 waagent[1988]: 2025-08-12T23:59:37.563569Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Aug 12 23:59:37.564308 waagent[1988]: 2025-08-12T23:59:37.564249Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: configuration enabled [True], cgroups enabled [False], python supported: [True] Aug 12 23:59:37.565183 waagent[1988]: 2025-08-12T23:59:37.565130Z INFO ExtHandler ExtHandler Starting env monitor service. Aug 12 23:59:37.565580 waagent[1988]: 2025-08-12T23:59:37.565525Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Aug 12 23:59:37.565773 waagent[1988]: 2025-08-12T23:59:37.565724Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Aug 12 23:59:37.565968 waagent[1988]: 2025-08-12T23:59:37.565924Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Aug 12 23:59:37.566416 waagent[1988]: 2025-08-12T23:59:37.566361Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Aug 12 23:59:37.566528 waagent[1988]: 2025-08-12T23:59:37.566457Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Aug 12 23:59:37.566597 waagent[1988]: 2025-08-12T23:59:37.566548Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Aug 12 23:59:37.566915 waagent[1988]: 2025-08-12T23:59:37.566856Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Aug 12 23:59:37.567578 waagent[1988]: 2025-08-12T23:59:37.567524Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Aug 12 23:59:37.567703 waagent[1988]: 2025-08-12T23:59:37.567635Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Aug 12 23:59:37.567824 waagent[1988]: 2025-08-12T23:59:37.567774Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Aug 12 23:59:37.568055 waagent[1988]: 2025-08-12T23:59:37.567987Z INFO EnvHandler ExtHandler Configure routes Aug 12 23:59:37.568255 waagent[1988]: 2025-08-12T23:59:37.568203Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Aug 12 23:59:37.568255 waagent[1988]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Aug 12 23:59:37.568255 waagent[1988]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Aug 12 23:59:37.568255 waagent[1988]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Aug 12 23:59:37.568255 waagent[1988]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Aug 12 23:59:37.568255 waagent[1988]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Aug 12 23:59:37.568255 waagent[1988]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Aug 12 23:59:37.568440 waagent[1988]: 2025-08-12T23:59:37.568309Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Aug 12 23:59:37.568804 waagent[1988]: 2025-08-12T23:59:37.568759Z INFO EnvHandler ExtHandler Gateway:None Aug 12 23:59:37.569437 waagent[1988]: 2025-08-12T23:59:37.569389Z INFO EnvHandler ExtHandler Routes:None Aug 12 23:59:37.575354 waagent[1988]: 2025-08-12T23:59:37.575301Z INFO ExtHandler ExtHandler Aug 12 23:59:37.575627 waagent[1988]: 2025-08-12T23:59:37.575580Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 1a2e438f-eb26-4eb8-a57e-e4c13880b5db correlation a5b612d8-6d2d-4db5-87ac-9fa858ba8ac9 created: 2025-08-12T23:58:12.247452Z] Aug 12 23:59:37.576824 waagent[1988]: 2025-08-12T23:59:37.576765Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Aug 12 23:59:37.577963 waagent[1988]: 2025-08-12T23:59:37.577917Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 2 ms] Aug 12 23:59:37.619104 waagent[1988]: 2025-08-12T23:59:37.619014Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.9.1.1 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: E03758F5-0852-4E27-983A-3C3959E5F956;DroppedPackets: 0;UpdateGSErrors: 0;AutoUpdate: 0] Aug 12 23:59:37.675394 waagent[1988]: 2025-08-12T23:59:37.675300Z INFO EnvHandler ExtHandler Successfully added Azure fabric firewall rules. Current Firewall rules: Aug 12 23:59:37.675394 waagent[1988]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Aug 12 23:59:37.675394 waagent[1988]: pkts bytes target prot opt in out source destination Aug 12 23:59:37.675394 waagent[1988]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Aug 12 23:59:37.675394 waagent[1988]: pkts bytes target prot opt in out source destination Aug 12 23:59:37.675394 waagent[1988]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Aug 12 23:59:37.675394 waagent[1988]: pkts bytes target prot opt in out source destination Aug 12 23:59:37.675394 waagent[1988]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Aug 12 23:59:37.675394 waagent[1988]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Aug 12 23:59:37.675394 waagent[1988]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Aug 12 23:59:37.684426 waagent[1988]: 2025-08-12T23:59:37.684364Z INFO EnvHandler ExtHandler Current Firewall rules: Aug 12 23:59:37.684426 waagent[1988]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Aug 12 23:59:37.684426 waagent[1988]: pkts bytes target prot opt in out source destination Aug 12 23:59:37.684426 waagent[1988]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Aug 12 23:59:37.684426 waagent[1988]: pkts bytes target prot opt in out source destination Aug 12 23:59:37.684426 waagent[1988]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Aug 12 23:59:37.684426 waagent[1988]: pkts bytes target prot opt in out source destination Aug 12 23:59:37.684426 waagent[1988]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Aug 12 23:59:37.684426 waagent[1988]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Aug 12 23:59:37.684426 waagent[1988]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Aug 12 23:59:37.684696 waagent[1988]: 2025-08-12T23:59:37.684665Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Aug 12 23:59:37.684859 waagent[1988]: 2025-08-12T23:59:37.684819Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Aug 12 23:59:37.782770 waagent[1988]: 2025-08-12T23:59:37.782681Z INFO MonitorHandler ExtHandler Network interfaces: Aug 12 23:59:37.782770 waagent[1988]: Executing ['ip', '-a', '-o', 'link']: Aug 12 23:59:37.782770 waagent[1988]: 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 Aug 12 23:59:37.782770 waagent[1988]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:1e:52:88:1c:2e brd ff:ff:ff:ff:ff:ff Aug 12 23:59:37.782770 waagent[1988]: Executing ['ip', '-4', '-a', '-o', 'address']: Aug 12 23:59:37.782770 waagent[1988]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Aug 12 23:59:37.782770 waagent[1988]: 2: eth0 inet 10.200.8.10/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Aug 12 23:59:37.782770 waagent[1988]: Executing ['ip', '-6', '-a', '-o', 'address']: Aug 12 23:59:37.782770 waagent[1988]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Aug 12 23:59:37.782770 waagent[1988]: 2: eth0 inet6 fe80::7e1e:52ff:fe88:1c2e/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Aug 12 23:59:48.151871 setup-nvidia[1853]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 482M 0 13037 0 0 18746 0 7:29:31 --:--:-- 7:29:31 18731\u000d 0 482M 0 3440k 0 0 2078k 0 0:03:57 0:00:01 0:03:56 2078k\u000d 5 482M 5 26.0M 0 0 9.8M 0 0:00:48 0:00:02 0:00:46 9.8M\u000d 10 482M 10 50.7M 0 0 13.9M 0 0:00:34 0:00:03 0:00:31 13.9M\u000d 15 482M 15 75.6M 0 0 16.3M 0 0:00:29 0:00:04 0:00:25 16.3M\u000d 20 482M 20 98.8M 0 0 17.6M 0 0:00:27 0:00:05 0:00:22 20.0M\u000d 24 482M 24 120M 0 0 18.2M 0 0:00:26 0:00:06 0:00:20 23.6M\u000d 28 482M 28 138M 0 0 17.9M 0 0:00:26 0:00:07 0:00:19 22.2M\u000d 33 482M 33 163M 0 0 18.7M 0 0:00:25 0:00:08 0:00:17 22.2M\u000d 38 482M 38 187M 0 0 19.4M 0 0:00:24 0:00:09 0:00:15 22.2M\u000d 43 482M 43 208M 0 0 19.5M 0 0:00:24 0:00:10 0:00:14 21.6M\u000d 48 482M 48 232M 0 0 19.9M 0 0:00:24 0:00:11 0:00:13 22.2M\u000d 53 482M 53 258M 0 0 20.4M 0 0:00:23 0:00:12 0:00:11 24.1M\u000d 58 482M 58 283M 0 0 20.7M 0 0:00:23 0:00:13 0:00:10 24.2M\u000d 63 482M 63 308M 0 0 21.0M 0 0:00:22 0:00:14 0:00:08 24.3M\u000d 69 482M 69 333M 0 0 21.3M 0 0:00:22 0:00:15 0:00:07 25.2M\u000d 74 482M 74 357M 0 0 21.5M 0 0:00:22 0:00:16 0:00:06 25.1M\u000d 79 482M 79 381M 0 0 21.6M 0 0:00:22 0:00:17 0:00:05 24.8M\u000d 84 482M 84 406M 0 0 21.8M 0 0:00:22 0:00:18 0:00:04 24.7M\u000d 89 482M 89 430M 0 0 21.9M 0 0:00:21 0:00:19 0:00:02 24.6M\u000d 94 482M 94 455M 0 0 22.1M 0 0:00:21 0:00:20 0:00:01 24.6M\u000d 99 482M 99 480M 0 0 22.2M 0 0:00:21 0:00:21 --:--:-- 24.7M\u000d100 482M 100 482M 0 0 22.3M 0 0:00:21 0:00:21 --:--:-- 25.3M Aug 12 23:59:48.963450 chronyd[1685]: Selected source PHC0 Aug 12 23:59:59.270116 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Aug 12 23:59:59.284407 systemd[1]: Started sshd@0-10.200.8.10:22-10.200.16.10:51608.service - OpenSSH per-connection server daemon (10.200.16.10:51608). Aug 12 23:59:59.562626 setup-nvidia[1682]: Downloading NVIDIA 535.230.02 Driver Aug 12 23:59:59.567927 setup-nvidia[2242]: % Total % Received % Xferd Average Speed Time Time Time Current Aug 12 23:59:59.568299 setup-nvidia[2242]: Dload Upload Total Spent Left Speed Aug 13 00:00:01.031285 systemd[1]: Started logrotate.service - Rotate and Compress System Logs. Aug 13 00:00:01.166574 sshd[2239]: Accepted publickey for core from 10.200.16.10 port 51608 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:00:01.168087 sshd-session[2239]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:00:01.172895 systemd-logind[1687]: New session 3 of user core. Aug 13 00:00:01.184162 systemd[1]: Started session-3.scope - Session 3 of User core. Aug 13 00:00:01.252666 systemd[1]: logrotate.service: Deactivated successfully. Aug 13 00:00:01.732729 systemd[1]: Started sshd@1-10.200.8.10:22-10.200.16.10:43532.service - OpenSSH per-connection server daemon (10.200.16.10:43532). Aug 13 00:00:01.855363 setup-nvidia[2242]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 7 326M 7 25.5M 0 0 45.0M 0 0:00:07 --:--:-- 0:00:07 44.9M\u000d 57 326M 57 188M 0 0 119M 0 0:00:02 0:00:01 0:00:01 119M\u000d100 326M 100 326M 0 0 142M 0 0:00:02 0:00:02 --:--:-- 142M Aug 13 00:00:02.368325 sshd[2248]: Accepted publickey for core from 10.200.16.10 port 43532 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:00:02.369772 sshd-session[2248]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:00:02.374483 systemd-logind[1687]: New session 4 of user core. Aug 13 00:00:02.387165 systemd[1]: Started session-4.scope - Session 4 of User core. Aug 13 00:00:02.827755 sshd[2251]: Connection closed by 10.200.16.10 port 43532 Aug 13 00:00:02.828233 sshd-session[2248]: pam_unix(sshd:session): session closed for user core Aug 13 00:00:02.831833 systemd[1]: sshd@1-10.200.8.10:22-10.200.16.10:43532.service: Deactivated successfully. Aug 13 00:00:02.833729 systemd[1]: session-4.scope: Deactivated successfully. Aug 13 00:00:02.834476 systemd-logind[1687]: Session 4 logged out. Waiting for processes to exit. Aug 13 00:00:02.835306 systemd-logind[1687]: Removed session 4. Aug 13 00:00:02.845176 setup-nvidia[1682]: Extract the NVIDIA Driver Installer 535.230.02 Aug 13 00:00:02.845388 setup-nvidia[1682]: /opt/nvidia/workdir/nvidia-workdir / Aug 13 00:00:02.863167 setup-nvidia[2257]: Creating directory NVIDIA-Linux-x86_64-535.230.02 Aug 13 00:00:02.940933 systemd[1]: Started sshd@2-10.200.8.10:22-10.200.16.10:43548.service - OpenSSH per-connection server daemon (10.200.16.10:43548). Aug 13 00:00:03.184088 setup-nvidia[2257]: Verifying archive integrity... OK Aug 13 00:00:03.575474 sshd[2277]: Accepted publickey for core from 10.200.16.10 port 43548 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:00:03.576933 sshd-session[2277]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:00:03.581487 systemd-logind[1687]: New session 5 of user core. Aug 13 00:00:03.595176 systemd[1]: Started session-5.scope - Session 5 of User core. Aug 13 00:00:04.027712 sshd[2283]: Connection closed by 10.200.16.10 port 43548 Aug 13 00:00:04.028182 sshd-session[2277]: pam_unix(sshd:session): session closed for user core Aug 13 00:00:04.031736 systemd[1]: sshd@2-10.200.8.10:22-10.200.16.10:43548.service: Deactivated successfully. Aug 13 00:00:04.033491 systemd[1]: session-5.scope: Deactivated successfully. Aug 13 00:00:04.034159 systemd-logind[1687]: Session 5 logged out. Waiting for processes to exit. Aug 13 00:00:04.034964 systemd-logind[1687]: Removed session 5. Aug 13 00:00:05.344190 setup-nvidia[2257]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.230.02 Aug 13 00:00:07.911747 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Aug 13 00:00:08.110177 setup-nvidia[2288]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Aug 13 00:00:09.691037 setup-nvidia[1682]: / Aug 13 00:00:09.691381 setup-nvidia[1682]: Spawn system-nspawn container to install the NVIDIA drivers Aug 13 00:00:09.982433 sudo[2298]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4230.2.2.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 Aug 13 00:00:09.984238 sudo[2298]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 13 00:00:09.994406 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Aug 13 00:00:10.016236 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Aug 13 00:00:10.026905 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Aug 13 00:00:10.030716 systemd[1]: Starting user@0.service - User Manager for UID 0... Aug 13 00:00:10.036856 (systemd)[2301]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Aug 13 00:00:10.039163 systemd-logind[1687]: New session c3 of user root. Aug 13 00:00:10.226429 systemd[2301]: Queued start job for default target default.target. Aug 13 00:00:10.238194 systemd[2301]: Created slice app.slice - User Application Slice. Aug 13 00:00:10.238225 systemd[2301]: Reached target paths.target - Paths. Aug 13 00:00:10.238276 systemd[2301]: Reached target timers.target - Timers. Aug 13 00:00:10.239599 systemd[2301]: Starting dbus.socket - D-Bus User Message Bus Socket... Aug 13 00:00:10.250654 systemd[2301]: Listening on dbus.socket - D-Bus User Message Bus Socket. Aug 13 00:00:10.250778 systemd[2301]: Reached target sockets.target - Sockets. Aug 13 00:00:10.250831 systemd[2301]: Reached target basic.target - Basic System. Aug 13 00:00:10.250880 systemd[2301]: Reached target default.target - Main User Target. Aug 13 00:00:10.250913 systemd[2301]: Startup finished in 206ms. Aug 13 00:00:10.251050 systemd[1]: Started user@0.service - User Manager for UID 0. Aug 13 00:00:10.255220 systemd[1]: Started session-c2.scope - Session c2 of User root. Aug 13 00:00:10.313037 kernel: loop6: detected capacity change from 0 to 12644352 Aug 13 00:00:10.315034 kernel: loop6: p9 Aug 13 00:00:10.352034 setup-nvidia[2310]: ░ Spawning container flatcardevelopercontainer-4230.2.2.bin on /opt/nvidia/workdir/flatcar_developer_container-4230.2.2.bin. Aug 13 00:00:10.360044 kernel: EXT4-fs (loop6p9): mounted filesystem f88d1834-7666-4561-9c55-bbe37350df98 ro without journal. Quota mode: none. Aug 13 00:00:10.807781 update_engine[1691]: I20250813 00:00:10.807704 1691 update_attempter.cc:509] Updating boot flags... Aug 13 00:00:10.902051 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 45 scanned by (udev-worker) (2386) Aug 13 00:00:10.992052 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 45 scanned by (udev-worker) (2388) Aug 13 00:00:14.145170 systemd[1]: Started sshd@3-10.200.8.10:22-10.200.16.10:48678.service - OpenSSH per-connection server daemon (10.200.16.10:48678). Aug 13 00:00:14.782387 sshd[3262]: Accepted publickey for core from 10.200.16.10 port 48678 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:00:14.783855 sshd-session[3262]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:00:14.788408 systemd-logind[1687]: New session 6 of user core. Aug 13 00:00:14.800167 systemd[1]: Started session-6.scope - Session 6 of User core. Aug 13 00:00:15.231425 sshd[3449]: Connection closed by 10.200.16.10 port 48678 Aug 13 00:00:15.231770 sshd-session[3262]: pam_unix(sshd:session): session closed for user core Aug 13 00:00:15.235642 systemd[1]: sshd@3-10.200.8.10:22-10.200.16.10:48678.service: Deactivated successfully. Aug 13 00:00:15.237380 systemd[1]: session-6.scope: Deactivated successfully. Aug 13 00:00:15.238034 systemd-logind[1687]: Session 6 logged out. Waiting for processes to exit. Aug 13 00:00:15.238946 systemd-logind[1687]: Removed session 6. Aug 13 00:00:15.342630 systemd[1]: Started sshd@4-10.200.8.10:22-10.200.16.10:48692.service - OpenSSH per-connection server daemon (10.200.16.10:48692). Aug 13 00:00:15.973206 sshd[3455]: Accepted publickey for core from 10.200.16.10 port 48692 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:00:15.974667 sshd-session[3455]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:00:15.979252 systemd-logind[1687]: New session 7 of user core. Aug 13 00:00:15.990150 systemd[1]: Started session-7.scope - Session 7 of User core. Aug 13 00:00:16.422226 sshd[3457]: Connection closed by 10.200.16.10 port 48692 Aug 13 00:00:16.422755 sshd-session[3455]: pam_unix(sshd:session): session closed for user core Aug 13 00:00:16.427245 systemd[1]: sshd@4-10.200.8.10:22-10.200.16.10:48692.service: Deactivated successfully. Aug 13 00:00:16.429162 systemd[1]: session-7.scope: Deactivated successfully. Aug 13 00:00:16.429921 systemd-logind[1687]: Session 7 logged out. Waiting for processes to exit. Aug 13 00:00:16.430770 systemd-logind[1687]: Removed session 7. Aug 13 00:00:26.541330 systemd[1]: Started sshd@5-10.200.8.10:22-10.200.16.10:49756.service - OpenSSH per-connection server daemon (10.200.16.10:49756). Aug 13 00:00:27.188058 sshd[6051]: Accepted publickey for core from 10.200.16.10 port 49756 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:00:27.190111 sshd-session[6051]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:00:27.196859 systemd-logind[1687]: New session 8 of user core. Aug 13 00:00:27.206198 systemd[1]: Started session-8.scope - Session 8 of User core. Aug 13 00:00:27.649758 sshd[6234]: Connection closed by 10.200.16.10 port 49756 Aug 13 00:00:27.653225 sshd-session[6051]: pam_unix(sshd:session): session closed for user core Aug 13 00:00:27.658971 systemd[1]: sshd@5-10.200.8.10:22-10.200.16.10:49756.service: Deactivated successfully. Aug 13 00:00:27.660929 systemd[1]: session-8.scope: Deactivated successfully. Aug 13 00:00:27.662900 systemd-logind[1687]: Session 8 logged out. Waiting for processes to exit. Aug 13 00:00:27.664297 systemd-logind[1687]: Removed session 8. Aug 13 00:00:27.783112 systemd[1]: Started sshd@6-10.200.8.10:22-10.200.16.10:49772.service - OpenSSH per-connection server daemon (10.200.16.10:49772). Aug 13 00:00:28.413739 sshd[6411]: Accepted publickey for core from 10.200.16.10 port 49772 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:00:28.415301 sshd-session[6411]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:00:28.420992 systemd-logind[1687]: New session 9 of user core. Aug 13 00:00:28.431169 systemd[1]: Started session-9.scope - Session 9 of User core. Aug 13 00:00:28.866181 sshd[6630]: Connection closed by 10.200.16.10 port 49772 Aug 13 00:00:28.866776 sshd-session[6411]: pam_unix(sshd:session): session closed for user core Aug 13 00:00:28.869914 systemd-logind[1687]: Session 9 logged out. Waiting for processes to exit. Aug 13 00:00:28.872107 systemd[1]: sshd@6-10.200.8.10:22-10.200.16.10:49772.service: Deactivated successfully. Aug 13 00:00:28.874320 systemd[1]: session-9.scope: Deactivated successfully. Aug 13 00:00:28.876704 systemd-logind[1687]: Removed session 9. Aug 13 00:00:38.988075 systemd[1]: Started sshd@7-10.200.8.10:22-10.200.16.10:57276.service - OpenSSH per-connection server daemon (10.200.16.10:57276). Aug 13 00:00:39.624631 sshd[9786]: Accepted publickey for core from 10.200.16.10 port 57276 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:00:39.626649 sshd-session[9786]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:00:39.635610 systemd-logind[1687]: New session 10 of user core. Aug 13 00:00:39.647671 systemd[1]: Started session-10.scope - Session 10 of User core. Aug 13 00:00:40.081296 sshd[9871]: Connection closed by 10.200.16.10 port 57276 Aug 13 00:00:40.082193 sshd-session[9786]: pam_unix(sshd:session): session closed for user core Aug 13 00:00:40.085915 systemd[1]: sshd@7-10.200.8.10:22-10.200.16.10:57276.service: Deactivated successfully. Aug 13 00:00:40.088646 systemd[1]: session-10.scope: Deactivated successfully. Aug 13 00:00:40.090113 systemd-logind[1687]: Session 10 logged out. Waiting for processes to exit. Aug 13 00:00:40.091733 systemd-logind[1687]: Removed session 10. Aug 13 00:00:40.197198 systemd[1]: Started sshd@8-10.200.8.10:22-10.200.16.10:57282.service - OpenSSH per-connection server daemon (10.200.16.10:57282). Aug 13 00:00:40.841350 sshd[10054]: Accepted publickey for core from 10.200.16.10 port 57282 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:00:40.842365 sshd-session[10054]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:00:40.849553 systemd-logind[1687]: New session 11 of user core. Aug 13 00:00:40.859283 systemd[1]: Started session-11.scope - Session 11 of User core. Aug 13 00:00:41.297073 sshd[10267]: Connection closed by 10.200.16.10 port 57282 Aug 13 00:00:41.297704 sshd-session[10054]: pam_unix(sshd:session): session closed for user core Aug 13 00:00:41.301807 systemd[1]: sshd@8-10.200.8.10:22-10.200.16.10:57282.service: Deactivated successfully. Aug 13 00:00:41.308779 systemd[1]: session-11.scope: Deactivated successfully. Aug 13 00:00:41.309891 systemd-logind[1687]: Session 11 logged out. Waiting for processes to exit. Aug 13 00:00:41.311232 systemd-logind[1687]: Removed session 11. Aug 13 00:00:51.416103 systemd[1]: Started sshd@9-10.200.8.10:22-10.200.16.10:36574.service - OpenSSH per-connection server daemon (10.200.16.10:36574). Aug 13 00:00:52.059317 sshd[11776]: Accepted publickey for core from 10.200.16.10 port 36574 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:00:52.062795 sshd-session[11776]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:00:52.071562 systemd-logind[1687]: New session 12 of user core. Aug 13 00:00:52.078927 systemd[1]: Started session-12.scope - Session 12 of User core. Aug 13 00:00:52.515066 sshd[11801]: Connection closed by 10.200.16.10 port 36574 Aug 13 00:00:52.516706 sshd-session[11776]: pam_unix(sshd:session): session closed for user core Aug 13 00:00:52.521375 systemd[1]: sshd@9-10.200.8.10:22-10.200.16.10:36574.service: Deactivated successfully. Aug 13 00:00:52.523469 systemd[1]: session-12.scope: Deactivated successfully. Aug 13 00:00:52.527451 systemd-logind[1687]: Session 12 logged out. Waiting for processes to exit. Aug 13 00:00:52.528501 systemd-logind[1687]: Removed session 12. Aug 13 00:00:52.635334 systemd[1]: Started sshd@10-10.200.8.10:22-10.200.16.10:36580.service - OpenSSH per-connection server daemon (10.200.16.10:36580). Aug 13 00:00:53.271719 sshd[11831]: Accepted publickey for core from 10.200.16.10 port 36580 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:00:53.273730 sshd-session[11831]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:00:53.280113 systemd-logind[1687]: New session 13 of user core. Aug 13 00:00:53.284369 systemd[1]: Started session-13.scope - Session 13 of User core. Aug 13 00:00:53.722393 sshd[11858]: Connection closed by 10.200.16.10 port 36580 Aug 13 00:00:53.724182 sshd-session[11831]: pam_unix(sshd:session): session closed for user core Aug 13 00:00:53.727297 systemd[1]: sshd@10-10.200.8.10:22-10.200.16.10:36580.service: Deactivated successfully. Aug 13 00:00:53.731301 systemd[1]: session-13.scope: Deactivated successfully. Aug 13 00:00:53.733053 systemd-logind[1687]: Session 13 logged out. Waiting for processes to exit. Aug 13 00:00:53.734561 systemd-logind[1687]: Removed session 13. Aug 13 00:01:03.837091 systemd[1]: Started sshd@11-10.200.8.10:22-10.200.16.10:33742.service - OpenSSH per-connection server daemon (10.200.16.10:33742). Aug 13 00:01:04.477044 sshd[12139]: Accepted publickey for core from 10.200.16.10 port 33742 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:01:04.478714 sshd-session[12139]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:01:04.485746 systemd-logind[1687]: New session 14 of user core. Aug 13 00:01:04.497348 systemd[1]: Started session-14.scope - Session 14 of User core. Aug 13 00:01:04.937115 sshd[12165]: Connection closed by 10.200.16.10 port 33742 Aug 13 00:01:04.937777 sshd-session[12139]: pam_unix(sshd:session): session closed for user core Aug 13 00:01:04.943516 systemd[1]: sshd@11-10.200.8.10:22-10.200.16.10:33742.service: Deactivated successfully. Aug 13 00:01:04.945581 systemd-logind[1687]: Session 14 logged out. Waiting for processes to exit. Aug 13 00:01:04.946390 systemd[1]: session-14.scope: Deactivated successfully. Aug 13 00:01:04.947808 systemd-logind[1687]: Removed session 14. Aug 13 00:01:05.069358 systemd[1]: Started sshd@12-10.200.8.10:22-10.200.16.10:33758.service - OpenSSH per-connection server daemon (10.200.16.10:33758). Aug 13 00:01:05.705124 sshd[12185]: Accepted publickey for core from 10.200.16.10 port 33758 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:01:05.706769 sshd-session[12185]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:01:05.714809 systemd-logind[1687]: New session 15 of user core. Aug 13 00:01:05.727279 systemd[1]: Started session-15.scope - Session 15 of User core. Aug 13 00:01:06.155621 sshd[12211]: Connection closed by 10.200.16.10 port 33758 Aug 13 00:01:06.156600 sshd-session[12185]: pam_unix(sshd:session): session closed for user core Aug 13 00:01:06.162291 systemd[1]: sshd@12-10.200.8.10:22-10.200.16.10:33758.service: Deactivated successfully. Aug 13 00:01:06.164180 systemd-logind[1687]: Session 15 logged out. Waiting for processes to exit. Aug 13 00:01:06.165008 systemd[1]: session-15.scope: Deactivated successfully. Aug 13 00:01:06.167900 systemd-logind[1687]: Removed session 15. Aug 13 00:01:16.274517 systemd[1]: Started sshd@13-10.200.8.10:22-10.200.16.10:49636.service - OpenSSH per-connection server daemon (10.200.16.10:49636). Aug 13 00:01:16.913504 sshd[12497]: Accepted publickey for core from 10.200.16.10 port 49636 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:01:16.915853 sshd-session[12497]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:01:16.922256 systemd-logind[1687]: New session 16 of user core. Aug 13 00:01:16.934240 systemd[1]: Started session-16.scope - Session 16 of User core. Aug 13 00:01:17.359385 sshd[12517]: Connection closed by 10.200.16.10 port 49636 Aug 13 00:01:17.360148 sshd-session[12497]: pam_unix(sshd:session): session closed for user core Aug 13 00:01:17.363417 systemd-logind[1687]: Session 16 logged out. Waiting for processes to exit. Aug 13 00:01:17.365225 systemd[1]: sshd@13-10.200.8.10:22-10.200.16.10:49636.service: Deactivated successfully. Aug 13 00:01:17.367303 systemd[1]: session-16.scope: Deactivated successfully. Aug 13 00:01:17.368861 systemd-logind[1687]: Removed session 16. Aug 13 00:01:17.476331 systemd[1]: Started sshd@14-10.200.8.10:22-10.200.16.10:49646.service - OpenSSH per-connection server daemon (10.200.16.10:49646). Aug 13 00:01:18.114659 sshd[12535]: Accepted publickey for core from 10.200.16.10 port 49646 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:01:18.116241 sshd-session[12535]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:01:18.123200 systemd-logind[1687]: New session 17 of user core. Aug 13 00:01:18.137201 systemd[1]: Started session-17.scope - Session 17 of User core. Aug 13 00:01:18.561451 sshd[12561]: Connection closed by 10.200.16.10 port 49646 Aug 13 00:01:18.561969 sshd-session[12535]: pam_unix(sshd:session): session closed for user core Aug 13 00:01:18.565013 systemd-logind[1687]: Session 17 logged out. Waiting for processes to exit. Aug 13 00:01:18.566964 systemd[1]: sshd@14-10.200.8.10:22-10.200.16.10:49646.service: Deactivated successfully. Aug 13 00:01:18.568739 systemd[1]: session-17.scope: Deactivated successfully. Aug 13 00:01:18.570557 systemd-logind[1687]: Removed session 17. Aug 13 00:01:28.686931 systemd[1]: Started sshd@15-10.200.8.10:22-10.200.16.10:52148.service - OpenSSH per-connection server daemon (10.200.16.10:52148). Aug 13 00:01:29.318413 sshd[12777]: Accepted publickey for core from 10.200.16.10 port 52148 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:01:29.319831 sshd-session[12777]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:01:29.324295 systemd-logind[1687]: New session 18 of user core. Aug 13 00:01:29.338157 systemd[1]: Started session-18.scope - Session 18 of User core. Aug 13 00:01:29.628934 setup-nvidia[2310]: \u000d Aug 13 00:01:29.628934 setup-nvidia[2310]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Aug 13 00:01:29.628934 setup-nvidia[2310]: frequently when this kernel module was built against the wrong or Aug 13 00:01:29.628934 setup-nvidia[2310]: improperly configured kernel sources, with a version of gcc that differs Aug 13 00:01:29.628934 setup-nvidia[2310]: from the one used to build the target kernel, or if another driver, such Aug 13 00:01:29.628934 setup-nvidia[2310]: as nouveau, is present and prevents the NVIDIA kernel module from Aug 13 00:01:29.628934 setup-nvidia[2310]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Aug 13 00:01:29.628934 setup-nvidia[2310]: installed in this system is supported by this NVIDIA Linux graphics Aug 13 00:01:29.628934 setup-nvidia[2310]: driver release. Aug 13 00:01:29.628934 setup-nvidia[2310]: \u000d Aug 13 00:01:29.628934 setup-nvidia[2310]: Please see the log entries 'Kernel module load error' and 'Kernel Aug 13 00:01:29.628934 setup-nvidia[2310]: messages' at the end of the file Aug 13 00:01:29.628934 setup-nvidia[2310]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more Aug 13 00:01:29.628934 setup-nvidia[2310]: information. Aug 13 00:01:29.628934 setup-nvidia[2310]: \u000d Aug 13 00:01:29.647272 setup-nvidia[2310]: dmesg: read kernel buffer failed: Operation not permitted Aug 13 00:01:29.670428 setup-nvidia[2310]: \u000d Aug 13 00:01:29.670428 setup-nvidia[2310]: ERROR: Installation has failed. Please see the file Aug 13 00:01:29.670428 setup-nvidia[2310]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for Aug 13 00:01:29.670428 setup-nvidia[2310]: details. You may find suggestions on fixing installation problems in Aug 13 00:01:29.670428 setup-nvidia[2310]: the README available on the Linux driver download page at Aug 13 00:01:29.670428 setup-nvidia[2310]: www.nvidia.com. Aug 13 00:01:29.670428 setup-nvidia[2310]: \u000d Aug 13 00:01:29.670886 setup-nvidia[2310]: Last 50 lines of nvidia-installer.log: Aug 13 00:01:29.671753 setup-nvidia[2310]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038989rm+0x32: 'naked' return found in RETHUNK build Aug 13 00:01:29.671753 setup-nvidia[2310]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039000rm+0x53: 'naked' return found in RETHUNK build Aug 13 00:01:29.671753 setup-nvidia[2310]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038997rm+0x90: 'naked' return found in RETHUNK build Aug 13 00:01:29.671753 setup-nvidia[2310]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038990rm+0x2b: 'naked' return found in RETHUNK build Aug 13 00:01:29.671753 setup-nvidia[2310]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039046rm+0x93: 'naked' return found in RETHUNK build Aug 13 00:01:29.671753 setup-nvidia[2310]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039042rm+0x36: 'naked' return found in RETHUNK build Aug 13 00:01:29.671753 setup-nvidia[2310]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039041rm+0x9d: 'naked' return found in RETHUNK build Aug 13 00:01:29.671753 setup-nvidia[2310]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039043rm+0x77: 'naked' return found in RETHUNK build Aug 13 00:01:29.671753 setup-nvidia[2310]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038987rm+0x7f: 'naked' return found in RETHUNK build Aug 13 00:01:29.671753 setup-nvidia[2310]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030558rm+0x3e: 'naked' return found in RETHUNK build Aug 13 00:01:29.671753 setup-nvidia[2310]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030557rm+0x27: 'naked' return found in RETHUNK build Aug 13 00:01:29.671753 setup-nvidia[2310]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030561rm+0x37: 'naked' return found in RETHUNK build Aug 13 00:01:29.671753 setup-nvidia[2310]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030560rm+0x24: 'naked' return found in RETHUNK build Aug 13 00:01:29.671753 setup-nvidia[2310]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030559rm+0xca: 'naked' return found in RETHUNK build Aug 13 00:01:29.671753 setup-nvidia[2310]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030562rm+0x193: 'naked' return found in RETHUNK build Aug 13 00:01:29.671753 setup-nvidia[2310]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030564rm+0x17f: 'naked' return found in RETHUNK build Aug 13 00:01:29.671753 setup-nvidia[2310]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv011860rm+0x5d: 'naked' return found in RETHUNK build Aug 13 00:01:29.671753 setup-nvidia[2310]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039354rm+0x12f: 'naked' return found in RETHUNK build Aug 13 00:01:29.671753 setup-nvidia[2310]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/Module.symvers Aug 13 00:01:29.671753 setup-nvidia[2310]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.mod.o Aug 13 00:01:29.671753 setup-nvidia[2310]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.mod.o Aug 13 00:01:29.671753 setup-nvidia[2310]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.mod.o Aug 13 00:01:29.671753 setup-nvidia[2310]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.mod.o Aug 13 00:01:29.671753 setup-nvidia[2310]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.mod.o Aug 13 00:01:29.671753 setup-nvidia[2310]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Aug 13 00:01:29.671753 setup-nvidia[2310]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Aug 13 00:01:29.671753 setup-nvidia[2310]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Aug 13 00:01:29.671753 setup-nvidia[2310]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Aug 13 00:01:29.671753 setup-nvidia[2310]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Aug 13 00:01:29.671753 setup-nvidia[2310]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Aug 13 00:01:29.671753 setup-nvidia[2310]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko due to unavailability of vmlinux Aug 13 00:01:29.671753 setup-nvidia[2310]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Aug 13 00:01:29.671753 setup-nvidia[2310]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko due to unavailability of vmlinux Aug 13 00:01:29.671753 setup-nvidia[2310]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Aug 13 00:01:29.671753 setup-nvidia[2310]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko due to unavailability of vmlinux Aug 13 00:01:29.671753 setup-nvidia[2310]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Aug 13 00:01:29.671753 setup-nvidia[2310]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko due to unavailability of vmlinux Aug 13 00:01:29.671753 setup-nvidia[2310]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Aug 13 00:01:29.671753 setup-nvidia[2310]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko due to unavailability of vmlinux Aug 13 00:01:29.671753 setup-nvidia[2310]: make[2]: Leaving directory '/usr/lib/modules/6.6.100-flatcar/build' Aug 13 00:01:29.671753 setup-nvidia[2310]: make[1]: Leaving directory '/usr/lib/modules/6.6.100-flatcar/source' Aug 13 00:01:29.671753 setup-nvidia[2310]: -> done. Aug 13 00:01:29.671753 setup-nvidia[2310]: -> Kernel module compilation complete. Aug 13 00:01:29.671753 setup-nvidia[2310]: -> Unable to determine if Secure Boot is enabled: No such file or directory Aug 13 00:01:29.672915 setup-nvidia[2310]: 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. Aug 13 00:01:29.672915 setup-nvidia[2310]: \u000d Aug 13 00:01:29.672915 setup-nvidia[2310]: 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. Aug 13 00:01:29.672915 setup-nvidia[2310]: -> Kernel module load error: Operation not permitted Aug 13 00:01:29.672915 setup-nvidia[2310]: -> Kernel messages: Aug 13 00:01:29.672915 setup-nvidia[2310]: 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. Aug 13 00:01:29.775183 sshd[12830]: Connection closed by 10.200.16.10 port 52148 Aug 13 00:01:29.775672 sshd-session[12777]: pam_unix(sshd:session): session closed for user core Aug 13 00:01:29.779381 systemd[1]: sshd@15-10.200.8.10:22-10.200.16.10:52148.service: Deactivated successfully. Aug 13 00:01:29.781308 systemd[1]: session-18.scope: Deactivated successfully. Aug 13 00:01:29.782039 systemd-logind[1687]: Session 18 logged out. Waiting for processes to exit. Aug 13 00:01:29.782884 systemd-logind[1687]: Removed session 18. Aug 13 00:01:29.888501 systemd[1]: Started sshd@16-10.200.8.10:22-10.200.16.10:52152.service - OpenSSH per-connection server daemon (10.200.16.10:52152). Aug 13 00:01:30.522137 sshd[12924]: Accepted publickey for core from 10.200.16.10 port 52152 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:01:30.523658 sshd-session[12924]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:01:30.528230 systemd-logind[1687]: New session 19 of user core. Aug 13 00:01:30.541139 systemd[1]: Started session-19.scope - Session 19 of User core. Aug 13 00:01:30.966645 sshd[12927]: Connection closed by 10.200.16.10 port 52152 Aug 13 00:01:30.967119 sshd-session[12924]: pam_unix(sshd:session): session closed for user core Aug 13 00:01:30.970637 systemd[1]: sshd@16-10.200.8.10:22-10.200.16.10:52152.service: Deactivated successfully. Aug 13 00:01:30.972540 systemd[1]: session-19.scope: Deactivated successfully. Aug 13 00:01:30.973225 systemd-logind[1687]: Session 19 logged out. Waiting for processes to exit. Aug 13 00:01:30.974034 systemd-logind[1687]: Removed session 19. Aug 13 00:01:41.082821 systemd[1]: Started sshd@17-10.200.8.10:22-10.200.16.10:60498.service - OpenSSH per-connection server daemon (10.200.16.10:60498). Aug 13 00:01:41.715817 sshd[12933]: Accepted publickey for core from 10.200.16.10 port 60498 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:01:41.717339 sshd-session[12933]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:01:41.721746 systemd-logind[1687]: New session 20 of user core. Aug 13 00:01:41.732141 systemd[1]: Started session-20.scope - Session 20 of User core. Aug 13 00:01:42.161007 sshd[12936]: Connection closed by 10.200.16.10 port 60498 Aug 13 00:01:42.161565 sshd-session[12933]: pam_unix(sshd:session): session closed for user core Aug 13 00:01:42.165978 systemd[1]: sshd@17-10.200.8.10:22-10.200.16.10:60498.service: Deactivated successfully. Aug 13 00:01:42.167889 systemd[1]: session-20.scope: Deactivated successfully. Aug 13 00:01:42.168643 systemd-logind[1687]: Session 20 logged out. Waiting for processes to exit. Aug 13 00:01:42.169476 systemd-logind[1687]: Removed session 20. Aug 13 00:01:42.272867 systemd[1]: Started sshd@18-10.200.8.10:22-10.200.16.10:60504.service - OpenSSH per-connection server daemon (10.200.16.10:60504). Aug 13 00:01:42.906221 sshd[12942]: Accepted publickey for core from 10.200.16.10 port 60504 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:01:42.907795 sshd-session[12942]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:01:42.912521 systemd-logind[1687]: New session 21 of user core. Aug 13 00:01:42.923142 systemd[1]: Started session-21.scope - Session 21 of User core. Aug 13 00:01:43.359212 sshd[12944]: Connection closed by 10.200.16.10 port 60504 Aug 13 00:01:43.359740 sshd-session[12942]: pam_unix(sshd:session): session closed for user core Aug 13 00:01:43.363400 systemd[1]: sshd@18-10.200.8.10:22-10.200.16.10:60504.service: Deactivated successfully. Aug 13 00:01:43.365171 systemd[1]: session-21.scope: Deactivated successfully. Aug 13 00:01:43.365834 systemd-logind[1687]: Session 21 logged out. Waiting for processes to exit. Aug 13 00:01:43.367689 systemd-logind[1687]: Removed session 21. Aug 13 00:01:45.333049 kernel: EXT4-fs (loop6p9): unmounting filesystem f88d1834-7666-4561-9c55-bbe37350df98. Aug 13 00:01:45.333222 setup-nvidia[2310]: Container flatcardevelopercontainer-4230.2.2.bin exited successfully. Aug 13 00:01:45.335710 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4230.2.2.bin.mount: Deactivated successfully. Aug 13 00:01:45.392946 sudo[2298]: pam_unix(sudo:session): session closed for user root Aug 13 00:01:45.394760 systemd[1]: session-c2.scope: Deactivated successfully. Aug 13 00:01:45.395096 systemd[1]: session-c2.scope: Consumed 5min 32.034s CPU time, 883M memory peak. Aug 13 00:01:45.851327 setup-nvidia[1682]: /opt/nvidia / Aug 13 00:01:45.852342 setup-nvidia[1682]: / Aug 13 00:01:45.869555 setup-nvidia[12966]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Aug 13 00:01:45.892044 kernel: IPMI message handler: version 39.2 Aug 13 00:01:45.895045 kernel: ipmi device interface Aug 13 00:01:45.896453 setup-nvidia[1682]: /opt/nvidia/current/usr/lib/modules/6.6.100-flatcar/video / Aug 13 00:01:45.959457 kernel: nvidia: loading out-of-tree module taints kernel. Aug 13 00:01:45.959615 kernel: nvidia: module license 'NVIDIA' taints kernel. Aug 13 00:01:45.959637 kernel: Disabling lock debugging due to kernel taint Aug 13 00:01:45.966746 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Aug 13 00:01:45.966810 kernel: nvidia: module license taints kernel. Aug 13 00:01:46.369054 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 245 Aug 13 00:01:46.909580 kernel: Aug 13 00:01:46.909750 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Aug 13 00:01:47.117052 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.230.02 Fri Dec 20 21:42:05 UTC 2024 Aug 13 00:01:47.139053 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.230.02 Fri Dec 20 21:52:34 UTC 2024 Aug 13 00:01:47.166077 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Aug 13 00:01:47.215638 setup-nvidia[1682]: / Aug 13 00:01:47.216243 kernel: nvidia-uvm: Loaded the UVM driver, major device number 243. Aug 13 00:01:48.987834 setup-nvidia[12996]: Wed Aug 13 00:01:48 2025 Aug 13 00:01:48.987834 setup-nvidia[12996]: +---------------------------------------------------------------------------------------+ Aug 13 00:01:48.987834 setup-nvidia[12996]: | NVIDIA-SMI 535.230.02 Driver Version: 535.230.02 CUDA Version: 12.2 | Aug 13 00:01:48.987834 setup-nvidia[12996]: |-----------------------------------------+----------------------+----------------------+ Aug 13 00:01:48.987834 setup-nvidia[12996]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Aug 13 00:01:48.987834 setup-nvidia[12996]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Aug 13 00:01:48.987834 setup-nvidia[12996]: | | | MIG M. | Aug 13 00:01:48.987834 setup-nvidia[12996]: |=========================================+======================+======================| Aug 13 00:01:48.991397 setup-nvidia[12996]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Aug 13 00:01:48.991397 setup-nvidia[12996]: | N/A 29C P0 36W / 250W | 0MiB / 16384MiB | 0% Default | Aug 13 00:01:48.991397 setup-nvidia[12996]: | | | N/A | Aug 13 00:01:48.991397 setup-nvidia[12996]: +-----------------------------------------+----------------------+----------------------+ Aug 13 00:01:48.991526 setup-nvidia[12996]: Aug 13 00:01:48.991526 setup-nvidia[12996]: +---------------------------------------------------------------------------------------+ Aug 13 00:01:48.991526 setup-nvidia[12996]: | Processes: | Aug 13 00:01:48.991526 setup-nvidia[12996]: | GPU GI CI PID Type Process name GPU Memory | Aug 13 00:01:48.991526 setup-nvidia[12996]: | ID ID Usage | Aug 13 00:01:48.991526 setup-nvidia[12996]: |=======================================================================================| Aug 13 00:01:48.991667 setup-nvidia[12996]: | No running processes found | Aug 13 00:01:48.991667 setup-nvidia[12996]: +---------------------------------------------------------------------------------------+ Aug 13 00:01:49.367466 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Aug 13 00:01:49.372698 systemd[1]: Reached target multi-user.target - Multi-User System. Aug 13 00:01:49.377515 systemd[1]: Startup finished in 1.035s (firmware) + 26.623s (loader) + 2.028s (kernel) + 11.467s (initrd) + 2min 36.074s (userspace) = 3min 17.230s. Aug 13 00:01:53.472621 systemd[1]: Started sshd@19-10.200.8.10:22-10.200.16.10:55752.service - OpenSSH per-connection server daemon (10.200.16.10:55752). Aug 13 00:01:54.105708 sshd[13003]: Accepted publickey for core from 10.200.16.10 port 55752 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:01:54.107260 sshd-session[13003]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:01:54.113128 systemd-logind[1687]: New session 22 of user core. Aug 13 00:01:54.130164 systemd[1]: Started session-22.scope - Session 22 of User core. Aug 13 00:01:54.559801 sshd[13005]: Connection closed by 10.200.16.10 port 55752 Aug 13 00:01:54.560258 sshd-session[13003]: pam_unix(sshd:session): session closed for user core Aug 13 00:01:54.563790 systemd[1]: sshd@19-10.200.8.10:22-10.200.16.10:55752.service: Deactivated successfully. Aug 13 00:01:54.565516 systemd[1]: session-22.scope: Deactivated successfully. Aug 13 00:01:54.566180 systemd-logind[1687]: Session 22 logged out. Waiting for processes to exit. Aug 13 00:01:54.566979 systemd-logind[1687]: Removed session 22. Aug 13 00:01:54.672652 systemd[1]: Started sshd@20-10.200.8.10:22-10.200.16.10:55764.service - OpenSSH per-connection server daemon (10.200.16.10:55764). Aug 13 00:01:55.304465 sshd[13011]: Accepted publickey for core from 10.200.16.10 port 55764 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:01:55.305883 sshd-session[13011]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:01:55.310354 systemd-logind[1687]: New session 23 of user core. Aug 13 00:01:55.317142 systemd[1]: Started session-23.scope - Session 23 of User core. Aug 13 00:01:55.528603 systemd[1]: Stopping user@0.service - User Manager for UID 0... Aug 13 00:01:55.529458 systemd[2301]: Activating special unit exit.target... Aug 13 00:01:55.529551 systemd[2301]: Stopped target default.target - Main User Target. Aug 13 00:01:55.529592 systemd[2301]: Stopped target basic.target - Basic System. Aug 13 00:01:55.529624 systemd[2301]: Stopped target paths.target - Paths. Aug 13 00:01:55.529653 systemd[2301]: Stopped target sockets.target - Sockets. Aug 13 00:01:55.529683 systemd[2301]: Stopped target timers.target - Timers. Aug 13 00:01:55.530086 systemd[2301]: Closed dbus.socket - D-Bus User Message Bus Socket. Aug 13 00:01:55.530314 systemd[2301]: Removed slice app.slice - User Application Slice. Aug 13 00:01:55.530352 systemd[2301]: Reached target shutdown.target - Shutdown. Aug 13 00:01:55.530511 systemd[2301]: Finished systemd-exit.service - Exit the Session. Aug 13 00:01:55.530553 systemd[2301]: Reached target exit.target - Exit the Session. Aug 13 00:01:55.544340 (sd-pam)[2302]: pam_unix(systemd-user:session): session closed for user root Aug 13 00:01:55.545962 systemd[1]: user@0.service: Deactivated successfully. Aug 13 00:01:55.546607 systemd[1]: Stopped user@0.service - User Manager for UID 0. Aug 13 00:01:55.559189 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Aug 13 00:01:55.568382 systemd[1]: run-user-0.mount: Deactivated successfully. Aug 13 00:01:55.569381 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Aug 13 00:01:55.569591 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Aug 13 00:01:55.571392 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Aug 13 00:01:55.571486 systemd[1]: user-0.slice: Consumed 5min 32.319s CPU time, 892M memory peak. Aug 13 00:01:55.571871 systemd-logind[1687]: Removed session c3. Aug 13 00:01:55.744449 sshd[13013]: Connection closed by 10.200.16.10 port 55764 Aug 13 00:01:55.744853 sshd-session[13011]: pam_unix(sshd:session): session closed for user core Aug 13 00:01:55.748530 systemd[1]: sshd@20-10.200.8.10:22-10.200.16.10:55764.service: Deactivated successfully. Aug 13 00:01:55.750307 systemd[1]: session-23.scope: Deactivated successfully. Aug 13 00:01:55.750953 systemd-logind[1687]: Session 23 logged out. Waiting for processes to exit. Aug 13 00:01:55.751768 systemd-logind[1687]: Removed session 23. Aug 13 00:01:55.863664 systemd[1]: Started sshd@21-10.200.8.10:22-10.200.16.10:55770.service - OpenSSH per-connection server daemon (10.200.16.10:55770). Aug 13 00:01:56.496583 sshd[13023]: Accepted publickey for core from 10.200.16.10 port 55770 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:01:56.498011 sshd-session[13023]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:01:56.502593 systemd-logind[1687]: New session 24 of user core. Aug 13 00:01:56.513142 systemd[1]: Started session-24.scope - Session 24 of User core. Aug 13 00:01:56.942585 sshd[13025]: Connection closed by 10.200.16.10 port 55770 Aug 13 00:01:56.943291 sshd-session[13023]: pam_unix(sshd:session): session closed for user core Aug 13 00:01:56.947723 systemd[1]: sshd@21-10.200.8.10:22-10.200.16.10:55770.service: Deactivated successfully. Aug 13 00:01:56.949577 systemd[1]: session-24.scope: Deactivated successfully. Aug 13 00:01:56.950268 systemd-logind[1687]: Session 24 logged out. Waiting for processes to exit. Aug 13 00:01:56.951123 systemd-logind[1687]: Removed session 24. Aug 13 00:01:57.055710 systemd[1]: Started sshd@22-10.200.8.10:22-10.200.16.10:55782.service - OpenSSH per-connection server daemon (10.200.16.10:55782). Aug 13 00:01:57.691206 sshd[13031]: Accepted publickey for core from 10.200.16.10 port 55782 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:01:57.692661 sshd-session[13031]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:01:57.697122 systemd-logind[1687]: New session 25 of user core. Aug 13 00:01:57.709334 systemd[1]: Started session-25.scope - Session 25 of User core. Aug 13 00:01:58.148638 sshd[13033]: Connection closed by 10.200.16.10 port 55782 Aug 13 00:01:58.149114 sshd-session[13031]: pam_unix(sshd:session): session closed for user core Aug 13 00:01:58.152703 systemd[1]: sshd@22-10.200.8.10:22-10.200.16.10:55782.service: Deactivated successfully. Aug 13 00:01:58.154535 systemd[1]: session-25.scope: Deactivated successfully. Aug 13 00:01:58.155209 systemd-logind[1687]: Session 25 logged out. Waiting for processes to exit. Aug 13 00:01:58.156011 systemd-logind[1687]: Removed session 25. Aug 13 00:01:58.260919 systemd[1]: Started sshd@23-10.200.8.10:22-10.200.16.10:55790.service - OpenSSH per-connection server daemon (10.200.16.10:55790). Aug 13 00:01:58.894278 sshd[13039]: Accepted publickey for core from 10.200.16.10 port 55790 ssh2: RSA SHA256:7nTk9azICAaAnuLF0TE0juVznnrC2xUiFpHejrvHntI Aug 13 00:01:58.895857 sshd-session[13039]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:01:58.900812 systemd-logind[1687]: New session 26 of user core. Aug 13 00:01:58.913171 systemd[1]: Started session-26.scope - Session 26 of User core. Aug 13 00:02:00.843943 sshd[13041]: Connection closed by 10.200.16.10 port 55790 Aug 13 00:02:00.844441 sshd-session[13039]: pam_unix(sshd:session): session closed for user core Aug 13 00:02:00.848056 systemd[1]: sshd@23-10.200.8.10:22-10.200.16.10:55790.service: Deactivated successfully. Aug 13 00:02:00.849790 systemd[1]: session-26.scope: Deactivated successfully. Aug 13 00:02:00.849998 systemd[1]: session-26.scope: Consumed 1.447s CPU time, 14.1M memory peak. Aug 13 00:02:00.850472 systemd-logind[1687]: Session 26 logged out. Waiting for processes to exit. Aug 13 00:02:00.851316 systemd-logind[1687]: Removed session 26.