Jun 20 19:11:47.207601 kernel: Linux version 6.12.34-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.44 p1) 2.44.0) #1 SMP PREEMPT_DYNAMIC Fri Jun 20 17:06:39 -00 2025 Jun 20 19:11:47.207629 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=b7bb3b1ced9c5d47870a8b74c6c30075189c27e25d75251cfa7215e4bbff75ea Jun 20 19:11:47.207640 kernel: BIOS-provided physical RAM map: Jun 20 19:11:47.207646 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Jun 20 19:11:47.207653 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Jun 20 19:11:47.207659 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Jun 20 19:11:47.207697 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Jun 20 19:11:47.207711 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Jun 20 19:11:47.207719 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Jun 20 19:11:47.207725 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Jun 20 19:11:47.207732 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Jun 20 19:11:47.207739 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Jun 20 19:11:47.207746 kernel: printk: legacy bootconsole [earlyser0] enabled Jun 20 19:11:47.207753 kernel: NX (Execute Disable) protection: active Jun 20 19:11:47.207764 kernel: APIC: Static calls initialized Jun 20 19:11:47.207772 kernel: efi: EFI v2.7 by Microsoft Jun 20 19:11:47.207780 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Jun 20 19:11:47.207788 kernel: random: crng init done Jun 20 19:11:47.207795 kernel: secureboot: Secure boot disabled Jun 20 19:11:47.207803 kernel: SMBIOS 3.1.0 present. Jun 20 19:11:47.207810 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Jun 20 19:11:47.207818 kernel: DMI: Memory slots populated: 3/3 Jun 20 19:11:47.207825 kernel: Hypervisor detected: Microsoft Hyper-V Jun 20 19:11:47.207833 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Jun 20 19:11:47.207843 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Jun 20 19:11:47.207850 kernel: Hyper-V: Using hypercall for remote TLB flush Jun 20 19:11:47.207858 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Jun 20 19:11:47.207866 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Jun 20 19:11:47.207874 kernel: tsc: Marking TSC unstable due to running on Hyper-V Jun 20 19:11:47.207882 kernel: tsc: Detected 2593.992 MHz processor Jun 20 19:11:47.207890 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Jun 20 19:11:47.207898 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Jun 20 19:11:47.207906 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Jun 20 19:11:47.207916 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Jun 20 19:11:47.207924 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Jun 20 19:11:47.207931 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Jun 20 19:11:47.207939 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Jun 20 19:11:47.207946 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Jun 20 19:11:47.207954 kernel: Using GB pages for direct mapping Jun 20 19:11:47.207962 kernel: ACPI: Early table checksum verification disabled Jun 20 19:11:47.207973 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Jun 20 19:11:47.207984 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 20 19:11:47.207992 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 20 19:11:47.208000 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Jun 20 19:11:47.208008 kernel: ACPI: FACS 0x000000003FFFE000 000040 Jun 20 19:11:47.208017 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 20 19:11:47.208025 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 20 19:11:47.208036 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 20 19:11:47.208044 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 20 19:11:47.208052 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 20 19:11:47.208060 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 20 19:11:47.208068 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jun 20 19:11:47.208077 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Jun 20 19:11:47.208085 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Jun 20 19:11:47.208093 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Jun 20 19:11:47.208103 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Jun 20 19:11:47.208112 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Jun 20 19:11:47.208120 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Jun 20 19:11:47.208128 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Jun 20 19:11:47.208136 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Jun 20 19:11:47.208144 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Jun 20 19:11:47.208152 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Jun 20 19:11:47.208160 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Jun 20 19:11:47.208168 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Jun 20 19:11:47.208179 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Jun 20 19:11:47.208187 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Jun 20 19:11:47.208195 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Jun 20 19:11:47.208203 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Jun 20 19:11:47.208211 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Jun 20 19:11:47.208219 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Jun 20 19:11:47.208228 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] Jun 20 19:11:47.208236 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] Jun 20 19:11:47.208244 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] Jun 20 19:11:47.208255 kernel: Zone ranges: Jun 20 19:11:47.208263 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Jun 20 19:11:47.208288 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Jun 20 19:11:47.208298 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Jun 20 19:11:47.208306 kernel: Device empty Jun 20 19:11:47.208314 kernel: Movable zone start for each node Jun 20 19:11:47.208323 kernel: Early memory node ranges Jun 20 19:11:47.208331 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Jun 20 19:11:47.208339 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Jun 20 19:11:47.208350 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Jun 20 19:11:47.208358 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Jun 20 19:11:47.208367 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Jun 20 19:11:47.208375 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Jun 20 19:11:47.208383 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Jun 20 19:11:47.208391 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Jun 20 19:11:47.208399 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Jun 20 19:11:47.208407 kernel: ACPI: PM-Timer IO Port: 0x408 Jun 20 19:11:47.208415 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Jun 20 19:11:47.208426 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Jun 20 19:11:47.208434 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Jun 20 19:11:47.208442 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Jun 20 19:11:47.208450 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Jun 20 19:11:47.208458 kernel: CPU topo: Max. logical packages: 1 Jun 20 19:11:47.208466 kernel: CPU topo: Max. logical dies: 1 Jun 20 19:11:47.208474 kernel: CPU topo: Max. dies per package: 1 Jun 20 19:11:47.208482 kernel: CPU topo: Max. threads per core: 1 Jun 20 19:11:47.208490 kernel: CPU topo: Num. cores per package: 6 Jun 20 19:11:47.208501 kernel: CPU topo: Num. threads per package: 6 Jun 20 19:11:47.208509 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs Jun 20 19:11:47.208517 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Jun 20 19:11:47.208525 kernel: Booting paravirtualized kernel on Hyper-V Jun 20 19:11:47.208533 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Jun 20 19:11:47.208541 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Jun 20 19:11:47.208550 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 Jun 20 19:11:47.208558 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 Jun 20 19:11:47.208566 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Jun 20 19:11:47.208576 kernel: Hyper-V: PV spinlocks enabled Jun 20 19:11:47.208584 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Jun 20 19:11:47.208594 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=b7bb3b1ced9c5d47870a8b74c6c30075189c27e25d75251cfa7215e4bbff75ea Jun 20 19:11:47.208605 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Jun 20 19:11:47.208613 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Jun 20 19:11:47.208621 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Jun 20 19:11:47.208629 kernel: Fallback order for Node 0: 0 Jun 20 19:11:47.208638 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 Jun 20 19:11:47.208649 kernel: Policy zone: Normal Jun 20 19:11:47.208664 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jun 20 19:11:47.208673 kernel: software IO TLB: area num 8. Jun 20 19:11:47.208684 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Jun 20 19:11:47.208693 kernel: Kernel/User page tables isolation: enabled Jun 20 19:11:47.208701 kernel: ftrace: allocating 40093 entries in 157 pages Jun 20 19:11:47.208710 kernel: ftrace: allocated 157 pages with 5 groups Jun 20 19:11:47.208719 kernel: Dynamic Preempt: voluntary Jun 20 19:11:47.208727 kernel: rcu: Preemptible hierarchical RCU implementation. Jun 20 19:11:47.208736 kernel: rcu: RCU event tracing is enabled. Jun 20 19:11:47.208745 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Jun 20 19:11:47.208754 kernel: Trampoline variant of Tasks RCU enabled. Jun 20 19:11:47.208765 kernel: Rude variant of Tasks RCU enabled. Jun 20 19:11:47.208774 kernel: Tracing variant of Tasks RCU enabled. Jun 20 19:11:47.208782 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Jun 20 19:11:47.208791 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Jun 20 19:11:47.208800 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jun 20 19:11:47.208811 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jun 20 19:11:47.208819 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jun 20 19:11:47.208828 kernel: Using NULL legacy PIC Jun 20 19:11:47.208837 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Jun 20 19:11:47.208845 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Jun 20 19:11:47.208854 kernel: Console: colour dummy device 80x25 Jun 20 19:11:47.208862 kernel: printk: legacy console [tty1] enabled Jun 20 19:11:47.208871 kernel: printk: legacy console [ttyS0] enabled Jun 20 19:11:47.208879 kernel: printk: legacy bootconsole [earlyser0] disabled Jun 20 19:11:47.208890 kernel: ACPI: Core revision 20240827 Jun 20 19:11:47.208899 kernel: Failed to register legacy timer interrupt Jun 20 19:11:47.208908 kernel: APIC: Switch to symmetric I/O mode setup Jun 20 19:11:47.208916 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Jun 20 19:11:47.208925 kernel: Hyper-V: enabling crash_kexec_post_notifiers Jun 20 19:11:47.208933 kernel: Hyper-V: Using IPI hypercalls Jun 20 19:11:47.208942 kernel: APIC: send_IPI() replaced with hv_send_ipi() Jun 20 19:11:47.208951 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Jun 20 19:11:47.208959 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Jun 20 19:11:47.208970 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Jun 20 19:11:47.208979 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Jun 20 19:11:47.208987 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Jun 20 19:11:47.208996 kernel: Hyper-V: Using enlightened APIC (xapic mode) Jun 20 19:11:47.209004 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Jun 20 19:11:47.209013 kernel: APIC: read() replaced with hv_apic_read() Jun 20 19:11:47.209022 kernel: APIC: write() replaced with hv_apic_write() Jun 20 19:11:47.209030 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Jun 20 19:11:47.209039 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Jun 20 19:11:47.209050 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593992) Jun 20 19:11:47.209058 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Jun 20 19:11:47.209067 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Jun 20 19:11:47.209075 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Jun 20 19:11:47.209084 kernel: Spectre V2 : Mitigation: Retpolines Jun 20 19:11:47.209092 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Jun 20 19:11:47.209101 kernel: Speculative Store Bypass: Vulnerable Jun 20 19:11:47.209109 kernel: MDS: Mitigation: Clear CPU buffers Jun 20 19:11:47.209117 kernel: TAA: Mitigation: Clear CPU buffers Jun 20 19:11:47.209126 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Jun 20 19:11:47.209136 kernel: ITS: Mitigation: Aligned branch/return thunks Jun 20 19:11:47.209145 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Jun 20 19:11:47.209153 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Jun 20 19:11:47.209162 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Jun 20 19:11:47.209170 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Jun 20 19:11:47.209179 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Jun 20 19:11:47.209187 kernel: Freeing SMP alternatives memory: 32K Jun 20 19:11:47.209195 kernel: pid_max: default: 32768 minimum: 301 Jun 20 19:11:47.209204 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Jun 20 19:11:47.209212 kernel: landlock: Up and running. Jun 20 19:11:47.209220 kernel: SELinux: Initializing. Jun 20 19:11:47.209231 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Jun 20 19:11:47.209240 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Jun 20 19:11:47.209248 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Jun 20 19:11:47.209257 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Jun 20 19:11:47.209265 kernel: signal: max sigframe size: 1776 Jun 20 19:11:47.209283 kernel: rcu: Hierarchical SRCU implementation. Jun 20 19:11:47.209292 kernel: rcu: Max phase no-delay instances is 400. Jun 20 19:11:47.209301 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Jun 20 19:11:47.209309 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Jun 20 19:11:47.209318 kernel: smp: Bringing up secondary CPUs ... Jun 20 19:11:47.209330 kernel: smpboot: x86: Booting SMP configuration: Jun 20 19:11:47.209338 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Jun 20 19:11:47.209347 kernel: smp: Brought up 1 node, 6 CPUs Jun 20 19:11:47.209355 kernel: smpboot: Total of 6 processors activated (31127.90 BogoMIPS) Jun 20 19:11:47.209364 kernel: Memory: 115339084K/117439364K available (14336K kernel code, 2430K rwdata, 9956K rodata, 54424K init, 2544K bss, 2090004K reserved, 0K cma-reserved) Jun 20 19:11:47.209373 kernel: devtmpfs: initialized Jun 20 19:11:47.209381 kernel: x86/mm: Memory block size: 1024MB Jun 20 19:11:47.209390 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Jun 20 19:11:47.209398 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Jun 20 19:11:47.209410 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Jun 20 19:11:47.209418 kernel: pinctrl core: initialized pinctrl subsystem Jun 20 19:11:47.209427 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jun 20 19:11:47.209435 kernel: audit: initializing netlink subsys (disabled) Jun 20 19:11:47.209444 kernel: audit: type=2000 audit(1750446703.028:1): state=initialized audit_enabled=0 res=1 Jun 20 19:11:47.209453 kernel: thermal_sys: Registered thermal governor 'step_wise' Jun 20 19:11:47.209461 kernel: thermal_sys: Registered thermal governor 'user_space' Jun 20 19:11:47.209470 kernel: cpuidle: using governor menu Jun 20 19:11:47.209478 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Jun 20 19:11:47.209489 kernel: dca service started, version 1.12.1 Jun 20 19:11:47.209498 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Jun 20 19:11:47.209506 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Jun 20 19:11:47.209515 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Jun 20 19:11:47.209523 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Jun 20 19:11:47.209532 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Jun 20 19:11:47.209540 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Jun 20 19:11:47.209549 kernel: ACPI: Added _OSI(Module Device) Jun 20 19:11:47.209558 kernel: ACPI: Added _OSI(Processor Device) Jun 20 19:11:47.209568 kernel: ACPI: Added _OSI(Processor Aggregator Device) Jun 20 19:11:47.209577 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Jun 20 19:11:47.209586 kernel: ACPI: Interpreter enabled Jun 20 19:11:47.209594 kernel: ACPI: PM: (supports S0 S5) Jun 20 19:11:47.209603 kernel: ACPI: Using IOAPIC for interrupt routing Jun 20 19:11:47.209611 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Jun 20 19:11:47.209620 kernel: PCI: Ignoring E820 reservations for host bridge windows Jun 20 19:11:47.209628 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Jun 20 19:11:47.209637 kernel: iommu: Default domain type: Translated Jun 20 19:11:47.209648 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Jun 20 19:11:47.209657 kernel: efivars: Registered efivars operations Jun 20 19:11:47.209665 kernel: PCI: Using ACPI for IRQ routing Jun 20 19:11:47.209674 kernel: PCI: System does not support PCI Jun 20 19:11:47.209682 kernel: vgaarb: loaded Jun 20 19:11:47.209691 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Jun 20 19:11:47.209700 kernel: VFS: Disk quotas dquot_6.6.0 Jun 20 19:11:47.209708 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jun 20 19:11:47.209717 kernel: pnp: PnP ACPI init Jun 20 19:11:47.209728 kernel: pnp: PnP ACPI: found 3 devices Jun 20 19:11:47.209736 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Jun 20 19:11:47.209745 kernel: NET: Registered PF_INET protocol family Jun 20 19:11:47.209761 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Jun 20 19:11:47.209770 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Jun 20 19:11:47.209779 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jun 20 19:11:47.209787 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Jun 20 19:11:47.209796 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jun 20 19:11:47.209808 kernel: TCP: Hash tables configured (established 524288 bind 65536) Jun 20 19:11:47.209816 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jun 20 19:11:47.209825 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jun 20 19:11:47.209834 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jun 20 19:11:47.209842 kernel: NET: Registered PF_XDP protocol family Jun 20 19:11:47.209851 kernel: PCI: CLS 0 bytes, default 64 Jun 20 19:11:47.209859 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Jun 20 19:11:47.209868 kernel: software IO TLB: mapped [mem 0x000000003b5bf000-0x000000003f5bf000] (64MB) Jun 20 19:11:47.209877 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Jun 20 19:11:47.209888 kernel: Initialise system trusted keyrings Jun 20 19:11:47.209896 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Jun 20 19:11:47.209905 kernel: Key type asymmetric registered Jun 20 19:11:47.209913 kernel: Asymmetric key parser 'x509' registered Jun 20 19:11:47.209922 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Jun 20 19:11:47.209931 kernel: io scheduler mq-deadline registered Jun 20 19:11:47.209939 kernel: io scheduler kyber registered Jun 20 19:11:47.209948 kernel: io scheduler bfq registered Jun 20 19:11:47.209956 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Jun 20 19:11:47.209967 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Jun 20 19:11:47.209976 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Jun 20 19:11:47.209985 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Jun 20 19:11:47.209993 kernel: i8042: PNP: No PS/2 controller found. Jun 20 19:11:47.210150 kernel: rtc_cmos 00:02: registered as rtc0 Jun 20 19:11:47.210251 kernel: rtc_cmos 00:02: setting system clock to 2025-06-20T19:11:46 UTC (1750446706) Jun 20 19:11:47.210404 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Jun 20 19:11:47.210420 kernel: intel_pstate: CPU model not supported Jun 20 19:11:47.210434 kernel: efifb: probing for efifb Jun 20 19:11:47.210442 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Jun 20 19:11:47.210451 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Jun 20 19:11:47.210460 kernel: efifb: scrolling: redraw Jun 20 19:11:47.210468 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Jun 20 19:11:47.210477 kernel: Console: switching to colour frame buffer device 128x48 Jun 20 19:11:47.210485 kernel: fb0: EFI VGA frame buffer device Jun 20 19:11:47.210494 kernel: pstore: Using crash dump compression: deflate Jun 20 19:11:47.210503 kernel: pstore: Registered efi_pstore as persistent store backend Jun 20 19:11:47.210514 kernel: NET: Registered PF_INET6 protocol family Jun 20 19:11:47.210522 kernel: Segment Routing with IPv6 Jun 20 19:11:47.210531 kernel: In-situ OAM (IOAM) with IPv6 Jun 20 19:11:47.210539 kernel: NET: Registered PF_PACKET protocol family Jun 20 19:11:47.210548 kernel: Key type dns_resolver registered Jun 20 19:11:47.210556 kernel: IPI shorthand broadcast: enabled Jun 20 19:11:47.210565 kernel: sched_clock: Marking stable (3718005100, 130108700)->(4174267900, -326154100) Jun 20 19:11:47.210574 kernel: registered taskstats version 1 Jun 20 19:11:47.210582 kernel: Loading compiled-in X.509 certificates Jun 20 19:11:47.210593 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.34-flatcar: 9a085d119111c823c157514215d0379e3a2f1b94' Jun 20 19:11:47.210602 kernel: Demotion targets for Node 0: null Jun 20 19:11:47.210610 kernel: Key type .fscrypt registered Jun 20 19:11:47.210619 kernel: Key type fscrypt-provisioning registered Jun 20 19:11:47.210627 kernel: ima: No TPM chip found, activating TPM-bypass! Jun 20 19:11:47.210636 kernel: ima: Allocated hash algorithm: sha1 Jun 20 19:11:47.210645 kernel: ima: No architecture policies found Jun 20 19:11:47.210653 kernel: clk: Disabling unused clocks Jun 20 19:11:47.210662 kernel: Warning: unable to open an initial console. Jun 20 19:11:47.210673 kernel: Freeing unused kernel image (initmem) memory: 54424K Jun 20 19:11:47.210681 kernel: Write protecting the kernel read-only data: 24576k Jun 20 19:11:47.210690 kernel: Freeing unused kernel image (rodata/data gap) memory: 284K Jun 20 19:11:47.210699 kernel: Run /init as init process Jun 20 19:11:47.210707 kernel: with arguments: Jun 20 19:11:47.210716 kernel: /init Jun 20 19:11:47.210724 kernel: with environment: Jun 20 19:11:47.210733 kernel: HOME=/ Jun 20 19:11:47.210741 kernel: TERM=linux Jun 20 19:11:47.210752 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Jun 20 19:11:47.210761 systemd[1]: Successfully made /usr/ read-only. Jun 20 19:11:47.210773 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Jun 20 19:11:47.210782 systemd[1]: Detected virtualization microsoft. Jun 20 19:11:47.210791 systemd[1]: Detected architecture x86-64. Jun 20 19:11:47.210800 systemd[1]: Running in initrd. Jun 20 19:11:47.210809 systemd[1]: No hostname configured, using default hostname. Jun 20 19:11:47.210820 systemd[1]: Hostname set to . Jun 20 19:11:47.210829 systemd[1]: Initializing machine ID from random generator. Jun 20 19:11:47.210839 systemd[1]: Queued start job for default target initrd.target. Jun 20 19:11:47.210848 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jun 20 19:11:47.210857 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jun 20 19:11:47.210867 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Jun 20 19:11:47.210876 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jun 20 19:11:47.210885 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Jun 20 19:11:47.210897 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Jun 20 19:11:47.210907 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Jun 20 19:11:47.210917 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Jun 20 19:11:47.210926 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jun 20 19:11:47.210935 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jun 20 19:11:47.210944 systemd[1]: Reached target paths.target - Path Units. Jun 20 19:11:47.210953 systemd[1]: Reached target slices.target - Slice Units. Jun 20 19:11:47.210965 systemd[1]: Reached target swap.target - Swaps. Jun 20 19:11:47.210974 systemd[1]: Reached target timers.target - Timer Units. Jun 20 19:11:47.210983 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Jun 20 19:11:47.210992 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jun 20 19:11:47.211001 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Jun 20 19:11:47.211010 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Jun 20 19:11:47.211020 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jun 20 19:11:47.211029 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jun 20 19:11:47.211038 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jun 20 19:11:47.211049 systemd[1]: Reached target sockets.target - Socket Units. Jun 20 19:11:47.211058 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Jun 20 19:11:47.211067 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jun 20 19:11:47.211076 systemd[1]: Finished network-cleanup.service - Network Cleanup. Jun 20 19:11:47.211086 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). Jun 20 19:11:47.211095 systemd[1]: Starting systemd-fsck-usr.service... Jun 20 19:11:47.211104 systemd[1]: Starting systemd-journald.service - Journal Service... Jun 20 19:11:47.211113 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jun 20 19:11:47.211143 systemd-journald[232]: Collecting audit messages is disabled. Jun 20 19:11:47.211166 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jun 20 19:11:47.211176 systemd-journald[232]: Journal started Jun 20 19:11:47.211199 systemd-journald[232]: Runtime Journal (/run/log/journal/7cb9e82640da49448fd0a8e2483c8a57) is 8M, max 2.2G, 2.1G free. Jun 20 19:11:47.213881 systemd-modules-load[234]: Inserted module 'overlay' Jun 20 19:11:47.219224 systemd[1]: Started systemd-journald.service - Journal Service. Jun 20 19:11:47.219604 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Jun 20 19:11:47.220091 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jun 20 19:11:47.220813 systemd[1]: Finished systemd-fsck-usr.service. Jun 20 19:11:47.223062 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jun 20 19:11:47.224176 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jun 20 19:11:47.268834 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jun 20 19:11:47.271486 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jun 20 19:11:47.272117 systemd-tmpfiles[243]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Jun 20 19:11:47.279429 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jun 20 19:11:47.316292 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Jun 20 19:11:47.318362 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jun 20 19:11:47.321471 kernel: Bridge firewalling registered Jun 20 19:11:47.319991 systemd-modules-load[234]: Inserted module 'br_netfilter' Jun 20 19:11:47.321630 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jun 20 19:11:47.323842 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jun 20 19:11:47.324870 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jun 20 19:11:47.345123 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jun 20 19:11:47.355355 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Jun 20 19:11:47.360044 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jun 20 19:11:47.377832 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jun 20 19:11:47.380606 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jun 20 19:11:47.409312 dracut-cmdline[268]: Using kernel command line parameters: rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1 rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=b7bb3b1ced9c5d47870a8b74c6c30075189c27e25d75251cfa7215e4bbff75ea Jun 20 19:11:47.430368 systemd-resolved[279]: Positive Trust Anchors: Jun 20 19:11:47.430379 systemd-resolved[279]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jun 20 19:11:47.430409 systemd-resolved[279]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Jun 20 19:11:47.432982 systemd-resolved[279]: Defaulting to hostname 'linux'. Jun 20 19:11:47.433991 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jun 20 19:11:47.440719 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jun 20 19:11:47.570300 kernel: SCSI subsystem initialized Jun 20 19:11:47.581294 kernel: Loading iSCSI transport class v2.0-870. Jun 20 19:11:47.593293 kernel: iscsi: registered transport (tcp) Jun 20 19:11:47.616511 kernel: iscsi: registered transport (qla4xxx) Jun 20 19:11:47.616539 kernel: QLogic iSCSI HBA Driver Jun 20 19:11:47.634573 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jun 20 19:11:47.667665 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jun 20 19:11:47.669517 systemd[1]: Reached target network-pre.target - Preparation for Network. Jun 20 19:11:47.730621 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Jun 20 19:11:47.733403 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Jun 20 19:11:47.805289 kernel: raid6: avx2x4 gen() 30541 MB/s Jun 20 19:11:47.824284 kernel: raid6: avx2x2 gen() 30969 MB/s Jun 20 19:11:47.848473 kernel: raid6: avx2x1 gen() 26189 MB/s Jun 20 19:11:47.848506 kernel: raid6: using algorithm avx2x2 gen() 30969 MB/s Jun 20 19:11:47.871467 kernel: raid6: .... xor() 18494 MB/s, rmw enabled Jun 20 19:11:47.871504 kernel: raid6: using avx2x2 recovery algorithm Jun 20 19:11:47.894296 kernel: xor: automatically using best checksumming function avx Jun 20 19:11:48.062304 kernel: Btrfs loaded, zoned=no, fsverity=no Jun 20 19:11:48.068938 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Jun 20 19:11:48.071811 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jun 20 19:11:48.109783 systemd-udevd[483]: Using default interface naming scheme 'v255'. Jun 20 19:11:48.115900 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jun 20 19:11:48.118782 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Jun 20 19:11:48.160113 dracut-pre-trigger[484]: rd.md=0: removing MD RAID activation Jun 20 19:11:48.189316 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Jun 20 19:11:48.191290 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jun 20 19:11:48.260715 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jun 20 19:11:48.263214 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Jun 20 19:11:48.291297 kernel: cryptd: max_cpu_qlen set to 1000 Jun 20 19:11:48.301323 kernel: AES CTR mode by8 optimization enabled Jun 20 19:11:48.304328 kernel: hv_vmbus: Vmbus version:4.0 Jun 20 19:11:48.310997 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jun 20 19:11:48.311166 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jun 20 19:11:48.323635 kernel: hv_vmbus: registering driver hyperv_keyboard Jun 20 19:11:48.320173 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Jun 20 19:11:48.342550 kernel: input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/VMBUS:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input0 Jun 20 19:11:48.325932 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jun 20 19:11:48.344307 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jun 20 19:11:48.363262 kernel: pps_core: LinuxPPS API ver. 1 registered Jun 20 19:11:48.363300 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Jun 20 19:11:48.364292 kernel: hid: raw HID events driver (C) Jiri Kosina Jun 20 19:11:48.371289 kernel: hv_vmbus: registering driver hid_hyperv Jun 20 19:11:48.371320 kernel: hv_vmbus: registering driver hv_netvsc Jun 20 19:11:48.374293 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Jun 20 19:11:48.384151 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Jun 20 19:11:48.384340 kernel: PTP clock support registered Jun 20 19:11:48.394443 kernel: hv_utils: Registering HyperV Utility Driver Jun 20 19:11:48.394486 kernel: hv_vmbus: registering driver hv_storvsc Jun 20 19:11:48.394499 kernel: hv_vmbus: registering driver hv_pci Jun 20 19:11:48.394511 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Jun 20 19:11:48.396287 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Jun 20 19:11:48.396423 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Jun 20 19:11:48.396582 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Jun 20 19:11:48.396743 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Jun 20 19:11:48.396862 kernel: hv_vmbus: registering driver hv_utils Jun 20 19:11:48.396875 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint Jun 20 19:11:48.397399 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] Jun 20 19:11:48.398311 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] Jun 20 19:11:48.399291 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] Jun 20 19:11:48.412645 kernel: scsi host0: storvsc_host_t Jun 20 19:11:48.412703 kernel: hv_utils: Heartbeat IC version 3.0 Jun 20 19:11:48.420284 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Jun 20 19:11:48.420332 kernel: hv_utils: Shutdown IC version 3.2 Jun 20 19:11:48.423311 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Jun 20 19:11:48.423348 kernel: hv_utils: TimeSync IC version 4.0 Jun 20 19:11:48.427310 kernel: scsi host1: storvsc_host_t Jun 20 19:11:48.427366 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Jun 20 19:11:48.953327 systemd-resolved[279]: Clock change detected. Flushing caches. Jun 20 19:11:49.071533 kernel: pci 0001:00:00.0: Enabling HDA controller Jun 20 19:11:49.071740 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Jun 20 19:11:49.071967 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Jun 20 19:11:49.072130 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks Jun 20 19:11:49.072264 kernel: sd 0:0:0:1: [sdb] Write Protect is off Jun 20 19:11:49.072400 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Jun 20 19:11:49.072627 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Jun 20 19:11:49.072769 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#519 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jun 20 19:11:49.072896 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#526 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jun 20 19:11:49.073010 kernel: pci 0001:00:00.0: quirk_nvidia_hda+0x0/0x70 took 23254 usecs Jun 20 19:11:49.073141 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Jun 20 19:11:49.073276 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Jun 20 19:11:49.073402 kernel: sd 0:0:0:0: [sda] Write Protect is off Jun 20 19:11:49.073547 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned Jun 20 19:11:49.073675 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Jun 20 19:11:49.073803 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned Jun 20 19:11:49.073928 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Jun 20 19:11:49.074054 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned Jun 20 19:11:49.074178 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#245 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jun 20 19:11:49.074297 kernel: sdb: sdb1 Jun 20 19:11:49.074311 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#321 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jun 20 19:11:49.074425 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk Jun 20 19:11:49.058068 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jun 20 19:11:49.084499 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Jun 20 19:11:49.084527 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Jun 20 19:11:49.094652 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Jun 20 19:11:49.094843 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Jun 20 19:11:49.096547 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Jun 20 19:11:49.726079 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Jun 20 19:11:49.740088 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Jun 20 19:11:49.761525 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Jun 20 19:11:49.772142 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Jun 20 19:11:49.773690 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Jun 20 19:11:49.793442 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Jun 20 19:11:49.836499 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#379 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jun 20 19:11:49.845495 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Jun 20 19:11:49.854495 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#202 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jun 20 19:11:49.869496 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Jun 20 19:11:50.877327 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#27 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jun 20 19:11:50.892384 disk-uuid[719]: The operation has completed successfully. Jun 20 19:11:50.896129 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Jun 20 19:11:51.487254 systemd[1]: disk-uuid.service: Deactivated successfully. Jun 20 19:11:51.487370 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Jun 20 19:11:51.501643 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Jun 20 19:11:51.511105 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Jun 20 19:11:51.520919 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jun 20 19:11:51.530973 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jun 20 19:11:51.540193 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Jun 20 19:11:51.563234 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Jun 20 19:11:51.575357 sh[887]: Success Jun 20 19:11:51.577350 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Jun 20 19:11:51.635315 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Jun 20 19:11:51.635350 kernel: device-mapper: uevent: version 1.0.3 Jun 20 19:11:51.640057 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Jun 20 19:11:51.653490 kernel: device-mapper: verity: sha256 using shash "sha256-avx2" Jun 20 19:11:51.900739 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Jun 20 19:11:51.910505 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Jun 20 19:11:51.931102 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Jun 20 19:11:51.952765 kernel: BTRFS info: 'norecovery' is for compatibility only, recommended to use 'rescue=nologreplay' Jun 20 19:11:51.952801 kernel: BTRFS: device fsid 048b924a-9f97-43f5-98d6-0fff18874966 devid 1 transid 41 /dev/mapper/usr (254:0) scanned by mount (910) Jun 20 19:11:51.958761 kernel: BTRFS info (device dm-0): first mount of filesystem 048b924a-9f97-43f5-98d6-0fff18874966 Jun 20 19:11:51.962938 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Jun 20 19:11:51.966045 kernel: BTRFS info (device dm-0): using free-space-tree Jun 20 19:11:52.371188 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Jun 20 19:11:52.373337 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Jun 20 19:11:52.379801 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Jun 20 19:11:52.380608 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Jun 20 19:11:52.418021 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Jun 20 19:11:52.446492 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/sda6 (8:6) scanned by mount (940) Jun 20 19:11:52.446526 kernel: BTRFS info (device sda6): first mount of filesystem 40288228-7b4b-4005-945b-574c4c10ab32 Jun 20 19:11:52.455960 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Jun 20 19:11:52.459443 kernel: BTRFS info (device sda6): using free-space-tree Jun 20 19:11:52.492509 kernel: BTRFS info (device sda6): last unmount of filesystem 40288228-7b4b-4005-945b-574c4c10ab32 Jun 20 19:11:52.493221 systemd[1]: Finished ignition-setup.service - Ignition (setup). Jun 20 19:11:52.500531 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Jun 20 19:11:52.531783 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jun 20 19:11:52.535089 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jun 20 19:11:52.589575 systemd-networkd[1079]: lo: Link UP Jun 20 19:11:52.589586 systemd-networkd[1079]: lo: Gained carrier Jun 20 19:11:52.590640 systemd-networkd[1079]: Enumeration completed Jun 20 19:11:52.590724 systemd[1]: Started systemd-networkd.service - Network Configuration. Jun 20 19:11:52.591011 systemd-networkd[1079]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jun 20 19:11:52.591015 systemd-networkd[1079]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jun 20 19:11:52.591783 systemd-networkd[1079]: eth0: Link UP Jun 20 19:11:52.591955 systemd-networkd[1079]: eth0: Gained carrier Jun 20 19:11:52.591965 systemd-networkd[1079]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jun 20 19:11:52.597374 systemd[1]: Reached target network.target - Network. Jun 20 19:11:52.653516 systemd-networkd[1079]: eth0: DHCPv4 address 10.200.4.33/24, gateway 10.200.4.1 acquired from 168.63.129.16 Jun 20 19:11:53.943086 ignition[1048]: Ignition 2.21.0 Jun 20 19:11:53.943105 ignition[1048]: Stage: fetch-offline Jun 20 19:11:53.943227 ignition[1048]: no configs at "/usr/lib/ignition/base.d" Jun 20 19:11:53.943241 ignition[1048]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jun 20 19:11:53.943349 ignition[1048]: parsed url from cmdline: "" Jun 20 19:11:53.943353 ignition[1048]: no config URL provided Jun 20 19:11:53.943359 ignition[1048]: reading system config file "/usr/lib/ignition/user.ign" Jun 20 19:11:53.943367 ignition[1048]: no config at "/usr/lib/ignition/user.ign" Jun 20 19:11:53.943373 ignition[1048]: failed to fetch config: resource requires networking Jun 20 19:11:53.943551 ignition[1048]: Ignition finished successfully Jun 20 19:11:53.975778 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Jun 20 19:11:53.978390 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Jun 20 19:11:54.016858 ignition[1094]: Ignition 2.21.0 Jun 20 19:11:54.016872 ignition[1094]: Stage: fetch Jun 20 19:11:54.017121 ignition[1094]: no configs at "/usr/lib/ignition/base.d" Jun 20 19:11:54.017133 ignition[1094]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jun 20 19:11:54.017234 ignition[1094]: parsed url from cmdline: "" Jun 20 19:11:54.017238 ignition[1094]: no config URL provided Jun 20 19:11:54.017243 ignition[1094]: reading system config file "/usr/lib/ignition/user.ign" Jun 20 19:11:54.017252 ignition[1094]: no config at "/usr/lib/ignition/user.ign" Jun 20 19:11:54.017286 ignition[1094]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Jun 20 19:11:54.106651 ignition[1094]: GET result: OK Jun 20 19:11:54.106701 ignition[1094]: config has been read from IMDS userdata Jun 20 19:11:54.106712 ignition[1094]: parsing config with SHA512: 0ca37610597fa326b11a4ef769dcd6a2b534dca94741c5036657581ba962334fd2519337e79f075f9579f1811f0665d01373fd09b24342f16cb2b8c12d124d1f Jun 20 19:11:54.110852 unknown[1094]: fetched base config from "system" Jun 20 19:11:54.111041 ignition[1094]: fetch: fetch complete Jun 20 19:11:54.110859 unknown[1094]: fetched base config from "system" Jun 20 19:11:54.111047 ignition[1094]: fetch: fetch passed Jun 20 19:11:54.110864 unknown[1094]: fetched user config from "azure" Jun 20 19:11:54.111104 ignition[1094]: Ignition finished successfully Jun 20 19:11:54.118087 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Jun 20 19:11:54.120769 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Jun 20 19:11:54.164199 ignition[1104]: Ignition 2.21.0 Jun 20 19:11:54.164213 ignition[1104]: Stage: kargs Jun 20 19:11:54.164498 ignition[1104]: no configs at "/usr/lib/ignition/base.d" Jun 20 19:11:54.164512 ignition[1104]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jun 20 19:11:54.165895 ignition[1104]: kargs: kargs passed Jun 20 19:11:54.174028 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Jun 20 19:11:54.165971 ignition[1104]: Ignition finished successfully Jun 20 19:11:54.182394 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Jun 20 19:11:54.220250 ignition[1114]: Ignition 2.21.0 Jun 20 19:11:54.220264 ignition[1114]: Stage: disks Jun 20 19:11:54.220541 ignition[1114]: no configs at "/usr/lib/ignition/base.d" Jun 20 19:11:54.220554 ignition[1114]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jun 20 19:11:54.227648 systemd[1]: Finished ignition-disks.service - Ignition (disks). Jun 20 19:11:54.221715 ignition[1114]: disks: disks passed Jun 20 19:11:54.229925 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Jun 20 19:11:54.221771 ignition[1114]: Ignition finished successfully Jun 20 19:11:54.238032 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Jun 20 19:11:54.250732 systemd[1]: Reached target local-fs.target - Local File Systems. Jun 20 19:11:54.257217 systemd[1]: Reached target sysinit.target - System Initialization. Jun 20 19:11:54.265639 systemd[1]: Reached target basic.target - Basic System. Jun 20 19:11:54.276962 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Jun 20 19:11:54.393137 systemd-fsck[1126]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks Jun 20 19:11:54.398574 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Jun 20 19:11:54.409594 systemd[1]: Mounting sysroot.mount - /sysroot... Jun 20 19:11:54.632606 systemd-networkd[1079]: eth0: Gained IPv6LL Jun 20 19:11:54.685496 kernel: EXT4-fs (sda9): mounted filesystem 6290a154-3512-46a6-a5f5-a7fb62c65caa r/w with ordered data mode. Quota mode: none. Jun 20 19:11:54.686581 systemd[1]: Mounted sysroot.mount - /sysroot. Jun 20 19:11:54.688588 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Jun 20 19:11:54.709440 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jun 20 19:11:54.711067 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Jun 20 19:11:54.744129 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Jun 20 19:11:54.755492 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/sda6 (8:6) scanned by mount (1140) Jun 20 19:11:54.757604 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Jun 20 19:11:54.757645 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Jun 20 19:11:54.788695 kernel: BTRFS info (device sda6): first mount of filesystem 40288228-7b4b-4005-945b-574c4c10ab32 Jun 20 19:11:54.788722 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Jun 20 19:11:54.788736 kernel: BTRFS info (device sda6): using free-space-tree Jun 20 19:11:54.772913 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Jun 20 19:11:54.791491 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jun 20 19:11:54.798677 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Jun 20 19:11:55.614011 coreos-metadata[1142]: Jun 20 19:11:55.613 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Jun 20 19:11:55.619944 coreos-metadata[1142]: Jun 20 19:11:55.616 INFO Fetch successful Jun 20 19:11:55.619944 coreos-metadata[1142]: Jun 20 19:11:55.616 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Jun 20 19:11:55.632119 coreos-metadata[1142]: Jun 20 19:11:55.623 INFO Fetch successful Jun 20 19:11:55.636144 coreos-metadata[1142]: Jun 20 19:11:55.634 INFO wrote hostname ci-4344.1.0-a-0c04071c8c to /sysroot/etc/hostname Jun 20 19:11:55.636979 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Jun 20 19:11:55.749173 initrd-setup-root[1170]: cut: /sysroot/etc/passwd: No such file or directory Jun 20 19:11:55.779689 initrd-setup-root[1177]: cut: /sysroot/etc/group: No such file or directory Jun 20 19:11:55.795345 initrd-setup-root[1184]: cut: /sysroot/etc/shadow: No such file or directory Jun 20 19:11:55.801313 initrd-setup-root[1191]: cut: /sysroot/etc/gshadow: No such file or directory Jun 20 19:11:56.980253 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Jun 20 19:11:56.983213 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Jun 20 19:11:57.002092 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Jun 20 19:11:57.007271 systemd[1]: sysroot-oem.mount: Deactivated successfully. Jun 20 19:11:57.018351 kernel: BTRFS info (device sda6): last unmount of filesystem 40288228-7b4b-4005-945b-574c4c10ab32 Jun 20 19:11:57.038004 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Jun 20 19:11:57.043532 systemd[1]: Finished ignition-mount.service - Ignition (mount). Jun 20 19:11:57.053302 ignition[1264]: INFO : Ignition 2.21.0 Jun 20 19:11:57.053302 ignition[1264]: INFO : Stage: mount Jun 20 19:11:57.053302 ignition[1264]: INFO : no configs at "/usr/lib/ignition/base.d" Jun 20 19:11:57.053302 ignition[1264]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jun 20 19:11:57.053302 ignition[1264]: INFO : mount: mount passed Jun 20 19:11:57.053302 ignition[1264]: INFO : Ignition finished successfully Jun 20 19:11:57.056306 systemd[1]: Starting ignition-files.service - Ignition (files)... Jun 20 19:11:57.089610 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jun 20 19:11:57.108487 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/sda6 (8:6) scanned by mount (1279) Jun 20 19:11:57.117784 kernel: BTRFS info (device sda6): first mount of filesystem 40288228-7b4b-4005-945b-574c4c10ab32 Jun 20 19:11:57.117812 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Jun 20 19:11:57.121119 kernel: BTRFS info (device sda6): using free-space-tree Jun 20 19:11:57.124882 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jun 20 19:11:57.171973 ignition[1296]: INFO : Ignition 2.21.0 Jun 20 19:11:57.171973 ignition[1296]: INFO : Stage: files Jun 20 19:11:57.177616 ignition[1296]: INFO : no configs at "/usr/lib/ignition/base.d" Jun 20 19:11:57.177616 ignition[1296]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jun 20 19:11:57.177616 ignition[1296]: DEBUG : files: compiled without relabeling support, skipping Jun 20 19:11:57.190756 ignition[1296]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Jun 20 19:11:57.190756 ignition[1296]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Jun 20 19:11:57.223829 ignition[1296]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Jun 20 19:11:57.229153 ignition[1296]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Jun 20 19:11:57.234513 unknown[1296]: wrote ssh authorized keys file for user: core Jun 20 19:11:57.238373 ignition[1296]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Jun 20 19:11:57.252662 ignition[1296]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Jun 20 19:11:57.259797 ignition[1296]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Jun 20 19:11:57.259797 ignition[1296]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Jun 20 19:11:57.259797 ignition[1296]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Jun 20 19:11:57.259797 ignition[1296]: INFO : files: files passed Jun 20 19:11:57.259797 ignition[1296]: INFO : Ignition finished successfully Jun 20 19:11:57.268334 systemd[1]: Finished ignition-files.service - Ignition (files). Jun 20 19:11:57.276119 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Jun 20 19:11:57.306041 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Jun 20 19:11:57.315978 systemd[1]: ignition-quench.service: Deactivated successfully. Jun 20 19:11:57.316070 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Jun 20 19:11:57.351471 initrd-setup-root-after-ignition[1329]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jun 20 19:11:57.357628 initrd-setup-root-after-ignition[1329]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Jun 20 19:11:57.364128 initrd-setup-root-after-ignition[1333]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jun 20 19:11:57.371103 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Jun 20 19:11:57.382158 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Jun 20 19:11:57.384344 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Jun 20 19:11:57.430753 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Jun 20 19:11:57.430860 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Jun 20 19:11:57.439600 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Jun 20 19:11:57.448514 systemd[1]: Reached target initrd.target - Initrd Default Target. Jun 20 19:11:57.456393 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Jun 20 19:11:57.457194 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Jun 20 19:11:57.486703 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jun 20 19:11:57.496969 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Jun 20 19:11:57.515302 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Jun 20 19:11:57.516880 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Jun 20 19:11:57.525548 systemd[1]: Stopped target timers.target - Timer Units. Jun 20 19:11:57.534699 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Jun 20 19:11:57.534817 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jun 20 19:11:57.548318 systemd[1]: Stopped target initrd.target - Initrd Default Target. Jun 20 19:11:57.556660 systemd[1]: Stopped target basic.target - Basic System. Jun 20 19:11:57.558776 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Jun 20 19:11:57.568512 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Jun 20 19:11:57.576701 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Jun 20 19:11:57.585457 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Jun 20 19:11:57.593830 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Jun 20 19:11:57.602173 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Jun 20 19:11:57.610041 systemd[1]: Stopped target sysinit.target - System Initialization. Jun 20 19:11:57.622093 systemd[1]: Stopped target local-fs.target - Local File Systems. Jun 20 19:11:57.629752 systemd[1]: Stopped target swap.target - Swaps. Jun 20 19:11:57.637208 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Jun 20 19:11:57.637328 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Jun 20 19:11:57.648323 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Jun 20 19:11:57.656697 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jun 20 19:11:57.665391 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Jun 20 19:11:57.665508 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jun 20 19:11:57.674542 systemd[1]: dracut-initqueue.service: Deactivated successfully. Jun 20 19:11:57.674655 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Jun 20 19:11:57.683802 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Jun 20 19:11:57.683926 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Jun 20 19:11:57.692944 systemd[1]: ignition-files.service: Deactivated successfully. Jun 20 19:11:57.693064 systemd[1]: Stopped ignition-files.service - Ignition (files). Jun 20 19:11:57.700736 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Jun 20 19:11:57.700849 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Jun 20 19:11:57.710590 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Jun 20 19:11:57.718192 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Jun 20 19:11:57.725686 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Jun 20 19:11:57.725930 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Jun 20 19:11:57.733993 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Jun 20 19:11:57.734112 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Jun 20 19:11:57.749084 systemd[1]: initrd-cleanup.service: Deactivated successfully. Jun 20 19:11:57.785703 ignition[1353]: INFO : Ignition 2.21.0 Jun 20 19:11:57.785703 ignition[1353]: INFO : Stage: umount Jun 20 19:11:57.785703 ignition[1353]: INFO : no configs at "/usr/lib/ignition/base.d" Jun 20 19:11:57.785703 ignition[1353]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jun 20 19:11:57.785703 ignition[1353]: INFO : umount: umount passed Jun 20 19:11:57.785703 ignition[1353]: INFO : Ignition finished successfully Jun 20 19:11:57.751608 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Jun 20 19:11:57.767298 systemd[1]: sysroot-boot.mount: Deactivated successfully. Jun 20 19:11:57.792015 systemd[1]: sysroot-boot.service: Deactivated successfully. Jun 20 19:11:57.792121 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Jun 20 19:11:57.799589 systemd[1]: ignition-mount.service: Deactivated successfully. Jun 20 19:11:57.799680 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Jun 20 19:11:57.802017 systemd[1]: ignition-disks.service: Deactivated successfully. Jun 20 19:11:57.802086 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Jun 20 19:11:57.814856 systemd[1]: ignition-kargs.service: Deactivated successfully. Jun 20 19:11:57.814906 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Jun 20 19:11:57.816998 systemd[1]: ignition-fetch.service: Deactivated successfully. Jun 20 19:11:57.817043 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Jun 20 19:11:57.828289 systemd[1]: Stopped target network.target - Network. Jun 20 19:11:57.835717 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Jun 20 19:11:57.835772 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Jun 20 19:11:57.842659 systemd[1]: Stopped target paths.target - Path Units. Jun 20 19:11:57.850495 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Jun 20 19:11:57.854522 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jun 20 19:11:57.858670 systemd[1]: Stopped target slices.target - Slice Units. Jun 20 19:11:57.866311 systemd[1]: Stopped target sockets.target - Socket Units. Jun 20 19:11:57.876272 systemd[1]: iscsid.socket: Deactivated successfully. Jun 20 19:11:57.876321 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Jun 20 19:11:57.883790 systemd[1]: iscsiuio.socket: Deactivated successfully. Jun 20 19:11:57.883832 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jun 20 19:11:57.891247 systemd[1]: ignition-setup.service: Deactivated successfully. Jun 20 19:11:57.891303 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Jun 20 19:11:57.898929 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Jun 20 19:11:57.898977 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Jun 20 19:11:57.906792 systemd[1]: initrd-setup-root.service: Deactivated successfully. Jun 20 19:11:57.906869 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Jun 20 19:11:57.914719 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Jun 20 19:11:57.921583 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Jun 20 19:11:57.929857 systemd[1]: systemd-resolved.service: Deactivated successfully. Jun 20 19:11:57.929960 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Jun 20 19:11:57.942000 systemd[1]: systemd-networkd.service: Deactivated successfully. Jun 20 19:11:57.942111 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Jun 20 19:11:57.958156 systemd[1]: Stopped target network-pre.target - Preparation for Network. Jun 20 19:11:57.965238 systemd[1]: systemd-networkd.socket: Deactivated successfully. Jun 20 19:11:57.965284 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Jun 20 19:11:57.974040 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Jun 20 19:11:57.980243 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Jun 20 19:11:57.980295 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jun 20 19:11:57.988768 systemd[1]: systemd-sysctl.service: Deactivated successfully. Jun 20 19:11:57.988819 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Jun 20 19:11:57.999684 systemd[1]: systemd-modules-load.service: Deactivated successfully. Jun 20 19:11:57.999730 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Jun 20 19:11:58.001161 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Jun 20 19:11:58.001213 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Jun 20 19:11:58.019595 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Jun 20 19:11:58.022757 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Jun 20 19:11:58.022815 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Jun 20 19:11:58.022853 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Jun 20 19:11:58.022895 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Jun 20 19:11:58.046162 systemd[1]: network-cleanup.service: Deactivated successfully. Jun 20 19:11:58.046271 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Jun 20 19:11:58.063174 systemd[1]: systemd-udevd.service: Deactivated successfully. Jun 20 19:11:58.063349 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Jun 20 19:11:58.076331 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Jun 20 19:11:58.076375 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Jun 20 19:11:58.084641 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Jun 20 19:11:58.084679 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Jun 20 19:11:58.093339 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Jun 20 19:11:58.093390 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Jun 20 19:11:58.105002 systemd[1]: dracut-cmdline.service: Deactivated successfully. Jun 20 19:11:58.105051 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Jun 20 19:11:58.117633 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jun 20 19:11:58.117690 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jun 20 19:11:58.131187 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Jun 20 19:11:58.141948 systemd[1]: systemd-network-generator.service: Deactivated successfully. Jun 20 19:11:58.142004 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Jun 20 19:11:58.151305 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Jun 20 19:11:58.151357 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jun 20 19:11:58.161674 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Jun 20 19:11:58.161722 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jun 20 19:11:58.171601 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Jun 20 19:11:58.171649 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Jun 20 19:11:58.185708 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jun 20 19:11:58.325381 systemd-journald[232]: Received SIGTERM from PID 1 (systemd). Jun 20 19:11:58.185755 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jun 20 19:11:58.196904 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Jun 20 19:11:58.196959 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev\x2dearly.service.mount: Deactivated successfully. Jun 20 19:11:58.196998 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Jun 20 19:11:58.197035 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jun 20 19:11:58.197354 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Jun 20 19:11:58.197444 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Jun 20 19:11:58.201399 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Jun 20 19:11:58.211919 systemd[1]: Starting initrd-switch-root.service - Switch Root... Jun 20 19:11:58.261347 systemd[1]: Switching root. Jun 20 19:11:58.335446 systemd-journald[232]: Journal stopped Jun 20 19:12:03.475400 kernel: SELinux: policy capability network_peer_controls=1 Jun 20 19:12:03.475430 kernel: SELinux: policy capability open_perms=1 Jun 20 19:12:03.475442 kernel: SELinux: policy capability extended_socket_class=1 Jun 20 19:12:03.475451 kernel: SELinux: policy capability always_check_network=0 Jun 20 19:12:03.475460 kernel: SELinux: policy capability cgroup_seclabel=1 Jun 20 19:12:03.475469 kernel: SELinux: policy capability nnp_nosuid_transition=1 Jun 20 19:12:03.475496 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Jun 20 19:12:03.475510 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Jun 20 19:12:03.475519 kernel: SELinux: policy capability userspace_initial_context=0 Jun 20 19:12:03.475529 kernel: audit: type=1403 audit(1750446719.167:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Jun 20 19:12:03.475541 systemd[1]: Successfully loaded SELinux policy in 180.201ms. Jun 20 19:12:03.475555 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 15.054ms. Jun 20 19:12:03.475567 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Jun 20 19:12:03.475579 systemd[1]: Detected virtualization microsoft. Jun 20 19:12:03.475593 systemd[1]: Detected architecture x86-64. Jun 20 19:12:03.475603 systemd[1]: Detected first boot. Jun 20 19:12:03.475614 systemd[1]: Hostname set to . Jun 20 19:12:03.475625 systemd[1]: Initializing machine ID from random generator. Jun 20 19:12:03.475636 zram_generator::config[1399]: No configuration found. Jun 20 19:12:03.475654 kernel: Guest personality initialized and is inactive Jun 20 19:12:03.475663 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Jun 20 19:12:03.475673 kernel: Initialized host personality Jun 20 19:12:03.475683 kernel: NET: Registered PF_VSOCK protocol family Jun 20 19:12:03.475693 systemd[1]: Populated /etc with preset unit settings. Jun 20 19:12:03.475704 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Jun 20 19:12:03.475715 systemd[1]: initrd-switch-root.service: Deactivated successfully. Jun 20 19:12:03.475728 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Jun 20 19:12:03.475738 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Jun 20 19:12:03.475749 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Jun 20 19:12:03.475760 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Jun 20 19:12:03.475771 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Jun 20 19:12:03.475782 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Jun 20 19:12:03.475792 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Jun 20 19:12:03.475803 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Jun 20 19:12:03.475816 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Jun 20 19:12:03.475827 systemd[1]: Created slice user.slice - User and Session Slice. Jun 20 19:12:03.475838 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jun 20 19:12:03.475849 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jun 20 19:12:03.475859 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Jun 20 19:12:03.475870 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Jun 20 19:12:03.475888 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Jun 20 19:12:03.475899 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jun 20 19:12:03.475913 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Jun 20 19:12:03.475924 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jun 20 19:12:03.475935 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jun 20 19:12:03.475947 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Jun 20 19:12:03.475957 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Jun 20 19:12:03.475968 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Jun 20 19:12:03.475979 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Jun 20 19:12:03.475993 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jun 20 19:12:03.476004 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jun 20 19:12:03.476015 systemd[1]: Reached target slices.target - Slice Units. Jun 20 19:12:03.476026 systemd[1]: Reached target swap.target - Swaps. Jun 20 19:12:03.476037 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Jun 20 19:12:03.476049 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Jun 20 19:12:03.476063 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Jun 20 19:12:03.476075 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jun 20 19:12:03.476086 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jun 20 19:12:03.476097 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jun 20 19:12:03.476108 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Jun 20 19:12:03.476119 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Jun 20 19:12:03.476133 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Jun 20 19:12:03.476147 systemd[1]: Mounting media.mount - External Media Directory... Jun 20 19:12:03.476158 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jun 20 19:12:03.476170 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Jun 20 19:12:03.476181 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Jun 20 19:12:03.476192 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Jun 20 19:12:03.476203 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Jun 20 19:12:03.476215 systemd[1]: Reached target machines.target - Containers. Jun 20 19:12:03.476226 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Jun 20 19:12:03.476237 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jun 20 19:12:03.476251 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jun 20 19:12:03.476262 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Jun 20 19:12:03.476273 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jun 20 19:12:03.476284 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jun 20 19:12:03.476296 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jun 20 19:12:03.476307 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Jun 20 19:12:03.476318 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jun 20 19:12:03.476329 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Jun 20 19:12:03.476343 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Jun 20 19:12:03.476354 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Jun 20 19:12:03.476365 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Jun 20 19:12:03.476380 systemd[1]: Stopped systemd-fsck-usr.service. Jun 20 19:12:03.476392 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Jun 20 19:12:03.476404 systemd[1]: Starting systemd-journald.service - Journal Service... Jun 20 19:12:03.476415 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jun 20 19:12:03.476426 kernel: loop: module loaded Jun 20 19:12:03.476439 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jun 20 19:12:03.476451 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Jun 20 19:12:03.476462 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Jun 20 19:12:03.476482 kernel: fuse: init (API version 7.41) Jun 20 19:12:03.476494 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jun 20 19:12:03.476505 systemd[1]: verity-setup.service: Deactivated successfully. Jun 20 19:12:03.476516 systemd[1]: Stopped verity-setup.service. Jun 20 19:12:03.476528 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jun 20 19:12:03.476539 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Jun 20 19:12:03.476553 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Jun 20 19:12:03.476564 systemd[1]: Mounted media.mount - External Media Directory. Jun 20 19:12:03.476575 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Jun 20 19:12:03.476587 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Jun 20 19:12:03.476621 systemd-journald[1506]: Collecting audit messages is disabled. Jun 20 19:12:03.476649 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Jun 20 19:12:03.476661 systemd-journald[1506]: Journal started Jun 20 19:12:03.476688 systemd-journald[1506]: Runtime Journal (/run/log/journal/997a23cdce284882865d884bfc7820d7) is 8M, max 2.2G, 2.1G free. Jun 20 19:12:02.676450 systemd[1]: Queued start job for default target multi-user.target. Jun 20 19:12:02.701893 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Jun 20 19:12:02.702404 systemd[1]: systemd-journald.service: Deactivated successfully. Jun 20 19:12:03.484891 systemd[1]: Started systemd-journald.service - Journal Service. Jun 20 19:12:03.489188 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Jun 20 19:12:03.494331 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jun 20 19:12:03.499614 systemd[1]: modprobe@configfs.service: Deactivated successfully. Jun 20 19:12:03.499803 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Jun 20 19:12:03.504818 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jun 20 19:12:03.504993 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jun 20 19:12:03.510163 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jun 20 19:12:03.510341 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jun 20 19:12:03.515508 systemd[1]: modprobe@fuse.service: Deactivated successfully. Jun 20 19:12:03.515704 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Jun 20 19:12:03.522299 kernel: ACPI: bus type drm_connector registered Jun 20 19:12:03.522597 systemd[1]: modprobe@loop.service: Deactivated successfully. Jun 20 19:12:03.522781 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jun 20 19:12:03.527303 systemd[1]: modprobe@drm.service: Deactivated successfully. Jun 20 19:12:03.527492 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jun 20 19:12:03.532082 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jun 20 19:12:03.536714 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jun 20 19:12:03.542263 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Jun 20 19:12:03.547364 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Jun 20 19:12:03.562114 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jun 20 19:12:03.568801 systemd[1]: Reached target network-pre.target - Preparation for Network. Jun 20 19:12:03.574789 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Jun 20 19:12:03.596196 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Jun 20 19:12:03.600540 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Jun 20 19:12:03.600581 systemd[1]: Reached target local-fs.target - Local File Systems. Jun 20 19:12:03.605690 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Jun 20 19:12:03.611625 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Jun 20 19:12:03.615694 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jun 20 19:12:03.617002 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Jun 20 19:12:03.622131 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Jun 20 19:12:03.626747 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jun 20 19:12:03.627676 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Jun 20 19:12:03.631844 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jun 20 19:12:03.632789 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jun 20 19:12:03.637722 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Jun 20 19:12:03.643203 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jun 20 19:12:03.649591 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Jun 20 19:12:03.654453 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Jun 20 19:12:03.657691 systemd-journald[1506]: Time spent on flushing to /var/log/journal/997a23cdce284882865d884bfc7820d7 is 14.870ms for 952 entries. Jun 20 19:12:03.657691 systemd-journald[1506]: System Journal (/var/log/journal/997a23cdce284882865d884bfc7820d7) is 8M, max 2.6G, 2.6G free. Jun 20 19:12:03.696883 systemd-journald[1506]: Received client request to flush runtime journal. Jun 20 19:12:03.679131 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Jun 20 19:12:03.684216 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Jun 20 19:12:03.692324 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Jun 20 19:12:03.711639 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Jun 20 19:12:03.726497 kernel: loop0: detected capacity change from 0 to 113872 Jun 20 19:12:03.764815 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Jun 20 19:12:03.765529 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Jun 20 19:12:03.772259 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jun 20 19:12:03.873349 systemd-tmpfiles[1544]: ACLs are not supported, ignoring. Jun 20 19:12:03.873373 systemd-tmpfiles[1544]: ACLs are not supported, ignoring. Jun 20 19:12:03.879094 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jun 20 19:12:03.885456 systemd[1]: Starting systemd-sysusers.service - Create System Users... Jun 20 19:12:04.420508 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jun 20 19:12:04.448181 systemd[1]: Finished systemd-sysusers.service - Create System Users. Jun 20 19:12:04.453813 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jun 20 19:12:04.485497 kernel: loop1: detected capacity change from 0 to 28496 Jun 20 19:12:04.485770 systemd-tmpfiles[1561]: ACLs are not supported, ignoring. Jun 20 19:12:04.485792 systemd-tmpfiles[1561]: ACLs are not supported, ignoring. Jun 20 19:12:04.490600 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jun 20 19:12:05.125513 kernel: loop2: detected capacity change from 0 to 146240 Jun 20 19:12:05.234385 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Jun 20 19:12:05.241098 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jun 20 19:12:05.290724 systemd-udevd[1566]: Using default interface naming scheme 'v255'. Jun 20 19:12:05.541980 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jun 20 19:12:05.550844 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jun 20 19:12:05.586947 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Jun 20 19:12:05.649492 kernel: hv_vmbus: registering driver hyperv_fb Jun 20 19:12:05.651602 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Jun 20 19:12:05.661443 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Jun 20 19:12:05.661510 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Jun 20 19:12:05.664406 kernel: Console: switching to colour dummy device 80x25 Jun 20 19:12:05.669531 kernel: Console: switching to colour frame buffer device 128x48 Jun 20 19:12:05.683502 kernel: mousedev: PS/2 mouse device common for all mice Jun 20 19:12:05.690494 kernel: hv_vmbus: registering driver hv_balloon Jun 20 19:12:05.690542 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Jun 20 19:12:05.705201 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jun 20 19:12:05.713436 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jun 20 19:12:05.713643 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jun 20 19:12:05.719190 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jun 20 19:12:05.725360 systemd[1]: Started systemd-userdbd.service - User Database Manager. Jun 20 19:12:05.732497 kernel: EDAC MC: Ver: 3.0.0 Jun 20 19:12:05.823498 kernel: loop3: detected capacity change from 0 to 113872 Jun 20 19:12:05.833503 kernel: loop4: detected capacity change from 0 to 28496 Jun 20 19:12:05.841491 kernel: loop5: detected capacity change from 0 to 146240 Jun 20 19:12:05.856759 (sd-merge)[1649]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Jun 20 19:12:05.857234 (sd-merge)[1649]: Merged extensions into '/usr'. Jun 20 19:12:05.860567 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Jun 20 19:12:05.863579 systemd[1]: Starting ensure-sysext.service... Jun 20 19:12:05.865242 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jun 20 19:12:05.897985 systemd[1]: Reload requested from client PID 1651 ('systemctl') (unit ensure-sysext.service)... Jun 20 19:12:05.898003 systemd[1]: Reloading... Jun 20 19:12:05.898040 systemd-tmpfiles[1652]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Jun 20 19:12:05.898072 systemd-tmpfiles[1652]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Jun 20 19:12:05.898346 systemd-tmpfiles[1652]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Jun 20 19:12:05.898648 systemd-tmpfiles[1652]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Jun 20 19:12:05.899594 systemd-tmpfiles[1652]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Jun 20 19:12:05.899882 systemd-tmpfiles[1652]: ACLs are not supported, ignoring. Jun 20 19:12:05.899940 systemd-tmpfiles[1652]: ACLs are not supported, ignoring. Jun 20 19:12:05.906540 systemd-networkd[1591]: lo: Link UP Jun 20 19:12:05.906551 systemd-networkd[1591]: lo: Gained carrier Jun 20 19:12:05.908307 systemd-networkd[1591]: Enumeration completed Jun 20 19:12:05.908708 systemd-networkd[1591]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jun 20 19:12:05.908805 systemd-networkd[1591]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jun 20 19:12:05.909387 systemd-networkd[1591]: eth0: Link UP Jun 20 19:12:05.909391 systemd-networkd[1591]: eth0: Gained carrier Jun 20 19:12:05.909405 systemd-networkd[1591]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jun 20 19:12:05.940374 systemd-tmpfiles[1652]: Detected autofs mount point /boot during canonicalization of boot. Jun 20 19:12:05.941641 systemd-tmpfiles[1652]: Skipping /boot Jun 20 19:12:05.952545 systemd-networkd[1591]: eth0: DHCPv4 address 10.200.4.33/24, gateway 10.200.4.1 acquired from 168.63.129.16 Jun 20 19:12:05.954497 zram_generator::config[1751]: No configuration found. Jun 20 19:12:05.957823 systemd-tmpfiles[1652]: Detected autofs mount point /boot during canonicalization of boot. Jun 20 19:12:05.957838 systemd-tmpfiles[1652]: Skipping /boot Jun 20 19:12:06.070276 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Jun 20 19:12:06.225545 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Jun 20 19:12:06.230990 systemd[1]: Reloading finished in 332 ms. Jun 20 19:12:06.264813 systemd[1]: Started systemd-networkd.service - Network Configuration. Jun 20 19:12:06.269531 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jun 20 19:12:06.302953 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jun 20 19:12:06.352251 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jun 20 19:12:06.353599 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jun 20 19:12:06.371295 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Jun 20 19:12:06.376094 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jun 20 19:12:06.377177 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jun 20 19:12:06.382209 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jun 20 19:12:06.387005 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jun 20 19:12:06.392759 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jun 20 19:12:06.396563 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jun 20 19:12:06.397623 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Jun 20 19:12:06.401887 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Jun 20 19:12:06.403125 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Jun 20 19:12:06.408352 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Jun 20 19:12:06.413608 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Jun 20 19:12:06.419575 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jun 20 19:12:06.423613 systemd[1]: Reached target time-set.target - System Time Set. Jun 20 19:12:06.428407 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Jun 20 19:12:06.429835 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jun 20 19:12:06.432538 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jun 20 19:12:06.432716 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jun 20 19:12:06.434846 systemd[1]: modprobe@drm.service: Deactivated successfully. Jun 20 19:12:06.435026 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jun 20 19:12:06.435915 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jun 20 19:12:06.436090 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jun 20 19:12:06.436628 systemd[1]: modprobe@loop.service: Deactivated successfully. Jun 20 19:12:06.436797 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jun 20 19:12:06.439554 systemd[1]: Finished ensure-sysext.service. Jun 20 19:12:06.445635 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jun 20 19:12:06.445694 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jun 20 19:12:06.456530 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Jun 20 19:12:06.461798 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Jun 20 19:12:06.467783 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Jun 20 19:12:06.532585 systemd-resolved[1822]: Positive Trust Anchors: Jun 20 19:12:06.532597 systemd-resolved[1822]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jun 20 19:12:06.532628 systemd-resolved[1822]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Jun 20 19:12:06.537009 systemd-resolved[1822]: Using system hostname 'ci-4344.1.0-a-0c04071c8c'. Jun 20 19:12:06.538953 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jun 20 19:12:06.543190 systemd[1]: Reached target network.target - Network. Jun 20 19:12:06.546527 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jun 20 19:12:06.565817 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Jun 20 19:12:06.603841 augenrules[1853]: No rules Jun 20 19:12:06.605007 systemd[1]: audit-rules.service: Deactivated successfully. Jun 20 19:12:06.605251 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jun 20 19:12:07.712666 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Jun 20 19:12:07.717980 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Jun 20 19:12:07.816581 systemd-networkd[1591]: eth0: Gained IPv6LL Jun 20 19:12:07.818781 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Jun 20 19:12:07.824058 systemd[1]: Reached target network-online.target - Network is Online. Jun 20 19:12:10.464440 ldconfig[1537]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jun 20 19:12:10.477279 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Jun 20 19:12:10.483976 systemd[1]: Starting systemd-update-done.service - Update is Completed... Jun 20 19:12:10.512942 systemd[1]: Finished systemd-update-done.service - Update is Completed. Jun 20 19:12:10.517560 systemd[1]: Reached target sysinit.target - System Initialization. Jun 20 19:12:10.521956 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Jun 20 19:12:10.526962 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Jun 20 19:12:10.532026 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Jun 20 19:12:10.537072 systemd[1]: Started logrotate.timer - Daily rotation of log files. Jun 20 19:12:10.541405 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Jun 20 19:12:10.546495 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Jun 20 19:12:10.551539 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Jun 20 19:12:10.551594 systemd[1]: Reached target paths.target - Path Units. Jun 20 19:12:10.555164 systemd[1]: Reached target timers.target - Timer Units. Jun 20 19:12:10.578741 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Jun 20 19:12:10.584516 systemd[1]: Starting docker.socket - Docker Socket for the API... Jun 20 19:12:10.591020 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Jun 20 19:12:10.596065 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Jun 20 19:12:10.601122 systemd[1]: Reached target ssh-access.target - SSH Access Available. Jun 20 19:12:10.607134 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Jun 20 19:12:10.611562 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Jun 20 19:12:10.616877 systemd[1]: Listening on docker.socket - Docker Socket for the API. Jun 20 19:12:10.621977 systemd[1]: Reached target sockets.target - Socket Units. Jun 20 19:12:10.625824 systemd[1]: Reached target basic.target - Basic System. Jun 20 19:12:10.629530 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Jun 20 19:12:10.629561 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Jun 20 19:12:10.632075 systemd[1]: Starting chronyd.service - NTP client/server... Jun 20 19:12:10.636331 systemd[1]: Starting containerd.service - containerd container runtime... Jun 20 19:12:10.641623 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Jun 20 19:12:10.647212 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Jun 20 19:12:10.651701 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Jun 20 19:12:10.658792 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Jun 20 19:12:10.665210 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Jun 20 19:12:10.669260 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Jun 20 19:12:10.670094 jq[1874]: false Jun 20 19:12:10.670359 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Jun 20 19:12:10.674383 systemd[1]: hv_fcopy_uio_daemon.service - Hyper-V FCOPY UIO daemon was skipped because of an unmet condition check (ConditionPathExists=/sys/bus/vmbus/devices/eb765408-105f-49b6-b4aa-c123b64d17d4/uio). Jun 20 19:12:10.675401 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Jun 20 19:12:10.679582 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Jun 20 19:12:10.680620 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Jun 20 19:12:10.684991 KVP[1880]: KVP starting; pid is:1880 Jun 20 19:12:10.685466 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Jun 20 19:12:10.688625 KVP[1880]: KVP LIC Version: 3.1 Jun 20 19:12:10.690493 kernel: hv_utils: KVP IC version 4.0 Jun 20 19:12:10.692618 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Jun 20 19:12:10.693034 extend-filesystems[1875]: Found /dev/sda6 Jun 20 19:12:10.695152 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Jun 20 19:12:10.701549 chronyd[1889]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) Jun 20 19:12:10.696407 (chronyd)[1866]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Jun 20 19:12:10.703038 chronyd[1889]: Timezone right/UTC failed leap second check, ignoring Jun 20 19:12:10.698118 systemd[1]: Starting systemd-logind.service - User Login Management... Jun 20 19:12:10.703186 chronyd[1889]: Loaded seccomp filter (level 2) Jun 20 19:12:10.716829 extend-filesystems[1875]: Found /dev/sda9 Jun 20 19:12:10.716829 extend-filesystems[1875]: Checking size of /dev/sda9 Jun 20 19:12:10.717814 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Jun 20 19:12:10.718372 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Jun 20 19:12:10.720329 systemd[1]: Starting update-engine.service - Update Engine... Jun 20 19:12:10.729391 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Jun 20 19:12:10.730569 google_oslogin_nss_cache[1876]: oslogin_cache_refresh[1876]: Refreshing passwd entry cache Jun 20 19:12:10.730571 oslogin_cache_refresh[1876]: Refreshing passwd entry cache Jun 20 19:12:10.735996 systemd[1]: Started chronyd.service - NTP client/server. Jun 20 19:12:10.739671 jq[1898]: true Jun 20 19:12:10.739804 oslogin_cache_refresh[1876]: Failure getting users, quitting Jun 20 19:12:10.739947 google_oslogin_nss_cache[1876]: oslogin_cache_refresh[1876]: Failure getting users, quitting Jun 20 19:12:10.739947 google_oslogin_nss_cache[1876]: oslogin_cache_refresh[1876]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Jun 20 19:12:10.739947 google_oslogin_nss_cache[1876]: oslogin_cache_refresh[1876]: Refreshing group entry cache Jun 20 19:12:10.739821 oslogin_cache_refresh[1876]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Jun 20 19:12:10.739860 oslogin_cache_refresh[1876]: Refreshing group entry cache Jun 20 19:12:10.740716 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Jun 20 19:12:10.745455 google_oslogin_nss_cache[1876]: oslogin_cache_refresh[1876]: Failure getting groups, quitting Jun 20 19:12:10.745455 google_oslogin_nss_cache[1876]: oslogin_cache_refresh[1876]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Jun 20 19:12:10.745442 oslogin_cache_refresh[1876]: Failure getting groups, quitting Jun 20 19:12:10.745453 oslogin_cache_refresh[1876]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Jun 20 19:12:10.745948 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Jun 20 19:12:10.746157 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Jun 20 19:12:10.746463 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Jun 20 19:12:10.746704 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Jun 20 19:12:10.751331 systemd[1]: motdgen.service: Deactivated successfully. Jun 20 19:12:10.751564 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Jun 20 19:12:10.755602 extend-filesystems[1875]: Old size kept for /dev/sda9 Jun 20 19:12:10.756052 systemd[1]: extend-filesystems.service: Deactivated successfully. Jun 20 19:12:10.756249 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Jun 20 19:12:10.763650 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Jun 20 19:12:10.763859 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Jun 20 19:12:10.784172 (ntainerd)[1911]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Jun 20 19:12:10.786687 jq[1910]: true Jun 20 19:12:10.844335 update_engine[1897]: I20250620 19:12:10.844257 1897 main.cc:92] Flatcar Update Engine starting Jun 20 19:12:10.847136 systemd-logind[1887]: New seat seat0. Jun 20 19:12:10.848457 systemd-logind[1887]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Jun 20 19:12:10.848668 systemd[1]: Started systemd-logind.service - User Login Management. Jun 20 19:12:10.858693 sshd_keygen[1896]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Jun 20 19:12:10.877038 bash[1968]: Updated "/home/core/.ssh/authorized_keys" Jun 20 19:12:10.878666 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Jun 20 19:12:10.884152 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Jun 20 19:12:10.890606 systemd[1]: Starting issuegen.service - Generate /run/issue... Jun 20 19:12:10.894564 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Jun 20 19:12:10.913292 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Jun 20 19:12:10.920044 systemd[1]: issuegen.service: Deactivated successfully. Jun 20 19:12:10.920643 systemd[1]: Finished issuegen.service - Generate /run/issue. Jun 20 19:12:10.921924 dbus-daemon[1869]: [system] SELinux support is enabled Jun 20 19:12:10.924833 systemd[1]: Started dbus.service - D-Bus System Message Bus. Jun 20 19:12:10.925088 update_engine[1897]: I20250620 19:12:10.925035 1897 update_check_scheduler.cc:74] Next update check in 8m53s Jun 20 19:12:10.932977 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Jun 20 19:12:10.933017 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Jun 20 19:12:10.933707 dbus-daemon[1869]: [system] Successfully activated service 'org.freedesktop.systemd1' Jun 20 19:12:10.948874 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Jun 20 19:12:10.955491 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Jun 20 19:12:10.958274 setup-nvidia[1882]: Downloading Flatcar Container Linux Developer Container for version: 4344.1.0 Jun 20 19:12:10.955519 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Jun 20 19:12:10.963892 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Jun 20 19:12:10.968504 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Jun 20 19:12:10.974534 systemd[1]: Started update-engine.service - Update Engine. Jun 20 19:12:10.981636 systemd[1]: Started getty@tty1.service - Getty on tty1. Jun 20 19:12:10.986822 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Jun 20 19:12:10.991290 systemd[1]: Reached target getty.target - Login Prompts. Jun 20 19:12:10.993416 coreos-metadata[1868]: Jun 20 19:12:10.993 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Jun 20 19:12:10.995740 coreos-metadata[1868]: Jun 20 19:12:10.995 INFO Fetch successful Jun 20 19:12:10.995884 coreos-metadata[1868]: Jun 20 19:12:10.995 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Jun 20 19:12:10.996294 systemd[1]: Started locksmithd.service - Cluster reboot manager. Jun 20 19:12:11.000458 coreos-metadata[1868]: Jun 20 19:12:10.999 INFO Fetch successful Jun 20 19:12:11.000458 coreos-metadata[1868]: Jun 20 19:12:11.000 INFO Fetching http://168.63.129.16/machine/5e689a31-5059-42cb-b750-3afc2d04d4ba/7e94c4d6%2Df228%2D4687%2D826d%2Dfcaa761c9337.%5Fci%2D4344.1.0%2Da%2D0c04071c8c?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Jun 20 19:12:11.030712 coreos-metadata[1868]: Jun 20 19:12:11.030 INFO Fetch successful Jun 20 19:12:11.030857 coreos-metadata[1868]: Jun 20 19:12:11.030 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Jun 20 19:12:11.037565 coreos-metadata[1868]: Jun 20 19:12:11.037 INFO Fetch successful Jun 20 19:12:11.080385 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Jun 20 19:12:11.085201 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Jun 20 19:12:11.244724 locksmithd[2031]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Jun 20 19:12:11.520277 setup-nvidia[2046]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 20 19:12:11.520673 setup-nvidia[2046]: Dload Upload Total Spent Left Speed Jun 20 19:12:11.901289 containerd[1911]: time="2025-06-20T19:12:11Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Jun 20 19:12:11.901895 containerd[1911]: time="2025-06-20T19:12:11.901864100Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 Jun 20 19:12:11.910214 containerd[1911]: time="2025-06-20T19:12:11.910177700Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="7.8µs" Jun 20 19:12:11.910214 containerd[1911]: time="2025-06-20T19:12:11.910206200Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Jun 20 19:12:11.910300 containerd[1911]: time="2025-06-20T19:12:11.910224400Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Jun 20 19:12:11.910382 containerd[1911]: time="2025-06-20T19:12:11.910361400Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Jun 20 19:12:11.910415 containerd[1911]: time="2025-06-20T19:12:11.910387000Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Jun 20 19:12:11.910441 containerd[1911]: time="2025-06-20T19:12:11.910413100Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jun 20 19:12:11.910507 containerd[1911]: time="2025-06-20T19:12:11.910468700Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jun 20 19:12:11.910507 containerd[1911]: time="2025-06-20T19:12:11.910502900Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Jun 20 19:12:11.910741 containerd[1911]: time="2025-06-20T19:12:11.910716700Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Jun 20 19:12:11.910741 containerd[1911]: time="2025-06-20T19:12:11.910736900Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jun 20 19:12:11.910789 containerd[1911]: time="2025-06-20T19:12:11.910748800Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jun 20 19:12:11.910789 containerd[1911]: time="2025-06-20T19:12:11.910757000Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Jun 20 19:12:11.910842 containerd[1911]: time="2025-06-20T19:12:11.910831800Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Jun 20 19:12:11.911105 containerd[1911]: time="2025-06-20T19:12:11.911081300Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Jun 20 19:12:11.911133 containerd[1911]: time="2025-06-20T19:12:11.911119500Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Jun 20 19:12:11.911133 containerd[1911]: time="2025-06-20T19:12:11.911129800Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Jun 20 19:12:11.911191 containerd[1911]: time="2025-06-20T19:12:11.911173200Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Jun 20 19:12:11.911781 containerd[1911]: time="2025-06-20T19:12:11.911752700Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Jun 20 19:12:11.911945 containerd[1911]: time="2025-06-20T19:12:11.911924800Z" level=info msg="metadata content store policy set" policy=shared Jun 20 19:12:11.925056 containerd[1911]: time="2025-06-20T19:12:11.925018500Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Jun 20 19:12:11.925110 containerd[1911]: time="2025-06-20T19:12:11.925062700Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Jun 20 19:12:11.925110 containerd[1911]: time="2025-06-20T19:12:11.925078100Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Jun 20 19:12:11.925110 containerd[1911]: time="2025-06-20T19:12:11.925089900Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Jun 20 19:12:11.925110 containerd[1911]: time="2025-06-20T19:12:11.925101100Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Jun 20 19:12:11.925220 containerd[1911]: time="2025-06-20T19:12:11.925110500Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Jun 20 19:12:11.925220 containerd[1911]: time="2025-06-20T19:12:11.925121400Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Jun 20 19:12:11.925220 containerd[1911]: time="2025-06-20T19:12:11.925131900Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Jun 20 19:12:11.925220 containerd[1911]: time="2025-06-20T19:12:11.925142600Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Jun 20 19:12:11.925220 containerd[1911]: time="2025-06-20T19:12:11.925151800Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Jun 20 19:12:11.925220 containerd[1911]: time="2025-06-20T19:12:11.925160300Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Jun 20 19:12:11.925220 containerd[1911]: time="2025-06-20T19:12:11.925171300Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Jun 20 19:12:11.925362 containerd[1911]: time="2025-06-20T19:12:11.925306900Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Jun 20 19:12:11.925362 containerd[1911]: time="2025-06-20T19:12:11.925328800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Jun 20 19:12:11.925362 containerd[1911]: time="2025-06-20T19:12:11.925347300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Jun 20 19:12:11.925362 containerd[1911]: time="2025-06-20T19:12:11.925358200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Jun 20 19:12:11.925446 containerd[1911]: time="2025-06-20T19:12:11.925369500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Jun 20 19:12:11.925446 containerd[1911]: time="2025-06-20T19:12:11.925379200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Jun 20 19:12:11.925446 containerd[1911]: time="2025-06-20T19:12:11.925390300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Jun 20 19:12:11.925446 containerd[1911]: time="2025-06-20T19:12:11.925411800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Jun 20 19:12:11.925446 containerd[1911]: time="2025-06-20T19:12:11.925427100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Jun 20 19:12:11.925446 containerd[1911]: time="2025-06-20T19:12:11.925438100Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Jun 20 19:12:11.925650 containerd[1911]: time="2025-06-20T19:12:11.925447500Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Jun 20 19:12:11.925650 containerd[1911]: time="2025-06-20T19:12:11.925520000Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Jun 20 19:12:11.925650 containerd[1911]: time="2025-06-20T19:12:11.925534900Z" level=info msg="Start snapshots syncer" Jun 20 19:12:11.925650 containerd[1911]: time="2025-06-20T19:12:11.925558900Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Jun 20 19:12:11.925778 containerd[1911]: time="2025-06-20T19:12:11.925740700Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Jun 20 19:12:11.925919 containerd[1911]: time="2025-06-20T19:12:11.925798700Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Jun 20 19:12:11.925919 containerd[1911]: time="2025-06-20T19:12:11.925899300Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Jun 20 19:12:11.926049 containerd[1911]: time="2025-06-20T19:12:11.926022600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Jun 20 19:12:11.926080 containerd[1911]: time="2025-06-20T19:12:11.926051900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Jun 20 19:12:11.926080 containerd[1911]: time="2025-06-20T19:12:11.926064600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Jun 20 19:12:11.926080 containerd[1911]: time="2025-06-20T19:12:11.926074900Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Jun 20 19:12:11.926150 containerd[1911]: time="2025-06-20T19:12:11.926086000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Jun 20 19:12:11.926150 containerd[1911]: time="2025-06-20T19:12:11.926095600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Jun 20 19:12:11.926150 containerd[1911]: time="2025-06-20T19:12:11.926104900Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Jun 20 19:12:11.926150 containerd[1911]: time="2025-06-20T19:12:11.926125600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Jun 20 19:12:11.926150 containerd[1911]: time="2025-06-20T19:12:11.926136200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Jun 20 19:12:11.926255 containerd[1911]: time="2025-06-20T19:12:11.926161000Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Jun 20 19:12:11.926255 containerd[1911]: time="2025-06-20T19:12:11.926210800Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Jun 20 19:12:11.926255 containerd[1911]: time="2025-06-20T19:12:11.926227700Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Jun 20 19:12:11.926255 containerd[1911]: time="2025-06-20T19:12:11.926236200Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jun 20 19:12:11.926255 containerd[1911]: time="2025-06-20T19:12:11.926244800Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jun 20 19:12:11.926255 containerd[1911]: time="2025-06-20T19:12:11.926252500Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Jun 20 19:12:11.926384 containerd[1911]: time="2025-06-20T19:12:11.926260900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Jun 20 19:12:11.926384 containerd[1911]: time="2025-06-20T19:12:11.926270600Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Jun 20 19:12:11.926384 containerd[1911]: time="2025-06-20T19:12:11.926286400Z" level=info msg="runtime interface created" Jun 20 19:12:11.926384 containerd[1911]: time="2025-06-20T19:12:11.926292400Z" level=info msg="created NRI interface" Jun 20 19:12:11.926384 containerd[1911]: time="2025-06-20T19:12:11.926300100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Jun 20 19:12:11.926384 containerd[1911]: time="2025-06-20T19:12:11.926309900Z" level=info msg="Connect containerd service" Jun 20 19:12:11.926384 containerd[1911]: time="2025-06-20T19:12:11.926339600Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Jun 20 19:12:11.927048 containerd[1911]: time="2025-06-20T19:12:11.927019000Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Jun 20 19:12:12.850191 containerd[1911]: time="2025-06-20T19:12:12.850138200Z" level=info msg="Start subscribing containerd event" Jun 20 19:12:12.850330 containerd[1911]: time="2025-06-20T19:12:12.850195500Z" level=info msg="Start recovering state" Jun 20 19:12:12.850330 containerd[1911]: time="2025-06-20T19:12:12.850306800Z" level=info msg="Start event monitor" Jun 20 19:12:12.850384 containerd[1911]: time="2025-06-20T19:12:12.850330800Z" level=info msg="Start cni network conf syncer for default" Jun 20 19:12:12.850384 containerd[1911]: time="2025-06-20T19:12:12.850338800Z" level=info msg="Start streaming server" Jun 20 19:12:12.850384 containerd[1911]: time="2025-06-20T19:12:12.850354100Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Jun 20 19:12:12.850384 containerd[1911]: time="2025-06-20T19:12:12.850362700Z" level=info msg="runtime interface starting up..." Jun 20 19:12:12.850384 containerd[1911]: time="2025-06-20T19:12:12.850368600Z" level=info msg="starting plugins..." Jun 20 19:12:12.850384 containerd[1911]: time="2025-06-20T19:12:12.850382800Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Jun 20 19:12:12.850532 containerd[1911]: time="2025-06-20T19:12:12.850357500Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jun 20 19:12:12.850559 containerd[1911]: time="2025-06-20T19:12:12.850527900Z" level=info msg=serving... address=/run/containerd/containerd.sock Jun 20 19:12:12.850623 containerd[1911]: time="2025-06-20T19:12:12.850604300Z" level=info msg="containerd successfully booted in 0.949747s" Jun 20 19:12:12.850709 systemd[1]: Started containerd.service - containerd container runtime. Jun 20 19:12:13.016015 waagent[2026]: 2025-06-20T19:12:13.015930Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Jun 20 19:12:13.020620 waagent[2026]: 2025-06-20T19:12:13.020569Z INFO Daemon Daemon OS: flatcar 4344.1.0 Jun 20 19:12:13.024100 waagent[2026]: 2025-06-20T19:12:13.024019Z INFO Daemon Daemon Python: 3.11.12 Jun 20 19:12:13.027643 waagent[2026]: 2025-06-20T19:12:13.027582Z INFO Daemon Daemon Run daemon Jun 20 19:12:13.030705 waagent[2026]: 2025-06-20T19:12:13.030657Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4344.1.0' Jun 20 19:12:13.037418 waagent[2026]: 2025-06-20T19:12:13.037374Z INFO Daemon Daemon Using waagent for provisioning Jun 20 19:12:13.041592 waagent[2026]: 2025-06-20T19:12:13.041550Z INFO Daemon Daemon Activate resource disk Jun 20 19:12:13.045217 waagent[2026]: 2025-06-20T19:12:13.045169Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Jun 20 19:12:13.052674 waagent[2026]: 2025-06-20T19:12:13.052627Z INFO Daemon Daemon Found device: sdb Jun 20 19:12:13.061847 waagent[2026]: 2025-06-20T19:12:13.061793Z INFO Daemon Daemon Examining partition table Jun 20 19:12:13.076489 waagent[2026]: 2025-06-20T19:12:13.076398Z INFO Daemon Daemon GPT not detected, determining filesystem Jun 20 19:12:13.146701 waagent[2026]: 2025-06-20T19:12:13.146450Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Jun 20 19:12:13.151094 waagent[2026]: 2025-06-20T19:12:13.151048Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Jun 20 19:12:13.572768 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#362 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jun 20 19:12:13.584488 kernel: sdb: sdb1 Jun 20 19:12:13.604178 waagent[2026]: 2025-06-20T19:12:13.604098Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Jun 20 19:12:13.622504 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#498 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jun 20 19:12:13.629489 kernel: sdb: sdb1 Jun 20 19:12:13.629649 waagent[2026]: 2025-06-20T19:12:13.629605Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Jun 20 19:12:18.984401 login[2029]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Jun 20 19:12:19.103283 login[2030]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Jun 20 19:12:19.116980 systemd-logind[1887]: New session 2 of user core. Jun 20 19:12:19.118022 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Jun 20 19:12:19.119603 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Jun 20 19:12:19.244434 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Jun 20 19:12:19.246721 systemd[1]: Starting user@500.service - User Manager for UID 500... Jun 20 19:12:19.351677 (systemd)[2127]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Jun 20 19:12:19.354219 systemd-logind[1887]: New session c1 of user core. Jun 20 19:12:19.447660 waagent[2026]: 2025-06-20T19:12:19.447579Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Jun 20 19:12:19.556390 waagent[2026]: 2025-06-20T19:12:19.556253Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Jun 20 19:12:19.556509 kernel: EXT4-fs (sdb1): mounted filesystem e331b121-136d-4dd1-8b84-953ec284b59d r/w with ordered data mode. Quota mode: none. Jun 20 19:12:19.564732 waagent[2026]: 2025-06-20T19:12:19.564672Z INFO Daemon Daemon Clean protocol and wireserver endpoint Jun 20 19:12:19.569168 waagent[2026]: 2025-06-20T19:12:19.569117Z INFO Daemon Daemon Running default provisioning handler Jun 20 19:12:19.579612 waagent[2026]: 2025-06-20T19:12:19.579561Z INFO Daemon Daemon Unable to get cloud-init enabled status from systemctl: Command '['systemctl', 'is-enabled', 'cloud-init-local.service']' returned non-zero exit status 4. Jun 20 19:12:19.589484 waagent[2026]: 2025-06-20T19:12:19.589422Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Jun 20 19:12:19.592220 waagent[2026]: 2025-06-20T19:12:19.592169Z INFO Daemon Daemon cloud-init is enabled: False Jun 20 19:12:19.593620 waagent[2026]: 2025-06-20T19:12:19.593586Z INFO Daemon Daemon Copying ovf-env.xml Jun 20 19:12:19.645229 systemd[2127]: Queued start job for default target default.target. Jun 20 19:12:19.664151 waagent[2026]: 2025-06-20T19:12:19.664072Z INFO Daemon Daemon Successfully mounted dvd Jun 20 19:12:19.667159 systemd[2127]: Created slice app.slice - User Application Slice. Jun 20 19:12:19.667192 systemd[2127]: Reached target paths.target - Paths. Jun 20 19:12:19.667243 systemd[2127]: Reached target timers.target - Timers. Jun 20 19:12:19.668616 systemd[2127]: Starting dbus.socket - D-Bus User Message Bus Socket... Jun 20 19:12:19.679203 systemd[2127]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jun 20 19:12:19.679334 systemd[2127]: Reached target sockets.target - Sockets. Jun 20 19:12:19.679379 systemd[2127]: Reached target basic.target - Basic System. Jun 20 19:12:19.679430 systemd[2127]: Reached target default.target - Main User Target. Jun 20 19:12:19.679462 systemd[2127]: Startup finished in 317ms. Jun 20 19:12:19.680419 systemd[1]: Started user@500.service - User Manager for UID 500. Jun 20 19:12:19.696604 systemd[1]: Started session-2.scope - Session 2 of User core. Jun 20 19:12:19.706209 waagent[2026]: 2025-06-20T19:12:19.706151Z INFO Daemon Daemon Detect protocol endpoint Jun 20 19:12:19.706223 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Jun 20 19:12:19.708011 waagent[2026]: 2025-06-20T19:12:19.707964Z INFO Daemon Daemon Clean protocol and wireserver endpoint Jun 20 19:12:19.709584 waagent[2026]: 2025-06-20T19:12:19.709544Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Jun 20 19:12:19.711065 waagent[2026]: 2025-06-20T19:12:19.710983Z INFO Daemon Daemon Test for route to 168.63.129.16 Jun 20 19:12:19.712769 waagent[2026]: 2025-06-20T19:12:19.712728Z INFO Daemon Daemon Route to 168.63.129.16 exists Jun 20 19:12:19.714128 waagent[2026]: 2025-06-20T19:12:19.714088Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Jun 20 19:12:19.740609 waagent[2026]: 2025-06-20T19:12:19.740554Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Jun 20 19:12:19.742600 waagent[2026]: 2025-06-20T19:12:19.742558Z INFO Daemon Daemon Wire protocol version:2012-11-30 Jun 20 19:12:19.743149 waagent[2026]: 2025-06-20T19:12:19.743115Z INFO Daemon Daemon Server preferred version:2015-04-05 Jun 20 19:12:19.909545 waagent[2026]: 2025-06-20T19:12:19.909440Z INFO Daemon Daemon Initializing goal state during protocol detection Jun 20 19:12:19.911309 waagent[2026]: 2025-06-20T19:12:19.911248Z INFO Daemon Daemon Forcing an update of the goal state. Jun 20 19:12:19.917078 waagent[2026]: 2025-06-20T19:12:19.917031Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Jun 20 19:12:19.929623 waagent[2026]: 2025-06-20T19:12:19.929583Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.175 Jun 20 19:12:19.931568 waagent[2026]: 2025-06-20T19:12:19.931524Z INFO Daemon Jun 20 19:12:19.933535 waagent[2026]: 2025-06-20T19:12:19.933399Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: c52f74a9-81d0-4cf3-aab3-724d18e44adb eTag: 12654550477361319863 source: Fabric] Jun 20 19:12:19.935251 waagent[2026]: 2025-06-20T19:12:19.935205Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Jun 20 19:12:19.936724 waagent[2026]: 2025-06-20T19:12:19.936687Z INFO Daemon Jun 20 19:12:19.937208 waagent[2026]: 2025-06-20T19:12:19.937177Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Jun 20 19:12:19.941890 waagent[2026]: 2025-06-20T19:12:19.941852Z INFO Daemon Daemon Downloading artifacts profile blob Jun 20 19:12:19.984762 login[2029]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Jun 20 19:12:19.989227 systemd-logind[1887]: New session 1 of user core. Jun 20 19:12:20.004602 systemd[1]: Started session-1.scope - Session 1 of User core. Jun 20 19:12:20.074449 waagent[2026]: 2025-06-20T19:12:20.074382Z INFO Daemon Downloaded certificate {'thumbprint': 'A59C768A54E7DA21D27E794A4E96CC642EDFB142', 'hasPrivateKey': True} Jun 20 19:12:20.076332 waagent[2026]: 2025-06-20T19:12:20.076276Z INFO Daemon Fetch goal state completed Jun 20 19:12:20.112836 waagent[2026]: 2025-06-20T19:12:20.112797Z INFO Daemon Daemon Starting provisioning Jun 20 19:12:20.114377 waagent[2026]: 2025-06-20T19:12:20.114326Z INFO Daemon Daemon Handle ovf-env.xml. Jun 20 19:12:20.118662 waagent[2026]: 2025-06-20T19:12:20.118619Z INFO Daemon Daemon Set hostname [ci-4344.1.0-a-0c04071c8c] Jun 20 19:12:20.153241 waagent[2026]: 2025-06-20T19:12:20.153199Z INFO Daemon Daemon Publish hostname [ci-4344.1.0-a-0c04071c8c] Jun 20 19:12:20.155763 waagent[2026]: 2025-06-20T19:12:20.155715Z INFO Daemon Daemon Examine /proc/net/route for primary interface Jun 20 19:12:20.157973 waagent[2026]: 2025-06-20T19:12:20.157922Z INFO Daemon Daemon Primary interface is [eth0] Jun 20 19:12:20.175063 systemd-networkd[1591]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jun 20 19:12:20.175075 systemd-networkd[1591]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jun 20 19:12:20.175107 systemd-networkd[1591]: eth0: DHCP lease lost Jun 20 19:12:20.175818 waagent[2026]: 2025-06-20T19:12:20.175619Z INFO Daemon Daemon Create user account if not exists Jun 20 19:12:20.177295 waagent[2026]: 2025-06-20T19:12:20.177246Z INFO Daemon Daemon User core already exists, skip useradd Jun 20 19:12:20.178626 waagent[2026]: 2025-06-20T19:12:20.178588Z INFO Daemon Daemon Configure sudoer Jun 20 19:12:20.191037 waagent[2026]: 2025-06-20T19:12:20.190979Z INFO Daemon Daemon Configure sshd Jun 20 19:12:20.196620 waagent[2026]: 2025-06-20T19:12:20.196512Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Jun 20 19:12:20.198295 waagent[2026]: 2025-06-20T19:12:20.198249Z INFO Daemon Daemon Deploy ssh public key. Jun 20 19:12:20.245526 systemd-networkd[1591]: eth0: DHCPv4 address 10.200.4.33/24, gateway 10.200.4.1 acquired from 168.63.129.16 Jun 20 19:12:21.341357 waagent[2026]: 2025-06-20T19:12:21.341293Z INFO Daemon Daemon Provisioning complete Jun 20 19:12:21.350683 waagent[2026]: 2025-06-20T19:12:21.350640Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Jun 20 19:12:21.352244 waagent[2026]: 2025-06-20T19:12:21.352202Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Jun 20 19:12:21.353673 waagent[2026]: 2025-06-20T19:12:21.353638Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Jun 20 19:12:21.506994 waagent[2184]: 2025-06-20T19:12:21.506903Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Jun 20 19:12:21.507316 waagent[2184]: 2025-06-20T19:12:21.507048Z INFO ExtHandler ExtHandler OS: flatcar 4344.1.0 Jun 20 19:12:21.507316 waagent[2184]: 2025-06-20T19:12:21.507117Z INFO ExtHandler ExtHandler Python: 3.11.12 Jun 20 19:12:21.507316 waagent[2184]: 2025-06-20T19:12:21.507185Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Jun 20 19:12:21.585096 waagent[2184]: 2025-06-20T19:12:21.585007Z INFO ExtHandler ExtHandler Distro: flatcar-4344.1.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.12; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Jun 20 19:12:21.585296 waagent[2184]: 2025-06-20T19:12:21.585257Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jun 20 19:12:21.585375 waagent[2184]: 2025-06-20T19:12:21.585342Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Jun 20 19:12:21.591997 waagent[2184]: 2025-06-20T19:12:21.591912Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Jun 20 19:12:21.596593 waagent[2184]: 2025-06-20T19:12:21.596554Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.175 Jun 20 19:12:21.597041 waagent[2184]: 2025-06-20T19:12:21.597003Z INFO ExtHandler Jun 20 19:12:21.597120 waagent[2184]: 2025-06-20T19:12:21.597090Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: f66684e9-f100-40e6-8a37-acb4c0928d8a eTag: 12654550477361319863 source: Fabric] Jun 20 19:12:21.597432 waagent[2184]: 2025-06-20T19:12:21.597395Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Jun 20 19:12:21.598064 waagent[2184]: 2025-06-20T19:12:21.598028Z INFO ExtHandler Jun 20 19:12:21.598138 waagent[2184]: 2025-06-20T19:12:21.598108Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Jun 20 19:12:21.601302 waagent[2184]: 2025-06-20T19:12:21.601269Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Jun 20 19:12:21.660942 waagent[2184]: 2025-06-20T19:12:21.660879Z INFO ExtHandler Downloaded certificate {'thumbprint': 'A59C768A54E7DA21D27E794A4E96CC642EDFB142', 'hasPrivateKey': True} Jun 20 19:12:21.661437 waagent[2184]: 2025-06-20T19:12:21.661397Z INFO ExtHandler Fetch goal state completed Jun 20 19:12:21.672621 waagent[2184]: 2025-06-20T19:12:21.672566Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.3.3 11 Feb 2025 (Library: OpenSSL 3.3.3 11 Feb 2025) Jun 20 19:12:21.678746 waagent[2184]: 2025-06-20T19:12:21.678692Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2184 Jun 20 19:12:21.678949 waagent[2184]: 2025-06-20T19:12:21.678902Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Jun 20 19:12:21.679354 waagent[2184]: 2025-06-20T19:12:21.679315Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Jun 20 19:12:21.681084 waagent[2184]: 2025-06-20T19:12:21.681043Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4344.1.0', '', 'Flatcar Container Linux by Kinvolk'] Jun 20 19:12:21.681525 waagent[2184]: 2025-06-20T19:12:21.681469Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4344.1.0', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Jun 20 19:12:21.681682 waagent[2184]: 2025-06-20T19:12:21.681648Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Jun 20 19:12:21.682273 waagent[2184]: 2025-06-20T19:12:21.682236Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Jun 20 19:12:21.762696 waagent[2184]: 2025-06-20T19:12:21.762635Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Jun 20 19:12:21.762896 waagent[2184]: 2025-06-20T19:12:21.762858Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Jun 20 19:12:21.769395 waagent[2184]: 2025-06-20T19:12:21.769361Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Jun 20 19:12:21.776658 systemd[1]: Reload requested from client PID 2199 ('systemctl') (unit waagent.service)... Jun 20 19:12:21.776674 systemd[1]: Reloading... Jun 20 19:12:21.847558 zram_generator::config[2238]: No configuration found. Jun 20 19:12:21.974655 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Jun 20 19:12:22.143902 systemd[1]: Reloading finished in 366 ms. Jun 20 19:12:22.167988 waagent[2184]: 2025-06-20T19:12:22.167914Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Jun 20 19:12:22.168088 waagent[2184]: 2025-06-20T19:12:22.168062Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Jun 20 19:12:22.497534 waagent[2184]: 2025-06-20T19:12:22.497433Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Jun 20 19:12:22.497845 waagent[2184]: 2025-06-20T19:12:22.497796Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: 1. configuration enabled [True], 2. cgroups v1 enabled [False] OR cgroups v2 is in use and v2 resource limiting configuration enabled [False], 3. python supported: [True] Jun 20 19:12:22.500717 waagent[2184]: 2025-06-20T19:12:22.498573Z INFO ExtHandler ExtHandler Starting env monitor service. Jun 20 19:12:22.500717 waagent[2184]: 2025-06-20T19:12:22.498918Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jun 20 19:12:22.500717 waagent[2184]: 2025-06-20T19:12:22.499113Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Jun 20 19:12:22.500717 waagent[2184]: 2025-06-20T19:12:22.499378Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Jun 20 19:12:22.500717 waagent[2184]: 2025-06-20T19:12:22.499854Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Jun 20 19:12:22.500717 waagent[2184]: 2025-06-20T19:12:22.500013Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Jun 20 19:12:22.500717 waagent[2184]: 2025-06-20T19:12:22.500278Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Jun 20 19:12:22.500717 waagent[2184]: 2025-06-20T19:12:22.500352Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jun 20 19:12:22.500938 waagent[2184]: 2025-06-20T19:12:22.500755Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Jun 20 19:12:22.501259 waagent[2184]: 2025-06-20T19:12:22.501199Z INFO EnvHandler ExtHandler Configure routes Jun 20 19:12:22.501554 waagent[2184]: 2025-06-20T19:12:22.501492Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Jun 20 19:12:22.501784 waagent[2184]: 2025-06-20T19:12:22.501732Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Jun 20 19:12:22.501982 waagent[2184]: 2025-06-20T19:12:22.501946Z INFO EnvHandler ExtHandler Gateway:None Jun 20 19:12:22.502246 waagent[2184]: 2025-06-20T19:12:22.502203Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Jun 20 19:12:22.502246 waagent[2184]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Jun 20 19:12:22.502246 waagent[2184]: eth0 00000000 0104C80A 0003 0 0 1024 00000000 0 0 0 Jun 20 19:12:22.502246 waagent[2184]: eth0 0004C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Jun 20 19:12:22.502246 waagent[2184]: eth0 0104C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Jun 20 19:12:22.502246 waagent[2184]: eth0 10813FA8 0104C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jun 20 19:12:22.502246 waagent[2184]: eth0 FEA9FEA9 0104C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jun 20 19:12:22.502438 waagent[2184]: 2025-06-20T19:12:22.502400Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Jun 20 19:12:22.502559 waagent[2184]: 2025-06-20T19:12:22.502512Z INFO EnvHandler ExtHandler Routes:None Jun 20 19:12:22.508587 waagent[2184]: 2025-06-20T19:12:22.508535Z INFO ExtHandler ExtHandler Jun 20 19:12:22.508877 waagent[2184]: 2025-06-20T19:12:22.508810Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 106aa37a-f664-4f16-b213-8a3fbdaa9f62 correlation 000028a1-259e-422c-a4c8-c28e6e4fcd68 created: 2025-06-20T19:10:57.308891Z] Jun 20 19:12:22.509973 waagent[2184]: 2025-06-20T19:12:22.509927Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Jun 20 19:12:22.512240 waagent[2184]: 2025-06-20T19:12:22.512193Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Jun 20 19:12:22.543725 waagent[2184]: 2025-06-20T19:12:22.543443Z WARNING ExtHandler ExtHandler Failed to get firewall packets: 'iptables -w -t security -L OUTPUT --zero OUTPUT -nxv' failed: 2 (iptables v1.8.11 (nf_tables): Illegal option `--numeric' with this command Jun 20 19:12:22.543725 waagent[2184]: Try `iptables -h' or 'iptables --help' for more information.) Jun 20 19:12:22.544018 waagent[2184]: 2025-06-20T19:12:22.543978Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: A0F10802-4C2B-489F-A985-FE40A5C4164F;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Jun 20 19:12:22.556706 waagent[2184]: 2025-06-20T19:12:22.556646Z INFO MonitorHandler ExtHandler Network interfaces: Jun 20 19:12:22.556706 waagent[2184]: Executing ['ip', '-a', '-o', 'link']: Jun 20 19:12:22.556706 waagent[2184]: 1: lo: mtu 65536 qdisc noqueue state UNKNOWN mode DEFAULT group default qlen 1000\ link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00 Jun 20 19:12:22.556706 waagent[2184]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:1e:52:79:23:21 brd ff:ff:ff:ff:ff:ff Jun 20 19:12:22.556706 waagent[2184]: Executing ['ip', '-4', '-a', '-o', 'address']: Jun 20 19:12:22.556706 waagent[2184]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Jun 20 19:12:22.556706 waagent[2184]: 2: eth0 inet 10.200.4.33/24 metric 1024 brd 10.200.4.255 scope global eth0\ valid_lft forever preferred_lft forever Jun 20 19:12:22.556706 waagent[2184]: Executing ['ip', '-6', '-a', '-o', 'address']: Jun 20 19:12:22.556706 waagent[2184]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Jun 20 19:12:22.556706 waagent[2184]: 2: eth0 inet6 fe80::7e1e:52ff:fe79:2321/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Jun 20 19:12:22.696933 waagent[2184]: 2025-06-20T19:12:22.696872Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Jun 20 19:12:22.696933 waagent[2184]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Jun 20 19:12:22.696933 waagent[2184]: pkts bytes target prot opt in out source destination Jun 20 19:12:22.696933 waagent[2184]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Jun 20 19:12:22.696933 waagent[2184]: pkts bytes target prot opt in out source destination Jun 20 19:12:22.696933 waagent[2184]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Jun 20 19:12:22.696933 waagent[2184]: pkts bytes target prot opt in out source destination Jun 20 19:12:22.696933 waagent[2184]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Jun 20 19:12:22.696933 waagent[2184]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Jun 20 19:12:22.696933 waagent[2184]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Jun 20 19:12:22.700334 waagent[2184]: 2025-06-20T19:12:22.700288Z INFO EnvHandler ExtHandler Current Firewall rules: Jun 20 19:12:22.700334 waagent[2184]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Jun 20 19:12:22.700334 waagent[2184]: pkts bytes target prot opt in out source destination Jun 20 19:12:22.700334 waagent[2184]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Jun 20 19:12:22.700334 waagent[2184]: pkts bytes target prot opt in out source destination Jun 20 19:12:22.700334 waagent[2184]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Jun 20 19:12:22.700334 waagent[2184]: pkts bytes target prot opt in out source destination Jun 20 19:12:22.700334 waagent[2184]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Jun 20 19:12:22.700334 waagent[2184]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Jun 20 19:12:22.700334 waagent[2184]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Jun 20 19:12:22.700638 waagent[2184]: 2025-06-20T19:12:22.700561Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Jun 20 19:12:22.700742 waagent[2184]: 2025-06-20T19:12:22.700709Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Jun 20 19:12:34.483412 chronyd[1889]: Selected source PHC0 Jun 20 19:12:38.141687 setup-nvidia[2046]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 486M 0 303k 0 0 275k 0 0:30:07 0:00:01 0:30:06 275k\u000d 2 486M 2 11.6M 0 0 5864k 0 0:01:24 0:00:02 0:01:22 5863k\u000d 6 486M 6 33.9M 0 0 11.0M 0 0:00:43 0:00:03 0:00:40 11.0M\u000d 12 486M 12 59.3M 0 0 14.3M 0 0:00:33 0:00:04 0:00:29 14.3M\u000d 16 486M 16 81.1M 0 0 16.1M 0 0:00:30 0:00:05 0:00:25 16.3M\u000d 21 486M 21 106M 0 0 17.4M 0 0:00:27 0:00:06 0:00:21 21.2M\u000d 27 486M 27 131M 0 0 18.4M 0 0:00:26 0:00:07 0:00:19 23.4M\u000d 31 486M 31 151M 0 0 18.8M 0 0:00:25 0:00:08 0:00:17 23.5M\u000d 34 486M 34 167M 0 0 18.0M 0 0:00:26 0:00:09 0:00:17 21.0M\u000d 34 486M 34 167M 0 0 16.4M 0 0:00:29 0:00:10 0:00:19 16.8M\u000d 34 486M 34 167M 0 0 15.0M 0 0:00:32 0:00:11 0:00:21 12.1M\u000d 34 486M 34 168M 0 0 14.0M 0 0:00:34 0:00:12 0:00:22 7804k\u000d 34 486M 34 169M 0 0 13.0M 0 0:00:37 0:00:13 0:00:24 3707k\u000d 39 486M 39 189M 0 0 13.4M 0 0:00:36 0:00:14 0:00:22 4739k\u000d 44 486M 44 215M 0 0 14.1M 0 0:00:34 0:00:15 0:00:19 9738k\u000d 48 486M 48 236M 0 0 14.7M 0 0:00:32 0:00:16 0:00:16 14.1M\u000d 53 486M 53 262M 0 0 15.3M 0 0:00:31 0:00:17 0:00:14 18.4M\u000d 58 486M 58 285M 0 0 15.8M 0 0:00:30 0:00:18 0:00:12 23.2M\u000d 63 486M 63 308M 0 0 16.1M 0 0:00:30 0:00:19 0:00:11 23.9M\u000d 68 486M 68 334M 0 0 16.5M 0 0:00:29 0:00:20 0:00:09 23.9M\u000d 73 486M 73 355M 0 0 16.9M 0 0:00:28 0:00:21 0:00:07 23.9M\u000d 78 486M 78 381M 0 0 17.2M 0 0:00:28 0:00:22 0:00:06 23.9M\u000d 82 486M 82 402M 0 0 17.3M 0 0:00:28 0:00:23 0:00:05 22.8M\u000d 87 486M 87 423M 0 0 17.6M 0 0:00:27 0:00:24 0:00:03 23.0M\u000d 92 486M 92 448M 0 0 17.8M 0 0:00:27 0:00:25 0:00:02 23.0M\u000d 97 486M 97 474M 0 0 18.1M 0 0:00:26 0:00:26 --:--:-- 23.0M\u000d100 486M 100 486M 0 0 18.2M 0 0:00:26 0:00:26 --:--:-- 23.2M Jun 20 19:12:44.345787 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Jun 20 19:12:44.352841 systemd[1]: Started sshd@0-10.200.4.33:22-10.200.16.10:33890.service - OpenSSH per-connection server daemon (10.200.16.10:33890). Jun 20 19:12:45.124270 sshd[2341]: Accepted publickey for core from 10.200.16.10 port 33890 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:12:45.125658 sshd-session[2341]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:12:45.130813 systemd-logind[1887]: New session 3 of user core. Jun 20 19:12:45.137640 systemd[1]: Started session-3.scope - Session 3 of User core. Jun 20 19:12:45.650728 systemd[1]: Started sshd@1-10.200.4.33:22-10.200.16.10:33904.service - OpenSSH per-connection server daemon (10.200.16.10:33904). Jun 20 19:12:47.044679 sshd-session[2346]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:12:47.049970 sshd[2346]: Accepted publickey for core from 10.200.16.10 port 33904 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:12:47.055809 systemd-logind[1887]: New session 4 of user core. Jun 20 19:12:47.061858 systemd[1]: Started session-4.scope - Session 4 of User core. Jun 20 19:12:47.407921 sshd[2348]: Connection closed by 10.200.16.10 port 33904 Jun 20 19:12:47.409626 sshd-session[2346]: pam_unix(sshd:session): session closed for user core Jun 20 19:12:47.413136 systemd[1]: sshd@1-10.200.4.33:22-10.200.16.10:33904.service: Deactivated successfully. Jun 20 19:12:47.415152 systemd[1]: session-4.scope: Deactivated successfully. Jun 20 19:12:47.419518 systemd-logind[1887]: Session 4 logged out. Waiting for processes to exit. Jun 20 19:12:47.420689 systemd-logind[1887]: Removed session 4. Jun 20 19:12:47.515064 systemd[1]: Started sshd@2-10.200.4.33:22-10.200.16.10:33916.service - OpenSSH per-connection server daemon (10.200.16.10:33916). Jun 20 19:12:48.124399 sshd[2354]: Accepted publickey for core from 10.200.16.10 port 33916 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:12:48.124266 sshd-session[2354]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:12:48.131667 systemd-logind[1887]: New session 5 of user core. Jun 20 19:12:48.139627 systemd[1]: Started session-5.scope - Session 5 of User core. Jun 20 19:12:48.554214 sshd[2356]: Connection closed by 10.200.16.10 port 33916 Jun 20 19:12:48.554567 sshd-session[2354]: pam_unix(sshd:session): session closed for user core Jun 20 19:12:48.557337 systemd[1]: sshd@2-10.200.4.33:22-10.200.16.10:33916.service: Deactivated successfully. Jun 20 19:12:48.559291 systemd[1]: session-5.scope: Deactivated successfully. Jun 20 19:12:48.560677 systemd-logind[1887]: Session 5 logged out. Waiting for processes to exit. Jun 20 19:12:48.561748 systemd-logind[1887]: Removed session 5. Jun 20 19:12:48.611688 setup-nvidia[1882]: Downloading NVIDIA 535.230.02 Driver Jun 20 19:12:48.615980 setup-nvidia[2362]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 20 19:12:48.616185 setup-nvidia[2362]: Dload Upload Total Spent Left Speed Jun 20 19:12:51.665099 setup-nvidia[2362]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 12 326M 12 42.0M 0 0 44.8M 0 0:00:07 --:--:-- 0:00:07 44.8M\u000d 44 326M 44 144M 0 0 74.4M 0 0:00:04 0:00:01 0:00:03 74.4M\u000d 93 326M 93 304M 0 0 103M 0 0:00:03 0:00:02 0:00:01 103M\u000d100 326M 100 326M 0 0 106M 0 0:00:03 0:00:03 --:--:-- 106M Jun 20 19:12:51.693028 setup-nvidia[1882]: Extract the NVIDIA Driver Installer 535.230.02 Jun 20 19:12:51.693219 setup-nvidia[1882]: /opt/nvidia/workdir/nvidia-workdir / Jun 20 19:12:51.713570 setup-nvidia[2365]: Creating directory NVIDIA-Linux-x86_64-535.230.02 Jun 20 19:12:51.952011 setup-nvidia[2365]: Verifying archive integrity... OK Jun 20 19:12:52.665278 setup-nvidia[2365]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.230.02 Jun 20 19:12:53.817150 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Jun 20 19:12:54.437709 setup-nvidia[2391]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Jun 20 19:12:54.479230 setup-nvidia[1882]: / Jun 20 19:12:54.479447 setup-nvidia[1882]: Spawn system-nspawn container to install the NVIDIA drivers Jun 20 19:12:54.666957 sudo[2398]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4344.1.0.bin --overlay=/usr/lib/modules::/usr/lib/modules --bind=/opt/nvidia/workdir/nvidia-workdir:/nvidia --bind=/usr/lib/nvidia/bin:/app/bin/ /app/bin/install-nvidia NVIDIA-Linux-x86_64-535.230.02 Jun 20 19:12:54.668700 sudo[2398]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 20 19:12:54.674363 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Jun 20 19:12:54.677034 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Jun 20 19:12:54.702956 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Jun 20 19:12:54.706863 systemd[1]: Starting user@0.service - User Manager for UID 0... Jun 20 19:12:54.732372 (systemd)[2401]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Jun 20 19:12:54.734656 systemd-logind[1887]: New session c3 of user root. Jun 20 19:12:54.921868 systemd[2401]: Queued start job for default target default.target. Jun 20 19:12:54.943604 systemd[2401]: Created slice app.slice - User Application Slice. Jun 20 19:12:54.943634 systemd[2401]: Reached target paths.target - Paths. Jun 20 19:12:54.943681 systemd[2401]: Reached target timers.target - Timers. Jun 20 19:12:54.944824 systemd[2401]: Starting dbus.socket - D-Bus User Message Bus Socket... Jun 20 19:12:54.954834 systemd[2401]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jun 20 19:12:54.954891 systemd[2401]: Reached target sockets.target - Sockets. Jun 20 19:12:54.954941 systemd[2401]: Reached target basic.target - Basic System. Jun 20 19:12:54.954989 systemd[2401]: Reached target default.target - Main User Target. Jun 20 19:12:54.955020 systemd[2401]: Startup finished in 214ms. Jun 20 19:12:54.955106 systemd[1]: Started user@0.service - User Manager for UID 0. Jun 20 19:12:54.958146 systemd[1]: Started session-c2.scope - Session c2 of User root. Jun 20 19:12:55.058502 kernel: loop6: detected capacity change from 0 to 12644352 Jun 20 19:12:55.066493 kernel: loop6: p9 Jun 20 19:12:55.097984 setup-nvidia[2410]: ░ Spawning container flatcardevelopercontainer-4344.1.0.bin on /opt/nvidia/workdir/flatcar_developer_container-4344.1.0.bin. Jun 20 19:12:55.293547 kernel: EXT4-fs (loop6p9): write access unavailable, skipping orphan cleanup Jun 20 19:12:55.293639 kernel: EXT4-fs (loop6p9): mounted filesystem c16469d9-dda3-462c-ac5d-9d8b0405d9ca ro without journal. Quota mode: none. Jun 20 19:12:56.387618 update_engine[1897]: I20250620 19:12:56.387537 1897 update_attempter.cc:509] Updating boot flags... Jun 20 19:12:58.663981 systemd[1]: Started sshd@3-10.200.4.33:22-10.200.16.10:35178.service - OpenSSH per-connection server daemon (10.200.16.10:35178). Jun 20 19:12:59.258225 sshd[2536]: Accepted publickey for core from 10.200.16.10 port 35178 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:12:59.259534 sshd-session[2536]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:12:59.263894 systemd-logind[1887]: New session 6 of user core. Jun 20 19:12:59.274594 systemd[1]: Started session-6.scope - Session 6 of User core. Jun 20 19:12:59.686570 sshd[2538]: Connection closed by 10.200.16.10 port 35178 Jun 20 19:12:59.686935 sshd-session[2536]: pam_unix(sshd:session): session closed for user core Jun 20 19:12:59.690296 systemd[1]: sshd@3-10.200.4.33:22-10.200.16.10:35178.service: Deactivated successfully. Jun 20 19:12:59.691838 systemd[1]: session-6.scope: Deactivated successfully. Jun 20 19:12:59.692493 systemd-logind[1887]: Session 6 logged out. Waiting for processes to exit. Jun 20 19:12:59.693424 systemd-logind[1887]: Removed session 6. Jun 20 19:12:59.791748 systemd[1]: Started sshd@4-10.200.4.33:22-10.200.16.10:35188.service - OpenSSH per-connection server daemon (10.200.16.10:35188). Jun 20 19:13:00.385908 sshd[2544]: Accepted publickey for core from 10.200.16.10 port 35188 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:13:00.387262 sshd-session[2544]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:13:00.391862 systemd-logind[1887]: New session 7 of user core. Jun 20 19:13:00.410632 systemd[1]: Started session-7.scope - Session 7 of User core. Jun 20 19:13:00.814334 sshd[2546]: Connection closed by 10.200.16.10 port 35188 Jun 20 19:13:00.814661 sshd-session[2544]: pam_unix(sshd:session): session closed for user core Jun 20 19:13:00.817934 systemd[1]: sshd@4-10.200.4.33:22-10.200.16.10:35188.service: Deactivated successfully. Jun 20 19:13:00.819418 systemd[1]: session-7.scope: Deactivated successfully. Jun 20 19:13:00.820084 systemd-logind[1887]: Session 7 logged out. Waiting for processes to exit. Jun 20 19:13:00.821005 systemd-logind[1887]: Removed session 7. Jun 20 19:13:10.930334 systemd[1]: Started sshd@5-10.200.4.33:22-10.200.16.10:39790.service - OpenSSH per-connection server daemon (10.200.16.10:39790). Jun 20 19:13:11.529254 sshd[3940]: Accepted publickey for core from 10.200.16.10 port 39790 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:13:11.530630 sshd-session[3940]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:13:11.535400 systemd-logind[1887]: New session 8 of user core. Jun 20 19:13:11.543666 systemd[1]: Started session-8.scope - Session 8 of User core. Jun 20 19:13:11.950836 sshd[4372]: Connection closed by 10.200.16.10 port 39790 Jun 20 19:13:11.951708 sshd-session[3940]: pam_unix(sshd:session): session closed for user core Jun 20 19:13:11.955426 systemd[1]: sshd@5-10.200.4.33:22-10.200.16.10:39790.service: Deactivated successfully. Jun 20 19:13:11.957806 systemd[1]: session-8.scope: Deactivated successfully. Jun 20 19:13:11.959677 systemd-logind[1887]: Session 8 logged out. Waiting for processes to exit. Jun 20 19:13:11.961581 systemd-logind[1887]: Removed session 8. Jun 20 19:13:12.054860 systemd[1]: Started sshd@6-10.200.4.33:22-10.200.16.10:39796.service - OpenSSH per-connection server daemon (10.200.16.10:39796). Jun 20 19:13:12.662853 sshd[4712]: Accepted publickey for core from 10.200.16.10 port 39796 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:13:12.664216 sshd-session[4712]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:13:12.669127 systemd-logind[1887]: New session 9 of user core. Jun 20 19:13:12.681623 systemd[1]: Started session-9.scope - Session 9 of User core. Jun 20 19:13:13.090320 sshd[5133]: Connection closed by 10.200.16.10 port 39796 Jun 20 19:13:13.090614 sshd-session[4712]: pam_unix(sshd:session): session closed for user core Jun 20 19:13:13.094105 systemd[1]: sshd@6-10.200.4.33:22-10.200.16.10:39796.service: Deactivated successfully. Jun 20 19:13:13.095648 systemd[1]: session-9.scope: Deactivated successfully. Jun 20 19:13:13.096322 systemd-logind[1887]: Session 9 logged out. Waiting for processes to exit. Jun 20 19:13:13.097375 systemd-logind[1887]: Removed session 9. Jun 20 19:13:23.200449 systemd[1]: Started sshd@7-10.200.4.33:22-10.200.16.10:36080.service - OpenSSH per-connection server daemon (10.200.16.10:36080). Jun 20 19:13:23.799172 sshd[6687]: Accepted publickey for core from 10.200.16.10 port 36080 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:13:23.800621 sshd-session[6687]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:13:23.805396 systemd-logind[1887]: New session 10 of user core. Jun 20 19:13:23.821000 systemd[1]: Started session-10.scope - Session 10 of User core. Jun 20 19:13:24.227666 sshd[6931]: Connection closed by 10.200.16.10 port 36080 Jun 20 19:13:24.229350 sshd-session[6687]: pam_unix(sshd:session): session closed for user core Jun 20 19:13:24.232966 systemd[1]: sshd@7-10.200.4.33:22-10.200.16.10:36080.service: Deactivated successfully. Jun 20 19:13:24.234647 systemd[1]: session-10.scope: Deactivated successfully. Jun 20 19:13:24.235337 systemd-logind[1887]: Session 10 logged out. Waiting for processes to exit. Jun 20 19:13:24.236431 systemd-logind[1887]: Removed session 10. Jun 20 19:13:24.343068 systemd[1]: Started sshd@8-10.200.4.33:22-10.200.16.10:36092.service - OpenSSH per-connection server daemon (10.200.16.10:36092). Jun 20 19:13:24.944152 sshd[6991]: Accepted publickey for core from 10.200.16.10 port 36092 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:13:24.945466 sshd-session[6991]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:13:24.949845 systemd-logind[1887]: New session 11 of user core. Jun 20 19:13:24.960810 systemd[1]: Started session-11.scope - Session 11 of User core. Jun 20 19:13:25.367690 sshd[7075]: Connection closed by 10.200.16.10 port 36092 Jun 20 19:13:25.369250 sshd-session[6991]: pam_unix(sshd:session): session closed for user core Jun 20 19:13:25.373878 systemd[1]: sshd@8-10.200.4.33:22-10.200.16.10:36092.service: Deactivated successfully. Jun 20 19:13:25.375552 systemd[1]: session-11.scope: Deactivated successfully. Jun 20 19:13:25.376958 systemd-logind[1887]: Session 11 logged out. Waiting for processes to exit. Jun 20 19:13:25.378161 systemd-logind[1887]: Removed session 11. Jun 20 19:13:35.477825 systemd[1]: Started sshd@9-10.200.4.33:22-10.200.16.10:37436.service - OpenSSH per-connection server daemon (10.200.16.10:37436). Jun 20 19:13:37.333107 sshd[8165]: Accepted publickey for core from 10.200.16.10 port 37436 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:13:37.333564 sshd-session[8165]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:13:37.339370 systemd-logind[1887]: New session 12 of user core. Jun 20 19:13:37.349627 systemd[1]: Started session-12.scope - Session 12 of User core. Jun 20 19:13:37.686594 sshd[8185]: Connection closed by 10.200.16.10 port 37436 Jun 20 19:13:37.687099 sshd-session[8165]: pam_unix(sshd:session): session closed for user core Jun 20 19:13:37.690927 systemd[1]: sshd@9-10.200.4.33:22-10.200.16.10:37436.service: Deactivated successfully. Jun 20 19:13:37.693012 systemd[1]: session-12.scope: Deactivated successfully. Jun 20 19:13:37.694527 systemd-logind[1887]: Session 12 logged out. Waiting for processes to exit. Jun 20 19:13:37.695791 systemd-logind[1887]: Removed session 12. Jun 20 19:13:37.793492 systemd[1]: Started sshd@10-10.200.4.33:22-10.200.16.10:37440.service - OpenSSH per-connection server daemon (10.200.16.10:37440). Jun 20 19:13:38.389390 sshd[8461]: Accepted publickey for core from 10.200.16.10 port 37440 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:13:39.882594 sshd-session[8461]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:13:39.887585 systemd-logind[1887]: New session 13 of user core. Jun 20 19:13:39.899687 systemd[1]: Started session-13.scope - Session 13 of User core. Jun 20 19:13:40.248511 sshd[8857]: Connection closed by 10.200.16.10 port 37440 Jun 20 19:13:40.247423 sshd-session[8461]: pam_unix(sshd:session): session closed for user core Jun 20 19:13:40.251730 systemd-logind[1887]: Session 13 logged out. Waiting for processes to exit. Jun 20 19:13:40.251832 systemd[1]: sshd@10-10.200.4.33:22-10.200.16.10:37440.service: Deactivated successfully. Jun 20 19:13:40.255033 systemd[1]: session-13.scope: Deactivated successfully. Jun 20 19:13:40.258256 systemd-logind[1887]: Removed session 13. Jun 20 19:13:50.358143 systemd[1]: Started sshd@11-10.200.4.33:22-10.200.16.10:47792.service - OpenSSH per-connection server daemon (10.200.16.10:47792). Jun 20 19:13:57.479567 sshd[10268]: Accepted publickey for core from 10.200.16.10 port 47792 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:13:50.953322 sshd-session[10268]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:13:50.958040 systemd-logind[1887]: New session 14 of user core. Jun 20 19:13:57.480251 sshd[10270]: Connection closed by 10.200.16.10 port 47792 Jun 20 19:13:57.480346 sshd[10276]: Accepted publickey for core from 10.200.16.10 port 47800 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:13:51.372383 sshd-session[10268]: pam_unix(sshd:session): session closed for user core Jun 20 19:13:50.980599 systemd[1]: Started session-14.scope - Session 14 of User core. Jun 20 19:13:57.480848 sshd[10278]: Connection closed by 10.200.16.10 port 47800 Jun 20 19:13:52.075756 sshd-session[10276]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:13:51.375940 systemd[1]: sshd@11-10.200.4.33:22-10.200.16.10:47792.service: Deactivated successfully. Jun 20 19:13:52.506392 sshd-session[10276]: pam_unix(sshd:session): session closed for user core Jun 20 19:13:51.377447 systemd[1]: session-14.scope: Deactivated successfully. Jun 20 19:13:51.378177 systemd-logind[1887]: Session 14 logged out. Waiting for processes to exit. Jun 20 19:13:51.379127 systemd-logind[1887]: Removed session 14. Jun 20 19:13:51.480803 systemd[1]: Started sshd@12-10.200.4.33:22-10.200.16.10:47800.service - OpenSSH per-connection server daemon (10.200.16.10:47800). Jun 20 19:13:52.080127 systemd-logind[1887]: New session 15 of user core. Jun 20 19:13:52.101598 systemd[1]: Started session-15.scope - Session 15 of User core. Jun 20 19:13:52.509229 systemd[1]: sshd@12-10.200.4.33:22-10.200.16.10:47800.service: Deactivated successfully. Jun 20 19:13:52.510805 systemd[1]: session-15.scope: Deactivated successfully. Jun 20 19:13:52.511461 systemd-logind[1887]: Session 15 logged out. Waiting for processes to exit. Jun 20 19:13:52.512398 systemd-logind[1887]: Removed session 15. Jun 20 19:14:02.617719 systemd[1]: Started sshd@13-10.200.4.33:22-10.200.16.10:54086.service - OpenSSH per-connection server daemon (10.200.16.10:54086). Jun 20 19:14:03.222885 sshd[11179]: Accepted publickey for core from 10.200.16.10 port 54086 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:14:03.224224 sshd-session[11179]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:14:03.228745 systemd-logind[1887]: New session 16 of user core. Jun 20 19:14:03.242599 systemd[1]: Started session-16.scope - Session 16 of User core. Jun 20 19:14:03.653733 sshd[11297]: Connection closed by 10.200.16.10 port 54086 Jun 20 19:14:03.654005 sshd-session[11179]: pam_unix(sshd:session): session closed for user core Jun 20 19:14:03.659332 systemd[1]: sshd@13-10.200.4.33:22-10.200.16.10:54086.service: Deactivated successfully. Jun 20 19:14:03.660984 systemd[1]: session-16.scope: Deactivated successfully. Jun 20 19:14:03.662678 systemd-logind[1887]: Session 16 logged out. Waiting for processes to exit. Jun 20 19:14:03.666925 systemd-logind[1887]: Removed session 16. Jun 20 19:14:03.763713 systemd[1]: Started sshd@14-10.200.4.33:22-10.200.16.10:54094.service - OpenSSH per-connection server daemon (10.200.16.10:54094). Jun 20 19:14:04.384061 sshd[11328]: Accepted publickey for core from 10.200.16.10 port 54094 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:14:04.385549 sshd-session[11328]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:14:04.391667 systemd-logind[1887]: New session 17 of user core. Jun 20 19:14:04.399623 systemd[1]: Started session-17.scope - Session 17 of User core. Jun 20 19:14:04.815369 sshd[11346]: Connection closed by 10.200.16.10 port 54094 Jun 20 19:14:04.818650 sshd-session[11328]: pam_unix(sshd:session): session closed for user core Jun 20 19:14:04.822741 systemd[1]: sshd@14-10.200.4.33:22-10.200.16.10:54094.service: Deactivated successfully. Jun 20 19:14:04.824380 systemd[1]: session-17.scope: Deactivated successfully. Jun 20 19:14:04.825503 systemd-logind[1887]: Session 17 logged out. Waiting for processes to exit. Jun 20 19:14:04.829236 systemd-logind[1887]: Removed session 17. Jun 20 19:14:14.926726 systemd[1]: Started sshd@15-10.200.4.33:22-10.200.16.10:50110.service - OpenSSH per-connection server daemon (10.200.16.10:50110). Jun 20 19:14:15.541438 sshd[11782]: Accepted publickey for core from 10.200.16.10 port 50110 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:14:15.545432 sshd-session[11782]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:14:15.554070 systemd-logind[1887]: New session 18 of user core. Jun 20 19:14:15.563577 systemd[1]: Started session-18.scope - Session 18 of User core. Jun 20 19:14:15.974368 sshd[11795]: Connection closed by 10.200.16.10 port 50110 Jun 20 19:14:15.974785 sshd-session[11782]: pam_unix(sshd:session): session closed for user core Jun 20 19:14:15.979196 systemd-logind[1887]: Session 18 logged out. Waiting for processes to exit. Jun 20 19:14:15.980774 systemd[1]: sshd@15-10.200.4.33:22-10.200.16.10:50110.service: Deactivated successfully. Jun 20 19:14:15.982342 systemd[1]: session-18.scope: Deactivated successfully. Jun 20 19:14:15.985077 systemd-logind[1887]: Removed session 18. Jun 20 19:14:16.084385 systemd[1]: Started sshd@16-10.200.4.33:22-10.200.16.10:50112.service - OpenSSH per-connection server daemon (10.200.16.10:50112). Jun 20 19:14:16.702154 sshd[11813]: Accepted publickey for core from 10.200.16.10 port 50112 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:14:16.704047 sshd-session[11813]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:14:16.710078 systemd-logind[1887]: New session 19 of user core. Jun 20 19:14:16.716722 systemd[1]: Started session-19.scope - Session 19 of User core. Jun 20 19:14:17.136411 sshd[11841]: Connection closed by 10.200.16.10 port 50112 Jun 20 19:14:17.136712 sshd-session[11813]: pam_unix(sshd:session): session closed for user core Jun 20 19:14:17.140550 systemd-logind[1887]: Session 19 logged out. Waiting for processes to exit. Jun 20 19:14:17.141180 systemd[1]: sshd@16-10.200.4.33:22-10.200.16.10:50112.service: Deactivated successfully. Jun 20 19:14:17.143034 systemd[1]: session-19.scope: Deactivated successfully. Jun 20 19:14:17.146867 systemd-logind[1887]: Removed session 19. Jun 20 19:14:27.244853 systemd[1]: Started sshd@17-10.200.4.33:22-10.200.16.10:58396.service - OpenSSH per-connection server daemon (10.200.16.10:58396). Jun 20 19:14:27.865834 sshd[12176]: Accepted publickey for core from 10.200.16.10 port 58396 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:14:27.867736 sshd-session[12176]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:14:27.875126 systemd-logind[1887]: New session 20 of user core. Jun 20 19:14:27.879054 systemd[1]: Started session-20.scope - Session 20 of User core. Jun 20 19:14:28.310685 sshd[12195]: Connection closed by 10.200.16.10 port 58396 Jun 20 19:14:28.311060 sshd-session[12176]: pam_unix(sshd:session): session closed for user core Jun 20 19:14:28.314537 systemd-logind[1887]: Session 20 logged out. Waiting for processes to exit. Jun 20 19:14:28.316089 systemd[1]: sshd@17-10.200.4.33:22-10.200.16.10:58396.service: Deactivated successfully. Jun 20 19:14:28.317645 systemd[1]: session-20.scope: Deactivated successfully. Jun 20 19:14:28.320141 systemd-logind[1887]: Removed session 20. Jun 20 19:14:28.419377 systemd[1]: Started sshd@18-10.200.4.33:22-10.200.16.10:58412.service - OpenSSH per-connection server daemon (10.200.16.10:58412). Jun 20 19:14:29.037840 sshd[12219]: Accepted publickey for core from 10.200.16.10 port 58412 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:14:29.039759 sshd-session[12219]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:14:29.047970 systemd-logind[1887]: New session 21 of user core. Jun 20 19:14:29.055668 systemd[1]: Started session-21.scope - Session 21 of User core. Jun 20 19:14:29.465759 sshd[12229]: Connection closed by 10.200.16.10 port 58412 Jun 20 19:14:29.466532 sshd-session[12219]: pam_unix(sshd:session): session closed for user core Jun 20 19:14:29.469920 systemd-logind[1887]: Session 21 logged out. Waiting for processes to exit. Jun 20 19:14:29.470601 systemd[1]: sshd@18-10.200.4.33:22-10.200.16.10:58412.service: Deactivated successfully. Jun 20 19:14:29.472674 systemd[1]: session-21.scope: Deactivated successfully. Jun 20 19:14:29.477804 systemd-logind[1887]: Removed session 21. Jun 20 19:14:39.577952 systemd[1]: Started sshd@19-10.200.4.33:22-10.200.16.10:44172.service - OpenSSH per-connection server daemon (10.200.16.10:44172). Jun 20 19:14:40.176929 sshd[12494]: Accepted publickey for core from 10.200.16.10 port 44172 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:14:40.178253 sshd-session[12494]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:14:40.182658 systemd-logind[1887]: New session 22 of user core. Jun 20 19:14:40.194623 systemd[1]: Started session-22.scope - Session 22 of User core. Jun 20 19:14:40.607643 sshd[12496]: Connection closed by 10.200.16.10 port 44172 Jun 20 19:14:40.607962 sshd-session[12494]: pam_unix(sshd:session): session closed for user core Jun 20 19:14:40.611337 systemd[1]: sshd@19-10.200.4.33:22-10.200.16.10:44172.service: Deactivated successfully. Jun 20 19:14:40.612892 systemd[1]: session-22.scope: Deactivated successfully. Jun 20 19:14:40.613577 systemd-logind[1887]: Session 22 logged out. Waiting for processes to exit. Jun 20 19:14:40.614813 systemd-logind[1887]: Removed session 22. Jun 20 19:14:40.725318 systemd[1]: Started sshd@20-10.200.4.33:22-10.200.16.10:44182.service - OpenSSH per-connection server daemon (10.200.16.10:44182). Jun 20 19:14:41.324818 sshd[12502]: Accepted publickey for core from 10.200.16.10 port 44182 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:14:41.326107 sshd-session[12502]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:14:41.330415 systemd-logind[1887]: New session 23 of user core. Jun 20 19:14:41.340600 systemd[1]: Started session-23.scope - Session 23 of User core. Jun 20 19:14:41.755272 sshd[12504]: Connection closed by 10.200.16.10 port 44182 Jun 20 19:14:41.755659 sshd-session[12502]: pam_unix(sshd:session): session closed for user core Jun 20 19:14:41.759215 systemd[1]: sshd@20-10.200.4.33:22-10.200.16.10:44182.service: Deactivated successfully. Jun 20 19:14:41.760793 systemd[1]: session-23.scope: Deactivated successfully. Jun 20 19:14:41.761484 systemd-logind[1887]: Session 23 logged out. Waiting for processes to exit. Jun 20 19:14:41.762464 systemd-logind[1887]: Removed session 23. Jun 20 19:14:44.855770 setup-nvidia[2410]: \u000d Jun 20 19:14:44.855770 setup-nvidia[2410]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Jun 20 19:14:44.855770 setup-nvidia[2410]: frequently when this kernel module was built against the wrong or Jun 20 19:14:44.855770 setup-nvidia[2410]: improperly configured kernel sources, with a version of gcc that differs Jun 20 19:14:44.855770 setup-nvidia[2410]: from the one used to build the target kernel, or if another driver, such Jun 20 19:14:44.855770 setup-nvidia[2410]: as nouveau, is present and prevents the NVIDIA kernel module from Jun 20 19:14:44.855770 setup-nvidia[2410]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Jun 20 19:14:44.855770 setup-nvidia[2410]: installed in this system is supported by this NVIDIA Linux graphics Jun 20 19:14:44.855770 setup-nvidia[2410]: driver release. Jun 20 19:14:44.855770 setup-nvidia[2410]: \u000d Jun 20 19:14:44.855770 setup-nvidia[2410]: Please see the log entries 'Kernel module load error' and 'Kernel Jun 20 19:14:44.855770 setup-nvidia[2410]: messages' at the end of the file Jun 20 19:14:44.855770 setup-nvidia[2410]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more Jun 20 19:14:44.855770 setup-nvidia[2410]: information. Jun 20 19:14:44.855770 setup-nvidia[2410]: \u000d Jun 20 19:14:44.870531 setup-nvidia[2410]: dmesg: read kernel buffer failed: Operation not permitted Jun 20 19:14:44.888953 setup-nvidia[2410]: \u000d Jun 20 19:14:44.888953 setup-nvidia[2410]: ERROR: Installation has failed. Please see the file Jun 20 19:14:44.888953 setup-nvidia[2410]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for Jun 20 19:14:44.888953 setup-nvidia[2410]: details. You may find suggestions on fixing installation problems in Jun 20 19:14:44.888953 setup-nvidia[2410]: the README available on the Linux driver download page at Jun 20 19:14:44.888953 setup-nvidia[2410]: www.nvidia.com. Jun 20 19:14:44.888953 setup-nvidia[2410]: \u000d Jun 20 19:14:44.889424 setup-nvidia[2410]: Last 50 lines of nvidia-installer.log: Jun 20 19:14:44.890237 setup-nvidia[2410]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039000rm+0x53: 'naked' return found in MITIGATION_RETHUNK build Jun 20 19:14:44.890237 setup-nvidia[2410]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038997rm+0x90: 'naked' return found in MITIGATION_RETHUNK build Jun 20 19:14:44.890237 setup-nvidia[2410]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038990rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build Jun 20 19:14:44.890237 setup-nvidia[2410]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039046rm+0x93: 'naked' return found in MITIGATION_RETHUNK build Jun 20 19:14:44.890237 setup-nvidia[2410]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039042rm+0x36: 'naked' return found in MITIGATION_RETHUNK build Jun 20 19:14:44.890237 setup-nvidia[2410]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039041rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build Jun 20 19:14:44.890237 setup-nvidia[2410]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039043rm+0x77: 'naked' return found in MITIGATION_RETHUNK build Jun 20 19:14:44.890237 setup-nvidia[2410]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038987rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build Jun 20 19:14:44.890237 setup-nvidia[2410]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030558rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build Jun 20 19:14:44.890237 setup-nvidia[2410]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030557rm+0x27: 'naked' return found in MITIGATION_RETHUNK build Jun 20 19:14:44.890237 setup-nvidia[2410]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030561rm+0x37: 'naked' return found in MITIGATION_RETHUNK build Jun 20 19:14:44.890237 setup-nvidia[2410]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030560rm+0x24: 'naked' return found in MITIGATION_RETHUNK build Jun 20 19:14:44.890237 setup-nvidia[2410]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030559rm+0xca: 'naked' return found in MITIGATION_RETHUNK build Jun 20 19:14:44.890237 setup-nvidia[2410]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030562rm+0x193: 'naked' return found in MITIGATION_RETHUNK build Jun 20 19:14:44.890237 setup-nvidia[2410]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030564rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build Jun 20 19:14:44.890237 setup-nvidia[2410]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv011860rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build Jun 20 19:14:44.890237 setup-nvidia[2410]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039354rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build Jun 20 19:14:44.890237 setup-nvidia[2410]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/Module.symvers Jun 20 19:14:44.890237 setup-nvidia[2410]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.mod.o Jun 20 19:14:44.890237 setup-nvidia[2410]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/.module-common.o Jun 20 19:14:44.890237 setup-nvidia[2410]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.mod.o Jun 20 19:14:44.890237 setup-nvidia[2410]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.mod.o Jun 20 19:14:44.890237 setup-nvidia[2410]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.mod.o Jun 20 19:14:44.890237 setup-nvidia[2410]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.mod.o Jun 20 19:14:44.890237 setup-nvidia[2410]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Jun 20 19:14:44.890237 setup-nvidia[2410]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Jun 20 19:14:44.890237 setup-nvidia[2410]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Jun 20 19:14:44.890237 setup-nvidia[2410]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Jun 20 19:14:44.890237 setup-nvidia[2410]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Jun 20 19:14:44.890237 setup-nvidia[2410]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Jun 20 19:14:44.890237 setup-nvidia[2410]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko due to unavailability of vmlinux Jun 20 19:14:44.890237 setup-nvidia[2410]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Jun 20 19:14:44.890237 setup-nvidia[2410]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko due to unavailability of vmlinux Jun 20 19:14:44.890237 setup-nvidia[2410]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Jun 20 19:14:44.890237 setup-nvidia[2410]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko due to unavailability of vmlinux Jun 20 19:14:44.890237 setup-nvidia[2410]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Jun 20 19:14:44.890237 setup-nvidia[2410]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko due to unavailability of vmlinux Jun 20 19:14:44.890237 setup-nvidia[2410]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Jun 20 19:14:44.890237 setup-nvidia[2410]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko due to unavailability of vmlinux Jun 20 19:14:44.890237 setup-nvidia[2410]: make[2]: Leaving directory '/usr/lib/modules/6.12.34-flatcar/build' Jun 20 19:14:44.890237 setup-nvidia[2410]: make[1]: Leaving directory '/usr/lib/modules/6.12.34-flatcar/source' Jun 20 19:14:44.890237 setup-nvidia[2410]: -> done. Jun 20 19:14:44.890237 setup-nvidia[2410]: -> Kernel module compilation complete. Jun 20 19:14:44.890237 setup-nvidia[2410]: -> Unable to determine if Secure Boot is enabled: No such file or directory Jun 20 19:14:44.891381 setup-nvidia[2410]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most frequently when this kernel module was built against the wrong or improperly configured kernel sources, with a version of gcc that differs from the one used to build the target kernel, or if another driver, such as nouveau, is present and prevents the NVIDIA kernel module from obtaining ownership of the NVIDIA device(s), or no NVIDIA device installed in this system is supported by this NVIDIA Linux graphics driver release. Jun 20 19:14:44.891381 setup-nvidia[2410]: \u000d Jun 20 19:14:44.891381 setup-nvidia[2410]: Please see the log entries 'Kernel module load error' and 'Kernel messages' at the end of the file '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more information. Jun 20 19:14:44.891381 setup-nvidia[2410]: -> Kernel module load error: Operation not permitted Jun 20 19:14:44.891381 setup-nvidia[2410]: -> Kernel messages: Jun 20 19:14:44.891381 setup-nvidia[2410]: ERROR: Installation has failed. Please see the file '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for details. You may find suggestions on fixing installation problems in the README available on the Linux driver download page at www.nvidia.com. Jun 20 19:14:51.864809 systemd[1]: Started sshd@21-10.200.4.33:22-10.200.16.10:60352.service - OpenSSH per-connection server daemon (10.200.16.10:60352). Jun 20 19:14:52.458445 sshd[12690]: Accepted publickey for core from 10.200.16.10 port 60352 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:14:52.459748 sshd-session[12690]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:14:52.464009 systemd-logind[1887]: New session 24 of user core. Jun 20 19:14:52.480600 systemd[1]: Started session-24.scope - Session 24 of User core. Jun 20 19:14:52.886495 sshd[12692]: Connection closed by 10.200.16.10 port 60352 Jun 20 19:14:52.886783 sshd-session[12690]: pam_unix(sshd:session): session closed for user core Jun 20 19:14:52.890102 systemd[1]: sshd@21-10.200.4.33:22-10.200.16.10:60352.service: Deactivated successfully. Jun 20 19:14:52.891789 systemd[1]: session-24.scope: Deactivated successfully. Jun 20 19:14:52.892549 systemd-logind[1887]: Session 24 logged out. Waiting for processes to exit. Jun 20 19:14:52.893509 systemd-logind[1887]: Removed session 24. Jun 20 19:14:52.996324 systemd[1]: Started sshd@22-10.200.4.33:22-10.200.16.10:60368.service - OpenSSH per-connection server daemon (10.200.16.10:60368). Jun 20 19:14:53.590238 sshd[12698]: Accepted publickey for core from 10.200.16.10 port 60368 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:14:53.591602 sshd-session[12698]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:14:53.595946 systemd-logind[1887]: New session 25 of user core. Jun 20 19:14:53.612598 systemd[1]: Started session-25.scope - Session 25 of User core. Jun 20 19:14:54.021676 sshd[12700]: Connection closed by 10.200.16.10 port 60368 Jun 20 19:14:54.022010 sshd-session[12698]: pam_unix(sshd:session): session closed for user core Jun 20 19:14:54.025370 systemd[1]: sshd@22-10.200.4.33:22-10.200.16.10:60368.service: Deactivated successfully. Jun 20 19:14:54.027074 systemd[1]: session-25.scope: Deactivated successfully. Jun 20 19:14:54.027839 systemd-logind[1887]: Session 25 logged out. Waiting for processes to exit. Jun 20 19:14:54.029029 systemd-logind[1887]: Removed session 25. Jun 20 19:14:57.453524 kernel: EXT4-fs (loop6p9): unmounting filesystem c16469d9-dda3-462c-ac5d-9d8b0405d9ca. Jun 20 19:14:57.454609 setup-nvidia[2410]: Container flatcardevelopercontainer-4344.1.0.bin exited successfully. Jun 20 19:14:57.457360 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4344.1.0.bin.mount: Deactivated successfully. Jun 20 19:14:57.512322 sudo[2398]: pam_unix(sudo:session): session closed for user root Jun 20 19:14:57.514292 systemd[1]: session-c2.scope: Deactivated successfully. Jun 20 19:14:57.514602 systemd[1]: session-c2.scope: Consumed 5min 13.482s CPU time, 892.6M memory peak. Jun 20 19:14:57.925552 setup-nvidia[1882]: /opt/nvidia / Jun 20 19:14:57.926514 setup-nvidia[1882]: / Jun 20 19:14:57.935496 kernel: IPMI message handler: version 39.2 Jun 20 19:14:57.939495 kernel: ipmi device interface Jun 20 19:14:57.952446 setup-nvidia[1882]: /opt/nvidia/current/usr/lib/modules/6.12.34-flatcar/video / Jun 20 19:14:58.003026 kernel: nvidia: loading out-of-tree module taints kernel. Jun 20 19:14:58.003171 kernel: nvidia: module license 'NVIDIA' taints kernel. Jun 20 19:14:58.003195 kernel: Disabling lock debugging due to kernel taint Jun 20 19:14:58.009995 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Jun 20 19:14:58.010043 kernel: nvidia: module license taints kernel. Jun 20 19:14:58.404510 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 Jun 20 19:14:58.808241 kernel: Jun 20 19:14:58.808378 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Jun 20 19:14:58.862493 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.230.02 Fri Dec 20 21:42:05 UTC 2024 Jun 20 19:14:58.883500 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.230.02 Fri Dec 20 21:52:34 UTC 2024 Jun 20 19:14:58.903523 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Jun 20 19:14:58.950314 setup-nvidia[1882]: / Jun 20 19:14:58.950757 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. Jun 20 19:14:58.965960 setup-nvidia[12740]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jun 20 19:15:00.552435 setup-nvidia[12757]: Fri Jun 20 19:15:00 2025 Jun 20 19:15:00.552435 setup-nvidia[12757]: +---------------------------------------------------------------------------------------+ Jun 20 19:15:00.552435 setup-nvidia[12757]: | NVIDIA-SMI 535.230.02 Driver Version: 535.230.02 CUDA Version: 12.2 | Jun 20 19:15:00.552435 setup-nvidia[12757]: |-----------------------------------------+----------------------+----------------------+ Jun 20 19:15:00.552435 setup-nvidia[12757]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Jun 20 19:15:00.552435 setup-nvidia[12757]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Jun 20 19:15:00.552435 setup-nvidia[12757]: | | | MIG M. | Jun 20 19:15:00.552435 setup-nvidia[12757]: |=========================================+======================+======================| Jun 20 19:15:00.555997 setup-nvidia[12757]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Jun 20 19:15:00.555997 setup-nvidia[12757]: | N/A 30C P0 34W / 250W | 0MiB / 16384MiB | 1% Default | Jun 20 19:15:00.555997 setup-nvidia[12757]: | | | N/A | Jun 20 19:15:00.555997 setup-nvidia[12757]: +-----------------------------------------+----------------------+----------------------+ Jun 20 19:15:00.556107 setup-nvidia[12757]: Jun 20 19:15:00.556107 setup-nvidia[12757]: +---------------------------------------------------------------------------------------+ Jun 20 19:15:00.556107 setup-nvidia[12757]: | Processes: | Jun 20 19:15:00.556107 setup-nvidia[12757]: | GPU GI CI PID Type Process name GPU Memory | Jun 20 19:15:00.556107 setup-nvidia[12757]: | ID ID Usage | Jun 20 19:15:00.556107 setup-nvidia[12757]: |=======================================================================================| Jun 20 19:15:00.556237 setup-nvidia[12757]: | No running processes found | Jun 20 19:15:00.556237 setup-nvidia[12757]: +---------------------------------------------------------------------------------------+ Jun 20 19:15:00.926112 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Jun 20 19:15:00.928057 systemd[1]: Reached target multi-user.target - Multi-User System. Jun 20 19:15:00.928298 systemd[1]: Startup finished in 1.074s (firmware) + 26.015s (loader) + 3.850s (kernel) + 11.751s (initrd) + 3min 1.940s (userspace) = 3min 44.631s. Jun 20 19:15:04.132062 systemd[1]: Started sshd@23-10.200.4.33:22-10.200.16.10:35678.service - OpenSSH per-connection server daemon (10.200.16.10:35678). Jun 20 19:15:04.740362 sshd[12764]: Accepted publickey for core from 10.200.16.10 port 35678 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:15:04.741675 sshd-session[12764]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:15:04.746015 systemd-logind[1887]: New session 26 of user core. Jun 20 19:15:04.756607 systemd[1]: Started session-26.scope - Session 26 of User core. Jun 20 19:15:05.168199 sshd[12766]: Connection closed by 10.200.16.10 port 35678 Jun 20 19:15:05.168573 sshd-session[12764]: pam_unix(sshd:session): session closed for user core Jun 20 19:15:05.171869 systemd[1]: sshd@23-10.200.4.33:22-10.200.16.10:35678.service: Deactivated successfully. Jun 20 19:15:05.173441 systemd[1]: session-26.scope: Deactivated successfully. Jun 20 19:15:05.174682 systemd-logind[1887]: Session 26 logged out. Waiting for processes to exit. Jun 20 19:15:05.175702 systemd-logind[1887]: Removed session 26. Jun 20 19:15:05.285699 systemd[1]: Started sshd@24-10.200.4.33:22-10.200.16.10:35686.service - OpenSSH per-connection server daemon (10.200.16.10:35686). Jun 20 19:15:05.883123 sshd[12772]: Accepted publickey for core from 10.200.16.10 port 35686 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:15:05.884570 sshd-session[12772]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:15:05.889628 systemd-logind[1887]: New session 27 of user core. Jun 20 19:15:05.897611 systemd[1]: Started session-27.scope - Session 27 of User core. Jun 20 19:15:06.308958 sshd[12774]: Connection closed by 10.200.16.10 port 35686 Jun 20 19:15:06.309327 sshd-session[12772]: pam_unix(sshd:session): session closed for user core Jun 20 19:15:06.312773 systemd[1]: sshd@24-10.200.4.33:22-10.200.16.10:35686.service: Deactivated successfully. Jun 20 19:15:06.314430 systemd[1]: session-27.scope: Deactivated successfully. Jun 20 19:15:06.315137 systemd-logind[1887]: Session 27 logged out. Waiting for processes to exit. Jun 20 19:15:06.316106 systemd-logind[1887]: Removed session 27. Jun 20 19:15:06.417913 systemd[1]: Started sshd@25-10.200.4.33:22-10.200.16.10:35690.service - OpenSSH per-connection server daemon (10.200.16.10:35690). Jun 20 19:15:07.012489 sshd[12781]: Accepted publickey for core from 10.200.16.10 port 35690 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:15:07.013849 sshd-session[12781]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:15:07.018157 systemd-logind[1887]: New session 28 of user core. Jun 20 19:15:07.037747 systemd[1]: Started session-28.scope - Session 28 of User core. Jun 20 19:15:07.441260 sshd[12783]: Connection closed by 10.200.16.10 port 35690 Jun 20 19:15:07.441650 sshd-session[12781]: pam_unix(sshd:session): session closed for user core Jun 20 19:15:07.445268 systemd[1]: sshd@25-10.200.4.33:22-10.200.16.10:35690.service: Deactivated successfully. Jun 20 19:15:07.446842 systemd[1]: session-28.scope: Deactivated successfully. Jun 20 19:15:07.447586 systemd-logind[1887]: Session 28 logged out. Waiting for processes to exit. Jun 20 19:15:07.448558 systemd-logind[1887]: Removed session 28. Jun 20 19:15:07.550711 systemd[1]: Started sshd@26-10.200.4.33:22-10.200.16.10:35704.service - OpenSSH per-connection server daemon (10.200.16.10:35704). Jun 20 19:15:07.551670 systemd[1]: Stopping user@0.service - User Manager for UID 0... Jun 20 19:15:07.552411 systemd[2401]: Activating special unit exit.target... Jun 20 19:15:07.552508 systemd[2401]: Stopped target default.target - Main User Target. Jun 20 19:15:07.552548 systemd[2401]: Stopped target basic.target - Basic System. Jun 20 19:15:07.552576 systemd[2401]: Stopped target paths.target - Paths. Jun 20 19:15:07.552600 systemd[2401]: Stopped target sockets.target - Sockets. Jun 20 19:15:07.552624 systemd[2401]: Stopped target timers.target - Timers. Jun 20 19:15:07.552951 systemd[2401]: Closed dbus.socket - D-Bus User Message Bus Socket. Jun 20 19:15:07.553155 systemd[2401]: Removed slice app.slice - User Application Slice. Jun 20 19:15:07.553186 systemd[2401]: Reached target shutdown.target - Shutdown. Jun 20 19:15:07.553321 systemd[2401]: Finished systemd-exit.service - Exit the Session. Jun 20 19:15:07.553356 systemd[2401]: Reached target exit.target - Exit the Session. Jun 20 19:15:07.562764 (sd-pam)[2402]: pam_unix(systemd-user:session): session closed for user root Jun 20 19:15:07.564005 systemd[1]: user@0.service: Deactivated successfully. Jun 20 19:15:07.564810 systemd[1]: Stopped user@0.service - User Manager for UID 0. Jun 20 19:15:07.567176 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Jun 20 19:15:07.579711 systemd[1]: run-user-0.mount: Deactivated successfully. Jun 20 19:15:07.580522 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Jun 20 19:15:07.580705 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Jun 20 19:15:07.582178 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Jun 20 19:15:07.582268 systemd[1]: user-0.slice: Consumed 5min 13.733s CPU time, 901.8M memory peak. Jun 20 19:15:07.582681 systemd-logind[1887]: Removed session c3. Jun 20 19:15:08.153439 sshd[12789]: Accepted publickey for core from 10.200.16.10 port 35704 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:15:08.154823 sshd-session[12789]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:15:08.159485 systemd-logind[1887]: New session 29 of user core. Jun 20 19:15:08.166595 systemd[1]: Started session-29.scope - Session 29 of User core. Jun 20 19:15:08.482281 sudo[12797]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Jun 20 19:15:08.482604 sudo[12797]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jun 20 19:15:08.500655 sudo[12797]: pam_unix(sudo:session): session closed for user root Jun 20 19:15:08.602677 sshd[12795]: Connection closed by 10.200.16.10 port 35704 Jun 20 19:15:08.603157 sshd-session[12789]: pam_unix(sshd:session): session closed for user core Jun 20 19:15:08.606914 systemd[1]: sshd@26-10.200.4.33:22-10.200.16.10:35704.service: Deactivated successfully. Jun 20 19:15:08.608460 systemd[1]: session-29.scope: Deactivated successfully. Jun 20 19:15:08.609156 systemd-logind[1887]: Session 29 logged out. Waiting for processes to exit. Jun 20 19:15:08.610149 systemd-logind[1887]: Removed session 29. Jun 20 19:15:08.708891 systemd[1]: Started sshd@27-10.200.4.33:22-10.200.16.10:49838.service - OpenSSH per-connection server daemon (10.200.16.10:49838). Jun 20 19:15:09.324501 sshd[12804]: Accepted publickey for core from 10.200.16.10 port 49838 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:15:09.325852 sshd-session[12804]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:15:09.330726 systemd-logind[1887]: New session 30 of user core. Jun 20 19:15:09.344617 systemd[1]: Started session-30.scope - Session 30 of User core. Jun 20 19:15:09.654522 sudo[12809]: 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 Jun 20 19:15:09.654819 sudo[12809]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jun 20 19:15:09.661946 sudo[12809]: pam_unix(sudo:session): session closed for user root Jun 20 19:15:09.667487 sudo[12808]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Jun 20 19:15:09.667770 sudo[12808]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jun 20 19:15:09.677355 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jun 20 19:15:09.718816 augenrules[12831]: No rules Jun 20 19:15:09.720081 systemd[1]: audit-rules.service: Deactivated successfully. Jun 20 19:15:09.720365 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jun 20 19:15:09.721216 sudo[12808]: pam_unix(sudo:session): session closed for user root Jun 20 19:15:09.819827 sshd[12807]: Connection closed by 10.200.16.10 port 49838 Jun 20 19:15:09.820159 sshd-session[12804]: pam_unix(sshd:session): session closed for user core Jun 20 19:15:09.823793 systemd[1]: sshd@27-10.200.4.33:22-10.200.16.10:49838.service: Deactivated successfully. Jun 20 19:15:09.825312 systemd[1]: session-30.scope: Deactivated successfully. Jun 20 19:15:09.826204 systemd-logind[1887]: Session 30 logged out. Waiting for processes to exit. Jun 20 19:15:09.827173 systemd-logind[1887]: Removed session 30. Jun 20 19:15:09.929333 systemd[1]: Started sshd@28-10.200.4.33:22-10.200.16.10:49852.service - OpenSSH per-connection server daemon (10.200.16.10:49852). Jun 20 19:15:10.545169 sshd[12840]: Accepted publickey for core from 10.200.16.10 port 49852 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:15:10.546567 sshd-session[12840]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:15:10.551104 systemd-logind[1887]: New session 31 of user core. Jun 20 19:15:10.565613 systemd[1]: Started session-31.scope - Session 31 of User core. Jun 20 19:15:10.873464 sudo[12843]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/mkdir -p . Jun 20 19:15:10.873773 sudo[12843]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jun 20 19:15:10.877077 sudo[12843]: pam_unix(sudo:session): session closed for user root Jun 20 19:15:10.979134 sshd[12842]: Connection closed by 10.200.16.10 port 49852 Jun 20 19:15:10.979470 sshd-session[12840]: pam_unix(sshd:session): session closed for user core Jun 20 19:15:10.982141 systemd[1]: sshd@28-10.200.4.33:22-10.200.16.10:49852.service: Deactivated successfully. Jun 20 19:15:10.983791 systemd[1]: session-31.scope: Deactivated successfully. Jun 20 19:15:10.985099 systemd-logind[1887]: Session 31 logged out. Waiting for processes to exit. Jun 20 19:15:10.986128 systemd-logind[1887]: Removed session 31. Jun 20 19:15:11.088890 systemd[1]: Started sshd@29-10.200.4.33:22-10.200.16.10:49866.service - OpenSSH per-connection server daemon (10.200.16.10:49866). Jun 20 19:15:11.710697 sshd[12849]: Accepted publickey for core from 10.200.16.10 port 49866 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:15:11.712030 sshd-session[12849]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:15:11.716299 systemd-logind[1887]: New session 32 of user core. Jun 20 19:15:11.730599 systemd[1]: Started session-32.scope - Session 32 of User core. Jun 20 19:15:12.041337 sudo[12852]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/install -m 0755 /dev/stdin kolet Jun 20 19:15:12.041696 sudo[12852]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jun 20 19:15:15.000336 sudo[12852]: pam_unix(sudo:session): session closed for user root Jun 20 19:15:15.095362 sshd[12851]: Connection closed by 10.200.16.10 port 49866 Jun 20 19:15:15.095650 sshd-session[12849]: pam_unix(sshd:session): session closed for user core Jun 20 19:15:15.099364 systemd[1]: sshd@29-10.200.4.33:22-10.200.16.10:49866.service: Deactivated successfully. Jun 20 19:15:15.101017 systemd[1]: session-32.scope: Deactivated successfully. Jun 20 19:15:15.101709 systemd-logind[1887]: Session 32 logged out. Waiting for processes to exit. Jun 20 19:15:15.102654 systemd-logind[1887]: Removed session 32. Jun 20 19:15:15.204804 systemd[1]: Started sshd@30-10.200.4.33:22-10.200.16.10:49880.service - OpenSSH per-connection server daemon (10.200.16.10:49880). Jun 20 19:15:15.796923 sshd[12858]: Accepted publickey for core from 10.200.16.10 port 49880 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:15:15.798283 sshd-session[12858]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:15:15.802678 systemd-logind[1887]: New session 33 of user core. Jun 20 19:15:15.811606 systemd[1]: Started session-33.scope - Session 33 of User core. Jun 20 19:15:16.278953 sshd[12860]: Connection closed by 10.200.16.10 port 49880 Jun 20 19:15:16.279320 sshd-session[12858]: pam_unix(sshd:session): session closed for user core Jun 20 19:15:16.282880 systemd[1]: sshd@30-10.200.4.33:22-10.200.16.10:49880.service: Deactivated successfully. Jun 20 19:15:16.284432 systemd[1]: session-33.scope: Deactivated successfully. Jun 20 19:15:16.285143 systemd-logind[1887]: Session 33 logged out. Waiting for processes to exit. Jun 20 19:15:16.286238 systemd-logind[1887]: Removed session 33. Jun 20 19:15:16.389011 systemd[1]: Started sshd@31-10.200.4.33:22-10.200.16.10:49894.service - OpenSSH per-connection server daemon (10.200.16.10:49894). Jun 20 19:15:16.986982 sshd[12876]: Accepted publickey for core from 10.200.16.10 port 49894 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:15:16.988324 sshd-session[12876]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:15:16.992640 systemd-logind[1887]: New session 34 of user core. Jun 20 19:15:17.002608 systemd[1]: Started session-34.scope - Session 34 of User core. Jun 20 19:15:18.082181 systemd[1]: Starting docker.service - Docker Application Container Engine... Jun 20 19:15:18.101816 (dockerd)[12898]: 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 Jun 20 19:15:19.149691 dockerd[12898]: time="2025-06-20T19:15:19.149631493Z" level=info msg="Starting up" Jun 20 19:15:19.150301 dockerd[12898]: time="2025-06-20T19:15:19.150273967Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Jun 20 19:15:20.583961 dockerd[12898]: time="2025-06-20T19:15:20.583907449Z" level=info msg="Loading containers: start." Jun 20 19:15:20.597500 kernel: Initializing XFRM netlink socket Jun 20 19:15:20.938612 systemd-networkd[1591]: docker0: Link UP Jun 20 19:15:20.956107 dockerd[12898]: time="2025-06-20T19:15:20.956069428Z" level=info msg="Loading containers: done." Jun 20 19:15:20.970018 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck2335950012-merged.mount: Deactivated successfully. Jun 20 19:15:20.985438 dockerd[12898]: time="2025-06-20T19:15:20.985328323Z" 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 Jun 20 19:15:20.985539 dockerd[12898]: time="2025-06-20T19:15:20.985522515Z" level=info msg="Docker daemon" commit=bbd0a17ccc67e48d4a69393287b7fcc4f0578683 containerd-snapshotter=false storage-driver=overlay2 version=28.0.1 Jun 20 19:15:20.985664 dockerd[12898]: time="2025-06-20T19:15:20.985641310Z" level=info msg="Initializing buildkit" Jun 20 19:15:21.033732 dockerd[12898]: time="2025-06-20T19:15:21.033703532Z" level=info msg="Completed buildkit initialization" Jun 20 19:15:21.038403 dockerd[12898]: time="2025-06-20T19:15:21.038373840Z" level=info msg="Daemon has completed initialization" Jun 20 19:15:21.038487 dockerd[12898]: time="2025-06-20T19:15:21.038431637Z" level=info msg="API listen on /run/docker.sock" Jun 20 19:15:21.038626 systemd[1]: Started docker.service - Docker Application Container Engine. Jun 20 19:15:22.309740 systemd[1]: var-lib-docker-overlay2-cbcc653c3781ab485538146914089a6c79e32f9301d50ba027d6c801912f5ac2\x2dinit-merged.mount: Deactivated successfully. Jun 20 19:15:22.420309 containerd[1911]: time="2025-06-20T19:15:22.420264671Z" level=info msg="connecting to shim 63d8c083715d29532bd2da2902b6407b5cdf53b0f4d60cfc7dd809db11e6d869" address="unix:///run/containerd/s/d1659f406700370b4dbb2d59132c94ef195e0b5f14c99db4b69c1b289ed7a8ef" namespace=moby protocol=ttrpc version=3 Jun 20 19:15:22.447608 systemd[1]: Started docker-63d8c083715d29532bd2da2902b6407b5cdf53b0f4d60cfc7dd809db11e6d869.scope - libcontainer container 63d8c083715d29532bd2da2902b6407b5cdf53b0f4d60cfc7dd809db11e6d869. Jun 20 19:15:22.495612 kernel: docker0: port 1(veth6961d64) entered blocking state Jun 20 19:15:22.495703 kernel: docker0: port 1(veth6961d64) entered disabled state Jun 20 19:15:22.495725 kernel: veth6961d64: entered allmulticast mode Jun 20 19:15:22.500629 kernel: veth6961d64: entered promiscuous mode Jun 20 19:15:22.501144 systemd-networkd[1591]: veth6961d64: Link UP Jun 20 19:15:22.528491 kernel: eth0: renamed from veth32b7c08 Jun 20 19:15:22.534635 kernel: docker0: port 1(veth6961d64) entered blocking state Jun 20 19:15:22.534691 kernel: docker0: port 1(veth6961d64) entered forwarding state Jun 20 19:15:22.534726 systemd-networkd[1591]: veth6961d64: Gained carrier Jun 20 19:15:22.535067 systemd-networkd[1591]: docker0: Gained carrier Jun 20 19:15:23.592620 systemd-networkd[1591]: veth6961d64: Gained IPv6LL Jun 20 19:15:24.040607 systemd-networkd[1591]: docker0: Gained IPv6LL Jun 20 19:15:25.826681 systemd[1]: docker-63d8c083715d29532bd2da2902b6407b5cdf53b0f4d60cfc7dd809db11e6d869.scope: Deactivated successfully. Jun 20 19:15:25.841031 dockerd[12898]: time="2025-06-20T19:15:25.840979614Z" level=info msg="ignoring event" container=63d8c083715d29532bd2da2902b6407b5cdf53b0f4d60cfc7dd809db11e6d869 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Jun 20 19:15:25.841370 containerd[1911]: time="2025-06-20T19:15:25.841178005Z" level=info msg="shim disconnected" id=63d8c083715d29532bd2da2902b6407b5cdf53b0f4d60cfc7dd809db11e6d869 namespace=moby Jun 20 19:15:25.841370 containerd[1911]: time="2025-06-20T19:15:25.841207104Z" level=warning msg="cleaning up after shim disconnected" id=63d8c083715d29532bd2da2902b6407b5cdf53b0f4d60cfc7dd809db11e6d869 namespace=moby Jun 20 19:15:25.841370 containerd[1911]: time="2025-06-20T19:15:25.841214904Z" level=info msg="cleaning up dead shim" namespace=moby Jun 20 19:15:25.873342 systemd-networkd[1591]: veth6961d64: Lost carrier Jun 20 19:15:25.873653 kernel: docker0: port 1(veth6961d64) entered disabled state Jun 20 19:15:25.873684 kernel: veth32b7c08: renamed from eth0 Jun 20 19:15:25.902213 systemd-networkd[1591]: veth6961d64: Link DOWN Jun 20 19:15:25.905503 kernel: docker0: port 1(veth6961d64) entered disabled state Jun 20 19:15:25.911692 kernel: veth6961d64 (unregistering): left allmulticast mode Jun 20 19:15:25.911748 kernel: veth6961d64 (unregistering): left promiscuous mode Jun 20 19:15:25.915171 kernel: docker0: port 1(veth6961d64) entered disabled state Jun 20 19:15:25.937201 systemd[1]: run-docker-netns-c6be0ac695c5.mount: Deactivated successfully. Jun 20 19:15:25.941151 systemd[1]: var-lib-docker-overlay2-cbcc653c3781ab485538146914089a6c79e32f9301d50ba027d6c801912f5ac2-merged.mount: Deactivated successfully. Jun 20 19:15:26.078426 sshd[12878]: Connection closed by 10.200.16.10 port 49894 Jun 20 19:15:26.078744 sshd-session[12876]: pam_unix(sshd:session): session closed for user core Jun 20 19:15:26.082194 systemd[1]: sshd@31-10.200.4.33:22-10.200.16.10:49894.service: Deactivated successfully. Jun 20 19:15:26.083888 systemd[1]: session-34.scope: Deactivated successfully. Jun 20 19:15:26.084669 systemd-logind[1887]: Session 34 logged out. Waiting for processes to exit. Jun 20 19:15:26.086530 systemd-logind[1887]: Removed session 34. Jun 20 19:15:26.186927 systemd[1]: Started sshd@32-10.200.4.33:22-10.200.16.10:51166.service - OpenSSH per-connection server daemon (10.200.16.10:51166). Jun 20 19:15:26.780731 sshd[13180]: Accepted publickey for core from 10.200.16.10 port 51166 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:15:26.782048 sshd-session[13180]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:15:26.786494 systemd-logind[1887]: New session 35 of user core. Jun 20 19:15:26.797604 systemd[1]: Started session-35.scope - Session 35 of User core. Jun 20 19:15:26.920733 systemd-networkd[1591]: docker0: Lost carrier Jun 20 19:15:27.164868 systemd[1]: var-lib-docker-overlay2-1946ba40997ecd6e14211178a747f0d369c700361cd9c69e36f86997164be534\x2dinit-merged.mount: Deactivated successfully. Jun 20 19:15:27.294110 containerd[1911]: time="2025-06-20T19:15:27.294066493Z" level=info msg="connecting to shim c9e9f47dbbfd7a22310d31acd2dffb50cc82ee39e3f0eccf35f2f1e15b9580f2" address="unix:///run/containerd/s/571bbd475e13b06f1f6d3ee1ea5c272ecfadb45d6a053afa834f15d01abd27b4" namespace=moby protocol=ttrpc version=3 Jun 20 19:15:27.335602 systemd[1]: Started docker-c9e9f47dbbfd7a22310d31acd2dffb50cc82ee39e3f0eccf35f2f1e15b9580f2.scope - libcontainer container c9e9f47dbbfd7a22310d31acd2dffb50cc82ee39e3f0eccf35f2f1e15b9580f2. Jun 20 19:15:27.379542 kernel: docker0: port 1(veth9f9bce8) entered blocking state Jun 20 19:15:27.379627 kernel: docker0: port 1(veth9f9bce8) entered disabled state Jun 20 19:15:27.382307 kernel: veth9f9bce8: entered allmulticast mode Jun 20 19:15:27.385138 kernel: veth9f9bce8: entered promiscuous mode Jun 20 19:15:27.385654 systemd-networkd[1591]: veth9f9bce8: Link UP Jun 20 19:15:27.409509 kernel: eth0: renamed from vethfc86948 Jun 20 19:15:27.409685 kernel: docker0: port 1(veth9f9bce8) entered blocking state Jun 20 19:15:27.415381 kernel: docker0: port 1(veth9f9bce8) entered forwarding state Jun 20 19:15:27.415778 systemd-networkd[1591]: veth9f9bce8: Gained carrier Jun 20 19:15:27.416061 systemd-networkd[1591]: docker0: Gained carrier Jun 20 19:15:27.453747 systemd[1]: docker-c9e9f47dbbfd7a22310d31acd2dffb50cc82ee39e3f0eccf35f2f1e15b9580f2.scope: Deactivated successfully. Jun 20 19:15:27.475977 dockerd[12898]: time="2025-06-20T19:15:27.475922406Z" level=info msg="ignoring event" container=c9e9f47dbbfd7a22310d31acd2dffb50cc82ee39e3f0eccf35f2f1e15b9580f2 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Jun 20 19:15:27.476441 containerd[1911]: time="2025-06-20T19:15:27.475946505Z" level=info msg="shim disconnected" id=c9e9f47dbbfd7a22310d31acd2dffb50cc82ee39e3f0eccf35f2f1e15b9580f2 namespace=moby Jun 20 19:15:27.476516 containerd[1911]: time="2025-06-20T19:15:27.476445684Z" level=warning msg="cleaning up after shim disconnected" id=c9e9f47dbbfd7a22310d31acd2dffb50cc82ee39e3f0eccf35f2f1e15b9580f2 namespace=moby Jun 20 19:15:27.476553 containerd[1911]: time="2025-06-20T19:15:27.476457784Z" level=info msg="cleaning up dead shim" namespace=moby Jun 20 19:15:27.506334 systemd-networkd[1591]: veth9f9bce8: Lost carrier Jun 20 19:15:27.506494 kernel: docker0: port 1(veth9f9bce8) entered disabled state Jun 20 19:15:27.506533 kernel: vethfc86948: renamed from eth0 Jun 20 19:15:27.534792 systemd-networkd[1591]: veth9f9bce8: Link DOWN Jun 20 19:15:27.538491 kernel: docker0: port 1(veth9f9bce8) entered disabled state Jun 20 19:15:27.545114 kernel: veth9f9bce8 (unregistering): left allmulticast mode Jun 20 19:15:27.545169 kernel: veth9f9bce8 (unregistering): left promiscuous mode Jun 20 19:15:27.548317 kernel: docker0: port 1(veth9f9bce8) entered disabled state Jun 20 19:15:27.699810 sshd[13182]: Connection closed by 10.200.16.10 port 51166 Jun 20 19:15:27.700117 sshd-session[13180]: pam_unix(sshd:session): session closed for user core Jun 20 19:15:27.702988 systemd[1]: sshd@32-10.200.4.33:22-10.200.16.10:51166.service: Deactivated successfully. Jun 20 19:15:27.704636 systemd[1]: session-35.scope: Deactivated successfully. Jun 20 19:15:27.705344 systemd-logind[1887]: Session 35 logged out. Waiting for processes to exit. Jun 20 19:15:27.706771 systemd-logind[1887]: Removed session 35. Jun 20 19:15:27.809825 systemd[1]: Started sshd@33-10.200.4.33:22-10.200.16.10:51176.service - OpenSSH per-connection server daemon (10.200.16.10:51176). Jun 20 19:15:27.928708 systemd-networkd[1591]: docker0: Lost carrier Jun 20 19:15:28.162147 systemd[1]: var-lib-docker-overlay2-1946ba40997ecd6e14211178a747f0d369c700361cd9c69e36f86997164be534-merged.mount: Deactivated successfully. Jun 20 19:15:28.406346 sshd[13281]: Accepted publickey for core from 10.200.16.10 port 51176 ssh2: RSA SHA256:O8GQOq7yebTfI/4qc3nDpZjJWJ986OS5ViAx3NvIOOk Jun 20 19:15:28.407729 sshd-session[13281]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jun 20 19:15:28.412024 systemd-logind[1887]: New session 36 of user core. Jun 20 19:15:28.420604 systemd[1]: Started session-36.scope - Session 36 of User core. Jun 20 19:15:28.782626 ntpdate[13294]: ntpdate 4.2.8p17@1.4004-o Fri Jun 20 16:24:38 UTC 2025 (1) Jun 20 19:15:35.496436 ntpdate[13294]: adjust time server 92.53.243.22 offset -0.000708 sec Jun 20 19:15:35.592093 sshd[13283]: Connection closed by 10.200.16.10 port 51176 Jun 20 19:15:35.592488 sshd-session[13281]: pam_unix(sshd:session): session closed for user core Jun 20 19:15:35.596145 systemd[1]: sshd@33-10.200.4.33:22-10.200.16.10:51176.service: Deactivated successfully. Jun 20 19:15:35.598092 systemd[1]: session-36.scope: Deactivated successfully. Jun 20 19:15:35.598864 systemd-logind[1887]: Session 36 logged out. Waiting for processes to exit. Jun 20 19:15:35.599814 systemd-logind[1887]: Removed session 36.