Jul 15 05:16:06.177620 kernel: Linux version 6.12.36-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.0 p8) 14.3.0, GNU ld (Gentoo 2.44 p1) 2.44.0) #1 SMP PREEMPT_DYNAMIC Tue Jul 15 03:28:48 -00 2025 Jul 15 05:16:06.177647 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=926b029026d98240a9e8b6527b65fc026ae523bea87c3b77ffd7237bcc7be4fb Jul 15 05:16:06.177657 kernel: BIOS-provided physical RAM map: Jul 15 05:16:06.177664 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Jul 15 05:16:06.177670 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Jul 15 05:16:06.177677 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Jul 15 05:16:06.177684 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Jul 15 05:16:06.177694 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Jul 15 05:16:06.177701 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Jul 15 05:16:06.177708 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Jul 15 05:16:06.177715 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Jul 15 05:16:06.177721 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Jul 15 05:16:06.177728 kernel: printk: legacy bootconsole [earlyser0] enabled Jul 15 05:16:06.177735 kernel: NX (Execute Disable) protection: active Jul 15 05:16:06.177746 kernel: APIC: Static calls initialized Jul 15 05:16:06.177753 kernel: efi: EFI v2.7 by Microsoft Jul 15 05:16:06.177761 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Jul 15 05:16:06.177769 kernel: random: crng init done Jul 15 05:16:06.177776 kernel: secureboot: Secure boot disabled Jul 15 05:16:06.177784 kernel: SMBIOS 3.1.0 present. Jul 15 05:16:06.177791 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Jul 15 05:16:06.177799 kernel: DMI: Memory slots populated: 3/3 Jul 15 05:16:06.177806 kernel: Hypervisor detected: Microsoft Hyper-V Jul 15 05:16:06.177814 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Jul 15 05:16:06.177823 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Jul 15 05:16:06.177831 kernel: Hyper-V: Using hypercall for remote TLB flush Jul 15 05:16:06.177838 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Jul 15 05:16:06.177846 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Jul 15 05:16:06.177854 kernel: tsc: Marking TSC unstable due to running on Hyper-V Jul 15 05:16:06.177862 kernel: tsc: Detected 2593.992 MHz processor Jul 15 05:16:06.177870 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Jul 15 05:16:06.177877 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Jul 15 05:16:06.177885 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Jul 15 05:16:06.177895 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Jul 15 05:16:06.177903 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Jul 15 05:16:06.177910 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Jul 15 05:16:06.177918 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Jul 15 05:16:06.177925 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Jul 15 05:16:06.177933 kernel: Using GB pages for direct mapping Jul 15 05:16:06.177940 kernel: ACPI: Early table checksum verification disabled Jul 15 05:16:06.177963 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Jul 15 05:16:06.177975 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 15 05:16:06.177984 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 15 05:16:06.177992 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Jul 15 05:16:06.178000 kernel: ACPI: FACS 0x000000003FFFE000 000040 Jul 15 05:16:06.178008 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 15 05:16:06.178016 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 15 05:16:06.178026 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 15 05:16:06.178034 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 15 05:16:06.178043 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 15 05:16:06.178051 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 15 05:16:06.178059 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 15 05:16:06.178067 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Jul 15 05:16:06.178074 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Jul 15 05:16:06.178082 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Jul 15 05:16:06.178090 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Jul 15 05:16:06.178101 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Jul 15 05:16:06.178109 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Jul 15 05:16:06.178117 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Jul 15 05:16:06.178125 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Jul 15 05:16:06.178133 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Jul 15 05:16:06.178141 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Jul 15 05:16:06.178149 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Jul 15 05:16:06.178157 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Jul 15 05:16:06.178167 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Jul 15 05:16:06.178175 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Jul 15 05:16:06.178183 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Jul 15 05:16:06.178191 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Jul 15 05:16:06.178199 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Jul 15 05:16:06.178207 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Jul 15 05:16:06.178215 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] Jul 15 05:16:06.178223 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] Jul 15 05:16:06.178231 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] Jul 15 05:16:06.178242 kernel: Zone ranges: Jul 15 05:16:06.178250 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Jul 15 05:16:06.178258 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Jul 15 05:16:06.178266 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Jul 15 05:16:06.178274 kernel: Device empty Jul 15 05:16:06.178282 kernel: Movable zone start for each node Jul 15 05:16:06.178290 kernel: Early memory node ranges Jul 15 05:16:06.178298 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Jul 15 05:16:06.178306 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Jul 15 05:16:06.178316 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Jul 15 05:16:06.178324 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Jul 15 05:16:06.178332 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Jul 15 05:16:06.178340 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Jul 15 05:16:06.178348 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Jul 15 05:16:06.178356 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Jul 15 05:16:06.178364 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Jul 15 05:16:06.178372 kernel: ACPI: PM-Timer IO Port: 0x408 Jul 15 05:16:06.178380 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Jul 15 05:16:06.178390 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Jul 15 05:16:06.178398 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Jul 15 05:16:06.178406 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Jul 15 05:16:06.178414 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Jul 15 05:16:06.178422 kernel: CPU topo: Max. logical packages: 1 Jul 15 05:16:06.178430 kernel: CPU topo: Max. logical dies: 1 Jul 15 05:16:06.178438 kernel: CPU topo: Max. dies per package: 1 Jul 15 05:16:06.178446 kernel: CPU topo: Max. threads per core: 1 Jul 15 05:16:06.178454 kernel: CPU topo: Num. cores per package: 6 Jul 15 05:16:06.178464 kernel: CPU topo: Num. threads per package: 6 Jul 15 05:16:06.178473 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs Jul 15 05:16:06.178481 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Jul 15 05:16:06.178489 kernel: Booting paravirtualized kernel on Hyper-V Jul 15 05:16:06.178497 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Jul 15 05:16:06.178505 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Jul 15 05:16:06.178513 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 Jul 15 05:16:06.178521 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 Jul 15 05:16:06.178528 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Jul 15 05:16:06.178539 kernel: Hyper-V: PV spinlocks enabled Jul 15 05:16:06.178547 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Jul 15 05:16:06.178556 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=926b029026d98240a9e8b6527b65fc026ae523bea87c3b77ffd7237bcc7be4fb Jul 15 05:16:06.178564 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Jul 15 05:16:06.178572 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Jul 15 05:16:06.178581 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Jul 15 05:16:06.178589 kernel: Fallback order for Node 0: 0 Jul 15 05:16:06.178597 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 Jul 15 05:16:06.178607 kernel: Policy zone: Normal Jul 15 05:16:06.178623 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jul 15 05:16:06.178631 kernel: software IO TLB: area num 8. Jul 15 05:16:06.178642 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Jul 15 05:16:06.178651 kernel: Kernel/User page tables isolation: enabled Jul 15 05:16:06.178659 kernel: ftrace: allocating 40097 entries in 157 pages Jul 15 05:16:06.178667 kernel: ftrace: allocated 157 pages with 5 groups Jul 15 05:16:06.178676 kernel: Dynamic Preempt: voluntary Jul 15 05:16:06.178684 kernel: rcu: Preemptible hierarchical RCU implementation. Jul 15 05:16:06.178693 kernel: rcu: RCU event tracing is enabled. Jul 15 05:16:06.178702 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Jul 15 05:16:06.178710 kernel: Trampoline variant of Tasks RCU enabled. Jul 15 05:16:06.178721 kernel: Rude variant of Tasks RCU enabled. Jul 15 05:16:06.178730 kernel: Tracing variant of Tasks RCU enabled. Jul 15 05:16:06.178738 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Jul 15 05:16:06.178747 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Jul 15 05:16:06.178755 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jul 15 05:16:06.178766 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jul 15 05:16:06.178775 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jul 15 05:16:06.178784 kernel: Using NULL legacy PIC Jul 15 05:16:06.178792 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Jul 15 05:16:06.178801 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Jul 15 05:16:06.178809 kernel: Console: colour dummy device 80x25 Jul 15 05:16:06.178817 kernel: printk: legacy console [tty1] enabled Jul 15 05:16:06.178826 kernel: printk: legacy console [ttyS0] enabled Jul 15 05:16:06.178834 kernel: printk: legacy bootconsole [earlyser0] disabled Jul 15 05:16:06.178845 kernel: ACPI: Core revision 20240827 Jul 15 05:16:06.178854 kernel: Failed to register legacy timer interrupt Jul 15 05:16:06.178862 kernel: APIC: Switch to symmetric I/O mode setup Jul 15 05:16:06.178871 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Jul 15 05:16:06.178879 kernel: Hyper-V: enabling crash_kexec_post_notifiers Jul 15 05:16:06.178887 kernel: Hyper-V: Using IPI hypercalls Jul 15 05:16:06.178896 kernel: APIC: send_IPI() replaced with hv_send_ipi() Jul 15 05:16:06.178904 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Jul 15 05:16:06.178913 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Jul 15 05:16:06.178923 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Jul 15 05:16:06.178935 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Jul 15 05:16:06.178943 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Jul 15 05:16:06.178959 kernel: Hyper-V: Using enlightened APIC (xapic mode) Jul 15 05:16:06.178968 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Jul 15 05:16:06.178976 kernel: APIC: read() replaced with hv_apic_read() Jul 15 05:16:06.178985 kernel: APIC: write() replaced with hv_apic_write() Jul 15 05:16:06.178993 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Jul 15 05:16:06.179001 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Jul 15 05:16:06.179013 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593992) Jul 15 05:16:06.179022 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Jul 15 05:16:06.179031 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Jul 15 05:16:06.179039 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Jul 15 05:16:06.179047 kernel: Spectre V2 : Mitigation: Retpolines Jul 15 05:16:06.179056 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Jul 15 05:16:06.179064 kernel: Speculative Store Bypass: Vulnerable Jul 15 05:16:06.179072 kernel: MDS: Mitigation: Clear CPU buffers Jul 15 05:16:06.179080 kernel: TAA: Mitigation: Clear CPU buffers Jul 15 05:16:06.179089 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Jul 15 05:16:06.179099 kernel: ITS: Mitigation: Aligned branch/return thunks Jul 15 05:16:06.179108 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Jul 15 05:16:06.179116 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Jul 15 05:16:06.179124 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Jul 15 05:16:06.179133 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Jul 15 05:16:06.179141 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Jul 15 05:16:06.179150 kernel: Freeing SMP alternatives memory: 32K Jul 15 05:16:06.179158 kernel: pid_max: default: 32768 minimum: 301 Jul 15 05:16:06.179166 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Jul 15 05:16:06.179175 kernel: landlock: Up and running. Jul 15 05:16:06.179183 kernel: SELinux: Initializing. Jul 15 05:16:06.179194 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Jul 15 05:16:06.179202 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Jul 15 05:16:06.179211 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Jul 15 05:16:06.179226 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Jul 15 05:16:06.179235 kernel: signal: max sigframe size: 1776 Jul 15 05:16:06.179249 kernel: rcu: Hierarchical SRCU implementation. Jul 15 05:16:06.179258 kernel: rcu: Max phase no-delay instances is 400. Jul 15 05:16:06.179266 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Jul 15 05:16:06.179275 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Jul 15 05:16:06.179283 kernel: smp: Bringing up secondary CPUs ... Jul 15 05:16:06.179295 kernel: smpboot: x86: Booting SMP configuration: Jul 15 05:16:06.179304 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Jul 15 05:16:06.179312 kernel: smp: Brought up 1 node, 6 CPUs Jul 15 05:16:06.179321 kernel: smpboot: Total of 6 processors activated (31127.90 BogoMIPS) Jul 15 05:16:06.179330 kernel: Memory: 115339092K/117439364K available (14336K kernel code, 2430K rwdata, 9956K rodata, 54608K init, 2360K bss, 2089996K reserved, 0K cma-reserved) Jul 15 05:16:06.179338 kernel: devtmpfs: initialized Jul 15 05:16:06.179347 kernel: x86/mm: Memory block size: 1024MB Jul 15 05:16:06.179355 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Jul 15 05:16:06.179364 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Jul 15 05:16:06.179375 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Jul 15 05:16:06.179384 kernel: pinctrl core: initialized pinctrl subsystem Jul 15 05:16:06.179392 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jul 15 05:16:06.179400 kernel: audit: initializing netlink subsys (disabled) Jul 15 05:16:06.179409 kernel: audit: type=2000 audit(1752556562.028:1): state=initialized audit_enabled=0 res=1 Jul 15 05:16:06.179417 kernel: thermal_sys: Registered thermal governor 'step_wise' Jul 15 05:16:06.179426 kernel: thermal_sys: Registered thermal governor 'user_space' Jul 15 05:16:06.179434 kernel: cpuidle: using governor menu Jul 15 05:16:06.179442 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Jul 15 05:16:06.179453 kernel: dca service started, version 1.12.1 Jul 15 05:16:06.179462 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Jul 15 05:16:06.179470 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Jul 15 05:16:06.179479 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Jul 15 05:16:06.179487 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Jul 15 05:16:06.179496 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Jul 15 05:16:06.179504 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Jul 15 05:16:06.179512 kernel: ACPI: Added _OSI(Module Device) Jul 15 05:16:06.179521 kernel: ACPI: Added _OSI(Processor Device) Jul 15 05:16:06.179532 kernel: ACPI: Added _OSI(Processor Aggregator Device) Jul 15 05:16:06.179540 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Jul 15 05:16:06.179548 kernel: ACPI: Interpreter enabled Jul 15 05:16:06.179562 kernel: ACPI: PM: (supports S0 S5) Jul 15 05:16:06.179571 kernel: ACPI: Using IOAPIC for interrupt routing Jul 15 05:16:06.179580 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Jul 15 05:16:06.179588 kernel: PCI: Ignoring E820 reservations for host bridge windows Jul 15 05:16:06.179600 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Jul 15 05:16:06.179610 kernel: iommu: Default domain type: Translated Jul 15 05:16:06.179622 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Jul 15 05:16:06.179630 kernel: efivars: Registered efivars operations Jul 15 05:16:06.179644 kernel: PCI: Using ACPI for IRQ routing Jul 15 05:16:06.179652 kernel: PCI: System does not support PCI Jul 15 05:16:06.179661 kernel: vgaarb: loaded Jul 15 05:16:06.179670 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Jul 15 05:16:06.179678 kernel: VFS: Disk quotas dquot_6.6.0 Jul 15 05:16:06.179687 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jul 15 05:16:06.179695 kernel: pnp: PnP ACPI init Jul 15 05:16:06.179706 kernel: pnp: PnP ACPI: found 3 devices Jul 15 05:16:06.179715 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Jul 15 05:16:06.179724 kernel: NET: Registered PF_INET protocol family Jul 15 05:16:06.179732 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Jul 15 05:16:06.179741 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Jul 15 05:16:06.179749 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jul 15 05:16:06.179758 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Jul 15 05:16:06.179766 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jul 15 05:16:06.179777 kernel: TCP: Hash tables configured (established 524288 bind 65536) Jul 15 05:16:06.179786 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jul 15 05:16:06.179795 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jul 15 05:16:06.179803 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jul 15 05:16:06.179811 kernel: NET: Registered PF_XDP protocol family Jul 15 05:16:06.179820 kernel: PCI: CLS 0 bytes, default 64 Jul 15 05:16:06.179828 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Jul 15 05:16:06.179837 kernel: software IO TLB: mapped [mem 0x000000003b5bf000-0x000000003f5bf000] (64MB) Jul 15 05:16:06.179845 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Jul 15 05:16:06.179856 kernel: Initialise system trusted keyrings Jul 15 05:16:06.179865 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Jul 15 05:16:06.179873 kernel: Key type asymmetric registered Jul 15 05:16:06.179881 kernel: Asymmetric key parser 'x509' registered Jul 15 05:16:06.179890 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Jul 15 05:16:06.179898 kernel: io scheduler mq-deadline registered Jul 15 05:16:06.179906 kernel: io scheduler kyber registered Jul 15 05:16:06.179915 kernel: io scheduler bfq registered Jul 15 05:16:06.179923 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Jul 15 05:16:06.179932 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Jul 15 05:16:06.179943 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Jul 15 05:16:06.179958 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Jul 15 05:16:06.179968 kernel: i8042: PNP: No PS/2 controller found. Jul 15 05:16:06.180176 kernel: rtc_cmos 00:02: registered as rtc0 Jul 15 05:16:06.180296 kernel: rtc_cmos 00:02: setting system clock to 2025-07-15T05:16:05 UTC (1752556565) Jul 15 05:16:06.180397 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Jul 15 05:16:06.180409 kernel: intel_pstate: CPU model not supported Jul 15 05:16:06.180424 kernel: efifb: probing for efifb Jul 15 05:16:06.180432 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Jul 15 05:16:06.180441 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Jul 15 05:16:06.180449 kernel: efifb: scrolling: redraw Jul 15 05:16:06.180458 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Jul 15 05:16:06.180466 kernel: Console: switching to colour frame buffer device 128x48 Jul 15 05:16:06.180475 kernel: fb0: EFI VGA frame buffer device Jul 15 05:16:06.180483 kernel: pstore: Using crash dump compression: deflate Jul 15 05:16:06.180491 kernel: pstore: Registered efi_pstore as persistent store backend Jul 15 05:16:06.180503 kernel: NET: Registered PF_INET6 protocol family Jul 15 05:16:06.180511 kernel: Segment Routing with IPv6 Jul 15 05:16:06.180520 kernel: In-situ OAM (IOAM) with IPv6 Jul 15 05:16:06.180528 kernel: NET: Registered PF_PACKET protocol family Jul 15 05:16:06.180536 kernel: Key type dns_resolver registered Jul 15 05:16:06.180545 kernel: IPI shorthand broadcast: enabled Jul 15 05:16:06.180553 kernel: sched_clock: Marking stable (3759003700, 108142400)->(4196524700, -329378600) Jul 15 05:16:06.180562 kernel: registered taskstats version 1 Jul 15 05:16:06.180570 kernel: Loading compiled-in X.509 certificates Jul 15 05:16:06.180581 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.36-flatcar: a24478b628e55368911ce1800a2bd6bc158938c7' Jul 15 05:16:06.180590 kernel: Demotion targets for Node 0: null Jul 15 05:16:06.180598 kernel: Key type .fscrypt registered Jul 15 05:16:06.180606 kernel: Key type fscrypt-provisioning registered Jul 15 05:16:06.180615 kernel: ima: No TPM chip found, activating TPM-bypass! Jul 15 05:16:06.180623 kernel: ima: Allocated hash algorithm: sha1 Jul 15 05:16:06.180631 kernel: ima: No architecture policies found Jul 15 05:16:06.180640 kernel: clk: Disabling unused clocks Jul 15 05:16:06.180648 kernel: Warning: unable to open an initial console. Jul 15 05:16:06.180659 kernel: Freeing unused kernel image (initmem) memory: 54608K Jul 15 05:16:06.180668 kernel: Write protecting the kernel read-only data: 24576k Jul 15 05:16:06.180676 kernel: Freeing unused kernel image (rodata/data gap) memory: 284K Jul 15 05:16:06.180685 kernel: Run /init as init process Jul 15 05:16:06.180693 kernel: with arguments: Jul 15 05:16:06.180702 kernel: /init Jul 15 05:16:06.180710 kernel: with environment: Jul 15 05:16:06.180718 kernel: HOME=/ Jul 15 05:16:06.180726 kernel: TERM=linux Jul 15 05:16:06.180737 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Jul 15 05:16:06.180747 systemd[1]: Successfully made /usr/ read-only. Jul 15 05:16:06.180758 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Jul 15 05:16:06.180768 systemd[1]: Detected virtualization microsoft. Jul 15 05:16:06.180776 systemd[1]: Detected architecture x86-64. Jul 15 05:16:06.180785 systemd[1]: Running in initrd. Jul 15 05:16:06.180794 systemd[1]: No hostname configured, using default hostname. Jul 15 05:16:06.180806 systemd[1]: Hostname set to . Jul 15 05:16:06.180815 systemd[1]: Initializing machine ID from random generator. Jul 15 05:16:06.180823 systemd[1]: Queued start job for default target initrd.target. Jul 15 05:16:06.180832 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 15 05:16:06.180841 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 15 05:16:06.180851 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Jul 15 05:16:06.180860 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jul 15 05:16:06.180869 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Jul 15 05:16:06.180881 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Jul 15 05:16:06.180891 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Jul 15 05:16:06.180900 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Jul 15 05:16:06.180910 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 15 05:16:06.180919 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jul 15 05:16:06.180927 systemd[1]: Reached target paths.target - Path Units. Jul 15 05:16:06.180936 systemd[1]: Reached target slices.target - Slice Units. Jul 15 05:16:06.180948 systemd[1]: Reached target swap.target - Swaps. Jul 15 05:16:06.180971 systemd[1]: Reached target timers.target - Timer Units. Jul 15 05:16:06.180980 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Jul 15 05:16:06.180989 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jul 15 05:16:06.180998 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Jul 15 05:16:06.181007 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Jul 15 05:16:06.181016 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jul 15 05:16:06.181025 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jul 15 05:16:06.181034 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jul 15 05:16:06.181046 systemd[1]: Reached target sockets.target - Socket Units. Jul 15 05:16:06.181055 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Jul 15 05:16:06.181064 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jul 15 05:16:06.181073 systemd[1]: Finished network-cleanup.service - Network Cleanup. Jul 15 05:16:06.181083 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). Jul 15 05:16:06.181092 systemd[1]: Starting systemd-fsck-usr.service... Jul 15 05:16:06.181100 systemd[1]: Starting systemd-journald.service - Journal Service... Jul 15 05:16:06.181109 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jul 15 05:16:06.181141 systemd-journald[232]: Collecting audit messages is disabled. Jul 15 05:16:06.181164 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 15 05:16:06.181174 systemd-journald[232]: Journal started Jul 15 05:16:06.181197 systemd-journald[232]: Runtime Journal (/run/log/journal/b06e3d84df4f4c558dae1c6cf4cd397b) is 8M, max 2.2G, 2.1G free. Jul 15 05:16:06.184843 systemd-modules-load[235]: Inserted module 'overlay' Jul 15 05:16:06.191789 systemd[1]: Started systemd-journald.service - Journal Service. Jul 15 05:16:06.192215 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Jul 15 05:16:06.192698 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jul 15 05:16:06.193411 systemd[1]: Finished systemd-fsck-usr.service. Jul 15 05:16:06.195561 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jul 15 05:16:06.196711 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jul 15 05:16:06.239764 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 15 05:16:06.241041 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jul 15 05:16:06.243160 systemd-tmpfiles[244]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Jul 15 05:16:06.249236 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jul 15 05:16:06.258994 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 15 05:16:06.265284 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jul 15 05:16:06.279255 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jul 15 05:16:06.285215 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Jul 15 05:16:06.290553 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 15 05:16:06.317973 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Jul 15 05:16:06.321318 systemd-modules-load[235]: Inserted module 'br_netfilter' Jul 15 05:16:06.324889 kernel: Bridge firewalling registered Jul 15 05:16:06.328075 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jul 15 05:16:06.333347 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jul 15 05:16:06.344528 dracut-cmdline[265]: 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=926b029026d98240a9e8b6527b65fc026ae523bea87c3b77ffd7237bcc7be4fb Jul 15 05:16:06.374211 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jul 15 05:16:06.375779 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jul 15 05:16:06.422061 systemd-resolved[294]: Positive Trust Anchors: Jul 15 05:16:06.422075 systemd-resolved[294]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 15 05:16:06.422106 systemd-resolved[294]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Jul 15 05:16:06.424836 systemd-resolved[294]: Defaulting to hostname 'linux'. Jul 15 05:16:06.425825 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jul 15 05:16:06.431024 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jul 15 05:16:06.520973 kernel: SCSI subsystem initialized Jul 15 05:16:06.531973 kernel: Loading iSCSI transport class v2.0-870. Jul 15 05:16:06.544974 kernel: iscsi: registered transport (tcp) Jul 15 05:16:06.567076 kernel: iscsi: registered transport (qla4xxx) Jul 15 05:16:06.567104 kernel: QLogic iSCSI HBA Driver Jul 15 05:16:06.586834 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jul 15 05:16:06.612226 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jul 15 05:16:06.613355 systemd[1]: Reached target network-pre.target - Preparation for Network. Jul 15 05:16:06.667181 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Jul 15 05:16:06.672998 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Jul 15 05:16:06.741978 kernel: raid6: avx2x4 gen() 29052 MB/s Jul 15 05:16:06.760972 kernel: raid6: avx2x2 gen() 30583 MB/s Jul 15 05:16:06.782415 kernel: raid6: avx2x1 gen() 26673 MB/s Jul 15 05:16:06.782448 kernel: raid6: using algorithm avx2x2 gen() 30583 MB/s Jul 15 05:16:06.805088 kernel: raid6: .... xor() 18213 MB/s, rmw enabled Jul 15 05:16:06.805116 kernel: raid6: using avx2x2 recovery algorithm Jul 15 05:16:06.827974 kernel: xor: automatically using best checksumming function avx Jul 15 05:16:06.995983 kernel: Btrfs loaded, zoned=no, fsverity=no Jul 15 05:16:07.003230 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Jul 15 05:16:07.009137 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 15 05:16:07.050592 systemd-udevd[484]: Using default interface naming scheme 'v255'. Jul 15 05:16:07.056647 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 15 05:16:07.067331 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Jul 15 05:16:07.097491 dracut-pre-trigger[494]: rd.md=0: removing MD RAID activation Jul 15 05:16:07.127041 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Jul 15 05:16:07.128290 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jul 15 05:16:07.204833 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jul 15 05:16:07.214388 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Jul 15 05:16:07.231746 kernel: cryptd: max_cpu_qlen set to 1000 Jul 15 05:16:07.241972 kernel: AES CTR mode by8 optimization enabled Jul 15 05:16:07.246978 kernel: hv_vmbus: Vmbus version:4.0 Jul 15 05:16:07.253166 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 15 05:16:07.253505 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jul 15 05:16:07.275123 kernel: pps_core: LinuxPPS API ver. 1 registered Jul 15 05:16:07.275182 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Jul 15 05:16:07.262762 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Jul 15 05:16:07.291326 kernel: hv_vmbus: registering driver hyperv_keyboard Jul 15 05:16:07.291345 kernel: hid: raw HID events driver (C) Jiri Kosina Jul 15 05:16:07.291359 kernel: input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/VMBUS:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input0 Jul 15 05:16:07.298238 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 15 05:16:07.298991 kernel: hv_vmbus: registering driver hv_netvsc Jul 15 05:16:07.307217 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jul 15 05:16:07.322711 kernel: PTP clock support registered Jul 15 05:16:07.322745 kernel: hv_vmbus: registering driver hv_pci Jul 15 05:16:07.322758 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Jul 15 05:16:07.323015 kernel: hv_vmbus: registering driver hv_storvsc Jul 15 05:16:07.323031 kernel: hv_vmbus: registering driver hid_hyperv Jul 15 05:16:07.327178 kernel: scsi host0: storvsc_host_t Jul 15 05:16:07.327224 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Jul 15 05:16:07.327379 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Jul 15 05:16:07.327533 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Jul 15 05:16:07.327667 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Jul 15 05:16:07.328123 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint Jul 15 05:16:07.333537 kernel: scsi host1: storvsc_host_t Jul 15 05:16:07.335716 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Jul 15 05:16:07.335746 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] Jul 15 05:16:07.335774 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] Jul 15 05:16:07.335793 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] Jul 15 05:16:07.338216 kernel: pci 0001:00:00.0: Enabling HDA controller Jul 15 05:16:07.338075 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 15 05:16:07.471349 kernel: scsi 1:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Jul 15 05:16:07.471555 kernel: scsi 1:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Jul 15 05:16:07.471708 kernel: scsi 1:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Jul 15 05:16:07.471881 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Jul 15 05:16:07.472013 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Jul 15 05:16:07.472148 kernel: hv_utils: Registering HyperV Utility Driver Jul 15 05:16:07.472166 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned Jul 15 05:16:07.472320 kernel: hv_vmbus: registering driver hv_utils Jul 15 05:16:07.472334 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned Jul 15 05:16:07.472465 kernel: hv_utils: Heartbeat IC version 3.0 Jul 15 05:16:07.472479 kernel: hv_utils: Shutdown IC version 3.2 Jul 15 05:16:07.472491 kernel: hv_utils: TimeSync IC version 4.0 Jul 15 05:16:07.472502 kernel: sd 1:0:0:0: [sdb] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Jul 15 05:16:07.472635 kernel: sd 1:0:0:1: [sda] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Jul 15 05:16:07.472773 kernel: sd 1:0:0:1: [sda] 4096-byte physical blocks Jul 15 05:16:07.472925 kernel: sd 1:0:0:1: [sda] Write Protect is off Jul 15 05:16:07.473058 kernel: sd 1:0:0:1: [sda] Mode Sense: 0f 00 10 00 Jul 15 05:16:07.473191 kernel: sd 1:0:0:1: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Jul 15 05:16:07.473329 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#325 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 15 05:16:07.473458 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#332 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 15 05:16:07.473582 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned Jul 15 05:16:07.473718 kernel: sd 1:0:0:0: [sdb] 4096-byte physical blocks Jul 15 05:16:07.473864 kernel: sd 1:0:0:0: [sdb] Write Protect is off Jul 15 05:16:07.473999 kernel: sd 1:0:0:0: [sdb] Mode Sense: 0f 00 10 00 Jul 15 05:16:07.474132 kernel: sd 1:0:0:0: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Jul 15 05:16:07.474322 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#450 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 15 05:16:07.474448 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#310 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 15 05:16:07.338201 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jul 15 05:16:07.394266 systemd-resolved[294]: Clock change detected. Flushing caches. Jul 15 05:16:07.487217 kernel: sda: sda1 Jul 15 05:16:07.487237 kernel: sd 1:0:0:1: [sda] Attached SCSI disk Jul 15 05:16:07.450550 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 15 05:16:07.500049 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Jul 15 05:16:07.500075 kernel: sd 1:0:0:0: [sdb] Attached SCSI disk Jul 15 05:16:07.508670 kernel: sr 1:0:0:2: [sr0] scsi-1 drive Jul 15 05:16:07.508882 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Jul 15 05:16:07.510929 kernel: sr 1:0:0:2: Attached scsi CD-ROM sr0 Jul 15 05:16:07.514412 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 15 05:16:08.286731 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Jul 15 05:16:08.311272 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Jul 15 05:16:08.340552 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Jul 15 05:16:08.340679 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Jul 15 05:16:08.353122 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Jul 15 05:16:08.380641 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Jul 15 05:16:08.401853 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#296 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 15 05:16:08.418848 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Jul 15 05:16:08.427851 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#479 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 15 05:16:08.440856 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Jul 15 05:16:09.094360 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Jul 15 05:16:09.094984 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Jul 15 05:16:09.103025 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 15 05:16:09.112955 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jul 15 05:16:09.128022 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Jul 15 05:16:09.167051 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Jul 15 05:16:09.449053 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#305 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 15 05:16:09.460376 disk-uuid[722]: The operation has completed successfully. Jul 15 05:16:09.464339 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Jul 15 05:16:09.514218 systemd[1]: disk-uuid.service: Deactivated successfully. Jul 15 05:16:09.514326 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Jul 15 05:16:09.565208 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Jul 15 05:16:09.597386 sh[895]: Success Jul 15 05:16:09.655452 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Jul 15 05:16:09.655490 kernel: device-mapper: uevent: version 1.0.3 Jul 15 05:16:09.661850 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Jul 15 05:16:09.672860 kernel: device-mapper: verity: sha256 using shash "sha256-avx2" Jul 15 05:16:09.870081 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Jul 15 05:16:09.872080 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Jul 15 05:16:09.893148 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Jul 15 05:16:09.913308 kernel: BTRFS info: 'norecovery' is for compatibility only, recommended to use 'rescue=nologreplay' Jul 15 05:16:09.913333 kernel: BTRFS: device fsid eb96c768-dac4-4ca9-ae1d-82815d4ce00b devid 1 transid 36 /dev/mapper/usr (254:0) scanned by mount (913) Jul 15 05:16:09.922880 kernel: BTRFS info (device dm-0): first mount of filesystem eb96c768-dac4-4ca9-ae1d-82815d4ce00b Jul 15 05:16:09.922909 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Jul 15 05:16:09.926114 kernel: BTRFS info (device dm-0): using free-space-tree Jul 15 05:16:10.400734 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Jul 15 05:16:10.402181 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Jul 15 05:16:10.407737 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Jul 15 05:16:10.408550 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Jul 15 05:16:10.441081 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Jul 15 05:16:10.473700 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sdb6 (8:22) scanned by mount (944) Jul 15 05:16:10.473746 kernel: BTRFS info (device sdb6): first mount of filesystem 86e7a055-b4ff-48a6-9a0a-c301ff74862f Jul 15 05:16:10.477897 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Jul 15 05:16:10.481245 kernel: BTRFS info (device sdb6): using free-space-tree Jul 15 05:16:10.502852 kernel: BTRFS info (device sdb6): last unmount of filesystem 86e7a055-b4ff-48a6-9a0a-c301ff74862f Jul 15 05:16:10.503589 systemd[1]: Finished ignition-setup.service - Ignition (setup). Jul 15 05:16:10.504661 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Jul 15 05:16:10.555266 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jul 15 05:16:10.567485 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jul 15 05:16:10.619938 systemd-networkd[1082]: lo: Link UP Jul 15 05:16:10.619948 systemd-networkd[1082]: lo: Gained carrier Jul 15 05:16:10.620964 systemd-networkd[1082]: Enumeration completed Jul 15 05:16:10.621054 systemd[1]: Started systemd-networkd.service - Network Configuration. Jul 15 05:16:10.621324 systemd-networkd[1082]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 15 05:16:10.621328 systemd-networkd[1082]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 15 05:16:10.622138 systemd-networkd[1082]: eth0: Link UP Jul 15 05:16:10.622300 systemd-networkd[1082]: eth0: Gained carrier Jul 15 05:16:10.622309 systemd-networkd[1082]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 15 05:16:10.622944 systemd[1]: Reached target network.target - Network. Jul 15 05:16:10.679865 systemd-networkd[1082]: eth0: DHCPv4 address 10.200.8.12/24, gateway 10.200.8.1 acquired from 168.63.129.16 Jul 15 05:16:12.034953 systemd-networkd[1082]: eth0: Gained IPv6LL Jul 15 05:16:12.129318 ignition[1036]: Ignition 2.21.0 Jul 15 05:16:12.129333 ignition[1036]: Stage: fetch-offline Jul 15 05:16:12.129437 ignition[1036]: no configs at "/usr/lib/ignition/base.d" Jul 15 05:16:12.129447 ignition[1036]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 15 05:16:12.129544 ignition[1036]: parsed url from cmdline: "" Jul 15 05:16:12.129551 ignition[1036]: no config URL provided Jul 15 05:16:12.129557 ignition[1036]: reading system config file "/usr/lib/ignition/user.ign" Jul 15 05:16:12.144965 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Jul 15 05:16:12.129566 ignition[1036]: no config at "/usr/lib/ignition/user.ign" Jul 15 05:16:12.155499 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Jul 15 05:16:12.129572 ignition[1036]: failed to fetch config: resource requires networking Jul 15 05:16:12.138791 ignition[1036]: Ignition finished successfully Jul 15 05:16:12.205925 ignition[1095]: Ignition 2.21.0 Jul 15 05:16:12.205937 ignition[1095]: Stage: fetch Jul 15 05:16:12.206378 ignition[1095]: no configs at "/usr/lib/ignition/base.d" Jul 15 05:16:12.206394 ignition[1095]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 15 05:16:12.206533 ignition[1095]: parsed url from cmdline: "" Jul 15 05:16:12.206539 ignition[1095]: no config URL provided Jul 15 05:16:12.206549 ignition[1095]: reading system config file "/usr/lib/ignition/user.ign" Jul 15 05:16:12.206559 ignition[1095]: no config at "/usr/lib/ignition/user.ign" Jul 15 05:16:12.206596 ignition[1095]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Jul 15 05:16:12.310600 ignition[1095]: GET result: OK Jul 15 05:16:12.310647 ignition[1095]: config has been read from IMDS userdata Jul 15 05:16:12.310662 ignition[1095]: parsing config with SHA512: 988103deb30238f101eaf0d7495bccc4feb622f760f1ce4100781eed0b8448fa67b948d51322fb6f74bdfd4cf2f0d7212dcacd5e281011c316511908e3d3a8bb Jul 15 05:16:12.314134 unknown[1095]: fetched base config from "system" Jul 15 05:16:12.314344 ignition[1095]: fetch: fetch complete Jul 15 05:16:12.314142 unknown[1095]: fetched base config from "system" Jul 15 05:16:12.314349 ignition[1095]: fetch: fetch passed Jul 15 05:16:12.314147 unknown[1095]: fetched user config from "azure" Jul 15 05:16:12.314405 ignition[1095]: Ignition finished successfully Jul 15 05:16:12.324756 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Jul 15 05:16:12.327893 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Jul 15 05:16:12.368777 ignition[1104]: Ignition 2.21.0 Jul 15 05:16:12.368791 ignition[1104]: Stage: kargs Jul 15 05:16:12.369044 ignition[1104]: no configs at "/usr/lib/ignition/base.d" Jul 15 05:16:12.369056 ignition[1104]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 15 05:16:12.369705 ignition[1104]: kargs: kargs passed Jul 15 05:16:12.369784 ignition[1104]: Ignition finished successfully Jul 15 05:16:12.384950 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Jul 15 05:16:12.395549 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Jul 15 05:16:12.438648 ignition[1114]: Ignition 2.21.0 Jul 15 05:16:12.438661 ignition[1114]: Stage: disks Jul 15 05:16:12.438911 ignition[1114]: no configs at "/usr/lib/ignition/base.d" Jul 15 05:16:12.438923 ignition[1114]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 15 05:16:12.440539 ignition[1114]: disks: disks passed Jul 15 05:16:12.440641 ignition[1114]: Ignition finished successfully Jul 15 05:16:12.452074 systemd[1]: Finished ignition-disks.service - Ignition (disks). Jul 15 05:16:12.454321 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Jul 15 05:16:12.461084 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Jul 15 05:16:12.474345 systemd[1]: Reached target local-fs.target - Local File Systems. Jul 15 05:16:12.482420 systemd[1]: Reached target sysinit.target - System Initialization. Jul 15 05:16:12.491107 systemd[1]: Reached target basic.target - Basic System. Jul 15 05:16:12.499319 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Jul 15 05:16:12.640052 systemd-fsck[1126]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks Jul 15 05:16:12.645508 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Jul 15 05:16:12.657080 systemd[1]: Mounting sysroot.mount - /sysroot... Jul 15 05:16:13.054855 kernel: EXT4-fs (sdb9): mounted filesystem 277c3938-5262-4ab1-8fa3-62fde82f8257 r/w with ordered data mode. Quota mode: none. Jul 15 05:16:13.056344 systemd[1]: Mounted sysroot.mount - /sysroot. Jul 15 05:16:13.065360 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Jul 15 05:16:13.078248 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jul 15 05:16:13.079118 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Jul 15 05:16:13.108405 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Jul 15 05:16:13.122608 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sdb6 (8:22) scanned by mount (1140) Jul 15 05:16:13.108553 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Jul 15 05:16:13.146556 kernel: BTRFS info (device sdb6): first mount of filesystem 86e7a055-b4ff-48a6-9a0a-c301ff74862f Jul 15 05:16:13.146576 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Jul 15 05:16:13.146589 kernel: BTRFS info (device sdb6): using free-space-tree Jul 15 05:16:13.108585 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Jul 15 05:16:13.139054 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Jul 15 05:16:13.151183 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Jul 15 05:16:13.168808 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jul 15 05:16:13.663799 coreos-metadata[1142]: Jul 15 05:16:13.663 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Jul 15 05:16:13.670053 coreos-metadata[1142]: Jul 15 05:16:13.666 INFO Fetch successful Jul 15 05:16:13.670053 coreos-metadata[1142]: Jul 15 05:16:13.666 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Jul 15 05:16:13.682138 coreos-metadata[1142]: Jul 15 05:16:13.673 INFO Fetch successful Jul 15 05:16:13.685984 coreos-metadata[1142]: Jul 15 05:16:13.684 INFO wrote hostname ci-4396.0.0-n-cc6edc876b to /sysroot/etc/hostname Jul 15 05:16:13.693150 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Jul 15 05:16:14.238489 initrd-setup-root[1170]: cut: /sysroot/etc/passwd: No such file or directory Jul 15 05:16:14.295226 initrd-setup-root[1177]: cut: /sysroot/etc/group: No such file or directory Jul 15 05:16:14.302736 initrd-setup-root[1184]: cut: /sysroot/etc/shadow: No such file or directory Jul 15 05:16:14.323441 initrd-setup-root[1191]: cut: /sysroot/etc/gshadow: No such file or directory Jul 15 05:16:15.471738 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Jul 15 05:16:15.477857 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Jul 15 05:16:15.485502 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Jul 15 05:16:15.508868 systemd[1]: sysroot-oem.mount: Deactivated successfully. Jul 15 05:16:15.517718 kernel: BTRFS info (device sdb6): last unmount of filesystem 86e7a055-b4ff-48a6-9a0a-c301ff74862f Jul 15 05:16:15.527657 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Jul 15 05:16:15.541638 ignition[1260]: INFO : Ignition 2.21.0 Jul 15 05:16:15.541638 ignition[1260]: INFO : Stage: mount Jul 15 05:16:15.547505 ignition[1260]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 15 05:16:15.547505 ignition[1260]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 15 05:16:15.547505 ignition[1260]: INFO : mount: mount passed Jul 15 05:16:15.547505 ignition[1260]: INFO : Ignition finished successfully Jul 15 05:16:15.548670 systemd[1]: Finished ignition-mount.service - Ignition (mount). Jul 15 05:16:15.552574 systemd[1]: Starting ignition-files.service - Ignition (files)... Jul 15 05:16:15.592430 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jul 15 05:16:15.617608 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sdb6 (8:22) scanned by mount (1274) Jul 15 05:16:15.617644 kernel: BTRFS info (device sdb6): first mount of filesystem 86e7a055-b4ff-48a6-9a0a-c301ff74862f Jul 15 05:16:15.621749 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Jul 15 05:16:15.624879 kernel: BTRFS info (device sdb6): using free-space-tree Jul 15 05:16:15.629581 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jul 15 05:16:15.664083 ignition[1291]: INFO : Ignition 2.21.0 Jul 15 05:16:15.664083 ignition[1291]: INFO : Stage: files Jul 15 05:16:15.669924 ignition[1291]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 15 05:16:15.669924 ignition[1291]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 15 05:16:15.669924 ignition[1291]: DEBUG : files: compiled without relabeling support, skipping Jul 15 05:16:15.732689 ignition[1291]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Jul 15 05:16:15.732689 ignition[1291]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Jul 15 05:16:15.879193 ignition[1291]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Jul 15 05:16:15.885094 ignition[1291]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Jul 15 05:16:15.892382 unknown[1291]: wrote ssh authorized keys file for user: core Jul 15 05:16:15.896851 ignition[1291]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Jul 15 05:16:15.902307 ignition[1291]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Jul 15 05:16:15.902307 ignition[1291]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Jul 15 05:16:15.902307 ignition[1291]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Jul 15 05:16:15.902307 ignition[1291]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Jul 15 05:16:15.902307 ignition[1291]: INFO : files: files passed Jul 15 05:16:15.902307 ignition[1291]: INFO : Ignition finished successfully Jul 15 05:16:15.911600 systemd[1]: Finished ignition-files.service - Ignition (files). Jul 15 05:16:15.918278 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Jul 15 05:16:15.950063 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Jul 15 05:16:15.954612 systemd[1]: ignition-quench.service: Deactivated successfully. Jul 15 05:16:15.954702 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Jul 15 05:16:15.983511 initrd-setup-root-after-ignition[1324]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jul 15 05:16:15.983511 initrd-setup-root-after-ignition[1324]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Jul 15 05:16:16.001111 initrd-setup-root-after-ignition[1328]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jul 15 05:16:15.985408 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Jul 15 05:16:15.994994 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Jul 15 05:16:16.006406 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Jul 15 05:16:16.060586 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Jul 15 05:16:16.060690 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Jul 15 05:16:16.060950 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Jul 15 05:16:16.069343 systemd[1]: Reached target initrd.target - Initrd Default Target. Jul 15 05:16:16.078490 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Jul 15 05:16:16.079218 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Jul 15 05:16:16.129869 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jul 15 05:16:16.131321 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Jul 15 05:16:16.167403 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Jul 15 05:16:16.167607 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 15 05:16:16.184936 systemd[1]: Stopped target timers.target - Timer Units. Jul 15 05:16:16.189959 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Jul 15 05:16:16.190079 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jul 15 05:16:16.205261 systemd[1]: Stopped target initrd.target - Initrd Default Target. Jul 15 05:16:16.209822 systemd[1]: Stopped target basic.target - Basic System. Jul 15 05:16:16.218430 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Jul 15 05:16:16.225552 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Jul 15 05:16:16.238627 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Jul 15 05:16:16.243164 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Jul 15 05:16:16.251669 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Jul 15 05:16:16.260362 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Jul 15 05:16:16.268228 systemd[1]: Stopped target sysinit.target - System Initialization. Jul 15 05:16:16.277842 systemd[1]: Stopped target local-fs.target - Local File Systems. Jul 15 05:16:16.285704 systemd[1]: Stopped target swap.target - Swaps. Jul 15 05:16:16.293566 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Jul 15 05:16:16.293686 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Jul 15 05:16:16.307741 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Jul 15 05:16:16.312938 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 15 05:16:16.321268 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Jul 15 05:16:16.331000 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 15 05:16:16.339733 systemd[1]: dracut-initqueue.service: Deactivated successfully. Jul 15 05:16:16.339877 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Jul 15 05:16:16.353094 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Jul 15 05:16:16.353221 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Jul 15 05:16:16.357271 systemd[1]: ignition-files.service: Deactivated successfully. Jul 15 05:16:16.357393 systemd[1]: Stopped ignition-files.service - Ignition (files). Jul 15 05:16:16.366541 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Jul 15 05:16:16.366655 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Jul 15 05:16:16.376293 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Jul 15 05:16:16.383772 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Jul 15 05:16:16.383916 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Jul 15 05:16:16.414439 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Jul 15 05:16:16.417854 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Jul 15 05:16:16.417986 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Jul 15 05:16:16.442545 ignition[1349]: INFO : Ignition 2.21.0 Jul 15 05:16:16.442545 ignition[1349]: INFO : Stage: umount Jul 15 05:16:16.442545 ignition[1349]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 15 05:16:16.442545 ignition[1349]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 15 05:16:16.442545 ignition[1349]: INFO : umount: umount passed Jul 15 05:16:16.442545 ignition[1349]: INFO : Ignition finished successfully Jul 15 05:16:16.429688 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Jul 15 05:16:16.429811 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Jul 15 05:16:16.445580 systemd[1]: sysroot-boot.mount: Deactivated successfully. Jul 15 05:16:16.446494 systemd[1]: ignition-mount.service: Deactivated successfully. Jul 15 05:16:16.446608 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Jul 15 05:16:16.450031 systemd[1]: ignition-disks.service: Deactivated successfully. Jul 15 05:16:16.450187 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Jul 15 05:16:16.456602 systemd[1]: ignition-kargs.service: Deactivated successfully. Jul 15 05:16:16.456707 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Jul 15 05:16:16.468995 systemd[1]: ignition-fetch.service: Deactivated successfully. Jul 15 05:16:16.469043 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Jul 15 05:16:16.472102 systemd[1]: Stopped target network.target - Network. Jul 15 05:16:16.483791 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Jul 15 05:16:16.483859 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Jul 15 05:16:16.493684 systemd[1]: Stopped target paths.target - Path Units. Jul 15 05:16:16.498100 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Jul 15 05:16:16.501874 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 15 05:16:16.505001 systemd[1]: Stopped target slices.target - Slice Units. Jul 15 05:16:16.513858 systemd[1]: Stopped target sockets.target - Socket Units. Jul 15 05:16:16.521042 systemd[1]: iscsid.socket: Deactivated successfully. Jul 15 05:16:16.521087 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Jul 15 05:16:16.528366 systemd[1]: iscsiuio.socket: Deactivated successfully. Jul 15 05:16:16.528405 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jul 15 05:16:16.536889 systemd[1]: ignition-setup.service: Deactivated successfully. Jul 15 05:16:16.536942 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Jul 15 05:16:16.544640 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Jul 15 05:16:16.544685 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Jul 15 05:16:16.552412 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Jul 15 05:16:16.560208 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Jul 15 05:16:16.568164 systemd[1]: systemd-resolved.service: Deactivated successfully. Jul 15 05:16:16.568276 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Jul 15 05:16:16.585022 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Jul 15 05:16:16.585430 systemd[1]: initrd-cleanup.service: Deactivated successfully. Jul 15 05:16:16.585526 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Jul 15 05:16:16.588070 systemd[1]: systemd-networkd.service: Deactivated successfully. Jul 15 05:16:16.588192 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Jul 15 05:16:16.601101 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Jul 15 05:16:16.601360 systemd[1]: sysroot-boot.service: Deactivated successfully. Jul 15 05:16:16.601456 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Jul 15 05:16:16.610877 systemd[1]: Stopped target network-pre.target - Preparation for Network. Jul 15 05:16:16.614161 systemd[1]: systemd-networkd.socket: Deactivated successfully. Jul 15 05:16:16.614257 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Jul 15 05:16:16.621736 systemd[1]: initrd-setup-root.service: Deactivated successfully. Jul 15 05:16:16.621797 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Jul 15 05:16:16.637195 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Jul 15 05:16:16.640802 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Jul 15 05:16:16.640867 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jul 15 05:16:16.652777 systemd[1]: systemd-sysctl.service: Deactivated successfully. Jul 15 05:16:16.652823 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Jul 15 05:16:16.664827 systemd[1]: systemd-modules-load.service: Deactivated successfully. Jul 15 05:16:16.664893 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Jul 15 05:16:16.668624 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Jul 15 05:16:16.668673 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 15 05:16:16.681922 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 15 05:16:16.695614 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Jul 15 05:16:16.695679 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Jul 15 05:16:16.717968 systemd[1]: network-cleanup.service: Deactivated successfully. Jul 15 05:16:16.718104 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Jul 15 05:16:16.733513 systemd[1]: systemd-udevd.service: Deactivated successfully. Jul 15 05:16:16.733669 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 15 05:16:16.737354 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Jul 15 05:16:16.737397 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Jul 15 05:16:16.749609 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Jul 15 05:16:16.749646 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Jul 15 05:16:16.753164 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Jul 15 05:16:16.753213 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Jul 15 05:16:16.769286 systemd[1]: dracut-cmdline.service: Deactivated successfully. Jul 15 05:16:16.769334 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Jul 15 05:16:16.781537 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jul 15 05:16:16.781582 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jul 15 05:16:16.798519 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Jul 15 05:16:16.803654 systemd[1]: systemd-network-generator.service: Deactivated successfully. Jul 15 05:16:16.803706 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Jul 15 05:16:16.820420 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Jul 15 05:16:16.820473 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 15 05:16:16.833549 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 15 05:16:16.833599 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jul 15 05:16:16.848257 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Jul 15 05:16:16.848313 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Jul 15 05:16:16.848354 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jul 15 05:16:16.863705 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Jul 15 05:16:16.863798 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Jul 15 05:16:16.866234 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Jul 15 05:16:16.879487 systemd[1]: Starting initrd-switch-root.service - Switch Root... Jul 15 05:16:16.915375 systemd[1]: Switching root. Jul 15 05:16:17.030081 systemd-journald[232]: Journal stopped Jul 15 05:16:22.619400 systemd-journald[232]: Received SIGTERM from PID 1 (systemd). Jul 15 05:16:22.619433 kernel: SELinux: policy capability network_peer_controls=1 Jul 15 05:16:22.619445 kernel: SELinux: policy capability open_perms=1 Jul 15 05:16:22.619455 kernel: SELinux: policy capability extended_socket_class=1 Jul 15 05:16:22.619463 kernel: SELinux: policy capability always_check_network=0 Jul 15 05:16:22.619472 kernel: SELinux: policy capability cgroup_seclabel=1 Jul 15 05:16:22.619482 kernel: SELinux: policy capability nnp_nosuid_transition=1 Jul 15 05:16:22.619495 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Jul 15 05:16:22.619504 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Jul 15 05:16:22.619513 kernel: SELinux: policy capability userspace_initial_context=0 Jul 15 05:16:22.619522 kernel: audit: type=1403 audit(1752556577.908:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Jul 15 05:16:22.619532 systemd[1]: Successfully loaded SELinux policy in 154.291ms. Jul 15 05:16:22.619543 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 7.764ms. Jul 15 05:16:22.619554 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Jul 15 05:16:22.619568 systemd[1]: Detected virtualization microsoft. Jul 15 05:16:22.619581 systemd[1]: Detected architecture x86-64. Jul 15 05:16:22.619592 systemd[1]: Detected first boot. Jul 15 05:16:22.619607 systemd[1]: Hostname set to . Jul 15 05:16:22.619617 systemd[1]: Initializing machine ID from random generator. Jul 15 05:16:22.619630 zram_generator::config[1393]: No configuration found. Jul 15 05:16:22.619641 kernel: Guest personality initialized and is inactive Jul 15 05:16:22.619651 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Jul 15 05:16:22.619660 kernel: Initialized host personality Jul 15 05:16:22.619670 kernel: NET: Registered PF_VSOCK protocol family Jul 15 05:16:22.619680 systemd[1]: Populated /etc with preset unit settings. Jul 15 05:16:22.619691 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Jul 15 05:16:22.619704 systemd[1]: initrd-switch-root.service: Deactivated successfully. Jul 15 05:16:22.619714 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Jul 15 05:16:22.619725 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Jul 15 05:16:22.619735 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Jul 15 05:16:22.619746 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Jul 15 05:16:22.619756 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Jul 15 05:16:22.619767 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Jul 15 05:16:22.619780 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Jul 15 05:16:22.619790 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Jul 15 05:16:22.619801 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Jul 15 05:16:22.619811 systemd[1]: Created slice user.slice - User and Session Slice. Jul 15 05:16:22.619822 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 15 05:16:22.619845 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 15 05:16:22.619857 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Jul 15 05:16:22.619872 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Jul 15 05:16:22.619883 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Jul 15 05:16:22.619897 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jul 15 05:16:22.619908 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Jul 15 05:16:22.619918 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 15 05:16:22.619929 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jul 15 05:16:22.619940 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Jul 15 05:16:22.619951 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Jul 15 05:16:22.619961 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Jul 15 05:16:22.619975 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Jul 15 05:16:22.619986 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 15 05:16:22.619996 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jul 15 05:16:22.620007 systemd[1]: Reached target slices.target - Slice Units. Jul 15 05:16:22.620018 systemd[1]: Reached target swap.target - Swaps. Jul 15 05:16:22.620029 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Jul 15 05:16:22.620039 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Jul 15 05:16:22.620431 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Jul 15 05:16:22.620452 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jul 15 05:16:22.620463 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jul 15 05:16:22.620479 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jul 15 05:16:22.620490 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Jul 15 05:16:22.620501 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Jul 15 05:16:22.620517 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Jul 15 05:16:22.620529 systemd[1]: Mounting media.mount - External Media Directory... Jul 15 05:16:22.620540 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 15 05:16:22.620551 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Jul 15 05:16:22.620562 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Jul 15 05:16:22.620573 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Jul 15 05:16:22.620585 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Jul 15 05:16:22.620596 systemd[1]: Reached target machines.target - Containers. Jul 15 05:16:22.620610 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Jul 15 05:16:22.620621 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 15 05:16:22.620632 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jul 15 05:16:22.620644 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Jul 15 05:16:22.620654 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 15 05:16:22.620665 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jul 15 05:16:22.620676 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 15 05:16:22.620687 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Jul 15 05:16:22.620700 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 15 05:16:22.620712 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Jul 15 05:16:22.620726 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Jul 15 05:16:22.620737 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Jul 15 05:16:22.620747 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Jul 15 05:16:22.620758 systemd[1]: Stopped systemd-fsck-usr.service. Jul 15 05:16:22.620770 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Jul 15 05:16:22.620781 systemd[1]: Starting systemd-journald.service - Journal Service... Jul 15 05:16:22.620792 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jul 15 05:16:22.620805 kernel: fuse: init (API version 7.41) Jul 15 05:16:22.620817 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jul 15 05:16:22.620827 kernel: loop: module loaded Jul 15 05:16:22.620853 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Jul 15 05:16:22.620864 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Jul 15 05:16:22.620875 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jul 15 05:16:22.620886 systemd[1]: verity-setup.service: Deactivated successfully. Jul 15 05:16:22.620897 systemd[1]: Stopped verity-setup.service. Jul 15 05:16:22.620912 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 15 05:16:22.620951 systemd-journald[1476]: Collecting audit messages is disabled. Jul 15 05:16:22.620976 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Jul 15 05:16:22.620989 systemd-journald[1476]: Journal started Jul 15 05:16:22.621014 systemd-journald[1476]: Runtime Journal (/run/log/journal/4521e6cc4a0545b7822a9b81dae09e8e) is 8M, max 2.2G, 2.1G free. Jul 15 05:16:21.783546 systemd[1]: Queued start job for default target multi-user.target. Jul 15 05:16:21.795263 systemd[1]: Unnecessary job was removed for dev-sdb6.device - /dev/sdb6. Jul 15 05:16:21.795759 systemd[1]: systemd-journald.service: Deactivated successfully. Jul 15 05:16:22.628874 systemd[1]: Started systemd-journald.service - Journal Service. Jul 15 05:16:22.633283 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Jul 15 05:16:22.637635 systemd[1]: Mounted media.mount - External Media Directory. Jul 15 05:16:22.641629 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Jul 15 05:16:22.646069 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Jul 15 05:16:22.650433 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Jul 15 05:16:22.654535 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Jul 15 05:16:22.659391 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jul 15 05:16:22.664495 systemd[1]: modprobe@configfs.service: Deactivated successfully. Jul 15 05:16:22.664682 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Jul 15 05:16:22.669605 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 15 05:16:22.669795 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 15 05:16:22.674495 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 15 05:16:22.674692 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 15 05:16:22.679669 systemd[1]: modprobe@fuse.service: Deactivated successfully. Jul 15 05:16:22.679871 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Jul 15 05:16:22.684712 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 15 05:16:22.684926 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 15 05:16:22.689442 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jul 15 05:16:22.694066 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jul 15 05:16:22.699424 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Jul 15 05:16:22.712346 systemd[1]: Reached target network-pre.target - Preparation for Network. Jul 15 05:16:22.717978 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Jul 15 05:16:22.738045 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Jul 15 05:16:22.742614 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Jul 15 05:16:22.742651 systemd[1]: Reached target local-fs.target - Local File Systems. Jul 15 05:16:22.748048 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Jul 15 05:16:22.754394 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Jul 15 05:16:22.758612 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 15 05:16:22.760386 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Jul 15 05:16:22.765606 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Jul 15 05:16:22.770292 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 15 05:16:22.771349 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Jul 15 05:16:22.775681 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jul 15 05:16:22.776628 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jul 15 05:16:22.781705 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Jul 15 05:16:22.786276 systemd-journald[1476]: Time spent on flushing to /var/log/journal/4521e6cc4a0545b7822a9b81dae09e8e is 14.318ms for 943 entries. Jul 15 05:16:22.786276 systemd-journald[1476]: System Journal (/var/log/journal/4521e6cc4a0545b7822a9b81dae09e8e) is 8M, max 2.6G, 2.6G free. Jul 15 05:16:22.836569 systemd-journald[1476]: Received client request to flush runtime journal. Jul 15 05:16:22.790993 systemd[1]: Starting systemd-sysusers.service - Create System Users... Jul 15 05:16:22.797785 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Jul 15 05:16:22.803841 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Jul 15 05:16:22.808804 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Jul 15 05:16:22.814159 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Jul 15 05:16:22.821013 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Jul 15 05:16:22.827385 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Jul 15 05:16:22.840162 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Jul 15 05:16:22.862848 kernel: ACPI: bus type drm_connector registered Jul 15 05:16:22.863391 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 15 05:16:22.863595 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jul 15 05:16:22.868299 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jul 15 05:16:22.885848 kernel: loop0: detected capacity change from 0 to 146488 Jul 15 05:16:22.890670 systemd[1]: Finished systemd-sysusers.service - Create System Users. Jul 15 05:16:22.896352 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jul 15 05:16:22.941022 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Jul 15 05:16:22.941615 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Jul 15 05:16:22.957764 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jul 15 05:16:22.972603 systemd-tmpfiles[1548]: ACLs are not supported, ignoring. Jul 15 05:16:22.972623 systemd-tmpfiles[1548]: ACLs are not supported, ignoring. Jul 15 05:16:22.976185 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 15 05:16:23.500866 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jul 15 05:16:23.552869 kernel: loop1: detected capacity change from 0 to 28624 Jul 15 05:16:24.132867 kernel: loop2: detected capacity change from 0 to 114000 Jul 15 05:16:24.195472 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Jul 15 05:16:24.201998 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 15 05:16:24.250618 systemd-udevd[1557]: Using default interface naming scheme 'v255'. Jul 15 05:16:24.709858 kernel: loop3: detected capacity change from 0 to 146488 Jul 15 05:16:24.723850 kernel: loop4: detected capacity change from 0 to 28624 Jul 15 05:16:24.760855 kernel: loop5: detected capacity change from 0 to 114000 Jul 15 05:16:24.767035 (sd-merge)[1559]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Jul 15 05:16:24.767496 (sd-merge)[1559]: Merged extensions into '/usr'. Jul 15 05:16:24.770734 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Jul 15 05:16:24.777693 systemd[1]: Starting ensure-sysext.service... Jul 15 05:16:24.781560 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jul 15 05:16:24.815486 systemd-tmpfiles[1562]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Jul 15 05:16:24.815524 systemd-tmpfiles[1562]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Jul 15 05:16:24.815800 systemd-tmpfiles[1562]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Jul 15 05:16:24.816103 systemd-tmpfiles[1562]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Jul 15 05:16:24.817012 systemd-tmpfiles[1562]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Jul 15 05:16:24.817301 systemd-tmpfiles[1562]: ACLs are not supported, ignoring. Jul 15 05:16:24.817367 systemd-tmpfiles[1562]: ACLs are not supported, ignoring. Jul 15 05:16:24.832726 systemd[1]: Reload requested from client PID 1561 ('systemctl') (unit ensure-sysext.service)... Jul 15 05:16:24.832741 systemd[1]: Reloading... Jul 15 05:16:24.840944 systemd-tmpfiles[1562]: Detected autofs mount point /boot during canonicalization of boot. Jul 15 05:16:24.840957 systemd-tmpfiles[1562]: Skipping /boot Jul 15 05:16:24.850439 systemd-tmpfiles[1562]: Detected autofs mount point /boot during canonicalization of boot. Jul 15 05:16:24.850453 systemd-tmpfiles[1562]: Skipping /boot Jul 15 05:16:24.894868 zram_generator::config[1612]: No configuration found. Jul 15 05:16:24.955863 kernel: hv_vmbus: registering driver hyperv_fb Jul 15 05:16:24.960858 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Jul 15 05:16:24.965858 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Jul 15 05:16:24.970146 kernel: Console: switching to colour dummy device 80x25 Jul 15 05:16:24.978880 kernel: mousedev: PS/2 mouse device common for all mice Jul 15 05:16:24.988066 kernel: Console: switching to colour frame buffer device 128x48 Jul 15 05:16:25.000727 kernel: hv_vmbus: registering driver hv_balloon Jul 15 05:16:25.000782 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Jul 15 05:16:25.028855 kernel: EDAC MC: Ver: 3.0.0 Jul 15 05:16:25.041259 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Jul 15 05:16:25.171180 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Jul 15 05:16:25.171484 systemd[1]: Reloading finished in 338 ms. Jul 15 05:16:25.196109 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 15 05:16:25.224911 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 15 05:16:25.260053 systemd[1]: Finished ensure-sysext.service. Jul 15 05:16:25.299040 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Jul 15 05:16:25.304196 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 15 05:16:25.305290 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jul 15 05:16:25.328617 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Jul 15 05:16:25.333432 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 15 05:16:25.334366 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 15 05:16:25.339431 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jul 15 05:16:25.344285 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 15 05:16:25.349854 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 15 05:16:25.354268 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 15 05:16:25.355138 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Jul 15 05:16:25.359839 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Jul 15 05:16:25.360893 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Jul 15 05:16:25.367295 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jul 15 05:16:25.376569 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jul 15 05:16:25.376694 systemd[1]: Reached target time-set.target - System Time Set. Jul 15 05:16:25.378327 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Jul 15 05:16:25.379809 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 15 05:16:25.380365 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 15 05:16:25.381296 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 15 05:16:25.381497 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 15 05:16:25.382072 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 15 05:16:25.382244 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jul 15 05:16:25.382744 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 15 05:16:25.382929 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 15 05:16:25.383539 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 15 05:16:25.383711 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 15 05:16:25.386242 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 15 05:16:25.386317 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jul 15 05:16:25.432416 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Jul 15 05:16:25.437929 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Jul 15 05:16:25.463759 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Jul 15 05:16:25.510156 systemd[1]: Started systemd-userdbd.service - User Database Manager. Jul 15 05:16:25.537232 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Jul 15 05:16:25.575258 augenrules[1835]: No rules Jul 15 05:16:25.576417 systemd[1]: audit-rules.service: Deactivated successfully. Jul 15 05:16:25.576668 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jul 15 05:16:25.624286 systemd-resolved[1796]: Positive Trust Anchors: Jul 15 05:16:25.624304 systemd-resolved[1796]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 15 05:16:25.624336 systemd-resolved[1796]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Jul 15 05:16:25.658682 systemd-resolved[1796]: Using system hostname 'ci-4396.0.0-n-cc6edc876b'. Jul 15 05:16:25.660090 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jul 15 05:16:25.660327 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jul 15 05:16:25.673696 systemd-networkd[1794]: lo: Link UP Jul 15 05:16:25.673704 systemd-networkd[1794]: lo: Gained carrier Jul 15 05:16:25.675050 systemd-networkd[1794]: Enumeration completed Jul 15 05:16:25.675137 systemd[1]: Started systemd-networkd.service - Network Configuration. Jul 15 05:16:25.675307 systemd[1]: Reached target network.target - Network. Jul 15 05:16:25.675432 systemd-networkd[1794]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 15 05:16:25.675442 systemd-networkd[1794]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 15 05:16:25.676023 systemd-networkd[1794]: eth0: Link UP Jul 15 05:16:25.676031 systemd-networkd[1794]: eth0: Gained carrier Jul 15 05:16:25.676046 systemd-networkd[1794]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 15 05:16:25.677215 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Jul 15 05:16:25.678538 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Jul 15 05:16:25.746874 systemd-networkd[1794]: eth0: DHCPv4 address 10.200.8.12/24, gateway 10.200.8.1 acquired from 168.63.129.16 Jul 15 05:16:25.771345 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Jul 15 05:16:25.781343 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 15 05:16:26.033270 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Jul 15 05:16:26.039389 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Jul 15 05:16:27.650959 systemd-networkd[1794]: eth0: Gained IPv6LL Jul 15 05:16:27.653469 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Jul 15 05:16:27.659258 systemd[1]: Reached target network-online.target - Network is Online. Jul 15 05:16:30.360908 ldconfig[1527]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jul 15 05:16:30.374398 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Jul 15 05:16:30.380916 systemd[1]: Starting systemd-update-done.service - Update is Completed... Jul 15 05:16:30.412978 systemd[1]: Finished systemd-update-done.service - Update is Completed. Jul 15 05:16:30.417811 systemd[1]: Reached target sysinit.target - System Initialization. Jul 15 05:16:30.422349 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Jul 15 05:16:30.428252 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Jul 15 05:16:30.433458 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Jul 15 05:16:30.438359 systemd[1]: Started logrotate.timer - Daily rotation of log files. Jul 15 05:16:30.442815 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Jul 15 05:16:30.447983 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Jul 15 05:16:30.453160 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Jul 15 05:16:30.453211 systemd[1]: Reached target paths.target - Path Units. Jul 15 05:16:30.456978 systemd[1]: Reached target timers.target - Timer Units. Jul 15 05:16:30.461812 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Jul 15 05:16:30.467675 systemd[1]: Starting docker.socket - Docker Socket for the API... Jul 15 05:16:30.473631 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Jul 15 05:16:30.478788 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Jul 15 05:16:30.483880 systemd[1]: Reached target ssh-access.target - SSH Access Available. Jul 15 05:16:30.489980 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Jul 15 05:16:30.494518 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Jul 15 05:16:30.499952 systemd[1]: Listening on docker.socket - Docker Socket for the API. Jul 15 05:16:30.505211 systemd[1]: Reached target sockets.target - Socket Units. Jul 15 05:16:30.509128 systemd[1]: Reached target basic.target - Basic System. Jul 15 05:16:30.512962 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Jul 15 05:16:30.513013 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Jul 15 05:16:30.515314 systemd[1]: Starting chronyd.service - NTP client/server... Jul 15 05:16:30.519679 systemd[1]: Starting containerd.service - containerd container runtime... Jul 15 05:16:30.544655 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Jul 15 05:16:30.549710 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Jul 15 05:16:30.554215 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Jul 15 05:16:30.559628 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Jul 15 05:16:30.564708 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Jul 15 05:16:30.568721 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Jul 15 05:16:30.569807 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Jul 15 05:16:30.573898 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). Jul 15 05:16:30.575350 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Jul 15 05:16:30.579594 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Jul 15 05:16:30.580620 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Jul 15 05:16:30.584511 jq[1863]: false Jul 15 05:16:30.582723 KVP[1866]: KVP starting; pid is:1866 Jul 15 05:16:30.585370 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Jul 15 05:16:30.591175 kernel: hv_utils: KVP IC version 4.0 Jul 15 05:16:30.586366 KVP[1866]: KVP LIC Version: 3.1 Jul 15 05:16:30.593059 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Jul 15 05:16:30.599016 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Jul 15 05:16:30.599427 (chronyd)[1855]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Jul 15 05:16:30.604520 chronyd[1876]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) Jul 15 05:16:30.605909 systemd[1]: Starting systemd-logind.service - User Login Management... Jul 15 05:16:30.611025 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Jul 15 05:16:30.611482 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Jul 15 05:16:30.612110 systemd[1]: Starting update-engine.service - Update Engine... Jul 15 05:16:30.616646 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Jul 15 05:16:30.623250 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Jul 15 05:16:30.628545 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Jul 15 05:16:30.628765 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Jul 15 05:16:30.629166 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Jul 15 05:16:30.629383 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Jul 15 05:16:30.636719 jq[1883]: true Jul 15 05:16:30.638557 extend-filesystems[1864]: Found /dev/sdb6 Jul 15 05:16:30.650173 jq[1885]: true Jul 15 05:16:30.650097 chronyd[1876]: Timezone right/UTC failed leap second check, ignoring Jul 15 05:16:30.650270 chronyd[1876]: Loaded seccomp filter (level 2) Jul 15 05:16:30.651627 systemd[1]: Started chronyd.service - NTP client/server. Jul 15 05:16:30.656345 extend-filesystems[1864]: Found /dev/sdb9 Jul 15 05:16:30.658659 systemd[1]: motdgen.service: Deactivated successfully. Jul 15 05:16:30.659066 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Jul 15 05:16:30.660061 extend-filesystems[1864]: Checking size of /dev/sdb9 Jul 15 05:16:30.665404 oslogin_cache_refresh[1865]: Refreshing passwd entry cache Jul 15 05:16:30.660213 (ntainerd)[1894]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Jul 15 05:16:30.667394 google_oslogin_nss_cache[1865]: oslogin_cache_refresh[1865]: Refreshing passwd entry cache Jul 15 05:16:30.670956 systemd-logind[1877]: New seat seat0. Jul 15 05:16:30.673723 systemd-logind[1877]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Jul 15 05:16:30.673916 google_oslogin_nss_cache[1865]: oslogin_cache_refresh[1865]: Failure getting users, quitting Jul 15 05:16:30.673916 google_oslogin_nss_cache[1865]: oslogin_cache_refresh[1865]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Jul 15 05:16:30.673907 oslogin_cache_refresh[1865]: Failure getting users, quitting Jul 15 05:16:30.673935 systemd[1]: Started systemd-logind.service - User Login Management. Jul 15 05:16:30.674225 google_oslogin_nss_cache[1865]: oslogin_cache_refresh[1865]: Refreshing group entry cache Jul 15 05:16:30.673924 oslogin_cache_refresh[1865]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Jul 15 05:16:30.673965 oslogin_cache_refresh[1865]: Refreshing group entry cache Jul 15 05:16:30.685978 google_oslogin_nss_cache[1865]: oslogin_cache_refresh[1865]: Failure getting groups, quitting Jul 15 05:16:30.685978 google_oslogin_nss_cache[1865]: oslogin_cache_refresh[1865]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Jul 15 05:16:30.685971 oslogin_cache_refresh[1865]: Failure getting groups, quitting Jul 15 05:16:30.685982 oslogin_cache_refresh[1865]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Jul 15 05:16:30.689825 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Jul 15 05:16:30.690093 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Jul 15 05:16:30.696292 extend-filesystems[1864]: Old size kept for /dev/sdb9 Jul 15 05:16:30.697010 systemd[1]: extend-filesystems.service: Deactivated successfully. Jul 15 05:16:30.697267 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Jul 15 05:16:30.716998 update_engine[1881]: I20250715 05:16:30.716920 1881 main.cc:92] Flatcar Update Engine starting Jul 15 05:16:30.756003 sshd_keygen[1882]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Jul 15 05:16:30.779638 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Jul 15 05:16:30.785341 systemd[1]: Starting issuegen.service - Generate /run/issue... Jul 15 05:16:30.795194 bash[1927]: Updated "/home/core/.ssh/authorized_keys" Jul 15 05:16:30.804062 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Jul 15 05:16:30.808490 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Jul 15 05:16:30.815327 systemd[1]: issuegen.service: Deactivated successfully. Jul 15 05:16:30.815560 systemd[1]: Finished issuegen.service - Generate /run/issue. Jul 15 05:16:30.821571 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Jul 15 05:16:30.822677 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Jul 15 05:16:30.829235 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Jul 15 05:16:30.834724 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Jul 15 05:16:30.841505 systemd[1]: Started getty@tty1.service - Getty on tty1. Jul 15 05:16:30.846416 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Jul 15 05:16:30.850916 systemd[1]: Reached target getty.target - Login Prompts. Jul 15 05:16:30.871076 dbus-daemon[1858]: [system] SELinux support is enabled Jul 15 05:16:30.871236 systemd[1]: Started dbus.service - D-Bus System Message Bus. Jul 15 05:16:30.873702 update_engine[1881]: I20250715 05:16:30.873651 1881 update_check_scheduler.cc:74] Next update check in 11m26s Jul 15 05:16:30.877812 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Jul 15 05:16:30.877870 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Jul 15 05:16:30.878615 dbus-daemon[1858]: [system] Successfully activated service 'org.freedesktop.systemd1' Jul 15 05:16:30.883232 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Jul 15 05:16:30.883255 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Jul 15 05:16:30.888542 systemd[1]: Started update-engine.service - Update Engine. Jul 15 05:16:30.894439 systemd[1]: Started locksmithd.service - Cluster reboot manager. Jul 15 05:16:30.925514 setup-nvidia[1871]: Downloading Flatcar Container Linux Developer Container for version: 4396.0.0+nightly-20250714-2100 Jul 15 05:16:30.945815 coreos-metadata[1857]: Jul 15 05:16:30.945 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Jul 15 05:16:30.948244 coreos-metadata[1857]: Jul 15 05:16:30.948 INFO Fetch successful Jul 15 05:16:30.948410 coreos-metadata[1857]: Jul 15 05:16:30.948 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Jul 15 05:16:30.952458 coreos-metadata[1857]: Jul 15 05:16:30.952 INFO Fetch successful Jul 15 05:16:30.952731 coreos-metadata[1857]: Jul 15 05:16:30.952 INFO Fetching http://168.63.129.16/machine/0c1b190a-46bd-4a70-98e5-2c7e1e76cac1/1dd4acd3%2D2717%2D4938%2D8104%2D954eaf40c9f2.%5Fci%2D4396.0.0%2Dn%2Dcc6edc876b?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Jul 15 05:16:30.954255 coreos-metadata[1857]: Jul 15 05:16:30.954 INFO Fetch successful Jul 15 05:16:30.954407 coreos-metadata[1857]: Jul 15 05:16:30.954 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Jul 15 05:16:30.961722 coreos-metadata[1857]: Jul 15 05:16:30.961 INFO Fetch successful Jul 15 05:16:30.992398 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Jul 15 05:16:30.997498 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Jul 15 05:16:31.180277 locksmithd[2012]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Jul 15 05:16:31.901838 containerd[1894]: time="2025-07-15T05:16:31Z" 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 Jul 15 05:16:31.903293 containerd[1894]: time="2025-07-15T05:16:31.903251700Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Jul 15 05:16:31.912867 containerd[1894]: time="2025-07-15T05:16:31.912762700Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="40µs" Jul 15 05:16:31.912867 containerd[1894]: time="2025-07-15T05:16:31.912862600Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Jul 15 05:16:31.912960 containerd[1894]: time="2025-07-15T05:16:31.912884600Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Jul 15 05:16:31.913041 containerd[1894]: time="2025-07-15T05:16:31.913017300Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Jul 15 05:16:31.913041 containerd[1894]: time="2025-07-15T05:16:31.913038500Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Jul 15 05:16:31.913098 containerd[1894]: time="2025-07-15T05:16:31.913062100Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jul 15 05:16:31.913140 containerd[1894]: time="2025-07-15T05:16:31.913116900Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jul 15 05:16:31.913140 containerd[1894]: time="2025-07-15T05:16:31.913135200Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Jul 15 05:16:31.913342 containerd[1894]: time="2025-07-15T05:16:31.913317800Z" 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 Jul 15 05:16:31.913342 containerd[1894]: time="2025-07-15T05:16:31.913338500Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jul 15 05:16:31.913396 containerd[1894]: time="2025-07-15T05:16:31.913350500Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jul 15 05:16:31.913396 containerd[1894]: time="2025-07-15T05:16:31.913358600Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Jul 15 05:16:31.913471 containerd[1894]: time="2025-07-15T05:16:31.913448500Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Jul 15 05:16:31.913745 containerd[1894]: time="2025-07-15T05:16:31.913721900Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Jul 15 05:16:31.913774 containerd[1894]: time="2025-07-15T05:16:31.913759800Z" 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 Jul 15 05:16:31.913774 containerd[1894]: time="2025-07-15T05:16:31.913770100Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Jul 15 05:16:31.913842 containerd[1894]: time="2025-07-15T05:16:31.913814400Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Jul 15 05:16:31.914081 containerd[1894]: time="2025-07-15T05:16:31.914056900Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Jul 15 05:16:31.914144 containerd[1894]: time="2025-07-15T05:16:31.914124900Z" level=info msg="metadata content store policy set" policy=shared Jul 15 05:16:31.928694 containerd[1894]: time="2025-07-15T05:16:31.928660100Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Jul 15 05:16:31.928753 containerd[1894]: time="2025-07-15T05:16:31.928706400Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Jul 15 05:16:31.928753 containerd[1894]: time="2025-07-15T05:16:31.928721500Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Jul 15 05:16:31.928753 containerd[1894]: time="2025-07-15T05:16:31.928733800Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Jul 15 05:16:31.928753 containerd[1894]: time="2025-07-15T05:16:31.928746100Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Jul 15 05:16:31.928861 containerd[1894]: time="2025-07-15T05:16:31.928756200Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Jul 15 05:16:31.928861 containerd[1894]: time="2025-07-15T05:16:31.928769000Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Jul 15 05:16:31.928861 containerd[1894]: time="2025-07-15T05:16:31.928779600Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Jul 15 05:16:31.928861 containerd[1894]: time="2025-07-15T05:16:31.928790000Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Jul 15 05:16:31.928861 containerd[1894]: time="2025-07-15T05:16:31.928813200Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Jul 15 05:16:31.928861 containerd[1894]: time="2025-07-15T05:16:31.928827200Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Jul 15 05:16:31.928987 containerd[1894]: time="2025-07-15T05:16:31.928866800Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Jul 15 05:16:31.928987 containerd[1894]: time="2025-07-15T05:16:31.928974500Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Jul 15 05:16:31.929033 containerd[1894]: time="2025-07-15T05:16:31.928994400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Jul 15 05:16:31.929033 containerd[1894]: time="2025-07-15T05:16:31.929008700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Jul 15 05:16:31.929033 containerd[1894]: time="2025-07-15T05:16:31.929018000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Jul 15 05:16:31.929033 containerd[1894]: time="2025-07-15T05:16:31.929028000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Jul 15 05:16:31.929110 containerd[1894]: time="2025-07-15T05:16:31.929038700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Jul 15 05:16:31.929110 containerd[1894]: time="2025-07-15T05:16:31.929048500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Jul 15 05:16:31.929110 containerd[1894]: time="2025-07-15T05:16:31.929058000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Jul 15 05:16:31.929110 containerd[1894]: time="2025-07-15T05:16:31.929068000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Jul 15 05:16:31.929110 containerd[1894]: time="2025-07-15T05:16:31.929078200Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Jul 15 05:16:31.929110 containerd[1894]: time="2025-07-15T05:16:31.929087900Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Jul 15 05:16:31.929234 containerd[1894]: time="2025-07-15T05:16:31.929156200Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Jul 15 05:16:31.929234 containerd[1894]: time="2025-07-15T05:16:31.929182500Z" level=info msg="Start snapshots syncer" Jul 15 05:16:31.929234 containerd[1894]: time="2025-07-15T05:16:31.929209700Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Jul 15 05:16:31.929477 containerd[1894]: time="2025-07-15T05:16:31.929425500Z" 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\"}" Jul 15 05:16:31.929598 containerd[1894]: time="2025-07-15T05:16:31.929493400Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Jul 15 05:16:31.929598 containerd[1894]: time="2025-07-15T05:16:31.929578100Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Jul 15 05:16:31.929713 containerd[1894]: time="2025-07-15T05:16:31.929674900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Jul 15 05:16:31.929743 containerd[1894]: time="2025-07-15T05:16:31.929725600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Jul 15 05:16:31.929766 containerd[1894]: time="2025-07-15T05:16:31.929747800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Jul 15 05:16:31.929766 containerd[1894]: time="2025-07-15T05:16:31.929761400Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Jul 15 05:16:31.929815 containerd[1894]: time="2025-07-15T05:16:31.929772000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Jul 15 05:16:31.929815 containerd[1894]: time="2025-07-15T05:16:31.929782200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Jul 15 05:16:31.929815 containerd[1894]: time="2025-07-15T05:16:31.929791800Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Jul 15 05:16:31.929815 containerd[1894]: time="2025-07-15T05:16:31.929811800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Jul 15 05:16:31.929921 containerd[1894]: time="2025-07-15T05:16:31.929822600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Jul 15 05:16:31.929921 containerd[1894]: time="2025-07-15T05:16:31.929863300Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Jul 15 05:16:31.929921 containerd[1894]: time="2025-07-15T05:16:31.929894200Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Jul 15 05:16:31.929921 containerd[1894]: time="2025-07-15T05:16:31.929908600Z" 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 Jul 15 05:16:31.929921 containerd[1894]: time="2025-07-15T05:16:31.929916800Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jul 15 05:16:31.930027 containerd[1894]: time="2025-07-15T05:16:31.929962700Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jul 15 05:16:31.930027 containerd[1894]: time="2025-07-15T05:16:31.929977400Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Jul 15 05:16:31.930027 containerd[1894]: time="2025-07-15T05:16:31.929987400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Jul 15 05:16:31.930027 containerd[1894]: time="2025-07-15T05:16:31.929996600Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Jul 15 05:16:31.930027 containerd[1894]: time="2025-07-15T05:16:31.930013400Z" level=info msg="runtime interface created" Jul 15 05:16:31.930027 containerd[1894]: time="2025-07-15T05:16:31.930018600Z" level=info msg="created NRI interface" Jul 15 05:16:31.930027 containerd[1894]: time="2025-07-15T05:16:31.930025800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Jul 15 05:16:31.930167 containerd[1894]: time="2025-07-15T05:16:31.930036500Z" level=info msg="Connect containerd service" Jul 15 05:16:31.930167 containerd[1894]: time="2025-07-15T05:16:31.930059200Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Jul 15 05:16:31.931006 containerd[1894]: time="2025-07-15T05:16:31.930977700Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Jul 15 05:16:33.100436 waagent[2005]: 2025-07-15T05:16:33.100355Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Jul 15 05:16:33.105175 waagent[2005]: 2025-07-15T05:16:33.105124Z INFO Daemon Daemon OS: flatcar 4396.0.0 Jul 15 05:16:33.109239 waagent[2005]: 2025-07-15T05:16:33.109185Z INFO Daemon Daemon Python: 3.11.13 Jul 15 05:16:33.112931 waagent[2005]: 2025-07-15T05:16:33.112771Z INFO Daemon Daemon Run daemon Jul 15 05:16:33.116265 waagent[2005]: 2025-07-15T05:16:33.116218Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4396.0.0' Jul 15 05:16:33.123276 waagent[2005]: 2025-07-15T05:16:33.123221Z INFO Daemon Daemon Using waagent for provisioning Jul 15 05:16:33.127642 waagent[2005]: 2025-07-15T05:16:33.127592Z INFO Daemon Daemon Activate resource disk Jul 15 05:16:33.131325 containerd[1894]: time="2025-07-15T05:16:33.131225900Z" level=info msg="Start subscribing containerd event" Jul 15 05:16:33.131325 containerd[1894]: time="2025-07-15T05:16:33.131285300Z" level=info msg="Start recovering state" Jul 15 05:16:33.131631 containerd[1894]: time="2025-07-15T05:16:33.131399500Z" level=info msg="Start event monitor" Jul 15 05:16:33.131631 containerd[1894]: time="2025-07-15T05:16:33.131421300Z" level=info msg="Start cni network conf syncer for default" Jul 15 05:16:33.131631 containerd[1894]: time="2025-07-15T05:16:33.131430800Z" level=info msg="Start streaming server" Jul 15 05:16:33.131631 containerd[1894]: time="2025-07-15T05:16:33.131459700Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Jul 15 05:16:33.131631 containerd[1894]: time="2025-07-15T05:16:33.131470500Z" level=info msg="runtime interface starting up..." Jul 15 05:16:33.131631 containerd[1894]: time="2025-07-15T05:16:33.131476600Z" level=info msg="starting plugins..." Jul 15 05:16:33.131631 containerd[1894]: time="2025-07-15T05:16:33.131491600Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Jul 15 05:16:33.131631 containerd[1894]: time="2025-07-15T05:16:33.131403000Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jul 15 05:16:33.131812 waagent[2005]: 2025-07-15T05:16:33.131492Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Jul 15 05:16:33.131806 systemd[1]: Started containerd.service - containerd container runtime. Jul 15 05:16:33.132033 containerd[1894]: time="2025-07-15T05:16:33.131646500Z" level=info msg=serving... address=/run/containerd/containerd.sock Jul 15 05:16:33.132033 containerd[1894]: time="2025-07-15T05:16:33.131719700Z" level=info msg="containerd successfully booted in 1.230397s" Jul 15 05:16:33.147014 waagent[2005]: 2025-07-15T05:16:33.146960Z INFO Daemon Daemon Found device: sda Jul 15 05:16:33.156458 waagent[2005]: 2025-07-15T05:16:33.156396Z INFO Daemon Daemon Examining partition table Jul 15 05:16:33.209650 waagent[2005]: 2025-07-15T05:16:33.209581Z INFO Daemon Daemon GPT not detected, determining filesystem Jul 15 05:16:33.245863 waagent[2005]: 2025-07-15T05:16:33.245789Z INFO Daemon Daemon sfdisk --part-type -f /dev/sda 1 -n succeeded Jul 15 05:16:33.250975 waagent[2005]: 2025-07-15T05:16:33.250928Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Jul 15 05:16:33.584450 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#289 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 15 05:16:33.595845 kernel: sda: sda1 Jul 15 05:16:33.608792 waagent[2005]: 2025-07-15T05:16:33.608715Z INFO Daemon Daemon sfdisk --part-type /dev/sda 1 83 succeeded Jul 15 05:16:33.627857 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#281 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 15 05:16:33.638847 kernel: sda: sda1 Jul 15 05:16:33.638916 waagent[2005]: 2025-07-15T05:16:33.638874Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sda1] Jul 15 05:16:39.393099 login[2008]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Jul 15 05:16:39.395049 login[2009]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Jul 15 05:16:39.402556 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Jul 15 05:16:39.403584 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Jul 15 05:16:39.410864 systemd-logind[1877]: New session 2 of user core. Jul 15 05:16:39.471636 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Jul 15 05:16:39.474563 systemd[1]: Starting user@500.service - User Manager for UID 500... Jul 15 05:16:39.480967 waagent[2005]: 2025-07-15T05:16:39.480905Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sda1 /mnt/resource] Jul 15 05:16:39.541592 (systemd)[2116]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Jul 15 05:16:39.544157 systemd-logind[1877]: New session c1 of user core. Jul 15 05:16:39.603519 waagent[2005]: 2025-07-15T05:16:39.603430Z INFO Daemon Daemon Resource disk /dev/sda is mounted at /mnt/resource with ext4 Jul 15 05:16:39.603852 kernel: EXT4-fs (sda1): mounted filesystem 536e6481-51ef-4603-a06f-471729504432 r/w with ordered data mode. Quota mode: none. Jul 15 05:16:39.604320 waagent[2005]: 2025-07-15T05:16:39.604278Z INFO Daemon Daemon Clean protocol and wireserver endpoint Jul 15 05:16:39.604838 waagent[2005]: 2025-07-15T05:16:39.604794Z INFO Daemon Daemon Running default provisioning handler Jul 15 05:16:39.611417 waagent[2005]: 2025-07-15T05:16:39.611368Z INFO Daemon Daemon Unable to get cloud-init enabled status from systemctl: Command '['systemctl', 'is-enabled', 'cloud-init-local.service']' returned non-zero exit status 4. Jul 15 05:16:39.612106 waagent[2005]: 2025-07-15T05:16:39.612068Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Jul 15 05:16:39.612550 waagent[2005]: 2025-07-15T05:16:39.612518Z INFO Daemon Daemon cloud-init is enabled: False Jul 15 05:16:39.614096 waagent[2005]: 2025-07-15T05:16:39.614063Z INFO Daemon Daemon Copying ovf-env.xml Jul 15 05:16:39.713126 waagent[2005]: 2025-07-15T05:16:39.713028Z INFO Daemon Daemon Successfully mounted dvd Jul 15 05:16:39.734480 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Jul 15 05:16:39.734608 waagent[2005]: 2025-07-15T05:16:39.734475Z INFO Daemon Daemon Detect protocol endpoint Jul 15 05:16:39.734713 waagent[2005]: 2025-07-15T05:16:39.734672Z INFO Daemon Daemon Clean protocol and wireserver endpoint Jul 15 05:16:39.736155 waagent[2005]: 2025-07-15T05:16:39.736120Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Jul 15 05:16:39.736851 waagent[2005]: 2025-07-15T05:16:39.736812Z INFO Daemon Daemon Test for route to 168.63.129.16 Jul 15 05:16:39.737659 waagent[2005]: 2025-07-15T05:16:39.737624Z INFO Daemon Daemon Route to 168.63.129.16 exists Jul 15 05:16:39.738980 waagent[2005]: 2025-07-15T05:16:39.738943Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Jul 15 05:16:39.750608 waagent[2005]: 2025-07-15T05:16:39.750569Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Jul 15 05:16:39.752072 waagent[2005]: 2025-07-15T05:16:39.752048Z INFO Daemon Daemon Wire protocol version:2012-11-30 Jul 15 05:16:39.752581 waagent[2005]: 2025-07-15T05:16:39.752552Z INFO Daemon Daemon Server preferred version:2015-04-05 Jul 15 05:16:39.814589 systemd[2116]: Queued start job for default target default.target. Jul 15 05:16:39.834743 systemd[2116]: Created slice app.slice - User Application Slice. Jul 15 05:16:39.834772 systemd[2116]: Reached target paths.target - Paths. Jul 15 05:16:39.834819 systemd[2116]: Reached target timers.target - Timers. Jul 15 05:16:39.836032 systemd[2116]: Starting dbus.socket - D-Bus User Message Bus Socket... Jul 15 05:16:39.846522 systemd[2116]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jul 15 05:16:39.846582 systemd[2116]: Reached target sockets.target - Sockets. Jul 15 05:16:39.846630 systemd[2116]: Reached target basic.target - Basic System. Jul 15 05:16:39.846674 systemd[2116]: Reached target default.target - Main User Target. Jul 15 05:16:39.846711 systemd[2116]: Startup finished in 296ms. Jul 15 05:16:39.846785 systemd[1]: Started user@500.service - User Manager for UID 500. Jul 15 05:16:39.848111 systemd[1]: Started session-2.scope - Session 2 of User core. Jul 15 05:16:39.863717 waagent[2005]: 2025-07-15T05:16:39.863650Z INFO Daemon Daemon Initializing goal state during protocol detection Jul 15 05:16:39.868483 waagent[2005]: 2025-07-15T05:16:39.868399Z INFO Daemon Daemon Forcing an update of the goal state. Jul 15 05:16:39.872057 waagent[2005]: 2025-07-15T05:16:39.872007Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Jul 15 05:16:39.902706 waagent[2005]: 2025-07-15T05:16:39.902659Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.175 Jul 15 05:16:39.903299 waagent[2005]: 2025-07-15T05:16:39.903259Z INFO Daemon Jul 15 05:16:39.903895 waagent[2005]: 2025-07-15T05:16:39.903844Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: c62136bd-5c61-4905-a152-529f5bd4975e eTag: 4047108221087259737 source: Fabric] Jul 15 05:16:39.904801 waagent[2005]: 2025-07-15T05:16:39.904761Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Jul 15 05:16:39.905532 waagent[2005]: 2025-07-15T05:16:39.905492Z INFO Daemon Jul 15 05:16:39.906732 waagent[2005]: 2025-07-15T05:16:39.906698Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Jul 15 05:16:39.911535 waagent[2005]: 2025-07-15T05:16:39.911499Z INFO Daemon Daemon Downloading artifacts profile blob Jul 15 05:16:40.046066 waagent[2005]: 2025-07-15T05:16:40.045957Z INFO Daemon Downloaded certificate {'thumbprint': '2AB012E98361C00BC4794D4ECE23B705B1988DBF', 'hasPrivateKey': True} Jul 15 05:16:40.046851 waagent[2005]: 2025-07-15T05:16:40.046515Z INFO Daemon Fetch goal state completed Jul 15 05:16:40.091958 waagent[2005]: 2025-07-15T05:16:40.091886Z INFO Daemon Daemon Starting provisioning Jul 15 05:16:40.092253 waagent[2005]: 2025-07-15T05:16:40.092204Z INFO Daemon Daemon Handle ovf-env.xml. Jul 15 05:16:40.093930 waagent[2005]: 2025-07-15T05:16:40.093875Z INFO Daemon Daemon Set hostname [ci-4396.0.0-n-cc6edc876b] Jul 15 05:16:40.138159 waagent[2005]: 2025-07-15T05:16:40.138098Z INFO Daemon Daemon Publish hostname [ci-4396.0.0-n-cc6edc876b] Jul 15 05:16:40.138590 waagent[2005]: 2025-07-15T05:16:40.138543Z INFO Daemon Daemon Examine /proc/net/route for primary interface Jul 15 05:16:40.140102 waagent[2005]: 2025-07-15T05:16:40.140058Z INFO Daemon Daemon Primary interface is [eth0] Jul 15 05:16:40.149100 systemd-networkd[1794]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 15 05:16:40.149111 systemd-networkd[1794]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 15 05:16:40.149139 systemd-networkd[1794]: eth0: DHCP lease lost Jul 15 05:16:40.149567 waagent[2005]: 2025-07-15T05:16:40.149520Z INFO Daemon Daemon Create user account if not exists Jul 15 05:16:40.150464 waagent[2005]: 2025-07-15T05:16:40.150428Z INFO Daemon Daemon User core already exists, skip useradd Jul 15 05:16:40.151911 waagent[2005]: 2025-07-15T05:16:40.151871Z INFO Daemon Daemon Configure sudoer Jul 15 05:16:40.156692 waagent[2005]: 2025-07-15T05:16:40.156646Z INFO Daemon Daemon Configure sshd Jul 15 05:16:40.161522 waagent[2005]: 2025-07-15T05:16:40.161471Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Jul 15 05:16:40.163023 waagent[2005]: 2025-07-15T05:16:40.162982Z INFO Daemon Daemon Deploy ssh public key. Jul 15 05:16:40.232876 systemd-networkd[1794]: eth0: DHCPv4 address 10.200.8.12/24, gateway 10.200.8.1 acquired from 168.63.129.16 Jul 15 05:16:40.393527 login[2008]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Jul 15 05:16:40.398049 systemd-logind[1877]: New session 1 of user core. Jul 15 05:16:40.407953 systemd[1]: Started session-1.scope - Session 1 of User core. Jul 15 05:16:41.346273 waagent[2005]: 2025-07-15T05:16:41.346209Z INFO Daemon Daemon Provisioning complete Jul 15 05:16:41.355134 waagent[2005]: 2025-07-15T05:16:41.355087Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Jul 15 05:16:41.355429 waagent[2005]: 2025-07-15T05:16:41.355379Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Jul 15 05:16:41.356863 waagent[2005]: 2025-07-15T05:16:41.356814Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Jul 15 05:16:41.509054 waagent[2176]: 2025-07-15T05:16:41.508974Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Jul 15 05:16:41.509380 waagent[2176]: 2025-07-15T05:16:41.509110Z INFO ExtHandler ExtHandler OS: flatcar 4396.0.0 Jul 15 05:16:41.509380 waagent[2176]: 2025-07-15T05:16:41.509179Z INFO ExtHandler ExtHandler Python: 3.11.13 Jul 15 05:16:41.509380 waagent[2176]: 2025-07-15T05:16:41.509245Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Jul 15 05:16:41.544719 waagent[2176]: 2025-07-15T05:16:41.544658Z INFO ExtHandler ExtHandler Distro: flatcar-4396.0.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.13; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Jul 15 05:16:41.544926 waagent[2176]: 2025-07-15T05:16:41.544887Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jul 15 05:16:41.545004 waagent[2176]: 2025-07-15T05:16:41.544972Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Jul 15 05:16:41.551581 waagent[2176]: 2025-07-15T05:16:41.551537Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Jul 15 05:16:41.556215 waagent[2176]: 2025-07-15T05:16:41.556177Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.175 Jul 15 05:16:41.556648 waagent[2176]: 2025-07-15T05:16:41.556609Z INFO ExtHandler Jul 15 05:16:41.556728 waagent[2176]: 2025-07-15T05:16:41.556697Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: ff15b318-086b-45e4-866c-0d2336967477 eTag: 4047108221087259737 source: Fabric] Jul 15 05:16:41.557069 waagent[2176]: 2025-07-15T05:16:41.557030Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Jul 15 05:16:41.557969 waagent[2176]: 2025-07-15T05:16:41.557923Z INFO ExtHandler Jul 15 05:16:41.558040 waagent[2176]: 2025-07-15T05:16:41.558009Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Jul 15 05:16:41.561365 waagent[2176]: 2025-07-15T05:16:41.561334Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Jul 15 05:16:41.619116 waagent[2176]: 2025-07-15T05:16:41.619006Z INFO ExtHandler Downloaded certificate {'thumbprint': '2AB012E98361C00BC4794D4ECE23B705B1988DBF', 'hasPrivateKey': True} Jul 15 05:16:41.619582 waagent[2176]: 2025-07-15T05:16:41.619541Z INFO ExtHandler Fetch goal state completed Jul 15 05:16:41.630912 waagent[2176]: 2025-07-15T05:16:41.630865Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.4.1 11 Feb 2025 (Library: OpenSSL 3.4.1 11 Feb 2025) Jul 15 05:16:41.635732 waagent[2176]: 2025-07-15T05:16:41.635685Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2176 Jul 15 05:16:41.635887 waagent[2176]: 2025-07-15T05:16:41.635848Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Jul 15 05:16:41.636228 waagent[2176]: 2025-07-15T05:16:41.636191Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Jul 15 05:16:41.637964 waagent[2176]: 2025-07-15T05:16:41.637917Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4396.0.0', '', 'Flatcar Container Linux by Kinvolk'] Jul 15 05:16:41.638389 waagent[2176]: 2025-07-15T05:16:41.638351Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4396.0.0', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Jul 15 05:16:41.638540 waagent[2176]: 2025-07-15T05:16:41.638504Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Jul 15 05:16:41.639150 waagent[2176]: 2025-07-15T05:16:41.639112Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Jul 15 05:16:41.687426 waagent[2176]: 2025-07-15T05:16:41.687384Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Jul 15 05:16:41.687596 waagent[2176]: 2025-07-15T05:16:41.687560Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Jul 15 05:16:41.694184 waagent[2176]: 2025-07-15T05:16:41.694149Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Jul 15 05:16:41.700661 systemd[1]: Reload requested from client PID 2191 ('systemctl') (unit waagent.service)... Jul 15 05:16:41.700676 systemd[1]: Reloading... Jul 15 05:16:41.756861 zram_generator::config[2230]: No configuration found. Jul 15 05:16:41.873408 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Jul 15 05:16:42.033383 systemd[1]: Reloading finished in 332 ms. Jul 15 05:16:42.057004 waagent[2176]: 2025-07-15T05:16:42.056934Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Jul 15 05:16:42.057121 waagent[2176]: 2025-07-15T05:16:42.057084Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Jul 15 05:16:42.440618 waagent[2176]: 2025-07-15T05:16:42.440541Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Jul 15 05:16:42.440940 waagent[2176]: 2025-07-15T05:16:42.440900Z 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] Jul 15 05:16:42.441688 waagent[2176]: 2025-07-15T05:16:42.441643Z INFO ExtHandler ExtHandler Starting env monitor service. Jul 15 05:16:42.442148 waagent[2176]: 2025-07-15T05:16:42.442108Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Jul 15 05:16:42.442376 waagent[2176]: 2025-07-15T05:16:42.442332Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jul 15 05:16:42.442594 waagent[2176]: 2025-07-15T05:16:42.442552Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jul 15 05:16:42.442728 waagent[2176]: 2025-07-15T05:16:42.442676Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Jul 15 05:16:42.442853 waagent[2176]: 2025-07-15T05:16:42.442802Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Jul 15 05:16:42.443004 waagent[2176]: 2025-07-15T05:16:42.442966Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Jul 15 05:16:42.443086 waagent[2176]: 2025-07-15T05:16:42.443049Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Jul 15 05:16:42.443629 waagent[2176]: 2025-07-15T05:16:42.443584Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Jul 15 05:16:42.443735 waagent[2176]: 2025-07-15T05:16:42.443685Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Jul 15 05:16:42.443929 waagent[2176]: 2025-07-15T05:16:42.443886Z INFO EnvHandler ExtHandler Configure routes Jul 15 05:16:42.444043 waagent[2176]: 2025-07-15T05:16:42.444002Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Jul 15 05:16:42.444407 waagent[2176]: 2025-07-15T05:16:42.444372Z INFO EnvHandler ExtHandler Gateway:None Jul 15 05:16:42.444467 waagent[2176]: 2025-07-15T05:16:42.444430Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Jul 15 05:16:42.445265 waagent[2176]: 2025-07-15T05:16:42.445217Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Jul 15 05:16:42.445265 waagent[2176]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Jul 15 05:16:42.445265 waagent[2176]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Jul 15 05:16:42.445265 waagent[2176]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Jul 15 05:16:42.445265 waagent[2176]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Jul 15 05:16:42.445265 waagent[2176]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jul 15 05:16:42.445265 waagent[2176]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jul 15 05:16:42.445442 waagent[2176]: 2025-07-15T05:16:42.445322Z INFO EnvHandler ExtHandler Routes:None Jul 15 05:16:42.450069 waagent[2176]: 2025-07-15T05:16:42.450015Z INFO ExtHandler ExtHandler Jul 15 05:16:42.450530 waagent[2176]: 2025-07-15T05:16:42.450482Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 501dbd33-1464-4d4d-9466-3551efb980d3 correlation 5fa252a4-fb1d-4078-a964-390bdc640039 created: 2025-07-15T05:15:13.500595Z] Jul 15 05:16:42.451647 waagent[2176]: 2025-07-15T05:16:42.451602Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Jul 15 05:16:42.453757 waagent[2176]: 2025-07-15T05:16:42.453716Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Jul 15 05:16:42.496197 waagent[2176]: 2025-07-15T05:16:42.496150Z 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 Jul 15 05:16:42.496197 waagent[2176]: Try `iptables -h' or 'iptables --help' for more information.) Jul 15 05:16:42.496559 waagent[2176]: 2025-07-15T05:16:42.496520Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: B5B123D3-EFC2-4565-8E71-085C4C637FF3;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Jul 15 05:16:42.515774 waagent[2176]: 2025-07-15T05:16:42.515720Z INFO MonitorHandler ExtHandler Network interfaces: Jul 15 05:16:42.515774 waagent[2176]: Executing ['ip', '-a', '-o', 'link']: Jul 15 05:16:42.515774 waagent[2176]: 1: lo: mtu 65536 qdisc noqueue state UNKNOWN mode DEFAULT group default qlen 1000\ link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00 Jul 15 05:16:42.515774 waagent[2176]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:1e:52:88:1c:14 brd ff:ff:ff:ff:ff:ff Jul 15 05:16:42.515774 waagent[2176]: Executing ['ip', '-4', '-a', '-o', 'address']: Jul 15 05:16:42.515774 waagent[2176]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Jul 15 05:16:42.515774 waagent[2176]: 2: eth0 inet 10.200.8.12/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Jul 15 05:16:42.515774 waagent[2176]: Executing ['ip', '-6', '-a', '-o', 'address']: Jul 15 05:16:42.515774 waagent[2176]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Jul 15 05:16:42.515774 waagent[2176]: 2: eth0 inet6 fe80::7e1e:52ff:fe88:1c14/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Jul 15 05:16:42.563056 waagent[2176]: 2025-07-15T05:16:42.563003Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Jul 15 05:16:42.563056 waagent[2176]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Jul 15 05:16:42.563056 waagent[2176]: pkts bytes target prot opt in out source destination Jul 15 05:16:42.563056 waagent[2176]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Jul 15 05:16:42.563056 waagent[2176]: pkts bytes target prot opt in out source destination Jul 15 05:16:42.563056 waagent[2176]: Chain OUTPUT (policy ACCEPT 4 packets, 416 bytes) Jul 15 05:16:42.563056 waagent[2176]: pkts bytes target prot opt in out source destination Jul 15 05:16:42.563056 waagent[2176]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Jul 15 05:16:42.563056 waagent[2176]: 10 1047 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Jul 15 05:16:42.563056 waagent[2176]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Jul 15 05:16:42.566795 waagent[2176]: 2025-07-15T05:16:42.566747Z INFO EnvHandler ExtHandler Current Firewall rules: Jul 15 05:16:42.566795 waagent[2176]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Jul 15 05:16:42.566795 waagent[2176]: pkts bytes target prot opt in out source destination Jul 15 05:16:42.566795 waagent[2176]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Jul 15 05:16:42.566795 waagent[2176]: pkts bytes target prot opt in out source destination Jul 15 05:16:42.566795 waagent[2176]: Chain OUTPUT (policy ACCEPT 7 packets, 765 bytes) Jul 15 05:16:42.566795 waagent[2176]: pkts bytes target prot opt in out source destination Jul 15 05:16:42.566795 waagent[2176]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Jul 15 05:16:42.566795 waagent[2176]: 11 1099 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Jul 15 05:16:42.566795 waagent[2176]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Jul 15 05:16:42.567050 waagent[2176]: 2025-07-15T05:16:42.567016Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Jul 15 05:16:42.567193 waagent[2176]: 2025-07-15T05:16:42.567161Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Jul 15 05:16:54.431030 chronyd[1876]: Selected source PHC0 Jul 15 05:17:00.711737 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Jul 15 05:17:00.712937 systemd[1]: Started sshd@0-10.200.8.12:22-10.200.16.10:55928.service - OpenSSH per-connection server daemon (10.200.16.10:55928). Jul 15 05:17:01.595974 sshd[2323]: Accepted publickey for core from 10.200.16.10 port 55928 ssh2: RSA SHA256:tFaO/Gkpdf2AjbVf3W+X+FNU/vH2JWmxNlepyUE0K5I Jul 15 05:17:01.597166 sshd-session[2323]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 05:17:01.601331 systemd-logind[1877]: New session 3 of user core. Jul 15 05:17:01.625600 systemd[1]: Started session-3.scope - Session 3 of User core. Jul 15 05:17:02.147740 systemd[1]: Started sshd@1-10.200.8.12:22-10.200.16.10:55930.service - OpenSSH per-connection server daemon (10.200.16.10:55930). Jul 15 05:17:02.781944 sshd[2329]: Accepted publickey for core from 10.200.16.10 port 55930 ssh2: RSA SHA256:tFaO/Gkpdf2AjbVf3W+X+FNU/vH2JWmxNlepyUE0K5I Jul 15 05:17:02.783191 sshd-session[2329]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 05:17:02.787234 systemd-logind[1877]: New session 4 of user core. Jul 15 05:17:02.801947 systemd[1]: Started session-4.scope - Session 4 of User core. Jul 15 05:17:03.233348 sshd[2332]: Connection closed by 10.200.16.10 port 55930 Jul 15 05:17:03.233694 sshd-session[2329]: pam_unix(sshd:session): session closed for user core Jul 15 05:17:03.237106 systemd[1]: sshd@1-10.200.8.12:22-10.200.16.10:55930.service: Deactivated successfully. Jul 15 05:17:03.238924 systemd[1]: session-4.scope: Deactivated successfully. Jul 15 05:17:03.239647 systemd-logind[1877]: Session 4 logged out. Waiting for processes to exit. Jul 15 05:17:03.240585 systemd-logind[1877]: Removed session 4. Jul 15 05:17:03.353130 systemd[1]: Started sshd@2-10.200.8.12:22-10.200.16.10:55938.service - OpenSSH per-connection server daemon (10.200.16.10:55938). Jul 15 05:17:03.983183 sshd[2339]: Accepted publickey for core from 10.200.16.10 port 55938 ssh2: RSA SHA256:tFaO/Gkpdf2AjbVf3W+X+FNU/vH2JWmxNlepyUE0K5I Jul 15 05:17:03.984402 sshd-session[2339]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 05:17:03.988476 systemd-logind[1877]: New session 5 of user core. Jul 15 05:17:04.006952 systemd[1]: Started session-5.scope - Session 5 of User core. Jul 15 05:17:04.434097 sshd[2342]: Connection closed by 10.200.16.10 port 55938 Jul 15 05:17:04.434461 sshd-session[2339]: pam_unix(sshd:session): session closed for user core Jul 15 05:17:04.437857 systemd[1]: sshd@2-10.200.8.12:22-10.200.16.10:55938.service: Deactivated successfully. Jul 15 05:17:04.439481 systemd[1]: session-5.scope: Deactivated successfully. Jul 15 05:17:04.440182 systemd-logind[1877]: Session 5 logged out. Waiting for processes to exit. Jul 15 05:17:04.441063 systemd-logind[1877]: Removed session 5. Jul 15 05:17:13.143086 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Jul 15 05:17:14.549784 systemd[1]: Started sshd@3-10.200.8.12:22-10.200.16.10:45586.service - OpenSSH per-connection server daemon (10.200.16.10:45586). Jul 15 05:17:15.178380 sshd[2348]: Accepted publickey for core from 10.200.16.10 port 45586 ssh2: RSA SHA256:tFaO/Gkpdf2AjbVf3W+X+FNU/vH2JWmxNlepyUE0K5I Jul 15 05:17:15.179606 sshd-session[2348]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 05:17:15.183727 systemd-logind[1877]: New session 6 of user core. Jul 15 05:17:15.192949 systemd[1]: Started session-6.scope - Session 6 of User core. Jul 15 05:17:15.621210 sshd[2351]: Connection closed by 10.200.16.10 port 45586 Jul 15 05:17:15.621570 sshd-session[2348]: pam_unix(sshd:session): session closed for user core Jul 15 05:17:15.624980 systemd[1]: sshd@3-10.200.8.12:22-10.200.16.10:45586.service: Deactivated successfully. Jul 15 05:17:15.626884 systemd[1]: session-6.scope: Deactivated successfully. Jul 15 05:17:15.627662 systemd-logind[1877]: Session 6 logged out. Waiting for processes to exit. Jul 15 05:17:15.628551 systemd-logind[1877]: Removed session 6. Jul 15 05:17:15.743126 systemd[1]: Started sshd@4-10.200.8.12:22-10.200.16.10:45594.service - OpenSSH per-connection server daemon (10.200.16.10:45594). Jul 15 05:17:15.818129 update_engine[1881]: I20250715 05:17:15.818066 1881 update_attempter.cc:509] Updating boot flags... Jul 15 05:17:16.373858 sshd[2357]: Accepted publickey for core from 10.200.16.10 port 45594 ssh2: RSA SHA256:tFaO/Gkpdf2AjbVf3W+X+FNU/vH2JWmxNlepyUE0K5I Jul 15 05:17:16.375129 sshd-session[2357]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 05:17:16.379270 systemd-logind[1877]: New session 7 of user core. Jul 15 05:17:16.390955 systemd[1]: Started session-7.scope - Session 7 of User core. Jul 15 05:17:16.829140 sshd[2476]: Connection closed by 10.200.16.10 port 45594 Jul 15 05:17:16.829490 sshd-session[2357]: pam_unix(sshd:session): session closed for user core Jul 15 05:17:16.833022 systemd[1]: sshd@4-10.200.8.12:22-10.200.16.10:45594.service: Deactivated successfully. Jul 15 05:17:16.834555 systemd[1]: session-7.scope: Deactivated successfully. Jul 15 05:17:16.835228 systemd-logind[1877]: Session 7 logged out. Waiting for processes to exit. Jul 15 05:17:16.836098 systemd-logind[1877]: Removed session 7. Jul 15 05:17:26.945100 systemd[1]: Started sshd@5-10.200.8.12:22-10.200.16.10:43526.service - OpenSSH per-connection server daemon (10.200.16.10:43526). Jul 15 05:17:27.578233 sshd[2482]: Accepted publickey for core from 10.200.16.10 port 43526 ssh2: RSA SHA256:tFaO/Gkpdf2AjbVf3W+X+FNU/vH2JWmxNlepyUE0K5I Jul 15 05:17:27.579440 sshd-session[2482]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 05:17:27.583530 systemd-logind[1877]: New session 8 of user core. Jul 15 05:17:27.593952 systemd[1]: Started session-8.scope - Session 8 of User core. Jul 15 05:17:28.020055 sshd[2485]: Connection closed by 10.200.16.10 port 43526 Jul 15 05:17:28.020333 sshd-session[2482]: pam_unix(sshd:session): session closed for user core Jul 15 05:17:28.023682 systemd[1]: sshd@5-10.200.8.12:22-10.200.16.10:43526.service: Deactivated successfully. Jul 15 05:17:28.025214 systemd[1]: session-8.scope: Deactivated successfully. Jul 15 05:17:28.025922 systemd-logind[1877]: Session 8 logged out. Waiting for processes to exit. Jul 15 05:17:28.026912 systemd-logind[1877]: Removed session 8. Jul 15 05:17:28.141949 systemd[1]: Started sshd@6-10.200.8.12:22-10.200.16.10:43532.service - OpenSSH per-connection server daemon (10.200.16.10:43532). Jul 15 05:17:28.783281 sshd[2491]: Accepted publickey for core from 10.200.16.10 port 43532 ssh2: RSA SHA256:tFaO/Gkpdf2AjbVf3W+X+FNU/vH2JWmxNlepyUE0K5I Jul 15 05:17:28.784493 sshd-session[2491]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 05:17:28.788503 systemd-logind[1877]: New session 9 of user core. Jul 15 05:17:28.797007 systemd[1]: Started session-9.scope - Session 9 of User core. Jul 15 05:17:29.226195 sshd[2494]: Connection closed by 10.200.16.10 port 43532 Jul 15 05:17:29.226555 sshd-session[2491]: pam_unix(sshd:session): session closed for user core Jul 15 05:17:29.230032 systemd[1]: sshd@6-10.200.8.12:22-10.200.16.10:43532.service: Deactivated successfully. Jul 15 05:17:29.231567 systemd[1]: session-9.scope: Deactivated successfully. Jul 15 05:17:29.232247 systemd-logind[1877]: Session 9 logged out. Waiting for processes to exit. Jul 15 05:17:29.233139 systemd-logind[1877]: Removed session 9. Jul 15 05:17:31.291557 setup-nvidia[2499]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 15 05:17:31.291927 setup-nvidia[2499]: Dload Upload Total Spent Left Speed Jul 15 05:17:39.337706 systemd[1]: Started sshd@7-10.200.8.12:22-10.200.16.10:35064.service - OpenSSH per-connection server daemon (10.200.16.10:35064). Jul 15 05:17:39.988443 sshd[2501]: Accepted publickey for core from 10.200.16.10 port 35064 ssh2: RSA SHA256:tFaO/Gkpdf2AjbVf3W+X+FNU/vH2JWmxNlepyUE0K5I Jul 15 05:17:39.989674 sshd-session[2501]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 05:17:39.993883 systemd-logind[1877]: New session 10 of user core. Jul 15 05:17:40.005948 systemd[1]: Started session-10.scope - Session 10 of User core. Jul 15 05:17:40.431712 sshd[2504]: Connection closed by 10.200.16.10 port 35064 Jul 15 05:17:40.432078 sshd-session[2501]: pam_unix(sshd:session): session closed for user core Jul 15 05:17:40.435478 systemd[1]: sshd@7-10.200.8.12:22-10.200.16.10:35064.service: Deactivated successfully. Jul 15 05:17:40.437293 systemd[1]: session-10.scope: Deactivated successfully. Jul 15 05:17:40.438041 systemd-logind[1877]: Session 10 logged out. Waiting for processes to exit. Jul 15 05:17:40.438950 systemd-logind[1877]: Removed session 10. Jul 15 05:17:40.546156 systemd[1]: Started sshd@8-10.200.8.12:22-10.200.16.10:40488.service - OpenSSH per-connection server daemon (10.200.16.10:40488). Jul 15 05:17:41.176386 sshd[2510]: Accepted publickey for core from 10.200.16.10 port 40488 ssh2: RSA SHA256:tFaO/Gkpdf2AjbVf3W+X+FNU/vH2JWmxNlepyUE0K5I Jul 15 05:17:41.177578 sshd-session[2510]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 05:17:41.181799 systemd-logind[1877]: New session 11 of user core. Jul 15 05:17:41.190962 systemd[1]: Started session-11.scope - Session 11 of User core. Jul 15 05:17:41.625382 sshd[2513]: Connection closed by 10.200.16.10 port 40488 Jul 15 05:17:41.625710 sshd-session[2510]: pam_unix(sshd:session): session closed for user core Jul 15 05:17:41.629023 systemd[1]: sshd@8-10.200.8.12:22-10.200.16.10:40488.service: Deactivated successfully. Jul 15 05:17:41.630644 systemd[1]: session-11.scope: Deactivated successfully. Jul 15 05:17:41.631340 systemd-logind[1877]: Session 11 logged out. Waiting for processes to exit. Jul 15 05:17:41.632214 systemd-logind[1877]: Removed session 11. Jul 15 05:17:51.741669 systemd[1]: Started sshd@9-10.200.8.12:22-10.200.16.10:60292.service - OpenSSH per-connection server daemon (10.200.16.10:60292). Jul 15 05:17:52.371024 sshd[2519]: Accepted publickey for core from 10.200.16.10 port 60292 ssh2: RSA SHA256:tFaO/Gkpdf2AjbVf3W+X+FNU/vH2JWmxNlepyUE0K5I Jul 15 05:17:52.372232 sshd-session[2519]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 05:17:52.376247 systemd-logind[1877]: New session 12 of user core. Jul 15 05:17:52.386947 systemd[1]: Started session-12.scope - Session 12 of User core. Jul 15 05:17:52.820273 sshd[2522]: Connection closed by 10.200.16.10 port 60292 Jul 15 05:17:52.820637 sshd-session[2519]: pam_unix(sshd:session): session closed for user core Jul 15 05:17:52.824023 systemd[1]: sshd@9-10.200.8.12:22-10.200.16.10:60292.service: Deactivated successfully. Jul 15 05:17:52.825702 systemd[1]: session-12.scope: Deactivated successfully. Jul 15 05:17:52.826527 systemd-logind[1877]: Session 12 logged out. Waiting for processes to exit. Jul 15 05:17:52.827521 systemd-logind[1877]: Removed session 12. Jul 15 05:17:52.934344 systemd[1]: Started sshd@10-10.200.8.12:22-10.200.16.10:60294.service - OpenSSH per-connection server daemon (10.200.16.10:60294). Jul 15 05:17:53.565425 sshd[2528]: Accepted publickey for core from 10.200.16.10 port 60294 ssh2: RSA SHA256:tFaO/Gkpdf2AjbVf3W+X+FNU/vH2JWmxNlepyUE0K5I Jul 15 05:17:53.566952 sshd-session[2528]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 05:17:53.571181 systemd-logind[1877]: New session 13 of user core. Jul 15 05:17:53.581953 systemd[1]: Started session-13.scope - Session 13 of User core. Jul 15 05:17:54.010365 sshd[2531]: Connection closed by 10.200.16.10 port 60294 Jul 15 05:17:54.010685 sshd-session[2528]: pam_unix(sshd:session): session closed for user core Jul 15 05:17:54.014014 systemd[1]: sshd@10-10.200.8.12:22-10.200.16.10:60294.service: Deactivated successfully. Jul 15 05:17:54.015644 systemd[1]: session-13.scope: Deactivated successfully. Jul 15 05:17:54.016316 systemd-logind[1877]: Session 13 logged out. Waiting for processes to exit. Jul 15 05:17:54.017198 systemd-logind[1877]: Removed session 13. Jul 15 05:17:55.259381 setup-nvidia[2499]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 546M 0 49152 0 0 69684 0 2:17:05 --:--:-- 2:17:05 69620\u000d 1 546M 1 9392k 0 0 5206k 0 0:01:47 0:00:01 0:01:46 5206k\u000d 6 546M 6 34.2M 0 0 12.2M 0 0:00:44 0:00:02 0:00:42 12.2M\u000d 10 546M 10 59.4M 0 0 15.7M 0 0:00:34 0:00:03 0:00:31 15.7M\u000d 15 546M 15 84.6M 0 0 17.7M 0 0:00:30 0:00:04 0:00:26 17.7M\u000d 20 546M 20 110M 0 0 19.0M 0 0:00:28 0:00:05 0:00:23 21.7M\u000d 23 546M 23 128M 0 0 18.9M 0 0:00:28 0:00:06 0:00:22 24.0M\u000d 28 546M 28 153M 0 0 19.8M 0 0:00:27 0:00:07 0:00:20 24.0M\u000d 32 546M 32 178M 0 0 20.4M 0 0:00:26 0:00:08 0:00:18 24.0M\u000d 37 546M 37 203M 0 0 20.8M 0 0:00:26 0:00:09 0:00:17 23.9M\u000d 41 546M 41 228M 0 0 21.2M 0 0:00:25 0:00:10 0:00:15 23.8M\u000d 45 546M 45 251M 0 0 21.4M 0 0:00:25 0:00:11 0:00:14 24.8M\u000d 50 546M 50 275M 0 0 21.7M 0 0:00:25 0:00:12 0:00:13 24.7M\u000d 55 546M 55 302M 0 0 21.9M 0 0:00:24 0:00:13 0:00:11 24.4M\u000d 59 546M 59 326M 0 0 22.0M 0 0:00:24 0:00:14 0:00:10 24.3M\u000d 64 546M 64 350M 0 0 22.2M 0 0:00:24 0:00:15 0:00:09 24.2M\u000d 68 546M 68 375M 0 0 22.3M 0 0:00:24 0:00:16 0:00:08 24.5M\u000d 72 546M 72 397M 0 0 22.4M 0 0:00:24 0:00:17 0:00:07 24.0M\u000d 77 546M 77 422M 0 0 22.5M 0 0:00:24 0:00:18 0:00:06 24.3M\u000d 81 546M 81 446M 0 0 22.6M 0 0:00:24 0:00:19 0:00:05 24.4M\u000d 85 546M 85 468M 0 0 22.6M 0 0:00:24 0:00:20 0:00:04 23.7M\u000d 89 546M 89 491M 0 0 22.6M 0 0:00:24 0:00:21 0:00:03 23.5M\u000d 93 546M 93 513M 0 0 22.6M 0 0:00:24 0:00:22 0:00:02 23.5M\u000d 98 546M 98 539M 0 0 22.7M 0 0:00:24 0:00:23 0:00:01 23.5M\u000d100 546M 100 546M 0 0 22.8M 0 0:00:23 0:00:23 --:--:-- 23.5M Jul 15 05:18:04.130126 systemd[1]: Started sshd@11-10.200.8.12:22-10.200.16.10:46758.service - OpenSSH per-connection server daemon (10.200.16.10:46758). Jul 15 05:18:04.778646 sshd[2547]: Accepted publickey for core from 10.200.16.10 port 46758 ssh2: RSA SHA256:tFaO/Gkpdf2AjbVf3W+X+FNU/vH2JWmxNlepyUE0K5I Jul 15 05:18:04.784751 sshd-session[2547]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 05:18:04.791871 systemd-logind[1877]: New session 14 of user core. Jul 15 05:18:04.798006 systemd[1]: Started session-14.scope - Session 14 of User core. Jul 15 05:18:05.230864 sshd[2550]: Connection closed by 10.200.16.10 port 46758 Jul 15 05:18:05.232018 sshd-session[2547]: pam_unix(sshd:session): session closed for user core Jul 15 05:18:05.237363 systemd[1]: sshd@11-10.200.8.12:22-10.200.16.10:46758.service: Deactivated successfully. Jul 15 05:18:05.238926 systemd[1]: session-14.scope: Deactivated successfully. Jul 15 05:18:05.240202 systemd-logind[1877]: Session 14 logged out. Waiting for processes to exit. Jul 15 05:18:05.244619 systemd-logind[1877]: Removed session 14. Jul 15 05:18:05.345313 systemd[1]: Started sshd@12-10.200.8.12:22-10.200.16.10:46766.service - OpenSSH per-connection server daemon (10.200.16.10:46766). Jul 15 05:18:05.993794 sshd[2556]: Accepted publickey for core from 10.200.16.10 port 46766 ssh2: RSA SHA256:tFaO/Gkpdf2AjbVf3W+X+FNU/vH2JWmxNlepyUE0K5I Jul 15 05:18:05.995197 sshd-session[2556]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 05:18:06.003128 systemd-logind[1877]: New session 15 of user core. Jul 15 05:18:06.010231 systemd[1]: Started session-15.scope - Session 15 of User core. Jul 15 05:18:06.150051 setup-nvidia[1871]: Downloading NVIDIA 535.247.01 Driver Jul 15 05:18:06.154564 setup-nvidia[2561]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 15 05:18:06.154783 setup-nvidia[2561]: Dload Upload Total Spent Left Speed Jul 15 05:18:06.435921 sshd[2559]: Connection closed by 10.200.16.10 port 46766 Jul 15 05:18:06.436317 sshd-session[2556]: pam_unix(sshd:session): session closed for user core Jul 15 05:18:06.439802 systemd[1]: sshd@12-10.200.8.12:22-10.200.16.10:46766.service: Deactivated successfully. Jul 15 05:18:06.441383 systemd[1]: session-15.scope: Deactivated successfully. Jul 15 05:18:06.442070 systemd-logind[1877]: Session 15 logged out. Waiting for processes to exit. Jul 15 05:18:06.443015 systemd-logind[1877]: Removed session 15. Jul 15 05:18:08.447238 setup-nvidia[2561]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 19 326M 19 62.1M 0 0 75.5M 0 0:00:04 --:--:-- 0:00:04 75.5M\u000d 72 326M 72 236M 0 0 129M 0 0:00:02 0:00:01 0:00:01 129M\u000d100 326M 100 326M 0 0 142M 0 0:00:02 0:00:02 --:--:-- 142M Jul 15 05:18:08.503494 setup-nvidia[1871]: Extract the NVIDIA Driver Installer 535.247.01 Jul 15 05:18:08.503683 setup-nvidia[1871]: /opt/nvidia/workdir/nvidia-workdir / Jul 15 05:18:08.521031 setup-nvidia[2568]: Creating directory NVIDIA-Linux-x86_64-535.247.01 Jul 15 05:18:08.869005 setup-nvidia[2568]: Verifying archive integrity... OK Jul 15 05:18:09.559754 setup-nvidia[2568]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.247.01 Jul 15 05:18:11.339712 setup-nvidia[2594]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Jul 15 05:18:11.442228 setup-nvidia[1871]: / Jul 15 05:18:11.442470 setup-nvidia[1871]: Spawn system-nspawn container to install the NVIDIA drivers Jul 15 05:18:11.616412 sudo[2601]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4396.0.0+nightly-20250714-2100.bin --overlay=/usr/lib/modules::/usr/lib/modules --bind=/opt/nvidia/workdir/nvidia-workdir:/nvidia --bind=/usr/lib/nvidia/bin:/app/bin/ /app/bin/install-nvidia NVIDIA-Linux-x86_64-535.247.01 Jul 15 05:18:11.618113 sudo[2601]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 15 05:18:11.623911 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Jul 15 05:18:11.625058 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Jul 15 05:18:11.649908 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Jul 15 05:18:11.655803 systemd[1]: Starting user@0.service - User Manager for UID 0... Jul 15 05:18:11.684881 (systemd)[2604]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Jul 15 05:18:11.687162 systemd-logind[1877]: New session c3 of user root. Jul 15 05:18:11.868468 systemd[2604]: Queued start job for default target default.target. Jul 15 05:18:11.884912 systemd[2604]: Created slice app.slice - User Application Slice. Jul 15 05:18:11.884940 systemd[2604]: Reached target paths.target - Paths. Jul 15 05:18:11.884985 systemd[2604]: Reached target timers.target - Timers. Jul 15 05:18:11.886059 systemd[2604]: Starting dbus.socket - D-Bus User Message Bus Socket... Jul 15 05:18:11.896146 systemd[2604]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jul 15 05:18:11.896203 systemd[2604]: Reached target sockets.target - Sockets. Jul 15 05:18:11.896252 systemd[2604]: Reached target basic.target - Basic System. Jul 15 05:18:11.896298 systemd[2604]: Reached target default.target - Main User Target. Jul 15 05:18:11.896328 systemd[2604]: Startup finished in 203ms. Jul 15 05:18:11.896400 systemd[1]: Started user@0.service - User Manager for UID 0. Jul 15 05:18:11.902178 systemd[1]: Started session-c2.scope - Session c2 of User root. Jul 15 05:18:11.954856 kernel: loop6: detected capacity change from 0 to 12644352 Jul 15 05:18:11.963856 kernel: loop6: p9 Jul 15 05:18:12.002804 setup-nvidia[2613]: ░ Spawning container flatcardevelopercontainer-4396.0.0nightly-20250714-2100.bin on /opt/nvidia/workdir/flatcar_developer_container-4396.0.0+nightly-20250714-2100.bin. Jul 15 05:18:12.629288 kernel: EXT4-fs (loop6p9): write access unavailable, skipping orphan cleanup Jul 15 05:18:12.629394 kernel: EXT4-fs (loop6p9): mounted filesystem 972f2beb-d1b6-473e-a98a-ae044746a8ba ro without journal. Quota mode: none. Jul 15 05:18:16.553126 systemd[1]: Started sshd@13-10.200.8.12:22-10.200.16.10:43636.service - OpenSSH per-connection server daemon (10.200.16.10:43636). Jul 15 05:18:17.186293 sshd[3235]: Accepted publickey for core from 10.200.16.10 port 43636 ssh2: RSA SHA256:tFaO/Gkpdf2AjbVf3W+X+FNU/vH2JWmxNlepyUE0K5I Jul 15 05:18:17.187517 sshd-session[3235]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 05:18:17.192227 systemd-logind[1877]: New session 16 of user core. Jul 15 05:18:17.206971 systemd[1]: Started session-16.scope - Session 16 of User core. Jul 15 05:18:17.632876 sshd[3340]: Connection closed by 10.200.16.10 port 43636 Jul 15 05:18:17.633214 sshd-session[3235]: pam_unix(sshd:session): session closed for user core Jul 15 05:18:17.636541 systemd[1]: sshd@13-10.200.8.12:22-10.200.16.10:43636.service: Deactivated successfully. Jul 15 05:18:17.638162 systemd[1]: session-16.scope: Deactivated successfully. Jul 15 05:18:17.638824 systemd-logind[1877]: Session 16 logged out. Waiting for processes to exit. Jul 15 05:18:17.639811 systemd-logind[1877]: Removed session 16. Jul 15 05:18:17.748035 systemd[1]: Started sshd@14-10.200.8.12:22-10.200.16.10:43646.service - OpenSSH per-connection server daemon (10.200.16.10:43646). Jul 15 05:18:18.386676 sshd[3346]: Accepted publickey for core from 10.200.16.10 port 43646 ssh2: RSA SHA256:tFaO/Gkpdf2AjbVf3W+X+FNU/vH2JWmxNlepyUE0K5I Jul 15 05:18:18.387969 sshd-session[3346]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 05:18:18.392151 systemd-logind[1877]: New session 17 of user core. Jul 15 05:18:18.401958 systemd[1]: Started session-17.scope - Session 17 of User core. Jul 15 05:18:18.833652 sshd[3349]: Connection closed by 10.200.16.10 port 43646 Jul 15 05:18:18.834016 sshd-session[3346]: pam_unix(sshd:session): session closed for user core Jul 15 05:18:18.837427 systemd[1]: sshd@14-10.200.8.12:22-10.200.16.10:43646.service: Deactivated successfully. Jul 15 05:18:18.839006 systemd[1]: session-17.scope: Deactivated successfully. Jul 15 05:18:18.839653 systemd-logind[1877]: Session 17 logged out. Waiting for processes to exit. Jul 15 05:18:18.841914 systemd-logind[1877]: Removed session 17. Jul 15 05:18:28.957084 systemd[1]: Started sshd@15-10.200.8.12:22-10.200.16.10:59410.service - OpenSSH per-connection server daemon (10.200.16.10:59410). Jul 15 05:18:29.598445 sshd[6906]: Accepted publickey for core from 10.200.16.10 port 59410 ssh2: RSA SHA256:tFaO/Gkpdf2AjbVf3W+X+FNU/vH2JWmxNlepyUE0K5I Jul 15 05:18:29.599849 sshd-session[6906]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 05:18:29.607379 systemd-logind[1877]: New session 18 of user core. Jul 15 05:18:29.610988 systemd[1]: Started session-18.scope - Session 18 of User core. Jul 15 05:18:30.044072 sshd[7231]: Connection closed by 10.200.16.10 port 59410 Jul 15 05:18:30.045978 sshd-session[6906]: pam_unix(sshd:session): session closed for user core Jul 15 05:18:30.049900 systemd[1]: sshd@15-10.200.8.12:22-10.200.16.10:59410.service: Deactivated successfully. Jul 15 05:18:30.052442 systemd[1]: session-18.scope: Deactivated successfully. Jul 15 05:18:30.053990 systemd-logind[1877]: Session 18 logged out. Waiting for processes to exit. Jul 15 05:18:30.060105 systemd-logind[1877]: Removed session 18. Jul 15 05:18:30.156327 systemd[1]: Started sshd@16-10.200.8.12:22-10.200.16.10:59414.service - OpenSSH per-connection server daemon (10.200.16.10:59414). Jul 15 05:18:30.787871 sshd[7346]: Accepted publickey for core from 10.200.16.10 port 59414 ssh2: RSA SHA256:tFaO/Gkpdf2AjbVf3W+X+FNU/vH2JWmxNlepyUE0K5I Jul 15 05:18:30.790640 sshd-session[7346]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 05:18:30.798225 systemd-logind[1877]: New session 19 of user core. Jul 15 05:18:30.803526 systemd[1]: Started session-19.scope - Session 19 of User core. Jul 15 05:18:31.233092 sshd[7569]: Connection closed by 10.200.16.10 port 59414 Jul 15 05:18:31.524068 sshd-session[7346]: pam_unix(sshd:session): session closed for user core Jul 15 05:18:31.527960 systemd[1]: sshd@16-10.200.8.12:22-10.200.16.10:59414.service: Deactivated successfully. Jul 15 05:18:31.530404 systemd[1]: session-19.scope: Deactivated successfully. Jul 15 05:18:31.531865 systemd-logind[1877]: Session 19 logged out. Waiting for processes to exit. Jul 15 05:18:31.532897 systemd-logind[1877]: Removed session 19. Jul 15 05:18:41.353998 systemd[1]: Started sshd@17-10.200.8.12:22-10.200.16.10:36540.service - OpenSSH per-connection server daemon (10.200.16.10:36540). Jul 15 05:18:42.001588 sshd[10257]: Accepted publickey for core from 10.200.16.10 port 36540 ssh2: RSA SHA256:tFaO/Gkpdf2AjbVf3W+X+FNU/vH2JWmxNlepyUE0K5I Jul 15 05:18:42.003385 sshd-session[10257]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 05:18:42.011436 systemd-logind[1877]: New session 20 of user core. Jul 15 05:18:42.017996 systemd[1]: Started session-20.scope - Session 20 of User core. Jul 15 05:18:42.446311 sshd[10409]: Connection closed by 10.200.16.10 port 36540 Jul 15 05:18:42.446912 sshd-session[10257]: pam_unix(sshd:session): session closed for user core Jul 15 05:18:42.451648 systemd-logind[1877]: Session 20 logged out. Waiting for processes to exit. Jul 15 05:18:42.454209 systemd[1]: sshd@17-10.200.8.12:22-10.200.16.10:36540.service: Deactivated successfully. Jul 15 05:18:42.456269 systemd[1]: session-20.scope: Deactivated successfully. Jul 15 05:18:42.460659 systemd-logind[1877]: Removed session 20. Jul 15 05:18:42.561077 systemd[1]: Started sshd@18-10.200.8.12:22-10.200.16.10:36548.service - OpenSSH per-connection server daemon (10.200.16.10:36548). Jul 15 05:18:43.210805 sshd[10508]: Accepted publickey for core from 10.200.16.10 port 36548 ssh2: RSA SHA256:tFaO/Gkpdf2AjbVf3W+X+FNU/vH2JWmxNlepyUE0K5I Jul 15 05:18:43.212549 sshd-session[10508]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 05:18:43.218913 systemd-logind[1877]: New session 21 of user core. Jul 15 05:18:43.225172 systemd[1]: Started session-21.scope - Session 21 of User core. Jul 15 05:18:43.655981 sshd[10653]: Connection closed by 10.200.16.10 port 36548 Jul 15 05:18:43.658209 sshd-session[10508]: pam_unix(sshd:session): session closed for user core Jul 15 05:18:43.663065 systemd[1]: sshd@18-10.200.8.12:22-10.200.16.10:36548.service: Deactivated successfully. Jul 15 05:18:43.663557 systemd-logind[1877]: Session 21 logged out. Waiting for processes to exit. Jul 15 05:18:43.664983 systemd[1]: session-21.scope: Deactivated successfully. Jul 15 05:18:43.668117 systemd-logind[1877]: Removed session 21. Jul 15 05:18:53.774413 systemd[1]: Started sshd@19-10.200.8.12:22-10.200.16.10:37228.service - OpenSSH per-connection server daemon (10.200.16.10:37228). Jul 15 05:18:54.429467 sshd[11946]: Accepted publickey for core from 10.200.16.10 port 37228 ssh2: RSA SHA256:tFaO/Gkpdf2AjbVf3W+X+FNU/vH2JWmxNlepyUE0K5I Jul 15 05:18:54.433283 sshd-session[11946]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 05:18:54.439880 systemd-logind[1877]: New session 22 of user core. Jul 15 05:18:54.450924 systemd[1]: Started session-22.scope - Session 22 of User core. Jul 15 05:18:54.875927 sshd[11979]: Connection closed by 10.200.16.10 port 37228 Jul 15 05:18:54.876508 sshd-session[11946]: pam_unix(sshd:session): session closed for user core Jul 15 05:18:54.880142 systemd[1]: sshd@19-10.200.8.12:22-10.200.16.10:37228.service: Deactivated successfully. Jul 15 05:18:54.881700 systemd[1]: session-22.scope: Deactivated successfully. Jul 15 05:18:54.882044 systemd-logind[1877]: Session 22 logged out. Waiting for processes to exit. Jul 15 05:18:54.884264 systemd-logind[1877]: Removed session 22. Jul 15 05:18:54.987744 systemd[1]: Started sshd@20-10.200.8.12:22-10.200.16.10:37230.service - OpenSSH per-connection server daemon (10.200.16.10:37230). Jul 15 05:18:55.633508 sshd[12010]: Accepted publickey for core from 10.200.16.10 port 37230 ssh2: RSA SHA256:tFaO/Gkpdf2AjbVf3W+X+FNU/vH2JWmxNlepyUE0K5I Jul 15 05:18:55.635373 sshd-session[12010]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 05:18:55.643861 systemd-logind[1877]: New session 23 of user core. Jul 15 05:18:55.653078 systemd[1]: Started session-23.scope - Session 23 of User core. Jul 15 05:18:56.083618 sshd[12042]: Connection closed by 10.200.16.10 port 37230 Jul 15 05:18:56.084202 sshd-session[12010]: pam_unix(sshd:session): session closed for user core Jul 15 05:18:56.089719 systemd-logind[1877]: Session 23 logged out. Waiting for processes to exit. Jul 15 05:18:56.090723 systemd[1]: sshd@20-10.200.8.12:22-10.200.16.10:37230.service: Deactivated successfully. Jul 15 05:18:56.094533 systemd[1]: session-23.scope: Deactivated successfully. Jul 15 05:18:56.097397 systemd-logind[1877]: Removed session 23. Jul 15 05:19:06.200080 systemd[1]: Started sshd@21-10.200.8.12:22-10.200.16.10:55328.service - OpenSSH per-connection server daemon (10.200.16.10:55328). Jul 15 05:19:06.854928 sshd[12360]: Accepted publickey for core from 10.200.16.10 port 55328 ssh2: RSA SHA256:tFaO/Gkpdf2AjbVf3W+X+FNU/vH2JWmxNlepyUE0K5I Jul 15 05:19:06.856331 sshd-session[12360]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 05:19:06.862512 systemd-logind[1877]: New session 24 of user core. Jul 15 05:19:06.872073 systemd[1]: Started session-24.scope - Session 24 of User core. Jul 15 05:19:07.399909 sshd[12393]: Connection closed by 10.200.16.10 port 55328 Jul 15 05:19:07.401782 sshd-session[12360]: pam_unix(sshd:session): session closed for user core Jul 15 05:19:07.405462 systemd[1]: sshd@21-10.200.8.12:22-10.200.16.10:55328.service: Deactivated successfully. Jul 15 05:19:07.408609 systemd[1]: session-24.scope: Deactivated successfully. Jul 15 05:19:07.410009 systemd-logind[1877]: Session 24 logged out. Waiting for processes to exit. Jul 15 05:19:07.412317 systemd-logind[1877]: Removed session 24. Jul 15 05:19:07.592129 systemd[1]: Started sshd@22-10.200.8.12:22-10.200.16.10:55334.service - OpenSSH per-connection server daemon (10.200.16.10:55334). Jul 15 05:19:08.246467 sshd[12429]: Accepted publickey for core from 10.200.16.10 port 55334 ssh2: RSA SHA256:tFaO/Gkpdf2AjbVf3W+X+FNU/vH2JWmxNlepyUE0K5I Jul 15 05:19:08.248318 sshd-session[12429]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 05:19:08.255472 systemd-logind[1877]: New session 25 of user core. Jul 15 05:19:08.263446 systemd[1]: Started session-25.scope - Session 25 of User core. Jul 15 05:19:08.694411 sshd[12462]: Connection closed by 10.200.16.10 port 55334 Jul 15 05:19:08.695218 sshd-session[12429]: pam_unix(sshd:session): session closed for user core Jul 15 05:19:08.699014 systemd-logind[1877]: Session 25 logged out. Waiting for processes to exit. Jul 15 05:19:08.700484 systemd[1]: sshd@22-10.200.8.12:22-10.200.16.10:55334.service: Deactivated successfully. Jul 15 05:19:08.703605 systemd[1]: session-25.scope: Deactivated successfully. Jul 15 05:19:08.706687 systemd-logind[1877]: Removed session 25. Jul 15 05:19:18.815457 systemd[1]: Started sshd@23-10.200.8.12:22-10.200.16.10:43030.service - OpenSSH per-connection server daemon (10.200.16.10:43030). Jul 15 05:19:19.465939 sshd[12781]: Accepted publickey for core from 10.200.16.10 port 43030 ssh2: RSA SHA256:tFaO/Gkpdf2AjbVf3W+X+FNU/vH2JWmxNlepyUE0K5I Jul 15 05:19:19.467363 sshd-session[12781]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 05:19:19.475769 systemd-logind[1877]: New session 26 of user core. Jul 15 05:19:19.482077 systemd[1]: Started session-26.scope - Session 26 of User core. Jul 15 05:19:19.915806 sshd[12825]: Connection closed by 10.200.16.10 port 43030 Jul 15 05:19:19.916319 sshd-session[12781]: pam_unix(sshd:session): session closed for user core Jul 15 05:19:19.920364 systemd[1]: sshd@23-10.200.8.12:22-10.200.16.10:43030.service: Deactivated successfully. Jul 15 05:19:19.922948 systemd[1]: session-26.scope: Deactivated successfully. Jul 15 05:19:19.923630 systemd-logind[1877]: Session 26 logged out. Waiting for processes to exit. Jul 15 05:19:19.926796 systemd-logind[1877]: Removed session 26. Jul 15 05:19:20.031101 systemd[1]: Started sshd@24-10.200.8.12:22-10.200.16.10:43032.service - OpenSSH per-connection server daemon (10.200.16.10:43032). Jul 15 05:19:20.660436 sshd[12858]: Accepted publickey for core from 10.200.16.10 port 43032 ssh2: RSA SHA256:tFaO/Gkpdf2AjbVf3W+X+FNU/vH2JWmxNlepyUE0K5I Jul 15 05:19:20.661712 sshd-session[12858]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 05:19:20.666038 systemd-logind[1877]: New session 27 of user core. Jul 15 05:19:20.685074 systemd[1]: Started session-27.scope - Session 27 of User core. Jul 15 05:19:21.103714 sshd[12861]: Connection closed by 10.200.16.10 port 43032 Jul 15 05:19:21.104126 sshd-session[12858]: pam_unix(sshd:session): session closed for user core Jul 15 05:19:21.107698 systemd[1]: sshd@24-10.200.8.12:22-10.200.16.10:43032.service: Deactivated successfully. Jul 15 05:19:21.109311 systemd[1]: session-27.scope: Deactivated successfully. Jul 15 05:19:21.109996 systemd-logind[1877]: Session 27 logged out. Waiting for processes to exit. Jul 15 05:19:21.111242 systemd-logind[1877]: Removed session 27. Jul 15 05:19:28.408009 setup-nvidia[2613]: \u000d Jul 15 05:19:28.408009 setup-nvidia[2613]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Jul 15 05:19:28.408009 setup-nvidia[2613]: frequently when this kernel module was built against the wrong or Jul 15 05:19:28.408009 setup-nvidia[2613]: improperly configured kernel sources, with a version of gcc that differs Jul 15 05:19:28.408009 setup-nvidia[2613]: from the one used to build the target kernel, or if another driver, such Jul 15 05:19:28.408009 setup-nvidia[2613]: as nouveau, is present and prevents the NVIDIA kernel module from Jul 15 05:19:28.408009 setup-nvidia[2613]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Jul 15 05:19:28.408009 setup-nvidia[2613]: installed in this system is supported by this NVIDIA Linux graphics Jul 15 05:19:28.408009 setup-nvidia[2613]: driver release. Jul 15 05:19:28.408009 setup-nvidia[2613]: \u000d Jul 15 05:19:28.408009 setup-nvidia[2613]: Please see the log entries 'Kernel module load error' and 'Kernel Jul 15 05:19:28.408009 setup-nvidia[2613]: messages' at the end of the file Jul 15 05:19:28.408009 setup-nvidia[2613]: '/nvidia/NVIDIA-Linux-x86_64-535.247.01/nvidia-installer.log' for more Jul 15 05:19:28.408009 setup-nvidia[2613]: information. Jul 15 05:19:28.408009 setup-nvidia[2613]: \u000d Jul 15 05:19:28.422396 setup-nvidia[2613]: dmesg: read kernel buffer failed: Operation not permitted Jul 15 05:19:28.440433 setup-nvidia[2613]: \u000d Jul 15 05:19:28.440433 setup-nvidia[2613]: ERROR: Installation has failed. Please see the file Jul 15 05:19:28.440433 setup-nvidia[2613]: '/nvidia/NVIDIA-Linux-x86_64-535.247.01/nvidia-installer.log' for Jul 15 05:19:28.440433 setup-nvidia[2613]: details. You may find suggestions on fixing installation problems in Jul 15 05:19:28.440433 setup-nvidia[2613]: the README available on the Linux driver download page at Jul 15 05:19:28.440433 setup-nvidia[2613]: www.nvidia.com. Jul 15 05:19:28.440433 setup-nvidia[2613]: \u000d Jul 15 05:19:28.441067 setup-nvidia[2613]: Last 50 lines of nvidia-installer.log: Jul 15 05:19:28.441837 setup-nvidia[2613]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039045rm+0x53: 'naked' return found in MITIGATION_RETHUNK build Jul 15 05:19:28.441837 setup-nvidia[2613]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039042rm+0x90: 'naked' return found in MITIGATION_RETHUNK build Jul 15 05:19:28.441837 setup-nvidia[2613]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039035rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build Jul 15 05:19:28.441837 setup-nvidia[2613]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039091rm+0x93: 'naked' return found in MITIGATION_RETHUNK build Jul 15 05:19:28.441837 setup-nvidia[2613]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039087rm+0x36: 'naked' return found in MITIGATION_RETHUNK build Jul 15 05:19:28.441837 setup-nvidia[2613]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039086rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build Jul 15 05:19:28.441837 setup-nvidia[2613]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039088rm+0x77: 'naked' return found in MITIGATION_RETHUNK build Jul 15 05:19:28.441837 setup-nvidia[2613]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039032rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build Jul 15 05:19:28.441837 setup-nvidia[2613]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv030603rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build Jul 15 05:19:28.441837 setup-nvidia[2613]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv030602rm+0x27: 'naked' return found in MITIGATION_RETHUNK build Jul 15 05:19:28.441837 setup-nvidia[2613]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv030606rm+0x37: 'naked' return found in MITIGATION_RETHUNK build Jul 15 05:19:28.441837 setup-nvidia[2613]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv030605rm+0x24: 'naked' return found in MITIGATION_RETHUNK build Jul 15 05:19:28.441837 setup-nvidia[2613]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv030604rm+0xca: 'naked' return found in MITIGATION_RETHUNK build Jul 15 05:19:28.441837 setup-nvidia[2613]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv030607rm+0x193: 'naked' return found in MITIGATION_RETHUNK build Jul 15 05:19:28.441837 setup-nvidia[2613]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv030609rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build Jul 15 05:19:28.441837 setup-nvidia[2613]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv011864rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build Jul 15 05:19:28.441837 setup-nvidia[2613]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039399rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build Jul 15 05:19:28.441837 setup-nvidia[2613]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/Module.symvers Jul 15 05:19:28.441837 setup-nvidia[2613]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.mod.o Jul 15 05:19:28.441837 setup-nvidia[2613]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/.module-common.o Jul 15 05:19:28.441837 setup-nvidia[2613]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-uvm.mod.o Jul 15 05:19:28.441837 setup-nvidia[2613]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-modeset.mod.o Jul 15 05:19:28.441837 setup-nvidia[2613]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-drm.mod.o Jul 15 05:19:28.441837 setup-nvidia[2613]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-peermem.mod.o Jul 15 05:19:28.441837 setup-nvidia[2613]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.ko Jul 15 05:19:28.441837 setup-nvidia[2613]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-uvm.ko Jul 15 05:19:28.441837 setup-nvidia[2613]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-modeset.ko Jul 15 05:19:28.441837 setup-nvidia[2613]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-drm.ko Jul 15 05:19:28.441837 setup-nvidia[2613]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-peermem.ko Jul 15 05:19:28.441837 setup-nvidia[2613]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-peermem.ko Jul 15 05:19:28.441837 setup-nvidia[2613]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-peermem.ko due to unavailability of vmlinux Jul 15 05:19:28.441837 setup-nvidia[2613]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-modeset.ko Jul 15 05:19:28.441837 setup-nvidia[2613]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-modeset.ko due to unavailability of vmlinux Jul 15 05:19:28.441837 setup-nvidia[2613]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-drm.ko Jul 15 05:19:28.441837 setup-nvidia[2613]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-drm.ko due to unavailability of vmlinux Jul 15 05:19:28.441837 setup-nvidia[2613]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-uvm.ko Jul 15 05:19:28.442672 setup-nvidia[2613]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-uvm.ko due to unavailability of vmlinux Jul 15 05:19:28.442672 setup-nvidia[2613]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.ko Jul 15 05:19:28.442672 setup-nvidia[2613]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.ko due to unavailability of vmlinux Jul 15 05:19:28.442672 setup-nvidia[2613]: make[2]: Leaving directory '/usr/lib/modules/6.12.36-flatcar/build' Jul 15 05:19:28.442672 setup-nvidia[2613]: make[1]: Leaving directory '/usr/lib/modules/6.12.36-flatcar/source' Jul 15 05:19:28.442672 setup-nvidia[2613]: -> done. Jul 15 05:19:28.442672 setup-nvidia[2613]: -> Kernel module compilation complete. Jul 15 05:19:28.442672 setup-nvidia[2613]: -> Unable to determine if Secure Boot is enabled: No such file or directory Jul 15 05:19:28.442672 setup-nvidia[2613]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most frequently when this kernel module was built against the wrong or improperly configured kernel sources, with a version of gcc that differs from the one used to build the target kernel, or if another driver, such as nouveau, is present and prevents the NVIDIA kernel module from obtaining ownership of the NVIDIA device(s), or no NVIDIA device installed in this system is supported by this NVIDIA Linux graphics driver release. Jul 15 05:19:28.442672 setup-nvidia[2613]: \u000d Jul 15 05:19:28.442672 setup-nvidia[2613]: Please see the log entries 'Kernel module load error' and 'Kernel messages' at the end of the file '/nvidia/NVIDIA-Linux-x86_64-535.247.01/nvidia-installer.log' for more information. Jul 15 05:19:28.442672 setup-nvidia[2613]: -> Kernel module load error: Operation not permitted Jul 15 05:19:28.442672 setup-nvidia[2613]: -> Kernel messages: Jul 15 05:19:28.442672 setup-nvidia[2613]: ERROR: Installation has failed. Please see the file '/nvidia/NVIDIA-Linux-x86_64-535.247.01/nvidia-installer.log' for details. You may find suggestions on fixing installation problems in the README available on the Linux driver download page at www.nvidia.com. Jul 15 05:19:31.215331 systemd[1]: Started sshd@25-10.200.8.12:22-10.200.16.10:41038.service - OpenSSH per-connection server daemon (10.200.16.10:41038). Jul 15 05:19:31.853921 sshd[13047]: Accepted publickey for core from 10.200.16.10 port 41038 ssh2: RSA SHA256:tFaO/Gkpdf2AjbVf3W+X+FNU/vH2JWmxNlepyUE0K5I Jul 15 05:19:31.855191 sshd-session[13047]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 05:19:31.859512 systemd-logind[1877]: New session 28 of user core. Jul 15 05:19:31.875962 systemd[1]: Started session-28.scope - Session 28 of User core. Jul 15 05:19:32.298213 sshd[13050]: Connection closed by 10.200.16.10 port 41038 Jul 15 05:19:32.298505 sshd-session[13047]: pam_unix(sshd:session): session closed for user core Jul 15 05:19:32.302071 systemd[1]: sshd@25-10.200.8.12:22-10.200.16.10:41038.service: Deactivated successfully. Jul 15 05:19:32.303675 systemd[1]: session-28.scope: Deactivated successfully. Jul 15 05:19:32.304344 systemd-logind[1877]: Session 28 logged out. Waiting for processes to exit. Jul 15 05:19:32.305282 systemd-logind[1877]: Removed session 28. Jul 15 05:19:32.419787 systemd[1]: Started sshd@26-10.200.8.12:22-10.200.16.10:41040.service - OpenSSH per-connection server daemon (10.200.16.10:41040). Jul 15 05:19:33.048184 sshd[13056]: Accepted publickey for core from 10.200.16.10 port 41040 ssh2: RSA SHA256:tFaO/Gkpdf2AjbVf3W+X+FNU/vH2JWmxNlepyUE0K5I Jul 15 05:19:33.049419 sshd-session[13056]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 05:19:33.053631 systemd-logind[1877]: New session 29 of user core. Jul 15 05:19:33.063955 systemd[1]: Started session-29.scope - Session 29 of User core. Jul 15 05:19:33.491547 sshd[13059]: Connection closed by 10.200.16.10 port 41040 Jul 15 05:19:33.491899 sshd-session[13056]: pam_unix(sshd:session): session closed for user core Jul 15 05:19:33.494672 systemd[1]: sshd@26-10.200.8.12:22-10.200.16.10:41040.service: Deactivated successfully. Jul 15 05:19:33.496364 systemd[1]: session-29.scope: Deactivated successfully. Jul 15 05:19:33.497541 systemd-logind[1877]: Session 29 logged out. Waiting for processes to exit. Jul 15 05:19:33.498686 systemd-logind[1877]: Removed session 29. Jul 15 05:19:43.608483 systemd[1]: Started sshd@27-10.200.8.12:22-10.200.16.10:43452.service - OpenSSH per-connection server daemon (10.200.16.10:43452). Jul 15 05:19:44.237355 sshd[13066]: Accepted publickey for core from 10.200.16.10 port 43452 ssh2: RSA SHA256:tFaO/Gkpdf2AjbVf3W+X+FNU/vH2JWmxNlepyUE0K5I Jul 15 05:19:44.238651 sshd-session[13066]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 05:19:44.243109 systemd-logind[1877]: New session 30 of user core. Jul 15 05:19:44.258964 systemd[1]: Started session-30.scope - Session 30 of User core. Jul 15 05:19:44.679990 sshd[13069]: Connection closed by 10.200.16.10 port 43452 Jul 15 05:19:44.680373 sshd-session[13066]: pam_unix(sshd:session): session closed for user core Jul 15 05:19:44.683878 systemd[1]: sshd@27-10.200.8.12:22-10.200.16.10:43452.service: Deactivated successfully. Jul 15 05:19:44.685425 systemd[1]: session-30.scope: Deactivated successfully. Jul 15 05:19:44.686140 systemd-logind[1877]: Session 30 logged out. Waiting for processes to exit. Jul 15 05:19:44.687105 systemd-logind[1877]: Removed session 30. Jul 15 05:19:44.802076 systemd[1]: Started sshd@28-10.200.8.12:22-10.200.16.10:43468.service - OpenSSH per-connection server daemon (10.200.16.10:43468). Jul 15 05:19:45.431782 sshd[13075]: Accepted publickey for core from 10.200.16.10 port 43468 ssh2: RSA SHA256:tFaO/Gkpdf2AjbVf3W+X+FNU/vH2JWmxNlepyUE0K5I Jul 15 05:19:45.433111 sshd-session[13075]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 05:19:45.444369 systemd-logind[1877]: New session 31 of user core. Jul 15 05:19:45.460980 systemd[1]: Started session-31.scope - Session 31 of User core. Jul 15 05:19:45.875815 sshd[13078]: Connection closed by 10.200.16.10 port 43468 Jul 15 05:19:45.876202 sshd-session[13075]: pam_unix(sshd:session): session closed for user core Jul 15 05:19:45.879617 systemd[1]: sshd@28-10.200.8.12:22-10.200.16.10:43468.service: Deactivated successfully. Jul 15 05:19:45.881176 systemd[1]: session-31.scope: Deactivated successfully. Jul 15 05:19:45.881883 systemd-logind[1877]: Session 31 logged out. Waiting for processes to exit. Jul 15 05:19:45.882760 systemd-logind[1877]: Removed session 31. Jul 15 05:19:47.068859 kernel: EXT4-fs (loop6p9): unmounting filesystem 972f2beb-d1b6-473e-a98a-ae044746a8ba. Jul 15 05:19:47.069374 setup-nvidia[2613]: Container flatcardevelopercontainer-4396.0.0nightly-20250714-2100.bin exited successfully. Jul 15 05:19:47.071753 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4396.0.0nightly\x2d20250714\x2d2100.bin.mount: Deactivated successfully. Jul 15 05:19:47.124636 sudo[2601]: pam_unix(sudo:session): session closed for user root Jul 15 05:19:47.126551 systemd[1]: session-c2.scope: Deactivated successfully. Jul 15 05:19:47.126819 systemd[1]: session-c2.scope: Consumed 5min 12.825s CPU time, 889M memory peak. Jul 15 05:19:47.537557 setup-nvidia[1871]: /opt/nvidia / Jul 15 05:19:47.538491 setup-nvidia[1871]: / Jul 15 05:19:47.547874 kernel: IPMI message handler: version 39.2 Jul 15 05:19:47.550852 kernel: ipmi device interface Jul 15 05:19:47.563554 setup-nvidia[1871]: /opt/nvidia/current/usr/lib/modules/6.12.36-flatcar/video / Jul 15 05:19:47.616724 kernel: nvidia: loading out-of-tree module taints kernel. Jul 15 05:19:47.616869 kernel: nvidia: module license 'NVIDIA' taints kernel. Jul 15 05:19:47.616893 kernel: Disabling lock debugging due to kernel taint Jul 15 05:19:47.624087 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Jul 15 05:19:47.624131 kernel: nvidia: module license taints kernel. Jul 15 05:19:48.010860 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 Jul 15 05:19:48.413090 kernel: Jul 15 05:19:48.413227 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Jul 15 05:19:48.620857 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.247.01 Wed Mar 26 11:50:32 UTC 2025 Jul 15 05:19:48.641851 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.247.01 Wed Mar 26 06:12:31 UTC 2025 Jul 15 05:19:48.661861 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Jul 15 05:19:48.708867 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. Jul 15 05:19:48.708977 setup-nvidia[1871]: / Jul 15 05:19:48.723020 setup-nvidia[13117]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jul 15 05:19:50.463418 setup-nvidia[13134]: Tue Jul 15 05:19:50 2025 Jul 15 05:19:50.463418 setup-nvidia[13134]: +---------------------------------------------------------------------------------------+ Jul 15 05:19:50.463418 setup-nvidia[13134]: | NVIDIA-SMI 535.247.01 Driver Version: 535.247.01 CUDA Version: 12.2 | Jul 15 05:19:50.463418 setup-nvidia[13134]: |-----------------------------------------+----------------------+----------------------+ Jul 15 05:19:50.463418 setup-nvidia[13134]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Jul 15 05:19:50.463418 setup-nvidia[13134]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Jul 15 05:19:50.463418 setup-nvidia[13134]: | | | MIG M. | Jul 15 05:19:50.463418 setup-nvidia[13134]: |=========================================+======================+======================| Jul 15 05:19:50.467230 setup-nvidia[13134]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Jul 15 05:19:50.467230 setup-nvidia[13134]: | N/A 24C P0 37W / 250W | 0MiB / 16384MiB | 0% Default | Jul 15 05:19:50.467230 setup-nvidia[13134]: | | | N/A | Jul 15 05:19:50.467230 setup-nvidia[13134]: +-----------------------------------------+----------------------+----------------------+ Jul 15 05:19:50.467230 setup-nvidia[13134]: Jul 15 05:19:50.467230 setup-nvidia[13134]: +---------------------------------------------------------------------------------------+ Jul 15 05:19:50.467230 setup-nvidia[13134]: | Processes: | Jul 15 05:19:50.467230 setup-nvidia[13134]: | GPU GI CI PID Type Process name GPU Memory | Jul 15 05:19:50.467230 setup-nvidia[13134]: | ID ID Usage | Jul 15 05:19:50.467230 setup-nvidia[13134]: |=======================================================================================| Jul 15 05:19:50.467478 setup-nvidia[13134]: | No running processes found | Jul 15 05:19:50.467478 setup-nvidia[13134]: +---------------------------------------------------------------------------------------+ Jul 15 05:19:50.845865 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Jul 15 05:19:50.850792 systemd[1]: Reached target multi-user.target - Multi-User System. Jul 15 05:19:50.855381 systemd[1]: Startup finished in 764ms (firmware) + 28.428s (loader) + 3.887s (kernel) + 12.049s (initrd) + 3min 33.100s (userspace) = 4min 18.230s. Jul 15 05:19:55.992076 systemd[1]: Started sshd@29-10.200.8.12:22-10.200.16.10:54256.service - OpenSSH per-connection server daemon (10.200.16.10:54256). Jul 15 05:19:56.635341 sshd[13141]: Accepted publickey for core from 10.200.16.10 port 54256 ssh2: RSA SHA256:tFaO/Gkpdf2AjbVf3W+X+FNU/vH2JWmxNlepyUE0K5I Jul 15 05:19:56.636546 sshd-session[13141]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 05:19:56.640802 systemd-logind[1877]: New session 32 of user core. Jul 15 05:19:56.655069 systemd[1]: Started session-32.scope - Session 32 of User core. Jul 15 05:19:57.079685 sshd[13144]: Connection closed by 10.200.16.10 port 54256 Jul 15 05:19:57.080100 sshd-session[13141]: pam_unix(sshd:session): session closed for user core Jul 15 05:19:57.083576 systemd[1]: sshd@29-10.200.8.12:22-10.200.16.10:54256.service: Deactivated successfully. Jul 15 05:19:57.085158 systemd[1]: session-32.scope: Deactivated successfully. Jul 15 05:19:57.085818 systemd-logind[1877]: Session 32 logged out. Waiting for processes to exit. Jul 15 05:19:57.086844 systemd-logind[1877]: Removed session 32. Jul 15 05:19:57.190354 systemd[1]: Started sshd@30-10.200.8.12:22-10.200.16.10:54264.service - OpenSSH per-connection server daemon (10.200.16.10:54264). Jul 15 05:19:57.191308 systemd[1]: Stopping user@0.service - User Manager for UID 0... Jul 15 05:19:57.192056 systemd[2604]: Activating special unit exit.target... Jul 15 05:19:57.192130 systemd[2604]: Stopped target default.target - Main User Target. Jul 15 05:19:57.192169 systemd[2604]: Stopped target basic.target - Basic System. Jul 15 05:19:57.192199 systemd[2604]: Stopped target paths.target - Paths. Jul 15 05:19:57.192225 systemd[2604]: Stopped target sockets.target - Sockets. Jul 15 05:19:57.192250 systemd[2604]: Stopped target timers.target - Timers. Jul 15 05:19:57.192572 systemd[2604]: Closed dbus.socket - D-Bus User Message Bus Socket. Jul 15 05:19:57.192775 systemd[2604]: Removed slice app.slice - User Application Slice. Jul 15 05:19:57.192806 systemd[2604]: Reached target shutdown.target - Shutdown. Jul 15 05:19:57.192960 systemd[2604]: Finished systemd-exit.service - Exit the Session. Jul 15 05:19:57.192995 systemd[2604]: Reached target exit.target - Exit the Session. Jul 15 05:19:57.201356 (sd-pam)[2605]: pam_unix(systemd-user:session): session closed for user root Jul 15 05:19:57.202436 systemd[1]: user@0.service: Deactivated successfully. Jul 15 05:19:57.202785 systemd[1]: Stopped user@0.service - User Manager for UID 0. Jul 15 05:19:57.204865 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Jul 15 05:19:57.242006 systemd[1]: run-user-0.mount: Deactivated successfully. Jul 15 05:19:57.242817 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Jul 15 05:19:57.243017 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Jul 15 05:19:57.244460 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Jul 15 05:19:57.244548 systemd[1]: user-0.slice: Consumed 5min 13.041s CPU time, 895.1M memory peak. Jul 15 05:19:57.245037 systemd-logind[1877]: Removed session c3. Jul 15 05:19:57.823565 sshd[13150]: Accepted publickey for core from 10.200.16.10 port 54264 ssh2: RSA SHA256:tFaO/Gkpdf2AjbVf3W+X+FNU/vH2JWmxNlepyUE0K5I Jul 15 05:19:57.824820 sshd-session[13150]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 05:19:57.829080 systemd-logind[1877]: New session 33 of user core. Jul 15 05:19:57.839972 systemd[1]: Started session-33.scope - Session 33 of User core. Jul 15 05:19:58.275418 sshd[13157]: Connection closed by 10.200.16.10 port 54264 Jul 15 05:19:58.275708 sshd-session[13150]: pam_unix(sshd:session): session closed for user core Jul 15 05:19:58.279226 systemd[1]: sshd@30-10.200.8.12:22-10.200.16.10:54264.service: Deactivated successfully. Jul 15 05:19:58.281019 systemd[1]: session-33.scope: Deactivated successfully. Jul 15 05:19:58.281677 systemd-logind[1877]: Session 33 logged out. Waiting for processes to exit. Jul 15 05:19:58.282615 systemd-logind[1877]: Removed session 33. Jul 15 05:19:58.390011 systemd[1]: Started sshd@31-10.200.8.12:22-10.200.16.10:54268.service - OpenSSH per-connection server daemon (10.200.16.10:54268). Jul 15 05:19:59.037330 sshd[13163]: Accepted publickey for core from 10.200.16.10 port 54268 ssh2: RSA SHA256:tFaO/Gkpdf2AjbVf3W+X+FNU/vH2JWmxNlepyUE0K5I Jul 15 05:20:00.395059 sshd-session[13163]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 05:20:00.399328 systemd-logind[1877]: New session 34 of user core. Jul 15 05:20:00.409972 systemd[1]: Started session-34.scope - Session 34 of User core. Jul 15 05:20:00.758207 sshd[13166]: Connection closed by 10.200.16.10 port 54268 Jul 15 05:20:00.758524 sshd-session[13163]: pam_unix(sshd:session): session closed for user core Jul 15 05:20:00.761569 systemd[1]: sshd@31-10.200.8.12:22-10.200.16.10:54268.service: Deactivated successfully. Jul 15 05:20:00.763184 systemd[1]: session-34.scope: Deactivated successfully. Jul 15 05:20:00.763817 systemd-logind[1877]: Session 34 logged out. Waiting for processes to exit. Jul 15 05:20:00.765186 systemd-logind[1877]: Removed session 34. Jul 15 05:20:00.888057 systemd[1]: Started sshd@32-10.200.8.12:22-10.200.16.10:42806.service - OpenSSH per-connection server daemon (10.200.16.10:42806). Jul 15 05:20:01.535493 sshd[13172]: Accepted publickey for core from 10.200.16.10 port 42806 ssh2: RSA SHA256:tFaO/Gkpdf2AjbVf3W+X+FNU/vH2JWmxNlepyUE0K5I Jul 15 05:20:01.536706 sshd-session[13172]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 05:20:01.542179 systemd-logind[1877]: New session 35 of user core. Jul 15 05:20:01.561952 systemd[1]: Started session-35.scope - Session 35 of User core. Jul 15 05:20:02.000211 sshd[13175]: Connection closed by 10.200.16.10 port 42806 Jul 15 05:20:02.000585 sshd-session[13172]: pam_unix(sshd:session): session closed for user core Jul 15 05:20:02.004154 systemd[1]: sshd@32-10.200.8.12:22-10.200.16.10:42806.service: Deactivated successfully. Jul 15 05:20:02.005773 systemd[1]: session-35.scope: Deactivated successfully. Jul 15 05:20:02.006477 systemd-logind[1877]: Session 35 logged out. Waiting for processes to exit. Jul 15 05:20:02.007426 systemd-logind[1877]: Removed session 35. Jul 15 05:20:02.115182 systemd[1]: Started sshd@33-10.200.8.12:22-10.200.16.10:42814.service - OpenSSH per-connection server daemon (10.200.16.10:42814). Jul 15 05:20:02.742237 sshd[13181]: Accepted publickey for core from 10.200.16.10 port 42814 ssh2: RSA SHA256:tFaO/Gkpdf2AjbVf3W+X+FNU/vH2JWmxNlepyUE0K5I Jul 15 05:20:02.743409 sshd-session[13181]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 15 05:20:02.747518 systemd-logind[1877]: New session 36 of user core. Jul 15 05:20:02.769954 systemd[1]: Started session-36.scope - Session 36 of User core. Jul 15 05:20:04.643486 sshd[13184]: Connection closed by 10.200.16.10 port 42814 Jul 15 05:20:04.643891 sshd-session[13181]: pam_unix(sshd:session): session closed for user core Jul 15 05:20:04.647545 systemd[1]: sshd@33-10.200.8.12:22-10.200.16.10:42814.service: Deactivated successfully. Jul 15 05:20:04.649704 systemd[1]: session-36.scope: Deactivated successfully. Jul 15 05:20:04.649959 systemd[1]: session-36.scope: Consumed 1.395s CPU time, 14.2M memory peak. Jul 15 05:20:04.650519 systemd-logind[1877]: Session 36 logged out. Waiting for processes to exit. Jul 15 05:20:04.651403 systemd-logind[1877]: Removed session 36.