Jul 6 23:27:20.235105 kernel: Linux version 6.6.95-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 Sun Jul 6 21:53:45 -00 2025 Jul 6 23:27:20.235134 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=7c120d8449636ab812a1f5387d02879f5beb6138a028d7566d1b80b47231d762 Jul 6 23:27:20.235145 kernel: BIOS-provided physical RAM map: Jul 6 23:27:20.235152 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Jul 6 23:27:20.235158 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Jul 6 23:27:20.235165 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Jul 6 23:27:20.235173 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Jul 6 23:27:20.235183 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Jul 6 23:27:20.235190 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Jul 6 23:27:20.235196 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Jul 6 23:27:20.235203 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Jul 6 23:27:20.235210 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Jul 6 23:27:20.235217 kernel: printk: bootconsole [earlyser0] enabled Jul 6 23:27:20.235223 kernel: NX (Execute Disable) protection: active Jul 6 23:27:20.235235 kernel: APIC: Static calls initialized Jul 6 23:27:20.235243 kernel: efi: EFI v2.7 by Microsoft Jul 6 23:27:20.235250 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Jul 6 23:27:20.235258 kernel: random: crng init done Jul 6 23:27:20.235265 kernel: secureboot: Secure boot disabled Jul 6 23:27:20.235273 kernel: SMBIOS 3.1.0 present. Jul 6 23:27:20.235280 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Jul 6 23:27:20.235288 kernel: Hypervisor detected: Microsoft Hyper-V Jul 6 23:27:20.235295 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Jul 6 23:27:20.235302 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Jul 6 23:27:20.235312 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Jul 6 23:27:20.235319 kernel: Hyper-V: Using hypercall for remote TLB flush Jul 6 23:27:20.235327 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Jul 6 23:27:20.235335 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Jul 6 23:27:20.235343 kernel: tsc: Marking TSC unstable due to running on Hyper-V Jul 6 23:27:20.235351 kernel: tsc: Detected 2593.992 MHz processor Jul 6 23:27:20.235359 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Jul 6 23:27:20.235367 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Jul 6 23:27:20.235374 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Jul 6 23:27:20.235385 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Jul 6 23:27:20.235392 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Jul 6 23:27:20.235400 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Jul 6 23:27:20.235411 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Jul 6 23:27:20.235419 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Jul 6 23:27:20.235426 kernel: Using GB pages for direct mapping Jul 6 23:27:20.235434 kernel: ACPI: Early table checksum verification disabled Jul 6 23:27:20.235446 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Jul 6 23:27:20.235457 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 6 23:27:20.235465 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 6 23:27:20.235473 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Jul 6 23:27:20.235481 kernel: ACPI: FACS 0x000000003FFFE000 000040 Jul 6 23:27:20.235489 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 6 23:27:20.235498 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 6 23:27:20.235508 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 6 23:27:20.235516 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 6 23:27:20.235524 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 6 23:27:20.235533 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 6 23:27:20.235541 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 6 23:27:20.235549 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Jul 6 23:27:20.235557 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Jul 6 23:27:20.235565 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Jul 6 23:27:20.235575 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Jul 6 23:27:20.235583 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Jul 6 23:27:20.235591 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Jul 6 23:27:20.235599 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Jul 6 23:27:20.235607 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Jul 6 23:27:20.235615 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Jul 6 23:27:20.235623 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Jul 6 23:27:20.235631 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0 Jul 6 23:27:20.235639 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0 Jul 6 23:27:20.235650 kernel: SRAT: PXM 0 -> APIC 0x02 -> Node 0 Jul 6 23:27:20.235658 kernel: SRAT: PXM 0 -> APIC 0x03 -> Node 0 Jul 6 23:27:20.235665 kernel: SRAT: PXM 0 -> APIC 0x04 -> Node 0 Jul 6 23:27:20.235673 kernel: SRAT: PXM 0 -> APIC 0x05 -> Node 0 Jul 6 23:27:20.235681 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Jul 6 23:27:20.235690 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Jul 6 23:27:20.235698 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Jul 6 23:27:20.235706 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Jul 6 23:27:20.235714 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Jul 6 23:27:20.235724 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Jul 6 23:27:20.235732 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Jul 6 23:27:20.235740 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Jul 6 23:27:20.235748 kernel: NUMA: Node 0 [mem 0x00000000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00000000-0xfdfffffff] Jul 6 23:27:20.235757 kernel: NUMA: Node 0 [mem 0x00000000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00000000-0x5cbfffffff] Jul 6 23:27:20.235765 kernel: NODE_DATA(0) allocated [mem 0x5cbfff9000-0x5cbfffefff] Jul 6 23:27:20.235773 kernel: Zone ranges: Jul 6 23:27:20.235781 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Jul 6 23:27:20.235789 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Jul 6 23:27:20.235810 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Jul 6 23:27:20.235818 kernel: Movable zone start for each node Jul 6 23:27:20.235826 kernel: Early memory node ranges Jul 6 23:27:20.235834 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Jul 6 23:27:20.235842 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Jul 6 23:27:20.235850 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Jul 6 23:27:20.235858 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Jul 6 23:27:20.235866 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Jul 6 23:27:20.235874 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Jul 6 23:27:20.235885 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Jul 6 23:27:20.235893 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Jul 6 23:27:20.235901 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Jul 6 23:27:20.235909 kernel: ACPI: PM-Timer IO Port: 0x408 Jul 6 23:27:20.235917 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Jul 6 23:27:20.235925 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Jul 6 23:27:20.235933 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Jul 6 23:27:20.235941 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Jul 6 23:27:20.235949 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Jul 6 23:27:20.235959 kernel: smpboot: Allowing 6 CPUs, 0 hotplug CPUs Jul 6 23:27:20.235967 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Jul 6 23:27:20.235975 kernel: Booting paravirtualized kernel on Hyper-V Jul 6 23:27:20.235984 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Jul 6 23:27:20.235992 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Jul 6 23:27:20.236000 kernel: percpu: Embedded 58 pages/cpu s197096 r8192 d32280 u262144 Jul 6 23:27:20.236091 kernel: pcpu-alloc: s197096 r8192 d32280 u262144 alloc=1*2097152 Jul 6 23:27:20.236162 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Jul 6 23:27:20.236205 kernel: Hyper-V: PV spinlocks enabled Jul 6 23:27:20.236222 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Jul 6 23:27:20.236231 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=7c120d8449636ab812a1f5387d02879f5beb6138a028d7566d1b80b47231d762 Jul 6 23:27:20.236240 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Jul 6 23:27:20.236248 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Jul 6 23:27:20.236257 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Jul 6 23:27:20.236265 kernel: Fallback order for Node 0: 0 Jul 6 23:27:20.236273 kernel: Built 1 zonelists, mobility grouping on. Total pages: 28899610 Jul 6 23:27:20.236281 kernel: Policy zone: Normal Jul 6 23:27:20.236292 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jul 6 23:27:20.236308 kernel: software IO TLB: area num 8. Jul 6 23:27:20.236317 kernel: Memory: 115328448K/117439364K available (14336K kernel code, 2295K rwdata, 22872K rodata, 43492K init, 1584K bss, 2110660K reserved, 0K cma-reserved) Jul 6 23:27:20.236328 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Jul 6 23:27:20.236336 kernel: Kernel/User page tables isolation: enabled Jul 6 23:27:20.236345 kernel: ftrace: allocating 37940 entries in 149 pages Jul 6 23:27:20.236353 kernel: ftrace: allocated 149 pages with 4 groups Jul 6 23:27:20.236362 kernel: Dynamic Preempt: voluntary Jul 6 23:27:20.236370 kernel: rcu: Preemptible hierarchical RCU implementation. Jul 6 23:27:20.236383 kernel: rcu: RCU event tracing is enabled. Jul 6 23:27:20.236392 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Jul 6 23:27:20.236400 kernel: Trampoline variant of Tasks RCU enabled. Jul 6 23:27:20.236411 kernel: Rude variant of Tasks RCU enabled. Jul 6 23:27:20.236420 kernel: Tracing variant of Tasks RCU enabled. Jul 6 23:27:20.236429 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Jul 6 23:27:20.236437 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Jul 6 23:27:20.236449 kernel: Using NULL legacy PIC Jul 6 23:27:20.236457 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Jul 6 23:27:20.236466 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Jul 6 23:27:20.236474 kernel: Console: colour dummy device 80x25 Jul 6 23:27:20.236483 kernel: printk: console [tty1] enabled Jul 6 23:27:20.236491 kernel: printk: console [ttyS0] enabled Jul 6 23:27:20.236500 kernel: printk: bootconsole [earlyser0] disabled Jul 6 23:27:20.236508 kernel: ACPI: Core revision 20230628 Jul 6 23:27:20.236517 kernel: Failed to register legacy timer interrupt Jul 6 23:27:20.236525 kernel: APIC: Switch to symmetric I/O mode setup Jul 6 23:27:20.236536 kernel: Hyper-V: enabling crash_kexec_post_notifiers Jul 6 23:27:20.236545 kernel: Hyper-V: Using IPI hypercalls Jul 6 23:27:20.236553 kernel: APIC: send_IPI() replaced with hv_send_ipi() Jul 6 23:27:20.236562 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Jul 6 23:27:20.236570 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Jul 6 23:27:20.236579 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Jul 6 23:27:20.236587 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Jul 6 23:27:20.236596 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Jul 6 23:27:20.236604 kernel: Hyper-V: Using enlightened APIC (xapic mode) Jul 6 23:27:20.236615 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Jul 6 23:27:20.236623 kernel: APIC: read() replaced with hv_apic_read() Jul 6 23:27:20.236632 kernel: APIC: write() replaced with hv_apic_write() Jul 6 23:27:20.236640 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Jul 6 23:27:20.236649 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Jul 6 23:27:20.236657 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593992) Jul 6 23:27:20.236666 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Jul 6 23:27:20.236674 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Jul 6 23:27:20.236683 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Jul 6 23:27:20.236694 kernel: Spectre V2 : Mitigation: Retpolines Jul 6 23:27:20.236702 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Jul 6 23:27:20.236710 kernel: Speculative Store Bypass: Vulnerable Jul 6 23:27:20.236719 kernel: MDS: Mitigation: Clear CPU buffers Jul 6 23:27:20.236727 kernel: TAA: Mitigation: Clear CPU buffers Jul 6 23:27:20.236735 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Jul 6 23:27:20.236744 kernel: ITS: Mitigation: Aligned branch/return thunks Jul 6 23:27:20.236752 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Jul 6 23:27:20.236760 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Jul 6 23:27:20.236768 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Jul 6 23:27:20.236777 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Jul 6 23:27:20.236788 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Jul 6 23:27:20.236809 kernel: Freeing SMP alternatives memory: 32K Jul 6 23:27:20.236818 kernel: pid_max: default: 32768 minimum: 301 Jul 6 23:27:20.236826 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Jul 6 23:27:20.236835 kernel: landlock: Up and running. Jul 6 23:27:20.236843 kernel: SELinux: Initializing. Jul 6 23:27:20.236851 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Jul 6 23:27:20.236860 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Jul 6 23:27:20.236868 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Jul 6 23:27:20.236877 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jul 6 23:27:20.236886 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jul 6 23:27:20.236897 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jul 6 23:27:20.236906 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Jul 6 23:27:20.236915 kernel: signal: max sigframe size: 1776 Jul 6 23:27:20.236923 kernel: rcu: Hierarchical SRCU implementation. Jul 6 23:27:20.236932 kernel: rcu: Max phase no-delay instances is 400. Jul 6 23:27:20.236941 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Jul 6 23:27:20.236949 kernel: smp: Bringing up secondary CPUs ... Jul 6 23:27:20.236958 kernel: smpboot: x86: Booting SMP configuration: Jul 6 23:27:20.236966 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Jul 6 23:27:20.236977 kernel: smp: Brought up 1 node, 6 CPUs Jul 6 23:27:20.236985 kernel: smpboot: Max logical packages: 1 Jul 6 23:27:20.236994 kernel: smpboot: Total of 6 processors activated (31127.90 BogoMIPS) Jul 6 23:27:20.237002 kernel: devtmpfs: initialized Jul 6 23:27:20.237011 kernel: x86/mm: Memory block size: 1024MB Jul 6 23:27:20.237019 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Jul 6 23:27:20.237028 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Jul 6 23:27:20.237037 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Jul 6 23:27:20.237045 kernel: pinctrl core: initialized pinctrl subsystem Jul 6 23:27:20.237056 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jul 6 23:27:20.237065 kernel: audit: initializing netlink subsys (disabled) Jul 6 23:27:20.237073 kernel: audit: type=2000 audit(1751844439.029:1): state=initialized audit_enabled=0 res=1 Jul 6 23:27:20.237082 kernel: thermal_sys: Registered thermal governor 'step_wise' Jul 6 23:27:20.237090 kernel: thermal_sys: Registered thermal governor 'user_space' Jul 6 23:27:20.237099 kernel: cpuidle: using governor menu Jul 6 23:27:20.237107 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Jul 6 23:27:20.237116 kernel: dca service started, version 1.12.1 Jul 6 23:27:20.237124 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Jul 6 23:27:20.237135 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Jul 6 23:27:20.237143 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Jul 6 23:27:20.237152 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Jul 6 23:27:20.237161 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Jul 6 23:27:20.237169 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Jul 6 23:27:20.237178 kernel: ACPI: Added _OSI(Module Device) Jul 6 23:27:20.237186 kernel: ACPI: Added _OSI(Processor Device) Jul 6 23:27:20.237194 kernel: ACPI: Added _OSI(Processor Aggregator Device) Jul 6 23:27:20.237203 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Jul 6 23:27:20.237214 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Jul 6 23:27:20.237222 kernel: ACPI: Interpreter enabled Jul 6 23:27:20.237231 kernel: ACPI: PM: (supports S0 S5) Jul 6 23:27:20.237239 kernel: ACPI: Using IOAPIC for interrupt routing Jul 6 23:27:20.237248 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Jul 6 23:27:20.237256 kernel: PCI: Ignoring E820 reservations for host bridge windows Jul 6 23:27:20.237265 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Jul 6 23:27:20.237273 kernel: iommu: Default domain type: Translated Jul 6 23:27:20.237282 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Jul 6 23:27:20.237293 kernel: efivars: Registered efivars operations Jul 6 23:27:20.237301 kernel: PCI: Using ACPI for IRQ routing Jul 6 23:27:20.237310 kernel: PCI: System does not support PCI Jul 6 23:27:20.237318 kernel: vgaarb: loaded Jul 6 23:27:20.237327 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Jul 6 23:27:20.237335 kernel: VFS: Disk quotas dquot_6.6.0 Jul 6 23:27:20.237344 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jul 6 23:27:20.237352 kernel: pnp: PnP ACPI init Jul 6 23:27:20.237361 kernel: pnp: PnP ACPI: found 3 devices Jul 6 23:27:20.237372 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Jul 6 23:27:20.237380 kernel: NET: Registered PF_INET protocol family Jul 6 23:27:20.237389 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Jul 6 23:27:20.237398 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Jul 6 23:27:20.237406 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jul 6 23:27:20.237421 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Jul 6 23:27:20.237431 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jul 6 23:27:20.237440 kernel: TCP: Hash tables configured (established 524288 bind 65536) Jul 6 23:27:20.237452 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jul 6 23:27:20.237461 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jul 6 23:27:20.237469 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jul 6 23:27:20.237478 kernel: NET: Registered PF_XDP protocol family Jul 6 23:27:20.237486 kernel: PCI: CLS 0 bytes, default 64 Jul 6 23:27:20.237495 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Jul 6 23:27:20.237503 kernel: software IO TLB: mapped [mem 0x000000003ae61000-0x000000003ee61000] (64MB) Jul 6 23:27:20.237512 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Jul 6 23:27:20.237521 kernel: Initialise system trusted keyrings Jul 6 23:27:20.237532 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Jul 6 23:27:20.237540 kernel: Key type asymmetric registered Jul 6 23:27:20.237548 kernel: Asymmetric key parser 'x509' registered Jul 6 23:27:20.237557 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Jul 6 23:27:20.237565 kernel: io scheduler mq-deadline registered Jul 6 23:27:20.237574 kernel: io scheduler kyber registered Jul 6 23:27:20.237582 kernel: io scheduler bfq registered Jul 6 23:27:20.237590 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Jul 6 23:27:20.237599 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Jul 6 23:27:20.237607 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Jul 6 23:27:20.237618 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Jul 6 23:27:20.237627 kernel: i8042: PNP: No PS/2 controller found. Jul 6 23:27:20.237790 kernel: rtc_cmos 00:02: registered as rtc0 Jul 6 23:27:20.237920 kernel: rtc_cmos 00:02: setting system clock to 2025-07-06T23:27:19 UTC (1751844439) Jul 6 23:27:20.238049 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Jul 6 23:27:20.238084 kernel: intel_pstate: CPU model not supported Jul 6 23:27:20.238093 kernel: efifb: probing for efifb Jul 6 23:27:20.238107 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Jul 6 23:27:20.238116 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Jul 6 23:27:20.238124 kernel: efifb: scrolling: redraw Jul 6 23:27:20.238133 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Jul 6 23:27:20.238142 kernel: Console: switching to colour frame buffer device 128x48 Jul 6 23:27:20.238150 kernel: fb0: EFI VGA frame buffer device Jul 6 23:27:20.238159 kernel: pstore: Using crash dump compression: deflate Jul 6 23:27:20.238167 kernel: pstore: Registered efi_pstore as persistent store backend Jul 6 23:27:20.238176 kernel: NET: Registered PF_INET6 protocol family Jul 6 23:27:20.238187 kernel: Segment Routing with IPv6 Jul 6 23:27:20.238195 kernel: In-situ OAM (IOAM) with IPv6 Jul 6 23:27:20.238204 kernel: NET: Registered PF_PACKET protocol family Jul 6 23:27:20.238212 kernel: Key type dns_resolver registered Jul 6 23:27:20.238221 kernel: IPI shorthand broadcast: enabled Jul 6 23:27:20.238229 kernel: sched_clock: Marking stable (1630005300, 104554100)->(2071612400, -337053000) Jul 6 23:27:20.238238 kernel: registered taskstats version 1 Jul 6 23:27:20.238246 kernel: Loading compiled-in X.509 certificates Jul 6 23:27:20.238255 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.95-flatcar: f74b958d282931d4f0d8d911dd18abd0ec707734' Jul 6 23:27:20.238266 kernel: Key type .fscrypt registered Jul 6 23:27:20.238274 kernel: Key type fscrypt-provisioning registered Jul 6 23:27:20.238283 kernel: ima: No TPM chip found, activating TPM-bypass! Jul 6 23:27:20.238291 kernel: ima: Allocated hash algorithm: sha1 Jul 6 23:27:20.238300 kernel: ima: No architecture policies found Jul 6 23:27:20.238308 kernel: clk: Disabling unused clocks Jul 6 23:27:20.238317 kernel: Freeing unused kernel image (initmem) memory: 43492K Jul 6 23:27:20.238325 kernel: Write protecting the kernel read-only data: 38912k Jul 6 23:27:20.238334 kernel: Freeing unused kernel image (rodata/data gap) memory: 1704K Jul 6 23:27:20.238345 kernel: Run /init as init process Jul 6 23:27:20.238353 kernel: with arguments: Jul 6 23:27:20.238362 kernel: /init Jul 6 23:27:20.238370 kernel: with environment: Jul 6 23:27:20.238378 kernel: HOME=/ Jul 6 23:27:20.238386 kernel: TERM=linux Jul 6 23:27:20.238395 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Jul 6 23:27:20.238405 systemd[1]: Successfully made /usr/ read-only. Jul 6 23:27:20.238416 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) Jul 6 23:27:20.238428 systemd[1]: Detected virtualization microsoft. Jul 6 23:27:20.238437 systemd[1]: Detected architecture x86-64. Jul 6 23:27:20.238446 systemd[1]: Running in initrd. Jul 6 23:27:20.238455 systemd[1]: No hostname configured, using default hostname. Jul 6 23:27:20.238464 systemd[1]: Hostname set to . Jul 6 23:27:20.238473 systemd[1]: Initializing machine ID from random generator. Jul 6 23:27:20.238481 systemd[1]: Queued start job for default target initrd.target. Jul 6 23:27:20.238493 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 6 23:27:20.238502 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 6 23:27:20.238512 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Jul 6 23:27:20.238601 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jul 6 23:27:20.238618 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Jul 6 23:27:20.238628 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Jul 6 23:27:20.238638 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Jul 6 23:27:20.238653 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Jul 6 23:27:20.238662 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 6 23:27:20.238671 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jul 6 23:27:20.238680 systemd[1]: Reached target paths.target - Path Units. Jul 6 23:27:20.238689 systemd[1]: Reached target slices.target - Slice Units. Jul 6 23:27:20.238698 systemd[1]: Reached target swap.target - Swaps. Jul 6 23:27:20.238707 systemd[1]: Reached target timers.target - Timer Units. Jul 6 23:27:20.238716 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Jul 6 23:27:20.238728 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jul 6 23:27:20.238737 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Jul 6 23:27:20.238746 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Jul 6 23:27:20.238755 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jul 6 23:27:20.238764 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jul 6 23:27:20.238773 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jul 6 23:27:20.238782 systemd[1]: Reached target sockets.target - Socket Units. Jul 6 23:27:20.238791 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Jul 6 23:27:20.238813 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jul 6 23:27:20.238825 systemd[1]: Finished network-cleanup.service - Network Cleanup. Jul 6 23:27:20.238834 systemd[1]: Starting systemd-fsck-usr.service... Jul 6 23:27:20.238844 systemd[1]: Starting systemd-journald.service - Journal Service... Jul 6 23:27:20.238853 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jul 6 23:27:20.238887 systemd-journald[205]: Collecting audit messages is disabled. Jul 6 23:27:20.238914 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 6 23:27:20.238924 systemd-journald[205]: Journal started Jul 6 23:27:20.238948 systemd-journald[205]: Runtime Journal (/run/log/journal/aa50a8d87ddb4d11a807a91e6a29fb33) is 8M, max 2.2G, 2.1G free. Jul 6 23:27:20.248256 systemd[1]: Started systemd-journald.service - Journal Service. Jul 6 23:27:20.251784 systemd-modules-load[207]: Inserted module 'overlay' Jul 6 23:27:20.252194 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Jul 6 23:27:20.259490 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jul 6 23:27:20.260917 systemd[1]: Finished systemd-fsck-usr.service. Jul 6 23:27:20.263298 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jul 6 23:27:20.264304 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jul 6 23:27:20.293640 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 6 23:27:20.303731 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jul 6 23:27:20.313616 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jul 6 23:27:20.323420 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jul 6 23:27:20.343851 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 6 23:27:20.348609 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 6 23:27:20.370054 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jul 6 23:27:20.381710 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Jul 6 23:27:20.384348 systemd-modules-load[207]: Inserted module 'br_netfilter' Jul 6 23:27:20.387893 kernel: Bridge firewalling registered Jul 6 23:27:20.392951 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Jul 6 23:27:20.393970 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jul 6 23:27:20.406535 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jul 6 23:27:20.417676 dracut-cmdline[236]: dracut-dracut-053 Jul 6 23:27:20.417676 dracut-cmdline[236]: 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=7c120d8449636ab812a1f5387d02879f5beb6138a028d7566d1b80b47231d762 Jul 6 23:27:20.449571 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jul 6 23:27:20.464935 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jul 6 23:27:20.499151 systemd-resolved[277]: Positive Trust Anchors: Jul 6 23:27:20.499165 systemd-resolved[277]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 6 23:27:20.499195 systemd-resolved[277]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Jul 6 23:27:20.501742 systemd-resolved[277]: Defaulting to hostname 'linux'. Jul 6 23:27:20.502790 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jul 6 23:27:20.512375 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jul 6 23:27:20.559812 kernel: SCSI subsystem initialized Jul 6 23:27:20.569810 kernel: Loading iSCSI transport class v2.0-870. Jul 6 23:27:20.583822 kernel: iscsi: registered transport (tcp) Jul 6 23:27:20.607744 kernel: iscsi: registered transport (qla4xxx) Jul 6 23:27:20.607775 kernel: QLogic iSCSI HBA Driver Jul 6 23:27:20.648380 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Jul 6 23:27:20.666013 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Jul 6 23:27:20.700436 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Jul 6 23:27:20.700471 kernel: device-mapper: uevent: version 1.0.3 Jul 6 23:27:20.705813 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Jul 6 23:27:20.745813 kernel: raid6: avx2x4 gen() 30849 MB/s Jul 6 23:27:20.765809 kernel: raid6: avx2x2 gen() 31018 MB/s Jul 6 23:27:20.787020 kernel: raid6: avx2x1 gen() 22045 MB/s Jul 6 23:27:20.787052 kernel: raid6: using algorithm avx2x2 gen() 31018 MB/s Jul 6 23:27:20.809256 kernel: raid6: .... xor() 18109 MB/s, rmw enabled Jul 6 23:27:20.809298 kernel: raid6: using avx2x2 recovery algorithm Jul 6 23:27:20.836821 kernel: xor: automatically using best checksumming function avx Jul 6 23:27:20.989826 kernel: Btrfs loaded, zoned=no, fsverity=no Jul 6 23:27:21.000087 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Jul 6 23:27:21.023932 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 6 23:27:21.046507 systemd-udevd[429]: Using default interface naming scheme 'v255'. Jul 6 23:27:21.052139 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 6 23:27:21.069939 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Jul 6 23:27:21.082073 dracut-pre-trigger[438]: rd.md=0: removing MD RAID activation Jul 6 23:27:21.111333 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Jul 6 23:27:21.129958 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jul 6 23:27:21.187566 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jul 6 23:27:21.205010 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Jul 6 23:27:21.214975 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Jul 6 23:27:21.220016 kernel: cryptd: max_cpu_qlen set to 1000 Jul 6 23:27:21.219015 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Jul 6 23:27:21.219270 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 6 23:27:21.244334 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jul 6 23:27:21.265067 kernel: AVX2 version of gcm_enc/dec engaged. Jul 6 23:27:21.265102 kernel: AES CTR mode by8 optimization enabled Jul 6 23:27:21.265122 kernel: hv_vmbus: Vmbus version:4.0 Jul 6 23:27:21.266132 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Jul 6 23:27:21.279819 kernel: hv_vmbus: registering driver hyperv_keyboard Jul 6 23:27:21.279849 kernel: pps_core: LinuxPPS API ver. 1 registered Jul 6 23:27:21.283903 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Jul 6 23:27:21.295574 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Jul 6 23:27:21.295594 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 Jul 6 23:27:21.304385 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jul 6 23:27:21.312542 kernel: hv_vmbus: registering driver hv_netvsc Jul 6 23:27:21.312566 kernel: PTP clock support registered Jul 6 23:27:21.312579 kernel: hid: raw HID events driver (C) Jiri Kosina Jul 6 23:27:21.304453 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jul 6 23:27:21.325042 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jul 6 23:27:21.329619 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 6 23:27:21.329690 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jul 6 23:27:21.347987 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Jul 6 23:27:21.362017 kernel: hv_vmbus: registering driver hid_hyperv Jul 6 23:27:21.362052 kernel: hv_vmbus: registering driver hv_storvsc Jul 6 23:27:21.362810 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Jul 6 23:27:21.362840 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Jul 6 23:27:21.363021 kernel: hv_utils: Registering HyperV Utility Driver Jul 6 23:27:21.363035 kernel: hv_vmbus: registering driver hv_pci Jul 6 23:27:21.364133 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Jul 6 23:27:21.364821 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Jul 6 23:27:21.373414 kernel: hv_vmbus: registering driver hv_utils Jul 6 23:27:21.373443 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Jul 6 23:27:21.378312 kernel: scsi host0: storvsc_host_t Jul 6 23:27:21.378357 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Jul 6 23:27:21.386817 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Jul 6 23:27:21.386859 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Jul 6 23:27:21.387978 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 6 23:27:21.452162 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Jul 6 23:27:21.452364 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 Jul 6 23:27:21.452394 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Jul 6 23:27:21.452556 kernel: pci 0001:00:00.0: reg 0x10: [mem 0x41000000-0x41ffffff] Jul 6 23:27:21.452586 kernel: pci 0001:00:00.0: reg 0x14: [mem 0x1000000000-0x13ffffffff 64bit pref] Jul 6 23:27:21.452606 kernel: pci 0001:00:00.0: reg 0x1c: [mem 0x1400000000-0x1401ffffff 64bit pref] Jul 6 23:27:21.452624 kernel: pci 0001:00:00.0: Enabling HDA controller Jul 6 23:27:21.435928 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jul 6 23:27:21.478010 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Jul 6 23:27:21.478230 kernel: pci 0001:00:00.0: BAR 1: assigned [mem 0x1000000000-0x13ffffffff 64bit pref] Jul 6 23:27:21.478398 kernel: pci 0001:00:00.0: BAR 3: assigned [mem 0x1400000000-0x1401ffffff 64bit pref] Jul 6 23:27:21.487427 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 6 23:27:21.499694 kernel: pci 0001:00:00.0: BAR 0: assigned [mem 0x41000000-0x41ffffff] Jul 6 23:27:21.493209 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jul 6 23:27:21.522995 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jul 6 23:27:21.600321 kernel: hv_utils: Heartbeat IC version 3.0 Jul 6 23:27:21.600362 kernel: scsi host1: storvsc_host_t Jul 6 23:27:21.600397 kernel: hv_utils: Shutdown IC version 3.2 Jul 6 23:27:21.606765 kernel: hv_utils: TimeSync IC version 4.0 Jul 6 23:27:22.436430 systemd-resolved[277]: Clock change detected. Flushing caches. Jul 6 23:27:22.450267 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Jul 6 23:27:22.450601 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Jul 6 23:27:22.456524 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Jul 6 23:27:22.473331 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Jul 6 23:27:22.473562 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Jul 6 23:27:22.473727 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks Jul 6 23:27:22.473873 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Jul 6 23:27:22.474021 kernel: sd 0:0:0:0: [sda] Write Protect is off Jul 6 23:27:22.478650 kernel: sd 0:0:0:1: [sdb] Write Protect is off Jul 6 23:27:22.478810 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Jul 6 23:27:22.481094 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Jul 6 23:27:22.481285 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Jul 6 23:27:22.484913 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Jul 6 23:27:22.489399 kernel: sdb: sdb1 Jul 6 23:27:22.494958 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Jul 6 23:27:22.494984 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk Jul 6 23:27:22.498403 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Jul 6 23:27:22.934941 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Jul 6 23:27:23.023086 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Jul 6 23:27:23.152410 kernel: BTRFS: device fsid 25bdfe43-d649-4808-8940-e1722efc7a2e devid 1 transid 37 /dev/sda3 scanned by (udev-worker) (475) Jul 6 23:27:23.172919 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Jul 6 23:27:23.182863 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Jul 6 23:27:23.204502 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Jul 6 23:27:23.235403 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 scanned by (udev-worker) (491) Jul 6 23:27:23.257736 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Jul 6 23:27:24.226404 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Jul 6 23:27:24.226760 disk-uuid[639]: The operation has completed successfully. Jul 6 23:27:24.265270 systemd[1]: disk-uuid.service: Deactivated successfully. Jul 6 23:27:24.265374 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Jul 6 23:27:24.327504 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Jul 6 23:27:24.337494 sh[730]: Success Jul 6 23:27:24.374400 kernel: device-mapper: verity: sha256 using implementation "sha256-avx2" Jul 6 23:27:24.577947 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Jul 6 23:27:24.591838 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Jul 6 23:27:24.593515 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Jul 6 23:27:24.628101 kernel: BTRFS info (device dm-0): first mount of filesystem 25bdfe43-d649-4808-8940-e1722efc7a2e Jul 6 23:27:24.628135 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Jul 6 23:27:24.633136 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Jul 6 23:27:24.636863 kernel: BTRFS info (device dm-0): disabling log replay at mount time Jul 6 23:27:24.640027 kernel: BTRFS info (device dm-0): using free space tree Jul 6 23:27:24.984059 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Jul 6 23:27:24.986154 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Jul 6 23:27:25.007506 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Jul 6 23:27:25.009626 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Jul 6 23:27:25.047871 kernel: BTRFS info (device sda6): first mount of filesystem 520cc21d-4438-4aef-a59e-8797d7bc85f5 Jul 6 23:27:25.047908 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Jul 6 23:27:25.051070 kernel: BTRFS info (device sda6): using free space tree Jul 6 23:27:25.068405 kernel: BTRFS info (device sda6): auto enabling async discard Jul 6 23:27:25.076403 kernel: BTRFS info (device sda6): last unmount of filesystem 520cc21d-4438-4aef-a59e-8797d7bc85f5 Jul 6 23:27:25.081034 systemd[1]: Finished ignition-setup.service - Ignition (setup). Jul 6 23:27:25.091544 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Jul 6 23:27:25.109582 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jul 6 23:27:25.121501 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jul 6 23:27:25.156492 systemd-networkd[912]: lo: Link UP Jul 6 23:27:25.156502 systemd-networkd[912]: lo: Gained carrier Jul 6 23:27:25.157610 systemd-networkd[912]: Enumeration completed Jul 6 23:27:25.157972 systemd-networkd[912]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 6 23:27:25.157977 systemd-networkd[912]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 6 23:27:25.158744 systemd-networkd[912]: eth0: Link UP Jul 6 23:27:25.158918 systemd-networkd[912]: eth0: Gained carrier Jul 6 23:27:25.158926 systemd-networkd[912]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 6 23:27:25.159262 systemd[1]: Started systemd-networkd.service - Network Configuration. Jul 6 23:27:25.166704 systemd[1]: Reached target network.target - Network. Jul 6 23:27:25.215419 systemd-networkd[912]: eth0: DHCPv4 address 10.200.8.18/24, gateway 10.200.8.1 acquired from 168.63.129.16 Jul 6 23:27:26.916665 ignition[890]: Ignition 2.20.0 Jul 6 23:27:26.916679 ignition[890]: Stage: fetch-offline Jul 6 23:27:26.916733 ignition[890]: no configs at "/usr/lib/ignition/base.d" Jul 6 23:27:26.916744 ignition[890]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 6 23:27:26.916848 ignition[890]: parsed url from cmdline: "" Jul 6 23:27:26.916853 ignition[890]: no config URL provided Jul 6 23:27:26.916859 ignition[890]: reading system config file "/usr/lib/ignition/user.ign" Jul 6 23:27:26.916872 ignition[890]: no config at "/usr/lib/ignition/user.ign" Jul 6 23:27:26.937316 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Jul 6 23:27:26.916878 ignition[890]: failed to fetch config: resource requires networking Jul 6 23:27:26.917054 ignition[890]: Ignition finished successfully Jul 6 23:27:26.957554 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Jul 6 23:27:26.969036 ignition[924]: Ignition 2.20.0 Jul 6 23:27:26.969047 ignition[924]: Stage: fetch Jul 6 23:27:26.969210 ignition[924]: no configs at "/usr/lib/ignition/base.d" Jul 6 23:27:26.969222 ignition[924]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 6 23:27:26.969314 ignition[924]: parsed url from cmdline: "" Jul 6 23:27:26.969318 ignition[924]: no config URL provided Jul 6 23:27:26.969323 ignition[924]: reading system config file "/usr/lib/ignition/user.ign" Jul 6 23:27:26.969333 ignition[924]: no config at "/usr/lib/ignition/user.ign" Jul 6 23:27:26.969357 ignition[924]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Jul 6 23:27:27.033849 ignition[924]: GET result: OK Jul 6 23:27:27.033889 ignition[924]: config has been read from IMDS userdata Jul 6 23:27:27.033899 ignition[924]: parsing config with SHA512: 510a98b0aecb86d2178f88ba3e1f9186b8c31f59b192bd24d8e587a11722b3bdc7b2ca905888d7ae01bad3b369119aa09aa6b11222128d7fc6fbc0e9c19e88d8 Jul 6 23:27:27.038095 unknown[924]: fetched base config from "system" Jul 6 23:27:27.038370 ignition[924]: fetch: fetch complete Jul 6 23:27:27.038116 unknown[924]: fetched base config from "system" Jul 6 23:27:27.038375 ignition[924]: fetch: fetch passed Jul 6 23:27:27.038123 unknown[924]: fetched user config from "azure" Jul 6 23:27:27.038434 ignition[924]: Ignition finished successfully Jul 6 23:27:27.041159 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Jul 6 23:27:27.059790 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Jul 6 23:27:27.090913 ignition[934]: Ignition 2.20.0 Jul 6 23:27:27.090924 ignition[934]: Stage: kargs Jul 6 23:27:27.091098 ignition[934]: no configs at "/usr/lib/ignition/base.d" Jul 6 23:27:27.096192 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Jul 6 23:27:27.091111 ignition[934]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 6 23:27:27.091647 ignition[934]: kargs: kargs passed Jul 6 23:27:27.091692 ignition[934]: Ignition finished successfully Jul 6 23:27:27.116521 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Jul 6 23:27:27.121463 systemd-networkd[912]: eth0: Gained IPv6LL Jul 6 23:27:27.129442 ignition[944]: Ignition 2.20.0 Jul 6 23:27:27.129454 ignition[944]: Stage: disks Jul 6 23:27:27.129627 ignition[944]: no configs at "/usr/lib/ignition/base.d" Jul 6 23:27:27.129639 ignition[944]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 6 23:27:27.134557 systemd[1]: Finished ignition-disks.service - Ignition (disks). Jul 6 23:27:27.130214 ignition[944]: disks: disks passed Jul 6 23:27:27.141763 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Jul 6 23:27:27.130258 ignition[944]: Ignition finished successfully Jul 6 23:27:27.149981 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Jul 6 23:27:27.158730 systemd[1]: Reached target local-fs.target - Local File Systems. Jul 6 23:27:27.165029 systemd[1]: Reached target sysinit.target - System Initialization. Jul 6 23:27:27.173416 systemd[1]: Reached target basic.target - Basic System. Jul 6 23:27:27.200526 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Jul 6 23:27:27.265198 systemd-fsck[955]: ROOT: clean, 14/7326000 files, 477710/7359488 blocks Jul 6 23:27:27.270434 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Jul 6 23:27:27.290518 systemd[1]: Mounting sysroot.mount - /sysroot... Jul 6 23:27:27.387395 kernel: EXT4-fs (sda9): mounted filesystem daab0c95-3783-44c0-bef8-9d61a5c53c14 r/w with ordered data mode. Quota mode: none. Jul 6 23:27:27.387892 systemd[1]: Mounted sysroot.mount - /sysroot. Jul 6 23:27:27.389906 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Jul 6 23:27:27.433457 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jul 6 23:27:27.435608 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Jul 6 23:27:27.451963 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/sda6 scanned by mount (967) Jul 6 23:27:27.448505 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Jul 6 23:27:27.466976 kernel: BTRFS info (device sda6): first mount of filesystem 520cc21d-4438-4aef-a59e-8797d7bc85f5 Jul 6 23:27:27.466996 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Jul 6 23:27:27.467009 kernel: BTRFS info (device sda6): using free space tree Jul 6 23:27:27.458978 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Jul 6 23:27:27.489301 kernel: BTRFS info (device sda6): auto enabling async discard Jul 6 23:27:27.459011 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Jul 6 23:27:27.479265 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Jul 6 23:27:27.491882 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jul 6 23:27:27.499890 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Jul 6 23:27:28.358404 coreos-metadata[969]: Jul 06 23:27:28.358 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Jul 6 23:27:28.364902 coreos-metadata[969]: Jul 06 23:27:28.364 INFO Fetch successful Jul 6 23:27:28.364902 coreos-metadata[969]: Jul 06 23:27:28.364 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Jul 6 23:27:28.377205 coreos-metadata[969]: Jul 06 23:27:28.368 INFO Fetch successful Jul 6 23:27:28.377205 coreos-metadata[969]: Jul 06 23:27:28.368 INFO wrote hostname ci-4230.2.1-a-04307b7828 to /sysroot/etc/hostname Jul 6 23:27:28.378155 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Jul 6 23:27:28.510066 initrd-setup-root[997]: cut: /sysroot/etc/passwd: No such file or directory Jul 6 23:27:28.628891 initrd-setup-root[1004]: cut: /sysroot/etc/group: No such file or directory Jul 6 23:27:28.636233 initrd-setup-root[1011]: cut: /sysroot/etc/shadow: No such file or directory Jul 6 23:27:28.671417 initrd-setup-root[1018]: cut: /sysroot/etc/gshadow: No such file or directory Jul 6 23:27:29.727998 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Jul 6 23:27:29.745467 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Jul 6 23:27:29.746962 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Jul 6 23:27:29.764746 systemd[1]: sysroot-oem.mount: Deactivated successfully. Jul 6 23:27:29.773477 kernel: BTRFS info (device sda6): last unmount of filesystem 520cc21d-4438-4aef-a59e-8797d7bc85f5 Jul 6 23:27:29.788159 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Jul 6 23:27:29.799785 ignition[1088]: INFO : Ignition 2.20.0 Jul 6 23:27:29.799785 ignition[1088]: INFO : Stage: mount Jul 6 23:27:29.805558 ignition[1088]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 6 23:27:29.805558 ignition[1088]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 6 23:27:29.805558 ignition[1088]: INFO : mount: mount passed Jul 6 23:27:29.805558 ignition[1088]: INFO : Ignition finished successfully Jul 6 23:27:29.810954 systemd[1]: Finished ignition-mount.service - Ignition (mount). Jul 6 23:27:29.819838 systemd[1]: Starting ignition-files.service - Ignition (files)... Jul 6 23:27:29.832895 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jul 6 23:27:29.855403 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/sda6 scanned by mount (1101) Jul 6 23:27:29.878934 kernel: BTRFS info (device sda6): first mount of filesystem 520cc21d-4438-4aef-a59e-8797d7bc85f5 Jul 6 23:27:29.878963 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Jul 6 23:27:29.882073 kernel: BTRFS info (device sda6): using free space tree Jul 6 23:27:29.887396 kernel: BTRFS info (device sda6): auto enabling async discard Jul 6 23:27:29.890488 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jul 6 23:27:29.922704 ignition[1118]: INFO : Ignition 2.20.0 Jul 6 23:27:29.922704 ignition[1118]: INFO : Stage: files Jul 6 23:27:29.928377 ignition[1118]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 6 23:27:29.928377 ignition[1118]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 6 23:27:29.928377 ignition[1118]: DEBUG : files: compiled without relabeling support, skipping Jul 6 23:27:29.928377 ignition[1118]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Jul 6 23:27:29.928377 ignition[1118]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Jul 6 23:27:30.076480 ignition[1118]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Jul 6 23:27:30.081966 ignition[1118]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Jul 6 23:27:30.087508 unknown[1118]: wrote ssh authorized keys file for user: core Jul 6 23:27:30.091308 ignition[1118]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Jul 6 23:27:30.117215 ignition[1118]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Jul 6 23:27:30.124095 ignition[1118]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Jul 6 23:27:30.124095 ignition[1118]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Jul 6 23:27:30.124095 ignition[1118]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Jul 6 23:27:30.124095 ignition[1118]: INFO : files: files passed Jul 6 23:27:30.124095 ignition[1118]: INFO : Ignition finished successfully Jul 6 23:27:30.132795 systemd[1]: Finished ignition-files.service - Ignition (files). Jul 6 23:27:30.165521 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Jul 6 23:27:30.167021 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Jul 6 23:27:30.178741 systemd[1]: ignition-quench.service: Deactivated successfully. Jul 6 23:27:30.178838 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Jul 6 23:27:30.202258 initrd-setup-root-after-ignition[1150]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jul 6 23:27:30.202258 initrd-setup-root-after-ignition[1150]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Jul 6 23:27:30.215352 initrd-setup-root-after-ignition[1154]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jul 6 23:27:30.216322 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Jul 6 23:27:30.225965 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Jul 6 23:27:30.247497 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Jul 6 23:27:30.273934 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Jul 6 23:27:30.274038 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Jul 6 23:27:30.283010 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Jul 6 23:27:30.285403 systemd[1]: Reached target initrd.target - Initrd Default Target. Jul 6 23:27:30.297781 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Jul 6 23:27:30.314510 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Jul 6 23:27:30.327237 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jul 6 23:27:30.330171 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Jul 6 23:27:30.346083 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Jul 6 23:27:30.347699 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 6 23:27:30.356569 systemd[1]: Stopped target timers.target - Timer Units. Jul 6 23:27:30.365792 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Jul 6 23:27:30.365908 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jul 6 23:27:30.381371 systemd[1]: Stopped target initrd.target - Initrd Default Target. Jul 6 23:27:30.389078 systemd[1]: Stopped target basic.target - Basic System. Jul 6 23:27:30.397565 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Jul 6 23:27:30.399701 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Jul 6 23:27:30.410597 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Jul 6 23:27:30.419807 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Jul 6 23:27:30.428594 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Jul 6 23:27:30.436443 systemd[1]: Stopped target sysinit.target - System Initialization. Jul 6 23:27:30.445359 systemd[1]: Stopped target local-fs.target - Local File Systems. Jul 6 23:27:30.456721 systemd[1]: Stopped target swap.target - Swaps. Jul 6 23:27:30.463610 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Jul 6 23:27:30.463745 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Jul 6 23:27:30.474543 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Jul 6 23:27:30.483157 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 6 23:27:30.492200 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Jul 6 23:27:30.492335 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 6 23:27:30.501694 systemd[1]: dracut-initqueue.service: Deactivated successfully. Jul 6 23:27:30.501818 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Jul 6 23:27:30.514920 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Jul 6 23:27:30.515056 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Jul 6 23:27:30.523864 systemd[1]: ignition-files.service: Deactivated successfully. Jul 6 23:27:30.523980 systemd[1]: Stopped ignition-files.service - Ignition (files). Jul 6 23:27:30.531874 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Jul 6 23:27:30.531994 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Jul 6 23:27:30.551560 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Jul 6 23:27:30.554880 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Jul 6 23:27:30.562826 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Jul 6 23:27:30.590331 ignition[1174]: INFO : Ignition 2.20.0 Jul 6 23:27:30.590331 ignition[1174]: INFO : Stage: umount Jul 6 23:27:30.590331 ignition[1174]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 6 23:27:30.590331 ignition[1174]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 6 23:27:30.590331 ignition[1174]: INFO : umount: umount passed Jul 6 23:27:30.590331 ignition[1174]: INFO : Ignition finished successfully Jul 6 23:27:30.562970 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Jul 6 23:27:30.571280 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Jul 6 23:27:30.571407 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Jul 6 23:27:30.588028 systemd[1]: ignition-mount.service: Deactivated successfully. Jul 6 23:27:30.588132 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Jul 6 23:27:30.594342 systemd[1]: initrd-cleanup.service: Deactivated successfully. Jul 6 23:27:30.594452 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Jul 6 23:27:30.599067 systemd[1]: ignition-disks.service: Deactivated successfully. Jul 6 23:27:30.599132 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Jul 6 23:27:30.606394 systemd[1]: ignition-kargs.service: Deactivated successfully. Jul 6 23:27:30.606446 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Jul 6 23:27:30.614237 systemd[1]: ignition-fetch.service: Deactivated successfully. Jul 6 23:27:30.614287 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Jul 6 23:27:30.622321 systemd[1]: Stopped target network.target - Network. Jul 6 23:27:30.631467 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Jul 6 23:27:30.631525 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Jul 6 23:27:30.639789 systemd[1]: Stopped target paths.target - Path Units. Jul 6 23:27:30.647747 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Jul 6 23:27:30.656430 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 6 23:27:30.664799 systemd[1]: Stopped target slices.target - Slice Units. Jul 6 23:27:30.672252 systemd[1]: Stopped target sockets.target - Socket Units. Jul 6 23:27:30.679764 systemd[1]: iscsid.socket: Deactivated successfully. Jul 6 23:27:30.679807 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Jul 6 23:27:30.684428 systemd[1]: iscsiuio.socket: Deactivated successfully. Jul 6 23:27:30.684473 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jul 6 23:27:30.692242 systemd[1]: ignition-setup.service: Deactivated successfully. Jul 6 23:27:30.692297 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Jul 6 23:27:30.699885 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Jul 6 23:27:30.699933 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Jul 6 23:27:30.707597 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Jul 6 23:27:30.715423 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Jul 6 23:27:30.723518 systemd[1]: sysroot-boot.mount: Deactivated successfully. Jul 6 23:27:30.724105 systemd[1]: systemd-resolved.service: Deactivated successfully. Jul 6 23:27:30.724212 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Jul 6 23:27:30.738518 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Jul 6 23:27:30.738798 systemd[1]: systemd-networkd.service: Deactivated successfully. Jul 6 23:27:30.738907 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Jul 6 23:27:30.750367 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Jul 6 23:27:30.750635 systemd[1]: sysroot-boot.service: Deactivated successfully. Jul 6 23:27:30.750729 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Jul 6 23:27:30.758977 systemd[1]: systemd-networkd.socket: Deactivated successfully. Jul 6 23:27:30.759026 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Jul 6 23:27:30.762341 systemd[1]: initrd-setup-root.service: Deactivated successfully. Jul 6 23:27:30.762405 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Jul 6 23:27:30.783518 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Jul 6 23:27:30.790429 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Jul 6 23:27:30.790486 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jul 6 23:27:30.798583 systemd[1]: systemd-sysctl.service: Deactivated successfully. Jul 6 23:27:30.798637 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Jul 6 23:27:30.807127 systemd[1]: systemd-modules-load.service: Deactivated successfully. Jul 6 23:27:30.807175 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Jul 6 23:27:30.814089 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Jul 6 23:27:30.814137 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 6 23:27:30.830164 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 6 23:27:30.833442 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Jul 6 23:27:30.833513 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Jul 6 23:27:30.873013 systemd[1]: network-cleanup.service: Deactivated successfully. Jul 6 23:27:30.873118 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Jul 6 23:27:30.884286 systemd[1]: systemd-udevd.service: Deactivated successfully. Jul 6 23:27:30.884445 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 6 23:27:30.893631 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Jul 6 23:27:30.893682 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Jul 6 23:27:30.900689 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Jul 6 23:27:30.900724 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Jul 6 23:27:30.908848 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Jul 6 23:27:30.908897 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Jul 6 23:27:30.919575 systemd[1]: dracut-cmdline.service: Deactivated successfully. Jul 6 23:27:30.919752 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Jul 6 23:27:30.937271 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jul 6 23:27:30.937326 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jul 6 23:27:30.974511 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Jul 6 23:27:30.978205 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Jul 6 23:27:30.978259 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 6 23:27:30.992268 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Jul 6 23:27:30.992316 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jul 6 23:27:31.002035 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Jul 6 23:27:31.002084 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Jul 6 23:27:31.010465 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 6 23:27:31.010512 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jul 6 23:27:31.020318 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Jul 6 23:27:31.020395 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jul 6 23:27:31.020746 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Jul 6 23:27:31.020833 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Jul 6 23:27:31.024156 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Jul 6 23:27:31.050510 systemd[1]: Starting initrd-switch-root.service - Switch Root... Jul 6 23:27:31.115009 systemd[1]: Switching root. Jul 6 23:27:31.472186 systemd-journald[205]: Journal stopped Jul 6 23:27:36.563610 systemd-journald[205]: Received SIGTERM from PID 1 (systemd). Jul 6 23:27:36.563642 kernel: SELinux: policy capability network_peer_controls=1 Jul 6 23:27:36.563655 kernel: SELinux: policy capability open_perms=1 Jul 6 23:27:36.563665 kernel: SELinux: policy capability extended_socket_class=1 Jul 6 23:27:36.563673 kernel: SELinux: policy capability always_check_network=0 Jul 6 23:27:36.563682 kernel: SELinux: policy capability cgroup_seclabel=1 Jul 6 23:27:36.563692 kernel: SELinux: policy capability nnp_nosuid_transition=1 Jul 6 23:27:36.563705 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Jul 6 23:27:36.563714 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Jul 6 23:27:36.563723 kernel: audit: type=1403 audit(1751844452.327:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Jul 6 23:27:36.563735 systemd[1]: Successfully loaded SELinux policy in 142.606ms. Jul 6 23:27:36.563746 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 15.704ms. Jul 6 23:27:36.563757 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) Jul 6 23:27:36.563768 systemd[1]: Detected virtualization microsoft. Jul 6 23:27:36.563782 systemd[1]: Detected architecture x86-64. Jul 6 23:27:36.563792 systemd[1]: Detected first boot. Jul 6 23:27:36.563804 systemd[1]: Hostname set to . Jul 6 23:27:36.563814 systemd[1]: Initializing machine ID from random generator. Jul 6 23:27:36.563825 zram_generator::config[1221]: No configuration found. Jul 6 23:27:36.563839 kernel: Guest personality initialized and is inactive Jul 6 23:27:36.563849 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Jul 6 23:27:36.563858 kernel: Initialized host personality Jul 6 23:27:36.563868 kernel: NET: Registered PF_VSOCK protocol family Jul 6 23:27:36.563878 systemd[1]: Populated /etc with preset unit settings. Jul 6 23:27:36.563890 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Jul 6 23:27:36.563900 systemd[1]: initrd-switch-root.service: Deactivated successfully. Jul 6 23:27:36.563911 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Jul 6 23:27:36.563924 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Jul 6 23:27:36.563935 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Jul 6 23:27:36.563947 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Jul 6 23:27:36.563957 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Jul 6 23:27:36.563969 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Jul 6 23:27:36.563980 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Jul 6 23:27:36.563991 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Jul 6 23:27:36.564005 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Jul 6 23:27:36.564016 systemd[1]: Created slice user.slice - User and Session Slice. Jul 6 23:27:36.564026 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 6 23:27:36.564037 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 6 23:27:36.564048 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Jul 6 23:27:36.564059 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Jul 6 23:27:36.564074 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Jul 6 23:27:36.564085 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jul 6 23:27:36.564099 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Jul 6 23:27:36.564111 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 6 23:27:36.564122 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Jul 6 23:27:36.564133 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Jul 6 23:27:36.564144 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Jul 6 23:27:36.564164 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Jul 6 23:27:36.564177 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 6 23:27:36.564189 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jul 6 23:27:36.564204 systemd[1]: Reached target slices.target - Slice Units. Jul 6 23:27:36.564215 systemd[1]: Reached target swap.target - Swaps. Jul 6 23:27:36.564228 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Jul 6 23:27:36.564239 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Jul 6 23:27:36.564250 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Jul 6 23:27:36.564264 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jul 6 23:27:36.564276 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jul 6 23:27:36.564287 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jul 6 23:27:36.564299 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Jul 6 23:27:36.564310 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Jul 6 23:27:36.564321 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Jul 6 23:27:36.564332 systemd[1]: Mounting media.mount - External Media Directory... Jul 6 23:27:36.564344 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 6 23:27:36.564358 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Jul 6 23:27:36.564369 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Jul 6 23:27:36.564389 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Jul 6 23:27:36.564403 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). Jul 6 23:27:36.564415 systemd[1]: Reached target machines.target - Containers. Jul 6 23:27:36.564433 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Jul 6 23:27:36.564447 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 6 23:27:36.564458 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jul 6 23:27:36.564486 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Jul 6 23:27:36.564511 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 6 23:27:36.564524 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jul 6 23:27:36.564544 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 6 23:27:36.564557 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Jul 6 23:27:36.564568 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 6 23:27:36.564580 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Jul 6 23:27:36.564592 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Jul 6 23:27:36.564607 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Jul 6 23:27:36.564618 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Jul 6 23:27:36.564630 systemd[1]: Stopped systemd-fsck-usr.service. Jul 6 23:27:36.564642 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). Jul 6 23:27:36.564655 systemd[1]: Starting systemd-journald.service - Journal Service... Jul 6 23:27:36.564666 kernel: loop: module loaded Jul 6 23:27:36.564677 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jul 6 23:27:36.564689 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jul 6 23:27:36.564703 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Jul 6 23:27:36.564714 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Jul 6 23:27:36.564726 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jul 6 23:27:36.564737 systemd[1]: verity-setup.service: Deactivated successfully. Jul 6 23:27:36.564749 systemd[1]: Stopped verity-setup.service. Jul 6 23:27:36.564760 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 6 23:27:36.564771 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Jul 6 23:27:36.564783 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Jul 6 23:27:36.564797 systemd[1]: Mounted media.mount - External Media Directory. Jul 6 23:27:36.564809 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Jul 6 23:27:36.564820 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Jul 6 23:27:36.564831 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Jul 6 23:27:36.564843 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Jul 6 23:27:36.564854 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jul 6 23:27:36.564866 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 6 23:27:36.564877 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 6 23:27:36.564888 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 6 23:27:36.564902 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 6 23:27:36.564914 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 6 23:27:36.564926 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 6 23:27:36.564937 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jul 6 23:27:36.564949 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jul 6 23:27:36.564960 kernel: fuse: init (API version 7.39) Jul 6 23:27:36.564971 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Jul 6 23:27:36.564982 systemd[1]: modprobe@configfs.service: Deactivated successfully. Jul 6 23:27:36.565017 systemd-journald[1328]: Collecting audit messages is disabled. Jul 6 23:27:36.565048 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Jul 6 23:27:36.565060 systemd[1]: modprobe@fuse.service: Deactivated successfully. Jul 6 23:27:36.565073 systemd-journald[1328]: Journal started Jul 6 23:27:36.565098 systemd-journald[1328]: Runtime Journal (/run/log/journal/eb267f1f03e040109a47524423d2114b) is 8M, max 2.2G, 2.1G free. Jul 6 23:27:35.685905 systemd[1]: Queued start job for default target multi-user.target. Jul 6 23:27:35.697829 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Jul 6 23:27:35.698285 systemd[1]: systemd-journald.service: Deactivated successfully. Jul 6 23:27:36.573150 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Jul 6 23:27:36.588140 systemd[1]: Started systemd-journald.service - Journal Service. Jul 6 23:27:36.592125 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jul 6 23:27:36.596974 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jul 6 23:27:36.601401 kernel: ACPI: bus type drm_connector registered Jul 6 23:27:36.605284 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 6 23:27:36.605818 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jul 6 23:27:36.610529 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Jul 6 23:27:36.623796 systemd[1]: Reached target network-pre.target - Preparation for Network. Jul 6 23:27:36.635460 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Jul 6 23:27:36.640819 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Jul 6 23:27:36.645277 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Jul 6 23:27:36.645313 systemd[1]: Reached target local-fs.target - Local File Systems. Jul 6 23:27:36.650500 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Jul 6 23:27:36.656507 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Jul 6 23:27:36.662050 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Jul 6 23:27:36.666195 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 6 23:27:36.667242 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Jul 6 23:27:36.672407 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Jul 6 23:27:36.676991 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 6 23:27:36.678107 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Jul 6 23:27:36.683197 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jul 6 23:27:36.688164 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Jul 6 23:27:36.694749 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jul 6 23:27:36.700106 systemd-tmpfiles[1337]: ACLs are not supported, ignoring. Jul 6 23:27:36.700127 systemd-tmpfiles[1337]: ACLs are not supported, ignoring. Jul 6 23:27:36.700934 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Jul 6 23:27:36.706023 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jul 6 23:27:36.711244 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Jul 6 23:27:36.716288 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Jul 6 23:27:36.721514 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Jul 6 23:27:36.729239 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Jul 6 23:27:36.743515 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Jul 6 23:27:36.749078 systemd[1]: Starting systemd-sysusers.service - Create System Users... Jul 6 23:27:36.753856 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Jul 6 23:27:36.761022 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jul 6 23:27:36.774375 udevadm[1377]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Jul 6 23:27:36.796740 systemd[1]: Finished systemd-sysusers.service - Create System Users. Jul 6 23:27:36.808906 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jul 6 23:27:36.820407 kernel: loop0: detected capacity change from 0 to 147912 Jul 6 23:27:36.827856 systemd-tmpfiles[1382]: ACLs are not supported, ignoring. Jul 6 23:27:36.827876 systemd-tmpfiles[1382]: ACLs are not supported, ignoring. Jul 6 23:27:36.832310 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 6 23:27:36.853451 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Jul 6 23:27:36.854122 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Jul 6 23:27:36.977298 systemd-journald[1328]: Time spent on flushing to /var/log/journal/eb267f1f03e040109a47524423d2114b is 13.810ms for 956 entries. Jul 6 23:27:36.977298 systemd-journald[1328]: System Journal (/var/log/journal/eb267f1f03e040109a47524423d2114b) is 8M, max 2.6G, 2.6G free. Jul 6 23:27:37.040984 systemd-journald[1328]: Received client request to flush runtime journal. Jul 6 23:27:37.042434 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Jul 6 23:27:37.527403 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jul 6 23:27:37.673409 kernel: loop1: detected capacity change from 0 to 138176 Jul 6 23:27:37.965045 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Jul 6 23:27:37.982512 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 6 23:27:38.009804 systemd-udevd[1392]: Using default interface naming scheme 'v255'. Jul 6 23:27:38.288129 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 6 23:27:38.311591 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jul 6 23:27:38.329172 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Jul 6 23:27:38.377409 kernel: hv_vmbus: registering driver hyperv_fb Jul 6 23:27:38.377492 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Jul 6 23:27:38.384481 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Jul 6 23:27:38.388521 kernel: Console: switching to colour dummy device 80x25 Jul 6 23:27:38.389433 kernel: Console: switching to colour frame buffer device 128x48 Jul 6 23:27:38.397403 kernel: mousedev: PS/2 mouse device common for all mice Jul 6 23:27:38.407401 kernel: hv_vmbus: registering driver hv_balloon Jul 6 23:27:38.407453 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Jul 6 23:27:38.410528 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Jul 6 23:27:38.423504 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 6 23:27:38.436668 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 6 23:27:38.437015 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jul 6 23:27:38.444543 kernel: EDAC MC: Ver: 3.0.0 Jul 6 23:27:38.454566 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 6 23:27:38.458978 systemd[1]: Started systemd-userdbd.service - User Database Manager. Jul 6 23:27:38.507404 kernel: loop2: detected capacity change from 0 to 28272 Jul 6 23:27:38.569975 systemd-networkd[1407]: lo: Link UP Jul 6 23:27:38.569984 systemd-networkd[1407]: lo: Gained carrier Jul 6 23:27:38.571369 systemd-networkd[1407]: Enumeration completed Jul 6 23:27:38.571473 systemd[1]: Started systemd-networkd.service - Network Configuration. Jul 6 23:27:38.571758 systemd-networkd[1407]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 6 23:27:38.571768 systemd-networkd[1407]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 6 23:27:38.572312 systemd-networkd[1407]: eth0: Link UP Jul 6 23:27:38.572321 systemd-networkd[1407]: eth0: Gained carrier Jul 6 23:27:38.572334 systemd-networkd[1407]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 6 23:27:38.586530 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Jul 6 23:27:38.591428 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Jul 6 23:27:38.620427 systemd-networkd[1407]: eth0: DHCPv4 address 10.200.8.18/24, gateway 10.200.8.1 acquired from 168.63.129.16 Jul 6 23:27:38.656583 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 37 scanned by (udev-worker) (1419) Jul 6 23:27:38.693548 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Jul 6 23:27:38.698650 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Jul 6 23:27:38.714538 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Jul 6 23:27:38.798818 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Jul 6 23:27:39.073734 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 6 23:27:39.125189 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Jul 6 23:27:39.144576 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Jul 6 23:27:39.179409 kernel: loop3: detected capacity change from 0 to 147912 Jul 6 23:27:39.194402 kernel: loop4: detected capacity change from 0 to 138176 Jul 6 23:27:39.208401 kernel: loop5: detected capacity change from 0 to 28272 Jul 6 23:27:39.213212 (sd-merge)[1531]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Jul 6 23:27:39.213739 (sd-merge)[1531]: Merged extensions into '/usr'. Jul 6 23:27:39.217371 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Jul 6 23:27:39.231647 systemd[1]: Starting ensure-sysext.service... Jul 6 23:27:39.235434 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jul 6 23:27:39.242317 systemd[1]: Reload requested from client PID 1533 ('systemctl') (unit ensure-sysext.service)... Jul 6 23:27:39.242334 systemd[1]: Reloading... Jul 6 23:27:39.262014 lvm[1530]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Jul 6 23:27:39.288412 zram_generator::config[1567]: No configuration found. Jul 6 23:27:39.313042 systemd-tmpfiles[1534]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Jul 6 23:27:39.313341 systemd-tmpfiles[1534]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Jul 6 23:27:39.314275 systemd-tmpfiles[1534]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Jul 6 23:27:39.314578 systemd-tmpfiles[1534]: ACLs are not supported, ignoring. Jul 6 23:27:39.314649 systemd-tmpfiles[1534]: ACLs are not supported, ignoring. Jul 6 23:27:39.318370 systemd-tmpfiles[1534]: Detected autofs mount point /boot during canonicalization of boot. Jul 6 23:27:39.318405 systemd-tmpfiles[1534]: Skipping /boot Jul 6 23:27:39.330618 systemd-tmpfiles[1534]: Detected autofs mount point /boot during canonicalization of boot. Jul 6 23:27:39.330633 systemd-tmpfiles[1534]: Skipping /boot Jul 6 23:27:39.603718 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. Jul 6 23:27:39.738205 systemd[1]: Reloading finished in 495 ms. Jul 6 23:27:39.757623 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Jul 6 23:27:39.782348 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 6 23:27:39.799700 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jul 6 23:27:39.804301 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 6 23:27:39.821607 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jul 6 23:27:39.826431 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Jul 6 23:27:39.831275 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 6 23:27:39.832458 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Jul 6 23:27:39.840086 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 6 23:27:39.844269 lvm[1630]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Jul 6 23:27:39.845249 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 6 23:27:39.850764 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 6 23:27:39.854908 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 6 23:27:39.855027 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). Jul 6 23:27:39.856239 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Jul 6 23:27:39.862578 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jul 6 23:27:39.868520 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Jul 6 23:27:39.873241 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 6 23:27:39.875692 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 6 23:27:39.875911 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 6 23:27:39.877850 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 6 23:27:39.878038 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 6 23:27:39.885865 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 6 23:27:39.886042 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 6 23:27:39.905681 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Jul 6 23:27:39.915564 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Jul 6 23:27:39.924204 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 6 23:27:39.924517 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 6 23:27:39.935589 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 6 23:27:39.940789 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jul 6 23:27:39.945785 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 6 23:27:39.951648 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 6 23:27:39.955735 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 6 23:27:39.955862 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). Jul 6 23:27:39.956044 systemd[1]: Reached target time-set.target - System Time Set. Jul 6 23:27:39.960269 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 6 23:27:39.962019 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 6 23:27:39.962200 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 6 23:27:39.967332 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 6 23:27:39.967526 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jul 6 23:27:39.972146 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 6 23:27:39.972320 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 6 23:27:39.977759 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 6 23:27:39.977934 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 6 23:27:39.985148 systemd[1]: Finished ensure-sysext.service. Jul 6 23:27:39.991473 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 6 23:27:39.991535 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jul 6 23:27:40.016137 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Jul 6 23:27:40.061551 systemd-resolved[1636]: Positive Trust Anchors: Jul 6 23:27:40.061567 systemd-resolved[1636]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 6 23:27:40.061598 systemd-resolved[1636]: 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 Jul 6 23:27:40.075734 systemd-resolved[1636]: Using system hostname 'ci-4230.2.1-a-04307b7828'. Jul 6 23:27:40.077613 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jul 6 23:27:40.082401 systemd[1]: Reached target network.target - Network. Jul 6 23:27:40.086014 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jul 6 23:27:40.177502 systemd-networkd[1407]: eth0: Gained IPv6LL Jul 6 23:27:40.179694 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Jul 6 23:27:40.184980 systemd[1]: Reached target network-online.target - Network is Online. Jul 6 23:27:40.310533 augenrules[1673]: No rules Jul 6 23:27:40.311687 systemd[1]: audit-rules.service: Deactivated successfully. Jul 6 23:27:40.311987 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jul 6 23:27:40.682279 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Jul 6 23:27:40.687753 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). Jul 6 23:27:45.259549 ldconfig[1360]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jul 6 23:27:45.271680 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Jul 6 23:27:45.288909 systemd[1]: Starting systemd-update-done.service - Update is Completed... Jul 6 23:27:45.298149 systemd[1]: Finished systemd-update-done.service - Update is Completed. Jul 6 23:27:45.302894 systemd[1]: Reached target sysinit.target - System Initialization. Jul 6 23:27:45.307362 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Jul 6 23:27:45.312596 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Jul 6 23:27:45.317884 systemd[1]: Started logrotate.timer - Daily rotation of log files. Jul 6 23:27:45.322204 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Jul 6 23:27:45.327393 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Jul 6 23:27:45.332365 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Jul 6 23:27:45.332431 systemd[1]: Reached target paths.target - Path Units. Jul 6 23:27:45.336090 systemd[1]: Reached target timers.target - Timer Units. Jul 6 23:27:45.340872 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Jul 6 23:27:45.346669 systemd[1]: Starting docker.socket - Docker Socket for the API... Jul 6 23:27:45.353081 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Jul 6 23:27:45.358203 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Jul 6 23:27:45.363302 systemd[1]: Reached target ssh-access.target - SSH Access Available. Jul 6 23:27:45.369508 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Jul 6 23:27:45.373921 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Jul 6 23:27:45.379284 systemd[1]: Listening on docker.socket - Docker Socket for the API. Jul 6 23:27:45.383677 systemd[1]: Reached target sockets.target - Socket Units. Jul 6 23:27:45.387555 systemd[1]: Reached target basic.target - Basic System. Jul 6 23:27:45.391265 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Jul 6 23:27:45.391297 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Jul 6 23:27:45.406468 systemd[1]: Starting chronyd.service - NTP client/server... Jul 6 23:27:45.410812 systemd[1]: Starting containerd.service - containerd container runtime... Jul 6 23:27:45.419231 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Jul 6 23:27:45.424269 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Jul 6 23:27:45.428734 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Jul 6 23:27:45.433690 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Jul 6 23:27:45.437696 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). Jul 6 23:27:45.437738 systemd[1]: hv_fcopy_daemon.service - Hyper-V FCOPY daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_fcopy). Jul 6 23:27:45.438724 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Jul 6 23:27:45.440019 jq[1692]: false Jul 6 23:27:45.442932 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Jul 6 23:27:45.443957 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Jul 6 23:27:45.445611 KVP[1694]: KVP starting; pid is:1694 Jul 6 23:27:45.448426 KVP[1694]: KVP LIC Version: 3.1 Jul 6 23:27:45.450395 kernel: hv_utils: KVP IC version 4.0 Jul 6 23:27:45.450855 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Jul 6 23:27:45.452087 (chronyd)[1685]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Jul 6 23:27:45.456470 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Jul 6 23:27:45.461662 chronyd[1699]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) Jul 6 23:27:45.461743 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Jul 6 23:27:45.468325 systemd[1]: Starting systemd-logind.service - User Login Management... Jul 6 23:27:45.473210 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Jul 6 23:27:45.473649 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Jul 6 23:27:45.474435 systemd[1]: Starting update-engine.service - Update Engine... Jul 6 23:27:45.480992 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Jul 6 23:27:45.487410 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Jul 6 23:27:45.487646 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Jul 6 23:27:45.489082 jq[1703]: true Jul 6 23:27:45.497753 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Jul 6 23:27:45.497975 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Jul 6 23:27:45.501300 jq[1708]: true Jul 6 23:27:45.525534 chronyd[1699]: Timezone right/UTC failed leap second check, ignoring Jul 6 23:27:45.525710 chronyd[1699]: Loaded seccomp filter (level 2) Jul 6 23:27:45.536755 (ntainerd)[1734]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Jul 6 23:27:45.536844 systemd[1]: Started chronyd.service - NTP client/server. Jul 6 23:27:45.541125 systemd[1]: motdgen.service: Deactivated successfully. Jul 6 23:27:45.541352 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Jul 6 23:27:45.590428 extend-filesystems[1693]: Found loop3 Jul 6 23:27:45.593298 extend-filesystems[1693]: Found loop4 Jul 6 23:27:45.593298 extend-filesystems[1693]: Found loop5 Jul 6 23:27:45.593298 extend-filesystems[1693]: Found sda Jul 6 23:27:45.593298 extend-filesystems[1693]: Found sda1 Jul 6 23:27:45.593298 extend-filesystems[1693]: Found sda2 Jul 6 23:27:45.593298 extend-filesystems[1693]: Found sda3 Jul 6 23:27:45.593298 extend-filesystems[1693]: Found usr Jul 6 23:27:45.593298 extend-filesystems[1693]: Found sda4 Jul 6 23:27:45.593298 extend-filesystems[1693]: Found sda6 Jul 6 23:27:45.593298 extend-filesystems[1693]: Found sda7 Jul 6 23:27:45.593298 extend-filesystems[1693]: Found sda9 Jul 6 23:27:45.593298 extend-filesystems[1693]: Checking size of /dev/sda9 Jul 6 23:27:45.633059 update_engine[1702]: I20250706 23:27:45.632982 1702 main.cc:92] Flatcar Update Engine starting Jul 6 23:27:45.659050 dbus-daemon[1688]: [system] SELinux support is enabled Jul 6 23:27:45.659252 systemd[1]: Started dbus.service - D-Bus System Message Bus. Jul 6 23:27:45.663367 update_engine[1702]: I20250706 23:27:45.661047 1702 update_check_scheduler.cc:74] Next update check in 2m37s Jul 6 23:27:45.666104 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). Jul 6 23:27:45.666141 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Jul 6 23:27:45.667924 systemd-logind[1701]: New seat seat0. Jul 6 23:27:45.671605 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). Jul 6 23:27:45.671630 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Jul 6 23:27:45.672491 systemd-logind[1701]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Jul 6 23:27:45.676710 systemd[1]: Started systemd-logind.service - User Login Management. Jul 6 23:27:45.681165 systemd[1]: Started update-engine.service - Update Engine. Jul 6 23:27:45.693582 extend-filesystems[1693]: Old size kept for /dev/sda9 Jul 6 23:27:45.696986 extend-filesystems[1693]: Found sdb Jul 6 23:27:45.696986 extend-filesystems[1693]: Found sdb1 Jul 6 23:27:45.696986 extend-filesystems[1693]: Found sr0 Jul 6 23:27:45.704739 systemd[1]: Started locksmithd.service - Cluster reboot manager. Jul 6 23:27:45.709957 systemd[1]: extend-filesystems.service: Deactivated successfully. Jul 6 23:27:45.710178 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Jul 6 23:27:45.740475 setup-nvidia[1696]: Downloading Flatcar Container Linux Developer Container for version: 4230.2.1 Jul 6 23:27:45.747152 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 37 scanned by (udev-worker) (1749) Jul 6 23:27:45.748522 coreos-metadata[1687]: Jul 06 23:27:45.748 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Jul 6 23:27:45.752808 coreos-metadata[1687]: Jul 06 23:27:45.751 INFO Fetch successful Jul 6 23:27:45.752808 coreos-metadata[1687]: Jul 06 23:27:45.751 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Jul 6 23:27:45.755978 coreos-metadata[1687]: Jul 06 23:27:45.755 INFO Fetch successful Jul 6 23:27:45.756289 coreos-metadata[1687]: Jul 06 23:27:45.756 INFO Fetching http://168.63.129.16/machine/196271a0-2e9a-4a67-9656-5b69a4bd352e/caa0c3cb%2D6141%2D47bf%2D9503%2D3039f1ea16db.%5Fci%2D4230.2.1%2Da%2D04307b7828?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Jul 6 23:27:45.786318 bash[1732]: Updated "/home/core/.ssh/authorized_keys" Jul 6 23:27:45.787872 coreos-metadata[1687]: Jul 06 23:27:45.787 INFO Fetch successful Jul 6 23:27:45.788076 coreos-metadata[1687]: Jul 06 23:27:45.788 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Jul 6 23:27:45.788432 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Jul 6 23:27:45.795958 coreos-metadata[1687]: Jul 06 23:27:45.795 INFO Fetch successful Jul 6 23:27:45.820825 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Jul 6 23:27:45.825100 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Jul 6 23:27:45.834134 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Jul 6 23:27:45.931785 sshd_keygen[1715]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Jul 6 23:27:45.953010 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Jul 6 23:27:45.971614 systemd[1]: Starting issuegen.service - Generate /run/issue... Jul 6 23:27:45.976307 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Jul 6 23:27:45.980928 systemd[1]: issuegen.service: Deactivated successfully. Jul 6 23:27:45.981129 systemd[1]: Finished issuegen.service - Generate /run/issue. Jul 6 23:27:45.987544 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Jul 6 23:27:46.000838 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Jul 6 23:27:46.006590 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Jul 6 23:27:46.013132 systemd[1]: Started getty@tty1.service - Getty on tty1. Jul 6 23:27:46.018096 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Jul 6 23:27:46.022880 systemd[1]: Reached target getty.target - Login Prompts. Jul 6 23:27:46.059795 locksmithd[1756]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Jul 6 23:27:46.393922 setup-nvidia[1849]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 6 23:27:46.394282 setup-nvidia[1849]: Dload Upload Total Spent Left Speed Jul 6 23:27:47.010068 containerd[1734]: time="2025-07-06T23:27:47.009984300Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Jul 6 23:27:47.027963 containerd[1734]: time="2025-07-06T23:27:47.027935500Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Jul 6 23:27:47.029552 containerd[1734]: time="2025-07-06T23:27:47.029512200Z" 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.95-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Jul 6 23:27:47.029552 containerd[1734]: time="2025-07-06T23:27:47.029541600Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Jul 6 23:27:47.029644 containerd[1734]: time="2025-07-06T23:27:47.029556900Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Jul 6 23:27:47.029717 containerd[1734]: time="2025-07-06T23:27:47.029694300Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Jul 6 23:27:47.029746 containerd[1734]: time="2025-07-06T23:27:47.029717300Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Jul 6 23:27:47.029802 containerd[1734]: time="2025-07-06T23:27:47.029780900Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Jul 6 23:27:47.029838 containerd[1734]: time="2025-07-06T23:27:47.029800300Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Jul 6 23:27:47.030007 containerd[1734]: time="2025-07-06T23:27:47.029982300Z" 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 Jul 6 23:27:47.030037 containerd[1734]: time="2025-07-06T23:27:47.030005200Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Jul 6 23:27:47.030037 containerd[1734]: time="2025-07-06T23:27:47.030018800Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Jul 6 23:27:47.030037 containerd[1734]: time="2025-07-06T23:27:47.030028300Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Jul 6 23:27:47.030136 containerd[1734]: time="2025-07-06T23:27:47.030117000Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Jul 6 23:27:47.030363 containerd[1734]: time="2025-07-06T23:27:47.030342800Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Jul 6 23:27:47.030513 containerd[1734]: time="2025-07-06T23:27:47.030491800Z" 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 Jul 6 23:27:47.030543 containerd[1734]: time="2025-07-06T23:27:47.030512800Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Jul 6 23:27:47.030615 containerd[1734]: time="2025-07-06T23:27:47.030595400Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Jul 6 23:27:47.030674 containerd[1734]: time="2025-07-06T23:27:47.030655700Z" level=info msg="metadata content store policy set" policy=shared Jul 6 23:27:47.055077 containerd[1734]: time="2025-07-06T23:27:47.055044500Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Jul 6 23:27:47.055135 containerd[1734]: time="2025-07-06T23:27:47.055092000Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Jul 6 23:27:47.055135 containerd[1734]: time="2025-07-06T23:27:47.055108000Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Jul 6 23:27:47.055135 containerd[1734]: time="2025-07-06T23:27:47.055122600Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Jul 6 23:27:47.055199 containerd[1734]: time="2025-07-06T23:27:47.055135300Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Jul 6 23:27:47.055279 containerd[1734]: time="2025-07-06T23:27:47.055254300Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Jul 6 23:27:47.055492 containerd[1734]: time="2025-07-06T23:27:47.055469100Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Jul 6 23:27:47.055597 containerd[1734]: time="2025-07-06T23:27:47.055575100Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Jul 6 23:27:47.055625 containerd[1734]: time="2025-07-06T23:27:47.055597400Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Jul 6 23:27:47.055625 containerd[1734]: time="2025-07-06T23:27:47.055612300Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Jul 6 23:27:47.055665 containerd[1734]: time="2025-07-06T23:27:47.055624100Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Jul 6 23:27:47.055665 containerd[1734]: time="2025-07-06T23:27:47.055635800Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Jul 6 23:27:47.055665 containerd[1734]: time="2025-07-06T23:27:47.055646600Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Jul 6 23:27:47.055665 containerd[1734]: time="2025-07-06T23:27:47.055657900Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Jul 6 23:27:47.055746 containerd[1734]: time="2025-07-06T23:27:47.055670800Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Jul 6 23:27:47.055746 containerd[1734]: time="2025-07-06T23:27:47.055683900Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Jul 6 23:27:47.055746 containerd[1734]: time="2025-07-06T23:27:47.055695300Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Jul 6 23:27:47.055746 containerd[1734]: time="2025-07-06T23:27:47.055705200Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Jul 6 23:27:47.055746 containerd[1734]: time="2025-07-06T23:27:47.055721900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Jul 6 23:27:47.055746 containerd[1734]: time="2025-07-06T23:27:47.055734400Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Jul 6 23:27:47.055746 containerd[1734]: time="2025-07-06T23:27:47.055745200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Jul 6 23:27:47.055888 containerd[1734]: time="2025-07-06T23:27:47.055756600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Jul 6 23:27:47.055888 containerd[1734]: time="2025-07-06T23:27:47.055767400Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Jul 6 23:27:47.055888 containerd[1734]: time="2025-07-06T23:27:47.055778600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Jul 6 23:27:47.055888 containerd[1734]: time="2025-07-06T23:27:47.055789300Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Jul 6 23:27:47.055888 containerd[1734]: time="2025-07-06T23:27:47.055800000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Jul 6 23:27:47.055888 containerd[1734]: time="2025-07-06T23:27:47.055811400Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Jul 6 23:27:47.055888 containerd[1734]: time="2025-07-06T23:27:47.055823900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Jul 6 23:27:47.055888 containerd[1734]: time="2025-07-06T23:27:47.055848000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Jul 6 23:27:47.055888 containerd[1734]: time="2025-07-06T23:27:47.055863200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Jul 6 23:27:47.055888 containerd[1734]: time="2025-07-06T23:27:47.055874300Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Jul 6 23:27:47.055888 containerd[1734]: time="2025-07-06T23:27:47.055886300Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Jul 6 23:27:47.056099 containerd[1734]: time="2025-07-06T23:27:47.055905000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Jul 6 23:27:47.056099 containerd[1734]: time="2025-07-06T23:27:47.055917200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Jul 6 23:27:47.056099 containerd[1734]: time="2025-07-06T23:27:47.055926700Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Jul 6 23:27:47.056099 containerd[1734]: time="2025-07-06T23:27:47.055971100Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Jul 6 23:27:47.056099 containerd[1734]: time="2025-07-06T23:27:47.055987800Z" 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 Jul 6 23:27:47.056099 containerd[1734]: time="2025-07-06T23:27:47.055997100Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Jul 6 23:27:47.056099 containerd[1734]: time="2025-07-06T23:27:47.056007600Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Jul 6 23:27:47.056099 containerd[1734]: time="2025-07-06T23:27:47.056016500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Jul 6 23:27:47.056099 containerd[1734]: time="2025-07-06T23:27:47.056027000Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Jul 6 23:27:47.056099 containerd[1734]: time="2025-07-06T23:27:47.056036000Z" level=info msg="NRI interface is disabled by configuration." Jul 6 23:27:47.056099 containerd[1734]: time="2025-07-06T23:27:47.056045500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Jul 6 23:27:47.056436 containerd[1734]: time="2025-07-06T23:27:47.056285000Z" 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}" Jul 6 23:27:47.056436 containerd[1734]: time="2025-07-06T23:27:47.056379000Z" level=info msg="Connect containerd service" Jul 6 23:27:47.056436 containerd[1734]: time="2025-07-06T23:27:47.056438400Z" level=info msg="using legacy CRI server" Jul 6 23:27:47.056621 containerd[1734]: time="2025-07-06T23:27:47.056449100Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Jul 6 23:27:47.056621 containerd[1734]: time="2025-07-06T23:27:47.056541900Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Jul 6 23:27:47.057153 containerd[1734]: time="2025-07-06T23:27:47.057121800Z" 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" Jul 6 23:27:47.057326 containerd[1734]: time="2025-07-06T23:27:47.057284000Z" level=info msg="Start subscribing containerd event" Jul 6 23:27:47.057355 containerd[1734]: time="2025-07-06T23:27:47.057337100Z" level=info msg="Start recovering state" Jul 6 23:27:47.057465 containerd[1734]: time="2025-07-06T23:27:47.057442500Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jul 6 23:27:47.060652 containerd[1734]: time="2025-07-06T23:27:47.060621500Z" level=info msg="Start event monitor" Jul 6 23:27:47.060702 containerd[1734]: time="2025-07-06T23:27:47.060655200Z" level=info msg="Start snapshots syncer" Jul 6 23:27:47.060702 containerd[1734]: time="2025-07-06T23:27:47.060658900Z" level=info msg=serving... address=/run/containerd/containerd.sock Jul 6 23:27:47.060702 containerd[1734]: time="2025-07-06T23:27:47.060673900Z" level=info msg="Start cni network conf syncer for default" Jul 6 23:27:47.060773 containerd[1734]: time="2025-07-06T23:27:47.060709000Z" level=info msg="Start streaming server" Jul 6 23:27:47.060795 containerd[1734]: time="2025-07-06T23:27:47.060777500Z" level=info msg="containerd successfully booted in 0.052272s" Jul 6 23:27:47.060866 systemd[1]: Started containerd.service - containerd container runtime. Jul 6 23:27:48.295314 waagent[1841]: 2025-07-06T23:27:48.295214Z INFO Daemon Daemon Azure Linux Agent Version: 2.9.1.1 Jul 6 23:27:48.299573 waagent[1841]: 2025-07-06T23:27:48.299508Z INFO Daemon Daemon OS: flatcar 4230.2.1 Jul 6 23:27:48.302798 waagent[1841]: 2025-07-06T23:27:48.302739Z INFO Daemon Daemon Python: 3.11.11 Jul 6 23:27:48.306208 waagent[1841]: 2025-07-06T23:27:48.306155Z INFO Daemon Daemon Run daemon Jul 6 23:27:48.309202 waagent[1841]: 2025-07-06T23:27:48.309151Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4230.2.1' Jul 6 23:27:48.315757 waagent[1841]: 2025-07-06T23:27:48.315619Z INFO Daemon Daemon Using waagent for provisioning Jul 6 23:27:48.320066 waagent[1841]: 2025-07-06T23:27:48.320011Z INFO Daemon Daemon Activate resource disk Jul 6 23:27:48.323628 waagent[1841]: 2025-07-06T23:27:48.323575Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Jul 6 23:27:48.331102 waagent[1841]: 2025-07-06T23:27:48.331042Z INFO Daemon Daemon Found device: sdb Jul 6 23:27:48.340153 waagent[1841]: 2025-07-06T23:27:48.340095Z INFO Daemon Daemon Examining partition table Jul 6 23:27:48.405746 waagent[1841]: 2025-07-06T23:27:48.405665Z INFO Daemon Daemon GPT not detected, determining filesystem Jul 6 23:27:48.458100 waagent[1841]: 2025-07-06T23:27:48.458015Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Jul 6 23:27:48.462640 waagent[1841]: 2025-07-06T23:27:48.462578Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Jul 6 23:27:48.976405 kernel: sdb: sdb1 Jul 6 23:27:48.979411 kernel: sdb: sdb1 Jul 6 23:27:48.993562 waagent[1841]: 2025-07-06T23:27:48.993472Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Jul 6 23:27:49.007869 waagent[1841]: 2025-07-06T23:27:49.007816Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Jul 6 23:27:49.012164 kernel: sdb: sdb1 Jul 6 23:27:53.581532 login[1844]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Jul 6 23:27:53.645329 login[1843]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Jul 6 23:27:53.656279 systemd-logind[1701]: New session 1 of user core. Jul 6 23:27:53.657350 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Jul 6 23:27:53.669587 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Jul 6 23:27:53.698582 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Jul 6 23:27:53.713607 systemd[1]: Starting user@500.service - User Manager for UID 500... Jul 6 23:27:53.735934 (systemd)[1938]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Jul 6 23:27:53.738125 systemd-logind[1701]: New session c1 of user core. Jul 6 23:27:54.494396 systemd[1938]: Queued start job for default target default.target. Jul 6 23:27:54.510466 systemd[1938]: Created slice app.slice - User Application Slice. Jul 6 23:27:54.510495 systemd[1938]: Reached target paths.target - Paths. Jul 6 23:27:54.510544 systemd[1938]: Reached target timers.target - Timers. Jul 6 23:27:54.511838 systemd[1938]: Starting dbus.socket - D-Bus User Message Bus Socket... Jul 6 23:27:54.522236 systemd[1938]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jul 6 23:27:54.522295 systemd[1938]: Reached target sockets.target - Sockets. Jul 6 23:27:54.522340 systemd[1938]: Reached target basic.target - Basic System. Jul 6 23:27:54.522400 systemd[1938]: Reached target default.target - Main User Target. Jul 6 23:27:54.522436 systemd[1938]: Startup finished in 777ms. Jul 6 23:27:54.522528 systemd[1]: Started user@500.service - User Manager for UID 500. Jul 6 23:27:54.524509 systemd[1]: Started session-1.scope - Session 1 of User core. Jul 6 23:27:54.559092 waagent[1841]: 2025-07-06T23:27:54.559008Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Jul 6 23:27:54.578412 kernel: EXT4-fs (sdb1): mounted filesystem c77c483b-2f6d-4f04-a077-b5d546c25fcc r/w with ordered data mode. Quota mode: none. Jul 6 23:27:54.579823 waagent[1841]: 2025-07-06T23:27:54.579765Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Jul 6 23:27:54.584095 waagent[1841]: 2025-07-06T23:27:54.582214Z INFO Daemon Daemon Clean protocol and wireserver endpoint Jul 6 23:27:54.584095 waagent[1841]: 2025-07-06T23:27:54.583219Z INFO Daemon Daemon Running default provisioning handler Jul 6 23:27:54.583325 login[1844]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Jul 6 23:27:54.590966 waagent[1841]: 2025-07-06T23:27:54.589852Z 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. Jul 6 23:27:54.591773 systemd-logind[1701]: New session 2 of user core. Jul 6 23:27:54.591865 waagent[1841]: 2025-07-06T23:27:54.591805Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Jul 6 23:27:54.592176 waagent[1841]: 2025-07-06T23:27:54.592134Z INFO Daemon Daemon cloud-init is enabled: False Jul 6 23:27:54.593663 waagent[1841]: 2025-07-06T23:27:54.593623Z INFO Daemon Daemon Copying ovf-env.xml Jul 6 23:27:54.626603 systemd[1]: Started session-2.scope - Session 2 of User core. Jul 6 23:27:54.733664 waagent[1841]: 2025-07-06T23:27:54.733573Z INFO Daemon Daemon Successfully mounted dvd Jul 6 23:27:54.757168 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Jul 6 23:27:54.757496 waagent[1841]: 2025-07-06T23:27:54.757188Z INFO Daemon Daemon Detect protocol endpoint Jul 6 23:27:54.758850 waagent[1841]: 2025-07-06T23:27:54.758801Z INFO Daemon Daemon Clean protocol and wireserver endpoint Jul 6 23:27:54.760283 waagent[1841]: 2025-07-06T23:27:54.760240Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Jul 6 23:27:54.761721 waagent[1841]: 2025-07-06T23:27:54.761675Z INFO Daemon Daemon Test for route to 168.63.129.16 Jul 6 23:27:54.763770 waagent[1841]: 2025-07-06T23:27:54.763371Z INFO Daemon Daemon Route to 168.63.129.16 exists Jul 6 23:27:54.765053 waagent[1841]: 2025-07-06T23:27:54.764996Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Jul 6 23:27:54.799303 waagent[1841]: 2025-07-06T23:27:54.799255Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Jul 6 23:27:54.801100 waagent[1841]: 2025-07-06T23:27:54.801057Z INFO Daemon Daemon Wire protocol version:2012-11-30 Jul 6 23:27:54.802395 waagent[1841]: 2025-07-06T23:27:54.802349Z INFO Daemon Daemon Server preferred version:2015-04-05 Jul 6 23:27:55.033330 waagent[1841]: 2025-07-06T23:27:55.033178Z INFO Daemon Daemon Initializing goal state during protocol detection Jul 6 23:27:55.035742 waagent[1841]: 2025-07-06T23:27:55.035688Z INFO Daemon Daemon Forcing an update of the goal state. Jul 6 23:27:55.040285 waagent[1841]: 2025-07-06T23:27:55.040230Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Jul 6 23:27:55.090757 waagent[1841]: 2025-07-06T23:27:55.090689Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.175 Jul 6 23:27:55.092763 waagent[1841]: 2025-07-06T23:27:55.092709Z INFO Daemon Jul 6 23:27:55.092978 waagent[1841]: 2025-07-06T23:27:55.092934Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 14ddb757-5ab9-4b44-9770-897f3153cba3 eTag: 10709795792831091579 source: Fabric] Jul 6 23:27:55.094692 waagent[1841]: 2025-07-06T23:27:55.094639Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Jul 6 23:27:55.095696 waagent[1841]: 2025-07-06T23:27:55.095647Z INFO Daemon Jul 6 23:27:55.095837 waagent[1841]: 2025-07-06T23:27:55.095796Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Jul 6 23:27:55.100985 waagent[1841]: 2025-07-06T23:27:55.100938Z INFO Daemon Daemon Downloading artifacts profile blob Jul 6 23:27:55.171816 waagent[1841]: 2025-07-06T23:27:55.171754Z INFO Daemon Downloaded certificate {'thumbprint': 'E6577AE15D04225F2B535E63DE8719AB1B2A057B', 'hasPrivateKey': True} Jul 6 23:27:55.173869 waagent[1841]: 2025-07-06T23:27:55.173807Z INFO Daemon Fetch goal state completed Jul 6 23:27:55.180156 waagent[1841]: 2025-07-06T23:27:55.180106Z INFO Daemon Daemon Starting provisioning Jul 6 23:27:55.180327 waagent[1841]: 2025-07-06T23:27:55.180283Z INFO Daemon Daemon Handle ovf-env.xml. Jul 6 23:27:55.181817 waagent[1841]: 2025-07-06T23:27:55.181760Z INFO Daemon Daemon Set hostname [ci-4230.2.1-a-04307b7828] Jul 6 23:27:55.184866 waagent[1841]: 2025-07-06T23:27:55.184816Z INFO Daemon Daemon Publish hostname [ci-4230.2.1-a-04307b7828] Jul 6 23:27:55.186682 waagent[1841]: 2025-07-06T23:27:55.186628Z INFO Daemon Daemon Examine /proc/net/route for primary interface Jul 6 23:27:55.187952 waagent[1841]: 2025-07-06T23:27:55.187858Z INFO Daemon Daemon Primary interface is [eth0] Jul 6 23:27:55.213880 systemd-networkd[1407]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 6 23:27:55.213891 systemd-networkd[1407]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 6 23:27:55.213916 systemd-networkd[1407]: eth0: DHCP lease lost Jul 6 23:27:55.214426 waagent[1841]: 2025-07-06T23:27:55.214328Z INFO Daemon Daemon Create user account if not exists Jul 6 23:27:55.216081 waagent[1841]: 2025-07-06T23:27:55.216030Z INFO Daemon Daemon User core already exists, skip useradd Jul 6 23:27:55.217534 waagent[1841]: 2025-07-06T23:27:55.217491Z INFO Daemon Daemon Configure sudoer Jul 6 23:27:55.218605 waagent[1841]: 2025-07-06T23:27:55.218556Z INFO Daemon Daemon Configure sshd Jul 6 23:27:55.219208 waagent[1841]: 2025-07-06T23:27:55.219162Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Jul 6 23:27:55.220464 waagent[1841]: 2025-07-06T23:27:55.220422Z INFO Daemon Daemon Deploy ssh public key. Jul 6 23:27:55.279419 systemd-networkd[1407]: eth0: DHCPv4 address 10.200.8.18/24, gateway 10.200.8.1 acquired from 168.63.129.16 Jul 6 23:27:56.440695 waagent[1841]: 2025-07-06T23:27:56.440634Z INFO Daemon Daemon Provisioning complete Jul 6 23:27:56.451476 waagent[1841]: 2025-07-06T23:27:56.451424Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Jul 6 23:27:56.453968 waagent[1841]: 2025-07-06T23:27:56.453141Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Jul 6 23:27:56.454949 waagent[1841]: 2025-07-06T23:27:56.454610Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.9.1.1 is the most current agent Jul 6 23:27:56.596452 waagent[1998]: 2025-07-06T23:27:56.596345Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.9.1.1) Jul 6 23:27:56.596772 waagent[1998]: 2025-07-06T23:27:56.596509Z INFO ExtHandler ExtHandler OS: flatcar 4230.2.1 Jul 6 23:27:56.596772 waagent[1998]: 2025-07-06T23:27:56.596591Z INFO ExtHandler ExtHandler Python: 3.11.11 Jul 6 23:27:56.663829 waagent[1998]: 2025-07-06T23:27:56.663747Z INFO ExtHandler ExtHandler Distro: flatcar-4230.2.1; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.11; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; Jul 6 23:27:56.664026 waagent[1998]: 2025-07-06T23:27:56.663981Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jul 6 23:27:56.664110 waagent[1998]: 2025-07-06T23:27:56.664070Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Jul 6 23:27:56.671864 waagent[1998]: 2025-07-06T23:27:56.671801Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Jul 6 23:27:56.676705 waagent[1998]: 2025-07-06T23:27:56.676649Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.175 Jul 6 23:27:56.677148 waagent[1998]: 2025-07-06T23:27:56.677102Z INFO ExtHandler Jul 6 23:27:56.677233 waagent[1998]: 2025-07-06T23:27:56.677195Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 21df5f1d-ed6d-4edd-8aac-c1ac5fbf5553 eTag: 10709795792831091579 source: Fabric] Jul 6 23:27:56.677597 waagent[1998]: 2025-07-06T23:27:56.677551Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Jul 6 23:27:56.678261 waagent[1998]: 2025-07-06T23:27:56.678212Z INFO ExtHandler Jul 6 23:27:56.678435 waagent[1998]: 2025-07-06T23:27:56.678303Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Jul 6 23:27:56.681698 waagent[1998]: 2025-07-06T23:27:56.681661Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Jul 6 23:27:56.739359 waagent[1998]: 2025-07-06T23:27:56.739262Z INFO ExtHandler Downloaded certificate {'thumbprint': 'E6577AE15D04225F2B535E63DE8719AB1B2A057B', 'hasPrivateKey': True} Jul 6 23:27:56.739847 waagent[1998]: 2025-07-06T23:27:56.739798Z INFO ExtHandler Fetch goal state completed Jul 6 23:27:56.750505 waagent[1998]: 2025-07-06T23:27:56.750455Z INFO ExtHandler ExtHandler WALinuxAgent-2.9.1.1 running as process 1998 Jul 6 23:27:56.750653 waagent[1998]: 2025-07-06T23:27:56.750612Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Jul 6 23:27:56.752428 waagent[1998]: 2025-07-06T23:27:56.752361Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '4230.2.1', '', 'Flatcar Container Linux by Kinvolk'] Jul 6 23:27:56.752803 waagent[1998]: 2025-07-06T23:27:56.752760Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Jul 6 23:27:56.793212 waagent[1998]: 2025-07-06T23:27:56.793170Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Jul 6 23:27:56.793391 waagent[1998]: 2025-07-06T23:27:56.793346Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Jul 6 23:27:56.799795 waagent[1998]: 2025-07-06T23:27:56.799761Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Jul 6 23:27:56.807212 systemd[1]: Reload requested from client PID 2011 ('systemctl') (unit waagent.service)... Jul 6 23:27:56.807227 systemd[1]: Reloading... Jul 6 23:27:56.868409 zram_generator::config[2051]: No configuration found. Jul 6 23:27:57.001844 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. Jul 6 23:27:57.149577 systemd[1]: Reloading finished in 341 ms. Jul 6 23:27:57.164928 waagent[1998]: 2025-07-06T23:27:57.164848Z INFO ExtHandler ExtHandler Executing systemctl daemon-reload for setting up waagent-network-setup.service Jul 6 23:27:57.170815 systemd[1]: Reload requested from client PID 2108 ('systemctl') (unit waagent.service)... Jul 6 23:27:57.170833 systemd[1]: Reloading... Jul 6 23:27:57.230657 zram_generator::config[2148]: No configuration found. Jul 6 23:27:57.366828 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. Jul 6 23:27:57.512074 systemd[1]: Reloading finished in 340 ms. Jul 6 23:27:57.526043 waagent[1998]: 2025-07-06T23:27:57.525958Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Jul 6 23:27:57.526157 waagent[1998]: 2025-07-06T23:27:57.526114Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Jul 6 23:27:57.932074 waagent[1998]: 2025-07-06T23:27:57.931987Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Jul 6 23:27:57.932665 waagent[1998]: 2025-07-06T23:27:57.932606Z 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] Jul 6 23:27:57.933572 waagent[1998]: 2025-07-06T23:27:57.933523Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jul 6 23:27:57.933678 waagent[1998]: 2025-07-06T23:27:57.933639Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Jul 6 23:27:57.933903 waagent[1998]: 2025-07-06T23:27:57.933855Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Jul 6 23:27:57.934142 waagent[1998]: 2025-07-06T23:27:57.934082Z INFO ExtHandler ExtHandler Starting env monitor service. Jul 6 23:27:57.934197 waagent[1998]: 2025-07-06T23:27:57.934148Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Jul 6 23:27:57.934197 waagent[1998]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Jul 6 23:27:57.934197 waagent[1998]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Jul 6 23:27:57.934197 waagent[1998]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Jul 6 23:27:57.934197 waagent[1998]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Jul 6 23:27:57.934197 waagent[1998]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jul 6 23:27:57.934197 waagent[1998]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jul 6 23:27:57.934870 waagent[1998]: 2025-07-06T23:27:57.934821Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Jul 6 23:27:57.935126 waagent[1998]: 2025-07-06T23:27:57.935068Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jul 6 23:27:57.935328 waagent[1998]: 2025-07-06T23:27:57.935266Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Jul 6 23:27:57.935411 waagent[1998]: 2025-07-06T23:27:57.935353Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Jul 6 23:27:57.935688 waagent[1998]: 2025-07-06T23:27:57.935642Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Jul 6 23:27:57.935990 waagent[1998]: 2025-07-06T23:27:57.935919Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Jul 6 23:27:57.936106 waagent[1998]: 2025-07-06T23:27:57.936058Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Jul 6 23:27:57.936248 waagent[1998]: 2025-07-06T23:27:57.936197Z INFO EnvHandler ExtHandler Configure routes Jul 6 23:27:57.936572 waagent[1998]: 2025-07-06T23:27:57.936526Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Jul 6 23:27:57.936614 waagent[1998]: 2025-07-06T23:27:57.936576Z INFO EnvHandler ExtHandler Gateway:None Jul 6 23:27:57.937184 waagent[1998]: 2025-07-06T23:27:57.937139Z INFO EnvHandler ExtHandler Routes:None Jul 6 23:27:57.941978 waagent[1998]: 2025-07-06T23:27:57.941929Z INFO ExtHandler ExtHandler Jul 6 23:27:57.942516 waagent[1998]: 2025-07-06T23:27:57.942471Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: b4f022e0-8779-484b-939d-8df286c3c48c correlation 44af3765-d8fa-40c0-9ec5-6bdc5eb707fe created: 2025-07-06T23:26:31.979660Z] Jul 6 23:27:57.943660 waagent[1998]: 2025-07-06T23:27:57.943612Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Jul 6 23:27:57.945457 waagent[1998]: 2025-07-06T23:27:57.945404Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Jul 6 23:27:58.041510 waagent[1998]: 2025-07-06T23:27:58.041449Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.9.1.1 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 088C3920-AB0F-4A83-A6B5-DD49ADE20580;DroppedPackets: 0;UpdateGSErrors: 0;AutoUpdate: 0] Jul 6 23:27:58.074532 waagent[1998]: 2025-07-06T23:27:58.074453Z INFO MonitorHandler ExtHandler Network interfaces: Jul 6 23:27:58.074532 waagent[1998]: Executing ['ip', '-a', '-o', 'link']: Jul 6 23:27:58.074532 waagent[1998]: 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 Jul 6 23:27:58.074532 waagent[1998]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:1e:52:fc:0a:d0 brd ff:ff:ff:ff:ff:ff Jul 6 23:27:58.074532 waagent[1998]: Executing ['ip', '-4', '-a', '-o', 'address']: Jul 6 23:27:58.074532 waagent[1998]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Jul 6 23:27:58.074532 waagent[1998]: 2: eth0 inet 10.200.8.18/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Jul 6 23:27:58.074532 waagent[1998]: Executing ['ip', '-6', '-a', '-o', 'address']: Jul 6 23:27:58.074532 waagent[1998]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Jul 6 23:27:58.074532 waagent[1998]: 2: eth0 inet6 fe80::7e1e:52ff:fefc:ad0/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Jul 6 23:27:58.127623 waagent[1998]: 2025-07-06T23:27:58.127555Z INFO EnvHandler ExtHandler Successfully added Azure fabric firewall rules. Current Firewall rules: Jul 6 23:27:58.127623 waagent[1998]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Jul 6 23:27:58.127623 waagent[1998]: pkts bytes target prot opt in out source destination Jul 6 23:27:58.127623 waagent[1998]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Jul 6 23:27:58.127623 waagent[1998]: pkts bytes target prot opt in out source destination Jul 6 23:27:58.127623 waagent[1998]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Jul 6 23:27:58.127623 waagent[1998]: pkts bytes target prot opt in out source destination Jul 6 23:27:58.127623 waagent[1998]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Jul 6 23:27:58.127623 waagent[1998]: 10 1102 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Jul 6 23:27:58.127623 waagent[1998]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Jul 6 23:27:58.132556 waagent[1998]: 2025-07-06T23:27:58.132497Z INFO EnvHandler ExtHandler Current Firewall rules: Jul 6 23:27:58.132556 waagent[1998]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Jul 6 23:27:58.132556 waagent[1998]: pkts bytes target prot opt in out source destination Jul 6 23:27:58.132556 waagent[1998]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Jul 6 23:27:58.132556 waagent[1998]: pkts bytes target prot opt in out source destination Jul 6 23:27:58.132556 waagent[1998]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Jul 6 23:27:58.132556 waagent[1998]: pkts bytes target prot opt in out source destination Jul 6 23:27:58.132556 waagent[1998]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Jul 6 23:27:58.132556 waagent[1998]: 20 1968 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Jul 6 23:27:58.132556 waagent[1998]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Jul 6 23:27:58.132812 waagent[1998]: 2025-07-06T23:27:58.132772Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Jul 6 23:27:58.132969 waagent[1998]: 2025-07-06T23:27:58.132932Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Jul 6 23:28:07.614882 setup-nvidia[1849]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 482M 0 13037 0 0 20575 0 6:50:09 --:--:-- 6:50:09 20563\u000d 0 482M 0 3504k 0 0 2201k 0 0:03:44 0:00:01 0:03:43 2201k\u000d 5 482M 5 27.4M 0 0 10.6M 0 0:00:45 0:00:02 0:00:43 10.6M\u000d 10 482M 10 50.1M 0 0 14.1M 0 0:00:34 0:00:03 0:00:31 14.1M\u000d 15 482M 15 73.4M 0 0 16.2M 0 0:00:29 0:00:04 0:00:25 16.2M\u000d 20 482M 20 100M 0 0 18.1M 0 0:00:26 0:00:05 0:00:21 20.4M\u000d 26 482M 26 125M 0 0 18.8M 0 0:00:25 0:00:06 0:00:19 24.1M\u000d 31 482M 31 150M 0 0 19.6M 0 0:00:24 0:00:07 0:00:17 24.2M\u000d 35 482M 35 172M 0 0 20.2M 0 0:00:23 0:00:08 0:00:15 24.5M\u000d 41 482M 41 199M 0 0 20.7M 0 0:00:23 0:00:09 0:00:14 24.7M\u000d 46 482M 46 223M 0 0 21.0M 0 0:00:22 0:00:10 0:00:12 24.1M\u000d 51 482M 51 249M 0 0 21.4M 0 0:00:22 0:00:11 0:00:11 24.7M\u000d 56 482M 56 271M 0 0 21.5M 0 0:00:22 0:00:12 0:00:10 24.4M\u000d 61 482M 61 296M 0 0 21.8M 0 0:00:22 0:00:13 0:00:09 24.5M\u000d 66 482M 66 321M 0 0 22.0M 0 0:00:21 0:00:14 0:00:07 24.7M\u000d 71 482M 71 345M 0 0 22.2M 0 0:00:21 0:00:15 0:00:06 24.7M\u000d 76 482M 76 369M 0 0 22.3M 0 0:00:21 0:00:16 0:00:05 24.4M\u000d 81 482M 81 393M 0 0 22.4M 0 0:00:21 0:00:17 0:00:04 24.5M\u000d 86 482M 86 416M 0 0 22.4M 0 0:00:21 0:00:18 0:00:03 24.3M\u000d 91 482M 91 441M 0 0 22.6M 0 0:00:21 0:00:19 0:00:02 24.2M\u000d 96 482M 96 467M 0 0 22.6M 0 0:00:21 0:00:20 0:00:01 23.9M\u000d100 482M 100 482M 0 0 22.7M 0 0:00:21 0:00:21 --:--:-- 24.3M Jul 6 23:28:09.308774 chronyd[1699]: Selected source PHC0 Jul 6 23:28:17.768465 setup-nvidia[1696]: Downloading NVIDIA 535.230.02 Driver Jul 6 23:28:17.773350 setup-nvidia[2249]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 6 23:28:17.773603 setup-nvidia[2249]: Dload Upload Total Spent Left Speed Jul 6 23:28:19.050041 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Jul 6 23:28:19.066692 systemd[1]: Started sshd@0-10.200.8.18:22-10.200.16.10:37470.service - OpenSSH per-connection server daemon (10.200.16.10:37470). Jul 6 23:28:19.780162 setup-nvidia[2249]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 326M 0 16384 0 0 88688 0 1:04:15 --:--:-- 1:04:15 88562\u000d 47 326M 47 156M 0 0 135M 0 0:00:02 0:00:01 0:00:01 135M\u000d100 326M 100 326M 0 0 162M 0 0:00:02 0:00:02 --:--:-- 162M Jul 6 23:28:19.809707 setup-nvidia[1696]: Extract the NVIDIA Driver Installer 535.230.02 Jul 6 23:28:19.809927 setup-nvidia[1696]: /opt/nvidia/workdir/nvidia-workdir / Jul 6 23:28:19.826909 sshd[2251]: Accepted publickey for core from 10.200.16.10 port 37470 ssh2: RSA SHA256:IDZY9LUw7eRIRMWEPq9LVAUVCt3KY9vfW+V4XsN2mGI Jul 6 23:28:19.827462 setup-nvidia[2255]: Creating directory NVIDIA-Linux-x86_64-535.230.02 Jul 6 23:28:19.828137 sshd-session[2251]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:28:19.832240 systemd-logind[1701]: New session 3 of user core. Jul 6 23:28:19.841511 systemd[1]: Started session-3.scope - Session 3 of User core. Jul 6 23:28:20.082486 setup-nvidia[2255]: Verifying archive integrity... OK Jul 6 23:28:20.376884 systemd[1]: Started sshd@1-10.200.8.18:22-10.200.16.10:45990.service - OpenSSH per-connection server daemon (10.200.16.10:45990). Jul 6 23:28:21.004883 sshd[2281]: Accepted publickey for core from 10.200.16.10 port 45990 ssh2: RSA SHA256:IDZY9LUw7eRIRMWEPq9LVAUVCt3KY9vfW+V4XsN2mGI Jul 6 23:28:21.006232 sshd-session[2281]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:28:21.010303 systemd-logind[1701]: New session 4 of user core. Jul 6 23:28:21.024525 systemd[1]: Started session-4.scope - Session 4 of User core. Jul 6 23:28:21.447204 sshd[2287]: Connection closed by 10.200.16.10 port 45990 Jul 6 23:28:21.447545 sshd-session[2281]: pam_unix(sshd:session): session closed for user core Jul 6 23:28:21.450693 systemd[1]: sshd@1-10.200.8.18:22-10.200.16.10:45990.service: Deactivated successfully. Jul 6 23:28:21.452270 systemd[1]: session-4.scope: Deactivated successfully. Jul 6 23:28:21.452901 systemd-logind[1701]: Session 4 logged out. Waiting for processes to exit. Jul 6 23:28:21.453654 systemd-logind[1701]: Removed session 4. Jul 6 23:28:21.558491 systemd[1]: Started sshd@2-10.200.8.18:22-10.200.16.10:46004.service - OpenSSH per-connection server daemon (10.200.16.10:46004). Jul 6 23:28:21.887465 setup-nvidia[2255]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.230.02 Jul 6 23:28:22.190079 sshd[2293]: Accepted publickey for core from 10.200.16.10 port 46004 ssh2: RSA SHA256:IDZY9LUw7eRIRMWEPq9LVAUVCt3KY9vfW+V4XsN2mGI Jul 6 23:28:22.191467 sshd-session[2293]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:28:22.195866 systemd-logind[1701]: New session 5 of user core. Jul 6 23:28:22.208525 systemd[1]: Started session-5.scope - Session 5 of User core. Jul 6 23:28:22.635051 sshd[2298]: Connection closed by 10.200.16.10 port 46004 Jul 6 23:28:22.636620 sshd-session[2293]: pam_unix(sshd:session): session closed for user core Jul 6 23:28:22.640020 systemd[1]: sshd@2-10.200.8.18:22-10.200.16.10:46004.service: Deactivated successfully. Jul 6 23:28:22.641622 systemd[1]: session-5.scope: Deactivated successfully. Jul 6 23:28:22.642297 systemd-logind[1701]: Session 5 logged out. Waiting for processes to exit. Jul 6 23:28:22.643147 systemd-logind[1701]: Removed session 5. Jul 6 23:28:23.748623 setup-nvidia[2286]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Jul 6 23:28:24.462729 setup-nvidia[1696]: / Jul 6 23:28:24.463024 setup-nvidia[1696]: Spawn system-nspawn container to install the NVIDIA drivers Jul 6 23:28:24.798021 sudo[2306]: 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.1.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 Jul 6 23:28:24.799788 sudo[2306]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 6 23:28:24.807782 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Jul 6 23:28:24.823631 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Jul 6 23:28:24.836420 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Jul 6 23:28:24.840063 systemd[1]: Starting user@0.service - User Manager for UID 0... Jul 6 23:28:24.846281 (systemd)[2309]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Jul 6 23:28:24.849425 systemd-logind[1701]: New session c3 of user root. Jul 6 23:28:25.032187 systemd[2309]: Queued start job for default target default.target. Jul 6 23:28:25.041485 systemd[2309]: Created slice app.slice - User Application Slice. Jul 6 23:28:25.041515 systemd[2309]: Reached target paths.target - Paths. Jul 6 23:28:25.041564 systemd[2309]: Reached target timers.target - Timers. Jul 6 23:28:25.042755 systemd[2309]: Starting dbus.socket - D-Bus User Message Bus Socket... Jul 6 23:28:25.053539 systemd[2309]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jul 6 23:28:25.053659 systemd[2309]: Reached target sockets.target - Sockets. Jul 6 23:28:25.053716 systemd[2309]: Reached target basic.target - Basic System. Jul 6 23:28:25.053764 systemd[2309]: Reached target default.target - Main User Target. Jul 6 23:28:25.053795 systemd[2309]: Startup finished in 198ms. Jul 6 23:28:25.053879 systemd[1]: Started user@0.service - User Manager for UID 0. Jul 6 23:28:25.056982 systemd[1]: Started session-c2.scope - Session c2 of User root. Jul 6 23:28:26.564151 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Jul 6 23:28:26.789412 kernel: loop6: detected capacity change from 0 to 12644352 Jul 6 23:28:26.915420 kernel: loop6: p9 Jul 6 23:28:27.563623 setup-nvidia[2318]: ░ Spawning container flatcardevelopercontainer-4230.2.1.bin on /opt/nvidia/workdir/flatcar_developer_container-4230.2.1.bin. Jul 6 23:28:27.618408 kernel: EXT4-fs (loop6p9): mounted filesystem 8f7557fd-4fa8-4060-8487-0a27261a8cb6 ro without journal. Quota mode: none. Jul 6 23:28:30.790486 update_engine[1702]: I20250706 23:28:30.790417 1702 update_attempter.cc:509] Updating boot flags... Jul 6 23:28:30.860413 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 37 scanned by (udev-worker) (2388) Jul 6 23:28:30.960410 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 37 scanned by (udev-worker) (2392) Jul 6 23:28:32.746709 systemd[1]: Started sshd@3-10.200.8.18:22-10.200.16.10:44876.service - OpenSSH per-connection server daemon (10.200.16.10:44876). Jul 6 23:28:33.376490 sshd[2670]: Accepted publickey for core from 10.200.16.10 port 44876 ssh2: RSA SHA256:IDZY9LUw7eRIRMWEPq9LVAUVCt3KY9vfW+V4XsN2mGI Jul 6 23:28:33.377728 sshd-session[2670]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:28:33.381892 systemd-logind[1701]: New session 6 of user core. Jul 6 23:28:33.394536 systemd[1]: Started session-6.scope - Session 6 of User core. Jul 6 23:28:33.820079 sshd[2954]: Connection closed by 10.200.16.10 port 44876 Jul 6 23:28:33.820440 sshd-session[2670]: pam_unix(sshd:session): session closed for user core Jul 6 23:28:33.823491 systemd[1]: sshd@3-10.200.8.18:22-10.200.16.10:44876.service: Deactivated successfully. Jul 6 23:28:33.825070 systemd[1]: session-6.scope: Deactivated successfully. Jul 6 23:28:33.825714 systemd-logind[1701]: Session 6 logged out. Waiting for processes to exit. Jul 6 23:28:33.826503 systemd-logind[1701]: Removed session 6. Jul 6 23:28:33.937795 systemd[1]: Started sshd@4-10.200.8.18:22-10.200.16.10:44880.service - OpenSSH per-connection server daemon (10.200.16.10:44880). Jul 6 23:28:34.568200 sshd[3174]: Accepted publickey for core from 10.200.16.10 port 44880 ssh2: RSA SHA256:IDZY9LUw7eRIRMWEPq9LVAUVCt3KY9vfW+V4XsN2mGI Jul 6 23:28:34.569491 sshd-session[3174]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:28:34.573555 systemd-logind[1701]: New session 7 of user core. Jul 6 23:28:34.583924 systemd[1]: Started session-7.scope - Session 7 of User core. Jul 6 23:28:35.011673 sshd[3446]: Connection closed by 10.200.16.10 port 44880 Jul 6 23:28:35.012009 sshd-session[3174]: pam_unix(sshd:session): session closed for user core Jul 6 23:28:35.014947 systemd[1]: sshd@4-10.200.8.18:22-10.200.16.10:44880.service: Deactivated successfully. Jul 6 23:28:35.016525 systemd[1]: session-7.scope: Deactivated successfully. Jul 6 23:28:35.017178 systemd-logind[1701]: Session 7 logged out. Waiting for processes to exit. Jul 6 23:28:35.017937 systemd-logind[1701]: Removed session 7. Jul 6 23:28:45.142474 systemd[1]: Started sshd@5-10.200.8.18:22-10.200.16.10:48322.service - OpenSSH per-connection server daemon (10.200.16.10:48322). Jul 6 23:28:45.777419 sshd[5889]: Accepted publickey for core from 10.200.16.10 port 48322 ssh2: RSA SHA256:IDZY9LUw7eRIRMWEPq9LVAUVCt3KY9vfW+V4XsN2mGI Jul 6 23:28:45.779240 sshd-session[5889]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:28:45.787554 systemd-logind[1701]: New session 8 of user core. Jul 6 23:28:45.797103 systemd[1]: Started session-8.scope - Session 8 of User core. Jul 6 23:28:46.228837 sshd[6062]: Connection closed by 10.200.16.10 port 48322 Jul 6 23:28:46.229377 sshd-session[5889]: pam_unix(sshd:session): session closed for user core Jul 6 23:28:46.232197 systemd-logind[1701]: Session 8 logged out. Waiting for processes to exit. Jul 6 23:28:46.233102 systemd[1]: sshd@5-10.200.8.18:22-10.200.16.10:48322.service: Deactivated successfully. Jul 6 23:28:46.234964 systemd[1]: session-8.scope: Deactivated successfully. Jul 6 23:28:46.236897 systemd-logind[1701]: Removed session 8. Jul 6 23:28:46.351131 systemd[1]: Started sshd@6-10.200.8.18:22-10.200.16.10:48334.service - OpenSSH per-connection server daemon (10.200.16.10:48334). Jul 6 23:28:46.992906 sshd[6218]: Accepted publickey for core from 10.200.16.10 port 48334 ssh2: RSA SHA256:IDZY9LUw7eRIRMWEPq9LVAUVCt3KY9vfW+V4XsN2mGI Jul 6 23:28:46.994768 sshd-session[6218]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:28:47.000838 systemd-logind[1701]: New session 9 of user core. Jul 6 23:28:47.013568 systemd[1]: Started session-9.scope - Session 9 of User core. Jul 6 23:28:47.450303 sshd[6416]: Connection closed by 10.200.16.10 port 48334 Jul 6 23:28:47.452096 sshd-session[6218]: pam_unix(sshd:session): session closed for user core Jul 6 23:28:47.455292 systemd-logind[1701]: Session 9 logged out. Waiting for processes to exit. Jul 6 23:28:47.457484 systemd[1]: sshd@6-10.200.8.18:22-10.200.16.10:48334.service: Deactivated successfully. Jul 6 23:28:47.459099 systemd[1]: session-9.scope: Deactivated successfully. Jul 6 23:28:47.460574 systemd-logind[1701]: Removed session 9. Jul 6 23:28:57.571752 systemd[1]: Started sshd@7-10.200.8.18:22-10.200.16.10:40738.service - OpenSSH per-connection server daemon (10.200.16.10:40738). Jul 6 23:28:58.216441 sshd[10209]: Accepted publickey for core from 10.200.16.10 port 40738 ssh2: RSA SHA256:IDZY9LUw7eRIRMWEPq9LVAUVCt3KY9vfW+V4XsN2mGI Jul 6 23:28:58.218219 sshd-session[10209]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:28:58.224396 systemd-logind[1701]: New session 10 of user core. Jul 6 23:28:58.236498 systemd[1]: Started session-10.scope - Session 10 of User core. Jul 6 23:28:58.667610 sshd[10343]: Connection closed by 10.200.16.10 port 40738 Jul 6 23:28:58.669768 sshd-session[10209]: pam_unix(sshd:session): session closed for user core Jul 6 23:28:58.674013 systemd[1]: sshd@7-10.200.8.18:22-10.200.16.10:40738.service: Deactivated successfully. Jul 6 23:28:58.675821 systemd[1]: session-10.scope: Deactivated successfully. Jul 6 23:28:58.676983 systemd-logind[1701]: Session 10 logged out. Waiting for processes to exit. Jul 6 23:28:58.678152 systemd-logind[1701]: Removed session 10. Jul 6 23:28:58.785330 systemd[1]: Started sshd@8-10.200.8.18:22-10.200.16.10:40740.service - OpenSSH per-connection server daemon (10.200.16.10:40740). Jul 6 23:28:59.421496 sshd[10478]: Accepted publickey for core from 10.200.16.10 port 40740 ssh2: RSA SHA256:IDZY9LUw7eRIRMWEPq9LVAUVCt3KY9vfW+V4XsN2mGI Jul 6 23:28:59.423517 sshd-session[10478]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:28:59.429687 systemd-logind[1701]: New session 11 of user core. Jul 6 23:28:59.439900 systemd[1]: Started session-11.scope - Session 11 of User core. Jul 6 23:28:59.991025 sshd[10640]: Connection closed by 10.200.16.10 port 40740 Jul 6 23:28:59.990707 sshd-session[10478]: pam_unix(sshd:session): session closed for user core Jul 6 23:28:59.995065 systemd[1]: sshd@8-10.200.8.18:22-10.200.16.10:40740.service: Deactivated successfully. Jul 6 23:28:59.997455 systemd[1]: session-11.scope: Deactivated successfully. Jul 6 23:28:59.998895 systemd-logind[1701]: Session 11 logged out. Waiting for processes to exit. Jul 6 23:28:59.999889 systemd-logind[1701]: Removed session 11. Jul 6 23:29:09.991308 systemd[1]: Started sshd@9-10.200.8.18:22-10.200.16.10:39760.service - OpenSSH per-connection server daemon (10.200.16.10:39760). Jul 6 23:29:10.646413 sshd[11833]: Accepted publickey for core from 10.200.16.10 port 39760 ssh2: RSA SHA256:IDZY9LUw7eRIRMWEPq9LVAUVCt3KY9vfW+V4XsN2mGI Jul 6 23:29:10.650882 sshd-session[11833]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:29:10.657349 systemd-logind[1701]: New session 12 of user core. Jul 6 23:29:10.661562 systemd[1]: Started session-12.scope - Session 12 of User core. Jul 6 23:29:11.108475 sshd[11882]: Connection closed by 10.200.16.10 port 39760 Jul 6 23:29:11.109441 sshd-session[11833]: pam_unix(sshd:session): session closed for user core Jul 6 23:29:11.113499 systemd-logind[1701]: Session 12 logged out. Waiting for processes to exit. Jul 6 23:29:11.115064 systemd[1]: sshd@9-10.200.8.18:22-10.200.16.10:39760.service: Deactivated successfully. Jul 6 23:29:11.116772 systemd[1]: session-12.scope: Deactivated successfully. Jul 6 23:29:11.118347 systemd-logind[1701]: Removed session 12. Jul 6 23:29:11.236720 systemd[1]: Started sshd@10-10.200.8.18:22-10.200.16.10:39770.service - OpenSSH per-connection server daemon (10.200.16.10:39770). Jul 6 23:29:11.882828 sshd[11895]: Accepted publickey for core from 10.200.16.10 port 39770 ssh2: RSA SHA256:IDZY9LUw7eRIRMWEPq9LVAUVCt3KY9vfW+V4XsN2mGI Jul 6 23:29:11.884650 sshd-session[11895]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:29:11.891347 systemd-logind[1701]: New session 13 of user core. Jul 6 23:29:11.895949 systemd[1]: Started session-13.scope - Session 13 of User core. Jul 6 23:29:12.334593 sshd[11914]: Connection closed by 10.200.16.10 port 39770 Jul 6 23:29:12.334996 sshd-session[11895]: pam_unix(sshd:session): session closed for user core Jul 6 23:29:12.338267 systemd[1]: sshd@10-10.200.8.18:22-10.200.16.10:39770.service: Deactivated successfully. Jul 6 23:29:12.339915 systemd[1]: session-13.scope: Deactivated successfully. Jul 6 23:29:12.340304 systemd-logind[1701]: Session 13 logged out. Waiting for processes to exit. Jul 6 23:29:12.346634 systemd-logind[1701]: Removed session 13. Jul 6 23:29:22.459173 systemd[1]: Started sshd@11-10.200.8.18:22-10.200.16.10:35922.service - OpenSSH per-connection server daemon (10.200.16.10:35922). Jul 6 23:29:23.108687 sshd[12233]: Accepted publickey for core from 10.200.16.10 port 35922 ssh2: RSA SHA256:IDZY9LUw7eRIRMWEPq9LVAUVCt3KY9vfW+V4XsN2mGI Jul 6 23:29:23.110550 sshd-session[12233]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:29:23.117128 systemd-logind[1701]: New session 14 of user core. Jul 6 23:29:23.127549 systemd[1]: Started session-14.scope - Session 14 of User core. Jul 6 23:29:23.557494 sshd[12253]: Connection closed by 10.200.16.10 port 35922 Jul 6 23:29:23.560584 sshd-session[12233]: pam_unix(sshd:session): session closed for user core Jul 6 23:29:23.563406 systemd[1]: sshd@11-10.200.8.18:22-10.200.16.10:35922.service: Deactivated successfully. Jul 6 23:29:23.565580 systemd[1]: session-14.scope: Deactivated successfully. Jul 6 23:29:23.569069 systemd-logind[1701]: Session 14 logged out. Waiting for processes to exit. Jul 6 23:29:23.570160 systemd-logind[1701]: Removed session 14. Jul 6 23:29:23.681744 systemd[1]: Started sshd@12-10.200.8.18:22-10.200.16.10:35926.service - OpenSSH per-connection server daemon (10.200.16.10:35926). Jul 6 23:29:24.322956 sshd[12283]: Accepted publickey for core from 10.200.16.10 port 35926 ssh2: RSA SHA256:IDZY9LUw7eRIRMWEPq9LVAUVCt3KY9vfW+V4XsN2mGI Jul 6 23:29:24.324770 sshd-session[12283]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:29:24.331115 systemd-logind[1701]: New session 15 of user core. Jul 6 23:29:24.344542 systemd[1]: Started session-15.scope - Session 15 of User core. Jul 6 23:29:24.770456 sshd[12314]: Connection closed by 10.200.16.10 port 35926 Jul 6 23:29:24.771226 sshd-session[12283]: pam_unix(sshd:session): session closed for user core Jul 6 23:29:24.774648 systemd[1]: sshd@12-10.200.8.18:22-10.200.16.10:35926.service: Deactivated successfully. Jul 6 23:29:24.774976 systemd-logind[1701]: Session 15 logged out. Waiting for processes to exit. Jul 6 23:29:24.776871 systemd[1]: session-15.scope: Deactivated successfully. Jul 6 23:29:24.780627 systemd-logind[1701]: Removed session 15. Jul 6 23:29:34.889221 systemd[1]: Started sshd@13-10.200.8.18:22-10.200.16.10:49544.service - OpenSSH per-connection server daemon (10.200.16.10:49544). Jul 6 23:29:35.546201 sshd[12626]: Accepted publickey for core from 10.200.16.10 port 49544 ssh2: RSA SHA256:IDZY9LUw7eRIRMWEPq9LVAUVCt3KY9vfW+V4XsN2mGI Jul 6 23:29:35.547623 sshd-session[12626]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:29:35.553011 systemd-logind[1701]: New session 16 of user core. Jul 6 23:29:35.567082 systemd[1]: Started session-16.scope - Session 16 of User core. Jul 6 23:29:35.994557 sshd[12668]: Connection closed by 10.200.16.10 port 49544 Jul 6 23:29:35.996778 sshd-session[12626]: pam_unix(sshd:session): session closed for user core Jul 6 23:29:36.000136 systemd[1]: sshd@13-10.200.8.18:22-10.200.16.10:49544.service: Deactivated successfully. Jul 6 23:29:36.002635 systemd[1]: session-16.scope: Deactivated successfully. Jul 6 23:29:36.004423 systemd-logind[1701]: Session 16 logged out. Waiting for processes to exit. Jul 6 23:29:36.005818 systemd-logind[1701]: Removed session 16. Jul 6 23:29:36.116810 systemd[1]: Started sshd@14-10.200.8.18:22-10.200.16.10:49546.service - OpenSSH per-connection server daemon (10.200.16.10:49546). Jul 6 23:29:36.754222 sshd[12699]: Accepted publickey for core from 10.200.16.10 port 49546 ssh2: RSA SHA256:IDZY9LUw7eRIRMWEPq9LVAUVCt3KY9vfW+V4XsN2mGI Jul 6 23:29:36.755561 sshd-session[12699]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:29:36.759736 systemd-logind[1701]: New session 17 of user core. Jul 6 23:29:36.772543 systemd[1]: Started session-17.scope - Session 17 of User core. Jul 6 23:29:37.208063 sshd[12711]: Connection closed by 10.200.16.10 port 49546 Jul 6 23:29:37.208402 sshd-session[12699]: pam_unix(sshd:session): session closed for user core Jul 6 23:29:37.211556 systemd[1]: sshd@14-10.200.8.18:22-10.200.16.10:49546.service: Deactivated successfully. Jul 6 23:29:37.213128 systemd[1]: session-17.scope: Deactivated successfully. Jul 6 23:29:37.213791 systemd-logind[1701]: Session 17 logged out. Waiting for processes to exit. Jul 6 23:29:37.214563 systemd-logind[1701]: Removed session 17. Jul 6 23:29:44.454027 setup-nvidia[2318]: \u000d Jul 6 23:29:44.454027 setup-nvidia[2318]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Jul 6 23:29:44.454027 setup-nvidia[2318]: frequently when this kernel module was built against the wrong or Jul 6 23:29:44.454027 setup-nvidia[2318]: improperly configured kernel sources, with a version of gcc that differs Jul 6 23:29:44.454027 setup-nvidia[2318]: from the one used to build the target kernel, or if another driver, such Jul 6 23:29:44.454027 setup-nvidia[2318]: as nouveau, is present and prevents the NVIDIA kernel module from Jul 6 23:29:44.454027 setup-nvidia[2318]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Jul 6 23:29:44.454027 setup-nvidia[2318]: installed in this system is supported by this NVIDIA Linux graphics Jul 6 23:29:44.454027 setup-nvidia[2318]: driver release. Jul 6 23:29:44.454027 setup-nvidia[2318]: \u000d Jul 6 23:29:44.454027 setup-nvidia[2318]: Please see the log entries 'Kernel module load error' and 'Kernel Jul 6 23:29:44.454027 setup-nvidia[2318]: messages' at the end of the file Jul 6 23:29:44.454027 setup-nvidia[2318]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more Jul 6 23:29:44.454027 setup-nvidia[2318]: information. Jul 6 23:29:44.454027 setup-nvidia[2318]: \u000d Jul 6 23:29:44.468524 setup-nvidia[2318]: dmesg: read kernel buffer failed: Operation not permitted Jul 6 23:29:44.489661 setup-nvidia[2318]: \u000d Jul 6 23:29:44.489661 setup-nvidia[2318]: ERROR: Installation has failed. Please see the file Jul 6 23:29:44.489661 setup-nvidia[2318]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for Jul 6 23:29:44.489661 setup-nvidia[2318]: details. You may find suggestions on fixing installation problems in Jul 6 23:29:44.489661 setup-nvidia[2318]: the README available on the Linux driver download page at Jul 6 23:29:44.489661 setup-nvidia[2318]: www.nvidia.com. Jul 6 23:29:44.489661 setup-nvidia[2318]: \u000d Jul 6 23:29:44.490065 setup-nvidia[2318]: Last 50 lines of nvidia-installer.log: Jul 6 23:29:44.490902 setup-nvidia[2318]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038989rm+0x32: 'naked' return found in RETHUNK build Jul 6 23:29:44.490902 setup-nvidia[2318]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039000rm+0x53: 'naked' return found in RETHUNK build Jul 6 23:29:44.490902 setup-nvidia[2318]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038997rm+0x90: 'naked' return found in RETHUNK build Jul 6 23:29:44.490902 setup-nvidia[2318]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038990rm+0x2b: 'naked' return found in RETHUNK build Jul 6 23:29:44.490902 setup-nvidia[2318]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039046rm+0x93: 'naked' return found in RETHUNK build Jul 6 23:29:44.490902 setup-nvidia[2318]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039042rm+0x36: 'naked' return found in RETHUNK build Jul 6 23:29:44.490902 setup-nvidia[2318]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039041rm+0x9d: 'naked' return found in RETHUNK build Jul 6 23:29:44.490902 setup-nvidia[2318]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039043rm+0x77: 'naked' return found in RETHUNK build Jul 6 23:29:44.490902 setup-nvidia[2318]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038987rm+0x7f: 'naked' return found in RETHUNK build Jul 6 23:29:44.490902 setup-nvidia[2318]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030558rm+0x3e: 'naked' return found in RETHUNK build Jul 6 23:29:44.490902 setup-nvidia[2318]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030557rm+0x27: 'naked' return found in RETHUNK build Jul 6 23:29:44.490902 setup-nvidia[2318]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030561rm+0x37: 'naked' return found in RETHUNK build Jul 6 23:29:44.490902 setup-nvidia[2318]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030560rm+0x24: 'naked' return found in RETHUNK build Jul 6 23:29:44.490902 setup-nvidia[2318]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030559rm+0xca: 'naked' return found in RETHUNK build Jul 6 23:29:44.490902 setup-nvidia[2318]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030562rm+0x193: 'naked' return found in RETHUNK build Jul 6 23:29:44.490902 setup-nvidia[2318]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030564rm+0x17f: 'naked' return found in RETHUNK build Jul 6 23:29:44.490902 setup-nvidia[2318]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv011860rm+0x5d: 'naked' return found in RETHUNK build Jul 6 23:29:44.490902 setup-nvidia[2318]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039354rm+0x12f: 'naked' return found in RETHUNK build Jul 6 23:29:44.490902 setup-nvidia[2318]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/Module.symvers Jul 6 23:29:44.490902 setup-nvidia[2318]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.mod.o Jul 6 23:29:44.490902 setup-nvidia[2318]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.mod.o Jul 6 23:29:44.490902 setup-nvidia[2318]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.mod.o Jul 6 23:29:44.490902 setup-nvidia[2318]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.mod.o Jul 6 23:29:44.490902 setup-nvidia[2318]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.mod.o Jul 6 23:29:44.490902 setup-nvidia[2318]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Jul 6 23:29:44.490902 setup-nvidia[2318]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Jul 6 23:29:44.490902 setup-nvidia[2318]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Jul 6 23:29:44.490902 setup-nvidia[2318]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Jul 6 23:29:44.490902 setup-nvidia[2318]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Jul 6 23:29:44.490902 setup-nvidia[2318]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Jul 6 23:29:44.490902 setup-nvidia[2318]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko due to unavailability of vmlinux Jul 6 23:29:44.490902 setup-nvidia[2318]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Jul 6 23:29:44.490902 setup-nvidia[2318]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko due to unavailability of vmlinux Jul 6 23:29:44.490902 setup-nvidia[2318]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Jul 6 23:29:44.490902 setup-nvidia[2318]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko due to unavailability of vmlinux Jul 6 23:29:44.490902 setup-nvidia[2318]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Jul 6 23:29:44.490902 setup-nvidia[2318]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko due to unavailability of vmlinux Jul 6 23:29:44.490902 setup-nvidia[2318]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Jul 6 23:29:44.490902 setup-nvidia[2318]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko due to unavailability of vmlinux Jul 6 23:29:44.490902 setup-nvidia[2318]: make[2]: Leaving directory '/usr/lib/modules/6.6.95-flatcar/build' Jul 6 23:29:44.490902 setup-nvidia[2318]: make[1]: Leaving directory '/usr/lib/modules/6.6.95-flatcar/source' Jul 6 23:29:44.490902 setup-nvidia[2318]: -> done. Jul 6 23:29:44.490902 setup-nvidia[2318]: -> Kernel module compilation complete. Jul 6 23:29:44.490902 setup-nvidia[2318]: -> Unable to determine if Secure Boot is enabled: No such file or directory Jul 6 23:29:44.490902 setup-nvidia[2318]: 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. Jul 6 23:29:44.490902 setup-nvidia[2318]: \u000d Jul 6 23:29:44.492014 setup-nvidia[2318]: 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. Jul 6 23:29:44.492014 setup-nvidia[2318]: -> Kernel module load error: Operation not permitted Jul 6 23:29:44.492014 setup-nvidia[2318]: -> Kernel messages: Jul 6 23:29:44.492014 setup-nvidia[2318]: 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. Jul 6 23:29:47.320795 systemd[1]: Started sshd@15-10.200.8.18:22-10.200.16.10:41994.service - OpenSSH per-connection server daemon (10.200.16.10:41994). Jul 6 23:29:47.950398 sshd[12926]: Accepted publickey for core from 10.200.16.10 port 41994 ssh2: RSA SHA256:IDZY9LUw7eRIRMWEPq9LVAUVCt3KY9vfW+V4XsN2mGI Jul 6 23:29:47.951681 sshd-session[12926]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:29:47.955705 systemd-logind[1701]: New session 18 of user core. Jul 6 23:29:47.967507 systemd[1]: Started session-18.scope - Session 18 of User core. Jul 6 23:29:48.404008 sshd[12928]: Connection closed by 10.200.16.10 port 41994 Jul 6 23:29:48.404364 sshd-session[12926]: pam_unix(sshd:session): session closed for user core Jul 6 23:29:48.407316 systemd[1]: sshd@15-10.200.8.18:22-10.200.16.10:41994.service: Deactivated successfully. Jul 6 23:29:48.408898 systemd[1]: session-18.scope: Deactivated successfully. Jul 6 23:29:48.409556 systemd-logind[1701]: Session 18 logged out. Waiting for processes to exit. Jul 6 23:29:48.410456 systemd-logind[1701]: Removed session 18. Jul 6 23:29:48.514893 systemd[1]: Started sshd@16-10.200.8.18:22-10.200.16.10:42008.service - OpenSSH per-connection server daemon (10.200.16.10:42008). Jul 6 23:29:49.144275 sshd[12934]: Accepted publickey for core from 10.200.16.10 port 42008 ssh2: RSA SHA256:IDZY9LUw7eRIRMWEPq9LVAUVCt3KY9vfW+V4XsN2mGI Jul 6 23:29:49.145620 sshd-session[12934]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:29:49.150134 systemd-logind[1701]: New session 19 of user core. Jul 6 23:29:49.162513 systemd[1]: Started session-19.scope - Session 19 of User core. Jul 6 23:29:49.587402 sshd[12936]: Connection closed by 10.200.16.10 port 42008 Jul 6 23:29:49.587799 sshd-session[12934]: pam_unix(sshd:session): session closed for user core Jul 6 23:29:49.590986 systemd[1]: sshd@16-10.200.8.18:22-10.200.16.10:42008.service: Deactivated successfully. Jul 6 23:29:49.592536 systemd[1]: session-19.scope: Deactivated successfully. Jul 6 23:29:49.593168 systemd-logind[1701]: Session 19 logged out. Waiting for processes to exit. Jul 6 23:29:49.593936 systemd-logind[1701]: Removed session 19. Jul 6 23:29:58.547408 kernel: EXT4-fs (loop6p9): unmounting filesystem 8f7557fd-4fa8-4060-8487-0a27261a8cb6. Jul 6 23:29:58.547595 setup-nvidia[2318]: Container flatcardevelopercontainer-4230.2.1.bin exited successfully. Jul 6 23:29:58.549807 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4230.2.1.bin.mount: Deactivated successfully. Jul 6 23:29:58.607157 sudo[2306]: pam_unix(sudo:session): session closed for user root Jul 6 23:29:58.608862 systemd[1]: session-c2.scope: Deactivated successfully. Jul 6 23:29:58.609121 systemd[1]: session-c2.scope: Consumed 5min 11.729s CPU time, 882.4M memory peak. Jul 6 23:29:59.037281 setup-nvidia[1696]: /opt/nvidia / Jul 6 23:29:59.038278 setup-nvidia[1696]: / Jul 6 23:29:59.054991 setup-nvidia[12959]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jul 6 23:29:59.078405 kernel: IPMI message handler: version 39.2 Jul 6 23:29:59.082400 kernel: ipmi device interface Jul 6 23:29:59.083056 setup-nvidia[1696]: /opt/nvidia/current/usr/lib/modules/6.6.95-flatcar/video / Jul 6 23:29:59.134367 kernel: nvidia: loading out-of-tree module taints kernel. Jul 6 23:29:59.134514 kernel: nvidia: module license 'NVIDIA' taints kernel. Jul 6 23:29:59.134538 kernel: Disabling lock debugging due to kernel taint Jul 6 23:29:59.141741 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Jul 6 23:29:59.141782 kernel: nvidia: module license taints kernel. Jul 6 23:29:59.550409 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 245 Jul 6 23:29:59.948825 kernel: Jul 6 23:29:59.948960 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Jul 6 23:29:59.963074 systemd[1]: Started sshd@17-10.200.8.18:22-10.200.16.10:43584.service - OpenSSH per-connection server daemon (10.200.16.10:43584). Jul 6 23:30:00.156415 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.230.02 Fri Dec 20 21:42:05 UTC 2024 Jul 6 23:30:00.177416 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.230.02 Fri Dec 20 21:52:34 UTC 2024 Jul 6 23:30:00.197410 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Jul 6 23:30:00.245329 setup-nvidia[1696]: / Jul 6 23:30:00.245778 kernel: nvidia-uvm: Loaded the UVM driver, major device number 243. Jul 6 23:30:00.592280 sshd[12967]: Accepted publickey for core from 10.200.16.10 port 43584 ssh2: RSA SHA256:IDZY9LUw7eRIRMWEPq9LVAUVCt3KY9vfW+V4XsN2mGI Jul 6 23:30:00.593528 sshd-session[12967]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:30:00.597823 systemd-logind[1701]: New session 20 of user core. Jul 6 23:30:00.609505 systemd[1]: Started session-20.scope - Session 20 of User core. Jul 6 23:30:01.044820 sshd[12996]: Connection closed by 10.200.16.10 port 43584 Jul 6 23:30:01.045215 sshd-session[12967]: pam_unix(sshd:session): session closed for user core Jul 6 23:30:01.048374 systemd[1]: sshd@17-10.200.8.18:22-10.200.16.10:43584.service: Deactivated successfully. Jul 6 23:30:01.050257 systemd[1]: session-20.scope: Deactivated successfully. Jul 6 23:30:01.051037 systemd-logind[1701]: Session 20 logged out. Waiting for processes to exit. Jul 6 23:30:01.051816 systemd-logind[1701]: Removed session 20. Jul 6 23:30:01.159951 systemd[1]: Started sshd@18-10.200.8.18:22-10.200.16.10:43600.service - OpenSSH per-connection server daemon (10.200.16.10:43600). Jul 6 23:30:01.790297 sshd[13002]: Accepted publickey for core from 10.200.16.10 port 43600 ssh2: RSA SHA256:IDZY9LUw7eRIRMWEPq9LVAUVCt3KY9vfW+V4XsN2mGI Jul 6 23:30:01.791709 sshd-session[13002]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:30:01.795867 systemd-logind[1701]: New session 21 of user core. Jul 6 23:30:01.811501 systemd[1]: Started session-21.scope - Session 21 of User core. Jul 6 23:30:02.003417 setup-nvidia[12992]: Sun Jul 6 23:30:02 2025 Jul 6 23:30:02.003417 setup-nvidia[12992]: +---------------------------------------------------------------------------------------+ Jul 6 23:30:02.003417 setup-nvidia[12992]: | NVIDIA-SMI 535.230.02 Driver Version: 535.230.02 CUDA Version: 12.2 | Jul 6 23:30:02.003417 setup-nvidia[12992]: |-----------------------------------------+----------------------+----------------------+ Jul 6 23:30:02.003417 setup-nvidia[12992]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Jul 6 23:30:02.003417 setup-nvidia[12992]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Jul 6 23:30:02.003417 setup-nvidia[12992]: | | | MIG M. | Jul 6 23:30:02.003417 setup-nvidia[12992]: |=========================================+======================+======================| Jul 6 23:30:02.006943 setup-nvidia[12992]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Jul 6 23:30:02.006943 setup-nvidia[12992]: | N/A 25C P0 37W / 250W | 0MiB / 16384MiB | 0% Default | Jul 6 23:30:02.006943 setup-nvidia[12992]: | | | N/A | Jul 6 23:30:02.006943 setup-nvidia[12992]: +-----------------------------------------+----------------------+----------------------+ Jul 6 23:30:02.007072 setup-nvidia[12992]: Jul 6 23:30:02.007072 setup-nvidia[12992]: +---------------------------------------------------------------------------------------+ Jul 6 23:30:02.007072 setup-nvidia[12992]: | Processes: | Jul 6 23:30:02.007072 setup-nvidia[12992]: | GPU GI CI PID Type Process name GPU Memory | Jul 6 23:30:02.007072 setup-nvidia[12992]: | ID ID Usage | Jul 6 23:30:02.007072 setup-nvidia[12992]: |=======================================================================================| Jul 6 23:30:02.007202 setup-nvidia[12992]: | No running processes found | Jul 6 23:30:02.007202 setup-nvidia[12992]: +---------------------------------------------------------------------------------------+ Jul 6 23:30:02.234844 sshd[13004]: Connection closed by 10.200.16.10 port 43600 Jul 6 23:30:02.235195 sshd-session[13002]: pam_unix(sshd:session): session closed for user core Jul 6 23:30:02.238166 systemd[1]: sshd@18-10.200.8.18:22-10.200.16.10:43600.service: Deactivated successfully. Jul 6 23:30:02.239784 systemd[1]: session-21.scope: Deactivated successfully. Jul 6 23:30:02.240432 systemd-logind[1701]: Session 21 logged out. Waiting for processes to exit. Jul 6 23:30:02.241177 systemd-logind[1701]: Removed session 21. Jul 6 23:30:02.377405 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Jul 6 23:30:02.379434 systemd[1]: Reached target multi-user.target - Multi-User System. Jul 6 23:30:02.379736 systemd[1]: Startup finished in 1.080s (firmware) + 25.714s (loader) + 1.810s (kernel) + 11.589s (initrd) + 2min 30.193s (userspace) = 3min 10.386s. Jul 6 23:30:08.709637 systemd[1]: Stopping user@0.service - User Manager for UID 0... Jul 6 23:30:08.710430 systemd[2309]: Activating special unit exit.target... Jul 6 23:30:08.710510 systemd[2309]: Stopped target default.target - Main User Target. Jul 6 23:30:08.710553 systemd[2309]: Stopped target basic.target - Basic System. Jul 6 23:30:08.710584 systemd[2309]: Stopped target paths.target - Paths. Jul 6 23:30:08.710612 systemd[2309]: Stopped target sockets.target - Sockets. Jul 6 23:30:08.710640 systemd[2309]: Stopped target timers.target - Timers. Jul 6 23:30:08.711000 systemd[2309]: Closed dbus.socket - D-Bus User Message Bus Socket. Jul 6 23:30:08.711215 systemd[2309]: Removed slice app.slice - User Application Slice. Jul 6 23:30:08.711250 systemd[2309]: Reached target shutdown.target - Shutdown. Jul 6 23:30:08.711423 systemd[2309]: Finished systemd-exit.service - Exit the Session. Jul 6 23:30:08.711465 systemd[2309]: Reached target exit.target - Exit the Session. Jul 6 23:30:08.723581 (sd-pam)[2310]: pam_unix(systemd-user:session): session closed for user root Jul 6 23:30:08.725018 systemd[1]: user@0.service: Deactivated successfully. Jul 6 23:30:08.725398 systemd[1]: Stopped user@0.service - User Manager for UID 0. Jul 6 23:30:08.735709 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Jul 6 23:30:08.744292 systemd[1]: run-user-0.mount: Deactivated successfully. Jul 6 23:30:08.745205 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Jul 6 23:30:08.745423 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Jul 6 23:30:08.747071 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Jul 6 23:30:08.747163 systemd[1]: user-0.slice: Consumed 5min 12.006s CPU time, 891.8M memory peak. Jul 6 23:30:08.747443 systemd-logind[1701]: Removed session c3. Jul 6 23:30:12.345908 systemd[1]: Started sshd@19-10.200.8.18:22-10.200.16.10:34754.service - OpenSSH per-connection server daemon (10.200.16.10:34754). Jul 6 23:30:12.983585 sshd[13016]: Accepted publickey for core from 10.200.16.10 port 34754 ssh2: RSA SHA256:IDZY9LUw7eRIRMWEPq9LVAUVCt3KY9vfW+V4XsN2mGI Jul 6 23:30:12.984838 sshd-session[13016]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:30:12.988875 systemd-logind[1701]: New session 22 of user core. Jul 6 23:30:12.996527 systemd[1]: Started session-22.scope - Session 22 of User core. Jul 6 23:30:13.427959 sshd[13018]: Connection closed by 10.200.16.10 port 34754 Jul 6 23:30:13.428226 sshd-session[13016]: pam_unix(sshd:session): session closed for user core Jul 6 23:30:13.431195 systemd[1]: sshd@19-10.200.8.18:22-10.200.16.10:34754.service: Deactivated successfully. Jul 6 23:30:13.432790 systemd[1]: session-22.scope: Deactivated successfully. Jul 6 23:30:13.433449 systemd-logind[1701]: Session 22 logged out. Waiting for processes to exit. Jul 6 23:30:13.434203 systemd-logind[1701]: Removed session 22. Jul 6 23:30:13.538587 systemd[1]: Started sshd@20-10.200.8.18:22-10.200.16.10:34762.service - OpenSSH per-connection server daemon (10.200.16.10:34762). Jul 6 23:30:14.166100 sshd[13024]: Accepted publickey for core from 10.200.16.10 port 34762 ssh2: RSA SHA256:IDZY9LUw7eRIRMWEPq9LVAUVCt3KY9vfW+V4XsN2mGI Jul 6 23:30:14.167500 sshd-session[13024]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:30:14.171585 systemd-logind[1701]: New session 23 of user core. Jul 6 23:30:14.182606 systemd[1]: Started session-23.scope - Session 23 of User core. Jul 6 23:30:14.606241 sshd[13026]: Connection closed by 10.200.16.10 port 34762 Jul 6 23:30:14.606642 sshd-session[13024]: pam_unix(sshd:session): session closed for user core Jul 6 23:30:14.609864 systemd[1]: sshd@20-10.200.8.18:22-10.200.16.10:34762.service: Deactivated successfully. Jul 6 23:30:14.611464 systemd[1]: session-23.scope: Deactivated successfully. Jul 6 23:30:14.612092 systemd-logind[1701]: Session 23 logged out. Waiting for processes to exit. Jul 6 23:30:14.612839 systemd-logind[1701]: Removed session 23. Jul 6 23:30:14.716585 systemd[1]: Started sshd@21-10.200.8.18:22-10.200.16.10:34770.service - OpenSSH per-connection server daemon (10.200.16.10:34770). Jul 6 23:30:15.346202 sshd[13032]: Accepted publickey for core from 10.200.16.10 port 34770 ssh2: RSA SHA256:IDZY9LUw7eRIRMWEPq9LVAUVCt3KY9vfW+V4XsN2mGI Jul 6 23:30:15.347546 sshd-session[13032]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:30:15.351892 systemd-logind[1701]: New session 24 of user core. Jul 6 23:30:15.363498 systemd[1]: Started session-24.scope - Session 24 of User core. Jul 6 23:30:15.790334 sshd[13034]: Connection closed by 10.200.16.10 port 34770 Jul 6 23:30:15.790729 sshd-session[13032]: pam_unix(sshd:session): session closed for user core Jul 6 23:30:15.793815 systemd[1]: sshd@21-10.200.8.18:22-10.200.16.10:34770.service: Deactivated successfully. Jul 6 23:30:15.795444 systemd[1]: session-24.scope: Deactivated successfully. Jul 6 23:30:15.796062 systemd-logind[1701]: Session 24 logged out. Waiting for processes to exit. Jul 6 23:30:15.796863 systemd-logind[1701]: Removed session 24. Jul 6 23:30:15.900567 systemd[1]: Started sshd@22-10.200.8.18:22-10.200.16.10:34778.service - OpenSSH per-connection server daemon (10.200.16.10:34778). Jul 6 23:30:16.529982 sshd[13040]: Accepted publickey for core from 10.200.16.10 port 34778 ssh2: RSA SHA256:IDZY9LUw7eRIRMWEPq9LVAUVCt3KY9vfW+V4XsN2mGI Jul 6 23:30:16.531307 sshd-session[13040]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:30:16.535395 systemd-logind[1701]: New session 25 of user core. Jul 6 23:30:16.548498 systemd[1]: Started session-25.scope - Session 25 of User core. Jul 6 23:30:16.875933 sudo[13043]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Jul 6 23:30:16.876252 sudo[13043]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jul 6 23:30:16.922641 sudo[13043]: pam_unix(sudo:session): session closed for user root Jul 6 23:30:17.023047 sshd[13042]: Connection closed by 10.200.16.10 port 34778 Jul 6 23:30:17.023457 sshd-session[13040]: pam_unix(sshd:session): session closed for user core Jul 6 23:30:17.026717 systemd[1]: sshd@22-10.200.8.18:22-10.200.16.10:34778.service: Deactivated successfully. Jul 6 23:30:17.028288 systemd[1]: session-25.scope: Deactivated successfully. Jul 6 23:30:17.028969 systemd-logind[1701]: Session 25 logged out. Waiting for processes to exit. Jul 6 23:30:17.029764 systemd-logind[1701]: Removed session 25. Jul 6 23:30:17.134717 systemd[1]: Started sshd@23-10.200.8.18:22-10.200.16.10:34788.service - OpenSSH per-connection server daemon (10.200.16.10:34788). Jul 6 23:30:17.765041 sshd[13049]: Accepted publickey for core from 10.200.16.10 port 34788 ssh2: RSA SHA256:IDZY9LUw7eRIRMWEPq9LVAUVCt3KY9vfW+V4XsN2mGI Jul 6 23:30:17.766359 sshd-session[13049]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:30:17.770562 systemd-logind[1701]: New session 26 of user core. Jul 6 23:30:17.783499 systemd[1]: Started session-26.scope - Session 26 of User core. Jul 6 23:30:18.111498 sudo[13053]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Jul 6 23:30:18.111806 sudo[13053]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jul 6 23:30:18.114898 sudo[13053]: pam_unix(sudo:session): session closed for user root Jul 6 23:30:18.120322 sudo[13052]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Jul 6 23:30:18.120642 sudo[13052]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jul 6 23:30:18.139650 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jul 6 23:30:18.164914 augenrules[13075]: No rules Jul 6 23:30:18.166024 systemd[1]: audit-rules.service: Deactivated successfully. Jul 6 23:30:18.166263 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jul 6 23:30:18.166989 sudo[13052]: pam_unix(sudo:session): session closed for user root Jul 6 23:30:18.276215 sshd[13051]: Connection closed by 10.200.16.10 port 34788 Jul 6 23:30:18.276629 sshd-session[13049]: pam_unix(sshd:session): session closed for user core Jul 6 23:30:18.279983 systemd[1]: sshd@23-10.200.8.18:22-10.200.16.10:34788.service: Deactivated successfully. Jul 6 23:30:18.281575 systemd[1]: session-26.scope: Deactivated successfully. Jul 6 23:30:18.282204 systemd-logind[1701]: Session 26 logged out. Waiting for processes to exit. Jul 6 23:30:18.282964 systemd-logind[1701]: Removed session 26. Jul 6 23:30:18.386554 systemd[1]: Started sshd@24-10.200.8.18:22-10.200.16.10:34800.service - OpenSSH per-connection server daemon (10.200.16.10:34800). Jul 6 23:30:19.032124 sshd[13084]: Accepted publickey for core from 10.200.16.10 port 34800 ssh2: RSA SHA256:IDZY9LUw7eRIRMWEPq9LVAUVCt3KY9vfW+V4XsN2mGI Jul 6 23:30:19.033437 sshd-session[13084]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:30:19.037440 systemd-logind[1701]: New session 27 of user core. Jul 6 23:30:19.048501 systemd[1]: Started session-27.scope - Session 27 of User core. Jul 6 23:30:19.377673 sudo[13087]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/mkdir -p . Jul 6 23:30:19.377987 sudo[13087]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jul 6 23:30:19.381097 sudo[13087]: pam_unix(sudo:session): session closed for user root Jul 6 23:30:19.481498 sshd[13086]: Connection closed by 10.200.16.10 port 34800 Jul 6 23:30:19.481860 sshd-session[13084]: pam_unix(sshd:session): session closed for user core Jul 6 23:30:19.484850 systemd[1]: sshd@24-10.200.8.18:22-10.200.16.10:34800.service: Deactivated successfully. Jul 6 23:30:19.486454 systemd[1]: session-27.scope: Deactivated successfully. Jul 6 23:30:19.487086 systemd-logind[1701]: Session 27 logged out. Waiting for processes to exit. Jul 6 23:30:19.487921 systemd-logind[1701]: Removed session 27. Jul 6 23:30:19.592932 systemd[1]: Started sshd@25-10.200.8.18:22-10.200.16.10:34802.service - OpenSSH per-connection server daemon (10.200.16.10:34802). Jul 6 23:30:20.223902 sshd[13093]: Accepted publickey for core from 10.200.16.10 port 34802 ssh2: RSA SHA256:IDZY9LUw7eRIRMWEPq9LVAUVCt3KY9vfW+V4XsN2mGI Jul 6 23:30:20.225172 sshd-session[13093]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:30:20.229174 systemd-logind[1701]: New session 28 of user core. Jul 6 23:30:20.240499 systemd[1]: Started session-28.scope - Session 28 of User core. Jul 6 23:30:20.570310 sudo[13096]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/install -m 0755 /dev/stdin kolet Jul 6 23:30:20.570634 sudo[13096]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jul 6 23:30:22.789936 update_engine[1702]: I20250706 23:30:22.789870 1702 prefs.cc:52] certificate-report-to-send-update not present in /var/lib/update_engine/prefs Jul 6 23:30:22.789936 update_engine[1702]: I20250706 23:30:22.789924 1702 prefs.cc:52] certificate-report-to-send-download not present in /var/lib/update_engine/prefs Jul 6 23:30:22.790342 update_engine[1702]: I20250706 23:30:22.790132 1702 prefs.cc:52] aleph-version not present in /var/lib/update_engine/prefs Jul 6 23:30:22.790630 update_engine[1702]: I20250706 23:30:22.790604 1702 omaha_request_params.cc:62] Current group set to stable Jul 6 23:30:22.790730 update_engine[1702]: I20250706 23:30:22.790709 1702 update_attempter.cc:499] Already updated boot flags. Skipping. Jul 6 23:30:22.790730 update_engine[1702]: I20250706 23:30:22.790724 1702 update_attempter.cc:643] Scheduling an action processor start. Jul 6 23:30:22.790783 update_engine[1702]: I20250706 23:30:22.790741 1702 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Jul 6 23:30:22.790783 update_engine[1702]: I20250706 23:30:22.790774 1702 prefs.cc:52] previous-version not present in /var/lib/update_engine/prefs Jul 6 23:30:22.790856 update_engine[1702]: I20250706 23:30:22.790834 1702 omaha_request_action.cc:271] Posting an Omaha request to disabled Jul 6 23:30:22.790856 update_engine[1702]: I20250706 23:30:22.790850 1702 omaha_request_action.cc:272] Request: Jul 6 23:30:22.790856 update_engine[1702]: Jul 6 23:30:22.790856 update_engine[1702]: Jul 6 23:30:22.790856 update_engine[1702]: Jul 6 23:30:22.790856 update_engine[1702]: Jul 6 23:30:22.790856 update_engine[1702]: Jul 6 23:30:22.790856 update_engine[1702]: Jul 6 23:30:22.790856 update_engine[1702]: Jul 6 23:30:22.790856 update_engine[1702]: Jul 6 23:30:22.791076 update_engine[1702]: I20250706 23:30:22.790859 1702 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Jul 6 23:30:22.791106 locksmithd[1756]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_CHECKING_FOR_UPDATE" NewVersion=0.0.0 NewSize=0 Jul 6 23:30:22.792116 update_engine[1702]: I20250706 23:30:22.792089 1702 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Jul 6 23:30:22.792465 update_engine[1702]: I20250706 23:30:22.792438 1702 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Jul 6 23:30:22.936536 update_engine[1702]: E20250706 23:30:22.936487 1702 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Jul 6 23:30:22.936600 update_engine[1702]: I20250706 23:30:22.936568 1702 libcurl_http_fetcher.cc:283] No HTTP response, retry 1 Jul 6 23:30:24.305198 sudo[13096]: pam_unix(sudo:session): session closed for user root Jul 6 23:30:24.414221 sshd[13095]: Connection closed by 10.200.16.10 port 34802 Jul 6 23:30:24.414487 sshd-session[13093]: pam_unix(sshd:session): session closed for user core Jul 6 23:30:24.417649 systemd[1]: sshd@25-10.200.8.18:22-10.200.16.10:34802.service: Deactivated successfully. Jul 6 23:30:24.419255 systemd[1]: session-28.scope: Deactivated successfully. Jul 6 23:30:24.419922 systemd-logind[1701]: Session 28 logged out. Waiting for processes to exit. Jul 6 23:30:24.421118 systemd-logind[1701]: Removed session 28. Jul 6 23:30:24.524720 systemd[1]: Started sshd@26-10.200.8.18:22-10.200.16.10:59962.service - OpenSSH per-connection server daemon (10.200.16.10:59962). Jul 6 23:30:25.154982 sshd[13103]: Accepted publickey for core from 10.200.16.10 port 59962 ssh2: RSA SHA256:IDZY9LUw7eRIRMWEPq9LVAUVCt3KY9vfW+V4XsN2mGI Jul 6 23:30:25.156332 sshd-session[13103]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:30:25.160627 systemd-logind[1701]: New session 29 of user core. Jul 6 23:30:25.172505 systemd[1]: Started session-29.scope - Session 29 of User core. Jul 6 23:30:25.655395 sshd[13107]: Connection closed by 10.200.16.10 port 59962 Jul 6 23:30:25.655784 sshd-session[13103]: pam_unix(sshd:session): session closed for user core Jul 6 23:30:25.658899 systemd[1]: sshd@26-10.200.8.18:22-10.200.16.10:59962.service: Deactivated successfully. Jul 6 23:30:25.660500 systemd[1]: session-29.scope: Deactivated successfully. Jul 6 23:30:25.661117 systemd-logind[1701]: Session 29 logged out. Waiting for processes to exit. Jul 6 23:30:25.661874 systemd-logind[1701]: Removed session 29. Jul 6 23:30:25.770882 systemd[1]: Started sshd@27-10.200.8.18:22-10.200.16.10:59978.service - OpenSSH per-connection server daemon (10.200.16.10:59978). Jul 6 23:30:26.400986 sshd[13122]: Accepted publickey for core from 10.200.16.10 port 59978 ssh2: RSA SHA256:IDZY9LUw7eRIRMWEPq9LVAUVCt3KY9vfW+V4XsN2mGI Jul 6 23:30:26.402355 sshd-session[13122]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:30:26.406651 systemd-logind[1701]: New session 30 of user core. Jul 6 23:30:26.419507 systemd[1]: Started session-30.scope - Session 30 of User core. Jul 6 23:30:27.613614 systemd[1]: Starting docker.service - Docker Application Container Engine... Jul 6 23:30:27.613772 (dockerd)[13144]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPTS, DOCKER_OPT_BIP, DOCKER_OPT_IPMASQ, DOCKER_OPT_MTU Jul 6 23:30:30.051217 dockerd[13144]: time="2025-07-06T23:30:30.051156971Z" level=info msg="Starting up" Jul 6 23:30:31.086175 dockerd[13144]: time="2025-07-06T23:30:31.086129296Z" level=info msg="Loading containers: start." Jul 6 23:30:31.271405 kernel: Initializing XFRM netlink socket Jul 6 23:30:31.333342 systemd-networkd[1407]: docker0: Link UP Jul 6 23:30:31.376681 dockerd[13144]: time="2025-07-06T23:30:31.376555934Z" level=info msg="Loading containers: done." Jul 6 23:30:31.389645 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck1644743103-merged.mount: Deactivated successfully. Jul 6 23:30:31.397835 dockerd[13144]: time="2025-07-06T23:30:31.397802242Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2 Jul 6 23:30:31.397904 dockerd[13144]: time="2025-07-06T23:30:31.397875634Z" level=info msg="Docker daemon" commit=41ca978a0a5400cc24b274137efa9f25517fcc0b containerd-snapshotter=false storage-driver=overlay2 version=27.3.1 Jul 6 23:30:31.397990 dockerd[13144]: time="2025-07-06T23:30:31.397969524Z" level=info msg="Daemon has completed initialization" Jul 6 23:30:31.453985 dockerd[13144]: time="2025-07-06T23:30:31.453937050Z" level=info msg="API listen on /run/docker.sock" Jul 6 23:30:31.454051 systemd[1]: Started docker.service - Docker Application Container Engine. Jul 6 23:30:32.789324 update_engine[1702]: I20250706 23:30:32.789245 1702 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Jul 6 23:30:32.789734 update_engine[1702]: I20250706 23:30:32.789525 1702 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Jul 6 23:30:32.789771 update_engine[1702]: I20250706 23:30:32.789744 1702 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Jul 6 23:30:32.798024 update_engine[1702]: E20250706 23:30:32.797981 1702 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Jul 6 23:30:32.798101 update_engine[1702]: I20250706 23:30:32.798043 1702 libcurl_http_fetcher.cc:283] No HTTP response, retry 2 Jul 6 23:30:33.708840 systemd[1]: var-lib-docker-overlay2-a910b7078ef6e256f1c3bdb7dfb27fdc2ffae38615141ee385dd66b84ba749b1\x2dinit-merged.mount: Deactivated successfully. Jul 6 23:30:33.776796 kernel: docker0: port 1(veth872c1af) entered blocking state Jul 6 23:30:33.776891 kernel: docker0: port 1(veth872c1af) entered disabled state Jul 6 23:30:33.776913 kernel: veth872c1af: entered allmulticast mode Jul 6 23:30:33.781869 kernel: veth872c1af: entered promiscuous mode Jul 6 23:30:33.782479 systemd-networkd[1407]: veth872c1af: Link UP Jul 6 23:30:34.958323 containerd[1734]: time="2025-07-06T23:30:34.958234490Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jul 6 23:30:34.958323 containerd[1734]: time="2025-07-06T23:30:34.958295885Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jul 6 23:30:34.958323 containerd[1734]: time="2025-07-06T23:30:34.958308983Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 6 23:30:34.958796 containerd[1734]: time="2025-07-06T23:30:34.958402175Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 6 23:30:34.994529 systemd[1]: Started docker-62664ea1b8a3605bfbb0f8fed776176b7466c4f381ca7c0da9b2293d149a4bc9.scope - libcontainer container 62664ea1b8a3605bfbb0f8fed776176b7466c4f381ca7c0da9b2293d149a4bc9. Jul 6 23:30:35.050407 kernel: eth0: renamed from veth9de2c02 Jul 6 23:30:35.063944 kernel: docker0: port 1(veth872c1af) entered blocking state Jul 6 23:30:35.063996 kernel: docker0: port 1(veth872c1af) entered forwarding state Jul 6 23:30:35.064051 systemd-networkd[1407]: veth872c1af: Gained carrier Jul 6 23:30:35.064328 systemd-networkd[1407]: docker0: Gained carrier Jul 6 23:30:36.881520 systemd-networkd[1407]: veth872c1af: Gained IPv6LL Jul 6 23:30:37.009500 systemd-networkd[1407]: docker0: Gained IPv6LL Jul 6 23:30:38.343428 systemd[1]: docker-62664ea1b8a3605bfbb0f8fed776176b7466c4f381ca7c0da9b2293d149a4bc9.scope: Deactivated successfully. Jul 6 23:30:38.355584 dockerd[13144]: time="2025-07-06T23:30:38.355542015Z" level=info msg="ignoring event" container=62664ea1b8a3605bfbb0f8fed776176b7466c4f381ca7c0da9b2293d149a4bc9 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Jul 6 23:30:38.355870 containerd[1734]: time="2025-07-06T23:30:38.355649705Z" level=info msg="shim disconnected" id=62664ea1b8a3605bfbb0f8fed776176b7466c4f381ca7c0da9b2293d149a4bc9 namespace=moby Jul 6 23:30:38.355870 containerd[1734]: time="2025-07-06T23:30:38.355699001Z" level=warning msg="cleaning up after shim disconnected" id=62664ea1b8a3605bfbb0f8fed776176b7466c4f381ca7c0da9b2293d149a4bc9 namespace=moby Jul 6 23:30:38.355870 containerd[1734]: time="2025-07-06T23:30:38.355706900Z" level=info msg="cleaning up dead shim" namespace=moby Jul 6 23:30:38.386778 systemd-networkd[1407]: veth872c1af: Lost carrier Jul 6 23:30:38.387400 kernel: docker0: port 1(veth872c1af) entered disabled state Jul 6 23:30:38.387439 kernel: veth9de2c02: renamed from eth0 Jul 6 23:30:38.420612 systemd-networkd[1407]: veth872c1af: Link DOWN Jul 6 23:30:38.424459 kernel: docker0: port 1(veth872c1af) entered disabled state Jul 6 23:30:38.424510 kernel: veth872c1af (unregistering): left allmulticast mode Jul 6 23:30:38.430775 kernel: veth872c1af (unregistering): left promiscuous mode Jul 6 23:30:38.434140 kernel: docker0: port 1(veth872c1af) entered disabled state Jul 6 23:30:38.464442 systemd[1]: run-docker-netns-96e8b426ce6e.mount: Deactivated successfully. Jul 6 23:30:38.468656 systemd[1]: var-lib-docker-overlay2-a910b7078ef6e256f1c3bdb7dfb27fdc2ffae38615141ee385dd66b84ba749b1-merged.mount: Deactivated successfully. Jul 6 23:30:38.600522 sshd[13124]: Connection closed by 10.200.16.10 port 59978 Jul 6 23:30:38.600868 sshd-session[13122]: pam_unix(sshd:session): session closed for user core Jul 6 23:30:38.604008 systemd[1]: sshd@27-10.200.8.18:22-10.200.16.10:59978.service: Deactivated successfully. Jul 6 23:30:38.605581 systemd[1]: session-30.scope: Deactivated successfully. Jul 6 23:30:38.606191 systemd-logind[1701]: Session 30 logged out. Waiting for processes to exit. Jul 6 23:30:38.606944 systemd-logind[1701]: Removed session 30. Jul 6 23:30:38.713652 systemd[1]: Started sshd@28-10.200.8.18:22-10.200.16.10:49712.service - OpenSSH per-connection server daemon (10.200.16.10:49712). Jul 6 23:30:39.346769 sshd[13420]: Accepted publickey for core from 10.200.16.10 port 49712 ssh2: RSA SHA256:IDZY9LUw7eRIRMWEPq9LVAUVCt3KY9vfW+V4XsN2mGI Jul 6 23:30:39.348056 sshd-session[13420]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:30:39.352257 systemd-logind[1701]: New session 31 of user core. Jul 6 23:30:39.362514 systemd[1]: Started session-31.scope - Session 31 of User core. Jul 6 23:30:39.441646 systemd-networkd[1407]: docker0: Lost carrier Jul 6 23:30:39.743225 systemd[1]: var-lib-docker-overlay2-0795e2d0d5adb443b5b779112eee8c919050f730c6f7f2ae0aaad155a3ae8071\x2dinit-merged.mount: Deactivated successfully. Jul 6 23:30:39.816328 kernel: docker0: port 1(veth07839d4) entered blocking state Jul 6 23:30:39.816448 kernel: docker0: port 1(veth07839d4) entered disabled state Jul 6 23:30:39.819216 kernel: veth07839d4: entered allmulticast mode Jul 6 23:30:39.822096 kernel: veth07839d4: entered promiscuous mode Jul 6 23:30:39.825630 kernel: docker0: port 1(veth07839d4) entered blocking state Jul 6 23:30:39.829125 kernel: docker0: port 1(veth07839d4) entered forwarding state Jul 6 23:30:39.829970 systemd-networkd[1407]: veth07839d4: Link UP Jul 6 23:30:39.830404 kernel: docker0: port 1(veth07839d4) entered disabled state Jul 6 23:30:39.908892 containerd[1734]: time="2025-07-06T23:30:39.908801802Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jul 6 23:30:39.908892 containerd[1734]: time="2025-07-06T23:30:39.908864996Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jul 6 23:30:39.908892 containerd[1734]: time="2025-07-06T23:30:39.908877895Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 6 23:30:39.909460 containerd[1734]: time="2025-07-06T23:30:39.908953188Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 6 23:30:39.929541 systemd[1]: Started docker-c48fddfea36b34853c7bc22c61c56bd4a562176be51cf9ae7c8a6d6f0622db7c.scope - libcontainer container c48fddfea36b34853c7bc22c61c56bd4a562176be51cf9ae7c8a6d6f0622db7c. Jul 6 23:30:39.986404 kernel: eth0: renamed from veth60dde49 Jul 6 23:30:40.002638 kernel: docker0: port 1(veth07839d4) entered blocking state Jul 6 23:30:40.002725 kernel: docker0: port 1(veth07839d4) entered forwarding state Jul 6 23:30:40.003090 systemd-networkd[1407]: veth07839d4: Gained carrier Jul 6 23:30:40.003347 systemd-networkd[1407]: docker0: Gained carrier Jul 6 23:30:40.042481 systemd[1]: docker-c48fddfea36b34853c7bc22c61c56bd4a562176be51cf9ae7c8a6d6f0622db7c.scope: Deactivated successfully. Jul 6 23:30:40.063484 containerd[1734]: time="2025-07-06T23:30:40.063306577Z" level=info msg="shim disconnected" id=c48fddfea36b34853c7bc22c61c56bd4a562176be51cf9ae7c8a6d6f0622db7c namespace=moby Jul 6 23:30:40.063484 containerd[1734]: time="2025-07-06T23:30:40.063359672Z" level=warning msg="cleaning up after shim disconnected" id=c48fddfea36b34853c7bc22c61c56bd4a562176be51cf9ae7c8a6d6f0622db7c namespace=moby Jul 6 23:30:40.063484 containerd[1734]: time="2025-07-06T23:30:40.063369071Z" level=info msg="cleaning up dead shim" namespace=moby Jul 6 23:30:40.063652 dockerd[13144]: time="2025-07-06T23:30:40.063422866Z" level=info msg="ignoring event" container=c48fddfea36b34853c7bc22c61c56bd4a562176be51cf9ae7c8a6d6f0622db7c module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Jul 6 23:30:40.094306 systemd-networkd[1407]: veth07839d4: Lost carrier Jul 6 23:30:40.094470 kernel: docker0: port 1(veth07839d4) entered disabled state Jul 6 23:30:40.094504 kernel: veth60dde49: renamed from eth0 Jul 6 23:30:40.127656 systemd-networkd[1407]: veth07839d4: Link DOWN Jul 6 23:30:40.131407 kernel: docker0: port 1(veth07839d4) entered disabled state Jul 6 23:30:40.138054 kernel: veth07839d4 (unregistering): left allmulticast mode Jul 6 23:30:40.138113 kernel: veth07839d4 (unregistering): left promiscuous mode Jul 6 23:30:40.141373 kernel: docker0: port 1(veth07839d4) entered disabled state Jul 6 23:30:40.297162 sshd[13422]: Connection closed by 10.200.16.10 port 49712 Jul 6 23:30:40.297522 sshd-session[13420]: pam_unix(sshd:session): session closed for user core Jul 6 23:30:40.300745 systemd[1]: sshd@28-10.200.8.18:22-10.200.16.10:49712.service: Deactivated successfully. Jul 6 23:30:40.302335 systemd[1]: session-31.scope: Deactivated successfully. Jul 6 23:30:40.302968 systemd-logind[1701]: Session 31 logged out. Waiting for processes to exit. Jul 6 23:30:40.303772 systemd-logind[1701]: Removed session 31. Jul 6 23:30:40.408606 systemd[1]: Started sshd@29-10.200.8.18:22-10.200.16.10:55238.service - OpenSSH per-connection server daemon (10.200.16.10:55238). Jul 6 23:30:40.449566 systemd-networkd[1407]: docker0: Lost carrier Jul 6 23:30:40.740434 systemd[1]: var-lib-docker-overlay2-0795e2d0d5adb443b5b779112eee8c919050f730c6f7f2ae0aaad155a3ae8071-merged.mount: Deactivated successfully. Jul 6 23:30:41.038508 sshd[13528]: Accepted publickey for core from 10.200.16.10 port 55238 ssh2: RSA SHA256:IDZY9LUw7eRIRMWEPq9LVAUVCt3KY9vfW+V4XsN2mGI Jul 6 23:30:41.039787 sshd-session[13528]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:30:41.043821 systemd-logind[1701]: New session 32 of user core. Jul 6 23:30:41.058501 systemd[1]: Started session-32.scope - Session 32 of User core. Jul 6 23:30:41.456478 ntpdate[13540]: ntpdate 4.2.8p17@1.4004-o Sun Jul 6 21:18:31 UTC 2025 (1) Jul 6 23:30:42.790118 update_engine[1702]: I20250706 23:30:42.790040 1702 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Jul 6 23:30:42.790529 update_engine[1702]: I20250706 23:30:42.790282 1702 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Jul 6 23:30:42.790574 update_engine[1702]: I20250706 23:30:42.790524 1702 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Jul 6 23:30:42.820461 update_engine[1702]: E20250706 23:30:42.820420 1702 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Jul 6 23:30:42.820517 update_engine[1702]: I20250706 23:30:42.820478 1702 libcurl_http_fetcher.cc:283] No HTTP response, retry 3 Jul 6 23:30:48.182131 ntpdate[13540]: adjust time server 89.234.64.77 offset -0.024598 sec Jul 6 23:30:48.283344 sshd[13530]: Connection closed by 10.200.16.10 port 55238 Jul 6 23:30:48.283756 sshd-session[13528]: pam_unix(sshd:session): session closed for user core Jul 6 23:30:48.286895 systemd[1]: sshd@29-10.200.8.18:22-10.200.16.10:55238.service: Deactivated successfully. Jul 6 23:30:48.288524 systemd[1]: session-32.scope: Deactivated successfully. Jul 6 23:30:48.289141 systemd-logind[1701]: Session 32 logged out. Waiting for processes to exit. Jul 6 23:30:48.289891 systemd-logind[1701]: Removed session 32.