Jun 20 18:49:03.216511 kernel: Linux version 6.6.94-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 Fri Jun 20 17:12:40 -00 2025 Jun 20 18:49:03.216540 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=c5ce7ee72c13e935b8a741ba19830125b417ea1672f46b6a215da9317cee8e17 Jun 20 18:49:03.216550 kernel: BIOS-provided physical RAM map: Jun 20 18:49:03.216557 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Jun 20 18:49:03.216563 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Jun 20 18:49:03.216570 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Jun 20 18:49:03.216578 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Jun 20 18:49:03.216588 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Jun 20 18:49:03.216606 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Jun 20 18:49:03.216614 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Jun 20 18:49:03.216621 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Jun 20 18:49:03.216628 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Jun 20 18:49:03.216634 kernel: printk: bootconsole [earlyser0] enabled Jun 20 18:49:03.216641 kernel: NX (Execute Disable) protection: active Jun 20 18:49:03.216653 kernel: APIC: Static calls initialized Jun 20 18:49:03.216661 kernel: efi: EFI v2.7 by Microsoft Jun 20 18:49:03.216669 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Jun 20 18:49:03.216676 kernel: random: crng init done Jun 20 18:49:03.216684 kernel: secureboot: Secure boot disabled Jun 20 18:49:03.216692 kernel: SMBIOS 3.1.0 present. Jun 20 18:49:03.216699 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Jun 20 18:49:03.216707 kernel: Hypervisor detected: Microsoft Hyper-V Jun 20 18:49:03.216715 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Jun 20 18:49:03.216722 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Jun 20 18:49:03.216732 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Jun 20 18:49:03.216739 kernel: Hyper-V: Using hypercall for remote TLB flush Jun 20 18:49:03.216747 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Jun 20 18:49:03.216755 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Jun 20 18:49:03.216763 kernel: tsc: Marking TSC unstable due to running on Hyper-V Jun 20 18:49:03.216771 kernel: tsc: Detected 2593.992 MHz processor Jun 20 18:49:03.216778 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Jun 20 18:49:03.216786 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Jun 20 18:49:03.216794 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Jun 20 18:49:03.216804 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Jun 20 18:49:03.216812 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Jun 20 18:49:03.216820 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Jun 20 18:49:03.216827 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Jun 20 18:49:03.216835 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Jun 20 18:49:03.216843 kernel: Using GB pages for direct mapping Jun 20 18:49:03.216850 kernel: ACPI: Early table checksum verification disabled Jun 20 18:49:03.216862 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Jun 20 18:49:03.216873 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 20 18:49:03.216881 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 20 18:49:03.216890 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Jun 20 18:49:03.216898 kernel: ACPI: FACS 0x000000003FFFE000 000040 Jun 20 18:49:03.216906 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 20 18:49:03.216914 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 20 18:49:03.216925 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 20 18:49:03.216933 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 20 18:49:03.216941 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 20 18:49:03.216949 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 20 18:49:03.216958 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 20 18:49:03.216966 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Jun 20 18:49:03.216974 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Jun 20 18:49:03.216982 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Jun 20 18:49:03.216993 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Jun 20 18:49:03.217001 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Jun 20 18:49:03.217009 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Jun 20 18:49:03.217017 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Jun 20 18:49:03.217025 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Jun 20 18:49:03.217033 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Jun 20 18:49:03.217041 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Jun 20 18:49:03.217049 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0 Jun 20 18:49:03.217057 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0 Jun 20 18:49:03.217068 kernel: SRAT: PXM 0 -> APIC 0x02 -> Node 0 Jun 20 18:49:03.217076 kernel: SRAT: PXM 0 -> APIC 0x03 -> Node 0 Jun 20 18:49:03.217084 kernel: SRAT: PXM 0 -> APIC 0x04 -> Node 0 Jun 20 18:49:03.217092 kernel: SRAT: PXM 0 -> APIC 0x05 -> Node 0 Jun 20 18:49:03.217100 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Jun 20 18:49:03.217108 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Jun 20 18:49:03.217116 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Jun 20 18:49:03.217124 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Jun 20 18:49:03.217132 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Jun 20 18:49:03.217143 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Jun 20 18:49:03.217151 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Jun 20 18:49:03.217159 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Jun 20 18:49:03.217168 kernel: NUMA: Node 0 [mem 0x00000000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00000000-0xfdfffffff] Jun 20 18:49:03.217176 kernel: NUMA: Node 0 [mem 0x00000000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00000000-0x5cbfffffff] Jun 20 18:49:03.217184 kernel: NODE_DATA(0) allocated [mem 0x5cbfff9000-0x5cbfffefff] Jun 20 18:49:03.217192 kernel: Zone ranges: Jun 20 18:49:03.217200 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Jun 20 18:49:03.217208 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Jun 20 18:49:03.217219 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Jun 20 18:49:03.217227 kernel: Movable zone start for each node Jun 20 18:49:03.217235 kernel: Early memory node ranges Jun 20 18:49:03.217243 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Jun 20 18:49:03.217252 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Jun 20 18:49:03.217260 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Jun 20 18:49:03.217268 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Jun 20 18:49:03.217276 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Jun 20 18:49:03.217284 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Jun 20 18:49:03.217294 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Jun 20 18:49:03.217302 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Jun 20 18:49:03.217311 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Jun 20 18:49:03.217319 kernel: ACPI: PM-Timer IO Port: 0x408 Jun 20 18:49:03.217327 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Jun 20 18:49:03.217335 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Jun 20 18:49:03.217343 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Jun 20 18:49:03.217351 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Jun 20 18:49:03.217359 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Jun 20 18:49:03.217370 kernel: smpboot: Allowing 6 CPUs, 0 hotplug CPUs Jun 20 18:49:03.217378 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Jun 20 18:49:03.217386 kernel: Booting paravirtualized kernel on Hyper-V Jun 20 18:49:03.217394 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Jun 20 18:49:03.217403 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Jun 20 18:49:03.217411 kernel: percpu: Embedded 58 pages/cpu s197096 r8192 d32280 u262144 Jun 20 18:49:03.217419 kernel: pcpu-alloc: s197096 r8192 d32280 u262144 alloc=1*2097152 Jun 20 18:49:03.217427 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Jun 20 18:49:03.217435 kernel: Hyper-V: PV spinlocks enabled Jun 20 18:49:03.217446 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Jun 20 18:49:03.217455 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=c5ce7ee72c13e935b8a741ba19830125b417ea1672f46b6a215da9317cee8e17 Jun 20 18:49:03.217464 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Jun 20 18:49:03.217472 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Jun 20 18:49:03.217480 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Jun 20 18:49:03.217507 kernel: Fallback order for Node 0: 0 Jun 20 18:49:03.217537 kernel: Built 1 zonelists, mobility grouping on. Total pages: 28899610 Jun 20 18:49:03.217546 kernel: Policy zone: Normal Jun 20 18:49:03.217559 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jun 20 18:49:03.217575 kernel: software IO TLB: area num 8. Jun 20 18:49:03.217584 kernel: Memory: 115335988K/117439364K available (14336K kernel code, 2295K rwdata, 22872K rodata, 43488K init, 1588K bss, 2103120K reserved, 0K cma-reserved) Jun 20 18:49:03.217607 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Jun 20 18:49:03.217616 kernel: Kernel/User page tables isolation: enabled Jun 20 18:49:03.217625 kernel: ftrace: allocating 37938 entries in 149 pages Jun 20 18:49:03.217634 kernel: ftrace: allocated 149 pages with 4 groups Jun 20 18:49:03.217642 kernel: Dynamic Preempt: voluntary Jun 20 18:49:03.217650 kernel: rcu: Preemptible hierarchical RCU implementation. Jun 20 18:49:03.217660 kernel: rcu: RCU event tracing is enabled. Jun 20 18:49:03.217668 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Jun 20 18:49:03.217677 kernel: Trampoline variant of Tasks RCU enabled. Jun 20 18:49:03.217689 kernel: Rude variant of Tasks RCU enabled. Jun 20 18:49:03.217697 kernel: Tracing variant of Tasks RCU enabled. Jun 20 18:49:03.217711 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Jun 20 18:49:03.217722 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Jun 20 18:49:03.217734 kernel: Using NULL legacy PIC Jun 20 18:49:03.217743 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Jun 20 18:49:03.217752 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Jun 20 18:49:03.217760 kernel: Console: colour dummy device 80x25 Jun 20 18:49:03.217769 kernel: printk: console [tty1] enabled Jun 20 18:49:03.217777 kernel: printk: console [ttyS0] enabled Jun 20 18:49:03.217786 kernel: printk: bootconsole [earlyser0] disabled Jun 20 18:49:03.217795 kernel: ACPI: Core revision 20230628 Jun 20 18:49:03.217803 kernel: Failed to register legacy timer interrupt Jun 20 18:49:03.217812 kernel: APIC: Switch to symmetric I/O mode setup Jun 20 18:49:03.217823 kernel: Hyper-V: enabling crash_kexec_post_notifiers Jun 20 18:49:03.217832 kernel: Hyper-V: Using IPI hypercalls Jun 20 18:49:03.217840 kernel: APIC: send_IPI() replaced with hv_send_ipi() Jun 20 18:49:03.217849 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Jun 20 18:49:03.217858 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Jun 20 18:49:03.217866 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Jun 20 18:49:03.217875 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Jun 20 18:49:03.217883 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Jun 20 18:49:03.217892 kernel: Hyper-V: Using enlightened APIC (xapic mode) Jun 20 18:49:03.217903 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Jun 20 18:49:03.217911 kernel: APIC: read() replaced with hv_apic_read() Jun 20 18:49:03.217920 kernel: APIC: write() replaced with hv_apic_write() Jun 20 18:49:03.217929 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Jun 20 18:49:03.217937 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Jun 20 18:49:03.217946 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593992) Jun 20 18:49:03.217954 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Jun 20 18:49:03.217963 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Jun 20 18:49:03.217972 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Jun 20 18:49:03.217983 kernel: Spectre V2 : Mitigation: Retpolines Jun 20 18:49:03.217991 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Jun 20 18:49:03.217999 kernel: Speculative Store Bypass: Vulnerable Jun 20 18:49:03.218008 kernel: MDS: Mitigation: Clear CPU buffers Jun 20 18:49:03.218016 kernel: TAA: Mitigation: Clear CPU buffers Jun 20 18:49:03.218025 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Jun 20 18:49:03.218033 kernel: ITS: Mitigation: Aligned branch/return thunks Jun 20 18:49:03.218041 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Jun 20 18:49:03.218050 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Jun 20 18:49:03.218058 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Jun 20 18:49:03.218066 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Jun 20 18:49:03.218077 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Jun 20 18:49:03.218086 kernel: Freeing SMP alternatives memory: 32K Jun 20 18:49:03.218094 kernel: pid_max: default: 32768 minimum: 301 Jun 20 18:49:03.218103 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Jun 20 18:49:03.218111 kernel: landlock: Up and running. Jun 20 18:49:03.218119 kernel: SELinux: Initializing. Jun 20 18:49:03.218128 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Jun 20 18:49:03.218136 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Jun 20 18:49:03.218145 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Jun 20 18:49:03.218153 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jun 20 18:49:03.218162 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jun 20 18:49:03.218173 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jun 20 18:49:03.218182 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Jun 20 18:49:03.218191 kernel: signal: max sigframe size: 1776 Jun 20 18:49:03.218199 kernel: rcu: Hierarchical SRCU implementation. Jun 20 18:49:03.218208 kernel: rcu: Max phase no-delay instances is 400. Jun 20 18:49:03.218217 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Jun 20 18:49:03.218225 kernel: smp: Bringing up secondary CPUs ... Jun 20 18:49:03.218234 kernel: smpboot: x86: Booting SMP configuration: Jun 20 18:49:03.218242 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Jun 20 18:49:03.218253 kernel: smp: Brought up 1 node, 6 CPUs Jun 20 18:49:03.218262 kernel: smpboot: Max logical packages: 1 Jun 20 18:49:03.218270 kernel: smpboot: Total of 6 processors activated (31127.90 BogoMIPS) Jun 20 18:49:03.218279 kernel: devtmpfs: initialized Jun 20 18:49:03.218287 kernel: x86/mm: Memory block size: 1024MB Jun 20 18:49:03.218296 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Jun 20 18:49:03.218305 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Jun 20 18:49:03.218313 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Jun 20 18:49:03.218322 kernel: pinctrl core: initialized pinctrl subsystem Jun 20 18:49:03.218333 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jun 20 18:49:03.218341 kernel: audit: initializing netlink subsys (disabled) Jun 20 18:49:03.218350 kernel: audit: type=2000 audit(1750445341.029:1): state=initialized audit_enabled=0 res=1 Jun 20 18:49:03.218359 kernel: thermal_sys: Registered thermal governor 'step_wise' Jun 20 18:49:03.218367 kernel: thermal_sys: Registered thermal governor 'user_space' Jun 20 18:49:03.218376 kernel: cpuidle: using governor menu Jun 20 18:49:03.218384 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Jun 20 18:49:03.218393 kernel: dca service started, version 1.12.1 Jun 20 18:49:03.218401 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Jun 20 18:49:03.218412 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Jun 20 18:49:03.218421 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Jun 20 18:49:03.218429 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Jun 20 18:49:03.218438 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Jun 20 18:49:03.218446 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Jun 20 18:49:03.218455 kernel: ACPI: Added _OSI(Module Device) Jun 20 18:49:03.218463 kernel: ACPI: Added _OSI(Processor Device) Jun 20 18:49:03.218472 kernel: ACPI: Added _OSI(Processor Aggregator Device) Jun 20 18:49:03.218481 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Jun 20 18:49:03.218492 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Jun 20 18:49:03.218500 kernel: ACPI: Interpreter enabled Jun 20 18:49:03.218509 kernel: ACPI: PM: (supports S0 S5) Jun 20 18:49:03.218517 kernel: ACPI: Using IOAPIC for interrupt routing Jun 20 18:49:03.218526 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Jun 20 18:49:03.218535 kernel: PCI: Ignoring E820 reservations for host bridge windows Jun 20 18:49:03.218543 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Jun 20 18:49:03.218552 kernel: iommu: Default domain type: Translated Jun 20 18:49:03.218560 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Jun 20 18:49:03.218571 kernel: efivars: Registered efivars operations Jun 20 18:49:03.218580 kernel: PCI: Using ACPI for IRQ routing Jun 20 18:49:03.218588 kernel: PCI: System does not support PCI Jun 20 18:49:03.218605 kernel: vgaarb: loaded Jun 20 18:49:03.218614 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Jun 20 18:49:03.218622 kernel: VFS: Disk quotas dquot_6.6.0 Jun 20 18:49:03.218631 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jun 20 18:49:03.218639 kernel: pnp: PnP ACPI init Jun 20 18:49:03.218648 kernel: pnp: PnP ACPI: found 3 devices Jun 20 18:49:03.218659 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Jun 20 18:49:03.218668 kernel: NET: Registered PF_INET protocol family Jun 20 18:49:03.218676 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Jun 20 18:49:03.218685 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Jun 20 18:49:03.218694 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jun 20 18:49:03.218702 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Jun 20 18:49:03.218711 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jun 20 18:49:03.218719 kernel: TCP: Hash tables configured (established 524288 bind 65536) Jun 20 18:49:03.218731 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jun 20 18:49:03.218739 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jun 20 18:49:03.218748 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jun 20 18:49:03.218756 kernel: NET: Registered PF_XDP protocol family Jun 20 18:49:03.218765 kernel: PCI: CLS 0 bytes, default 64 Jun 20 18:49:03.218773 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Jun 20 18:49:03.218782 kernel: software IO TLB: mapped [mem 0x000000003b5bf000-0x000000003f5bf000] (64MB) Jun 20 18:49:03.218791 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Jun 20 18:49:03.218799 kernel: Initialise system trusted keyrings Jun 20 18:49:03.218810 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Jun 20 18:49:03.218819 kernel: Key type asymmetric registered Jun 20 18:49:03.218827 kernel: Asymmetric key parser 'x509' registered Jun 20 18:49:03.218835 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Jun 20 18:49:03.218844 kernel: io scheduler mq-deadline registered Jun 20 18:49:03.218852 kernel: io scheduler kyber registered Jun 20 18:49:03.218861 kernel: io scheduler bfq registered Jun 20 18:49:03.218869 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Jun 20 18:49:03.218878 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Jun 20 18:49:03.218886 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Jun 20 18:49:03.218898 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Jun 20 18:49:03.218906 kernel: i8042: PNP: No PS/2 controller found. Jun 20 18:49:03.219066 kernel: rtc_cmos 00:02: registered as rtc0 Jun 20 18:49:03.219179 kernel: rtc_cmos 00:02: setting system clock to 2025-06-20T18:49:02 UTC (1750445342) Jun 20 18:49:03.219283 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Jun 20 18:49:03.219297 kernel: intel_pstate: CPU model not supported Jun 20 18:49:03.219305 kernel: efifb: probing for efifb Jun 20 18:49:03.219319 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Jun 20 18:49:03.219328 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Jun 20 18:49:03.219336 kernel: efifb: scrolling: redraw Jun 20 18:49:03.219345 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Jun 20 18:49:03.219354 kernel: Console: switching to colour frame buffer device 128x48 Jun 20 18:49:03.219362 kernel: fb0: EFI VGA frame buffer device Jun 20 18:49:03.219371 kernel: pstore: Using crash dump compression: deflate Jun 20 18:49:03.219379 kernel: pstore: Registered efi_pstore as persistent store backend Jun 20 18:49:03.219388 kernel: NET: Registered PF_INET6 protocol family Jun 20 18:49:03.219399 kernel: Segment Routing with IPv6 Jun 20 18:49:03.219408 kernel: In-situ OAM (IOAM) with IPv6 Jun 20 18:49:03.219416 kernel: NET: Registered PF_PACKET protocol family Jun 20 18:49:03.219425 kernel: Key type dns_resolver registered Jun 20 18:49:03.219433 kernel: IPI shorthand broadcast: enabled Jun 20 18:49:03.219442 kernel: sched_clock: Marking stable (1665004900, 109227100)->(2132034400, -357802400) Jun 20 18:49:03.219450 kernel: registered taskstats version 1 Jun 20 18:49:03.219459 kernel: Loading compiled-in X.509 certificates Jun 20 18:49:03.219468 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.94-flatcar: 583832681762bbd3c2cbcca308896cbba88c4497' Jun 20 18:49:03.219479 kernel: Key type .fscrypt registered Jun 20 18:49:03.219487 kernel: Key type fscrypt-provisioning registered Jun 20 18:49:03.219496 kernel: ima: No TPM chip found, activating TPM-bypass! Jun 20 18:49:03.219504 kernel: ima: Allocated hash algorithm: sha1 Jun 20 18:49:03.219513 kernel: ima: No architecture policies found Jun 20 18:49:03.219521 kernel: clk: Disabling unused clocks Jun 20 18:49:03.219530 kernel: Freeing unused kernel image (initmem) memory: 43488K Jun 20 18:49:03.219538 kernel: Write protecting the kernel read-only data: 38912k Jun 20 18:49:03.219547 kernel: Freeing unused kernel image (rodata/data gap) memory: 1704K Jun 20 18:49:03.219558 kernel: Run /init as init process Jun 20 18:49:03.219567 kernel: with arguments: Jun 20 18:49:03.219575 kernel: /init Jun 20 18:49:03.219583 kernel: with environment: Jun 20 18:49:03.219592 kernel: HOME=/ Jun 20 18:49:03.219612 kernel: TERM=linux Jun 20 18:49:03.219621 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Jun 20 18:49:03.219630 systemd[1]: Successfully made /usr/ read-only. Jun 20 18:49:03.219642 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) Jun 20 18:49:03.219655 systemd[1]: Detected virtualization microsoft. Jun 20 18:49:03.219664 systemd[1]: Detected architecture x86-64. Jun 20 18:49:03.219673 systemd[1]: Running in initrd. Jun 20 18:49:03.219681 systemd[1]: No hostname configured, using default hostname. Jun 20 18:49:03.219691 systemd[1]: Hostname set to . Jun 20 18:49:03.219699 systemd[1]: Initializing machine ID from random generator. Jun 20 18:49:03.219708 systemd[1]: Queued start job for default target initrd.target. Jun 20 18:49:03.219720 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jun 20 18:49:03.219729 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jun 20 18:49:03.219739 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Jun 20 18:49:03.219748 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jun 20 18:49:03.219757 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Jun 20 18:49:03.219767 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Jun 20 18:49:03.219777 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Jun 20 18:49:03.219789 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Jun 20 18:49:03.219798 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jun 20 18:49:03.219807 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jun 20 18:49:03.219816 systemd[1]: Reached target paths.target - Path Units. Jun 20 18:49:03.219825 systemd[1]: Reached target slices.target - Slice Units. Jun 20 18:49:03.219834 systemd[1]: Reached target swap.target - Swaps. Jun 20 18:49:03.219843 systemd[1]: Reached target timers.target - Timer Units. Jun 20 18:49:03.219852 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Jun 20 18:49:03.219864 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jun 20 18:49:03.219873 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Jun 20 18:49:03.219882 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Jun 20 18:49:03.219891 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jun 20 18:49:03.219900 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jun 20 18:49:03.219909 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jun 20 18:49:03.219918 systemd[1]: Reached target sockets.target - Socket Units. Jun 20 18:49:03.219928 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Jun 20 18:49:03.219937 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jun 20 18:49:03.219948 systemd[1]: Finished network-cleanup.service - Network Cleanup. Jun 20 18:49:03.219957 systemd[1]: Starting systemd-fsck-usr.service... Jun 20 18:49:03.219966 systemd[1]: Starting systemd-journald.service - Journal Service... Jun 20 18:49:03.219975 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jun 20 18:49:03.220006 systemd-journald[204]: Collecting audit messages is disabled. Jun 20 18:49:03.220032 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jun 20 18:49:03.220042 systemd-journald[204]: Journal started Jun 20 18:49:03.220062 systemd-journald[204]: Runtime Journal (/run/log/journal/93cfa04f90e04af4a90e28a944476ced) is 8M, max 2.2G, 2.1G free. Jun 20 18:49:03.227204 systemd[1]: Started systemd-journald.service - Journal Service. Jun 20 18:49:03.230731 systemd-modules-load[207]: Inserted module 'overlay' Jun 20 18:49:03.231178 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Jun 20 18:49:03.232448 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jun 20 18:49:03.233123 systemd[1]: Finished systemd-fsck-usr.service. Jun 20 18:49:03.235378 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jun 20 18:49:03.268288 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jun 20 18:49:03.273206 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jun 20 18:49:03.282686 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jun 20 18:49:03.286181 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jun 20 18:49:03.307069 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jun 20 18:49:03.310767 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jun 20 18:49:03.332747 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jun 20 18:49:03.341758 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Jun 20 18:49:03.352931 systemd-modules-load[207]: Inserted module 'br_netfilter' Jun 20 18:49:03.354423 kernel: Bridge firewalling registered Jun 20 18:49:03.353832 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jun 20 18:49:03.372753 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jun 20 18:49:03.373786 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jun 20 18:49:03.387767 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Jun 20 18:49:03.405401 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jun 20 18:49:03.408195 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jun 20 18:49:03.425606 dracut-cmdline[242]: dracut-dracut-053 Jun 20 18:49:03.429242 dracut-cmdline[242]: 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=c5ce7ee72c13e935b8a741ba19830125b417ea1672f46b6a215da9317cee8e17 Jun 20 18:49:03.476287 systemd-resolved[249]: Positive Trust Anchors: Jun 20 18:49:03.476303 systemd-resolved[249]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jun 20 18:49:03.476334 systemd-resolved[249]: 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 Jun 20 18:49:03.478869 systemd-resolved[249]: Defaulting to hostname 'linux'. Jun 20 18:49:03.479994 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jun 20 18:49:03.489237 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jun 20 18:49:03.584624 kernel: SCSI subsystem initialized Jun 20 18:49:03.594617 kernel: Loading iSCSI transport class v2.0-870. Jun 20 18:49:03.607618 kernel: iscsi: registered transport (tcp) Jun 20 18:49:03.631877 kernel: iscsi: registered transport (qla4xxx) Jun 20 18:49:03.631906 kernel: QLogic iSCSI HBA Driver Jun 20 18:49:03.672383 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Jun 20 18:49:03.695764 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Jun 20 18:49:03.732093 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Jun 20 18:49:03.732136 kernel: device-mapper: uevent: version 1.0.3 Jun 20 18:49:03.736667 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Jun 20 18:49:03.779625 kernel: raid6: avx2x4 gen() 30943 MB/s Jun 20 18:49:03.799612 kernel: raid6: avx2x2 gen() 30868 MB/s Jun 20 18:49:03.820824 kernel: raid6: avx2x1 gen() 27741 MB/s Jun 20 18:49:03.820852 kernel: raid6: using algorithm avx2x4 gen() 30943 MB/s Jun 20 18:49:03.842753 kernel: raid6: .... xor() 9922 MB/s, rmw enabled Jun 20 18:49:03.842779 kernel: raid6: using avx2x2 recovery algorithm Jun 20 18:49:03.868622 kernel: xor: automatically using best checksumming function avx Jun 20 18:49:04.023624 kernel: Btrfs loaded, zoned=no, fsverity=no Jun 20 18:49:04.034367 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Jun 20 18:49:04.049728 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jun 20 18:49:04.071278 systemd-udevd[430]: Using default interface naming scheme 'v255'. Jun 20 18:49:04.079169 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jun 20 18:49:04.103726 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Jun 20 18:49:04.123791 dracut-pre-trigger[439]: rd.md=0: removing MD RAID activation Jun 20 18:49:04.154779 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Jun 20 18:49:04.173724 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jun 20 18:49:04.231458 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jun 20 18:49:04.245222 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Jun 20 18:49:04.262535 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Jun 20 18:49:04.267295 kernel: cryptd: max_cpu_qlen set to 1000 Jun 20 18:49:04.274448 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Jun 20 18:49:04.275968 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jun 20 18:49:04.290380 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jun 20 18:49:04.311781 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Jun 20 18:49:04.322744 kernel: AVX2 version of gcm_enc/dec engaged. Jun 20 18:49:04.322773 kernel: hv_vmbus: Vmbus version:4.0 Jun 20 18:49:04.321916 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jun 20 18:49:04.322098 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jun 20 18:49:04.340865 kernel: AES CTR mode by8 optimization enabled Jun 20 18:49:04.334435 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jun 20 18:49:04.353898 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jun 20 18:49:04.356654 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jun 20 18:49:04.375918 kernel: hv_vmbus: registering driver hyperv_keyboard Jun 20 18:49:04.375940 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 Jun 20 18:49:04.376061 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Jun 20 18:49:04.385132 kernel: pps_core: LinuxPPS API ver. 1 registered Jun 20 18:49:04.385152 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Jun 20 18:49:04.397223 kernel: hid: raw HID events driver (C) Jiri Kosina Jun 20 18:49:04.397268 kernel: PTP clock support registered Jun 20 18:49:04.400616 kernel: hv_vmbus: registering driver hid_hyperv Jun 20 18:49:04.407780 kernel: hv_utils: Registering HyperV Utility Driver Jun 20 18:49:04.407832 kernel: hv_vmbus: registering driver hv_utils Jun 20 18:49:04.406124 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jun 20 18:49:04.611202 kernel: hv_utils: Heartbeat IC version 3.0 Jun 20 18:49:04.611237 kernel: hv_utils: Shutdown IC version 3.2 Jun 20 18:49:04.611262 kernel: hv_utils: TimeSync IC version 4.0 Jun 20 18:49:04.611291 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Jun 20 18:49:04.611309 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Jun 20 18:49:04.417863 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Jun 20 18:49:04.596796 systemd-resolved[249]: Clock change detected. Flushing caches. Jun 20 18:49:04.624130 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jun 20 18:49:04.666194 kernel: hv_vmbus: registering driver hv_netvsc Jun 20 18:49:04.666220 kernel: hv_vmbus: registering driver hv_pci Jun 20 18:49:04.666233 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Jun 20 18:49:04.666430 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Jun 20 18:49:04.666566 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Jun 20 18:49:04.666731 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Jun 20 18:49:04.666880 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Jun 20 18:49:04.667006 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 Jun 20 18:49:04.629591 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jun 20 18:49:04.671897 kernel: pci 0001:00:00.0: reg 0x10: [mem 0x41000000-0x41ffffff] Jun 20 18:49:04.676863 kernel: pci 0001:00:00.0: reg 0x14: [mem 0x1000000000-0x13ffffffff 64bit pref] Jun 20 18:49:04.682833 kernel: pci 0001:00:00.0: reg 0x1c: [mem 0x1400000000-0x1401ffffff 64bit pref] Jun 20 18:49:04.687884 kernel: pci 0001:00:00.0: Enabling HDA controller Jun 20 18:49:04.693832 kernel: hv_vmbus: registering driver hv_storvsc Jun 20 18:49:04.697835 kernel: scsi host1: storvsc_host_t Jun 20 18:49:04.697886 kernel: scsi host0: storvsc_host_t Jun 20 18:49:04.705829 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Jun 20 18:49:04.710860 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Jun 20 18:49:04.710906 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Jun 20 18:49:04.717590 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Jun 20 18:49:04.717646 kernel: pci 0001:00:00.0: BAR 1: assigned [mem 0x1000000000-0x13ffffffff 64bit pref] Jun 20 18:49:04.723910 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jun 20 18:49:04.742198 kernel: pci 0001:00:00.0: BAR 3: assigned [mem 0x1400000000-0x1401ffffff 64bit pref] Jun 20 18:49:04.742381 kernel: pci 0001:00:00.0: BAR 0: assigned [mem 0x41000000-0x41ffffff] Jun 20 18:49:04.749664 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Jun 20 18:49:04.749881 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Jun 20 18:49:04.750836 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Jun 20 18:49:04.765820 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Jun 20 18:49:04.766071 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Jun 20 18:49:04.766232 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks Jun 20 18:49:04.766375 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Jun 20 18:49:04.766515 kernel: sd 0:0:0:0: [sda] Write Protect is off Jun 20 18:49:04.770919 kernel: sd 0:0:0:1: [sdb] Write Protect is off Jun 20 18:49:04.771121 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Jun 20 18:49:04.777826 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Jun 20 18:49:04.778007 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Jun 20 18:49:04.787474 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Jun 20 18:49:04.787829 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Jun 20 18:49:04.791606 kernel: sdb: sdb1 Jun 20 18:49:04.791645 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Jun 20 18:49:04.791847 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk Jun 20 18:49:05.495841 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/sda6 scanned by (udev-worker) (475) Jun 20 18:49:05.514486 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Jun 20 18:49:05.528061 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Jun 20 18:49:05.540898 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Jun 20 18:49:05.582840 kernel: BTRFS: device fsid 5ff786f3-14e2-4689-ad32-ff903cf13f91 devid 1 transid 38 /dev/sda3 scanned by (udev-worker) (492) Jun 20 18:49:05.603050 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Jun 20 18:49:05.603797 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Jun 20 18:49:05.633937 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Jun 20 18:49:05.651834 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Jun 20 18:49:05.660831 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Jun 20 18:49:06.666833 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Jun 20 18:49:06.667248 disk-uuid[634]: The operation has completed successfully. Jun 20 18:49:08.025398 systemd[1]: disk-uuid.service: Deactivated successfully. Jun 20 18:49:08.025516 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Jun 20 18:49:08.048997 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Jun 20 18:49:08.061877 sh[721]: Success Jun 20 18:49:08.111830 kernel: device-mapper: verity: sha256 using implementation "sha256-avx2" Jun 20 18:49:08.380740 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Jun 20 18:49:08.401219 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Jun 20 18:49:08.404613 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Jun 20 18:49:08.427826 kernel: BTRFS info (device dm-0): first mount of filesystem 5ff786f3-14e2-4689-ad32-ff903cf13f91 Jun 20 18:49:08.427861 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Jun 20 18:49:08.432686 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Jun 20 18:49:08.436291 kernel: BTRFS info (device dm-0): disabling log replay at mount time Jun 20 18:49:08.439351 kernel: BTRFS info (device dm-0): using free space tree Jun 20 18:49:09.154113 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Jun 20 18:49:09.158189 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Jun 20 18:49:09.178949 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Jun 20 18:49:09.183229 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Jun 20 18:49:09.211817 kernel: BTRFS info (device sda6): first mount of filesystem 0d4ae0d2-6537-4cbd-8c37-7b929dcf3a9f Jun 20 18:49:09.211854 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Jun 20 18:49:09.216056 kernel: BTRFS info (device sda6): using free space tree Jun 20 18:49:09.238832 kernel: BTRFS info (device sda6): auto enabling async discard Jun 20 18:49:09.246831 kernel: BTRFS info (device sda6): last unmount of filesystem 0d4ae0d2-6537-4cbd-8c37-7b929dcf3a9f Jun 20 18:49:09.252033 systemd[1]: Finished ignition-setup.service - Ignition (setup). Jun 20 18:49:09.270966 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Jun 20 18:49:09.298096 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jun 20 18:49:09.320941 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jun 20 18:49:09.349719 systemd-networkd[902]: lo: Link UP Jun 20 18:49:09.349729 systemd-networkd[902]: lo: Gained carrier Jun 20 18:49:09.350874 systemd-networkd[902]: Enumeration completed Jun 20 18:49:09.350957 systemd[1]: Started systemd-networkd.service - Network Configuration. Jun 20 18:49:09.351242 systemd-networkd[902]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jun 20 18:49:09.351246 systemd-networkd[902]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jun 20 18:49:09.352088 systemd-networkd[902]: eth0: Link UP Jun 20 18:49:09.352260 systemd-networkd[902]: eth0: Gained carrier Jun 20 18:49:09.352268 systemd-networkd[902]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jun 20 18:49:09.356645 systemd[1]: Reached target network.target - Network. Jun 20 18:49:09.421848 systemd-networkd[902]: eth0: DHCPv4 address 10.200.8.16/24, gateway 10.200.8.1 acquired from 168.63.129.16 Jun 20 18:49:10.418934 systemd-networkd[902]: eth0: Gained IPv6LL Jun 20 18:49:10.939426 ignition[875]: Ignition 2.20.0 Jun 20 18:49:10.939440 ignition[875]: Stage: fetch-offline Jun 20 18:49:10.939495 ignition[875]: no configs at "/usr/lib/ignition/base.d" Jun 20 18:49:10.939507 ignition[875]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jun 20 18:49:10.939614 ignition[875]: parsed url from cmdline: "" Jun 20 18:49:10.939619 ignition[875]: no config URL provided Jun 20 18:49:10.939624 ignition[875]: reading system config file "/usr/lib/ignition/user.ign" Jun 20 18:49:10.939638 ignition[875]: no config at "/usr/lib/ignition/user.ign" Jun 20 18:49:10.939644 ignition[875]: failed to fetch config: resource requires networking Jun 20 18:49:10.963061 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Jun 20 18:49:10.939835 ignition[875]: Ignition finished successfully Jun 20 18:49:10.986949 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Jun 20 18:49:11.000572 ignition[914]: Ignition 2.20.0 Jun 20 18:49:11.000583 ignition[914]: Stage: fetch Jun 20 18:49:11.000745 ignition[914]: no configs at "/usr/lib/ignition/base.d" Jun 20 18:49:11.000758 ignition[914]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jun 20 18:49:11.000870 ignition[914]: parsed url from cmdline: "" Jun 20 18:49:11.000874 ignition[914]: no config URL provided Jun 20 18:49:11.000914 ignition[914]: reading system config file "/usr/lib/ignition/user.ign" Jun 20 18:49:11.000956 ignition[914]: no config at "/usr/lib/ignition/user.ign" Jun 20 18:49:11.001029 ignition[914]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Jun 20 18:49:11.111423 ignition[914]: GET result: OK Jun 20 18:49:11.111472 ignition[914]: config has been read from IMDS userdata Jun 20 18:49:11.111484 ignition[914]: parsing config with SHA512: 2eee22f894bda9caa94e2dc2df04137da7db597723333c90568bae17eb1f002d42a5b4b3bce11dbd729af035d9a781e844fa09284e1e007814a27a7bcde4ef54 Jun 20 18:49:11.115476 unknown[914]: fetched base config from "system" Jun 20 18:49:11.115700 ignition[914]: fetch: fetch complete Jun 20 18:49:11.115484 unknown[914]: fetched base config from "system" Jun 20 18:49:11.115705 ignition[914]: fetch: fetch passed Jun 20 18:49:11.115490 unknown[914]: fetched user config from "azure" Jun 20 18:49:11.115749 ignition[914]: Ignition finished successfully Jun 20 18:49:11.136564 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Jun 20 18:49:11.153927 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Jun 20 18:49:11.177151 ignition[924]: Ignition 2.20.0 Jun 20 18:49:11.177162 ignition[924]: Stage: kargs Jun 20 18:49:11.177313 ignition[924]: no configs at "/usr/lib/ignition/base.d" Jun 20 18:49:11.177325 ignition[924]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jun 20 18:49:11.177926 ignition[924]: kargs: kargs passed Jun 20 18:49:11.185748 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Jun 20 18:49:11.177972 ignition[924]: Ignition finished successfully Jun 20 18:49:11.207977 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Jun 20 18:49:11.221612 ignition[933]: Ignition 2.20.0 Jun 20 18:49:11.221623 ignition[933]: Stage: disks Jun 20 18:49:11.221804 ignition[933]: no configs at "/usr/lib/ignition/base.d" Jun 20 18:49:11.221852 ignition[933]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jun 20 18:49:11.222535 ignition[933]: disks: disks passed Jun 20 18:49:11.222582 ignition[933]: Ignition finished successfully Jun 20 18:49:11.239394 systemd[1]: Finished ignition-disks.service - Ignition (disks). Jun 20 18:49:11.241403 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Jun 20 18:49:11.248577 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Jun 20 18:49:11.256631 systemd[1]: Reached target local-fs.target - Local File Systems. Jun 20 18:49:11.269762 systemd[1]: Reached target sysinit.target - System Initialization. Jun 20 18:49:11.277516 systemd[1]: Reached target basic.target - Basic System. Jun 20 18:49:11.295938 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Jun 20 18:49:11.384104 systemd-fsck[946]: ROOT: clean, 14/7326000 files, 477710/7359488 blocks Jun 20 18:49:11.389327 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Jun 20 18:49:11.423914 systemd[1]: Mounting sysroot.mount - /sysroot... Jun 20 18:49:11.518828 kernel: EXT4-fs (sda9): mounted filesystem 943f8432-3dc9-4e22-b9bd-c29bf6a1f5e1 r/w with ordered data mode. Quota mode: none. Jun 20 18:49:11.519343 systemd[1]: Mounted sysroot.mount - /sysroot. Jun 20 18:49:11.521359 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Jun 20 18:49:11.561905 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jun 20 18:49:11.563454 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Jun 20 18:49:11.579351 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/sda6 scanned by mount (957) Jun 20 18:49:11.570106 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Jun 20 18:49:11.594958 kernel: BTRFS info (device sda6): first mount of filesystem 0d4ae0d2-6537-4cbd-8c37-7b929dcf3a9f Jun 20 18:49:11.594980 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Jun 20 18:49:11.594993 kernel: BTRFS info (device sda6): using free space tree Jun 20 18:49:11.590843 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Jun 20 18:49:11.590878 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Jun 20 18:49:11.606941 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Jun 20 18:49:11.632897 kernel: BTRFS info (device sda6): auto enabling async discard Jun 20 18:49:11.619281 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Jun 20 18:49:11.634261 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jun 20 18:49:12.746227 coreos-metadata[959]: Jun 20 18:49:12.746 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Jun 20 18:49:12.753821 coreos-metadata[959]: Jun 20 18:49:12.753 INFO Fetch successful Jun 20 18:49:12.757543 coreos-metadata[959]: Jun 20 18:49:12.753 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Jun 20 18:49:12.773192 coreos-metadata[959]: Jun 20 18:49:12.773 INFO Fetch successful Jun 20 18:49:12.776866 coreos-metadata[959]: Jun 20 18:49:12.773 INFO wrote hostname ci-4230.2.0-a-e85dac22cd to /sysroot/etc/hostname Jun 20 18:49:12.784854 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Jun 20 18:49:12.909948 initrd-setup-root[987]: cut: /sysroot/etc/passwd: No such file or directory Jun 20 18:49:12.939533 initrd-setup-root[994]: cut: /sysroot/etc/group: No such file or directory Jun 20 18:49:12.946384 initrd-setup-root[1001]: cut: /sysroot/etc/shadow: No such file or directory Jun 20 18:49:12.979427 initrd-setup-root[1008]: cut: /sysroot/etc/gshadow: No such file or directory Jun 20 18:49:14.334043 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Jun 20 18:49:14.347888 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Jun 20 18:49:14.349369 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Jun 20 18:49:14.366144 systemd[1]: sysroot-oem.mount: Deactivated successfully. Jun 20 18:49:14.374763 kernel: BTRFS info (device sda6): last unmount of filesystem 0d4ae0d2-6537-4cbd-8c37-7b929dcf3a9f Jun 20 18:49:14.392984 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Jun 20 18:49:14.400739 ignition[1077]: INFO : Ignition 2.20.0 Jun 20 18:49:14.400739 ignition[1077]: INFO : Stage: mount Jun 20 18:49:14.406291 ignition[1077]: INFO : no configs at "/usr/lib/ignition/base.d" Jun 20 18:49:14.406291 ignition[1077]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jun 20 18:49:14.406291 ignition[1077]: INFO : mount: mount passed Jun 20 18:49:14.406291 ignition[1077]: INFO : Ignition finished successfully Jun 20 18:49:14.411340 systemd[1]: Finished ignition-mount.service - Ignition (mount). Jun 20 18:49:14.434926 systemd[1]: Starting ignition-files.service - Ignition (files)... Jun 20 18:49:14.448827 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jun 20 18:49:14.467828 kernel: BTRFS: device label OEM devid 1 transid 17 /dev/sda6 scanned by mount (1089) Jun 20 18:49:14.477244 kernel: BTRFS info (device sda6): first mount of filesystem 0d4ae0d2-6537-4cbd-8c37-7b929dcf3a9f Jun 20 18:49:14.477275 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Jun 20 18:49:14.480345 kernel: BTRFS info (device sda6): using free space tree Jun 20 18:49:14.485829 kernel: BTRFS info (device sda6): auto enabling async discard Jun 20 18:49:14.487378 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jun 20 18:49:14.518169 ignition[1105]: INFO : Ignition 2.20.0 Jun 20 18:49:14.518169 ignition[1105]: INFO : Stage: files Jun 20 18:49:14.523974 ignition[1105]: INFO : no configs at "/usr/lib/ignition/base.d" Jun 20 18:49:14.523974 ignition[1105]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jun 20 18:49:14.523974 ignition[1105]: DEBUG : files: compiled without relabeling support, skipping Jun 20 18:49:14.632844 ignition[1105]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Jun 20 18:49:14.632844 ignition[1105]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Jun 20 18:49:14.755939 ignition[1105]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Jun 20 18:49:14.761941 ignition[1105]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Jun 20 18:49:14.767742 unknown[1105]: wrote ssh authorized keys file for user: core Jun 20 18:49:14.771522 ignition[1105]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Jun 20 18:49:14.782455 ignition[1105]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Jun 20 18:49:14.782455 ignition[1105]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Jun 20 18:49:14.782455 ignition[1105]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Jun 20 18:49:14.782455 ignition[1105]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Jun 20 18:49:14.782455 ignition[1105]: INFO : files: files passed Jun 20 18:49:14.782455 ignition[1105]: INFO : Ignition finished successfully Jun 20 18:49:14.782151 systemd[1]: Finished ignition-files.service - Ignition (files). Jun 20 18:49:14.804991 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Jun 20 18:49:14.809294 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Jun 20 18:49:14.823742 systemd[1]: ignition-quench.service: Deactivated successfully. Jun 20 18:49:14.823856 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Jun 20 18:49:14.859578 initrd-setup-root-after-ignition[1137]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jun 20 18:49:14.859578 initrd-setup-root-after-ignition[1137]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Jun 20 18:49:14.857013 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Jun 20 18:49:14.889216 initrd-setup-root-after-ignition[1141]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jun 20 18:49:14.861205 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Jun 20 18:49:14.889942 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Jun 20 18:49:14.921497 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Jun 20 18:49:14.921605 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Jun 20 18:49:14.930533 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Jun 20 18:49:14.932497 systemd[1]: Reached target initrd.target - Initrd Default Target. Jun 20 18:49:14.944635 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Jun 20 18:49:14.945484 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Jun 20 18:49:14.978532 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jun 20 18:49:14.981206 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Jun 20 18:49:15.001228 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Jun 20 18:49:15.002762 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Jun 20 18:49:15.010995 systemd[1]: Stopped target timers.target - Timer Units. Jun 20 18:49:15.023144 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Jun 20 18:49:15.023274 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jun 20 18:49:15.031545 systemd[1]: Stopped target initrd.target - Initrd Default Target. Jun 20 18:49:15.039418 systemd[1]: Stopped target basic.target - Basic System. Jun 20 18:49:15.047719 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Jun 20 18:49:15.056055 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Jun 20 18:49:15.065752 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Jun 20 18:49:15.074484 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Jun 20 18:49:15.083264 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Jun 20 18:49:15.092553 systemd[1]: Stopped target sysinit.target - System Initialization. Jun 20 18:49:15.100665 systemd[1]: Stopped target local-fs.target - Local File Systems. Jun 20 18:49:15.109407 systemd[1]: Stopped target swap.target - Swaps. Jun 20 18:49:15.116570 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Jun 20 18:49:15.116723 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Jun 20 18:49:15.127375 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Jun 20 18:49:15.135519 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jun 20 18:49:15.148440 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Jun 20 18:49:15.148583 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jun 20 18:49:15.157401 systemd[1]: dracut-initqueue.service: Deactivated successfully. Jun 20 18:49:15.157538 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Jun 20 18:49:15.170666 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Jun 20 18:49:15.170820 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Jun 20 18:49:15.181138 systemd[1]: ignition-files.service: Deactivated successfully. Jun 20 18:49:15.181258 systemd[1]: Stopped ignition-files.service - Ignition (files). Jun 20 18:49:15.188615 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Jun 20 18:49:15.188738 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Jun 20 18:49:15.207954 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Jun 20 18:49:15.213982 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Jun 20 18:49:15.214127 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Jun 20 18:49:15.223295 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Jun 20 18:49:15.224517 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Jun 20 18:49:15.224643 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Jun 20 18:49:15.272835 ignition[1161]: INFO : Ignition 2.20.0 Jun 20 18:49:15.272835 ignition[1161]: INFO : Stage: umount Jun 20 18:49:15.272835 ignition[1161]: INFO : no configs at "/usr/lib/ignition/base.d" Jun 20 18:49:15.272835 ignition[1161]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jun 20 18:49:15.272835 ignition[1161]: INFO : umount: umount passed Jun 20 18:49:15.272835 ignition[1161]: INFO : Ignition finished successfully Jun 20 18:49:15.235953 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Jun 20 18:49:15.236078 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Jun 20 18:49:15.254409 systemd[1]: initrd-cleanup.service: Deactivated successfully. Jun 20 18:49:15.254511 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Jun 20 18:49:15.276709 systemd[1]: ignition-mount.service: Deactivated successfully. Jun 20 18:49:15.276888 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Jun 20 18:49:15.282640 systemd[1]: ignition-disks.service: Deactivated successfully. Jun 20 18:49:15.282701 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Jun 20 18:49:15.290416 systemd[1]: ignition-kargs.service: Deactivated successfully. Jun 20 18:49:15.290469 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Jun 20 18:49:15.299061 systemd[1]: ignition-fetch.service: Deactivated successfully. Jun 20 18:49:15.299109 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Jun 20 18:49:15.306156 systemd[1]: Stopped target network.target - Network. Jun 20 18:49:15.313315 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Jun 20 18:49:15.313372 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Jun 20 18:49:15.322203 systemd[1]: Stopped target paths.target - Path Units. Jun 20 18:49:15.329861 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Jun 20 18:49:15.335858 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jun 20 18:49:15.339620 systemd[1]: Stopped target slices.target - Slice Units. Jun 20 18:49:15.348070 systemd[1]: Stopped target sockets.target - Socket Units. Jun 20 18:49:15.355356 systemd[1]: iscsid.socket: Deactivated successfully. Jun 20 18:49:15.355401 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Jun 20 18:49:15.362624 systemd[1]: iscsiuio.socket: Deactivated successfully. Jun 20 18:49:15.362663 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jun 20 18:49:15.370120 systemd[1]: ignition-setup.service: Deactivated successfully. Jun 20 18:49:15.370176 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Jun 20 18:49:15.377668 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Jun 20 18:49:15.377716 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Jun 20 18:49:15.386378 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Jun 20 18:49:15.394137 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Jun 20 18:49:15.402727 systemd[1]: sysroot-boot.mount: Deactivated successfully. Jun 20 18:49:15.403324 systemd[1]: systemd-resolved.service: Deactivated successfully. Jun 20 18:49:15.403428 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Jun 20 18:49:15.416668 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Jun 20 18:49:15.416963 systemd[1]: sysroot-boot.service: Deactivated successfully. Jun 20 18:49:15.417064 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Jun 20 18:49:15.423440 systemd[1]: initrd-setup-root.service: Deactivated successfully. Jun 20 18:49:15.423534 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Jun 20 18:49:15.430912 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Jun 20 18:49:15.430983 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Jun 20 18:49:15.447805 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Jun 20 18:49:15.448101 systemd[1]: systemd-networkd.service: Deactivated successfully. Jun 20 18:49:15.448209 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Jun 20 18:49:15.457981 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Jun 20 18:49:15.458487 systemd[1]: systemd-networkd.socket: Deactivated successfully. Jun 20 18:49:15.458551 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Jun 20 18:49:15.487890 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Jun 20 18:49:15.494168 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Jun 20 18:49:15.494232 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jun 20 18:49:15.496350 systemd[1]: systemd-sysctl.service: Deactivated successfully. Jun 20 18:49:15.496397 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Jun 20 18:49:15.514972 systemd[1]: systemd-modules-load.service: Deactivated successfully. Jun 20 18:49:15.515028 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Jun 20 18:49:15.516492 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Jun 20 18:49:15.529609 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Jun 20 18:49:15.550590 systemd[1]: network-cleanup.service: Deactivated successfully. Jun 20 18:49:15.550735 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Jun 20 18:49:15.577576 systemd[1]: systemd-udevd.service: Deactivated successfully. Jun 20 18:49:15.577770 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Jun 20 18:49:15.579370 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Jun 20 18:49:15.579432 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Jun 20 18:49:15.595203 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Jun 20 18:49:15.595240 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Jun 20 18:49:15.603420 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Jun 20 18:49:15.603480 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Jun 20 18:49:15.614504 systemd[1]: dracut-cmdline.service: Deactivated successfully. Jun 20 18:49:15.614553 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Jun 20 18:49:15.628246 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jun 20 18:49:15.628304 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jun 20 18:49:15.663937 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Jun 20 18:49:15.672579 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Jun 20 18:49:15.672639 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jun 20 18:49:15.682201 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jun 20 18:49:15.682252 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jun 20 18:49:15.690759 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Jun 20 18:49:15.690865 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Jun 20 18:49:15.698115 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Jun 20 18:49:15.723963 systemd[1]: Starting initrd-switch-root.service - Switch Root... Jun 20 18:49:15.733629 systemd[1]: Switching root. Jun 20 18:49:16.102826 systemd-journald[204]: Received SIGTERM from PID 1 (systemd). Jun 20 18:49:16.102890 systemd-journald[204]: Journal stopped Jun 20 18:49:22.665178 kernel: SELinux: policy capability network_peer_controls=1 Jun 20 18:49:22.665216 kernel: SELinux: policy capability open_perms=1 Jun 20 18:49:22.665229 kernel: SELinux: policy capability extended_socket_class=1 Jun 20 18:49:22.665239 kernel: SELinux: policy capability always_check_network=0 Jun 20 18:49:22.665248 kernel: SELinux: policy capability cgroup_seclabel=1 Jun 20 18:49:22.665257 kernel: SELinux: policy capability nnp_nosuid_transition=1 Jun 20 18:49:22.665268 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Jun 20 18:49:22.665281 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Jun 20 18:49:22.665312 kernel: audit: type=1403 audit(1750445357.153:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Jun 20 18:49:22.665338 systemd[1]: Successfully loaded SELinux policy in 200.980ms. Jun 20 18:49:22.665351 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 17.506ms. Jun 20 18:49:22.665362 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) Jun 20 18:49:22.665377 systemd[1]: Detected virtualization microsoft. Jun 20 18:49:22.665388 systemd[1]: Detected architecture x86-64. Jun 20 18:49:22.665404 systemd[1]: Detected first boot. Jun 20 18:49:22.665416 systemd[1]: Hostname set to . Jun 20 18:49:22.665427 systemd[1]: Initializing machine ID from random generator. Jun 20 18:49:22.665438 zram_generator::config[1207]: No configuration found. Jun 20 18:49:22.665479 kernel: Guest personality initialized and is inactive Jun 20 18:49:22.665499 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Jun 20 18:49:22.665510 kernel: Initialized host personality Jun 20 18:49:22.665520 kernel: NET: Registered PF_VSOCK protocol family Jun 20 18:49:22.665531 systemd[1]: Populated /etc with preset unit settings. Jun 20 18:49:22.665543 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Jun 20 18:49:22.665554 systemd[1]: initrd-switch-root.service: Deactivated successfully. Jun 20 18:49:22.665565 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Jun 20 18:49:22.665576 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Jun 20 18:49:22.665621 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Jun 20 18:49:22.665638 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Jun 20 18:49:22.665682 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Jun 20 18:49:22.665695 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Jun 20 18:49:22.665707 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Jun 20 18:49:22.665718 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Jun 20 18:49:22.665733 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Jun 20 18:49:22.665750 systemd[1]: Created slice user.slice - User and Session Slice. Jun 20 18:49:22.665762 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jun 20 18:49:22.665773 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jun 20 18:49:22.665785 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Jun 20 18:49:22.665796 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Jun 20 18:49:22.665808 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Jun 20 18:49:22.665838 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jun 20 18:49:22.665851 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Jun 20 18:49:22.665862 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jun 20 18:49:22.665877 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Jun 20 18:49:22.665889 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Jun 20 18:49:22.665900 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Jun 20 18:49:22.665911 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Jun 20 18:49:22.665922 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jun 20 18:49:22.665934 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jun 20 18:49:22.665945 systemd[1]: Reached target slices.target - Slice Units. Jun 20 18:49:22.665959 systemd[1]: Reached target swap.target - Swaps. Jun 20 18:49:22.665971 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Jun 20 18:49:22.665983 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Jun 20 18:49:22.665994 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Jun 20 18:49:22.666009 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jun 20 18:49:22.666058 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jun 20 18:49:22.666077 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jun 20 18:49:22.666089 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Jun 20 18:49:22.666101 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Jun 20 18:49:22.666112 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Jun 20 18:49:22.666124 systemd[1]: Mounting media.mount - External Media Directory... Jun 20 18:49:22.666135 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jun 20 18:49:22.666146 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Jun 20 18:49:22.666163 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Jun 20 18:49:22.666175 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Jun 20 18:49:22.666187 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). Jun 20 18:49:22.666198 systemd[1]: Reached target machines.target - Containers. Jun 20 18:49:22.666210 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Jun 20 18:49:22.666221 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jun 20 18:49:22.666233 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jun 20 18:49:22.666244 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Jun 20 18:49:22.666259 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jun 20 18:49:22.666271 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jun 20 18:49:22.666282 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jun 20 18:49:22.666294 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Jun 20 18:49:22.666309 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jun 20 18:49:22.666321 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Jun 20 18:49:22.666332 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Jun 20 18:49:22.666344 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Jun 20 18:49:22.666356 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Jun 20 18:49:22.666370 systemd[1]: Stopped systemd-fsck-usr.service. Jun 20 18:49:22.666383 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). Jun 20 18:49:22.666394 systemd[1]: Starting systemd-journald.service - Journal Service... Jun 20 18:49:22.666406 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jun 20 18:49:22.666417 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jun 20 18:49:22.666429 kernel: loop: module loaded Jun 20 18:49:22.666440 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Jun 20 18:49:22.666454 kernel: fuse: init (API version 7.39) Jun 20 18:49:22.666466 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Jun 20 18:49:22.666503 systemd-journald[1314]: Collecting audit messages is disabled. Jun 20 18:49:22.666532 systemd-journald[1314]: Journal started Jun 20 18:49:22.666600 systemd-journald[1314]: Runtime Journal (/run/log/journal/1e551dbd35a54d7691d3ee9e8c9266d2) is 8M, max 2.2G, 2.1G free. Jun 20 18:49:21.893753 systemd[1]: Queued start job for default target multi-user.target. Jun 20 18:49:21.905234 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Jun 20 18:49:21.905712 systemd[1]: systemd-journald.service: Deactivated successfully. Jun 20 18:49:22.686052 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jun 20 18:49:22.692883 systemd[1]: verity-setup.service: Deactivated successfully. Jun 20 18:49:22.692921 systemd[1]: Stopped verity-setup.service. Jun 20 18:49:22.709834 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jun 20 18:49:22.716945 systemd[1]: Started systemd-journald.service - Journal Service. Jun 20 18:49:22.721052 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Jun 20 18:49:22.725254 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Jun 20 18:49:22.729790 systemd[1]: Mounted media.mount - External Media Directory. Jun 20 18:49:22.733773 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Jun 20 18:49:22.738230 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Jun 20 18:49:22.742742 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Jun 20 18:49:22.747093 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Jun 20 18:49:22.754185 kernel: ACPI: bus type drm_connector registered Jun 20 18:49:22.754484 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jun 20 18:49:22.759888 systemd[1]: modprobe@configfs.service: Deactivated successfully. Jun 20 18:49:22.760092 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Jun 20 18:49:22.765102 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jun 20 18:49:22.765344 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jun 20 18:49:22.770194 systemd[1]: modprobe@drm.service: Deactivated successfully. Jun 20 18:49:22.770379 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jun 20 18:49:22.774882 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jun 20 18:49:22.775074 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jun 20 18:49:22.780317 systemd[1]: modprobe@fuse.service: Deactivated successfully. Jun 20 18:49:22.780508 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Jun 20 18:49:22.785176 systemd[1]: modprobe@loop.service: Deactivated successfully. Jun 20 18:49:22.785363 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jun 20 18:49:22.789869 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jun 20 18:49:22.794508 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jun 20 18:49:22.799756 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Jun 20 18:49:22.805044 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Jun 20 18:49:22.819448 systemd[1]: Reached target network-pre.target - Preparation for Network. Jun 20 18:49:22.832922 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Jun 20 18:49:22.843245 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Jun 20 18:49:22.847375 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Jun 20 18:49:22.847414 systemd[1]: Reached target local-fs.target - Local File Systems. Jun 20 18:49:22.852506 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Jun 20 18:49:22.862038 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Jun 20 18:49:22.867806 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Jun 20 18:49:22.871819 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jun 20 18:49:22.873169 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Jun 20 18:49:22.878327 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Jun 20 18:49:22.882900 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jun 20 18:49:22.883993 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Jun 20 18:49:22.888331 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jun 20 18:49:22.889322 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jun 20 18:49:22.894075 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Jun 20 18:49:22.899599 systemd[1]: Starting systemd-sysusers.service - Create System Users... Jun 20 18:49:22.905720 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jun 20 18:49:22.910838 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Jun 20 18:49:22.912225 systemd-journald[1314]: Time spent on flushing to /var/log/journal/1e551dbd35a54d7691d3ee9e8c9266d2 is 13.749ms for 933 entries. Jun 20 18:49:22.912225 systemd-journald[1314]: System Journal (/var/log/journal/1e551dbd35a54d7691d3ee9e8c9266d2) is 8M, max 2.6G, 2.6G free. Jun 20 18:49:22.979687 systemd-journald[1314]: Received client request to flush runtime journal. Jun 20 18:49:22.979747 kernel: loop0: detected capacity change from 0 to 147912 Jun 20 18:49:22.919583 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Jun 20 18:49:22.925909 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Jun 20 18:49:22.931264 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Jun 20 18:49:22.938808 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Jun 20 18:49:22.956992 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Jun 20 18:49:22.962958 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Jun 20 18:49:22.979443 udevadm[1361]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Jun 20 18:49:22.980534 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Jun 20 18:49:23.027920 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Jun 20 18:49:23.028629 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Jun 20 18:49:23.052697 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jun 20 18:49:23.107285 systemd[1]: Finished systemd-sysusers.service - Create System Users. Jun 20 18:49:23.124945 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jun 20 18:49:23.177874 systemd-tmpfiles[1368]: ACLs are not supported, ignoring. Jun 20 18:49:23.177898 systemd-tmpfiles[1368]: ACLs are not supported, ignoring. Jun 20 18:49:23.183284 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jun 20 18:49:23.756844 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jun 20 18:49:23.835840 kernel: loop1: detected capacity change from 0 to 28272 Jun 20 18:49:24.291836 kernel: loop2: detected capacity change from 0 to 138176 Jun 20 18:49:24.314317 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Jun 20 18:49:24.333008 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jun 20 18:49:24.356288 systemd-udevd[1376]: Using default interface naming scheme 'v255'. Jun 20 18:49:24.637228 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jun 20 18:49:24.655261 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jun 20 18:49:24.662721 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Jun 20 18:49:24.714845 kernel: mousedev: PS/2 mouse device common for all mice Jun 20 18:49:24.720891 kernel: loop3: detected capacity change from 0 to 147912 Jun 20 18:49:24.727831 kernel: hv_vmbus: registering driver hv_balloon Jun 20 18:49:24.731835 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Jun 20 18:49:24.736830 kernel: hv_vmbus: registering driver hyperv_fb Jun 20 18:49:24.740835 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Jun 20 18:49:24.740927 kernel: loop4: detected capacity change from 0 to 28272 Jun 20 18:49:24.744243 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Jun 20 18:49:24.751842 kernel: Console: switching to colour dummy device 80x25 Jun 20 18:49:24.753058 kernel: Console: switching to colour frame buffer device 128x48 Jun 20 18:49:24.753034 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Jun 20 18:49:24.763010 kernel: loop5: detected capacity change from 0 to 138176 Jun 20 18:49:24.774075 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jun 20 18:49:24.786081 (sd-merge)[1417]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Jun 20 18:49:24.786579 (sd-merge)[1417]: Merged extensions into '/usr'. Jun 20 18:49:24.789786 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Jun 20 18:49:24.808054 systemd[1]: Starting ensure-sysext.service... Jun 20 18:49:24.811972 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jun 20 18:49:24.814077 systemd[1]: Started systemd-userdbd.service - User Database Manager. Jun 20 18:49:24.818256 systemd[1]: Reload requested from client PID 1431 ('systemctl') (unit ensure-sysext.service)... Jun 20 18:49:24.818266 systemd[1]: Reloading... Jun 20 18:49:24.870356 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 38 scanned by (udev-worker) (1386) Jun 20 18:49:24.870440 zram_generator::config[1476]: No configuration found. Jun 20 18:49:24.888103 systemd-tmpfiles[1435]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Jun 20 18:49:24.889095 systemd-tmpfiles[1435]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Jun 20 18:49:24.890086 systemd-tmpfiles[1435]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Jun 20 18:49:24.890378 systemd-tmpfiles[1435]: ACLs are not supported, ignoring. Jun 20 18:49:24.890453 systemd-tmpfiles[1435]: ACLs are not supported, ignoring. Jun 20 18:49:24.906965 kernel: EDAC MC: Ver: 3.0.0 Jun 20 18:49:24.969049 systemd-networkd[1393]: lo: Link UP Jun 20 18:49:24.969060 systemd-networkd[1393]: lo: Gained carrier Jun 20 18:49:24.970396 systemd-networkd[1393]: Enumeration completed Jun 20 18:49:24.970768 systemd-networkd[1393]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jun 20 18:49:24.970778 systemd-networkd[1393]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jun 20 18:49:24.971343 systemd-networkd[1393]: eth0: Link UP Jun 20 18:49:24.971353 systemd-networkd[1393]: eth0: Gained carrier Jun 20 18:49:24.971367 systemd-networkd[1393]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jun 20 18:49:25.001325 systemd-tmpfiles[1435]: Detected autofs mount point /boot during canonicalization of boot. Jun 20 18:49:25.001339 systemd-tmpfiles[1435]: Skipping /boot Jun 20 18:49:25.012859 systemd-networkd[1393]: eth0: DHCPv4 address 10.200.8.16/24, gateway 10.200.8.1 acquired from 168.63.129.16 Jun 20 18:49:25.016999 systemd-tmpfiles[1435]: Detected autofs mount point /boot during canonicalization of boot. Jun 20 18:49:25.017015 systemd-tmpfiles[1435]: Skipping /boot Jun 20 18:49:25.032804 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. Jun 20 18:49:25.172991 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Jun 20 18:49:25.178709 systemd[1]: Reloading finished in 360 ms. Jun 20 18:49:25.202838 systemd[1]: Started systemd-networkd.service - Network Configuration. Jun 20 18:49:25.232221 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jun 20 18:49:25.238640 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jun 20 18:49:25.238882 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jun 20 18:49:25.245271 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jun 20 18:49:25.262576 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Jun 20 18:49:25.293237 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jun 20 18:49:25.309142 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jun 20 18:49:25.325771 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Jun 20 18:49:25.330497 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jun 20 18:49:25.331696 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Jun 20 18:49:25.337285 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jun 20 18:49:25.342437 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jun 20 18:49:25.348044 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jun 20 18:49:25.352217 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jun 20 18:49:25.353332 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Jun 20 18:49:25.358344 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). Jun 20 18:49:25.359686 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Jun 20 18:49:25.365503 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Jun 20 18:49:25.371275 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Jun 20 18:49:25.377681 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jun 20 18:49:25.382757 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Jun 20 18:49:25.388326 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jun 20 18:49:25.394394 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jun 20 18:49:25.397749 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jun 20 18:49:25.397971 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jun 20 18:49:25.403044 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jun 20 18:49:25.403236 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jun 20 18:49:25.410475 systemd[1]: modprobe@loop.service: Deactivated successfully. Jun 20 18:49:25.410668 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jun 20 18:49:25.427181 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Jun 20 18:49:25.429254 systemd[1]: Finished ensure-sysext.service. Jun 20 18:49:25.432775 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jun 20 18:49:25.433042 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jun 20 18:49:25.434252 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jun 20 18:49:25.435658 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jun 20 18:49:25.438760 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jun 20 18:49:25.441115 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jun 20 18:49:25.441510 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jun 20 18:49:25.441558 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). Jun 20 18:49:25.441620 systemd[1]: Reached target time-set.target - System Time Set. Jun 20 18:49:25.442172 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jun 20 18:49:25.442756 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jun 20 18:49:25.442952 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jun 20 18:49:25.443954 systemd[1]: modprobe@drm.service: Deactivated successfully. Jun 20 18:49:25.444129 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jun 20 18:49:25.444583 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jun 20 18:49:25.444758 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jun 20 18:49:25.446919 systemd[1]: modprobe@loop.service: Deactivated successfully. Jun 20 18:49:25.447109 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jun 20 18:49:25.447990 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jun 20 18:49:25.448073 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jun 20 18:49:25.451162 lvm[1599]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Jun 20 18:49:25.456628 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Jun 20 18:49:25.493929 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Jun 20 18:49:25.497838 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Jun 20 18:49:25.531562 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Jun 20 18:49:25.533267 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jun 20 18:49:25.546176 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Jun 20 18:49:25.550083 lvm[1637]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Jun 20 18:49:25.593751 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Jun 20 18:49:25.674393 systemd-resolved[1608]: Positive Trust Anchors: Jun 20 18:49:25.674410 systemd-resolved[1608]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jun 20 18:49:25.674440 systemd-resolved[1608]: 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 Jun 20 18:49:25.731121 systemd-resolved[1608]: Using system hostname 'ci-4230.2.0-a-e85dac22cd'. Jun 20 18:49:25.750301 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jun 20 18:49:25.751993 systemd[1]: Reached target network.target - Network. Jun 20 18:49:25.752623 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jun 20 18:49:25.771336 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jun 20 18:49:25.783021 augenrules[1655]: No rules Jun 20 18:49:25.784118 systemd[1]: audit-rules.service: Deactivated successfully. Jun 20 18:49:25.784366 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jun 20 18:49:26.191739 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Jun 20 18:49:26.197088 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). Jun 20 18:49:26.546946 systemd-networkd[1393]: eth0: Gained IPv6LL Jun 20 18:49:26.549289 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Jun 20 18:49:26.554634 systemd[1]: Reached target network-online.target - Network is Online. Jun 20 18:49:30.395031 ldconfig[1345]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jun 20 18:49:30.417362 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Jun 20 18:49:30.435976 systemd[1]: Starting systemd-update-done.service - Update is Completed... Jun 20 18:49:30.444690 systemd[1]: Finished systemd-update-done.service - Update is Completed. Jun 20 18:49:30.449423 systemd[1]: Reached target sysinit.target - System Initialization. Jun 20 18:49:30.453834 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Jun 20 18:49:30.458925 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Jun 20 18:49:30.464645 systemd[1]: Started logrotate.timer - Daily rotation of log files. Jun 20 18:49:30.470195 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Jun 20 18:49:30.475239 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Jun 20 18:49:30.480282 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Jun 20 18:49:30.480320 systemd[1]: Reached target paths.target - Path Units. Jun 20 18:49:30.484035 systemd[1]: Reached target timers.target - Timer Units. Jun 20 18:49:30.488433 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Jun 20 18:49:30.494380 systemd[1]: Starting docker.socket - Docker Socket for the API... Jun 20 18:49:30.500978 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Jun 20 18:49:30.506200 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Jun 20 18:49:30.511249 systemd[1]: Reached target ssh-access.target - SSH Access Available. Jun 20 18:49:30.517433 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Jun 20 18:49:30.521911 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Jun 20 18:49:30.527176 systemd[1]: Listening on docker.socket - Docker Socket for the API. Jun 20 18:49:30.531557 systemd[1]: Reached target sockets.target - Socket Units. Jun 20 18:49:30.535416 systemd[1]: Reached target basic.target - Basic System. Jun 20 18:49:30.539091 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Jun 20 18:49:30.539121 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Jun 20 18:49:30.541210 systemd[1]: Starting chronyd.service - NTP client/server... Jun 20 18:49:30.545418 systemd[1]: Starting containerd.service - containerd container runtime... Jun 20 18:49:30.550659 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Jun 20 18:49:30.554931 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Jun 20 18:49:30.559455 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Jun 20 18:49:30.564322 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Jun 20 18:49:30.568280 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). Jun 20 18:49:30.568323 systemd[1]: hv_fcopy_daemon.service - Hyper-V FCOPY daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_fcopy). Jun 20 18:49:30.569375 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Jun 20 18:49:30.573560 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Jun 20 18:49:30.574347 jq[1675]: false Jun 20 18:49:30.574577 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Jun 20 18:49:30.579406 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Jun 20 18:49:30.579993 KVP[1677]: KVP starting; pid is:1677 Jun 20 18:49:30.581908 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Jun 20 18:49:30.584348 KVP[1677]: KVP LIC Version: 3.1 Jun 20 18:49:30.584829 kernel: hv_utils: KVP IC version 4.0 Jun 20 18:49:30.590724 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Jun 20 18:49:30.596408 (chronyd)[1668]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Jun 20 18:49:30.597257 systemd[1]: Starting systemd-logind.service - User Login Management... Jun 20 18:49:30.599636 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Jun 20 18:49:30.600095 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Jun 20 18:49:30.602206 chronyd[1687]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) Jun 20 18:49:30.602593 systemd[1]: Starting update-engine.service - Update Engine... Jun 20 18:49:30.607302 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Jun 20 18:49:30.614354 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Jun 20 18:49:30.614598 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Jun 20 18:49:30.614977 systemd[1]: motdgen.service: Deactivated successfully. Jun 20 18:49:30.615196 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Jun 20 18:49:30.617943 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Jun 20 18:49:30.618155 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Jun 20 18:49:30.622745 jq[1689]: true Jun 20 18:49:30.625329 chronyd[1687]: Timezone right/UTC failed leap second check, ignoring Jun 20 18:49:30.625514 chronyd[1687]: Loaded seccomp filter (level 2) Jun 20 18:49:30.626583 systemd[1]: Started chronyd.service - NTP client/server. Jun 20 18:49:30.635178 jq[1697]: true Jun 20 18:49:30.636294 (ntainerd)[1700]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Jun 20 18:49:30.725116 systemd-logind[1684]: New seat seat0. Jun 20 18:49:30.726328 systemd-logind[1684]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Jun 20 18:49:30.726522 systemd[1]: Started systemd-logind.service - User Login Management. Jun 20 18:49:30.765752 update_engine[1685]: I20250620 18:49:30.765429 1685 main.cc:92] Flatcar Update Engine starting Jun 20 18:49:30.768046 extend-filesystems[1676]: Found loop3 Jun 20 18:49:30.771181 extend-filesystems[1676]: Found loop4 Jun 20 18:49:30.771181 extend-filesystems[1676]: Found loop5 Jun 20 18:49:30.771181 extend-filesystems[1676]: Found sda Jun 20 18:49:30.771181 extend-filesystems[1676]: Found sda1 Jun 20 18:49:30.771181 extend-filesystems[1676]: Found sda2 Jun 20 18:49:30.771181 extend-filesystems[1676]: Found sda3 Jun 20 18:49:30.771181 extend-filesystems[1676]: Found usr Jun 20 18:49:30.771181 extend-filesystems[1676]: Found sda4 Jun 20 18:49:30.771181 extend-filesystems[1676]: Found sda6 Jun 20 18:49:30.771181 extend-filesystems[1676]: Found sda7 Jun 20 18:49:30.771181 extend-filesystems[1676]: Found sda9 Jun 20 18:49:30.771181 extend-filesystems[1676]: Checking size of /dev/sda9 Jun 20 18:49:30.810029 bash[1724]: Updated "/home/core/.ssh/authorized_keys" Jun 20 18:49:30.773430 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Jun 20 18:49:30.780247 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Jun 20 18:49:30.834161 extend-filesystems[1676]: Old size kept for /dev/sda9 Jun 20 18:49:30.838626 extend-filesystems[1676]: Found sdb Jun 20 18:49:30.838626 extend-filesystems[1676]: Found sdb1 Jun 20 18:49:30.838626 extend-filesystems[1676]: Found sr0 Jun 20 18:49:30.835383 systemd[1]: extend-filesystems.service: Deactivated successfully. Jun 20 18:49:30.844388 dbus-daemon[1671]: [system] SELinux support is enabled Jun 20 18:49:30.836147 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Jun 20 18:49:30.845425 systemd[1]: Started dbus.service - D-Bus System Message Bus. Jun 20 18:49:30.854688 update_engine[1685]: I20250620 18:49:30.854629 1685 update_check_scheduler.cc:74] Next update check in 4m53s Jun 20 18:49:30.857205 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). Jun 20 18:49:30.857247 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Jun 20 18:49:30.858769 dbus-daemon[1671]: [system] Successfully activated service 'org.freedesktop.systemd1' Jun 20 18:49:30.867183 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). Jun 20 18:49:30.867215 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Jun 20 18:49:30.867867 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 38 scanned by (udev-worker) (1736) Jun 20 18:49:30.874303 systemd[1]: Started update-engine.service - Update Engine. Jun 20 18:49:30.889076 systemd[1]: Started locksmithd.service - Cluster reboot manager. Jun 20 18:49:30.902018 setup-nvidia[1679]: Downloading Flatcar Container Linux Developer Container for version: 4230.2.0 Jun 20 18:49:30.974404 coreos-metadata[1670]: Jun 20 18:49:30.974 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Jun 20 18:49:30.976565 coreos-metadata[1670]: Jun 20 18:49:30.976 INFO Fetch successful Jun 20 18:49:30.976705 coreos-metadata[1670]: Jun 20 18:49:30.976 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Jun 20 18:49:30.980586 coreos-metadata[1670]: Jun 20 18:49:30.980 INFO Fetch successful Jun 20 18:49:30.980915 coreos-metadata[1670]: Jun 20 18:49:30.980 INFO Fetching http://168.63.129.16/machine/6e40a2a6-27c8-4484-9150-ce445edb7c15/cc9c147b%2D7daf%2D496a%2Db899%2D09e45ba4b6a4.%5Fci%2D4230.2.0%2Da%2De85dac22cd?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Jun 20 18:49:30.982315 coreos-metadata[1670]: Jun 20 18:49:30.982 INFO Fetch successful Jun 20 18:49:30.982456 coreos-metadata[1670]: Jun 20 18:49:30.982 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Jun 20 18:49:30.989367 coreos-metadata[1670]: Jun 20 18:49:30.989 INFO Fetch successful Jun 20 18:49:31.013493 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Jun 20 18:49:31.018323 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Jun 20 18:49:31.041398 sshd_keygen[1699]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Jun 20 18:49:31.058434 locksmithd[1775]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Jun 20 18:49:31.063212 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Jun 20 18:49:31.086250 systemd[1]: Starting issuegen.service - Generate /run/issue... Jun 20 18:49:31.091061 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Jun 20 18:49:31.096991 systemd[1]: issuegen.service: Deactivated successfully. Jun 20 18:49:31.097206 systemd[1]: Finished issuegen.service - Generate /run/issue. Jun 20 18:49:31.104792 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Jun 20 18:49:31.114944 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Jun 20 18:49:31.119619 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Jun 20 18:49:31.126927 systemd[1]: Started getty@tty1.service - Getty on tty1. Jun 20 18:49:31.132104 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Jun 20 18:49:31.136710 systemd[1]: Reached target getty.target - Login Prompts. Jun 20 18:49:31.518133 setup-nvidia[1839]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 20 18:49:31.518550 setup-nvidia[1839]: Dload Upload Total Spent Left Speed Jun 20 18:49:31.955877 containerd[1700]: time="2025-06-20T18:49:31.955741900Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Jun 20 18:49:31.976831 containerd[1700]: time="2025-06-20T18:49:31.976784700Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Jun 20 18:49:31.978383 containerd[1700]: time="2025-06-20T18:49:31.978314200Z" 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.94-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Jun 20 18:49:31.978383 containerd[1700]: time="2025-06-20T18:49:31.978378500Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Jun 20 18:49:31.978465 containerd[1700]: time="2025-06-20T18:49:31.978399600Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Jun 20 18:49:31.978562 containerd[1700]: time="2025-06-20T18:49:31.978539400Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Jun 20 18:49:31.978594 containerd[1700]: time="2025-06-20T18:49:31.978563200Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Jun 20 18:49:31.978657 containerd[1700]: time="2025-06-20T18:49:31.978634400Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Jun 20 18:49:31.978687 containerd[1700]: time="2025-06-20T18:49:31.978655400Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Jun 20 18:49:31.978885 containerd[1700]: time="2025-06-20T18:49:31.978861300Z" 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 Jun 20 18:49:31.978925 containerd[1700]: time="2025-06-20T18:49:31.978883200Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Jun 20 18:49:31.978925 containerd[1700]: time="2025-06-20T18:49:31.978897000Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Jun 20 18:49:31.978925 containerd[1700]: time="2025-06-20T18:49:31.978906100Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Jun 20 18:49:31.979029 containerd[1700]: time="2025-06-20T18:49:31.979005500Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Jun 20 18:49:31.979304 containerd[1700]: time="2025-06-20T18:49:31.979281300Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Jun 20 18:49:31.979442 containerd[1700]: time="2025-06-20T18:49:31.979420600Z" 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 Jun 20 18:49:31.979442 containerd[1700]: time="2025-06-20T18:49:31.979439800Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Jun 20 18:49:31.979544 containerd[1700]: time="2025-06-20T18:49:31.979524500Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Jun 20 18:49:31.979599 containerd[1700]: time="2025-06-20T18:49:31.979580800Z" level=info msg="metadata content store policy set" policy=shared Jun 20 18:49:31.996429 containerd[1700]: time="2025-06-20T18:49:31.996401000Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Jun 20 18:49:31.996513 containerd[1700]: time="2025-06-20T18:49:31.996448800Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Jun 20 18:49:31.996513 containerd[1700]: time="2025-06-20T18:49:31.996467000Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Jun 20 18:49:31.996513 containerd[1700]: time="2025-06-20T18:49:31.996483000Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Jun 20 18:49:31.996587 containerd[1700]: time="2025-06-20T18:49:31.996515300Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Jun 20 18:49:31.996678 containerd[1700]: time="2025-06-20T18:49:31.996653200Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Jun 20 18:49:31.996898 containerd[1700]: time="2025-06-20T18:49:31.996875900Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Jun 20 18:49:31.997009 containerd[1700]: time="2025-06-20T18:49:31.996986700Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Jun 20 18:49:31.997044 containerd[1700]: time="2025-06-20T18:49:31.997010000Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Jun 20 18:49:31.997044 containerd[1700]: time="2025-06-20T18:49:31.997024200Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Jun 20 18:49:31.997044 containerd[1700]: time="2025-06-20T18:49:31.997037400Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Jun 20 18:49:31.997108 containerd[1700]: time="2025-06-20T18:49:31.997050600Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Jun 20 18:49:31.997108 containerd[1700]: time="2025-06-20T18:49:31.997062800Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Jun 20 18:49:31.997108 containerd[1700]: time="2025-06-20T18:49:31.997075600Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Jun 20 18:49:31.997172 containerd[1700]: time="2025-06-20T18:49:31.997105700Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Jun 20 18:49:31.997172 containerd[1700]: time="2025-06-20T18:49:31.997133600Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Jun 20 18:49:31.997172 containerd[1700]: time="2025-06-20T18:49:31.997148700Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Jun 20 18:49:31.997172 containerd[1700]: time="2025-06-20T18:49:31.997160400Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Jun 20 18:49:31.997255 containerd[1700]: time="2025-06-20T18:49:31.997181900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Jun 20 18:49:31.997255 containerd[1700]: time="2025-06-20T18:49:31.997195900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Jun 20 18:49:31.997255 containerd[1700]: time="2025-06-20T18:49:31.997207900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Jun 20 18:49:31.997255 containerd[1700]: time="2025-06-20T18:49:31.997232900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Jun 20 18:49:31.997255 containerd[1700]: time="2025-06-20T18:49:31.997248900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Jun 20 18:49:31.997365 containerd[1700]: time="2025-06-20T18:49:31.997262200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Jun 20 18:49:31.997365 containerd[1700]: time="2025-06-20T18:49:31.997273600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Jun 20 18:49:31.997365 containerd[1700]: time="2025-06-20T18:49:31.997286000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Jun 20 18:49:31.997365 containerd[1700]: time="2025-06-20T18:49:31.997299100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Jun 20 18:49:31.997365 containerd[1700]: time="2025-06-20T18:49:31.997313400Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Jun 20 18:49:31.997365 containerd[1700]: time="2025-06-20T18:49:31.997324800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Jun 20 18:49:31.997365 containerd[1700]: time="2025-06-20T18:49:31.997336400Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Jun 20 18:49:31.997365 containerd[1700]: time="2025-06-20T18:49:31.997347700Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Jun 20 18:49:31.997365 containerd[1700]: time="2025-06-20T18:49:31.997360600Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Jun 20 18:49:31.997561 containerd[1700]: time="2025-06-20T18:49:31.997379800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Jun 20 18:49:31.997561 containerd[1700]: time="2025-06-20T18:49:31.997392500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Jun 20 18:49:31.997561 containerd[1700]: time="2025-06-20T18:49:31.997402400Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Jun 20 18:49:31.997561 containerd[1700]: time="2025-06-20T18:49:31.997447400Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Jun 20 18:49:31.997561 containerd[1700]: time="2025-06-20T18:49:31.997465700Z" 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 Jun 20 18:49:31.997561 containerd[1700]: time="2025-06-20T18:49:31.997475700Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Jun 20 18:49:31.997561 containerd[1700]: time="2025-06-20T18:49:31.997487800Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Jun 20 18:49:31.997561 containerd[1700]: time="2025-06-20T18:49:31.997496900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Jun 20 18:49:31.997561 containerd[1700]: time="2025-06-20T18:49:31.997508800Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Jun 20 18:49:31.997561 containerd[1700]: time="2025-06-20T18:49:31.997519600Z" level=info msg="NRI interface is disabled by configuration." Jun 20 18:49:31.997561 containerd[1700]: time="2025-06-20T18:49:31.997530000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Jun 20 18:49:31.997883 containerd[1700]: time="2025-06-20T18:49:31.997830900Z" 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}" Jun 20 18:49:31.997883 containerd[1700]: time="2025-06-20T18:49:31.997883800Z" level=info msg="Connect containerd service" Jun 20 18:49:31.998058 containerd[1700]: time="2025-06-20T18:49:31.997933500Z" level=info msg="using legacy CRI server" Jun 20 18:49:31.998058 containerd[1700]: time="2025-06-20T18:49:31.997945500Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Jun 20 18:49:31.998110 containerd[1700]: time="2025-06-20T18:49:31.998059500Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Jun 20 18:49:31.999255 containerd[1700]: time="2025-06-20T18:49:31.999221700Z" 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" Jun 20 18:49:31.999393 containerd[1700]: time="2025-06-20T18:49:31.999351300Z" level=info msg="Start subscribing containerd event" Jun 20 18:49:31.999432 containerd[1700]: time="2025-06-20T18:49:31.999406700Z" level=info msg="Start recovering state" Jun 20 18:49:31.999487 containerd[1700]: time="2025-06-20T18:49:31.999466700Z" level=info msg="Start event monitor" Jun 20 18:49:31.999520 containerd[1700]: time="2025-06-20T18:49:31.999489400Z" level=info msg="Start snapshots syncer" Jun 20 18:49:31.999520 containerd[1700]: time="2025-06-20T18:49:31.999499300Z" level=info msg="Start cni network conf syncer for default" Jun 20 18:49:31.999520 containerd[1700]: time="2025-06-20T18:49:31.999506900Z" level=info msg="Start streaming server" Jun 20 18:49:31.999586 containerd[1700]: time="2025-06-20T18:49:31.999540400Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jun 20 18:49:31.999619 containerd[1700]: time="2025-06-20T18:49:31.999594800Z" level=info msg=serving... address=/run/containerd/containerd.sock Jun 20 18:49:31.999690 containerd[1700]: time="2025-06-20T18:49:31.999666100Z" level=info msg="containerd successfully booted in 0.044899s" Jun 20 18:49:31.999768 systemd[1]: Started containerd.service - containerd container runtime. Jun 20 18:49:33.495034 waagent[1830]: 2025-06-20T18:49:33.494945Z INFO Daemon Daemon Azure Linux Agent Version: 2.9.1.1 Jun 20 18:49:33.499136 waagent[1830]: 2025-06-20T18:49:33.499082Z INFO Daemon Daemon OS: flatcar 4230.2.0 Jun 20 18:49:33.502556 waagent[1830]: 2025-06-20T18:49:33.502500Z INFO Daemon Daemon Python: 3.11.11 Jun 20 18:49:33.505868 waagent[1830]: 2025-06-20T18:49:33.505787Z INFO Daemon Daemon Run daemon Jun 20 18:49:33.508796 waagent[1830]: 2025-06-20T18:49:33.508746Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4230.2.0' Jun 20 18:49:33.515657 waagent[1830]: 2025-06-20T18:49:33.515490Z INFO Daemon Daemon Using waagent for provisioning Jun 20 18:49:33.520247 waagent[1830]: 2025-06-20T18:49:33.520130Z INFO Daemon Daemon Activate resource disk Jun 20 18:49:33.523890 waagent[1830]: 2025-06-20T18:49:33.523838Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Jun 20 18:49:33.532791 waagent[1830]: 2025-06-20T18:49:33.531769Z INFO Daemon Daemon Found device: sdb Jun 20 18:49:33.540789 waagent[1830]: 2025-06-20T18:49:33.540731Z INFO Daemon Daemon Examining partition table Jun 20 18:49:33.567824 waagent[1830]: 2025-06-20T18:49:33.567749Z INFO Daemon Daemon GPT not detected, determining filesystem Jun 20 18:49:33.640614 waagent[1830]: 2025-06-20T18:49:33.640526Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Jun 20 18:49:33.645189 waagent[1830]: 2025-06-20T18:49:33.645105Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Jun 20 18:49:34.140836 kernel: sdb: sdb1 Jun 20 18:49:34.145838 kernel: sdb: sdb1 Jun 20 18:49:34.185768 waagent[1830]: 2025-06-20T18:49:34.185674Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Jun 20 18:49:34.200266 waagent[1830]: 2025-06-20T18:49:34.200207Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Jun 20 18:49:34.200829 kernel: sdb: sdb1 Jun 20 18:49:36.937337 login[1832]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Jun 20 18:49:36.939242 login[1833]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Jun 20 18:49:36.950295 systemd-logind[1684]: New session 1 of user core. Jun 20 18:49:36.951368 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Jun 20 18:49:36.962028 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Jun 20 18:49:36.972335 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Jun 20 18:49:36.974581 systemd[1]: Starting user@500.service - User Manager for UID 500... Jun 20 18:49:36.981787 (systemd)[1917]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Jun 20 18:49:36.984019 systemd-logind[1684]: New session c1 of user core. Jun 20 18:49:37.275642 systemd[1917]: Queued start job for default target default.target. Jun 20 18:49:37.286852 systemd[1917]: Created slice app.slice - User Application Slice. Jun 20 18:49:37.286912 systemd[1917]: Reached target paths.target - Paths. Jun 20 18:49:37.286970 systemd[1917]: Reached target timers.target - Timers. Jun 20 18:49:37.288330 systemd[1917]: Starting dbus.socket - D-Bus User Message Bus Socket... Jun 20 18:49:37.299083 systemd[1917]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jun 20 18:49:37.299207 systemd[1917]: Reached target sockets.target - Sockets. Jun 20 18:49:37.299261 systemd[1917]: Reached target basic.target - Basic System. Jun 20 18:49:37.299344 systemd[1917]: Reached target default.target - Main User Target. Jun 20 18:49:37.299380 systemd[1917]: Startup finished in 309ms. Jun 20 18:49:37.299442 systemd[1]: Started user@500.service - User Manager for UID 500. Jun 20 18:49:37.300898 systemd[1]: Started session-1.scope - Session 1 of User core. Jun 20 18:49:37.937775 login[1832]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Jun 20 18:49:37.942214 systemd-logind[1684]: New session 2 of user core. Jun 20 18:49:37.952972 systemd[1]: Started session-2.scope - Session 2 of User core. Jun 20 18:49:39.357048 waagent[1830]: 2025-06-20T18:49:39.356952Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Jun 20 18:49:39.381491 waagent[1830]: 2025-06-20T18:49:39.381428Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Jun 20 18:49:39.384147 waagent[1830]: 2025-06-20T18:49:39.384088Z INFO Daemon Daemon Clean protocol and wireserver endpoint Jun 20 18:49:39.384705 waagent[1830]: 2025-06-20T18:49:39.384659Z INFO Daemon Daemon Running default provisioning handler Jun 20 18:49:39.393181 waagent[1830]: 2025-06-20T18:49:39.392104Z 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. Jun 20 18:49:39.394092 waagent[1830]: 2025-06-20T18:49:39.394042Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Jun 20 18:49:39.394442 waagent[1830]: 2025-06-20T18:49:39.394401Z INFO Daemon Daemon cloud-init is enabled: False Jun 20 18:49:39.395991 waagent[1830]: 2025-06-20T18:49:39.395950Z INFO Daemon Daemon Copying ovf-env.xml Jun 20 18:49:39.419099 kernel: EXT4-fs (sdb1): mounted filesystem 533707ef-231f-4f98-9bc8-2c86f632eedc r/w with ordered data mode. Quota mode: none. Jun 20 18:49:39.531056 waagent[1830]: 2025-06-20T18:49:39.530974Z INFO Daemon Daemon Successfully mounted dvd Jun 20 18:49:39.554916 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Jun 20 18:49:39.555206 waagent[1830]: 2025-06-20T18:49:39.554899Z INFO Daemon Daemon Detect protocol endpoint Jun 20 18:49:39.556675 waagent[1830]: 2025-06-20T18:49:39.556626Z INFO Daemon Daemon Clean protocol and wireserver endpoint Jun 20 18:49:39.558264 waagent[1830]: 2025-06-20T18:49:39.558221Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Jun 20 18:49:39.559034 waagent[1830]: 2025-06-20T18:49:39.558995Z INFO Daemon Daemon Test for route to 168.63.129.16 Jun 20 18:49:39.560739 waagent[1830]: 2025-06-20T18:49:39.560687Z INFO Daemon Daemon Route to 168.63.129.16 exists Jun 20 18:49:39.562095 waagent[1830]: 2025-06-20T18:49:39.562048Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Jun 20 18:49:39.617852 waagent[1830]: 2025-06-20T18:49:39.617722Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Jun 20 18:49:39.619677 waagent[1830]: 2025-06-20T18:49:39.619644Z INFO Daemon Daemon Wire protocol version:2012-11-30 Jun 20 18:49:39.620988 waagent[1830]: 2025-06-20T18:49:39.620948Z INFO Daemon Daemon Server preferred version:2015-04-05 Jun 20 18:49:39.915743 waagent[1830]: 2025-06-20T18:49:39.915583Z INFO Daemon Daemon Initializing goal state during protocol detection Jun 20 18:49:39.917361 waagent[1830]: 2025-06-20T18:49:39.917310Z INFO Daemon Daemon Forcing an update of the goal state. Jun 20 18:49:39.926308 waagent[1830]: 2025-06-20T18:49:39.926251Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Jun 20 18:49:39.943761 waagent[1830]: 2025-06-20T18:49:39.943710Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.175 Jun 20 18:49:39.946259 waagent[1830]: 2025-06-20T18:49:39.946157Z INFO Daemon Jun 20 18:49:39.948930 waagent[1830]: 2025-06-20T18:49:39.948881Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 8cd73b6d-33f5-4418-944b-5ce95e86ed61 eTag: 4957725483033079703 source: Fabric] Jun 20 18:49:39.951474 waagent[1830]: 2025-06-20T18:49:39.951379Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Jun 20 18:49:39.954315 waagent[1830]: 2025-06-20T18:49:39.954221Z INFO Daemon Jun 20 18:49:39.955972 waagent[1830]: 2025-06-20T18:49:39.955919Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Jun 20 18:49:39.962471 waagent[1830]: 2025-06-20T18:49:39.962424Z INFO Daemon Daemon Downloading artifacts profile blob Jun 20 18:49:40.079197 waagent[1830]: 2025-06-20T18:49:40.079125Z INFO Daemon Downloaded certificate {'thumbprint': '1AE70400C795BA50BD144EEC81D54348F28B8926', 'hasPrivateKey': True} Jun 20 18:49:40.081077 waagent[1830]: 2025-06-20T18:49:40.081028Z INFO Daemon Fetch goal state completed Jun 20 18:49:40.110582 waagent[1830]: 2025-06-20T18:49:40.110529Z INFO Daemon Daemon Starting provisioning Jun 20 18:49:40.112292 waagent[1830]: 2025-06-20T18:49:40.112229Z INFO Daemon Daemon Handle ovf-env.xml. Jun 20 18:49:40.113779 waagent[1830]: 2025-06-20T18:49:40.113694Z INFO Daemon Daemon Set hostname [ci-4230.2.0-a-e85dac22cd] Jun 20 18:49:40.197889 waagent[1830]: 2025-06-20T18:49:40.197669Z INFO Daemon Daemon Publish hostname [ci-4230.2.0-a-e85dac22cd] Jun 20 18:49:40.202699 waagent[1830]: 2025-06-20T18:49:40.202638Z INFO Daemon Daemon Examine /proc/net/route for primary interface Jun 20 18:49:40.207923 waagent[1830]: 2025-06-20T18:49:40.207873Z INFO Daemon Daemon Primary interface is [eth0] Jun 20 18:49:40.220307 systemd-networkd[1393]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jun 20 18:49:40.220317 systemd-networkd[1393]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jun 20 18:49:40.220346 systemd-networkd[1393]: eth0: DHCP lease lost Jun 20 18:49:40.220724 waagent[1830]: 2025-06-20T18:49:40.220672Z INFO Daemon Daemon Create user account if not exists Jun 20 18:49:40.225082 waagent[1830]: 2025-06-20T18:49:40.225019Z INFO Daemon Daemon User core already exists, skip useradd Jun 20 18:49:40.226741 waagent[1830]: 2025-06-20T18:49:40.226686Z INFO Daemon Daemon Configure sudoer Jun 20 18:49:40.228464 waagent[1830]: 2025-06-20T18:49:40.228416Z INFO Daemon Daemon Configure sshd Jun 20 18:49:40.229882 waagent[1830]: 2025-06-20T18:49:40.229830Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Jun 20 18:49:40.230397 waagent[1830]: 2025-06-20T18:49:40.230358Z INFO Daemon Daemon Deploy ssh public key. Jun 20 18:49:40.274852 systemd-networkd[1393]: eth0: DHCPv4 address 10.200.8.16/24, gateway 10.200.8.1 acquired from 168.63.129.16 Jun 20 18:49:41.454197 waagent[1830]: 2025-06-20T18:49:41.454134Z INFO Daemon Daemon Provisioning complete Jun 20 18:49:41.464094 waagent[1830]: 2025-06-20T18:49:41.464042Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Jun 20 18:49:41.465645 waagent[1830]: 2025-06-20T18:49:41.465587Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Jun 20 18:49:41.466968 waagent[1830]: 2025-06-20T18:49:41.466920Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.9.1.1 is the most current agent Jun 20 18:49:41.610303 waagent[1980]: 2025-06-20T18:49:41.610217Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.9.1.1) Jun 20 18:49:41.610640 waagent[1980]: 2025-06-20T18:49:41.610369Z INFO ExtHandler ExtHandler OS: flatcar 4230.2.0 Jun 20 18:49:41.610640 waagent[1980]: 2025-06-20T18:49:41.610455Z INFO ExtHandler ExtHandler Python: 3.11.11 Jun 20 18:49:41.747204 waagent[1980]: 2025-06-20T18:49:41.747049Z INFO ExtHandler ExtHandler Distro: flatcar-4230.2.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.11; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; Jun 20 18:49:41.747376 waagent[1980]: 2025-06-20T18:49:41.747326Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jun 20 18:49:41.747469 waagent[1980]: 2025-06-20T18:49:41.747427Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Jun 20 18:49:41.755098 waagent[1980]: 2025-06-20T18:49:41.755033Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Jun 20 18:49:41.759791 waagent[1980]: 2025-06-20T18:49:41.759748Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.175 Jun 20 18:49:41.760262 waagent[1980]: 2025-06-20T18:49:41.760216Z INFO ExtHandler Jun 20 18:49:41.760352 waagent[1980]: 2025-06-20T18:49:41.760314Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 5ed11bcb-2c9e-4023-9e7f-5dde2e670f55 eTag: 4957725483033079703 source: Fabric] Jun 20 18:49:41.760692 waagent[1980]: 2025-06-20T18:49:41.760648Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Jun 20 18:49:41.761401 waagent[1980]: 2025-06-20T18:49:41.761353Z INFO ExtHandler Jun 20 18:49:41.761491 waagent[1980]: 2025-06-20T18:49:41.761452Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Jun 20 18:49:41.764674 waagent[1980]: 2025-06-20T18:49:41.764630Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Jun 20 18:49:41.823091 waagent[1980]: 2025-06-20T18:49:41.823026Z INFO ExtHandler Downloaded certificate {'thumbprint': '1AE70400C795BA50BD144EEC81D54348F28B8926', 'hasPrivateKey': True} Jun 20 18:49:41.823569 waagent[1980]: 2025-06-20T18:49:41.823521Z INFO ExtHandler Fetch goal state completed Jun 20 18:49:41.836538 waagent[1980]: 2025-06-20T18:49:41.836486Z INFO ExtHandler ExtHandler WALinuxAgent-2.9.1.1 running as process 1980 Jun 20 18:49:41.836686 waagent[1980]: 2025-06-20T18:49:41.836645Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Jun 20 18:49:41.838555 waagent[1980]: 2025-06-20T18:49:41.838507Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '4230.2.0', '', 'Flatcar Container Linux by Kinvolk'] Jun 20 18:49:41.838954 waagent[1980]: 2025-06-20T18:49:41.838910Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Jun 20 18:49:41.860553 waagent[1980]: 2025-06-20T18:49:41.860506Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Jun 20 18:49:41.860733 waagent[1980]: 2025-06-20T18:49:41.860679Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Jun 20 18:49:41.868478 waagent[1980]: 2025-06-20T18:49:41.868436Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Jun 20 18:49:41.876030 systemd[1]: Reload requested from client PID 1993 ('systemctl') (unit waagent.service)... Jun 20 18:49:41.876047 systemd[1]: Reloading... Jun 20 18:49:41.945853 zram_generator::config[2036]: No configuration found. Jun 20 18:49:42.081785 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. Jun 20 18:49:42.230131 systemd[1]: Reloading finished in 353 ms. Jun 20 18:49:42.244946 waagent[1980]: 2025-06-20T18:49:42.244863Z INFO ExtHandler ExtHandler Executing systemctl daemon-reload for setting up waagent-network-setup.service Jun 20 18:49:42.250864 systemd[1]: Reload requested from client PID 2090 ('systemctl') (unit waagent.service)... Jun 20 18:49:42.250883 systemd[1]: Reloading... Jun 20 18:49:42.316843 zram_generator::config[2133]: No configuration found. Jun 20 18:49:42.445301 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. Jun 20 18:49:42.597191 systemd[1]: Reloading finished in 345 ms. Jun 20 18:49:42.610594 waagent[1980]: 2025-06-20T18:49:42.610514Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Jun 20 18:49:42.610912 waagent[1980]: 2025-06-20T18:49:42.610684Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Jun 20 18:49:43.333554 waagent[1980]: 2025-06-20T18:49:43.333464Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Jun 20 18:49:43.334135 waagent[1980]: 2025-06-20T18:49:43.334076Z 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] Jun 20 18:49:43.334946 waagent[1980]: 2025-06-20T18:49:43.334894Z INFO ExtHandler ExtHandler Starting env monitor service. Jun 20 18:49:43.335326 waagent[1980]: 2025-06-20T18:49:43.335270Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jun 20 18:49:43.335514 waagent[1980]: 2025-06-20T18:49:43.335465Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Jun 20 18:49:43.335861 waagent[1980]: 2025-06-20T18:49:43.335795Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jun 20 18:49:43.336024 waagent[1980]: 2025-06-20T18:49:43.335975Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Jun 20 18:49:43.336400 waagent[1980]: 2025-06-20T18:49:43.336336Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Jun 20 18:49:43.336466 waagent[1980]: 2025-06-20T18:49:43.336418Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Jun 20 18:49:43.336689 waagent[1980]: 2025-06-20T18:49:43.336634Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Jun 20 18:49:43.336855 waagent[1980]: 2025-06-20T18:49:43.336798Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Jun 20 18:49:43.337395 waagent[1980]: 2025-06-20T18:49:43.337343Z INFO EnvHandler ExtHandler Configure routes Jun 20 18:49:43.337782 waagent[1980]: 2025-06-20T18:49:43.337717Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Jun 20 18:49:43.337900 waagent[1980]: 2025-06-20T18:49:43.337856Z INFO EnvHandler ExtHandler Gateway:None Jun 20 18:49:43.338024 waagent[1980]: 2025-06-20T18:49:43.337973Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Jun 20 18:49:43.338299 waagent[1980]: 2025-06-20T18:49:43.338220Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Jun 20 18:49:43.338299 waagent[1980]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Jun 20 18:49:43.338299 waagent[1980]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Jun 20 18:49:43.338299 waagent[1980]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Jun 20 18:49:43.338299 waagent[1980]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Jun 20 18:49:43.338299 waagent[1980]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jun 20 18:49:43.338299 waagent[1980]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jun 20 18:49:43.338469 waagent[1980]: 2025-06-20T18:49:43.338312Z INFO EnvHandler ExtHandler Routes:None Jun 20 18:49:43.338971 waagent[1980]: 2025-06-20T18:49:43.338922Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Jun 20 18:49:43.345054 waagent[1980]: 2025-06-20T18:49:43.344997Z INFO ExtHandler ExtHandler Jun 20 18:49:43.345384 waagent[1980]: 2025-06-20T18:49:43.345337Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: ed509fe8-5c2c-4288-97a6-12578a9f0aac correlation 8048ed68-1149-4229-8b95-94600f6dedbc created: 2025-06-20T18:48:11.628479Z] Jun 20 18:49:43.346275 waagent[1980]: 2025-06-20T18:49:43.346224Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Jun 20 18:49:43.347914 waagent[1980]: 2025-06-20T18:49:43.347868Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 2 ms] Jun 20 18:49:43.413950 waagent[1980]: 2025-06-20T18:49:43.413890Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.9.1.1 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 20EC6F34-FF8E-4B0D-B23C-2DA3F2C5819D;DroppedPackets: 0;UpdateGSErrors: 0;AutoUpdate: 0] Jun 20 18:49:43.476646 waagent[1980]: 2025-06-20T18:49:43.476559Z INFO MonitorHandler ExtHandler Network interfaces: Jun 20 18:49:43.476646 waagent[1980]: Executing ['ip', '-a', '-o', 'link']: Jun 20 18:49:43.476646 waagent[1980]: 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 Jun 20 18:49:43.476646 waagent[1980]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:ed:8d:46:a5:65 brd ff:ff:ff:ff:ff:ff Jun 20 18:49:43.476646 waagent[1980]: Executing ['ip', '-4', '-a', '-o', 'address']: Jun 20 18:49:43.476646 waagent[1980]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Jun 20 18:49:43.476646 waagent[1980]: 2: eth0 inet 10.200.8.16/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Jun 20 18:49:43.476646 waagent[1980]: Executing ['ip', '-6', '-a', '-o', 'address']: Jun 20 18:49:43.476646 waagent[1980]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Jun 20 18:49:43.476646 waagent[1980]: 2: eth0 inet6 fe80::7eed:8dff:fe46:a565/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Jun 20 18:49:43.538384 waagent[1980]: 2025-06-20T18:49:43.538312Z INFO EnvHandler ExtHandler Successfully added Azure fabric firewall rules. Current Firewall rules: Jun 20 18:49:43.538384 waagent[1980]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Jun 20 18:49:43.538384 waagent[1980]: pkts bytes target prot opt in out source destination Jun 20 18:49:43.538384 waagent[1980]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Jun 20 18:49:43.538384 waagent[1980]: pkts bytes target prot opt in out source destination Jun 20 18:49:43.538384 waagent[1980]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Jun 20 18:49:43.538384 waagent[1980]: pkts bytes target prot opt in out source destination Jun 20 18:49:43.538384 waagent[1980]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Jun 20 18:49:43.538384 waagent[1980]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Jun 20 18:49:43.538384 waagent[1980]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Jun 20 18:49:43.542074 waagent[1980]: 2025-06-20T18:49:43.542016Z INFO EnvHandler ExtHandler Current Firewall rules: Jun 20 18:49:43.542074 waagent[1980]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Jun 20 18:49:43.542074 waagent[1980]: pkts bytes target prot opt in out source destination Jun 20 18:49:43.542074 waagent[1980]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Jun 20 18:49:43.542074 waagent[1980]: pkts bytes target prot opt in out source destination Jun 20 18:49:43.542074 waagent[1980]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Jun 20 18:49:43.542074 waagent[1980]: pkts bytes target prot opt in out source destination Jun 20 18:49:43.542074 waagent[1980]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Jun 20 18:49:43.542074 waagent[1980]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Jun 20 18:49:43.542074 waagent[1980]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Jun 20 18:49:43.542338 waagent[1980]: 2025-06-20T18:49:43.542296Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Jun 20 18:49:43.542503 waagent[1980]: 2025-06-20T18:49:43.542464Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Jun 20 18:49:52.759669 setup-nvidia[1839]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 479M 0 81920 0 0 103k 0 1:18:59 --:--:-- 1:18:59 103k\u000d 2 479M 2 10.0M 0 0 5632k 0 0:01:27 0:00:01 0:01:26 5632k\u000d 7 479M 7 35.3M 0 0 12.4M 0 0:00:38 0:00:02 0:00:36 12.4M\u000d 12 479M 12 60.3M 0 0 15.7M 0 0:00:30 0:00:03 0:00:27 15.7M\u000d 17 479M 17 85.7M 0 0 17.7M 0 0:00:27 0:00:04 0:00:23 17.7M\u000d 22 479M 22 109M 0 0 18.7M 0 0:00:25 0:00:05 0:00:20 21.5M\u000d 27 479M 27 130M 0 0 19.2M 0 0:00:24 0:00:06 0:00:18 24.2M\u000d 32 479M 32 154M 0 0 19.7M 0 0:00:24 0:00:07 0:00:17 23.9M\u000d 36 479M 36 177M 0 0 20.1M 0 0:00:23 0:00:08 0:00:15 23.6M\u000d 42 479M 42 202M 0 0 20.6M 0 0:00:23 0:00:09 0:00:14 23.5M\u000d 46 479M 46 223M 0 0 20.8M 0 0:00:23 0:00:10 0:00:13 23.2M\u000d 51 479M 51 247M 0 0 21.0M 0 0:00:22 0:00:11 0:00:11 23.7M\u000d 56 479M 56 272M 0 0 21.3M 0 0:00:22 0:00:12 0:00:10 23.9M\u000d 62 479M 62 298M 0 0 21.7M 0 0:00:22 0:00:13 0:00:09 24.4M\u000d 67 479M 67 324M 0 0 21.8M 0 0:00:22 0:00:14 0:00:08 23.9M\u000d 72 479M 72 349M 0 0 22.0M 0 0:00:21 0:00:15 0:00:06 24.5M\u000d 77 479M 77 372M 0 0 22.0M 0 0:00:21 0:00:16 0:00:05 24.3M\u000d 82 479M 82 397M 0 0 22.2M 0 0:00:21 0:00:17 0:00:04 24.3M\u000d 87 479M 87 419M 0 0 22.3M 0 0:00:21 0:00:18 0:00:03 23.8M\u000d 92 479M 92 442M 0 0 22.3M 0 0:00:21 0:00:19 0:00:02 23.8M\u000d 97 479M 97 466M 0 0 22.4M 0 0:00:21 0:00:20 0:00:01 23.7M\u000d100 479M 100 479M 0 0 22.5M 0 0:00:21 0:00:21 --:--:-- 24.5M Jun 20 18:49:54.408453 chronyd[1687]: Selected source PHC0 Jun 20 18:49:58.763804 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Jun 20 18:49:58.781235 systemd[1]: Started sshd@0-10.200.8.16:22-10.200.16.10:33316.service - OpenSSH per-connection server daemon (10.200.16.10:33316). Jun 20 18:49:59.547520 sshd[2232]: Accepted publickey for core from 10.200.16.10 port 33316 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:49:59.548613 sshd-session[2232]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:49:59.557981 systemd-logind[1684]: New session 3 of user core. Jun 20 18:49:59.565985 systemd[1]: Started session-3.scope - Session 3 of User core. Jun 20 18:50:00.109107 systemd[1]: Started sshd@1-10.200.8.16:22-10.200.16.10:33330.service - OpenSSH per-connection server daemon (10.200.16.10:33330). Jun 20 18:50:00.742364 sshd[2237]: Accepted publickey for core from 10.200.16.10 port 33330 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:50:00.743906 sshd-session[2237]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:50:00.751294 systemd-logind[1684]: New session 4 of user core. Jun 20 18:50:00.762025 systemd[1]: Started session-4.scope - Session 4 of User core. Jun 20 18:50:01.192709 sshd[2239]: Connection closed by 10.200.16.10 port 33330 Jun 20 18:50:01.193210 sshd-session[2237]: pam_unix(sshd:session): session closed for user core Jun 20 18:50:01.196832 systemd-logind[1684]: Session 4 logged out. Waiting for processes to exit. Jun 20 18:50:01.199281 systemd[1]: sshd@1-10.200.8.16:22-10.200.16.10:33330.service: Deactivated successfully. Jun 20 18:50:01.201117 systemd[1]: session-4.scope: Deactivated successfully. Jun 20 18:50:01.205398 systemd-logind[1684]: Removed session 4. Jun 20 18:50:01.314732 systemd[1]: Started sshd@2-10.200.8.16:22-10.200.16.10:33340.service - OpenSSH per-connection server daemon (10.200.16.10:33340). Jun 20 18:50:01.949569 sshd[2245]: Accepted publickey for core from 10.200.16.10 port 33340 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:50:01.951091 sshd-session[2245]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:50:01.957702 systemd-logind[1684]: New session 5 of user core. Jun 20 18:50:01.962943 systemd[1]: Started session-5.scope - Session 5 of User core. Jun 20 18:50:02.412833 sshd[2247]: Connection closed by 10.200.16.10 port 33340 Jun 20 18:50:02.409484 sshd-session[2245]: pam_unix(sshd:session): session closed for user core Jun 20 18:50:02.413121 systemd[1]: sshd@2-10.200.8.16:22-10.200.16.10:33340.service: Deactivated successfully. Jun 20 18:50:02.415053 systemd[1]: session-5.scope: Deactivated successfully. Jun 20 18:50:02.418505 systemd-logind[1684]: Session 5 logged out. Waiting for processes to exit. Jun 20 18:50:02.419548 systemd-logind[1684]: Removed session 5. Jun 20 18:50:03.358856 setup-nvidia[1679]: Downloading NVIDIA 535.230.02 Driver Jun 20 18:50:03.363873 setup-nvidia[2253]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 20 18:50:03.364123 setup-nvidia[2253]: Dload Upload Total Spent Left Speed Jun 20 18:50:05.511446 setup-nvidia[2253]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 24 326M 24 78.4M 0 0 87.7M 0 0:00:03 --:--:-- 0:00:03 87.6M\u000d 82 326M 82 270M 0 0 142M 0 0:00:02 0:00:01 0:00:01 142M\u000d100 326M 100 326M 0 0 151M 0 0:00:02 0:00:02 --:--:-- 151M Jun 20 18:50:05.603954 setup-nvidia[1679]: Extract the NVIDIA Driver Installer 535.230.02 Jun 20 18:50:05.604207 setup-nvidia[1679]: /opt/nvidia/workdir/nvidia-workdir / Jun 20 18:50:05.622456 setup-nvidia[2256]: Creating directory NVIDIA-Linux-x86_64-535.230.02 Jun 20 18:50:05.883273 setup-nvidia[2256]: Verifying archive integrity... OK Jun 20 18:50:06.664053 setup-nvidia[2256]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.230.02 Jun 20 18:50:08.547095 setup-nvidia[2282]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Jun 20 18:50:08.646278 setup-nvidia[1679]: / Jun 20 18:50:08.646553 setup-nvidia[1679]: Spawn system-nspawn container to install the NVIDIA drivers Jun 20 18:50:08.923235 sudo[2289]: 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.0.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 Jun 20 18:50:08.924950 sudo[2289]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 20 18:50:08.930681 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Jun 20 18:50:08.945074 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Jun 20 18:50:08.958363 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Jun 20 18:50:08.962327 systemd[1]: Starting user@0.service - User Manager for UID 0... Jun 20 18:50:08.969537 (systemd)[2292]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Jun 20 18:50:08.971923 systemd-logind[1684]: New session c3 of user root. Jun 20 18:50:09.152422 systemd[2292]: Queued start job for default target default.target. Jun 20 18:50:09.162978 systemd[2292]: Created slice app.slice - User Application Slice. Jun 20 18:50:09.163008 systemd[2292]: Reached target paths.target - Paths. Jun 20 18:50:09.163057 systemd[2292]: Reached target timers.target - Timers. Jun 20 18:50:09.164237 systemd[2292]: Starting dbus.socket - D-Bus User Message Bus Socket... Jun 20 18:50:09.175790 systemd[2292]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jun 20 18:50:09.175953 systemd[2292]: Reached target sockets.target - Sockets. Jun 20 18:50:09.176009 systemd[2292]: Reached target basic.target - Basic System. Jun 20 18:50:09.176057 systemd[2292]: Reached target default.target - Main User Target. Jun 20 18:50:09.176090 systemd[2292]: Startup finished in 198ms. Jun 20 18:50:09.176372 systemd[1]: Started user@0.service - User Manager for UID 0. Jun 20 18:50:09.179417 systemd[1]: Started session-c2.scope - Session c2 of User root. Jun 20 18:50:09.247958 kernel: loop6: detected capacity change from 0 to 12644352 Jun 20 18:50:09.256830 kernel: loop6: p9 Jun 20 18:50:09.330013 setup-nvidia[2301]: ░ Spawning container flatcardevelopercontainer-4230.2.0.bin on /opt/nvidia/workdir/flatcar_developer_container-4230.2.0.bin. Jun 20 18:50:09.378839 kernel: EXT4-fs (loop6p9): mounted filesystem 290506de-cc1b-48db-af12-9d0ced62311c ro without journal. Quota mode: none. Jun 20 18:50:12.521580 systemd[1]: Started sshd@3-10.200.8.16:22-10.200.16.10:41148.service - OpenSSH per-connection server daemon (10.200.16.10:41148). Jun 20 18:50:12.863445 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Jun 20 18:50:13.153135 sshd[2364]: Accepted publickey for core from 10.200.16.10 port 41148 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:50:13.154347 sshd-session[2364]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:50:13.158488 systemd-logind[1684]: New session 6 of user core. Jun 20 18:50:13.167957 systemd[1]: Started session-6.scope - Session 6 of User core. Jun 20 18:50:13.601742 sshd[2366]: Connection closed by 10.200.16.10 port 41148 Jun 20 18:50:13.602126 sshd-session[2364]: pam_unix(sshd:session): session closed for user core Jun 20 18:50:13.606125 systemd[1]: sshd@3-10.200.8.16:22-10.200.16.10:41148.service: Deactivated successfully. Jun 20 18:50:13.607770 systemd[1]: session-6.scope: Deactivated successfully. Jun 20 18:50:13.608441 systemd-logind[1684]: Session 6 logged out. Waiting for processes to exit. Jun 20 18:50:13.609246 systemd-logind[1684]: Removed session 6. Jun 20 18:50:13.713310 systemd[1]: Started sshd@4-10.200.8.16:22-10.200.16.10:41150.service - OpenSSH per-connection server daemon (10.200.16.10:41150). Jun 20 18:50:14.346442 sshd[2372]: Accepted publickey for core from 10.200.16.10 port 41150 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:50:14.347666 sshd-session[2372]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:50:14.351754 systemd-logind[1684]: New session 7 of user core. Jun 20 18:50:14.366935 systemd[1]: Started session-7.scope - Session 7 of User core. Jun 20 18:50:14.801491 sshd[2377]: Connection closed by 10.200.16.10 port 41150 Jun 20 18:50:14.801883 sshd-session[2372]: pam_unix(sshd:session): session closed for user core Jun 20 18:50:14.805216 systemd[1]: sshd@4-10.200.8.16:22-10.200.16.10:41150.service: Deactivated successfully. Jun 20 18:50:14.806878 systemd[1]: session-7.scope: Deactivated successfully. Jun 20 18:50:14.807650 systemd-logind[1684]: Session 7 logged out. Waiting for processes to exit. Jun 20 18:50:14.808469 systemd-logind[1684]: Removed session 7. Jun 20 18:50:16.105496 update_engine[1685]: I20250620 18:50:16.105420 1685 update_attempter.cc:509] Updating boot flags... Jun 20 18:50:16.156830 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 38 scanned by (udev-worker) (2466) Jun 20 18:50:24.918395 systemd[1]: Started sshd@5-10.200.8.16:22-10.200.16.10:36414.service - OpenSSH per-connection server daemon (10.200.16.10:36414). Jun 20 18:50:25.635530 sshd[3561]: Accepted publickey for core from 10.200.16.10 port 36414 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:50:25.636789 sshd-session[3561]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:50:25.642205 systemd-logind[1684]: New session 8 of user core. Jun 20 18:50:25.652068 systemd[1]: Started session-8.scope - Session 8 of User core. Jun 20 18:50:26.090628 sshd[3921]: Connection closed by 10.200.16.10 port 36414 Jun 20 18:50:26.091233 sshd-session[3561]: pam_unix(sshd:session): session closed for user core Jun 20 18:50:26.094447 systemd[1]: sshd@5-10.200.8.16:22-10.200.16.10:36414.service: Deactivated successfully. Jun 20 18:50:26.097454 systemd[1]: session-8.scope: Deactivated successfully. Jun 20 18:50:26.098198 systemd-logind[1684]: Session 8 logged out. Waiting for processes to exit. Jun 20 18:50:26.099257 systemd-logind[1684]: Removed session 8. Jun 20 18:50:26.207240 systemd[1]: Started sshd@6-10.200.8.16:22-10.200.16.10:36420.service - OpenSSH per-connection server daemon (10.200.16.10:36420). Jun 20 18:50:26.836123 sshd[4264]: Accepted publickey for core from 10.200.16.10 port 36420 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:50:26.837436 sshd-session[4264]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:50:26.841763 systemd-logind[1684]: New session 9 of user core. Jun 20 18:50:26.851970 systemd[1]: Started session-9.scope - Session 9 of User core. Jun 20 18:50:27.285995 sshd[4669]: Connection closed by 10.200.16.10 port 36420 Jun 20 18:50:27.286354 sshd-session[4264]: pam_unix(sshd:session): session closed for user core Jun 20 18:50:27.290672 systemd[1]: sshd@6-10.200.8.16:22-10.200.16.10:36420.service: Deactivated successfully. Jun 20 18:50:27.292389 systemd[1]: session-9.scope: Deactivated successfully. Jun 20 18:50:27.293070 systemd-logind[1684]: Session 9 logged out. Waiting for processes to exit. Jun 20 18:50:27.293878 systemd-logind[1684]: Removed session 9. Jun 20 18:50:37.408458 systemd[1]: Started sshd@7-10.200.8.16:22-10.200.16.10:53088.service - OpenSSH per-connection server daemon (10.200.16.10:53088). Jun 20 18:50:38.042156 sshd[6866]: Accepted publickey for core from 10.200.16.10 port 53088 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:50:38.043139 sshd-session[6866]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:50:38.048953 systemd-logind[1684]: New session 10 of user core. Jun 20 18:50:38.066321 systemd[1]: Started session-10.scope - Session 10 of User core. Jun 20 18:50:38.487319 sshd[7141]: Connection closed by 10.200.16.10 port 53088 Jun 20 18:50:38.486321 sshd-session[6866]: pam_unix(sshd:session): session closed for user core Jun 20 18:50:38.489691 systemd[1]: sshd@7-10.200.8.16:22-10.200.16.10:53088.service: Deactivated successfully. Jun 20 18:50:38.491369 systemd[1]: session-10.scope: Deactivated successfully. Jun 20 18:50:38.492087 systemd-logind[1684]: Session 10 logged out. Waiting for processes to exit. Jun 20 18:50:38.493174 systemd-logind[1684]: Removed session 10. Jun 20 18:50:38.601370 systemd[1]: Started sshd@8-10.200.8.16:22-10.200.16.10:40494.service - OpenSSH per-connection server daemon (10.200.16.10:40494). Jun 20 18:50:39.256281 sshd[7215]: Accepted publickey for core from 10.200.16.10 port 40494 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:50:39.257669 sshd-session[7215]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:50:39.264676 systemd-logind[1684]: New session 11 of user core. Jun 20 18:50:39.272967 systemd[1]: Started session-11.scope - Session 11 of User core. Jun 20 18:50:39.803529 sshd[7293]: Connection closed by 10.200.16.10 port 40494 Jun 20 18:50:39.805108 sshd-session[7215]: pam_unix(sshd:session): session closed for user core Jun 20 18:50:39.808444 systemd[1]: sshd@8-10.200.8.16:22-10.200.16.10:40494.service: Deactivated successfully. Jun 20 18:50:39.810291 systemd[1]: session-11.scope: Deactivated successfully. Jun 20 18:50:39.811418 systemd-logind[1684]: Session 11 logged out. Waiting for processes to exit. Jun 20 18:50:39.812570 systemd-logind[1684]: Removed session 11. Jun 20 18:50:49.919575 systemd[1]: Started sshd@9-10.200.8.16:22-10.200.16.10:46468.service - OpenSSH per-connection server daemon (10.200.16.10:46468). Jun 20 18:50:50.560038 sshd[10496]: Accepted publickey for core from 10.200.16.10 port 46468 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:50:50.561848 sshd-session[10496]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:50:50.567666 systemd-logind[1684]: New session 12 of user core. Jun 20 18:50:50.573663 systemd[1]: Started session-12.scope - Session 12 of User core. Jun 20 18:50:51.018709 sshd[10678]: Connection closed by 10.200.16.10 port 46468 Jun 20 18:50:51.022028 sshd-session[10496]: pam_unix(sshd:session): session closed for user core Jun 20 18:50:51.024695 systemd[1]: sshd@9-10.200.8.16:22-10.200.16.10:46468.service: Deactivated successfully. Jun 20 18:50:51.026350 systemd[1]: session-12.scope: Deactivated successfully. Jun 20 18:50:51.028699 systemd-logind[1684]: Session 12 logged out. Waiting for processes to exit. Jun 20 18:50:51.029903 systemd-logind[1684]: Removed session 12. Jun 20 18:50:51.131475 systemd[1]: Started sshd@10-10.200.8.16:22-10.200.16.10:46474.service - OpenSSH per-connection server daemon (10.200.16.10:46474). Jun 20 18:50:51.767967 sshd[10915]: Accepted publickey for core from 10.200.16.10 port 46474 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:50:51.769290 sshd-session[10915]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:50:51.775260 systemd-logind[1684]: New session 13 of user core. Jun 20 18:50:51.782966 systemd[1]: Started session-13.scope - Session 13 of User core. Jun 20 18:50:52.213209 sshd[11158]: Connection closed by 10.200.16.10 port 46474 Jun 20 18:50:52.213584 sshd-session[10915]: pam_unix(sshd:session): session closed for user core Jun 20 18:50:52.216430 systemd-logind[1684]: Session 13 logged out. Waiting for processes to exit. Jun 20 18:50:52.218579 systemd[1]: sshd@10-10.200.8.16:22-10.200.16.10:46474.service: Deactivated successfully. Jun 20 18:50:52.222967 systemd[1]: session-13.scope: Deactivated successfully. Jun 20 18:50:52.224598 systemd-logind[1684]: Removed session 13. Jun 20 18:51:02.339085 systemd[1]: Started sshd@11-10.200.8.16:22-10.200.16.10:54780.service - OpenSSH per-connection server daemon (10.200.16.10:54780). Jun 20 18:51:02.970845 sshd[11866]: Accepted publickey for core from 10.200.16.10 port 54780 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:51:02.972720 sshd-session[11866]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:51:02.984293 systemd-logind[1684]: New session 14 of user core. Jun 20 18:51:02.994121 systemd[1]: Started session-14.scope - Session 14 of User core. Jun 20 18:51:03.420320 sshd[11881]: Connection closed by 10.200.16.10 port 54780 Jun 20 18:51:03.423068 sshd-session[11866]: pam_unix(sshd:session): session closed for user core Jun 20 18:51:03.425942 systemd-logind[1684]: Session 14 logged out. Waiting for processes to exit. Jun 20 18:51:03.427573 systemd[1]: sshd@11-10.200.8.16:22-10.200.16.10:54780.service: Deactivated successfully. Jun 20 18:51:03.429532 systemd[1]: session-14.scope: Deactivated successfully. Jun 20 18:51:03.430914 systemd-logind[1684]: Removed session 14. Jun 20 18:51:03.536656 systemd[1]: Started sshd@12-10.200.8.16:22-10.200.16.10:54784.service - OpenSSH per-connection server daemon (10.200.16.10:54784). Jun 20 18:51:04.183565 sshd[11898]: Accepted publickey for core from 10.200.16.10 port 54784 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:51:04.185427 sshd-session[11898]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:51:04.191450 systemd-logind[1684]: New session 15 of user core. Jun 20 18:51:04.196977 systemd[1]: Started session-15.scope - Session 15 of User core. Jun 20 18:51:04.629449 sshd[11929]: Connection closed by 10.200.16.10 port 54784 Jun 20 18:51:04.631527 sshd-session[11898]: pam_unix(sshd:session): session closed for user core Jun 20 18:51:04.634891 systemd[1]: sshd@12-10.200.8.16:22-10.200.16.10:54784.service: Deactivated successfully. Jun 20 18:51:04.636553 systemd[1]: session-15.scope: Deactivated successfully. Jun 20 18:51:04.638654 systemd-logind[1684]: Session 15 logged out. Waiting for processes to exit. Jun 20 18:51:04.640049 systemd-logind[1684]: Removed session 15. Jun 20 18:51:14.746312 systemd[1]: Started sshd@13-10.200.8.16:22-10.200.16.10:59836.service - OpenSSH per-connection server daemon (10.200.16.10:59836). Jun 20 18:51:15.385346 sshd[12255]: Accepted publickey for core from 10.200.16.10 port 59836 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:51:15.387245 sshd-session[12255]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:51:15.393806 systemd-logind[1684]: New session 16 of user core. Jun 20 18:51:15.402996 systemd[1]: Started session-16.scope - Session 16 of User core. Jun 20 18:51:15.835683 sshd[12273]: Connection closed by 10.200.16.10 port 59836 Jun 20 18:51:15.838998 sshd-session[12255]: pam_unix(sshd:session): session closed for user core Jun 20 18:51:15.842504 systemd[1]: sshd@13-10.200.8.16:22-10.200.16.10:59836.service: Deactivated successfully. Jun 20 18:51:15.844762 systemd[1]: session-16.scope: Deactivated successfully. Jun 20 18:51:15.845876 systemd-logind[1684]: Session 16 logged out. Waiting for processes to exit. Jun 20 18:51:15.847149 systemd-logind[1684]: Removed session 16. Jun 20 18:51:15.958424 systemd[1]: Started sshd@14-10.200.8.16:22-10.200.16.10:59842.service - OpenSSH per-connection server daemon (10.200.16.10:59842). Jun 20 18:51:16.602378 sshd[12297]: Accepted publickey for core from 10.200.16.10 port 59842 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:51:16.604159 sshd-session[12297]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:51:16.610199 systemd-logind[1684]: New session 17 of user core. Jun 20 18:51:16.620515 systemd[1]: Started session-17.scope - Session 17 of User core. Jun 20 18:51:17.056675 sshd[12324]: Connection closed by 10.200.16.10 port 59842 Jun 20 18:51:17.057485 sshd-session[12297]: pam_unix(sshd:session): session closed for user core Jun 20 18:51:17.060473 systemd-logind[1684]: Session 17 logged out. Waiting for processes to exit. Jun 20 18:51:17.061422 systemd[1]: sshd@14-10.200.8.16:22-10.200.16.10:59842.service: Deactivated successfully. Jun 20 18:51:17.063323 systemd[1]: session-17.scope: Deactivated successfully. Jun 20 18:51:17.068187 systemd-logind[1684]: Removed session 17. Jun 20 18:51:27.170611 systemd[1]: Started sshd@15-10.200.8.16:22-10.200.16.10:41100.service - OpenSSH per-connection server daemon (10.200.16.10:41100). Jun 20 18:51:27.805623 sshd[12650]: Accepted publickey for core from 10.200.16.10 port 41100 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:51:27.806973 sshd-session[12650]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:51:27.811290 systemd-logind[1684]: New session 18 of user core. Jun 20 18:51:27.827024 systemd[1]: Started session-18.scope - Session 18 of User core. Jun 20 18:51:28.256207 sshd[12652]: Connection closed by 10.200.16.10 port 41100 Jun 20 18:51:28.256556 sshd-session[12650]: pam_unix(sshd:session): session closed for user core Jun 20 18:51:28.259825 systemd[1]: sshd@15-10.200.8.16:22-10.200.16.10:41100.service: Deactivated successfully. Jun 20 18:51:28.261483 systemd[1]: session-18.scope: Deactivated successfully. Jun 20 18:51:28.262144 systemd-logind[1684]: Session 18 logged out. Waiting for processes to exit. Jun 20 18:51:28.262968 systemd-logind[1684]: Removed session 18. Jun 20 18:51:28.372353 systemd[1]: Started sshd@16-10.200.8.16:22-10.200.16.10:41106.service - OpenSSH per-connection server daemon (10.200.16.10:41106). Jun 20 18:51:29.005571 sshd[12658]: Accepted publickey for core from 10.200.16.10 port 41106 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:51:29.006980 sshd-session[12658]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:51:29.011357 systemd-logind[1684]: New session 19 of user core. Jun 20 18:51:29.030844 systemd[1]: Started session-19.scope - Session 19 of User core. Jun 20 18:51:29.455916 sshd[12660]: Connection closed by 10.200.16.10 port 41106 Jun 20 18:51:29.456319 sshd-session[12658]: pam_unix(sshd:session): session closed for user core Jun 20 18:51:29.459601 systemd[1]: sshd@16-10.200.8.16:22-10.200.16.10:41106.service: Deactivated successfully. Jun 20 18:51:29.461250 systemd[1]: session-19.scope: Deactivated successfully. Jun 20 18:51:29.461889 systemd-logind[1684]: Session 19 logged out. Waiting for processes to exit. Jun 20 18:51:29.462652 systemd-logind[1684]: Removed session 19. Jun 20 18:51:34.928166 setup-nvidia[2301]: \u000d Jun 20 18:51:34.928166 setup-nvidia[2301]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Jun 20 18:51:34.928166 setup-nvidia[2301]: frequently when this kernel module was built against the wrong or Jun 20 18:51:34.928166 setup-nvidia[2301]: improperly configured kernel sources, with a version of gcc that differs Jun 20 18:51:34.928166 setup-nvidia[2301]: from the one used to build the target kernel, or if another driver, such Jun 20 18:51:34.928166 setup-nvidia[2301]: as nouveau, is present and prevents the NVIDIA kernel module from Jun 20 18:51:34.928166 setup-nvidia[2301]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Jun 20 18:51:34.928166 setup-nvidia[2301]: installed in this system is supported by this NVIDIA Linux graphics Jun 20 18:51:34.928166 setup-nvidia[2301]: driver release. Jun 20 18:51:34.928166 setup-nvidia[2301]: \u000d Jun 20 18:51:34.928166 setup-nvidia[2301]: Please see the log entries 'Kernel module load error' and 'Kernel Jun 20 18:51:34.928166 setup-nvidia[2301]: messages' at the end of the file Jun 20 18:51:34.928166 setup-nvidia[2301]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more Jun 20 18:51:34.928166 setup-nvidia[2301]: information. Jun 20 18:51:34.928166 setup-nvidia[2301]: \u000d Jun 20 18:51:34.943226 setup-nvidia[2301]: dmesg: read kernel buffer failed: Operation not permitted Jun 20 18:51:34.964617 setup-nvidia[2301]: \u000d Jun 20 18:51:34.964617 setup-nvidia[2301]: ERROR: Installation has failed. Please see the file Jun 20 18:51:34.964617 setup-nvidia[2301]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for Jun 20 18:51:34.964617 setup-nvidia[2301]: details. You may find suggestions on fixing installation problems in Jun 20 18:51:34.964617 setup-nvidia[2301]: the README available on the Linux driver download page at Jun 20 18:51:34.964617 setup-nvidia[2301]: www.nvidia.com. Jun 20 18:51:34.964617 setup-nvidia[2301]: \u000d Jun 20 18:51:34.965099 setup-nvidia[2301]: Last 50 lines of nvidia-installer.log: Jun 20 18:51:34.965941 setup-nvidia[2301]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038989rm+0x32: 'naked' return found in RETHUNK build Jun 20 18:51:34.965941 setup-nvidia[2301]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039000rm+0x53: 'naked' return found in RETHUNK build Jun 20 18:51:34.965941 setup-nvidia[2301]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038997rm+0x90: 'naked' return found in RETHUNK build Jun 20 18:51:34.965941 setup-nvidia[2301]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038990rm+0x2b: 'naked' return found in RETHUNK build Jun 20 18:51:34.965941 setup-nvidia[2301]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039046rm+0x93: 'naked' return found in RETHUNK build Jun 20 18:51:34.965941 setup-nvidia[2301]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039042rm+0x36: 'naked' return found in RETHUNK build Jun 20 18:51:34.965941 setup-nvidia[2301]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039041rm+0x9d: 'naked' return found in RETHUNK build Jun 20 18:51:34.965941 setup-nvidia[2301]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039043rm+0x77: 'naked' return found in RETHUNK build Jun 20 18:51:34.965941 setup-nvidia[2301]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038987rm+0x7f: 'naked' return found in RETHUNK build Jun 20 18:51:34.965941 setup-nvidia[2301]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030558rm+0x3e: 'naked' return found in RETHUNK build Jun 20 18:51:34.965941 setup-nvidia[2301]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030557rm+0x27: 'naked' return found in RETHUNK build Jun 20 18:51:34.965941 setup-nvidia[2301]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030561rm+0x37: 'naked' return found in RETHUNK build Jun 20 18:51:34.965941 setup-nvidia[2301]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030560rm+0x24: 'naked' return found in RETHUNK build Jun 20 18:51:34.965941 setup-nvidia[2301]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030559rm+0xca: 'naked' return found in RETHUNK build Jun 20 18:51:34.965941 setup-nvidia[2301]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030562rm+0x193: 'naked' return found in RETHUNK build Jun 20 18:51:34.965941 setup-nvidia[2301]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030564rm+0x17f: 'naked' return found in RETHUNK build Jun 20 18:51:34.965941 setup-nvidia[2301]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv011860rm+0x5d: 'naked' return found in RETHUNK build Jun 20 18:51:34.965941 setup-nvidia[2301]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039354rm+0x12f: 'naked' return found in RETHUNK build Jun 20 18:51:34.965941 setup-nvidia[2301]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/Module.symvers Jun 20 18:51:34.965941 setup-nvidia[2301]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.mod.o Jun 20 18:51:34.965941 setup-nvidia[2301]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.mod.o Jun 20 18:51:34.965941 setup-nvidia[2301]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.mod.o Jun 20 18:51:34.965941 setup-nvidia[2301]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.mod.o Jun 20 18:51:34.965941 setup-nvidia[2301]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.mod.o Jun 20 18:51:34.965941 setup-nvidia[2301]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Jun 20 18:51:34.965941 setup-nvidia[2301]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Jun 20 18:51:34.965941 setup-nvidia[2301]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Jun 20 18:51:34.965941 setup-nvidia[2301]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Jun 20 18:51:34.965941 setup-nvidia[2301]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Jun 20 18:51:34.965941 setup-nvidia[2301]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Jun 20 18:51:34.965941 setup-nvidia[2301]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko due to unavailability of vmlinux Jun 20 18:51:34.965941 setup-nvidia[2301]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Jun 20 18:51:34.965941 setup-nvidia[2301]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko due to unavailability of vmlinux Jun 20 18:51:34.965941 setup-nvidia[2301]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Jun 20 18:51:34.965941 setup-nvidia[2301]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko due to unavailability of vmlinux Jun 20 18:51:34.965941 setup-nvidia[2301]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Jun 20 18:51:34.965941 setup-nvidia[2301]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko due to unavailability of vmlinux Jun 20 18:51:34.965941 setup-nvidia[2301]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Jun 20 18:51:34.965941 setup-nvidia[2301]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko due to unavailability of vmlinux Jun 20 18:51:34.965941 setup-nvidia[2301]: make[2]: Leaving directory '/usr/lib/modules/6.6.94-flatcar/build' Jun 20 18:51:34.965941 setup-nvidia[2301]: make[1]: Leaving directory '/usr/lib/modules/6.6.94-flatcar/source' Jun 20 18:51:34.965941 setup-nvidia[2301]: -> done. Jun 20 18:51:34.965941 setup-nvidia[2301]: -> Kernel module compilation complete. Jun 20 18:51:34.965941 setup-nvidia[2301]: -> Unable to determine if Secure Boot is enabled: No such file or directory Jun 20 18:51:34.965941 setup-nvidia[2301]: 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. Jun 20 18:51:34.965941 setup-nvidia[2301]: \u000d Jun 20 18:51:34.967124 setup-nvidia[2301]: 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. Jun 20 18:51:34.967124 setup-nvidia[2301]: -> Kernel module load error: Operation not permitted Jun 20 18:51:34.967124 setup-nvidia[2301]: -> Kernel messages: Jun 20 18:51:34.967124 setup-nvidia[2301]: 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. Jun 20 18:51:39.568275 systemd[1]: Started sshd@17-10.200.8.16:22-10.200.16.10:50002.service - OpenSSH per-connection server daemon (10.200.16.10:50002). Jun 20 18:51:40.199600 sshd[12876]: Accepted publickey for core from 10.200.16.10 port 50002 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:51:40.201065 sshd-session[12876]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:51:40.205281 systemd-logind[1684]: New session 20 of user core. Jun 20 18:51:40.211931 systemd[1]: Started session-20.scope - Session 20 of User core. Jun 20 18:51:40.654451 sshd[12878]: Connection closed by 10.200.16.10 port 50002 Jun 20 18:51:40.654955 sshd-session[12876]: pam_unix(sshd:session): session closed for user core Jun 20 18:51:40.658191 systemd[1]: sshd@17-10.200.8.16:22-10.200.16.10:50002.service: Deactivated successfully. Jun 20 18:51:40.659841 systemd[1]: session-20.scope: Deactivated successfully. Jun 20 18:51:40.660488 systemd-logind[1684]: Session 20 logged out. Waiting for processes to exit. Jun 20 18:51:40.661279 systemd-logind[1684]: Removed session 20. Jun 20 18:51:40.766225 systemd[1]: Started sshd@18-10.200.8.16:22-10.200.16.10:50010.service - OpenSSH per-connection server daemon (10.200.16.10:50010). Jun 20 18:51:41.474332 sshd[12884]: Accepted publickey for core from 10.200.16.10 port 50010 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:51:41.475663 sshd-session[12884]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:51:41.479964 systemd-logind[1684]: New session 21 of user core. Jun 20 18:51:41.492934 systemd[1]: Started session-21.scope - Session 21 of User core. Jun 20 18:51:41.938915 sshd[12886]: Connection closed by 10.200.16.10 port 50010 Jun 20 18:51:41.939299 sshd-session[12884]: pam_unix(sshd:session): session closed for user core Jun 20 18:51:41.942393 systemd[1]: sshd@18-10.200.8.16:22-10.200.16.10:50010.service: Deactivated successfully. Jun 20 18:51:41.944007 systemd[1]: session-21.scope: Deactivated successfully. Jun 20 18:51:41.944632 systemd-logind[1684]: Session 21 logged out. Waiting for processes to exit. Jun 20 18:51:41.945485 systemd-logind[1684]: Removed session 21. Jun 20 18:51:49.876839 kernel: EXT4-fs (loop6p9): unmounting filesystem 290506de-cc1b-48db-af12-9d0ced62311c. Jun 20 18:51:49.877055 setup-nvidia[2301]: Container flatcardevelopercontainer-4230.2.0.bin exited successfully. Jun 20 18:51:49.879226 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4230.2.0.bin.mount: Deactivated successfully. Jun 20 18:51:49.938684 sudo[2289]: pam_unix(sudo:session): session closed for user root Jun 20 18:51:49.940566 systemd[1]: session-c2.scope: Deactivated successfully. Jun 20 18:51:49.940854 systemd[1]: session-c2.scope: Consumed 5min 15.797s CPU time, 882.7M memory peak. Jun 20 18:51:50.352826 setup-nvidia[1679]: /opt/nvidia / Jun 20 18:51:50.353786 setup-nvidia[1679]: / Jun 20 18:51:50.369417 setup-nvidia[12909]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jun 20 18:51:50.395834 kernel: IPMI message handler: version 39.2 Jun 20 18:51:50.398831 kernel: ipmi device interface Jun 20 18:51:50.400048 setup-nvidia[1679]: /opt/nvidia/current/usr/lib/modules/6.6.94-flatcar/video / Jun 20 18:51:50.453251 kernel: nvidia: loading out-of-tree module taints kernel. Jun 20 18:51:50.453380 kernel: nvidia: module license 'NVIDIA' taints kernel. Jun 20 18:51:50.453404 kernel: Disabling lock debugging due to kernel taint Jun 20 18:51:50.460635 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Jun 20 18:51:50.460677 kernel: nvidia: module license taints kernel. Jun 20 18:51:50.869841 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 245 Jun 20 18:51:51.269527 kernel: Jun 20 18:51:51.269667 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Jun 20 18:51:51.480836 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.230.02 Fri Dec 20 21:42:05 UTC 2024 Jun 20 18:51:51.501835 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.230.02 Fri Dec 20 21:52:34 UTC 2024 Jun 20 18:51:51.521843 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Jun 20 18:51:51.567841 kernel: nvidia-uvm: Loaded the UVM driver, major device number 243. Jun 20 18:51:51.567967 setup-nvidia[1679]: / Jun 20 18:51:52.057918 systemd[1]: Started sshd@19-10.200.8.16:22-10.200.16.10:45062.service - OpenSSH per-connection server daemon (10.200.16.10:45062). Jun 20 18:51:52.690728 sshd[12944]: Accepted publickey for core from 10.200.16.10 port 45062 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:51:52.692027 sshd-session[12944]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:51:52.696215 systemd-logind[1684]: New session 22 of user core. Jun 20 18:51:52.705943 systemd[1]: Started session-22.scope - Session 22 of User core. Jun 20 18:51:53.135125 sshd[12946]: Connection closed by 10.200.16.10 port 45062 Jun 20 18:51:53.135505 sshd-session[12944]: pam_unix(sshd:session): session closed for user core Jun 20 18:51:53.138792 systemd[1]: sshd@19-10.200.8.16:22-10.200.16.10:45062.service: Deactivated successfully. Jun 20 18:51:53.140471 systemd[1]: session-22.scope: Deactivated successfully. Jun 20 18:51:53.141137 systemd-logind[1684]: Session 22 logged out. Waiting for processes to exit. Jun 20 18:51:53.141910 systemd-logind[1684]: Removed session 22. Jun 20 18:51:53.246251 systemd[1]: Started sshd@20-10.200.8.16:22-10.200.16.10:45068.service - OpenSSH per-connection server daemon (10.200.16.10:45068). Jun 20 18:51:53.322471 setup-nvidia[12939]: Fri Jun 20 18:51:53 2025 Jun 20 18:51:53.322471 setup-nvidia[12939]: +---------------------------------------------------------------------------------------+ Jun 20 18:51:53.322471 setup-nvidia[12939]: | NVIDIA-SMI 535.230.02 Driver Version: 535.230.02 CUDA Version: 12.2 | Jun 20 18:51:53.322471 setup-nvidia[12939]: |-----------------------------------------+----------------------+----------------------+ Jun 20 18:51:53.322471 setup-nvidia[12939]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Jun 20 18:51:53.322471 setup-nvidia[12939]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Jun 20 18:51:53.322471 setup-nvidia[12939]: | | | MIG M. | Jun 20 18:51:53.322471 setup-nvidia[12939]: |=========================================+======================+======================| Jun 20 18:51:53.326377 setup-nvidia[12939]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Jun 20 18:51:53.326377 setup-nvidia[12939]: | N/A 30C P0 38W / 250W | 0MiB / 16384MiB | 0% Default | Jun 20 18:51:53.326377 setup-nvidia[12939]: | | | N/A | Jun 20 18:51:53.326377 setup-nvidia[12939]: +-----------------------------------------+----------------------+----------------------+ Jun 20 18:51:53.326503 setup-nvidia[12939]: Jun 20 18:51:53.326503 setup-nvidia[12939]: +---------------------------------------------------------------------------------------+ Jun 20 18:51:53.326503 setup-nvidia[12939]: | Processes: | Jun 20 18:51:53.326503 setup-nvidia[12939]: | GPU GI CI PID Type Process name GPU Memory | Jun 20 18:51:53.326503 setup-nvidia[12939]: | ID ID Usage | Jun 20 18:51:53.326503 setup-nvidia[12939]: |=======================================================================================| Jun 20 18:51:53.326634 setup-nvidia[12939]: | No running processes found | Jun 20 18:51:53.326634 setup-nvidia[12939]: +---------------------------------------------------------------------------------------+ Jun 20 18:51:53.699123 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Jun 20 18:51:53.701140 systemd[1]: Reached target multi-user.target - Multi-User System. Jun 20 18:51:53.701420 systemd[1]: Startup finished in 1.126s (firmware) + 28.226s (loader) + 1.849s (kernel) + 14.000s (initrd) + 2min 36.747s (userspace) = 3min 21.950s. Jun 20 18:51:53.877720 sshd[12952]: Accepted publickey for core from 10.200.16.10 port 45068 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:51:53.879010 sshd-session[12952]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:51:53.883255 systemd-logind[1684]: New session 23 of user core. Jun 20 18:51:53.893932 systemd[1]: Started session-23.scope - Session 23 of User core. Jun 20 18:51:54.325911 sshd[12956]: Connection closed by 10.200.16.10 port 45068 Jun 20 18:51:54.326289 sshd-session[12952]: pam_unix(sshd:session): session closed for user core Jun 20 18:51:54.329431 systemd[1]: sshd@20-10.200.8.16:22-10.200.16.10:45068.service: Deactivated successfully. Jun 20 18:51:54.331113 systemd[1]: session-23.scope: Deactivated successfully. Jun 20 18:51:54.331746 systemd-logind[1684]: Session 23 logged out. Waiting for processes to exit. Jun 20 18:51:54.332515 systemd-logind[1684]: Removed session 23. Jun 20 18:52:00.040661 systemd[1]: Stopping user@0.service - User Manager for UID 0... Jun 20 18:52:00.041474 systemd[2292]: Activating special unit exit.target... Jun 20 18:52:00.041564 systemd[2292]: Stopped target default.target - Main User Target. Jun 20 18:52:00.041607 systemd[2292]: Stopped target basic.target - Basic System. Jun 20 18:52:00.041638 systemd[2292]: Stopped target paths.target - Paths. Jun 20 18:52:00.041666 systemd[2292]: Stopped target sockets.target - Sockets. Jun 20 18:52:00.041694 systemd[2292]: Stopped target timers.target - Timers. Jun 20 18:52:00.042066 systemd[2292]: Closed dbus.socket - D-Bus User Message Bus Socket. Jun 20 18:52:00.042288 systemd[2292]: Removed slice app.slice - User Application Slice. Jun 20 18:52:00.042324 systemd[2292]: Reached target shutdown.target - Shutdown. Jun 20 18:52:00.042483 systemd[2292]: Finished systemd-exit.service - Exit the Session. Jun 20 18:52:00.042523 systemd[2292]: Reached target exit.target - Exit the Session. Jun 20 18:52:00.054932 (sd-pam)[2293]: pam_unix(systemd-user:session): session closed for user root Jun 20 18:52:00.056371 systemd[1]: user@0.service: Deactivated successfully. Jun 20 18:52:00.056744 systemd[1]: Stopped user@0.service - User Manager for UID 0. Jun 20 18:52:00.065116 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Jun 20 18:52:00.073947 systemd[1]: run-user-0.mount: Deactivated successfully. Jun 20 18:52:00.074889 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Jun 20 18:52:00.075086 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Jun 20 18:52:00.076742 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Jun 20 18:52:00.076857 systemd[1]: user-0.slice: Consumed 5min 16.047s CPU time, 891.8M memory peak. Jun 20 18:52:00.077227 systemd-logind[1684]: Removed session c3. Jun 20 18:52:04.438480 systemd[1]: Started sshd@21-10.200.8.16:22-10.200.16.10:46080.service - OpenSSH per-connection server daemon (10.200.16.10:46080). Jun 20 18:52:05.069920 sshd[12966]: Accepted publickey for core from 10.200.16.10 port 46080 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:52:05.071246 sshd-session[12966]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:52:05.075790 systemd-logind[1684]: New session 24 of user core. Jun 20 18:52:05.084948 systemd[1]: Started session-24.scope - Session 24 of User core. Jun 20 18:52:05.525496 sshd[12968]: Connection closed by 10.200.16.10 port 46080 Jun 20 18:52:05.525951 sshd-session[12966]: pam_unix(sshd:session): session closed for user core Jun 20 18:52:05.529547 systemd[1]: sshd@21-10.200.8.16:22-10.200.16.10:46080.service: Deactivated successfully. Jun 20 18:52:05.531321 systemd[1]: session-24.scope: Deactivated successfully. Jun 20 18:52:05.532072 systemd-logind[1684]: Session 24 logged out. Waiting for processes to exit. Jun 20 18:52:05.533183 systemd-logind[1684]: Removed session 24. Jun 20 18:52:05.637344 systemd[1]: Started sshd@22-10.200.8.16:22-10.200.16.10:46092.service - OpenSSH per-connection server daemon (10.200.16.10:46092). Jun 20 18:52:06.269458 sshd[12974]: Accepted publickey for core from 10.200.16.10 port 46092 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:52:06.270730 sshd-session[12974]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:52:06.275012 systemd-logind[1684]: New session 25 of user core. Jun 20 18:52:06.290940 systemd[1]: Started session-25.scope - Session 25 of User core. Jun 20 18:52:06.710087 sshd[12976]: Connection closed by 10.200.16.10 port 46092 Jun 20 18:52:06.710462 sshd-session[12974]: pam_unix(sshd:session): session closed for user core Jun 20 18:52:06.713705 systemd[1]: sshd@22-10.200.8.16:22-10.200.16.10:46092.service: Deactivated successfully. Jun 20 18:52:06.715399 systemd[1]: session-25.scope: Deactivated successfully. Jun 20 18:52:06.716086 systemd-logind[1684]: Session 25 logged out. Waiting for processes to exit. Jun 20 18:52:06.716862 systemd-logind[1684]: Removed session 25. Jun 20 18:52:06.821249 systemd[1]: Started sshd@23-10.200.8.16:22-10.200.16.10:46094.service - OpenSSH per-connection server daemon (10.200.16.10:46094). Jun 20 18:52:07.453786 sshd[12982]: Accepted publickey for core from 10.200.16.10 port 46094 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:52:07.455153 sshd-session[12982]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:52:07.463620 systemd-logind[1684]: New session 26 of user core. Jun 20 18:52:07.478941 systemd[1]: Started session-26.scope - Session 26 of User core. Jun 20 18:52:07.902719 sshd[12984]: Connection closed by 10.200.16.10 port 46094 Jun 20 18:52:07.903123 sshd-session[12982]: pam_unix(sshd:session): session closed for user core Jun 20 18:52:07.906296 systemd[1]: sshd@23-10.200.8.16:22-10.200.16.10:46094.service: Deactivated successfully. Jun 20 18:52:07.908010 systemd[1]: session-26.scope: Deactivated successfully. Jun 20 18:52:07.908631 systemd-logind[1684]: Session 26 logged out. Waiting for processes to exit. Jun 20 18:52:07.909432 systemd-logind[1684]: Removed session 26. Jun 20 18:52:08.017184 systemd[1]: Started sshd@24-10.200.8.16:22-10.200.16.10:46102.service - OpenSSH per-connection server daemon (10.200.16.10:46102). Jun 20 18:52:08.647461 sshd[12990]: Accepted publickey for core from 10.200.16.10 port 46102 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:52:08.648857 sshd-session[12990]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:52:08.653028 systemd-logind[1684]: New session 27 of user core. Jun 20 18:52:08.666930 systemd[1]: Started session-27.scope - Session 27 of User core. Jun 20 18:52:09.106635 sshd[12992]: Connection closed by 10.200.16.10 port 46102 Jun 20 18:52:09.107037 sshd-session[12990]: pam_unix(sshd:session): session closed for user core Jun 20 18:52:09.110214 systemd[1]: sshd@24-10.200.8.16:22-10.200.16.10:46102.service: Deactivated successfully. Jun 20 18:52:09.111913 systemd[1]: session-27.scope: Deactivated successfully. Jun 20 18:52:09.112821 systemd-logind[1684]: Session 27 logged out. Waiting for processes to exit. Jun 20 18:52:09.113681 systemd-logind[1684]: Removed session 27. Jun 20 18:52:09.225885 systemd[1]: Started sshd@25-10.200.8.16:22-10.200.16.10:47734.service - OpenSSH per-connection server daemon (10.200.16.10:47734). Jun 20 18:52:09.856088 sshd[12998]: Accepted publickey for core from 10.200.16.10 port 47734 ssh2: RSA SHA256:G+Fu4FCiL3LjUxHyNtKn4C9Y4XS6LoV7FhI4sOFZmNI Jun 20 18:52:09.857419 sshd-session[12998]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 18:52:09.862219 systemd-logind[1684]: New session 28 of user core. Jun 20 18:52:09.874935 systemd[1]: Started session-28.scope - Session 28 of User core. Jun 20 18:52:11.772685 sshd[13000]: Connection closed by 10.200.16.10 port 47734 Jun 20 18:52:11.773144 sshd-session[12998]: pam_unix(sshd:session): session closed for user core Jun 20 18:52:11.776561 systemd[1]: sshd@25-10.200.8.16:22-10.200.16.10:47734.service: Deactivated successfully. Jun 20 18:52:11.778240 systemd[1]: session-28.scope: Deactivated successfully. Jun 20 18:52:11.778440 systemd[1]: session-28.scope: Consumed 1.416s CPU time, 14.1M memory peak. Jun 20 18:52:11.778896 systemd-logind[1684]: Session 28 logged out. Waiting for processes to exit. Jun 20 18:52:11.779712 systemd-logind[1684]: Removed session 28.