Jul 7 00:13:01.230740 kernel: Linux version 6.12.35-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.44 p1) 2.44.0) #1 SMP PREEMPT_DYNAMIC Sun Jul 6 21:58:13 -00 2025 Jul 7 00:13:01.230769 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=e91aabf5a2d4674d97b8508f9502216224d5fb9433440e4c8f906b950e21abf8 Jul 7 00:13:01.230779 kernel: BIOS-provided physical RAM map: Jul 7 00:13:01.230786 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Jul 7 00:13:01.230792 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Jul 7 00:13:01.230799 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Jul 7 00:13:01.230807 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Jul 7 00:13:01.230816 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Jul 7 00:13:01.230823 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Jul 7 00:13:01.230830 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Jul 7 00:13:01.230837 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Jul 7 00:13:01.230844 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Jul 7 00:13:01.230851 kernel: printk: legacy bootconsole [earlyser0] enabled Jul 7 00:13:01.230858 kernel: NX (Execute Disable) protection: active Jul 7 00:13:01.230869 kernel: APIC: Static calls initialized Jul 7 00:13:01.230876 kernel: efi: EFI v2.7 by Microsoft Jul 7 00:13:01.230884 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Jul 7 00:13:01.230892 kernel: random: crng init done Jul 7 00:13:01.230900 kernel: secureboot: Secure boot disabled Jul 7 00:13:01.230907 kernel: SMBIOS 3.1.0 present. Jul 7 00:13:01.230915 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Jul 7 00:13:01.230923 kernel: DMI: Memory slots populated: 3/3 Jul 7 00:13:01.230930 kernel: Hypervisor detected: Microsoft Hyper-V Jul 7 00:13:01.230938 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Jul 7 00:13:01.230948 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Jul 7 00:13:01.230956 kernel: Hyper-V: Using hypercall for remote TLB flush Jul 7 00:13:01.230964 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Jul 7 00:13:01.230971 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Jul 7 00:13:01.230979 kernel: tsc: Marking TSC unstable due to running on Hyper-V Jul 7 00:13:01.230987 kernel: tsc: Detected 2593.990 MHz processor Jul 7 00:13:01.230995 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Jul 7 00:13:01.231003 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Jul 7 00:13:01.231022 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Jul 7 00:13:01.231034 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Jul 7 00:13:01.231042 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Jul 7 00:13:01.231050 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Jul 7 00:13:01.231057 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Jul 7 00:13:01.231065 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Jul 7 00:13:01.231073 kernel: Using GB pages for direct mapping Jul 7 00:13:01.231080 kernel: ACPI: Early table checksum verification disabled Jul 7 00:13:01.231092 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Jul 7 00:13:01.231102 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 7 00:13:01.231111 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 7 00:13:01.231119 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Jul 7 00:13:01.231127 kernel: ACPI: FACS 0x000000003FFFE000 000040 Jul 7 00:13:01.231135 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 7 00:13:01.231143 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 7 00:13:01.231154 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 7 00:13:01.231162 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 7 00:13:01.231170 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 7 00:13:01.231178 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 7 00:13:01.231187 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 7 00:13:01.231195 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Jul 7 00:13:01.231203 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Jul 7 00:13:01.231211 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Jul 7 00:13:01.231221 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Jul 7 00:13:01.231229 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Jul 7 00:13:01.231237 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Jul 7 00:13:01.231246 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Jul 7 00:13:01.231254 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Jul 7 00:13:01.231262 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Jul 7 00:13:01.231270 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Jul 7 00:13:01.231278 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Jul 7 00:13:01.231286 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Jul 7 00:13:01.231297 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Jul 7 00:13:01.231308 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Jul 7 00:13:01.231316 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Jul 7 00:13:01.231324 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Jul 7 00:13:01.231332 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Jul 7 00:13:01.231340 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Jul 7 00:13:01.231349 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] Jul 7 00:13:01.231357 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] Jul 7 00:13:01.231365 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] Jul 7 00:13:01.231376 kernel: Zone ranges: Jul 7 00:13:01.231384 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Jul 7 00:13:01.231392 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Jul 7 00:13:01.231400 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Jul 7 00:13:01.231408 kernel: Device empty Jul 7 00:13:01.231416 kernel: Movable zone start for each node Jul 7 00:13:01.231425 kernel: Early memory node ranges Jul 7 00:13:01.231433 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Jul 7 00:13:01.231441 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Jul 7 00:13:01.231451 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Jul 7 00:13:01.231460 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Jul 7 00:13:01.231468 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Jul 7 00:13:01.231476 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Jul 7 00:13:01.231484 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Jul 7 00:13:01.231492 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Jul 7 00:13:01.231500 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Jul 7 00:13:01.231508 kernel: ACPI: PM-Timer IO Port: 0x408 Jul 7 00:13:01.231516 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Jul 7 00:13:01.231527 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Jul 7 00:13:01.231535 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Jul 7 00:13:01.231543 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Jul 7 00:13:01.231551 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Jul 7 00:13:01.231559 kernel: CPU topo: Max. logical packages: 1 Jul 7 00:13:01.231567 kernel: CPU topo: Max. logical dies: 1 Jul 7 00:13:01.231575 kernel: CPU topo: Max. dies per package: 1 Jul 7 00:13:01.231583 kernel: CPU topo: Max. threads per core: 1 Jul 7 00:13:01.231591 kernel: CPU topo: Num. cores per package: 6 Jul 7 00:13:01.231602 kernel: CPU topo: Num. threads per package: 6 Jul 7 00:13:01.231610 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs Jul 7 00:13:01.231618 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Jul 7 00:13:01.231626 kernel: Booting paravirtualized kernel on Hyper-V Jul 7 00:13:01.231634 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Jul 7 00:13:01.231642 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Jul 7 00:13:01.231651 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 Jul 7 00:13:01.231659 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 Jul 7 00:13:01.231667 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Jul 7 00:13:01.231677 kernel: Hyper-V: PV spinlocks enabled Jul 7 00:13:01.231685 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Jul 7 00:13:01.231695 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=e91aabf5a2d4674d97b8508f9502216224d5fb9433440e4c8f906b950e21abf8 Jul 7 00:13:01.231703 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Jul 7 00:13:01.231711 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Jul 7 00:13:01.231720 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Jul 7 00:13:01.231728 kernel: Fallback order for Node 0: 0 Jul 7 00:13:01.231736 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 Jul 7 00:13:01.231746 kernel: Policy zone: Normal Jul 7 00:13:01.231762 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jul 7 00:13:01.231771 kernel: software IO TLB: area num 8. Jul 7 00:13:01.231782 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Jul 7 00:13:01.231791 kernel: Kernel/User page tables isolation: enabled Jul 7 00:13:01.231799 kernel: ftrace: allocating 40095 entries in 157 pages Jul 7 00:13:01.231808 kernel: ftrace: allocated 157 pages with 5 groups Jul 7 00:13:01.231816 kernel: Dynamic Preempt: voluntary Jul 7 00:13:01.231825 kernel: rcu: Preemptible hierarchical RCU implementation. Jul 7 00:13:01.231834 kernel: rcu: RCU event tracing is enabled. Jul 7 00:13:01.231843 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Jul 7 00:13:01.231852 kernel: Trampoline variant of Tasks RCU enabled. Jul 7 00:13:01.231906 kernel: Rude variant of Tasks RCU enabled. Jul 7 00:13:01.231915 kernel: Tracing variant of Tasks RCU enabled. Jul 7 00:13:01.231924 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Jul 7 00:13:01.231933 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Jul 7 00:13:01.231942 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jul 7 00:13:01.231956 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jul 7 00:13:01.231964 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jul 7 00:13:01.231973 kernel: Using NULL legacy PIC Jul 7 00:13:01.231982 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Jul 7 00:13:01.231991 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Jul 7 00:13:01.231999 kernel: Console: colour dummy device 80x25 Jul 7 00:13:01.232008 kernel: printk: legacy console [tty1] enabled Jul 7 00:13:01.232029 kernel: printk: legacy console [ttyS0] enabled Jul 7 00:13:01.232038 kernel: printk: legacy bootconsole [earlyser0] disabled Jul 7 00:13:01.232050 kernel: ACPI: Core revision 20240827 Jul 7 00:13:01.232058 kernel: Failed to register legacy timer interrupt Jul 7 00:13:01.232067 kernel: APIC: Switch to symmetric I/O mode setup Jul 7 00:13:01.232076 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Jul 7 00:13:01.232084 kernel: Hyper-V: enabling crash_kexec_post_notifiers Jul 7 00:13:01.232093 kernel: Hyper-V: Using IPI hypercalls Jul 7 00:13:01.232102 kernel: APIC: send_IPI() replaced with hv_send_ipi() Jul 7 00:13:01.232110 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Jul 7 00:13:01.232119 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Jul 7 00:13:01.232130 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Jul 7 00:13:01.232139 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Jul 7 00:13:01.232148 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Jul 7 00:13:01.232156 kernel: Hyper-V: Using enlightened APIC (xapic mode) Jul 7 00:13:01.232165 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Jul 7 00:13:01.232174 kernel: APIC: read() replaced with hv_apic_read() Jul 7 00:13:01.232182 kernel: APIC: write() replaced with hv_apic_write() Jul 7 00:13:01.232191 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Jul 7 00:13:01.232200 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Jul 7 00:13:01.232211 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593990) Jul 7 00:13:01.232220 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Jul 7 00:13:01.232228 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Jul 7 00:13:01.232237 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Jul 7 00:13:01.232245 kernel: Spectre V2 : Mitigation: Retpolines Jul 7 00:13:01.232254 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Jul 7 00:13:01.232262 kernel: Speculative Store Bypass: Vulnerable Jul 7 00:13:01.232271 kernel: MDS: Mitigation: Clear CPU buffers Jul 7 00:13:01.232279 kernel: TAA: Mitigation: Clear CPU buffers Jul 7 00:13:01.232288 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Jul 7 00:13:01.232299 kernel: ITS: Mitigation: Aligned branch/return thunks Jul 7 00:13:01.232307 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Jul 7 00:13:01.232316 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Jul 7 00:13:01.232324 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Jul 7 00:13:01.232333 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Jul 7 00:13:01.232342 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Jul 7 00:13:01.232350 kernel: Freeing SMP alternatives memory: 32K Jul 7 00:13:01.232358 kernel: pid_max: default: 32768 minimum: 301 Jul 7 00:13:01.232367 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Jul 7 00:13:01.232375 kernel: landlock: Up and running. Jul 7 00:13:01.232391 kernel: SELinux: Initializing. Jul 7 00:13:01.232404 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Jul 7 00:13:01.232412 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Jul 7 00:13:01.232421 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Jul 7 00:13:01.232430 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Jul 7 00:13:01.232439 kernel: signal: max sigframe size: 1776 Jul 7 00:13:01.232447 kernel: rcu: Hierarchical SRCU implementation. Jul 7 00:13:01.232456 kernel: rcu: Max phase no-delay instances is 400. Jul 7 00:13:01.232465 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Jul 7 00:13:01.232474 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Jul 7 00:13:01.232482 kernel: smp: Bringing up secondary CPUs ... Jul 7 00:13:01.232494 kernel: smpboot: x86: Booting SMP configuration: Jul 7 00:13:01.232502 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Jul 7 00:13:01.232511 kernel: smp: Brought up 1 node, 6 CPUs Jul 7 00:13:01.232520 kernel: smpboot: Total of 6 processors activated (31127.88 BogoMIPS) Jul 7 00:13:01.232529 kernel: Memory: 115339096K/117439364K available (14336K kernel code, 2430K rwdata, 9956K rodata, 54432K init, 2536K bss, 2089992K reserved, 0K cma-reserved) Jul 7 00:13:01.232538 kernel: devtmpfs: initialized Jul 7 00:13:01.232546 kernel: x86/mm: Memory block size: 1024MB Jul 7 00:13:01.232555 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Jul 7 00:13:01.232564 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Jul 7 00:13:01.232575 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Jul 7 00:13:01.232584 kernel: pinctrl core: initialized pinctrl subsystem Jul 7 00:13:01.232592 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jul 7 00:13:01.232601 kernel: audit: initializing netlink subsys (disabled) Jul 7 00:13:01.232610 kernel: audit: type=2000 audit(1751847177.028:1): state=initialized audit_enabled=0 res=1 Jul 7 00:13:01.232618 kernel: thermal_sys: Registered thermal governor 'step_wise' Jul 7 00:13:01.232627 kernel: thermal_sys: Registered thermal governor 'user_space' Jul 7 00:13:01.232636 kernel: cpuidle: using governor menu Jul 7 00:13:01.232644 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Jul 7 00:13:01.232655 kernel: dca service started, version 1.12.1 Jul 7 00:13:01.232664 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Jul 7 00:13:01.232673 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Jul 7 00:13:01.232681 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Jul 7 00:13:01.232690 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Jul 7 00:13:01.232699 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Jul 7 00:13:01.232707 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Jul 7 00:13:01.232716 kernel: ACPI: Added _OSI(Module Device) Jul 7 00:13:01.232724 kernel: ACPI: Added _OSI(Processor Device) Jul 7 00:13:01.232735 kernel: ACPI: Added _OSI(Processor Aggregator Device) Jul 7 00:13:01.232744 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Jul 7 00:13:01.232753 kernel: ACPI: Interpreter enabled Jul 7 00:13:01.232761 kernel: ACPI: PM: (supports S0 S5) Jul 7 00:13:01.232770 kernel: ACPI: Using IOAPIC for interrupt routing Jul 7 00:13:01.232779 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Jul 7 00:13:01.232787 kernel: PCI: Ignoring E820 reservations for host bridge windows Jul 7 00:13:01.232796 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Jul 7 00:13:01.232804 kernel: iommu: Default domain type: Translated Jul 7 00:13:01.232816 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Jul 7 00:13:01.232824 kernel: efivars: Registered efivars operations Jul 7 00:13:01.232833 kernel: PCI: Using ACPI for IRQ routing Jul 7 00:13:01.232841 kernel: PCI: System does not support PCI Jul 7 00:13:01.232850 kernel: vgaarb: loaded Jul 7 00:13:01.232859 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Jul 7 00:13:01.232867 kernel: VFS: Disk quotas dquot_6.6.0 Jul 7 00:13:01.232876 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jul 7 00:13:01.232884 kernel: pnp: PnP ACPI init Jul 7 00:13:01.232896 kernel: pnp: PnP ACPI: found 3 devices Jul 7 00:13:01.232904 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Jul 7 00:13:01.232913 kernel: NET: Registered PF_INET protocol family Jul 7 00:13:01.232922 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Jul 7 00:13:01.232931 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Jul 7 00:13:01.232939 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jul 7 00:13:01.232948 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Jul 7 00:13:01.232957 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jul 7 00:13:01.232968 kernel: TCP: Hash tables configured (established 524288 bind 65536) Jul 7 00:13:01.232977 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jul 7 00:13:01.232986 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jul 7 00:13:01.232994 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jul 7 00:13:01.233003 kernel: NET: Registered PF_XDP protocol family Jul 7 00:13:01.233019 kernel: PCI: CLS 0 bytes, default 64 Jul 7 00:13:01.233029 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Jul 7 00:13:01.233038 kernel: software IO TLB: mapped [mem 0x000000003b5bf000-0x000000003f5bf000] (64MB) Jul 7 00:13:01.233046 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Jul 7 00:13:01.233058 kernel: Initialise system trusted keyrings Jul 7 00:13:01.233067 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Jul 7 00:13:01.233075 kernel: Key type asymmetric registered Jul 7 00:13:01.233084 kernel: Asymmetric key parser 'x509' registered Jul 7 00:13:01.233092 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Jul 7 00:13:01.233101 kernel: io scheduler mq-deadline registered Jul 7 00:13:01.233110 kernel: io scheduler kyber registered Jul 7 00:13:01.233118 kernel: io scheduler bfq registered Jul 7 00:13:01.233127 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Jul 7 00:13:01.233138 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Jul 7 00:13:01.233147 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Jul 7 00:13:01.233155 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Jul 7 00:13:01.233164 kernel: i8042: PNP: No PS/2 controller found. Jul 7 00:13:01.233311 kernel: rtc_cmos 00:02: registered as rtc0 Jul 7 00:13:01.233412 kernel: rtc_cmos 00:02: setting system clock to 2025-07-07T00:13:00 UTC (1751847180) Jul 7 00:13:01.233506 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Jul 7 00:13:01.233518 kernel: intel_pstate: CPU model not supported Jul 7 00:13:01.233531 kernel: efifb: probing for efifb Jul 7 00:13:01.233540 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Jul 7 00:13:01.233549 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Jul 7 00:13:01.233557 kernel: efifb: scrolling: redraw Jul 7 00:13:01.233566 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Jul 7 00:13:01.233575 kernel: Console: switching to colour frame buffer device 128x48 Jul 7 00:13:01.233584 kernel: fb0: EFI VGA frame buffer device Jul 7 00:13:01.233592 kernel: pstore: Using crash dump compression: deflate Jul 7 00:13:01.233601 kernel: pstore: Registered efi_pstore as persistent store backend Jul 7 00:13:01.233612 kernel: NET: Registered PF_INET6 protocol family Jul 7 00:13:01.233621 kernel: Segment Routing with IPv6 Jul 7 00:13:01.233629 kernel: In-situ OAM (IOAM) with IPv6 Jul 7 00:13:01.233638 kernel: NET: Registered PF_PACKET protocol family Jul 7 00:13:01.233647 kernel: Key type dns_resolver registered Jul 7 00:13:01.233655 kernel: IPI shorthand broadcast: enabled Jul 7 00:13:01.233664 kernel: sched_clock: Marking stable (3895004200, 119637600)->(4356320200, -341678400) Jul 7 00:13:01.233672 kernel: registered taskstats version 1 Jul 7 00:13:01.233681 kernel: Loading compiled-in X.509 certificates Jul 7 00:13:01.233692 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.35-flatcar: 025c05e23c9778f7a70ff09fb369dd949499fb06' Jul 7 00:13:01.233701 kernel: Demotion targets for Node 0: null Jul 7 00:13:01.233710 kernel: Key type .fscrypt registered Jul 7 00:13:01.233718 kernel: Key type fscrypt-provisioning registered Jul 7 00:13:01.233727 kernel: ima: No TPM chip found, activating TPM-bypass! Jul 7 00:13:01.233736 kernel: ima: Allocated hash algorithm: sha1 Jul 7 00:13:01.233744 kernel: ima: No architecture policies found Jul 7 00:13:01.233753 kernel: clk: Disabling unused clocks Jul 7 00:13:01.233761 kernel: Warning: unable to open an initial console. Jul 7 00:13:01.233773 kernel: Freeing unused kernel image (initmem) memory: 54432K Jul 7 00:13:01.233782 kernel: Write protecting the kernel read-only data: 24576k Jul 7 00:13:01.233790 kernel: Freeing unused kernel image (rodata/data gap) memory: 284K Jul 7 00:13:01.233799 kernel: Run /init as init process Jul 7 00:13:01.233808 kernel: with arguments: Jul 7 00:13:01.233816 kernel: /init Jul 7 00:13:01.233825 kernel: with environment: Jul 7 00:13:01.233833 kernel: HOME=/ Jul 7 00:13:01.233841 kernel: TERM=linux Jul 7 00:13:01.233852 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Jul 7 00:13:01.233862 systemd[1]: Successfully made /usr/ read-only. Jul 7 00:13:01.233908 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 7 00:13:01.233925 systemd[1]: Detected virtualization microsoft. Jul 7 00:13:01.233934 systemd[1]: Detected architecture x86-64. Jul 7 00:13:01.233943 systemd[1]: Running in initrd. Jul 7 00:13:01.233952 systemd[1]: No hostname configured, using default hostname. Jul 7 00:13:01.233966 systemd[1]: Hostname set to . Jul 7 00:13:01.233975 systemd[1]: Initializing machine ID from random generator. Jul 7 00:13:01.233984 systemd[1]: Queued start job for default target initrd.target. Jul 7 00:13:01.233994 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 7 00:13:01.234003 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 7 00:13:01.234025 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Jul 7 00:13:01.234035 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jul 7 00:13:01.234044 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Jul 7 00:13:01.234058 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Jul 7 00:13:01.234068 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Jul 7 00:13:01.234078 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Jul 7 00:13:01.234087 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 7 00:13:01.234096 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jul 7 00:13:01.234106 systemd[1]: Reached target paths.target - Path Units. Jul 7 00:13:01.234115 systemd[1]: Reached target slices.target - Slice Units. Jul 7 00:13:01.234126 systemd[1]: Reached target swap.target - Swaps. Jul 7 00:13:01.234136 systemd[1]: Reached target timers.target - Timer Units. Jul 7 00:13:01.234145 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Jul 7 00:13:01.234154 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jul 7 00:13:01.234164 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Jul 7 00:13:01.234173 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Jul 7 00:13:01.234182 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jul 7 00:13:01.234191 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jul 7 00:13:01.234201 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jul 7 00:13:01.234213 systemd[1]: Reached target sockets.target - Socket Units. Jul 7 00:13:01.234222 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Jul 7 00:13:01.234231 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jul 7 00:13:01.234240 systemd[1]: Finished network-cleanup.service - Network Cleanup. Jul 7 00:13:01.234250 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 7 00:13:01.234259 systemd[1]: Starting systemd-fsck-usr.service... Jul 7 00:13:01.234268 systemd[1]: Starting systemd-journald.service - Journal Service... Jul 7 00:13:01.234277 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jul 7 00:13:01.234312 systemd-journald[233]: Collecting audit messages is disabled. Jul 7 00:13:01.234335 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 7 00:13:01.234345 systemd-journald[233]: Journal started Jul 7 00:13:01.234369 systemd-journald[233]: Runtime Journal (/run/log/journal/27ea638090084b61be3d6aa28b69ef5a) is 8M, max 2.2G, 2.1G free. Jul 7 00:13:01.237792 systemd-modules-load[235]: Inserted module 'overlay' Jul 7 00:13:01.243068 systemd[1]: Started systemd-journald.service - Journal Service. Jul 7 00:13:01.243424 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Jul 7 00:13:01.243997 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jul 7 00:13:01.244741 systemd[1]: Finished systemd-fsck-usr.service. Jul 7 00:13:01.246896 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jul 7 00:13:01.248340 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jul 7 00:13:01.298325 systemd-tmpfiles[243]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Jul 7 00:13:01.305262 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jul 7 00:13:01.307060 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 7 00:13:01.318072 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 7 00:13:01.328798 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jul 7 00:13:01.337926 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jul 7 00:13:01.366032 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 7 00:13:01.369621 systemd-modules-load[235]: Inserted module 'br_netfilter' Jul 7 00:13:01.373290 kernel: Bridge firewalling registered Jul 7 00:13:01.374226 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jul 7 00:13:01.376608 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jul 7 00:13:01.390901 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jul 7 00:13:01.393117 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Jul 7 00:13:01.414363 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 7 00:13:01.416100 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jul 7 00:13:01.427829 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jul 7 00:13:01.442125 dracut-cmdline[271]: 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=e91aabf5a2d4674d97b8508f9502216224d5fb9433440e4c8f906b950e21abf8 Jul 7 00:13:01.493836 systemd-resolved[282]: Positive Trust Anchors: Jul 7 00:13:01.493851 systemd-resolved[282]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 7 00:13:01.493882 systemd-resolved[282]: 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 7 00:13:01.496469 systemd-resolved[282]: Defaulting to hostname 'linux'. Jul 7 00:13:01.497490 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jul 7 00:13:01.507431 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jul 7 00:13:01.602040 kernel: SCSI subsystem initialized Jul 7 00:13:01.612029 kernel: Loading iSCSI transport class v2.0-870. Jul 7 00:13:01.625032 kernel: iscsi: registered transport (tcp) Jul 7 00:13:01.649025 kernel: iscsi: registered transport (qla4xxx) Jul 7 00:13:01.649057 kernel: QLogic iSCSI HBA Driver Jul 7 00:13:01.670933 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jul 7 00:13:01.710517 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jul 7 00:13:01.722541 systemd[1]: Reached target network-pre.target - Preparation for Network. Jul 7 00:13:01.773858 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Jul 7 00:13:01.783303 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Jul 7 00:13:01.852037 kernel: raid6: avx2x4 gen() 29923 MB/s Jul 7 00:13:01.871034 kernel: raid6: avx2x2 gen() 31183 MB/s Jul 7 00:13:01.893529 kernel: raid6: avx2x1 gen() 26066 MB/s Jul 7 00:13:01.893573 kernel: raid6: using algorithm avx2x2 gen() 31183 MB/s Jul 7 00:13:01.916826 kernel: raid6: .... xor() 18375 MB/s, rmw enabled Jul 7 00:13:01.916853 kernel: raid6: using avx2x2 recovery algorithm Jul 7 00:13:01.940034 kernel: xor: automatically using best checksumming function avx Jul 7 00:13:02.185040 kernel: Btrfs loaded, zoned=no, fsverity=no Jul 7 00:13:02.191827 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Jul 7 00:13:02.194760 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 7 00:13:02.236445 systemd-udevd[486]: Using default interface naming scheme 'v255'. Jul 7 00:13:02.242440 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 7 00:13:02.246122 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Jul 7 00:13:02.280219 dracut-pre-trigger[494]: rd.md=0: removing MD RAID activation Jul 7 00:13:02.306846 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Jul 7 00:13:02.309443 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jul 7 00:13:02.389055 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jul 7 00:13:02.401028 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Jul 7 00:13:02.419033 kernel: cryptd: max_cpu_qlen set to 1000 Jul 7 00:13:02.428655 kernel: AES CTR mode by8 optimization enabled Jul 7 00:13:02.446033 kernel: hv_vmbus: Vmbus version:4.0 Jul 7 00:13:02.446945 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 7 00:13:02.447240 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jul 7 00:13:02.480086 kernel: hv_vmbus: registering driver hyperv_keyboard Jul 7 00:13:02.480111 kernel: pps_core: LinuxPPS API ver. 1 registered Jul 7 00:13:02.480124 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 7 00:13:02.480148 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Jul 7 00:13:02.481207 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Jul 7 00:13:02.490142 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 7 00:13:02.495994 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jul 7 00:13:02.507037 kernel: hid: raw HID events driver (C) Jiri Kosina Jul 7 00:13:02.507072 kernel: hv_vmbus: registering driver hv_storvsc Jul 7 00:13:02.507085 kernel: hv_vmbus: registering driver hv_netvsc Jul 7 00:13:02.515037 kernel: PTP clock support registered Jul 7 00:13:02.515079 kernel: hv_vmbus: registering driver hv_pci Jul 7 00:13:02.516034 kernel: scsi host1: storvsc_host_t Jul 7 00:13:02.517080 kernel: scsi host0: storvsc_host_t Jul 7 00:13:02.520202 kernel: hv_vmbus: registering driver hid_hyperv Jul 7 00:13:02.520229 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Jul 7 00:13:02.520406 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Jul 7 00:13:02.520531 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Jul 7 00:13:02.520674 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Jul 7 00:13:02.520796 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Jul 7 00:13:02.521526 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint Jul 7 00:13:02.530360 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Jul 7 00:13:02.530410 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Jul 7 00:13:02.530424 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Jul 7 00:13:02.530581 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] Jul 7 00:13:02.538296 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Jul 7 00:13:02.538336 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] Jul 7 00:13:02.543051 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Jul 7 00:13:02.547027 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] Jul 7 00:13:02.591126 kernel: hv_utils: Registering HyperV Utility Driver Jul 7 00:13:02.598697 kernel: pci 0001:00:00.0: Enabling HDA controller Jul 7 00:13:02.598745 kernel: hv_vmbus: registering driver hv_utils Jul 7 00:13:02.598759 kernel: sd 0:0:0:0: [sdb] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Jul 7 00:13:02.598986 kernel: sd 0:0:0:1: [sda] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Jul 7 00:13:02.599211 kernel: sd 0:0:0:0: [sdb] 4096-byte physical blocks Jul 7 00:13:02.599353 kernel: sd 0:0:0:1: [sda] 4096-byte physical blocks Jul 7 00:13:02.599486 kernel: sd 0:0:0:1: [sda] Write Protect is off Jul 7 00:13:02.599614 kernel: sd 0:0:0:1: [sda] Mode Sense: 0f 00 10 00 Jul 7 00:13:02.599741 kernel: sd 0:0:0:0: [sdb] Write Protect is off Jul 7 00:13:02.599869 kernel: sd 0:0:0:0: [sdb] Mode Sense: 0f 00 10 00 Jul 7 00:13:02.599996 kernel: sd 0:0:0:0: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Jul 7 00:13:02.600145 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#324 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 7 00:13:02.600273 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#331 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 7 00:13:02.600387 kernel: sd 0:0:0:1: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Jul 7 00:13:02.617026 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Jul 7 00:13:02.620294 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#517 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 7 00:13:02.620536 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned Jul 7 00:13:02.624729 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#522 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 7 00:13:02.630024 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned Jul 7 00:13:02.674033 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned Jul 7 00:13:02.680030 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Jul 7 00:13:02.697034 kernel: sd 0:0:0:0: [sdb] Attached SCSI disk Jul 7 00:13:02.697343 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 7 00:13:02.719331 kernel: sda: sda1 Jul 7 00:13:02.719352 kernel: sd 0:0:0:1: [sda] Attached SCSI disk Jul 7 00:13:02.719525 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Jul 7 00:13:02.719677 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Jul 7 00:13:02.719691 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Jul 7 00:13:02.765552 kernel: hv_utils: Heartbeat IC version 3.0 Jul 7 00:13:02.765591 kernel: hv_utils: Shutdown IC version 3.2 Jul 7 00:13:02.770396 kernel: hv_utils: TimeSync IC version 4.0 Jul 7 00:13:03.336509 systemd-resolved[282]: Clock change detected. Flushing caches. Jul 7 00:13:03.851836 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Jul 7 00:13:03.864965 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Jul 7 00:13:03.885378 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Jul 7 00:13:03.886936 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Jul 7 00:13:03.908780 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Jul 7 00:13:03.911176 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Jul 7 00:13:03.955065 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#371 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 7 00:13:03.973067 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Jul 7 00:13:03.982064 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#324 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 7 00:13:04.005064 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Jul 7 00:13:04.984786 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Jul 7 00:13:04.994043 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Jul 7 00:13:05.004253 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 7 00:13:05.019164 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#55 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 7 00:13:05.005811 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jul 7 00:13:05.029536 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Jul 7 00:13:05.046854 disk-uuid[723]: The operation has completed successfully. Jul 7 00:13:05.051592 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Jul 7 00:13:05.067271 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Jul 7 00:13:05.107106 systemd[1]: disk-uuid.service: Deactivated successfully. Jul 7 00:13:05.107213 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Jul 7 00:13:05.155764 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Jul 7 00:13:05.180854 sh[896]: Success Jul 7 00:13:05.219411 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Jul 7 00:13:05.219445 kernel: device-mapper: uevent: version 1.0.3 Jul 7 00:13:05.224289 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Jul 7 00:13:05.236065 kernel: device-mapper: verity: sha256 using shash "sha256-avx2" Jul 7 00:13:05.433624 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Jul 7 00:13:05.437012 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Jul 7 00:13:05.462598 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Jul 7 00:13:05.484785 kernel: BTRFS info: 'norecovery' is for compatibility only, recommended to use 'rescue=nologreplay' Jul 7 00:13:05.484820 kernel: BTRFS: device fsid 9d729180-1373-4e9f-840c-4db0e9220239 devid 1 transid 39 /dev/mapper/usr (254:0) scanned by mount (914) Jul 7 00:13:05.490663 kernel: BTRFS info (device dm-0): first mount of filesystem 9d729180-1373-4e9f-840c-4db0e9220239 Jul 7 00:13:05.498470 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Jul 7 00:13:05.498495 kernel: BTRFS info (device dm-0): using free-space-tree Jul 7 00:13:06.033013 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Jul 7 00:13:06.040285 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Jul 7 00:13:06.049490 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Jul 7 00:13:06.059733 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Jul 7 00:13:06.067687 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Jul 7 00:13:06.105573 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sdb6 (8:22) scanned by mount (946) Jul 7 00:13:06.105610 kernel: BTRFS info (device sdb6): first mount of filesystem a5b10ed8-ad12-45a6-8115-f8814df6901b Jul 7 00:13:06.109996 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Jul 7 00:13:06.113205 kernel: BTRFS info (device sdb6): using free-space-tree Jul 7 00:13:06.136063 kernel: BTRFS info (device sdb6): last unmount of filesystem a5b10ed8-ad12-45a6-8115-f8814df6901b Jul 7 00:13:06.136822 systemd[1]: Finished ignition-setup.service - Ignition (setup). Jul 7 00:13:06.139323 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Jul 7 00:13:06.175335 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jul 7 00:13:06.178427 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jul 7 00:13:06.237070 systemd-networkd[1083]: lo: Link UP Jul 7 00:13:06.237079 systemd-networkd[1083]: lo: Gained carrier Jul 7 00:13:06.238122 systemd-networkd[1083]: Enumeration completed Jul 7 00:13:06.238214 systemd[1]: Started systemd-networkd.service - Network Configuration. Jul 7 00:13:06.238548 systemd-networkd[1083]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 7 00:13:06.238552 systemd-networkd[1083]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 7 00:13:06.239351 systemd-networkd[1083]: eth0: Link UP Jul 7 00:13:06.239549 systemd-networkd[1083]: eth0: Gained carrier Jul 7 00:13:06.239559 systemd-networkd[1083]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 7 00:13:06.244385 systemd[1]: Reached target network.target - Network. Jul 7 00:13:06.317131 systemd-networkd[1083]: eth0: DHCPv4 address 10.200.4.15/24, gateway 10.200.4.1 acquired from 168.63.129.16 Jul 7 00:13:07.571161 systemd-networkd[1083]: eth0: Gained IPv6LL Jul 7 00:13:07.970076 ignition[1042]: Ignition 2.21.0 Jul 7 00:13:07.970101 ignition[1042]: Stage: fetch-offline Jul 7 00:13:07.970233 ignition[1042]: no configs at "/usr/lib/ignition/base.d" Jul 7 00:13:07.970245 ignition[1042]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 7 00:13:07.970346 ignition[1042]: parsed url from cmdline: "" Jul 7 00:13:07.970351 ignition[1042]: no config URL provided Jul 7 00:13:07.970357 ignition[1042]: reading system config file "/usr/lib/ignition/user.ign" Jul 7 00:13:07.970367 ignition[1042]: no config at "/usr/lib/ignition/user.ign" Jul 7 00:13:07.970372 ignition[1042]: failed to fetch config: resource requires networking Jul 7 00:13:07.970549 ignition[1042]: Ignition finished successfully Jul 7 00:13:08.002299 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Jul 7 00:13:08.004822 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Jul 7 00:13:08.041757 ignition[1096]: Ignition 2.21.0 Jul 7 00:13:08.041771 ignition[1096]: Stage: fetch Jul 7 00:13:08.041976 ignition[1096]: no configs at "/usr/lib/ignition/base.d" Jul 7 00:13:08.041988 ignition[1096]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 7 00:13:08.042093 ignition[1096]: parsed url from cmdline: "" Jul 7 00:13:08.042097 ignition[1096]: no config URL provided Jul 7 00:13:08.042103 ignition[1096]: reading system config file "/usr/lib/ignition/user.ign" Jul 7 00:13:08.042111 ignition[1096]: no config at "/usr/lib/ignition/user.ign" Jul 7 00:13:08.042143 ignition[1096]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Jul 7 00:13:08.149779 ignition[1096]: GET result: OK Jul 7 00:13:08.149832 ignition[1096]: config has been read from IMDS userdata Jul 7 00:13:08.149845 ignition[1096]: parsing config with SHA512: 84d035067fda5e74ac847be105d6d5caa269703c863c365dfdaf95cc217b092bb63aace6b8f6278e8c646ab9640ef0ff071c6af072187358e34a132e869f9d6d Jul 7 00:13:08.153065 unknown[1096]: fetched base config from "system" Jul 7 00:13:08.153226 ignition[1096]: fetch: fetch complete Jul 7 00:13:08.153072 unknown[1096]: fetched base config from "system" Jul 7 00:13:08.153231 ignition[1096]: fetch: fetch passed Jul 7 00:13:08.153078 unknown[1096]: fetched user config from "azure" Jul 7 00:13:08.153285 ignition[1096]: Ignition finished successfully Jul 7 00:13:08.161428 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Jul 7 00:13:08.164378 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Jul 7 00:13:08.208001 ignition[1106]: Ignition 2.21.0 Jul 7 00:13:08.208014 ignition[1106]: Stage: kargs Jul 7 00:13:08.208288 ignition[1106]: no configs at "/usr/lib/ignition/base.d" Jul 7 00:13:08.208300 ignition[1106]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 7 00:13:08.209270 ignition[1106]: kargs: kargs passed Jul 7 00:13:08.217104 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Jul 7 00:13:08.209348 ignition[1106]: Ignition finished successfully Jul 7 00:13:08.226152 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Jul 7 00:13:08.266943 ignition[1116]: Ignition 2.21.0 Jul 7 00:13:08.267758 ignition[1116]: Stage: disks Jul 7 00:13:08.270061 systemd[1]: Finished ignition-disks.service - Ignition (disks). Jul 7 00:13:08.268070 ignition[1116]: no configs at "/usr/lib/ignition/base.d" Jul 7 00:13:08.276260 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Jul 7 00:13:08.268090 ignition[1116]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 7 00:13:08.278344 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Jul 7 00:13:08.268617 ignition[1116]: disks: disks passed Jul 7 00:13:08.290974 systemd[1]: Reached target local-fs.target - Local File Systems. Jul 7 00:13:08.268665 ignition[1116]: Ignition finished successfully Jul 7 00:13:08.299879 systemd[1]: Reached target sysinit.target - System Initialization. Jul 7 00:13:08.312089 systemd[1]: Reached target basic.target - Basic System. Jul 7 00:13:08.323265 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Jul 7 00:13:08.414017 systemd-fsck[1127]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks Jul 7 00:13:08.419550 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Jul 7 00:13:08.421977 systemd[1]: Mounting sysroot.mount - /sysroot... Jul 7 00:13:08.645065 kernel: EXT4-fs (sdb9): mounted filesystem 98c55dfc-aac4-4fdd-8ec0-1f5587b3aa36 r/w with ordered data mode. Quota mode: none. Jul 7 00:13:08.645408 systemd[1]: Mounted sysroot.mount - /sysroot. Jul 7 00:13:08.647324 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Jul 7 00:13:08.665251 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jul 7 00:13:08.672298 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Jul 7 00:13:08.691614 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Jul 7 00:13:08.696368 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Jul 7 00:13:08.721390 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sdb6 (8:22) scanned by mount (1141) Jul 7 00:13:08.721416 kernel: BTRFS info (device sdb6): first mount of filesystem a5b10ed8-ad12-45a6-8115-f8814df6901b Jul 7 00:13:08.721429 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Jul 7 00:13:08.696403 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Jul 7 00:13:08.737475 kernel: BTRFS info (device sdb6): using free-space-tree Jul 7 00:13:08.712607 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Jul 7 00:13:08.736401 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Jul 7 00:13:08.754435 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jul 7 00:13:09.462892 coreos-metadata[1143]: Jul 07 00:13:09.462 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Jul 7 00:13:09.470697 coreos-metadata[1143]: Jul 07 00:13:09.470 INFO Fetch successful Jul 7 00:13:09.474826 coreos-metadata[1143]: Jul 07 00:13:09.470 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Jul 7 00:13:09.483331 coreos-metadata[1143]: Jul 07 00:13:09.477 INFO Fetch successful Jul 7 00:13:09.493808 coreos-metadata[1143]: Jul 07 00:13:09.493 INFO wrote hostname ci-4344.1.1-a-4bf75d77c8 to /sysroot/etc/hostname Jul 7 00:13:09.501272 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Jul 7 00:13:09.689856 initrd-setup-root[1171]: cut: /sysroot/etc/passwd: No such file or directory Jul 7 00:13:09.738339 initrd-setup-root[1178]: cut: /sysroot/etc/group: No such file or directory Jul 7 00:13:09.764894 initrd-setup-root[1185]: cut: /sysroot/etc/shadow: No such file or directory Jul 7 00:13:09.775147 initrd-setup-root[1192]: cut: /sysroot/etc/gshadow: No such file or directory Jul 7 00:13:10.920835 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Jul 7 00:13:10.926668 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Jul 7 00:13:10.932190 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Jul 7 00:13:10.966261 systemd[1]: sysroot-oem.mount: Deactivated successfully. Jul 7 00:13:10.979749 kernel: BTRFS info (device sdb6): last unmount of filesystem a5b10ed8-ad12-45a6-8115-f8814df6901b Jul 7 00:13:10.994150 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Jul 7 00:13:11.009163 ignition[1261]: INFO : Ignition 2.21.0 Jul 7 00:13:11.009163 ignition[1261]: INFO : Stage: mount Jul 7 00:13:11.015083 ignition[1261]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 7 00:13:11.015083 ignition[1261]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 7 00:13:11.015083 ignition[1261]: INFO : mount: mount passed Jul 7 00:13:11.015083 ignition[1261]: INFO : Ignition finished successfully Jul 7 00:13:11.016297 systemd[1]: Finished ignition-mount.service - Ignition (mount). Jul 7 00:13:11.029287 systemd[1]: Starting ignition-files.service - Ignition (files)... Jul 7 00:13:11.070285 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jul 7 00:13:11.090065 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sdb6 (8:22) scanned by mount (1274) Jul 7 00:13:11.090099 kernel: BTRFS info (device sdb6): first mount of filesystem a5b10ed8-ad12-45a6-8115-f8814df6901b Jul 7 00:13:11.098542 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Jul 7 00:13:11.101840 kernel: BTRFS info (device sdb6): using free-space-tree Jul 7 00:13:11.105515 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jul 7 00:13:11.142518 ignition[1291]: INFO : Ignition 2.21.0 Jul 7 00:13:11.145690 ignition[1291]: INFO : Stage: files Jul 7 00:13:11.145690 ignition[1291]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 7 00:13:11.145690 ignition[1291]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 7 00:13:11.145690 ignition[1291]: DEBUG : files: compiled without relabeling support, skipping Jul 7 00:13:11.162427 ignition[1291]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Jul 7 00:13:11.162427 ignition[1291]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Jul 7 00:13:11.248949 ignition[1291]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Jul 7 00:13:11.255062 ignition[1291]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Jul 7 00:13:11.260305 ignition[1291]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Jul 7 00:13:11.255528 unknown[1291]: wrote ssh authorized keys file for user: core Jul 7 00:13:11.319954 ignition[1291]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Jul 7 00:13:11.327393 ignition[1291]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Jul 7 00:13:11.327393 ignition[1291]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Jul 7 00:13:11.327393 ignition[1291]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Jul 7 00:13:11.327393 ignition[1291]: INFO : files: files passed Jul 7 00:13:11.327393 ignition[1291]: INFO : Ignition finished successfully Jul 7 00:13:11.335942 systemd[1]: Finished ignition-files.service - Ignition (files). Jul 7 00:13:11.347770 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Jul 7 00:13:11.375399 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Jul 7 00:13:11.378626 systemd[1]: ignition-quench.service: Deactivated successfully. Jul 7 00:13:11.378732 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Jul 7 00:13:11.421134 initrd-setup-root-after-ignition[1324]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jul 7 00:13:11.427513 initrd-setup-root-after-ignition[1324]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Jul 7 00:13:11.433905 initrd-setup-root-after-ignition[1328]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jul 7 00:13:11.440861 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Jul 7 00:13:11.442655 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Jul 7 00:13:11.453619 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Jul 7 00:13:11.502546 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Jul 7 00:13:11.502654 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Jul 7 00:13:11.514447 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Jul 7 00:13:11.523508 systemd[1]: Reached target initrd.target - Initrd Default Target. Jul 7 00:13:11.531822 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Jul 7 00:13:11.532612 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Jul 7 00:13:11.573009 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jul 7 00:13:11.574925 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Jul 7 00:13:11.611613 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Jul 7 00:13:11.614070 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 7 00:13:11.621936 systemd[1]: Stopped target timers.target - Timer Units. Jul 7 00:13:11.634501 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Jul 7 00:13:11.634623 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jul 7 00:13:11.646693 systemd[1]: Stopped target initrd.target - Initrd Default Target. Jul 7 00:13:11.655587 systemd[1]: Stopped target basic.target - Basic System. Jul 7 00:13:11.663414 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Jul 7 00:13:11.671353 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Jul 7 00:13:11.680353 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Jul 7 00:13:11.689471 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Jul 7 00:13:11.698411 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Jul 7 00:13:11.706979 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Jul 7 00:13:11.715923 systemd[1]: Stopped target sysinit.target - System Initialization. Jul 7 00:13:11.724916 systemd[1]: Stopped target local-fs.target - Local File Systems. Jul 7 00:13:11.732971 systemd[1]: Stopped target swap.target - Swaps. Jul 7 00:13:11.740136 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Jul 7 00:13:11.740271 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Jul 7 00:13:11.751301 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Jul 7 00:13:11.752826 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 7 00:13:11.765537 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Jul 7 00:13:11.765660 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 7 00:13:11.775411 systemd[1]: dracut-initqueue.service: Deactivated successfully. Jul 7 00:13:11.775554 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Jul 7 00:13:11.792722 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Jul 7 00:13:11.792847 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Jul 7 00:13:11.815565 systemd[1]: ignition-files.service: Deactivated successfully. Jul 7 00:13:11.815704 systemd[1]: Stopped ignition-files.service - Ignition (files). Jul 7 00:13:11.823467 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Jul 7 00:13:11.823590 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Jul 7 00:13:11.833108 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Jul 7 00:13:11.859344 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Jul 7 00:13:11.860871 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Jul 7 00:13:11.861013 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Jul 7 00:13:11.868116 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Jul 7 00:13:11.868235 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Jul 7 00:13:11.886696 systemd[1]: sysroot-boot.mount: Deactivated successfully. Jul 7 00:13:11.889378 systemd[1]: initrd-cleanup.service: Deactivated successfully. Jul 7 00:13:11.906341 ignition[1349]: INFO : Ignition 2.21.0 Jul 7 00:13:11.906341 ignition[1349]: INFO : Stage: umount Jul 7 00:13:11.906341 ignition[1349]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 7 00:13:11.906341 ignition[1349]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 7 00:13:11.906341 ignition[1349]: INFO : umount: umount passed Jul 7 00:13:11.889474 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Jul 7 00:13:11.938654 ignition[1349]: INFO : Ignition finished successfully Jul 7 00:13:11.905693 systemd[1]: sysroot-boot.service: Deactivated successfully. Jul 7 00:13:11.905801 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Jul 7 00:13:11.921766 systemd[1]: ignition-mount.service: Deactivated successfully. Jul 7 00:13:11.921916 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Jul 7 00:13:11.930043 systemd[1]: ignition-disks.service: Deactivated successfully. Jul 7 00:13:11.930125 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Jul 7 00:13:11.937343 systemd[1]: ignition-kargs.service: Deactivated successfully. Jul 7 00:13:11.937409 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Jul 7 00:13:11.946133 systemd[1]: ignition-fetch.service: Deactivated successfully. Jul 7 00:13:11.946186 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Jul 7 00:13:11.953671 systemd[1]: Stopped target network.target - Network. Jul 7 00:13:11.961547 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Jul 7 00:13:11.961608 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Jul 7 00:13:11.963797 systemd[1]: Stopped target paths.target - Path Units. Jul 7 00:13:11.974829 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Jul 7 00:13:11.980106 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 7 00:13:11.989513 systemd[1]: Stopped target slices.target - Slice Units. Jul 7 00:13:11.996938 systemd[1]: Stopped target sockets.target - Socket Units. Jul 7 00:13:12.004380 systemd[1]: iscsid.socket: Deactivated successfully. Jul 7 00:13:12.004429 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Jul 7 00:13:12.012040 systemd[1]: iscsiuio.socket: Deactivated successfully. Jul 7 00:13:12.012097 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jul 7 00:13:12.019909 systemd[1]: ignition-setup.service: Deactivated successfully. Jul 7 00:13:12.019968 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Jul 7 00:13:12.027718 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Jul 7 00:13:12.027764 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Jul 7 00:13:12.035399 systemd[1]: initrd-setup-root.service: Deactivated successfully. Jul 7 00:13:12.035450 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Jul 7 00:13:12.044018 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Jul 7 00:13:12.052555 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Jul 7 00:13:12.060520 systemd[1]: systemd-resolved.service: Deactivated successfully. Jul 7 00:13:12.060853 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Jul 7 00:13:12.076668 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Jul 7 00:13:12.076968 systemd[1]: systemd-networkd.service: Deactivated successfully. Jul 7 00:13:12.077101 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Jul 7 00:13:12.087110 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Jul 7 00:13:12.088041 systemd[1]: Stopped target network-pre.target - Preparation for Network. Jul 7 00:13:12.094095 systemd[1]: systemd-networkd.socket: Deactivated successfully. Jul 7 00:13:12.094136 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Jul 7 00:13:12.103287 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Jul 7 00:13:12.109635 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Jul 7 00:13:12.113639 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jul 7 00:13:12.122640 systemd[1]: systemd-sysctl.service: Deactivated successfully. Jul 7 00:13:12.122687 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Jul 7 00:13:12.135206 systemd[1]: systemd-modules-load.service: Deactivated successfully. Jul 7 00:13:12.135252 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Jul 7 00:13:12.136750 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Jul 7 00:13:12.136798 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 7 00:13:12.158295 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 7 00:13:12.159935 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Jul 7 00:13:12.160001 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Jul 7 00:13:12.202342 systemd[1]: systemd-udevd.service: Deactivated successfully. Jul 7 00:13:12.202507 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 7 00:13:12.236410 systemd[1]: network-cleanup.service: Deactivated successfully. Jul 7 00:13:12.236506 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Jul 7 00:13:12.239275 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Jul 7 00:13:12.239328 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Jul 7 00:13:12.245124 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Jul 7 00:13:12.245164 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Jul 7 00:13:12.257454 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Jul 7 00:13:12.257515 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Jul 7 00:13:12.267981 systemd[1]: dracut-cmdline.service: Deactivated successfully. Jul 7 00:13:12.268035 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Jul 7 00:13:12.283275 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jul 7 00:13:12.283340 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jul 7 00:13:12.303573 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Jul 7 00:13:12.311309 systemd[1]: systemd-network-generator.service: Deactivated successfully. Jul 7 00:13:12.311362 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Jul 7 00:13:12.321260 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Jul 7 00:13:12.321312 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 7 00:13:12.331463 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Jul 7 00:13:12.331509 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jul 7 00:13:12.341853 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Jul 7 00:13:12.341899 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Jul 7 00:13:12.350843 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 7 00:13:12.350895 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jul 7 00:13:12.362679 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Jul 7 00:13:12.496055 systemd-journald[233]: Received SIGTERM from PID 1 (systemd). Jul 7 00:13:12.362736 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev\x2dearly.service.mount: Deactivated successfully. Jul 7 00:13:12.362775 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Jul 7 00:13:12.362815 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jul 7 00:13:12.363212 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Jul 7 00:13:12.363306 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Jul 7 00:13:12.366321 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Jul 7 00:13:12.383680 systemd[1]: Starting initrd-switch-root.service - Switch Root... Jul 7 00:13:12.425090 systemd[1]: Switching root. Jul 7 00:13:12.513903 systemd-journald[233]: Journal stopped Jul 7 00:13:17.255807 kernel: SELinux: policy capability network_peer_controls=1 Jul 7 00:13:17.255836 kernel: SELinux: policy capability open_perms=1 Jul 7 00:13:17.255849 kernel: SELinux: policy capability extended_socket_class=1 Jul 7 00:13:17.255858 kernel: SELinux: policy capability always_check_network=0 Jul 7 00:13:17.255868 kernel: SELinux: policy capability cgroup_seclabel=1 Jul 7 00:13:17.255877 kernel: SELinux: policy capability nnp_nosuid_transition=1 Jul 7 00:13:17.255888 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Jul 7 00:13:17.255900 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Jul 7 00:13:17.255910 kernel: SELinux: policy capability userspace_initial_context=0 Jul 7 00:13:17.255919 kernel: audit: type=1403 audit(1751847193.422:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Jul 7 00:13:17.255931 systemd[1]: Successfully loaded SELinux policy in 220.842ms. Jul 7 00:13:17.255947 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 17.641ms. Jul 7 00:13:17.255958 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 7 00:13:17.255969 systemd[1]: Detected virtualization microsoft. Jul 7 00:13:17.255983 systemd[1]: Detected architecture x86-64. Jul 7 00:13:17.255994 systemd[1]: Detected first boot. Jul 7 00:13:17.256005 systemd[1]: Hostname set to . Jul 7 00:13:17.256015 systemd[1]: Initializing machine ID from random generator. Jul 7 00:13:17.256026 zram_generator::config[1394]: No configuration found. Jul 7 00:13:17.256040 kernel: Guest personality initialized and is inactive Jul 7 00:13:17.256063 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Jul 7 00:13:17.256074 kernel: Initialized host personality Jul 7 00:13:17.256084 kernel: NET: Registered PF_VSOCK protocol family Jul 7 00:13:17.256095 systemd[1]: Populated /etc with preset unit settings. Jul 7 00:13:17.256106 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Jul 7 00:13:17.256117 systemd[1]: initrd-switch-root.service: Deactivated successfully. Jul 7 00:13:17.256131 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Jul 7 00:13:17.256141 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Jul 7 00:13:17.256152 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Jul 7 00:13:17.256163 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Jul 7 00:13:17.256174 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Jul 7 00:13:17.256185 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Jul 7 00:13:17.256200 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Jul 7 00:13:17.256211 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Jul 7 00:13:17.256224 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Jul 7 00:13:17.256235 systemd[1]: Created slice user.slice - User and Session Slice. Jul 7 00:13:17.256246 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 7 00:13:17.256257 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 7 00:13:17.256269 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Jul 7 00:13:17.256280 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Jul 7 00:13:17.256294 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Jul 7 00:13:17.256306 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jul 7 00:13:17.256320 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Jul 7 00:13:17.256331 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 7 00:13:17.256342 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jul 7 00:13:17.256353 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Jul 7 00:13:17.256365 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Jul 7 00:13:17.256376 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Jul 7 00:13:17.256387 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Jul 7 00:13:17.256401 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 7 00:13:17.256412 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jul 7 00:13:17.256423 systemd[1]: Reached target slices.target - Slice Units. Jul 7 00:13:17.256434 systemd[1]: Reached target swap.target - Swaps. Jul 7 00:13:17.256448 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Jul 7 00:13:17.256460 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Jul 7 00:13:17.256474 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Jul 7 00:13:17.256486 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jul 7 00:13:17.256497 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jul 7 00:13:17.256509 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jul 7 00:13:17.256520 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Jul 7 00:13:17.256531 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Jul 7 00:13:17.256543 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Jul 7 00:13:17.256557 systemd[1]: Mounting media.mount - External Media Directory... Jul 7 00:13:17.256568 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 7 00:13:17.256580 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Jul 7 00:13:17.256591 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Jul 7 00:13:17.256602 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Jul 7 00:13:17.256614 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 7 00:13:17.256625 systemd[1]: Reached target machines.target - Containers. Jul 7 00:13:17.256637 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Jul 7 00:13:17.256648 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 7 00:13:17.256662 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jul 7 00:13:17.256673 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Jul 7 00:13:17.256685 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 7 00:13:17.256699 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jul 7 00:13:17.256711 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 7 00:13:17.256722 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Jul 7 00:13:17.256733 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 7 00:13:17.256744 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Jul 7 00:13:17.256759 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Jul 7 00:13:17.256770 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Jul 7 00:13:17.256781 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Jul 7 00:13:17.256793 systemd[1]: Stopped systemd-fsck-usr.service. Jul 7 00:13:17.256804 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 7 00:13:17.256816 systemd[1]: Starting systemd-journald.service - Journal Service... Jul 7 00:13:17.256827 kernel: fuse: init (API version 7.41) Jul 7 00:13:17.256838 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jul 7 00:13:17.256852 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jul 7 00:13:17.256863 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Jul 7 00:13:17.256874 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Jul 7 00:13:17.256886 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jul 7 00:13:17.256897 systemd[1]: verity-setup.service: Deactivated successfully. Jul 7 00:13:17.256908 systemd[1]: Stopped verity-setup.service. Jul 7 00:13:17.256920 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 7 00:13:17.256931 kernel: loop: module loaded Jul 7 00:13:17.256945 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Jul 7 00:13:17.256959 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Jul 7 00:13:17.256971 systemd[1]: Mounted media.mount - External Media Directory. Jul 7 00:13:17.256982 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Jul 7 00:13:17.257016 systemd-journald[1477]: Collecting audit messages is disabled. Jul 7 00:13:17.257054 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Jul 7 00:13:17.257068 systemd-journald[1477]: Journal started Jul 7 00:13:17.257091 systemd-journald[1477]: Runtime Journal (/run/log/journal/d219682af13249c08eacccfd48f1aec0) is 8M, max 2.2G, 2.1G free. Jul 7 00:13:16.457678 systemd[1]: Queued start job for default target multi-user.target. Jul 7 00:13:16.479481 systemd[1]: Unnecessary job was removed for dev-sdb6.device - /dev/sdb6. Jul 7 00:13:16.479971 systemd[1]: systemd-journald.service: Deactivated successfully. Jul 7 00:13:17.269133 systemd[1]: Started systemd-journald.service - Journal Service. Jul 7 00:13:17.273323 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Jul 7 00:13:17.277591 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Jul 7 00:13:17.283179 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jul 7 00:13:17.288557 systemd[1]: modprobe@configfs.service: Deactivated successfully. Jul 7 00:13:17.288753 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Jul 7 00:13:17.294082 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 7 00:13:17.294268 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 7 00:13:17.299244 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 7 00:13:17.299429 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 7 00:13:17.304686 systemd[1]: modprobe@fuse.service: Deactivated successfully. Jul 7 00:13:17.304878 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Jul 7 00:13:17.309631 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 7 00:13:17.309812 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 7 00:13:17.314492 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jul 7 00:13:17.319352 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jul 7 00:13:17.324857 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Jul 7 00:13:17.330464 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Jul 7 00:13:17.335906 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jul 7 00:13:17.351304 systemd[1]: Reached target network-pre.target - Preparation for Network. Jul 7 00:13:17.357283 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Jul 7 00:13:17.373282 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Jul 7 00:13:17.377761 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Jul 7 00:13:17.377801 systemd[1]: Reached target local-fs.target - Local File Systems. Jul 7 00:13:17.383022 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Jul 7 00:13:17.389258 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Jul 7 00:13:17.393383 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 7 00:13:17.419066 kernel: ACPI: bus type drm_connector registered Jul 7 00:13:17.439257 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Jul 7 00:13:17.457317 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Jul 7 00:13:17.462096 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 7 00:13:17.463153 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Jul 7 00:13:17.467524 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jul 7 00:13:17.468497 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jul 7 00:13:17.473715 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Jul 7 00:13:17.479486 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jul 7 00:13:17.486296 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 7 00:13:17.486486 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jul 7 00:13:17.491263 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Jul 7 00:13:17.497199 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Jul 7 00:13:17.502655 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Jul 7 00:13:17.508764 systemd-journald[1477]: Time spent on flushing to /var/log/journal/d219682af13249c08eacccfd48f1aec0 is 14.077ms for 952 entries. Jul 7 00:13:17.508764 systemd-journald[1477]: System Journal (/var/log/journal/d219682af13249c08eacccfd48f1aec0) is 8M, max 2.6G, 2.6G free. Jul 7 00:13:17.558870 systemd-journald[1477]: Received client request to flush runtime journal. Jul 7 00:13:17.509720 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Jul 7 00:13:17.519861 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Jul 7 00:13:17.559765 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Jul 7 00:13:17.570068 kernel: loop0: detected capacity change from 0 to 113872 Jul 7 00:13:17.584247 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Jul 7 00:13:17.584993 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Jul 7 00:13:17.612685 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jul 7 00:13:17.657429 systemd-tmpfiles[1537]: ACLs are not supported, ignoring. Jul 7 00:13:17.657451 systemd-tmpfiles[1537]: ACLs are not supported, ignoring. Jul 7 00:13:17.662852 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jul 7 00:13:17.669505 systemd[1]: Starting systemd-sysusers.service - Create System Users... Jul 7 00:13:17.965909 systemd[1]: Finished systemd-sysusers.service - Create System Users. Jul 7 00:13:17.971877 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jul 7 00:13:18.002731 systemd-tmpfiles[1554]: ACLs are not supported, ignoring. Jul 7 00:13:18.002752 systemd-tmpfiles[1554]: ACLs are not supported, ignoring. Jul 7 00:13:18.007213 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 7 00:13:18.245067 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jul 7 00:13:18.326085 kernel: loop1: detected capacity change from 0 to 146240 Jul 7 00:13:19.115075 kernel: loop2: detected capacity change from 0 to 28496 Jul 7 00:13:19.372877 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Jul 7 00:13:19.379649 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 7 00:13:19.424903 systemd-udevd[1562]: Using default interface naming scheme 'v255'. Jul 7 00:13:19.472066 kernel: loop3: detected capacity change from 0 to 113872 Jul 7 00:13:19.483072 kernel: loop4: detected capacity change from 0 to 146240 Jul 7 00:13:19.498064 kernel: loop5: detected capacity change from 0 to 28496 Jul 7 00:13:19.503291 (sd-merge)[1564]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Jul 7 00:13:19.503751 (sd-merge)[1564]: Merged extensions into '/usr'. Jul 7 00:13:19.506969 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Jul 7 00:13:19.514007 systemd[1]: Starting ensure-sysext.service... Jul 7 00:13:19.518092 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jul 7 00:13:19.556097 systemd-tmpfiles[1567]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Jul 7 00:13:19.556135 systemd-tmpfiles[1567]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Jul 7 00:13:19.556415 systemd-tmpfiles[1567]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Jul 7 00:13:19.556697 systemd-tmpfiles[1567]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Jul 7 00:13:19.556699 systemd[1]: Reload requested from client PID 1566 ('systemctl') (unit ensure-sysext.service)... Jul 7 00:13:19.556714 systemd[1]: Reloading... Jul 7 00:13:19.557591 systemd-tmpfiles[1567]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Jul 7 00:13:19.557873 systemd-tmpfiles[1567]: ACLs are not supported, ignoring. Jul 7 00:13:19.557939 systemd-tmpfiles[1567]: ACLs are not supported, ignoring. Jul 7 00:13:19.597289 systemd-tmpfiles[1567]: Detected autofs mount point /boot during canonicalization of boot. Jul 7 00:13:19.597306 systemd-tmpfiles[1567]: Skipping /boot Jul 7 00:13:19.606070 zram_generator::config[1631]: No configuration found. Jul 7 00:13:19.611138 systemd-tmpfiles[1567]: Detected autofs mount point /boot during canonicalization of boot. Jul 7 00:13:19.611240 systemd-tmpfiles[1567]: Skipping /boot Jul 7 00:13:19.661075 kernel: hv_vmbus: registering driver hv_balloon Jul 7 00:13:19.661518 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Jul 7 00:13:19.673087 kernel: mousedev: PS/2 mouse device common for all mice Jul 7 00:13:19.673143 kernel: hv_vmbus: registering driver hyperv_fb Jul 7 00:13:19.680075 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Jul 7 00:13:19.685088 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Jul 7 00:13:19.693891 kernel: Console: switching to colour dummy device 80x25 Jul 7 00:13:19.693953 kernel: Console: switching to colour frame buffer device 128x48 Jul 7 00:13:19.738745 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 7 00:13:19.758077 kernel: EDAC MC: Ver: 3.0.0 Jul 7 00:13:19.865485 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Jul 7 00:13:19.865854 systemd[1]: Reloading finished in 308 ms. Jul 7 00:13:19.888534 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 7 00:13:19.909257 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 7 00:13:19.939232 systemd[1]: Finished ensure-sysext.service. Jul 7 00:13:19.977032 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Jul 7 00:13:19.985250 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 7 00:13:19.986512 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jul 7 00:13:20.010924 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Jul 7 00:13:20.015848 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 7 00:13:20.016828 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 7 00:13:20.022215 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jul 7 00:13:20.027476 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 7 00:13:20.033108 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 7 00:13:20.037312 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 7 00:13:20.038234 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Jul 7 00:13:20.042911 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 7 00:13:20.043962 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Jul 7 00:13:20.050431 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jul 7 00:13:20.066247 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jul 7 00:13:20.071903 systemd[1]: Reached target time-set.target - System Time Set. Jul 7 00:13:20.077158 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Jul 7 00:13:20.096312 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 7 00:13:20.097840 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 7 00:13:20.099483 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 7 00:13:20.099687 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 7 00:13:20.100590 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 7 00:13:20.100781 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jul 7 00:13:20.102920 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 7 00:13:20.103371 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 7 00:13:20.103994 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 7 00:13:20.104240 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 7 00:13:20.110240 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 7 00:13:20.110405 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jul 7 00:13:20.111897 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Jul 7 00:13:20.127181 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Jul 7 00:13:20.136012 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Jul 7 00:13:20.144845 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Jul 7 00:13:20.181167 augenrules[1833]: No rules Jul 7 00:13:20.182417 systemd[1]: audit-rules.service: Deactivated successfully. Jul 7 00:13:20.182695 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jul 7 00:13:20.184964 systemd[1]: Started systemd-userdbd.service - User Database Manager. Jul 7 00:13:20.281361 systemd-resolved[1801]: Positive Trust Anchors: Jul 7 00:13:20.281384 systemd-resolved[1801]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 7 00:13:20.281415 systemd-resolved[1801]: 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 7 00:13:20.285228 systemd-resolved[1801]: Using system hostname 'ci-4344.1.1-a-4bf75d77c8'. Jul 7 00:13:20.286706 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jul 7 00:13:20.288240 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jul 7 00:13:20.329470 systemd-networkd[1800]: lo: Link UP Jul 7 00:13:20.329479 systemd-networkd[1800]: lo: Gained carrier Jul 7 00:13:20.330816 systemd-networkd[1800]: Enumeration completed Jul 7 00:13:20.330913 systemd[1]: Started systemd-networkd.service - Network Configuration. Jul 7 00:13:20.331195 systemd-networkd[1800]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 7 00:13:20.331204 systemd-networkd[1800]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 7 00:13:20.331753 systemd-networkd[1800]: eth0: Link UP Jul 7 00:13:20.331763 systemd-networkd[1800]: eth0: Gained carrier Jul 7 00:13:20.331778 systemd-networkd[1800]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 7 00:13:20.332577 systemd[1]: Reached target network.target - Network. Jul 7 00:13:20.334573 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Jul 7 00:13:20.335736 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Jul 7 00:13:20.402097 systemd-networkd[1800]: eth0: DHCPv4 address 10.200.4.15/24, gateway 10.200.4.1 acquired from 168.63.129.16 Jul 7 00:13:20.426250 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Jul 7 00:13:20.532939 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Jul 7 00:13:20.534763 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 7 00:13:20.545001 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 7 00:13:21.907187 systemd-networkd[1800]: eth0: Gained IPv6LL Jul 7 00:13:21.909504 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Jul 7 00:13:21.914959 systemd[1]: Reached target network-online.target - Network is Online. Jul 7 00:13:23.918758 ldconfig[1530]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jul 7 00:13:23.935218 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Jul 7 00:13:23.942071 systemd[1]: Starting systemd-update-done.service - Update is Completed... Jul 7 00:13:23.993370 systemd[1]: Finished systemd-update-done.service - Update is Completed. Jul 7 00:13:23.998376 systemd[1]: Reached target sysinit.target - System Initialization. Jul 7 00:13:24.002865 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Jul 7 00:13:24.008545 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 7 00:13:24.014007 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Jul 7 00:13:24.019029 systemd[1]: Started logrotate.timer - Daily rotation of log files. Jul 7 00:13:24.025426 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Jul 7 00:13:24.030984 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Jul 7 00:13:24.036205 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Jul 7 00:13:24.036234 systemd[1]: Reached target paths.target - Path Units. Jul 7 00:13:24.040062 systemd[1]: Reached target timers.target - Timer Units. Jul 7 00:13:24.059409 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Jul 7 00:13:24.065787 systemd[1]: Starting docker.socket - Docker Socket for the API... Jul 7 00:13:24.072405 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Jul 7 00:13:24.077971 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Jul 7 00:13:24.083216 systemd[1]: Reached target ssh-access.target - SSH Access Available. Jul 7 00:13:24.089737 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Jul 7 00:13:24.094625 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Jul 7 00:13:24.100282 systemd[1]: Listening on docker.socket - Docker Socket for the API. Jul 7 00:13:24.105750 systemd[1]: Reached target sockets.target - Socket Units. Jul 7 00:13:24.109736 systemd[1]: Reached target basic.target - Basic System. Jul 7 00:13:24.113613 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Jul 7 00:13:24.113643 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Jul 7 00:13:24.116314 systemd[1]: Starting chronyd.service - NTP client/server... Jul 7 00:13:24.120855 systemd[1]: Starting containerd.service - containerd container runtime... Jul 7 00:13:24.138958 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Jul 7 00:13:24.144741 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Jul 7 00:13:24.149380 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Jul 7 00:13:24.155529 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Jul 7 00:13:24.161446 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Jul 7 00:13:24.166751 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 7 00:13:24.168226 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Jul 7 00:13:24.172950 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 7 00:13:24.174472 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Jul 7 00:13:24.179061 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Jul 7 00:13:24.183235 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Jul 7 00:13:24.184108 jq[1868]: false Jul 7 00:13:24.190215 (chronyd)[1860]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Jul 7 00:13:24.191256 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Jul 7 00:13:24.191439 extend-filesystems[1869]: Found /dev/sdb6 Jul 7 00:13:24.205474 kernel: hv_utils: KVP IC version 4.0 Jul 7 00:13:24.205574 google_oslogin_nss_cache[1870]: oslogin_cache_refresh[1870]: Refreshing passwd entry cache Jul 7 00:13:24.195410 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Jul 7 00:13:24.198970 KVP[1874]: KVP starting; pid is:1874 Jul 7 00:13:24.205208 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Jul 7 00:13:24.202649 chronyd[1882]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) Jul 7 00:13:24.210447 systemd[1]: Starting systemd-logind.service - User Login Management... Jul 7 00:13:24.204097 oslogin_cache_refresh[1870]: Refreshing passwd entry cache Jul 7 00:13:24.204823 KVP[1874]: KVP LIC Version: 3.1 Jul 7 00:13:24.215860 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Jul 7 00:13:24.216328 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Jul 7 00:13:24.217131 systemd[1]: Starting update-engine.service - Update Engine... Jul 7 00:13:24.218022 chronyd[1882]: Timezone right/UTC failed leap second check, ignoring Jul 7 00:13:24.218227 chronyd[1882]: Loaded seccomp filter (level 2) Jul 7 00:13:24.221983 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Jul 7 00:13:24.223413 google_oslogin_nss_cache[1870]: oslogin_cache_refresh[1870]: Failure getting users, quitting Jul 7 00:13:24.223413 google_oslogin_nss_cache[1870]: oslogin_cache_refresh[1870]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Jul 7 00:13:24.223400 oslogin_cache_refresh[1870]: Failure getting users, quitting Jul 7 00:13:24.223543 google_oslogin_nss_cache[1870]: oslogin_cache_refresh[1870]: Refreshing group entry cache Jul 7 00:13:24.223419 oslogin_cache_refresh[1870]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Jul 7 00:13:24.223464 oslogin_cache_refresh[1870]: Refreshing group entry cache Jul 7 00:13:24.228253 systemd[1]: Started chronyd.service - NTP client/server. Jul 7 00:13:24.230163 jq[1889]: true Jul 7 00:13:24.233266 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Jul 7 00:13:24.233359 google_oslogin_nss_cache[1870]: oslogin_cache_refresh[1870]: Failure getting groups, quitting Jul 7 00:13:24.233359 google_oslogin_nss_cache[1870]: oslogin_cache_refresh[1870]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Jul 7 00:13:24.233306 oslogin_cache_refresh[1870]: Failure getting groups, quitting Jul 7 00:13:24.233318 oslogin_cache_refresh[1870]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Jul 7 00:13:24.238829 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Jul 7 00:13:24.239075 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Jul 7 00:13:24.239358 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Jul 7 00:13:24.239572 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Jul 7 00:13:24.248885 extend-filesystems[1869]: Found /dev/sdb9 Jul 7 00:13:24.252206 extend-filesystems[1869]: Checking size of /dev/sdb9 Jul 7 00:13:24.260105 jq[1891]: true Jul 7 00:13:24.249922 systemd[1]: motdgen.service: Deactivated successfully. Jul 7 00:13:24.250168 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Jul 7 00:13:24.252591 (ntainerd)[1897]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Jul 7 00:13:24.264131 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Jul 7 00:13:24.264473 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Jul 7 00:13:24.269594 extend-filesystems[1869]: Old size kept for /dev/sdb9 Jul 7 00:13:24.280590 systemd[1]: extend-filesystems.service: Deactivated successfully. Jul 7 00:13:24.280809 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Jul 7 00:13:24.299809 update_engine[1888]: I20250707 00:13:24.299731 1888 main.cc:92] Flatcar Update Engine starting Jul 7 00:13:24.314138 systemd-logind[1886]: New seat seat0. Jul 7 00:13:24.316720 systemd-logind[1886]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Jul 7 00:13:24.316915 systemd[1]: Started systemd-logind.service - User Login Management. Jul 7 00:13:24.343973 bash[1929]: Updated "/home/core/.ssh/authorized_keys" Jul 7 00:13:24.344850 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Jul 7 00:13:24.354331 dbus-daemon[1863]: [system] SELinux support is enabled Jul 7 00:13:24.355870 systemd[1]: Started dbus.service - D-Bus System Message Bus. Jul 7 00:13:24.358041 update_engine[1888]: I20250707 00:13:24.357975 1888 update_check_scheduler.cc:74] Next update check in 9m47s Jul 7 00:13:24.372153 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Jul 7 00:13:24.372245 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 7 00:13:24.372516 dbus-daemon[1863]: [system] Successfully activated service 'org.freedesktop.systemd1' Jul 7 00:13:24.372273 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Jul 7 00:13:24.377448 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 7 00:13:24.377475 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Jul 7 00:13:24.382685 systemd[1]: Started update-engine.service - Update Engine. Jul 7 00:13:24.389858 systemd[1]: Started locksmithd.service - Cluster reboot manager. Jul 7 00:13:24.399828 setup-nvidia[1876]: Downloading Flatcar Container Linux Developer Container for version: 4344.1.1 Jul 7 00:13:24.437777 coreos-metadata[1862]: Jul 07 00:13:24.437 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Jul 7 00:13:24.440192 coreos-metadata[1862]: Jul 07 00:13:24.440 INFO Fetch successful Jul 7 00:13:24.440344 coreos-metadata[1862]: Jul 07 00:13:24.440 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Jul 7 00:13:24.444803 coreos-metadata[1862]: Jul 07 00:13:24.444 INFO Fetch successful Jul 7 00:13:24.445069 coreos-metadata[1862]: Jul 07 00:13:24.445 INFO Fetching http://168.63.129.16/machine/827f1d1e-b014-4383-a17b-58f65c723877/2c18f05b%2Dcfa9%2D4170%2D9d17%2D37b07ba725e9.%5Fci%2D4344.1.1%2Da%2D4bf75d77c8?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Jul 7 00:13:24.476916 coreos-metadata[1862]: Jul 07 00:13:24.476 INFO Fetch successful Jul 7 00:13:24.477053 coreos-metadata[1862]: Jul 07 00:13:24.477 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Jul 7 00:13:24.484093 coreos-metadata[1862]: Jul 07 00:13:24.484 INFO Fetch successful Jul 7 00:13:24.524686 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Jul 7 00:13:24.530367 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Jul 7 00:13:24.562750 locksmithd[2000]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Jul 7 00:13:24.672951 sshd_keygen[1930]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Jul 7 00:13:24.694859 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Jul 7 00:13:24.701263 systemd[1]: Starting issuegen.service - Generate /run/issue... Jul 7 00:13:24.723463 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Jul 7 00:13:24.729920 systemd[1]: issuegen.service: Deactivated successfully. Jul 7 00:13:24.730170 systemd[1]: Finished issuegen.service - Generate /run/issue. Jul 7 00:13:24.735881 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Jul 7 00:13:24.753672 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Jul 7 00:13:24.756718 systemd[1]: Started getty@tty1.service - Getty on tty1. Jul 7 00:13:24.761081 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Jul 7 00:13:24.766741 systemd[1]: Reached target getty.target - Login Prompts. Jul 7 00:13:24.772128 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Jul 7 00:13:25.281358 containerd[1897]: time="2025-07-07T00:13:25Z" 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 7 00:13:25.283642 containerd[1897]: time="2025-07-07T00:13:25.283608100Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 Jul 7 00:13:25.291429 containerd[1897]: time="2025-07-07T00:13:25.291395700Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="7.6µs" Jul 7 00:13:25.291429 containerd[1897]: time="2025-07-07T00:13:25.291424300Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Jul 7 00:13:25.291510 containerd[1897]: time="2025-07-07T00:13:25.291441000Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Jul 7 00:13:25.291602 containerd[1897]: time="2025-07-07T00:13:25.291580700Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Jul 7 00:13:25.291634 containerd[1897]: time="2025-07-07T00:13:25.291603100Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Jul 7 00:13:25.291634 containerd[1897]: time="2025-07-07T00:13:25.291625900Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jul 7 00:13:25.291703 containerd[1897]: time="2025-07-07T00:13:25.291682000Z" 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 7 00:13:25.291703 containerd[1897]: time="2025-07-07T00:13:25.291700300Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Jul 7 00:13:25.291907 containerd[1897]: time="2025-07-07T00:13:25.291885800Z" 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 7 00:13:25.291938 containerd[1897]: time="2025-07-07T00:13:25.291904900Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jul 7 00:13:25.291938 containerd[1897]: time="2025-07-07T00:13:25.291915900Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jul 7 00:13:25.291938 containerd[1897]: time="2025-07-07T00:13:25.291924300Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Jul 7 00:13:25.292058 containerd[1897]: time="2025-07-07T00:13:25.292023800Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Jul 7 00:13:25.292293 containerd[1897]: time="2025-07-07T00:13:25.292270800Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Jul 7 00:13:25.292331 containerd[1897]: time="2025-07-07T00:13:25.292306900Z" 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 7 00:13:25.292331 containerd[1897]: time="2025-07-07T00:13:25.292317900Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Jul 7 00:13:25.292381 containerd[1897]: time="2025-07-07T00:13:25.292344300Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Jul 7 00:13:25.292543 containerd[1897]: time="2025-07-07T00:13:25.292523000Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Jul 7 00:13:25.292606 containerd[1897]: time="2025-07-07T00:13:25.292588800Z" level=info msg="metadata content store policy set" policy=shared Jul 7 00:13:25.307393 containerd[1897]: time="2025-07-07T00:13:25.307363700Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Jul 7 00:13:25.307457 containerd[1897]: time="2025-07-07T00:13:25.307405200Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Jul 7 00:13:25.307457 containerd[1897]: time="2025-07-07T00:13:25.307420000Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Jul 7 00:13:25.307457 containerd[1897]: time="2025-07-07T00:13:25.307431100Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Jul 7 00:13:25.307457 containerd[1897]: time="2025-07-07T00:13:25.307441500Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Jul 7 00:13:25.307547 containerd[1897]: time="2025-07-07T00:13:25.307466900Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Jul 7 00:13:25.307547 containerd[1897]: time="2025-07-07T00:13:25.307486600Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Jul 7 00:13:25.307547 containerd[1897]: time="2025-07-07T00:13:25.307497500Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Jul 7 00:13:25.307547 containerd[1897]: time="2025-07-07T00:13:25.307507300Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Jul 7 00:13:25.307547 containerd[1897]: time="2025-07-07T00:13:25.307516600Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Jul 7 00:13:25.307547 containerd[1897]: time="2025-07-07T00:13:25.307525000Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Jul 7 00:13:25.307547 containerd[1897]: time="2025-07-07T00:13:25.307535500Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Jul 7 00:13:25.307686 containerd[1897]: time="2025-07-07T00:13:25.307642700Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Jul 7 00:13:25.307686 containerd[1897]: time="2025-07-07T00:13:25.307662400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Jul 7 00:13:25.307686 containerd[1897]: time="2025-07-07T00:13:25.307675700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Jul 7 00:13:25.307755 containerd[1897]: time="2025-07-07T00:13:25.307700400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Jul 7 00:13:25.307755 containerd[1897]: time="2025-07-07T00:13:25.307731600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Jul 7 00:13:25.307755 containerd[1897]: time="2025-07-07T00:13:25.307744000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Jul 7 00:13:25.307755 containerd[1897]: time="2025-07-07T00:13:25.307754000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Jul 7 00:13:25.307848 containerd[1897]: time="2025-07-07T00:13:25.307775500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Jul 7 00:13:25.307848 containerd[1897]: time="2025-07-07T00:13:25.307789300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Jul 7 00:13:25.307848 containerd[1897]: time="2025-07-07T00:13:25.307798700Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Jul 7 00:13:25.307848 containerd[1897]: time="2025-07-07T00:13:25.307808100Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Jul 7 00:13:25.307930 containerd[1897]: time="2025-07-07T00:13:25.307863400Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Jul 7 00:13:25.307930 containerd[1897]: time="2025-07-07T00:13:25.307877600Z" level=info msg="Start snapshots syncer" Jul 7 00:13:25.307930 containerd[1897]: time="2025-07-07T00:13:25.307904000Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Jul 7 00:13:25.308201 containerd[1897]: time="2025-07-07T00:13:25.308153000Z" 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 7 00:13:25.308324 containerd[1897]: time="2025-07-07T00:13:25.308220300Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Jul 7 00:13:25.308324 containerd[1897]: time="2025-07-07T00:13:25.308292500Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Jul 7 00:13:25.308411 containerd[1897]: time="2025-07-07T00:13:25.308388400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Jul 7 00:13:25.308445 containerd[1897]: time="2025-07-07T00:13:25.308414600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Jul 7 00:13:25.308445 containerd[1897]: time="2025-07-07T00:13:25.308424900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Jul 7 00:13:25.308445 containerd[1897]: time="2025-07-07T00:13:25.308436400Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Jul 7 00:13:25.308508 containerd[1897]: time="2025-07-07T00:13:25.308447400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Jul 7 00:13:25.308508 containerd[1897]: time="2025-07-07T00:13:25.308471500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Jul 7 00:13:25.308508 containerd[1897]: time="2025-07-07T00:13:25.308496000Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Jul 7 00:13:25.308578 containerd[1897]: time="2025-07-07T00:13:25.308520900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Jul 7 00:13:25.308578 containerd[1897]: time="2025-07-07T00:13:25.308532600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Jul 7 00:13:25.308578 containerd[1897]: time="2025-07-07T00:13:25.308542700Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Jul 7 00:13:25.308578 containerd[1897]: time="2025-07-07T00:13:25.308571900Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Jul 7 00:13:25.308665 containerd[1897]: time="2025-07-07T00:13:25.308586300Z" 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 7 00:13:25.308665 containerd[1897]: time="2025-07-07T00:13:25.308594600Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jul 7 00:13:25.308665 containerd[1897]: time="2025-07-07T00:13:25.308602700Z" 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 7 00:13:25.308665 containerd[1897]: time="2025-07-07T00:13:25.308609600Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Jul 7 00:13:25.308665 containerd[1897]: time="2025-07-07T00:13:25.308618100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Jul 7 00:13:25.308665 containerd[1897]: time="2025-07-07T00:13:25.308627100Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Jul 7 00:13:25.308665 containerd[1897]: time="2025-07-07T00:13:25.308641500Z" level=info msg="runtime interface created" Jul 7 00:13:25.308665 containerd[1897]: time="2025-07-07T00:13:25.308647100Z" level=info msg="created NRI interface" Jul 7 00:13:25.308665 containerd[1897]: time="2025-07-07T00:13:25.308655200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Jul 7 00:13:25.308665 containerd[1897]: time="2025-07-07T00:13:25.308664800Z" level=info msg="Connect containerd service" Jul 7 00:13:25.308858 containerd[1897]: time="2025-07-07T00:13:25.308687900Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Jul 7 00:13:25.309339 containerd[1897]: time="2025-07-07T00:13:25.309297800Z" 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 7 00:13:25.579781 setup-nvidia[2050]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 7 00:13:25.580099 setup-nvidia[2050]: Dload Upload Total Spent Left Speed Jul 7 00:13:26.305761 containerd[1897]: time="2025-07-07T00:13:26.305704100Z" level=info msg="Start subscribing containerd event" Jul 7 00:13:26.305761 containerd[1897]: time="2025-07-07T00:13:26.305757800Z" level=info msg="Start recovering state" Jul 7 00:13:26.306183 containerd[1897]: time="2025-07-07T00:13:26.305918900Z" level=info msg="Start event monitor" Jul 7 00:13:26.306183 containerd[1897]: time="2025-07-07T00:13:26.305918100Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jul 7 00:13:26.306183 containerd[1897]: time="2025-07-07T00:13:26.305949600Z" level=info msg="Start cni network conf syncer for default" Jul 7 00:13:26.306183 containerd[1897]: time="2025-07-07T00:13:26.305996200Z" level=info msg="Start streaming server" Jul 7 00:13:26.306183 containerd[1897]: time="2025-07-07T00:13:26.306011400Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Jul 7 00:13:26.306183 containerd[1897]: time="2025-07-07T00:13:26.306019000Z" level=info msg="runtime interface starting up..." Jul 7 00:13:26.306183 containerd[1897]: time="2025-07-07T00:13:26.306024800Z" level=info msg="starting plugins..." Jul 7 00:13:26.306183 containerd[1897]: time="2025-07-07T00:13:26.305999800Z" level=info msg=serving... address=/run/containerd/containerd.sock Jul 7 00:13:26.306183 containerd[1897]: time="2025-07-07T00:13:26.306060300Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Jul 7 00:13:26.306457 containerd[1897]: time="2025-07-07T00:13:26.306427000Z" level=info msg="containerd successfully booted in 1.025429s" Jul 7 00:13:26.306695 systemd[1]: Started containerd.service - containerd container runtime. Jul 7 00:13:26.733464 waagent[2037]: 2025-07-07T00:13:26.733384Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Jul 7 00:13:26.738111 waagent[2037]: 2025-07-07T00:13:26.738067Z INFO Daemon Daemon OS: flatcar 4344.1.1 Jul 7 00:13:26.741799 waagent[2037]: 2025-07-07T00:13:26.741758Z INFO Daemon Daemon Python: 3.11.12 Jul 7 00:13:26.745474 waagent[2037]: 2025-07-07T00:13:26.745430Z INFO Daemon Daemon Run daemon Jul 7 00:13:26.748798 waagent[2037]: 2025-07-07T00:13:26.748761Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4344.1.1' Jul 7 00:13:26.755740 waagent[2037]: 2025-07-07T00:13:26.755689Z INFO Daemon Daemon Using waagent for provisioning Jul 7 00:13:26.759959 waagent[2037]: 2025-07-07T00:13:26.759917Z INFO Daemon Daemon Activate resource disk Jul 7 00:13:26.763881 waagent[2037]: 2025-07-07T00:13:26.763833Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Jul 7 00:13:26.771599 waagent[2037]: 2025-07-07T00:13:26.771546Z INFO Daemon Daemon Found device: sda Jul 7 00:13:26.781961 waagent[2037]: 2025-07-07T00:13:26.781907Z INFO Daemon Daemon Examining partition table Jul 7 00:13:26.828260 waagent[2037]: 2025-07-07T00:13:26.828193Z INFO Daemon Daemon GPT not detected, determining filesystem Jul 7 00:13:26.862957 waagent[2037]: 2025-07-07T00:13:26.862880Z INFO Daemon Daemon sfdisk --part-type -f /dev/sda 1 -n succeeded Jul 7 00:13:26.867825 waagent[2037]: 2025-07-07T00:13:26.867777Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Jul 7 00:13:27.237396 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#63 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 7 00:13:27.249065 kernel: sda: sda1 Jul 7 00:13:27.256767 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#7 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 7 00:13:27.265389 waagent[2037]: 2025-07-07T00:13:27.265314Z INFO Daemon Daemon sfdisk --part-type /dev/sda 1 83 succeeded Jul 7 00:13:27.274064 kernel: sda: sda1 Jul 7 00:13:27.285074 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#90 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 7 00:13:27.297071 kernel: sda: sda1 Jul 7 00:13:27.297241 waagent[2037]: 2025-07-07T00:13:27.297187Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sda1] Jul 7 00:13:32.921413 login[2034]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Jul 7 00:13:32.924384 login[2035]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Jul 7 00:13:32.928453 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Jul 7 00:13:32.929544 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Jul 7 00:13:32.936837 systemd-logind[1886]: New session 1 of user core. Jul 7 00:13:32.946138 systemd-logind[1886]: New session 2 of user core. Jul 7 00:13:32.984403 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Jul 7 00:13:32.987750 systemd[1]: Starting user@500.service - User Manager for UID 500... Jul 7 00:13:33.022043 waagent[2037]: 2025-07-07T00:13:33.021978Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sda1 /mnt/resource] Jul 7 00:13:33.054890 (systemd)[2140]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Jul 7 00:13:33.057273 systemd-logind[1886]: New session c1 of user core. Jul 7 00:13:33.140036 waagent[2037]: 2025-07-07T00:13:33.139948Z INFO Daemon Daemon Resource disk /dev/sda is mounted at /mnt/resource with ext4 Jul 7 00:13:33.140170 kernel: EXT4-fs (sda1): mounted filesystem 50a19f32-7a53-4bd0-993a-2c07f6195cbf r/w with ordered data mode. Quota mode: none. Jul 7 00:13:33.142260 waagent[2037]: 2025-07-07T00:13:33.142202Z INFO Daemon Daemon Clean protocol and wireserver endpoint Jul 7 00:13:33.143202 waagent[2037]: 2025-07-07T00:13:33.143166Z INFO Daemon Daemon Running default provisioning handler Jul 7 00:13:33.150874 waagent[2037]: 2025-07-07T00:13:33.150823Z 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 7 00:13:33.152680 waagent[2037]: 2025-07-07T00:13:33.152641Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Jul 7 00:13:33.153880 waagent[2037]: 2025-07-07T00:13:33.153845Z INFO Daemon Daemon cloud-init is enabled: False Jul 7 00:13:33.155402 waagent[2037]: 2025-07-07T00:13:33.155370Z INFO Daemon Daemon Copying ovf-env.xml Jul 7 00:13:33.266127 waagent[2037]: 2025-07-07T00:13:33.266037Z INFO Daemon Daemon Successfully mounted dvd Jul 7 00:13:33.280148 systemd[2140]: Queued start job for default target default.target. Jul 7 00:13:33.302588 systemd[2140]: Created slice app.slice - User Application Slice. Jul 7 00:13:33.302619 systemd[2140]: Reached target paths.target - Paths. Jul 7 00:13:33.302666 systemd[2140]: Reached target timers.target - Timers. Jul 7 00:13:33.303874 systemd[2140]: Starting dbus.socket - D-Bus User Message Bus Socket... Jul 7 00:13:33.312814 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Jul 7 00:13:33.312939 waagent[2037]: 2025-07-07T00:13:33.312853Z INFO Daemon Daemon Detect protocol endpoint Jul 7 00:13:33.314590 waagent[2037]: 2025-07-07T00:13:33.314543Z INFO Daemon Daemon Clean protocol and wireserver endpoint Jul 7 00:13:33.315766 systemd[2140]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jul 7 00:13:33.315886 systemd[2140]: Reached target sockets.target - Sockets. Jul 7 00:13:33.315941 systemd[2140]: Reached target basic.target - Basic System. Jul 7 00:13:33.315986 systemd[2140]: Reached target default.target - Main User Target. Jul 7 00:13:33.316018 systemd[2140]: Startup finished in 252ms. Jul 7 00:13:33.316116 waagent[2037]: 2025-07-07T00:13:33.316076Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Jul 7 00:13:33.316120 systemd[1]: Started user@500.service - User Manager for UID 500. Jul 7 00:13:33.316829 waagent[2037]: 2025-07-07T00:13:33.316791Z INFO Daemon Daemon Test for route to 168.63.129.16 Jul 7 00:13:33.317502 systemd[1]: Started session-1.scope - Session 1 of User core. Jul 7 00:13:33.318314 systemd[1]: Started session-2.scope - Session 2 of User core. Jul 7 00:13:33.318492 waagent[2037]: 2025-07-07T00:13:33.318454Z INFO Daemon Daemon Route to 168.63.129.16 exists Jul 7 00:13:33.319124 waagent[2037]: 2025-07-07T00:13:33.319080Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Jul 7 00:13:33.329971 waagent[2037]: 2025-07-07T00:13:33.329928Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Jul 7 00:13:33.331128 waagent[2037]: 2025-07-07T00:13:33.331104Z INFO Daemon Daemon Wire protocol version:2012-11-30 Jul 7 00:13:33.331755 waagent[2037]: 2025-07-07T00:13:33.331726Z INFO Daemon Daemon Server preferred version:2015-04-05 Jul 7 00:13:33.478947 waagent[2037]: 2025-07-07T00:13:33.478879Z INFO Daemon Daemon Initializing goal state during protocol detection Jul 7 00:13:33.480676 waagent[2037]: 2025-07-07T00:13:33.480627Z INFO Daemon Daemon Forcing an update of the goal state. Jul 7 00:13:33.484905 waagent[2037]: 2025-07-07T00:13:33.484866Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Jul 7 00:13:33.523217 waagent[2037]: 2025-07-07T00:13:33.523120Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.175 Jul 7 00:13:33.525299 waagent[2037]: 2025-07-07T00:13:33.525258Z INFO Daemon Jul 7 00:13:33.525633 waagent[2037]: 2025-07-07T00:13:33.525600Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 4337b5dc-2cdc-4bdd-adb8-6cde71ce053e eTag: 505418822727357346 source: Fabric] Jul 7 00:13:33.527484 waagent[2037]: 2025-07-07T00:13:33.527430Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Jul 7 00:13:33.528246 waagent[2037]: 2025-07-07T00:13:33.528205Z INFO Daemon Jul 7 00:13:33.529403 waagent[2037]: 2025-07-07T00:13:33.529361Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Jul 7 00:13:33.534239 waagent[2037]: 2025-07-07T00:13:33.534196Z INFO Daemon Daemon Downloading artifacts profile blob Jul 7 00:13:33.659624 waagent[2037]: 2025-07-07T00:13:33.659559Z INFO Daemon Downloaded certificate {'thumbprint': '2A6644C9F626C8889831A1F0A15D852D4FF5C997', 'hasPrivateKey': True} Jul 7 00:13:33.661655 waagent[2037]: 2025-07-07T00:13:33.661610Z INFO Daemon Fetch goal state completed Jul 7 00:13:33.703954 waagent[2037]: 2025-07-07T00:13:33.703910Z INFO Daemon Daemon Starting provisioning Jul 7 00:13:33.705654 waagent[2037]: 2025-07-07T00:13:33.705588Z INFO Daemon Daemon Handle ovf-env.xml. Jul 7 00:13:33.707037 waagent[2037]: 2025-07-07T00:13:33.706994Z INFO Daemon Daemon Set hostname [ci-4344.1.1-a-4bf75d77c8] Jul 7 00:13:33.742028 waagent[2037]: 2025-07-07T00:13:33.741965Z INFO Daemon Daemon Publish hostname [ci-4344.1.1-a-4bf75d77c8] Jul 7 00:13:33.743750 waagent[2037]: 2025-07-07T00:13:33.743701Z INFO Daemon Daemon Examine /proc/net/route for primary interface Jul 7 00:13:33.745239 waagent[2037]: 2025-07-07T00:13:33.745200Z INFO Daemon Daemon Primary interface is [eth0] Jul 7 00:13:33.762740 systemd-networkd[1800]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 7 00:13:33.762750 systemd-networkd[1800]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 7 00:13:33.762778 systemd-networkd[1800]: eth0: DHCP lease lost Jul 7 00:13:33.763245 waagent[2037]: 2025-07-07T00:13:33.763199Z INFO Daemon Daemon Create user account if not exists Jul 7 00:13:33.764964 waagent[2037]: 2025-07-07T00:13:33.764926Z INFO Daemon Daemon User core already exists, skip useradd Jul 7 00:13:33.767187 waagent[2037]: 2025-07-07T00:13:33.767140Z INFO Daemon Daemon Configure sudoer Jul 7 00:13:33.771557 waagent[2037]: 2025-07-07T00:13:33.771512Z INFO Daemon Daemon Configure sshd Jul 7 00:13:33.774969 waagent[2037]: 2025-07-07T00:13:33.774897Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Jul 7 00:13:33.775482 waagent[2037]: 2025-07-07T00:13:33.775443Z INFO Daemon Daemon Deploy ssh public key. Jul 7 00:13:33.840084 systemd-networkd[1800]: eth0: DHCPv4 address 10.200.4.15/24, gateway 10.200.4.1 acquired from 168.63.129.16 Jul 7 00:13:34.918508 waagent[2037]: 2025-07-07T00:13:34.918446Z INFO Daemon Daemon Provisioning complete Jul 7 00:13:34.927765 waagent[2037]: 2025-07-07T00:13:34.927720Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Jul 7 00:13:34.929518 waagent[2037]: 2025-07-07T00:13:34.929468Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Jul 7 00:13:34.930972 waagent[2037]: 2025-07-07T00:13:34.930935Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Jul 7 00:13:35.102794 waagent[2198]: 2025-07-07T00:13:35.102705Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Jul 7 00:13:35.103155 waagent[2198]: 2025-07-07T00:13:35.102861Z INFO ExtHandler ExtHandler OS: flatcar 4344.1.1 Jul 7 00:13:35.103155 waagent[2198]: 2025-07-07T00:13:35.102929Z INFO ExtHandler ExtHandler Python: 3.11.12 Jul 7 00:13:35.103155 waagent[2198]: 2025-07-07T00:13:35.102998Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Jul 7 00:13:35.156390 waagent[2198]: 2025-07-07T00:13:35.156313Z INFO ExtHandler ExtHandler Distro: flatcar-4344.1.1; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.12; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Jul 7 00:13:35.156595 waagent[2198]: 2025-07-07T00:13:35.156557Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jul 7 00:13:35.156674 waagent[2198]: 2025-07-07T00:13:35.156642Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Jul 7 00:13:35.163253 waagent[2198]: 2025-07-07T00:13:35.163207Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Jul 7 00:13:35.168363 waagent[2198]: 2025-07-07T00:13:35.167878Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.175 Jul 7 00:13:35.168838 waagent[2198]: 2025-07-07T00:13:35.168770Z INFO ExtHandler Jul 7 00:13:35.168881 waagent[2198]: 2025-07-07T00:13:35.168857Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: e8febf9c-1c31-4612-ad19-0def7003b039 eTag: 505418822727357346 source: Fabric] Jul 7 00:13:35.169215 waagent[2198]: 2025-07-07T00:13:35.169177Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Jul 7 00:13:35.169817 waagent[2198]: 2025-07-07T00:13:35.169779Z INFO ExtHandler Jul 7 00:13:35.169886 waagent[2198]: 2025-07-07T00:13:35.169856Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Jul 7 00:13:35.173054 waagent[2198]: 2025-07-07T00:13:35.173020Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Jul 7 00:13:35.230561 waagent[2198]: 2025-07-07T00:13:35.230503Z INFO ExtHandler Downloaded certificate {'thumbprint': '2A6644C9F626C8889831A1F0A15D852D4FF5C997', 'hasPrivateKey': True} Jul 7 00:13:35.231036 waagent[2198]: 2025-07-07T00:13:35.230989Z INFO ExtHandler Fetch goal state completed Jul 7 00:13:35.241538 waagent[2198]: 2025-07-07T00:13:35.241491Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.3.3 11 Feb 2025 (Library: OpenSSL 3.3.3 11 Feb 2025) Jul 7 00:13:35.246564 waagent[2198]: 2025-07-07T00:13:35.246522Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2198 Jul 7 00:13:35.246702 waagent[2198]: 2025-07-07T00:13:35.246668Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Jul 7 00:13:35.247031 waagent[2198]: 2025-07-07T00:13:35.246996Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Jul 7 00:13:35.248709 waagent[2198]: 2025-07-07T00:13:35.248666Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4344.1.1', '', 'Flatcar Container Linux by Kinvolk'] Jul 7 00:13:35.249162 waagent[2198]: 2025-07-07T00:13:35.249123Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4344.1.1', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Jul 7 00:13:35.249315 waagent[2198]: 2025-07-07T00:13:35.249278Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Jul 7 00:13:35.249912 waagent[2198]: 2025-07-07T00:13:35.249873Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Jul 7 00:13:35.340515 waagent[2198]: 2025-07-07T00:13:35.340464Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Jul 7 00:13:35.340722 waagent[2198]: 2025-07-07T00:13:35.340684Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Jul 7 00:13:35.347246 waagent[2198]: 2025-07-07T00:13:35.347215Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Jul 7 00:13:35.353958 systemd[1]: Reload requested from client PID 2213 ('systemctl') (unit waagent.service)... Jul 7 00:13:35.353975 systemd[1]: Reloading... Jul 7 00:13:35.415080 zram_generator::config[2252]: No configuration found. Jul 7 00:13:35.526116 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 7 00:13:35.689725 systemd[1]: Reloading finished in 335 ms. Jul 7 00:13:35.712625 waagent[2198]: 2025-07-07T00:13:35.712553Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Jul 7 00:13:35.712734 waagent[2198]: 2025-07-07T00:13:35.712699Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Jul 7 00:13:36.314575 waagent[2198]: 2025-07-07T00:13:36.314492Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Jul 7 00:13:36.315208 waagent[2198]: 2025-07-07T00:13:36.314841Z 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 7 00:13:36.315695 waagent[2198]: 2025-07-07T00:13:36.315648Z INFO ExtHandler ExtHandler Starting env monitor service. Jul 7 00:13:36.316063 waagent[2198]: 2025-07-07T00:13:36.316003Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jul 7 00:13:36.316236 waagent[2198]: 2025-07-07T00:13:36.316192Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Jul 7 00:13:36.316485 waagent[2198]: 2025-07-07T00:13:36.316439Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Jul 7 00:13:36.316932 waagent[2198]: 2025-07-07T00:13:36.316880Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Jul 7 00:13:36.317103 waagent[2198]: 2025-07-07T00:13:36.316961Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jul 7 00:13:36.317103 waagent[2198]: 2025-07-07T00:13:36.317004Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Jul 7 00:13:36.317355 waagent[2198]: 2025-07-07T00:13:36.317311Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Jul 7 00:13:36.317668 waagent[2198]: 2025-07-07T00:13:36.317553Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Jul 7 00:13:36.318285 waagent[2198]: 2025-07-07T00:13:36.318233Z INFO EnvHandler ExtHandler Configure routes Jul 7 00:13:36.318498 waagent[2198]: 2025-07-07T00:13:36.318446Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Jul 7 00:13:36.318576 waagent[2198]: 2025-07-07T00:13:36.318536Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Jul 7 00:13:36.318576 waagent[2198]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Jul 7 00:13:36.318576 waagent[2198]: eth0 00000000 0104C80A 0003 0 0 1024 00000000 0 0 0 Jul 7 00:13:36.318576 waagent[2198]: eth0 0004C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Jul 7 00:13:36.318576 waagent[2198]: eth0 0104C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Jul 7 00:13:36.318576 waagent[2198]: eth0 10813FA8 0104C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jul 7 00:13:36.318576 waagent[2198]: eth0 FEA9FEA9 0104C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jul 7 00:13:36.318855 waagent[2198]: 2025-07-07T00:13:36.318667Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Jul 7 00:13:36.318855 waagent[2198]: 2025-07-07T00:13:36.318808Z INFO EnvHandler ExtHandler Gateway:None Jul 7 00:13:36.319435 waagent[2198]: 2025-07-07T00:13:36.319395Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Jul 7 00:13:36.319479 waagent[2198]: 2025-07-07T00:13:36.319446Z INFO EnvHandler ExtHandler Routes:None Jul 7 00:13:36.324744 waagent[2198]: 2025-07-07T00:13:36.324698Z INFO ExtHandler ExtHandler Jul 7 00:13:36.325132 waagent[2198]: 2025-07-07T00:13:36.324974Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 2adef8ce-0bf5-47d3-a7c1-66eda20135ba correlation 45f6801e-7214-43ba-9474-b69216ebe6ff created: 2025-07-07T00:12:08.226929Z] Jul 7 00:13:36.326340 waagent[2198]: 2025-07-07T00:13:36.326244Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Jul 7 00:13:36.328854 waagent[2198]: 2025-07-07T00:13:36.328801Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 4 ms] Jul 7 00:13:36.368484 waagent[2198]: 2025-07-07T00:13:36.368434Z 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 7 00:13:36.368484 waagent[2198]: Try `iptables -h' or 'iptables --help' for more information.) Jul 7 00:13:36.368839 waagent[2198]: 2025-07-07T00:13:36.368801Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 4B5FCF2F-8A35-4552-AAA6-B13DE6D09F3E;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Jul 7 00:13:36.426868 waagent[2198]: 2025-07-07T00:13:36.426804Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Jul 7 00:13:36.426868 waagent[2198]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Jul 7 00:13:36.426868 waagent[2198]: pkts bytes target prot opt in out source destination Jul 7 00:13:36.426868 waagent[2198]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Jul 7 00:13:36.426868 waagent[2198]: pkts bytes target prot opt in out source destination Jul 7 00:13:36.426868 waagent[2198]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Jul 7 00:13:36.426868 waagent[2198]: pkts bytes target prot opt in out source destination Jul 7 00:13:36.426868 waagent[2198]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Jul 7 00:13:36.426868 waagent[2198]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Jul 7 00:13:36.426868 waagent[2198]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Jul 7 00:13:36.428806 waagent[2198]: 2025-07-07T00:13:36.428761Z INFO MonitorHandler ExtHandler Network interfaces: Jul 7 00:13:36.428806 waagent[2198]: Executing ['ip', '-a', '-o', 'link']: Jul 7 00:13:36.428806 waagent[2198]: 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 7 00:13:36.428806 waagent[2198]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:1e:52:1e:28:55 brd ff:ff:ff:ff:ff:ff Jul 7 00:13:36.428806 waagent[2198]: Executing ['ip', '-4', '-a', '-o', 'address']: Jul 7 00:13:36.428806 waagent[2198]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Jul 7 00:13:36.428806 waagent[2198]: 2: eth0 inet 10.200.4.15/24 metric 1024 brd 10.200.4.255 scope global eth0\ valid_lft forever preferred_lft forever Jul 7 00:13:36.428806 waagent[2198]: Executing ['ip', '-6', '-a', '-o', 'address']: Jul 7 00:13:36.428806 waagent[2198]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Jul 7 00:13:36.428806 waagent[2198]: 2: eth0 inet6 fe80::7e1e:52ff:fe1e:2855/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Jul 7 00:13:36.449583 waagent[2198]: 2025-07-07T00:13:36.449534Z INFO EnvHandler ExtHandler Current Firewall rules: Jul 7 00:13:36.449583 waagent[2198]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Jul 7 00:13:36.449583 waagent[2198]: pkts bytes target prot opt in out source destination Jul 7 00:13:36.449583 waagent[2198]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Jul 7 00:13:36.449583 waagent[2198]: pkts bytes target prot opt in out source destination Jul 7 00:13:36.449583 waagent[2198]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Jul 7 00:13:36.449583 waagent[2198]: pkts bytes target prot opt in out source destination Jul 7 00:13:36.449583 waagent[2198]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Jul 7 00:13:36.449583 waagent[2198]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Jul 7 00:13:36.449583 waagent[2198]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Jul 7 00:13:36.449829 waagent[2198]: 2025-07-07T00:13:36.449795Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Jul 7 00:13:36.449981 waagent[2198]: 2025-07-07T00:13:36.449950Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Jul 7 00:13:47.998537 chronyd[1882]: Selected source PHC0 Jul 7 00:13:48.774040 setup-nvidia[2050]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 485M 0 160k 0 0 179k 0 0:46:09 --:--:-- 0:46:09 179k\u000d 1 485M 1 9872k 0 0 5095k 0 0:01:37 0:00:01 0:01:36 5093k\u000d 7 485M 7 34.0M 0 0 11.4M 0 0:00:42 0:00:02 0:00:40 11.4M\u000d 11 485M 11 55.9M 0 0 14.3M 0 0:00:33 0:00:03 0:00:30 14.3M\u000d 16 485M 16 78.5M 0 0 15.9M 0 0:00:30 0:00:04 0:00:26 15.9M\u000d 20 485M 20 101M 0 0 16.9M 0 0:00:28 0:00:05 0:00:23 19.9M\u000d 24 485M 24 119M 0 0 17.0M 0 0:00:28 0:00:06 0:00:22 21.6M\u000d 28 485M 28 140M 0 0 17.8M 0 0:00:27 0:00:07 0:00:20 21.7M\u000d 33 485M 33 164M 0 0 18.4M 0 0:00:26 0:00:08 0:00:18 21.5M\u000d 38 485M 38 188M 0 0 18.8M 0 0:00:25 0:00:09 0:00:16 21.7M\u000d 42 485M 42 208M 0 0 19.1M 0 0:00:25 0:00:10 0:00:15 21.7M\u000d 47 485M 47 230M 0 0 19.3M 0 0:00:25 0:00:11 0:00:14 22.5M\u000d 52 485M 52 254M 0 0 19.6M 0 0:00:24 0:00:12 0:00:12 22.4M\u000d 56 485M 56 274M 0 0 19.7M 0 0:00:24 0:00:13 0:00:11 22.0M\u000d 61 485M 61 296M 0 0 19.8M 0 0:00:24 0:00:14 0:00:10 21.8M\u000d 65 485M 65 319M 0 0 19.9M 0 0:00:24 0:00:15 0:00:09 21.8M\u000d 69 485M 69 339M 0 0 20.1M 0 0:00:24 0:00:16 0:00:08 22.0M\u000d 74 485M 74 363M 0 0 20.2M 0 0:00:23 0:00:17 0:00:06 21.9M\u000d 79 485M 79 387M 0 0 20.3M 0 0:00:23 0:00:18 0:00:05 22.1M\u000d 83 485M 83 407M 0 0 20.4M 0 0:00:23 0:00:19 0:00:04 22.3M\u000d 88 485M 88 431M 0 0 20.5M 0 0:00:23 0:00:20 0:00:03 22.5M\u000d 93 485M 93 455M 0 0 20.7M 0 0:00:23 0:00:21 0:00:02 22.6M\u000d 98 485M 98 476M 0 0 20.8M 0 0:00:23 0:00:22 0:00:01 22.7M\u000d100 485M 100 485M 0 0 20.9M 0 0:00:23 0:00:23 --:--:-- 23.3M Jul 7 00:13:55.346428 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Jul 7 00:13:55.356435 systemd[1]: Started sshd@0-10.200.4.15:22-10.200.16.10:43972.service - OpenSSH per-connection server daemon (10.200.16.10:43972). Jul 7 00:13:56.101169 sshd[2353]: Accepted publickey for core from 10.200.16.10 port 43972 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:13:56.101991 sshd-session[2353]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:13:56.112243 systemd-logind[1886]: New session 3 of user core. Jul 7 00:13:56.118226 systemd[1]: Started session-3.scope - Session 3 of User core. Jul 7 00:13:56.630382 systemd[1]: Started sshd@1-10.200.4.15:22-10.200.16.10:43982.service - OpenSSH per-connection server daemon (10.200.16.10:43982). Jul 7 00:13:57.238422 sshd[2358]: Accepted publickey for core from 10.200.16.10 port 43982 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:13:57.239819 sshd-session[2358]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:13:57.248227 systemd-logind[1886]: New session 4 of user core. Jul 7 00:13:57.254472 systemd[1]: Started session-4.scope - Session 4 of User core. Jul 7 00:13:57.672010 sshd[2360]: Connection closed by 10.200.16.10 port 43982 Jul 7 00:13:57.671249 sshd-session[2358]: pam_unix(sshd:session): session closed for user core Jul 7 00:13:57.675331 systemd[1]: sshd@1-10.200.4.15:22-10.200.16.10:43982.service: Deactivated successfully. Jul 7 00:13:57.678577 systemd[1]: session-4.scope: Deactivated successfully. Jul 7 00:13:57.681184 systemd-logind[1886]: Session 4 logged out. Waiting for processes to exit. Jul 7 00:13:57.682660 systemd-logind[1886]: Removed session 4. Jul 7 00:13:57.781406 systemd[1]: Started sshd@2-10.200.4.15:22-10.200.16.10:43984.service - OpenSSH per-connection server daemon (10.200.16.10:43984). Jul 7 00:13:58.403825 sshd[2366]: Accepted publickey for core from 10.200.16.10 port 43984 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:13:58.405249 sshd-session[2366]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:13:58.410821 systemd-logind[1886]: New session 5 of user core. Jul 7 00:13:58.417194 systemd[1]: Started session-5.scope - Session 5 of User core. Jul 7 00:13:58.832896 sshd[2368]: Connection closed by 10.200.16.10 port 43984 Jul 7 00:13:58.833257 sshd-session[2366]: pam_unix(sshd:session): session closed for user core Jul 7 00:13:58.836772 systemd[1]: sshd@2-10.200.4.15:22-10.200.16.10:43984.service: Deactivated successfully. Jul 7 00:13:58.838330 systemd[1]: session-5.scope: Deactivated successfully. Jul 7 00:13:58.838998 systemd-logind[1886]: Session 5 logged out. Waiting for processes to exit. Jul 7 00:13:58.839965 systemd-logind[1886]: Removed session 5. Jul 7 00:13:58.987669 setup-nvidia[1876]: Downloading NVIDIA 535.230.02 Driver Jul 7 00:13:58.991954 setup-nvidia[2374]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 7 00:13:58.992176 setup-nvidia[2374]: Dload Upload Total Spent Left Speed Jul 7 00:14:01.679642 setup-nvidia[2374]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 2 326M 2 8193k 0 0 17.3M 0 0:00:18 --:--:-- 0:00:18 17.3M\u000d 46 326M 46 150M 0 0 102M 0 0:00:03 0:00:01 0:00:02 102M\u000d 90 326M 90 294M 0 0 119M 0 0:00:02 0:00:02 --:--:-- 119M\u000d100 326M 100 326M 0 0 121M 0 0:00:02 0:00:02 --:--:-- 121M Jul 7 00:14:01.779162 setup-nvidia[1876]: Extract the NVIDIA Driver Installer 535.230.02 Jul 7 00:14:01.779394 setup-nvidia[1876]: /opt/nvidia/workdir/nvidia-workdir / Jul 7 00:14:01.796535 setup-nvidia[2377]: Creating directory NVIDIA-Linux-x86_64-535.230.02 Jul 7 00:14:02.051023 setup-nvidia[2377]: Verifying archive integrity... OK Jul 7 00:14:02.808038 setup-nvidia[2377]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.230.02 Jul 7 00:14:04.570638 setup-nvidia[2403]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Jul 7 00:14:04.584580 setup-nvidia[1876]: / Jul 7 00:14:04.584754 setup-nvidia[1876]: Spawn system-nspawn container to install the NVIDIA drivers Jul 7 00:14:04.904707 sudo[2410]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4344.1.1.bin --overlay=/usr/lib/modules::/usr/lib/modules --bind=/opt/nvidia/workdir/nvidia-workdir:/nvidia --bind=/usr/lib/nvidia/bin:/app/bin/ /app/bin/install-nvidia NVIDIA-Linux-x86_64-535.230.02 Jul 7 00:14:04.906470 sudo[2410]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 7 00:14:04.912314 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Jul 7 00:14:04.915845 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Jul 7 00:14:04.942612 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Jul 7 00:14:04.946469 systemd[1]: Starting user@0.service - User Manager for UID 0... Jul 7 00:14:04.970796 (systemd)[2413]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Jul 7 00:14:04.973164 systemd-logind[1886]: New session c3 of user root. Jul 7 00:14:05.168420 systemd[2413]: Queued start job for default target default.target. Jul 7 00:14:05.189226 systemd[2413]: Created slice app.slice - User Application Slice. Jul 7 00:14:05.189257 systemd[2413]: Reached target paths.target - Paths. Jul 7 00:14:05.189303 systemd[2413]: Reached target timers.target - Timers. Jul 7 00:14:05.190523 systemd[2413]: Starting dbus.socket - D-Bus User Message Bus Socket... Jul 7 00:14:05.201015 systemd[2413]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jul 7 00:14:05.201205 systemd[2413]: Reached target sockets.target - Sockets. Jul 7 00:14:05.201263 systemd[2413]: Reached target basic.target - Basic System. Jul 7 00:14:05.201310 systemd[2413]: Reached target default.target - Main User Target. Jul 7 00:14:05.201343 systemd[2413]: Startup finished in 222ms. Jul 7 00:14:05.201415 systemd[1]: Started user@0.service - User Manager for UID 0. Jul 7 00:14:05.204709 systemd[1]: Started session-c2.scope - Session c2 of User root. Jul 7 00:14:05.238071 kernel: loop6: detected capacity change from 0 to 12644352 Jul 7 00:14:05.248068 kernel: loop6: p9 Jul 7 00:14:05.369460 setup-nvidia[2422]: ░ Spawning container flatcardevelopercontainer-4344.1.1.bin on /opt/nvidia/workdir/flatcar_developer_container-4344.1.1.bin. Jul 7 00:14:06.698838 kernel: EXT4-fs (loop6p9): write access unavailable, skipping orphan cleanup Jul 7 00:14:06.698968 kernel: EXT4-fs (loop6p9): mounted filesystem 652653fa-37fd-4534-a1c2-1154064d51e9 ro without journal. Quota mode: none. Jul 7 00:14:07.777003 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Jul 7 00:14:08.940498 systemd[1]: Started sshd@3-10.200.4.15:22-10.200.16.10:53680.service - OpenSSH per-connection server daemon (10.200.16.10:53680). Jul 7 00:14:09.254142 update_engine[1888]: I20250707 00:14:09.254087 1888 update_attempter.cc:509] Updating boot flags... Jul 7 00:14:09.563023 sshd[2440]: Accepted publickey for core from 10.200.16.10 port 53680 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:14:09.564352 sshd-session[2440]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:14:09.568679 systemd-logind[1886]: New session 6 of user core. Jul 7 00:14:09.584172 systemd[1]: Started session-6.scope - Session 6 of User core. Jul 7 00:14:09.985461 sshd[2602]: Connection closed by 10.200.16.10 port 53680 Jul 7 00:14:09.985817 sshd-session[2440]: pam_unix(sshd:session): session closed for user core Jul 7 00:14:09.989255 systemd[1]: sshd@3-10.200.4.15:22-10.200.16.10:53680.service: Deactivated successfully. Jul 7 00:14:09.990793 systemd[1]: session-6.scope: Deactivated successfully. Jul 7 00:14:09.991482 systemd-logind[1886]: Session 6 logged out. Waiting for processes to exit. Jul 7 00:14:09.992435 systemd-logind[1886]: Removed session 6. Jul 7 00:14:10.095295 systemd[1]: Started sshd@4-10.200.4.15:22-10.200.16.10:54732.service - OpenSSH per-connection server daemon (10.200.16.10:54732). Jul 7 00:14:10.695062 sshd[2609]: Accepted publickey for core from 10.200.16.10 port 54732 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:14:10.696400 sshd-session[2609]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:14:10.700750 systemd-logind[1886]: New session 7 of user core. Jul 7 00:14:10.715188 systemd[1]: Started session-7.scope - Session 7 of User core. Jul 7 00:14:11.125772 sshd[2611]: Connection closed by 10.200.16.10 port 54732 Jul 7 00:14:11.126171 sshd-session[2609]: pam_unix(sshd:session): session closed for user core Jul 7 00:14:11.129156 systemd[1]: sshd@4-10.200.4.15:22-10.200.16.10:54732.service: Deactivated successfully. Jul 7 00:14:11.130849 systemd[1]: session-7.scope: Deactivated successfully. Jul 7 00:14:11.132106 systemd-logind[1886]: Session 7 logged out. Waiting for processes to exit. Jul 7 00:14:11.133672 systemd-logind[1886]: Removed session 7. Jul 7 00:14:21.244399 systemd[1]: Started sshd@5-10.200.4.15:22-10.200.16.10:34946.service - OpenSSH per-connection server daemon (10.200.16.10:34946). Jul 7 00:14:21.838920 sshd[3278]: Accepted publickey for core from 10.200.16.10 port 34946 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:14:21.840250 sshd-session[3278]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:14:21.844561 systemd-logind[1886]: New session 8 of user core. Jul 7 00:14:21.856168 systemd[1]: Started session-8.scope - Session 8 of User core. Jul 7 00:14:22.267584 sshd[3280]: Connection closed by 10.200.16.10 port 34946 Jul 7 00:14:22.267943 sshd-session[3278]: pam_unix(sshd:session): session closed for user core Jul 7 00:14:22.271359 systemd[1]: sshd@5-10.200.4.15:22-10.200.16.10:34946.service: Deactivated successfully. Jul 7 00:14:22.272876 systemd[1]: session-8.scope: Deactivated successfully. Jul 7 00:14:22.273543 systemd-logind[1886]: Session 8 logged out. Waiting for processes to exit. Jul 7 00:14:22.274498 systemd-logind[1886]: Removed session 8. Jul 7 00:14:22.383341 systemd[1]: Started sshd@6-10.200.4.15:22-10.200.16.10:34958.service - OpenSSH per-connection server daemon (10.200.16.10:34958). Jul 7 00:14:22.977943 sshd[3286]: Accepted publickey for core from 10.200.16.10 port 34958 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:14:22.979337 sshd-session[3286]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:14:22.984477 systemd-logind[1886]: New session 9 of user core. Jul 7 00:14:23.005166 systemd[1]: Started session-9.scope - Session 9 of User core. Jul 7 00:14:23.405794 sshd[3288]: Connection closed by 10.200.16.10 port 34958 Jul 7 00:14:23.406173 sshd-session[3286]: pam_unix(sshd:session): session closed for user core Jul 7 00:14:23.409672 systemd[1]: sshd@6-10.200.4.15:22-10.200.16.10:34958.service: Deactivated successfully. Jul 7 00:14:23.411245 systemd[1]: session-9.scope: Deactivated successfully. Jul 7 00:14:23.411948 systemd-logind[1886]: Session 9 logged out. Waiting for processes to exit. Jul 7 00:14:23.412886 systemd-logind[1886]: Removed session 9. Jul 7 00:14:33.524630 systemd[1]: Started sshd@7-10.200.4.15:22-10.200.16.10:46932.service - OpenSSH per-connection server daemon (10.200.16.10:46932). Jul 7 00:14:34.135712 sshd[3435]: Accepted publickey for core from 10.200.16.10 port 46932 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:14:34.137112 sshd-session[3435]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:14:34.141404 systemd-logind[1886]: New session 10 of user core. Jul 7 00:14:34.154169 systemd[1]: Started session-10.scope - Session 10 of User core. Jul 7 00:14:34.559314 sshd[3437]: Connection closed by 10.200.16.10 port 46932 Jul 7 00:14:34.576454 sshd-session[3435]: pam_unix(sshd:session): session closed for user core Jul 7 00:14:34.579901 systemd[1]: sshd@7-10.200.4.15:22-10.200.16.10:46932.service: Deactivated successfully. Jul 7 00:14:34.581482 systemd[1]: session-10.scope: Deactivated successfully. Jul 7 00:14:34.582153 systemd-logind[1886]: Session 10 logged out. Waiting for processes to exit. Jul 7 00:14:34.583083 systemd-logind[1886]: Removed session 10. Jul 7 00:14:34.664302 systemd[1]: Started sshd@8-10.200.4.15:22-10.200.16.10:46948.service - OpenSSH per-connection server daemon (10.200.16.10:46948). Jul 7 00:14:35.259508 sshd[3443]: Accepted publickey for core from 10.200.16.10 port 46948 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:14:35.260817 sshd-session[3443]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:14:35.265063 systemd-logind[1886]: New session 11 of user core. Jul 7 00:14:35.274761 systemd[1]: Started session-11.scope - Session 11 of User core. Jul 7 00:14:35.688783 sshd[3445]: Connection closed by 10.200.16.10 port 46948 Jul 7 00:14:35.689086 sshd-session[3443]: pam_unix(sshd:session): session closed for user core Jul 7 00:14:35.692513 systemd[1]: sshd@8-10.200.4.15:22-10.200.16.10:46948.service: Deactivated successfully. Jul 7 00:14:35.694304 systemd[1]: session-11.scope: Deactivated successfully. Jul 7 00:14:35.694974 systemd-logind[1886]: Session 11 logged out. Waiting for processes to exit. Jul 7 00:14:35.696122 systemd-logind[1886]: Removed session 11. Jul 7 00:14:45.798102 systemd[1]: Started sshd@9-10.200.4.15:22-10.200.16.10:36300.service - OpenSSH per-connection server daemon (10.200.16.10:36300). Jul 7 00:14:46.417202 sshd[6511]: Accepted publickey for core from 10.200.16.10 port 36300 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:14:46.418602 sshd-session[6511]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:14:46.427253 systemd-logind[1886]: New session 12 of user core. Jul 7 00:14:46.435625 systemd[1]: Started session-12.scope - Session 12 of User core. Jul 7 00:14:46.848269 sshd[6735]: Connection closed by 10.200.16.10 port 36300 Jul 7 00:14:46.848830 sshd-session[6511]: pam_unix(sshd:session): session closed for user core Jul 7 00:14:46.851881 systemd[1]: sshd@9-10.200.4.15:22-10.200.16.10:36300.service: Deactivated successfully. Jul 7 00:14:46.853708 systemd[1]: session-12.scope: Deactivated successfully. Jul 7 00:14:46.858105 systemd-logind[1886]: Session 12 logged out. Waiting for processes to exit. Jul 7 00:14:46.859224 systemd-logind[1886]: Removed session 12. Jul 7 00:14:46.956704 systemd[1]: Started sshd@10-10.200.4.15:22-10.200.16.10:36306.service - OpenSSH per-connection server daemon (10.200.16.10:36306). Jul 7 00:14:47.581894 sshd[6901]: Accepted publickey for core from 10.200.16.10 port 36306 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:14:47.582404 sshd-session[6901]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:14:47.590674 systemd-logind[1886]: New session 13 of user core. Jul 7 00:14:47.598660 systemd[1]: Started session-13.scope - Session 13 of User core. Jul 7 00:14:48.013659 sshd[7112]: Connection closed by 10.200.16.10 port 36306 Jul 7 00:14:48.014211 sshd-session[6901]: pam_unix(sshd:session): session closed for user core Jul 7 00:14:48.019961 systemd[1]: sshd@10-10.200.4.15:22-10.200.16.10:36306.service: Deactivated successfully. Jul 7 00:14:48.023471 systemd[1]: session-13.scope: Deactivated successfully. Jul 7 00:14:48.024715 systemd-logind[1886]: Session 13 logged out. Waiting for processes to exit. Jul 7 00:14:48.026429 systemd-logind[1886]: Removed session 13. Jul 7 00:14:58.124093 systemd[1]: Started sshd@11-10.200.4.15:22-10.200.16.10:53552.service - OpenSSH per-connection server daemon (10.200.16.10:53552). Jul 7 00:14:58.744077 sshd[10719]: Accepted publickey for core from 10.200.16.10 port 53552 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:14:58.744670 sshd-session[10719]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:14:58.749840 systemd-logind[1886]: New session 14 of user core. Jul 7 00:14:58.758333 systemd[1]: Started session-14.scope - Session 14 of User core. Jul 7 00:14:59.168374 sshd[10908]: Connection closed by 10.200.16.10 port 53552 Jul 7 00:14:59.169670 sshd-session[10719]: pam_unix(sshd:session): session closed for user core Jul 7 00:14:59.174126 systemd[1]: sshd@11-10.200.4.15:22-10.200.16.10:53552.service: Deactivated successfully. Jul 7 00:14:59.176119 systemd[1]: session-14.scope: Deactivated successfully. Jul 7 00:14:59.178123 systemd-logind[1886]: Session 14 logged out. Waiting for processes to exit. Jul 7 00:14:59.179672 systemd-logind[1886]: Removed session 14. Jul 7 00:14:59.275281 systemd[1]: Started sshd@12-10.200.4.15:22-10.200.16.10:53568.service - OpenSSH per-connection server daemon (10.200.16.10:53568). Jul 7 00:14:59.875492 sshd[11201]: Accepted publickey for core from 10.200.16.10 port 53568 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:14:59.876849 sshd-session[11201]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:14:59.881136 systemd-logind[1886]: New session 15 of user core. Jul 7 00:14:59.892188 systemd[1]: Started session-15.scope - Session 15 of User core. Jul 7 00:15:00.305328 sshd[11354]: Connection closed by 10.200.16.10 port 53568 Jul 7 00:15:00.305663 sshd-session[11201]: pam_unix(sshd:session): session closed for user core Jul 7 00:15:00.309306 systemd-logind[1886]: Session 15 logged out. Waiting for processes to exit. Jul 7 00:15:00.309730 systemd[1]: sshd@12-10.200.4.15:22-10.200.16.10:53568.service: Deactivated successfully. Jul 7 00:15:00.312943 systemd[1]: session-15.scope: Deactivated successfully. Jul 7 00:15:00.315529 systemd-logind[1886]: Removed session 15. Jul 7 00:15:10.422857 systemd[1]: Started sshd@13-10.200.4.15:22-10.200.16.10:40184.service - OpenSSH per-connection server daemon (10.200.16.10:40184). Jul 7 00:15:11.039818 sshd[11782]: Accepted publickey for core from 10.200.16.10 port 40184 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:15:11.040710 sshd-session[11782]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:15:11.047750 systemd-logind[1886]: New session 16 of user core. Jul 7 00:15:11.054266 systemd[1]: Started session-16.scope - Session 16 of User core. Jul 7 00:15:11.468179 sshd[11807]: Connection closed by 10.200.16.10 port 40184 Jul 7 00:15:11.468939 sshd-session[11782]: pam_unix(sshd:session): session closed for user core Jul 7 00:15:11.476464 systemd-logind[1886]: Session 16 logged out. Waiting for processes to exit. Jul 7 00:15:11.477276 systemd[1]: sshd@13-10.200.4.15:22-10.200.16.10:40184.service: Deactivated successfully. Jul 7 00:15:11.479464 systemd[1]: session-16.scope: Deactivated successfully. Jul 7 00:15:11.484537 systemd-logind[1886]: Removed session 16. Jul 7 00:15:11.577693 systemd[1]: Started sshd@14-10.200.4.15:22-10.200.16.10:40186.service - OpenSSH per-connection server daemon (10.200.16.10:40186). Jul 7 00:15:12.202552 sshd[11837]: Accepted publickey for core from 10.200.16.10 port 40186 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:15:12.204009 sshd-session[11837]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:15:12.213022 systemd-logind[1886]: New session 17 of user core. Jul 7 00:15:12.222140 systemd[1]: Started session-17.scope - Session 17 of User core. Jul 7 00:15:12.631715 sshd[11851]: Connection closed by 10.200.16.10 port 40186 Jul 7 00:15:12.632349 sshd-session[11837]: pam_unix(sshd:session): session closed for user core Jul 7 00:15:12.637723 systemd-logind[1886]: Session 17 logged out. Waiting for processes to exit. Jul 7 00:15:12.639273 systemd[1]: sshd@14-10.200.4.15:22-10.200.16.10:40186.service: Deactivated successfully. Jul 7 00:15:12.640793 systemd[1]: session-17.scope: Deactivated successfully. Jul 7 00:15:12.643437 systemd-logind[1886]: Removed session 17. Jul 7 00:15:22.737932 systemd[1]: Started sshd@15-10.200.4.15:22-10.200.16.10:35262.service - OpenSSH per-connection server daemon (10.200.16.10:35262). Jul 7 00:15:23.360261 sshd[12183]: Accepted publickey for core from 10.200.16.10 port 35262 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:15:23.361536 sshd-session[12183]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:15:23.370890 systemd-logind[1886]: New session 18 of user core. Jul 7 00:15:23.379207 systemd[1]: Started session-18.scope - Session 18 of User core. Jul 7 00:15:23.791280 sshd[12213]: Connection closed by 10.200.16.10 port 35262 Jul 7 00:15:23.791650 sshd-session[12183]: pam_unix(sshd:session): session closed for user core Jul 7 00:15:23.794608 systemd[1]: sshd@15-10.200.4.15:22-10.200.16.10:35262.service: Deactivated successfully. Jul 7 00:15:23.796700 systemd[1]: session-18.scope: Deactivated successfully. Jul 7 00:15:23.797838 systemd-logind[1886]: Session 18 logged out. Waiting for processes to exit. Jul 7 00:15:23.802355 systemd-logind[1886]: Removed session 18. Jul 7 00:15:23.898806 systemd[1]: Started sshd@16-10.200.4.15:22-10.200.16.10:35266.service - OpenSSH per-connection server daemon (10.200.16.10:35266). Jul 7 00:15:24.525718 sshd[12231]: Accepted publickey for core from 10.200.16.10 port 35266 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:15:24.526907 sshd-session[12231]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:15:24.535871 systemd-logind[1886]: New session 19 of user core. Jul 7 00:15:24.544224 systemd[1]: Started session-19.scope - Session 19 of User core. Jul 7 00:15:24.961291 sshd[12257]: Connection closed by 10.200.16.10 port 35266 Jul 7 00:15:24.962265 sshd-session[12231]: pam_unix(sshd:session): session closed for user core Jul 7 00:15:24.966452 systemd-logind[1886]: Session 19 logged out. Waiting for processes to exit. Jul 7 00:15:24.967946 systemd[1]: sshd@16-10.200.4.15:22-10.200.16.10:35266.service: Deactivated successfully. Jul 7 00:15:24.969536 systemd[1]: session-19.scope: Deactivated successfully. Jul 7 00:15:24.971910 systemd-logind[1886]: Removed session 19. Jul 7 00:15:35.075686 systemd[1]: Started sshd@17-10.200.4.15:22-10.200.16.10:46460.service - OpenSSH per-connection server daemon (10.200.16.10:46460). Jul 7 00:15:35.675873 sshd[12542]: Accepted publickey for core from 10.200.16.10 port 46460 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:15:35.677224 sshd-session[12542]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:15:35.681585 systemd-logind[1886]: New session 20 of user core. Jul 7 00:15:35.693175 systemd[1]: Started session-20.scope - Session 20 of User core. Jul 7 00:15:36.097737 sshd[12544]: Connection closed by 10.200.16.10 port 46460 Jul 7 00:15:36.098123 sshd-session[12542]: pam_unix(sshd:session): session closed for user core Jul 7 00:15:36.101682 systemd[1]: sshd@17-10.200.4.15:22-10.200.16.10:46460.service: Deactivated successfully. Jul 7 00:15:36.103320 systemd[1]: session-20.scope: Deactivated successfully. Jul 7 00:15:36.103971 systemd-logind[1886]: Session 20 logged out. Waiting for processes to exit. Jul 7 00:15:36.104896 systemd-logind[1886]: Removed session 20. Jul 7 00:15:36.214378 systemd[1]: Started sshd@18-10.200.4.15:22-10.200.16.10:46472.service - OpenSSH per-connection server daemon (10.200.16.10:46472). Jul 7 00:15:36.812761 sshd[12550]: Accepted publickey for core from 10.200.16.10 port 46472 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:15:36.814112 sshd-session[12550]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:15:36.818495 systemd-logind[1886]: New session 21 of user core. Jul 7 00:15:36.830183 systemd[1]: Started session-21.scope - Session 21 of User core. Jul 7 00:15:37.235224 sshd[12552]: Connection closed by 10.200.16.10 port 46472 Jul 7 00:15:37.235598 sshd-session[12550]: pam_unix(sshd:session): session closed for user core Jul 7 00:15:37.239326 systemd[1]: sshd@18-10.200.4.15:22-10.200.16.10:46472.service: Deactivated successfully. Jul 7 00:15:37.240867 systemd[1]: session-21.scope: Deactivated successfully. Jul 7 00:15:37.241547 systemd-logind[1886]: Session 21 logged out. Waiting for processes to exit. Jul 7 00:15:37.242486 systemd-logind[1886]: Removed session 21. Jul 7 00:15:41.825549 setup-nvidia[2422]: \u000d Jul 7 00:15:41.825549 setup-nvidia[2422]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Jul 7 00:15:41.825549 setup-nvidia[2422]: frequently when this kernel module was built against the wrong or Jul 7 00:15:41.825549 setup-nvidia[2422]: improperly configured kernel sources, with a version of gcc that differs Jul 7 00:15:41.825549 setup-nvidia[2422]: from the one used to build the target kernel, or if another driver, such Jul 7 00:15:41.825549 setup-nvidia[2422]: as nouveau, is present and prevents the NVIDIA kernel module from Jul 7 00:15:41.825549 setup-nvidia[2422]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Jul 7 00:15:41.825549 setup-nvidia[2422]: installed in this system is supported by this NVIDIA Linux graphics Jul 7 00:15:41.825549 setup-nvidia[2422]: driver release. Jul 7 00:15:41.825549 setup-nvidia[2422]: \u000d Jul 7 00:15:41.825549 setup-nvidia[2422]: Please see the log entries 'Kernel module load error' and 'Kernel Jul 7 00:15:41.825549 setup-nvidia[2422]: messages' at the end of the file Jul 7 00:15:41.825549 setup-nvidia[2422]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more Jul 7 00:15:41.825549 setup-nvidia[2422]: information. Jul 7 00:15:41.825549 setup-nvidia[2422]: \u000d Jul 7 00:15:41.840165 setup-nvidia[2422]: dmesg: read kernel buffer failed: Operation not permitted Jul 7 00:15:41.858782 setup-nvidia[2422]: \u000d Jul 7 00:15:41.858782 setup-nvidia[2422]: ERROR: Installation has failed. Please see the file Jul 7 00:15:41.858782 setup-nvidia[2422]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for Jul 7 00:15:41.858782 setup-nvidia[2422]: details. You may find suggestions on fixing installation problems in Jul 7 00:15:41.858782 setup-nvidia[2422]: the README available on the Linux driver download page at Jul 7 00:15:41.858782 setup-nvidia[2422]: www.nvidia.com. Jul 7 00:15:41.858782 setup-nvidia[2422]: \u000d Jul 7 00:15:41.859158 setup-nvidia[2422]: Last 50 lines of nvidia-installer.log: Jul 7 00:15:41.859947 setup-nvidia[2422]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039000rm+0x53: 'naked' return found in MITIGATION_RETHUNK build Jul 7 00:15:41.859947 setup-nvidia[2422]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038997rm+0x90: 'naked' return found in MITIGATION_RETHUNK build Jul 7 00:15:41.859947 setup-nvidia[2422]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038990rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build Jul 7 00:15:41.859947 setup-nvidia[2422]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039046rm+0x93: 'naked' return found in MITIGATION_RETHUNK build Jul 7 00:15:41.859947 setup-nvidia[2422]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039042rm+0x36: 'naked' return found in MITIGATION_RETHUNK build Jul 7 00:15:41.859947 setup-nvidia[2422]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039041rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build Jul 7 00:15:41.859947 setup-nvidia[2422]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039043rm+0x77: 'naked' return found in MITIGATION_RETHUNK build Jul 7 00:15:41.859947 setup-nvidia[2422]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038987rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build Jul 7 00:15:41.859947 setup-nvidia[2422]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030558rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build Jul 7 00:15:41.859947 setup-nvidia[2422]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030557rm+0x27: 'naked' return found in MITIGATION_RETHUNK build Jul 7 00:15:41.859947 setup-nvidia[2422]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030561rm+0x37: 'naked' return found in MITIGATION_RETHUNK build Jul 7 00:15:41.859947 setup-nvidia[2422]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030560rm+0x24: 'naked' return found in MITIGATION_RETHUNK build Jul 7 00:15:41.859947 setup-nvidia[2422]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030559rm+0xca: 'naked' return found in MITIGATION_RETHUNK build Jul 7 00:15:41.859947 setup-nvidia[2422]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030562rm+0x193: 'naked' return found in MITIGATION_RETHUNK build Jul 7 00:15:41.859947 setup-nvidia[2422]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030564rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build Jul 7 00:15:41.859947 setup-nvidia[2422]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv011860rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build Jul 7 00:15:41.859947 setup-nvidia[2422]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039354rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build Jul 7 00:15:41.859947 setup-nvidia[2422]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/Module.symvers Jul 7 00:15:41.859947 setup-nvidia[2422]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.mod.o Jul 7 00:15:41.859947 setup-nvidia[2422]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/.module-common.o Jul 7 00:15:41.859947 setup-nvidia[2422]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.mod.o Jul 7 00:15:41.859947 setup-nvidia[2422]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.mod.o Jul 7 00:15:41.859947 setup-nvidia[2422]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.mod.o Jul 7 00:15:41.859947 setup-nvidia[2422]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.mod.o Jul 7 00:15:41.859947 setup-nvidia[2422]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Jul 7 00:15:41.859947 setup-nvidia[2422]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Jul 7 00:15:41.859947 setup-nvidia[2422]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Jul 7 00:15:41.859947 setup-nvidia[2422]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Jul 7 00:15:41.859947 setup-nvidia[2422]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Jul 7 00:15:41.859947 setup-nvidia[2422]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Jul 7 00:15:41.860668 setup-nvidia[2422]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko due to unavailability of vmlinux Jul 7 00:15:41.860668 setup-nvidia[2422]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Jul 7 00:15:41.860668 setup-nvidia[2422]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko due to unavailability of vmlinux Jul 7 00:15:41.860668 setup-nvidia[2422]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Jul 7 00:15:41.860668 setup-nvidia[2422]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko due to unavailability of vmlinux Jul 7 00:15:41.860668 setup-nvidia[2422]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Jul 7 00:15:41.860668 setup-nvidia[2422]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko due to unavailability of vmlinux Jul 7 00:15:41.860668 setup-nvidia[2422]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Jul 7 00:15:41.860668 setup-nvidia[2422]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko due to unavailability of vmlinux Jul 7 00:15:41.860668 setup-nvidia[2422]: make[2]: Leaving directory '/usr/lib/modules/6.12.35-flatcar/build' Jul 7 00:15:41.860668 setup-nvidia[2422]: make[1]: Leaving directory '/usr/lib/modules/6.12.35-flatcar/source' Jul 7 00:15:41.860668 setup-nvidia[2422]: -> done. Jul 7 00:15:41.860668 setup-nvidia[2422]: -> Kernel module compilation complete. Jul 7 00:15:41.860668 setup-nvidia[2422]: -> Unable to determine if Secure Boot is enabled: No such file or directory Jul 7 00:15:41.860668 setup-nvidia[2422]: 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 7 00:15:41.860668 setup-nvidia[2422]: \u000d Jul 7 00:15:41.860668 setup-nvidia[2422]: Please see the log entries 'Kernel module load error' and 'Kernel messages' at the end of the file '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more information. Jul 7 00:15:41.860668 setup-nvidia[2422]: -> Kernel module load error: Operation not permitted Jul 7 00:15:41.860668 setup-nvidia[2422]: -> Kernel messages: Jul 7 00:15:41.860668 setup-nvidia[2422]: ERROR: Installation has failed. Please see the file '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for details. You may find suggestions on fixing installation problems in the README available on the Linux driver download page at www.nvidia.com. Jul 7 00:15:47.340417 systemd[1]: Started sshd@19-10.200.4.15:22-10.200.16.10:38872.service - OpenSSH per-connection server daemon (10.200.16.10:38872). Jul 7 00:15:47.933828 sshd[12738]: Accepted publickey for core from 10.200.16.10 port 38872 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:15:47.935272 sshd-session[12738]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:15:47.939571 systemd-logind[1886]: New session 22 of user core. Jul 7 00:15:47.956186 systemd[1]: Started session-22.scope - Session 22 of User core. Jul 7 00:15:48.358165 sshd[12740]: Connection closed by 10.200.16.10 port 38872 Jul 7 00:15:48.358516 sshd-session[12738]: pam_unix(sshd:session): session closed for user core Jul 7 00:15:48.361921 systemd[1]: sshd@19-10.200.4.15:22-10.200.16.10:38872.service: Deactivated successfully. Jul 7 00:15:48.363473 systemd[1]: session-22.scope: Deactivated successfully. Jul 7 00:15:48.364152 systemd-logind[1886]: Session 22 logged out. Waiting for processes to exit. Jul 7 00:15:48.365089 systemd-logind[1886]: Removed session 22. Jul 7 00:15:48.467509 systemd[1]: Started sshd@20-10.200.4.15:22-10.200.16.10:38884.service - OpenSSH per-connection server daemon (10.200.16.10:38884). Jul 7 00:15:49.062595 sshd[12746]: Accepted publickey for core from 10.200.16.10 port 38884 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:15:49.063954 sshd-session[12746]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:15:49.068624 systemd-logind[1886]: New session 23 of user core. Jul 7 00:15:49.081177 systemd[1]: Started session-23.scope - Session 23 of User core. Jul 7 00:15:49.490816 sshd[12748]: Connection closed by 10.200.16.10 port 38884 Jul 7 00:15:49.491205 sshd-session[12746]: pam_unix(sshd:session): session closed for user core Jul 7 00:15:49.494572 systemd[1]: sshd@20-10.200.4.15:22-10.200.16.10:38884.service: Deactivated successfully. Jul 7 00:15:49.496145 systemd[1]: session-23.scope: Deactivated successfully. Jul 7 00:15:49.496812 systemd-logind[1886]: Session 23 logged out. Waiting for processes to exit. Jul 7 00:15:49.497758 systemd-logind[1886]: Removed session 23. Jul 7 00:15:59.610231 systemd[1]: Started sshd@21-10.200.4.15:22-10.200.16.10:58248.service - OpenSSH per-connection server daemon (10.200.16.10:58248). Jul 7 00:16:00.212482 sshd[12755]: Accepted publickey for core from 10.200.16.10 port 58248 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:16:00.213826 sshd-session[12755]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:16:00.218180 systemd-logind[1886]: New session 24 of user core. Jul 7 00:16:00.230167 systemd[1]: Started session-24.scope - Session 24 of User core. Jul 7 00:16:05.710662 sshd[12757]: Connection closed by 10.200.16.10 port 58248 Jul 7 00:16:00.744131 systemd[1]: Started sshd@22-10.200.4.15:22-10.200.16.10:53810.service - OpenSSH per-connection server daemon (10.200.16.10:53810). Jul 7 00:16:05.710746 sshd-session[12755]: pam_unix(sshd:session): session closed for user core Jul 7 00:16:05.711565 sshd[12760]: Accepted publickey for core from 10.200.16.10 port 53810 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:16:05.711003 sshd-session[12760]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:16:05.714613 systemd[1]: sshd@21-10.200.4.15:22-10.200.16.10:58248.service: Deactivated successfully. Jul 7 00:16:05.716385 systemd[1]: session-24.scope: Deactivated successfully. Jul 7 00:16:05.718704 systemd-logind[1886]: New session 25 of user core. Jul 7 00:16:05.719817 systemd-logind[1886]: Session 24 logged out. Waiting for processes to exit. Jul 7 00:16:05.732195 systemd[1]: Started session-25.scope - Session 25 of User core. Jul 7 00:16:05.738068 kernel: EXT4-fs (loop6p9): unmounting filesystem 652653fa-37fd-4534-a1c2-1154064d51e9. Jul 7 00:16:05.741590 setup-nvidia[2422]: Container flatcardevelopercontainer-4344.1.1.bin exited successfully. Jul 7 00:16:05.741744 systemd-logind[1886]: Removed session 24. Jul 7 00:16:05.743738 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4344.1.1.bin.mount: Deactivated successfully. Jul 7 00:16:05.793855 sudo[2410]: pam_unix(sudo:session): session closed for user root Jul 7 00:16:05.795747 systemd[1]: session-c2.scope: Deactivated successfully. Jul 7 00:16:05.796005 systemd[1]: session-c2.scope: Consumed 5min 13.152s CPU time, 893.5M memory peak. Jul 7 00:16:06.079455 sshd[12765]: Connection closed by 10.200.16.10 port 53810 Jul 7 00:16:06.079845 sshd-session[12760]: pam_unix(sshd:session): session closed for user core Jul 7 00:16:06.083403 systemd[1]: sshd@22-10.200.4.15:22-10.200.16.10:53810.service: Deactivated successfully. Jul 7 00:16:06.084972 systemd[1]: session-25.scope: Deactivated successfully. Jul 7 00:16:06.085655 systemd-logind[1886]: Session 25 logged out. Waiting for processes to exit. Jul 7 00:16:06.086631 systemd-logind[1886]: Removed session 25. Jul 7 00:16:06.213279 setup-nvidia[1876]: /opt/nvidia / Jul 7 00:16:06.214294 setup-nvidia[1876]: / Jul 7 00:16:06.224071 kernel: IPMI message handler: version 39.2 Jul 7 00:16:06.227065 kernel: ipmi device interface Jul 7 00:16:06.240262 setup-nvidia[1876]: /opt/nvidia/current/usr/lib/modules/6.12.35-flatcar/video / Jul 7 00:16:06.293287 kernel: nvidia: loading out-of-tree module taints kernel. Jul 7 00:16:06.293415 kernel: nvidia: module license 'NVIDIA' taints kernel. Jul 7 00:16:06.293439 kernel: Disabling lock debugging due to kernel taint Jul 7 00:16:06.301833 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Jul 7 00:16:06.301878 kernel: nvidia: module license taints kernel. Jul 7 00:16:06.689072 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 Jul 7 00:16:07.094533 kernel: Jul 7 00:16:07.094673 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Jul 7 00:16:07.146071 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.230.02 Fri Dec 20 21:42:05 UTC 2024 Jul 7 00:16:07.167067 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.230.02 Fri Dec 20 21:52:34 UTC 2024 Jul 7 00:16:07.188076 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Jul 7 00:16:07.236069 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. Jul 7 00:16:07.236322 setup-nvidia[1876]: / Jul 7 00:16:07.251300 setup-nvidia[12804]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jul 7 00:16:08.819529 setup-nvidia[12821]: Mon Jul 7 00:16:08 2025 Jul 7 00:16:08.819529 setup-nvidia[12821]: +---------------------------------------------------------------------------------------+ Jul 7 00:16:08.819529 setup-nvidia[12821]: | NVIDIA-SMI 535.230.02 Driver Version: 535.230.02 CUDA Version: 12.2 | Jul 7 00:16:08.819529 setup-nvidia[12821]: |-----------------------------------------+----------------------+----------------------+ Jul 7 00:16:08.819529 setup-nvidia[12821]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Jul 7 00:16:08.819529 setup-nvidia[12821]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Jul 7 00:16:08.819529 setup-nvidia[12821]: | | | MIG M. | Jul 7 00:16:08.819529 setup-nvidia[12821]: |=========================================+======================+======================| Jul 7 00:16:08.826842 setup-nvidia[12821]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | 0 | Jul 7 00:16:08.826842 setup-nvidia[12821]: | N/A 29C P0 35W / 250W | 0MiB / 16384MiB | 1% Default | Jul 7 00:16:08.826842 setup-nvidia[12821]: | | | N/A | Jul 7 00:16:08.826842 setup-nvidia[12821]: +-----------------------------------------+----------------------+----------------------+ Jul 7 00:16:08.826842 setup-nvidia[12821]: Jul 7 00:16:08.826842 setup-nvidia[12821]: +---------------------------------------------------------------------------------------+ Jul 7 00:16:08.826842 setup-nvidia[12821]: | Processes: | Jul 7 00:16:08.826842 setup-nvidia[12821]: | GPU GI CI PID Type Process name GPU Memory | Jul 7 00:16:08.826842 setup-nvidia[12821]: | ID ID Usage | Jul 7 00:16:08.826842 setup-nvidia[12821]: |=======================================================================================| Jul 7 00:16:08.827098 setup-nvidia[12821]: | No running processes found | Jul 7 00:16:08.827098 setup-nvidia[12821]: +---------------------------------------------------------------------------------------+ Jul 7 00:16:09.196912 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Jul 7 00:16:09.199117 systemd[1]: Reached target multi-user.target - Multi-User System. Jul 7 00:16:09.199435 systemd[1]: Startup finished in 913ms (firmware) + 28.594s (loader) + 4.035s (kernel) + 11.915s (initrd) + 2min 55.996s (userspace) = 3min 41.455s. Jul 7 00:16:15.980167 systemd[1]: Stopping user@0.service - User Manager for UID 0... Jul 7 00:16:15.980974 systemd[2413]: Activating special unit exit.target... Jul 7 00:16:15.981088 systemd[2413]: Stopped target default.target - Main User Target. Jul 7 00:16:15.981131 systemd[2413]: Stopped target basic.target - Basic System. Jul 7 00:16:15.981160 systemd[2413]: Stopped target paths.target - Paths. Jul 7 00:16:15.981185 systemd[2413]: Stopped target sockets.target - Sockets. Jul 7 00:16:15.981210 systemd[2413]: Stopped target timers.target - Timers. Jul 7 00:16:15.981568 systemd[2413]: Closed dbus.socket - D-Bus User Message Bus Socket. Jul 7 00:16:15.981850 systemd[2413]: Removed slice app.slice - User Application Slice. Jul 7 00:16:15.981892 systemd[2413]: Reached target shutdown.target - Shutdown. Jul 7 00:16:15.982068 systemd[2413]: Finished systemd-exit.service - Exit the Session. Jul 7 00:16:15.982109 systemd[2413]: Reached target exit.target - Exit the Session. Jul 7 00:16:15.995204 systemd[1]: user@0.service: Deactivated successfully. Jul 7 00:16:15.995556 systemd[1]: Stopped user@0.service - User Manager for UID 0. Jul 7 00:16:15.998112 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Jul 7 00:16:16.027151 systemd[1]: run-user-0.mount: Deactivated successfully. Jul 7 00:16:16.028112 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Jul 7 00:16:16.028316 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Jul 7 00:16:16.030018 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Jul 7 00:16:16.030142 systemd[1]: user-0.slice: Consumed 5min 13.438s CPU time, 902.4M memory peak. Jul 7 00:16:16.030562 systemd-logind[1886]: Removed session c3. Jul 7 00:16:16.190447 systemd[1]: Started sshd@23-10.200.4.15:22-10.200.16.10:49856.service - OpenSSH per-connection server daemon (10.200.16.10:49856). Jul 7 00:16:16.785741 sshd[12832]: Accepted publickey for core from 10.200.16.10 port 49856 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:16:16.787143 sshd-session[12832]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:16:16.791769 systemd-logind[1886]: New session 26 of user core. Jul 7 00:16:16.807171 systemd[1]: Started session-26.scope - Session 26 of User core. Jul 7 00:16:17.206582 sshd[12834]: Connection closed by 10.200.16.10 port 49856 Jul 7 00:16:17.206884 sshd-session[12832]: pam_unix(sshd:session): session closed for user core Jul 7 00:16:17.210520 systemd[1]: sshd@23-10.200.4.15:22-10.200.16.10:49856.service: Deactivated successfully. Jul 7 00:16:17.212118 systemd[1]: session-26.scope: Deactivated successfully. Jul 7 00:16:17.212774 systemd-logind[1886]: Session 26 logged out. Waiting for processes to exit. Jul 7 00:16:17.213723 systemd-logind[1886]: Removed session 26. Jul 7 00:16:17.318860 systemd[1]: Started sshd@24-10.200.4.15:22-10.200.16.10:49862.service - OpenSSH per-connection server daemon (10.200.16.10:49862). Jul 7 00:16:17.914186 sshd[12840]: Accepted publickey for core from 10.200.16.10 port 49862 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:16:17.915489 sshd-session[12840]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:16:17.920687 systemd-logind[1886]: New session 27 of user core. Jul 7 00:16:17.936186 systemd[1]: Started session-27.scope - Session 27 of User core. Jul 7 00:16:18.330329 sshd[12842]: Connection closed by 10.200.16.10 port 49862 Jul 7 00:16:18.330716 sshd-session[12840]: pam_unix(sshd:session): session closed for user core Jul 7 00:16:18.334472 systemd[1]: sshd@24-10.200.4.15:22-10.200.16.10:49862.service: Deactivated successfully. Jul 7 00:16:18.336099 systemd[1]: session-27.scope: Deactivated successfully. Jul 7 00:16:18.336748 systemd-logind[1886]: Session 27 logged out. Waiting for processes to exit. Jul 7 00:16:18.337686 systemd-logind[1886]: Removed session 27. Jul 7 00:16:18.440421 systemd[1]: Started sshd@25-10.200.4.15:22-10.200.16.10:49876.service - OpenSSH per-connection server daemon (10.200.16.10:49876). Jul 7 00:16:19.036328 sshd[12848]: Accepted publickey for core from 10.200.16.10 port 49876 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:16:19.037678 sshd-session[12848]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:16:19.043368 systemd-logind[1886]: New session 28 of user core. Jul 7 00:16:19.060198 systemd[1]: Started session-28.scope - Session 28 of User core. Jul 7 00:16:19.465558 sshd[12850]: Connection closed by 10.200.16.10 port 49876 Jul 7 00:16:19.465863 sshd-session[12848]: pam_unix(sshd:session): session closed for user core Jul 7 00:16:19.469326 systemd[1]: sshd@25-10.200.4.15:22-10.200.16.10:49876.service: Deactivated successfully. Jul 7 00:16:19.470899 systemd[1]: session-28.scope: Deactivated successfully. Jul 7 00:16:19.471600 systemd-logind[1886]: Session 28 logged out. Waiting for processes to exit. Jul 7 00:16:19.472562 systemd-logind[1886]: Removed session 28. Jul 7 00:16:19.570279 systemd[1]: Started sshd@26-10.200.4.15:22-10.200.16.10:49892.service - OpenSSH per-connection server daemon (10.200.16.10:49892). Jul 7 00:16:20.165835 sshd[12856]: Accepted publickey for core from 10.200.16.10 port 49892 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:16:20.167230 sshd-session[12856]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:16:20.171490 systemd-logind[1886]: New session 29 of user core. Jul 7 00:16:20.181186 systemd[1]: Started session-29.scope - Session 29 of User core. Jul 7 00:16:20.494264 sudo[12859]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Jul 7 00:16:20.494562 sudo[12859]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jul 7 00:16:20.550457 sudo[12859]: pam_unix(sudo:session): session closed for user root Jul 7 00:16:20.652742 sshd[12858]: Connection closed by 10.200.16.10 port 49892 Jul 7 00:16:20.653139 sshd-session[12856]: pam_unix(sshd:session): session closed for user core Jul 7 00:16:20.656697 systemd[1]: sshd@26-10.200.4.15:22-10.200.16.10:49892.service: Deactivated successfully. Jul 7 00:16:20.658255 systemd[1]: session-29.scope: Deactivated successfully. Jul 7 00:16:20.658977 systemd-logind[1886]: Session 29 logged out. Waiting for processes to exit. Jul 7 00:16:20.660166 systemd-logind[1886]: Removed session 29. Jul 7 00:16:20.762526 systemd[1]: Started sshd@27-10.200.4.15:22-10.200.16.10:43508.service - OpenSSH per-connection server daemon (10.200.16.10:43508). Jul 7 00:16:21.382516 sshd[12865]: Accepted publickey for core from 10.200.16.10 port 43508 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:16:21.383864 sshd-session[12865]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:16:21.388258 systemd-logind[1886]: New session 30 of user core. Jul 7 00:16:21.403183 systemd[1]: Started session-30.scope - Session 30 of User core. Jul 7 00:16:21.712592 sudo[12869]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Jul 7 00:16:21.712887 sudo[12869]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jul 7 00:16:21.720002 sudo[12869]: pam_unix(sudo:session): session closed for user root Jul 7 00:16:21.725433 sudo[12868]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Jul 7 00:16:21.725719 sudo[12868]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jul 7 00:16:21.734858 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jul 7 00:16:21.776404 augenrules[12891]: No rules Jul 7 00:16:21.777609 systemd[1]: audit-rules.service: Deactivated successfully. Jul 7 00:16:21.777833 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jul 7 00:16:21.778587 sudo[12868]: pam_unix(sudo:session): session closed for user root Jul 7 00:16:21.872675 sshd[12867]: Connection closed by 10.200.16.10 port 43508 Jul 7 00:16:21.873018 sshd-session[12865]: pam_unix(sshd:session): session closed for user core Jul 7 00:16:21.876635 systemd[1]: sshd@27-10.200.4.15:22-10.200.16.10:43508.service: Deactivated successfully. Jul 7 00:16:21.878221 systemd[1]: session-30.scope: Deactivated successfully. Jul 7 00:16:21.878888 systemd-logind[1886]: Session 30 logged out. Waiting for processes to exit. Jul 7 00:16:21.879841 systemd-logind[1886]: Removed session 30. Jul 7 00:16:21.982503 systemd[1]: Started sshd@28-10.200.4.15:22-10.200.16.10:43516.service - OpenSSH per-connection server daemon (10.200.16.10:43516). Jul 7 00:16:22.576892 sshd[12900]: Accepted publickey for core from 10.200.16.10 port 43516 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:16:22.578266 sshd-session[12900]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:16:22.582555 systemd-logind[1886]: New session 31 of user core. Jul 7 00:16:22.599174 systemd[1]: Started session-31.scope - Session 31 of User core. Jul 7 00:16:22.905494 sudo[12903]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/mkdir -p . Jul 7 00:16:22.905801 sudo[12903]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jul 7 00:16:22.908943 sudo[12903]: pam_unix(sudo:session): session closed for user root Jul 7 00:16:23.010653 sshd[12902]: Connection closed by 10.200.16.10 port 43516 Jul 7 00:16:23.011011 sshd-session[12900]: pam_unix(sshd:session): session closed for user core Jul 7 00:16:23.014528 systemd[1]: sshd@28-10.200.4.15:22-10.200.16.10:43516.service: Deactivated successfully. Jul 7 00:16:23.016100 systemd[1]: session-31.scope: Deactivated successfully. Jul 7 00:16:23.016776 systemd-logind[1886]: Session 31 logged out. Waiting for processes to exit. Jul 7 00:16:23.017769 systemd-logind[1886]: Removed session 31. Jul 7 00:16:23.120348 systemd[1]: Started sshd@29-10.200.4.15:22-10.200.16.10:43528.service - OpenSSH per-connection server daemon (10.200.16.10:43528). Jul 7 00:16:23.719783 sshd[12909]: Accepted publickey for core from 10.200.16.10 port 43528 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:16:23.721123 sshd-session[12909]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:16:23.725310 systemd-logind[1886]: New session 32 of user core. Jul 7 00:16:23.735195 systemd[1]: Started session-32.scope - Session 32 of User core. Jul 7 00:16:24.049503 sudo[12912]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/install -m 0755 /dev/stdin kolet Jul 7 00:16:24.049810 sudo[12912]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jul 7 00:16:27.179644 sudo[12912]: pam_unix(sudo:session): session closed for user root Jul 7 00:16:27.274486 sshd[12911]: Connection closed by 10.200.16.10 port 43528 Jul 7 00:16:27.274770 sshd-session[12909]: pam_unix(sshd:session): session closed for user core Jul 7 00:16:27.278256 systemd[1]: sshd@29-10.200.4.15:22-10.200.16.10:43528.service: Deactivated successfully. Jul 7 00:16:27.279834 systemd[1]: session-32.scope: Deactivated successfully. Jul 7 00:16:27.280743 systemd-logind[1886]: Session 32 logged out. Waiting for processes to exit. Jul 7 00:16:27.282110 systemd-logind[1886]: Removed session 32. Jul 7 00:16:27.379197 systemd[1]: Started sshd@30-10.200.4.15:22-10.200.16.10:43542.service - OpenSSH per-connection server daemon (10.200.16.10:43542). Jul 7 00:16:28.008857 sshd[12918]: Accepted publickey for core from 10.200.16.10 port 43542 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:16:28.010206 sshd-session[12918]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:16:28.014514 systemd-logind[1886]: New session 33 of user core. Jul 7 00:16:28.024193 systemd[1]: Started session-33.scope - Session 33 of User core. Jul 7 00:16:28.587018 sshd[12920]: Connection closed by 10.200.16.10 port 43542 Jul 7 00:16:28.587393 sshd-session[12918]: pam_unix(sshd:session): session closed for user core Jul 7 00:16:28.590752 systemd[1]: sshd@30-10.200.4.15:22-10.200.16.10:43542.service: Deactivated successfully. Jul 7 00:16:28.592313 systemd[1]: session-33.scope: Deactivated successfully. Jul 7 00:16:28.592977 systemd-logind[1886]: Session 33 logged out. Waiting for processes to exit. Jul 7 00:16:28.593978 systemd-logind[1886]: Removed session 33. Jul 7 00:16:28.696170 systemd[1]: Started sshd@31-10.200.4.15:22-10.200.16.10:43544.service - OpenSSH per-connection server daemon (10.200.16.10:43544). Jul 7 00:16:29.295061 sshd[12936]: Accepted publickey for core from 10.200.16.10 port 43544 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:16:29.296418 sshd-session[12936]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:16:29.300821 systemd-logind[1886]: New session 34 of user core. Jul 7 00:16:29.315194 systemd[1]: Started session-34.scope - Session 34 of User core. Jul 7 00:16:32.673651 systemd[1]: Starting docker.service - Docker Application Container Engine... Jul 7 00:16:32.691459 (dockerd)[12958]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPTS, DOCKER_OPT_BIP, DOCKER_OPT_IPMASQ, DOCKER_OPT_MTU Jul 7 00:16:34.979207 dockerd[12958]: time="2025-07-07T00:16:34.979147892Z" level=info msg="Starting up" Jul 7 00:16:34.979809 dockerd[12958]: time="2025-07-07T00:16:34.979784457Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Jul 7 00:16:39.717869 dockerd[12958]: time="2025-07-07T00:16:39.717820096Z" level=info msg="Loading containers: start." Jul 7 00:16:39.731065 kernel: Initializing XFRM netlink socket Jul 7 00:16:39.971882 systemd-networkd[1800]: docker0: Link UP Jul 7 00:16:39.984208 dockerd[12958]: time="2025-07-07T00:16:39.984146817Z" level=info msg="Loading containers: done." Jul 7 00:16:39.999119 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck1564371651-merged.mount: Deactivated successfully. Jul 7 00:16:40.002534 dockerd[12958]: time="2025-07-07T00:16:40.002501484Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2 Jul 7 00:16:40.002610 dockerd[12958]: time="2025-07-07T00:16:40.002567581Z" level=info msg="Docker daemon" commit=bbd0a17ccc67e48d4a69393287b7fcc4f0578683 containerd-snapshotter=false storage-driver=overlay2 version=28.0.1 Jul 7 00:16:40.002686 dockerd[12958]: time="2025-07-07T00:16:40.002666875Z" level=info msg="Initializing buildkit" Jul 7 00:16:40.047477 dockerd[12958]: time="2025-07-07T00:16:40.047436057Z" level=info msg="Completed buildkit initialization" Jul 7 00:16:40.054653 dockerd[12958]: time="2025-07-07T00:16:40.054624353Z" level=info msg="Daemon has completed initialization" Jul 7 00:16:40.054726 dockerd[12958]: time="2025-07-07T00:16:40.054687149Z" level=info msg="API listen on /run/docker.sock" Jul 7 00:16:40.054867 systemd[1]: Started docker.service - Docker Application Container Engine. Jul 7 00:16:41.179746 systemd[1]: var-lib-docker-overlay2-4a65f3ee2d1b0e9d26df4b463ad9f14fe37b3e39a492f2b3a59f3576f5dc0764\x2dinit-merged.mount: Deactivated successfully. Jul 7 00:16:41.267034 containerd[1897]: time="2025-07-07T00:16:41.266987566Z" level=info msg="connecting to shim eb53a033d9e41adc02d11a65de9aa94fd56af45007bced1404bbdee4742ccdb8" address="unix:///run/containerd/s/6f3b61d2e6cc4caebb916da6d2af8f88ca35922751e50d75319fe380ea68c97e" namespace=moby protocol=ttrpc version=3 Jul 7 00:16:41.292779 systemd[1]: Started docker-eb53a033d9e41adc02d11a65de9aa94fd56af45007bced1404bbdee4742ccdb8.scope - libcontainer container eb53a033d9e41adc02d11a65de9aa94fd56af45007bced1404bbdee4742ccdb8. Jul 7 00:16:41.341303 kernel: docker0: port 1(veth2272568) entered blocking state Jul 7 00:16:41.341401 kernel: docker0: port 1(veth2272568) entered disabled state Jul 7 00:16:41.341425 kernel: veth2272568: entered allmulticast mode Jul 7 00:16:41.346568 kernel: veth2272568: entered promiscuous mode Jul 7 00:16:41.347040 systemd-networkd[1800]: veth2272568: Link UP Jul 7 00:16:41.368063 kernel: eth0: renamed from vetha30ff4d Jul 7 00:16:41.375070 kernel: docker0: port 1(veth2272568) entered blocking state Jul 7 00:16:41.375116 kernel: docker0: port 1(veth2272568) entered forwarding state Jul 7 00:16:41.375166 systemd-networkd[1800]: veth2272568: Gained carrier Jul 7 00:16:41.375421 systemd-networkd[1800]: docker0: Gained carrier Jul 7 00:16:42.995203 systemd-networkd[1800]: veth2272568: Gained IPv6LL Jul 7 00:16:43.315186 systemd-networkd[1800]: docker0: Gained IPv6LL Jul 7 00:16:44.658607 systemd[1]: docker-eb53a033d9e41adc02d11a65de9aa94fd56af45007bced1404bbdee4742ccdb8.scope: Deactivated successfully. Jul 7 00:16:44.672026 dockerd[12958]: time="2025-07-07T00:16:44.671793404Z" level=info msg="ignoring event" container=eb53a033d9e41adc02d11a65de9aa94fd56af45007bced1404bbdee4742ccdb8 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Jul 7 00:16:44.672328 containerd[1897]: time="2025-07-07T00:16:44.672076488Z" level=info msg="shim disconnected" id=eb53a033d9e41adc02d11a65de9aa94fd56af45007bced1404bbdee4742ccdb8 namespace=moby Jul 7 00:16:44.672328 containerd[1897]: time="2025-07-07T00:16:44.672107886Z" level=warning msg="cleaning up after shim disconnected" id=eb53a033d9e41adc02d11a65de9aa94fd56af45007bced1404bbdee4742ccdb8 namespace=moby Jul 7 00:16:44.672328 containerd[1897]: time="2025-07-07T00:16:44.672123285Z" level=info msg="cleaning up dead shim" namespace=moby Jul 7 00:16:44.700835 systemd-networkd[1800]: veth2272568: Lost carrier Jul 7 00:16:44.701109 kernel: docker0: port 1(veth2272568) entered disabled state Jul 7 00:16:44.701140 kernel: vetha30ff4d: renamed from eth0 Jul 7 00:16:44.736506 systemd-networkd[1800]: veth2272568: Link DOWN Jul 7 00:16:44.740082 kernel: docker0: port 1(veth2272568) entered disabled state Jul 7 00:16:44.740143 kernel: veth2272568 (unregistering): left allmulticast mode Jul 7 00:16:44.746381 kernel: veth2272568 (unregistering): left promiscuous mode Jul 7 00:16:44.749937 kernel: docker0: port 1(veth2272568) entered disabled state Jul 7 00:16:44.775112 systemd[1]: run-docker-netns-11860e69ad40.mount: Deactivated successfully. Jul 7 00:16:44.778501 systemd[1]: var-lib-docker-overlay2-4a65f3ee2d1b0e9d26df4b463ad9f14fe37b3e39a492f2b3a59f3576f5dc0764-merged.mount: Deactivated successfully. Jul 7 00:16:44.909273 sshd[12938]: Connection closed by 10.200.16.10 port 43544 Jul 7 00:16:44.909575 sshd-session[12936]: pam_unix(sshd:session): session closed for user core Jul 7 00:16:44.913232 systemd[1]: sshd@31-10.200.4.15:22-10.200.16.10:43544.service: Deactivated successfully. Jul 7 00:16:44.915192 systemd[1]: session-34.scope: Deactivated successfully. Jul 7 00:16:44.916057 systemd-logind[1886]: Session 34 logged out. Waiting for processes to exit. Jul 7 00:16:44.917101 systemd-logind[1886]: Removed session 34. Jul 7 00:16:45.024297 systemd[1]: Started sshd@32-10.200.4.15:22-10.200.16.10:50910.service - OpenSSH per-connection server daemon (10.200.16.10:50910). Jul 7 00:16:45.645558 sshd[13242]: Accepted publickey for core from 10.200.16.10 port 50910 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:16:45.646869 sshd-session[13242]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:16:45.651092 systemd-logind[1886]: New session 35 of user core. Jul 7 00:16:45.666169 systemd[1]: Started session-35.scope - Session 35 of User core. Jul 7 00:16:45.747302 systemd-networkd[1800]: docker0: Lost carrier Jul 7 00:16:46.028759 systemd[1]: var-lib-docker-overlay2-b0e2af582b08ad58214e06895d77c7eb0c393b8e1459c57c4da66de5666dc979\x2dinit-merged.mount: Deactivated successfully. Jul 7 00:16:46.112967 containerd[1897]: time="2025-07-07T00:16:46.112914660Z" level=info msg="connecting to shim a0e7026842ce831dc797866008f980589fa2ea45d3da0ea825739f159e17fbd3" address="unix:///run/containerd/s/c08a3f35b725695af88ec4a959b494bffa5a0a51a23397930e9c29542dbfe8d5" namespace=moby protocol=ttrpc version=3 Jul 7 00:16:46.139190 systemd[1]: Started docker-a0e7026842ce831dc797866008f980589fa2ea45d3da0ea825739f159e17fbd3.scope - libcontainer container a0e7026842ce831dc797866008f980589fa2ea45d3da0ea825739f159e17fbd3. Jul 7 00:16:46.181384 kernel: docker0: port 1(veth88ec491) entered blocking state Jul 7 00:16:46.181471 kernel: docker0: port 1(veth88ec491) entered disabled state Jul 7 00:16:46.184339 kernel: veth88ec491: entered allmulticast mode Jul 7 00:16:46.187077 kernel: veth88ec491: entered promiscuous mode Jul 7 00:16:46.187814 systemd-networkd[1800]: veth88ec491: Link UP Jul 7 00:16:46.206061 kernel: eth0: renamed from veth18a9435 Jul 7 00:16:46.213420 kernel: docker0: port 1(veth88ec491) entered blocking state Jul 7 00:16:46.213472 kernel: docker0: port 1(veth88ec491) entered forwarding state Jul 7 00:16:46.213838 systemd-networkd[1800]: veth88ec491: Gained carrier Jul 7 00:16:46.214121 systemd-networkd[1800]: docker0: Gained carrier Jul 7 00:16:46.243092 systemd[1]: docker-a0e7026842ce831dc797866008f980589fa2ea45d3da0ea825739f159e17fbd3.scope: Deactivated successfully. Jul 7 00:16:46.261072 containerd[1897]: time="2025-07-07T00:16:46.260838151Z" level=info msg="shim disconnected" id=a0e7026842ce831dc797866008f980589fa2ea45d3da0ea825739f159e17fbd3 namespace=moby Jul 7 00:16:46.261072 containerd[1897]: time="2025-07-07T00:16:46.260905147Z" level=warning msg="cleaning up after shim disconnected" id=a0e7026842ce831dc797866008f980589fa2ea45d3da0ea825739f159e17fbd3 namespace=moby Jul 7 00:16:46.261072 containerd[1897]: time="2025-07-07T00:16:46.260914347Z" level=info msg="cleaning up dead shim" namespace=moby Jul 7 00:16:46.261230 dockerd[12958]: time="2025-07-07T00:16:46.260954145Z" level=info msg="ignoring event" container=a0e7026842ce831dc797866008f980589fa2ea45d3da0ea825739f159e17fbd3 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Jul 7 00:16:46.291075 kernel: docker0: port 1(veth88ec491) entered disabled state Jul 7 00:16:46.291148 kernel: veth18a9435: renamed from eth0 Jul 7 00:16:46.291141 systemd-networkd[1800]: veth88ec491: Lost carrier Jul 7 00:16:46.320240 systemd-networkd[1800]: veth88ec491: Link DOWN Jul 7 00:16:46.324064 kernel: docker0: port 1(veth88ec491) entered disabled state Jul 7 00:16:46.330806 kernel: veth88ec491 (unregistering): left allmulticast mode Jul 7 00:16:46.330855 kernel: veth88ec491 (unregistering): left promiscuous mode Jul 7 00:16:46.334199 kernel: docker0: port 1(veth88ec491) entered disabled state Jul 7 00:16:46.468771 sshd[13244]: Connection closed by 10.200.16.10 port 50910 Jul 7 00:16:46.469146 sshd-session[13242]: pam_unix(sshd:session): session closed for user core Jul 7 00:16:46.472583 systemd[1]: sshd@32-10.200.4.15:22-10.200.16.10:50910.service: Deactivated successfully. Jul 7 00:16:46.474160 systemd[1]: session-35.scope: Deactivated successfully. Jul 7 00:16:46.474822 systemd-logind[1886]: Session 35 logged out. Waiting for processes to exit. Jul 7 00:16:46.475764 systemd-logind[1886]: Removed session 35. Jul 7 00:16:46.578309 systemd[1]: Started sshd@33-10.200.4.15:22-10.200.16.10:50916.service - OpenSSH per-connection server daemon (10.200.16.10:50916). Jul 7 00:16:46.755310 systemd-networkd[1800]: docker0: Lost carrier Jul 7 00:16:47.025520 systemd[1]: var-lib-docker-overlay2-b0e2af582b08ad58214e06895d77c7eb0c393b8e1459c57c4da66de5666dc979-merged.mount: Deactivated successfully. Jul 7 00:16:47.195284 sshd[13345]: Accepted publickey for core from 10.200.16.10 port 50916 ssh2: RSA SHA256:IyMJtgYFhdpAVNA+dVtHUQzEaxKZsjoiQQAdaQNd1BI Jul 7 00:16:47.196606 sshd-session[13345]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 00:16:47.201010 systemd-logind[1886]: New session 36 of user core. Jul 7 00:16:47.211179 systemd[1]: Started session-36.scope - Session 36 of User core. Jul 7 00:16:47.570465 ntpdate[13358]: ntpdate 4.2.8p17@1.4004-o Sun Jul 6 21:17:45 UTC 2025 (1) Jul 7 00:16:54.451129 ntpdate[13358]: adjust time server 85.199.214.99 offset -0.000885 sec Jul 7 00:16:54.554509 sshd[13347]: Connection closed by 10.200.16.10 port 50916 Jul 7 00:16:54.554872 sshd-session[13345]: pam_unix(sshd:session): session closed for user core Jul 7 00:16:54.558337 systemd[1]: sshd@33-10.200.4.15:22-10.200.16.10:50916.service: Deactivated successfully. Jul 7 00:16:54.559922 systemd[1]: session-36.scope: Deactivated successfully. Jul 7 00:16:54.560604 systemd-logind[1886]: Session 36 logged out. Waiting for processes to exit. Jul 7 00:16:54.561539 systemd-logind[1886]: Removed session 36.